Backup email says job failed but log says it worked

   I have a little more information. I emailed the full log and what is failing is the bat file I run to do a chkdsk on the backup drive before backing up. It says it failed with an error code 2 but it sure looks like a normal chkdsk to me. Here it is:

1   Information   4/4/2017 1:49:15 PM   Task 'Simple backup (Full)' was started.

2   Information   4/4/2017 1:49:16 PM   Command 'Running backup plan 'Nightly Image'' is running.

3   Information   4/4/2017 1:49:28 PM   TOL: Failed to execute the command. The 'Executing command' command executes the specified command in the command-line interface.

Additional info:

--------------------

Error code: 22

Module: 309

LineInfo: 8d165e86fb8195f7

Fields:  TraceLevel : 1, $module : disk_bundle_vs_44421

Message: TOL: Failed to execute the command. The 'Executing command' command executes the specified command in the command-line interface.

--------------------

Error code: 1024

Module: 0

LineInfo: 858cc05f488a8dcc

Fields:  ExitCode : 2, $module : mms_vs_44421

Message: Child process has exited with code '2'.

--------------------

Error code: 3

Module: 167

LineInfo: 6bf76712d83410c4

Fields:  $module : mms_vs_44421

Message: Failed to execute command '

C:\windows\system32>echo off

 

The requested service has already been started.

 

More help is available by typing NET HELPMSG 2182.

 

File not found - B:\*.*

The type of the file system is NTFS.

Volume label is BackupDrive.

 

CHKDSK is verifying files (stage 1 of 3)...

 0 percent complete. (0 of 32 file records processed)       

 1 percent complete. (4 of 32 file records processed)       

 2 percent complete. (7 of 32 file records processed)       

 3 percent complete. (10 of 32 file records processed)       

 4 percent complete. (13 of 32 file records processed)        

 5 percent complete. (16 of 32 file records processed)       

 6 percent complete. (20 of 32 file records processed)       

 7 percent complete. (23 of 32 file records processed)       

 8 percent complete. (26 of 32 file records processed)        

 9 percent complete. (29 of 32 file records processed)       

103232                                                       

32 file records processed.                                 

 

File verification completed.

1000                                                        

0 large file records processed.                           

 

1000                                                       

0 bad file records processed.                             

 

1000                                                       

0 EA records processed.                                   

 

1000                                                       

0 reparse records processed.                              

 

CHKDSK is verifying indexes (stage 2 of 3)...

13 percent complete. (4 of 87 index entries processed)      

17 percent complete. (8 of 87 index entries processed)      

20 percent complete. (11 of 87 index entries processed)      

22 percent complete. (14 of 87 index entries processed)      

23 percent complete. (15 of 87 index entries processed)      

27 percent complete. (19 of 87 index entries processed)      

30 percent complete. (22 of 87 index entries processed)      

33 percent complete. (26 of 87 index entries processed)      

36 percent complete. (29 of 87 index entries processed)      

37 percent complete. (30 of 87 index entries processed)      

38 percent complete. (31 of 87 index entries processed)      

39 percent complete. (32 of 87 index entries processed)      

40 percent complete. (33 of 87 index entries processed)      

41 percent complete. (34 of 87 index entries processed)      

42 percent complete. (35 of 87 index entries processed)      

43 percent complete. (36 of 87 index entries processed)      

44 percent complete. (37 of 87 index entries processed)      

45 percent complete. (39 of 87 index entries processed)      

46 percent complete. (40 of 87 index entries processed)      

47 percent complete. (41 of 87 index entries processed)      

48 percent complete. (42 of 87 index entries processed)      

49 percent complete. (43 of 87 index entries processed)      

50 percent complete. (44 of 87 index entries processed)      

51 percent complete. (45 of 87 index entries processed)      

52 percent complete. (46 of 87 index entries processed)      

53 percent complete. (47 of 87 index entries processed)      

54 percent complete. (48 of 87 index entries processed)      

55 percent complete. (49 of 87 index entries processed)       

56 percent complete. (50 of 87 index entries processed)      

57 percent complete. (52 of 87 index entries processed)      

58 percent complete. (53 of 87 index entries processed)      

59 percent complete. (54 of 87 index entries processed)      

60 percent complete. (55 of 87 index entries processed)      

61 percent complete. (56 of 87 index entries processed)      

62 percent complete. (57 of 87 index entries processed)      

