Job Scheduler Exit code is 1

[frdba]

I have a Job scheduler set up to erase my tapes each night before the backup starts. This was running fine, but now 3 of my jobs are not actually erasing the tapes. The job starts and it says it has completed successfully. The job log says:

Run command: ca_devmgr -erase 4 0 qz.
N3386
Scheduled command executed. Exit code is 1

Totals For...............Job
Elapsed Time.............1s

Job Scheduler Operation Successful.

The problem is that the erase does not happen! And there are no errors. On my other jobs that actually work, the exit code is 0. Anyone have any ideas what exit code 1 means? Or can point me to documentation on Job scheduler troubleshooting?

Thanks

 

[davidmichel]

exit codes are just simple 0, 1 true false.
Put the Job and Tape Engines into debug and check the log directory for any other logs that get updated at the time for clues as to the cause.

 

[frdba]

What kind of things do I need to look for? I put both the tape and job engines in debug mode, but I didn't see anything helpful towards resolving the issue in the logfiles. Any other ideas on places to look?

Thanks

 

[davidmichel]

erase not happening" is pretty open. The tape log in detail mode records all interaction between the Tape Engine and the tape drive. Look for that point in time to see if that tape was moved from a slot to a drive, and then if erased. It may be a little cryptic but enough is plain text so that you should be able to tell that much, and if there was any errors.

 

[frdba]

I decided to upgrade to r11.5 SP3 (Build 4402) thinking it may have to do with the version of arcserve I'm running and the windows version (BAB 9/ Win2003 sp2). The same job now shows as failed

Run command: ca_devmgr -erase 4 0 qz.
E3385
Scheduled command failed. Exit code is -1

Totals For...............Job
Elapsed Time.............1s

Job Scheduler Operation Failed

*********
The tskJob03.log is the logfile that gets updated right after the job fails.

20070620 123119 00 OPER Job JobNo 3, CPU# 0, PID 0x145c
20070620 123119 00 OPER Job Allocating job monitor file Global\ASJob$00000046
20070620 123119 00 OPER Job Allocating job monitor returns 0
20070620 123120 00 Misc Report Report job description: Comments [Erase Tape], Owner [caroot], Job ID [70], WriteToJobLog [0]

20070620 123120 00 Misc Report AsLogGetJobLogPath returns job log name [C:\Program Files\CA\BrightStor ARCserve Backup\LOG\J0000070.LOG]

20070620 123120 00 Misc Report Job log path [C:\Program Files\CA\BrightStor ARCserve Backup\LOG\cas_user_logs\caroot\J0000070.LOG]

20070620 123120 00 OPER LogDebugMsg Called AsLogRegisterLogFile to create job log file <C:\Program Files\CA\BrightStor ARCserve Backup\LOG\cas_user_logs\caroot\J0000070.LOG>

20070620 123120 00 OPER LogDebugMsg GetUserObjectSecurity, rc=122

20070620 123120 00 OPER LogDebugMsg Ace count =19

20070620 123122 00 Misc Misc Message Scheduled command failed. Exit code is -1.

20070620 123122 00 OPER LogDebugMsg ReportTotals(REPORT.C) was called.

20070620 123142 00 OPER Job Enter _FreeJCB
20070620 123142 00 OPER Job Enter _FreeMonitor
20070620 123142 00 OPER Job Leaving _FreeMonitor
20070620 123142 00 OPER Job Leaving _FreeJCB
20070620 123142 00 OPER Job _RunJobThread returns 65535.
20070620 123142 00 OPER Job QJobInfo execution time: 46788A20h [ 6/19/07 21:00:00]
20070620 123142 00 OPER Job QJobInfo last execution time: 00000000h [12/31/69 18:00:00]
20070620 123142 00 OPER Job QJobInfo next execution time: 4679DBA0h [ 6/20/07 21:00:00]
20070620 123142 00 OPER Job RescheduleJob(Delta=0,Prev=0)
20070620 123142 00 OPER Job CRA next execution time: 4679DBA0h [ 6/20/07 21:00:00]
20070620 123142 00 OPER Job JobProc returns thread handle 0x608

*******
The activity log shows:

Information 06/20/2007 12:31:42 PM [JOBQUEUE]: Rescheduled for 6/20/07 at 9:00 PM [Job No: 3] [Description: Erase Tape]
Information 06/20/2007 12:31:22 PM 70 Generic Job Operation Failed.
Information 06/20/2007 12:31:22 PM 70 ReportTotals(REPORT.C) was called.
Information 06/20/2007 12:31:22 PM 70 End Impersonation.
E3385 06/20/2007 12:31:22 PM 70 Scheduled command failed. Exit code is -1.
Information 06/20/2007 12:31:20 PM 70 Ace count =19
Information 06/20/2007 12:31:20 PM 70 GetUserObjectSecurity, rc=122
Information 06/20/2007 12:31:20 PM 70 Run Command: ca_devmgr -erase 2 6 qz.
Information 06/20/2007 12:31:20 PM 70 Called AsLogRegisterLogFile to create job log file <C:\Program Files\CA\BrightStor ARCserve Backup\LOG\cas_user_logs\caroot\J0000070.LOG>
Information 06/20/2007 12:31:20 PM 70 Perform Operation for User. (USER=Administrator).
Information 06/20/2007 12:31:20 PM 70 Description: Erase Tape.
Information 06/20/2007 12:31:20 PM 70 Start Generic Job Operation. (QUEUE=1, JOB=3)
Information 06/20/2007 12:31:19 PM 70 Run Generic Job Job Scheduled for 6/19/07 at 9:00 PM.
*******

When I click on the error in the activity log, it tells me "Decide what action to take based on the meaning of the error code. Exit code is (error code)." Yet it doesn't list what the exit codes mean. And I can't find a list anywhere.

I'm hoping that someone has come across this list of codes before.

Thanks for any insight.

 

[frdba]

I reinstalled Arcserve,and reapplied all my patches. Now everything is working as it should.