opensc debug

opensc_debug.log

Linux96(root)> OPENSC_DEBUG=9 pkcs11-tool --module /usr/lib/pkcs11-spy.so -vvvvvvvvv --login --pin <pin> -k --id 01 --key-type EC:prime256v1
P:18087; T:0x139882926028928 18:03:23.474 [opensc-pkcs11] ctx.c:727:process_config_file: Used configuration file '/etc/opensc/opensc.conf'
P:18087; T:0x139882926028928 18:03:23.474 [opensc-pkcs11] ctx.c:858:sc_context_create: ===================================
P:18087; T:0x139882926028928 18:03:23.474 [opensc-pkcs11] ctx.c:859:sc_context_create: opensc version: 0.23.0
P:18087; T:0x139882926028928 18:03:23.474 [opensc-pkcs11] reader-pcsc.c:896: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:18087; T:0x139882926028928 18:03:23.474 [opensc-pkcs11] reader-pcsc.c:1376:pcsc_detect_readers: called
P:18087; T:0x139882926028928 18:03:23.474 [opensc-pkcs11] reader-pcsc.c:1389:pcsc_detect_readers: Probing PC/SC readers
P:18087; T:0x139882926028928 18:03:23.474 [opensc-pkcs11] reader-pcsc.c:1442:pcsc_detect_readers: Establish PC/SC context
P:18087; T:0x139882926028928 18:03:23.474 [opensc-pkcs11] reader-pcsc.c:1325:pcsc_add_reader: Adding new PC/SC reader 'Identiv uTrust 3512 SAM slot Token [CCID Interface] (55512030608814) 00 00'
P:18087; T:0x139882926028928 18:03:23.474 [opensc-pkcs11] reader-pcsc.c:362:refresh_attributes: Identiv uTrust 3512 SAM slot Token [CCID Interface] (55512030608814) 00 00 check
P:18087; T:0x139882926028928 18:03:23.474 [opensc-pkcs11] reader-pcsc.c:406:refresh_attributes: current  state: 0x00000022
P:18087; T:0x139882926028928 18:03:23.474 [opensc-pkcs11] reader-pcsc.c:407:refresh_attributes: previous state: 0x00000000
P:18087; T:0x139882926028928 18:03:23.474 [opensc-pkcs11] reader-pcsc.c:462:refresh_attributes: card present, changed
P:18087; T:0x139882926028928 18:03:23.529 [opensc-pkcs11] reader-pcsc.c:1543:pcsc_detect_readers: Identiv uTrust 3512 SAM slot Token [CCID Interface] (55512030608814) 00 00:SCardConnect(SHARED): 0x00000000
P:18087; T:0x139882926028928 18:03:23.529 [opensc-pkcs11] reader-pcsc.c:1144:detect_reader_features: called
P:18087; T:0x139882926028928 18:03:23.529 [opensc-pkcs11] reader-pcsc.c:1146:detect_reader_features: Requesting reader features ...
P:18087; T:0x139882926028928 18:03:23.530 [opensc-pkcs11] reader-pcsc.c:1558:pcsc_detect_readers: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.530 [opensc-pkcs11] slot.c:393:card_detect_all: Detect all cards
P:18087; T:0x139882926028928 18:03:23.530 [opensc-pkcs11] slot.c:128:create_slot: Creating new slot
P:18087; T:0x139882926028928 18:03:23.530 [opensc-pkcs11] slot.c:163:create_slot: VSS Finished initializing this slot
P:18087; T:0x139882926028928 18:03:23.530 [opensc-pkcs11] slot.c:128:create_slot: Creating new slot
P:18087; T:0x139882926028928 18:03:23.530 [opensc-pkcs11] slot.c:163:create_slot: VSS Finished initializing this slot
P:18087; T:0x139882926028928 18:03:23.530 [opensc-pkcs11] slot.c:128:create_slot: Creating new slot
P:18087; T:0x139882926028928 18:03:23.530 [opensc-pkcs11] slot.c:163:create_slot: VSS Finished initializing this slot
P:18087; T:0x139882926028928 18:03:23.530 [opensc-pkcs11] slot.c:128:create_slot: Creating new slot
P:18087; T:0x139882926028928 18:03:23.530 [opensc-pkcs11] slot.c:163:create_slot: VSS Finished initializing this slot
P:18087; T:0x139882926028928 18:03:23.530 [opensc-pkcs11] slot.c:219:card_detect: Identiv uTrust 3512 SAM slot Token [CCID Interface] (55512030608814) 00 00: Detecting smart card
P:18087; T:0x139882926028928 18:03:23.530 [opensc-pkcs11] sc.c:340:sc_detect_card_presence: called
P:18087; T:0x139882926028928 18:03:23.530 [opensc-pkcs11] reader-pcsc.c:470:pcsc_detect_card_presence: called
P:18087; T:0x139882926028928 18:03:23.530 [opensc-pkcs11] reader-pcsc.c:362:refresh_attributes: Identiv uTrust 3512 SAM slot Token [CCID Interface] (55512030608814) 00 00 check
P:18087; T:0x139882926028928 18:03:23.531 [opensc-pkcs11] reader-pcsc.c:387:refresh_attributes: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.531 [opensc-pkcs11] reader-pcsc.c:478:pcsc_detect_card_presence: returning with: 1
P:18087; T:0x139882926028928 18:03:23.531 [opensc-pkcs11] sc.c:351:sc_detect_card_presence: returning with: 1
P:18087; T:0x139882926028928 18:03:23.531 [opensc-pkcs11] slot.c:256:card_detect: Identiv uTrust 3512 SAM slot Token [CCID Interface] (55512030608814) 00 00: First seen the card
P:18087; T:0x139882926028928 18:03:23.531 [opensc-pkcs11] slot.c:265:card_detect: Identiv uTrust 3512 SAM slot Token [CCID Interface] (55512030608814) 00 00: Connecting ...
P:18087; T:0x139882926028928 18:03:23.531 [opensc-pkcs11] card.c:254:sc_connect_card: called
P:18087; T:0x139882926028928 18:03:23.531 [opensc-pkcs11] reader-pcsc.c:609:pcsc_connect: called
P:18087; T:0x139882926028928 18:03:23.532 [opensc-pkcs11] reader-pcsc.c:362:refresh_attributes: Identiv uTrust 3512 SAM slot Token [CCID Interface] (55512030608814) 00 00 check
P:18087; T:0x139882926028928 18:03:23.533 [opensc-pkcs11] reader-pcsc.c:387:refresh_attributes: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.533 [opensc-pkcs11] reader-pcsc.c:641:pcsc_connect: Initial protocol: T=1
P:18087; T:0x139882926028928 18:03:23.533 [opensc-pkcs11] card.c:1234:match_atr_table: ATR     : 3b:de:96:ff:81:91:fe:1f:c3:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:92
P:18087; T:0x139882926028928 18:03:23.533 [opensc-pkcs11] card.c:1245: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:18087; T:0x139882926028928 18:03:23.533 [opensc-pkcs11] card.c:1245: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:18087; T:0x139882926028928 18:03:23.533 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.533 [opensc-pkcs11] card.c:1245: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:18087; T:0x139882926028928 18:03:23.533 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3B:DE:96:FF:81:91:FE:1F:C3:80:31:81:54:48:53:4D:31:73:80:21:40:81:07:92
P:18087; T:0x139882926028928 18:03:23.533 [opensc-pkcs11] reader-pcsc.c:655:pcsc_connect: Final protocol: T=1
P:18087; T:0x139882926028928 18:03:23.533 [opensc-pkcs11] card.c:279:sc_connect_card: matching configured ATRs
P:18087; T:0x139882926028928 18:03:23.533 [opensc-pkcs11] card.c:323:sc_connect_card: matching built-in ATRs
P:18087; T:0x139882926028928 18:03:23.533 [opensc-pkcs11] card.c:341:sc_connect_card: trying driver 'cardos'
P:18087; T:0x139882926028928 18:03:23.533 [opensc-pkcs11] card.c:1234:match_atr_table: ATR     : 3b:de:96:ff:81:91:fe:1f:c3:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:92
P:18087; T:0x139882926028928 18:03:23.533 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3b:e2:00:ff:c1:10:31:fe:55:c8:02:9c
P:18087; T:0x139882926028928 18:03:23.533 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.533 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3b:f2:18:00:ff:c1:0a:31:fe:55:c8:06:8a
P:18087; T:0x139882926028928 18:03:23.533 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.533 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3b:d2:18:02:c1:0a:31:fe:58:c8:0d:51
P:18087; T:0x139882926028928 18:03:23.533 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.533 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3b:d2:18:00:81:31:fe:58:c9:01:14
P:18087; T:0x139882926028928 18:03:23.533 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.533 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3b:d2:18:00:81:31:fe:58:c9:02:17
P:18087; T:0x139882926028928 18:03:23.533 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.533 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3b:d2:18:00:81:31:fe:58:c9:03:16
P:18087; T:0x139882926028928 18:03:23.534 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.534 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3b:d2:18:00:81:31:fe:58:c9:04:11
P:18087; T:0x139882926028928 18:03:23.534 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.534 [opensc-pkcs11] card.c:341:sc_connect_card: trying driver 'cyberflex'
P:18087; T:0x139882926028928 18:03:23.534 [opensc-pkcs11] card.c:1234:match_atr_table: ATR     : 3b:de:96:ff:81:91:fe:1f:c3:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:92
P:18087; T:0x139882926028928 18:03:23.534 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3b:95:15:40:20:68:01:02:00:00
P:18087; T:0x139882926028928 18:03:23.534 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.534 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3B:95:15:40:FF:68:01:02:02:01
P:18087; T:0x139882926028928 18:03:23.534 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.534 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3B:95:15:40:FF:68:01:02:02:04
P:18087; T:0x139882926028928 18:03:23.534 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.534 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3B:85:40:20:68:01:01:05:01
P:18087; T:0x139882926028928 18:03:23.534 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.534 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3B:95:94:40:FF:63:01:01:02:01
P:18087; T:0x139882926028928 18:03:23.534 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.534 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3B:95:15:40:FF:63:01:01:02:01
P:18087; T:0x139882926028928 18:03:23.534 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.534 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3B:95:18:40:FF:64:02:01:01:02
P:18087; T:0x139882926028928 18:03:23.534 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.534 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3B:95:18:40:FF:62:01:01:00:00
P:18087; T:0x139882926028928 18:03:23.534 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.534 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3B:95:18:40:FF:62:01:02:01:04
P:18087; T:0x139882926028928 18:03:23.534 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.534 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3B:95:18:40:FF:62:04:01:01:05
P:18087; T:0x139882926028928 18:03:23.534 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.534 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3b:95:15:40:ff:68:01:02:45:47
P:18087; T:0x139882926028928 18:03:23.534 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.534 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3B:E2:00:00:40:20:49:06
P:18087; T:0x139882926028928 18:03:23.534 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.534 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3B:E2:00:00:40:20:49:05
P:18087; T:0x139882926028928 18:03:23.534 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.534 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3B:E2:00:00:40:20:49:07
P:18087; T:0x139882926028928 18:03:23.534 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.534 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3B:85:40:20:68:01:01:03:05
P:18087; T:0x139882926028928 18:03:23.534 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.535 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3B:02:14:50
P:18087; T:0x139882926028928 18:03:23.535 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.535 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3B:19:14:55:90:01:02:01:00:05:04:B0
P:18087; T:0x139882926028928 18:03:23.535 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.535 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3B:32:15:00:06:80
P:18087; T:0x139882926028928 18:03:23.535 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.535 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3B:32:15:00:06:95
P:18087; T:0x139882926028928 18:03:23.535 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.535 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3B:19:14:59:01:01:0F:01:00:05:08:B0
P:18087; T:0x139882926028928 18:03:23.535 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.535 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3B:19:14:55:90:01:01:01:00:05:08:B0
P:18087; T:0x139882926028928 18:03:23.535 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.535 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3B:16:94:81:10:06:01:81:3F
P:18087; T:0x139882926028928 18:03:23.535 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.535 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3B:16:94:81:10:06:01:81:2F
P:18087; T:0x139882926028928 18:03:23.535 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.535 [opensc-pkcs11] card.c:341:sc_connect_card: trying driver 'gemsafeV1'
P:18087; T:0x139882926028928 18:03:23.535 [opensc-pkcs11] card.c:1234:match_atr_table: ATR     : 3b:de:96:ff:81:91:fe:1f:c3:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:92
P:18087; T:0x139882926028928 18:03:23.535 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3B:7B:94:00:00:80:65:B0:83:01:01:74:83:00:90:00
P:18087; T:0x139882926028928 18:03:23.535 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.535 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3B:6B:00:00:80:65:B0:83:01:01:74:83:00:90:00
P:18087; T:0x139882926028928 18:03:23.535 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.535 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3b:6d:00:00:80:31:80:65:b0:83:01:02:90:83:00:90:00
P:18087; T:0x139882926028928 18:03:23.535 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.535 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3B:6B:00:00:80:65:B0:83:01:03:74:83:00:90:00
P:18087; T:0x139882926028928 18:03:23.535 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.536 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3B:7A:94:00:00:80:65:A2:01:01:01:3D:72:D6:43
P:18087; T:0x139882926028928 18:03:23.536 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.536 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3B:7D:94:00:00:80:31:80:65:B0:83:01:01:90:83:00:90:00
P:18087; T:0x139882926028928 18:03:23.536 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.536 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3B:7D:96:00:00:80:31:80:65:B0:83:11:48:C8:83:00:90:00
P:18087; T:0x139882926028928 18:03:23.536 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.536 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3B:7D:95:00:00:80:31:80:65:B0:83:11:C0:A9:83:00
P:18087; T:0x139882926028928 18:03:23.536 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.536 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3B:7D:95:00:00:80:31:80:65:B0:83:11:C0:A9:83:00:90:00
P:18087; T:0x139882926028928 18:03:23.536 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.536 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3B:7D:95:00:00:80:31:80:65:B0:83:11:00:C8:83:00
P:18087; T:0x139882926028928 18:03:23.536 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.536 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3B:7D:95:00:00:80:31:80:65:B0:83:11:00:C8:83:00:90:00
P:18087; T:0x139882926028928 18:03:23.536 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.536 [opensc-pkcs11] card.c:1245: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:18087; T:0x139882926028928 18:03:23.536 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.536 [opensc-pkcs11] card.c:1245: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:18087; T:0x139882926028928 18:03:23.536 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.536 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3B:7D:96:00:00:80:31:80:65:B0:83:11:00:C8:83:00:90:00
P:18087; T:0x139882926028928 18:03:23.536 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.536 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3b:7d:96:00:00:80:31:80:65:b0:83:02:01:f3:83:00:90:00
P:18087; T:0x139882926028928 18:03:23.536 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.536 [opensc-pkcs11] card.c:341:sc_connect_card: trying driver 'starcos'
P:18087; T:0x139882926028928 18:03:23.536 [opensc-pkcs11] card.c:1234:match_atr_table: ATR     : 3b:de:96:ff:81:91:fe:1f:c3:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:92
P:18087; T:0x139882926028928 18:03:23.536 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3B:B7:94:00:c0:24:31:fe:65:53:50:4b:32:33:90:00:b4
P:18087; T:0x139882926028928 18:03:23.536 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.536 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3B:B7:94:00:81:31:fe:65:53:50:4b:32:33:90:00:d1
P:18087; T:0x139882926028928 18:03:23.536 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.536 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3b:b7:18:00:c0:3e:31:fe:65:53:50:4b:32:34:90:00:25
P:18087; T:0x139882926028928 18:03:23.536 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.536 [opensc-pkcs11] card.c:1245: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:18087; T:0x139882926028928 18:03:23.536 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.536 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3b:d3:96:ff:81:b1:fe:45:1f:07:80:81:05:2d
P:18087; T:0x139882926028928 18:03:23.536 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.536 [opensc-pkcs11] card.c:1245: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:18087; T:0x139882926028928 18:03:23.536 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.537 [opensc-pkcs11] card.c:1245: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:18087; T:0x139882926028928 18:03:23.537 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.537 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3B:D9:96:FF:81:31:FE:45:80:31:B8:73:86:01:C0:81:05:02
P:18087; T:0x139882926028928 18:03:23.537 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.537 [opensc-pkcs11] card.c:1245: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:18087; T:0x139882926028928 18:03:23.537 [opensc-pkcs11] card.c:1245: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:18087; T:0x139882926028928 18:03:23.537 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3B:D9:96:FF:81:31:FE:45:80:31:B8:73:86:01:E0:81:05:22
P:18087; T:0x139882926028928 18:03:23.537 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.537 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3B:D0:97:FF:81:B1:FE:45:1F:07:2B
P:18087; T:0x139882926028928 18:03:23.537 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.537 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3B:D0:96:FF:81:B1:FE:45:1F:07:2A
P:18087; T:0x139882926028928 18:03:23.537 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.537 [opensc-pkcs11] card.c:1245: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:18087; T:0x139882926028928 18:03:23.537 [opensc-pkcs11] card.c:341:sc_connect_card: trying driver 'tcos'
P:18087; T:0x139882926028928 18:03:23.537 [opensc-pkcs11] card.c:1234:match_atr_table: ATR     : 3b:de:96:ff:81:91:fe:1f:c3:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:92
P:18087; T:0x139882926028928 18:03:23.537 [opensc-pkcs11] card.c:1245: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:18087; T:0x139882926028928 18:03:23.537 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.537 [opensc-pkcs11] card.c:1245: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:18087; T:0x139882926028928 18:03:23.537 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.537 [opensc-pkcs11] card.c:1245: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:18087; T:0x139882926028928 18:03:23.537 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.537 [opensc-pkcs11] card.c:1245: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:18087; T:0x139882926028928 18:03:23.537 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.537 [opensc-pkcs11] card.c:1245: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:18087; T:0x139882926028928 18:03:23.537 [opensc-pkcs11] card.c:1245: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
P:18087; T:0x139882926028928 18:03:23.537 [opensc-pkcs11] card.c:1245: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:18087; T:0x139882926028928 18:03:23.537 [opensc-pkcs11] card.c:341:sc_connect_card: trying driver 'oberthur'
P:18087; T:0x139882926028928 18:03:23.537 [opensc-pkcs11] card.c:1234:match_atr_table: ATR     : 3b:de:96:ff:81:91:fe:1f:c3:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:92
P:18087; T:0x139882926028928 18:03:23.537 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3B:7D:18:00:00:00:31:80:71:8E:64:77:E3:01:00:82:90:00
P:18087; T:0x139882926028928 18:03:23.537 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.538 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3B:7D:18:00:00:00:31:80:71:8E:64:77:E3:02:00:82:90:00
P:18087; T:0x139882926028928 18:03:23.538 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.538 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3B:7D:11:00:00:00:31:80:71:8E:64:77:E3:01:00:82:90:00
P:18087; T:0x139882926028928 18:03:23.538 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.538 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3B:7D:11:00:00:00:31:80:71:8E:64:77:E3:02:00:82:90:00
P:18087; T:0x139882926028928 18:03:23.538 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.538 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3B:7B:18:00:00:00:31:C0:64:77:E3:03:00:82:90:00
P:18087; T:0x139882926028928 18:03:23.538 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.538 [opensc-pkcs11] card.c:1245: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:18087; T:0x139882926028928 18:03:23.538 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.538 [opensc-pkcs11] card.c:341:sc_connect_card: trying driver 'authentic'
P:18087; T:0x139882926028928 18:03:23.538 [opensc-pkcs11] card-authentic.c:429:authentic_match_card:
try to match card with ATR (24 bytes):
3B DE 96 FF 81 91 FE 1F C3 80 31 81 54 48 53 4D ;.........1.THSM
31 73 80 21 40 81 07 92                         1s.!@...

