Errorless Restore Failure??

[thunder88]

Hey all,

I have a very peculiar problem with Arcserve 11.5 SP2. I am attempting to run a restore job from a Document level backup of an exchange server. I merged the tape I wanted to run the restore from to get the index, and drilled down to exactly the item i was looking for. Kicked off the job and this is the log file I get:

BrightStor ARCserve Backup -- Restore


Job No....................... 10
Job ID....................... 63
Workstation.................. SERVERNAME
Source....................... F-FDAILY-MON-11/17/08, ID 5EF1, Sequence #1
Session...................... 5
Target....................... \\SERVERNAME\dbaexsis\First Storage Group
Start Time................... 1/13/09 11:23 PM
Total Skip(s)................ 0
Total Size (Disk)............ 0 KB
Total Size (Media)........... 1.25 MB
Elapsed Time................. 1m 6s
Average Throughput........... 1.13 MB/min


Totals For................... Job
Total Session(s)............. 1
Total Skip(s)................ 0
Total Size (Disk)............ 0 KB
Total Size (Media)........... 1.25 MB
Elapsed Time................. 1m 6s
Average Throughput........... 1.13 MB/min


Restore Operation Failed.

Thats it. I'm floored frankly, I have never seen a more useless failure log. Someone coming from Veritas this is appalling. So my question to the greater community is what can I do to troubleshoot this? I tried turning logging up to "Log all activity" but that resulted in the same results being written to a txt file.

Many thanks to anyone who can help me out here!

~thunder

Here is the verbose log file, didn't see much of use in here, but I must be missing something:
20090113 232144 00 OPER Job JobNo 10, CPU# 0, PID 0x2e50
20090113 232146 00 OPER Job Allocating job monitor file Global\ASJob$0000003F
20090113 232146 00 OPER Job Allocating job monitor returns 0
20090113 232146 00 OPER isWin64 Processor Architecture is x86

20090113 232146 00 OPER LogDebugMsg Japanese? 0409

20090113 232146 00 TSIF TSI Funcs TSICreateTSIHandle

20090113 232146 00 TSIF TSI Funcs calling TASK_TSIMUXSendJobToken

20090113 232146 00 TSIF TSI Funcs calling TSIGetXCB : MUX_BEB_JOB_TAB_6 [job token = 0x496d6854]

20090113 232146 00 TSIF TSI Funcs exit TASK_TSIMUXSendJobToken [0]

20090113 232146 00 OPER Prompt Message StartTime 0x496d685a, Time interval 60 sec. Timeout in 300 sec.

20090113 232146 00 TSIF TSI Funcs TSIFindGroupOpen

20090113 232146 00 TSIF TSI Funcs TSIFindGroupClose

20090113 232146 00 OPER LogDebugMsg Find group name: GROUP0

20090113 232146 00 OPER Prompt Message Reset time interval. Default time out 300 sec.

20090113 232146 00 TSIF TSI Funcs TSIConnectToGroup Name:GROUP0 Mode:2

20090113 232146 00 OPER LogDebugMsg Backup/restore privilege enabled.

20090113 232146 00 Misc Report Report job description: Comments [(null)], Owner [caroot], Job ID [63], WriteToJobLog [0]

20090113 232146 00 Misc Report AsLogGetJobLogPath returns job log name [D:\Program Files\CA\BrightStor ARCserve Backup\LOG\J0000063.LOG]

20090113 232146 00 Misc Report Job log path [D:\Program Files\CA\BrightStor ARCserve Backup\LOG\cas_user_logs\caroot\J0000063.LOG]

20090113 232146 00 OPER LogDebugMsg Called AsLogRegisterLogFile to create job log file <D:\Program Files\CA\BrightStor ARCserve Backup\LOG\cas_user_logs\caroot\J0000063.LOG>

20090113 232146 00 OPER Prompt Message StartTime 0x496d685a, Time interval 60 sec. Timeout in 300 sec.

20090113 232146 00 OPER Prompt Message Reset time interval. Default time out 300 sec.

20090113 232146 00 TSIF TSI Funcs TSIConnectToTapeEx flags:7 tapeFlags: 268443905,ulMode:2 DeviceNumber:3 ret:0

20090113 232146 00 TSIF TSI Funcs TSIReadTapeHeader

20090113 232146 00 OPER Prompt Message StartTime 0x496d685a, Time interval 60 sec. Timeout in 3600 sec.

20090113 232146 00 OPER Prompt Message Reset time interval. Default time out 3600 sec.

20090113 232146 00 TSIF TSI Funcs TSIGotoSession ulSession:5

20090113 232356 00 TSIF TSI Funcs TSIGotoSession returns.

20090113 232356 00 TSIF TSI Funcs calling TASK_TSIMUXGetBufferSize

20090113 232356 00 TSIF TSI Funcs calling TSIGetXCB : MUX_BEB_JOB_TAG_4

20090113 232356 00 TSIF TSI Funcs TSIGetXCB : MUX_BEB_JOB_TAG_4 returns chunk # 0

20090113 232356 00 TSIF TSI Funcs exit TASK_TSIMUXGetBufferSize [0]

20090113 232356 00 OPER LogDebugMsg The restore buffer size is 256 KB


20090113 232356 00 TSIF TSI Funcs TSIStartSession jobFlag:2 tapeSessionID: 0

20090113 232356 00 TSIF TSI Funcs TSIStartSession returns.

20090113 232356 00 OPER PortAddress [ Defaults ] SERVERNAME, 10.1.10.2, 6050, 0

20090113 232356 00 OPER PortAddress [ Detected ] SERVERNAME, 10.1.10.2, 6050, 0

20090113 232356 00 OPER LogDebugMsg Creating temp file X*.*

20090113 232502 00 TSIF TSI Funcs TSIQFALocate

20090113 232525 00 TSIF TSI Funcs TSIQFALocate

20090113 232642 00 TSIF TSI Funcs TSIQFALocate

20090113 232643 00 OPER LogDebugMsg ReportTotals(REPORT.C) was called.

20090113 232643 00 OPER LogDebugMsg LogTotals(REPORT.C) was called.

20090113 232643 00 TSIF TSI Funcs TSIEndSession

20090113 232643 00 TSIF TSI Funcs TSIEndSession returns.

20090113 232643 00 TSIF TSI Funcs TSIDisConnectFromTape

20090113 232806 00 TSIF TSI Funcs TSIDisConnectFromGroup

20090113 232806 00 OPER LogDebugMsg ReportTotals(REPORT.C) was called.

20090113 232806 00 OPER LogDebugMsg LogTotals(REPORT.C) was called.

20090113 232826 00 TSIF TSI Funcs TSIDestroyTSIHandle

20090113 232826 00 OPER Restore Freeing memory allocations
20090113 232826 00 OPER Restore restore returns -1
20090113 232826 00 OPER Restore _RunJob returns 65535
20090113 232826 00 OPER Job Enter _FreeJCB
20090113 232826 00 OPER Job Enter _FreeMonitor
20090113 232826 00 OPER Job Leaving _FreeMonitor
20090113 232826 00 OPER Job Leaving _FreeJCB
20090113 232826 00 OPER Job _RunJobThread returns 65535.
20090113 232826 00 OPER Job QJobInfo last execution time: 00000000h [12/31/69 19:00:00]
20090113 232826 00 OPER Job JobProc returns thread handle 0x680

 

[thunder88]

Upgraded to 11.5 SP4, rerunning merge and restore, will post log files when done.

~thunder