Hi,

I have a problem with my daily backup jobs.
I use 2 tapes for mon-thu and set them to 25h retention.
As mentioned in some past posts, I put in an Admin job to recycle
volumes as needed.
But apparently that didn't work.
Today again, I had to mount the volume to release the job.
Could someone help me to find out what I'm doing wrong?

The timeline for the log is:
- 02 Aug, I purged and relabeled the two Daily volumes
- then I ran a backup on Daily2 (end time was 17:14)
- that evening, Daily1 ran as scheduled at 22:00
- 03 Aug, 21:50 - Admin Job (scheduled)
- 03 Aug, 22:00 - Backup could not find appendable volumes
- 03 Aug, 23:00 - Volume Daily2 recycled
job waited all night for me to mount volume. As soon as I did, job ran fine.

I have a snippet from bacula-dir.conf, a 'list volumes' before the mount
this morning and a current log file attached.

Regards
    TT


### ------------- Pools -------------

Pool {
  Name = Daily
  Pool Type = Backup
  Use Volume Once = no
  Maximum Volume Jobs = 2 # Backup+Catalog
  AutoPrune = yes
  VolumeRetention = 25h   # recycle after 1 day (25h so it won't be pruned in 
time for the next daily backup)
  Maximum Volumes = 2
  Recycle = yes
  #Recycle Current Volume = yes
  #Label Format = "Daily-"
}
Pool {
  Name = Weekly
  Pool Type = Backup
  Use Volume Once = no    # we want to append the catalog
  Maximum Volume Jobs = 2
  AutoPrune = yes
  VolumeRetention = 23d  # recycle in 23 days (more than 3 weeks)
  Maximum Volumes = 4
  Recycle = yes
  #Recycle Current Volume = yes
  #Label Format = "Weekly-"
}
Pool {
  Name = Monthly
  Pool Type = Backup
  Use Volume Once = no
  Maximum Volume Jobs = 2
  AutoPrune = yes
  VolumeRetention = 56d  # recycle in 56 days (more than 1 month, less than 2)
  Maximum Volumes = 2
  Recycle = yes
  #Recycle Current Volume = yes
  #Label Format = "Monthly-"
}

### ------------- Nightly Backup -------------

Schedule {
  Name = "Nightly Backup"

  Run = Level=Full Pool=Monthly 1st fri at 22:00  
  Run = Level=Full Pool=Weekly 2nd-5th fri at 22:00
  Run = Level=Differential Pool=Daily mon-thu at 22:00
}
Job {
  Name = "Full Backup"
  Type = Backup
  Level = Full
  Client = FIM-FS-fd
  FileSet = "Full Set"
  Messages = Standard
  Storage = Tape
  Pool = Snapshot
  Priority = 10
  Schedule = "Nightly Backup"
  Write Bootstrap = "/var/lib/bacula/Backup.bsr"
}

FileSet {
  Name = "Full Set"

  Include {
    Options {
      signature = MD5
      verify = pins5
      onefs = yes
      sparse = yes
      aclsupport = yes
      #compression = GZIP # should be done by hardware
    }
    File = /home
  }

  Include {
    Options {
      signature = MD5
      verify = pins5
      onefs = yes
      sparse = yes
    }
    File = /usr
    File = /var
    File = /root
    File = /
  }

  Exclude {
    File = /proc
    File = /tmp
    File = /.journal
    File = /.fsck
  }
}

### ------------- Admin Job (pruning etc.) -------------

Schedule {
  Name = "Nightly Admin"
  Run = Level=Differential Pool=Daily mon-fri at 21:50
}
Job {
  Name = "Admin-Job"
  Type = Admin
  Client = FIM-FS-fd
  FileSet = "Full Set"
  Messages = Standard
  Storage = Tape
  Pool = Snapshot
  Schedule = "Nightly Admin"
}

### ------------- Catalog Backup -------------

# Backup the catalog database (after the nightly save)
Job {
  Name = "BackupCatalog"
  JobDefs = "DefaultJob"
  Level = Full
  FileSet="Catalog"
  Pool = Snapshot
  Schedule = "WeeklyCycleAfterBackup"
  # This creates an ASCII copy of the catalog
  RunBeforeJob = "/etc/bacula/scripts/make_catalog_backup -u<user> -p<password>"
  # This deletes the copy of the catalog
  RunAfterJob  = "/etc/bacula/scripts/delete_catalog_backup"
  Write Bootstrap = "/var/lib/bacula/BackupCatalog.bsr"
  Priority = 11                   # run after main backup
}