P:18087; T:0x139882926028928 18:03:23.538 [opensc-pkcs11] card.c:1234:match_atr_table: ATR     : 3b:de:96:ff:81:91:fe:1f:c3:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:92
P:18087; T:0x139882926028928 18:03:23.538 [opensc-pkcs11] card.c:1245: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:18087; T:0x139882926028928 18:03:23.538 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.538 [opensc-pkcs11] card-authentic.c:432:authentic_match_card: card not matched
P:18087; T:0x139882926028928 18:03:23.538 [opensc-pkcs11] card.c:341:sc_connect_card: trying driver 'iasecc'
P:18087; T:0x139882926028928 18:03:23.538 [opensc-pkcs11] card.c:1234:match_atr_table: ATR     : 3b:de:96:ff:81:91:fe:1f:c3:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:92
P:18087; T:0x139882926028928 18:03:23.538 [opensc-pkcs11] card.c:1245: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:18087; T:0x139882926028928 18:03:23.538 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.538 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3B:DD:00:00:81:31:FE:45:80:F9:A0:00:00:00:77:01:08:00:07:90:00:00
P:18087; T:0x139882926028928 18:03:23.538 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.538 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3B:7D:13:00:00:4D:44:57:2D:49:41:53:2D:43:41:52:44:32
P:18087; T:0x139882926028928 18:03:23.538 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.538 [opensc-pkcs11] card.c:1245: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:18087; T:0x139882926028928 18:03:23.538 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.538 [opensc-pkcs11] card.c:1245: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:18087; T:0x139882926028928 18:03:23.538 [opensc-pkcs11] card.c:1245: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:18087; T:0x139882926028928 18:03:23.538 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.538 [opensc-pkcs11] card.c:1245: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:18087; T:0x139882926028928 18:03:23.539 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.539 [opensc-pkcs11] card.c:1245: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:18087; T:0x139882926028928 18:03:23.539 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.539 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3B:AC:00:40:2A:00:12:25:00:64:80:00:03:10:00:90:00
P:18087; T:0x139882926028928 18:03:23.539 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.539 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 2B:8F:80:01:00:31:B8:64:04:B0:EC:C1:73:94:01:80:82:90:00:0E
P:18087; T:0x139882926028928 18:03:23.539 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.539 [opensc-pkcs11] card-iasecc.c:396:iasecc_match_card: card not matched
P:18087; T:0x139882926028928 18:03:23.539 [opensc-pkcs11] card.c:341:sc_connect_card: trying driver 'belpic'
P:18087; T:0x139882926028928 18:03:23.539 [opensc-pkcs11] card.c:1234:match_atr_table: ATR     : 3b:de:96:ff:81:91:fe:1f:c3:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:92
P:18087; T:0x139882926028928 18:03:23.539 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3B:7F:96:00:00:80:31:80:65:B0:85:04:01:20:12:0F:FF:82:90:00
P:18087; T:0x139882926028928 18:03:23.539 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.539 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3B:98:13:40:0A:A5:03:01:01:01:AD:13:11
P:18087; T:0x139882926028928 18:03:23.539 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.539 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3B:98:94:40:0A:A5:03:01:01:01:AD:13:10
P:18087; T:0x139882926028928 18:03:23.539 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.539 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3B:98:94:40:FF:A5:03:01:01:01:AD:13:10
P:18087; T:0x139882926028928 18:03:23.539 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.539 [opensc-pkcs11] card.c:341:sc_connect_card: trying driver 'entersafe'
P:18087; T:0x139882926028928 18:03:23.539 [opensc-pkcs11] card-entersafe.c:138:entersafe_match_card: called
P:18087; T:0x139882926028928 18:03:23.539 [opensc-pkcs11] card.c:1234:match_atr_table: ATR     : 3b:de:96:ff:81:91:fe:1f:c3:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:92
P:18087; T:0x139882926028928 18:03:23.539 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3b:0f:00:65:46:53:05:19:05:71:df:00:00:00:00:00:00
P:18087; T:0x139882926028928 18:03:23.539 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.539 [opensc-pkcs11] card.c:1245: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:18087; T:0x139882926028928 18:03:23.539 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.539 [opensc-pkcs11] card.c:1245: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:18087; T:0x139882926028928 18:03:23.539 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.539 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3b:7c:18:00:00:90:67:46:4a:20:28:8c:58:00:00:00:00
P:18087; T:0x139882926028928 18:03:23.539 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.539 [opensc-pkcs11] card.c:1245: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:18087; T:0x139882926028928 18:03:23.539 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.540 [opensc-pkcs11] card.c:1245: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:18087; T:0x139882926028928 18:03:23.540 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.540 [opensc-pkcs11] card.c:1245: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:18087; T:0x139882926028928 18:03:23.540 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.540 [opensc-pkcs11] card.c:1245: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:18087; T:0x139882926028928 18:03:23.540 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.540 [opensc-pkcs11] card.c:1245: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:18087; T:0x139882926028928 18:03:23.540 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.540 [opensc-pkcs11] card.c:1245: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:18087; T:0x139882926028928 18:03:23.540 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.540 [opensc-pkcs11] card.c:341:sc_connect_card: trying driver 'epass2003'
P:18087; T:0x139882926028928 18:03:23.540 [opensc-pkcs11] card-epass2003.c:1529:epass2003_match_card: called
P:18087; T:0x139882926028928 18:03:23.540 [opensc-pkcs11] card.c:1234:match_atr_table: ATR     : 3b:de:96:ff:81:91:fe:1f:c3:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:92
P:18087; T:0x139882926028928 18:03:23.540 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3B:9F:95:81:31:FE:9F:00:66:46:53:05:00:00:00:71:df:00:00:00:00:00:00
P:18087; T:0x139882926028928 18:03:23.540 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.540 [opensc-pkcs11] card.c:341:sc_connect_card: trying driver 'rutoken'
P:18087; T:0x139882926028928 18:03:23.540 [opensc-pkcs11] card-rutoken.c:103:rutoken_match_card: called
P:18087; T:0x139882926028928 18:03:23.540 [opensc-pkcs11] card.c:1234:match_atr_table: ATR     : 3b:de:96:ff:81:91:fe:1f:c3:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:92
P:18087; T:0x139882926028928 18:03:23.540 [opensc-pkcs11] card.c:1245: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:18087; T:0x139882926028928 18:03:23.540 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.540 [opensc-pkcs11] card.c:1245: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:18087; T:0x139882926028928 18:03:23.540 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.540 [opensc-pkcs11] card-rutoken.c:109:rutoken_match_card: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.540 [opensc-pkcs11] card.c:341:sc_connect_card: trying driver 'rutoken_ecp'
P:18087; T:0x139882926028928 18:03:23.540 [opensc-pkcs11] card.c:1234:match_atr_table: ATR     : 3b:de:96:ff:81:91:fe:1f:c3:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:92
P:18087; T:0x139882926028928 18:03:23.540 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3B:8B:01:52:75:74:6F:6B:65:6E:20:45:43:50:A0
P:18087; T:0x139882926028928 18:03:23.540 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.540 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3B:8B:01:52:75:74:6F:6B:65:6E:20:44:53:20:C1
P:18087; T:0x139882926028928 18:03:23.540 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.540 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3B:9C:96:00:52:75:74:6F:6B:65:6E:45:43:50:73:63
P:18087; T:0x139882926028928 18:03:23.540 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.540 [opensc-pkcs11] card.c:1245: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:18087; T:0x139882926028928 18:03:23.540 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.540 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3B:88:80:01:52:74:53:43:77:81:83:20:6A
P:18087; T:0x139882926028928 18:03:23.540 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.540 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3B:8B:01:52:75:74:6F:6B:65:6E:6C:69:74:65:C2
P:18087; T:0x139882926028928 18:03:23.541 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.541 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3B:9E:96:00:52:75:74:6F:6B:65:6E:4C:69:74:65:53:43:32
P:18087; T:0x139882926028928 18:03:23.541 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.541 [opensc-pkcs11] card-rtecp.c:81:rtecp_match_card: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.541 [opensc-pkcs11] card.c:341:sc_connect_card: trying driver 'myeid'
P:18087; T:0x139882926028928 18:03:23.541 [opensc-pkcs11] card.c:341:sc_connect_card: trying driver 'dnie'
P:18087; T:0x139882926028928 18:03:23.541 [opensc-pkcs11] card-dnie.c:738:dnie_match_card: called
P:18087; T:0x139882926028928 18:03:23.541 [opensc-pkcs11] card.c:1234:match_atr_table: ATR     : 3b:de:96:ff:81:91:fe:1f:c3:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:92
P:18087; T:0x139882926028928 18:03:23.541 [opensc-pkcs11] card.c:1245: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:18087; T:0x139882926028928 18:03:23.541 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.541 [opensc-pkcs11] card.c:1245: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:18087; T:0x139882926028928 18:03:23.541 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.541 [opensc-pkcs11] card-dnie.c:741:dnie_match_card: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.541 [opensc-pkcs11] card.c:341:sc_connect_card: trying driver 'MaskTech'
P:18087; T:0x139882926028928 18:03:23.541 [opensc-pkcs11] card.c:1234:match_atr_table: ATR     : 3b:de:96:ff:81:91:fe:1f:c3:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:92
P:18087; T:0x139882926028928 18:03:23.541 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3B:89:80:01:4D:54:43:4F:53:70:02:00:04:31
P:18087; T:0x139882926028928 18:03:23.541 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.541 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3B:88:80:01:00:00:00:00:77:81:80:00:6E
P:18087; T:0x139882926028928 18:03:23.541 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.541 [opensc-pkcs11] card.c:1245: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:18087; T:0x139882926028928 18:03:23.541 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.541 [opensc-pkcs11] card.c:341:sc_connect_card: trying driver 'esteid2018'
P:18087; T:0x139882926028928 18:03:23.541 [opensc-pkcs11] card.c:1234:match_atr_table: ATR     : 3b:de:96:ff:81:91:fe:1f:c3:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:92
P:18087; T:0x139882926028928 18:03:23.541 [opensc-pkcs11] card.c:1245: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:18087; T:0x139882926028928 18:03:23.541 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.541 [opensc-pkcs11] card.c:341:sc_connect_card: trying driver 'idprime'
P:18087; T:0x139882926028928 18:03:23.541 [opensc-pkcs11] card-idprime.c:362:idprime_match_card: called
P:18087; T:0x139882926028928 18:03:23.541 [opensc-pkcs11] card.c:1234:match_atr_table: ATR     : 3b:de:96:ff:81:91:fe:1f:c3:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:92
P:18087; T:0x139882926028928 18:03:23.541 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3b:7f:96:00:00:80:31:80:65:b0:84:41:3d:f6:12:0f:fe:82:90:00
P:18087; T:0x139882926028928 18:03:23.542 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.542 [opensc-pkcs11] card.c:341:sc_connect_card: trying driver 'coolkey'
P:18087; T:0x139882926028928 18:03:23.542 [opensc-pkcs11] card-coolkey.c:2266:coolkey_match_card: called
P:18087; T:0x139882926028928 18:03:23.542 [opensc-pkcs11] card-coolkey.c:917:coolkey_apdu_io: called
P:18087; T:0x139882926028928 18:03:23.542 [opensc-pkcs11] card-coolkey.c:922:coolkey_apdu_io: a4 04 00 7 : 0 0
P:18087; T:0x139882926028928 18:03:23.542 [opensc-pkcs11] card-coolkey.c:986:coolkey_apdu_io: calling sc_transmit_apdu flags=0 le=0, resplen=0, resp=0x7ffe79a39db0
P:18087; T:0x139882926028928 18:03:23.542 [opensc-pkcs11] apdu.c:550:sc_transmit_apdu: called
P:18087; T:0x139882926028928 18:03:23.542 [opensc-pkcs11] card.c:471:sc_lock: called
P:18087; T:0x139882926028928 18:03:23.542 [opensc-pkcs11] reader-pcsc.c:688:pcsc_lock: called
P:18087; T:0x139882926028928 18:03:23.542 [opensc-pkcs11] card-coolkey.c:2411:coolkey_card_reader_lock_obtained: called
P:18087; T:0x139882926028928 18:03:23.542 [opensc-pkcs11] card-coolkey.c:2417:coolkey_card_reader_lock_obtained: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.542 [opensc-pkcs11] card.c:520:sc_lock: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.542 [opensc-pkcs11] apdu.c:515:sc_transmit: called
P:18087; T:0x139882926028928 18:03:23.542 [opensc-pkcs11] apdu.c:363:sc_single_transmit: called
P:18087; T:0x139882926028928 18:03:23.542 [opensc-pkcs11] apdu.c:370:sc_single_transmit: CLA:0, INS:A4, P1:4, P2:0, data(7) 0x7ffe79a3aea0
P:18087; T:0x139882926028928 18:03:23.542 [opensc-pkcs11] reader-pcsc.c:325:pcsc_transmit: reader 'Identiv uTrust 3512 SAM slot Token [CCID Interface] (55512030608814) 00 00'
P:18087; T:0x139882926028928 18:03:23.542 [opensc-pkcs11] reader-pcsc.c:326:pcsc_transmit:
Outgoing APDU (12 bytes):
00 A4 04 00 07 62 76 01 FF 00 00 00 .....bv.....

P:18087; T:0x139882926028928 18:03:23.542 [opensc-pkcs11] reader-pcsc.c:244:pcsc_internal_transmit: called
P:18087; T:0x139882926028928 18:03:23.567 [opensc-pkcs11] reader-pcsc.c:335:pcsc_transmit:
Incoming APDU (2 bytes):
6A 82 j.

P:18087; T:0x139882926028928 18:03:23.567 [opensc-pkcs11] apdu.c:382:sc_single_transmit: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.567 [opensc-pkcs11] apdu.c:539:sc_transmit: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.567 [opensc-pkcs11] card.c:530:sc_unlock: called
P:18087; T:0x139882926028928 18:03:23.567 [opensc-pkcs11] reader-pcsc.c:740:pcsc_unlock: called
P:18087; T:0x139882926028928 18:03:23.567 [opensc-pkcs11] card-coolkey.c:993:coolkey_apdu_io: result r=0 apdu.resplen=0 sw1=6a sw2=82
P:18087; T:0x139882926028928 18:03:23.567 [opensc-pkcs11] card-coolkey.c:873:coolkey_check_sw: sw1 = 0x6a, sw2 = 0x82
P:18087; T:0x139882926028928 18:03:23.567 [opensc-pkcs11] iso7816.c:128:iso7816_check_sw: File or application not found
P:18087; T:0x139882926028928 18:03:23.567 [opensc-pkcs11] card-coolkey.c:1001:coolkey_apdu_io: Transmit failed
P:18087; T:0x139882926028928 18:03:23.567 [opensc-pkcs11] card-coolkey.c:1019:coolkey_apdu_io: returning with: -1201 (File not found)
P:18087; T:0x139882926028928 18:03:23.567 [opensc-pkcs11] card.c:341:sc_connect_card: trying driver 'muscle'
P:18087; T:0x139882926028928 18:03:23.567 [opensc-pkcs11] apdu.c:550:sc_transmit_apdu: called
P:18087; T:0x139882926028928 18:03:23.567 [opensc-pkcs11] card.c:471:sc_lock: called
P:18087; T:0x139882926028928 18:03:23.567 [opensc-pkcs11] reader-pcsc.c:688:pcsc_lock: called
P:18087; T:0x139882926028928 18:03:23.567 [opensc-pkcs11] card-muscle.c:845:muscle_card_reader_lock_obtained: called
P:18087; T:0x139882926028928 18:03:23.567 [opensc-pkcs11] card-muscle.c:853:muscle_card_reader_lock_obtained: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.567 [opensc-pkcs11] card.c:520:sc_lock: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.567 [opensc-pkcs11] apdu.c:515:sc_transmit: called
P:18087; T:0x139882926028928 18:03:23.567 [opensc-pkcs11] apdu.c:363:sc_single_transmit: called
P:18087; T:0x139882926028928 18:03:23.567 [opensc-pkcs11] apdu.c:370:sc_single_transmit: CLA:0, INS:A4, P1:4, P2:0, data(6) 0x7f3907ad6570
P:18087; T:0x139882926028928 18:03:23.567 [opensc-pkcs11] reader-pcsc.c:325:pcsc_transmit: reader 'Identiv uTrust 3512 SAM slot Token [CCID Interface] (55512030608814) 00 00'
P:18087; T:0x139882926028928 18:03:23.567 [opensc-pkcs11] reader-pcsc.c:326:pcsc_transmit:
Outgoing APDU (11 bytes):
00 A4 04 00 06 A0 00 00 00 01 01 ...........

P:18087; T:0x139882926028928 18:03:23.567 [opensc-pkcs11] reader-pcsc.c:244:pcsc_internal_transmit: called
P:18087; T:0x139882926028928 18:03:23.579 [opensc-pkcs11] reader-pcsc.c:335:pcsc_transmit:
Incoming APDU (2 bytes):
6A 82 j.

P:18087; T:0x139882926028928 18:03:23.579 [opensc-pkcs11] apdu.c:382:sc_single_transmit: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.579 [opensc-pkcs11] apdu.c:539:sc_transmit: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.579 [opensc-pkcs11] card.c:530:sc_unlock: called
P:18087; T:0x139882926028928 18:03:23.579 [opensc-pkcs11] reader-pcsc.c:740:pcsc_unlock: called
P:18087; T:0x139882926028928 18:03:23.580 [opensc-pkcs11] muscle.c:274:msc_select_applet: returning with: -1200 (Card command failed)
P:18087; T:0x139882926028928 18:03:23.580 [opensc-pkcs11] card.c:341:sc_connect_card: trying driver 'sc-hsm'
P:18087; T:0x139882926028928 18:03:23.580 [opensc-pkcs11] card.c:1234:match_atr_table: ATR     : 3b:de:96:ff:81:91:fe:1f:c3:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:92
P:18087; T:0x139882926028928 18:03:23.580 [opensc-pkcs11] card.c:1245: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:18087; T:0x139882926028928 18:03:23.580 [opensc-pkcs11] card.c:1245: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:18087; T:0x139882926028928 18:03:23.580 [opensc-pkcs11] card.c:1248:match_atr_table: ignored - wrong length
P:18087; T:0x139882926028928 18:03:23.580 [opensc-pkcs11] card.c:1245: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:18087; T:0x139882926028928 18:03:23.580 [opensc-pkcs11] card.c:1245:match_atr_table: ATR try : 3B:DE:96:FF:81:91:FE:1F:C3:80:31:81:54:48:53:4D:31:73:80:21:40:81:07:92
P:18087; T:0x139882926028928 18:03:23.580 [opensc-pkcs11] card.c:355:sc_connect_card: matched: SmartCard-HSM
P:18087; T:0x139882926028928 18:03:23.580 [opensc-pkcs11] card-sc-hsm.c:1760:sc_hsm_init: called
P:18087; T:0x139882926028928 18:03:23.580 [opensc-pkcs11] apdu.c:550:sc_transmit_apdu: called
P:18087; T:0x139882926028928 18:03:23.580 [opensc-pkcs11] card.c:471:sc_lock: called
P:18087; T:0x139882926028928 18:03:23.580 [opensc-pkcs11] reader-pcsc.c:688:pcsc_lock: called
P:18087; T:0x139882926028928 18:03:23.580 [opensc-pkcs11] card.c:520:sc_lock: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.580 [opensc-pkcs11] apdu.c:515:sc_transmit: called
P:18087; T:0x139882926028928 18:03:23.580 [opensc-pkcs11] apdu.c:363:sc_single_transmit: called
P:18087; T:0x139882926028928 18:03:23.580 [opensc-pkcs11] apdu.c:370:sc_single_transmit: CLA:0, INS:A4, P1:4, P2:0, data(11) 0x7ffe79a3ab90
P:18087; T:0x139882926028928 18:03:23.580 [opensc-pkcs11] reader-pcsc.c:325:pcsc_transmit: reader 'Identiv uTrust 3512 SAM slot Token [CCID Interface] (55512030608814) 00 00'
P:18087; T:0x139882926028928 18:03:23.580 [opensc-pkcs11] reader-pcsc.c:326:pcsc_transmit:
Outgoing APDU (17 bytes):
00 A4 04 00 0B E8 2B 06 01 04 01 81 C3 1F 02 01 ......+.........
00                                              .

P:18087; T:0x139882926028928 18:03:23.580 [opensc-pkcs11] reader-pcsc.c:244:pcsc_internal_transmit: called
P:18087; T:0x139882926028928 18:03:23.599 [opensc-pkcs11] reader-pcsc.c:335:pcsc_transmit:
Incoming APDU (14 bytes):
6F 0A 82 01 78 85 05 00 01 05 03 05 90 00 o...x.........

P:18087; T:0x139882926028928 18:03:23.599 [opensc-pkcs11] apdu.c:382:sc_single_transmit: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.599 [opensc-pkcs11] apdu.c:539:sc_transmit: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.599 [opensc-pkcs11] card.c:530:sc_unlock: called
P:18087; T:0x139882926028928 18:03:23.599 [opensc-pkcs11] reader-pcsc.c:740:pcsc_unlock: called
P:18087; T:0x139882926028928 18:03:23.599 [opensc-pkcs11] iso7816.c:366:iso7816_process_fci:   shareable: yes
P:18087; T:0x139882926028928 18:03:23.599 [opensc-pkcs11] iso7816.c:386:iso7816_process_fci:   type: DF
P:18087; T:0x139882926028928 18:03:23.599 [opensc-pkcs11] iso7816.c:387:iso7816_process_fci:   EF structure: 0
P:18087; T:0x139882926028928 18:03:23.599 [opensc-pkcs11] iso7816.c:388:iso7816_process_fci:   tag 0x82: 0x78
P:18087; T:0x139882926028928 18:03:23.599 [opensc-pkcs11] card.c:385:sc_connect_card: card info name:'SmartCard-HSM version 3.5', type:26000, flags:0x0, max_send/recv_size:255/65536
P:18087; T:0x139882926028928 18:03:23.599 [opensc-pkcs11] card.c:1612:sc_card_sm_check: called
P:18087; T:0x139882926028928 18:03:23.599 [opensc-pkcs11] card.c:1617:sc_card_sm_check: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.599 [opensc-pkcs11] card.c:397:sc_connect_card: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.599 [opensc-pkcs11] slot.c:285:card_detect: Identiv uTrust 3512 SAM slot Token [CCID Interface] (55512030608814) 00 00: Connected SC card 0x16b9500
P:18087; T:0x139882926028928 18:03:23.599 [opensc-pkcs11] dir.c:171:sc_enum_apps: called
P:18087; T:0x139882926028928 18:03:23.599 [opensc-pkcs11] card.c:851:sc_select_file: called; type=2, path=3f002f00
P:18087; T:0x139882926028928 18:03:23.599 [opensc-pkcs11] apdu.c:550:sc_transmit_apdu: called
P:18087; T:0x139882926028928 18:03:23.599 [opensc-pkcs11] card.c:471:sc_lock: called
P:18087; T:0x139882926028928 18:03:23.599 [opensc-pkcs11] reader-pcsc.c:688:pcsc_lock: called
P:18087; T:0x139882926028928 18:03:23.599 [opensc-pkcs11] card.c:520:sc_lock: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.600 [opensc-pkcs11] apdu.c:515:sc_transmit: called
P:18087; T:0x139882926028928 18:03:23.600 [opensc-pkcs11] apdu.c:363:sc_single_transmit: called
P:18087; T:0x139882926028928 18:03:23.600 [opensc-pkcs11] apdu.c:370:sc_single_transmit: CLA:0, INS:A4, P1:8, P2:0, data(2) 0x7ffe79a3af30
P:18087; T:0x139882926028928 18:03:23.600 [opensc-pkcs11] reader-pcsc.c:325:pcsc_transmit: reader 'Identiv uTrust 3512 SAM slot Token [CCID Interface] (55512030608814) 00 00'
P:18087; T:0x139882926028928 18:03:23.600 [opensc-pkcs11] reader-pcsc.c:326:pcsc_transmit:
Outgoing APDU (8 bytes):
00 A4 08 00 02 2F 00 00 ...../..