63 percent complete. (58 of 87 index entries processed)      

64 percent complete. (59 of 87 index entries processed)      

65 percent complete. (60 of 87 index entries processed)      

66 percent complete. (61 of 87 index entries processed)      

67 percent complete. (62 of 87 index entries processed)      

68 percent complete. (63 of 87 index entries processed)      

69 percent complete. (65 of 87 index entries processed)      

70 percent complete. (66 of 87 index entries processed)      

71 percent complete. (67 of 87 index entries processed)      

72 percent complete. (68 of 87 index entries processed)      

73 percent complete. (69 of 87 index entries processed)      

74 percent complete. (70 of 87 index entries processed)      

75 percent complete. (71 of 87 index entries processed)      

76 percent complete. (72 of 87 index entries processed)      

77 percent complete. (73 of 87 index entries processed)      

78 percent complete. (74 of 87 index entries processed)      

79 percent complete. (75 of 87 index entries processed)      

80 percent complete. (77 of 87 index entries processed)      

81 percent complete. (78 of 87 index entries processed)      

82 percent complete. (79 of 87 index entries processed)      

908787                                                       

87 index entries processed.                                

 

Index verification completed.

89 percent complete. (0 of 5 unindexed files processed)    

90 percent complete. (1 of 5 unindexed files processed)    

9155                                                       

5 unindexed files processed.                              

 

CHKDSK is verifying security descriptors (stage 3 of 3)...

92 percent complete. (5 of 32 descriptors processed)        

93 percent complete. (9 of 32 descriptors processed)        

94 percent complete. (13 of 32 descriptors processed)        

95 percent complete. (18 of 32 descriptors processed)        

96 percent complete. (22 of 32 descriptors processed)        

97 percent complete. (26 of 32 descriptors processed)        

98 percent complete. (31 of 32 descriptors processed)        

983232                                                       

32 security descriptors processed.                         

 

Security descriptor verification completed.

10077                                                        

7 data files processed.                                   

 

 

 976727039 KB total disk space.

         0 KB in 1 files.

         4 KB in 9 indexes.

         0 KB in bad sectors.

     95795 KB in use by the system.

     65536 KB occupied by the log file.

 976631240 KB available on disk.

 

      4096 bytes in each allocation unit.

 244181759 total allocation units on disk.

 244157810 allocation units available on disk.

The name has already been shared.

 

More help is available by typing NET HELPMSG 2118.

 

'.

--------------------

4   Information   4/4/2017 1:49:28 PM   Child process has exited with code '2'.

5   Information   4/4/2017 1:49:29 PM   Command 'Backing up' is running.

6   Information   4/4/2017 1:49:31 PM   Analyzing partition '1-0'...

7   Information   4/4/2017 1:49:31 PM   Create Full Backup

From:    Disk '1'

To file:  "file:B:\Server_2017_04_04_13_49_30_968D.TIB"

Compression:    Normal

Exclude:               Files matching mask

Match criterion:                *\System Volume Information\*{3808876B-C176-4e48-B7AE-04046E6CC752}; ...

 

8   Information   4/4/2017 1:49:31 PM   Pending operation 153 started: 'Creating partition image (No label (C))'.

9   Information   4/4/2017 1:49:36 PM   Locking partition 0-0...

10   Information   4/4/2017 2:00:22 PM   Pending operation 153 started: 'Creating partition image (No label (D))'.

11   Information   4/4/2017 2:26:24 PM   Pending operation 150 started: 'Saving partition structure (Hard disk 1)'.

12   Information   4/4/2017 2:26:24 PM   Command 'Backing up' has completed successfully.

13   Information   4/4/2017 2:26:27 PM   Command 'Replicating backups/cleaning up archives (1st location)' is running.

14   Information   4/4/2017 2:26:27 PM   Command 'Replicating backups/cleaning up archives (1st location)' has completed successfully.

Task 'Simple backup (Full)' has failed on machine 'GEMSRV1.gemcityinsurance.intern

 

   For those that are interested.... even though the pre-backup bat file appears to have worked with no errors Acronis, for some reason, thinks it failed. So the email picks up the failure in the bat file and sends the email that the job failed. The log says the backup was successful because it was. It was the bat file that failed. 

   Sure looks like an Acronis issue to me..... the big reason being that even though the bat file worked Acronis doesn't think it did.....