# This schedule does the catalog. It starts after the WeeklyCycle
Schedule {
  Name = "WeeklyCycleAfterBackup"
  #Run = Full mon-fri at 22:05 
  
  Run = Level=Full Pool=Monthly 1st fri at 22:05
  Run = Level=Full Pool=Weekly 2nd-5th fri at 22:05
  Run = Level=Full Pool=Daily mon-thu at 22:05
}

# This is the backup of the catalog
FileSet {
  Name = "Catalog"
  Include {
    Options {
      signature = MD5
    }
    File = /var/lib/bacula/bacula.sql
    File = /var/lib/bacula/Backup.bsr
  }
}
02-Aug 16:57 FIM-FS-dir: Start Backup JobId 63, 
Job=BackupCatalog.2006-08-02_16.56.25
02-Aug 16:57 FIM-FS-sd: Wrote label to prelabeled Volume "Daily2" on device 
"/dev/st0"
02-Aug 16:57 FIM-FS-dir: Bacula 1.36.2 (28Feb05): 02-Aug-2006 16:57:35
  JobId:                  63
  Job:                    BackupCatalog.2006-08-02_16.56.25
  Backup Level:           Full
  Client:                 FIM-FS-fd
  FileSet:                "Catalog" 2006-07-04 00:01:34
  Pool:                   "Daily"
  Storage:                "Tape"
  Start time:             02-Aug-2006 16:56:27
  End time:               02-Aug-2006 16:57:35
  FD Files Written:       2
  SD Files Written:       2
  FD Bytes Written:       150,350,522
  SD Bytes Written:       150,350,743
  Rate:                   2211.0 KB/s
  Software Compression:   None
  Volume name(s):         Daily2
  Volume Session Id:      1
  Volume Session Time:    1154534228
  Last Volume Bytes:      150,490,218
  Non-fatal FD errors:    0
  SD Errors:              0
  FD termination status:  OK
  SD termination status:  OK
  Termination:            Backup OK

02-Aug 16:57 FIM-FS-dir: Begin pruning Jobs.
02-Aug 16:57 FIM-FS-dir: No Jobs found to prune.
02-Aug 16:57 FIM-FS-dir: Begin pruning Files.
02-Aug 16:57 FIM-FS-dir: Pruned 0 Files from 1 Jobs for client FIM-FS-fd from 
catalog.
02-Aug 16:57 FIM-FS-dir: End auto prune.

02-Aug 16:57 FIM-FS-dir: Start Backup JobId 64, 
Job=Full_Backup.2006-08-02_16.57.29
02-Aug 17:14 FIM-FS-dir: Max Volume jobs exceeded. Marking Volume "Daily2" as 
Used.
02-Aug 17:14 FIM-FS-dir: Bacula 1.36.2 (28Feb05): 02-Aug-2006 17:14:04
  JobId:                  64
  Job:                    Full_Backup.2006-08-02_16.57.29
  Backup Level:           Differential, since=2006-07-31 09:11:41
  Client:                 FIM-FS-fd
  FileSet:                "Full Set" 2006-07-03 12:36:13
  Pool:                   "Daily"
  Storage:                "Tape"
  Start time:             02-Aug-2006 16:57:37
  End time:               02-Aug-2006 17:14:04
  FD Files Written:       1,216
  SD Files Written:       1,216
  FD Bytes Written:       5,666,366,950
  SD Bytes Written:       5,657,180,919
  Rate:                   5741.0 KB/s
  Software Compression:   None
  Volume name(s):         Daily2
  Volume Session Id:      2
  Volume Session Time:    1154534228
  Last Volume Bytes:      5,812,945,261
  Non-fatal FD errors:    0
  SD Errors:              0
  FD termination status:  OK
  SD termination status:  OK
  Termination:            Backup OK

02-Aug 17:14 FIM-FS-dir: Begin pruning Jobs.
02-Aug 17:14 FIM-FS-dir: No Jobs found to prune.
02-Aug 17:14 FIM-FS-dir: Begin pruning Files.
02-Aug 17:14 FIM-FS-dir: No Files found to prune.
02-Aug 17:14 FIM-FS-dir: End auto prune.