P:18087; T:0x139882926028928 18:03:23.600 [opensc-pkcs11] reader-pcsc.c:244:pcsc_internal_transmit: called
P:18087; T:0x139882926028928 18:03:23.610 [opensc-pkcs11] reader-pcsc.c:335:pcsc_transmit:
Incoming APDU (2 bytes):
6A 86 j.

P:18087; T:0x139882926028928 18:03:23.610 [opensc-pkcs11] apdu.c:382:sc_single_transmit: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.610 [opensc-pkcs11] apdu.c:539:sc_transmit: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.610 [opensc-pkcs11] card.c:530:sc_unlock: called
P:18087; T:0x139882926028928 18:03:23.610 [opensc-pkcs11] reader-pcsc.c:740:pcsc_unlock: called
P:18087; T:0x139882926028928 18:03:23.610 [opensc-pkcs11] iso7816.c:128:iso7816_check_sw: Incorrect parameters P1-P2
P:18087; T:0x139882926028928 18:03:23.610 [opensc-pkcs11] iso7816.c:627:iso7816_select_file: returning with: -1205 (Incorrect parameters in APDU)
P:18087; T:0x139882926028928 18:03:23.610 [opensc-pkcs11] card.c:873:sc_select_file: 'SELECT' error: -1205 (Incorrect parameters in APDU)
P:18087; T:0x139882926028928 18:03:23.610 [opensc-pkcs11] dir.c:180:sc_enum_apps: Cannot select EF.DIR file: -1205 (Incorrect parameters in APDU)
P:18087; T:0x139882926028928 18:03:23.610 [opensc-pkcs11] slot.c:292:card_detect: Identiv uTrust 3512 SAM slot Token [CCID Interface] (55512030608814) 00 00: Detecting Framework. 0 on-card applications
P:18087; T:0x139882926028928 18:03:23.610 [opensc-pkcs11] slot.c:293:card_detect: Identiv uTrust 3512 SAM slot Token [CCID Interface] (55512030608814) 00 00: generic application <none>
P:18087; T:0x139882926028928 18:03:23.610 [opensc-pkcs11] slot.c:307:card_detect: Identiv uTrust 3512 SAM slot Token [CCID Interface] (55512030608814) 00 00: Detected framework 0. Creating tokens.
P:18087; T:0x139882926028928 18:03:23.610 [opensc-pkcs11] slot.c:322:card_detect: Identiv uTrust 3512 SAM slot Token [CCID Interface] (55512030608814) 00 00: Try to bind 'generic' token.
P:18087; T:0x139882926028928 18:03:23.610 [opensc-pkcs11] framework-pkcs15.c:322:pkcs15_bind: Bind PKCS#15 '<anonymous>' application
P:18087; T:0x139882926028928 18:03:23.611 [opensc-pkcs11] pkcs15.c:1231:sc_pkcs15_bind: called
P:18087; T:0x139882926028928 18:03:23.611 [opensc-pkcs11] pkcs15.c:1232:sc_pkcs15_bind: application(aid:'empty')
P:18087; T:0x139882926028928 18:03:23.611 [opensc-pkcs11] pkcs15.c:1279:sc_pkcs15_bind: PKCS#15 options: use_file_cache=0 use_pin_cache=1 pin_cache_counter=10 pin_cache_ignore_user_consent=0 private_certificate=0
P:18087; T:0x139882926028928 18:03:23.611 [opensc-pkcs11] card.c:471:sc_lock: called
P:18087; T:0x139882926028928 18:03:23.611 [opensc-pkcs11] reader-pcsc.c:688:pcsc_lock: called
P:18087; T:0x139882926028928 18:03:23.611 [opensc-pkcs11] card.c:520:sc_lock: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.611 [opensc-pkcs11] pkcs15.c:1290:sc_pkcs15_bind: PKCS#15 emulation enabled
P:18087; T:0x139882926028928 18:03:23.611 [opensc-pkcs11] pkcs15-syn.c:126:sc_pkcs15_bind_synthetic: called
P:18087; T:0x139882926028928 18:03:23.611 [opensc-pkcs11] pkcs15-syn.c:175:sc_pkcs15_bind_synthetic: no emulator list in config file, trying all builtin emulators
P:18087; T:0x139882926028928 18:03:23.611 [opensc-pkcs11] pkcs15-syn.c:177:sc_pkcs15_bind_synthetic: trying openpgp
P:18087; T:0x139882926028928 18:03:23.611 [opensc-pkcs11] pkcs15-syn.c:177:sc_pkcs15_bind_synthetic: trying starcert
P:18087; T:0x139882926028928 18:03:23.611 [opensc-pkcs11] pkcs15-syn.c:177:sc_pkcs15_bind_synthetic: trying tcos
P:18087; T:0x139882926028928 18:03:23.611 [opensc-pkcs11] pkcs15-syn.c:177:sc_pkcs15_bind_synthetic: trying esteid
P:18087; T:0x139882926028928 18:03:23.611 [opensc-pkcs11] pkcs15-syn.c:177:sc_pkcs15_bind_synthetic: trying itacns
P:18087; T:0x139882926028928 18:03:23.611 [opensc-pkcs11] pkcs15-itacns.c:880:sc_pkcs15emu_itacns_init_ex: called
P:18087; T:0x139882926028928 18:03:23.611 [opensc-pkcs11] pkcs15-syn.c:177:sc_pkcs15_bind_synthetic: trying PIV-II
P:18087; T:0x139882926028928 18:03:23.611 [opensc-pkcs11] pkcs15-piv.c:1235:sc_pkcs15emu_piv_init_ex: called
P:18087; T:0x139882926028928 18:03:23.611 [opensc-pkcs11] pkcs15-piv.c:238:piv_detect_card: called
P:18087; T:0x139882926028928 18:03:23.611 [opensc-pkcs11] pkcs15-syn.c:177:sc_pkcs15_bind_synthetic: trying cac
P:18087; T:0x139882926028928 18:03:23.611 [opensc-pkcs11] pkcs15-cac.c:376:sc_pkcs15emu_cac_init_ex: called
P:18087; T:0x139882926028928 18:03:23.611 [opensc-pkcs11] pkcs15-cac.c:68:cac_detect_card: called
P:18087; T:0x139882926028928 18:03:23.611 [opensc-pkcs11] pkcs15-syn.c:177:sc_pkcs15_bind_synthetic: trying idprime
P:18087; T:0x139882926028928 18:03:23.611 [opensc-pkcs11] pkcs15-idprime.c:298:sc_pkcs15emu_idprime_init_ex: called
P:18087; T:0x139882926028928 18:03:23.611 [opensc-pkcs11] pkcs15-idprime.c:43:idprime_detect_card: called
P:18087; T:0x139882926028928 18:03:23.611 [opensc-pkcs11] pkcs15-syn.c:177:sc_pkcs15_bind_synthetic: trying gemsafeV1
P:18087; T:0x139882926028928 18:03:23.611 [opensc-pkcs11] pkcs15-syn.c:177:sc_pkcs15_bind_synthetic: trying entersafe
P:18087; T:0x139882926028928 18:03:23.611 [opensc-pkcs11] pkcs15-esinit.c:80:sc_pkcs15emu_entersafe_init_ex: called
P:18087; T:0x139882926028928 18:03:23.611 [opensc-pkcs11] pkcs15-esinit.c:36:entersafe_detect_card: called
P:18087; T:0x139882926028928 18:03:23.611 [opensc-pkcs11] pkcs15-syn.c:177:sc_pkcs15_bind_synthetic: trying pteid
P:18087; T:0x139882926028928 18:03:23.611 [opensc-pkcs11] pkcs15-pteid.c:345:sc_pkcs15emu_pteid_init_ex: called
P:18087; T:0x139882926028928 18:03:23.611 [opensc-pkcs11] pkcs15-pteid.c:350:sc_pkcs15emu_pteid_init_ex: returning with: -1413 (Unsupported card)
P:18087; T:0x139882926028928 18:03:23.611 [opensc-pkcs11] pkcs15-syn.c:177:sc_pkcs15_bind_synthetic: trying oberthur
P:18087; T:0x139882926028928 18:03:23.611 [opensc-pkcs11] pkcs15-oberthur.c:1164:sc_pkcs15emu_oberthur_init_ex: called
P:18087; T:0x139882926028928 18:03:23.611 [opensc-pkcs11] pkcs15-oberthur.c:1152:oberthur_detect_card: called
P:18087; T:0x139882926028928 18:03:23.612 [opensc-pkcs11] pkcs15-oberthur.c:1154:oberthur_detect_card: returning with: -1413 (Unsupported card)
P:18087; T:0x139882926028928 18:03:23.612 [opensc-pkcs11] pkcs15-oberthur.c:1169:sc_pkcs15emu_oberthur_init_ex: returning with: -1413 (Unsupported card)
P:18087; T:0x139882926028928 18:03:23.612 [opensc-pkcs11] pkcs15-syn.c:177:sc_pkcs15_bind_synthetic: trying sc-hsm
P:18087; T:0x139882926028928 18:03:23.612 [opensc-pkcs11] pkcs15-sc-hsm.c:1283:sc_pkcs15emu_sc_hsm_init: called
P:18087; T:0x139882926028928 18:03:23.612 [opensc-pkcs11] card.c:851:sc_select_file: called; type=1, path=e82b0601040181c31f0201::
P:18087; T:0x139882926028928 18:03:23.612 [opensc-pkcs11] card.c:886:sc_select_file: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.612 [opensc-pkcs11] card.c:851:sc_select_file: called; type=0, path=2f02
P:18087; T:0x139882926028928 18:03:23.612 [opensc-pkcs11] apdu.c:550:sc_transmit_apdu: called
P:18087; T:0x139882926028928 18:03:23.612 [opensc-pkcs11] card.c:471:sc_lock: called
P:18087; T:0x139882926028928 18:03:23.612 [opensc-pkcs11] card.c:520:sc_lock: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.612 [opensc-pkcs11] apdu.c:515:sc_transmit: called
P:18087; T:0x139882926028928 18:03:23.612 [opensc-pkcs11] apdu.c:363:sc_single_transmit: called
P:18087; T:0x139882926028928 18:03:23.612 [opensc-pkcs11] apdu.c:370:sc_single_transmit: CLA:0, INS:A4, P1:0, P2:0, data(2) 0x7ffe79a394e0
P:18087; T:0x139882926028928 18:03:23.612 [opensc-pkcs11] reader-pcsc.c:325:pcsc_transmit: reader 'Identiv uTrust 3512 SAM slot Token [CCID Interface] (55512030608814) 00 00'
P:18087; T:0x139882926028928 18:03:23.612 [opensc-pkcs11] reader-pcsc.c:326:pcsc_transmit:
Outgoing APDU (8 bytes):
00 A4 00 00 02 2F 02 00 ...../..

P:18087; T:0x139882926028928 18:03:23.612 [opensc-pkcs11] reader-pcsc.c:244:pcsc_internal_transmit: called
P:18087; T:0x139882926028928 18:03:23.625 [opensc-pkcs11] reader-pcsc.c:335:pcsc_transmit:
Incoming APDU (15 bytes):
6F 0B 80 02 01 CE 82 01 01 83 02 2F 02 90 00 o........../...

P:18087; T:0x139882926028928 18:03:23.625 [opensc-pkcs11] apdu.c:382:sc_single_transmit: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.625 [opensc-pkcs11] apdu.c:539:sc_transmit: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.625 [opensc-pkcs11] card.c:530:sc_unlock: called
P:18087; T:0x139882926028928 18:03:23.625 [opensc-pkcs11] iso7816.c:356:iso7816_process_fci:   bytes in file: 462
P:18087; T:0x139882926028928 18:03:23.625 [opensc-pkcs11] iso7816.c:366:iso7816_process_fci:   shareable: no
P:18087; T:0x139882926028928 18:03:23.625 [opensc-pkcs11] iso7816.c:386:iso7816_process_fci:   type: working EF
P:18087; T:0x139882926028928 18:03:23.625 [opensc-pkcs11] iso7816.c:387:iso7816_process_fci:   EF structure: 1
P:18087; T:0x139882926028928 18:03:23.625 [opensc-pkcs11] iso7816.c:388:iso7816_process_fci:   tag 0x82: 0x01
P:18087; T:0x139882926028928 18:03:23.625 [opensc-pkcs11] iso7816.c:419:iso7816_process_fci:   file identifier: 0x2F02
P:18087; T:0x139882926028928 18:03:23.625 [opensc-pkcs11] card.c:886:sc_select_file: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.625 [opensc-pkcs11] card.c:637:sc_read_binary: called; 1024 bytes at index 0
P:18087; T:0x139882926028928 18:03:23.625 [opensc-pkcs11] card.c:471:sc_lock: called
P:18087; T:0x139882926028928 18:03:23.625 [opensc-pkcs11] card.c:520:sc_lock: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.625 [opensc-pkcs11] apdu.c:550:sc_transmit_apdu: called
P:18087; T:0x139882926028928 18:03:23.625 [opensc-pkcs11] card.c:471:sc_lock: called
P:18087; T:0x139882926028928 18:03:23.625 [opensc-pkcs11] card.c:520:sc_lock: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.626 [opensc-pkcs11] apdu.c:515:sc_transmit: called
P:18087; T:0x139882926028928 18:03:23.626 [opensc-pkcs11] apdu.c:363:sc_single_transmit: called
P:18087; T:0x139882926028928 18:03:23.626 [opensc-pkcs11] apdu.c:370:sc_single_transmit: CLA:0, INS:B1, P1:0, P2:0, data(4) 0x7ffe79a39830
P:18087; T:0x139882926028928 18:03:23.626 [opensc-pkcs11] reader-pcsc.c:325:pcsc_transmit: reader 'Identiv uTrust 3512 SAM slot Token [CCID Interface] (55512030608814) 00 00'
P:18087; T:0x139882926028928 18:03:23.626 [opensc-pkcs11] reader-pcsc.c:326:pcsc_transmit:
Outgoing APDU (10 bytes):
00 B1 00 00 04 54 02 00 00 00 .....T....

P:18087; T:0x139882926028928 18:03:23.626 [opensc-pkcs11] reader-pcsc.c:244:pcsc_internal_transmit: called
P:18087; T:0x139882926028928 18:03:23.653 [opensc-pkcs11] reader-pcsc.c:335:pcsc_transmit:
Incoming APDU (258 bytes):
7F 21 81 E4 7F 4E 81 9D 5F 29 01 00 42 0D 44 45 .!...N.._)..B.DE
44 49 43 43 30 39 30 30 30 30 31 7F 49 4F 06 0A DICC0900001.IO..
04 00 7F 00 07 02 02 02 02 03 86 41 04 37 DD 49 ...........A.7.I
1B AB 37 AC 48 55 85 8F C2 6A 78 BD EE 13 36 1E ..7.HU...jx...6.
A0 18 FD F8 72 EC DD 41 9F 41 65 E8 00 2E A9 2B ....r..A.Ae....+
43 36 C7 C5 3D 82 F6 0A 7F 05 BF 21 C4 B2 85 C7 C6..=......!....
6F 92 96 4E 32 29 9D DA C4 84 E9 18 BF 5F 20 10 o..N2)......._ .
44 45 43 43 30 39 30 30 31 35 34 30 30 30 30 30 DECC090015400000
7F 4C 10 06 0B 2B 06 01 04 01 81 C3 1F 03 01 01 .L...+..........
53 01 00 5F 25 06 02 03 01 01 01 05 5F 24 06 02 S.._%......._$..
07 00 06 02 06 5F 37 40 23 0A 55 27 9B CF 28 C6 ....._7@#.U'..(.
9C 9D DE 4B CB DA 99 49 57 32 35 A9 51 42 10 28 ...K...IW25.QB.(
21 BC C9 22 EE BB 91 11 8F C8 54 E7 E0 8F F2 F6 !.."......T.....
87 51 E3 DB 85 DC 2B D4 1D AC 6B 0D A7 56 17 02 .Q....+...k..V..
C2 5F 92 CB 0E D5 58 79 7F 21 81 E2 7F 4E 81 9B ._....Xy.!...N..
5F 29 01 00 42 0E 44 45 53 52 43 41 43 43 31 30 _)..B.DESRCACC10
90 00                                           ..

P:18087; T:0x139882926028928 18:03:23.653 [opensc-pkcs11] apdu.c:382:sc_single_transmit: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.653 [opensc-pkcs11] apdu.c:539:sc_transmit: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.653 [opensc-pkcs11] card.c:530:sc_unlock: called
P:18087; T:0x139882926028928 18:03:23.653 [opensc-pkcs11] card-sc-hsm.c:826:sc_hsm_read_binary: returning with: 256
P:18087; T:0x139882926028928 18:03:23.653 [opensc-pkcs11] apdu.c:550:sc_transmit_apdu: called
P:18087; T:0x139882926028928 18:03:23.653 [opensc-pkcs11] card.c:471:sc_lock: called
P:18087; T:0x139882926028928 18:03:23.653 [opensc-pkcs11] card.c:520:sc_lock: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.653 [opensc-pkcs11] apdu.c:515:sc_transmit: called
P:18087; T:0x139882926028928 18:03:23.653 [opensc-pkcs11] apdu.c:363:sc_single_transmit: called
P:18087; T:0x139882926028928 18:03:23.653 [opensc-pkcs11] apdu.c:370:sc_single_transmit: CLA:0, INS:B1, P1:0, P2:0, data(4) 0x7ffe79a39830
P:18087; T:0x139882926028928 18:03:23.654 [opensc-pkcs11] reader-pcsc.c:325:pcsc_transmit: reader 'Identiv uTrust 3512 SAM slot Token [CCID Interface] (55512030608814) 00 00'
P:18087; T:0x139882926028928 18:03:23.654 [opensc-pkcs11] reader-pcsc.c:326:pcsc_transmit:
Outgoing APDU (10 bytes):
00 B1 00 00 04 54 02 01 00 00 .....T....

