Problems formating Crypto Smart Card ACOS5-32

[Irmo (unregistered)]

I have this hardware:
1. SmartCard - Crypto Smart Card ACOS5-32 (http://www.acs.com.hk)
2. Reader - ACR38F (http://www.acs.com.hk)
OS tested "Windows Vista busines 32" and "Windows XP Professional SP2"

Problem:
Dekart Logon Says, that "The Key you connected has not been formated!".
Dekart key formatting utility - still formating my card. Allready for 2 days!! Green lite on reader device is ON. If I pull out card, Green lite on device starts blink. Formatting utility still formating card! And I can't close it normaly. It hang.

I use PC/SC driver for reader device. (with ACS propietary driver - Dekart software doesn't see any card).

ACS Diagnostic utility Log says:
ACS QuickView Log : 2008.05.06:12:49:10
Starting diagnostics routine...
Reading System Information
Operating System : Windows Vista 32-bit
OS Build: 6000
CSD Version:
Processor: Intel - Xeon or Core 2 Duo/Extreme
Processor speed: 2327,630774 MHz
Submodel: Primary
Model ID: Family 6 Model 15 Stepping 11 Level 10
No of Processors: 2
Physical Memory Total: 1.025.175.552 Bytes
Physical Memory Free: 241.664.000 Bytes
Virtual Memory Free: 2.098.941.952 Bytes

Checking for Proprietary readers

Loading ACR30 library...
No ACR30 library found, loading acsr2032 library...
No ACSR2032 library found

Loading ACR38 library...
No ACR38 library found

Loading ACR80 library...
No ACR80 library found

Checking for PC/SC readers

Loading PC/SC library...
PC/SC library is loaded
Checking PC/SC functions...
PC/SC functions loaded
Calling SCardEstablishContext...
Success!
Calling SCardListReaders...
Success!
Calling SCardConnect...ACS ACR38U 0
Success!
Calling SCardControl...
Success!

ACS ACR38U 0
Firmware Version: ACR38-1100

Calling SCardDisconnect...
Success!

[Alex Railean]

You could be using a version of the formatting tool that does not support ACOS5 cards; try this one: xttp://files.dekart.com/beta/efcard-ACOS5.exe

If the problem persists:

Are there any other programs that are accessing the card at this moment?
Enable the logging mode and send us the logs

[irmooo]

I found how to enable logging on Dekart key formatting utility. I'm useing version 1.16 - you sent link to me.

Formating allready ~10 minutes. dk_smartkey.log size is about 3.5MB

How can I send to You log file?

[irmooo]

Ok. I'l paste some pieces of log here:
*
[ 9:16 : 49:516] E [ ] Start logfile ============================================
Product: Dekart Smartkey, Ver: 4.25.0.0
Module: C:\windows\system32\smartk32.dll, Ver: 4.22.17
OS: Windows Vista code name "Longhorn"
Date: 07.05.2008
[ 9:16 : 53:402] T [ EnumReaders] 1. 0.
[ 9:16 : 53:402] T [ EnumReaders] 3.
[ 9:16 : 53:402] T [ EnumReaders] 4.
[ 9:16 : 53:402] T [ EnumReaders] 5. 0.
[ 9:16 : 53:403] T [ SHandler::EnumReaders] 1. 0.
[ 9:16 : 53:403] T [ SHandler::EnumReaders] 3.
[ 9:16 : 53:403] T [ SSodos::get_readers] buf=0x12A738, length = 2048
[ 9:16 : 53:403] T [ managersdk:InitManagerSDK]
[ 9:16 : 53:404] T [ managersdk:InitManagerSDK] LoadLibrary(ManagerSDK.dll), hManagerSDKdll=0
[ 9:16 : 53:404] T [ managersdk:ErrorExit]
[ 9:16 : 53:404] E [ managersdk:ErrorExit] Error=LoadLibrary failed with error 126: The specified module could not be found.

[ 9:16 : 53:404] E [ SSodos::get_readers] InitManagerSDK() Error, ManagerSDK.dll not found
[ 9:16 : 53:404] T [ SHandler::EnumReaders] 4.
[ 9:16 : 53:404] T [ SHandler::EnumReaders] 5.
[ 9:16 : 53:404] T [ EnumReaders] 8. 0.
[ 9:16 : 53:405] T [ EnumReaders] 5. 1.
[ 9:16 : 53:405] T [ SHandler::EnumReaders] 1. 0.
...
[ 9:16 : 53:510] T [ SCard::get_readers] 4. 000003F0.
[ 9:16 : 53:510] T [ SHandler::EnumReaders] 4.
[ 9:16 : 53:511] T [ SHandler::get_index_from_name] 1. ACS ACR38U 0.
[ 9:16 : 53:511] T [ SHandler::get_index_from_name] 2. .
...
[ 9:16 : 53:512] T [ SHandler::get_index_from_name] 2. .
[ 9:16 : 53:512] T [ SHandler::get_index_from_name] 4. 16.
[ 9:16 : 53:512] T [ SHandler::get_index_from_name] 1. .
[ 9:16 : 53:513] T [ SHandler::get_index_from_name] 2. .
[ 9:16 : 53:513] T [ SHandler::get_index_from_name] 3. 0.
[ 9:16 : 53:513] T [ SHandler::EnumReaders] 5.
[ 9:16 : 53:513] T [ SHandler::get_index_from_name] 1. ACS ACR38U 0.
[ 9:16 : 53:513] T [ SHandler::get_index_from_name] 2. ACS ACR38U 0.
[ 9:16 : 53:513] T [ SHandler::get_index_from_name] 3. 0.
[ 9:16 : 53:513] T [ EnumReaders] 6. ACS ACR38U 0. 1.
[ 9:16 : 53:513] T [ add_name. ] readers = 010B5A70, num = 64, Name = ACS ACR38U 0, handler = 010ADB40
[ 9:16 : 53:514] T [ get_index_from_name. ] readers = 010B5A70, num = 64, Name = ACS ACR38U 0
[ 9:16 : 53:514] T [ add_name. ] reader doesn't exist
[ 9:16 : 53:514] T [ get_index_from_name. ] readers = 010B5A70, num = 64, Name =
[ 9:16 : 53:514] T [ add_name. ] index = 0
[ 9:16 : 53:514] T [ EnumReaders] 7. ACS ACR38U 0.
[ 9:16 : 53:514] T [ SHandler::EnumReaders] 1. 1.
[ 9:16 : 53:514] T [ EnumReaders] 8. 1.
[ 9:16 : 53:514] T [ EnumReaders] 5. 2.
[ 9:16 : 53:514] T [ SHandler::EnumReaders] 1. 0.
[ 9:16 : 53:515] T [ SHandler::EnumReaders] 3.
[ 9:16 : 53:515] T [ SHandler::EnumReaders] 4.
...
[ 9:16 : 53:516] T [ EnumReaders] 8. 1.
[ 9:16 : 53:516] T [ get_index_from_name. ] readers = 010B5A70, num = 64, Name = ACS ACR38U 0
[ 9:16 : 53:517] T [ EnumReaders] 9. 0. ACS ACR38U 0.
[ 9:16 : 53:517] T [ get_index_from_name. ] readers = 010B5A70, num = 64, Name = ACS ACR38U 0
[ 9:16 : 53:517] T [ InitReader. ] Initialize reader: ACS ACR38U 0
[ 9:16 : 53:517] T [ InitReader. ] Occupied connections: 0
[ 9:16 : 53:517] T [ get_index_from_name. ] readers = 010B5A70, num = 64, Name = ACS ACR38U 0
[ 9:16 : 53:517] T [ SHandler::get_index_from_name] 1. ACS ACR38U 0.
[ 9:16 : 53:517] T [ SHandler::get_index_from_name] 2. ACS ACR38U 0.
[ 9:16 : 53:517] T [ SHandler::get_index_from_name] 3. 0.
[ 9:16 : 53:518] T [ SCard::InitReader] Reader 0,
[ 9:16 : 53:518] T [ InitReader. ] Temporary handle: 00000000
[ 9:16 : 53:518] T [ InitReader. ] Name is not a path
[ 9:16 : 53:518] T [ get_index_from_name. ] readers = 010B5A70, num = 64, Name = ACS ACR38U 0
[ 9:16 : 53:519] T [ InitReader. ] Reader ACS ACR38U 0, handle = 0
[ 9:16 : 53:519] T [ InitCard] Reader handle 00000000, App = 0
[ 9:16 : 53:519] T [ SCard::InitCard] 0 START, app_id=0
[ 9:16 : 53:897] T [ SCard::InitCard] 0 Card is present
[ 9:16 : 53:899] T [ SCard::card_connect] 0 try to made first exclusive connection hContext=CD020000 reader=ACS ACR38U 0 hCard=00000000 dwProtocol=00000000 00000000
[ 9:16 : 54:102] T [ SCard::card_connect] 0 Connecting to card ok
[ 9:16 : 54:102] T [ SCard::get_card_object] ATR - 3BBE1800004105100000000000000000009000
[ 9:16 : 54:102] T [ SCard::get_card_object] ATR[i] = 41, length = 19
...
[ 9:16 : 54:116] T [ SCard::get_index_from_handle] 3. 0.
[ 9:16 : 54:116] T [ SCard::is_gsm_card]
[ 9:16 : 54:116] T [ SCard::InitCard] 0 reading serial number...
[ 9:16 : 54:120] T [ SCard::InitCard] 0 selecting issuer file...
[ 9:16 : 54:129] E [ SCard::InitCard] 0 selecting issuer file 00000000 Dekart SmartKey
[ 9:16 : 54:228] T [ InitCard] Index = 0, AppID = 0, return -2
[ 9:16 : 54:228] T [ SCard::card_connect] 0 try to made first exclusive connection hContext=CD020000 reader=ACS ACR38U 0 hCard=00000000 dwProtocol=00000000 00000000
[ 9:16 : 54:431] T [ SCard::card_connect] 0 Connecting to card ok
[ 9:16 : 54:431] T [ SCard::get_card_object] ATR - 3BBE1800004105100000000000000000009000
[ 9:16 : 54:431] T [ SCard::get_card_object] ATR[i] = 41, length = 19
[ 9:16 : 54:431] T [ SCard::get_index_from_handle] 1. EA030000.
[ 9:16 : 54:431] T [ SCard::get_index_from_handle] 2. EA030000.
[ 9:16 : 54:431] T [ SCard::get_index_from_handle] 3. 0.
[ 9:16 : 54:431] T [ SCard::is_gsm_card]
[ 9:16 : 54:432] T [ SCard::card_connect] 0 trying to check protocol...
[ 9:16 : 54:437] T [ SCard::get_card_object] ATR - 3BBE1800004105100000000000000000009000
[ 9:16 : 54:437] T [ SCard::get_card_object] ATR[i] = 41, length = 19
[ 9:16 : 54:437] T [ SCard::get_index_from_handle] 1. EA030000.
[ 9:16 : 54:437] T [ SCard::get_index_from_handle] 2. EA030000.
[ 9:16 : 54:437] T [ SCard::get_index_from_handle] 3. 0.
[ 9:16 : 54:437] T [ SCard::is_gsm_card]
[ 9:16 : 54:541] T [ SCard::UnformatCard] Start, flags = 0
[ 9:16 : 54:541] T [ SCard::card_connect] 0 try to made first exclusive connection hContext=CD020000 reader=ACS ACR38U 0 hCard=00000000 dwProtocol=00000000 00000000
[ 9:16 : 54:745] T [ SCard::card_connect] 0 Connecting to card ok
[ 9:16 : 54:745] T [ SCard::get_card_object] ATR - 3BBE1800004105100000000000000000009000
[ 9:16 : 54:745] T [ SCard::get_card_object] ATR[i] = 41, length = 19
[ 9:16 : 54:745] T [ SCard::get_index_from_handle] 1. EA040000.
[ 9:16 : 54:745] T [ SCard::get_index_from_handle] 2. EA040000.
[ 9:16 : 54:745] T [ SCard::get_index_from_handle] 3. 0.
[ 9:16 : 54:745] T [ SCard::is_gsm_card]
[ 9:16 : 54:746] T [ SCard::card_connect] 0 trying to check protocol...
[ 9:16 : 54:751] T [ SCard::get_card_object] ATR - 3BBE1800004105100000000000000000009000
[ 9:16 : 54:751] T [ SCard::get_card_object] ATR[i] = 41, length = 19
[ 9:16 : 54:751] T [ SCard::get_index_from_handle] 1. EA040000.
[ 9:16 : 54:751] T [ SCard::get_index_from_handle] 2. EA040000.
...
[ 9:19 : 37:538] T [ SCard::get_index_from_handle] 1. EA050000.
[ 9:19 : 37:538] T [ SCard::get_index_from_handle] 2. EA050000.
[ 9:19 : 37:539] T [ SCard::get_index_from_handle] 3. 0.
[ 9:19 : 37:539] T [ SCard::is_gsm_card]
[ 9:19 : 37:546] T [ ACOS5::create_file_command] 1: 0002 0008
[ 9:19 : 37:546] T [ SCard::FormatCard_ex] Start
[ 9:19 : 37:546] T [ SCard::get_card_object] ATR - 3BBE1800004105100000000000000000009000
[ 9:19 : 37:546] T [ SCard::get_card_object] ATR[i] = 41, length = 19
[ 9:19 : 37:547] T [ SCard::get_index_from_handle] 1. EA050000.
[ 9:19 : 37:547] T [ SCard::get_index_from_handle] 2. EA050000.
[ 9:19 : 37:547] T [ SCard::get_index_from_handle] 3. 0.
[ 9:19 : 37:547] T [ SCard::is_gsm_card]
[ 9:19 : 37:555] T [ ACOS5::create_file_command] 1: DE00 9520
[ 9:19 : 37:563] T [ ACOS5::create_file_command] 1: DE00 94E0
...
[ 9:28 : 33:276] T [ ACOS5::create_file_command] 1: DE00 07A0
[ 9:28 : 33:284] T [ ACOS5::create_file_command] 1: DE00 0760
...
[ 9:28 : 33:980] T [ ACOS5::create_file_command] 1: DE00 F1A0
[ 9:28 : 33:988] T [ ACOS5::create_file_command] 1: DE00 F160
...
[ 9:28 : 34: 92] T [ ACOS5::create_file_command] 1: DE00 EE20
[ 9:28 : 34:100] T [ ACOS5::create_file_command] 1: DE00 EDE0
...
[ 9:28 : 34:652] T [ ACOS5::create_file_command] 1: DE00 DCA0
[ 9:28 : 34:660] T [ ACOS5::create_file_command] 1: DE00 DC60
...
[ 9:29 : 50:707] T [ ACOS5::create_file_command] 1: DE00 9520
[ 9:29 : 50:715] T [ ACOS5::create_file_command] 1: DE00 94E0
...
[ 9:30 : 4:420] T [ ACOS5::create_file_command] 1: DE00 EE20
[ 9:30 : 4:428] T [ ACOS5::create_file_command] 1: DE00 EDE0
...
********************************

You can see that [ ACOS5::create_file_command] goes round (cyclic)...

Where is a problem?

[Alex Railean]

Try the updated version of the tool: xttp://files.dekart.com/beta/efcard-ACOS5pp.exe

Initially try it without the "clear all" option; if there is still a problem, enable that option (but note that all the information on the card will be deleted).

[irmooo]

I tried with and without "clear all" option. Problem still persist.:mad:

Log is attached (with option "clear all" and more than 10 minutes of formatting)68

[Alex Railean]

You need to reboot the system after installing the newer version of the program; in the logs we can see that one of the modules has not been updated.

[irmooo]

OK. I have new Computer with WinXP and the same SC reader and card.
Installed Dekart software. Formating utility (last v.).
Tried to format SC with "Clear data" option and without. In both cases I'v got an error in some minutes of formating. Here is both logs:
1. With "Clear data" option - 69;
2. Without "Clear data" option - 70.

P.S. Antivirus software disabled. Dekart logon works with USB key.

[santiago]

Any solution found? I have the same card and the very same problem...Thanks for anything.

[Alex Railean]

Where did you obtain the smart cards from?
Is it a smart card or a token?
Do you have several cards of the same type or only one?
Which version of the formatting tool do you have?
Was the card formatted by another tool prior to this process?


In the case of the person who started this thread the problem was solved when they tried it on another machine (in a clean state). Apparently some other components on the computer were interfering.

[santiago]

Hi again,

1/ rassro.cz (czech official ACS supplier)
2/ it's a smart card, bought together with a reader (xttp://www.rassro.cz/files/ACOS5_PKI.pdf)
3/ only one
4/ 1.17
5/ yes, by ACOS Admin Tool

Thanks for info, I alredy tried it on another machine, the same problem occured (Win XP / SP3). I will provide more information and/or error.log if necessary. One lame question - how do I enable logging for KFU (I read xttp://www.dekart.com/howto/general/howto_create_log/, but there is no mention about KFU) - I only need to know the correct names for the log files, am I right? Thanks.

[Alex Railean]

The name of the log file is dk_smartkey.log, you need to create it in C:\

Once you have it, you can either attach it to this thread or email it to support at dekart dot com.


p.s. we have an antispam system on this forum, sometimes good messages are flagged as spam, this explains why they were not posted immediately.

[santiago]

p.s. we have an antispam system on this forum, sometimes good messages are flagged as spam, this explains why they were not posted immediately.
ok, got it:-)

here are my logs:
dk_smartkey_clear.log (http://log.santiago.cz/dk_smartkey_clear.log)
dk_smartkey_no-clear.log (http://log.santiago.cz/dk_smartkey_no-clear.log)