[Nitrokey HSM] After CKR_DEVICE_MEMORY (0x31) re-initialization does not work anymore

BHR · November 4, 2020, 10:20am

I filled my Nitrokey HSM 2 with a lot of keys & certificates and received at the end:

 error: PKCS11 function C_GenerateKeyPair failed: rv = CKR_DEVICE_MEMORY (0x31)

After that cert signing, deleting of keys, updating firmware and initialization did not work anymore.

For example the following command runs for about 5-7 minutes and stops with the response:

 $ sc-hsm-tool --initialize --so-pin 3537363231383830 --pin 648219 --label "Test" --dkek-shares 1
 sc_card_ctl(*, SC_CARDCTL_SC_HSM_INITIALIZE, *) failed with Transmit failed

Same for

$ pkcs11-tool --init-token --so-pin=3537363231383830 --label "Test"
error: PKCS11 function C_InitToken failed: rv = CKR_GENERAL_ERROR (0x5)
Aborting.

Any suggestions how to re-initialize the key and solve this?

szszszsz · November 4, 2020, 6:30pm

Hi!

Could you please run the command with OPENSC_DEBUG set and upload the log here? Please do not use any secret PINs.

BHR · November 5, 2020, 9:53am

Part 1/2
$ OPENSC_DEBUG=9 sc-hsm-tool --initialize --so-pin 3537363231383830 --pin 648219 --label “Testkey with Debug”