P:18087; T:0x139882926028928 18:03:23.654 [opensc-pkcs11] reader-pcsc.c:244:pcsc_internal_transmit: called
P:18087; T:0x139882926028928 18:03:23.677 [opensc-pkcs11] reader-pcsc.c:335:pcsc_transmit:
Incoming APDU (208 bytes):
30 30 30 31 7F 49 4F 06 0A 04 00 7F 00 07 02 02 0001.IO.........
02 02 03 86 41 04 71 1A F9 ED 12 D1 E8 1A 16 DC ....A.q.........
C9 A9 89 18 E2 47 DE 92 E3 2C BA E5 F0 E2 F0 75 .....G...,.....u
39 D3 74 97 43 EE 32 D0 EB C3 8C 2E 39 52 CE A5 9.t.C.2.....9R..
27 93 81 EA D9 30 0F 7D C7 A9 07 FB 96 72 26 0A '....0.}.....r&.
FF 73 FC D3 B3 82 5F 20 0D 44 45 44 49 43 43 30 .s...._ .DEDICC0
39 30 30 30 30 31 7F 4C 10 06 0B 2B 06 01 04 01 900001.L...+....
81 C3 1F 03 01 01 53 01 80 5F 25 06 01 09 00 06 ......S.._%.....
02 07 5F 24 06 02 07 00 06 02 06 5F 37 40 9E C5 .._$......._7@..
A0 2B 6E 36 FF 59 FB CD EC C5 6C 0E CE CD 8B AD .+n6.Y....l.....
E4 BD 02 13 CB DA 41 5E 56 1F D3 FB 07 08 32 35 ......A^V.....25
09 9D 1D D8 4C B7 1D 34 F4 96 C6 25 E0 EA C8 21 ....L..4...%...!
DC 75 62 07 40 7B E7 73 D7 03 E0 57 E3 A4 90 00 .ub.@{.s...W....

P:18087; T:0x139882926028928 18:03:23.677 [opensc-pkcs11] apdu.c:382:sc_single_transmit: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.677 [opensc-pkcs11] apdu.c:539:sc_transmit: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.677 [opensc-pkcs11] card.c:530:sc_unlock: called
P:18087; T:0x139882926028928 18:03:23.677 [opensc-pkcs11] card-sc-hsm.c:826:sc_hsm_read_binary: returning with: 206
P:18087; T:0x139882926028928 18:03:23.677 [opensc-pkcs11] apdu.c:550:sc_transmit_apdu: called
P:18087; T:0x139882926028928 18:03:23.677 [opensc-pkcs11] card.c:471:sc_lock: called
P:18087; T:0x139882926028928 18:03:23.677 [opensc-pkcs11] card.c:520:sc_lock: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.677 [opensc-pkcs11] apdu.c:515:sc_transmit: called
P:18087; T:0x139882926028928 18:03:23.677 [opensc-pkcs11] apdu.c:363:sc_single_transmit: called
P:18087; T:0x139882926028928 18:03:23.677 [opensc-pkcs11] apdu.c:370:sc_single_transmit: CLA:0, INS:B1, P1:0, P2:0, data(4) 0x7ffe79a39830
P:18087; T:0x139882926028928 18:03:23.677 [opensc-pkcs11] reader-pcsc.c:325:pcsc_transmit: reader 'Identiv uTrust 3512 SAM slot Token [CCID Interface] (55512030608814) 00 00'
P:18087; T:0x139882926028928 18:03:23.677 [opensc-pkcs11] reader-pcsc.c:326:pcsc_transmit:
Outgoing APDU (10 bytes):
00 B1 00 00 04 54 02 01 CE 00 .....T....

P:18087; T:0x139882926028928 18:03:23.677 [opensc-pkcs11] reader-pcsc.c:244:pcsc_internal_transmit: called
P:18087; T:0x139882926028928 18:03:23.689 [opensc-pkcs11] reader-pcsc.c:335:pcsc_transmit:
Incoming APDU (2 bytes):
90 00 ..

P:18087; T:0x139882926028928 18:03:23.689 [opensc-pkcs11] apdu.c:382:sc_single_transmit: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.689 [opensc-pkcs11] apdu.c:539:sc_transmit: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.689 [opensc-pkcs11] card.c:530:sc_unlock: called
P:18087; T:0x139882926028928 18:03:23.689 [opensc-pkcs11] card-sc-hsm.c:826:sc_hsm_read_binary: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.689 [opensc-pkcs11] card.c:530:sc_unlock: called
P:18087; T:0x139882926028928 18:03:23.689 [opensc-pkcs11] card.c:679:sc_read_binary: returning with: 462
P:18087; T:0x139882926028928 18:03:23.689 [opensc-pkcs11] asn1.c:1725:asn1_decode: called, left=462, depth 0
P:18087; T:0x139882926028928 18:03:23.689 [opensc-pkcs11] asn1.c:1749:asn1_decode: Looking for 'certificate', tag 0x60001f21
P:18087; T:0x139882926028928 18:03:23.689 [opensc-pkcs11] asn1.c:1502:asn1_decode_entry: decoding 'certificate', raw data:7F4E819D5F290100420D444544494343...
P:18087; T:0x139882926028928 18:03:23.689 [opensc-pkcs11] asn1.c:1725:asn1_decode:  called, left=228, depth 1
P:18087; T:0x139882926028928 18:03:23.689 [opensc-pkcs11] asn1.c:1749:asn1_decode: Looking for 'certificateBody', tag 0x60001f4e
P:18087; T:0x139882926028928 18:03:23.689 [opensc-pkcs11] asn1.c:1502:asn1_decode_entry:  decoding 'certificateBody', raw data:5F290100420D44454449434330393030...
P:18087; T:0x139882926028928 18:03:23.689 [opensc-pkcs11] asn1.c:1725:asn1_decode:   called, left=157, depth 2
P:18087; T:0x139882926028928 18:03:23.689 [opensc-pkcs11] asn1.c:1749:asn1_decode: Looking for 'certificateProfileIdentifier', tag 0x40001f29
P:18087; T:0x139882926028928 18:03:23.689 [opensc-pkcs11] asn1.c:1502:asn1_decode_entry:   decoding 'certificateProfileIdentifier', raw data:00
P:18087; T:0x139882926028928 18:03:23.689 [opensc-pkcs11] asn1.c:1528:asn1_decode_entry:   decoding 'certificateProfileIdentifier' returned 0
P:18087; T:0x139882926028928 18:03:23.689 [opensc-pkcs11] asn1.c:1749:asn1_decode: Looking for 'certificationAuthorityReference', tag 0x40000002, OPTIONAL
P:18087; T:0x139882926028928 18:03:23.689 [opensc-pkcs11] asn1.c:1502:asn1_decode_entry:   decoding 'certificationAuthorityReference', raw data:44454449434330393030303031
P:18087; T:0x139882926028928 18:03:23.689 [opensc-pkcs11] asn1.c:1749:asn1_decode: Looking for 'publicKey', tag 0x60001f49
P:18087; T:0x139882926028928 18:03:23.689 [opensc-pkcs11] asn1.c:1502:asn1_decode_entry:   decoding 'publicKey', raw data:060A04007F0007020202020386410437...
P:18087; T:0x139882926028928 18:03:23.689 [opensc-pkcs11] asn1.c:1725:asn1_decode:    called, left=79, depth 3
P:18087; T:0x139882926028928 18:03:23.689 [opensc-pkcs11] asn1.c:1749:asn1_decode: Looking for 'publicKeyOID', tag 0x6
P:18087; T:0x139882926028928 18:03:23.689 [opensc-pkcs11] asn1.c:1502:asn1_decode_entry:    decoding 'publicKeyOID', raw data:04007F00070202020203
P:18087; T:0x139882926028928 18:03:23.689 [opensc-pkcs11] asn1.c:1749:asn1_decode: Looking for 'primeOrModulus', tag 0x80000001, OPTIONAL
P:18087; T:0x139882926028928 18:03:23.690 [opensc-pkcs11] asn1.c:1763:asn1_decode: 'primeOrModulus' not present
P:18087; T:0x139882926028928 18:03:23.690 [opensc-pkcs11] asn1.c:1749:asn1_decode: Looking for 'coefficientAorExponent', tag 0x80000002, OPTIONAL
P:18087; T:0x139882926028928 18:03:23.690 [opensc-pkcs11] asn1.c:1763:asn1_decode: 'coefficientAorExponent' not present
P:18087; T:0x139882926028928 18:03:23.690 [opensc-pkcs11] asn1.c:1749:asn1_decode: Looking for 'coefficientB', tag 0x80000003, OPTIONAL
P:18087; T:0x139882926028928 18:03:23.690 [opensc-pkcs11] asn1.c:1763:asn1_decode: 'coefficientB' not present
P:18087; T:0x139882926028928 18:03:23.690 [opensc-pkcs11] asn1.c:1749:asn1_decode: Looking for 'basePointG', tag 0x80000004, OPTIONAL
P:18087; T:0x139882926028928 18:03:23.690 [opensc-pkcs11] asn1.c:1763:asn1_decode: 'basePointG' not present
P:18087; T:0x139882926028928 18:03:23.690 [opensc-pkcs11] asn1.c:1749:asn1_decode: Looking for 'order', tag 0x80000005, OPTIONAL
P:18087; T:0x139882926028928 18:03:23.690 [opensc-pkcs11] asn1.c:1763:asn1_decode: 'order' not present
P:18087; T:0x139882926028928 18:03:23.690 [opensc-pkcs11] asn1.c:1749:asn1_decode: Looking for 'publicPoint', tag 0x80000006, OPTIONAL
P:18087; T:0x139882926028928 18:03:23.690 [opensc-pkcs11] asn1.c:1502:asn1_decode_entry:    decoding 'publicPoint', raw data:0437DD491BAB37AC4855858FC26A78BD...
P:18087; T:0x139882926028928 18:03:23.690 [opensc-pkcs11] asn1.c:1749:asn1_decode: Looking for 'cofactor', tag 0x80000007, OPTIONAL
P:18087; T:0x139882926028928 18:03:23.690 [opensc-pkcs11] asn1.c:1763:asn1_decode: 'cofactor' not present
P:18087; T:0x139882926028928 18:03:23.690 [opensc-pkcs11] asn1.c:1749:asn1_decode: Looking for 'modulusSize', tag 0x2, OPTIONAL
P:18087; T:0x139882926028928 18:03:23.690 [opensc-pkcs11] asn1.c:1763:asn1_decode: 'modulusSize' not present
P:18087; T:0x139882926028928 18:03:23.690 [opensc-pkcs11] asn1.c:1799:asn1_decode: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.690 [opensc-pkcs11] asn1.c:1749:asn1_decode: Looking for 'certificateHolderReference', tag 0x40001f20
P:18087; T:0x139882926028928 18:03:23.690 [opensc-pkcs11] asn1.c:1502:asn1_decode_entry:   decoding 'certificateHolderReference', raw data:44454343303930303135343030303030
P:18087; T:0x139882926028928 18:03:23.690 [opensc-pkcs11] asn1.c:1799:asn1_decode: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.690 [opensc-pkcs11] asn1.c:1749:asn1_decode: Looking for 'signature', tag 0x40001f37
P:18087; T:0x139882926028928 18:03:23.690 [opensc-pkcs11] asn1.c:1502:asn1_decode_entry:  decoding 'signature', raw data:230A55279BCF28C69C9DDE4BCBDA9949...
P:18087; T:0x139882926028928 18:03:23.690 [opensc-pkcs11] asn1.c:1799:asn1_decode: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.690 [opensc-pkcs11] asn1.c:1799:asn1_decode: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.690 [opensc-pkcs11] pkcs15-sc-hsm.c:431:sc_pkcs15emu_sc_hsm_decode_cvc: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.690 [opensc-pkcs11] pkcs15-sc-hsm.c:1246:sc_pkcs15emu_sc_hsm_read_tokeninfo: called
P:18087; T:0x139882926028928 18:03:23.690 [opensc-pkcs11] card.c:851:sc_select_file: called; type=0, path=2f03
P:18087; T:0x139882926028928 18:03:23.690 [opensc-pkcs11] apdu.c:550:sc_transmit_apdu: called
P:18087; T:0x139882926028928 18:03:23.690 [opensc-pkcs11] card.c:471:sc_lock: called
P:18087; T:0x139882926028928 18:03:23.690 [opensc-pkcs11] card.c:520:sc_lock: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.690 [opensc-pkcs11] apdu.c:515:sc_transmit: called
P:18087; T:0x139882926028928 18:03:23.690 [opensc-pkcs11] apdu.c:363:sc_single_transmit: called
P:18087; T:0x139882926028928 18:03:23.690 [opensc-pkcs11] apdu.c:370:sc_single_transmit: CLA:0, INS:A4, P1:0, P2:0, data(2) 0x7ffe79a39290
P:18087; T:0x139882926028928 18:03:23.690 [opensc-pkcs11] reader-pcsc.c:325:pcsc_transmit: reader 'Identiv uTrust 3512 SAM slot Token [CCID Interface] (55512030608814) 00 00'
P:18087; T:0x139882926028928 18:03:23.691 [opensc-pkcs11] reader-pcsc.c:326:pcsc_transmit:
Outgoing APDU (8 bytes):
00 A4 00 00 02 2F 03 00 ...../..

P:18087; T:0x139882926028928 18:03:23.691 [opensc-pkcs11] reader-pcsc.c:244:pcsc_internal_transmit: called
P:18087; T:0x139882926028928 18:03:23.702 [opensc-pkcs11] reader-pcsc.c:335:pcsc_transmit:
Incoming APDU (2 bytes):
6A 82 j.

P:18087; T:0x139882926028928 18:03:23.702 [opensc-pkcs11] apdu.c:382:sc_single_transmit: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.702 [opensc-pkcs11] apdu.c:539:sc_transmit: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.702 [opensc-pkcs11] card.c:530:sc_unlock: called
P:18087; T:0x139882926028928 18:03:23.702 [opensc-pkcs11] iso7816.c:128:iso7816_check_sw: File or application not found
P:18087; T:0x139882926028928 18:03:23.702 [opensc-pkcs11] iso7816.c:627:iso7816_select_file: returning with: -1201 (File not found)
P:18087; T:0x139882926028928 18:03:23.702 [opensc-pkcs11] card.c:873:sc_select_file: 'SELECT' error: -1201 (File not found)
P:18087; T:0x139882926028928 18:03:23.702 [opensc-pkcs11] pkcs15-sc-hsm.c:256:read_file: Could not select EF
P:18087; T:0x139882926028928 18:03:23.702 [opensc-pkcs11] pkcs15-sc-hsm.c:262:read_file: Could not read EF
P:18087; T:0x139882926028928 18:03:23.702 [opensc-pkcs11] asn1.c:1725:asn1_decode: called, left=0, depth 0
P:18087; T:0x139882926028928 18:03:23.702 [opensc-pkcs11] asn1.c:1738:asn1_decode: End of ASN.1 stream, non-optional field "TokenInfo" not found
P:18087; T:0x139882926028928 18:03:23.702 [opensc-pkcs11] pkcs15.c:224:sc_pkcs15_parse_tokeninfo: ASN.1 parsing of EF(TokenInfo) failed: -1402 (Required ASN.1 object not found)
P:18087; T:0x139882926028928 18:03:23.702 [opensc-pkcs11] pkcs15-sc-hsm.c:1254:sc_pkcs15emu_sc_hsm_read_tokeninfo: Skipping optional EF.TokenInfo: -1402 (Required ASN.1 object not found)
P:18087; T:0x139882926028928 18:03:23.702 [opensc-pkcs11] pkcs15-syn.c:476:sc_pkcs15emu_object_add: called
P:18087; T:0x139882926028928 18:03:23.702 [opensc-pkcs11] pkcs15-syn.c:523:sc_pkcs15emu_object_add: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.702 [opensc-pkcs11] pkcs15-syn.c:476:sc_pkcs15emu_object_add: called
P:18087; T:0x139882926028928 18:03:23.702 [opensc-pkcs11] pkcs15-syn.c:523:sc_pkcs15emu_object_add: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.702 [opensc-pkcs11] sec.c:203:sc_pin_cmd: called
P:18087; T:0x139882926028928 18:03:23.703 [opensc-pkcs11] apdu.c:550:sc_transmit_apdu: called
P:18087; T:0x139882926028928 18:03:23.703 [opensc-pkcs11] card.c:471:sc_lock: called
P:18087; T:0x139882926028928 18:03:23.703 [opensc-pkcs11] card.c:520:sc_lock: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.703 [opensc-pkcs11] apdu.c:515:sc_transmit: called
P:18087; T:0x139882926028928 18:03:23.703 [opensc-pkcs11] apdu.c:363:sc_single_transmit: called
P:18087; T:0x139882926028928 18:03:23.703 [opensc-pkcs11] apdu.c:370:sc_single_transmit: CLA:0, INS:20, P1:0, P2:85, data(0) 0x7ffe79a394f0
P:18087; T:0x139882926028928 18:03:23.703 [opensc-pkcs11] reader-pcsc.c:325:pcsc_transmit: reader 'Identiv uTrust 3512 SAM slot Token [CCID Interface] (55512030608814) 00 00'
P:18087; T:0x139882926028928 18:03:23.703 [opensc-pkcs11] reader-pcsc.c:326:pcsc_transmit:
Outgoing APDU (4 bytes):
00 20 00 85 . ..

P:18087; T:0x139882926028928 18:03:23.703 [opensc-pkcs11] reader-pcsc.c:244:pcsc_internal_transmit: called
P:18087; T:0x139882926028928 18:03:23.713 [opensc-pkcs11] reader-pcsc.c:335:pcsc_transmit:
Incoming APDU (2 bytes):
6A 88 j.

P:18087; T:0x139882926028928 18:03:23.713 [opensc-pkcs11] apdu.c:382:sc_single_transmit: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.713 [opensc-pkcs11] apdu.c:539:sc_transmit: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.713 [opensc-pkcs11] card.c:530:sc_unlock: called
P:18087; T:0x139882926028928 18:03:23.713 [opensc-pkcs11] iso7816.c:128:iso7816_check_sw: Referenced data not found
P:18087; T:0x139882926028928 18:03:23.713 [opensc-pkcs11] card-sc-hsm.c:724:sc_hsm_pin_cmd: Verification failed: -1216 (Data object not found)
P:18087; T:0x139882926028928 18:03:23.714 [opensc-pkcs11] sec.c:259:sc_pin_cmd: returning with: -1216 (Data object not found)
P:18087; T:0x139882926028928 18:03:23.714 [opensc-pkcs11] sec.c:203:sc_pin_cmd: called
P:18087; T:0x139882926028928 18:03:23.714 [opensc-pkcs11] apdu.c:550:sc_transmit_apdu: called
P:18087; T:0x139882926028928 18:03:23.714 [opensc-pkcs11] card.c:471:sc_lock: called
P:18087; T:0x139882926028928 18:03:23.714 [opensc-pkcs11] card.c:520:sc_lock: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.714 [opensc-pkcs11] apdu.c:515:sc_transmit: called
P:18087; T:0x139882926028928 18:03:23.714 [opensc-pkcs11] apdu.c:363:sc_single_transmit: called
P:18087; T:0x139882926028928 18:03:23.714 [opensc-pkcs11] apdu.c:370:sc_single_transmit: CLA:0, INS:20, P1:0, P2:86, data(0) 0x7ffe79a394f0
P:18087; T:0x139882926028928 18:03:23.714 [opensc-pkcs11] reader-pcsc.c:325:pcsc_transmit: reader 'Identiv uTrust 3512 SAM slot Token [CCID Interface] (55512030608814) 00 00'
P:18087; T:0x139882926028928 18:03:23.714 [opensc-pkcs11] reader-pcsc.c:326:pcsc_transmit:
Outgoing APDU (4 bytes):
00 20 00 86 . ..

P:18087; T:0x139882926028928 18:03:23.714 [opensc-pkcs11] reader-pcsc.c:244:pcsc_internal_transmit: called
P:18087; T:0x139882926028928 18:03:23.724 [opensc-pkcs11] reader-pcsc.c:335:pcsc_transmit:
Incoming APDU (2 bytes):
6A 88 j.

P:18087; T:0x139882926028928 18:03:23.724 [opensc-pkcs11] apdu.c:382:sc_single_transmit: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.724 [opensc-pkcs11] apdu.c:539:sc_transmit: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.725 [opensc-pkcs11] card.c:530:sc_unlock: called
P:18087; T:0x139882926028928 18:03:23.725 [opensc-pkcs11] iso7816.c:128:iso7816_check_sw: Referenced data not found
P:18087; T:0x139882926028928 18:03:23.725 [opensc-pkcs11] card-sc-hsm.c:724:sc_hsm_pin_cmd: Verification failed: -1216 (Data object not found)
P:18087; T:0x139882926028928 18:03:23.725 [opensc-pkcs11] sec.c:259:sc_pin_cmd: returning with: -1216 (Data object not found)
P:18087; T:0x139882926028928 18:03:23.725 [opensc-pkcs11] card.c:566:sc_list_files: called
P:18087; T:0x139882926028928 18:03:23.725 [opensc-pkcs11] apdu.c:550:sc_transmit_apdu: called
P:18087; T:0x139882926028928 18:03:23.725 [opensc-pkcs11] card.c:471:sc_lock: called
P:18087; T:0x139882926028928 18:03:23.725 [opensc-pkcs11] card.c:520:sc_lock: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.725 [opensc-pkcs11] apdu.c:515:sc_transmit: called
P:18087; T:0x139882926028928 18:03:23.725 [opensc-pkcs11] apdu.c:363:sc_single_transmit: called
P:18087; T:0x139882926028928 18:03:23.725 [opensc-pkcs11] apdu.c:370:sc_single_transmit: CLA:80, INS:58, P1:0, P2:0, data(0) (nil)
P:18087; T:0x139882926028928 18:03:23.725 [opensc-pkcs11] reader-pcsc.c:325:pcsc_transmit: reader 'Identiv uTrust 3512 SAM slot Token [CCID Interface] (55512030608814) 00 00'
P:18087; T:0x139882926028928 18:03:23.725 [opensc-pkcs11] reader-pcsc.c:326:pcsc_transmit:
Outgoing APDU (7 bytes):
80 58 00 00 00 00 00 .X.....

P:18087; T:0x139882926028928 18:03:23.725 [opensc-pkcs11] reader-pcsc.c:244:pcsc_internal_transmit: called
P:18087; T:0x139882926028928 18:03:23.738 [opensc-pkcs11] reader-pcsc.c:335:pcsc_transmit:
Incoming APDU (6 bytes):
2F 02 CC 00 90 00 /.....

P:18087; T:0x139882926028928 18:03:23.738 [opensc-pkcs11] apdu.c:382:sc_single_transmit: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.739 [opensc-pkcs11] apdu.c:539:sc_transmit: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.739 [opensc-pkcs11] card.c:530:sc_unlock: called
P:18087; T:0x139882926028928 18:03:23.739 [opensc-pkcs11] card-sc-hsm.c:932:sc_hsm_list_files: returning with: 4
P:18087; T:0x139882926028928 18:03:23.739 [opensc-pkcs11] card.c:572:sc_list_files: returning with: 4
P:18087; T:0x139882926028928 18:03:23.739 [opensc-pkcs11] pkcs15-sc-hsm.c:1488:sc_pkcs15emu_sc_hsm_init: Error -1216 adding elements to framework
P:18087; T:0x139882926028928 18:03:23.739 [opensc-pkcs11] card.c:851:sc_select_file: called; type=0, path=c400
P:18087; T:0x139882926028928 18:03:23.739 [opensc-pkcs11] apdu.c:550:sc_transmit_apdu: called
P:18087; T:0x139882926028928 18:03:23.739 [opensc-pkcs11] card.c:471:sc_lock: called
P:18087; T:0x139882926028928 18:03:23.739 [opensc-pkcs11] card.c:520:sc_lock: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.739 [opensc-pkcs11] apdu.c:515:sc_transmit: called
P:18087; T:0x139882926028928 18:03:23.739 [opensc-pkcs11] apdu.c:363:sc_single_transmit: called
P:18087; T:0x139882926028928 18:03:23.739 [opensc-pkcs11] apdu.c:370:sc_single_transmit: CLA:0, INS:A4, P1:0, P2:0, data(2) 0x7ffe79a36d50
P:18087; T:0x139882926028928 18:03:23.739 [opensc-pkcs11] reader-pcsc.c:325:pcsc_transmit: reader 'Identiv uTrust 3512 SAM slot Token [CCID Interface] (55512030608814) 00 00'
P:18087; T:0x139882926028928 18:03:23.739 [opensc-pkcs11] reader-pcsc.c:326:pcsc_transmit:
Outgoing APDU (8 bytes):
00 A4 00 00 02 C4 00 00 ........

P:18087; T:0x139882926028928 18:03:23.739 [opensc-pkcs11] reader-pcsc.c:244:pcsc_internal_transmit: called
P:18087; T:0x139882926028928 18:03:23.750 [opensc-pkcs11] reader-pcsc.c:335:pcsc_transmit:
Incoming APDU (2 bytes):
6A 82 j.

P:18087; T:0x139882926028928 18:03:23.750 [opensc-pkcs11] apdu.c:382:sc_single_transmit: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.750 [opensc-pkcs11] apdu.c:539:sc_transmit: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.750 [opensc-pkcs11] card.c:530:sc_unlock: called
P:18087; T:0x139882926028928 18:03:23.750 [opensc-pkcs11] iso7816.c:128:iso7816_check_sw: File or application not found
P:18087; T:0x139882926028928 18:03:23.751 [opensc-pkcs11] iso7816.c:627:iso7816_select_file: returning with: -1201 (File not found)
P:18087; T:0x139882926028928 18:03:23.751 [opensc-pkcs11] card.c:873:sc_select_file: 'SELECT' error: -1201 (File not found)
P:18087; T:0x139882926028928 18:03:23.751 [opensc-pkcs11] pkcs15-sc-hsm.c:256:read_file: Could not select EF
P:18087; T:0x139882926028928 18:03:23.751 [opensc-pkcs11] pkcs15-sc-hsm.c:262:read_file: Could not read EF
P:18087; T:0x139882926028928 18:03:23.751 [opensc-pkcs11] asn1.c:1725:asn1_decode: called, left=0, depth 0, choice
P:18087; T:0x139882926028928 18:03:23.751 [opensc-pkcs11] pkcs15-prkey.c:261:sc_pkcs15_decode_prkdf_entry: Neither RSA or GOSTR3410 or ECC key in PrKDF entry.: -1401 (Invalid ASN.1 object)
P:18087; T:0x139882926028928 18:03:23.751 [opensc-pkcs11] pkcs15-sc-hsm.c:1091:sc_pkcs15emu_sc_hsm_add_prkd: Skipping optional EF.PRKD: -1401 (Invalid ASN.1 object)
P:18087; T:0x139882926028928 18:03:23.751 [opensc-pkcs11] pkcs15-sc-hsm.c:1488:sc_pkcs15emu_sc_hsm_init: Error -1401 adding elements to framework
P:18087; T:0x139882926028928 18:03:23.751 [opensc-pkcs11] pkcs15-sc-hsm.c:1492:sc_pkcs15emu_sc_hsm_init: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.751 [opensc-pkcs11] pkcs15-syn.c:211:sc_pkcs15_bind_synthetic: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.751 [opensc-pkcs11] card.c:530:sc_unlock: called
P:18087; T:0x139882926028928 18:03:23.751 [opensc-pkcs11] reader-pcsc.c:740:pcsc_unlock: called
P:18087; T:0x139882926028928 18:03:23.751 [opensc-pkcs11] pkcs15.c:1316:sc_pkcs15_bind: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.751 [opensc-pkcs11] slot.c:335:card_detect: Identiv uTrust 3512 SAM slot Token [CCID Interface] (55512030608814) 00 00: Creating 'generic' token.
P:18087; T:0x139882926028928 18:03:23.751 [opensc-pkcs11] framework-pkcs15.c:1583:pkcs15_create_tokens: create PKCS#15 tokens; fws:0x16ba590,(nil),(nil)
P:18087; T:0x139882926028928 18:03:23.751 [opensc-pkcs11] framework-pkcs15.c:1585:pkcs15_create_tokens: create slots flags 0x8
P:18087; T:0x139882926028928 18:03:23.751 [opensc-pkcs11] framework-pkcs15.c:1596:pkcs15_create_tokens: Use FW data with index 0; fw_data->p15_card 0x16ba1d0
P:18087; T:0x139882926028928 18:03:23.751 [opensc-pkcs11] pkcs15.c:1758:sc_pkcs15_find_pin_by_flags: called
P:18087; T:0x139882926028928 18:03:23.751 [opensc-pkcs11] pkcs15.c:1759:sc_pkcs15_find_pin_by_flags: Find PIN flags:0x10, mask:0xD2, index:-1
P:18087; T:0x139882926028928 18:03:23.751 [opensc-pkcs11] pkcs15.c:1785:sc_pkcs15_find_pin_by_flags: returning with: -1407 (Requested object not found)
P:18087; T:0x139882926028928 18:03:23.751 [opensc-pkcs11] pkcs15.c:1758:sc_pkcs15_find_pin_by_flags: called
P:18087; T:0x139882926028928 18:03:23.751 [opensc-pkcs11] pkcs15.c:1759:sc_pkcs15_find_pin_by_flags: Find PIN flags:0x12, mask:0xD2, index:-1
P:18087; T:0x139882926028928 18:03:23.752 [opensc-pkcs11] pkcs15.c:1782:sc_pkcs15_find_pin_by_flags: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.752 [opensc-pkcs11] framework-pkcs15.c:1602:pkcs15_create_tokens: Flags:0x8; Auth User/Sign PINs 0x16bb5a0/(nil)
P:18087; T:0x139882926028928 18:03:23.752 [opensc-pkcs11] framework-pkcs15.c:905:pkcs15_create_pkcs11_objects: Found 0 RSA private keys
P:18087; T:0x139882926028928 18:03:23.752 [opensc-pkcs11] framework-pkcs15.c:905:pkcs15_create_pkcs11_objects: Found 0 RSA public keys
P:18087; T:0x139882926028928 18:03:23.752 [opensc-pkcs11] framework-pkcs15.c:905:pkcs15_create_pkcs11_objects: Found 0 EC private keys
P:18087; T:0x139882926028928 18:03:23.752 [opensc-pkcs11] framework-pkcs15.c:905:pkcs15_create_pkcs11_objects: Found 0 EC public keys
P:18087; T:0x139882926028928 18:03:23.752 [opensc-pkcs11] framework-pkcs15.c:905:pkcs15_create_pkcs11_objects: Found 0 EdDSA private keys
P:18087; T:0x139882926028928 18:03:23.752 [opensc-pkcs11] framework-pkcs15.c:905:pkcs15_create_pkcs11_objects: Found 0 EdDSA public keys
P:18087; T:0x139882926028928 18:03:23.752 [opensc-pkcs11] framework-pkcs15.c:905:pkcs15_create_pkcs11_objects: Found 0 XEdDSA private keys
P:18087; T:0x139882926028928 18:03:23.752 [opensc-pkcs11] framework-pkcs15.c:905:pkcs15_create_pkcs11_objects: Found 0 XEdDSA public keys
P:18087; T:0x139882926028928 18:03:23.752 [opensc-pkcs11] framework-pkcs15.c:905:pkcs15_create_pkcs11_objects: Found 0 GOSTR3410 private keys
P:18087; T:0x139882926028928 18:03:23.752 [opensc-pkcs11] framework-pkcs15.c:905:pkcs15_create_pkcs11_objects: Found 0 GOSTR3410 public keys
P:18087; T:0x139882926028928 18:03:23.752 [opensc-pkcs11] framework-pkcs15.c:905:pkcs15_create_pkcs11_objects: Found 0 certificates
P:18087; T:0x139882926028928 18:03:23.752 [opensc-pkcs11] framework-pkcs15.c:905:pkcs15_create_pkcs11_objects: Found 0 data objects
P:18087; T:0x139882926028928 18:03:23.752 [opensc-pkcs11] framework-pkcs15.c:905:pkcs15_create_pkcs11_objects: Found 0 Generic secret keys
P:18087; T:0x139882926028928 18:03:23.752 [opensc-pkcs11] framework-pkcs15.c:1334:_pkcs15_create_typed_objects: found 0 FW objects
P:18087; T:0x139882926028928 18:03:23.752 [opensc-pkcs11] framework-pkcs15.c:1608:pkcs15_create_tokens: Found 0 FW objects objects
P:18087; T:0x139882926028928 18:03:23.752 [opensc-pkcs11] framework-pkcs15.c:1624:pkcs15_create_tokens: Found 2 authentication objects
P:18087; T:0x139882926028928 18:03:23.752 [opensc-pkcs11] framework-pkcs15.c:1633:pkcs15_create_tokens: Found authentication object 'UserPIN'
P:18087; T:0x139882926028928 18:03:23.752 [opensc-pkcs11] slot.c:450:slot_allocate: Allocated slot 0x0 for card in reader Identiv uTrust 3512 SAM slot Token [CCID Interface] (55512030608814) 00 00
P:18087; T:0x139882926028928 18:03:23.752 [opensc-pkcs11] framework-pkcs15.c:1137:pkcs15_init_slot: Called
P:18087; T:0x139882926028928 18:03:23.752 [opensc-pkcs11] framework-pkcs15.c:1229:pkcs15_init_slot: Initialized slot 0x0 with token SmartCard-HSM (UserPIN)         www.CardContact.de              PKCS#15 emulatedDECC0900154
P:18087; T:0x139882926028928 18:03:23.752 [opensc-pkcs11] framework-pkcs15.c:1463:_add_pin_related_objects: Add objects related to PIN('UserPIN',ID:01)
P:18087; T:0x139882926028928 18:03:23.752 [opensc-pkcs11] framework-pkcs15.c:1681:pkcs15_create_tokens: Add public objects to slot 0x16b8ac0
P:18087; T:0x139882926028928 18:03:23.752 [opensc-pkcs11] framework-pkcs15.c:1527:_add_public_objects: 0 public objects to process
P:18087; T:0x139882926028928 18:03:23.752 [opensc-pkcs11] framework-pkcs15.c:1080:pkcs15_add_object: Slot:0 Setting object handle of 0x0 to 0x16b4e50
P:18087; T:0x139882926028928 18:03:23.752 [opensc-pkcs11] framework-pkcs15.c:1685:pkcs15_create_tokens: All tokens created
P:18087; T:0x139882926028928 18:03:23.752 [opensc-pkcs11] slot.c:376:card_detect: Identiv uTrust 3512 SAM slot Token [CCID Interface] (55512030608814) 00 00: Detection ended
P:18087; T:0x139882926028928 18:03:23.752 [opensc-pkcs11] slot.c:432:card_detect_all: All cards detected
P:18087; T:0x139882926028928 18:03:23.752 [opensc-pkcs11] pkcs11-global.c:355:C_Initialize: C_Initialize() = CKR_OK
P:18087; T:0x139882926028928 18:03:23.752 [opensc-pkcs11] pkcs11-global.c:493:C_GetSlotList: C_GetSlotList(token=0, plug-n-play)
P:18087; T:0x139882926028928 18:03:23.752 [opensc-pkcs11] pkcs11-global.c:494:C_GetSlotList: VSS C_GetSlotList before ctx_detect_detect
P:18087; T:0x139882926028928 18:03:23.753 [opensc-pkcs11] reader-pcsc.c:1376:pcsc_detect_readers: called
P:18087; T:0x139882926028928 18:03:23.753 [opensc-pkcs11] reader-pcsc.c:1389:pcsc_detect_readers: Probing PC/SC readers
P:18087; T:0x139882926028928 18:03:23.753 [opensc-pkcs11] reader-pcsc.c:1558:pcsc_detect_readers: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.753 [opensc-pkcs11] pkcs11-global.c:500:C_GetSlotList: VSS C_GetSlotList after ctx_detect_readers
P:18087; T:0x139882926028928 18:03:23.753 [opensc-pkcs11] slot.c:393:card_detect_all: Detect all cards
P:18087; T:0x139882926028928 18:03:23.753 [opensc-pkcs11] slot.c:219:card_detect: Identiv uTrust 3512 SAM slot Token [CCID Interface] (55512030608814) 00 00: Detecting smart card
P:18087; T:0x139882926028928 18:03:23.753 [opensc-pkcs11] sc.c:340:sc_detect_card_presence: called
P:18087; T:0x139882926028928 18:03:23.753 [opensc-pkcs11] reader-pcsc.c:470:pcsc_detect_card_presence: called
P:18087; T:0x139882926028928 18:03:23.753 [opensc-pkcs11] reader-pcsc.c:362:refresh_attributes: Identiv uTrust 3512 SAM slot Token [CCID Interface] (55512030608814) 00 00 check
P:18087; T:0x139882926028928 18:03:23.754 [opensc-pkcs11] reader-pcsc.c:387:refresh_attributes: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.754 [opensc-pkcs11] reader-pcsc.c:478:pcsc_detect_card_presence: returning with: 1
P:18087; T:0x139882926028928 18:03:23.754 [opensc-pkcs11] sc.c:351:sc_detect_card_presence: returning with: 1
P:18087; T:0x139882926028928 18:03:23.754 [opensc-pkcs11] slot.c:376:card_detect: Identiv uTrust 3512 SAM slot Token [CCID Interface] (55512030608814) 00 00: Detection ended
P:18087; T:0x139882926028928 18:03:23.754 [opensc-pkcs11] slot.c:432:card_detect_all: All cards detected
P:18087; T:0x139882926028928 18:03:23.754 [opensc-pkcs11] pkcs11-global.c:536:C_GetSlotList: VSS C_GetSlotList after card_detect_all
P:18087; T:0x139882926028928 18:03:23.754 [opensc-pkcs11] pkcs11-global.c:539:C_GetSlotList: was only a size inquiry (1)
P:18087; T:0x139882926028928 18:03:23.754 [opensc-pkcs11] pkcs11-global.c:493:C_GetSlotList: C_GetSlotList(token=0, refresh)
P:18087; T:0x139882926028928 18:03:23.754 [opensc-pkcs11] pkcs11-global.c:494:C_GetSlotList: VSS C_GetSlotList before ctx_detect_detect
P:18087; T:0x139882926028928 18:03:23.754 [opensc-pkcs11] pkcs11-global.c:500:C_GetSlotList: VSS C_GetSlotList after ctx_detect_readers
P:18087; T:0x139882926028928 18:03:23.754 [opensc-pkcs11] slot.c:393:card_detect_all: Detect all cards
P:18087; T:0x139882926028928 18:03:23.754 [opensc-pkcs11] slot.c:219:card_detect: Identiv uTrust 3512 SAM slot Token [CCID Interface] (55512030608814) 00 00: Detecting smart card
P:18087; T:0x139882926028928 18:03:23.754 [opensc-pkcs11] sc.c:340:sc_detect_card_presence: called
P:18087; T:0x139882926028928 18:03:23.754 [opensc-pkcs11] reader-pcsc.c:470:pcsc_detect_card_presence: called
P:18087; T:0x139882926028928 18:03:23.754 [opensc-pkcs11] reader-pcsc.c:362:refresh_attributes: Identiv uTrust 3512 SAM slot Token [CCID Interface] (55512030608814) 00 00 check
P:18087; T:0x139882926028928 18:03:23.756 [opensc-pkcs11] reader-pcsc.c:387:refresh_attributes: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.756 [opensc-pkcs11] reader-pcsc.c:478:pcsc_detect_card_presence: returning with: 1
P:18087; T:0x139882926028928 18:03:23.756 [opensc-pkcs11] sc.c:351:sc_detect_card_presence: returning with: 1
P:18087; T:0x139882926028928 18:03:23.756 [opensc-pkcs11] slot.c:376:card_detect: Identiv uTrust 3512 SAM slot Token [CCID Interface] (55512030608814) 00 00: Detection ended
P:18087; T:0x139882926028928 18:03:23.756 [opensc-pkcs11] slot.c:432:card_detect_all: All cards detected
P:18087; T:0x139882926028928 18:03:23.756 [opensc-pkcs11] pkcs11-global.c:536:C_GetSlotList: VSS C_GetSlotList after card_detect_all
P:18087; T:0x139882926028928 18:03:23.756 [opensc-pkcs11] pkcs11-global.c:544:C_GetSlotList: VSS C_GetSlotList after slot->id reassigned
P:18087; T:0x139882926028928 18:03:23.756 [opensc-pkcs11] pkcs11-global.c:557:C_GetSlotList: returned 1 slots
P:18087; T:0x139882926028928 18:03:23.756 [opensc-pkcs11] pkcs11-global.c:558:C_GetSlotList: VSS Returning a new slot list
P:18087; T:0x139882926028928 18:03:23.756 [opensc-pkcs11] pkcs11-global.c:607:C_GetSlotInfo: C_GetSlotInfo(0x0)
P:18087; T:0x139882926028928 18:03:23.756 [opensc-pkcs11] slot.c:393:card_detect_all: Detect all cards
P:18087; T:0x139882926028928 18:03:23.756 [opensc-pkcs11] slot.c:219:card_detect: Identiv uTrust 3512 SAM slot Token [CCID Interface] (55512030608814) 00 00: Detecting smart card
P:18087; T:0x139882926028928 18:03:23.756 [opensc-pkcs11] sc.c:340:sc_detect_card_presence: called
P:18087; T:0x139882926028928 18:03:23.756 [opensc-pkcs11] reader-pcsc.c:470:pcsc_detect_card_presence: called
P:18087; T:0x139882926028928 18:03:23.756 [opensc-pkcs11] reader-pcsc.c:362:refresh_attributes: Identiv uTrust 3512 SAM slot Token [CCID Interface] (55512030608814) 00 00 check
P:18087; T:0x139882926028928 18:03:23.757 [opensc-pkcs11] reader-pcsc.c:387:refresh_attributes: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.757 [opensc-pkcs11] reader-pcsc.c:478:pcsc_detect_card_presence: returning with: 1
P:18087; T:0x139882926028928 18:03:23.757 [opensc-pkcs11] sc.c:351:sc_detect_card_presence: returning with: 1
P:18087; T:0x139882926028928 18:03:23.757 [opensc-pkcs11] slot.c:376:card_detect: Identiv uTrust 3512 SAM slot Token [CCID Interface] (55512030608814) 00 00: Detection ended
P:18087; T:0x139882926028928 18:03:23.757 [opensc-pkcs11] slot.c:432:card_detect_all: All cards detected
P:18087; T:0x139882926028928 18:03:23.757 [opensc-pkcs11] pkcs11-global.c:619:C_GetSlotInfo: VSS C_GetSlotInfo found
P:18087; T:0x139882926028928 18:03:23.757 [opensc-pkcs11] pkcs11-global.c:620:C_GetSlotInfo: C_GetSlotInfo() get slot rv CKR_OK
P:18087; T:0x139882926028928 18:03:23.757 [opensc-pkcs11] slot.c:219:card_detect: Identiv uTrust 3512 SAM slot Token [CCID Interface] (55512030608814) 00 00: Detecting smart card
P:18087; T:0x139882926028928 18:03:23.757 [opensc-pkcs11] sc.c:340:sc_detect_card_presence: called
P:18087; T:0x139882926028928 18:03:23.757 [opensc-pkcs11] reader-pcsc.c:470:pcsc_detect_card_presence: called
P:18087; T:0x139882926028928 18:03:23.757 [opensc-pkcs11] reader-pcsc.c:362:refresh_attributes: Identiv uTrust 3512 SAM slot Token [CCID Interface] (55512030608814) 00 00 check
P:18087; T:0x139882926028928 18:03:23.759 [opensc-pkcs11] reader-pcsc.c:387:refresh_attributes: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.759 [opensc-pkcs11] reader-pcsc.c:478:pcsc_detect_card_presence: returning with: 1
P:18087; T:0x139882926028928 18:03:23.759 [opensc-pkcs11] sc.c:351:sc_detect_card_presence: returning with: 1
P:18087; T:0x139882926028928 18:03:23.759 [opensc-pkcs11] slot.c:376:card_detect: Identiv uTrust 3512 SAM slot Token [CCID Interface] (55512030608814) 00 00: Detection ended
P:18087; T:0x139882926028928 18:03:23.759 [opensc-pkcs11] pkcs11-global.c:629:C_GetSlotInfo: C_GetSlotInfo() card detect rv 0x0
P:18087; T:0x139882926028928 18:03:23.759 [opensc-pkcs11] pkcs11-global.c:646:C_GetSlotInfo: C_GetSlotInfo() flags 0x7
P:18087; T:0x139882926028928 18:03:23.759 [opensc-pkcs11] pkcs11-global.c:650:C_GetSlotInfo: C_GetSlotInfo(0x0) = CKR_OK
Using slot 0 with a present token (0x0)
P:18087; T:0x139882926028928 18:03:23.759 [opensc-pkcs11] pkcs11-session.c:58:C_OpenSession: C_OpenSession(0x0)
P:18087; T:0x139882926028928 18:03:23.759 [opensc-pkcs11] slot.c:472:slot_get_token: Slot(id=0x0): get token
P:18087; T:0x139882926028928 18:03:23.759 [opensc-pkcs11] slot.c:490:slot_get_token: Slot-get-token returns OK
P:18087; T:0x139882926028928 18:03:23.759 [opensc-pkcs11] pkcs11-session.c:94:C_OpenSession: C_OpenSession handle: 0x16c1c50
P:18087; T:0x139882926028928 18:03:23.759 [opensc-pkcs11] pkcs11-session.c:97:C_OpenSession: C_OpenSession() = CKR_OK
P:18087; T:0x139882926028928 18:03:23.759 [opensc-pkcs11] framework-pkcs15.c:555:C_GetTokenInfo: C_GetTokenInfo(0)
P:18087; T:0x139882926028928 18:03:23.759 [opensc-pkcs11] slot.c:472:slot_get_token: Slot(id=0x0): get token
P:18087; T:0x139882926028928 18:03:23.759 [opensc-pkcs11] slot.c:490:slot_get_token: Slot-get-token returns OK
P:18087; T:0x139882926028928 18:03:23.759 [opensc-pkcs11] framework-pkcs15.c:588:C_GetTokenInfo: C_GetTokenInfo() auth. object 0x16bb5a0, token-info flags 0x40D
P:18087; T:0x139882926028928 18:03:23.759 [opensc-pkcs11] pkcs15-pin.c:707:sc_pkcs15_get_pin_info: called
P:18087; T:0x139882926028928 18:03:23.759 [opensc-pkcs11] card.c:471:sc_lock: called
P:18087; T:0x139882926028928 18:03:23.759 [opensc-pkcs11] reader-pcsc.c:688:pcsc_lock: called
P:18087; T:0x139882926028928 18:03:23.759 [opensc-pkcs11] card.c:520:sc_lock: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.759 [opensc-pkcs11] card.c:851:sc_select_file: called; type=0, path=e82b0601040181c31f0201::
P:18087; T:0x139882926028928 18:03:23.759 [opensc-pkcs11] card.c:886:sc_select_file: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.759 [opensc-pkcs11] sec.c:203:sc_pin_cmd: called
P:18087; T:0x139882926028928 18:03:23.759 [opensc-pkcs11] apdu.c:550:sc_transmit_apdu: called
P:18087; T:0x139882926028928 18:03:23.759 [opensc-pkcs11] card.c:471:sc_lock: called
P:18087; T:0x139882926028928 18:03:23.759 [opensc-pkcs11] card.c:520:sc_lock: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.759 [opensc-pkcs11] apdu.c:515:sc_transmit: called
P:18087; T:0x139882926028928 18:03:23.759 [opensc-pkcs11] apdu.c:363:sc_single_transmit: called
P:18087; T:0x139882926028928 18:03:23.759 [opensc-pkcs11] apdu.c:370:sc_single_transmit: CLA:0, INS:20, P1:0, P2:81, data(0) 0x7ffe79a3aea0
P:18087; T:0x139882926028928 18:03:23.760 [opensc-pkcs11] reader-pcsc.c:325:pcsc_transmit: reader 'Identiv uTrust 3512 SAM slot Token [CCID Interface] (55512030608814) 00 00'
P:18087; T:0x139882926028928 18:03:23.760 [opensc-pkcs11] reader-pcsc.c:326:pcsc_transmit:
Outgoing APDU (4 bytes):
00 20 00 81 . ..

P:18087; T:0x139882926028928 18:03:23.760 [opensc-pkcs11] reader-pcsc.c:244:pcsc_internal_transmit: called
P:18087; T:0x139882926028928 18:03:23.771 [opensc-pkcs11] reader-pcsc.c:335:pcsc_transmit:
Incoming APDU (2 bytes):
63 C3 c.

P:18087; T:0x139882926028928 18:03:23.771 [opensc-pkcs11] apdu.c:382:sc_single_transmit: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.771 [opensc-pkcs11] apdu.c:539:sc_transmit: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.771 [opensc-pkcs11] card.c:530:sc_unlock: called
P:18087; T:0x139882926028928 18:03:23.771 [opensc-pkcs11] iso7816.c:123:iso7816_check_sw: PIN not verified (remaining tries: 3)
P:18087; T:0x139882926028928 18:03:23.771 [opensc-pkcs11] card-sc-hsm.c:769:sc_hsm_pin_cmd: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.771 [opensc-pkcs11] sec.c:259:sc_pin_cmd: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.771 [opensc-pkcs11] card.c:530:sc_unlock: called
P:18087; T:0x139882926028928 18:03:23.771 [opensc-pkcs11] reader-pcsc.c:740:pcsc_unlock: called
P:18087; T:0x139882926028928 18:03:23.771 [opensc-pkcs11] pkcs15-pin.c:742:sc_pkcs15_get_pin_info: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.771 [opensc-pkcs11] framework-pkcs15.c:618:C_GetTokenInfo: C_GetTokenInfo(0) returns CKR_OK
P:18087; T:0x139882926028928 18:03:23.772 [opensc-pkcs11] pkcs11-session.c:347:C_Login: C_Login(0x16c1c50, 1)
P:18087; T:0x139882926028928 18:03:23.772 [opensc-pkcs11] pkcs11-session.c:369:C_Login: C_Login() slot->login_user -1
P:18087; T:0x139882926028928 18:03:23.772 [opensc-pkcs11] pkcs11-session.c:380:C_Login: C_Login() userType 1
P:18087; T:0x139882926028928 18:03:23.772 [opensc-pkcs11] framework-pkcs15.c:1721:pkcs15_login: pkcs15-login: userType 0x1, PIN length 6
P:18087; T:0x139882926028928 18:03:23.772 [opensc-pkcs11] pkcs15-pin.c:304:sc_pkcs15_verify_pin: called
P:18087; T:0x139882926028928 18:03:23.772 [opensc-pkcs11] pkcs15-pin.c:363:sc_pkcs15_verify_pin_with_session_pin: called
P:18087; T:0x139882926028928 18:03:23.772 [opensc-pkcs11] pkcs15-pin.c:367:sc_pkcs15_verify_pin_with_session_pin: PIN(type:0; method:1; value(0x7ffe79a3c946:6)
P:18087; T:0x139882926028928 18:03:23.772 [opensc-pkcs11] card.c:471:sc_lock: called
P:18087; T:0x139882926028928 18:03:23.772 [opensc-pkcs11] reader-pcsc.c:688:pcsc_lock: called
P:18087; T:0x139882926028928 18:03:23.772 [opensc-pkcs11] card.c:520:sc_lock: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.772 [opensc-pkcs11] card.c:851:sc_select_file: called; type=0, path=e82b0601040181c31f0201::
P:18087; T:0x139882926028928 18:03:23.772 [opensc-pkcs11] card.c:886:sc_select_file: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.772 [opensc-pkcs11] sec.c:203:sc_pin_cmd: called
P:18087; T:0x139882926028928 18:03:23.772 [opensc-pkcs11] apdu.c:550:sc_transmit_apdu: called
P:18087; T:0x139882926028928 18:03:23.772 [opensc-pkcs11] card.c:471:sc_lock: called
P:18087; T:0x139882926028928 18:03:23.772 [opensc-pkcs11] card.c:520:sc_lock: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.772 [opensc-pkcs11] apdu.c:515:sc_transmit: called
P:18087; T:0x139882926028928 18:03:23.772 [opensc-pkcs11] apdu.c:363:sc_single_transmit: called
P:18087; T:0x139882926028928 18:03:23.772 [opensc-pkcs11] apdu.c:370:sc_single_transmit: CLA:0, INS:20, P1:0, P2:81, data(6) 0x7ffe79a3ad10
P:18087; T:0x139882926028928 18:03:23.772 [opensc-pkcs11] reader-pcsc.c:325:pcsc_transmit: reader 'Identiv uTrust 3512 SAM slot Token [CCID Interface] (55512030608814) 00 00'
P:18087; T:0x139882926028928 18:03:23.772 [opensc-pkcs11] reader-pcsc.c:326:pcsc_transmit:
Outgoing APDU (11 bytes):
00 20 00 81 06 30 30 30 30 30 30 . ...000000

P:18087; T:0x139882926028928 18:03:23.772 [opensc-pkcs11] reader-pcsc.c:244:pcsc_internal_transmit: called
P:18087; T:0x139882926028928 18:03:23.853 [opensc-pkcs11] reader-pcsc.c:335:pcsc_transmit:
Incoming APDU (2 bytes):
90 00 ..

P:18087; T:0x139882926028928 18:03:23.853 [opensc-pkcs11] apdu.c:382:sc_single_transmit: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.853 [opensc-pkcs11] apdu.c:539:sc_transmit: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.853 [opensc-pkcs11] card.c:530:sc_unlock: called
P:18087; T:0x139882926028928 18:03:23.853 [opensc-pkcs11] card-sc-hsm.c:769:sc_hsm_pin_cmd: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.853 [opensc-pkcs11] sec.c:259:sc_pin_cmd: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.853 [opensc-pkcs11] pkcs15-pin.c:455:sc_pkcs15_verify_pin_with_session_pin: PIN cmd result 0
P:18087; T:0x139882926028928 18:03:23.853 [opensc-pkcs11] pkcs15-pin.c:761:sc_pkcs15_pincache_add: called
P:18087; T:0x139882926028928 18:03:23.853 [opensc-pkcs11] pkcs15-pin.c:805:sc_pkcs15_pincache_add: PIN(UserPIN) cached
P:18087; T:0x139882926028928 18:03:23.853 [opensc-pkcs11] card.c:530:sc_unlock: called
P:18087; T:0x139882926028928 18:03:23.853 [opensc-pkcs11] reader-pcsc.c:740:pcsc_unlock: called
P:18087; T:0x139882926028928 18:03:23.854 [opensc-pkcs11] pkcs15-pin.c:477:sc_pkcs15_verify_pin_with_session_pin: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.854 [opensc-pkcs11] pkcs15-pin.c:761:sc_pkcs15_pincache_add: called
P:18087; T:0x139882926028928 18:03:23.854 [opensc-pkcs11] pkcs15-pin.c:805:sc_pkcs15_pincache_add: PIN(UserPIN) cached
P:18087; T:0x139882926028928 18:03:23.854 [opensc-pkcs11] pkcs15-pin.c:333:sc_pkcs15_verify_pin: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.854 [opensc-pkcs11] framework-pkcs15.c:1817:pkcs15_login: PKCS15 verify PIN returned 0
P:18087; T:0x139882926028928 18:03:23.854 [opensc-pkcs11] framework-pkcs15.c:1826:pkcs15_login: Check if pkcs15 object list can be completed.
P:18087; T:0x139882926028928 18:03:23.854 [opensc-pkcs11] pkcs11-session.c:384:C_Login: fLogin() rv 0
P:18087; T:0x139882926028928 18:03:23.854 [opensc-pkcs11] slot.c:472:slot_get_token: Slot(id=0x0): get token
P:18087; T:0x139882926028928 18:03:23.854 [opensc-pkcs11] slot.c:490:slot_get_token: Slot-get-token returns OK
P:18087; T:0x139882926028928 18:03:23.854 [opensc-pkcs11] slot.c:472:slot_get_token: Slot(id=0x0): get token
P:18087; T:0x139882926028928 18:03:23.854 [opensc-pkcs11] slot.c:490:slot_get_token: Slot-get-token returns OK
P:18087; T:0x139882926028928 18:03:23.854 [opensc-pkcs11] slot.c:472:slot_get_token: Slot(id=0x0): get token
P:18087; T:0x139882926028928 18:03:23.854 [opensc-pkcs11] slot.c:490:slot_get_token: Slot-get-token returns OK
P:18087; T:0x139882926028928 18:03:23.854 [opensc-pkcs11] slot.c:472:slot_get_token: Slot(id=0x0): get token
P:18087; T:0x139882926028928 18:03:23.854 [opensc-pkcs11] slot.c:490:slot_get_token: Slot-get-token returns OK
P:18087; T:0x139882926028928 18:03:23.854 [opensc-pkcs11] slot.c:472:slot_get_token: Slot(id=0x0): get token
P:18087; T:0x139882926028928 18:03:23.854 [opensc-pkcs11] slot.c:490:slot_get_token: Slot-get-token returns OK
P:18087; T:0x139882926028928 18:03:23.854 [opensc-pkcs11] slot.c:472:slot_get_token: Slot(id=0x0): get token
P:18087; T:0x139882926028928 18:03:23.854 [opensc-pkcs11] slot.c:490:slot_get_token: Slot-get-token returns OK
P:18087; T:0x139882926028928 18:03:23.854 [opensc-pkcs11] slot.c:472:slot_get_token: Slot(id=0x0): get token
P:18087; T:0x139882926028928 18:03:23.854 [opensc-pkcs11] slot.c:490:slot_get_token: Slot-get-token returns OK
P:18087; T:0x139882926028928 18:03:23.854 [opensc-pkcs11] slot.c:472:slot_get_token: Slot(id=0x0): get token
P:18087; T:0x139882926028928 18:03:23.854 [opensc-pkcs11] slot.c:490:slot_get_token: Slot-get-token returns OK
P:18087; T:0x139882926028928 18:03:23.854 [opensc-pkcs11] slot.c:472:slot_get_token: Slot(id=0x0): get token
P:18087; T:0x139882926028928 18:03:23.854 [opensc-pkcs11] slot.c:490:slot_get_token: Slot-get-token returns OK
P:18087; T:0x139882926028928 18:03:23.854 [opensc-pkcs11] slot.c:472:slot_get_token: Slot(id=0x0): get token
P:18087; T:0x139882926028928 18:03:23.854 [opensc-pkcs11] slot.c:490:slot_get_token: Slot-get-token returns OK
P:18087; T:0x139882926028928 18:03:23.855 [opensc-pkcs11] slot.c:472:slot_get_token: Slot(id=0x0): get token
P:18087; T:0x139882926028928 18:03:23.855 [opensc-pkcs11] slot.c:490:slot_get_token: Slot-get-token returns OK
P:18087; T:0x139882926028928 18:03:23.855 [opensc-pkcs11] slot.c:472:slot_get_token: Slot(id=0x0): get token
P:18087; T:0x139882926028928 18:03:23.855 [opensc-pkcs11] slot.c:490:slot_get_token: Slot-get-token returns OK
P:18087; T:0x139882926028928 18:03:23.855 [opensc-pkcs11] slot.c:472:slot_get_token: Slot(id=0x0): get token
P:18087; T:0x139882926028928 18:03:23.855 [opensc-pkcs11] slot.c:490:slot_get_token: Slot-get-token returns OK
P:18087; T:0x139882926028928 18:03:23.855 [opensc-pkcs11] slot.c:472:slot_get_token: Slot(id=0x0): get token
P:18087; T:0x139882926028928 18:03:23.855 [opensc-pkcs11] slot.c:490:slot_get_token: Slot-get-token returns OK
P:18087; T:0x139882926028928 18:03:23.855 [opensc-pkcs11] slot.c:472:slot_get_token: Slot(id=0x0): get token
P:18087; T:0x139882926028928 18:03:23.855 [opensc-pkcs11] slot.c:490:slot_get_token: Slot-get-token returns OK
P:18087; T:0x139882926028928 18:03:23.855 [opensc-pkcs11] slot.c:472:slot_get_token: Slot(id=0x0): get token
P:18087; T:0x139882926028928 18:03:23.855 [opensc-pkcs11] slot.c:490:slot_get_token: Slot-get-token returns OK
P:18087; T:0x139882926028928 18:03:23.855 [opensc-pkcs11] slot.c:472:slot_get_token: Slot(id=0x0): get token
P:18087; T:0x139882926028928 18:03:23.855 [opensc-pkcs11] slot.c:490:slot_get_token: Slot-get-token returns OK
P:18087; T:0x139882926028928 18:03:23.855 [opensc-pkcs11] slot.c:472:slot_get_token: Slot(id=0x0): get token
P:18087; T:0x139882926028928 18:03:23.855 [opensc-pkcs11] slot.c:490:slot_get_token: Slot-get-token returns OK
P:18087; T:0x139882926028928 18:03:23.855 [opensc-pkcs11] slot.c:472:slot_get_token: Slot(id=0x0): get token
P:18087; T:0x139882926028928 18:03:23.855 [opensc-pkcs11] slot.c:490:slot_get_token: Slot-get-token returns OK
P:18087; T:0x139882926028928 18:03:23.855 [opensc-pkcs11] slot.c:472:slot_get_token: Slot(id=0x0): get token
P:18087; T:0x139882926028928 18:03:23.855 [opensc-pkcs11] slot.c:490:slot_get_token: Slot-get-token returns OK
P:18087; T:0x139882926028928 18:03:23.855 [opensc-pkcs11] slot.c:472:slot_get_token: Slot(id=0x0): get token
P:18087; T:0x139882926028928 18:03:23.855 [opensc-pkcs11] slot.c:490:slot_get_token: Slot-get-token returns OK
P:18087; T:0x139882926028928 18:03:23.855 [opensc-pkcs11] slot.c:472:slot_get_token: Slot(id=0x0): get token
P:18087; T:0x139882926028928 18:03:23.855 [opensc-pkcs11] slot.c:490:slot_get_token: Slot-get-token returns OK
P:18087; T:0x139882926028928 18:03:23.855 [opensc-pkcs11] slot.c:472:slot_get_token: Slot(id=0x0): get token
P:18087; T:0x139882926028928 18:03:23.855 [opensc-pkcs11] slot.c:490:slot_get_token: Slot-get-token returns OK
P:18087; T:0x139882926028928 18:03:23.855 [opensc-pkcs11] slot.c:472:slot_get_token: Slot(id=0x0): get token
P:18087; T:0x139882926028928 18:03:23.855 [opensc-pkcs11] slot.c:490:slot_get_token: Slot-get-token returns OK
P:18087; T:0x139882926028928 18:03:23.855 [opensc-pkcs11] slot.c:472:slot_get_token: Slot(id=0x0): get token
P:18087; T:0x139882926028928 18:03:23.855 [opensc-pkcs11] slot.c:490:slot_get_token: Slot-get-token returns OK
P:18087; T:0x139882926028928 18:03:23.855 [opensc-pkcs11] slot.c:472:slot_get_token: Slot(id=0x0): get token
P:18087; T:0x139882926028928 18:03:23.856 [opensc-pkcs11] slot.c:490:slot_get_token: Slot-get-token returns OK
P:18087; T:0x139882926028928 18:03:23.856 [opensc-pkcs11] slot.c:472:slot_get_token: Slot(id=0x0): get token
P:18087; T:0x139882926028928 18:03:23.856 [opensc-pkcs11] slot.c:490:slot_get_token: Slot-get-token returns OK
P:18087; T:0x139882926028928 18:03:23.856 [opensc-pkcs11] slot.c:472:slot_get_token: Slot(id=0x0): get token
P:18087; T:0x139882926028928 18:03:23.856 [opensc-pkcs11] slot.c:490:slot_get_token: Slot-get-token returns OK
P:18087; T:0x139882926028928 18:03:23.856 [opensc-pkcs11] slot.c:472:slot_get_token: Slot(id=0x0): get token
P:18087; T:0x139882926028928 18:03:23.856 [opensc-pkcs11] slot.c:490:slot_get_token: Slot-get-token returns OK
P:18087; T:0x139882926028928 18:03:23.856 [opensc-pkcs11] slot.c:472:slot_get_token: Slot(id=0x0): get token
P:18087; T:0x139882926028928 18:03:23.856 [opensc-pkcs11] slot.c:490:slot_get_token: Slot-get-token returns OK
P:18087; T:0x139882926028928 18:03:23.856 [opensc-pkcs11] slot.c:472:slot_get_token: Slot(id=0x0): get token
P:18087; T:0x139882926028928 18:03:23.856 [opensc-pkcs11] slot.c:490:slot_get_token: Slot-get-token returns OK
P:18087; T:0x139882926028928 18:03:23.856 [opensc-pkcs11] slot.c:472:slot_get_token: Slot(id=0x0): get token
P:18087; T:0x139882926028928 18:03:23.856 [opensc-pkcs11] slot.c:490:slot_get_token: Slot-get-token returns OK
P:18087; T:0x139882926028928 18:03:23.856 [opensc-pkcs11] slot.c:472:slot_get_token: Slot(id=0x0): get token
P:18087; T:0x139882926028928 18:03:23.856 [opensc-pkcs11] slot.c:490:slot_get_token: Slot-get-token returns OK
P:18087; T:0x139882926028928 18:03:23.856 [opensc-pkcs11] slot.c:472:slot_get_token: Slot(id=0x0): get token
P:18087; T:0x139882926028928 18:03:23.856 [opensc-pkcs11] slot.c:490:slot_get_token: Slot-get-token returns OK
P:18087; T:0x139882926028928 18:03:23.856 [opensc-pkcs11] slot.c:472:slot_get_token: Slot(id=0x0): get token
P:18087; T:0x139882926028928 18:03:23.856 [opensc-pkcs11] slot.c:490:slot_get_token: Slot-get-token returns OK
P:18087; T:0x139882926028928 18:03:23.856 [opensc-pkcs11] slot.c:472:slot_get_token: Slot(id=0x0): get token
P:18087; T:0x139882926028928 18:03:23.856 [opensc-pkcs11] slot.c:490:slot_get_token: Slot-get-token returns OK
P:18087; T:0x139882926028928 18:03:23.856 [opensc-pkcs11] pkcs11-object.c:1161:C_GenerateKeyPair: C_GenerateKeyPair(), PrivKey attrs: CKA_CLASS = CKO_PRIVATE_KEY
P:18087; T:0x139882926028928 18:03:23.856 [opensc-pkcs11] pkcs11-object.c:1161:C_GenerateKeyPair: C_GenerateKeyPair(), PrivKey attrs: CKA_TOKEN = TRUE
P:18087; T:0x139882926028928 18:03:23.856 [opensc-pkcs11] pkcs11-object.c:1161:C_GenerateKeyPair: C_GenerateKeyPair(), PrivKey attrs: CKA_PRIVATE = TRUE
P:18087; T:0x139882926028928 18:03:23.856 [opensc-pkcs11] pkcs11-object.c:1161:C_GenerateKeyPair: C_GenerateKeyPair(), PrivKey attrs: CKA_SENSITIVE = TRUE
P:18087; T:0x139882926028928 18:03:23.856 [opensc-pkcs11] pkcs11-object.c:1161:C_GenerateKeyPair: C_GenerateKeyPair(), PrivKey attrs: CKA_SIGN = TRUE
P:18087; T:0x139882926028928 18:03:23.856 [opensc-pkcs11] pkcs11-object.c:1161:C_GenerateKeyPair: C_GenerateKeyPair(), PrivKey attrs: CKA_DERIVE = TRUE
P:18087; T:0x139882926028928 18:03:23.856 [opensc-pkcs11] pkcs11-object.c:1161:C_GenerateKeyPair: C_GenerateKeyPair(), PrivKey attrs: CKA_KEY_TYPE = CKK_ECDSA
P:18087; T:0x139882926028928 18:03:23.856 [opensc-pkcs11] pkcs11-object.c:1161:C_GenerateKeyPair: C_GenerateKeyPair(), PrivKey attrs: CKA_ID = 01
P:18087; T:0x139882926028928 18:03:23.856 [opensc-pkcs11] pkcs11-object.c:1162:C_GenerateKeyPair: C_GenerateKeyPair(), PubKey attrs: CKA_CLASS = CKO_PUBLIC_KEY
P:18087; T:0x139882926028928 18:03:23.856 [opensc-pkcs11] pkcs11-object.c:1162:C_GenerateKeyPair: C_GenerateKeyPair(), PubKey attrs: CKA_TOKEN = TRUE
P:18087; T:0x139882926028928 18:03:23.856 [opensc-pkcs11] pkcs11-object.c:1162:C_GenerateKeyPair: C_GenerateKeyPair(), PubKey attrs: CKA_VERIFY = TRUE
P:18087; T:0x139882926028928 18:03:23.856 [opensc-pkcs11] pkcs11-object.c:1162:C_GenerateKeyPair: C_GenerateKeyPair(), PubKey attrs: CKA_DERIVE = TRUE
P:18087; T:0x139882926028928 18:03:23.857 [opensc-pkcs11] pkcs11-object.c:1162:C_GenerateKeyPair: C_GenerateKeyPair(), PubKey attrs: CKA_EC_PARAMS = 06082A8648CE3D030107
P:18087; T:0x139882926028928 18:03:23.857 [opensc-pkcs11] pkcs11-object.c:1162:C_GenerateKeyPair: C_GenerateKeyPair(), PubKey attrs: CKA_KEY_TYPE = CKK_ECDSA
P:18087; T:0x139882926028928 18:03:23.857 [opensc-pkcs11] pkcs11-object.c:1162:C_GenerateKeyPair: C_GenerateKeyPair(), PubKey attrs: CKA_ID = 01
P:18087; T:0x139882926028928 18:03:23.857 [opensc-pkcs11] pkcs11-object.c:1162:C_GenerateKeyPair: C_GenerateKeyPair(), PubKey attrs: CKA_PRIVATE = FALSE
P:18087; T:0x139882926028928 18:03:23.857 [opensc-pkcs11] framework-pkcs15.c:3181:pkcs15_gen_keypair: Key pair generation, mech = 0x1040
P:18087; T:0x139882926028928 18:03:23.857 [opensc-pkcs11] card.c:471:sc_lock: called
P:18087; T:0x139882926028928 18:03:23.857 [opensc-pkcs11] reader-pcsc.c:688:pcsc_lock: called
P:18087; T:0x139882926028928 18:03:23.857 [opensc-pkcs11] card.c:520:sc_lock: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.857 [opensc-pkcs11] pkcs15-lib.c:321:sc_pkcs15init_bind: called
P:18087; T:0x139882926028928 18:03:23.857 [opensc-pkcs11] card.c:1062:sc_card_ctl: called
P:18087; T:0x139882926028928 18:03:23.857 [opensc-pkcs11] card.c:1069:sc_card_ctl: card_ctl(4) not supported
P:18087; T:0x139882926028928 18:03:23.857 [opensc-pkcs11] card.c:851:sc_select_file: called; type=2, path=3f0050154946
P:18087; T:0x139882926028928 18:03:23.857 [opensc-pkcs11] apdu.c:550:sc_transmit_apdu: called
P:18087; T:0x139882926028928 18:03:23.857 [opensc-pkcs11] card.c:471:sc_lock: called
P:18087; T:0x139882926028928 18:03:23.857 [opensc-pkcs11] card.c:520:sc_lock: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.857 [opensc-pkcs11] apdu.c:515:sc_transmit: called
P:18087; T:0x139882926028928 18:03:23.857 [opensc-pkcs11] apdu.c:363:sc_single_transmit: called
P:18087; T:0x139882926028928 18:03:23.857 [opensc-pkcs11] apdu.c:370:sc_single_transmit: CLA:0, INS:A4, P1:8, P2:0, data(4) 0x7ffe79a39170
P:18087; T:0x139882926028928 18:03:23.857 [opensc-pkcs11] reader-pcsc.c:325:pcsc_transmit: reader 'Identiv uTrust 3512 SAM slot Token [CCID Interface] (55512030608814) 00 00'
P:18087; T:0x139882926028928 18:03:23.857 [opensc-pkcs11] reader-pcsc.c:326:pcsc_transmit:
Outgoing APDU (10 bytes):
00 A4 08 00 04 50 15 49 46 00 .....P.IF.

P:18087; T:0x139882926028928 18:03:23.857 [opensc-pkcs11] reader-pcsc.c:244:pcsc_internal_transmit: called
P:18087; T:0x139882926028928 18:03:23.868 [opensc-pkcs11] reader-pcsc.c:335:pcsc_transmit:
Incoming APDU (2 bytes):
6A 86 j.

P:18087; T:0x139882926028928 18:03:23.868 [opensc-pkcs11] apdu.c:382:sc_single_transmit: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.868 [opensc-pkcs11] apdu.c:539:sc_transmit: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.868 [opensc-pkcs11] card.c:530:sc_unlock: called
P:18087; T:0x139882926028928 18:03:23.868 [opensc-pkcs11] iso7816.c:128:iso7816_check_sw: Incorrect parameters P1-P2
P:18087; T:0x139882926028928 18:03:23.868 [opensc-pkcs11] iso7816.c:627:iso7816_select_file: returning with: -1205 (Incorrect parameters in APDU)
P:18087; T:0x139882926028928 18:03:23.868 [opensc-pkcs11] card.c:873:sc_select_file: 'SELECT' error: -1205 (Incorrect parameters in APDU)
P:18087; T:0x139882926028928 18:03:23.868 [opensc-pkcs11] profile.c:334:sc_profile_load: called
P:18087; T:0x139882926028928 18:03:23.868 [opensc-pkcs11] profile.c:354:sc_profile_load: Using profile directory '/usr/share/opensc'.
P:18087; T:0x139882926028928 18:03:23.868 [opensc-pkcs11] profile.c:362:sc_profile_load: Trying profile file /usr/share/opensc/pkcs15.profile
P:18087; T:0x139882926028928 18:03:23.868 [opensc-pkcs11] profile.c:367:sc_profile_load: profile /usr/share/opensc/pkcs15.profile loaded ok
P:18087; T:0x139882926028928 18:03:23.868 [opensc-pkcs11] profile.c:381:sc_profile_load: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.868 [opensc-pkcs11] profile.c:334:sc_profile_load: called
P:18087; T:0x139882926028928 18:03:23.868 [opensc-pkcs11] profile.c:354:sc_profile_load: Using profile directory '/usr/share/opensc'.
P:18087; T:0x139882926028928 18:03:23.868 [opensc-pkcs11] profile.c:362:sc_profile_load: Trying profile file /usr/share/opensc/sc-hsm.profile
P:18087; T:0x139882926028928 18:03:23.868 [opensc-pkcs11] profile.c:367:sc_profile_load: profile /usr/share/opensc/sc-hsm.profile loaded ok
P:18087; T:0x139882926028928 18:03:23.868 [opensc-pkcs11] profile.c:381:sc_profile_load: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.868 [opensc-pkcs11] profile.c:393:sc_profile_finish: called
P:18087; T:0x139882926028928 18:03:23.868 [opensc-pkcs11] profile.c:436:sc_profile_finish: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.868 [opensc-pkcs11] pkcs15-lib.c:428:sc_pkcs15init_bind: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.868 [opensc-pkcs11] pkcs15-lib.c:757:sc_pkcs15init_finalize_profile: called
P:18087; T:0x139882926028928 18:03:23.868 [opensc-pkcs11] pkcs15-lib.c:772:sc_pkcs15init_finalize_profile: Finalize profile with application 'default'
P:18087; T:0x139882926028928 18:03:23.869 [opensc-pkcs11] profile.c:393:sc_profile_finish: called
P:18087; T:0x139882926028928 18:03:23.869 [opensc-pkcs11] profile.c:436:sc_profile_finish: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.869 [opensc-pkcs11] pkcs15-lib.c:775:sc_pkcs15init_finalize_profile: sc_pkcs15init_finalize_profile() returns 0
P:18087; T:0x139882926028928 18:03:23.869 [opensc-pkcs11] pkcs15-lib.c:776:sc_pkcs15init_finalize_profile: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.869 [opensc-pkcs11] pkcs15-lib.c:458:sc_pkcs15init_set_p15card: called
P:18087; T:0x139882926028928 18:03:23.869 [opensc-pkcs11] pkcs15-lib.c:491:sc_pkcs15init_set_p15card: sc_pkcs15init_set_p15card() returns
P:18087; T:0x139882926028928 18:03:23.869 [opensc-pkcs11] framework-pkcs15.c:3334:pkcs15_gen_keypair: Try on-card key pair generation
P:18087; T:0x139882926028928 18:03:23.869 [opensc-pkcs11] pkcs15-lib.c:1537:sc_pkcs15init_generate_key: called
P:18087; T:0x139882926028928 18:03:23.869 [opensc-pkcs11] pkcs15-pubkey.c:1517:sc_pkcs15_fix_ec_parameters: called
P:18087; T:0x139882926028928 18:03:23.869 [opensc-pkcs11] asn1.c:1829:asn1_encode_entry:  encoding 'oid'
P:18087; T:0x139882926028928 18:03:23.869 [opensc-pkcs11] asn1.c:1835:asn1_encode_entry:  type=6, tag=0x06, parm=0x7ffe79a3a0b0, len=0
P:18087; T:0x139882926028928 18:03:23.869 [opensc-pkcs11] asn1.c:2027:asn1_encode_entry:  length of encoded item=10
P:18087; T:0x139882926028928 18:03:23.869 [opensc-pkcs11] asn1.c:1829:asn1_encode_entry:  encoding 'oid'
P:18087; T:0x139882926028928 18:03:23.869 [opensc-pkcs11] asn1.c:1835:asn1_encode_entry:  type=6, tag=0x06, parm=0x7ffe79a3a0b0, len=0
P:18087; T:0x139882926028928 18:03:23.869 [opensc-pkcs11] asn1.c:2027:asn1_encode_entry:  length of encoded item=10
P:18087; T:0x139882926028928 18:03:23.869 [opensc-pkcs11] asn1.c:1829:asn1_encode_entry:  encoding 'oid'
P:18087; T:0x139882926028928 18:03:23.869 [opensc-pkcs11] asn1.c:1835:asn1_encode_entry:  type=6, tag=0x06, parm=0x7ffe79a3a0b0, len=0
P:18087; T:0x139882926028928 18:03:23.869 [opensc-pkcs11] asn1.c:2027:asn1_encode_entry:  length of encoded item=10
P:18087; T:0x139882926028928 18:03:23.869 [opensc-pkcs11] asn1.c:1829:asn1_encode_entry:  encoding 'oid'
P:18087; T:0x139882926028928 18:03:23.869 [opensc-pkcs11] asn1.c:1835:asn1_encode_entry:  type=6, tag=0x06, parm=0x7ffe79a3a0b0, len=0
P:18087; T:0x139882926028928 18:03:23.869 [opensc-pkcs11] asn1.c:2027:asn1_encode_entry:  length of encoded item=10
P:18087; T:0x139882926028928 18:03:23.869 [opensc-pkcs11] asn1.c:1829:asn1_encode_entry:  encoding 'oid'
P:18087; T:0x139882926028928 18:03:23.869 [opensc-pkcs11] asn1.c:1835:asn1_encode_entry:  type=6, tag=0x06, parm=0x7ffe79a3a0b0, len=0
P:18087; T:0x139882926028928 18:03:23.869 [opensc-pkcs11] asn1.c:2027:asn1_encode_entry:  length of encoded item=7
P:18087; T:0x139882926028928 18:03:23.869 [opensc-pkcs11] asn1.c:1829:asn1_encode_entry:  encoding 'oid'
P:18087; T:0x139882926028928 18:03:23.869 [opensc-pkcs11] asn1.c:1835:asn1_encode_entry:  type=6, tag=0x06, parm=0x7ffe79a3a0b0, len=0
P:18087; T:0x139882926028928 18:03:23.869 [opensc-pkcs11] asn1.c:2027:asn1_encode_entry:  length of encoded item=7
P:18087; T:0x139882926028928 18:03:23.869 [opensc-pkcs11] asn1.c:1829:asn1_encode_entry:  encoding 'oid'
P:18087; T:0x139882926028928 18:03:23.869 [opensc-pkcs11] asn1.c:1835:asn1_encode_entry:  type=6, tag=0x06, parm=0x7ffe79a3a0b0, len=0
P:18087; T:0x139882926028928 18:03:23.869 [opensc-pkcs11] asn1.c:2027:asn1_encode_entry:  length of encoded item=10
P:18087; T:0x139882926028928 18:03:23.869 [opensc-pkcs11] pkcs15-pubkey.c:1541:sc_pkcs15_fix_ec_parameters: Found known curve 'secp256r1'
P:18087; T:0x139882926028928 18:03:23.869 [opensc-pkcs11] pkcs15-pubkey.c:1547:sc_pkcs15_fix_ec_parameters: Curve name: 'secp256r1'
P:18087; T:0x139882926028928 18:03:23.869 [opensc-pkcs11] pkcs15-pubkey.c:1555:sc_pkcs15_fix_ec_parameters: Curve length 256
P:18087; T:0x139882926028928 18:03:23.869 [opensc-pkcs11] pkcs15-pubkey.c:1582:sc_pkcs15_fix_ec_parameters: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.869 [opensc-pkcs11] pkcs15-lib.c:2484:check_keygen_params_consistency: EC parameters: 06082A8648CE3D030107
P:18087; T:0x139882926028928 18:03:23.870 [opensc-pkcs11] pkcs15-lib.c:2498:check_keygen_params_consistency: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.870 [opensc-pkcs11] pkcs15-lib.c:2521:check_key_compatibility: called
P:18087; T:0x139882926028928 18:03:23.870 [opensc-pkcs11] pkcs15-lib.c:2555:check_key_compatibility: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.870 [opensc-pkcs11] pkcs15-lib.c:1234:sc_pkcs15init_init_prkdf: called
P:18087; T:0x139882926028928 18:03:23.870 [opensc-pkcs11] pkcs15-lib.c:2862:select_id: called
P:18087; T:0x139882926028928 18:03:23.870 [opensc-pkcs11] pkcs15-lib.c:2872:select_id: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.870 [opensc-pkcs11] pkcs15-lib.c:2994:select_object_path: called
P:18087; T:0x139882926028928 18:03:23.870 [opensc-pkcs11] pkcs15-lib.c:3019:select_object_path: key-domain.private-key @e82b0601040181c31f0201:: (auth_id.len=1)
P:18087; T:0x139882926028928 18:03:23.870 [opensc-pkcs11] profile.c:687:sc_profile_instantiate_template: Template key-domain not found
P:18087; T:0x139882926028928 18:03:23.870 [opensc-pkcs11] pkcs15-lib.c:3031:select_object_path: get instance 0 of 'template-private-key'
P:18087; T:0x139882926028928 18:03:23.870 [opensc-pkcs11] profile.c:575:sc_profile_get_file_instance: called
P:18087; T:0x139882926028928 18:03:23.870 [opensc-pkcs11] profile.c:576:sc_profile_get_file_instance: try to get 'template-private-key' file instance
P:18087; T:0x139882926028928 18:03:23.870 [opensc-pkcs11] profile.c:579:sc_profile_get_file_instance: returning with: -1201 (File not found)
P:18087; T:0x139882926028928 18:03:23.870 [opensc-pkcs11] pkcs15-lib.c:3034:select_object_path: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.870 [opensc-pkcs11] pkcs15-lib.c:1363:sc_pkcs15init_init_prkdf: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.870 [opensc-pkcs11] pkcs15-lib.c:1474:_pkcd15init_set_aux_md_data: called
P:18087; T:0x139882926028928 18:03:23.870 [opensc-pkcs11] pkcs15-lib.c:1477:_pkcd15init_set_aux_md_data: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.870 [opensc-pkcs11] pkcs15-sc-hsm.c:116:sc_hsm_create_key: called
P:18087; T:0x139882926028928 18:03:23.870 [opensc-pkcs11] pkcs15-sc-hsm.c:117:sc_hsm_create_key: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.870 [opensc-pkcs11] pkcs15-sc-hsm.c:260:sc_hsm_generate_key: called
P:18087; T:0x139882926028928 18:03:23.870 [opensc-pkcs11] pkcs15-sc-hsm.c:137:sc_hsm_determine_free_id: called
P:18087; T:0x139882926028928 18:03:23.870 [opensc-pkcs11] card.c:566:sc_list_files: called
P:18087; T:0x139882926028928 18:03:23.870 [opensc-pkcs11] apdu.c:550:sc_transmit_apdu: called
P:18087; T:0x139882926028928 18:03:23.870 [opensc-pkcs11] card.c:471:sc_lock: called
P:18087; T:0x139882926028928 18:03:23.870 [opensc-pkcs11] card.c:520:sc_lock: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.870 [opensc-pkcs11] apdu.c:515:sc_transmit: called
P:18087; T:0x139882926028928 18:03:23.870 [opensc-pkcs11] apdu.c:363:sc_single_transmit: called
P:18087; T:0x139882926028928 18:03:23.870 [opensc-pkcs11] apdu.c:370:sc_single_transmit: CLA:80, INS:58, P1:0, P2:0, data(0) (nil)
P:18087; T:0x139882926028928 18:03:23.870 [opensc-pkcs11] reader-pcsc.c:325:pcsc_transmit: reader 'Identiv uTrust 3512 SAM slot Token [CCID Interface] (55512030608814) 00 00'
P:18087; T:0x139882926028928 18:03:23.870 [opensc-pkcs11] reader-pcsc.c:326:pcsc_transmit:
Outgoing APDU (7 bytes):
80 58 00 00 00 00 00 .X.....

P:18087; T:0x139882926028928 18:03:23.871 [opensc-pkcs11] reader-pcsc.c:244:pcsc_internal_transmit: called
P:18087; T:0x139882926028928 18:03:23.884 [opensc-pkcs11] reader-pcsc.c:335:pcsc_transmit:
Incoming APDU (6 bytes):
2F 02 CC 00 90 00 /.....

P:18087; T:0x139882926028928 18:03:23.884 [opensc-pkcs11] apdu.c:382:sc_single_transmit: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.884 [opensc-pkcs11] apdu.c:539:sc_transmit: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.884 [opensc-pkcs11] card.c:530:sc_unlock: called
P:18087; T:0x139882926028928 18:03:23.884 [opensc-pkcs11] card-sc-hsm.c:932:sc_hsm_list_files: returning with: 4
P:18087; T:0x139882926028928 18:03:23.884 [opensc-pkcs11] card.c:572:sc_list_files: returning with: 4
P:18087; T:0x139882926028928 18:03:23.884 [opensc-pkcs11] pkcs15-sc-hsm.c:149:sc_hsm_determine_free_id: returning with: 1
P:18087; T:0x139882926028928 18:03:23.884 [opensc-pkcs11] pkcs15-sc-hsm.c:184:sc_hsm_encode_gakp_ec: called
P:18087; T:0x139882926028928 18:03:23.884 [opensc-pkcs11] pkcs15-sc-hsm.c:241:sc_hsm_encode_gakp_ec: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.884 [opensc-pkcs11] asn1.c:1829:asn1_encode_entry: encoding 'certificate'
P:18087; T:0x139882926028928 18:03:23.884 [opensc-pkcs11] asn1.c:1835:asn1_encode_entry: type=129, tag=0x60001f21, parm=0x7ffe79a39ea0, len=0
P:18087; T:0x139882926028928 18:03:23.884 [opensc-pkcs11] asn1.c:1829:asn1_encode_entry:  encoding 'certificateBody'
P:18087; T:0x139882926028928 18:03:23.884 [opensc-pkcs11] asn1.c:1835:asn1_encode_entry:  type=129, tag=0x60001f4e, parm=0x7ffe79a39dd0, len=0
P:18087; T:0x139882926028928 18:03:23.884 [opensc-pkcs11] asn1.c:1829:asn1_encode_entry:   encoding 'certificateProfileIdentifier'
P:18087; T:0x139882926028928 18:03:23.884 [opensc-pkcs11] asn1.c:1835:asn1_encode_entry:   type=2, tag=0x40001f29, parm=0x7ffe79a39ff0, len=0
P:18087; T:0x139882926028928 18:03:23.884 [opensc-pkcs11] asn1.c:2027:asn1_encode_entry:   length of encoded item=4
P:18087; T:0x139882926028928 18:03:23.884 [opensc-pkcs11] asn1.c:1829:asn1_encode_entry:   encoding 'certificationAuthorityReference'
P:18087; T:0x139882926028928 18:03:23.884 [opensc-pkcs11] asn1.c:1835:asn1_encode_entry:   type=19, tag=0x40000002, parm=0x7ffe79a39ff4, len=9
P:18087; T:0x139882926028928 18:03:23.885 [opensc-pkcs11] asn1.c:2027:asn1_encode_entry:   length of encoded item=11
P:18087; T:0x139882926028928 18:03:23.885 [opensc-pkcs11] asn1.c:1829:asn1_encode_entry:   encoding 'publicKey'
P:18087; T:0x139882926028928 18:03:23.885 [opensc-pkcs11] asn1.c:1835:asn1_encode_entry:   type=129, tag=0x60001f49, parm=0x7ffe79a39c40, len=0
P:18087; T:0x139882926028928 18:03:23.885 [opensc-pkcs11] asn1.c:1829:asn1_encode_entry:    encoding 'publicKeyOID'
P:18087; T:0x139882926028928 18:03:23.885 [opensc-pkcs11] asn1.c:1835:asn1_encode_entry:    type=6, tag=0x06, parm=0x7ffe79a3a010, len=0
P:18087; T:0x139882926028928 18:03:23.885 [opensc-pkcs11] asn1.c:2027:asn1_encode_entry:    length of encoded item=12
P:18087; T:0x139882926028928 18:03:23.885 [opensc-pkcs11] asn1.c:1829:asn1_encode_entry:    encoding 'primeOrModulus'
P:18087; T:0x139882926028928 18:03:23.885 [opensc-pkcs11] asn1.c:1835:asn1_encode_entry:    type=4, tag=0x80000001, parm=0x16bda20, len=32
P:18087; T:0x139882926028928 18:03:23.885 [opensc-pkcs11] asn1.c:2027:asn1_encode_entry:    length of encoded item=34
P:18087; T:0x139882926028928 18:03:23.885 [opensc-pkcs11] asn1.c:1829:asn1_encode_entry:    encoding 'coefficientAorExponent'
P:18087; T:0x139882926028928 18:03:23.885 [opensc-pkcs11] asn1.c:1835:asn1_encode_entry:    type=4, tag=0x80000002, parm=0x16c94b0, len=32
P:18087; T:0x139882926028928 18:03:23.885 [opensc-pkcs11] asn1.c:2027:asn1_encode_entry:    length of encoded item=34
P:18087; T:0x139882926028928 18:03:23.885 [opensc-pkcs11] asn1.c:1829:asn1_encode_entry:    encoding 'coefficientB'
P:18087; T:0x139882926028928 18:03:23.885 [opensc-pkcs11] asn1.c:1835:asn1_encode_entry:    type=4, tag=0x80000003, parm=0x16c2bf0, len=32
P:18087; T:0x139882926028928 18:03:23.885 [opensc-pkcs11] asn1.c:2027:asn1_encode_entry:    length of encoded item=34
P:18087; T:0x139882926028928 18:03:23.885 [opensc-pkcs11] asn1.c:1829:asn1_encode_entry:    encoding 'basePointG'
P:18087; T:0x139882926028928 18:03:23.885 [opensc-pkcs11] asn1.c:1835:asn1_encode_entry:    type=4, tag=0x80000004, parm=0x16c2c20, len=65
P:18087; T:0x139882926028928 18:03:23.885 [opensc-pkcs11] asn1.c:2027:asn1_encode_entry:    length of encoded item=67
P:18087; T:0x139882926028928 18:03:23.885 [opensc-pkcs11] asn1.c:1829:asn1_encode_entry:    encoding 'order'
P:18087; T:0x139882926028928 18:03:23.885 [opensc-pkcs11] asn1.c:1835:asn1_encode_entry:    type=4, tag=0x80000005, parm=0x16c2c70, len=32
P:18087; T:0x139882926028928 18:03:23.885 [opensc-pkcs11] asn1.c:2027:asn1_encode_entry:    length of encoded item=34
P:18087; T:0x139882926028928 18:03:23.885 [opensc-pkcs11] asn1.c:1829:asn1_encode_entry:    encoding 'publicPoint' (not present)
P:18087; T:0x139882926028928 18:03:23.885 [opensc-pkcs11] asn1.c:2027:asn1_encode_entry:    length of encoded item=0
P:18087; T:0x139882926028928 18:03:23.885 [opensc-pkcs11] asn1.c:1829:asn1_encode_entry:    encoding 'cofactor'
P:18087; T:0x139882926028928 18:03:23.885 [opensc-pkcs11] asn1.c:1835:asn1_encode_entry:    type=4, tag=0x80000007, parm=0x16c2ca0, len=1
P:18087; T:0x139882926028928 18:03:23.885 [opensc-pkcs11] asn1.c:2027:asn1_encode_entry:    length of encoded item=3
P:18087; T:0x139882926028928 18:03:23.885 [opensc-pkcs11] asn1.c:1829:asn1_encode_entry:    encoding 'modulusSize' (not present)
P:18087; T:0x139882926028928 18:03:23.885 [opensc-pkcs11] asn1.c:2027:asn1_encode_entry:    length of encoded item=0
P:18087; T:0x139882926028928 18:03:23.885 [opensc-pkcs11] asn1.c:2027:asn1_encode_entry:   length of encoded item=222
P:18087; T:0x139882926028928 18:03:23.885 [opensc-pkcs11] asn1.c:1829:asn1_encode_entry:   encoding 'certificateHolderReference'
P:18087; T:0x139882926028928 18:03:23.885 [opensc-pkcs11] asn1.c:1835:asn1_encode_entry:   type=19, tag=0x40001f20, parm=0x7ffe79a3a0c4, len=16
P:18087; T:0x139882926028928 18:03:23.885 [opensc-pkcs11] asn1.c:2027:asn1_encode_entry:   length of encoded item=19
P:18087; T:0x139882926028928 18:03:23.885 [opensc-pkcs11] asn1.c:2027:asn1_encode_entry:  length of encoded item=261
P:18087; T:0x139882926028928 18:03:23.885 [opensc-pkcs11] asn1.c:1829:asn1_encode_entry:  encoding 'signature' (not present)
P:18087; T:0x139882926028928 18:03:23.885 [opensc-pkcs11] asn1.c:2027:asn1_encode_entry:  length of encoded item=0
P:18087; T:0x139882926028928 18:03:23.886 [opensc-pkcs11] asn1.c:2027:asn1_encode_entry: length of encoded item=266
P:18087; T:0x139882926028928 18:03:23.886 [opensc-pkcs11] pkcs15-sc-hsm.c:816:sc_pkcs15emu_sc_hsm_encode_cvc: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.886 [opensc-pkcs11] card.c:1062:sc_card_ctl: called
P:18087; T:0x139882926028928 18:03:23.886 [opensc-pkcs11] card-sc-hsm.c:1688:sc_hsm_generate_keypair: called
P:18087; T:0x139882926028928 18:03:23.886 [opensc-pkcs11] apdu.c:550:sc_transmit_apdu: called
P:18087; T:0x139882926028928 18:03:23.886 [opensc-pkcs11] card.c:471:sc_lock: called
P:18087; T:0x139882926028928 18:03:23.886 [opensc-pkcs11] card.c:520:sc_lock: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:23.886 [opensc-pkcs11] apdu.c:515:sc_transmit: called
P:18087; T:0x139882926028928 18:03:23.886 [opensc-pkcs11] apdu.c:363:sc_single_transmit: called
P:18087; T:0x139882926028928 18:03:23.886 [opensc-pkcs11] apdu.c:370:sc_single_transmit: CLA:0, INS:46, P1:1, P2:0, data(256) 0x16c4f9a
P:18087; T:0x139882926028928 18:03:23.886 [opensc-pkcs11] reader-pcsc.c:325:pcsc_transmit: reader 'Identiv uTrust 3512 SAM slot Token [CCID Interface] (55512030608814) 00 00'
P:18087; T:0x139882926028928 18:03:23.886 [opensc-pkcs11] reader-pcsc.c:326:pcsc_transmit:
Outgoing APDU (265 bytes):
00 46 01 00 00 01 00 5F 29 01 00 42 09 55 54 43 .F....._)..B.UTC
41 30 30 30 30 31 7F 49 81 DA 06 0A 04 00 7F 00 A00001.I........
07 02 02 02 02 03 81 20 FF FF FF FF 00 00 00 01 ....... ........
00 00 00 00 00 00 00 00 00 00 00 00 FF FF FF FF ................
FF FF FF FF FF FF FF FF 82 20 FF FF FF FF 00 00 ......... ......
00 01 00 00 00 00 00 00 00 00 00 00 00 00 FF FF ................
FF FF FF FF FF FF FF FF FF FC 83 20 5A C6 35 D8 ........... Z.5.
AA 3A 93 E7 B3 EB BD 55 76 98 86 BC 65 1D 06 B0 .:.....Uv...e...
CC 53 B0 F6 3B CE 3C 3E 27 D2 60 4B 84 41 04 6B .S..;.<>'.`K.A.k
17 D1 F2 E1 2C 42 47 F8 BC E6 E5 63 A4 40 F2 77 ....,BG....c.@.w
03 7D 81 2D EB 33 A0 F4 A1 39 45 D8 98 C2 96 4F .}.-.3...9E....O
E3 42 E2 FE 1A 7F 9B 8E E7 EB 4A 7C 0F 9E 16 2B .B........J|...+
CE 33 57 6B 31 5E CE CB B6 40 68 37 BF 51 F5 85 .3Wk1^...@h7.Q..
20 FF FF FF FF 00 00 00 00 FF FF FF FF FF FF FF  ...............
FF BC E6 FA AD A7 17 9E 84 F3 B9 CA C2 FC 63 25 ..............c%
51 87 01 01 5F 20 10 44 45 43 43 30 39 30 30 31 Q..._ .DECC09001
35 34 30 30 30 30 31 00 00                      5400001..

