tried again with only opensc updated, everything else
kept the same: also broken. so it must be a bug in opensc.
debug log (level 6, but no DEBUG) attached.
Andreas
[EMAIL PROTECTED]:~$ openssl req -config openssl.conf -engine pkcs11 -new
-key id_45 -keyform engine -out req.pem -text -x509
-subj "/CN=Andreas Jellinghaus"
engine "pkcs11" set.
iso7816.c:99:iso7816_check_sw: Security status not satisfied
card-flex.c:1073:cryptoflex_compute_signature: Card returned error:
Security status not satisfied
sec.c:53:sc_compute_signature: returning with: Security status not satisfied
pkcs15-sec.c:332:sc_pkcs15_compute_signature: sc_compute_signature()
failed: Security status not satisfied
6193:error:8000A101:Vendor defined:PKCS11_rsa_sign:User not logged
in:p11_ops.c:96:
6193:error:0D0C3006:asn1 encoding routines:ASN1_item_sign:EVP
lib:a_sign.c:276:
[EMAIL PROTECTED]:~$
ctx.c:704:sc_context_create: ===================================
ctx.c:705:sc_context_create: opensc version: 0.11.2-pre2
reader-openct.c:89:openct_reader_init: called
slot.c:83:card_detect: 0: Detecting smart card
sc.c:196:sc_detect_card_presence: called
reader-openct.c:207:openct_reader_detect_card_presence: called
sc.c:201:sc_detect_card_presence: returning with: 1
slot.c:123:card_detect: 0: Connecting to smart card
card.c:110:sc_connect_card: called
reader-openct.c:231:openct_reader_connect: called
card.c:140:sc_connect_card: matching configured ATRs
card.c:182:sc_connect_card: matching built-in ATRs
card.c:188:sc_connect_card: trying driver: cardos
card.c:745:match_atr_table: ATR : 3b:95:18:40:ff:62:01:02:01:04
card.c:753:match_atr_table: ATR try : 3b:e2:00:ff:c1:10:31:fe:55:c8:02:9c
card.c:753:match_atr_table: ATR try : 3b:f2:98:00:ff:c1:10:31:fe:55:c8:03:15
card.c:753:match_atr_table: ATR try : 3b:f2:98:00:ff:c1:10:31:fe:55:c8:04:12
card.c:753:match_atr_table: ATR try : 3b:f2:18:00:ff:c1:0a:31:fe:55:c8:06:8a
card.c:753:match_atr_table: ATR try : 3b:f2:18:00:ff:c1:0a:31:fe:55:c8:06:75
card.c:753:match_atr_table: ATR try : 3b:f2:18:00:02:c1:0a:31:fe:55:c8:07:76
card.c:753:match_atr_table: ATR try : 3b:f2:18:00:02:c1:0a:31:fe:58:c8:08:74
card.c:753:match_atr_table: ATR try :
3b:e9:00:ff:c1:10:31:fe:55:00:64:05:00:c8:02:31:80:00:47
card.c:753:match_atr_table: ATR try :
3b:fb:98:00:ff:c1:10:31:fe:55:00:64:05:20:47:03:31:80:00:90:00:f3
card.c:753:match_atr_table: ATR try :
3b:fc:98:00:ff:c1:10:31:fe:55:c8:03:49:6e:66:6f:63:61:6d:65:72:65:28
card.c:753:match_atr_table: ATR try :
3b:f4:98:00:ff:c1:10:31:fe:55:4d:34:63:76:b4
card.c:188:sc_connect_card: trying driver: cardos
card.c:745:match_atr_table: ATR : 3b:95:18:40:ff:62:01:02:01:04
card.c:753:match_atr_table: ATR try : 3b:e2:00:ff:c1:10:31:fe:55:c8:02:9c
card.c:753:match_atr_table: ATR try : 3b:f2:98:00:ff:c1:10:31:fe:55:c8:03:15
card.c:753:match_atr_table: ATR try : 3b:f2:98:00:ff:c1:10:31:fe:55:c8:04:12
card.c:753:match_atr_table: ATR try : 3b:f2:18:00:ff:c1:0a:31:fe:55:c8:06:8a
card.c:753:match_atr_table: ATR try : 3b:f2:18:00:ff:c1:0a:31:fe:55:c8:06:75
card.c:753:match_atr_table: ATR try : 3b:f2:18:00:02:c1:0a:31:fe:55:c8:07:76
card.c:753:match_atr_table: ATR try : 3b:f2:18:00:02:c1:0a:31:fe:58:c8:08:74
card.c:753:match_atr_table: ATR try :
3b:e9:00:ff:c1:10:31:fe:55:00:64:05:00:c8:02:31:80:00:47
card.c:753:match_atr_table: ATR try :
3b:fb:98:00:ff:c1:10:31:fe:55:00:64:05:20:47:03:31:80:00:90:00:f3
card.c:753:match_atr_table: ATR try :
3b:fc:98:00:ff:c1:10:31:fe:55:c8:03:49:6e:66:6f:63:61:6d:65:72:65:28
card.c:753:match_atr_table: ATR try :
3b:f4:98:00:ff:c1:10:31:fe:55:4d:34:63:76:b4
card.c:188:sc_connect_card: trying driver: flex
card.c:745:match_atr_table: ATR : 3b:95:18:40:ff:62:01:02:01:04
card.c:753:match_atr_table: ATR try : 3b:95:15:40:20:68:01:02:00:00
card.c:753:match_atr_table: ATR try : 3B:95:15:40:FF:68:01:02:02:01
card.c:753:match_atr_table: ATR try : 3B:95:15:40:FF:68:01:02:02:04
card.c:753:match_atr_table: ATR try : 3B:85:40:20:68:01:01:05:01
card.c:753:match_atr_table: ATR try : 3B:95:94:40:FF:63:01:01:02:01
card.c:753:match_atr_table: ATR try : 3B:95:15:40:FF:63:01:01:02:01
card.c:762:match_atr_table: ATR mask: FF:FF:FF:FF:FF:FF:FF:FF:00:00
card.c:753:match_atr_table: ATR try : 3B:95:18:40:FF:64:02:01:01:02
card.c:762:match_atr_table: ATR mask: FF:FF:FF:FF:FF:FF:FF:FF:00:00
card.c:753:match_atr_table: ATR try : 3B:95:18:40:FF:62:01:01:00:00
card.c:762:match_atr_table: ATR mask: FF:FF:FF:FF:FF:FF:FF:FF:00:00
card.c:753:match_atr_table: ATR try : 3B:95:18:40:FF:62:01:02:01:04
card.c:196:sc_connect_card: matched: Schlumberger Multiflex/Cryptoflex
card.c:221:sc_connect_card: card info: Cryptoflex 32K e-gate, 2002, 0x1
card.c:222:sc_connect_card: returning with: 0
slot.c:131:card_detect: 0: Detecting Framework
pkcs15.c:700:sc_pkcs15_bind: called
card.c:291:sc_lock: called
reader-openct.c:435:openct_reader_lock: called
pkcs15.c:526:sc_pkcs15_bind_internal: trying normal pkcs15 processing
card.c:531:sc_select_file: called; type=2, path=3f002f00
card-flex.c:608:flex_select_file: called, cached path=
card-flex.c:547:select_file_id: called, p1=0, path=3f00
apdu.c:504:sc_transmit_apdu: called
card.c:311:sc_unlock: called
card-flex.c:547:select_file_id: called, p1=0, path=2f00
apdu.c:504:sc_transmit_apdu: called
apdu.c:504:sc_transmit_apdu: called
card.c:311:sc_unlock: called
card.c:311:sc_unlock: called
card.c:311:sc_unlock: called
card-flex.c:650:flex_select_file: returning with: 0
card.c:553:sc_select_file: returning with: 0
card.c:398:sc_read_binary: called; 128 bytes at index 0
apdu.c:504:sc_transmit_apdu: called
card.c:311:sc_unlock: called
iso7816.c:132:iso7816_read_binary: returning with: 128
card.c:429:sc_read_binary: returning with: 128
asn1.c:1123:asn1_decode: called, left=128, depth 0
asn1.c:1148:asn1_decode: Looking for 'dirRecord', tag 0x11000001
asn1.c:944:asn1_decode_entry: decoding 'dirRecord'
asn1.c:1123:asn1_decode: called, left=41, depth 1
asn1.c:1148:asn1_decode: Looking for 'aid', tag 0x1000000f
asn1.c:944:asn1_decode_entry: decoding 'aid'
asn1.c:1148:asn1_decode: Looking for 'label', tag 0x10000010, OPTIONAL
asn1.c:944:asn1_decode_entry: decoding 'label'
asn1.c:1148:asn1_decode: Looking for 'path', tag 0x10000011, OPTIONAL
asn1.c:944:asn1_decode_entry: decoding 'path'
asn1.c:1148:asn1_decode: Looking for 'ddo', tag 0x11000013, OPTIONAL
asn1.c:1164:asn1_decode: not present
asn1.c:1199:asn1_decode: returning with: 0
asn1.c:1199:asn1_decode: returning with: 0
asn1.c:1123:asn1_decode: called, left=85, depth 0
card.c:531:sc_select_file: called; type=2, path=3f005015
card-flex.c:608:flex_select_file: called, cached path=3f002f00
card-flex.c:547:select_file_id: called, p1=0, path=3f00
apdu.c:504:sc_transmit_apdu: called
card.c:311:sc_unlock: called
card-flex.c:547:select_file_id: called, p1=0, path=5015
apdu.c:504:sc_transmit_apdu: called
card.c:311:sc_unlock: called
card.c:311:sc_unlock: called
card-flex.c:650:flex_select_file: returning with: 0
card.c:553:sc_select_file: returning with: 0
card.c:531:sc_select_file: called; type=2, path=3f0050155031
card-flex.c:608:flex_select_file: called, cached path=3f005015
card-flex.c:547:select_file_id: called, p1=0, path=5031
apdu.c:504:sc_transmit_apdu: called
apdu.c:504:sc_transmit_apdu: called
card.c:311:sc_unlock: called
card.c:311:sc_unlock: called
card.c:311:sc_unlock: called
card-flex.c:650:flex_select_file: returning with: 0
card.c:553:sc_select_file: returning with: 0
card.c:398:sc_read_binary: called; 256 bytes at index 0
card.c:398:sc_read_binary: called; 248 bytes at index 0
apdu.c:504:sc_transmit_apdu: called
card.c:311:sc_unlock: called
iso7816.c:132:iso7816_read_binary: returning with: 248
card.c:429:sc_read_binary: returning with: 248
card.c:398:sc_read_binary: called; 8 bytes at index 248
apdu.c:504:sc_transmit_apdu: called
card.c:311:sc_unlock: called
iso7816.c:132:iso7816_read_binary: returning with: 8
card.c:429:sc_read_binary: returning with: 8
card.c:311:sc_unlock: called
card.c:426:sc_read_binary: returning with: 256
asn1.c:1123:asn1_decode: called, left=256, depth 0, choice
asn1.c:1148:asn1_decode: Looking for 'privateKeys', tag 0x21000000, CHOICE
asn1.c:1164:asn1_decode: not present
asn1.c:1148:asn1_decode: Looking for 'publicKeys', tag 0x21000001, CHOICE
asn1.c:1164:asn1_decode: not present
asn1.c:1148:asn1_decode: Looking for 'trustedPublicKeys', tag 0x21000002, CHOICE
asn1.c:1164:asn1_decode: not present
asn1.c:1148:asn1_decode: Looking for 'secretKeys', tag 0x21000003, CHOICE
asn1.c:1164:asn1_decode: not present
asn1.c:1148:asn1_decode: Looking for 'certificates', tag 0x21000004, CHOICE
asn1.c:1164:asn1_decode: not present
asn1.c:1148:asn1_decode: Looking for 'trustedCertificates', tag 0x21000005,
CHOICE
asn1.c:1164:asn1_decode: not present
asn1.c:1148:asn1_decode: Looking for 'usefulCertificates', tag 0x21000006,
CHOICE
asn1.c:1164:asn1_decode: not present
asn1.c:1148:asn1_decode: Looking for 'dataObjects', tag 0x21000007, CHOICE
asn1.c:1164:asn1_decode: not present
asn1.c:1148:asn1_decode: Looking for 'authObjects', tag 0x21000008, CHOICE
asn1.c:944:asn1_decode_entry: decoding 'authObjects'
asn1.c:1123:asn1_decode: called, left=10, depth 1
asn1.c:1148:asn1_decode: Looking for 'path', tag 0x1000010
asn1.c:944:asn1_decode_entry: decoding 'path'
asn1.c:1123:asn1_decode: called, left=8, depth 2
asn1.c:1148:asn1_decode: Looking for 'path', tag 0x4
asn1.c:944:asn1_decode_entry: decoding 'path'
asn1.c:1148:asn1_decode: Looking for 'index', tag 0x2, OPTIONAL
asn1.c:1164:asn1_decode: not present
asn1.c:1148:asn1_decode: Looking for 'length', tag 0x20000000, OPTIONAL
asn1.c:1164:asn1_decode: not present
asn1.c:1199:asn1_decode: returning with: 0
asn1.c:1199:asn1_decode: returning with: 0
asn1.c:1198:asn1_decode: returning with: 8
asn1.c:1123:asn1_decode: called, left=244, depth 0, choice
asn1.c:1148:asn1_decode: Looking for 'privateKeys', tag 0x21000000, CHOICE
asn1.c:944:asn1_decode_entry: decoding 'privateKeys'
asn1.c:1123:asn1_decode: called, left=10, depth 1
asn1.c:1148:asn1_decode: Looking for 'path', tag 0x1000010
asn1.c:944:asn1_decode_entry: decoding 'path'
asn1.c:1123:asn1_decode: called, left=8, depth 2
asn1.c:1148:asn1_decode: Looking for 'path', tag 0x4
asn1.c:944:asn1_decode_entry: decoding 'path'
asn1.c:1148:asn1_decode: Looking for 'index', tag 0x2, OPTIONAL
asn1.c:1164:asn1_decode: not present
asn1.c:1148:asn1_decode: Looking for 'length', tag 0x20000000, OPTIONAL
asn1.c:1164:asn1_decode: not present
asn1.c:1199:asn1_decode: returning with: 0
asn1.c:1199:asn1_decode: returning with: 0
asn1.c:1198:asn1_decode: returning with: 0
asn1.c:1123:asn1_decode: called, left=232, depth 0, choice
asn1.c:1148:asn1_decode: Looking for 'privateKeys', tag 0x21000000, CHOICE
asn1.c:1164:asn1_decode: not present
asn1.c:1148:asn1_decode: Looking for 'publicKeys', tag 0x21000001, CHOICE
asn1.c:944:asn1_decode_entry: decoding 'publicKeys'
asn1.c:1123:asn1_decode: called, left=10, depth 1
asn1.c:1148:asn1_decode: Looking for 'path', tag 0x1000010
asn1.c:944:asn1_decode_entry: decoding 'path'
asn1.c:1123:asn1_decode: called, left=8, depth 2
asn1.c:1148:asn1_decode: Looking for 'path', tag 0x4
asn1.c:944:asn1_decode_entry: decoding 'path'
asn1.c:1148:asn1_decode: Looking for 'index', tag 0x2, OPTIONAL
asn1.c:1164:asn1_decode: not present
asn1.c:1148:asn1_decode: Looking for 'length', tag 0x20000000, OPTIONAL
asn1.c:1164:asn1_decode: not present
asn1.c:1199:asn1_decode: returning with: 0
asn1.c:1199:asn1_decode: returning with: 0
asn1.c:1198:asn1_decode: returning with: 1
asn1.c:1123:asn1_decode: called, left=220, depth 0, choice
pkcs15.c:623:sc_pkcs15_bind_internal: The following DFs were found:
pkcs15.c:633:sc_pkcs15_bind_internal: DF type 8, path 3f0050154401, index 0,
count -1
pkcs15.c:633:sc_pkcs15_bind_internal: DF type 0, path 3f0050154402, index 0,
count -1
pkcs15.c:633:sc_pkcs15_bind_internal: DF type 1, path 3f0050154403, index 0,
count -1
card.c:531:sc_select_file: called; type=2, path=3f0050155032
card-flex.c:608:flex_select_file: called, cached path=3f0050155031
card-flex.c:547:select_file_id: called, p1=0, path=3f00
apdu.c:504:sc_transmit_apdu: called
card.c:311:sc_unlock: called
card-flex.c:547:select_file_id: called, p1=0, path=5015
apdu.c:504:sc_transmit_apdu: called
card.c:311:sc_unlock: called
card-flex.c:547:select_file_id: called, p1=0, path=5032
apdu.c:504:sc_transmit_apdu: called
apdu.c:504:sc_transmit_apdu: called
card.c:311:sc_unlock: called
card.c:311:sc_unlock: called
card.c:311:sc_unlock: called
card-flex.c:650:flex_select_file: returning with: 0
card.c:553:sc_select_file: returning with: 0
card.c:398:sc_read_binary: called; 73 bytes at index 0
apdu.c:504:sc_transmit_apdu: called
card.c:311:sc_unlock: called
iso7816.c:132:iso7816_read_binary: returning with: 73
card.c:429:sc_read_binary: returning with: 73
asn1.c:1123:asn1_decode: called, left=73, depth 0
asn1.c:1148:asn1_decode: Looking for 'TokenInfo', tag 0x1000010
asn1.c:944:asn1_decode_entry: decoding 'TokenInfo'
asn1.c:1123:asn1_decode: called, left=71, depth 1
asn1.c:1148:asn1_decode: Looking for 'version', tag 0x2
asn1.c:944:asn1_decode_entry: decoding 'version'
asn1.c:1148:asn1_decode: Looking for 'serialNumber', tag 0x4, OPTIONAL
asn1.c:944:asn1_decode_entry: decoding 'serialNumber'
asn1.c:1148:asn1_decode: Looking for 'manufacturerID', tag 0xc, OPTIONAL
asn1.c:944:asn1_decode_entry: decoding 'manufacturerID'
asn1.c:1148:asn1_decode: Looking for 'label', tag 0x20000000, OPTIONAL
asn1.c:944:asn1_decode_entry: decoding 'label'
asn1.c:1148:asn1_decode: Looking for 'label-tw', tag 0x21000000, OPTIONAL
asn1.c:1164:asn1_decode: not present
asn1.c:1148:asn1_decode: Looking for 'tokenflags', tag 0x3
asn1.c:944:asn1_decode_entry: decoding 'tokenflags'
asn1.c:1148:asn1_decode: Looking for 'seInfo', tag 0x1000010, OPTIONAL
asn1.c:1164:asn1_decode: not present
asn1.c:1148:asn1_decode: Looking for 'recordInfo', tag 0x21000001, OPTIONAL
asn1.c:1164:asn1_decode: not present
asn1.c:1148:asn1_decode: Looking for 'supportedAlgorithms', tag 0x21000002,
OPTIONAL
asn1.c:1164:asn1_decode: not present
asn1.c:1148:asn1_decode: Looking for 'issuerId', tag 0x20000003, OPTIONAL
asn1.c:1164:asn1_decode: not present
asn1.c:1148:asn1_decode: Looking for 'holderId', tag 0x20000004, OPTIONAL
asn1.c:1164:asn1_decode: not present
asn1.c:1148:asn1_decode: Looking for 'lastUpdate', tag 0x20000005, OPTIONAL
asn1.c:944:asn1_decode_entry: decoding 'lastUpdate'
asn1.c:1148:asn1_decode: Looking for 'preferredLanguage', tag 0x13, OPTIONAL
asn1.c:1164:asn1_decode: not present
asn1.c:1199:asn1_decode: returning with: 0
asn1.c:1199:asn1_decode: returning with: 0
card.c:311:sc_unlock: called
reader-openct.c:462:openct_reader_unlock: called
framework-pkcs15.c:157:pkcs15_bind: Binding to PKCS#15, rc=0
slot.c:145:card_detect: 0: Detected framework 0. Creating tokens.
pkcs15.c:1593:sc_pkcs15_read_file: called, path=3f0050154401, index=0, count=-1
card.c:291:sc_lock: called
reader-openct.c:435:openct_reader_lock: called
card.c:531:sc_select_file: called; type=2, path=3f0050154401
card-flex.c:608:flex_select_file: called, cached path=
card-flex.c:547:select_file_id: called, p1=0, path=3f00
apdu.c:504:sc_transmit_apdu: called
card.c:311:sc_unlock: called
card-flex.c:547:select_file_id: called, p1=0, path=5015
apdu.c:504:sc_transmit_apdu: called
card.c:311:sc_unlock: called
card-flex.c:547:select_file_id: called, p1=0, path=4401
apdu.c:504:sc_transmit_apdu: called
apdu.c:504:sc_transmit_apdu: called
card.c:311:sc_unlock: called
card.c:311:sc_unlock: called
card.c:311:sc_unlock: called
card-flex.c:650:flex_select_file: returning with: 0
card.c:553:sc_select_file: returning with: 0
card.c:398:sc_read_binary: called; 256 bytes at index 0
card.c:398:sc_read_binary: called; 248 bytes at index 0
apdu.c:504:sc_transmit_apdu: called
card.c:311:sc_unlock: called
iso7816.c:132:iso7816_read_binary: returning with: 248
card.c:429:sc_read_binary: returning with: 248
card.c:398:sc_read_binary: called; 8 bytes at index 248
apdu.c:504:sc_transmit_apdu: called
card.c:311:sc_unlock: called
iso7816.c:132:iso7816_read_binary: returning with: 8
card.c:429:sc_read_binary: returning with: 8
card.c:311:sc_unlock: called
card.c:426:sc_read_binary: returning with: 256
card.c:311:sc_unlock: called
reader-openct.c:462:openct_reader_unlock: called
asn1.c:1123:asn1_decode: called, left=256, depth 0
asn1.c:1148:asn1_decode: Looking for 'pin', tag 0x1000010
asn1.c:944:asn1_decode_entry: decoding 'pin'
asn1.c:1123:asn1_decode: called, left=52, depth 1
asn1.c:1148:asn1_decode: Looking for 'commonObjectAttributes', tag 0x1000010
asn1.c:944:asn1_decode_entry: decoding 'commonObjectAttributes'
asn1.c:1123:asn1_decode: called, left=14, depth 2
asn1.c:1148:asn1_decode: Looking for 'label', tag 0xc, OPTIONAL
asn1.c:944:asn1_decode_entry: decoding 'label'
asn1.c:1148:asn1_decode: Looking for 'flags', tag 0x3, OPTIONAL
asn1.c:944:asn1_decode_entry: decoding 'flags'
asn1.c:1148:asn1_decode: Looking for 'authId', tag 0x4, OPTIONAL
asn1.c:1164:asn1_decode: not present
asn1.c:1148:asn1_decode: Looking for 'userConsent', tag 0x2, OPTIONAL
asn1.c:1164:asn1_decode: not present
asn1.c:1148:asn1_decode: Looking for 'accessControlRules', tag 0x1000010,
OPTIONAL
asn1.c:1164:asn1_decode: not present
asn1.c:1199:asn1_decode: returning with: 0
asn1.c:1148:asn1_decode: Looking for 'classAttributes', tag 0x1000010
asn1.c:944:asn1_decode_entry: decoding 'classAttributes'
asn1.c:1123:asn1_decode: called, left=3, depth 2
asn1.c:1148:asn1_decode: Looking for 'authId', tag 0x4
asn1.c:944:asn1_decode_entry: decoding 'authId'
asn1.c:1199:asn1_decode: returning with: 0
asn1.c:1148:asn1_decode: Looking for 'subClassAttributes', tag 0x21000000,
OPTIONAL
asn1.c:1164:asn1_decode: not present
asn1.c:1148:asn1_decode: Looking for 'typeAttributes', tag 0x21000001
asn1.c:944:asn1_decode_entry: decoding 'typeAttributes'
asn1.c:1123:asn1_decode: called, left=29, depth 2
asn1.c:1148:asn1_decode: Looking for 'pinAttributes', tag 0x1000010
asn1.c:944:asn1_decode_entry: decoding 'pinAttributes'
asn1.c:1123:asn1_decode: called, left=27, depth 3
asn1.c:1148:asn1_decode: Looking for 'pinFlags', tag 0x3
asn1.c:944:asn1_decode_entry: decoding 'pinFlags'
asn1.c:1148:asn1_decode: Looking for 'pinType', tag 0xa
asn1.c:944:asn1_decode_entry: decoding 'pinType'
asn1.c:1148:asn1_decode: Looking for 'minLength', tag 0x2
asn1.c:944:asn1_decode_entry: decoding 'minLength'
asn1.c:1148:asn1_decode: Looking for 'storedLength', tag 0x2
asn1.c:944:asn1_decode_entry: decoding 'storedLength'
asn1.c:1148:asn1_decode: Looking for 'maxLength', tag 0x2, OPTIONAL
asn1.c:1164:asn1_decode: not present
asn1.c:1148:asn1_decode: Looking for 'pinReference', tag 0x20000000, OPTIONAL
asn1.c:944:asn1_decode_entry: decoding 'pinReference'
asn1.c:1148:asn1_decode: Looking for 'padChar', tag 0x4, OPTIONAL
asn1.c:944:asn1_decode_entry: decoding 'padChar'
asn1.c:1148:asn1_decode: Looking for 'lastPinChange', tag 0x18, OPTIONAL
asn1.c:1164:asn1_decode: not present
asn1.c:1148:asn1_decode: Looking for 'path', tag 0x1000010, OPTIONAL
asn1.c:944:asn1_decode_entry: decoding 'path'
asn1.c:1123:asn1_decode: called, left=6, depth 4
asn1.c:1148:asn1_decode: Looking for 'path', tag 0x4
asn1.c:944:asn1_decode_entry: decoding 'path'
asn1.c:1148:asn1_decode: Looking for 'index', tag 0x2, OPTIONAL
asn1.c:1164:asn1_decode: not present
asn1.c:1148:asn1_decode: Looking for 'length', tag 0x20000000, OPTIONAL
asn1.c:1164:asn1_decode: not present
asn1.c:1199:asn1_decode: returning with: 0
asn1.c:1199:asn1_decode: returning with: 0
asn1.c:1199:asn1_decode: returning with: 0
asn1.c:1199:asn1_decode: returning with: 0
asn1.c:1199:asn1_decode: returning with: 0
framework-pkcs15.c:720:pkcs15_create_tokens: Found 1 authentication objects
pkcs15.c:1593:sc_pkcs15_read_file: called, path=3f0050154402, index=0, count=-1
card.c:291:sc_lock: called
reader-openct.c:435:openct_reader_lock: called
card.c:531:sc_select_file: called; type=2, path=3f0050154402
card-flex.c:608:flex_select_file: called, cached path=
card-flex.c:547:select_file_id: called, p1=0, path=3f00
apdu.c:504:sc_transmit_apdu: called
card.c:311:sc_unlock: called
card-flex.c:547:select_file_id: called, p1=0, path=5015
apdu.c:504:sc_transmit_apdu: called
card.c:311:sc_unlock: called
card-flex.c:547:select_file_id: called, p1=0, path=4402
apdu.c:504:sc_transmit_apdu: called
apdu.c:504:sc_transmit_apdu: called
card.c:311:sc_unlock: called
card.c:311:sc_unlock: called
card.c:311:sc_unlock: called
card-flex.c:650:flex_select_file: returning with: 0
card.c:553:sc_select_file: returning with: 0
card.c:398:sc_read_binary: called; 256 bytes at index 0
card.c:398:sc_read_binary: called; 248 bytes at index 0
apdu.c:504:sc_transmit_apdu: called
card.c:311:sc_unlock: called
iso7816.c:132:iso7816_read_binary: returning with: 248
card.c:429:sc_read_binary: returning with: 248
card.c:398:sc_read_binary: called; 8 bytes at index 248
apdu.c:504:sc_transmit_apdu: called
card.c:311:sc_unlock: called
iso7816.c:132:iso7816_read_binary: returning with: 8
card.c:429:sc_read_binary: returning with: 8
card.c:311:sc_unlock: called
card.c:426:sc_read_binary: returning with: 256
card.c:311:sc_unlock: called
reader-openct.c:462:openct_reader_unlock: called
asn1.c:1123:asn1_decode: called, left=256, depth 0, choice
asn1.c:1148:asn1_decode: Looking for 'privateRSAKey', tag 0x1000010, CHOICE,
OPTIONAL
asn1.c:944:asn1_decode_entry: decoding 'privateRSAKey'
asn1.c:1123:asn1_decode: called, left=60, depth 1
asn1.c:1148:asn1_decode: Looking for 'commonObjectAttributes', tag 0x1000010
asn1.c:944:asn1_decode_entry: decoding 'commonObjectAttributes'
asn1.c:1123:asn1_decode: called, left=20, depth 2
asn1.c:1148:asn1_decode: Looking for 'label', tag 0xc, OPTIONAL
asn1.c:944:asn1_decode_entry: decoding 'label'
asn1.c:1148:asn1_decode: Looking for 'flags', tag 0x3, OPTIONAL
asn1.c:944:asn1_decode_entry: decoding 'flags'
asn1.c:1148:asn1_decode: Looking for 'authId', tag 0x4, OPTIONAL
asn1.c:944:asn1_decode_entry: decoding 'authId'
asn1.c:1148:asn1_decode: Looking for 'userConsent', tag 0x2, OPTIONAL
asn1.c:1164:asn1_decode: not present
asn1.c:1148:asn1_decode: Looking for 'accessControlRules', tag 0x1000010,
OPTIONAL
asn1.c:1164:asn1_decode: not present
asn1.c:1199:asn1_decode: returning with: 0
asn1.c:1148:asn1_decode: Looking for 'classAttributes', tag 0x1000010
asn1.c:944:asn1_decode_entry: decoding 'classAttributes'
asn1.c:1123:asn1_decode: called, left=14, depth 2
asn1.c:1148:asn1_decode: Looking for 'iD', tag 0x4
asn1.c:944:asn1_decode_entry: decoding 'iD'
asn1.c:1148:asn1_decode: Looking for 'usage', tag 0x3
asn1.c:944:asn1_decode_entry: decoding 'usage'
asn1.c:1148:asn1_decode: Looking for 'native', tag 0x1, OPTIONAL
asn1.c:1164:asn1_decode: not present
asn1.c:1148:asn1_decode: Looking for 'accessFlags', tag 0x3, OPTIONAL
asn1.c:944:asn1_decode_entry: decoding 'accessFlags'
asn1.c:1148:asn1_decode: Looking for 'keyReference', tag 0x2, OPTIONAL
asn1.c:944:asn1_decode_entry: decoding 'keyReference'
asn1.c:1199:asn1_decode: returning with: 0
asn1.c:1148:asn1_decode: Looking for 'subClassAttributes', tag 0x21000000,
OPTIONAL
asn1.c:944:asn1_decode_entry: decoding 'subClassAttributes'
asn1.c:1123:asn1_decode: called, left=0, depth 2
asn1.c:1148:asn1_decode: Looking for 'typeAttributes', tag 0x21000001
asn1.c:944:asn1_decode_entry: decoding 'typeAttributes'
asn1.c:1123:asn1_decode: called, left=18, depth 2
asn1.c:1148:asn1_decode: Looking for 'privateRSAKeyAttributes', tag 0x1000010
asn1.c:944:asn1_decode_entry: decoding 'privateRSAKeyAttributes'
asn1.c:1123:asn1_decode: called, left=16, depth 3
asn1.c:1148:asn1_decode: Looking for 'value', tag 0x1000010
asn1.c:944:asn1_decode_entry: decoding 'value'
asn1.c:1123:asn1_decode: called, left=10, depth 4
asn1.c:1148:asn1_decode: Looking for 'path', tag 0x4
asn1.c:944:asn1_decode_entry: decoding 'path'
asn1.c:1148:asn1_decode: Looking for 'index', tag 0x2, OPTIONAL
asn1.c:1164:asn1_decode: not present
asn1.c:1148:asn1_decode: Looking for 'length', tag 0x20000000, OPTIONAL
asn1.c:1164:asn1_decode: not present
asn1.c:1199:asn1_decode: returning with: 0
asn1.c:1148:asn1_decode: Looking for 'modulusLength', tag 0x2
asn1.c:944:asn1_decode_entry: decoding 'modulusLength'
asn1.c:1148:asn1_decode: Looking for 'keyInfo', tag 0x2, OPTIONAL
asn1.c:1164:asn1_decode: not present
asn1.c:1199:asn1_decode: returning with: 0
asn1.c:1199:asn1_decode: returning with: 0
asn1.c:1199:asn1_decode: returning with: 0
asn1.c:1198:asn1_decode: returning with: 0
framework-pkcs15.c:443:pkcs15_create_pkcs11_objects: Found 1 private key
pkcs15.c:1593:sc_pkcs15_read_file: called, path=3f0050154403, index=0, count=-1
card.c:291:sc_lock: called
reader-openct.c:435:openct_reader_lock: called
card.c:531:sc_select_file: called; type=2, path=3f0050154403
card-flex.c:608:flex_select_file: called, cached path=
card-flex.c:547:select_file_id: called, p1=0, path=3f00
apdu.c:504:sc_transmit_apdu: called
card.c:311:sc_unlock: called
card-flex.c:547:select_file_id: called, p1=0, path=5015
apdu.c:504:sc_transmit_apdu: called
card.c:311:sc_unlock: called
card-flex.c:547:select_file_id: called, p1=0, path=4403
apdu.c:504:sc_transmit_apdu: called
apdu.c:504:sc_transmit_apdu: called
card.c:311:sc_unlock: called
card.c:311:sc_unlock: called
card.c:311:sc_unlock: called
card-flex.c:650:flex_select_file: returning with: 0
card.c:553:sc_select_file: returning with: 0
card.c:398:sc_read_binary: called; 256 bytes at index 0
card.c:398:sc_read_binary: called; 248 bytes at index 0
apdu.c:504:sc_transmit_apdu: called
card.c:311:sc_unlock: called
iso7816.c:132:iso7816_read_binary: returning with: 248
card.c:429:sc_read_binary: returning with: 248
card.c:398:sc_read_binary: called; 8 bytes at index 248
apdu.c:504:sc_transmit_apdu: called
card.c:311:sc_unlock: called
iso7816.c:132:iso7816_read_binary: returning with: 8
card.c:429:sc_read_binary: returning with: 8
card.c:311:sc_unlock: called
card.c:426:sc_read_binary: returning with: 256
card.c:311:sc_unlock: called
reader-openct.c:462:openct_reader_unlock: called
asn1.c:1123:asn1_decode: called, left=256, depth 0
asn1.c:1148:asn1_decode: Looking for 'publicKey', tag 0x0
asn1.c:1123:asn1_decode: called, left=256, depth 1, choice
asn1.c:1148:asn1_decode: Looking for 'publicRSAKey', tag 0x1000010, CHOICE
asn1.c:944:asn1_decode_entry: decoding 'publicRSAKey'
asn1.c:1123:asn1_decode: called, left=53, depth 2
asn1.c:1148:asn1_decode: Looking for 'commonObjectAttributes', tag 0x1000010
asn1.c:944:asn1_decode_entry: decoding 'commonObjectAttributes'
asn1.c:1123:asn1_decode: called, left=16, depth 3
asn1.c:1148:asn1_decode: Looking for 'label', tag 0xc, OPTIONAL
asn1.c:944:asn1_decode_entry: decoding 'label'
asn1.c:1148:asn1_decode: Looking for 'flags', tag 0x3, OPTIONAL
asn1.c:944:asn1_decode_entry: decoding 'flags'
asn1.c:1148:asn1_decode: Looking for 'authId', tag 0x4, OPTIONAL
asn1.c:1164:asn1_decode: not present
asn1.c:1148:asn1_decode: Looking for 'userConsent', tag 0x2, OPTIONAL
asn1.c:1164:asn1_decode: not present
asn1.c:1148:asn1_decode: Looking for 'accessControlRules', tag 0x1000010,
OPTIONAL
asn1.c:1164:asn1_decode: not present
asn1.c:1199:asn1_decode: returning with: 0
asn1.c:1148:asn1_decode: Looking for 'classAttributes', tag 0x1000010
asn1.c:944:asn1_decode_entry: decoding 'classAttributes'
asn1.c:1123:asn1_decode: called, left=13, depth 3
asn1.c:1148:asn1_decode: Looking for 'iD', tag 0x4
asn1.c:944:asn1_decode_entry: decoding 'iD'
asn1.c:1148:asn1_decode: Looking for 'usage', tag 0x3
asn1.c:944:asn1_decode_entry: decoding 'usage'
asn1.c:1148:asn1_decode: Looking for 'native', tag 0x1, OPTIONAL
asn1.c:944:asn1_decode_entry: decoding 'native'
asn1.c:1148:asn1_decode: Looking for 'accessFlags', tag 0x3, OPTIONAL
asn1.c:1164:asn1_decode: not present
asn1.c:1148:asn1_decode: Looking for 'keyReference', tag 0x2, OPTIONAL
asn1.c:944:asn1_decode_entry: decoding 'keyReference'
asn1.c:1199:asn1_decode: returning with: 0
asn1.c:1148:asn1_decode: Looking for 'subClassAttributes', tag 0x21000000,
OPTIONAL
asn1.c:944:asn1_decode_entry: decoding 'subClassAttributes'
asn1.c:1123:asn1_decode: called, left=0, depth 3
asn1.c:1148:asn1_decode: Looking for 'typeAttributes', tag 0x21000001
asn1.c:944:asn1_decode_entry: decoding 'typeAttributes'
asn1.c:1123:asn1_decode: called, left=16, depth 3
asn1.c:1148:asn1_decode: Looking for 'publicRSAKeyAttributes', tag 0x1000010
asn1.c:944:asn1_decode_entry: decoding 'publicRSAKeyAttributes'
asn1.c:1123:asn1_decode: called, left=14, depth 4
asn1.c:1148:asn1_decode: Looking for 'value', tag 0x1000010
asn1.c:944:asn1_decode_entry: decoding 'value'
asn1.c:1123:asn1_decode: called, left=8, depth 5
asn1.c:1148:asn1_decode: Looking for 'path', tag 0x4
asn1.c:944:asn1_decode_entry: decoding 'path'
asn1.c:1148:asn1_decode: Looking for 'index', tag 0x2, OPTIONAL
asn1.c:1164:asn1_decode: not present
asn1.c:1148:asn1_decode: Looking for 'length', tag 0x20000000, OPTIONAL
asn1.c:1164:asn1_decode: not present
asn1.c:1199:asn1_decode: returning with: 0
asn1.c:1148:asn1_decode: Looking for 'modulusLength', tag 0x2
asn1.c:944:asn1_decode_entry: decoding 'modulusLength'
asn1.c:1148:asn1_decode: Looking for 'keyInfo', tag 0x2, OPTIONAL
asn1.c:1164:asn1_decode: not present
asn1.c:1199:asn1_decode: returning with: 0
asn1.c:1199:asn1_decode: returning with: 0
asn1.c:1199:asn1_decode: returning with: 0
asn1.c:1198:asn1_decode: returning with: 0
asn1.c:1199:asn1_decode: returning with: 0
framework-pkcs15.c:443:pkcs15_create_pkcs11_objects: Found 1 public key
pkcs15-pubkey.c:385:sc_pkcs15_read_pubkey: called
pkcs15.c:1593:sc_pkcs15_read_file: called, path=3f0050154445, index=0, count=-1
card.c:291:sc_lock: called
reader-openct.c:435:openct_reader_lock: called
card.c:531:sc_select_file: called; type=2, path=3f0050154445
card-flex.c:608:flex_select_file: called, cached path=
card-flex.c:547:select_file_id: called, p1=0, path=3f00
apdu.c:504:sc_transmit_apdu: called
card.c:311:sc_unlock: called
card-flex.c:547:select_file_id: called, p1=0, path=5015
apdu.c:504:sc_transmit_apdu: called
card.c:311:sc_unlock: called
card-flex.c:547:select_file_id: called, p1=0, path=4445
apdu.c:504:sc_transmit_apdu: called
apdu.c:504:sc_transmit_apdu: called
card.c:311:sc_unlock: called
card.c:311:sc_unlock: called
card.c:311:sc_unlock: called
card-flex.c:650:flex_select_file: returning with: 0
card.c:553:sc_select_file: returning with: 0
card.c:398:sc_read_binary: called; 140 bytes at index 0
apdu.c:504:sc_transmit_apdu: called
card.c:311:sc_unlock: called
iso7816.c:132:iso7816_read_binary: returning with: 140
card.c:429:sc_read_binary: returning with: 140
card.c:311:sc_unlock: called
reader-openct.c:462:openct_reader_unlock: called
asn1.c:1123:asn1_decode: called, left=140, depth 0
asn1.c:1148:asn1_decode: Looking for 'publicKeyCoefficients', tag 0x1000010
asn1.c:944:asn1_decode_entry: decoding 'publicKeyCoefficients'
asn1.c:1123:asn1_decode: called, left=137, depth 1
asn1.c:1148:asn1_decode: Looking for 'modulus', tag 0x2
asn1.c:944:asn1_decode_entry: decoding 'modulus'
asn1.c:1148:asn1_decode: Looking for 'exponent', tag 0x2
asn1.c:944:asn1_decode_entry: decoding 'exponent'
asn1.c:1199:asn1_decode: returning with: 0
asn1.c:1199:asn1_decode: returning with: 0
framework-pkcs15.c:443:pkcs15_create_pkcs11_objects: Found 0 certificates
framework-pkcs15.c:443:pkcs15_create_pkcs11_objects: Found 0 data objects
slot.c:232:slot_allocate: Allocated slot 0
framework-pkcs15.c:680:pkcs15_init_slot: Initialized token 'Andreas Jellinghaus
(User PIN)'
framework-pkcs15.c:776:pkcs15_create_tokens: Adding private key 0 to PIN 0
slot.c:232:slot_allocate: Allocated slot 1
slot.c:232:slot_allocate: Allocated slot 2
slot.c:232:slot_allocate: Allocated slot 3
framework-pkcs15.c:822:pkcs15_create_tokens: All tokens created
slot.c:153:card_detect: 0: Detection ended
slot.c:83:card_detect: 1: Detecting smart card
sc.c:196:sc_detect_card_presence: called
reader-openct.c:207:openct_reader_detect_card_presence: called
sc.c:201:sc_detect_card_presence: returning with: 0
slot.c:104:card_detect: 1: Card absent
slot.c:185:card_removed: 1: smart card removed
slot.c:83:card_detect: 2: Detecting smart card
sc.c:196:sc_detect_card_presence: called
reader-openct.c:207:openct_reader_detect_card_presence: called
sc.c:201:sc_detect_card_presence: returning with: 0
slot.c:104:card_detect: 2: Card absent
slot.c:185:card_removed: 2: smart card removed
slot.c:83:card_detect: 3: Detecting smart card
sc.c:196:sc_detect_card_presence: called
reader-openct.c:207:openct_reader_detect_card_presence: called
sc.c:201:sc_detect_card_presence: returning with: 0
slot.c:104:card_detect: 3: Card absent
slot.c:185:card_removed: 3: smart card removed
slot.c:83:card_detect: 4: Detecting smart card
sc.c:196:sc_detect_card_presence: called
reader-openct.c:207:openct_reader_detect_card_presence: called
sc.c:201:sc_detect_card_presence: returning with: 0
slot.c:104:card_detect: 4: Card absent
slot.c:185:card_removed: 4: smart card removed
pkcs11-global.c:213:C_Initialize: C_Initialize: result = 0
pkcs11-global.c:257:C_GetInfo: Cryptoki info query
pkcs11-global.c:303:C_GetSlotList: Getting slot listing
slot.c:83:card_detect: 0: Detecting smart card
sc.c:196:sc_detect_card_presence: called
reader-openct.c:207:openct_reader_detect_card_presence: called
sc.c:201:sc_detect_card_presence: returning with: 1
slot.c:153:card_detect: 0: Detection ended
slot.c:83:card_detect: 1: Detecting smart card
sc.c:196:sc_detect_card_presence: called
reader-openct.c:207:openct_reader_detect_card_presence: called
sc.c:201:sc_detect_card_presence: returning with: 0
slot.c:104:card_detect: 1: Card absent
slot.c:185:card_removed: 1: smart card removed
slot.c:83:card_detect: 2: Detecting smart card
sc.c:196:sc_detect_card_presence: called
reader-openct.c:207:openct_reader_detect_card_presence: called
sc.c:201:sc_detect_card_presence: returning with: 0
slot.c:104:card_detect: 2: Card absent
slot.c:185:card_removed: 2: smart card removed
slot.c:83:card_detect: 3: Detecting smart card
sc.c:196:sc_detect_card_presence: called
reader-openct.c:207:openct_reader_detect_card_presence: called
sc.c:201:sc_detect_card_presence: returning with: 0
slot.c:104:card_detect: 3: Card absent
slot.c:185:card_removed: 3: smart card removed
slot.c:83:card_detect: 4: Detecting smart card
sc.c:196:sc_detect_card_presence: called
reader-openct.c:207:openct_reader_detect_card_presence: called
sc.c:201:sc_detect_card_presence: returning with: 0
slot.c:104:card_detect: 4: Card absent
slot.c:185:card_removed: 4: smart card removed
pkcs11-global.c:315:C_GetSlotList: was only a size inquiry (8)
pkcs11-global.c:303:C_GetSlotList: Getting slot listing
slot.c:83:card_detect: 0: Detecting smart card
sc.c:196:sc_detect_card_presence: called
reader-openct.c:207:openct_reader_detect_card_presence: called
sc.c:201:sc_detect_card_presence: returning with: 1
slot.c:153:card_detect: 0: Detection ended
slot.c:83:card_detect: 1: Detecting smart card
sc.c:196:sc_detect_card_presence: called
reader-openct.c:207:openct_reader_detect_card_presence: called
sc.c:201:sc_detect_card_presence: returning with: 0
slot.c:104:card_detect: 1: Card absent
slot.c:185:card_removed: 1: smart card removed
slot.c:83:card_detect: 2: Detecting smart card
sc.c:196:sc_detect_card_presence: called
reader-openct.c:207:openct_reader_detect_card_presence: called
sc.c:201:sc_detect_card_presence: returning with: 0
slot.c:104:card_detect: 2: Card absent
slot.c:185:card_removed: 2: smart card removed
slot.c:83:card_detect: 3: Detecting smart card
sc.c:196:sc_detect_card_presence: called
reader-openct.c:207:openct_reader_detect_card_presence: called
sc.c:201:sc_detect_card_presence: returning with: 0
slot.c:104:card_detect: 3: Card absent
slot.c:185:card_removed: 3: smart card removed
slot.c:83:card_detect: 4: Detecting smart card
sc.c:196:sc_detect_card_presence: called
reader-openct.c:207:openct_reader_detect_card_presence: called
sc.c:201:sc_detect_card_presence: returning with: 0
slot.c:104:card_detect: 4: Card absent
slot.c:185:card_removed: 4: smart card removed
pkcs11-global.c:332:C_GetSlotList: returned 8 slots
pkcs11-global.c:380:C_GetSlotInfo: Getting info about slot 0
slot.c:83:card_detect: 0: Detecting smart card
sc.c:196:sc_detect_card_presence: called
reader-openct.c:207:openct_reader_detect_card_presence: called
sc.c:201:sc_detect_card_presence: returning with: 1
slot.c:153:card_detect: 0: Detection ended
pkcs11-global.c:416:C_GetTokenInfo: Getting info about token in slot 0
pkcs11-global.c:380:C_GetSlotInfo: Getting info about slot 1
pkcs11-global.c:380:C_GetSlotInfo: Getting info about slot 2
pkcs11-global.c:380:C_GetSlotInfo: Getting info about slot 3
pkcs11-global.c:380:C_GetSlotInfo: Getting info about slot 4
slot.c:83:card_detect: 1: Detecting smart card
sc.c:196:sc_detect_card_presence: called
reader-openct.c:207:openct_reader_detect_card_presence: called
sc.c:201:sc_detect_card_presence: returning with: 0
slot.c:104:card_detect: 1: Card absent
slot.c:185:card_removed: 1: smart card removed
pkcs11-global.c:380:C_GetSlotInfo: Getting info about slot 5
pkcs11-global.c:380:C_GetSlotInfo: Getting info about slot 6
pkcs11-global.c:380:C_GetSlotInfo: Getting info about slot 7
pkcs11-session.c:40:C_OpenSession: Opening new session for slot 0
pkcs11-object.c:210:C_FindObjectsInit: C_FindObjectsInit(slot = 0)
pkcs11-object.c:211:C_FindObjectsInit: C_FindObjectsInit(): CKA_CLASS =
CKO_CERTIFICATE
pkcs11-object.c:240:C_FindObjectsInit: Object 0/1: Private object and not
logged in.
pkcs11-object.c:254:C_FindObjectsInit: Object 0/2: Attribute 0x0 does NOT match.
pkcs11-object.c:280:C_FindObjectsInit: 0 matching objects
pkcs11-session.c:239:C_Login: Login for session 1
card.c:291:sc_lock: called
reader-openct.c:435:openct_reader_lock: called
card.c:531:sc_select_file: called; type=2, path=3f005015
card-flex.c:608:flex_select_file: called, cached path=
card-flex.c:547:select_file_id: called, p1=0, path=3f00
apdu.c:504:sc_transmit_apdu: called
card.c:311:sc_unlock: called
card-flex.c:547:select_file_id: called, p1=0, path=5015
apdu.c:504:sc_transmit_apdu: called
card.c:311:sc_unlock: called
card.c:311:sc_unlock: called
card-flex.c:650:flex_select_file: returning with: 0
card.c:553:sc_select_file: returning with: 0
sec.c:154:sc_pin_cmd: called
apdu.c:504:sc_transmit_apdu: called
card.c:311:sc_unlock: called
sec.c:201:sc_pin_cmd: returning with: 0
card.c:311:sc_unlock: called
reader-openct.c:462:openct_reader_unlock: called
framework-pkcs15.c:895:pkcs15_login: PIN verification returned 0
pkcs11-object.c:210:C_FindObjectsInit: C_FindObjectsInit(slot = 0)
pkcs11-object.c:211:C_FindObjectsInit: C_FindObjectsInit(): CKA_CLASS =
CKO_PRIVATE_KEY
pkcs11-object.c:263:C_FindObjectsInit: Object 0/1: Attribute 0x0 matches.
pkcs11-object.c:269:C_FindObjectsInit: Object 0/1 matches
pkcs11-object.c:254:C_FindObjectsInit: Object 0/2: Attribute 0x0 does NOT match.
pkcs11-object.c:280:C_FindObjectsInit: 1 matching objects
pkcs11-object.c:124:C_GetAttributeValue: Object 1: CKA_KEY_TYPE = CKK_RSA
pkcs11-object.c:124:C_GetAttributeValue: Object 1: CKA_LABEL = Private Key
pkcs11-object.c:124:C_GetAttributeValue: Object 1: CKA_ID = 45
pkcs11-object.c:124:C_GetAttributeValue: Object 1: CKA_ID = 45
pkcs11-object.c:210:C_FindObjectsInit: C_FindObjectsInit(slot = 0)
pkcs11-object.c:211:C_FindObjectsInit: C_FindObjectsInit(): CKA_CLASS =
CKO_PUBLIC_KEY
pkcs11-object.c:254:C_FindObjectsInit: Object 0/1: Attribute 0x0 does NOT match.
pkcs11-object.c:263:C_FindObjectsInit: Object 0/2: Attribute 0x0 matches.
pkcs11-object.c:269:C_FindObjectsInit: Object 0/2 matches
pkcs11-object.c:280:C_FindObjectsInit: 1 matching objects
pkcs11-object.c:124:C_GetAttributeValue: Object 2: CKA_KEY_TYPE = CKK_RSA
pkcs11-object.c:124:C_GetAttributeValue: Object 2: CKA_LABEL = Public Key
pkcs11-object.c:124:C_GetAttributeValue: Object 2: CKA_ID = 45
pkcs11-object.c:124:C_GetAttributeValue: Object 2: CKA_ID = 45
pkcs11-object.c:124:C_GetAttributeValue: Object 1: CKA_SENSITIVE = TRUE
pkcs11-object.c:124:C_GetAttributeValue: Object 1: CKA_EXTRACTABLE = FALSE
pkcs11-object.c:124:C_GetAttributeValue: Object 1: CKA_MODULUS =
A7D27E0086069D6B1BE4EF1DD11333B46027B40F233425DE57F0405AB4443A48
pkcs11-object.c:124:C_GetAttributeValue: Object 1: CKA_PUBLIC_EXPONENT = 010001
pkcs11-object.c:124:C_GetAttributeValue: Object 1: CKA_MODULUS =
A7D27E0086069D6B1BE4EF1DD11333B46027B40F233425DE57F0405AB4443A48
pkcs11-object.c:485:C_SignInit: Sign initialization returns 0
framework-pkcs15.c:1928:pkcs15_prkey_sign: Initiating signing operation,
mechanism 0x1.
card.c:291:sc_lock: called
reader-openct.c:435:openct_reader_lock: called
framework-pkcs15.c:2779:reselect_app_df: reselect application df
card.c:531:sc_select_file: called; type=2, path=3f005015
card-flex.c:608:flex_select_file: called, cached path=
card-flex.c:547:select_file_id: called, p1=0, path=3f00
apdu.c:504:sc_transmit_apdu: called
card.c:311:sc_unlock: called
card-flex.c:547:select_file_id: called, p1=0, path=5015
apdu.c:504:sc_transmit_apdu: called
card.c:311:sc_unlock: called
card.c:311:sc_unlock: called
card-flex.c:650:flex_select_file: returning with: 0
card.c:553:sc_select_file: returning with: 0
framework-pkcs15.c:1972:pkcs15_prkey_sign: Selected flags 12. Now computing
signature for 35 bytes. 128 bytes reserved.
pkcs15-sec.c:163:sc_pkcs15_compute_signature: called
card.c:531:sc_select_file: called; type=2, path=3f00501530450012
card-flex.c:608:flex_select_file: called, cached path=3f005015
card-flex.c:547:select_file_id: called, p1=0, path=3045
apdu.c:504:sc_transmit_apdu: called
card.c:311:sc_unlock: called
card-flex.c:547:select_file_id: called, p1=0, path=0012
apdu.c:504:sc_transmit_apdu: called
card.c:311:sc_unlock: called
card.c:311:sc_unlock: called
card-flex.c:650:flex_select_file: returning with: 0
card.c:553:sc_select_file: returning with: 0
sec.c:63:sc_set_security_env: called
sec.c:67:sc_set_security_env: returning with: 0
sec.c:49:sc_compute_signature: called
apdu.c:504:sc_transmit_apdu: called
card.c:311:sc_unlock: called
card.c:311:sc_unlock: called
framework-pkcs15.c:2584:revalidate_pin: PIN revalidation
card.c:311:sc_unlock: called
reader-openct.c:462:openct_reader_unlock: called
framework-pkcs15.c:1992:pkcs15_prkey_sign: Sign complete. Result -1211.
pkcs11-object.c:527:C_Sign: Signing result was 257
pkcs11-global.c:231:C_Finalize: Shutting down Cryptoki
slot.c:185:card_removed: 0: smart card removed
pkcs11-session.c:115:sc_pkcs11_close_all_sessions: C_CloseAllSessions(slot 0).
apdu.c:504:sc_transmit_apdu: called
card.c:291:sc_lock: called
reader-openct.c:435:openct_reader_lock: called
card.c:311:sc_unlock: called
reader-openct.c:462:openct_reader_unlock: called
card-flex.c:1344:flex_logout: returning with: 0
pkcs11-session.c:115:sc_pkcs11_close_all_sessions: C_CloseAllSessions(slot 1).
pkcs11-session.c:115:sc_pkcs11_close_all_sessions: C_CloseAllSessions(slot 2).
pkcs11-session.c:115:sc_pkcs11_close_all_sessions: C_CloseAllSessions(slot 3).
pkcs15.c:775:sc_pkcs15_unbind: called
card.c:236:sc_disconnect_card: called
reader-openct.c:280:openct_reader_disconnect: called
card.c:251:sc_disconnect_card: returning with: 0
slot.c:185:card_removed: 1: smart card removed
slot.c:185:card_removed: 2: smart card removed
slot.c:185:card_removed: 3: smart card removed
slot.c:185:card_removed: 4: smart card removed
ctx.c:737:sc_release_context: called
reader-openct.c:180:openct_reader_release: called
reader-openct.c:180:openct_reader_release: called
reader-openct.c:180:openct_reader_release: called
reader-openct.c:180:openct_reader_release: called
reader-openct.c:180:openct_reader_release: called
reader-openct.c:165:openct_reader_finish: called
_______________________________________________
opensc-devel mailing list
opensc-devel@lists.opensc-project.org
http://www.opensc-project.org/mailman/listinfo/opensc-devel
