EDC Issues Strange Offline Mode Issue

[Todai]

Hello all,

I am having an issue with EDC that I cannot figure out & was looking for your assistance. Whenever a credit card is swiped on the front of house the system attempts to obtain an authorization and then errors out with the following message “This credit card has been processed off-line. Please contact your manager.” Seconds later EDC see’s there is a Spooled transaction *.SPL and pulls it over to the file server where it obtains the authorization about a minute later. The file server will also delete the “SPOOLING” flag that is generated by the master terminal. This process will happen over and over again with each new authorization. I am looking to see why this issue continues to happen over and over again. I will try to give you everything about this issue in as much detail as possible…

Terminal #2’s IBERCFG.BAT file shows the following…
SET IBERROOT=aloha
SET LOCALDIR=C:\Aloha-system\aloha
SET CALIBRATE=C:\Calib.bat
SET EDCPATH=\\ldcserver3\ALOHA-EDC
SET TERMSTR=TERM
SET TERM=2
SET NUMTERMS=9
SET SERVER=LDCSERVER3
SET MASTERCAPABLE=TRUE
SET SERVERCAPABLE=TRUE
SET DOUBLECLICK=TRUE
SET AUTOEXIT=TRUE
SET REBOOTNT=TRUE
COPY %LOCALDIR%\newbin\*.* %LOCALDIR%\bin
ECHO y | DEL %LOCALDIR%\newbin\*.*
START C:\Aloha-system\aloha\BIN\IBER.EXE

The Aloha directory for terminal 2 is located in the following directory…
\\term2\c$\ALOHA-SYSTEM\ALOHA
Or
C:\Aloha-SYSTEM\Aloha

Terminal #2 is the acting master at this time. We have tried multiple terminals, but still have not had any luck.

The file server name is “LDCSERVER3”
Its environmental variables are the following:
EDCPATH = D:\Aloha-EDC (This environmental variable showed as EDCPROCPATH before but that didn’t work either)
IBERDIR = D:\ALOHA-SYSTEM\ALOHA
IBERROOT = ALOHA
NUMTERMs = 9
SERVER = LDCSERVER3
TERMSTR = TERM

I believe that is all the Environmental Variables set for the File Server

I will run a credit card transaction right now to trigger this problem and post the Error Logs from the master terminal & the file server…
(I renamed the old logs so it wasn’t so much spam)

Term2 Debout
Apr 17, 09:22:02, [796], [WARNING],,"The system cannot find the file specified Z:\aloha\DATA\00200001.OLK. 2"
Apr 17, 09:22:31, [796], [INFO],,"CheckForEDCDown: EDC server appears down (took 1 seconds)"
Apr 17, 09:22:31, [796], [INFO],,"SetEDCDown: Creating C:\Aloha-system\aloha\EDC\SPOOLING"
Apr 17, 09:22:31, [796], [INFO],,"ProcessEdcDownTransaction"
Apr 17, 09:22:31, [796], [INFO],,"Generated fake EDC auth 065490"
Apr 17, 09:22:31, [796], [INFO],,"Generate fake EDC ref id 2373272"
Apr 17, 09:22:31, [796], [INFO],,"ProcessEdcDownTransaction: Generated fake auth 065490 ref 2373272"
Apr 17, 09:22:31, [796], [INFO],,"SpoolEDCDownTxn: Successfully wrote C:\Aloha-system\aloha\EDC\00000001.SPL for payment "
Apr 17, 09:22:31, [796], [INFO],,":rintVoucherCommonTitles()::Vouchers common titles Messages is not enabled"
Apr 17, 09:22:31, [796], [INFO],,":rintVoucherCommonTitles()::0"
Apr 17, 09:22:31, [796], [INFO],,":rintVoucherCommonMessages()::Voucher common Messages is not enabled"
Apr 17, 09:22:31, [796], [INFO],,":rintVoucherCommonMessages()::0"
Apr 17, 09:22:31, [796], [INFO],,":rintVoucherCommonTitles()::Vouchers common titles Messages is not enabled"
Apr 17, 09:22:31, [796], [INFO],,":rintVoucherCommonTitles()::0"
Apr 17, 09:22:31, [796], [INFO],,":rintVoucherCommonMessages()::Voucher common Messages is not enabled"
Apr 17, 09:22:31, [796], [INFO],,":rintVoucherCommonMessages()::0"