P:18087; T:0x139882926028928 18:03:23.886 [opensc-pkcs11] reader-pcsc.c:244:pcsc_internal_transmit: called
P:18087; T:0x139882926028928 18:03:27.150 [opensc-pkcs11] reader-pcsc.c:273:pcsc_internal_transmit: Identiv uTrust 3512 SAM slot Token [CCID Interface] (55512030608814) 00 00:SCardTransmit/Control failed: 0x80100016
P:18087; T:0x139882926028928 18:03:27.150 [opensc-pkcs11] reader-pcsc.c:470:pcsc_detect_card_presence: called
P:18087; T:0x139882926028928 18:03:27.150 [opensc-pkcs11] reader-pcsc.c:362:refresh_attributes: Identiv uTrust 3512 SAM slot Token [CCID Interface] (55512030608814) 00 00 check
P:18087; T:0x139882926028928 18:03:27.152 [opensc-pkcs11] reader-pcsc.c:387:refresh_attributes: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:27.152 [opensc-pkcs11] reader-pcsc.c:478:pcsc_detect_card_presence: returning with: 1
P:18087; T:0x139882926028928 18:03:27.152 [opensc-pkcs11] reader-pcsc.c:470:pcsc_detect_card_presence: called
P:18087; T:0x139882926028928 18:03:27.152 [opensc-pkcs11] reader-pcsc.c:362:refresh_attributes: Identiv uTrust 3512 SAM slot Token [CCID Interface] (55512030608814) 00 00 check
P:18087; T:0x139882926028928 18:03:27.153 [opensc-pkcs11] reader-pcsc.c:387:refresh_attributes: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:27.153 [opensc-pkcs11] reader-pcsc.c:478:pcsc_detect_card_presence: returning with: 1
P:18087; T:0x139882926028928 18:03:27.153 [opensc-pkcs11] reader-pcsc.c:332:pcsc_transmit: unable to transmit
P:18087; T:0x139882926028928 18:03:27.153 [opensc-pkcs11] apdu.c:380:sc_single_transmit: unable to transmit APDU: -1107 (Transmit failed)
P:18087; T:0x139882926028928 18:03:27.153 [opensc-pkcs11] apdu.c:518:sc_transmit: transmit APDU failed: -1107 (Transmit failed)
P:18087; T:0x139882926028928 18:03:27.153 [opensc-pkcs11] card.c:530:sc_unlock: called
P:18087; T:0x139882926028928 18:03:27.153 [opensc-pkcs11] card-sc-hsm.c:1701:sc_hsm_generate_keypair: APDU transmit failed: -1107 (Transmit failed)
P:18087; T:0x139882926028928 18:03:27.153 [opensc-pkcs11] card.c:1072:sc_card_ctl: returning with: -1107 (Transmit failed)
P:18087; T:0x139882926028928 18:03:27.153 [opensc-pkcs11] pkcs15-sc-hsm.c:338:sc_hsm_generate_key: returning with: -1107 (Transmit failed)
P:18087; T:0x139882926028928 18:03:27.153 [opensc-pkcs11] pkcs15-lib.c:1616:sc_pkcs15init_generate_key: Failed to generate key: -1107 (Transmit failed)
P:18087; T:0x139882926028928 18:03:27.153 [opensc-pkcs11] pkcs15-lib.c:1688:sc_pkcs15init_generate_key: returning with: -1107 (Transmit failed)
P:18087; T:0x139882926028928 18:03:27.153 [opensc-pkcs11] framework-pkcs15.c:3346:pkcs15_gen_keypair: sc_pkcs15init_generate_key returned -1107
P:18087; T:0x139882926028928 18:03:27.153 [opensc-pkcs11] misc.c:71:sc_to_cryptoki_error_common: libopensc return value: -1107 (Transmit failed)
P:18087; T:0x139882926028928 18:03:27.153 [opensc-pkcs11] pkcs15-lib.c:438:sc_pkcs15init_unbind: called
P:18087; T:0x139882926028928 18:03:27.153 [opensc-pkcs11] pkcs15-lib.c:439:sc_pkcs15init_unbind: Pksc15init Unbind: 0:0x16ba1d0:1
P:18087; T:0x139882926028928 18:03:27.153 [opensc-pkcs11] card.c:530:sc_unlock: called
P:18087; T:0x139882926028928 18:03:27.153 [opensc-pkcs11] reader-pcsc.c:740:pcsc_unlock: called
P:18087; T:0x139882926028928 18:03:27.153 [opensc-pkcs11] pkcs11-global.c:393:C_Finalize: C_Finalize()
P:18087; T:0x139882926028928 18:03:27.154 [opensc-pkcs11] ctx.c:909:sc_cancel: called
P:18087; T:0x139882926028928 18:03:27.154 [opensc-pkcs11] reader-pcsc.c:790:pcsc_cancel: called
P:18087; T:0x139882926028928 18:03:27.154 [opensc-pkcs11] slot.c:191:card_removed: Identiv uTrust 3512 SAM slot Token [CCID Interface] (55512030608814) 00 00: card removed
P:18087; T:0x139882926028928 18:03:27.154 [opensc-pkcs11] slot.c:501:slot_token_removed: slot_token_removed(0x0)
P:18087; T:0x139882926028928 18:03:27.154 [opensc-pkcs11] pkcs11-session.c:145:sc_pkcs11_close_all_sessions: real C_CloseAllSessions(0x0) 1
P:18087; T:0x139882926028928 18:03:27.154 [opensc-pkcs11] pkcs11-session.c:109:sc_pkcs11_close_session: real C_CloseSession(0x16c1c50)
P:18087; T:0x139882926028928 18:03:27.154 [opensc-pkcs11] pkcs15-pin.c:863:sc_pkcs15_pincache_clear: called
P:18087; T:0x139882926028928 18:03:27.154 [opensc-pkcs11] apdu.c:550:sc_transmit_apdu: called
P:18087; T:0x139882926028928 18:03:27.154 [opensc-pkcs11] card.c:471:sc_lock: called
P:18087; T:0x139882926028928 18:03:27.154 [opensc-pkcs11] reader-pcsc.c:688:pcsc_lock: called
P:18087; T:0x139882926028928 18:03:27.154 [opensc-pkcs11] card.c:520:sc_lock: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:27.154 [opensc-pkcs11] apdu.c:515:sc_transmit: called
P:18087; T:0x139882926028928 18:03:27.154 [opensc-pkcs11] apdu.c:363:sc_single_transmit: called
P:18087; T:0x139882926028928 18:03:27.154 [opensc-pkcs11] apdu.c:370:sc_single_transmit: CLA:0, INS:A4, P1:4, P2:0, data(11) 0x7ffe79a3ab30
P:18087; T:0x139882926028928 18:03:27.154 [opensc-pkcs11] reader-pcsc.c:325:pcsc_transmit: reader 'Identiv uTrust 3512 SAM slot Token [CCID Interface] (55512030608814) 00 00'
P:18087; T:0x139882926028928 18:03:27.154 [opensc-pkcs11] reader-pcsc.c:326:pcsc_transmit:
Outgoing APDU (17 bytes):
00 A4 04 00 0B E8 2B 06 01 04 01 81 C3 1F 02 01 ......+.........
00                                              .