P:2334; T:0x140262427064384 10:42:53.408 [sc-hsm-tool] ctx.c:720:process_config_file: Used configuration file '/etc/opensc/opensc.conf'
P:2334; T:0x140262427064384 10:42:53.408 [sc-hsm-tool] ctx.c:851:sc_context_create: ===================================
P:2334; T:0x140262427064384 10:42:53.409 [sc-hsm-tool] ctx.c:852:sc_context_create: opensc version: 0.20.0
P:2334; T:0x140262427064384 10:42:53.409 [sc-hsm-tool] reader-pcsc.c:858:pcsc_init: PC/SC options: connect_exclusive=0 disconnect_action=0 transaction_end_action=0 reconnect_action=0 enable_pinpad=1 enable_pace=1
P:2334; T:0x140262427064384 10:42:53.415 [sc-hsm-tool] reader-pcsc.c:1347:pcsc_detect_readers: called
P:2334; T:0x140262427064384 10:42:53.415 [sc-hsm-tool] reader-pcsc.c:1360:pcsc_detect_readers: Probing PC/SC readers
P:2334; T:0x140262427064384 10:42:53.415 [sc-hsm-tool] reader-pcsc.c:1411:pcsc_detect_readers: Establish PC/SC context
P:2334; T:0x140262427064384 10:42:54.571 [sc-hsm-tool] reader-pcsc.c:1296:pcsc_add_reader: Adding new PC/SC reader 'Nitrokey Nitrokey HSM (DENK01040490000         ) 00 00'
P:2334; T:0x140262427064384 10:42:54.571 [sc-hsm-tool] reader-pcsc.c:333:refresh_attributes: Nitrokey Nitrokey HSM (DENK01040490000         ) 00 00 check
P:2334; T:0x140262427064384 10:42:54.571 [sc-hsm-tool] reader-pcsc.c:381:refresh_attributes: current  state: 0x00000022
P:2334; T:0x140262427064384 10:42:54.571 [sc-hsm-tool] reader-pcsc.c:382:refresh_attributes: previous state: 0x00000000
P:2334; T:0x140262427064384 10:42:54.572 [sc-hsm-tool] reader-pcsc.c:435:refresh_attributes: card present, changed
P:2334; T:0x140262427064384 10:42:54.573 [sc-hsm-tool] reader-pcsc.c:1500:pcsc_detect_readers: Nitrokey Nitrokey HSM (DENK01040490000         ) 00 00:SCardConnect(SHARED): 0x00000000
P:2334; T:0x140262427064384 10:42:54.574 [sc-hsm-tool] reader-pcsc.c:1114:detect_reader_features: called
P:2334; T:0x140262427064384 10:42:54.574 [sc-hsm-tool] reader-pcsc.c:1116:detect_reader_features: Requesting reader features ... 
P:2334; T:0x140262427064384 10:42:54.574 [sc-hsm-tool] reader-pcsc.c:1137:detect_reader_features: Reader feature 12 found
P:2334; T:0x140262427064384 10:42:54.574 [sc-hsm-tool] reader-pcsc.c:1054:part10_detect_max_data: get dwMaxAPDUDataSize property returned 65536
P:2334; T:0x140262427064384 10:42:54.574 [sc-hsm-tool] reader-pcsc.c:1246:detect_reader_features: Reader supports transceiving 65536 bytes of data
P:2334; T:0x140262427064384 10:42:54.575 [sc-hsm-tool] reader-pcsc.c:1093:part10_get_vendor_product: id_vendor=20a0 id_product=4230
P:2334; T:0x140262427064384 10:42:54.576 [sc-hsm-tool] reader-pcsc.c:1515:pcsc_detect_readers: returning with: 0 (Success)
P:2334; T:0x140262427064384 10:42:54.580 [sc-hsm-tool] sc.c:315:sc_detect_card_presence: called
P:2334; T:0x140262427064384 10:42:54.581 [sc-hsm-tool] reader-pcsc.c:445:pcsc_detect_card_presence: called
P:2334; T:0x140262427064384 10:42:54.581 [sc-hsm-tool] reader-pcsc.c:333:refresh_attributes: Nitrokey Nitrokey HSM (DENK01040490000         ) 00 00 check
P:2334; T:0x140262427064384 10:42:54.583 [sc-hsm-tool] reader-pcsc.c:358:refresh_attributes: returning with: 0 (Success)
P:2334; T:0x140262427064384 10:42:54.583 [sc-hsm-tool] reader-pcsc.c:450:pcsc_detect_card_presence: returning with: 1
P:2334; T:0x140262427064384 10:42:54.583 [sc-hsm-tool] sc.c:320:sc_detect_card_presence: returning with: 1
Using reader with a card: Nitrokey Nitrokey HSM (DENK01040490000         ) 00 00
P:2334; T:0x140262427064384 10:42:54.583 [sc-hsm-tool] sc.c:315:sc_detect_card_presence: called
P:2334; T:0x140262427064384 10:42:54.583 [sc-hsm-tool] reader-pcsc.c:445:pcsc_detect_card_presence: called
P:2334; T:0x140262427064384 10:42:54.583 [sc-hsm-tool] reader-pcsc.c:333:refresh_attributes: Nitrokey Nitrokey HSM (DENK01040490000         ) 00 00 check
P:2334; T:0x140262427064384 10:42:54.585 [sc-hsm-tool] reader-pcsc.c:358:refresh_attributes: returning with: 0 (Success)
P:2334; T:0x140262427064384 10:42:54.585 [sc-hsm-tool] reader-pcsc.c:450:pcsc_detect_card_presence: returning with: 1
P:2334; T:0x140262427064384 10:42:54.585 [sc-hsm-tool] sc.c:320:sc_detect_card_presence: returning with: 1
P:2334; T:0x140262427064384 10:42:54.585 [sc-hsm-tool] card.c:254:sc_connect_card: called
P:2334; T:0x140262427064384 10:42:54.585 [sc-hsm-tool] reader-pcsc.c:578:pcsc_connect: called
P:2334; T:0x140262427064384 10:42:54.585 [sc-hsm-tool] reader-pcsc.c:333:refresh_attributes: Nitrokey Nitrokey HSM (DENK01040490000         ) 00 00 check
P:2334; T:0x140262427064384 10:42:54.587 [sc-hsm-tool] reader-pcsc.c:358:refresh_attributes: returning with: 0 (Success)
P:2334; T:0x140262427064384 10:42:54.588 [sc-hsm-tool] reader-pcsc.c:610:pcsc_connect: Initial protocol: T=1
P:2334; T:0x140262427064384 10:42:54.588 [sc-hsm-tool] card.c:1147:match_atr_table: ATR     : 3b:de:18:ff:81:91:fe:1f:c3:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:1c
P:2334; T:0x140262427064384 10:42:54.588 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:FE:18:00:00:81:31:FE:45:80:31:81:54:48:53:4D:31:73:80:21:40:81:07:FA
P:2334; T:0x140262427064384 10:42:54.588 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:8E:80:01:80:31:81:54:48:53:4D:31:73:80:21:40:81:07:18
P:2334; T:0x140262427064384 10:42:54.588 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.588 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:DE:18:FF:81:91:FE:1F:C3:80:31:81:54:48:53:4D:31:73:80:21:40:81:07:1C
P:2334; T:0x140262427064384 10:42:54.588 [sc-hsm-tool] reader-pcsc.c:624:pcsc_connect: Final protocol: T=1
P:2334; T:0x140262427064384 10:42:54.588 [sc-hsm-tool] card.c:279:sc_connect_card: matching configured ATRs
P:2334; T:0x140262427064384 10:42:54.588 [sc-hsm-tool] card.c:323:sc_connect_card: matching built-in ATRs
P:2334; T:0x140262427064384 10:42:54.588 [sc-hsm-tool] card.c:341:sc_connect_card: trying driver 'cardos'
P:2334; T:0x140262427064384 10:42:54.589 [sc-hsm-tool] card.c:1147:match_atr_table: ATR     : 3b:de:18:ff:81:91:fe:1f:c3:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:1c
P:2334; T:0x140262427064384 10:42:54.589 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3b:e2:00:ff:c1:10:31:fe:55:c8:02:9c
P:2334; T:0x140262427064384 10:42:54.589 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.589 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3b:f2:18:00:ff:c1:0a:31:fe:55:c8:06:8a
P:2334; T:0x140262427064384 10:42:54.589 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.589 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3b:d2:18:02:c1:0a:31:fe:58:c8:0d:51
P:2334; T:0x140262427064384 10:42:54.589 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.589 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3b:d2:18:00:81:31:fe:58:c9:01:14
P:2334; T:0x140262427064384 10:42:54.589 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.589 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3b:d2:18:00:81:31:fe:58:c9:02:17
P:2334; T:0x140262427064384 10:42:54.589 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.589 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3b:d2:18:00:81:31:fe:58:c9:03:16
P:2334; T:0x140262427064384 10:42:54.589 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.589 [sc-hsm-tool] card.c:341:sc_connect_card: trying driver 'flex'
P:2334; T:0x140262427064384 10:42:54.589 [sc-hsm-tool] card.c:1147:match_atr_table: ATR     : 3b:de:18:ff:81:91:fe:1f:c3:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:1c
P:2334; T:0x140262427064384 10:42:54.589 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3b:95:15:40:20:68:01:02:00:00
P:2334; T:0x140262427064384 10:42:54.590 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.590 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:95:15:40:FF:68:01:02:02:01
P:2334; T:0x140262427064384 10:42:54.590 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.590 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:95:15:40:FF:68:01:02:02:04
P:2334; T:0x140262427064384 10:42:54.590 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.590 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:85:40:20:68:01:01:05:01
P:2334; T:0x140262427064384 10:42:54.590 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.590 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:95:94:40:FF:63:01:01:02:01
P:2334; T:0x140262427064384 10:42:54.590 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.590 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:95:15:40:FF:63:01:01:02:01
P:2334; T:0x140262427064384 10:42:54.590 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.590 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:95:18:40:FF:64:02:01:01:02
P:2334; T:0x140262427064384 10:42:54.590 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.590 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:95:18:40:FF:62:01:01:00:00
P:2334; T:0x140262427064384 10:42:54.590 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.590 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:95:18:40:FF:62:01:02:01:04
P:2334; T:0x140262427064384 10:42:54.590 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.590 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:95:18:40:FF:62:04:01:01:05
P:2334; T:0x140262427064384 10:42:54.590 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.591 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3b:95:15:40:ff:68:01:02:45:47
P:2334; T:0x140262427064384 10:42:54.591 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.591 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:E2:00:00:40:20:49:06
P:2334; T:0x140262427064384 10:42:54.591 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.591 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:E2:00:00:40:20:49:05
P:2334; T:0x140262427064384 10:42:54.591 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.591 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:E2:00:00:40:20:49:07
P:2334; T:0x140262427064384 10:42:54.591 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.591 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:85:40:20:68:01:01:03:05
P:2334; T:0x140262427064384 10:42:54.591 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.591 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:02:14:50
P:2334; T:0x140262427064384 10:42:54.591 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.591 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:19:14:55:90:01:02:01:00:05:04:B0
P:2334; T:0x140262427064384 10:42:54.591 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.591 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:32:15:00:06:80
P:2334; T:0x140262427064384 10:42:54.591 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.591 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:32:15:00:06:95
P:2334; T:0x140262427064384 10:42:54.591 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.592 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:19:14:59:01:01:0F:01:00:05:08:B0
P:2334; T:0x140262427064384 10:42:54.592 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.592 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:19:14:55:90:01:01:01:00:05:08:B0
P:2334; T:0x140262427064384 10:42:54.592 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.592 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:16:94:81:10:06:01:81:3F
P:2334; T:0x140262427064384 10:42:54.592 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.592 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:16:94:81:10:06:01:81:2F
P:2334; T:0x140262427064384 10:42:54.592 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.592 [sc-hsm-tool] card.c:341:sc_connect_card: trying driver 'cyberflex'
P:2334; T:0x140262427064384 10:42:54.592 [sc-hsm-tool] card.c:1147:match_atr_table: ATR     : 3b:de:18:ff:81:91:fe:1f:c3:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:1c
P:2334; T:0x140262427064384 10:42:54.592 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3b:95:15:40:20:68:01:02:00:00
P:2334; T:0x140262427064384 10:42:54.592 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.592 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:95:15:40:FF:68:01:02:02:01
P:2334; T:0x140262427064384 10:42:54.592 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.592 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:95:15:40:FF:68:01:02:02:04
P:2334; T:0x140262427064384 10:42:54.592 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.592 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:85:40:20:68:01:01:05:01
P:2334; T:0x140262427064384 10:42:54.592 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.592 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:95:94:40:FF:63:01:01:02:01
P:2334; T:0x140262427064384 10:42:54.593 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.593 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:95:15:40:FF:63:01:01:02:01
P:2334; T:0x140262427064384 10:42:54.593 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.593 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:95:18:40:FF:64:02:01:01:02
P:2334; T:0x140262427064384 10:42:54.593 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.593 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:95:18:40:FF:62:01:01:00:00
P:2334; T:0x140262427064384 10:42:54.593 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.593 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:95:18:40:FF:62:01:02:01:04
P:2334; T:0x140262427064384 10:42:54.593 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.593 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:95:18:40:FF:62:04:01:01:05
P:2334; T:0x140262427064384 10:42:54.593 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.593 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3b:95:15:40:ff:68:01:02:45:47
P:2334; T:0x140262427064384 10:42:54.593 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.593 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:E2:00:00:40:20:49:06
P:2334; T:0x140262427064384 10:42:54.593 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.593 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:E2:00:00:40:20:49:05
P:2334; T:0x140262427064384 10:42:54.593 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.593 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:E2:00:00:40:20:49:07
P:2334; T:0x140262427064384 10:42:54.593 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.593 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:85:40:20:68:01:01:03:05
P:2334; T:0x140262427064384 10:42:54.593 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.593 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:02:14:50
P:2334; T:0x140262427064384 10:42:54.593 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.593 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:19:14:55:90:01:02:01:00:05:04:B0
P:2334; T:0x140262427064384 10:42:54.593 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.593 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:32:15:00:06:80
P:2334; T:0x140262427064384 10:42:54.593 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.593 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:32:15:00:06:95
P:2334; T:0x140262427064384 10:42:54.593 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.593 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:19:14:59:01:01:0F:01:00:05:08:B0
P:2334; T:0x140262427064384 10:42:54.593 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.593 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:19:14:55:90:01:01:01:00:05:08:B0
P:2334; T:0x140262427064384 10:42:54.593 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.593 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:16:94:81:10:06:01:81:3F
P:2334; T:0x140262427064384 10:42:54.593 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.593 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:16:94:81:10:06:01:81:2F
P:2334; T:0x140262427064384 10:42:54.593 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.593 [sc-hsm-tool] card.c:341:sc_connect_card: trying driver 'gpk'
P:2334; T:0x140262427064384 10:42:54.593 [sc-hsm-tool] card.c:1147:match_atr_table: ATR     : 3b:de:18:ff:81:91:fe:1f:c3:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:1c
P:2334; T:0x140262427064384 10:42:54.593 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:27:00:80:65:A2:04:01:01:37
P:2334; T:0x140262427064384 10:42:54.593 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.593 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:27:00:80:65:A2:05:01:01:37
P:2334; T:0x140262427064384 10:42:54.593 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.594 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:27:00:80:65:A2:0C:01:01:37
P:2334; T:0x140262427064384 10:42:54.594 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.594 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:A7:00:40:14:80:65:A2:14:01:01:37
P:2334; T:0x140262427064384 10:42:54.594 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.594 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:A7:00:40:18:80:65:A2:08:01:01:52
P:2334; T:0x140262427064384 10:42:54.594 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.594 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:A7:00:40:18:80:65:A2:09:01:01:52
P:2334; T:0x140262427064384 10:42:54.594 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.594 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:A7:00:40:18:80:65:A2:09:01:02:52
P:2334; T:0x140262427064384 10:42:54.594 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.594 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:A7:00:40:18:80:65:A2:09:01:03:52
P:2334; T:0x140262427064384 10:42:54.594 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.594 [sc-hsm-tool] card.c:341:sc_connect_card: trying driver 'gemsafeV1'
P:2334; T:0x140262427064384 10:42:54.594 [sc-hsm-tool] card.c:1147:match_atr_table: ATR     : 3b:de:18:ff:81:91:fe:1f:c3:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:1c
P:2334; T:0x140262427064384 10:42:54.594 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:7B:94:00:00:80:65:B0:83:01:01:74:83:00:90:00
P:2334; T:0x140262427064384 10:42:54.594 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.594 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:6B:00:00:80:65:B0:83:01:01:74:83:00:90:00
P:2334; T:0x140262427064384 10:42:54.594 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.594 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3b:6d:00:00:80:31:80:65:b0:83:01:02:90:83:00:90:00
P:2334; T:0x140262427064384 10:42:54.594 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.594 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:6B:00:00:80:65:B0:83:01:03:74:83:00:90:00
P:2334; T:0x140262427064384 10:42:54.594 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.594 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:7A:94:00:00:80:65:A2:01:01:01:3D:72:D6:43
P:2334; T:0x140262427064384 10:42:54.594 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.594 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:7D:94:00:00:80:31:80:65:B0:83:01:01:90:83:00:90:00
P:2334; T:0x140262427064384 10:42:54.594 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.594 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:7D:96:00:00:80:31:80:65:B0:83:11:48:C8:83:00:90:00
P:2334; T:0x140262427064384 10:42:54.594 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.594 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:7D:95:00:00:80:31:80:65:B0:83:11:C0:A9:83:00
P:2334; T:0x140262427064384 10:42:54.594 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.594 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:7D:95:00:00:80:31:80:65:B0:83:11:C0:A9:83:00:90:00
P:2334; T:0x140262427064384 10:42:54.594 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.594 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:7D:95:00:00:80:31:80:65:B0:83:11:00:C8:83:00
P:2334; T:0x140262427064384 10:42:54.594 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.594 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:7D:95:00:00:80:31:80:65:B0:83:11:00:C8:83:00:90:00
P:2334; T:0x140262427064384 10:42:54.594 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.594 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:FF:96:00:00:81:31:80:43:80:31:80:65:B0:85:03:00:EF:12:0F:FF:82:90:00:67
P:2334; T:0x140262427064384 10:42:54.594 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.594 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:FF:96:00:00:81:31:FE:43:80:31:80:65:B0:85:04:01:20:12:0F:FF:82:90:00:D0
P:2334; T:0x140262427064384 10:42:54.594 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.594 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:7D:96:00:00:80:31:80:65:B0:83:11:00:C8:83:00:90:00
P:2334; T:0x140262427064384 10:42:54.594 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.594 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3b:7d:96:00:00:80:31:80:65:b0:83:02:01:f3:83:00:90:00
P:2334; T:0x140262427064384 10:42:54.594 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.594 [sc-hsm-tool] card.c:341:sc_connect_card: trying driver 'asepcos'
P:2334; T:0x140262427064384 10:42:54.594 [sc-hsm-tool] card.c:1147:match_atr_table: ATR     : 3b:de:18:ff:81:91:fe:1f:c3:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:1c
P:2334; T:0x140262427064384 10:42:54.594 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3b:d6:18:00:81:b1:80:7d:1f:03:80:51:00:61:10:30:8f
P:2334; T:0x140262427064384 10:42:54.594 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.594 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3b:d6:18:00:81:b1:fe:7d:1f:03:41:53:45:37:35:35:01
P:2334; T:0x140262427064384 10:42:54.594 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.594 [sc-hsm-tool] card.c:341:sc_connect_card: trying driver 'starcos'
P:2334; T:0x140262427064384 10:42:54.594 [sc-hsm-tool] card.c:1147:match_atr_table: ATR     : 3b:de:18:ff:81:91:fe:1f:c3:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:1c
P:2334; T:0x140262427064384 10:42:54.594 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:B7:94:00:c0:24:31:fe:65:53:50:4b:32:33:90:00:b4
P:2334; T:0x140262427064384 10:42:54.595 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.595 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:B7:94:00:81:31:fe:65:53:50:4b:32:33:90:00:d1
P:2334; T:0x140262427064384 10:42:54.595 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.595 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3b:b7:18:00:c0:3e:31:fe:65:53:50:4b:32:34:90:00:25
P:2334; T:0x140262427064384 10:42:54.595 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.595 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3b:d8:18:ff:81:b1:fe:45:1f:03:80:64:04:1a:b4:03:81:05:61
P:2334; T:0x140262427064384 10:42:54.595 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.595 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3b:d3:96:ff:81:b1:fe:45:1f:07:80:81:05:2d
P:2334; T:0x140262427064384 10:42:54.595 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.595 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:9B:96:C0:0A:31:FE:45:80:67:04:1E:B5:01:00:89:4C:81:05:45
P:2334; T:0x140262427064384 10:42:54.595 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.595 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:DB:96:FF:81:31:FE:45:80:67:05:34:B5:02:01:C0:A1:81:05:3C
P:2334; T:0x140262427064384 10:42:54.595 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.595 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:D9:96:FF:81:31:FE:45:80:31:B8:73:86:01:C0:81:05:02
P:2334; T:0x140262427064384 10:42:54.595 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.595 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:DF:96:FF:81:31:FE:45:80:5B:44:45:2E:42:4E:4F:54:4B:31:31:31:81:05:A0
P:2334; T:0x140262427064384 10:42:54.595 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:DF:96:FF:81:31:FE:45:80:5B:44:45:2E:42:4E:4F:54:4B:31:30:30:81:05:A0
P:2334; T:0x140262427064384 10:42:54.595 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:D9:96:FF:81:31:FE:45:80:31:B8:73:86:01:E0:81:05:22
P:2334; T:0x140262427064384 10:42:54.595 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.595 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:D0:97:FF:81:B1:FE:45:1F:07:2B
P:2334; T:0x140262427064384 10:42:54.595 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.595 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3b:df:96:ff:81:31:fe:45:80:5b:44:45:2e:42:41:5f:53:43:33:35:32:81:05:b5
P:2334; T:0x140262427064384 10:42:54.595 [sc-hsm-tool] card.c:341:sc_connect_card: trying driver 'tcos'
P:2334; T:0x140262427064384 10:42:54.595 [sc-hsm-tool] card.c:1147:match_atr_table: ATR     : 3b:de:18:ff:81:91:fe:1f:c3:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:1c
P:2334; T:0x140262427064384 10:42:54.595 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:BA:13:00:81:31:86:5D:00:64:05:0A:02:01:31:80:90:00:8B
P:2334; T:0x140262427064384 10:42:54.595 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.595 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:BA:14:00:81:31:86:5D:00:64:05:14:02:02:31:80:90:00:91
P:2334; T:0x140262427064384 10:42:54.595 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.595 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:BA:96:00:81:31:86:5D:00:64:05:60:02:03:31:80:90:00:66
P:2334; T:0x140262427064384 10:42:54.595 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.595 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:BA:96:00:81:31:86:5D:00:64:05:7B:02:03:31:80:90:00:7D
P:2334; T:0x140262427064384 10:42:54.595 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.595 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:BF:96:00:81:31:FE:5D:00:64:04:11:03:01:31:C0:73:F7:01:D0:00:90:00:7D
P:2334; T:0x140262427064384 10:42:54.595 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:BF:96:00:81:31:FE:5D:00:64:04:11:04:0F:31:C0:73:F7:01:D0:00:90:00:74

