Cryptotech Setcos card signing problem

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

Cryptotech Setcos card signing problem

TMS Brokers / Łukasz Kościesza

Hi all,

 

I’m trying to sign a file using compiled from sources opensc 12.2.

Card is manufactured by Cryptotech and it has setcos 4.1.1 on board.

The card was initialized by www.sigillum.pl company.

Even though PIN is fine and there are no failures in the card reading process I keep on receiving: Security status not satisfied, after sending portion of data for signing.

Can anyone point me to some solution?

 

Here is the part of the logs which I guess is relevant:

 

0xb7b1d6c0 13:35:27.314 [opensc-pkcs11] framework-pkcs15.c:2630:pkcs15_prkey_sign: Initiating signing operation, mechanism 0x1.

0xb7b1d6c0 13:35:27.314 [opensc-pkcs11] card.c:292:sc_lock: called

0xb7b1d6c0 13:35:27.314 [opensc-pkcs11] reader-pcsc.c:511:pcsc_lock: called

0xb7b1d6c0 13:35:27.314 [opensc-pkcs11] framework-pkcs15.c:3611:reselect_app_df: reselect application df

0xb7b1d6c0 13:35:27.314 [opensc-pkcs11] card.c:571:sc_select_file: called; type=2, path=3f00de00

0xb7b1d6c0 13:35:27.314 [opensc-pkcs11] apdu.c:525:sc_transmit_apdu: called

0xb7b1d6c0 13:35:27.314 [opensc-pkcs11] card.c:292:sc_lock: called

0xb7b1d6c0 13:35:27.314 [opensc-pkcs11] reader-pcsc.c:243:pcsc_transmit: reader 'ACS ACR38U 00 00'

0xb7b1d6c0 13:35:27.314 [opensc-pkcs11] apdu.c:184:sc_apdu_log:

Outgoing APDU data [    7 bytes] =====================================

00 A4 08 00 02 DE 00 .......

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

0xb7b1d6c0 13:35:27.314 [opensc-pkcs11] reader-pcsc.c:176:pcsc_internal_transmit: called

0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] apdu.c:184:sc_apdu_log:

Incoming APDU data [    2 bytes] =====================================

61 2D a-

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

0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] card.c:330:sc_unlock: called

0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] iso7816.c:481:iso7816_select_file: returning with: 0 (Success)

0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] card.c:597:sc_select_file: returning with: 0 (Success)

0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] framework-pkcs15.c:2710:pkcs15_prkey_sign: Selected flags 12. Now computing signature for 3 bytes. 512 bytes reserved.

0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] pkcs15-sec.c:190:sc_pkcs15_compute_signature: called

0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] pkcs15-sec.c:191:sc_pkcs15_compute_signature: security operation flags 0x12

0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] pkcs15-sec.c:273:sc_pkcs15_compute_signature: supported algorithm flags 0x80000033, private key usage 0x26

0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] padding.c:273:sc_get_encoding_flags: called

0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] padding.c:277:sc_get_encoding_flags: iFlags 0x12, card capabilities 0x80000033

0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] padding.c:306:sc_get_encoding_flags: pad flags 0x10, secure algorithm flags 0x2

0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] padding.c:307:sc_get_encoding_flags: returning with: 0 (Success)

0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] pkcs15-sec.c:324:sc_pkcs15_compute_signature: DEE flags:0x00000012 alg_info->flags:0x80000033 pad:0x00000010 sec:0x00000002

0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] padding.c:232:sc_pkcs1_encode: called

0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] padding.c:236:sc_pkcs1_encode: hash algorithm 0x10, pad algorithm 0x0

0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] padding.c:255:sc_pkcs1_encode: returning with: 0 (Success)

0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] card.c:292:sc_lock: called

0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] pkcs15-sec.c:42:select_key_file: called

0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] card.c:571:sc_select_file: called; type=2, path=3f00df01efd5

0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] apdu.c:525:sc_transmit_apdu: called

0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] card.c:292:sc_lock: called

0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] reader-pcsc.c:243:pcsc_transmit: reader 'ACS ACR38U 00 00'

0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] apdu.c:184:sc_apdu_log:

Outgoing APDU data [    9 bytes] =====================================

00 A4 08 00 04 DF 01 EF D5 .........

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

0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] reader-pcsc.c:176:pcsc_internal_transmit: called

0xb7b1d6c0 13:35:27.419 [opensc-pkcs11] apdu.c:184:sc_apdu_log:

Incoming APDU data [    2 bytes] =====================================

61 25 a%

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

0xb7b1d6c0 13:35:27.419 [opensc-pkcs11] card.c:330:sc_unlock: called

0xb7b1d6c0 13:35:27.419 [opensc-pkcs11] iso7816.c:481:iso7816_select_file: returning with: 0 (Success)

0xb7b1d6c0 13:35:27.419 [opensc-pkcs11] card.c:597:sc_select_file: returning with: 0 (Success)

0xb7b1d6c0 13:35:27.419 [opensc-pkcs11] pkcs15-sec.c:68:select_key_file: returning with: 0 (Success)

0xb7b1d6c0 13:35:27.419 [opensc-pkcs11] sec.c:66:sc_set_security_env: called

0xb7b1d6c0 13:35:27.419 [opensc-pkcs11] apdu.c:525:sc_transmit_apdu: called

0xb7b1d6c0 13:35:27.419 [opensc-pkcs11] card.c:292:sc_lock: called

0xb7b1d6c0 13:35:27.419 [opensc-pkcs11] reader-pcsc.c:243:pcsc_transmit: reader 'ACS ACR38U 00 00'

0xb7b1d6c0 13:35:27.419 [opensc-pkcs11] apdu.c:184:sc_apdu_log:

Outgoing APDU data [   12 bytes] =====================================

00 22 41 B6 07 80 01 02 81 02 EF D5 ."A.........

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

0xb7b1d6c0 13:35:27.419 [opensc-pkcs11] reader-pcsc.c:176:pcsc_internal_transmit: called

0xb7b1d6c0 13:35:27.446 [opensc-pkcs11] apdu.c:184:sc_apdu_log:

Incoming APDU data [    2 bytes] =====================================

90 00 ..

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

0xb7b1d6c0 13:35:27.446 [opensc-pkcs11] card.c:330:sc_unlock: called

0xb7b1d6c0 13:35:27.446 [opensc-pkcs11] sec.c:70:sc_set_security_env: returning with: 0 (Success)

0xb7b1d6c0 13:35:27.446 [opensc-pkcs11] sec.c:52:sc_compute_signature: called

0xb7b1d6c0 13:35:27.446 [opensc-pkcs11] apdu.c:525:sc_transmit_apdu: called

0xb7b1d6c0 13:35:27.446 [opensc-pkcs11] card.c:292:sc_lock: called

0xb7b1d6c0 13:35:27.446 [opensc-pkcs11] reader-pcsc.c:243:pcsc_transmit: reader 'ACS ACR38U 00 00'

0xb7b1d6c0 13:35:27.446 [opensc-pkcs11] apdu.c:184:sc_apdu_log:

Outgoing APDU data [    8 bytes] =====================================

00 2A 9E 9A 03 41 6C 61 .*...Ala

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

0xb7b1d6c0 13:35:27.446 [opensc-pkcs11] reader-pcsc.c:176:pcsc_internal_transmit: called

0xb7b1d6c0 13:35:27.471 [opensc-pkcs11] apdu.c:184:sc_apdu_log:

Incoming APDU data [    2 bytes] =====================================

69 82 i.

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

0xb7b1d6c0 13:35:27.471 [opensc-pkcs11] card.c:330:sc_unlock: called

0xb7b1d6c0 13:35:27.471 [opensc-pkcs11] iso7816.c:103:iso7816_check_sw: Security status not satisfied

0xb7b1d6c0 13:35:27.471 [opensc-pkcs11] iso7816.c:820:iso7816_compute_signature: returning with: -1211 (Security status not satisfied)

0xb7b1d6c0 13:35:27.471 [opensc-pkcs11] sec.c:56:sc_compute_signature: returning with: -1211 (Security status not satisfied)

0xb7b1d6c0 13:35:27.471 [opensc-pkcs11] pkcs15-pin.c:553:sc_pkcs15_pincache_revalidate: called

0xb7b1d6c0 13:35:27.471 [opensc-pkcs11] pkcs15-pin.c:566:sc_pkcs15_pincache_revalidate: Could not find pin object for auth_id 01

0xb7b1d6c0 13:35:27.471 [opensc-pkcs11] card.c:330:sc_unlock: called

0xb7b1d6c0 13:35:27.471 [opensc-pkcs11] pkcs15-sec.c:380:sc_pkcs15_compute_signature: sc_compute_signature() failed: -1211 (Security status not satisfied)

0xb7b1d6c0 13:35:27.471 [opensc-pkcs11] card.c:330:sc_unlock: called

0xb7b1d6c0 13:35:27.471 [opensc-pkcs11] reader-pcsc.c:548:pcsc_unlock: called

0xb7b1d6c0 13:35:27.481 [opensc-pkcs11] framework-pkcs15.c:2721:pkcs15_prkey_sign: Sign complete. Result -1211.

0xb7b1d6c0 13:35:27.481 [opensc-pkcs11] misc.c:59:sc_to_cryptoki_error_common: libopensc return value: -1211 (Security status not satisfied)

0xb7b1d6c0 13:35:27.481 [opensc-pkcs11] pkcs11-object.c:635:C_Sign: C_Sign() = CKR_USER_NOT_LOGGED_IN


_______________________________________________
opensc-devel mailing list
[hidden email]
http://www.opensc-project.org/mailman/listinfo/opensc-devel
Reply | Threaded
Open this post in threaded view
|

Re: Cryptotech Setcos card signing problem

Douglas E. Engert
It looks like you did not logon to the card.

Did the program ever ask for the pin?

What program are you using?


On 8/12/2011 7:05 AM, TMS Brokers / Łukasz Kościesza wrote:

> Hi all,
>
> I’m trying to sign a file using compiled from sources opensc 12.2.
>
> Card is manufactured by Cryptotech and it has setcos 4.1.1 on board.
>
> The card was initialized by www.sigillum.pl <http://www.sigillum.pl> company.
>
> Even though PIN is fine and there are no failures in the card reading process I keep on receiving: Security status not satisfied, after sending portion of data for signing.
>
> Can anyone point me to some solution?
>
> Here is the part of the logs which I guess is relevant:
>
> 0xb7b1d6c0 13:35:27.314 [opensc-pkcs11] framework-pkcs15.c:2630:pkcs15_prkey_sign: Initiating signing operation, mechanism 0x1.
>
> 0xb7b1d6c0 13:35:27.314 [opensc-pkcs11] card.c:292:sc_lock: called
>
> 0xb7b1d6c0 13:35:27.314 [opensc-pkcs11] reader-pcsc.c:511:pcsc_lock: called
>
> 0xb7b1d6c0 13:35:27.314 [opensc-pkcs11] framework-pkcs15.c:3611:reselect_app_df: reselect application df
>
> 0xb7b1d6c0 13:35:27.314 [opensc-pkcs11] card.c:571:sc_select_file: called; type=2, path=3f00de00
>
> 0xb7b1d6c0 13:35:27.314 [opensc-pkcs11] apdu.c:525:sc_transmit_apdu: called
>
> 0xb7b1d6c0 13:35:27.314 [opensc-pkcs11] card.c:292:sc_lock: called
>
> 0xb7b1d6c0 13:35:27.314 [opensc-pkcs11] reader-pcsc.c:243:pcsc_transmit: reader 'ACS ACR38U 00 00'
>
> 0xb7b1d6c0 13:35:27.314 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
>
> Outgoing APDU data [    7 bytes] =====================================
>
> 00 A4 08 00 02 DE 00 .......
>
> ======================================================================
>
> 0xb7b1d6c0 13:35:27.314 [opensc-pkcs11] reader-pcsc.c:176:pcsc_internal_transmit: called
>
> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
>
> Incoming APDU data [    2 bytes] =====================================
>
> 61 2D a-
>
> ======================================================================
>
> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] card.c:330:sc_unlock: called
>
> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] iso7816.c:481:iso7816_select_file: returning with: 0 (Success)
>
> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] card.c:597:sc_select_file: returning with: 0 (Success)
>
> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] framework-pkcs15.c:2710:pkcs15_prkey_sign: Selected flags 12. Now computing signature for 3 bytes. 512 bytes reserved.
>
> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] pkcs15-sec.c:190:sc_pkcs15_compute_signature: called
>
> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] pkcs15-sec.c:191:sc_pkcs15_compute_signature: security operation flags 0x12
>
> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] pkcs15-sec.c:273:sc_pkcs15_compute_signature: supported algorithm flags 0x80000033, private key usage 0x26
>
> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] padding.c:273:sc_get_encoding_flags: called
>
> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] padding.c:277:sc_get_encoding_flags: iFlags 0x12, card capabilities 0x80000033
>
> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] padding.c:306:sc_get_encoding_flags: pad flags 0x10, secure algorithm flags 0x2
>
> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] padding.c:307:sc_get_encoding_flags: returning with: 0 (Success)
>
> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] pkcs15-sec.c:324:sc_pkcs15_compute_signature: DEE flags:0x00000012 alg_info->flags:0x80000033 pad:0x00000010 sec:0x00000002
>
> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] padding.c:232:sc_pkcs1_encode: called
>
> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] padding.c:236:sc_pkcs1_encode: hash algorithm 0x10, pad algorithm 0x0
>
> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] padding.c:255:sc_pkcs1_encode: returning with: 0 (Success)
>
> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] card.c:292:sc_lock: called
>
> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] pkcs15-sec.c:42:select_key_file: called
>
> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] card.c:571:sc_select_file: called; type=2, path=3f00df01efd5
>
> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] apdu.c:525:sc_transmit_apdu: called
>
> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] card.c:292:sc_lock: called
>
> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] reader-pcsc.c:243:pcsc_transmit: reader 'ACS ACR38U 00 00'
>
> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
>
> Outgoing APDU data [    9 bytes] =====================================
>
> 00 A4 08 00 04 DF 01 EF D5 .........
>
> ======================================================================
>
> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] reader-pcsc.c:176:pcsc_internal_transmit: called
>
> 0xb7b1d6c0 13:35:27.419 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
>
> Incoming APDU data [    2 bytes] =====================================
>
> 61 25 a%
>
> ======================================================================
>
> 0xb7b1d6c0 13:35:27.419 [opensc-pkcs11] card.c:330:sc_unlock: called
>
> 0xb7b1d6c0 13:35:27.419 [opensc-pkcs11] iso7816.c:481:iso7816_select_file: returning with: 0 (Success)
>
> 0xb7b1d6c0 13:35:27.419 [opensc-pkcs11] card.c:597:sc_select_file: returning with: 0 (Success)
>
> 0xb7b1d6c0 13:35:27.419 [opensc-pkcs11] pkcs15-sec.c:68:select_key_file: returning with: 0 (Success)
>
> 0xb7b1d6c0 13:35:27.419 [opensc-pkcs11] sec.c:66:sc_set_security_env: called
>
> 0xb7b1d6c0 13:35:27.419 [opensc-pkcs11] apdu.c:525:sc_transmit_apdu: called
>
> 0xb7b1d6c0 13:35:27.419 [opensc-pkcs11] card.c:292:sc_lock: called
>
> 0xb7b1d6c0 13:35:27.419 [opensc-pkcs11] reader-pcsc.c:243:pcsc_transmit: reader 'ACS ACR38U 00 00'
>
> 0xb7b1d6c0 13:35:27.419 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
>
> Outgoing APDU data [   12 bytes] =====================================
>
> 00 22 41 B6 07 80 01 02 81 02 EF D5 ."A.........
>
> ======================================================================
>
> 0xb7b1d6c0 13:35:27.419 [opensc-pkcs11] reader-pcsc.c:176:pcsc_internal_transmit: called
>
> 0xb7b1d6c0 13:35:27.446 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
>
> Incoming APDU data [    2 bytes] =====================================
>
> 90 00 ..
>
> ======================================================================
>
> 0xb7b1d6c0 13:35:27.446 [opensc-pkcs11] card.c:330:sc_unlock: called
>
> 0xb7b1d6c0 13:35:27.446 [opensc-pkcs11] sec.c:70:sc_set_security_env: returning with: 0 (Success)
>
> 0xb7b1d6c0 13:35:27.446 [opensc-pkcs11] sec.c:52:sc_compute_signature: called
>
> 0xb7b1d6c0 13:35:27.446 [opensc-pkcs11] apdu.c:525:sc_transmit_apdu: called
>
> 0xb7b1d6c0 13:35:27.446 [opensc-pkcs11] card.c:292:sc_lock: called
>
> 0xb7b1d6c0 13:35:27.446 [opensc-pkcs11] reader-pcsc.c:243:pcsc_transmit: reader 'ACS ACR38U 00 00'
>
> 0xb7b1d6c0 13:35:27.446 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
>
> Outgoing APDU data [    8 bytes] =====================================
>
> 00 2A 9E 9A 03 41 6C 61 .*...Ala
>
> ======================================================================
>
> 0xb7b1d6c0 13:35:27.446 [opensc-pkcs11] reader-pcsc.c:176:pcsc_internal_transmit: called
>
> 0xb7b1d6c0 13:35:27.471 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
>
> Incoming APDU data [    2 bytes] =====================================
>
> 69 82 i.
>
> ======================================================================
>
> 0xb7b1d6c0 13:35:27.471 [opensc-pkcs11] card.c:330:sc_unlock: called
>
> 0xb7b1d6c0 13:35:27.471 [opensc-pkcs11] iso7816.c:103:iso7816_check_sw: Security status not satisfied
>
> 0xb7b1d6c0 13:35:27.471 [opensc-pkcs11] iso7816.c:820:iso7816_compute_signature: returning with: -1211 (Security status not satisfied)
>
> 0xb7b1d6c0 13:35:27.471 [opensc-pkcs11] sec.c:56:sc_compute_signature: returning with: -1211 (Security status not satisfied)
>
> 0xb7b1d6c0 13:35:27.471 [opensc-pkcs11] pkcs15-pin.c:553:sc_pkcs15_pincache_revalidate: called
>
> 0xb7b1d6c0 13:35:27.471 [opensc-pkcs11] pkcs15-pin.c:566:sc_pkcs15_pincache_revalidate: Could not find pin object for auth_id 01
>
> 0xb7b1d6c0 13:35:27.471 [opensc-pkcs11] card.c:330:sc_unlock: called
>
> 0xb7b1d6c0 13:35:27.471 [opensc-pkcs11] pkcs15-sec.c:380:sc_pkcs15_compute_signature: sc_compute_signature() failed: -1211 (Security status not satisfied)
>
> 0xb7b1d6c0 13:35:27.471 [opensc-pkcs11] card.c:330:sc_unlock: called
>
> 0xb7b1d6c0 13:35:27.471 [opensc-pkcs11] reader-pcsc.c:548:pcsc_unlock: called
>
> 0xb7b1d6c0 13:35:27.481 [opensc-pkcs11] framework-pkcs15.c:2721:pkcs15_prkey_sign: Sign complete. Result -1211.
>
> 0xb7b1d6c0 13:35:27.481 [opensc-pkcs11] misc.c:59:sc_to_cryptoki_error_common: libopensc return value: -1211 (Security status not satisfied)
>
> 0xb7b1d6c0 13:35:27.481 [opensc-pkcs11] pkcs11-object.c:635:C_Sign: C_Sign() = CKR_USER_NOT_LOGGED_IN
>
>
>
> _______________________________________________
> opensc-devel mailing list
> [hidden email]
> http://www.opensc-project.org/mailman/listinfo/opensc-devel

--

  Douglas E. Engert  <[hidden email]>
  Argonne National Laboratory
  9700 South Cass Avenue
  Argonne, Illinois  60439
  (630) 252-5444
_______________________________________________
opensc-devel mailing list
[hidden email]
http://www.opensc-project.org/mailman/listinfo/opensc-devel
Reply | Threaded
Open this post in threaded view
|

Re: Cryptotech Setcos card signing problem

TMS Brokers / Łukasz Kościesza
I use pkcs11-tool
pkcs11-tool --module /usr/lib/opensc-pkcs11.so --sign --slot 1 -m RSA-PKCS --input-file file.txt  --output-file signature.txt --pin my_pin

It's sending the PIN to the card (I can see it in APDU log a little bit earlier in logs).
If I enter wrong PIN it's failing, so it also checks the PIN.

