> do you still have that RHEL5 machine? if you had a debug log from it too, > that could help in seeing what changed. the interesting parts start with > the first "C_Sign" line.
Here comes the debug-output from my RHEL5 System. Regards, Dominik
ctx.c:695:sc_context_create: =================================== ctx.c:696:sc_context_create: opensc version: 0.11.1 slot.c:83:card_detect: 0: Detecting smart card sc.c:168:sc_detect_card_presence: called sc.c:173:sc_detect_card_presence: returning with: 1 slot.c:123:card_detect: 0: Connecting to smart card card.c:110:sc_connect_card: called card.c:755:match_atr_table: ATR : 3b:b7:94:00:81:31:fe:65:53:50:4b:32:33:90:00:d1 card.c:763:match_atr_table: ATR try : 3b:6e:00:ff:45:73:74:45:49:44:20:76:65:72:20:31:2e:30 card.c:763:match_atr_table: ATR try : 3b:fe:94:00:ff:80:b1:fa:45:1f:03:45:73:74:45:49:44:20:76:65:72:20:31:2e:30:43 reader-pcsc.c:534:pcsc_connect: Requesting reader features ... 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:755:match_atr_table: ATR : 3b:b7:94:00:81:31:fe:65:53:50:4b:32:33:90:00:d1 card.c:763:match_atr_table: ATR try : 3b:e2:00:ff:c1:10:31:fe:55:c8:02:9c card.c:763:match_atr_table: ATR try : 3b:f2:98:00:ff:c1:10:31:fe:55:c8:03:15 card.c:763:match_atr_table: ATR try : 3b:f2:98:00:ff:c1:10:31:fe:55:c8:04:12 card.c:763:match_atr_table: ATR try : 3b:f2:18:00:ff:c1:0a:31:fe:55:c8:06:8a card.c:763:match_atr_table: ATR try : 3b:f2:18:00:02:c1:0a:31:fe;55:c8:07:76 card.c:763: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:763: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:763: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:763: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:755:match_atr_table: ATR : 3b:b7:94:00:81:31:fe:65:53:50:4b:32:33:90:00:d1 card.c:763:match_atr_table: ATR try : 3b:e2:00:ff:c1:10:31:fe:55:c8:02:9c card.c:763:match_atr_table: ATR try : 3b:f2:98:00:ff:c1:10:31:fe:55:c8:03:15 card.c:763:match_atr_table: ATR try : 3b:f2:98:00:ff:c1:10:31:fe:55:c8:04:12 card.c:763:match_atr_table: ATR try : 3b:f2:18:00:ff:c1:0a:31:fe:55:c8:06:8a card.c:763:match_atr_table: ATR try : 3b:f2:18:00:02:c1:0a:31:fe;55:c8:07:76 card.c:763: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:763: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:763: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:763: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:755:match_atr_table: ATR : 3b:b7:94:00:81:31:fe:65:53:50:4b:32:33:90:00:d1 card.c:763:match_atr_table: ATR try : 3b:95:15:40:20:68:01:02:00:00 card.c:763:match_atr_table: ATR try : 3B:95:15:40:FF:68:01:02:02:01 card.c:763:match_atr_table: ATR try : 3B:95:15:40:FF:68:01:02:02:04 card.c:763:match_atr_table: ATR try : 3B:85:40:20:68:01:01:05:01 card.c:763:match_atr_table: ATR try : 3B:95:94:40:FF:63:01:01:02:01 card.c:763:match_atr_table: ATR try : 3B:95:18:40:FF:64:02:01:01:02 card.c:763:match_atr_table: ATR try : 3B:95:18:40:FF:62:01:02:01:04 card.c:763:match_atr_table: ATR try : 3B:95:18:40:FF:62:04:01:01:05 card.c:763:match_atr_table: ATR try : 3B:E2:00:00:40:20:49:06 card.c:763:match_atr_table: ATR try : 3B:E2:00:00:40:20:49:05 card.c:763:match_atr_table: ATR try : 3B:E2:00:00:40:20:49:07 card.c:763:match_atr_table: ATR try : 3B:85:40:20:68:01:01:03:05 card.c:763:match_atr_table: ATR try : 3B:02:14:50 card.c:763:match_atr_table: ATR try : 3B:19:14:55:90:01:02:01:00:05:04:B0 card.c:763:match_atr_table: ATR try : 3B:32:15:00:06:80 card.c:763:match_atr_table: ATR try : 3B:32:15:00:06:95 card.c:763:match_atr_table: ATR try : 3B:19:14:59:01:01:0F:01:00:05:08:B0 card.c:763:match_atr_table: ATR try : 3B:19:14:55:90:01:01:01:00:05:08:B0 card.c:763:match_atr_table: ATR try : 3B:16:94:81:10:06:01:81:3F card.c:763:match_atr_table: ATR try : 3B:16:94:81:10:06:01:81:2F card.c:188:sc_connect_card: trying driver: cyberflex card.c:755:match_atr_table: ATR : 3b:b7:94:00:81:31:fe:65:53:50:4b:32:33:90:00:d1 card.c:763:match_atr_table: ATR try : 3b:95:15:40:20:68:01:02:00:00 card.c:763:match_atr_table: ATR try : 3B:95:15:40:FF:68:01:02:02:01 card.c:763:match_atr_table: ATR try : 3B:95:15:40:FF:68:01:02:02:04 card.c:763:match_atr_table: ATR try : 3B:85:40:20:68:01:01:05:01 card.c:763:match_atr_table: ATR try : 3B:95:94:40:FF:63:01:01:02:01 card.c:763:match_atr_table: ATR try : 3B:95:18:40:FF:64:02:01:01:02 card.c:763:match_atr_table: ATR try : 3B:95:18:40:FF:62:01:02:01:04 card.c:763:match_atr_table: ATR try : 3B:95:18:40:FF:62:04:01:01:05 card.c:763:match_atr_table: ATR try : 3B:E2:00:00:40:20:49:06 card.c:763:match_atr_table: ATR try : 3B:E2:00:00:40:20:49:05 card.c:763:match_atr_table: ATR try : 3B:E2:00:00:40:20:49:07 card.c:763:match_atr_table: ATR try : 3B:85:40:20:68:01:01:03:05 card.c:763:match_atr_table: ATR try : 3B:02:14:50 card.c:763:match_atr_table: ATR try : 3B:19:14:55:90:01:02:01:00:05:04:B0 card.c:763:match_atr_table: ATR try : 3B:32:15:00:06:80 card.c:763:match_atr_table: ATR try : 3B:32:15:00:06:95 card.c:763:match_atr_table: ATR try : 3B:19:14:59:01:01:0F:01:00:05:08:B0 card.c:763:match_atr_table: ATR try : 3B:19:14:55:90:01:01:01:00:05:08:B0 card.c:763:match_atr_table: ATR try : 3B:16:94:81:10:06:01:81:3F card.c:763:match_atr_table: ATR try : 3B:16:94:81:10:06:01:81:2F card.c:188:sc_connect_card: trying driver: gpk card.c:755:match_atr_table: ATR : 3b:b7:94:00:81:31:fe:65:53:50:4b:32:33:90:00:d1 card.c:763:match_atr_table: ATR try : 3B:27:00:80:65:A2:04:01:01:37 card.c:763:match_atr_table: ATR try : 3B:27:00:80:65:A2:05:01:01:37 card.c:763:match_atr_table: ATR try : 3B:27:00:80:65:A2:0C:01:01:37 card.c:763:match_atr_table: ATR try : 3B:A7:00:40:14:80:65:A2:14:01:01:37 card.c:763:match_atr_table: ATR try : 3B:A7:00:40:18:80:65:A2:08:01:01:52 card.c:763:match_atr_table: ATR try : 3B:A7:00:40:18:80:65:A2:09:01:01:52 card.c:763:match_atr_table: ATR try : 3B:A7:00:40:18:80:65:A2:09:01:02:52 card.c:763:match_atr_table: ATR try : 3B:A7:00:40:18:80:65:A2:09:01:03:52 card.c:188:sc_connect_card: trying driver: miocos card.c:755:match_atr_table: ATR : 3b:b7:94:00:81:31:fe:65:53:50:4b:32:33:90:00:d1 card.c:763:match_atr_table: ATR try : 3B:9D:94:40:23:00:68:10:11:4D:69:6F:43:4F:53:00:90:00 card.c:763:match_atr_table: ATR try : 3B:9D:94:40:23:00:68:20:01:4D:69:6F:43:4F:53:00:90:00 card.c:188:sc_connect_card: trying driver: mcrd card.c:755:match_atr_table: ATR : 3b:b7:94:00:81:31:fe:65:53:50:4b:32:33:90:00:d1 card.c:763:match_atr_table: ATR try : 3B:FF:94:00:FF:80:B1:FE:45:1F:03:00:68:D2:76:00:00:28:FF:05:1E:31:80:00:90:00:23 card.c:763:match_atr_table: ATR try : 3B:FE:94:00:FF:80:B1:FA:45:1F:03:45:73:74:45:49:44:20:76:65:72:20:31:2E:30:43 card.c:763:match_atr_table: ATR try : 3B:6E:00:FF:45:73:74:45:49:44:20:76:65:72:20:31:2E:30 card.c:763:match_atr_table: ATR try : 3b:6f:00:ff:00:68:d2:76:00:00:28:ff:05:1e:31:80:00:90:00 card.c:763:match_atr_table: ATR try : 3b:ff:11:00:ff:80:b1:fe:45:1f:03:00:68:d2:76:00:00:28:ff:05:1e:31:80:00:90:00:a6 card.c:188:sc_connect_card: trying driver: setcos card.c:755:match_atr_table: ATR : 3b:b7:94:00:81:31:fe:65:53:50:4b:32:33:90:00:d1 card.c:763:match_atr_table: ATR try : 3B:1F:11:00:67:80:42:46:49:53:45:10:52:66:FF:81:90:00 card.c:763:match_atr_table: ATR try : 3B:9F:94:40:1E:00:67:16:43:46:49:53:45:10:52:66:FF:81:90:00 card.c:763:match_atr_table: ATR try : 3b:9f:94:40:1e:00:67:00:43:46:49:53:45:10:52:66:ff:81:90:00 card.c:763:match_atr_table: ATR try : 3b:6b:00:ff:80:62:00:a2:56:46:69:6e:45:49:44 card.c:763:match_atr_table: ATR try : 3b:64:00:ff:80:62:00:a2 card.c:763:match_atr_table: ATR try : 3b:7b:00:00:00:80:62:00:51:56:46:69:6e:45:49:44 card.c:772:match_atr_table: ATR mask: ff:ff:00:ff:ff:ff:ff:f0:ff:ff:ff:ff:ff:ff:ff:ff card.c:763:match_atr_table: ATR try : 3b:64:00:00:80:62:00:51 card.c:763:match_atr_table: ATR try : 3b:6e:00:00:00:62:00:00:57:41:56:41:4e:54:10:81:90:00 card.c:763:match_atr_table: ATR try : 3b:7b:94:00:00:80:62:11:51:56:46:69:6e:45:49:44 card.c:763: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 card.c:763:match_atr_table: ATR try : 3b:9f:94:80:1f:c3:00:68:11:44:05:01:46:49:53:45:31:c8:00:00:00:00 card.c:188:sc_connect_card: trying driver: starcos card.c:755:match_atr_table: ATR : 3b:b7:94:00:81:31:fe:65:53:50:4b:32:33:90:00:d1 card.c:763:match_atr_table: ATR try : 3B:B7:94:00:c0:24:31:fe:65:53:50:4b:32:33:90:00:b4 card.c:763:match_atr_table: ATR try : 3B:B7:94:00:81:31:fe:65:53:50:4b:32:33:90:00:d1 card.c:194:sc_connect_card: matched: STARCOS SPK 2.3 card.c:219:sc_connect_card: card info: STARCOS SPK 2.3, 7001, 0x0 card.c:220:sc_connect_card: returning with: 0 slot.c:131:card_detect: 0: Detecting Framework pkcs15.c:656:sc_pkcs15_bind: called card.c:289:sc_lock: called pkcs15.c:496:sc_pkcs15_bind_internal: trying normal pkcs15 processing card.c:541:sc_select_file: called; type=2, path=3f002f00 card-starcos.c:366:starcos_select_file: called card-starcos.c:378:starcos_select_file: current path (path, valid): (len: 0) apdu.c:504:sc_transmit_apdu: called apdu.c:504:sc_transmit_apdu: called card-starcos.c:355:starcos_select_fid: returning with: 0 apdu.c:504:sc_transmit_apdu: called apdu.c:504:sc_transmit_apdu: called card-starcos.c:137:process_fci: processing FCI bytes card-starcos.c:159:process_fci: bytes in file: 128 card-starcos.c:208:process_fci: type: working EF card-starcos.c:209:process_fci: EF structure: transparent card-starcos.c:355:starcos_select_fid: returning with: 0 card.c:563:sc_select_file: returning with: 0 card.c:408:sc_read_binary: called; 128 bytes at index 0 apdu.c:504:sc_transmit_apdu: called iso7816.c:132:iso7816_read_binary: returning with: 128 card.c:439:sc_read_binary: returning with: 128 asn1.c:1044:asn1_decode: called, left=128, depth 0 asn1.c:1069:asn1_decode: Looking for 'dirRecord', tag 0x11000001 asn1.c:869:asn1_decode_entry: decoding 'dirRecord' asn1.c:1044:asn1_decode: called, left=33, depth 1 asn1.c:1069:asn1_decode: Looking for 'aid', tag 0x1000000f asn1.c:869:asn1_decode_entry: decoding 'aid' asn1.c:1069:asn1_decode: Looking for 'label', tag 0x10000010, OPTIONAL asn1.c:869:asn1_decode_entry: decoding 'label' asn1.c:1069:asn1_decode: Looking for 'path', tag 0x10000011, OPTIONAL asn1.c:869:asn1_decode_entry: decoding 'path' asn1.c:1069:asn1_decode: Looking for 'ddo', tag 0x11000013, OPTIONAL asn1.c:1085:asn1_decode: not present asn1.c:1120:asn1_decode: returning with: 0 asn1.c:1120:asn1_decode: returning with: 0 asn1.c:1044:asn1_decode: called, left=93, depth 0 card.c:541:sc_select_file: called; type=2, path=3f005015 card-starcos.c:366:starcos_select_file: called card-starcos.c:378:starcos_select_file: current path (path, valid): 3f00 (len: 2) apdu.c:504:sc_transmit_apdu: called apdu.c:504:sc_transmit_apdu: called card-starcos.c:355:starcos_select_fid: returning with: 0 card.c:563:sc_select_file: returning with: 0 card.c:541:sc_select_file: called; type=2, path=3f0050155031 card-starcos.c:366:starcos_select_file: called card-starcos.c:378:starcos_select_file: current path (path, valid): 3f005015 (len: 4) apdu.c:504:sc_transmit_apdu: called apdu.c:504:sc_transmit_apdu: called card-starcos.c:137:process_fci: processing FCI bytes card-starcos.c:159:process_fci: bytes in file: 256 card-starcos.c:208:process_fci: type: working EF card-starcos.c:209:process_fci: EF structure: transparent card-starcos.c:355:starcos_select_fid: returning with: 0 card.c:563:sc_select_file: returning with: 0 card.c:408:sc_read_binary: called; 256 bytes at index 0 card.c:408:sc_read_binary: called; 128 bytes at index 0 apdu.c:504:sc_transmit_apdu: called iso7816.c:132:iso7816_read_binary: returning with: 128 card.c:439:sc_read_binary: returning with: 128 card.c:408:sc_read_binary: called; 128 bytes at index 128 apdu.c:504:sc_transmit_apdu: called iso7816.c:132:iso7816_read_binary: returning with: 128 card.c:439:sc_read_binary: returning with: 128 card.c:436:sc_read_binary: returning with: 256 asn1.c:1044:asn1_decode: called, left=256, depth 0, choice asn1.c:1069:asn1_decode: Looking for 'privateKeys', tag 0x21000000, CHOICE asn1.c:1085:asn1_decode: not present asn1.c:1069:asn1_decode: Looking for 'publicKeys', tag 0x21000001, CHOICE asn1.c:1085:asn1_decode: not present asn1.c:1069:asn1_decode: Looking for 'trustedPublicKeys', tag 0x21000002, CHOICE asn1.c:1085:asn1_decode: not present asn1.c:1069:asn1_decode: Looking for 'certificates', tag 0x21000004, CHOICE asn1.c:1085:asn1_decode: not present asn1.c:1069:asn1_decode: Looking for 'trustedCertificates', tag 0x21000005, CHOICE asn1.c:1085:asn1_decode: not present asn1.c:1069:asn1_decode: Looking for 'usefulCertificates', tag 0x21000006, CHOICE asn1.c:1085:asn1_decode: not present asn1.c:1069:asn1_decode: Looking for 'dataObjects', tag 0x21000007, CHOICE asn1.c:1085:asn1_decode: not present asn1.c:1069:asn1_decode: Looking for 'authObjects', tag 0x21000008, CHOICE asn1.c:869:asn1_decode_entry: decoding 'authObjects' asn1.c:1044:asn1_decode: called, left=10, depth 1 asn1.c:1069:asn1_decode: Looking for 'path', tag 0x1000010 asn1.c:869:asn1_decode_entry: decoding 'path' asn1.c:1044:asn1_decode: called, left=8, depth 2 asn1.c:1069:asn1_decode: Looking for 'path', tag 0x4 asn1.c:869:asn1_decode_entry: decoding 'path' asn1.c:1069:asn1_decode: Looking for 'index', tag 0x2, OPTIONAL asn1.c:1085:asn1_decode: not present asn1.c:1069:asn1_decode: Looking for 'length', tag 0x20000000, OPTIONAL asn1.c:1085:asn1_decode: not present asn1.c:1120:asn1_decode: returning with: 0 asn1.c:1120:asn1_decode: returning with: 0 asn1.c:1119:asn1_decode: returning with: 7 asn1.c:1044:asn1_decode: called, left=244, depth 0, choice asn1.c:1069:asn1_decode: Looking for 'privateKeys', tag 0x21000000, CHOICE asn1.c:869:asn1_decode_entry: decoding 'privateKeys' asn1.c:1044:asn1_decode: called, left=10, depth 1 asn1.c:1069:asn1_decode: Looking for 'path', tag 0x1000010 asn1.c:869:asn1_decode_entry: decoding 'path' asn1.c:1044:asn1_decode: called, left=8, depth 2 asn1.c:1069:asn1_decode: Looking for 'path', tag 0x4 asn1.c:869:asn1_decode_entry: decoding 'path' asn1.c:1069:asn1_decode: Looking for 'index', tag 0x2, OPTIONAL asn1.c:1085:asn1_decode: not present asn1.c:1069:asn1_decode: Looking for 'length', tag 0x20000000, OPTIONAL asn1.c:1085:asn1_decode: not present asn1.c:1120:asn1_decode: returning with: 0 asn1.c:1120:asn1_decode: returning with: 0 asn1.c:1119:asn1_decode: returning with: 0 asn1.c:1044:asn1_decode: called, left=232, depth 0, choice asn1.c:1069:asn1_decode: Looking for 'privateKeys', tag 0x21000000, CHOICE asn1.c:1085:asn1_decode: not present asn1.c:1069:asn1_decode: Looking for 'publicKeys', tag 0x21000001, CHOICE asn1.c:1085:asn1_decode: not present asn1.c:1069:asn1_decode: Looking for 'trustedPublicKeys', tag 0x21000002, CHOICE asn1.c:1085:asn1_decode: not present asn1.c:1069:asn1_decode: Looking for 'certificates', tag 0x21000004, CHOICE asn1.c:869:asn1_decode_entry: decoding 'certificates' asn1.c:1044:asn1_decode: called, left=10, depth 1 asn1.c:1069:asn1_decode: Looking for 'path', tag 0x1000010 asn1.c:869:asn1_decode_entry: decoding 'path' asn1.c:1044:asn1_decode: called, left=8, depth 2 asn1.c:1069:asn1_decode: Looking for 'path', tag 0x4 asn1.c:869:asn1_decode_entry: decoding 'path' asn1.c:1069:asn1_decode: Looking for 'index', tag 0x2, OPTIONAL asn1.c:1085:asn1_decode: not present asn1.c:1069:asn1_decode: Looking for 'length', tag 0x20000000, OPTIONAL asn1.c:1085:asn1_decode: not present asn1.c:1120:asn1_decode: returning with: 0 asn1.c:1120:asn1_decode: returning with: 0 asn1.c:1119:asn1_decode: returning with: 3 asn1.c:1044:asn1_decode: called, left=220, depth 0, choice pkcs15.c:588:sc_pkcs15_bind_internal: The following DFs were found: pkcs15.c:598:sc_pkcs15_bind_internal: DF type 8, path 3f0050154401, index 0, count -1 pkcs15.c:598:sc_pkcs15_bind_internal: DF type 0, path 3f0050154402, index 0, count -1 pkcs15.c:598:sc_pkcs15_bind_internal: DF type 4, path 3f0050154404, index 0, count -1 card.c:541:sc_select_file: called; type=2, path=3f0050155032 card-starcos.c:366:starcos_select_file: called card-starcos.c:378:starcos_select_file: current path (path, valid): 3f005015 (len: 4) apdu.c:504:sc_transmit_apdu: called apdu.c:504:sc_transmit_apdu: called card-starcos.c:137:process_fci: processing FCI bytes card-starcos.c:159:process_fci: bytes in file: 65 card-starcos.c:208:process_fci: type: working EF card-starcos.c:209:process_fci: EF structure: transparent card-starcos.c:355:starcos_select_fid: returning with: 0 card.c:563:sc_select_file: returning with: 0 card.c:408:sc_read_binary: called; 65 bytes at index 0 apdu.c:504:sc_transmit_apdu: called iso7816.c:132:iso7816_read_binary: returning with: 65 card.c:439:sc_read_binary: returning with: 65 asn1.c:1044:asn1_decode: called, left=65, depth 0 asn1.c:1069:asn1_decode: Looking for 'TokenInfo', tag 0x1000010 asn1.c:869:asn1_decode_entry: decoding 'TokenInfo' asn1.c:1044:asn1_decode: called, left=63, depth 1 asn1.c:1069:asn1_decode: Looking for 'version', tag 0x2 asn1.c:869:asn1_decode_entry: decoding 'version' asn1.c:1069:asn1_decode: Looking for 'serialNumber', tag 0x4 asn1.c:869:asn1_decode_entry: decoding 'serialNumber' asn1.c:1069:asn1_decode: Looking for 'manufacturerID', tag 0xc, OPTIONAL asn1.c:869:asn1_decode_entry: decoding 'manufacturerID' asn1.c:1069:asn1_decode: Looking for 'label', tag 0x20000000, OPTIONAL asn1.c:869:asn1_decode_entry: decoding 'label' asn1.c:1069:asn1_decode: Looking for 'tokenflags', tag 0x3 asn1.c:869:asn1_decode_entry: decoding 'tokenflags' asn1.c:1069:asn1_decode: Looking for 'seInfo', tag 0x1000010, OPTIONAL asn1.c:1085:asn1_decode: not present asn1.c:1069:asn1_decode: Looking for 'recordInfo', tag 0x21000001, OPTIONAL asn1.c:1085:asn1_decode: not present asn1.c:1069:asn1_decode: Looking for 'supportedAlgorithms', tag 0x21000002, OPTIONAL asn1.c:1085:asn1_decode: not present asn1.c:1069:asn1_decode: Looking for 'issuerId', tag 0x20000003, OPTIONAL asn1.c:1085:asn1_decode: not present asn1.c:1069:asn1_decode: Looking for 'holderId', tag 0x20000004, OPTIONAL asn1.c:1085:asn1_decode: not present asn1.c:1069:asn1_decode: Looking for 'lastUpdate', tag 0x20000005, OPTIONAL asn1.c:869:asn1_decode_entry: decoding 'lastUpdate' asn1.c:1069:asn1_decode: Looking for 'preferredLanguage', tag 0x13, OPTIONAL asn1.c:1085:asn1_decode: not present asn1.c:1120:asn1_decode: returning with: 0 asn1.c:1120:asn1_decode: returning with: 0 card.c:316:sc_unlock: called card.c:327:sc_unlock: Calling card logout function apdu.c:504:sc_transmit_apdu: called card-starcos.c:1242:starcos_check_sw: sw1 = 0x90, sw2 = 0x00 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:1547:sc_pkcs15_read_file: called, path=3f0050154401, index=0, count=-1 card.c:289:sc_lock: called card.c:541:sc_select_file: called; type=2, path=3f0050154401 card-starcos.c:366:starcos_select_file: called card-starcos.c:378:starcos_select_file: current path (path, valid): (len: 0) apdu.c:504:sc_transmit_apdu: called apdu.c:504:sc_transmit_apdu: called card-starcos.c:355:starcos_select_fid: returning with: 0 apdu.c:504:sc_transmit_apdu: called apdu.c:504:sc_transmit_apdu: called card-starcos.c:355:starcos_select_fid: returning with: 0 apdu.c:504:sc_transmit_apdu: called apdu.c:504:sc_transmit_apdu: called card-starcos.c:137:process_fci: processing FCI bytes card-starcos.c:159:process_fci: bytes in file: 256 card-starcos.c:208:process_fci: type: working EF card-starcos.c:209:process_fci: EF structure: transparent card-starcos.c:355:starcos_select_fid: returning with: 0 card.c:563:sc_select_file: returning with: 0 card.c:408:sc_read_binary: called; 256 bytes at index 0 card.c:408:sc_read_binary: called; 128 bytes at index 0 apdu.c:504:sc_transmit_apdu: called iso7816.c:132:iso7816_read_binary: returning with: 128 card.c:439:sc_read_binary: returning with: 128 card.c:408:sc_read_binary: called; 128 bytes at index 128 apdu.c:504:sc_transmit_apdu: called iso7816.c:132:iso7816_read_binary: returning with: 128 card.c:439:sc_read_binary: returning with: 128 card.c:436:sc_read_binary: returning with: 256 card.c:316:sc_unlock: called card.c:327:sc_unlock: Calling card logout function apdu.c:504:sc_transmit_apdu: called card-starcos.c:1242:starcos_check_sw: sw1 = 0x90, sw2 = 0x00 asn1.c:1044:asn1_decode: called, left=256, depth 0 asn1.c:1069:asn1_decode: Looking for 'pin', tag 0x1000010 asn1.c:869:asn1_decode_entry: decoding 'pin' asn1.c:1044:asn1_decode: called, left=52, depth 1 asn1.c:1069:asn1_decode: Looking for 'commonObjectAttributes', tag 0x1000010 asn1.c:869:asn1_decode_entry: decoding 'commonObjectAttributes' asn1.c:1044:asn1_decode: called, left=14, depth 2 asn1.c:1069:asn1_decode: Looking for 'label', tag 0xc, OPTIONAL asn1.c:869:asn1_decode_entry: decoding 'label' asn1.c:1069:asn1_decode: Looking for 'flags', tag 0x3, OPTIONAL asn1.c:869:asn1_decode_entry: decoding 'flags' asn1.c:1069:asn1_decode: Looking for 'authId', tag 0x4, OPTIONAL asn1.c:1085:asn1_decode: not present asn1.c:1069:asn1_decode: Looking for 'userConsent', tag 0x2, OPTIONAL asn1.c:1085:asn1_decode: not present asn1.c:1069:asn1_decode: Looking for 'accessControlRules', tag 0x1000010, OPTIONAL asn1.c:1085:asn1_decode: not present asn1.c:1120:asn1_decode: returning with: 0 asn1.c:1069:asn1_decode: Looking for 'classAttributes', tag 0x1000010 asn1.c:869:asn1_decode_entry: decoding 'classAttributes' asn1.c:1044:asn1_decode: called, left=3, depth 2 asn1.c:1069:asn1_decode: Looking for 'authId', tag 0x4 asn1.c:869:asn1_decode_entry: decoding 'authId' asn1.c:1120:asn1_decode: returning with: 0 asn1.c:1069:asn1_decode: Looking for 'subClassAttributes', tag 0x21000000, OPTIONAL asn1.c:1085:asn1_decode: not present asn1.c:1069:asn1_decode: Looking for 'typeAttributes', tag 0x21000001 asn1.c:869:asn1_decode_entry: decoding 'typeAttributes' asn1.c:1044:asn1_decode: called, left=29, depth 2 asn1.c:1069:asn1_decode: Looking for 'pinAttributes', tag 0x1000010 asn1.c:869:asn1_decode_entry: decoding 'pinAttributes' asn1.c:1044:asn1_decode: called, left=27, depth 3 asn1.c:1069:asn1_decode: Looking for 'pinFlags', tag 0x3 asn1.c:869:asn1_decode_entry: decoding 'pinFlags' asn1.c:1069:asn1_decode: Looking for 'pinType', tag 0xa asn1.c:869:asn1_decode_entry: decoding 'pinType' asn1.c:1069:asn1_decode: Looking for 'minLength', tag 0x2 asn1.c:869:asn1_decode_entry: decoding 'minLength' asn1.c:1069:asn1_decode: Looking for 'storedLength', tag 0x2 asn1.c:869:asn1_decode_entry: decoding 'storedLength' asn1.c:1069:asn1_decode: Looking for 'maxLength', tag 0x2, OPTIONAL asn1.c:1085:asn1_decode: not present asn1.c:1069:asn1_decode: Looking for 'pinReference', tag 0x20000000, OPTIONAL asn1.c:869:asn1_decode_entry: decoding 'pinReference' asn1.c:1069:asn1_decode: Looking for 'padChar', tag 0x4, OPTIONAL asn1.c:869:asn1_decode_entry: decoding 'padChar' asn1.c:1069:asn1_decode: Looking for 'lastPinChange', tag 0x18, OPTIONAL asn1.c:1085:asn1_decode: not present asn1.c:1069:asn1_decode: Looking for 'path', tag 0x1000010, OPTIONAL asn1.c:869:asn1_decode_entry: decoding 'path' asn1.c:1044:asn1_decode: called, left=6, depth 4 asn1.c:1069:asn1_decode: Looking for 'path', tag 0x4 asn1.c:869:asn1_decode_entry: decoding 'path' asn1.c:1069:asn1_decode: Looking for 'index', tag 0x2, OPTIONAL asn1.c:1085:asn1_decode: not present asn1.c:1069:asn1_decode: Looking for 'length', tag 0x20000000, OPTIONAL asn1.c:1085:asn1_decode: not present asn1.c:1120:asn1_decode: returning with: 0 asn1.c:1120:asn1_decode: returning with: 0 asn1.c:1120:asn1_decode: returning with: 0 asn1.c:1120:asn1_decode: returning with: 0 asn1.c:1120:asn1_decode: returning with: 0 asn1.c:1044:asn1_decode: called, left=202, depth 0 framework-pkcs15.c:681:pkcs15_create_tokens: Found 1 authentication objects pkcs15.c:1547:sc_pkcs15_read_file: called, path=3f0050154402, index=0, count=-1 card.c:289:sc_lock: called card.c:541:sc_select_file: called; type=2, path=3f0050154402 card-starcos.c:366:starcos_select_file: called card-starcos.c:378:starcos_select_file: current path (path, valid): (len: 0) apdu.c:504:sc_transmit_apdu: called apdu.c:504:sc_transmit_apdu: called card-starcos.c:355:starcos_select_fid: returning with: 0 apdu.c:504:sc_transmit_apdu: called apdu.c:504:sc_transmit_apdu: called card-starcos.c:355:starcos_select_fid: returning with: 0 apdu.c:504:sc_transmit_apdu: called apdu.c:504:sc_transmit_apdu: called card-starcos.c:137:process_fci: processing FCI bytes card-starcos.c:159:process_fci: bytes in file: 256 card-starcos.c:208:process_fci: type: working EF card-starcos.c:209:process_fci: EF structure: transparent card-starcos.c:355:starcos_select_fid: returning with: 0 card.c:563:sc_select_file: returning with: 0 card.c:408:sc_read_binary: called; 256 bytes at index 0 card.c:408:sc_read_binary: called; 128 bytes at index 0 apdu.c:504:sc_transmit_apdu: called iso7816.c:132:iso7816_read_binary: returning with: 128 card.c:439:sc_read_binary: returning with: 128 card.c:408:sc_read_binary: called; 128 bytes at index 128 apdu.c:504:sc_transmit_apdu: called iso7816.c:132:iso7816_read_binary: returning with: 128 card.c:439:sc_read_binary: returning with: 128 card.c:436:sc_read_binary: returning with: 256 card.c:316:sc_unlock: called card.c:327:sc_unlock: Calling card logout function apdu.c:504:sc_transmit_apdu: called card-starcos.c:1242:starcos_check_sw: sw1 = 0x90, sw2 = 0x00 asn1.c:1044:asn1_decode: called, left=256, depth 0, choice asn1.c:1069:asn1_decode: Looking for 'privateRSAKey', tag 0x1000010, CHOICE, OPTIONAL asn1.c:869:asn1_decode_entry: decoding 'privateRSAKey' asn1.c:1044:asn1_decode: called, left=56, depth 1 asn1.c:1069:asn1_decode: Looking for 'commonObjectAttributes', tag 0x1000010 asn1.c:869:asn1_decode_entry: decoding 'commonObjectAttributes' asn1.c:1044:asn1_decode: called, left=20, depth 2 asn1.c:1069:asn1_decode: Looking for 'label', tag 0xc, OPTIONAL asn1.c:869:asn1_decode_entry: decoding 'label' asn1.c:1069:asn1_decode: Looking for 'flags', tag 0x3, OPTIONAL asn1.c:869:asn1_decode_entry: decoding 'flags' asn1.c:1069:asn1_decode: Looking for 'authId', tag 0x4, OPTIONAL asn1.c:869:asn1_decode_entry: decoding 'authId' asn1.c:1069:asn1_decode: Looking for 'userConsent', tag 0x2, OPTIONAL asn1.c:1085:asn1_decode: not present asn1.c:1069:asn1_decode: Looking for 'accessControlRules', tag 0x1000010, OPTIONAL asn1.c:1085:asn1_decode: not present asn1.c:1120:asn1_decode: returning with: 0 asn1.c:1069:asn1_decode: Looking for 'classAttributes', tag 0x1000010 asn1.c:869:asn1_decode_entry: decoding 'classAttributes' asn1.c:1044:asn1_decode: called, left=14, depth 2 asn1.c:1069:asn1_decode: Looking for 'iD', tag 0x4 asn1.c:869:asn1_decode_entry: decoding 'iD' asn1.c:1069:asn1_decode: Looking for 'usage', tag 0x3 asn1.c:869:asn1_decode_entry: decoding 'usage' asn1.c:1069:asn1_decode: Looking for 'native', tag 0x1, OPTIONAL asn1.c:1085:asn1_decode: not present asn1.c:1069:asn1_decode: Looking for 'accessFlags', tag 0x3, OPTIONAL asn1.c:869:asn1_decode_entry: decoding 'accessFlags' asn1.c:1069:asn1_decode: Looking for 'keyReference', tag 0x2, OPTIONAL asn1.c:869:asn1_decode_entry: decoding 'keyReference' asn1.c:1120:asn1_decode: returning with: 0 asn1.c:1069:asn1_decode: Looking for 'subClassAttributes', tag 0x21000000, OPTIONAL asn1.c:869:asn1_decode_entry: decoding 'subClassAttributes' asn1.c:1044:asn1_decode: called, left=0, depth 2 asn1.c:1069:asn1_decode: Looking for 'typeAttributes', tag 0x21000001 asn1.c:869:asn1_decode_entry: decoding 'typeAttributes' asn1.c:1044:asn1_decode: called, left=14, depth 2 asn1.c:1069:asn1_decode: Looking for 'privateRSAKeyAttributes', tag 0x1000010 asn1.c:869:asn1_decode_entry: decoding 'privateRSAKeyAttributes' asn1.c:1044:asn1_decode: called, left=12, depth 3 asn1.c:1069:asn1_decode: Looking for 'value', tag 0x1000010 asn1.c:869:asn1_decode_entry: decoding 'value' asn1.c:1044:asn1_decode: called, left=6, depth 4 asn1.c:1069:asn1_decode: Looking for 'path', tag 0x4 asn1.c:869:asn1_decode_entry: decoding 'path' asn1.c:1069:asn1_decode: Looking for 'index', tag 0x2, OPTIONAL asn1.c:1085:asn1_decode: not present asn1.c:1069:asn1_decode: Looking for 'length', tag 0x20000000, OPTIONAL asn1.c:1085:asn1_decode: not present asn1.c:1120:asn1_decode: returning with: 0 asn1.c:1069:asn1_decode: Looking for 'modulusLength', tag 0x2 asn1.c:869:asn1_decode_entry: decoding 'modulusLength' asn1.c:1069:asn1_decode: Looking for 'keyInfo', tag 0x2, OPTIONAL asn1.c:1085:asn1_decode: not present asn1.c:1120:asn1_decode: returning with: 0 asn1.c:1120:asn1_decode: returning with: 0 asn1.c:1120:asn1_decode: returning with: 0 asn1.c:1119:asn1_decode: returning with: 0 asn1.c:1044:asn1_decode: called, left=198, depth 0, choice framework-pkcs15.c:405:pkcs15_create_pkcs11_objects: Found 1 private key framework-pkcs15.c:405:pkcs15_create_pkcs11_objects: Found 0 public keys pkcs15.c:1547:sc_pkcs15_read_file: called, path=3f0050154404, index=0, count=-1 card.c:289:sc_lock: called card.c:541:sc_select_file: called; type=2, path=3f0050154404 card-starcos.c:366:starcos_select_file: called card-starcos.c:378:starcos_select_file: current path (path, valid): (len: 0) apdu.c:504:sc_transmit_apdu: called apdu.c:504:sc_transmit_apdu: called card-starcos.c:355:starcos_select_fid: returning with: 0 apdu.c:504:sc_transmit_apdu: called apdu.c:504:sc_transmit_apdu: called card-starcos.c:355:starcos_select_fid: returning with: 0 apdu.c:504:sc_transmit_apdu: called apdu.c:504:sc_transmit_apdu: called card-starcos.c:137:process_fci: processing FCI bytes card-starcos.c:159:process_fci: bytes in file: 512 card-starcos.c:208:process_fci: type: working EF card-starcos.c:209:process_fci: EF structure: transparent card-starcos.c:355:starcos_select_fid: returning with: 0 card.c:563:sc_select_file: returning with: 0 card.c:408:sc_read_binary: called; 512 bytes at index 0 card.c:408:sc_read_binary: called; 128 bytes at index 0 apdu.c:504:sc_transmit_apdu: called iso7816.c:132:iso7816_read_binary: returning with: 128 card.c:439:sc_read_binary: returning with: 128 card.c:408:sc_read_binary: called; 128 bytes at index 128 apdu.c:504:sc_transmit_apdu: called iso7816.c:132:iso7816_read_binary: returning with: 128 card.c:439:sc_read_binary: returning with: 128 card.c:408:sc_read_binary: called; 128 bytes at index 256 apdu.c:504:sc_transmit_apdu: called iso7816.c:132:iso7816_read_binary: returning with: 128 card.c:439:sc_read_binary: returning with: 128 card.c:408:sc_read_binary: called; 128 bytes at index 384 apdu.c:504:sc_transmit_apdu: called iso7816.c:132:iso7816_read_binary: returning with: 128 card.c:439:sc_read_binary: returning with: 128 card.c:436:sc_read_binary: returning with: 512 card.c:316:sc_unlock: called card.c:327:sc_unlock: Calling card logout function apdu.c:504:sc_transmit_apdu: called card-starcos.c:1242:starcos_check_sw: sw1 = 0x90, sw2 = 0x00 asn1.c:1044:asn1_decode: called, left=512, depth 0 asn1.c:1069:asn1_decode: Looking for 'x509Certificate', tag 0x1000010 asn1.c:869:asn1_decode_entry: decoding 'x509Certificate' asn1.c:1044:asn1_decode: called, left=60, depth 1 asn1.c:1069:asn1_decode: Looking for 'commonObjectAttributes', tag 0x1000010 asn1.c:869:asn1_decode_entry: decoding 'commonObjectAttributes' asn1.c:1044:asn1_decode: called, left=39, depth 2 asn1.c:1069:asn1_decode: Looking for 'label', tag 0xc, OPTIONAL asn1.c:869:asn1_decode_entry: decoding 'label' asn1.c:1069:asn1_decode: Looking for 'flags', tag 0x3, OPTIONAL asn1.c:869:asn1_decode_entry: decoding 'flags' asn1.c:1069:asn1_decode: Looking for 'authId', tag 0x4, OPTIONAL asn1.c:1085:asn1_decode: not present asn1.c:1069:asn1_decode: Looking for 'userConsent', tag 0x2, OPTIONAL asn1.c:1085:asn1_decode: not present asn1.c:1069:asn1_decode: Looking for 'accessControlRules', tag 0x1000010, OPTIONAL asn1.c:1085:asn1_decode: not present asn1.c:1120:asn1_decode: returning with: 0 asn1.c:1069:asn1_decode: Looking for 'classAttributes', tag 0x1000010 asn1.c:869:asn1_decode_entry: decoding 'classAttributes' asn1.c:1044:asn1_decode: called, left=3, depth 2 asn1.c:1069:asn1_decode: Looking for 'iD', tag 0x4 asn1.c:869:asn1_decode_entry: decoding 'iD' asn1.c:1069:asn1_decode: Looking for 'authority', tag 0x1, OPTIONAL asn1.c:1085:asn1_decode: not present asn1.c:1069:asn1_decode: Looking for 'identifier', tag 0x1000010, OPTIONAL asn1.c:1085:asn1_decode: not present asn1.c:1120:asn1_decode: returning with: 0 asn1.c:1069:asn1_decode: Looking for 'subClassAttributes', tag 0x21000000, OPTIONAL asn1.c:1085:asn1_decode: not present asn1.c:1069:asn1_decode: Looking for 'typeAttributes', tag 0x21000001 asn1.c:869:asn1_decode_entry: decoding 'typeAttributes' asn1.c:1044:asn1_decode: called, left=12, depth 2 asn1.c:1069:asn1_decode: Looking for 'x509CertificateAttributes', tag 0x1000010 asn1.c:869:asn1_decode_entry: decoding 'x509CertificateAttributes' asn1.c:1044:asn1_decode: called, left=10, depth 3 asn1.c:1069:asn1_decode: Looking for 'value', tag 0x0 asn1.c:1044:asn1_decode: called, left=10, depth 4, choice asn1.c:1069:asn1_decode: Looking for 'path', tag 0x1000010, CHOICE, OPTIONAL asn1.c:869:asn1_decode_entry: decoding 'path' asn1.c:1044:asn1_decode: called, left=8, depth 5 asn1.c:1069:asn1_decode: Looking for 'path', tag 0x4 asn1.c:869:asn1_decode_entry: decoding 'path' asn1.c:1069:asn1_decode: Looking for 'index', tag 0x2, OPTIONAL asn1.c:1085:asn1_decode: not present asn1.c:1069:asn1_decode: Looking for 'length', tag 0x20000000, OPTIONAL asn1.c:1085:asn1_decode: not present asn1.c:1120:asn1_decode: returning with: 0 asn1.c:1119:asn1_decode: returning with: 0 asn1.c:1120:asn1_decode: returning with: 0 asn1.c:1120:asn1_decode: returning with: 0 asn1.c:1120:asn1_decode: returning with: 0 asn1.c:1120:asn1_decode: returning with: 0 asn1.c:1044:asn1_decode: called, left=450, depth 0 asn1.c:1069:asn1_decode: Looking for 'x509Certificate', tag 0x1000010 asn1.c:869:asn1_decode_entry: decoding 'x509Certificate' asn1.c:1044:asn1_decode: called, left=64, depth 1 asn1.c:1069:asn1_decode: Looking for 'commonObjectAttributes', tag 0x1000010 asn1.c:869:asn1_decode_entry: decoding 'commonObjectAttributes' asn1.c:1044:asn1_decode: called, left=40, depth 2 asn1.c:1069:asn1_decode: Looking for 'label', tag 0xc, OPTIONAL asn1.c:869:asn1_decode_entry: decoding 'label' asn1.c:1069:asn1_decode: Looking for 'flags', tag 0x3, OPTIONAL asn1.c:869:asn1_decode_entry: decoding 'flags' asn1.c:1069:asn1_decode: Looking for 'authId', tag 0x4, OPTIONAL asn1.c:1085:asn1_decode: not present asn1.c:1069:asn1_decode: Looking for 'userConsent', tag 0x2, OPTIONAL asn1.c:1085:asn1_decode: not present asn1.c:1069:asn1_decode: Looking for 'accessControlRules', tag 0x1000010, OPTIONAL asn1.c:1085:asn1_decode: not present asn1.c:1120:asn1_decode: returning with: 0 asn1.c:1069:asn1_decode: Looking for 'classAttributes', tag 0x1000010 asn1.c:869:asn1_decode_entry: decoding 'classAttributes' asn1.c:1044:asn1_decode: called, left=6, depth 2 asn1.c:1069:asn1_decode: Looking for 'iD', tag 0x4 asn1.c:869:asn1_decode_entry: decoding 'iD' asn1.c:1069:asn1_decode: Looking for 'authority', tag 0x1, OPTIONAL asn1.c:869:asn1_decode_entry: decoding 'authority' asn1.c:1069:asn1_decode: Looking for 'identifier', tag 0x1000010, OPTIONAL asn1.c:1085:asn1_decode: not present asn1.c:1120:asn1_decode: returning with: 0 asn1.c:1069:asn1_decode: Looking for 'subClassAttributes', tag 0x21000000, OPTIONAL asn1.c:1085:asn1_decode: not present asn1.c:1069:asn1_decode: Looking for 'typeAttributes', tag 0x21000001 asn1.c:869:asn1_decode_entry: decoding 'typeAttributes' asn1.c:1044:asn1_decode: called, left=12, depth 2 asn1.c:1069:asn1_decode: Looking for 'x509CertificateAttributes', tag 0x1000010 asn1.c:869:asn1_decode_entry: decoding 'x509CertificateAttributes' asn1.c:1044:asn1_decode: called, left=10, depth 3 asn1.c:1069:asn1_decode: Looking for 'value', tag 0x0 asn1.c:1044:asn1_decode: called, left=10, depth 4, choice asn1.c:1069:asn1_decode: Looking for 'path', tag 0x1000010, CHOICE, OPTIONAL asn1.c:869:asn1_decode_entry: decoding 'path' asn1.c:1044:asn1_decode: called, left=8, depth 5 asn1.c:1069:asn1_decode: Looking for 'path', tag 0x4 asn1.c:869:asn1_decode_entry: decoding 'path' asn1.c:1069:asn1_decode: Looking for 'index', tag 0x2, OPTIONAL asn1.c:1085:asn1_decode: not present asn1.c:1069:asn1_decode: Looking for 'length', tag 0x20000000, OPTIONAL asn1.c:1085:asn1_decode: not present asn1.c:1120:asn1_decode: returning with: 0 asn1.c:1119:asn1_decode: returning with: 0 asn1.c:1120:asn1_decode: returning with: 0 asn1.c:1120:asn1_decode: returning with: 0 asn1.c:1120:asn1_decode: returning with: 0 asn1.c:1120:asn1_decode: returning with: 0 framework-pkcs15.c:405:pkcs15_create_pkcs11_objects: Found 2 certificates pkcs15-cert.c:115:sc_pkcs15_read_certificate: called pkcs15.c:1547:sc_pkcs15_read_file: called, path=3f0050153149, index=0, count=-1 card.c:289:sc_lock: called card.c:541:sc_select_file: called; type=2, path=3f0050153149 card-starcos.c:366:starcos_select_file: called card-starcos.c:378:starcos_select_file: current path (path, valid): (len: 0) apdu.c:504:sc_transmit_apdu: called apdu.c:504:sc_transmit_apdu: called card-starcos.c:355:starcos_select_fid: returning with: 0 apdu.c:504:sc_transmit_apdu: called apdu.c:504:sc_transmit_apdu: called card-starcos.c:355:starcos_select_fid: returning with: 0 apdu.c:504:sc_transmit_apdu: called apdu.c:504:sc_transmit_apdu: called card-starcos.c:137:process_fci: processing FCI bytes card-starcos.c:159:process_fci: bytes in file: 794 card-starcos.c:208:process_fci: type: working EF card-starcos.c:209:process_fci: EF structure: transparent card-starcos.c:355:starcos_select_fid: returning with: 0 card.c:563:sc_select_file: returning with: 0 card.c:408:sc_read_binary: called; 794 bytes at index 0 card.c:408:sc_read_binary: called; 128 bytes at index 0 apdu.c:504:sc_transmit_apdu: called iso7816.c:132:iso7816_read_binary: returning with: 128 card.c:439:sc_read_binary: returning with: 128 card.c:408:sc_read_binary: called; 128 bytes at index 128 apdu.c:504:sc_transmit_apdu: called iso7816.c:132:iso7816_read_binary: returning with: 128 card.c:439:sc_read_binary: returning with: 128 card.c:408:sc_read_binary: called; 128 bytes at index 256 apdu.c:504:sc_transmit_apdu: called iso7816.c:132:iso7816_read_binary: returning with: 128 card.c:439:sc_read_binary: returning with: 128 card.c:408:sc_read_binary: called; 128 bytes at index 384 apdu.c:504:sc_transmit_apdu: called iso7816.c:132:iso7816_read_binary: returning with: 128 card.c:439:sc_read_binary: returning with: 128 card.c:408:sc_read_binary: called; 128 bytes at index 512 apdu.c:504:sc_transmit_apdu: called iso7816.c:132:iso7816_read_binary: returning with: 128 card.c:439:sc_read_binary: returning with: 128 card.c:408:sc_read_binary: called; 128 bytes at index 640 apdu.c:504:sc_transmit_apdu: called iso7816.c:132:iso7816_read_binary: returning with: 128 card.c:439:sc_read_binary: returning with: 128 card.c:408:sc_read_binary: called; 26 bytes at index 768 apdu.c:504:sc_transmit_apdu: called iso7816.c:132:iso7816_read_binary: returning with: 26 card.c:439:sc_read_binary: returning with: 26 card.c:436:sc_read_binary: returning with: 794 card.c:316:sc_unlock: called card.c:327:sc_unlock: Calling card logout function apdu.c:504:sc_transmit_apdu: called card-starcos.c:1242:starcos_check_sw: sw1 = 0x90, sw2 = 0x00 asn1.c:1044:asn1_decode: called, left=790, depth 0 asn1.c:1069:asn1_decode: Looking for 'tbsCertificate', tag 0x1000010 asn1.c:869:asn1_decode_entry: decoding 'tbsCertificate' asn1.c:1044:asn1_decode: called, left=639, depth 1 asn1.c:1069:asn1_decode: Looking for 'version', tag 0x21000000, OPTIONAL asn1.c:869:asn1_decode_entry: decoding 'version' asn1.c:1044:asn1_decode: called, left=3, depth 2 asn1.c:1069:asn1_decode: Looking for 'version', tag 0x2 asn1.c:869:asn1_decode_entry: decoding 'version' asn1.c:1120:asn1_decode: returning with: 0 asn1.c:1069:asn1_decode: Looking for 'serialNumber', tag 0x2 asn1.c:869:asn1_decode_entry: decoding 'serialNumber' asn1.c:1069:asn1_decode: Looking for 'signature', tag 0x1000010 asn1.c:869:asn1_decode_entry: decoding 'signature' asn1.c:1069:asn1_decode: Looking for 'issuer', tag 0x1000010 asn1.c:869:asn1_decode_entry: decoding 'issuer' asn1.c:1069:asn1_decode: Looking for 'validity', tag 0x1000010 asn1.c:869:asn1_decode_entry: decoding 'validity' asn1.c:1069:asn1_decode: Looking for 'subject', tag 0x1000010 asn1.c:869:asn1_decode_entry: decoding 'subject' asn1.c:1069:asn1_decode: Looking for 'subjectPublicKeyInfo', tag 0x1000010 asn1.c:869:asn1_decode_entry: decoding 'subjectPublicKeyInfo' asn1.c:1044:asn1_decode: called, left=159, depth 2 asn1.c:1069:asn1_decode: Looking for 'algorithm', tag 0x1000010 asn1.c:869:asn1_decode_entry: decoding 'algorithm' asn1.c:1044:asn1_decode: called, left=13, depth 3 asn1.c:1069:asn1_decode: Looking for 'algorithm', tag 0x6 asn1.c:869:asn1_decode_entry: decoding 'algorithm' asn1.c:1069:asn1_decode: Looking for 'nullParam', tag 0x5, OPTIONAL asn1.c:869:asn1_decode_entry: decoding 'nullParam' asn1.c:1120:asn1_decode: returning with: 0 asn1.c:1069:asn1_decode: Looking for 'subjectPublicKey', tag 0x3 asn1.c:869:asn1_decode_entry: decoding 'subjectPublicKey' asn1.c:1120:asn1_decode: returning with: 0 asn1.c:1069:asn1_decode: Looking for 'extensions', tag 0x21000003, OPTIONAL asn1.c:869:asn1_decode_entry: decoding 'extensions' asn1.c:1044:asn1_decode: called, left=285, depth 2 asn1.c:1069:asn1_decode: Looking for 'x509v3', tag 0x1000010, OPTIONAL asn1.c:869:asn1_decode_entry: decoding 'x509v3' asn1.c:1044:asn1_decode: called, left=281, depth 3 asn1.c:1069:asn1_decode: Looking for 'certificatePolicies', tag 0x1000010, OPTIONAL asn1.c:869:asn1_decode_entry: decoding 'certificatePolicies' asn1.c:1069:asn1_decode: Looking for 'subjectKeyIdentifier', tag 0x1000010, OPTIONAL asn1.c:869:asn1_decode_entry: decoding 'subjectKeyIdentifier' asn1.c:1069:asn1_decode: Looking for 'crlDistributionPoints', tag 0x1000010, OPTIONAL asn1.c:869:asn1_decode_entry: decoding 'crlDistributionPoints' asn1.c:1069:asn1_decode: Looking for 'authorityKeyIdentifier', tag 0x1000010, OPTIONAL asn1.c:869:asn1_decode_entry: decoding 'authorityKeyIdentifier' asn1.c:1069:asn1_decode: Looking for 'keyUsage', tag 0x1000010, OPTIONAL asn1.c:869:asn1_decode_entry: decoding 'keyUsage' asn1.c:1120:asn1_decode: returning with: 0 asn1.c:1120:asn1_decode: returning with: 0 asn1.c:1120:asn1_decode: returning with: 0 asn1.c:1069:asn1_decode: Looking for 'signatureAlgorithm', tag 0x1000010 asn1.c:869:asn1_decode_entry: decoding 'signatureAlgorithm' asn1.c:1044:asn1_decode: called, left=13, depth 1 asn1.c:1069:asn1_decode: Looking for 'algorithm', tag 0x6 asn1.c:869:asn1_decode_entry: decoding 'algorithm' asn1.c:1069:asn1_decode: Looking for 'nullParam', tag 0x5, OPTIONAL asn1.c:869:asn1_decode_entry: decoding 'nullParam' asn1.c:1120:asn1_decode: returning with: 0 asn1.c:1069:asn1_decode: Looking for 'signatureValue', tag 0x3 asn1.c:869:asn1_decode_entry: decoding 'signatureValue' asn1.c:1120:asn1_decode: returning with: 0 asn1.c:1044:asn1_decode: called, left=140, depth 0 asn1.c:1069:asn1_decode: Looking for 'publicKeyCoefficients', tag 0x1000010 asn1.c:869:asn1_decode_entry: decoding 'publicKeyCoefficients' asn1.c:1044:asn1_decode: called, left=137, depth 1 asn1.c:1069:asn1_decode: Looking for 'modulus', tag 0x2 asn1.c:869:asn1_decode_entry: decoding 'modulus' asn1.c:1069:asn1_decode: Looking for 'exponent', tag 0x2 asn1.c:869:asn1_decode_entry: decoding 'exponent' asn1.c:1120:asn1_decode: returning with: 0 asn1.c:1120:asn1_decode: returning with: 0 pkcs15-cert.c:115:sc_pkcs15_read_certificate: called pkcs15.c:1547:sc_pkcs15_read_file: called, path=3f005015314a, index=0, count=-1 card.c:289:sc_lock: called card.c:541:sc_select_file: called; type=2, path=3f005015314a card-starcos.c:366:starcos_select_file: called card-starcos.c:378:starcos_select_file: current path (path, valid): (len: 0) apdu.c:504:sc_transmit_apdu: called apdu.c:504:sc_transmit_apdu: called card-starcos.c:355:starcos_select_fid: returning with: 0 apdu.c:504:sc_transmit_apdu: called apdu.c:504:sc_transmit_apdu: called card-starcos.c:355:starcos_select_fid: returning with: 0 apdu.c:504:sc_transmit_apdu: called apdu.c:504:sc_transmit_apdu: called card-starcos.c:137:process_fci: processing FCI bytes card-starcos.c:159:process_fci: bytes in file: 661 card-starcos.c:208:process_fci: type: working EF card-starcos.c:209:process_fci: EF structure: transparent card-starcos.c:355:starcos_select_fid: returning with: 0 card.c:563:sc_select_file: returning with: 0 card.c:408:sc_read_binary: called; 661 bytes at index 0 card.c:408:sc_read_binary: called; 128 bytes at index 0 apdu.c:504:sc_transmit_apdu: called iso7816.c:132:iso7816_read_binary: returning with: 128 card.c:439:sc_read_binary: returning with: 128 card.c:408:sc_read_binary: called; 128 bytes at index 128 apdu.c:504:sc_transmit_apdu: called iso7816.c:132:iso7816_read_binary: returning with: 128 card.c:439:sc_read_binary: returning with: 128 card.c:408:sc_read_binary: called; 128 bytes at index 256 apdu.c:504:sc_transmit_apdu: called iso7816.c:132:iso7816_read_binary: returning with: 128 card.c:439:sc_read_binary: returning with: 128 card.c:408:sc_read_binary: called; 128 bytes at index 384 apdu.c:504:sc_transmit_apdu: called iso7816.c:132:iso7816_read_binary: returning with: 128 card.c:439:sc_read_binary: returning with: 128 card.c:408:sc_read_binary: called; 128 bytes at index 512 apdu.c:504:sc_transmit_apdu: called iso7816.c:132:iso7816_read_binary: returning with: 128 card.c:439:sc_read_binary: returning with: 128 card.c:408:sc_read_binary: called; 21 bytes at index 640 apdu.c:504:sc_transmit_apdu: called iso7816.c:132:iso7816_read_binary: returning with: 21 card.c:439:sc_read_binary: returning with: 21 card.c:436:sc_read_binary: returning with: 661 card.c:316:sc_unlock: called card.c:327:sc_unlock: Calling card logout function apdu.c:504:sc_transmit_apdu: called card-starcos.c:1242:starcos_check_sw: sw1 = 0x90, sw2 = 0x00 asn1.c:1044:asn1_decode: called, left=657, depth 0 asn1.c:1069:asn1_decode: Looking for 'tbsCertificate', tag 0x1000010 asn1.c:869:asn1_decode_entry: decoding 'tbsCertificate' asn1.c:1044:asn1_decode: called, left=506, depth 1 asn1.c:1069:asn1_decode: Looking for 'version', tag 0x21000000, OPTIONAL asn1.c:869:asn1_decode_entry: decoding 'version' asn1.c:1044:asn1_decode: called, left=3, depth 2 asn1.c:1069:asn1_decode: Looking for 'version', tag 0x2 asn1.c:869:asn1_decode_entry: decoding 'version' asn1.c:1120:asn1_decode: returning with: 0 asn1.c:1069:asn1_decode: Looking for 'serialNumber', tag 0x2 asn1.c:869:asn1_decode_entry: decoding 'serialNumber' asn1.c:1069:asn1_decode: Looking for 'signature', tag 0x1000010 asn1.c:869:asn1_decode_entry: decoding 'signature' asn1.c:1069:asn1_decode: Looking for 'issuer', tag 0x1000010 asn1.c:869:asn1_decode_entry: decoding 'issuer' asn1.c:1069:asn1_decode: Looking for 'validity', tag 0x1000010 asn1.c:869:asn1_decode_entry: decoding 'validity' asn1.c:1069:asn1_decode: Looking for 'subject', tag 0x1000010 asn1.c:869:asn1_decode_entry: decoding 'subject' asn1.c:1069:asn1_decode: Looking for 'subjectPublicKeyInfo', tag 0x1000010 asn1.c:869:asn1_decode_entry: decoding 'subjectPublicKeyInfo' asn1.c:1044:asn1_decode: called, left=159, depth 2 asn1.c:1069:asn1_decode: Looking for 'algorithm', tag 0x1000010 asn1.c:869:asn1_decode_entry: decoding 'algorithm' asn1.c:1044:asn1_decode: called, left=13, depth 3 asn1.c:1069:asn1_decode: Looking for 'algorithm', tag 0x6 asn1.c:869:asn1_decode_entry: decoding 'algorithm' asn1.c:1069:asn1_decode: Looking for 'nullParam', tag 0x5, OPTIONAL asn1.c:869:asn1_decode_entry: decoding 'nullParam' asn1.c:1120:asn1_decode: returning with: 0 asn1.c:1069:asn1_decode: Looking for 'subjectPublicKey', tag 0x3 asn1.c:869:asn1_decode_entry: decoding 'subjectPublicKey' asn1.c:1120:asn1_decode: returning with: 0 asn1.c:1069:asn1_decode: Looking for 'extensions', tag 0x21000003, OPTIONAL asn1.c:869:asn1_decode_entry: decoding 'extensions' asn1.c:1044:asn1_decode: called, left=154, depth 2 asn1.c:1069:asn1_decode: Looking for 'x509v3', tag 0x1000010, OPTIONAL asn1.c:869:asn1_decode_entry: decoding 'x509v3' asn1.c:1044:asn1_decode: called, left=151, depth 3 asn1.c:1069:asn1_decode: Looking for 'certificatePolicies', tag 0x1000010, OPTIONAL asn1.c:869:asn1_decode_entry: decoding 'certificatePolicies' asn1.c:1069:asn1_decode: Looking for 'subjectKeyIdentifier', tag 0x1000010, OPTIONAL asn1.c:869:asn1_decode_entry: decoding 'subjectKeyIdentifier' asn1.c:1069:asn1_decode: Looking for 'crlDistributionPoints', tag 0x1000010, OPTIONAL asn1.c:869:asn1_decode_entry: decoding 'crlDistributionPoints' asn1.c:1069:asn1_decode: Looking for 'authorityKeyIdentifier', tag 0x1000010, OPTIONAL asn1.c:1085:asn1_decode: not present asn1.c:1069:asn1_decode: Looking for 'keyUsage', tag 0x1000010, OPTIONAL asn1.c:1085:asn1_decode: not present asn1.c:1120:asn1_decode: returning with: 0 asn1.c:1120:asn1_decode: returning with: 0 asn1.c:1120:asn1_decode: returning with: 0 asn1.c:1069:asn1_decode: Looking for 'signatureAlgorithm', tag 0x1000010 asn1.c:869:asn1_decode_entry: decoding 'signatureAlgorithm' asn1.c:1044:asn1_decode: called, left=13, depth 1 asn1.c:1069:asn1_decode: Looking for 'algorithm', tag 0x6 asn1.c:869:asn1_decode_entry: decoding 'algorithm' asn1.c:1069:asn1_decode: Looking for 'nullParam', tag 0x5, OPTIONAL asn1.c:869:asn1_decode_entry: decoding 'nullParam' asn1.c:1120:asn1_decode: returning with: 0 asn1.c:1069:asn1_decode: Looking for 'signatureValue', tag 0x3 asn1.c:869:asn1_decode_entry: decoding 'signatureValue' asn1.c:1120:asn1_decode: returning with: 0 asn1.c:1044:asn1_decode: called, left=140, depth 0 asn1.c:1069:asn1_decode: Looking for 'publicKeyCoefficients', tag 0x1000010 asn1.c:869:asn1_decode_entry: decoding 'publicKeyCoefficients' asn1.c:1044:asn1_decode: called, left=137, depth 1 asn1.c:1069:asn1_decode: Looking for 'modulus', tag 0x2 asn1.c:869:asn1_decode_entry: decoding 'modulus' asn1.c:1069:asn1_decode: Looking for 'exponent', tag 0x2 asn1.c:869:asn1_decode_entry: decoding 'exponent' asn1.c:1120:asn1_decode: returning with: 0 asn1.c:1120:asn1_decode: returning with: 0 framework-pkcs15.c:405:pkcs15_create_pkcs11_objects: Found 0 data objects slot.c:232:slot_allocate: Allocated slot 0 framework-pkcs15.c:641:pkcs15_init_slot: Initialized token 'OpenSC Card (User PIN)' framework-pkcs15.c:737: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:783:pkcs15_create_tokens: All tokens created slot.c:153:card_detect: 0: Detection ended pkcs11-global.c:212:C_Initialize: C_Initialize: result = 0 pkcs11-global.c:302:C_GetSlotList: Getting slot listing slot.c:83:card_detect: 0: Detecting smart card sc.c:168:sc_detect_card_presence: called sc.c:173:sc_detect_card_presence: returning with: 1 slot.c:153:card_detect: 0: Detection ended pkcs11-global.c:314:C_GetSlotList: was only a size inquiry (8) pkcs11-global.c:302:C_GetSlotList: Getting slot listing slot.c:83:card_detect: 0: Detecting smart card sc.c:168:sc_detect_card_presence: called sc.c:173:sc_detect_card_presence: returning with: 1 slot.c:153:card_detect: 0: Detection ended pkcs11-global.c:331:C_GetSlotList: returned 8 slots pkcs11-global.c:415:C_GetTokenInfo: Getting info about token in slot 0 pkcs11-session.c:40:C_OpenSession: Opening new session for slot 0 pkcs11-global.c:415:C_GetTokenInfo: Getting info about token in slot 0 pkcs11-session.c:239:C_Login: Login for session 1 card.c:289:sc_lock: called card.c:541:sc_select_file: called; type=2, path=3f005015 card-starcos.c:366:starcos_select_file: called card-starcos.c:378:starcos_select_file: current path (path, valid): (len: 0) apdu.c:504:sc_transmit_apdu: called apdu.c:504:sc_transmit_apdu: called card-starcos.c:355:starcos_select_fid: returning with: 0 apdu.c:504:sc_transmit_apdu: called apdu.c:504:sc_transmit_apdu: called card-starcos.c:355:starcos_select_fid: returning with: 0 card.c:563:sc_select_file: returning with: 0 sec.c:157:sc_pin_cmd: called apdu.c:504:sc_transmit_apdu: called card-starcos.c:1242:starcos_check_sw: sw1 = 0x90, sw2 = 0x00 sec.c:204:sc_pin_cmd: returning with: 0 card.c:316:sc_unlock: called card.c:327:sc_unlock: Calling card logout function apdu.c:504:sc_transmit_apdu: called card-starcos.c:1242:starcos_check_sw: sw1 = 0x90, sw2 = 0x00 framework-pkcs15.c:856: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:254:C_FindObjectsInit: Object 0/3: Attribute 0x0 does NOT match. pkcs11-object.c:254:C_FindObjectsInit: Object 0/4: Attribute 0x0 does NOT match. pkcs11-object.c:254:C_FindObjectsInit: Object 0/5: Attribute 0x0 does NOT match. pkcs11-object.c:280:C_FindObjectsInit: 1 matching objects pkcs11-object.c:485:C_SignInit: Sign initialization returns 0 pkcs11-object.c:547:C_SignUpdate: C_SignUpdate returns 0 framework-pkcs15.c:1889:pkcs15_prkey_sign: Initiating signing operation, mechanism 0x1. card.c:289:sc_lock: called framework-pkcs15.c:2740:reselect_app_df: reselect application df card.c:541:sc_select_file: called; type=2, path=3f005015 card-starcos.c:366:starcos_select_file: called card-starcos.c:378:starcos_select_file: current path (path, valid): (len: 0) apdu.c:504:sc_transmit_apdu: called apdu.c:504:sc_transmit_apdu: called card-starcos.c:355:starcos_select_fid: returning with: 0 apdu.c:504:sc_transmit_apdu: called apdu.c:504:sc_transmit_apdu: called card-starcos.c:355:starcos_select_fid: returning with: 0 card.c:563:sc_select_file: returning with: 0 framework-pkcs15.c:1933:pkcs15_prkey_sign: Selected flags 12. Now computing signature for 7 bytes. 512 bytes reserved. pkcs15-sec.c:162:sc_pkcs15_compute_signature: called card.c:541:sc_select_file: called; type=2, path=3f005015 card-starcos.c:366:starcos_select_file: called card-starcos.c:378:starcos_select_file: current path (path, valid): 3f005015 (len: 4) card-starcos.c:472:starcos_select_file: cache hit card.c:563:sc_select_file: returning with: 0 sec.c:63:sc_set_security_env: called apdu.c:504:sc_transmit_apdu: 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-starcos.c:1224:starcos_compute_signature: returning with: 128 sec.c:53:sc_compute_signature: returning with: 128 card.c:316:sc_unlock: called card.c:327:sc_unlock: Calling card logout function apdu.c:504:sc_transmit_apdu: called card-starcos.c:1242:starcos_check_sw: sw1 = 0x90, sw2 = 0x00 framework-pkcs15.c:1953:pkcs15_prkey_sign: Sign complete. Result 128. pkcs11-object.c:583:C_SignFinal: C_SignFinal returns 0 pkcs11-session.c:131:C_CloseSession: C_CloseSession(1) apdu.c:504:sc_transmit_apdu: called card.c:289:sc_lock: called card.c:316:sc_unlock: called card.c:327:sc_unlock: Calling card logout function apdu.c:504:sc_transmit_apdu: called card-starcos.c:1242:starcos_check_sw: sw1 = 0x90, sw2 = 0x00 card-starcos.c:1242:starcos_check_sw: sw1 = 0x90, sw2 = 0x00 sec.c:104:sc_logout: returning with: 0 pkcs11-global.c:230: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). 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:731:sc_pkcs15_unbind: called card.c:234:sc_disconnect_card: called card.c:249:sc_disconnect_card: returning with: 0 ctx.c:728:sc_release_context: called
_______________________________________________ opensc-devel mailing list opensc-devel@lists.opensc-project.org http://www.opensc-project.org/mailman/listinfo/opensc-devel