02-Aug 21:50 FIM-FS-dir: Start Admin JobId 66, Job=Admin-Job.2006-08-02_21.50.00
02-Aug 21:50 FIM-FS-dir: Bacula 1.36.2 (28Feb05): 02-Aug-2006 21:50
  JobId:                  66
  Job:                    Admin-Job.2006-08-02_21.50.00
  Start time:             02-Aug-2006 21:50
  End time:               02-Aug-2006 21:50
  Termination:            Admin OK

02-Aug 21:50 FIM-FS-dir: Begin pruning Jobs.
02-Aug 21:50 FIM-FS-dir: No Jobs found to prune.
02-Aug 21:50 FIM-FS-dir: Begin pruning Files.
02-Aug 21:50 FIM-FS-dir: No Files found to prune.
02-Aug 21:50 FIM-FS-dir: End auto prune.

02-Aug 22:00 FIM-FS-dir: Start Backup JobId 67, 
Job=Full_Backup.2006-08-02_22.00.00
02-Aug 22:00 FIM-FS-sd: Wrote label to prelabeled Volume "Daily1" on device 
"/dev/st0"
02-Aug 22:10 FIM-FS-dir: Bacula 1.36.2 (28Feb05): 02-Aug-2006 22:10:49
  JobId:                  67
  Job:                    Full_Backup.2006-08-02_22.00.00
  Backup Level:           Differential, since=2006-07-31 09:11:41
  Client:                 FIM-FS-fd
  FileSet:                "Full Set" 2006-07-03 12:36:13
  Pool:                   "Daily"
  Storage:                "Tape"
  Start time:             02-Aug-2006 22:00:02
  End time:               02-Aug-2006 22:10:49
  FD Files Written:       1,227
  SD Files Written:       1,227
  FD Bytes Written:       5,826,402,259
  SD Bytes Written:       5,817,217,851
  Rate:                   9005.3 KB/s
  Software Compression:   None
  Volume name(s):         Daily1
  Volume Session Id:      4
  Volume Session Time:    1154534228
  Last Volume Bytes:      5,822,640,548
  Non-fatal FD errors:    0
  SD Errors:              0
  FD termination status:  OK
  SD termination status:  OK
  Termination:            Backup OK

02-Aug 22:10 FIM-FS-dir: Begin pruning Jobs.
02-Aug 22:10 FIM-FS-dir: No Jobs found to prune.
02-Aug 22:10 FIM-FS-dir: Begin pruning Files.
02-Aug 22:10 FIM-FS-dir: No Files found to prune.
02-Aug 22:10 FIM-FS-dir: End auto prune.

02-Aug 22:11 FIM-FS-dir: Start Backup JobId 68, 
Job=BackupCatalog.2006-08-02_22.05.00
02-Aug 22:11 FIM-FS-dir: Max Volume jobs exceeded. Marking Volume "Daily1" as 
Used.
02-Aug 22:11 FIM-FS-dir: Bacula 1.36.2 (28Feb05): 02-Aug-2006 22:11:12
  JobId:                  68
  Job:                    BackupCatalog.2006-08-02_22.05.00
  Backup Level:           Full
  Client:                 FIM-FS-fd
  FileSet:                "Catalog" 2006-07-04 00:01:34
  Pool:                   "Daily"
  Storage:                "Tape"
  Start time:             02-Aug-2006 22:10:51
  End time:               02-Aug-2006 22:11:12
  FD Files Written:       2
  SD Files Written:       2
  FD Bytes Written:       150,703,335
  SD Bytes Written:       150,703,556
  Rate:                   7176.3 KB/s
  Software Compression:   None
  Volume name(s):         Daily1
  Volume Session Id:      5
  Volume Session Time:    1154534228
  Last Volume Bytes:      5,973,483,914
  Non-fatal FD errors:    0
  SD Errors:              0
  FD termination status:  OK
  SD termination status:  OK
  Termination:            Backup OK

02-Aug 22:11 FIM-FS-dir: Begin pruning Jobs.
02-Aug 22:11 FIM-FS-dir: No Jobs found to prune.
02-Aug 22:11 FIM-FS-dir: Begin pruning Files.
02-Aug 22:11 FIM-FS-dir: No Files found to prune.
02-Aug 22:11 FIM-FS-dir: End auto prune.