-----Original Message-----
From: [hidden email] [mailto:[hidden email]] On Behalf Of Douglas E. Engert
Sent: Friday, August 12, 2011 3:39 PM
To: [hidden email]
Subject: Re: [opensc-devel] Cryptotech Setcos card signing problem

It looks like you did not logon to the card.

Did the program ever ask for the pin?

What program are you using?


On 8/12/2011 7:05 AM, TMS Brokers / Łukasz Kościesza wrote:

> Hi all,
>
> I’m trying to sign a file using compiled from sources opensc 12.2.
>
> Card is manufactured by Cryptotech and it has setcos 4.1.1 on board.
>
> The card was initialized by www.sigillum.pl <http://www.sigillum.pl> company.
>
> Even though PIN is fine and there are no failures in the card reading process I keep on receiving: Security status not satisfied, after sending portion of data for signing.
>
> Can anyone point me to some solution?
>
> Here is the part of the logs which I guess is relevant:
>
> 0xb7b1d6c0 13:35:27.314 [opensc-pkcs11] framework-pkcs15.c:2630:pkcs15_prkey_sign: Initiating signing operation, mechanism 0x1.
>
> 0xb7b1d6c0 13:35:27.314 [opensc-pkcs11] card.c:292:sc_lock: called
>
> 0xb7b1d6c0 13:35:27.314 [opensc-pkcs11] reader-pcsc.c:511:pcsc_lock: called
>
> 0xb7b1d6c0 13:35:27.314 [opensc-pkcs11] framework-pkcs15.c:3611:reselect_app_df: reselect application df
>
> 0xb7b1d6c0 13:35:27.314 [opensc-pkcs11] card.c:571:sc_select_file: called; type=2, path=3f00de00
>
> 0xb7b1d6c0 13:35:27.314 [opensc-pkcs11] apdu.c:525:sc_transmit_apdu: called
>
> 0xb7b1d6c0 13:35:27.314 [opensc-pkcs11] card.c:292:sc_lock: called
>
> 0xb7b1d6c0 13:35:27.314 [opensc-pkcs11] reader-pcsc.c:243:pcsc_transmit: reader 'ACS ACR38U 00 00'
>
> 0xb7b1d6c0 13:35:27.314 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
>
> Outgoing APDU data [    7 bytes] =====================================
>
> 00 A4 08 00 02 DE 00 .......
>
> ======================================================================
>
> 0xb7b1d6c0 13:35:27.314 [opensc-pkcs11] reader-pcsc.c:176:pcsc_internal_transmit: called
>
> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
>
> Incoming APDU data [    2 bytes] =====================================
>
> 61 2D a-
>
> ======================================================================
>
> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] card.c:330:sc_unlock: called
>
> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] iso7816.c:481:iso7816_select_file: returning with: 0 (Success)
>
> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] card.c:597:sc_select_file: returning with: 0 (Success)
>
> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] framework-pkcs15.c:2710:pkcs15_prkey_sign: Selected flags 12. Now computing signature for 3 bytes. 512 bytes reserved.
>
> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] pkcs15-sec.c:190:sc_pkcs15_compute_signature: called
>
> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] pkcs15-sec.c:191:sc_pkcs15_compute_signature: security operation flags 0x12
>
> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] pkcs15-sec.c:273:sc_pkcs15_compute_signature: supported algorithm flags 0x80000033, private key usage 0x26
>
> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] padding.c:273:sc_get_encoding_flags: called
>
> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] padding.c:277:sc_get_encoding_flags: iFlags 0x12, card capabilities 0x80000033
>
> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] padding.c:306:sc_get_encoding_flags: pad flags 0x10, secure algorithm flags 0x2
>
> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] padding.c:307:sc_get_encoding_flags: returning with: 0 (Success)
>
> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] pkcs15-sec.c:324:sc_pkcs15_compute_signature: DEE flags:0x00000012 alg_info->flags:0x80000033 pad:0x00000010 sec:0x00000002
>
> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] padding.c:232:sc_pkcs1_encode: called
>
> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] padding.c:236:sc_pkcs1_encode: hash algorithm 0x10, pad algorithm 0x0
>
> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] padding.c:255:sc_pkcs1_encode: returning with: 0 (Success)
>
> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] card.c:292:sc_lock: called
>
> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] pkcs15-sec.c:42:select_key_file: called
>
> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] card.c:571:sc_select_file: called; type=2, path=3f00df01efd5
>
> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] apdu.c:525:sc_transmit_apdu: called
>
> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] card.c:292:sc_lock: called
>
> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] reader-pcsc.c:243:pcsc_transmit: reader 'ACS ACR38U 00 00'
>
> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
>
> Outgoing APDU data [    9 bytes] =====================================
>
> 00 A4 08 00 04 DF 01 EF D5 .........
>
> ======================================================================
>
> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] reader-pcsc.c:176:pcsc_internal_transmit: called
>
> 0xb7b1d6c0 13:35:27.419 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
>
> Incoming APDU data [    2 bytes] =====================================
>
> 61 25 a%
>
> ======================================================================
>
> 0xb7b1d6c0 13:35:27.419 [opensc-pkcs11] card.c:330:sc_unlock: called
>
> 0xb7b1d6c0 13:35:27.419 [opensc-pkcs11] iso7816.c:481:iso7816_select_file: returning with: 0 (Success)
>
> 0xb7b1d6c0 13:35:27.419 [opensc-pkcs11] card.c:597:sc_select_file: returning with: 0 (Success)
>
> 0xb7b1d6c0 13:35:27.419 [opensc-pkcs11] pkcs15-sec.c:68:select_key_file: returning with: 0 (Success)
>
> 0xb7b1d6c0 13:35:27.419 [opensc-pkcs11] sec.c:66:sc_set_security_env: called
>
> 0xb7b1d6c0 13:35:27.419 [opensc-pkcs11] apdu.c:525:sc_transmit_apdu: called
>
> 0xb7b1d6c0 13:35:27.419 [opensc-pkcs11] card.c:292:sc_lock: called
>
> 0xb7b1d6c0 13:35:27.419 [opensc-pkcs11] reader-pcsc.c:243:pcsc_transmit: reader 'ACS ACR38U 00 00'
>
> 0xb7b1d6c0 13:35:27.419 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
>
> Outgoing APDU data [   12 bytes] =====================================
>
> 00 22 41 B6 07 80 01 02 81 02 EF D5 ."A.........
>
> ======================================================================
>
> 0xb7b1d6c0 13:35:27.419 [opensc-pkcs11] reader-pcsc.c:176:pcsc_internal_transmit: called
>
> 0xb7b1d6c0 13:35:27.446 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
>
> Incoming APDU data [    2 bytes] =====================================
>
> 90 00 ..
>
> ======================================================================
>
> 0xb7b1d6c0 13:35:27.446 [opensc-pkcs11] card.c:330:sc_unlock: called
>
> 0xb7b1d6c0 13:35:27.446 [opensc-pkcs11] sec.c:70:sc_set_security_env: returning with: 0 (Success)
>
> 0xb7b1d6c0 13:35:27.446 [opensc-pkcs11] sec.c:52:sc_compute_signature: called
>
> 0xb7b1d6c0 13:35:27.446 [opensc-pkcs11] apdu.c:525:sc_transmit_apdu: called
>
> 0xb7b1d6c0 13:35:27.446 [opensc-pkcs11] card.c:292:sc_lock: called
>
> 0xb7b1d6c0 13:35:27.446 [opensc-pkcs11] reader-pcsc.c:243:pcsc_transmit: reader 'ACS ACR38U 00 00'
>
> 0xb7b1d6c0 13:35:27.446 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
>
> Outgoing APDU data [    8 bytes] =====================================
>
> 00 2A 9E 9A 03 41 6C 61 .*...Ala
>
> ======================================================================
>
> 0xb7b1d6c0 13:35:27.446 [opensc-pkcs11] reader-pcsc.c:176:pcsc_internal_transmit: called
>
> 0xb7b1d6c0 13:35:27.471 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
>
> Incoming APDU data [    2 bytes] =====================================
>
> 69 82 i.
>
> ======================================================================
>
> 0xb7b1d6c0 13:35:27.471 [opensc-pkcs11] card.c:330:sc_unlock: called
>
> 0xb7b1d6c0 13:35:27.471 [opensc-pkcs11] iso7816.c:103:iso7816_check_sw: Security status not satisfied
>
> 0xb7b1d6c0 13:35:27.471 [opensc-pkcs11] iso7816.c:820:iso7816_compute_signature: returning with: -1211 (Security status not satisfied)
>
> 0xb7b1d6c0 13:35:27.471 [opensc-pkcs11] sec.c:56:sc_compute_signature: returning with: -1211 (Security status not satisfied)
>
> 0xb7b1d6c0 13:35:27.471 [opensc-pkcs11] pkcs15-pin.c:553:sc_pkcs15_pincache_revalidate: called
>
> 0xb7b1d6c0 13:35:27.471 [opensc-pkcs11] pkcs15-pin.c:566:sc_pkcs15_pincache_revalidate: Could not find pin object for auth_id 01
>
> 0xb7b1d6c0 13:35:27.471 [opensc-pkcs11] card.c:330:sc_unlock: called
>
> 0xb7b1d6c0 13:35:27.471 [opensc-pkcs11] pkcs15-sec.c:380:sc_pkcs15_compute_signature: sc_compute_signature() failed: -1211 (Security status not satisfied)
>
> 0xb7b1d6c0 13:35:27.471 [opensc-pkcs11] card.c:330:sc_unlock: called
>
> 0xb7b1d6c0 13:35:27.471 [opensc-pkcs11] reader-pcsc.c:548:pcsc_unlock: called
>
> 0xb7b1d6c0 13:35:27.481 [opensc-pkcs11] framework-pkcs15.c:2721:pkcs15_prkey_sign: Sign complete. Result -1211.
>
> 0xb7b1d6c0 13:35:27.481 [opensc-pkcs11] misc.c:59:sc_to_cryptoki_error_common: libopensc return value: -1211 (Security status not satisfied)
>
> 0xb7b1d6c0 13:35:27.481 [opensc-pkcs11] pkcs11-object.c:635:C_Sign: C_Sign() = CKR_USER_NOT_LOGGED_IN
>
>
>
> _______________________________________________
> opensc-devel mailing list
> [hidden email]
> http://www.opensc-project.org/mailman/listinfo/opensc-devel

--

  Douglas E. Engert  <[hidden email]>
  Argonne National Laboratory
  9700 South Cass Avenue
  Argonne, Illinois  60439
  (630) 252-5444
_______________________________________________
opensc-devel mailing list
[hidden email]
http://www.opensc-project.org/mailman/listinfo/opensc-devel
_______________________________________________
opensc-devel mailing list
[hidden email]
http://www.opensc-project.org/mailman/listinfo/opensc-devel
Reply | Threaded
Open this post in threaded view
|

Re: Cryptotech Setcos card signing problem

Douglas E. Engert
Developers,
Could this be a card that is enforcing user_consent. i.e. CKA_ALWAYS_AUTHENTICATIE
and the pkcs11-tool is not doing this?

The one card I know that does enforce it (newewr PIV cards), requires the sign
operation to be preceeded immediatley by the presentation of the pin.

Łukasz,
  Can you look at your trace and see if any other operations are sent to the card between these too?


On 8/12/2011 8:51 AM, TMS Brokers / Łukasz Kościesza wrote:

> I use pkcs11-tool
> pkcs11-tool --module /usr/lib/opensc-pkcs11.so --sign --slot 1 -m RSA-PKCS --input-file file.txt  --output-file signature.txt --pin my_pin
>
> It's sending the PIN to the card (I can see it in APDU log a little bit earlier in logs).
> If I enter wrong PIN it's failing, so it also checks the PIN.
>
> -----Original Message-----
> From: [hidden email] [mailto:[hidden email]] On Behalf Of Douglas E. Engert
> Sent: Friday, August 12, 2011 3:39 PM
> To: [hidden email]
> Subject: Re: [opensc-devel] Cryptotech Setcos card signing problem
>
> It looks like you did not logon to the card.
>
> Did the program ever ask for the pin?
>
> What program are you using?
>
>
> On 8/12/2011 7:05 AM, TMS Brokers / Łukasz Kościesza wrote:
>> Hi all,
>>
>> I’m trying to sign a file using compiled from sources opensc 12.2.
>>
>> Card is manufactured by Cryptotech and it has setcos 4.1.1 on board.
>>
>> The card was initialized by www.sigillum.pl<http://www.sigillum.pl>  company.
>>
>> Even though PIN is fine and there are no failures in the card reading process I keep on receiving: Security status not satisfied, after sending portion of data for signing.
>>
>> Can anyone point me to some solution?
>>
>> Here is the part of the logs which I guess is relevant:
>>
>> 0xb7b1d6c0 13:35:27.314 [opensc-pkcs11] framework-pkcs15.c:2630:pkcs15_prkey_sign: Initiating signing operation, mechanism 0x1.
>>
>> 0xb7b1d6c0 13:35:27.314 [opensc-pkcs11] card.c:292:sc_lock: called
>>
>> 0xb7b1d6c0 13:35:27.314 [opensc-pkcs11] reader-pcsc.c:511:pcsc_lock: called
>>
>> 0xb7b1d6c0 13:35:27.314 [opensc-pkcs11] framework-pkcs15.c:3611:reselect_app_df: reselect application df
>>
>> 0xb7b1d6c0 13:35:27.314 [opensc-pkcs11] card.c:571:sc_select_file: called; type=2, path=3f00de00
>>
>> 0xb7b1d6c0 13:35:27.314 [opensc-pkcs11] apdu.c:525:sc_transmit_apdu: called
>>
>> 0xb7b1d6c0 13:35:27.314 [opensc-pkcs11] card.c:292:sc_lock: called
>>
>> 0xb7b1d6c0 13:35:27.314 [opensc-pkcs11] reader-pcsc.c:243:pcsc_transmit: reader 'ACS ACR38U 00 00'
>>
>> 0xb7b1d6c0 13:35:27.314 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
>>
>> Outgoing APDU data [    7 bytes] =====================================
>>
>> 00 A4 08 00 02 DE 00 .......
>>
>> ======================================================================
>>
>> 0xb7b1d6c0 13:35:27.314 [opensc-pkcs11] reader-pcsc.c:176:pcsc_internal_transmit: called
>>
>> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
>>
>> Incoming APDU data [    2 bytes] =====================================
>>
>> 61 2D a-
>>
>> ======================================================================
>>
>> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] card.c:330:sc_unlock: called
>>
>> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] iso7816.c:481:iso7816_select_file: returning with: 0 (Success)
>>
>> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] card.c:597:sc_select_file: returning with: 0 (Success)
>>
>> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] framework-pkcs15.c:2710:pkcs15_prkey_sign: Selected flags 12. Now computing signature for 3 bytes. 512 bytes reserved.
>>
>> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] pkcs15-sec.c:190:sc_pkcs15_compute_signature: called
>>
>> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] pkcs15-sec.c:191:sc_pkcs15_compute_signature: security operation flags 0x12
>>
>> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] pkcs15-sec.c:273:sc_pkcs15_compute_signature: supported algorithm flags 0x80000033, private key usage 0x26
>>
>> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] padding.c:273:sc_get_encoding_flags: called
>>
>> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] padding.c:277:sc_get_encoding_flags: iFlags 0x12, card capabilities 0x80000033
>>
>> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] padding.c:306:sc_get_encoding_flags: pad flags 0x10, secure algorithm flags 0x2
>>
>> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] padding.c:307:sc_get_encoding_flags: returning with: 0 (Success)
>>
>> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] pkcs15-sec.c:324:sc_pkcs15_compute_signature: DEE flags:0x00000012 alg_info->flags:0x80000033 pad:0x00000010 sec:0x00000002
>>
>> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] padding.c:232:sc_pkcs1_encode: called
>>
>> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] padding.c:236:sc_pkcs1_encode: hash algorithm 0x10, pad algorithm 0x0
>>
>> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] padding.c:255:sc_pkcs1_encode: returning with: 0 (Success)
>>
>> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] card.c:292:sc_lock: called
>>
>> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] pkcs15-sec.c:42:select_key_file: called
>>
>> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] card.c:571:sc_select_file: called; type=2, path=3f00df01efd5
>>
>> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] apdu.c:525:sc_transmit_apdu: called
>>
>> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] card.c:292:sc_lock: called
>>
>> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] reader-pcsc.c:243:pcsc_transmit: reader 'ACS ACR38U 00 00'
>>
>> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
>>
>> Outgoing APDU data [    9 bytes] =====================================
>>
>> 00 A4 08 00 04 DF 01 EF D5 .........
>>
>> ======================================================================
>>
>> 0xb7b1d6c0 13:35:27.363 [opensc-pkcs11] reader-pcsc.c:176:pcsc_internal_transmit: called
>>
>> 0xb7b1d6c0 13:35:27.419 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
>>
>> Incoming APDU data [    2 bytes] =====================================
>>
>> 61 25 a%
>>
>> ======================================================================
>>
>> 0xb7b1d6c0 13:35:27.419 [opensc-pkcs11] card.c:330:sc_unlock: called
>>
>> 0xb7b1d6c0 13:35:27.419 [opensc-pkcs11] iso7816.c:481:iso7816_select_file: returning with: 0 (Success)
>>
>> 0xb7b1d6c0 13:35:27.419 [opensc-pkcs11] card.c:597:sc_select_file: returning with: 0 (Success)
>>
>> 0xb7b1d6c0 13:35:27.419 [opensc-pkcs11] pkcs15-sec.c:68:select_key_file: returning with: 0 (Success)
>>
>> 0xb7b1d6c0 13:35:27.419 [opensc-pkcs11] sec.c:66:sc_set_security_env: called
>>
>> 0xb7b1d6c0 13:35:27.419 [opensc-pkcs11] apdu.c:525:sc_transmit_apdu: called
>>
>> 0xb7b1d6c0 13:35:27.419 [opensc-pkcs11] card.c:292:sc_lock: called
>>
>> 0xb7b1d6c0 13:35:27.419 [opensc-pkcs11] reader-pcsc.c:243:pcsc_transmit: reader 'ACS ACR38U 00 00'
>>
>> 0xb7b1d6c0 13:35:27.419 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
>>
>> Outgoing APDU data [   12 bytes] =====================================
>>
>> 00 22 41 B6 07 80 01 02 81 02 EF D5 ."A.........
>>
>> ======================================================================
>>
>> 0xb7b1d6c0 13:35:27.419 [opensc-pkcs11] reader-pcsc.c:176:pcsc_internal_transmit: called
>>
>> 0xb7b1d6c0 13:35:27.446 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
>>
>> Incoming APDU data [    2 bytes] =====================================
>>
>> 90 00 ..
>>
>> ======================================================================
>>
>> 0xb7b1d6c0 13:35:27.446 [opensc-pkcs11] card.c:330:sc_unlock: called
>>
>> 0xb7b1d6c0 13:35:27.446 [opensc-pkcs11] sec.c:70:sc_set_security_env: returning with: 0 (Success)
>>
>> 0xb7b1d6c0 13:35:27.446 [opensc-pkcs11] sec.c:52:sc_compute_signature: called
>>
>> 0xb7b1d6c0 13:35:27.446 [opensc-pkcs11] apdu.c:525:sc_transmit_apdu: called
>>
>> 0xb7b1d6c0 13:35:27.446 [opensc-pkcs11] card.c:292:sc_lock: called
>>
>> 0xb7b1d6c0 13:35:27.446 [opensc-pkcs11] reader-pcsc.c:243:pcsc_transmit: reader 'ACS ACR38U 00 00'
>>
>> 0xb7b1d6c0 13:35:27.446 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
>>
>> Outgoing APDU data [    8 bytes] =====================================
>>
>> 00 2A 9E 9A 03 41 6C 61 .*...Ala
>>
>> ======================================================================
>>
>> 0xb7b1d6c0 13:35:27.446 [opensc-pkcs11] reader-pcsc.c:176:pcsc_internal_transmit: called
>>
>> 0xb7b1d6c0 13:35:27.471 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
>>
>> Incoming APDU data [    2 bytes] =====================================
>>
>> 69 82 i.
>>
>> ======================================================================
>>
>> 0xb7b1d6c0 13:35:27.471 [opensc-pkcs11] card.c:330:sc_unlock: called
>>
>> 0xb7b1d6c0 13:35:27.471 [opensc-pkcs11] iso7816.c:103:iso7816_check_sw: Security status not satisfied
>>
>> 0xb7b1d6c0 13:35:27.471 [opensc-pkcs11] iso7816.c:820:iso7816_compute_signature: returning with: -1211 (Security status not satisfied)
>>
>> 0xb7b1d6c0 13:35:27.471 [opensc-pkcs11] sec.c:56:sc_compute_signature: returning with: -1211 (Security status not satisfied)
>>
>> 0xb7b1d6c0 13:35:27.471 [opensc-pkcs11] pkcs15-pin.c:553:sc_pkcs15_pincache_revalidate: called
>>
>> 0xb7b1d6c0 13:35:27.471 [opensc-pkcs11] pkcs15-pin.c:566:sc_pkcs15_pincache_revalidate: Could not find pin object for auth_id 01
>>
>> 0xb7b1d6c0 13:35:27.471 [opensc-pkcs11] card.c:330:sc_unlock: called
>>
>> 0xb7b1d6c0 13:35:27.471 [opensc-pkcs11] pkcs15-sec.c:380:sc_pkcs15_compute_signature: sc_compute_signature() failed: -1211 (Security status not satisfied)
>>
>> 0xb7b1d6c0 13:35:27.471 [opensc-pkcs11] card.c:330:sc_unlock: called
>>
>> 0xb7b1d6c0 13:35:27.471 [opensc-pkcs11] reader-pcsc.c:548:pcsc_unlock: called
>>
>> 0xb7b1d6c0 13:35:27.481 [opensc-pkcs11] framework-pkcs15.c:2721:pkcs15_prkey_sign: Sign complete. Result -1211.
>>
>> 0xb7b1d6c0 13:35:27.481 [opensc-pkcs11] misc.c:59:sc_to_cryptoki_error_common: libopensc return value: -1211 (Security status not satisfied)
>>
>> 0xb7b1d6c0 13:35:27.481 [opensc-pkcs11] pkcs11-object.c:635:C_Sign: C_Sign() = CKR_USER_NOT_LOGGED_IN
>>
>>
>>
>> _______________________________________________
>> opensc-devel mailing list
>> [hidden email]
>> http://www.opensc-project.org/mailman/listinfo/opensc-devel
>

