Bacula-users

Re: [Bacula-users] Error on Bacula job

2008-11-16 15:12:40
Subject: Re: [Bacula-users] Error on Bacula job
From: Arno Lehmann <al AT its-lehmann DOT de>
To: bacula-users AT lists.sourceforge DOT net
Date: Sun, 16 Nov 2008 21:09:22 +0100
Hi,

16.11.2008 18:50, Daniel Bareiro wrote:
> 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.

Are tho jobs set up identically, except for the client name?

> 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

This seems to be a broken programm call... probably the file set 
definition is done with a program, and that programm isn't executed 
properly.

What is shown in the output is definitely not a useful 'find' invocation.

Can you post the relevant configuration setting, and also the result 
of the *identical* program call from the shell?

Arno

> 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
> 
> 
> ------------------------------------------------------------------------
> 
> -------------------------------------------------------------------------
> 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

-- 
Arno Lehmann
IT-Service Lehmann
Sandstr. 6, 49080 Osnabrück
www.its-lehmann.de

-------------------------------------------------------------------------
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

<Prev in Thread] Current Thread [Next in Thread>