P:18087; T:0x139882926028928 18:03:27.154 [opensc-pkcs11] reader-pcsc.c:244:pcsc_internal_transmit: called
P:18087; T:0x139882926028928 18:03:27.154 [opensc-pkcs11] reader-pcsc.c:273:pcsc_internal_transmit: Identiv uTrust 3512 SAM slot Token [CCID Interface] (55512030608814) 00 00:SCardTransmit/Control failed: 0x80100016
P:18087; T:0x139882926028928 18:03:27.154 [opensc-pkcs11] reader-pcsc.c:470:pcsc_detect_card_presence: called
P:18087; T:0x139882926028928 18:03:27.154 [opensc-pkcs11] reader-pcsc.c:362:refresh_attributes: Identiv uTrust 3512 SAM slot Token [CCID Interface] (55512030608814) 00 00 check
P:18087; T:0x139882926028928 18:03:27.155 [opensc-pkcs11] reader-pcsc.c:387:refresh_attributes: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:27.155 [opensc-pkcs11] reader-pcsc.c:478:pcsc_detect_card_presence: returning with: 1
P:18087; T:0x139882926028928 18:03:27.155 [opensc-pkcs11] reader-pcsc.c:470:pcsc_detect_card_presence: called
P:18087; T:0x139882926028928 18:03:27.155 [opensc-pkcs11] reader-pcsc.c:362:refresh_attributes: Identiv uTrust 3512 SAM slot Token [CCID Interface] (55512030608814) 00 00 check
P:18087; T:0x139882926028928 18:03:27.157 [opensc-pkcs11] reader-pcsc.c:387:refresh_attributes: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:27.157 [opensc-pkcs11] reader-pcsc.c:478:pcsc_detect_card_presence: returning with: 1
P:18087; T:0x139882926028928 18:03:27.157 [opensc-pkcs11] reader-pcsc.c:332:pcsc_transmit: unable to transmit
P:18087; T:0x139882926028928 18:03:27.157 [opensc-pkcs11] apdu.c:380:sc_single_transmit: unable to transmit APDU: -1107 (Transmit failed)
P:18087; T:0x139882926028928 18:03:27.157 [opensc-pkcs11] apdu.c:518:sc_transmit: transmit APDU failed: -1107 (Transmit failed)
P:18087; T:0x139882926028928 18:03:27.157 [opensc-pkcs11] card.c:530:sc_unlock: called
P:18087; T:0x139882926028928 18:03:27.157 [opensc-pkcs11] reader-pcsc.c:740:pcsc_unlock: called
P:18087; T:0x139882926028928 18:03:27.157 [opensc-pkcs11] iso7816.c:611:iso7816_select_file: APDU transmit failed: -1107 (Transmit failed)
P:18087; T:0x139882926028928 18:03:27.157 [opensc-pkcs11] card-sc-hsm.c:181:sc_hsm_select_file_ex: Could not select SmartCard-HSM application: -1107 (Transmit failed)
P:18087; T:0x139882926028928 18:03:27.157 [opensc-pkcs11] misc.c:71:sc_to_cryptoki_error_common: libopensc return value: -1107 (Transmit failed)
P:18087; T:0x139882926028928 18:03:27.157 [opensc-pkcs11] framework-pkcs15.c:1693:pkcs15_release_token: pkcs15_release_token() not implemented
P:18087; T:0x139882926028928 18:03:27.157 [opensc-pkcs11] slot.c:501:slot_token_removed: slot_token_removed(0x1)
P:18087; T:0x139882926028928 18:03:27.157 [opensc-pkcs11] pkcs11-session.c:145:sc_pkcs11_close_all_sessions: real C_CloseAllSessions(0x1) 0
P:18087; T:0x139882926028928 18:03:27.157 [opensc-pkcs11] slot.c:501:slot_token_removed: slot_token_removed(0x2)
P:18087; T:0x139882926028928 18:03:27.157 [opensc-pkcs11] pkcs11-session.c:145:sc_pkcs11_close_all_sessions: real C_CloseAllSessions(0x2) 0
P:18087; T:0x139882926028928 18:03:27.157 [opensc-pkcs11] slot.c:501:slot_token_removed: slot_token_removed(0x3)
P:18087; T:0x139882926028928 18:03:27.157 [opensc-pkcs11] pkcs11-session.c:145:sc_pkcs11_close_all_sessions: real C_CloseAllSessions(0x3) 0
P:18087; T:0x139882926028928 18:03:27.157 [opensc-pkcs11] sc.c:340:sc_detect_card_presence: called
P:18087; T:0x139882926028928 18:03:27.157 [opensc-pkcs11] reader-pcsc.c:470:pcsc_detect_card_presence: called
P:18087; T:0x139882926028928 18:03:27.157 [opensc-pkcs11] reader-pcsc.c:362:refresh_attributes: Identiv uTrust 3512 SAM slot Token [CCID Interface] (55512030608814) 00 00 check
P:18087; T:0x139882926028928 18:03:27.158 [opensc-pkcs11] reader-pcsc.c:387:refresh_attributes: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:27.158 [opensc-pkcs11] reader-pcsc.c:478:pcsc_detect_card_presence: returning with: 1
P:18087; T:0x139882926028928 18:03:27.158 [opensc-pkcs11] sc.c:351:sc_detect_card_presence: returning with: 1
P:18087; T:0x139882926028928 18:03:27.158 [opensc-pkcs11] pkcs15.c:1331:sc_pkcs15_unbind: called
P:18087; T:0x139882926028928 18:03:27.158 [opensc-pkcs11] pkcs15-pin.c:863:sc_pkcs15_pincache_clear: called
P:18087; T:0x139882926028928 18:03:27.159 [opensc-pkcs11] misc.c:71:sc_to_cryptoki_error_common: libopensc return value: 0 (Success)
P:18087; T:0x139882926028928 18:03:27.159 [opensc-pkcs11] card.c:414:sc_disconnect_card: called
P:18087; T:0x139882926028928 18:03:27.159 [opensc-pkcs11] reader-pcsc.c:673:pcsc_disconnect: Identiv uTrust 3512 SAM slot Token [CCID Interface] (55512030608814) 00 00:SCardDisconnect returned: 0x00000000
P:18087; T:0x139882926028928 18:03:27.159 [opensc-pkcs11] card.c:434:sc_disconnect_card: returning with: 0 (Success)
P:18087; T:0x139882926028928 18:03:27.159 [opensc-pkcs11] ctx.c:934:sc_release_context: called
P:18087; T:0x139882926028928 18:03:27.159 [opensc-pkcs11] reader-pcsc.c:977:pcsc_finish: called
error: PKCS11 function C_GenerateKeyPair failed: rv = CKR_GENERAL_ERROR (0x5)
Aborting.