03-Aug 21:50 FIM-FS-dir: Start Admin JobId 69, Job=Admin-Job.2006-08-03_21.50.00
03-Aug 21:50 FIM-FS-dir: Bacula 1.36.2 (28Feb05): 03-Aug-2006 21:50
  JobId:                  69
  Job:                    Admin-Job.2006-08-03_21.50.00
  Start time:             03-Aug-2006 21:50
  End time:               03-Aug-2006 21:50
  Termination:            Admin OK

03-Aug 21:50 FIM-FS-dir: Begin pruning Jobs.
03-Aug 21:50 FIM-FS-dir: No Jobs found to prune.
03-Aug 21:50 FIM-FS-dir: Begin pruning Files.
03-Aug 21:50 FIM-FS-dir: Pruned 0 Files from 1 Jobs for client FIM-FS-fd from 
catalog.
03-Aug 21:50 FIM-FS-dir: End auto prune.

03-Aug 22:00 FIM-FS-dir: Start Backup JobId 70, 
Job=Full_Backup.2006-08-03_22.00.00
03-Aug 22:00 FIM-FS-dir: Pruned 2 Jobs on Volume "Daily2" from catalog.
03-Aug 22:00 FIM-FS-sd: Job Full_Backup.2006-08-03_22.00.00 waiting. Cannot 
find any appendable volumes.
Please use the "label"  command to create a new Volume for:
    Storage:      Ultrium-1
    Media type:   Ultrium-1
    Pool:         Daily
03-Aug 23:00 FIM-FS-dir: Recycled volume "Daily2"
03-Aug 23:00 FIM-FS-sd: Please mount Volume "Daily2" on Storage Device 
"Ultrium-1" for Job Full_Backup.2006-08-03_22.00.00
Use "mount" command to release Job.
04-Aug 01:00 FIM-FS-sd: Please mount Volume "Daily2" on Storage Device 
"Ultrium-1" for Job Full_Backup.2006-08-03_22.00.00
Use "mount" command to release Job.
04-Aug 05:00 FIM-FS-sd: Please mount Volume "Daily2" on Storage Device 
"Ultrium-1" for Job Full_Backup.2006-08-03_22.00.00
Use "mount" command to release Job.
04-Aug 09:25 FIM-FS-sd: Recycled volume "Daily2" on device "/dev/st0", all 
previous data lost.
04-Aug 09:39 FIM-FS-dir: Bacula 1.36.2 (28Feb05): 04-Aug-2006 09:39:41
  JobId:                  70
  Job:                    Full_Backup.2006-08-03_22.00.00
  Backup Level:           Differential, since=2006-07-31 09:11:41
  Client:                 FIM-FS-fd
  FileSet:                "Full Set" 2006-07-03 12:36:13
  Pool:                   "Daily"
  Storage:                "Tape"
  Start time:             03-Aug-2006 22:00:02
  End time:               04-Aug-2006 09:39:41
  FD Files Written:       1,458
  SD Files Written:       1,458
  FD Bytes Written:       5,677,711,625
  SD Bytes Written:       5,668,588,166
  Rate:                   135.3 KB/s
  Software Compression:   None
  Volume name(s):         Daily2
  Volume Session Id:      6
  Volume Session Time:    1154534228
  Last Volume Bytes:      5,673,882,103
  Non-fatal FD errors:    0
  SD Errors:              0
  FD termination status:  OK
  SD termination status:  OK
  Termination:            Backup OK

04-Aug 09:39 FIM-FS-dir: Begin pruning Jobs.
04-Aug 09:39 FIM-FS-dir: No Jobs found to prune.
04-Aug 09:39 FIM-FS-dir: Begin pruning Files.
04-Aug 09:39 FIM-FS-dir: No Files found to prune.
04-Aug 09:39 FIM-FS-dir: End auto prune.

