Hi all Baculas.

This night I had a slight problem when Bacula changed and recycled tapes.
We have a "Max Use Duration" of 36 hours configured for our daily pool.

As you can see from the attached output of one job this night, that time wasn't 
used correctly, cause a tape that was recycled at 3:07am was marked "used" ~45 
minutes later.
I can't provide any other information on the state before the backup than that 
the tapes was marked "purged" and was therefor correctly used.

As you can see below we are running 1.38.5 on a Linux 2.6 machinge (suse 10.0).
Any known problems with that?

Greetings, Michael

---------- Forwarded message ----------
From: Bacula
To:  Backup-Master
Date: Wed, 31 May 2006 03:53:20 +0200
Subject: [Bacula] :OK: Backup-Job 6262 (raid_fserv1)

31-May 01:34 GiS-dir: Start Backup JobId 6262, 
Job=raid_fserv1.2006-05-31_00.00.01
31-May 01:34 bck01-sd: 3301 Issuing autochanger "loaded drive 0" command.
31-May 01:34 bck01-sd: 3302 Autochanger "loaded drive 0", result is Slot 1.
31-May 01:34 bck01-sd: 3301 Issuing autochanger "loaded drive 0" command.
31-May 01:34 bck01-sd: 3302 Autochanger "loaded drive 0", result is Slot 1.
31-May 01:35 bck01-sd: Volume "D13291" previously written, moving to end of 
data.
31-May 01:36 bck01-sd: Ready to append to end of Volume "D13291" at file=107.
31-May 03:04 bck01-sd: End of Volume "D13291" at 184:4974 on device 
"TapeStorage" (/dev/tape). Write of 64512 bytes got -1.
31-May 03:05 bck01-sd: Re-read of last block succeeded.
31-May 03:05 bck01-sd: End of medium on Volume "D13291" Bytes=179,878,423,235 
Blocks=2,788,299 at 31-May-2006 03:05.
31-May 03:05 bck01-sd: 3301 Issuing autochanger "loaded drive 0" command.
31-May 03:05 bck01-sd: 3302 Autochanger "loaded drive 0", result is Slot 1.
31-May 03:05 bck01-sd: 3307 Issuing autochanger "unload slot 1, drive 0" 
command.
31-May 03:06 bck01-sd: 3304 Issuing autochanger "load slot 2, drive 0" command.
31-May 03:07 bck01-sd: 3305 Autochanger "load slot 2, drive 0", status is OK.
31-May 03:07 bck01-sd: 3301 Issuing autochanger "loaded drive 0" command.
31-May 03:07 bck01-sd: 3302 Autochanger "loaded drive 0", result is Slot 2.
31-May 03:07 bck01-sd: Recycled volume "D13290" on device "TapeStorage" 
(/dev/tape), all previous data lost.
31-May 03:07 bck01-sd: New volume "D13290" mounted on device "TapeStorage" 
(/dev/tape) at 31-May-2006 03:07.
31-May 03:51 GiS-dir: Max configured use duration exceeded. Marking Volume 
"D13290" as Used.
31-May 03:53 GiS-dir: Bacula 1.38.5 (18Jan06): 31-May-2006 03:53:19
  JobId:                  6262
  Job:                    raid_fserv1.2006-05-31_00.00.01
  Backup Level:           Full
  Client:                 "bck01" x86_64-suse-linux-gnu,suse,10.0
  FileSet:                "raid_fserv1" 2005-12-15 00:00:02
  Pool:                   "Daily"
  Storage:                "LTO"
  Scheduled time:         31-May-2006 00:00:00
  Start time:             31-May-2006 01:34:24
  End time:               31-May-2006 03:53:19
  Priority:               80
  FD Files Written:       744,724
  SD Files Written:       744,724
  FD Bytes Written:       92,948,981,352
  SD Bytes Written:       93,071,639,372
  Rate:                   11151.6 KB/s
  Software Compression:   None
  Volume name(s):         D13291|D13290
  Volume Session Id:      287
  Volume Session Time:    1146829671
  Last Volume Bytes:      15,840,661,079
  Non-fatal FD errors:    0
  SD Errors:              0
  FD termination status:  OK
  SD termination status:  OK
  Termination:            Backup OK

31-May 03:53 GiS-dir: Begin pruning Jobs.
31-May 03:53 GiS-dir: No Jobs found to prune.
31-May 03:53 GiS-dir: Begin pruning Files.
31-May 03:53 GiS-dir: No Files found to prune.
31-May 03:53 GiS-dir: End auto prune.




_______________________________________________
Bacula-users mailing list
[email protected]
https://lists.sourceforge.net/lists/listinfo/bacula-users

Reply via email to