--

  Douglas E. Engert  <[hidden email]>
  Argonne National Laboratory
  9700 South Cass Avenue
  Argonne, Illinois  60439
  (630) 252-5444
_______________________________________________
opensc-devel mailing list
[hidden email]
http://www.opensc-project.org/mailman/listinfo/opensc-devel
Reply | Threaded
Open this post in threaded view
|

Re: Cryptotech Setcos card signing problem

Viktor Tarasov-3
In reply to this post by TMS Brokers / Łukasz Kościesza
Hello,

Le 12/08/2011 14:05, TMS Brokers / Łukasz Kościesza a écrit :

>
> Hi all,
>
> I’m trying to sign a file using compiled from sources opensc 12.2.
>
> Card is manufactured by Cryptotech and it has setcos 4.1.1 on board.
>
> The card was initialized by www.sigillum.pl <http://www.sigillum.pl> company.
>
> Even though PIN is fine and there are Pno failures in the card reading process I keep on receiving: Security status not satisfied, after sending portion of data for signing.
>
> Can anyone point me to some solution?
>
> Here is the part of the logs which I guess is relevant:
>

Could you send here full logs?
Sweep off any confidential data (PINs), if any.

Kind regards,
Viktor.


_______________________________________________
opensc-devel mailing list
[hidden email]
http://www.opensc-project.org/mailman/listinfo/opensc-devel
Reply | Threaded
Open this post in threaded view
|

Re: Cryptotech Setcos card signing problem

TMS Brokers / Łukasz Kościesza
OK, I'm attaching the log file:
And I was able to sniff USB communication with card reader with native windows middleware for comparition:
01 A0 00 07 -> 00 A4 08 00 02 DF 01
01 00 00 02 <- 61 22
01 A0 00 05 -> 00 C0 00 00 22
01 00 00 24 -> (40 bytes - some id?)
01 A0 00 05 -> 00 20 00 81 00
01 00 00 02 <- 63 C3
01 A0 00 07 -> 00 0A 08 00 02 DF 01
01 00 00 02 <- 61 22
01 A0 00 05 -> 00 C0 00 00 22
01 00 00 24 <- (40 bytes - some id?)
01 A0 00 0D -> 00 20  (PIN!)
01 00 00 02 <- 90 00
01 A0 00 05 -> 00 20 00 81 00
01 00 00 02 <- 90 00
01 A0 00 07 -> A4 08 00 02 DF 01
01 00 00 02 <- 61 22
01 A0 00 05 -> 00 20 00 81 00
01 00 00 02 <- 90 00
01 A0 00 07 -> A4 08 00 02 DF 01
01 00 00 02 <- 61 22
01 A0 00 0C -> 00 22 41 B6 07 80 01 02 81 02 EF D5
01 00 00 02 <- 90 00
01 A0 00 28 -> (44 bytes) (data to be signed?)
01 00 00 02 <- 61 80
01 A0 00 05 -> 00 C0 00 00 80
01 00 00 82 <- (64 bytes) (signature?)
               (64 bytes)
01 A0 00 07 -> 00 A4 08 00 02 DF 01
01 00 00 02 <- 61 22
01 A0 00 05 -> 00 20 00 81 00
01 00 00 02 <- 63 C3

