Hi all!
I am using a JobDefs with three Jobs (servers) and although with two of
them I am not having problems, I am experiencing troubles with one of them.
After the execution of the job, I receive a email with the following errors:
-----------------------------------------------------------------------------
16-Nov 01:13 backup1-dir: sql_find.c:126 No Job record found: ERR=
CMD=SELECT StartTime FROM Job WHERE JobStatus='T' AND Type='B' AND Level='F'
AND Name='Sdp2' AND ClientId=21 AND FileSetId=31
+ORDER BY StartTime DESC LIMIT 1
16-Nov 01:13 backup1-dir: No prior or suitable Full backup found. Doing FULL
backup.
16-Nov 01:13 backup1-dir: Start Backup JobId 5231, Job=Sdp2.2008-11-16_00.00.18
16-Nov 01:17 sdp2-fd: Sdp2.2008-11-16_00.00.18 Fatal error: Error running
program: bash -c "find
+/space/backup/$HOSTNAME/*_etc.tar.gz ". RtnStat=268435457 ERR=Conseguido
16-Nov 01:17 sdp2-fd: Sdp2.2008-11-16_00.00.18 Fatal error: Error running
program: bash -c "find
+/space/backup/$HOSTNAME/oracle/controlfile/ ". RtnStat=268435457 ERR=Conseguido
16-Nov 01:17 sdp2-fd: Sdp2.2008-11-16_00.00.18 Fatal error: Error running
program: bash -c "find
+/space/backup/$HOSTNAME/oracle/pfile/ ". RtnStat=268435457 ERR=Conseguido
16-Nov 01:17 sdp2-fd: Sdp2.2008-11-16_00.00.18 Fatal error: Error running
program: bash -c "find
+/space/backup/$HOSTNAME/oracle/scripts/ ". RtnStat=268435457 ERR=Conseguido
16-Nov 01:15 backup1-sd: 3301 Issuing autochanger "loaded drive 0" command.
16-Nov 01:15 backup1-sd: 3302 Autochanger "loaded drive 0", result is Slot 5.
16-Nov 01:15 backup1-sd: 3307 Issuing autochanger "unload slot 5, drive 0"
command.
16-Nov 01:15 backup1-sd: 3304 Issuing autochanger "load slot 1, drive 0"
command.
16-Nov 01:18 backup1-sd: 3305 Autochanger "load slot 1, drive 0", status is OK.
16-Nov 01:18 backup1-sd: 3301 Issuing autochanger "loaded drive 0" command.
16-Nov 01:18 backup1-sd: 3302 Autochanger "loaded drive 0", result is Slot 1.
16-Nov 01:19 backup1-sd: Volume "Full0004" previously written, moving to end of
data.
16-Nov 01:20 backup1-sd: Ready to append to end of Volume "Full0004" at file=37.
16-Nov 01:20 backup1-dir: Sdp2.2008-11-16_00.00.18 Error: Bacula 1.38.11
(28Jun06): 16-Nov-2008 01:20:12
JobId: 5231
Job: Sdp2.2008-11-16_00.00.18
Backup Level: Full (upgraded from Differential)
Client: "sdp2-fd" i486-pc-linux-gnu,debian,4.0
FileSet: "Bases" 2008-07-08 22:10:11
Pool: "Full"
Storage: "DLTDrive"
Scheduled time: 16-Nov-2008 00:00:17
Start time: 16-Nov-2008 01:13:53
End time: 16-Nov-2008 01:20:12
Elapsed time: 6 mins 19 secs
Priority: 28
FD Files Written: 0
SD Files Written: 0
FD Bytes Written: 0 (0 B)
SD Bytes Written: 0 (0 B)
Rate: 0.0 KB/s
Software Compression: None
Volume name(s): Full0004
Volume Session Id: 39
Volume Session Time: 1226689534
Last Volume Bytes: 17,929,018,039 (17.92 GB)
Non-fatal FD errors: 0
SD Errors: 0
FD termination status: Error
SD termination status: OK
Termination: *** Backup Error ***
-----------------------------------------------------------------------------
15-Nov 00:12 backup1-dir: No prior Full backup Job record found.
15-Nov 00:12 backup1-dir: No prior or suitable Full backup found. Doing FULL
backup.
15-Nov 00:12 backup1-dir: Start Backup JobId 5211, Job=Sdp2.2008-11-15_00.00.18
15-Nov 00:16 sdp2-fd: Sdp2.2008-11-15_00.00.18 Fatal error: Error running
program: bash -c "find
+/space/backup/$HOSTNAME/*_etc.tar.gz ". RtnStat=268435457 ERR=Conseguido
15-Nov 00:16 sdp2-fd: Sdp2.2008-11-15_00.00.18 Fatal error: Error running
program: bash -c "find
+/space/backup/$HOSTNAME/oracle/controlfile/ ". RtnStat=268435457 ERR=Conseguido
15-Nov 00:16 sdp2-fd: Sdp2.2008-11-15_00.00.18 Fatal error: Error running
program: bash -c "find
+/space/backup/$HOSTNAME/oracle/pfile/ ". RtnStat=268435457 ERR=Conseguido
15-Nov 00:16 sdp2-fd: Sdp2.2008-11-15_00.00.18 Fatal error: Error running
program: bash -c "find
+/space/backup/$HOSTNAME/oracle/scripts/ ". RtnStat=268435457 ERR=Conseguido
15-Nov 00:14 backup1-sd: 3301 Issuing autochanger "loaded drive 0" command.
15-Nov 00:14 backup1-sd: 3302 Autochanger "loaded drive 0", result is Slot 6.
15-Nov 00:14 backup1-sd: 3307 Issuing autochanger "unload slot 6, drive 0"
command.
15-Nov 00:15 backup1-sd: 3304 Issuing autochanger "load slot 1, drive 0"
command.
15-Nov 00:18 backup1-sd: 3305 Autochanger "load slot 1, drive 0", status is OK.
15-Nov 00:18 backup1-sd: 3301 Issuing autochanger "loaded drive 0" command.
15-Nov 00:18 backup1-sd: 3302 Autochanger "loaded drive 0", result is Slot 1.
15-Nov 00:18 backup1-sd: Volume "Full0004" previously written, moving to end of
data.
15-Nov 00:19 backup1-sd: Ready to append to end of Volume "Full0004" at file=35.
15-Nov 00:19 backup1-dir: Sdp2.2008-11-15_00.00.18 Error: Bacula 1.38.11
(28Jun06): 15-Nov-2008 00:19:38
JobId: 5211
Job: Sdp2.2008-11-15_00.00.18
Backup Level: Full (upgraded from Incremental)
Client: "sdp2-fd" i486-pc-linux-gnu,debian,4.0
FileSet: "Bases" 2008-07-08 22:10:11
Pool: "Full"
Storage: "DLTDrive"
Scheduled time: 15-Nov-2008 00:00:17
Start time: 15-Nov-2008 00:12:56
End time: 15-Nov-2008 00:19:38
Elapsed time: 6 mins 42 secs
Priority: 28
FD Files Written: 0
SD Files Written: 0
FD Bytes Written: 0 (0 B)
SD Bytes Written: 0 (0 B)
Rate: 0.0 KB/s
Software Compression: None
Volume name(s): Full0004
Volume Session Id: 19
Volume Session Time: 1226689534
Last Volume Bytes: 17,812,225,023 (17.81 GB)
Non-fatal FD errors: 0
SD Errors: 0
FD termination status: Error
SD termination status: OK
Termination: *** Backup Error ***
-----------------------------------------------------------------------------
Doing a test from bconsole, I obtain the following thing:
estimate listing job=Sdp2
Connecting to Client sdp2-fd at sdp2.x.y.z:9102
[...]
2000 OK estimate files=864 bytes=5,442,305
You have messages.
*m
16-Nov 15:21 backup1-dir: ERROR in getmsg.c:148 Malformed message: Error
running program: or running program: or running program: or running p.
RtnStat=268435457 ERR=Conseguido
16-Nov 15:21 backup1-dir: ERROR in getmsg.c:148 Malformed message: Error
running program: or running program: or running program: or running
program:. RtnStat=268435457 ERR=Conseguido
16-Nov 15:21 backup1-dir: ERROR in getmsg.c:148 Malformed message: Error
running program: or running program: or running program: or running pr.
RtnStat=268435457 ERR=Conseguido
16-Nov 15:21 backup1-dir: ERROR in getmsg.c:148 Malformed message: Error
running program: or running program: or running program: or running
prog. RtnStat=268435457 ERR=Conseguido
Some idea of what it can be being the problem?
Thanks in advance.
Regards,
Daniel
--
Daniel Bareiro - System Administrator
Fingerprint: BFB3 08D6 B4D1 31B2 72B9 29CE 6696 BF1B 14E6 1D37
Powered by Debian GNU/Linux Lenny - Linux user #188.598
signature.asc
Description: Digital signature
-------------------------------------------------------------------------
This SF.Net email is sponsored by the Moblin Your Move Developer's challenge
Build the coolest Linux based applications with Moblin SDK & win great prizes
Grand prize is a trip for two to an Open Source event anywhere in the world
http://moblin-contest.org/redirect.php?banner_id=100&url=/ _______________________________________________
Bacula-users mailing list
Bacula-users AT lists.sourceforge DOT net
https://lists.sourceforge.net/lists/listinfo/bacula-users
|