File Server Debout
Apr 17, 09:22:53, [3860], [INFO],,"SpoolChecker: found and opened 00000001.SPL"
Apr 17, 09:22:53, [3860], [INFO],,"|: SubmitTxn() called."
Apr 17, 09:22:53, [3860], [INFO],,"Successfully deleted \\TERM2\BOOTDRV\Aloha\EDC\SPOOLING"
Apr 17, 09:22:53, [3692], [INFO],,"ARequestManager:rocessRequestFile: FOUND: D:\ALOHA-EDC\10000000.REQ, size = 787"
Apr 17, 09:22:53, [3692], [INFO],,"ARequestManager:rocessRequestFile: --1 about to process .req file: D:\ALOHA-EDC\10000000.REQ"
Apr 17, 09:22:53, [3692], [INFO],,"|: CEngine::StoreTxn - RFC 049885 replacing auth field, orig data was:[065490]"
Apr 17, 09:22:53, [3692], [INFO],,"ARequestManager:rocessTxnFile --2 txn stored. (renamed to HLD)"
Apr 17, 09:22:53, [3692], [INFO],,"ARequestManager:rocessTxnFile --3 .req file processed."
Apr 17, 09:22:54, [3856], [INFO],,"|: CEngine::Removing Txn 10000000"
Apr 17, 09:22:54, [3856], [INFO],,"|: "
Apr 17, 09:22:54, [3856], [INFO],,"|: ----------------------------------------"
Apr 17, 09:22:54, [3856], [INFO],,"|: [1] CEngine:rocessNextStoredTxn() - START - IPN Manager"
Apr 17, 09:22:54, [3856], [INFO],,"|: Engine filenumber = 10000000 (10000000) "
Apr 17, 09:22:54, [3856], [INFO],,"|: CEngine[1]:rocessStoredTxn() FileNumber = 10000000 - begin."
Apr 17, 09:22:54, [3856], [INFO],,"|: CEngine:rocessStoredTxn() act: #!@$#1R2O5M512:0C535@3Y2B4O3D372I5W0A#$@!#"
Apr 17, 09:22:54, [3856], [INFO],,"CEngine[1]:reProcessTxn() begin"
Apr 17, 09:22:54, [3856], [INFO],,"CEngine[1]:rocessStoredTxn() - after 'PreProcessTxn(*parms)"
Apr 17, 09:22:54, [3856], [INFO],,"|: ADatawire::SetMessageBuf() begin"
Apr 17, 09:22:54, [3856], [INFO],,"** ADatawire::SetMessageBuf(): Length=113"
Apr 17, 09:22:54, [3856], [INFO],,"#!@$#1Q2M5;550M07515F462B4J333=2>5G0A5K0H2K5?0P4C3I2G1M565W2E4I0:5L1>4R055;1;4B220N524F3Y060R3D3E2S#$@!# T.XXXXXXXXXXXXXX"
Apr 17, 09:22:54, [3856], [INFO],,"#!@$#1O2Q5;562E07515@462B4J333;0N5G0D320H2P5=0P4<3C2G1T405W2C4B0:5L1A4R055D1;4@0S0N524M3Y060R3D3E2Y#$@!# XXXX.l@HN.XX.XXX"
Apr 17, 09:22:54, [3856], [INFO],,"#!@$#1M045;562F07515@462B4H333=2E5G0A5Q0H2K590P4C3L2G1M545W2E4I0:5L1C4R055<1;4B1V0N524R3Y060Y3D3E2R#$@!# .XXXXXXXXXXXXXXX"
Apr 17, 09:22:54, [3856], [INFO],,"#!@$#1O2N5;580K07515>462B4G333=2F5G0A5N0H2M3F0P4<3L2G1M525W2E4P0:5L1>4R035<1;4C1U0N514O3Y07103D3F2R#$@!# X.XXXX.AXXXY...."
Apr 17, 09:22:54, [3856], [INFO],,"#!@$#1P2Q5;552B07525>462C4F333>2G5G0@5I0H2J5=0P4B3K2G1N525W2D4Q0:5K1B4R045C1;4C1U0N514O3Y07103D3F2R#$@!# ................"
Apr 17, 09:22:54, [3856], [INFO],,"#!@$#1P2Q5;562B07515>462B4F333=2G5G0A5I0H2K5=0P4C3K2G1M525W2E4Q0:5L1B4R055C1;4B1U0N524O3Y06103D3E2R#$@!# .XXXXXXXXXXXXXXX"
Apr 17, 09:22:54, [3856], [INFO],,"#!@$#1O2Q5;562B07515>462B4F333=2G5G0A5I0H2K5=0P4C3K2G1M525W2C4B0:5L1B4R055D1;4@0S0N524M3Y04223D3E2Y#$@!# XXXXXXXXX.XX.X.X"
Apr 17, 09:22:54, [3856], [INFO],,"#!@$#1O2P#$@!# X"
Apr 17, 09:22:54, [3856], [INFO],,"***********"
Apr 17, 09:22:54, [3856], [INFO],,"|: CEngineCES:ostBuildMessage() - begin"
Apr 17, 09:22:54, [3856], [INFO],,"** CEngineCES:ostBuildMessage(): Length=113"
Apr 17, 09:22:54, [3856], [INFO],,"#!@$#1Q2M5;550M07515F462B4J333=2>5G0A5K0H2K5?0P4C3I2G1M565W2E4I0:5L1>4R055;1;4B220N524F3Y060R3D3E2S#$@!# T.XXXXXXXXXXXXXX"
Apr 17, 09:22:54, [3856], [INFO],,"#!@$#1O2Q5;562E07515@462B4J333;0N5G0D320H2P5=0P4<3C2G1T405W2C4B0:5L1A4R055D1;4@0S0N524M3Y060R3D3E2Y#$@!# XXXX.l@HN.XX.XXX"
Apr 17, 09:22:54, [3856], [INFO],,"#!@$#1M045;562F07515@462B4H333=2E5G0A5Q0H2K590P4C3L2G1M545W2E4I0:5L1C4R055<1;4B1V0N524R3Y060Y3D3E2R#$@!# .XXXXXXXXXXXXXXX"
Apr 17, 09:22:54, [3856], [INFO],,"#!@$#1O2N5;580K07515>462B4G333=2F5G0A5N0H2M3F0P4<3L2G1M525W2E4P0:5L1>4R035<1;4C1U0N514O3Y07103D3F2R#$@!# X.XXXX.AXXXY...."
Apr 17, 09:22:54, [3856], [INFO],,"#!@$#1P2Q5;552B07525>462C4F333>2G5G0@5I0H2J5=0P4B3K2G1N525W2D4Q0:5K1B4R045C1;4C1U0N514O3Y07103D3F2R#$@!# ................"
Apr 17, 09:22:54, [3856], [INFO],,"#!@$#1P2Q5;562B07515>462B4F333=2G5G0A5I0H2K5=0P4C3K2G1M525W2E4Q0:5L1B4R055C1;4B1U0N524O3Y06103D3E2R#$@!# .XXXXXXXXXXXXXXX"
Apr 17, 09:22:54, [3856], [INFO],,"#!@$#1O2Q5;562B07515>462B4F333=2G5G0A5I0H2K5=0P4C3K2G1M525W2C4B0:5L1B4R055D1;4@0S0N524M3Y04223D3E2Y#$@!# XXXXXXXXX.XX.X.X"
Apr 17, 09:22:54, [3856], [INFO],,"#!@$#1O2P#$@!# X"
Apr 17, 09:22:54, [3856], [INFO],,"***********"
Apr 17, 09:22:54, [3856], [INFO],,"|: CEngine::WrapMessage(2) - len before = 113"
Apr 17, 09:22:54, [3856], [INFO],,"|: WrapMessage(2) - len after = 116"
Apr 17, 09:22:54, [3856], [INFO],,"|: ADatawire::SendTransaction() begin."
Apr 17, 09:22:54, [3856], [INFO],,"|: SimpleTransaction() returned 0"
Apr 17, 09:22:54, [3856], [INFO],,"ADatawire:etermineTypeOfError() Received status / error code 0"
Apr 17, 09:22:54, [3856], [INFO],,"|: ADatawire::Analyze_Reply() - ResponseMessage #!@$#1M25324B2B0N585F3V0I5G3C382F5R1C552S2L5>194F3;2G254M5W2R560:59124R0F5S1;411E0N4N543Y0E10343D2R482?2E2A4F39114Z4G4U3V3I2G0S0:4;5P2Q1C050C1;#$@!# : Response length - 69 "
Apr 17, 09:22:54, [3856], [INFO],,"** Received (DWire): Length=69"
Apr 17, 09:22:54, [3856], [INFO],,"#!@$#1O2P5;580K07585=462H4F333=2G5G0A5R0H2K590P4C3C2G1M555W2F4S0:5J1S4R055C1;4B1Y0N524N3Y06113D3C0;#$@!# X.APXXXXXB.XXXX."
Apr 17, 09:22:54, [3856], [INFO],,"#!@$#1M045;532C07515>462B4G333=2C5G0F340H2J5=0P4B3K2G1N525W2D4Q0:5K1B4R045C1;4C1U0N514O3Y07103D3F2R#$@!# .AXXXN.........."
Apr 17, 09:22:54, [3856], [INFO],,"#!@$#1P2Q5;552B07525>462C4F333>2G5G0@5I0H2J5=0P4B3K2G1N525W2D4Q0:5K1B4R055C1;4B1U0N524O3Y06103D3E2R#$@!# ...........XXXXX"
Apr 17, 09:22:54, [3856], [INFO],,"#!@$#1O2Q5;562B07515>462B4F333=2G5G0A5I0H2K5=0P4C3K2G1M525W2E4Q0:5L1B4R055C1;4B1U0N524O3Y06103D3E2R#$@!# XXXXXXXXXXXXXXXX"
Apr 17, 09:22:54, [3856], [INFO],,"#!@$#1O2Q5;562B07515>462D45333>0O#$@!# XXX.+"
Apr 17, 09:22:54, [3856], [INFO],,"***********"
Apr 17, 09:22:54, [3856], [INFO],,"|: "
Apr 17, 09:22:54, [3856], [INFO],,"|: SendDefaultMessage() - RESULT = 0"
Apr 17, 09:22:54, [3856], [INFO],,"|: "
Apr 17, 09:22:54, [3856], [INFO],,"|: CEngineCES:rocessResponse - begin."
Apr 17, 09:22:54, [3856], [INFO],,"ResponseBuf = 1 AP09485B 0417 A014N 000000000000000000000000 +"
Apr 17, 09:22:54, [3856], [INFO],,"|: Capture Flag = 1"
Apr 17, 09:22:54, [3856], [INFO],,"|: AuthResponse string = AP09485B 0417 A014N 000000000000000000000000 +"
Apr 17, 09:22:54, [3856], [INFO],,"|: Approval Code = AP09485B"
Apr 17, 09:22:54, [3856], [INFO],," Date 0417"
Apr 17, 09:22:54, [3856], [INFO],," AuthResponse A014N 000000000000000000000000 +"
Apr 17, 09:22:54, [3856], [INFO],," AuthResponse A014N 000000000000000000000000 +"
Apr 17, 09:22:54, [3856], [INFO],,"|: ProcessVisaCPS2000Table14Response"
Apr 17, 09:22:54, [3856], [INFO],,"|: TableID = A014"
Apr 17, 09:22:54, [3856], [INFO],,"|: ACI = N"
Apr 17, 09:22:54, [3856], [INFO],,"|: TransID = "
Apr 17, 09:22:54, [3856], [INFO],,"|: ValCode = "
Apr 17, 09:22:54, [3856], [INFO],,"|: MSDI = "
Apr 17, 09:22:54, [3856], [INFO],,"|: RPSI = "
Apr 17, 09:22:54, [3856], [INFO],,"|: FirstAuthAmount = 000000000000"
Apr 17, 09:22:54, [3856], [INFO],,"|: TotalAuthAmount = 000000000000"
Apr 17, 09:22:54, [3856], [INFO],,"CEngineCES:ostTxnProcess - begin,"
Apr 17, 09:22:54, [3856], [INFO],,"CEngineCES:ostTxnProcess - end"
Apr 17, 09:22:54, [3856], [INFO],,"CEngine[1]:rocessStoredTxn() - after 'ProcessTxn(*parms)"
Apr 17, 09:22:54, [3856], [INFO],,"CEngine[1]:rocessFinishedTxn() - "
Apr 17, 09:22:54, [3856], [INFO],,"|: StoredTxn corresponding to FileNumber (10000000) deleted"
Apr 17, 09:22:54, [3856], [INFO],,"|: ProcessTxn - Added Txn corresponding to PaymentId (20003), Time (09:22:30), DOB (20130417) to the TXN map"
Apr 17, 09:22:54, [3856], [INFO],,"|: Write ANS filenumber = 10000000 (10000000) "
Apr 17, 09:22:54, [3856], [INFO],,"|: Write parms ANS filenumber = 10000000"
Apr 17, 09:22:54, [3856], [INFO],,"[1] LastTxnNumber before increment is: 5"
Apr 17, 09:22:54, [3856], [INFO],,"[1] LastTxnNumber after increment is: 6"
Apr 17, 09:22:54, [3856], [INFO],,"Writing D:\ALOHA-EDC\CES\TXN00006..."
Apr 17, 09:22:54, [3856], [INFO],,"Skipping D:\ALOHA-EDC\10000000.ANS file creation"
Apr 17, 09:22:54, [3672], [INFO],,"|: m_nOfflineFileCount = 0"
Apr 17, 09:22:54, [3672], [INFO],,"|: # of offline txns = 0"
Apr 17, 09:22:55, [3856], [INFO],,"|: CEngine[1]:rocessNextStoredTxn() - END - IPN Manager"
Apr 17, 09:22:55, [3856], [INFO],,"|: ----------------------------------------"