0xb7ad06c0 17:20:11.660 [opensc-pkcs11] ctx.c:659:sc_context_create: ===================================
0xb7ad06c0 17:20:11.660 [opensc-pkcs11] ctx.c:660:sc_context_create: opensc version: 0.12.2
0xb7ad06c0 17:20:11.679 [opensc-pkcs11] reader-pcsc.c:657:pcsc_init: PC/SC options: connect_exclusive=0 disconnect_action=1 transaction_end_action=0 reconnect_action=0 enable_pinpad=1
0xb7ad06c0 17:20:11.726 [opensc-pkcs11] reader-pcsc.c:870:pcsc_detect_readers: called
0xb7ad06c0 17:20:11.726 [opensc-pkcs11] reader-pcsc.c:878:pcsc_detect_readers: Probing pcsc readers
0xb7ad06c0 17:20:11.726 [opensc-pkcs11] reader-pcsc.c:900:pcsc_detect_readers: Establish pcsc context
0xb7ad06c0 17:20:11.727 [opensc-pkcs11] reader-pcsc.c:948:pcsc_detect_readers: Found new pcsc reader 'ACS ACR38U 00 00'
0xb7ad06c0 17:20:11.727 [opensc-pkcs11] reader-pcsc.c:277:refresh_attributes: ACS ACR38U 00 00 check
0xb7ad06c0 17:20:11.727 [opensc-pkcs11] reader-pcsc.c:301:refresh_attributes: current  state: 0x00000022
0xb7ad06c0 17:20:11.727 [opensc-pkcs11] reader-pcsc.c:302:refresh_attributes: previous state: 0x00000000
0xb7ad06c0 17:20:11.727 [opensc-pkcs11] reader-pcsc.c:356:refresh_attributes: card present, changed
0xb7ad06c0 17:20:11.727 [opensc-pkcs11] reader-pcsc.c:979:pcsc_detect_readers: Requesting reader features ...
0xb7ad06c0 17:20:11.764 [opensc-pkcs11] reader-pcsc.c:993:pcsc_detect_readers: ACS ACR38U 00 00:SCardConnect(SHARED): 0x00000000
0xb7ad06c0 17:20:11.764 [opensc-pkcs11] reader-pcsc.c:760:detect_reader_features: called
0xb7ad06c0 17:20:11.765 [opensc-pkcs11] reader-pcsc.c:767:detect_reader_features: ACS ACR38U 00 00:SCardControl failed: 0x80100004
0xb7ad06c0 17:20:11.765 [opensc-pkcs11] reader-pcsc.c:1023:pcsc_detect_readers: returning with: 0 (Success)
0xb7ad06c0 17:20:11.765 [opensc-pkcs11] misc.c:323:load_pkcs11_parameters: 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
0xb7ad06c0 17:20:11.765 [opensc-pkcs11] slot.c:90:create_slot: Creating slot with id 0x0
0xb7ad06c0 17:20:11.765 [opensc-pkcs11] slot.c:90:create_slot: Creating slot with id 0x1
0xb7ad06c0 17:20:11.765 [opensc-pkcs11] slot.c:90:create_slot: Creating slot with id 0x2
0xb7ad06c0 17:20:11.765 [opensc-pkcs11] slot.c:90:create_slot: Creating slot with id 0x3
0xb7ad06c0 17:20:11.765 [opensc-pkcs11] slot.c:90:create_slot: Creating slot with id 0x4
0xb7ad06c0 17:20:11.765 [opensc-pkcs11] sc.c:195:sc_detect_card_presence: called
0xb7ad06c0 17:20:11.765 [opensc-pkcs11] reader-pcsc.c:364:pcsc_detect_card_presence: called
0xb7ad06c0 17:20:11.765 [opensc-pkcs11] reader-pcsc.c:277:refresh_attributes: ACS ACR38U 00 00 check
0xb7ad06c0 17:20:11.765 [opensc-pkcs11] reader-pcsc.c:293:refresh_attributes: returning with: 0 (Success)
0xb7ad06c0 17:20:11.765 [opensc-pkcs11] reader-pcsc.c:369:pcsc_detect_card_presence: returning with: 1
0xb7ad06c0 17:20:11.765 [opensc-pkcs11] sc.c:200:sc_detect_card_presence: returning with: 1
0xb7ad06c0 17:20:11.765 [opensc-pkcs11] slot.c:188:card_detect: ACS ACR38U 00 00: Detecting smart card
0xb7ad06c0 17:20:11.766 [opensc-pkcs11] sc.c:195:sc_detect_card_presence: called
0xb7ad06c0 17:20:11.766 [opensc-pkcs11] reader-pcsc.c:364:pcsc_detect_card_presence: called
0xb7ad06c0 17:20:11.766 [opensc-pkcs11] reader-pcsc.c:277:refresh_attributes: ACS ACR38U 00 00 check
0xb7ad06c0 17:20:11.766 [opensc-pkcs11] reader-pcsc.c:293:refresh_attributes: returning with: 0 (Success)
0xb7ad06c0 17:20:11.766 [opensc-pkcs11] reader-pcsc.c:369:pcsc_detect_card_presence: returning with: 1
0xb7ad06c0 17:20:11.766 [opensc-pkcs11] sc.c:200:sc_detect_card_presence: returning with: 1
0xb7ad06c0 17:20:11.766 [opensc-pkcs11] slot.c:224:card_detect: ACS ACR38U 00 00: First seen the card
0xb7ad06c0 17:20:11.766 [opensc-pkcs11] slot.c:232:card_detect: ACS ACR38U 00 00: Connecting ...
0xb7ad06c0 17:20:11.766 [opensc-pkcs11] card.c:115:sc_connect_card: called
0xb7ad06c0 17:20:11.766 [opensc-pkcs11] reader-pcsc.c:444:pcsc_connect: called
0xb7ad06c0 17:20:11.766 [opensc-pkcs11] reader-pcsc.c:277:refresh_attributes: ACS ACR38U 00 00 check
0xb7ad06c0 17:20:11.766 [opensc-pkcs11] reader-pcsc.c:293:refresh_attributes: returning with: 0 (Success)
0xb7ad06c0 17:20:11.766 [opensc-pkcs11] reader-pcsc.c:473:pcsc_connect: Initial protocol: T=0
0xb7ad06c0 17:20:11.766 [opensc-pkcs11] card.c:829:match_atr_table: ATR     : 3b:9f:94:80:1f:c3:00:68:10:44:05:01:46:49:53:45:31:c8:07:90:00:18
0xb7ad06c0 17:20:11.766 [opensc-pkcs11] card.c:840:match_atr_table: ATR try : 3b:6e:00:ff:45:73:74:45:49:44:20:76:65:72:20:31:2e:30
0xb7ad06c0 17:20:11.766 [opensc-pkcs11] card.c:843:match_atr_table: ignored - wrong length
0xb7ad06c0 17:20:11.803 [opensc-pkcs11] card.c:840:match_atr_table: ATR try : 3b:9f:94:80:1f:c3:00:68:10:44:05:01:46:49:53:45:31:c8:07:90:00:18
0xb7ad06c0 17:20:11.803 [opensc-pkcs11] card.c:571:sc_select_file: called; type=1, path=a000000063504b43532d3135::
0xb7ad06c0 17:20:11.821 [opensc-pkcs11] apdu.c:525:sc_transmit_apdu: called
0xb7ad06c0 17:20:11.821 [opensc-pkcs11] card.c:292:sc_lock: called
0xb7ad06c0 17:20:11.821 [opensc-pkcs11] reader-pcsc.c:511:pcsc_lock: called
0xb7ad06c0 17:20:11.821 [opensc-pkcs11] reader-pcsc.c:243:pcsc_transmit: reader 'ACS ACR38U 00 00'
0xb7ad06c0 17:20:11.821 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Outgoing APDU data [   17 bytes] =====================================
00 A4 04 00 0C A0 00 00 00 63 50 4B 43 53 2D 31 .........cPKCS-1
35                                              5
======================================================================
0xb7ad06c0 17:20:11.821 [opensc-pkcs11] reader-pcsc.c:176:pcsc_internal_transmit: called
0xb7ad06c0 17:20:11.942 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Incoming APDU data [    2 bytes] =====================================
61 2D a-
======================================================================
0xb7ad06c0 17:20:11.942 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb7ad06c0 17:20:11.942 [opensc-pkcs11] reader-pcsc.c:548:pcsc_unlock: called
0xb7ad06c0 17:20:11.950 [opensc-pkcs11] iso7816.c:481:iso7816_select_file: returning with: 0 (Success)
0xb7ad06c0 17:20:11.950 [opensc-pkcs11] card.c:597:sc_select_file: returning with: 0 (Success)
0xb7ad06c0 17:20:11.950 [opensc-pkcs11] card.c:224:sc_connect_card: card info name:'SetCOS', type:6005, flags:0x0, max_send/recv_size:0/0
0xb7ad06c0 17:20:11.950 [opensc-pkcs11] card.c:225:sc_connect_card: returning with: 0 (Success)
0xb7ad06c0 17:20:11.950 [opensc-pkcs11] slot.c:240:card_detect: ACS ACR38U 00 00: Detecting Framework
0xb7ad06c0 17:20:11.950 [opensc-pkcs11] pkcs15.c:901:sc_pkcs15_bind: called
0xb7ad06c0 17:20:11.950 [opensc-pkcs11] pkcs15.c:902:sc_pkcs15_bind: application(aid:'empty')
0xb7ad06c0 17:20:11.950 [opensc-pkcs11] pkcs15.c:922:sc_pkcs15_bind: PKCS#15 options: use_file_cache=0 use_pin_cache=1 pin_cache_counter=10
0xb7ad06c0 17:20:11.950 [opensc-pkcs11] card.c:292:sc_lock: called
0xb7ad06c0 17:20:11.950 [opensc-pkcs11] reader-pcsc.c:511:pcsc_lock: called
0xb7ad06c0 17:20:11.950 [opensc-pkcs11] pkcs15.c:725:sc_pkcs15_bind_internal: called
0xb7ad06c0 17:20:11.950 [opensc-pkcs11] dir.c:141:sc_enum_apps: called
0xb7ad06c0 17:20:11.950 [opensc-pkcs11] card.c:571:sc_select_file: called; type=2, path=3f002f00
0xb7ad06c0 17:20:11.950 [opensc-pkcs11] apdu.c:525:sc_transmit_apdu: called
0xb7ad06c0 17:20:11.950 [opensc-pkcs11] card.c:292:sc_lock: called
0xb7ad06c0 17:20:11.950 [opensc-pkcs11] reader-pcsc.c:243:pcsc_transmit: reader 'ACS ACR38U 00 00'
0xb7ad06c0 17:20:11.950 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Outgoing APDU data [    7 bytes] =====================================
00 A4 08 00 02 2F 00 ...../.
======================================================================
0xb7ad06c0 17:20:11.950 [opensc-pkcs11] reader-pcsc.c:176:pcsc_internal_transmit: called
0xb7ad06c0 17:20:11.982 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Incoming APDU data [    2 bytes] =====================================
61 1A a.
======================================================================
0xb7ad06c0 17:20:11.982 [opensc-pkcs11] apdu.c:525:sc_transmit_apdu: called
0xb7ad06c0 17:20:11.982 [opensc-pkcs11] card.c:292:sc_lock: called
0xb7ad06c0 17:20:11.982 [opensc-pkcs11] reader-pcsc.c:243:pcsc_transmit: reader 'ACS ACR38U 00 00'
0xb7ad06c0 17:20:11.982 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Outgoing APDU data [    5 bytes] =====================================
00 C0 00 00 1A .....
======================================================================
0xb7ad06c0 17:20:11.982 [opensc-pkcs11] reader-pcsc.c:176:pcsc_internal_transmit: called
0xb7ad06c0 17:20:11.992 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Incoming APDU data [   28 bytes] =====================================
6F 18 81 02 00 6C 82 01 01 83 02 2F 00 86 08 01 o....l...../....
01 22 40 21 22 40 24 8A 01 07 90 00             ."@!"@$.....
======================================================================
0xb7ad06c0 17:20:11.992 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb7ad06c0 17:20:11.992 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb7ad06c0 17:20:11.992 [opensc-pkcs11] iso7816.c:320:iso7816_process_fci: processing FCI bytes
0xb7ad06c0 17:20:11.992 [opensc-pkcs11] iso7816.c:325:iso7816_process_fci:   file identifier: 0x2F00
0xb7ad06c0 17:20:11.992 [opensc-pkcs11] iso7816.c:339:iso7816_process_fci:   bytes in file: 108
0xb7ad06c0 17:20:11.992 [opensc-pkcs11] iso7816.c:351:iso7816_process_fci:   shareable: no
0xb7ad06c0 17:20:11.992 [opensc-pkcs11] iso7816.c:371:iso7816_process_fci:   type: working EF
0xb7ad06c0 17:20:11.992 [opensc-pkcs11] iso7816.c:373:iso7816_process_fci:   EF structure: 1
0xb7ad06c0 17:20:11.992 [opensc-pkcs11] card.c:597:sc_select_file: returning with: 0 (Success)
0xb7ad06c0 17:20:11.993 [opensc-pkcs11] card.c:422:sc_read_binary: called; 108 bytes at index 0
0xb7ad06c0 17:20:11.993 [opensc-pkcs11] apdu.c:525:sc_transmit_apdu: called
0xb7ad06c0 17:20:11.993 [opensc-pkcs11] card.c:292:sc_lock: called
0xb7ad06c0 17:20:11.993 [opensc-pkcs11] reader-pcsc.c:243:pcsc_transmit: reader 'ACS ACR38U 00 00'
0xb7ad06c0 17:20:11.993 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Outgoing APDU data [    5 bytes] =====================================
00 B0 00 00 6C ....l
======================================================================
0xb7ad06c0 17:20:11.993 [opensc-pkcs11] reader-pcsc.c:176:pcsc_internal_transmit: called
0xb7ad06c0 17:20:12.032 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Incoming APDU data [  110 bytes] =====================================
61 24 4F 0C A0 00 00 00 63 50 4B 43 53 2D 31 35 a$O.....cPKCS-15
50 0E 43 72 79 70 74 6F 43 61 72 64 20 50 4B 49 P.CryptoCard PKI
51 04 3F 00 50 15 61 28 4F 0C A0 00 00 00 63 50 Q.?.P.a(O.....cP
4B 43 53 2D 31 35 50 12 53 65 74 45 49 44 20 31 KCS-15P.SetEID 1
2E 30 20 50 4B 43 53 23 31 35 51 04 3F 00 DE 00 .0 PKCS#15Q.?...
61 1A 4F 06 D2 76 00 00 66 01 50 0A 53 65 74 45 a.O..v..f.P.SetE
49 44 20 31 2E 30 51 04 3F 00 DF 01 90 00       ID 1.0Q.?.....
======================================================================
0xb7ad06c0 17:20:12.032 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb7ad06c0 17:20:12.032 [opensc-pkcs11] iso7816.c:151:iso7816_read_binary: returning with: 108
0xb7ad06c0 17:20:12.032 [opensc-pkcs11] card.c:455:sc_read_binary: returning with: 108
0xb7ad06c0 17:20:12.032 [opensc-pkcs11] dir.c:229:sc_enum_apps: returning with: 0 (Success)
0xb7ad06c0 17:20:12.032 [opensc-pkcs11] pkcs15.c:743:sc_pkcs15_bind_internal: bind to application('SetEID 1.0 PKCS#15',aid:'A000000063504B43532D3135')
0xb7ad06c0 17:20:12.033 [opensc-pkcs11] pkcs15.c:762:sc_pkcs15_bind_internal: application path '3f00de00'
0xb7ad06c0 17:20:12.033 [opensc-pkcs11] card.c:571:sc_select_file: called; type=2, path=3f00de00
0xb7ad06c0 17:20:12.033 [opensc-pkcs11] apdu.c:525:sc_transmit_apdu: called
0xb7ad06c0 17:20:12.033 [opensc-pkcs11] card.c:292:sc_lock: called
0xb7ad06c0 17:20:12.033 [opensc-pkcs11] reader-pcsc.c:243:pcsc_transmit: reader 'ACS ACR38U 00 00'
0xb7ad06c0 17:20:12.033 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Outgoing APDU data [    7 bytes] =====================================
00 A4 08 00 02 DE 00 .......
======================================================================
0xb7ad06c0 17:20:12.033 [opensc-pkcs11] reader-pcsc.c:176:pcsc_internal_transmit: called
0xb7ad06c0 17:20:12.081 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Incoming APDU data [    2 bytes] =====================================
61 2D a-
======================================================================
0xb7ad06c0 17:20:12.081 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb7ad06c0 17:20:12.081 [opensc-pkcs11] iso7816.c:481:iso7816_select_file: returning with: 0 (Success)
0xb7ad06c0 17:20:12.081 [opensc-pkcs11] card.c:597:sc_select_file: returning with: 0 (Success)
0xb7ad06c0 17:20:12.081 [opensc-pkcs11] pkcs15.c:785:sc_pkcs15_bind_internal: absolute path to EF(ODF) 3f00de005031
0xb7ad06c0 17:20:12.081 [opensc-pkcs11] card.c:571:sc_select_file: called; type=2, path=3f00de005031
0xb7ad06c0 17:20:12.081 [opensc-pkcs11] apdu.c:525:sc_transmit_apdu: called
0xb7ad06c0 17:20:12.081 [opensc-pkcs11] card.c:292:sc_lock: called
0xb7ad06c0 17:20:12.081 [opensc-pkcs11] reader-pcsc.c:243:pcsc_transmit: reader 'ACS ACR38U 00 00'
0xb7ad06c0 17:20:12.081 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Outgoing APDU data [    9 bytes] =====================================
00 A4 08 00 04 DE 00 50 31 .......P1
======================================================================
0xb7ad06c0 17:20:12.082 [opensc-pkcs11] reader-pcsc.c:176:pcsc_internal_transmit: called
0xb7ad06c0 17:20:12.133 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Incoming APDU data [    2 bytes] =====================================
61 14 a.
======================================================================
0xb7ad06c0 17:20:12.133 [opensc-pkcs11] apdu.c:525:sc_transmit_apdu: called
0xb7ad06c0 17:20:12.133 [opensc-pkcs11] card.c:292:sc_lock: called
0xb7ad06c0 17:20:12.133 [opensc-pkcs11] reader-pcsc.c:243:pcsc_transmit: reader 'ACS ACR38U 00 00'
0xb7ad06c0 17:20:12.133 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Outgoing APDU data [    5 bytes] =====================================
00 C0 00 00 14 .....
======================================================================
0xb7ad06c0 17:20:12.133 [opensc-pkcs11] reader-pcsc.c:176:pcsc_internal_transmit: called
0xb7ad06c0 17:20:12.143 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Incoming APDU data [   22 bytes] =====================================
6F 12 81 02 00 24 82 01 01 83 02 50 31 86 02 01 o....$.....P1...
41 8A 01 07 90 00                               A.....
======================================================================
0xb7ad06c0 17:20:12.143 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb7ad06c0 17:20:12.143 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb7ad06c0 17:20:12.143 [opensc-pkcs11] iso7816.c:320:iso7816_process_fci: processing FCI bytes
0xb7ad06c0 17:20:12.143 [opensc-pkcs11] iso7816.c:325:iso7816_process_fci:   file identifier: 0x5031
0xb7ad06c0 17:20:12.143 [opensc-pkcs11] iso7816.c:339:iso7816_process_fci:   bytes in file: 36
0xb7ad06c0 17:20:12.143 [opensc-pkcs11] iso7816.c:351:iso7816_process_fci:   shareable: no
0xb7ad06c0 17:20:12.143 [opensc-pkcs11] iso7816.c:371:iso7816_process_fci:   type: working EF
0xb7ad06c0 17:20:12.143 [opensc-pkcs11] iso7816.c:373:iso7816_process_fci:   EF structure: 1
0xb7ad06c0 17:20:12.143 [opensc-pkcs11] card.c:597:sc_select_file: returning with: 0 (Success)
0xb7ad06c0 17:20:12.144 [opensc-pkcs11] card.c:422:sc_read_binary: called; 36 bytes at index 0
0xb7ad06c0 17:20:12.144 [opensc-pkcs11] apdu.c:525:sc_transmit_apdu: called
0xb7ad06c0 17:20:12.144 [opensc-pkcs11] card.c:292:sc_lock: called
0xb7ad06c0 17:20:12.144 [opensc-pkcs11] reader-pcsc.c:243:pcsc_transmit: reader 'ACS ACR38U 00 00'
0xb7ad06c0 17:20:12.144 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Outgoing APDU data [    5 bytes] =====================================
00 B0 00 00 24 ....$
======================================================================
0xb7ad06c0 17:20:12.144 [opensc-pkcs11] reader-pcsc.c:176:pcsc_internal_transmit: called
0xb7ad06c0 17:20:12.168 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Incoming APDU data [   38 bytes] =====================================
A0 0A 30 08 04 06 3F 00 DE 00 53 01 A4 0A 30 08 ..0...?...S...0.
04 06 3F 00 DE 00 53 03 A8 0A 30 08 04 06 3F 00 ..?...S...0...?.
DE 00 53 06 90 00                               ..S...
======================================================================
0xb7ad06c0 17:20:12.168 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb7ad06c0 17:20:12.168 [opensc-pkcs11] iso7816.c:151:iso7816_read_binary: returning with: 36
0xb7ad06c0 17:20:12.168 [opensc-pkcs11] card.c:455:sc_read_binary: returning with: 36
0xb7ad06c0 17:20:12.168 [opensc-pkcs11] pkcs15.c:825:sc_pkcs15_bind_internal: The following DFs were found:
0xb7ad06c0 17:20:12.168 [opensc-pkcs11] pkcs15.c:828:sc_pkcs15_bind_internal:   DF type 0, path 3f00de005301, index 0, count -1
0xb7ad06c0 17:20:12.168 [opensc-pkcs11] pkcs15.c:828:sc_pkcs15_bind_internal:   DF type 4, path 3f00de005303, index 0, count -1
0xb7ad06c0 17:20:12.168 [opensc-pkcs11] pkcs15.c:828:sc_pkcs15_bind_internal:   DF type 8, path 3f00de005306, index 0, count -1
0xb7ad06c0 17:20:12.169 [opensc-pkcs11] pkcs15.c:837:sc_pkcs15_bind_internal: absolute path to EF(TokenInfo) 3f00de005032
0xb7ad06c0 17:20:12.169 [opensc-pkcs11] card.c:571:sc_select_file: called; type=2, path=3f00de005032
0xb7ad06c0 17:20:12.169 [opensc-pkcs11] apdu.c:525:sc_transmit_apdu: called
0xb7ad06c0 17:20:12.169 [opensc-pkcs11] card.c:292:sc_lock: called
0xb7ad06c0 17:20:12.169 [opensc-pkcs11] reader-pcsc.c:243:pcsc_transmit: reader 'ACS ACR38U 00 00'
0xb7ad06c0 17:20:12.169 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Outgoing APDU data [    9 bytes] =====================================
00 A4 08 00 04 DE 00 50 32 .......P2
======================================================================
0xb7ad06c0 17:20:12.169 [opensc-pkcs11] reader-pcsc.c:176:pcsc_internal_transmit: called
0xb7ad06c0 17:20:12.218 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Incoming APDU data [    2 bytes] =====================================
61 14 a.
======================================================================
0xb7ad06c0 17:20:12.218 [opensc-pkcs11] apdu.c:525:sc_transmit_apdu: called
0xb7ad06c0 17:20:12.218 [opensc-pkcs11] card.c:292:sc_lock: called
0xb7ad06c0 17:20:12.218 [opensc-pkcs11] reader-pcsc.c:243:pcsc_transmit: reader 'ACS ACR38U 00 00'
0xb7ad06c0 17:20:12.218 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Outgoing APDU data [    5 bytes] =====================================
00 C0 00 00 14 .....
======================================================================
0xb7ad06c0 17:20:12.218 [opensc-pkcs11] reader-pcsc.c:176:pcsc_internal_transmit: called
0xb7ad06c0 17:20:12.227 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Incoming APDU data [   22 bytes] =====================================
6F 12 81 02 00 3D 82 01 01 83 02 50 32 86 02 01 o....=.....P2...
41 8A 01 07 90 00                               A.....
======================================================================
0xb7ad06c0 17:20:12.227 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb7ad06c0 17:20:12.227 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb7ad06c0 17:20:12.227 [opensc-pkcs11] iso7816.c:320:iso7816_process_fci: processing FCI bytes
0xb7ad06c0 17:20:12.227 [opensc-pkcs11] iso7816.c:325:iso7816_process_fci:   file identifier: 0x5032
0xb7ad06c0 17:20:12.227 [opensc-pkcs11] iso7816.c:339:iso7816_process_fci:   bytes in file: 61
0xb7ad06c0 17:20:12.227 [opensc-pkcs11] iso7816.c:351:iso7816_process_fci:   shareable: no
0xb7ad06c0 17:20:12.227 [opensc-pkcs11] iso7816.c:371:iso7816_process_fci:   type: working EF
0xb7ad06c0 17:20:12.227 [opensc-pkcs11] iso7816.c:373:iso7816_process_fci:   EF structure: 1
0xb7ad06c0 17:20:12.228 [opensc-pkcs11] card.c:597:sc_select_file: returning with: 0 (Success)
0xb7ad06c0 17:20:12.228 [opensc-pkcs11] card.c:422:sc_read_binary: called; 61 bytes at index 0
0xb7ad06c0 17:20:12.228 [opensc-pkcs11] apdu.c:525:sc_transmit_apdu: called
0xb7ad06c0 17:20:12.228 [opensc-pkcs11] card.c:292:sc_lock: called
0xb7ad06c0 17:20:12.228 [opensc-pkcs11] reader-pcsc.c:243:pcsc_transmit: reader 'ACS ACR38U 00 00'
0xb7ad06c0 17:20:12.228 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Outgoing APDU data [    5 bytes] =====================================
00 B0 00 00 3D ....=
======================================================================
0xb7ad06c0 17:20:12.228 [opensc-pkcs11] reader-pcsc.c:176:pcsc_internal_transmit: called
0xb7ad06c0 17:20:12.257 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Incoming APDU data [   63 bytes] =====================================
30 3B 02 01 00 04 04 04 20 86 80 0C 0A 43 72 79 0;...... ....Cry
70 74 6F 54 65 63 68 A0 08 0C 06 53 65 74 45 49 ptoTech....SetEI
44 03 02 04 60 05 00 A1 02 05 00 A2 02 05 00 A3 D...`...........
02 05 00 A4 02 05 00 A5 02 05 00 05 00 90 00    ...............
======================================================================
0xb7ad06c0 17:20:12.257 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb7ad06c0 17:20:12.257 [opensc-pkcs11] iso7816.c:151:iso7816_read_binary: returning with: 61
0xb7ad06c0 17:20:12.257 [opensc-pkcs11] card.c:455:sc_read_binary: returning with: 61
0xb7ad06c0 17:20:12.257 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb7ad06c0 17:20:12.258 [opensc-pkcs11] reader-pcsc.c:548:pcsc_unlock: called
0xb7ad06c0 17:20:12.262 [opensc-pkcs11] pkcs15.c:959:sc_pkcs15_bind: returning with: 0 (Success)
0xb7ad06c0 17:20:12.262 [opensc-pkcs11] slot.c:254:card_detect: ACS ACR38U 00 00: Detected framework 0. Creating tokens.
0xb7ad06c0 17:20:12.262 [opensc-pkcs11] pkcs15.c:1603:sc_pkcs15_parse_df: called; path=3f00de005306, type=8, enum=0
0xb7ad06c0 17:20:12.262 [opensc-pkcs11] pkcs15.c:1865:sc_pkcs15_read_file: called; path=3f00de005306, index=0, count=-1
0xb7ad06c0 17:20:12.262 [opensc-pkcs11] card.c:292:sc_lock: called
0xb7ad06c0 17:20:12.262 [opensc-pkcs11] reader-pcsc.c:511:pcsc_lock: called
0xb7ad06c0 17:20:12.262 [opensc-pkcs11] card.c:571:sc_select_file: called; type=2, path=3f00de005306
0xb7ad06c0 17:20:12.262 [opensc-pkcs11] apdu.c:525:sc_transmit_apdu: called
0xb7ad06c0 17:20:12.262 [opensc-pkcs11] card.c:292:sc_lock: called
0xb7ad06c0 17:20:12.262 [opensc-pkcs11] reader-pcsc.c:243:pcsc_transmit: reader 'ACS ACR38U 00 00'
0xb7ad06c0 17:20:12.262 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Outgoing APDU data [    9 bytes] =====================================
00 A4 08 00 04 DE 00 53 06 .......S.
======================================================================
0xb7ad06c0 17:20:12.262 [opensc-pkcs11] reader-pcsc.c:176:pcsc_internal_transmit: called
0xb7ad06c0 17:20:12.315 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Incoming APDU data [    2 bytes] =====================================
61 14 a.
======================================================================
0xb7ad06c0 17:20:12.315 [opensc-pkcs11] apdu.c:525:sc_transmit_apdu: called
0xb7ad06c0 17:20:12.315 [opensc-pkcs11] card.c:292:sc_lock: called
0xb7ad06c0 17:20:12.316 [opensc-pkcs11] reader-pcsc.c:243:pcsc_transmit: reader 'ACS ACR38U 00 00'
0xb7ad06c0 17:20:12.316 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Outgoing APDU data [    5 bytes] =====================================
00 C0 00 00 14 .....
======================================================================
0xb7ad06c0 17:20:12.316 [opensc-pkcs11] reader-pcsc.c:176:pcsc_internal_transmit: called
0xb7ad06c0 17:20:12.324 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Incoming APDU data [   22 bytes] =====================================
6F 12 81 02 00 34 82 01 01 83 02 53 06 86 02 01 o....4.....S....
41 8A 01 07 90 00                               A.....
======================================================================
_______________________________________________
opensc-devel mailing list
[hidden email]
http://www.opensc-project.org/mailman/listinfo/opensc-devel
Reply | Threaded
Open this post in threaded view
|

Re: Cryptotech Setcos card signing problem

TMS Brokers / Łukasz Kościesza
In reply to this post by Viktor Tarasov-3
0xb7ad06c0 17:20:12.324 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb7ad06c0 17:20:12.324 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb7ad06c0 17:20:12.324 [opensc-pkcs11] iso7816.c:320:iso7816_process_fci: processing FCI bytes
0xb7ad06c0 17:20:12.324 [opensc-pkcs11] iso7816.c:325:iso7816_process_fci:   file identifier: 0x5306
0xb7ad06c0 17:20:12.324 [opensc-pkcs11] iso7816.c:339:iso7816_process_fci:   bytes in file: 52
0xb7ad06c0 17:20:12.324 [opensc-pkcs11] iso7816.c:351:iso7816_process_fci:   shareable: no
0xb7ad06c0 17:20:12.324 [opensc-pkcs11] iso7816.c:371:iso7816_process_fci:   type: working EF
0xb7ad06c0 17:20:12.324 [opensc-pkcs11] iso7816.c:373:iso7816_process_fci:   EF structure: 1
0xb7ad06c0 17:20:12.325 [opensc-pkcs11] card.c:597:sc_select_file: returning with: 0 (Success)
0xb7ad06c0 17:20:12.325 [opensc-pkcs11] card.c:422:sc_read_binary: called; 52 bytes at index 0
0xb7ad06c0 17:20:12.325 [opensc-pkcs11] apdu.c:525:sc_transmit_apdu: called
0xb7ad06c0 17:20:12.325 [opensc-pkcs11] card.c:292:sc_lock: called
0xb7ad06c0 17:20:12.325 [opensc-pkcs11] reader-pcsc.c:243:pcsc_transmit: reader 'ACS ACR38U 00 00'
0xb7ad06c0 17:20:12.325 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Outgoing APDU data [    5 bytes] =====================================
00 B0 00 00 34 ....4
======================================================================
0xb7ad06c0 17:20:12.325 [opensc-pkcs11] reader-pcsc.c:176:pcsc_internal_transmit: called
0xb7ad06c0 17:20:12.352 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Incoming APDU data [   54 bytes] =====================================
30 32 30 0C 0C 0A 53 65 74 45 49 44 20 50 49 4E 020...SetEID PIN
30 03 04 01 04 A1 1D 30 1B 03 02 02 0C 0A 01 01 0......0........
02 01 04 02 01 08 80 01 81 04 01 00 30 06 04 04 ............0...
3F 00 DF 01 90 00                               ?.....
======================================================================
0xb7ad06c0 17:20:12.352 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb7ad06c0 17:20:12.352 [opensc-pkcs11] iso7816.c:151:iso7816_read_binary: returning with: 52
0xb7ad06c0 17:20:12.352 [opensc-pkcs11] card.c:455:sc_read_binary: returning with: 52
0xb7ad06c0 17:20:12.352 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb7ad06c0 17:20:12.352 [opensc-pkcs11] reader-pcsc.c:548:pcsc_unlock: called
0xb7ad06c0 17:20:12.358 [opensc-pkcs11] pkcs15.c:1944:sc_pkcs15_read_file: returning with: 0 (Success)
0xb7ad06c0 17:20:12.358 [opensc-pkcs11] pkcs15.c:1640:sc_pkcs15_parse_df: bufsize 52; first tag 0x30
0xb7ad06c0 17:20:12.358 [opensc-pkcs11] pkcs15.c:1649:sc_pkcs15_parse_df: rv 0
0xb7ad06c0 17:20:12.358 [opensc-pkcs11] pkcs15.c:1676:sc_pkcs15_parse_df: returning with: 0 (Success)
0xb7ad06c0 17:20:12.358 [opensc-pkcs11] framework-pkcs15.c:885:pkcs15_create_tokens: Found 1 authentication objects
0xb7ad06c0 17:20:12.358 [opensc-pkcs11] pkcs15.c:1603:sc_pkcs15_parse_df: called; path=3f00de005301, type=0, enum=0
0xb7ad06c0 17:20:12.358 [opensc-pkcs11] pkcs15.c:1865:sc_pkcs15_read_file: called; path=3f00de005301, index=0, count=-1
0xb7ad06c0 17:20:12.358 [opensc-pkcs11] card.c:292:sc_lock: called
0xb7ad06c0 17:20:12.358 [opensc-pkcs11] reader-pcsc.c:511:pcsc_lock: called
0xb7ad06c0 17:20:12.358 [opensc-pkcs11] card.c:571:sc_select_file: called; type=2, path=3f00de005301
0xb7ad06c0 17:20:12.358 [opensc-pkcs11] apdu.c:525:sc_transmit_apdu: called
0xb7ad06c0 17:20:12.358 [opensc-pkcs11] card.c:292:sc_lock: called
0xb7ad06c0 17:20:12.358 [opensc-pkcs11] reader-pcsc.c:243:pcsc_transmit: reader 'ACS ACR38U 00 00'
0xb7ad06c0 17:20:12.358 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Outgoing APDU data [    9 bytes] =====================================
00 A4 08 00 04 DE 00 53 01 .......S.
======================================================================
0xb7ad06c0 17:20:12.358 [opensc-pkcs11] reader-pcsc.c:176:pcsc_internal_transmit: called
0xb7ad06c0 17:20:12.414 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Incoming APDU data [    2 bytes] =====================================
61 14 a.
======================================================================
0xb7ad06c0 17:20:12.414 [opensc-pkcs11] apdu.c:525:sc_transmit_apdu: called
0xb7ad06c0 17:20:12.414 [opensc-pkcs11] card.c:292:sc_lock: called
0xb7ad06c0 17:20:12.414 [opensc-pkcs11] reader-pcsc.c:243:pcsc_transmit: reader 'ACS ACR38U 00 00'
0xb7ad06c0 17:20:12.414 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Outgoing APDU data [    5 bytes] =====================================
00 C0 00 00 14 .....
======================================================================
0xb7ad06c0 17:20:12.414 [opensc-pkcs11] reader-pcsc.c:176:pcsc_internal_transmit: called
0xb7ad06c0 17:20:12.423 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Incoming APDU data [   22 bytes] =====================================
6F 12 81 02 00 4D 82 01 01 83 02 53 01 86 02 01 o....M.....S....
41 8A 01 07 90 00                               A.....
======================================================================
0xb7ad06c0 17:20:12.423 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb7ad06c0 17:20:12.423 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb7ad06c0 17:20:12.423 [opensc-pkcs11] iso7816.c:320:iso7816_process_fci: processing FCI bytes
0xb7ad06c0 17:20:12.423 [opensc-pkcs11] iso7816.c:325:iso7816_process_fci:   file identifier: 0x5301
0xb7ad06c0 17:20:12.423 [opensc-pkcs11] iso7816.c:339:iso7816_process_fci:   bytes in file: 77
0xb7ad06c0 17:20:12.423 [opensc-pkcs11] iso7816.c:351:iso7816_process_fci:   shareable: no
0xb7ad06c0 17:20:12.423 [opensc-pkcs11] iso7816.c:371:iso7816_process_fci:   type: working EF
0xb7ad06c0 17:20:12.423 [opensc-pkcs11] iso7816.c:373:iso7816_process_fci:   EF structure: 1
0xb7ad06c0 17:20:12.424 [opensc-pkcs11] card.c:597:sc_select_file: returning with: 0 (Success)
0xb7ad06c0 17:20:12.424 [opensc-pkcs11] card.c:422:sc_read_binary: called; 77 bytes at index 0
0xb7ad06c0 17:20:12.424 [opensc-pkcs11] apdu.c:525:sc_transmit_apdu: called
0xb7ad06c0 17:20:12.424 [opensc-pkcs11] card.c:292:sc_lock: called
0xb7ad06c0 17:20:12.424 [opensc-pkcs11] reader-pcsc.c:243:pcsc_transmit: reader 'ACS ACR38U 00 00'
0xb7ad06c0 17:20:12.424 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Outgoing APDU data [    5 bytes] =====================================
00 B0 00 00 4D ....M
======================================================================
0xb7ad06c0 17:20:12.424 [opensc-pkcs11] reader-pcsc.c:176:pcsc_internal_transmit: called
0xb7ad06c0 17:20:12.456 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Incoming APDU data [   79 bytes] =====================================
30 4B 30 09 0C 00 03 02 03 C0 04 01 01 30 26 04 0K0..........0&.
14 8D 2E 44 64 7F 68 5C E8 5E 09 D0 2A 72 3B 67 ...Dd.h\.^..*r;g
3D C3 66 64 39 03 03 06 64 00 01 01 FF 03 02 03 =.fd9...d.......
A8 03 02 03 A8 A0 04 30 02 30 00 A1 10 30 0E 30 .......0.0...0.0
08 04 06 3F 00 DF 01 EF D5 02 02 04 00 90 00    ...?...........
======================================================================
0xb7ad06c0 17:20:12.456 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb7ad06c0 17:20:12.456 [opensc-pkcs11] iso7816.c:151:iso7816_read_binary: returning with: 77
0xb7ad06c0 17:20:12.456 [opensc-pkcs11] card.c:455:sc_read_binary: returning with: 77
0xb7ad06c0 17:20:12.456 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb7ad06c0 17:20:12.456 [opensc-pkcs11] reader-pcsc.c:548:pcsc_unlock: called
0xb7ad06c0 17:20:12.466 [opensc-pkcs11] pkcs15.c:1944:sc_pkcs15_read_file: returning with: 0 (Success)
0xb7ad06c0 17:20:12.466 [opensc-pkcs11] pkcs15.c:1640:sc_pkcs15_parse_df: bufsize 77; first tag 0x30
0xb7ad06c0 17:20:12.466 [opensc-pkcs11] pkcs15.c:1649:sc_pkcs15_parse_df: rv 0
0xb7ad06c0 17:20:12.466 [opensc-pkcs11] pkcs15.c:1676:sc_pkcs15_parse_df: returning with: 0 (Success)
0xb7ad06c0 17:20:12.466 [opensc-pkcs11] framework-pkcs15.c:576:pkcs15_create_pkcs11_objects: Found 1 RSA private key
0xb7ad06c0 17:20:12.466 [opensc-pkcs11] framework-pkcs15.c:576:pkcs15_create_pkcs11_objects: Found 0 RSA public keys
0xb7ad06c0 17:20:12.466 [opensc-pkcs11] framework-pkcs15.c:576:pkcs15_create_pkcs11_objects: Found 0 EC private keys
0xb7ad06c0 17:20:12.466 [opensc-pkcs11] framework-pkcs15.c:576:pkcs15_create_pkcs11_objects: Found 0 EC public keys
0xb7ad06c0 17:20:12.466 [opensc-pkcs11] framework-pkcs15.c:576:pkcs15_create_pkcs11_objects: Found 0 GOSTR3410 private keys
0xb7ad06c0 17:20:12.466 [opensc-pkcs11] framework-pkcs15.c:576:pkcs15_create_pkcs11_objects: Found 0 GOSTR3410 public keys
0xb7ad06c0 17:20:12.466 [opensc-pkcs11] pkcs15.c:1603:sc_pkcs15_parse_df: called; path=3f00de005303, type=4, enum=0
0xb7ad06c0 17:20:12.466 [opensc-pkcs11] pkcs15.c:1865:sc_pkcs15_read_file: called; path=3f00de005303, index=0, count=-1
0xb7ad06c0 17:20:12.466 [opensc-pkcs11] card.c:292:sc_lock: called
0xb7ad06c0 17:20:12.466 [opensc-pkcs11] reader-pcsc.c:511:pcsc_lock: called
0xb7ad06c0 17:20:12.466 [opensc-pkcs11] card.c:571:sc_select_file: called; type=2, path=3f00de005303
0xb7ad06c0 17:20:12.466 [opensc-pkcs11] apdu.c:525:sc_transmit_apdu: called
0xb7ad06c0 17:20:12.466 [opensc-pkcs11] card.c:292:sc_lock: called
0xb7ad06c0 17:20:12.466 [opensc-pkcs11] reader-pcsc.c:243:pcsc_transmit: reader 'ACS ACR38U 00 00'
0xb7ad06c0 17:20:12.466 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Outgoing APDU data [    9 bytes] =====================================
00 A4 08 00 04 DE 00 53 03 .......S.
======================================================================
0xb7ad06c0 17:20:12.466 [opensc-pkcs11] reader-pcsc.c:176:pcsc_internal_transmit: called
0xb7ad06c0 17:20:12.525 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Incoming APDU data [    2 bytes] =====================================
61 14 a.
======================================================================
0xb7ad06c0 17:20:12.525 [opensc-pkcs11] apdu.c:525:sc_transmit_apdu: called
0xb7ad06c0 17:20:12.525 [opensc-pkcs11] card.c:292:sc_lock: called
0xb7ad06c0 17:20:12.525 [opensc-pkcs11] reader-pcsc.c:243:pcsc_transmit: reader 'ACS ACR38U 00 00'
0xb7ad06c0 17:20:12.525 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Outgoing APDU data [    5 bytes] =====================================
00 C0 00 00 14 .....
======================================================================
0xb7ad06c0 17:20:12.525 [opensc-pkcs11] reader-pcsc.c:176:pcsc_internal_transmit: called
0xb7ad06c0 17:20:12.534 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Incoming APDU data [   22 bytes] =====================================
6F 12 81 02 00 6C 82 01 01 83 02 53 03 86 02 01 o....l.....S....
41 8A 01 07 90 00                               A.....
======================================================================
0xb7ad06c0 17:20:12.534 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb7ad06c0 17:20:12.534 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb7ad06c0 17:20:12.534 [opensc-pkcs11] iso7816.c:320:iso7816_process_fci: processing FCI bytes
0xb7ad06c0 17:20:12.534 [opensc-pkcs11] iso7816.c:325:iso7816_process_fci:   file identifier: 0x5303
0xb7ad06c0 17:20:12.534 [opensc-pkcs11] iso7816.c:339:iso7816_process_fci:   bytes in file: 108
0xb7ad06c0 17:20:12.534 [opensc-pkcs11] iso7816.c:351:iso7816_process_fci:   shareable: no
0xb7ad06c0 17:20:12.535 [opensc-pkcs11] iso7816.c:371:iso7816_process_fci:   type: working EF
0xb7ad06c0 17:20:12.535 [opensc-pkcs11] iso7816.c:373:iso7816_process_fci:   EF structure: 1
0xb7ad06c0 17:20:12.535 [opensc-pkcs11] card.c:597:sc_select_file: returning with: 0 (Success)
0xb7ad06c0 17:20:12.535 [opensc-pkcs11] card.c:422:sc_read_binary: called; 108 bytes at index 0
0xb7ad06c0 17:20:12.535 [opensc-pkcs11] apdu.c:525:sc_transmit_apdu: called
0xb7ad06c0 17:20:12.535 [opensc-pkcs11] card.c:292:sc_lock: called
0xb7ad06c0 17:20:12.535 [opensc-pkcs11] reader-pcsc.c:243:pcsc_transmit: reader 'ACS ACR38U 00 00'
0xb7ad06c0 17:20:12.535 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Outgoing APDU data [    5 bytes] =====================================
00 B0 00 00 6C ....l
======================================================================
0xb7ad06c0 17:20:12.535 [opensc-pkcs11] reader-pcsc.c:176:pcsc_internal_transmit: called
0xb7ad06c0 17:20:12.574 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Incoming APDU data [  110 bytes] =====================================
30 34 30 06 0C 00 03 02 03 40 30 16 04 14 8D 2E 040......@0.....
44 64 7F 68 5C E8 5E 09 D0 2A 72 3B 67 3D C3 66 Dd.h\.^..*r;g=.f
64 39 A1 12 30 10 30 08 04 06 3F 00 DE 00 C0 01 d9..0.0...?.....
30 00 A0 02 30 00 30 34 30 06 0C 00 03 02 03 40 0...0.040......@
30 16 04 14 8D 2E 44 64 7F 68 5C E8 5E 09 D0 2A 0.....Dd.h\.^..*
72 3B 67 3D C3 66 64 39 A1 12 30 10 30 08 04 06 r;g=.fd9..0.0...
3F 00 DE 00 C0 01 30 00 A0 02 30 00 90 00       ?.....0...0...
======================================================================
0xb7ad06c0 17:20:12.574 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb7ad06c0 17:20:12.574 [opensc-pkcs11] iso7816.c:151:iso7816_read_binary: returning with: 108
0xb7ad06c0 17:20:12.574 [opensc-pkcs11] card.c:455:sc_read_binary: returning with: 108
0xb7ad06c0 17:20:12.574 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb7ad06c0 17:20:12.575 [opensc-pkcs11] reader-pcsc.c:548:pcsc_unlock: called
0xb7ad06c0 17:20:12.582 [opensc-pkcs11] pkcs15.c:1944:sc_pkcs15_read_file: returning with: 0 (Success)
0xb7ad06c0 17:20:12.582 [opensc-pkcs11] pkcs15.c:1640:sc_pkcs15_parse_df: bufsize 108; first tag 0x30
0xb7ad06c0 17:20:12.582 [opensc-pkcs11] pkcs15.c:1649:sc_pkcs15_parse_df: rv 0
0xb7ad06c0 17:20:12.582 [opensc-pkcs11] pkcs15.c:1649:sc_pkcs15_parse_df: rv 0
0xb7ad06c0 17:20:12.582 [opensc-pkcs11] pkcs15.c:1676:sc_pkcs15_parse_df: returning with: 0 (Success)
0xb7ad06c0 17:20:12.582 [opensc-pkcs11] framework-pkcs15.c:576:pkcs15_create_pkcs11_objects: Found 2 certificates
0xb7ad06c0 17:20:12.582 [opensc-pkcs11] pkcs15-cert.c:147:sc_pkcs15_read_certificate: called
0xb7ad06c0 17:20:12.582 [opensc-pkcs11] pkcs15.c:1865:sc_pkcs15_read_file: called; path=3f00de00c001, index=0, count=-1
0xb7ad06c0 17:20:12.582 [opensc-pkcs11] card.c:292:sc_lock: called
0xb7ad06c0 17:20:12.582 [opensc-pkcs11] reader-pcsc.c:511:pcsc_lock: called
0xb7ad06c0 17:20:12.582 [opensc-pkcs11] card.c:571:sc_select_file: called; type=2, path=3f00de00c001
0xb7ad06c0 17:20:12.582 [opensc-pkcs11] apdu.c:525:sc_transmit_apdu: called
0xb7ad06c0 17:20:12.582 [opensc-pkcs11] card.c:292:sc_lock: called
0xb7ad06c0 17:20:12.582 [opensc-pkcs11] reader-pcsc.c:243:pcsc_transmit: reader 'ACS ACR38U 00 00'
0xb7ad06c0 17:20:12.582 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Outgoing APDU data [    9 bytes] =====================================
00 A4 08 00 04 DE 00 C0 01 .........
======================================================================
0xb7ad06c0 17:20:12.582 [opensc-pkcs11] reader-pcsc.c:176:pcsc_internal_transmit: called
0xb7ad06c0 17:20:12.647 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Incoming APDU data [    2 bytes] =====================================
61 14 a.
======================================================================
0xb7ad06c0 17:20:12.647 [opensc-pkcs11] apdu.c:525:sc_transmit_apdu: called
0xb7ad06c0 17:20:12.647 [opensc-pkcs11] card.c:292:sc_lock: called
0xb7ad06c0 17:20:12.648 [opensc-pkcs11] reader-pcsc.c:243:pcsc_transmit: reader 'ACS ACR38U 00 00'
0xb7ad06c0 17:20:12.648 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Outgoing APDU data [    5 bytes] =====================================
00 C0 00 00 14 .....
======================================================================
0xb7ad06c0 17:20:12.648 [opensc-pkcs11] reader-pcsc.c:176:pcsc_internal_transmit: called
0xb7ad06c0 17:20:12.656 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Incoming APDU data [   22 bytes] =====================================
6F 12 81 02 05 B0 82 01 01 83 02 C0 01 86 02 01 o...............
41 8A 01 07 90 00                               A.....
======================================================================
0xb7ad06c0 17:20:12.656 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb7ad06c0 17:20:12.656 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb7ad06c0 17:20:12.656 [opensc-pkcs11] iso7816.c:320:iso7816_process_fci: processing FCI bytes
0xb7ad06c0 17:20:12.656 [opensc-pkcs11] iso7816.c:325:iso7816_process_fci:   file identifier: 0xC001
0xb7ad06c0 17:20:12.656 [opensc-pkcs11] iso7816.c:339:iso7816_process_fci:   bytes in file: 1456
0xb7ad06c0 17:20:12.656 [opensc-pkcs11] iso7816.c:351:iso7816_process_fci:   shareable: no
0xb7ad06c0 17:20:12.657 [opensc-pkcs11] iso7816.c:371:iso7816_process_fci:   type: working EF
0xb7ad06c0 17:20:12.657 [opensc-pkcs11] iso7816.c:373:iso7816_process_fci:   EF structure: 1
0xb7ad06c0 17:20:12.657 [opensc-pkcs11] card.c:597:sc_select_file: returning with: 0 (Success)
0xb7ad06c0 17:20:12.657 [opensc-pkcs11] card.c:422:sc_read_binary: called; 1456 bytes at index 0
0xb7ad06c0 17:20:12.657 [opensc-pkcs11] card.c:292:sc_lock: called
0xb7ad06c0 17:20:12.657 [opensc-pkcs11] card.c:422:sc_read_binary: called; 256 bytes at index 0
0xb7ad06c0 17:20:12.657 [opensc-pkcs11] apdu.c:525:sc_transmit_apdu: called
0xb7ad06c0 17:20:12.657 [opensc-pkcs11] card.c:292:sc_lock: called
0xb7ad06c0 17:20:12.657 [opensc-pkcs11] reader-pcsc.c:243:pcsc_transmit: reader 'ACS ACR38U 00 00'
0xb7ad06c0 17:20:12.657 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Outgoing APDU data [    5 bytes] =====================================
00 B0 00 00 00 .....
======================================================================
0xb7ad06c0 17:20:12.657 [opensc-pkcs11] reader-pcsc.c:176:pcsc_internal_transmit: called
0xb7ad06c0 17:20:12.732 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Incoming APDU data [  258 bytes] =====================================
<SWIPPED OFF CERTIFICATE DATA>
90 00                                           ..
======================================================================
0xb7ad06c0 17:20:12.732 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb7ad06c0 17:20:12.732 [opensc-pkcs11] iso7816.c:151:iso7816_read_binary: returning with: 256
0xb7ad06c0 17:20:12.732 [opensc-pkcs11] card.c:455:sc_read_binary: returning with: 256
0xb7ad06c0 17:20:12.732 [opensc-pkcs11] card.c:422:sc_read_binary: called; 256 bytes at index 256
0xb7ad06c0 17:20:12.732 [opensc-pkcs11] apdu.c:525:sc_transmit_apdu: called
0xb7ad06c0 17:20:12.732 [opensc-pkcs11] card.c:292:sc_lock: called
0xb7ad06c0 17:20:12.732 [opensc-pkcs11] reader-pcsc.c:243:pcsc_transmit: reader 'ACS ACR38U 00 00'
0xb7ad06c0 17:20:12.732 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Outgoing APDU data [    5 bytes] =====================================
00 B0 01 00 00 .....
======================================================================
0xb7ad06c0 17:20:12.732 [opensc-pkcs11] reader-pcsc.c:176:pcsc_internal_transmit: called
0xb7ad06c0 17:20:12.806 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Incoming APDU data [  258 bytes] =====================================
<SWIPPED OFF CERTIFICATE DATA>
90 00                                           ..
======================================================================
0xb7ad06c0 17:20:12.807 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb7ad06c0 17:20:12.807 [opensc-pkcs11] iso7816.c:151:iso7816_read_binary: returning with: 256
0xb7ad06c0 17:20:12.807 [opensc-pkcs11] card.c:455:sc_read_binary: returning with: 256
0xb7ad06c0 17:20:12.807 [opensc-pkcs11] card.c:422:sc_read_binary: called; 256 bytes at index 512
0xb7ad06c0 17:20:12.807 [opensc-pkcs11] apdu.c:525:sc_transmit_apdu: called
0xb7ad06c0 17:20:12.807 [opensc-pkcs11] card.c:292:sc_lock: called
0xb7ad06c0 17:20:12.807 [opensc-pkcs11] reader-pcsc.c:243:pcsc_transmit: reader 'ACS ACR38U 00 00'
0xb7ad06c0 17:20:12.807 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Outgoing APDU data [    5 bytes] =====================================
00 B0 02 00 00 .....
======================================================================
0xb7ad06c0 17:20:12.807 [opensc-pkcs11] reader-pcsc.c:176:pcsc_internal_transmit: called
0xb7ad06c0 17:20:12.881 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Incoming APDU data [  258 bytes] =====================================
<SWIPPED OFF CERTIFICATE DATA>
90 00                                           ..
======================================================================
0xb7ad06c0 17:20:12.882 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb7ad06c0 17:20:12.882 [opensc-pkcs11] iso7816.c:151:iso7816_read_binary: returning with: 256
0xb7ad06c0 17:20:12.882 [opensc-pkcs11] card.c:455:sc_read_binary: returning with: 256
0xb7ad06c0 17:20:12.882 [opensc-pkcs11] card.c:422:sc_read_binary: called; 256 bytes at index 768
0xb7ad06c0 17:20:12.882 [opensc-pkcs11] apdu.c:525:sc_transmit_apdu: called
0xb7ad06c0 17:20:12.882 [opensc-pkcs11] card.c:292:sc_lock: called
0xb7ad06c0 17:20:12.882 [opensc-pkcs11] reader-pcsc.c:243:pcsc_transmit: reader 'ACS ACR38U 00 00'
0xb7ad06c0 17:20:12.882 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Outgoing APDU data [    5 bytes] =====================================
00 B0 03 00 00 .....
======================================================================
0xb7ad06c0 17:20:12.882 [opensc-pkcs11] reader-pcsc.c:176:pcsc_internal_transmit: called
0xb7ad06c0 17:20:12.956 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Incoming APDU data [  258 bytes] =====================================
<SWIPPED OFF CERTIFICATE DATA>
90 00                                           ..
======================================================================
0xb7ad06c0 17:20:12.957 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb7ad06c0 17:20:12.957 [opensc-pkcs11] iso7816.c:151:iso7816_read_binary: returning with: 256
0xb7ad06c0 17:20:12.957 [opensc-pkcs11] card.c:455:sc_read_binary: returning with: 256
0xb7ad06c0 17:20:12.957 [opensc-pkcs11] card.c:422:sc_read_binary: called; 256 bytes at index 1024
0xb7ad06c0 17:20:12.957 [opensc-pkcs11] apdu.c:525:sc_transmit_apdu: called
0xb7ad06c0 17:20:12.957 [opensc-pkcs11] card.c:292:sc_lock: called
0xb7ad06c0 17:20:12.957 [opensc-pkcs11] reader-pcsc.c:243:pcsc_transmit: reader 'ACS ACR38U 00 00'
0xb7ad06c0 17:20:12.957 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Outgoing APDU data [    5 bytes] =====================================
00 B0 04 00 00 .....
======================================================================
0xb7ad06c0 17:20:12.957 [opensc-pkcs11] reader-pcsc.c:176:pcsc_internal_transmit: called
0xb7ad06c0 17:20:13.032 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Incoming APDU data [  258 bytes] =====================================
<SWIPPED OFF CERTIFICATE DATA>
90 00                                           ..
======================================================================
0xb7ad06c0 17:20:13.032 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb7ad06c0 17:20:13.032 [opensc-pkcs11] iso7816.c:151:iso7816_read_binary: returning with: 256
0xb7ad06c0 17:20:13.032 [opensc-pkcs11] card.c:455:sc_read_binary: returning with: 256
0xb7ad06c0 17:20:13.032 [opensc-pkcs11] card.c:422:sc_read_binary: called; 176 bytes at index 1280
0xb7ad06c0 17:20:13.032 [opensc-pkcs11] apdu.c:525:sc_transmit_apdu: called
0xb7ad06c0 17:20:13.032 [opensc-pkcs11] card.c:292:sc_lock: called
0xb7ad06c0 17:20:13.032 [opensc-pkcs11] reader-pcsc.c:243:pcsc_transmit: reader 'ACS ACR38U 00 00'
0xb7ad06c0 17:20:13.032 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Outgoing APDU data [    5 bytes] =====================================
00 B0 05 00 B0 .....
======================================================================
0xb7ad06c0 17:20:13.032 [opensc-pkcs11] reader-pcsc.c:176:pcsc_internal_transmit: called
0xb7ad06c0 17:20:13.092 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Incoming APDU data [  178 bytes] =====================================
85 3D 87 F2 83 2F 68 A7 E6 FA B9 23 63 0F B4 E0 .=.../h....#c...
5E A8 A7 A2 03 19 4D 18 C2 44 A0 B1 BC 75 5C 84 ^.....M..D...u\.
E3 0A C2 44 36 50 F2 52 36 70 7A E7 C2 CD B9 57 ...D6P.R6pz....W
43 B0 D5 E1 39 6F 84 25 69 84 5B 5E 48 18 02 A2 C...9o.%i.[^H...
97 33 36 E1 4E D4 E1 36 A6 81 B4 96 1C 4D B5 AA .36.N..6.....M..
B4 31 E9 F2 10 F5 1F B2 3E B0 9B B9 43 DF 6A C4 .1......>...C.j.
DE F6 59 04 08 D1 61 70 07 96 67 0B ED 54 C2 A7 ..Y...ap..g..T..
AA 29 1D 56 0A 2A FC 8C 52 2F 33 3F 7B D0 E5 87 .).V.*..R/3?{...
27 D6 17 2F C9 26 A3 22 D3 74 0C 4C C0 B5 EC DB '../.&.".t.L....
ED 79 C0 43 4E 65 01 2D B3 38 B2 8A 4E 1C 48 77 .y.CNe.-.8..N.Hw
5E 9C E2 B3 D0 CB E3 85 5D 2D D0 36 EF 79 25 C7 ^.......]-.6.y%.
90 00                                           ..
======================================================================
0xb7ad06c0 17:20:13.093 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb7ad06c0 17:20:13.093 [opensc-pkcs11] iso7816.c:151:iso7816_read_binary: returning with: 176
0xb7ad06c0 17:20:13.093 [opensc-pkcs11] card.c:455:sc_read_binary: returning with: 176
0xb7ad06c0 17:20:13.093 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb7ad06c0 17:20:13.093 [opensc-pkcs11] card.c:452:sc_read_binary: returning with: 1456
0xb7ad06c0 17:20:13.093 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb7ad06c0 17:20:13.093 [opensc-pkcs11] reader-pcsc.c:548:pcsc_unlock: called
0xb7ad06c0 17:20:13.098 [opensc-pkcs11] pkcs15.c:1944:sc_pkcs15_read_file: returning with: 0 (Success)
0xb7ad06c0 17:20:13.098 [opensc-pkcs11] pkcs15-pubkey.c:869:sc_pkcs15_pubkey_from_spki: sc_pkcs15_pubkey_from_spki 0x806bb33:159
0xb7ad06c0 17:20:13.098 [opensc-pkcs11] pkcs15-pubkey.c:894:sc_pkcs15_pubkey_from_spki: DEE pk_alg.algorithm=0
0xb7ad06c0 17:20:13.098 [opensc-pkcs11] pkcs15-cert.c:147:sc_pkcs15_read_certificate: called
0xb7ad06c0 17:20:13.098 [opensc-pkcs11] pkcs15.c:1865:sc_pkcs15_read_file: called; path=3f00de00c001, index=0, count=-1
0xb7ad06c0 17:20:13.098 [opensc-pkcs11] card.c:292:sc_lock: called
0xb7ad06c0 17:20:13.098 [opensc-pkcs11] reader-pcsc.c:511:pcsc_lock: called
0xb7ad06c0 17:20:13.098 [opensc-pkcs11] card.c:571:sc_select_file: called; type=2, path=3f00de00c001
0xb7ad06c0 17:20:13.098 [opensc-pkcs11] apdu.c:525:sc_transmit_apdu: called
0xb7ad06c0 17:20:13.098 [opensc-pkcs11] card.c:292:sc_lock: called
0xb7ad06c0 17:20:13.098 [opensc-pkcs11] reader-pcsc.c:243:pcsc_transmit: reader 'ACS ACR38U 00 00'
0xb7ad06c0 17:20:13.098 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Outgoing APDU data [    9 bytes] =====================================
00 A4 08 00 04 DE 00 C0 01 .........
======================================================================
0xb7ad06c0 17:20:13.098 [opensc-pkcs11] reader-pcsc.c:176:pcsc_internal_transmit: called
0xb7ad06c0 17:20:13.163 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Incoming APDU data [    2 bytes] =====================================
61 14 a.
======================================================================
0xb7ad06c0 17:20:13.163 [opensc-pkcs11] apdu.c:525:sc_transmit_apdu: called
0xb7ad06c0 17:20:13.163 [opensc-pkcs11] card.c:292:sc_lock: called
0xb7ad06c0 17:20:13.164 [opensc-pkcs11] reader-pcsc.c:243:pcsc_transmit: reader 'ACS ACR38U 00 00'
0xb7ad06c0 17:20:13.164 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Outgoing APDU data [    5 bytes] =====================================
00 C0 00 00 14 .....
======================================================================
0xb7ad06c0 17:20:13.164 [opensc-pkcs11] reader-pcsc.c:176:pcsc_internal_transmit: called
0xb7ad06c0 17:20:13.172 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Incoming APDU data [   22 bytes] =====================================
6F 12 81 02 05 B0 82 01 01 83 02 C0 01 86 02 01 o...............
41 8A 01 07 90 00                               A.....
======================================================================
0xb7ad06c0 17:20:13.172 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb7ad06c0 17:20:13.172 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb7ad06c0 17:20:13.172 [opensc-pkcs11] iso7816.c:320:iso7816_process_fci: processing FCI bytes
0xb7ad06c0 17:20:13.173 [opensc-pkcs11] iso7816.c:325:iso7816_process_fci:   file identifier: 0xC001
0xb7ad06c0 17:20:13.173 [opensc-pkcs11] iso7816.c:339:iso7816_process_fci:   bytes in file: 1456
0xb7ad06c0 17:20:13.173 [opensc-pkcs11] iso7816.c:351:iso7816_process_fci:   shareable: no
0xb7ad06c0 17:20:13.173 [opensc-pkcs11] iso7816.c:371:iso7816_process_fci:   type: working EF
0xb7ad06c0 17:20:13.173 [opensc-pkcs11] iso7816.c:373:iso7816_process_fci:   EF structure: 1
0xb7ad06c0 17:20:13.173 [opensc-pkcs11] card.c:597:sc_select_file: returning with: 0 (Success)
0xb7ad06c0 17:20:13.173 [opensc-pkcs11] card.c:422:sc_read_binary: called; 1456 bytes at index 0
0xb7ad06c0 17:20:13.173 [opensc-pkcs11] card.c:292:sc_lock: called
0xb7ad06c0 17:20:13.173 [opensc-pkcs11] card.c:422:sc_read_binary: called; 256 bytes at index 0
0xb7ad06c0 17:20:13.173 [opensc-pkcs11] apdu.c:525:sc_transmit_apdu: called
0xb7ad06c0 17:20:13.173 [opensc-pkcs11] card.c:292:sc_lock: called
0xb7ad06c0 17:20:13.173 [opensc-pkcs11] reader-pcsc.c:243:pcsc_transmit: reader 'ACS ACR38U 00 00'
0xb7ad06c0 17:20:13.173 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Outgoing APDU data [    5 bytes] =====================================
00 B0 00 00 00 .....
======================================================================
0xb7ad06c0 17:20:13.173 [opensc-pkcs11] reader-pcsc.c:176:pcsc_internal_transmit: called
0xb7ad06c0 17:20:13.248 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Incoming APDU data [  258 bytes] =====================================
<SWIPPED OFF CERTIFICATE DATA>
90 00                                           ..
======================================================================
0xb7ad06c0 17:20:13.248 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb7ad06c0 17:20:13.248 [opensc-pkcs11] iso7816.c:151:iso7816_read_binary: returning with: 256
0xb7ad06c0 17:20:13.248 [opensc-pkcs11] card.c:455:sc_read_binary: returning with: 256
0xb7ad06c0 17:20:13.248 [opensc-pkcs11] card.c:422:sc_read_binary: called; 256 bytes at index 256
0xb7ad06c0 17:20:13.248 [opensc-pkcs11] apdu.c:525:sc_transmit_apdu: called
0xb7ad06c0 17:20:13.248 [opensc-pkcs11] card.c:292:sc_lock: called
0xb7ad06c0 17:20:13.248 [opensc-pkcs11] reader-pcsc.c:243:pcsc_transmit: reader 'ACS ACR38U 00 00'
_______________________________________________
opensc-devel mailing list
[hidden email]
http://www.opensc-project.org/mailman/listinfo/opensc-devel
Reply | Threaded
Open this post in threaded view
|

Re: Cryptotech Setcos card signing problem

TMS Brokers / Łukasz Kościesza
In reply to this post by Viktor Tarasov-3
0xb7ad06c0 17:20:13.248 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Outgoing APDU data [    5 bytes] =====================================
00 B0 01 00 00 .....
======================================================================
0xb7ad06c0 17:20:13.248 [opensc-pkcs11] reader-pcsc.c:176:pcsc_internal_transmit: called
0xb7ad06c0 17:20:13.323 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Incoming APDU data [  258 bytes] =====================================
<SWIPPED OFF CERTIFICATE DATA>
90 00                                           ..
======================================================================
0xb7ad06c0 17:20:13.323 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb7ad06c0 17:20:13.323 [opensc-pkcs11] iso7816.c:151:iso7816_read_binary: returning with: 256
0xb7ad06c0 17:20:13.323 [opensc-pkcs11] card.c:455:sc_read_binary: returning with: 256
0xb7ad06c0 17:20:13.323 [opensc-pkcs11] card.c:422:sc_read_binary: called; 256 bytes at index 512
0xb7ad06c0 17:20:13.323 [opensc-pkcs11] apdu.c:525:sc_transmit_apdu: called
0xb7ad06c0 17:20:13.323 [opensc-pkcs11] card.c:292:sc_lock: called
0xb7ad06c0 17:20:13.323 [opensc-pkcs11] reader-pcsc.c:243:pcsc_transmit: reader 'ACS ACR38U 00 00'
0xb7ad06c0 17:20:13.323 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Outgoing APDU data [    5 bytes] =====================================
00 B0 02 00 00 .....
======================================================================
0xb7ad06c0 17:20:13.323 [opensc-pkcs11] reader-pcsc.c:176:pcsc_internal_transmit: called
0xb7ad06c0 17:20:13.398 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Incoming APDU data [  258 bytes] =====================================
<SWIPPED OFF CERTIFICATE DATA>
90 00                                           ..
======================================================================
0xb7ad06c0 17:20:13.398 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb7ad06c0 17:20:13.398 [opensc-pkcs11] iso7816.c:151:iso7816_read_binary: returning with: 256
0xb7ad06c0 17:20:13.398 [opensc-pkcs11] card.c:455:sc_read_binary: returning with: 256
0xb7ad06c0 17:20:13.398 [opensc-pkcs11] card.c:422:sc_read_binary: called; 256 bytes at index 768
0xb7ad06c0 17:20:13.398 [opensc-pkcs11] apdu.c:525:sc_transmit_apdu: called
0xb7ad06c0 17:20:13.398 [opensc-pkcs11] card.c:292:sc_lock: called
0xb7ad06c0 17:20:13.398 [opensc-pkcs11] reader-pcsc.c:243:pcsc_transmit: reader 'ACS ACR38U 00 00'
0xb7ad06c0 17:20:13.398 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Outgoing APDU data [    5 bytes] =====================================
00 B0 03 00 00 .....
======================================================================
0xb7ad06c0 17:20:13.398 [opensc-pkcs11] reader-pcsc.c:176:pcsc_internal_transmit: called
0xb7ad06c0 17:20:13.473 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Incoming APDU data [  258 bytes] =====================================
<SWIPPED OFF CERTIFICATE DATA>
90 00                                           ..
======================================================================
0xb7ad06c0 17:20:13.473 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb7ad06c0 17:20:13.473 [opensc-pkcs11] iso7816.c:151:iso7816_read_binary: returning with: 256
0xb7ad06c0 17:20:13.473 [opensc-pkcs11] card.c:455:sc_read_binary: returning with: 256
0xb7ad06c0 17:20:13.473 [opensc-pkcs11] card.c:422:sc_read_binary: called; 256 bytes at index 1024
0xb7ad06c0 17:20:13.473 [opensc-pkcs11] apdu.c:525:sc_transmit_apdu: called
0xb7ad06c0 17:20:13.473 [opensc-pkcs11] card.c:292:sc_lock: called
0xb7ad06c0 17:20:13.473 [opensc-pkcs11] reader-pcsc.c:243:pcsc_transmit: reader 'ACS ACR38U 00 00'
0xb7ad06c0 17:20:13.473 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Outgoing APDU data [    5 bytes] =====================================
00 B0 04 00 00 .....
======================================================================
0xb7ad06c0 17:20:13.473 [opensc-pkcs11] reader-pcsc.c:176:pcsc_internal_transmit: called
0xb7ad06c0 17:20:13.550 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Incoming APDU data [  258 bytes] =====================================
<SWIPPED OFF CERTIFICATE DATA>
90 00                                           ..
======================================================================
0xb7ad06c0 17:20:13.550 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb7ad06c0 17:20:13.550 [opensc-pkcs11] iso7816.c:151:iso7816_read_binary: returning with: 256
0xb7ad06c0 17:20:13.550 [opensc-pkcs11] card.c:455:sc_read_binary: returning with: 256
0xb7ad06c0 17:20:13.550 [opensc-pkcs11] card.c:422:sc_read_binary: called; 176 bytes at index 1280
0xb7ad06c0 17:20:13.550 [opensc-pkcs11] apdu.c:525:sc_transmit_apdu: called
0xb7ad06c0 17:20:13.550 [opensc-pkcs11] card.c:292:sc_lock: called
0xb7ad06c0 17:20:13.550 [opensc-pkcs11] reader-pcsc.c:243:pcsc_transmit: reader 'ACS ACR38U 00 00'
0xb7ad06c0 17:20:13.550 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Outgoing APDU data [    5 bytes] =====================================
00 B0 05 00 B0 .....
======================================================================
0xb7ad06c0 17:20:13.550 [opensc-pkcs11] reader-pcsc.c:176:pcsc_internal_transmit: called
0xb7ad06c0 17:20:13.609 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Incoming APDU data [  178 bytes] =====================================
85 3D 87 F2 83 2F 68 A7 E6 FA B9 23 63 0F B4 E0 .=.../h....#c...
5E A8 A7 A2 03 19 4D 18 C2 44 A0 B1 BC 75 5C 84 ^.....M..D...u\.
E3 0A C2 44 36 50 F2 52 36 70 7A E7 C2 CD B9 57 ...D6P.R6pz....W
43 B0 D5 E1 39 6F 84 25 69 84 5B 5E 48 18 02 A2 C...9o.%i.[^H...
97 33 36 E1 4E D4 E1 36 A6 81 B4 96 1C 4D B5 AA .36.N..6.....M..
B4 31 E9 F2 10 F5 1F B2 3E B0 9B B9 43 DF 6A C4 .1......>...C.j.
DE F6 59 04 08 D1 61 70 07 96 67 0B ED 54 C2 A7 ..Y...ap..g..T..
AA 29 1D 56 0A 2A FC 8C 52 2F 33 3F 7B D0 E5 87 .).V.*..R/3?{...
27 D6 17 2F C9 26 A3 22 D3 74 0C 4C C0 B5 EC DB '../.&.".t.L....
ED 79 C0 43 4E 65 01 2D B3 38 B2 8A 4E 1C 48 77 .y.CNe.-.8..N.Hw
5E 9C E2 B3 D0 CB E3 85 5D 2D D0 36 EF 79 25 C7 ^.......]-.6.y%.
90 00                                           ..
======================================================================
0xb7ad06c0 17:20:13.609 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb7ad06c0 17:20:13.609 [opensc-pkcs11] iso7816.c:151:iso7816_read_binary: returning with: 176
0xb7ad06c0 17:20:13.609 [opensc-pkcs11] card.c:455:sc_read_binary: returning with: 176
0xb7ad06c0 17:20:13.609 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb7ad06c0 17:20:13.609 [opensc-pkcs11] card.c:452:sc_read_binary: returning with: 1456
0xb7ad06c0 17:20:13.609 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb7ad06c0 17:20:13.609 [opensc-pkcs11] reader-pcsc.c:548:pcsc_unlock: called
0xb7ad06c0 17:20:13.622 [opensc-pkcs11] pkcs15.c:1944:sc_pkcs15_read_file: returning with: 0 (Success)
0xb7ad06c0 17:20:13.622 [opensc-pkcs11] pkcs15-pubkey.c:869:sc_pkcs15_pubkey_from_spki: sc_pkcs15_pubkey_from_spki 0x806c623:159
0xb7ad06c0 17:20:13.622 [opensc-pkcs11] pkcs15-pubkey.c:894:sc_pkcs15_pubkey_from_spki: DEE pk_alg.algorithm=0
0xb7ad06c0 17:20:13.622 [opensc-pkcs11] framework-pkcs15.c:576:pkcs15_create_pkcs11_objects: Found 0 data objects
0xb7ad06c0 17:20:13.622 [opensc-pkcs11] framework-pkcs15.c:685:pkcs15_bind_related_objects: Looking for objects related to object 0
0xb7ad06c0 17:20:13.622 [opensc-pkcs11] framework-pkcs15.c:593:__pkcs15_prkey_bind_related: Object is a private key and has id <SWIPPED OFF ID>
0xb7ad06c0 17:20:13.622 [opensc-pkcs11] framework-pkcs15.c:685:pkcs15_bind_related_objects: Looking for objects related to object 1
0xb7ad06c0 17:20:13.622 [opensc-pkcs11] framework-pkcs15.c:635:__pkcs15_cert_bind_related: Object is a certificate and has id <SWIPPED OFF ID>
0xb7ad06c0 17:20:13.622 [opensc-pkcs11] framework-pkcs15.c:664:__pkcs15_cert_bind_related: Associating object 0 as private key
0xb7ad06c0 17:20:13.622 [opensc-pkcs11] framework-pkcs15.c:685:pkcs15_bind_related_objects: Looking for objects related to object 2
0xb7ad06c0 17:20:13.622 [opensc-pkcs11] framework-pkcs15.c:685:pkcs15_bind_related_objects: Looking for objects related to object 3
0xb7ad06c0 17:20:13.622 [opensc-pkcs11] framework-pkcs15.c:635:__pkcs15_cert_bind_related: Object is a certificate and has id <SWIPPED OFF ID>
0xb7ad06c0 17:20:13.622 [opensc-pkcs11] framework-pkcs15.c:664:__pkcs15_cert_bind_related: Associating object 0 as private key
0xb7ad06c0 17:20:13.622 [opensc-pkcs11] framework-pkcs15.c:685:pkcs15_bind_related_objects: Looking for objects related to object 4
0xb7ad06c0 17:20:13.622 [opensc-pkcs11] slot.c:293:slot_allocate: Allocated slot 0x1 for card in reader ACS ACR38U 00 00
0xb7ad06c0 17:20:13.622 [opensc-pkcs11] framework-pkcs15.c:844:pkcs15_init_slot: Initialized token 'SetEID (SetEID PIN)' in slot 0x1
0xb7ad06c0 17:20:13.622 [opensc-pkcs11] framework-pkcs15.c:1026:pkcs15_create_tokens: 0: Object ('',type:101) was not seen previously
0xb7ad06c0 17:20:13.622 [opensc-pkcs11] framework-pkcs15.c:753:pkcs15_add_object: Setting object handle of 0x0 to 0x8068530
0xb7ad06c0 17:20:13.622 [opensc-pkcs11] framework-pkcs15.c:753:pkcs15_add_object: Setting object handle of 0x0 to 0x806a1a0
0xb7ad06c0 17:20:13.622 [opensc-pkcs11] framework-pkcs15.c:753:pkcs15_add_object: Setting object handle of 0x0 to 0x806a1d0
0xb7ad06c0 17:20:13.622 [opensc-pkcs11] framework-pkcs15.c:753:pkcs15_add_object: Setting object handle of 0x0 to 0x806ce78
0xb7ad06c0 17:20:13.622 [opensc-pkcs11] framework-pkcs15.c:753:pkcs15_add_object: Setting object handle of 0x0 to 0x806cea8
0xb7ad06c0 17:20:13.622 [opensc-pkcs11] framework-pkcs15.c:1046:pkcs15_create_tokens: All tokens created
0xb7ad06c0 17:20:13.622 [opensc-pkcs11] slot.c:261:card_detect: ACS ACR38U 00 00: Detection ended
0xb7ad06c0 17:20:13.622 [opensc-pkcs11] pkcs11-global.c:259:C_Initialize: C_Initialize() = CKR_OK
0xb7ad06c0 17:20:13.622 [opensc-pkcs11] pkcs11-global.c:375:C_GetSlotList: C_GetSlotList(token=0, plug-n-play)
0xb7ad06c0 17:20:13.622 [opensc-pkcs11] reader-pcsc.c:870:pcsc_detect_readers: called
0xb7ad06c0 17:20:13.623 [opensc-pkcs11] reader-pcsc.c:878:pcsc_detect_readers: Probing pcsc readers
0xb7ad06c0 17:20:13.623 [opensc-pkcs11] reader-pcsc.c:1023:pcsc_detect_readers: returning with: 0 (Success)
0xb7ad06c0 17:20:13.623 [opensc-pkcs11] slot.c:188:card_detect: ACS ACR38U 00 00: Detecting smart card
0xb7ad06c0 17:20:13.623 [opensc-pkcs11] sc.c:195:sc_detect_card_presence: called
0xb7ad06c0 17:20:13.623 [opensc-pkcs11] reader-pcsc.c:364:pcsc_detect_card_presence: called
0xb7ad06c0 17:20:13.623 [opensc-pkcs11] reader-pcsc.c:277:refresh_attributes: ACS ACR38U 00 00 check
0xb7ad06c0 17:20:13.623 [opensc-pkcs11] reader-pcsc.c:293:refresh_attributes: returning with: 0 (Success)
0xb7ad06c0 17:20:13.623 [opensc-pkcs11] reader-pcsc.c:369:pcsc_detect_card_presence: returning with: 1
0xb7ad06c0 17:20:13.623 [opensc-pkcs11] sc.c:200:sc_detect_card_presence: returning with: 1
0xb7ad06c0 17:20:13.623 [opensc-pkcs11] slot.c:261:card_detect: ACS ACR38U 00 00: Detection ended
0xb7ad06c0 17:20:13.623 [opensc-pkcs11] pkcs11-global.c:411:C_GetSlotList: was only a size inquiry (2)
0xb7ad06c0 17:20:13.623 [opensc-pkcs11] pkcs11-global.c:375:C_GetSlotList: C_GetSlotList(token=0, refresh)
0xb7ad06c0 17:20:13.623 [opensc-pkcs11] slot.c:188:card_detect: ACS ACR38U 00 00: Detecting smart card
0xb7ad06c0 17:20:13.623 [opensc-pkcs11] sc.c:195:sc_detect_card_presence: called
0xb7ad06c0 17:20:13.623 [opensc-pkcs11] reader-pcsc.c:364:pcsc_detect_card_presence: called
0xb7ad06c0 17:20:13.623 [opensc-pkcs11] reader-pcsc.c:277:refresh_attributes: ACS ACR38U 00 00 check
0xb7ad06c0 17:20:13.623 [opensc-pkcs11] reader-pcsc.c:293:refresh_attributes: returning with: 0 (Success)
0xb7ad06c0 17:20:13.623 [opensc-pkcs11] reader-pcsc.c:369:pcsc_detect_card_presence: returning with: 1
0xb7ad06c0 17:20:13.623 [opensc-pkcs11] sc.c:200:sc_detect_card_presence: returning with: 1
0xb7ad06c0 17:20:13.623 [opensc-pkcs11] slot.c:261:card_detect: ACS ACR38U 00 00: Detection ended
0xb7ad06c0 17:20:13.623 [opensc-pkcs11] pkcs11-global.c:428:C_GetSlotList: returned 2 slots
0xb7ad06c0 17:20:13.623 [opensc-pkcs11] framework-pkcs15.c:332:C_GetTokenInfo: C_GetTokenInfo(1)
0xb7ad06c0 17:20:13.623 [opensc-pkcs11] sec.c:157:sc_pin_cmd: called
0xb7ad06c0 17:20:13.623 [opensc-pkcs11] sec.c:204:sc_pin_cmd: returning with: -1408 (Not supported)
0xb7ad06c0 17:20:13.623 [opensc-pkcs11] pkcs11-session.c:57:C_OpenSession: C_OpenSession(0x1)
0xb7ad06c0 17:20:13.623 [opensc-pkcs11] pkcs11-session.c:83:C_OpenSession: C_OpenSession handle: 0x806cf18
0xb7ad06c0 17:20:13.623 [opensc-pkcs11] pkcs11-session.c:86:C_OpenSession: C_OpenSession() = CKR_OK
0xb7ad06c0 17:20:13.623 [opensc-pkcs11] framework-pkcs15.c:332:C_GetTokenInfo: C_GetTokenInfo(1)
0xb7ad06c0 17:20:13.623 [opensc-pkcs11] sec.c:157:sc_pin_cmd: called
0xb7ad06c0 17:20:13.623 [opensc-pkcs11] sec.c:204:sc_pin_cmd: returning with: -1408 (Not supported)
0xb7ad06c0 17:20:13.623 [opensc-pkcs11] pkcs11-session.c:259:C_Login: C_Login(0x806cf18, 1)
0xb7ad06c0 17:20:13.623 [opensc-pkcs11] pkcs15-pin.c:234:sc_pkcs15_verify_pin: called
0xb7ad06c0 17:20:13.623 [opensc-pkcs11] pkcs15-pin.c:235:sc_pkcs15_verify_pin: PIN(0xbfcf1e35;len:7)
0xb7ad06c0 17:20:13.623 [opensc-pkcs11] card.c:292:sc_lock: called
0xb7ad06c0 17:20:13.623 [opensc-pkcs11] reader-pcsc.c:511:pcsc_lock: called
0xb7ad06c0 17:20:13.623 [opensc-pkcs11] card.c:571:sc_select_file: called; type=2, path=3f00df01
0xb7ad06c0 17:20:13.623 [opensc-pkcs11] apdu.c:525:sc_transmit_apdu: called
0xb7ad06c0 17:20:13.623 [opensc-pkcs11] card.c:292:sc_lock: called
0xb7ad06c0 17:20:13.623 [opensc-pkcs11] reader-pcsc.c:243:pcsc_transmit: reader 'ACS ACR38U 00 00'
0xb7ad06c0 17:20:13.623 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Outgoing APDU data [    7 bytes] =====================================
00 A4 08 00 02 DF 01 .......
======================================================================
0xb7ad06c0 17:20:13.623 [opensc-pkcs11] reader-pcsc.c:176:pcsc_internal_transmit: called
0xb7ad06c0 17:20:13.670 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Incoming APDU data [    2 bytes] =====================================
61 22 a"
======================================================================
0xb7ad06c0 17:20:13.670 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb7ad06c0 17:20:13.671 [opensc-pkcs11] iso7816.c:481:iso7816_select_file: returning with: 0 (Success)
0xb7ad06c0 17:20:13.671 [opensc-pkcs11] card.c:597:sc_select_file: returning with: 0 (Success)
0xb7ad06c0 17:20:13.671 [opensc-pkcs11] sec.c:157:sc_pin_cmd: called
0xb7ad06c0 17:20:13.671 [opensc-pkcs11] apdu.c:525:sc_transmit_apdu: called
0xb7ad06c0 17:20:13.671 [opensc-pkcs11] card.c:292:sc_lock: called
0xb7ad06c0 17:20:13.671 [opensc-pkcs11] reader-pcsc.c:243:pcsc_transmit: reader 'ACS ACR38U 00 00'
0xb7ad06c0 17:20:13.671 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Outgoing APDU data [   13 bytes] =====================================
<SWIPPED OFF PIN>
======================================================================
0xb7ad06c0 17:20:13.671 [opensc-pkcs11] reader-pcsc.c:176:pcsc_internal_transmit: called
0xb7ad06c0 17:20:13.806 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Incoming APDU data [    2 bytes] =====================================
90 00 ..
======================================================================
0xb7ad06c0 17:20:13.806 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb7ad06c0 17:20:13.806 [opensc-pkcs11] sec.c:204:sc_pin_cmd: returning with: 0 (Success)
0xb7ad06c0 17:20:13.806 [opensc-pkcs11] pkcs15-pin.c:509:sc_pkcs15_pincache_add: called
0xb7ad06c0 17:20:13.806 [opensc-pkcs11] pkcs15-pin.c:543:sc_pkcs15_pincache_add: PIN(SetEID PIN) cached
0xb7ad06c0 17:20:13.806 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb7ad06c0 17:20:13.806 [opensc-pkcs11] reader-pcsc.c:548:pcsc_unlock: called
0xb7ad06c0 17:20:13.814 [opensc-pkcs11] pkcs15-pin.c:296:sc_pkcs15_verify_pin: returning with: 0 (Success)
0xb7ad06c0 17:20:13.814 [opensc-pkcs11] framework-pkcs15.c:1186:pkcs15_login: PKCS15 verify PIN returned 0
0xb7ad06c0 17:20:13.814 [opensc-pkcs11] framework-pkcs15.c:1195:pkcs15_login: Check if pkcs15 object list can be completed.
0xb7ad06c0 17:20:13.814 [opensc-pkcs11] pkcs11-object.c:307:C_FindObjectsInit: C_FindObjectsInit(slot = 1)
0xb7ad06c0 17:20:13.814 [opensc-pkcs11] pkcs11-object.c:308:C_FindObjectsInit: C_FindObjectsInit(): CKA_CLASS = CKO_PRIVATE_KEY
0xb7ad06c0 17:20:13.814 [opensc-pkcs11] misc.c:136:session_start_operation: called
0xb7ad06c0 17:20:13.814 [opensc-pkcs11] misc.c:137:session_start_operation: Session 0x806cf18, type 0
0xb7ad06c0 17:20:13.814 [opensc-pkcs11] pkcs11-object.c:329:C_FindObjectsInit: Object with handle 0x8068530
0xb7ad06c0 17:20:13.814 [opensc-pkcs11] pkcs11-object.c:357:C_FindObjectsInit: Object 1/134645040: Attribute 0x0 matches.
0xb7ad06c0 17:20:13.814 [opensc-pkcs11] pkcs11-object.c:362:C_FindObjectsInit: Object 1/134645040 matches
0xb7ad06c0 17:20:13.814 [opensc-pkcs11] pkcs11-object.c:367:C_FindObjectsInit: realloc for 32 handles
0xb7ad06c0 17:20:13.814 [opensc-pkcs11] pkcs11-object.c:329:C_FindObjectsInit: Object with handle 0x806a1a0
0xb7ad06c0 17:20:13.814 [opensc-pkcs11] pkcs11-object.c:350:C_FindObjectsInit: Object 1/134652320: Attribute 0x0 does NOT match.
0xb7ad06c0 17:20:13.814 [opensc-pkcs11] pkcs11-object.c:329:C_FindObjectsInit: Object with handle 0x806a1d0
0xb7ad06c0 17:20:13.814 [opensc-pkcs11] pkcs11-object.c:350:C_FindObjectsInit: Object 1/134652368: Attribute 0x0 does NOT match.
0xb7ad06c0 17:20:13.814 [opensc-pkcs11] pkcs11-object.c:329:C_FindObjectsInit: Object with handle 0x806ce78
0xb7ad06c0 17:20:13.814 [opensc-pkcs11] pkcs11-object.c:350:C_FindObjectsInit: Object 1/134663800: Attribute 0x0 does NOT match.
0xb7ad06c0 17:20:13.814 [opensc-pkcs11] pkcs11-object.c:329:C_FindObjectsInit: Object with handle 0x806cea8
0xb7ad06c0 17:20:13.814 [opensc-pkcs11] pkcs11-object.c:350:C_FindObjectsInit: Object 1/134663848: Attribute 0x0 does NOT match.
0xb7ad06c0 17:20:13.814 [opensc-pkcs11] pkcs11-object.c:380:C_FindObjectsInit: 1 matching objects
0xb7ad06c0 17:20:13.814 [opensc-pkcs11] misc.c:158:session_get_operation: called
0xb7ad06c0 17:20:13.814 [opensc-pkcs11] misc.c:158:session_get_operation: called
0xb7ad06c0 17:20:13.814 [opensc-pkcs11] pkcs11-object.c:60:sc_find_release: freeing 32 handles used 1  at 0x806cf88
0xb7ad06c0 17:20:13.853 [opensc-pkcs11] misc.c:136:session_start_operation: called
0xb7ad06c0 17:20:13.853 [opensc-pkcs11] misc.c:137:session_start_operation: Session 0x806cf18, type 1
0xb7ad06c0 17:20:13.853 [opensc-pkcs11] pkcs11-object.c:594:C_SignInit: C_SignInit() = CKR_OK
0xb7ad06c0 17:20:13.853 [opensc-pkcs11] misc.c:158:session_get_operation: called
0xb7ad06c0 17:20:13.853 [opensc-pkcs11] misc.c:158:session_get_operation: called
0xb7ad06c0 17:20:13.853 [opensc-pkcs11] misc.c:158:session_get_operation: called
0xb7ad06c0 17:20:13.853 [opensc-pkcs11] framework-pkcs15.c:2630:pkcs15_prkey_sign: Initiating signing operation, mechanism 0x1.
0xb7ad06c0 17:20:13.853 [opensc-pkcs11] card.c:292:sc_lock: called
0xb7ad06c0 17:20:13.853 [opensc-pkcs11] reader-pcsc.c:511:pcsc_lock: called
0xb7ad06c0 17:20:13.853 [opensc-pkcs11] framework-pkcs15.c:3611:reselect_app_df: reselect application df
0xb7ad06c0 17:20:13.853 [opensc-pkcs11] card.c:571:sc_select_file: called; type=2, path=3f00de00
0xb7ad06c0 17:20:13.853 [opensc-pkcs11] apdu.c:525:sc_transmit_apdu: called
0xb7ad06c0 17:20:13.853 [opensc-pkcs11] card.c:292:sc_lock: called
0xb7ad06c0 17:20:13.853 [opensc-pkcs11] reader-pcsc.c:243:pcsc_transmit: reader 'ACS ACR38U 00 00'
0xb7ad06c0 17:20:13.853 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Outgoing APDU data [    7 bytes] =====================================
00 A4 08 00 02 DE 00 .......
======================================================================
0xb7ad06c0 17:20:13.853 [opensc-pkcs11] reader-pcsc.c:176:pcsc_internal_transmit: called
0xb7ad06c0 17:20:13.902 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Incoming APDU data [    2 bytes] =====================================
61 2D a-
======================================================================
0xb7ad06c0 17:20:13.903 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb7ad06c0 17:20:13.903 [opensc-pkcs11] iso7816.c:481:iso7816_select_file: returning with: 0 (Success)
0xb7ad06c0 17:20:13.903 [opensc-pkcs11] card.c:597:sc_select_file: returning with: 0 (Success)
0xb7ad06c0 17:20:13.903 [opensc-pkcs11] framework-pkcs15.c:2710:pkcs15_prkey_sign: Selected flags 12. Now computing signature for 32 bytes. 512 bytes reserved.
0xb7ad06c0 17:20:13.903 [opensc-pkcs11] pkcs15-sec.c:190:sc_pkcs15_compute_signature: called
0xb7ad06c0 17:20:13.903 [opensc-pkcs11] pkcs15-sec.c:191:sc_pkcs15_compute_signature: security operation flags 0x12
0xb7ad06c0 17:20:13.903 [opensc-pkcs11] pkcs15-sec.c:273:sc_pkcs15_compute_signature: supported algorithm flags 0x80000033, private key usage 0x26
0xb7ad06c0 17:20:13.903 [opensc-pkcs11] padding.c:273:sc_get_encoding_flags: called
0xb7ad06c0 17:20:13.903 [opensc-pkcs11] padding.c:277:sc_get_encoding_flags: iFlags 0x12, card capabilities 0x80000033
0xb7ad06c0 17:20:13.903 [opensc-pkcs11] padding.c:306:sc_get_encoding_flags: pad flags 0x10, secure algorithm flags 0x2
0xb7ad06c0 17:20:13.903 [opensc-pkcs11] padding.c:307:sc_get_encoding_flags: returning with: 0 (Success)
0xb7ad06c0 17:20:13.903 [opensc-pkcs11] pkcs15-sec.c:324:sc_pkcs15_compute_signature: DEE flags:0x00000012 alg_info->flags:0x80000033 pad:0x00000010 sec:0x00000002
0xb7ad06c0 17:20:13.903 [opensc-pkcs11] padding.c:232:sc_pkcs1_encode: called
0xb7ad06c0 17:20:13.903 [opensc-pkcs11] padding.c:236:sc_pkcs1_encode: hash algorithm 0x10, pad algorithm 0x0
0xb7ad06c0 17:20:13.903 [opensc-pkcs11] padding.c:255:sc_pkcs1_encode: returning with: 0 (Success)
0xb7ad06c0 17:20:13.903 [opensc-pkcs11] card.c:292:sc_lock: called
0xb7ad06c0 17:20:13.903 [opensc-pkcs11] pkcs15-sec.c:42:select_key_file: called
0xb7ad06c0 17:20:13.903 [opensc-pkcs11] card.c:571:sc_select_file: called; type=2, path=3f00df01efd5
0xb7ad06c0 17:20:13.903 [opensc-pkcs11] apdu.c:525:sc_transmit_apdu: called
0xb7ad06c0 17:20:13.903 [opensc-pkcs11] card.c:292:sc_lock: called
0xb7ad06c0 17:20:13.903 [opensc-pkcs11] reader-pcsc.c:243:pcsc_transmit: reader 'ACS ACR38U 00 00'
0xb7ad06c0 17:20:13.903 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Outgoing APDU data [    9 bytes] =====================================
00 A4 08 00 04 DF 01 EF D5 .........
======================================================================
0xb7ad06c0 17:20:13.903 [opensc-pkcs11] reader-pcsc.c:176:pcsc_internal_transmit: called
0xb7ad06c0 17:20:13.960 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Incoming APDU data [    2 bytes] =====================================
61 25 a%
======================================================================
0xb7ad06c0 17:20:13.961 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb7ad06c0 17:20:13.961 [opensc-pkcs11] iso7816.c:481:iso7816_select_file: returning with: 0 (Success)
0xb7ad06c0 17:20:13.961 [opensc-pkcs11] card.c:597:sc_select_file: returning with: 0 (Success)
0xb7ad06c0 17:20:13.961 [opensc-pkcs11] pkcs15-sec.c:68:select_key_file: returning with: 0 (Success)
0xb7ad06c0 17:20:13.961 [opensc-pkcs11] sec.c:66:sc_set_security_env: called
0xb7ad06c0 17:20:13.961 [opensc-pkcs11] apdu.c:525:sc_transmit_apdu: called
0xb7ad06c0 17:20:13.961 [opensc-pkcs11] card.c:292:sc_lock: called
0xb7ad06c0 17:20:13.961 [opensc-pkcs11] reader-pcsc.c:243:pcsc_transmit: reader 'ACS ACR38U 00 00'
0xb7ad06c0 17:20:13.961 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Outgoing APDU data [   12 bytes] =====================================
00 22 41 B6 07 80 01 02 81 02 EF D5 ."A.........
======================================================================
0xb7ad06c0 17:20:13.961 [opensc-pkcs11] reader-pcsc.c:176:pcsc_internal_transmit: called
0xb7ad06c0 17:20:13.987 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Incoming APDU data [    2 bytes] =====================================
90 00 ..
======================================================================
0xb7ad06c0 17:20:13.988 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb7ad06c0 17:20:13.988 [opensc-pkcs11] sec.c:70:sc_set_security_env: returning with: 0 (Success)
0xb7ad06c0 17:20:13.988 [opensc-pkcs11] sec.c:52:sc_compute_signature: called
0xb7ad06c0 17:20:13.988 [opensc-pkcs11] apdu.c:525:sc_transmit_apdu: called
0xb7ad06c0 17:20:13.988 [opensc-pkcs11] card.c:292:sc_lock: called
0xb7ad06c0 17:20:13.988 [opensc-pkcs11] reader-pcsc.c:243:pcsc_transmit: reader 'ACS ACR38U 00 00'
0xb7ad06c0 17:20:13.988 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Outgoing APDU data [   37 bytes] =====================================
00 2A 9E 9A 20 31 30 39 38 37 36 35 34 33 32 31 .*.. 10987654321
30 39 38 37 36 35 34 33 32 31 30 39 38 37 36 35 0987654321098765
34 33 32 31 40                                  4321@
======================================================================
0xb7ad06c0 17:20:13.988 [opensc-pkcs11] reader-pcsc.c:176:pcsc_internal_transmit: called
0xb7ad06c0 17:20:14.018 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Incoming APDU data [    2 bytes] =====================================
69 82 i.
======================================================================
0xb7ad06c0 17:20:14.019 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb7ad06c0 17:20:14.019 [opensc-pkcs11] iso7816.c:103:iso7816_check_sw: Security status not satisfied
0xb7ad06c0 17:20:14.019 [opensc-pkcs11] iso7816.c:820:iso7816_compute_signature: returning with: -1211 (Security status not satisfied)
0xb7ad06c0 17:20:14.019 [opensc-pkcs11] sec.c:56:sc_compute_signature: returning with: -1211 (Security status not satisfied)
0xb7ad06c0 17:20:14.019 [opensc-pkcs11] pkcs15-pin.c:553:sc_pkcs15_pincache_revalidate: called
0xb7ad06c0 17:20:14.019 [opensc-pkcs11] pkcs15-pin.c:566:sc_pkcs15_pincache_revalidate: Could not find pin object for auth_id 01
0xb7ad06c0 17:20:14.019 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb7ad06c0 17:20:14.019 [opensc-pkcs11] pkcs15-sec.c:380:sc_pkcs15_compute_signature: sc_compute_signature() failed: -1211 (Security status not satisfied)
0xb7ad06c0 17:20:14.019 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb7ad06c0 17:20:14.019 [opensc-pkcs11] reader-pcsc.c:548:pcsc_unlock: called
0xb7ad06c0 17:20:14.022 [opensc-pkcs11] framework-pkcs15.c:2721:pkcs15_prkey_sign: Sign complete. Result -1211.
0xb7ad06c0 17:20:14.022 [opensc-pkcs11] misc.c:59:sc_to_cryptoki_error_common: libopensc return value: -1211 (Security status not satisfied)
0xb7ad06c0 17:20:14.022 [opensc-pkcs11] pkcs11-object.c:635:C_Sign: C_Sign() = CKR_USER_NOT_LOGGED_IN
0xb7ad06c0 17:20:14.022 [opensc-pkcs11] misc.c:136:session_start_operation: called
0xb7ad06c0 17:20:14.022 [opensc-pkcs11] misc.c:137:session_start_operation: Session 0x806cf18, type 1
0xb7ad06c0 17:20:14.022 [opensc-pkcs11] pkcs11-object.c:594:C_SignInit: C_SignInit() = CKR_OK
0xb7ad06c0 17:20:14.022 [opensc-pkcs11] misc.c:158:session_get_operation: called
0xb7ad06c0 17:20:14.022 [opensc-pkcs11] pkcs11-object.c:655:C_SignUpdate: C_SignUpdate() = CKR_OK
0xb7ad06c0 17:20:14.022 [opensc-pkcs11] misc.c:158:session_get_operation: called
0xb7ad06c0 17:20:14.022 [opensc-pkcs11] misc.c:158:session_get_operation: called
0xb7ad06c0 17:20:14.022 [opensc-pkcs11] framework-pkcs15.c:2630:pkcs15_prkey_sign: Initiating signing operation, mechanism 0x1.
0xb7ad06c0 17:20:14.022 [opensc-pkcs11] card.c:292:sc_lock: called
0xb7ad06c0 17:20:14.022 [opensc-pkcs11] reader-pcsc.c:511:pcsc_lock: called
0xb7ad06c0 17:20:14.022 [opensc-pkcs11] framework-pkcs15.c:3611:reselect_app_df: reselect application df
0xb7ad06c0 17:20:14.022 [opensc-pkcs11] card.c:571:sc_select_file: called; type=2, path=3f00de00
0xb7ad06c0 17:20:14.022 [opensc-pkcs11] apdu.c:525:sc_transmit_apdu: called
0xb7ad06c0 17:20:14.022 [opensc-pkcs11] card.c:292:sc_lock: called
0xb7ad06c0 17:20:14.022 [opensc-pkcs11] reader-pcsc.c:243:pcsc_transmit: reader 'ACS ACR38U 00 00'
0xb7ad06c0 17:20:14.022 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Outgoing APDU data [    7 bytes] =====================================
00 A4 08 00 02 DE 00 .......
======================================================================
0xb7ad06c0 17:20:14.022 [opensc-pkcs11] reader-pcsc.c:176:pcsc_internal_transmit: called
0xb7ad06c0 17:20:14.071 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Incoming APDU data [    2 bytes] =====================================
61 2D a-
======================================================================
0xb7ad06c0 17:20:14.072 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb7ad06c0 17:20:14.072 [opensc-pkcs11] iso7816.c:481:iso7816_select_file: returning with: 0 (Success)
0xb7ad06c0 17:20:14.072 [opensc-pkcs11] card.c:597:sc_select_file: returning with: 0 (Success)
0xb7ad06c0 17:20:14.072 [opensc-pkcs11] framework-pkcs15.c:2710:pkcs15_prkey_sign: Selected flags 12. Now computing signature for 32 bytes. 512 bytes reserved.
0xb7ad06c0 17:20:14.072 [opensc-pkcs11] pkcs15-sec.c:190:sc_pkcs15_compute_signature: called
0xb7ad06c0 17:20:14.072 [opensc-pkcs11] pkcs15-sec.c:191:sc_pkcs15_compute_signature: security operation flags 0x12
0xb7ad06c0 17:20:14.072 [opensc-pkcs11] pkcs15-sec.c:273:sc_pkcs15_compute_signature: supported algorithm flags 0x80000033, private key usage 0x26
0xb7ad06c0 17:20:14.072 [opensc-pkcs11] padding.c:273:sc_get_encoding_flags: called
0xb7ad06c0 17:20:14.072 [opensc-pkcs11] padding.c:277:sc_get_encoding_flags: iFlags 0x12, card capabilities 0x80000033
0xb7ad06c0 17:20:14.072 [opensc-pkcs11] padding.c:306:sc_get_encoding_flags: pad flags 0x10, secure algorithm flags 0x2
0xb7ad06c0 17:20:14.072 [opensc-pkcs11] padding.c:307:sc_get_encoding_flags: returning with: 0 (Success)
0xb7ad06c0 17:20:14.072 [opensc-pkcs11] pkcs15-sec.c:324:sc_pkcs15_compute_signature: DEE flags:0x00000012 alg_info->flags:0x80000033 pad:0x00000010 sec:0x00000002
0xb7ad06c0 17:20:14.072 [opensc-pkcs11] padding.c:232:sc_pkcs1_encode: called
0xb7ad06c0 17:20:14.072 [opensc-pkcs11] padding.c:236:sc_pkcs1_encode: hash algorithm 0x10, pad algorithm 0x0
0xb7ad06c0 17:20:14.072 [opensc-pkcs11] padding.c:255:sc_pkcs1_encode: returning with: 0 (Success)
0xb7ad06c0 17:20:14.072 [opensc-pkcs11] card.c:292:sc_lock: called
0xb7ad06c0 17:20:14.072 [opensc-pkcs11] pkcs15-sec.c:42:select_key_file: called
0xb7ad06c0 17:20:14.072 [opensc-pkcs11] card.c:571:sc_select_file: called; type=2, path=3f00df01efd5
0xb7ad06c0 17:20:14.072 [opensc-pkcs11] apdu.c:525:sc_transmit_apdu: called
0xb7ad06c0 17:20:14.072 [opensc-pkcs11] card.c:292:sc_lock: called
0xb7ad06c0 17:20:14.072 [opensc-pkcs11] reader-pcsc.c:243:pcsc_transmit: reader 'ACS ACR38U 00 00'
0xb7ad06c0 17:20:14.072 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Outgoing APDU data [    9 bytes] =====================================
00 A4 08 00 04 DF 01 EF D5 .........
======================================================================
0xb7ad06c0 17:20:14.072 [opensc-pkcs11] reader-pcsc.c:176:pcsc_internal_transmit: called
0xb7ad06c0 17:20:14.127 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Incoming APDU data [    2 bytes] =====================================
61 25 a%
======================================================================
0xb7ad06c0 17:20:14.128 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb7ad06c0 17:20:14.128 [opensc-pkcs11] iso7816.c:481:iso7816_select_file: returning with: 0 (Success)
0xb7ad06c0 17:20:14.128 [opensc-pkcs11] card.c:597:sc_select_file: returning with: 0 (Success)
0xb7ad06c0 17:20:14.128 [opensc-pkcs11] pkcs15-sec.c:68:select_key_file: returning with: 0 (Success)
0xb7ad06c0 17:20:14.128 [opensc-pkcs11] sec.c:66:sc_set_security_env: called
0xb7ad06c0 17:20:14.128 [opensc-pkcs11] apdu.c:525:sc_transmit_apdu: called
0xb7ad06c0 17:20:14.128 [opensc-pkcs11] card.c:292:sc_lock: called
0xb7ad06c0 17:20:14.128 [opensc-pkcs11] reader-pcsc.c:243:pcsc_transmit: reader 'ACS ACR38U 00 00'
0xb7ad06c0 17:20:14.128 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Outgoing APDU data [   12 bytes] =====================================
00 22 41 B6 07 80 01 02 81 02 EF D5 ."A.........
======================================================================
0xb7ad06c0 17:20:14.128 [opensc-pkcs11] reader-pcsc.c:176:pcsc_internal_transmit: called
0xb7ad06c0 17:20:14.155 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Incoming APDU data [    2 bytes] =====================================
90 00 ..
======================================================================
0xb7ad06c0 17:20:14.155 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb7ad06c0 17:20:14.155 [opensc-pkcs11] sec.c:70:sc_set_security_env: returning with: 0 (Success)
0xb7ad06c0 17:20:14.155 [opensc-pkcs11] sec.c:52:sc_compute_signature: called
0xb7ad06c0 17:20:14.155 [opensc-pkcs11] apdu.c:525:sc_transmit_apdu: called
0xb7ad06c0 17:20:14.155 [opensc-pkcs11] card.c:292:sc_lock: called
0xb7ad06c0 17:20:14.155 [opensc-pkcs11] reader-pcsc.c:243:pcsc_transmit: reader 'ACS ACR38U 00 00'
0xb7ad06c0 17:20:14.155 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Outgoing APDU data [   37 bytes] =====================================
00 2A 9E 9A 20 31 30 39 38 37 36 35 34 33 32 31 .*.. 10987654321
30 39 38 37 36 35 34 33 32 31 30 39 38 37 36 35 0987654321098765
34 33 32 31 40                                  4321@
======================================================================
0xb7ad06c0 17:20:14.155 [opensc-pkcs11] reader-pcsc.c:176:pcsc_internal_transmit: called
0xb7ad06c0 17:20:14.186 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Incoming APDU data [    2 bytes] =====================================
69 82 i.
======================================================================
0xb7ad06c0 17:20:14.186 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb7ad06c0 17:20:14.186 [opensc-pkcs11] iso7816.c:103:iso7816_check_sw: Security status not satisfied
0xb7ad06c0 17:20:14.186 [opensc-pkcs11] iso7816.c:820:iso7816_compute_signature: returning with: -1211 (Security status not satisfied)
0xb7ad06c0 17:20:14.186 [opensc-pkcs11] sec.c:56:sc_compute_signature: returning with: -1211 (Security status not satisfied)
0xb7ad06c0 17:20:14.186 [opensc-pkcs11] pkcs15-pin.c:553:sc_pkcs15_pincache_revalidate: called
0xb7ad06c0 17:20:14.186 [opensc-pkcs11] pkcs15-pin.c:566:sc_pkcs15_pincache_revalidate: Could not find pin object for auth_id 01
0xb7ad06c0 17:20:14.186 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb7ad06c0 17:20:14.186 [opensc-pkcs11] pkcs15-sec.c:380:sc_pkcs15_compute_signature: sc_compute_signature() failed: -1211 (Security status not satisfied)
0xb7ad06c0 17:20:14.186 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb7ad06c0 17:20:14.186 [opensc-pkcs11] reader-pcsc.c:548:pcsc_unlock: called
0xb7ad06c0 17:20:14.190 [opensc-pkcs11] framework-pkcs15.c:2721:pkcs15_prkey_sign: Sign complete. Result -1211.
0xb7ad06c0 17:20:14.190 [opensc-pkcs11] misc.c:59:sc_to_cryptoki_error_common: libopensc return value: -1211 (Security status not satisfied)
0xb7ad06c0 17:20:14.190 [opensc-pkcs11] pkcs11-object.c:691:C_SignFinal: C_SignFinal() = CKR_USER_NOT_LOGGED_IN
0xb7ad06c0 17:20:14.190 [opensc-pkcs11] pkcs11-global.c:290:C_Finalize: C_Finalize()
0xb7ad06c0 17:20:14.190 [opensc-pkcs11] ctx.c:714:sc_cancel: called
0xb7ad06c0 17:20:14.190 [opensc-pkcs11] reader-pcsc.c:591:pcsc_cancel: called
0xb7ad06c0 17:20:14.190 [opensc-pkcs11] slot.c:144:card_removed: ACS ACR38U 00 00: card removed
0xb7ad06c0 17:20:14.190 [opensc-pkcs11] slot.c:340:slot_token_removed: slot_token_removed(0x1)
0xb7ad06c0 17:20:14.190 [opensc-pkcs11] pkcs11-session.c:126:sc_pkcs11_close_all_sessions: real C_CloseAllSessions(0x1) 1
0xb7ad06c0 17:20:14.190 [opensc-pkcs11] pkcs11-session.c:98:sc_pkcs11_close_session: real C_CloseSession(0x806cf18)
0xb7ad06c0 17:20:14.190 [opensc-pkcs11] pkcs15-pin.c:596:sc_pkcs15_pincache_clear: called
0xb7ad06c0 17:20:14.190 [opensc-pkcs11] slot.c:340:slot_token_removed: slot_token_removed(0x2)
0xb7ad06c0 17:20:14.190 [opensc-pkcs11] pkcs11-session.c:126:sc_pkcs11_close_all_sessions: real C_CloseAllSessions(0x2) 0
0xb7ad06c0 17:20:14.190 [opensc-pkcs11] slot.c:340:slot_token_removed: slot_token_removed(0x3)
0xb7ad06c0 17:20:14.190 [opensc-pkcs11] pkcs11-session.c:126:sc_pkcs11_close_all_sessions: real C_CloseAllSessions(0x3) 0
0xb7ad06c0 17:20:14.190 [opensc-pkcs11] slot.c:340:slot_token_removed: slot_token_removed(0x4)
0xb7ad06c0 17:20:14.190 [opensc-pkcs11] pkcs11-session.c:126:sc_pkcs11_close_all_sessions: real C_CloseAllSessions(0x4) 0
0xb7ad06c0 17:20:14.190 [opensc-pkcs11] pkcs15.c:969:sc_pkcs15_unbind: called
0xb7ad06c0 17:20:14.190 [opensc-pkcs11] pkcs15-pin.c:596:sc_pkcs15_pincache_clear: called
0xb7ad06c0 17:20:14.190 [opensc-pkcs11] misc.c:59:sc_to_cryptoki_error_common: libopensc return value: 0 (Success)
0xb7ad06c0 17:20:14.190 [opensc-pkcs11] card.c:242:sc_disconnect_card: called
0xb7ad06c0 17:20:14.190 [opensc-pkcs11] reader-pcsc.c:498:pcsc_disconnect: called
0xb7ad06c0 17:20:14.277 [opensc-pkcs11] card.c:258:sc_disconnect_card: returning with: 0 (Success)
0xb7ad06c0 17:20:14.277 [opensc-pkcs11] ctx.c:737:sc_release_context: called
0xb7ad06c0 17:20:14.277 [opensc-pkcs11] reader-pcsc.c:736:pcsc_finish: called
_______________________________________________
opensc-devel mailing list
[hidden email]
http://www.opensc-project.org/mailman/listinfo/opensc-devel
Reply | Threaded
Open this post in threaded view
|

Re: Cryptotech Setcos card signing problem

TMS Brokers / Łukasz Kościesza
In reply to this post by Douglas E. Engert
-----Original Message-----
From: Douglas E. Engert [mailto:[hidden email]]
Sent: Friday, August 12, 2011 4:55 PM
To: TMS Brokers / Łukasz Kościesza
Cc: [hidden email]
Subject: Re: [opensc-devel] Cryptotech Setcos card signing problem

> Developers,
> Could this be a card that is enforcing user_consent. i.e. CKA_ALWAYS_AUTHENTICATIE and the pkcs11-tool is not doing this?
>
> The one card I know that does enforce it (newewr PIV cards), requires the sign operation to be preceeded immediatley by the presentation of the pin.
>
> Łukasz,
>  Can you look at your trace and see if any other operations are sent to the card between these too?


Hi all,

Were my log information helpful? Anyone has any ideas what is wrong here?


Regards
Łukasz
_______________________________________________
opensc-devel mailing list
[hidden email]
http://www.opensc-project.org/mailman/listinfo/opensc-devel
Reply | Threaded
Open this post in threaded view
|

Re: Cryptotech Setcos card signing problem

Viktor Tarasov-3
Hello,

Le 15/08/2011 12:40, TMS Brokers / Łukasz Kościesza a écrit :
> Were my log information helpful? Anyone has any ideas what is wrong here?

Can you set 'lock_login = true' in the pkcs11 section of opensc.conf
and re-try?

> Regards
> Łukasz

Kind wishes,
Viktor.

_______________________________________________
opensc-devel mailing list
[hidden email]
http://www.opensc-project.org/mailman/listinfo/opensc-devel
Reply | Threaded
Open this post in threaded view
|

Re: Cryptotech Setcos card signing problem

TMS Brokers / Łukasz Kościesza
-----Original Message-----
From: Viktor Tarasov [mailto:[hidden email]]
Sent: Monday, August 15, 2011 10:34 PM
To: TMS Brokers / Łukasz Kościesza
Cc: Douglas E. Engert; [hidden email]
Subject: Re: [opensc-devel] Cryptotech Setcos card signing problem

> Hello,

> Le 15/08/2011 12:40, TMS Brokers / Łukasz Kościesza a écrit :
>> Were my log information helpful? Anyone has any ideas what is wrong here?
> Can you set 'lock_login = true' in the pkcs11 section of opensc.conf and re-try?

It looks like it works!
Thanks a lot :)
_______________________________________________
opensc-devel mailing list
[hidden email]
http://www.opensc-project.org/mailman/listinfo/opensc-devel