Bacula-users

[Bacula-users] LONG: Possible cause(s) when restore jobs are OK in bconsole, but no files are written to restore dir

2010-04-24 14:52:36
Subject: [Bacula-users] LONG: Possible cause(s) when restore jobs are OK in bconsole, but no files are written to restore dir
From: Holger Rauch <holger.rauch AT empic DOT de>
To: bacula-users AT lists.sourceforge DOT net
Date: Sat, 24 Apr 2010 20:46:56 +0200
Hi,

I'm using Bacula 5.0.1 on a server (HP ProLiant DL380 with a HP
StorageWorks 1/8 G2 Autoloader attached via SAS; host name: nathan)
and a client (QNAP NAS) compiled from the official sources.

The server runs

- bacula-dir
- bacula-fd
- bacula-sd

Bacula data is stored in a PostgreSQL 8.4.3 database (also compiled
from the official sources).

Both are running Debian Lenny amd64, Bacula was compiled from sources.
The btape and mtx-changer tests as well as the actual backup worked
without a problem.

Generally speaking, I'm about to perform all tests specified in

http://www.lllf.uam.es/~antonio/documentos/bacula/dev-manual/Testing_Your_Tape_Drive.html

However, when I perform step 10, which says

restore select all done
yes

I get the following output inside bconsole:

===

restore select all done
Automatically selected Catalog: MyCatalog
Using Catalog "MyCatalog"
Automatically selected Client: QNAP
Automatically selected FileSet: QNAP fileset
+-------+-------+----------+------------+---------------------+------------+
| jobid | level | jobfiles | jobbytes   | starttime           |
volumename |
+-------+-------+----------+------------+---------------------+------------+
|    21 | F     |    9,924 | 29,652,827 | 2010-04-24 18:41:49 |
ADE891L3   |
+-------+-------+----------+------------+---------------------+------------+
You have selected the following JobId: 21

Building directory tree for JobId(s) 21 ...
++++++++++++++++++++++++++++++++++++++++++++
8,844 files inserted into the tree and marked for extraction.
Bootstrap records written to
/usr/local/bacula5/var/bacula/working/nathan-dir.restore.5.bsr

The job will require the following
   Volume(s)                 Storage(s)                SD Device(s)
   ===========================================================================
      
         *ADE891L3                  nathan-sd
HPStorageWorks18G2Autoldr

Volumes marked with "*" are online.


9,924 files selected to be restored.

Run Restore job
JobName:         Common restore procedure
Bootstrap:       /usr/local/bacula5/var/bacula/working/nathan-dir.restore.5.bsr
Where:           /backup/restores
Replace:         always
FileSet:         restore fileset
Backup Client:   QNAP
Restore Client:  QNAP
Storage:         nathan-sd
When:            2010-04-24 20:29:31
Catalog:         MyCatalog
Priority:        10
Plugin Options:  *None*
OK to run? (yes/mod/no): 
yes
Job queued. JobId=25

===

(/backup/restores is NFS-mounted on nathan from the QNAP host, and the
bacula user can store files there; I verified this doing a "touch
<file_name>" at the prompt. The .bsr file exists and is readable as
well).

When I do "messages" inside bconsole, I get the following messages,
making me believe that everything's OK:

===

24-Apr 20:30 nathan-dir JobId 25: shell command: run BeforeJob
"/usr/local/bacula5/etc/create-bootstrap-dir QNAP"
24-Apr 20:30 nathan-dir JobId 25: Start Restore Job
Common_restore_procedure.2010-04-24_20.30.05_16
24-Apr 20:30 nathan-dir JobId 25: Using Device "HPUltrium920LTO3"
24-Apr 20:30 nathan-sd JobId 25: Ready to read from volume "ADE891L3"
on device "HPUltrium920LTO3" (/dev/nst0).
24-Apr 20:30 nathan-sd JobId 25: Forward spacing Volume "ADE891L3" to
file:block 0:1.
24-Apr 20:30 nathan-sd JobId 25: End of Volume at file 1 on device
"HPUltrium920LTO3" (/dev/nst0), Volume "ADE891L3"
24-Apr 20:30 nathan-sd JobId 25: End of all volumes.
24-Apr 20:30 nathan-sd JobId 25: Alert: smartctl version 5.38
[x86_64-unknown-linux-gnu] Copyright (C) 2002-8 Bruce Allen
24-Apr 20:30 nathan-sd JobId 25: Alert: Home page is
http://smartmontools.sourceforge.net/
24-Apr 20:30 nathan-sd JobId 25: Alert: 
24-Apr 20:30 nathan-sd JobId 25: Alert: TapeAlert: OK
24-Apr 20:30 nathan-sd JobId 25: Alert: 
24-Apr 20:30 nathan-sd JobId 25: Alert: Error Counter logging not
supported
24-Apr 20:30 nathan-sd JobId 25: Alert: 
24-Apr 20:30 nathan-sd JobId 25: Alert: Last n error events log page
24-Apr 20:30 nathan-sd JobId 25: Alert:   <<short Last n error events
log page>>
24-Apr 20:30 nathan-dir JobId 25: Bacula nathan-dir 5.0.1 (24Feb10):
24-Apr-2010 20:30:10
  Build OS:               x86_64-unknown-linux-gnu debian 5.0.4
  JobId:                  25
  Job:                    Common_restore_procedure.2010-04-24_20.30.05_16
  Restore Client:         QNAP
  Start time:             24-Apr-2010 20:30:07
  End time:               24-Apr-2010 20:30:10
  Files Expected:         9,924
  Files Restored:         9,924
  Bytes Restored:         29,593,795
  Rate:                   9864.6 KB/s
  FD Errors:              0
  FD termination status:  OK
  SD termination status:  OK
  Termination:            Restore OK
                      