04-Aug 09:39 FIM-FS-dir: Start Backup JobId 71, 
Job=BackupCatalog.2006-08-03_22.05.00
04-Aug 09:40 FIM-FS-dir: Max Volume jobs exceeded. Marking Volume "Daily2" as 
Used.
04-Aug 09:40 FIM-FS-dir: Bacula 1.36.2 (28Feb05): 04-Aug-2006 09:40:08
  JobId:                  71
  Job:                    BackupCatalog.2006-08-03_22.05.00
  Backup Level:           Full
  Client:                 FIM-FS-fd
  FileSet:                "Catalog" 2006-07-04 00:01:34
  Pool:                   "Daily"
  Storage:                "Tape"
  Start time:             04-Aug-2006 09:39:43
  End time:               04-Aug-2006 09:40:08
  FD Files Written:       2
  SD Files Written:       2
  FD Bytes Written:       150,747,332
  SD Bytes Written:       150,747,553
  Rate:                   6029.9 KB/s
  Software Compression:   None
  Volume name(s):         Daily2
  Volume Session Id:      7
  Volume Session Time:    1154534228
  Last Volume Bytes:      5,824,769,490
  Non-fatal FD errors:    0
  SD Errors:              0
  FD termination status:  OK
  SD termination status:  OK
  Termination:            Backup OK

04-Aug 09:40 FIM-FS-dir: Begin pruning Jobs.
04-Aug 09:40 FIM-FS-dir: No Jobs found to prune.
04-Aug 09:40 FIM-FS-dir: Begin pruning Files.
04-Aug 09:40 FIM-FS-dir: No Files found to prune.
04-Aug 09:40 FIM-FS-dir: End auto prune.
'list volumes' Aug 03, 9:20

+---------+------------+-----------+------------+----------+--------------+---------+------+-----------+-----------+---------------------+
| MediaId | VolumeName | VolStatus | VolBytes   | VolFiles | VolRetention | 
Recycle | Slot | InChanger | MediaType | LastWritten         |
+---------+------------+-----------+------------+----------+--------------+---------+------+-----------+-----------+---------------------+
| 20      | Daily2     | Recycle   | 1          | 0        | 90000        | 1   
    | 0    | 1         | Ultrium-1 | 2006-08-02 17:14:04 |
| 21      | Daily1     | Used      | 5973483914 | 7        | 90000        | 1   
    | 0    | 1         | Ultrium-1 | 2006-08-02 22:11:12 |
+---------+------------+-----------+------------+----------+--------------+---------+------+-----------+-----------+---------------------+
Pool: Weekly
+---------+------------+-----------+-------------+----------+--------------+---------+------+-----------+-----------+---------------------+
| MediaId | VolumeName | VolStatus | VolBytes    | VolFiles | VolRetention | 
Recycle | Slot | InChanger | MediaType | LastWritten         |
+---------+------------+-----------+-------------+----------+--------------+---------+------+-----------+-----------+---------------------+
| 5       | Week1      | Used      | 67309343073 | 69       | 1987200      | 1  
     | 0    | 1         | Ultrium-1 | 2006-07-15 00:11:48 |
| 6       | Week2      | Used      | 70031299923 | 71       | 1987200      | 1  
     | 0    | 1         | Ultrium-1 | 2006-07-22 00:15:59 |
| 10      | Week3      | Used      | 70422610439 | 72       | 1987200      | 1  
     | 0    | 1         | Ultrium-1 | 2006-07-31 11:32:27 |
| 11      | Week4      | Purged    | 1           | 0        | 1987200      | 1  
     | 0    | 1         | Ultrium-1 | 0                   |
+---------+------------+-----------+-------------+----------+--------------+---------+------+-----------+-----------+---------------------+
Pool: Monthly
+---------+------------+-----------+----------+----------+--------------+---------+------+-----------+-----------+-------------+
| MediaId | VolumeName | VolStatus | VolBytes | VolFiles | VolRetention | 
Recycle | Slot | InChanger | MediaType | LastWritten |
+---------+------------+-----------+----------+----------+--------------+---------+------+-----------+-----------+-------------+
| 8       | Month1     | Append    | 1        | 0        | 4838400      | 1     
  | 0    | 1         | Ultrium-1 | 0           |
| 9       | Month2     | Append    | 1        | 0        | 4838400      | 1     
  | 0    | 1         | Ultrium-1 | 0           |
+---------+------------+-----------+----------+----------+--------------+---------+------+-----------+-----------+-------------+
-------------------------------------------------------------------------
Take Surveys. Earn Cash. Influence the Future of IT
Join SourceForge.net's Techsay panel and you'll get the chance to share your
opinions on IT & business topics through brief surveys -- and earn cash
http://www.techsay.com/default.php?page=join.php&p=sourceforge&CID=DEVDEV
_______________________________________________
Bacula-users mailing list
[email protected]
https://lists.sourceforge.net/lists/listinfo/bacula-users

Reply via email to