BHR · November 5, 2020, 9:54am

Part 2/2

P:2334; T:0x140262427064384 10:42:54.595 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:BF:B6:00:81:31:FE:5D:00:64:04:28:03:02:31:C0:73:F7:01:D0:00:90:00:67
P:2334; T:0x140262427064384 10:42:54.595 [sc-hsm-tool] card.c:341:sc_connect_card: trying driver 'oberthur'
P:2334; T:0x140262427064384 10:42:54.595 [sc-hsm-tool] card.c:1147:match_atr_table: ATR     : 3b:de:18:ff:81:91:fe:1f:c3:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:1c
P:2334; T:0x140262427064384 10:42:54.595 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:7D:18:00:00:00:31:80:71:8E:64:77:E3:01:00:82:90:00
P:2334; T:0x140262427064384 10:42:54.595 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.595 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:7D:18:00:00:00:31:80:71:8E:64:77:E3:02:00:82:90:00
P:2334; T:0x140262427064384 10:42:54.595 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.595 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:7D:11:00:00:00:31:80:71:8E:64:77:E3:01:00:82:90:00
P:2334; T:0x140262427064384 10:42:54.595 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.596 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:7D:11:00:00:00:31:80:71:8E:64:77:E3:02:00:82:90:00
P:2334; T:0x140262427064384 10:42:54.596 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.596 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:7B:18:00:00:00:31:C0:64:77:E3:03:00:82:90:00
P:2334; T:0x140262427064384 10:42:54.596 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.596 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:FB:11:00:00:81:31:FE:45:00:31:C0:64:77:E9:10:00:00:90:00:6A
P:2334; T:0x140262427064384 10:42:54.596 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.596 [sc-hsm-tool] card.c:341:sc_connect_card: trying driver 'authentic'
P:2334; T:0x140262427064384 10:42:54.596 [sc-hsm-tool] card-authentic.c:416:authentic_match_card: 
try to match card with ATR (24 bytes):
3B DE 18 FF 81 91 FE 1F C3 80 31 81 54 48 53 4D ;.........1.THSM
31 73 80 21 40 81 07 1C                         1s.!@...
P:2334; T:0x140262427064384 10:42:54.600 [sc-hsm-tool] card.c:1147:match_atr_table: ATR     : 3b:de:18:ff:81:91:fe:1f:c3:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:1c
P:2334; T:0x140262427064384 10:42:54.600 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:DD:18:00:81:31:FE:45:80:F9:A0:00:00:00:77:01:00:70:0A:90:00:8B
P:2334; T:0x140262427064384 10:42:54.600 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.600 [sc-hsm-tool] card-authentic.c:419:authentic_match_card: card not matched
P:2334; T:0x140262427064384 10:42:54.600 [sc-hsm-tool] card.c:341:sc_connect_card: trying driver 'iasecc'
P:2334; T:0x140262427064384 10:42:54.600 [sc-hsm-tool] card.c:1147:match_atr_table: ATR     : 3b:de:18:ff:81:91:fe:1f:c3:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:1c
P:2334; T:0x140262427064384 10:42:54.600 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:7F:96:00:00:00:31:B8:64:40:70:14:10:73:94:01:80:82:90:00
P:2334; T:0x140262427064384 10:42:54.600 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.600 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:DD:18:00:81:31:FE:45:80:F9:A0:00:00:00:77:01:08:00:07:90:00:FE
P:2334; T:0x140262427064384 10:42:54.600 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.601 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:7D:13:00:00:4D:44:57:2D:49:41:53:2D:43:41:52:44:32
P:2334; T:0x140262427064384 10:42:54.601 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.601 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:7F:18:00:00:00:31:B8:64:50:23:EC:C1:73:94:01:80:82:90:00
P:2334; T:0x140262427064384 10:42:54.601 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.601 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:DF:96:00:80:31:FE:45:00:31:B8:64:04:1F:EC:C1:73:94:01:80:82:90:00:EC
P:2334; T:0x140262427064384 10:42:54.601 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:DF:18:FF:81:91:FE:1F:C3:00:31:B8:64:0C:01:EC:C1:73:94:01:80:82:90:00:B3
P:2334; T:0x140262427064384 10:42:54.601 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.601 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:DC:18:FF:81:91:FE:1F:C3:80:73:C8:21:13:66:02:04:03:55:00:02:34
P:2334; T:0x140262427064384 10:42:54.601 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.601 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:DC:18:FF:81:91:FE:1F:C3:80:73:C8:21:13:66:01:0B:03:52:00:05:38
P:2334; T:0x140262427064384 10:42:54.601 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.601 [sc-hsm-tool] card-iasecc.c:345:iasecc_match_card: card not matched
P:2334; T:0x140262427064384 10:42:54.601 [sc-hsm-tool] card.c:341:sc_connect_card: trying driver 'belpic'
P:2334; T:0x140262427064384 10:42:54.601 [sc-hsm-tool] card.c:1147:match_atr_table: ATR     : 3b:de:18:ff:81:91:fe:1f:c3:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:1c
P:2334; T:0x140262427064384 10:42:54.601 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:98:13:40:0A:A5:03:01:01:01:AD:13:11
P:2334; T:0x140262427064384 10:42:54.601 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.601 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:98:94:40:0A:A5:03:01:01:01:AD:13:10
P:2334; T:0x140262427064384 10:42:54.601 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.601 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:98:94:40:FF:A5:03:01:01:01:AD:13:10
P:2334; T:0x140262427064384 10:42:54.601 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.601 [sc-hsm-tool] card.c:341:sc_connect_card: trying driver 'incrypto34'
P:2334; T:0x140262427064384 10:42:54.601 [sc-hsm-tool] card.c:1147:match_atr_table: ATR     : 3b:de:18:ff:81:91:fe:1f:c3:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:1c
P:2334; T:0x140262427064384 10:42:54.601 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3b:ff:18:00:ff:81:31:fe:55:00:6b:02:09:02:00:01:01:01:44:53:44:10:31:80:92
P:2334; T:0x140262427064384 10:42:54.601 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.601 [sc-hsm-tool] card.c:341:sc_connect_card: trying driver 'akis'
P:2334; T:0x140262427064384 10:42:54.601 [sc-hsm-tool] card.c:1147:match_atr_table: ATR     : 3b:de:18:ff:81:91:fe:1f:c3:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:1c
P:2334; T:0x140262427064384 10:42:54.601 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3b:ba:11:00:81:31:fe:4d:55:45:4b:41:45:20:56:31:2e:30:ae
P:2334; T:0x140262427064384 10:42:54.601 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.601 [sc-hsm-tool] card.c:341:sc_connect_card: trying driver 'entersafe'
P:2334; T:0x140262427064384 10:42:54.601 [sc-hsm-tool] card-entersafe.c:138:entersafe_match_card: called
P:2334; T:0x140262427064384 10:42:54.601 [sc-hsm-tool] card.c:1147:match_atr_table: ATR     : 3b:de:18:ff:81:91:fe:1f:c3:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:1c
P:2334; T:0x140262427064384 10:42:54.601 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3b:0f:00:65:46:53:05:19:05:71:df:00:00:00:00:00:00
P:2334; T:0x140262427064384 10:42:54.601 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.601 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3b:9f:95:81:31:fe:9f:00:65:46:53:05:30:06:71:df:00:00:00:80:6a:82:5e
P:2334; T:0x140262427064384 10:42:54.601 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.601 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3b:fc:18:00:00:81:31:80:45:90:67:46:4a:00:64:18:14:00:00:00:00:02
P:2334; T:0x140262427064384 10:42:54.601 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.601 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3b:7c:18:00:00:90:67:46:4a:20:28:8c:58:00:00:00:00
P:2334; T:0x140262427064384 10:42:54.601 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.601 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:FC:18:00:00:81:31:80:45:90:67:46:4A:21:28:8C:58:00:00:00:00:B7
P:2334; T:0x140262427064384 10:42:54.601 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.601 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:FC:18:00:00:81:31:80:45:90:67:46:4A:20:25:c3:30:00:00:00:00
P:2334; T:0x140262427064384 10:42:54.601 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.601 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:FC:18:00:00:81:31:80:45:90:67:46:4A:00:6A:04:24:00:00:00:00:20
P:2334; T:0x140262427064384 10:42:54.601 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.601 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:FC:18:00:00:81:31:80:45:90:67:46:4A:00:68:08:04:00:00:00:00:0E
P:2334; T:0x140262427064384 10:42:54.602 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.602 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:FC:18:00:00:81:31:80:45:90:67:46:4A:10:27:61:30:00:00:00:00:0C
P:2334; T:0x140262427064384 10:42:54.602 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.602 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3b:fc:18:00:00:81:31:80:45:90:67:46:4a:00:68:08:06:00:00:00:00:0c
P:2334; T:0x140262427064384 10:42:54.602 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.602 [sc-hsm-tool] card.c:341:sc_connect_card: trying driver 'epass2003'
P:2334; T:0x140262427064384 10:42:54.602 [sc-hsm-tool] card-epass2003.c:1143:epass2003_match_card: called
P:2334; T:0x140262427064384 10:42:54.602 [sc-hsm-tool] card.c:1147:match_atr_table: ATR     : 3b:de:18:ff:81:91:fe:1f:c3:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:1c
P:2334; T:0x140262427064384 10:42:54.602 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:9F:95:81:31:FE:9F:00:66:46:53:05:10:00:11:71:df:00:00:00:6a:82:5e
P:2334; T:0x140262427064384 10:42:54.602 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.602 [sc-hsm-tool] card.c:341:sc_connect_card: trying driver 'rutoken'
P:2334; T:0x140262427064384 10:42:54.602 [sc-hsm-tool] card-rutoken.c:103:rutoken_match_card: called
P:2334; T:0x140262427064384 10:42:54.602 [sc-hsm-tool] card.c:1147:match_atr_table: ATR     : 3b:de:18:ff:81:91:fe:1f:c3:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:1c
P:2334; T:0x140262427064384 10:42:54.602 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3b:6f:00:ff:00:56:72:75:54:6f:6b:6e:73:30:20:00:00:90:00
P:2334; T:0x140262427064384 10:42:54.602 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.602 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3b:6f:00:ff:00:56:75:61:54:6f:6b:6e:73:30:20:00:00:90:00
P:2334; T:0x140262427064384 10:42:54.602 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.602 [sc-hsm-tool] card-rutoken.c:109:rutoken_match_card: returning with: 0 (Success)
P:2334; T:0x140262427064384 10:42:54.602 [sc-hsm-tool] card.c:341:sc_connect_card: trying driver 'rutoken_ecp'
P:2334; T:0x140262427064384 10:42:54.602 [sc-hsm-tool] card.c:1147:match_atr_table: ATR     : 3b:de:18:ff:81:91:fe:1f:c3:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:1c
P:2334; T:0x140262427064384 10:42:54.603 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:8B:01:52:75:74:6F:6B:65:6E:20:45:43:50:A0
P:2334; T:0x140262427064384 10:42:54.603 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.603 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:8B:01:52:75:74:6F:6B:65:6E:20:44:53:20:C1
P:2334; T:0x140262427064384 10:42:54.603 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.603 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:9C:96:00:52:75:74:6F:6B:65:6E:45:43:50:73:63
P:2334; T:0x140262427064384 10:42:54.603 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.603 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:9C:94:80:11:40:52:75:74:6F:6B:65:6E:45:43:50:73:63:C3
P:2334; T:0x140262427064384 10:42:54.603 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.603 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:8B:01:52:75:74:6F:6B:65:6E:6C:69:74:65:C2
P:2334; T:0x140262427064384 10:42:54.603 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.603 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:9E:96:00:52:75:74:6F:6B:65:6E:4C:69:74:65:53:43:32
P:2334; T:0x140262427064384 10:42:54.603 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.603 [sc-hsm-tool] card-rtecp.c:77:rtecp_match_card: returning with: 0 (Success)
P:2334; T:0x140262427064384 10:42:54.603 [sc-hsm-tool] card.c:341:sc_connect_card: trying driver 'myeid'
P:2334; T:0x140262427064384 10:42:54.603 [sc-hsm-tool] card.c:341:sc_connect_card: trying driver 'dnie'
P:2334; T:0x140262427064384 10:42:54.603 [sc-hsm-tool] card-dnie.c:738:dnie_match_card: called
P:2334; T:0x140262427064384 10:42:54.603 [sc-hsm-tool] card.c:1147:match_atr_table: ATR     : 3b:de:18:ff:81:91:fe:1f:c3:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:1c
P:2334; T:0x140262427064384 10:42:54.603 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:7F:00:00:00:00:6A:44:4E:49:65:00:00:00:00:00:00:03:90:00
P:2334; T:0x140262427064384 10:42:54.603 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.603 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:7F:00:00:00:00:6A:44:4E:49:65:00:00:00:00:00:00:0F:65:81
P:2334; T:0x140262427064384 10:42:54.603 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.603 [sc-hsm-tool] card-dnie.c:741:dnie_match_card: returning with: 0 (Success)
P:2334; T:0x140262427064384 10:42:54.603 [sc-hsm-tool] card.c:341:sc_connect_card: trying driver 'MaskTech'
P:2334; T:0x140262427064384 10:42:54.603 [sc-hsm-tool] card.c:1147:match_atr_table: ATR     : 3b:de:18:ff:81:91:fe:1f:c3:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:1c
P:2334; T:0x140262427064384 10:42:54.603 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:89:80:01:4D:54:43:4F:53:70:02:00:04:31
P:2334; T:0x140262427064384 10:42:54.603 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.603 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:88:80:01:00:00:00:00:77:81:80:00:6E
P:2334; T:0x140262427064384 10:42:54.603 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.603 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:9D:13:81:31:60:35:80:31:C0:69:4D:54:43:4F:53:73:02:00:00:40
P:2334; T:0x140262427064384 10:42:54.603 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.603 [sc-hsm-tool] card.c:341:sc_connect_card: trying driver 'atrust-acos'
P:2334; T:0x140262427064384 10:42:54.603 [sc-hsm-tool] card.c:341:sc_connect_card: trying driver 'westcos'
P:2334; T:0x140262427064384 10:42:54.603 [sc-hsm-tool] card.c:1147:match_atr_table: ATR     : 3b:de:18:ff:81:91:fe:1f:c3:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:1c
P:2334; T:0x140262427064384 10:42:54.603 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3F:69:00:00:00:64:01:00:00:00:80:90:00
P:2334; T:0x140262427064384 10:42:54.603 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.603 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:95:94:80:1F:C3:80:73:C8:21:13:54
P:2334; T:0x140262427064384 10:42:54.603 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.603 [sc-hsm-tool] card.c:341:sc_connect_card: trying driver 'esteid2018'
P:2334; T:0x140262427064384 10:42:54.603 [sc-hsm-tool] card.c:1147:match_atr_table: ATR     : 3b:de:18:ff:81:91:fe:1f:c3:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:1c
P:2334; T:0x140262427064384 10:42:54.603 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3b:db:96:00:80:b1:fe:45:1f:83:00:12:23:3f:53:65:49:44:0f:90:00:f1
P:2334; T:0x140262427064384 10:42:54.603 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.604 [sc-hsm-tool] card.c:341:sc_connect_card: trying driver 'coolkey'
P:2334; T:0x140262427064384 10:42:54.604 [sc-hsm-tool] card-coolkey.c:2240:coolkey_match_card: called
P:2334; T:0x140262427064384 10:42:54.604 [sc-hsm-tool] card-coolkey.c:919:coolkey_apdu_io: called
P:2334; T:0x140262427064384 10:42:54.604 [sc-hsm-tool] card-coolkey.c:921:coolkey_apdu_io: a4 04 00 7 : 0 0
P:2334; T:0x140262427064384 10:42:54.604 [sc-hsm-tool] card-coolkey.c:986:coolkey_apdu_io: calling sc_transmit_apdu flags=0 le=0, resplen=0, resp=0x7ffec08ee0c0
P:2334; T:0x140262427064384 10:42:54.604 [sc-hsm-tool] apdu.c:546:sc_transmit_apdu: called
P:2334; T:0x140262427064384 10:42:54.604 [sc-hsm-tool] card.c:473:sc_lock: called
P:2334; T:0x140262427064384 10:42:54.604 [sc-hsm-tool] reader-pcsc.c:657:pcsc_lock: called
P:2334; T:0x140262427064384 10:42:54.604 [sc-hsm-tool] card-coolkey.c:2385:coolkey_card_reader_lock_obtained: called
P:2334; T:0x140262427064384 10:42:54.604 [sc-hsm-tool] card-coolkey.c:2391:coolkey_card_reader_lock_obtained: returning with: 0 (Success)
P:2334; T:0x140262427064384 10:42:54.604 [sc-hsm-tool] card.c:513:sc_lock: returning with: 0 (Success)
P:2334; T:0x140262427064384 10:42:54.604 [sc-hsm-tool] apdu.c:513:sc_transmit: called
P:2334; T:0x140262427064384 10:42:54.604 [sc-hsm-tool] apdu.c:363:sc_single_transmit: called
P:2334; T:0x140262427064384 10:42:54.604 [sc-hsm-tool] apdu.c:367:sc_single_transmit: CLA:0, INS:A4, P1:4, P2:0, data(7) 0x7ffec08f0141
P:2334; T:0x140262427064384 10:42:54.604 [sc-hsm-tool] reader-pcsc.c:297:pcsc_transmit: reader 'Nitrokey Nitrokey HSM (DENK01040490000         ) 00 00'
P:2334; T:0x140262427064384 10:42:54.604 [sc-hsm-tool] reader-pcsc.c:298:pcsc_transmit: 
Outgoing APDU (12 bytes):
00 A4 04 00 07 62 76 01 FF 00 00 00 .....bv.....
P:2334; T:0x140262427064384 10:42:54.604 [sc-hsm-tool] reader-pcsc.c:216:pcsc_internal_transmit: called
P:2334; T:0x140262427064384 10:42:54.646 [sc-hsm-tool] reader-pcsc.c:307:pcsc_transmit: 
Incoming APDU (2 bytes):
6A 82 j.
P:2334; T:0x140262427064384 10:42:54.646 [sc-hsm-tool] apdu.c:382:sc_single_transmit: returning with: 0 (Success)
P:2334; T:0x140262427064384 10:42:54.646 [sc-hsm-tool] apdu.c:535:sc_transmit: returning with: 0 (Success)
P:2334; T:0x140262427064384 10:42:54.646 [sc-hsm-tool] card.c:523:sc_unlock: called
P:2334; T:0x140262427064384 10:42:54.646 [sc-hsm-tool] reader-pcsc.c:709:pcsc_unlock: called
P:2334; T:0x140262427064384 10:42:54.656 [sc-hsm-tool] card-coolkey.c:993:coolkey_apdu_io: result r=0 apdu.resplen=0 sw1=6a sw2=82
P:2334; T:0x140262427064384 10:42:54.656 [sc-hsm-tool] card-coolkey.c:874:coolkey_check_sw: sw1 = 0x6a, sw2 = 0x82
P:2334; T:0x140262427064384 10:42:54.656 [sc-hsm-tool] iso7816.c:128:iso7816_check_sw: File or application not found
P:2334; T:0x140262427064384 10:42:54.656 [sc-hsm-tool] card-coolkey.c:1003:coolkey_apdu_io: Transmit failed
P:2334; T:0x140262427064384 10:42:54.656 [sc-hsm-tool] card-coolkey.c:1021:coolkey_apdu_io: returning with: -1201 (File not found)
P:2334; T:0x140262427064384 10:42:54.657 [sc-hsm-tool] card.c:341:sc_connect_card: trying driver 'muscle'
P:2334; T:0x140262427064384 10:42:54.657 [sc-hsm-tool] apdu.c:546:sc_transmit_apdu: called
P:2334; T:0x140262427064384 10:42:54.657 [sc-hsm-tool] card.c:473:sc_lock: called
P:2334; T:0x140262427064384 10:42:54.657 [sc-hsm-tool] reader-pcsc.c:657:pcsc_lock: called
P:2334; T:0x140262427064384 10:42:54.658 [sc-hsm-tool] card-muscle.c:832:muscle_card_reader_lock_obtained: called
P:2334; T:0x140262427064384 10:42:54.658 [sc-hsm-tool] card-muscle.c:840:muscle_card_reader_lock_obtained: returning with: 0 (Success)
P:2334; T:0x140262427064384 10:42:54.658 [sc-hsm-tool] card.c:513:sc_lock: returning with: 0 (Success)
P:2334; T:0x140262427064384 10:42:54.658 [sc-hsm-tool] apdu.c:513:sc_transmit: called
P:2334; T:0x140262427064384 10:42:54.658 [sc-hsm-tool] apdu.c:363:sc_single_transmit: called
P:2334; T:0x140262427064384 10:42:54.658 [sc-hsm-tool] apdu.c:367:sc_single_transmit: CLA:0, INS:A4, P1:4, P2:0, data(6) 0x7f9164be60d0
P:2334; T:0x140262427064384 10:42:54.658 [sc-hsm-tool] reader-pcsc.c:297:pcsc_transmit: reader 'Nitrokey Nitrokey HSM (DENK01040490000         ) 00 00'
P:2334; T:0x140262427064384 10:42:54.658 [sc-hsm-tool] reader-pcsc.c:298:pcsc_transmit: 
Outgoing APDU (11 bytes):
00 A4 04 00 06 A0 00 00 00 01 01 ...........
P:2334; T:0x140262427064384 10:42:54.658 [sc-hsm-tool] reader-pcsc.c:216:pcsc_internal_transmit: called
P:2334; T:0x140262427064384 10:42:54.689 [sc-hsm-tool] reader-pcsc.c:307:pcsc_transmit: 
Incoming APDU (2 bytes):
6A 82 j.
P:2334; T:0x140262427064384 10:42:54.689 [sc-hsm-tool] apdu.c:382:sc_single_transmit: returning with: 0 (Success)
P:2334; T:0x140262427064384 10:42:54.689 [sc-hsm-tool] apdu.c:535:sc_transmit: returning with: 0 (Success)
P:2334; T:0x140262427064384 10:42:54.689 [sc-hsm-tool] card.c:523:sc_unlock: called
P:2334; T:0x140262427064384 10:42:54.689 [sc-hsm-tool] reader-pcsc.c:709:pcsc_unlock: called
P:2334; T:0x140262427064384 10:42:54.694 [sc-hsm-tool] muscle.c:276:msc_select_applet: returning with: -1200 (Card command failed)
P:2334; T:0x140262427064384 10:42:54.694 [sc-hsm-tool] card.c:341:sc_connect_card: trying driver 'sc-hsm'
P:2334; T:0x140262427064384 10:42:54.694 [sc-hsm-tool] card.c:1147:match_atr_table: ATR     : 3b:de:18:ff:81:91:fe:1f:c3:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:1c
P:2334; T:0x140262427064384 10:42:54.694 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:FE:18:00:00:81:31:FE:45:80:31:81:54:48:53:4D:31:73:80:21:40:81:07:FA
P:2334; T:0x140262427064384 10:42:54.694 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:8E:80:01:80:31:81:54:48:53:4D:31:73:80:21:40:81:07:18
P:2334; T:0x140262427064384 10:42:54.694 [sc-hsm-tool] card.c:1161:match_atr_table: ignored - wrong length
P:2334; T:0x140262427064384 10:42:54.694 [sc-hsm-tool] card.c:1158:match_atr_table: ATR try : 3B:DE:18:FF:81:91:FE:1F:C3:80:31:81:54:48:53:4D:31:73:80:21:40:81:07:1C
P:2334; T:0x140262427064384 10:42:54.694 [sc-hsm-tool] card.c:355:sc_connect_card: matched: SmartCard-HSM
P:2334; T:0x140262427064384 10:42:54.694 [sc-hsm-tool] card-sc-hsm.c:1618:sc_hsm_init: called
P:2334; T:0x140262427064384 10:42:54.694 [sc-hsm-tool] apdu.c:546:sc_transmit_apdu: called
P:2334; T:0x140262427064384 10:42:54.694 [sc-hsm-tool] card.c:473:sc_lock: called
P:2334; T:0x140262427064384 10:42:54.694 [sc-hsm-tool] reader-pcsc.c:657:pcsc_lock: called
P:2334; T:0x140262427064384 10:42:54.694 [sc-hsm-tool] card.c:513:sc_lock: returning with: 0 (Success)
P:2334; T:0x140262427064384 10:42:54.694 [sc-hsm-tool] apdu.c:513:sc_transmit: called
P:2334; T:0x140262427064384 10:42:54.694 [sc-hsm-tool] apdu.c:363:sc_single_transmit: called
P:2334; T:0x140262427064384 10:42:54.694 [sc-hsm-tool] apdu.c:367:sc_single_transmit: CLA:0, INS:A4, P1:4, P2:0, data(11) 0x7ffec08eff10
P:2334; T:0x140262427064384 10:42:54.694 [sc-hsm-tool] reader-pcsc.c:297:pcsc_transmit: reader 'Nitrokey Nitrokey HSM (DENK01040490000         ) 00 00'
P:2334; T:0x140262427064384 10:42:54.694 [sc-hsm-tool] reader-pcsc.c:298:pcsc_transmit: 
Outgoing APDU (17 bytes):
00 A4 04 00 0B E8 2B 06 01 04 01 81 C3 1F 02 01 ......+.........
00                                              .
P:2334; T:0x140262427064384 10:42:54.694 [sc-hsm-tool] reader-pcsc.c:216:pcsc_internal_transmit: called
P:2334; T:0x140262427064384 10:42:54.733 [sc-hsm-tool] reader-pcsc.c:307:pcsc_transmit: 
Incoming APDU (14 bytes):
6F 0A 82 01 78 85 05 00 01 05 03 04 90 00 o...x.........
P:2334; T:0x140262427064384 10:42:54.733 [sc-hsm-tool] apdu.c:382:sc_single_transmit: returning with: 0 (Success)
P:2334; T:0x140262427064384 10:42:54.733 [sc-hsm-tool] apdu.c:535:sc_transmit: returning with: 0 (Success)
P:2334; T:0x140262427064384 10:42:54.733 [sc-hsm-tool] card.c:523:sc_unlock: called
P:2334; T:0x140262427064384 10:42:54.733 [sc-hsm-tool] reader-pcsc.c:709:pcsc_unlock: called
P:2334; T:0x140262427064384 10:42:54.741 [sc-hsm-tool] iso7816.c:374:iso7816_process_fci:   shareable: yes
P:2334; T:0x140262427064384 10:42:54.741 [sc-hsm-tool] iso7816.c:393:iso7816_process_fci:   type: DF
P:2334; T:0x140262427064384 10:42:54.741 [sc-hsm-tool] iso7816.c:394:iso7816_process_fci:   EF structure: 0
P:2334; T:0x140262427064384 10:42:54.741 [sc-hsm-tool] iso7816.c:395:iso7816_process_fci:   tag 0x82: 0x78
P:2334; T:0x140262427064384 10:42:54.741 [sc-hsm-tool] card.c:382:sc_connect_card: card info name:'SmartCard-HSM version 3.4', type:26000, flags:0x0, max_send/recv_size:1215/65536
P:2334; T:0x140262427064384 10:42:54.741 [sc-hsm-tool] card.c:1521:sc_card_sm_check: called
P:2334; T:0x140262427064384 10:42:54.741 [sc-hsm-tool] card.c:1526:sc_card_sm_check: returning with: 0 (Success)
P:2334; T:0x140262427064384 10:42:54.741 [sc-hsm-tool] card.c:397:sc_connect_card: returning with: 0 (Success)
P:2334; T:0x140262427064384 10:42:54.741 [sc-hsm-tool] card.c:817:sc_select_file: called; type=1, path=e82b0601040181c31f0201::
P:2334; T:0x140262427064384 10:42:54.741 [sc-hsm-tool] card.c:852:sc_select_file: returning with: 0 (Success)
P:2334; T:0x140262427064384 10:42:54.741 [sc-hsm-tool] card.c:1014:sc_card_ctl: called
P:2334; T:0x140262427064384 10:42:54.741 [sc-hsm-tool] card-sc-hsm.c:1241:sc_hsm_initialize: called
P:2334; T:0x140262427064384 10:42:54.741 [sc-hsm-tool] apdu.c:546:sc_transmit_apdu: called
P:2334; T:0x140262427064384 10:42:54.742 [sc-hsm-tool] card.c:473:sc_lock: called
P:2334; T:0x140262427064384 10:42:54.742 [sc-hsm-tool] reader-pcsc.c:657:pcsc_lock: called
P:2334; T:0x140262427064384 10:42:54.742 [sc-hsm-tool] card.c:513:sc_lock: returning with: 0 (Success)
P:2334; T:0x140262427064384 10:42:54.742 [sc-hsm-tool] apdu.c:513:sc_transmit: called
P:2334; T:0x140262427064384 10:42:54.742 [sc-hsm-tool] apdu.c:363:sc_single_transmit: called
P:2334; T:0x140262427064384 10:42:54.742 [sc-hsm-tool] apdu.c:367:sc_single_transmit: CLA:80, INS:50, P1:0, P2:0, data(25) 0x7ffec08f05a0
P:2334; T:0x140262427064384 10:42:54.742 [sc-hsm-tool] reader-pcsc.c:297:pcsc_transmit: reader 'Nitrokey Nitrokey HSM (DENK01040490000         ) 00 00'
P:2334; T:0x140262427064384 10:42:54.742 [sc-hsm-tool] reader-pcsc.c:298:pcsc_transmit: 
Outgoing APDU (30 bytes):
80 50 00 00 19 80 02 00 01 81 06 36 34 38 32 31 .P.........64821
39 82 08 35 37 36 32 31 38 38 30 91 01 03       9..57621880...
P:2334; T:0x140262427064384 10:42:54.742 [sc-hsm-tool] reader-pcsc.c:216:pcsc_internal_transmit: called
P:2334; T:0x140262427064384 10:43:17.120 [sc-hsm-tool] reader-pcsc.c:245:pcsc_internal_transmit: Nitrokey Nitrokey HSM (DENK01040490000         ) 00 00:SCardTransmit/Control failed: 0x80100016
P:2334; T:0x140262427064384 10:43:17.121 [sc-hsm-tool] reader-pcsc.c:445:pcsc_detect_card_presence: called
P:2334; T:0x140262427064384 10:43:17.121 [sc-hsm-tool] reader-pcsc.c:333:refresh_attributes: Nitrokey Nitrokey HSM (DENK01040490000         ) 00 00 check
P:2334; T:0x140262427064384 10:43:17.123 [sc-hsm-tool] reader-pcsc.c:358:refresh_attributes: returning with: 0 (Success)
P:2334; T:0x140262427064384 10:43:17.123 [sc-hsm-tool] reader-pcsc.c:450:pcsc_detect_card_presence: returning with: 1
P:2334; T:0x140262427064384 10:43:17.123 [sc-hsm-tool] reader-pcsc.c:445:pcsc_detect_card_presence: called
P:2334; T:0x140262427064384 10:43:17.123 [sc-hsm-tool] reader-pcsc.c:333:refresh_attributes: Nitrokey Nitrokey HSM (DENK01040490000         ) 00 00 check
P:2334; T:0x140262427064384 10:43:17.124 [sc-hsm-tool] reader-pcsc.c:358:refresh_attributes: returning with: 0 (Success)
P:2334; T:0x140262427064384 10:43:17.124 [sc-hsm-tool] reader-pcsc.c:450:pcsc_detect_card_presence: returning with: 1
P:2334; T:0x140262427064384 10:43:17.124 [sc-hsm-tool] reader-pcsc.c:304:pcsc_transmit: unable to transmit
P:2334; T:0x140262427064384 10:43:17.124 [sc-hsm-tool] apdu.c:380:sc_single_transmit: unable to transmit APDU: -1107 (Transmit failed)
P:2334; T:0x140262427064384 10:43:17.124 [sc-hsm-tool] apdu.c:516:sc_transmit: transmit APDU failed: -1107 (Transmit failed)
P:2334; T:0x140262427064384 10:43:17.125 [sc-hsm-tool] card.c:523:sc_unlock: called
P:2334; T:0x140262427064384 10:43:17.125 [sc-hsm-tool] reader-pcsc.c:709:pcsc_unlock: called
P:2334; T:0x140262427064384 10:43:17.134 [sc-hsm-tool] card-sc-hsm.c:1292:sc_hsm_initialize: APDU transmit failed: -1107 (Transmit failed)
P:2334; T:0x140262427064384 10:43:17.134 [sc-hsm-tool] card.c:1024:sc_card_ctl: returning with: -1107 (Transmit failed)
sc_card_ctl(*, SC_CARDCTL_SC_HSM_INITIALIZE, *) failed with Transmit failed
P:2334; T:0x140262427064384 10:43:17.134 [sc-hsm-tool] card.c:414:sc_disconnect_card: called
P:2334; T:0x140262427064384 10:43:22.123 [sc-hsm-tool] reader-pcsc.c:642:pcsc_disconnect: Nitrokey Nitrokey HSM (DENK01040490000         ) 00 00:SCardDisconnect returned: 0x00000000
P:2334; T:0x140262427064384 10:43:22.123 [sc-hsm-tool] card.c:436:sc_disconnect_card: returning with: 0 (Success)
P:2334; T:0x140262427064384 10:43:22.123 [sc-hsm-tool] ctx.c:927:sc_release_context: called
P:2334; T:0x140262427064384 10:43:22.123 [sc-hsm-tool] reader-pcsc.c:946:pcsc_finish: called

sc-hsm · November 5, 2020, 11:31am

Looks like the JCVM’s garbage collector fails to complete before the response times out.

Can you try

opensc-tool -s 80:50:00:00

a couple of times. That command triggers the garbage collection only.

BHR · November 5, 2020, 2:53pm

@sc-hsm I tried the command 15 times in a row with the following output:

$ opensc-tool -s 80:50:00:00
Sending: 80 50 00 00
Received (SW1=0x90, SW2=0x00)

But the result is the same. Initializing results in an error.

Maybe relevant. I noticed, that the LED is still burning for about 2-3 minutes, after the sc-hsm-tool finished.

sc-hsm · November 6, 2020, 4:46pm

Looking at the log, the transmission protocol has a timeout. Usually a timeout should not happen, as the device returns a Waiting Time Extension (WTX) Response to extend timeout processing. In this case the WTX is either too late or is not send at all.

I’ve never seen this issue before and we do a lot of OOM tests during release testing. So this might be a rare issue, that is a combination of chip, firmware and CCID driver.

Are there production keys on the device and would it be an option to return the device for further analysis ?