24-Apr 20:30 nathan-dir JobId 25: Begin pruning Jobs older than 1 year .
24-Apr 20:30 nathan-dir JobId 25: No Jobs found to prune.
24-Apr 20:30 nathan-dir JobId 25: Begin pruning Jobs.
24-Apr 20:30 nathan-dir JobId 25: No Files found to prune.
24-Apr 20:30 nathan-dir JobId 25: End auto prune.

===

However, the /backup/restores directory is empty and I can't figure
out why. The job definition for the restore procedure looks like this:

===

Job {
 Name = "Common restore procedure"
 Type = Restore
 Fileset = "restore fileset"
 Client = QNAP
 Storage = nathan-sd
 Pool = RestorePool
 Messages = Standard
 Where = /backup/restores
        
 RunScript {
  RunsWhen = Before
  FailJobOnError = Yes
  Command = "/usr/local/bacula5/etc/create-bootstrap-dir %c"
  RunsOnClient = no
 }
         
}

===

The client definitition for QNAP looks like this:

===

# Client
Client {
  Name = QNAP
  Address = ww.xx.yy.zz
  FDPort = 9102 
  Catalog = MyCatalog
  Password = "xxx"          # password for FileDaemon
  File Retention = 31 days
  Job Retention = 1 year
  AutoPrune = yes
}

# Fileset
FileSet {
  Name = "QNAP fileset"
  Ignore FileSet Changes = yes
  Include {
    Options {
      signature = SHA1
      accurate = ipnugsmcd1
      verify = ipnugsmcd1
      sparse = yes
      aclsupport = yes
    }

    File = /etc
    File = /root
  }

  Exclude {
    File = /var/lock
    File = /var/cache
    File = /tmp
  }
}

Job {
  Name = "QNAP backup"
  JobDefs = "DefaultJob"
  Client = QNAP
  FileSet = "QNAP fileset"
  Enabled = yes

}

===

The DefaultJob definition looks like this

===

JobDefs {
  Name = "DefaultJob"
  Type = Backup
  Level = Incremental
  Maximum Concurrent Jobs = 3
  Messages = Standard
  Spool Data = yes
  Write Bootstrap = "/backup/bacula/bootstraps/%c/%c-%t-%l-%i.bsr"
  Pool = DailyBackups
  Full Backup Pool = WeeklyBackups
  Schedule = "MonthlyCycle"
}

===

(The file /backup/bacula/bootstraps/QNAP/QNAP-Backup-Full-21.bsr is
present and can be read by the bacula user). It contains the following
data

===

# 24-Apr-2010 18:43:01 - QNAP_backup.2010-04-24_18.41.47_05 - Full
(upgraded from Incremental)
Volume="ADE891L3"
MediaType="LTO-3"
Slot=2
VolSessionId=1
VolSessionTime=1272125935
VolAddr=1-486
FileIndex=1-9924

===

The DailyBackup and WeeklyBackup pool definitions are

===

Pool {
  Name = DailyBackups
  Pool Type = Backup
  Recycle = yes
  AutoPrune = yes
  Volume Retention = 31 days
  Recycle Oldest Volume = yes
  Label Format = "_daily_${Day}-${Month}-${Year}_${WeekDay}"
  Storage = nathan-sd
}
          
Pool {
  Name = WeeklyBackups
  Pool Type = Backup
  Recycle = yes
  AutoPrune = yes
  Volume Retention = 31 days
  Recycle Oldest Volume = yes
  Label Format = "_weekly_${Day}-${Month}-${Year}"
  Storage = nathan-sd
}

===

Any idea as to how I can debug this further (better yet, how can I
find out where my files have gone)?

Thanks in advance for any hints & kind regards,

    Holger
    

Attachment: signature.asc
Description: Digital signature

------------------------------------------------------------------------------
_______________________________________________
Bacula-users mailing list
Bacula-users AT lists.sourceforge DOT net
https://lists.sourceforge.net/lists/listinfo/bacula-users