[pkg-opensc-maint] Bug#786412: pkcs11-tool: pkcs15-pubkey.c:1064: sc_pkcs15_dup_pubkey: Assertion `key && out' failed.

[Damyan Ivanov]

Enabling 'debug = 9' in opensc.conf produces a lot of output, here's 
the tail:

0x7f7e0d56a700 16:02:04.317 [opensc-pkcs11] card.c:727:sc_read_record: called
0x7f7e0d56a700 16:02:04.317 [opensc-pkcs11] apdu.c:563:sc_transmit_apdu: called
0x7f7e0d56a700 16:02:04.317 [opensc-pkcs11] card.c:352:sc_lock: called
0x7f7e0d56a700 16:02:04.317 [opensc-pkcs11] apdu.c:530:sc_transmit: called
0x7f7e0d56a700 16:02:04.317 [opensc-pkcs11] apdu.c:384:sc_single_transmit: called
0x7f7e0d56a700 16:02:04.317 [opensc-pkcs11] apdu.c:389:sc_single_transmit: CLA:0, INS:B2, P1:1, P2:4, data(0) (nil)
0x7f7e0d56a700 16:02:04.317 [opensc-pkcs11] reader-pcsc.c:251:pcsc_transmit: reader 'OMNIKEY CardMan 6121 00 00'
0x7f7e0d56a700 16:02:04.317 [opensc-pkcs11] apdu.c:187:sc_apdu_log: 
Outgoing APDU data [    5 bytes] =====================================
00 B2 01 04 00 .....
======================================================================
0x7f7e0d56a700 16:02:04.317 [opensc-pkcs11] reader-pcsc.c:184:pcsc_internal_transmit: called
0x7f7e0d56a700 16:02:04.322 [opensc-pkcs11] apdu.c:187:sc_apdu_log: 
Incoming APDU data [    2 bytes] =====================================
6A 83 j.
======================================================================
0x7f7e0d56a700 16:02:04.322 [opensc-pkcs11] apdu.c:399:sc_single_transmit: returning with: 0 (Success)
0x7f7e0d56a700 16:02:04.322 [opensc-pkcs11] apdu.c:552:sc_transmit: returning with: 0 (Success)
0x7f7e0d56a700 16:02:04.322 [opensc-pkcs11] card.c:392:sc_unlock: called
0x7f7e0d56a700 16:02:04.322 [opensc-pkcs11] card-cardos.c:278:cardos_check_sw: record not found
0x7f7e0d56a700 16:02:04.322 [opensc-pkcs11] iso7816.c:210:iso7816_read_record: returning with: -1202 (Record not found)
0x7f7e0d56a700 16:02:04.322 [opensc-pkcs11] card.c:733:sc_read_record: returning with: -1202 (Record not found)
0x7f7e0d56a700 16:02:04.322 [opensc-pkcs11] card.c:392:sc_unlock: called
0x7f7e0d56a700 16:02:04.322 [opensc-pkcs11] reader-pcsc.c:556:pcsc_unlock: called
0x7f7e0d56a700 16:02:04.326 [opensc-pkcs11] pkcs15.c:2397:sc_pkcs15_read_file: returning with: 0 (Success)
0x7f7e0d56a700 16:02:04.326 [opensc-pkcs11] pkcs15.c:2098:sc_pkcs15_parse_df: returning with: 0 (Success)
0x7f7e0d56a700 16:02:04.326 [opensc-pkcs11] framework-pkcs15.c:728:pkcs15_create_pkcs11_objects: Found 0 data objects
0x7f7e0d56a700 16:02:04.326 [opensc-pkcs11] framework-pkcs15.c:836:pkcs15_bind_related_objects: Looking for objects related to object 0
0x7f7e0d56a700 16:02:04.326 [opensc-pkcs11] framework-pkcs15.c:743:__pkcs15_prkey_bind_related: Object is a private key and has id 69f9f837c4ef0c6eae38d61873dde123da79343a
0x7f7e0d56a700 16:02:04.326 [opensc-pkcs11] framework-pkcs15.c:768:__pkcs15_prkey_bind_related: Associating object 6 as public key
0x7f7e0d56a700 16:02:04.326 [opensc-pkcs11] pkcs15-algo.c:530:sc_asn1_encode_algorithm_id: called
0x7f7e0d56a700 16:02:04.326 [opensc-pkcs11] pkcs15-algo.c:531:sc_asn1_encode_algorithm_id: type of algorithm to encode: 0
0x7f7e0d56a700 16:02:04.326 [opensc-pkcs11] pkcs15-algo.c:545:sc_asn1_encode_algorithm_id: encode algo 1.2.840.113549.1.1.1
0x7f7e0d56a700 16:02:04.326 [opensc-pkcs11] asn1.c:1546:asn1_encode_entry:  encoding 'algorithm'
0x7f7e0d56a700 16:02:04.326 [opensc-pkcs11] asn1.c:1551:asn1_encode_entry:  type=6, tag=0x06, parm=0xc138f4, len=0
0x7f7e0d56a700 16:02:04.326 [opensc-pkcs11] asn1.c:1729:asn1_encode_entry:  length of encoded item=11
0x7f7e0d56a700 16:02:04.326 [opensc-pkcs11] asn1.c:1546:asn1_encode_entry:  encoding 'nullParam'
0x7f7e0d56a700 16:02:04.326 [opensc-pkcs11] asn1.c:1551:asn1_encode_entry:  type=5, tag=0x05, parm=(nil), len=0
0x7f7e0d56a700 16:02:04.326 [opensc-pkcs11] asn1.c:1729:asn1_encode_entry:  length of encoded item=2
0x7f7e0d56a700 16:02:04.326 [opensc-pkcs11] pkcs15-algo.c:580:sc_asn1_encode_algorithm_id: return encoded algorithm ID: 06092A864886F70D0101010500
0x7f7e0d56a700 16:02:04.326 [opensc-pkcs11] pkcs15-algo.c:581:sc_asn1_encode_algorithm_id: returning with: 0 (Success)
0x7f7e0d56a700 16:02:04.326 [opensc-pkcs11] pkcs15-algo.c:489:sc_asn1_decode_algorithm_id: called
0x7f7e0d56a700 16:02:04.326 [opensc-pkcs11] asn1.c:1443:asn1_decode:  called, left=13, depth 1
0x7f7e0d56a700 16:02:04.326 [opensc-pkcs11] asn1.c:1467:asn1_decode: Looking for 'algorithm', tag 0x6
0x7f7e0d56a700 16:02:04.326 [opensc-pkcs11] asn1.c:1259:asn1_decode_entry:  decoding 'algorithm'
0x7f7e0d56a700 16:02:04.326 [opensc-pkcs11] asn1.c:1467:asn1_decode: Looking for 'nullParam', tag 0x5, OPTIONAL
0x7f7e0d56a700 16:02:04.326 [opensc-pkcs11] asn1.c:1259:asn1_decode_entry:  decoding 'nullParam'
0x7f7e0d56a700 16:02:04.326 [opensc-pkcs11] asn1.c:1516:asn1_decode: returning with: 0 (Success)
0x7f7e0d56a700 16:02:04.326 [opensc-pkcs11] pkcs15-algo.c:497:sc_asn1_decode_algorithm_id: decoded OID '1.2.840.113549.1.1.1'
0x7f7e0d56a700 16:02:04.326 [opensc-pkcs11] pkcs15-algo.c:514:sc_asn1_decode_algorithm_id: returning with: 0 (Success)
0x7f7e0d56a700 16:02:04.326 [opensc-pkcs11] pkcs15-pubkey.c:1131:sc_pkcs15_dup_pubkey: returning with: 0 (Success)
0x7f7e0d56a700 16:02:04.326 [opensc-pkcs11] framework-pkcs15.c:836:pkcs15_bind_related_objects: Looking for objects related to object 1
0x7f7e0d56a700 16:02:04.326 [opensc-pkcs11] framework-pkcs15.c:743:__pkcs15_prkey_bind_related: Object is a private key and has id b05340a04f8a678d0058912ca1ac4c92037d7068
0x7f7e0d56a700 16:02:04.326 [opensc-pkcs11] framework-pkcs15.c:768:__pkcs15_prkey_bind_related: Associating object 7 as public key
pkcs11-tool: pkcs15-pubkey.c:1064: sc_pkcs15_dup_pubkey: Assertion `key && out' failed.
zsh: abort      pkcs11-tool -O

b05340a04f8a678d0058912ca1ac4c92037d7068 is indeed the ID of the 
private and public keys that have no matching certificate file.


HTH,
    dam