Based on the debouts I believe the problem comes from the master but I cannot find the smoking gun yet. The history of this site is the site just received a version upgrade right before this problem happened. I do not believe the version upgrade is the cause of this problem. The other change that has been made is the Aloha directory structure has been changed (which is what I think may be causing this).

Front of house used to be C:\AlohaNow it’s C:\Aloha-System\Aloha
File Server EDC folder used to be D:\Aloha-system\Aloha\EDCNow it’s C:\Aloha-EDC
I have personally run out of ideas as to why this problem could be happening and was hoping that someone could see something that I have missed. Thanks for your help in advance.

Aloha EDC Version = 6.5.26
Aloha Manager = 6.5.30
IBER.EXE = 6.5.30
File Server OS = I believe its Server 2008

 

[360fish]

Unless they upgraded from 6.1 to 6.5 then the change to the file structure is likely the culprit (just logically) but why on earth they'd change the directory structure... Some thoughts;
When the directory structure was changed was aloha de-registered and re-registered on the server?
Does the FOH have full read/write to the BOH directories? Does the BOH have full read/write to the FOH?
I had a problem when I did a new install and moved the EDC directory outside the Aloha root on the server - it appears you did the same, have you considered moving it back?
Z:\aloha\DATA\00200001.OLK - is this a valid path after the directory change?

sorry not more helpful...

 

[Knectech]

check your ibercfg.bat and verify that EDCPath is correct.

Knectech
Aloha POS Helpdesk

 

[Todai]

Thank you guys so much for the responses. In fact the solution was the pathing in the EDC Path was the problem. It was missing thr BOOTDRV in the pathing causing EDC to fail on the front of house. The pathing has been corrected and the issue has been resolved. Thank you very much.

It was SET EDCPATH=\\ldcserver3\ALOHA-EDC
It has been changed to SET EDCPATH=\\ldcserver3\BOOTDRV\ALOHA-EDC (This resolved the issue)