My Fulls have a 60 day lifespan.
Full was last run May 18.
Fileset was last modified May 2.
Bacula version on DIR, SD and FD are all 15.0.3
Log from the new, elevated job.
+---------------------+----------------------------------------------------------------------------------------------------+
| time | logtext
|
+---------------------+----------------------------------------------------------------------------------------------------+
| 2025-06-12 10:24:38 | bacula-dir JobId 4129: No prior or suitable Full
backup found in catalog. Doing FULL backup. |
| 2025-06-12 10:24:40 | bacula-dir JobId 4129: Start Backup JobId 4129,
Job=Taco-Data-E.2025-06-12_10.24.38_27 |
| 2025-06-12 10:24:40 | bacula-dir JobId 4129: Connected to Storage
"FileChanger" at si-scott.miserver.it.umich.edu:9103 with TLS |
| 2025-06-12 10:24:40 | bacula-dir JobId 4129: Using Device
"FileChanger-Dev7" to write. |
| 2025-06-12 10:24:40 | bacula-dir JobId 4129: Connected to Client "taco"
at taco.si.umich.edu:9102 with TLS |
| 2025-06-12 10:24:40 | taco JobId 4129: Connected to Storage at
si-scott.miserver.it.umich.edu:9103 with TLS |
| 2025-06-12 10:24:42 | bacula-sd JobId 4129: Recycled volume "Taco-F-0803"
on File device "FileChanger-Dev7"
(/mnt/nfs/locker/umsi-locker-backups/storage/), all previous data lost. |
| 2025-06-12 10:24:42 | bacula-dir JobId 4129: Max Volume jobs=1 exceeded.
Marking Volume "Taco-F-0803" as Used. |
| 2025-06-12 10:41:11 | bacula-sd JobId 4129: End of medium on Volume
"Taco-F-0803" Bytes=107,374,159,824 Blocks=1,664,406 at 12-Jun-2025 10:41. |
| 2025-06-12 10:41:13 | bacula-sd JobId 4129: Recycled volume "Taco-F-0806"
on File device "FileChanger-Dev7"
(/mnt/nfs/locker/umsi-locker-backups/storage/), all previous data lost. |
| 2025-06-12 10:41:13 | bacula-dir JobId 4129: Max Volume jobs=1 exceeded.
Marking Volume "Taco-F-0806" as Used. |
| 2025-06-12 10:41:13 | bacula-sd JobId 4129: New volume "Taco-F-0806"
mounted on device "FileChanger-Dev7"
(/mnt/nfs/locker/umsi-locker-backups/storage/) at 12-Jun-2025 10:41. |
| 2025-06-12 10:57:33 | bacula-sd JobId 4129: End of medium on Volume
"Taco-F-0806" Bytes=107,374,159,833 Blocks=1,664,406 at 12-Jun-2025 10:57. |
| 2025-06-12 10:57:34 | bacula-sd JobId 4129: Recycled volume "Taco-F-0808"
on File device "FileChanger-Dev7"
(/mnt/nfs/locker/umsi-locker-backups/storage/), all previous data lost. |
| 2025-06-12 10:57:34 | bacula-dir JobId 4129: Max Volume jobs=1 exceeded.
Marking Volume "Taco-F-0808" as Used. |
| 2025-06-12 10:57:34 | bacula-sd JobId 4129: New volume "Taco-F-0808"
mounted on device "FileChanger-Dev7"
(/mnt/nfs/locker/umsi-locker-backups/storage/) at 12-Jun-2025 10:57. |
| 2025-06-12 11:25:13 | bacula-sd JobId 4129: End of medium on Volume
"Taco-F-0808" Bytes=107,374,158,154 Blocks=1,664,406 at 12-Jun-2025 11:25. |
| 2025-06-12 11:25:14 | bacula-sd JobId 4129: Recycled volume "Taco-F-0810"
on File device "FileChanger-Dev7"
(/mnt/nfs/locker/umsi-locker-backups/storage/), all previous data lost. |
| 2025-06-12 11:25:14 | bacula-dir JobId 4129: Max Volume jobs=1 exceeded.
Marking Volume "Taco-F-0810" as Used. |
| 2025-06-12 11:25:14 | bacula-sd JobId 4129: New volume "Taco-F-0810"
mounted on device "FileChanger-Dev7"
(/mnt/nfs/locker/umsi-locker-backups/storage/) at 12-Jun-2025 11:25. |
| 2025-06-12 11:57:59 | bacula-sd JobId 4129: End of medium on Volume
"Taco-F-0810" Bytes=107,374,157,610 Blocks=1,664,406 at 12-Jun-2025 11:57. |
| 2025-06-12 11:58:01 | bacula-sd JobId 4129: Recycled volume "Taco-F-0813"
on File device "FileChanger-Dev7"
(/mnt/nfs/locker/umsi-locker-backups/storage/), all previous data lost. |
| 2025-06-12 11:58:01 | bacula-dir JobId 4129: Max Volume jobs=1 exceeded.
Marking Volume "Taco-F-0813" as Used. |
| 2025-06-12 11:58:01 | bacula-sd JobId 4129: New volume "Taco-F-0813"
mounted on device "FileChanger-Dev7"
(/mnt/nfs/locker/umsi-locker-backups/storage/) at 12-Jun-2025 11:58. |
+---------------------+----------------------------------------------------------------------------------------------------+
Log from previous, failed, VirtualFull job.
+---------------------+----------------------------------------------------------------------------------------------------+
| time | logtext
|
+---------------------+----------------------------------------------------------------------------------------------------+
| 2025-06-12 06:38:42 | bacula-dir JobId 4126: Start Virtual Backup JobId
4126, Job=Taco-Data-E.2025-06-11_21.15.00_22 |
| 2025-06-12 06:38:42 | bacula-dir JobId 4126: Consolidating
JobIds=3331,3627,3647,3707,3736,3763,3790,3817 |
| 2025-06-12 06:39:09 | bacula-dir JobId 4126: Found 1499732 files to
consolidate into Virtual Full. |
| 2025-06-12 06:39:09 | bacula-dir JobId 4126: Connected to Storage
"FileChanger" at si-scott.miserver.it.umich.edu:9103 with TLS |
| 2025-06-12 06:39:09 | bacula-dir JobId 4126: Using Device
"FileChanger-Dev1" to read. |
| 2025-06-12 06:39:09 | bacula-dir JobId 4126: There are no more Jobs
associated with Volume "Taco-F-0782". Marking it purged. |
| 2025-06-12 06:39:09 | bacula-dir JobId 4126: All records pruned from
Volume "Taco-F-0782"; marking it "Purged" |
| 2025-06-12 06:39:09 | bacula-dir JobId 4126: Recycled volume
"Taco-F-0782" |
| 2025-06-12 06:39:09 | bacula-dir JobId 4126: Using Device
"FileChanger-Dev4" to write. |
| 2025-06-12 06:39:09 | bacula-sd JobId 4126: Ready to read from volume
"Taco-F-0803" on File device "FileChanger-Dev1"
(/mnt/nfs/locker/umsi-locker-backups/storage/). |
| 2025-06-12 06:39:10 | bacula-sd JobId 4126: Recycled volume "Taco-F-0782"
on File device "FileChanger-Dev4"
(/mnt/nfs/locker/umsi-locker-backups/storage/), all previous data lost. |
| 2025-06-12 06:39:10 | bacula-dir JobId 4126: Max Volume jobs=1 exceeded.
Marking Volume "Taco-F-0782" as Used. |
| 2025-06-12 06:39:10 | bacula-sd JobId 4126: Forward spacing Volume
"Taco-F-0803" to addr=268 |
| 2025-06-12 06:50:50 | bacula-sd JobId 4126: End of Volume "Taco-F-0803"
at addr=107374159765 on device "FileChanger-Dev1"
(/mnt/nfs/locker/umsi-locker-backups/storage/). |
| 2025-06-12 06:50:50 | bacula-sd JobId 4126: Ready to read from volume
"Taco-F-0806" on File device "FileChanger-Dev1"
(/mnt/nfs/locker/umsi-locker-backups/storage/). |
| 2025-06-12 06:50:50 | bacula-sd JobId 4126: Forward spacing Volume
"Taco-F-0806" to addr=268 |
| 2025-06-12 06:50:50 | bacula-sd JobId 4126: End of medium on Volume
"Taco-F-0782" Bytes=107,374,159,749 Blocks=1,664,406 at 12-Jun-2025 06:50. |
| 2025-06-12 06:50:54 | bacula-dir JobId 4126: There are no more Jobs
associated with Volume "Taco-F-0786". Marking it purged. |
| 2025-06-12 06:50:54 | bacula-dir JobId 4126: All records pruned from
Volume "Taco-F-0786"; marking it "Purged" |
| 2025-06-12 06:50:54 | bacula-dir JobId 4126: Recycled volume
"Taco-F-0786" |
|
...some equally similar lines removed...
|
| 2025-06-12 07:44:34 | bacula-sd JobId 4126: Recycled volume "Taco-F-0801"
on File device "FileChanger-Dev4"
(/mnt/nfs/locker/umsi-locker-backups/storage/), all previous data lost. |
| 2025-06-12 07:44:34 | bacula-dir JobId 4126: Max Volume jobs=1 exceeded.
Marking Volume "Taco-F-0801" as Used. |
| 2025-06-12 07:44:34 | bacula-sd JobId 4126: New volume "Taco-F-0801"
mounted on device "FileChanger-Dev4"
(/mnt/nfs/locker/umsi-locker-backups/storage/) at 12-Jun-2025 07:44. |
| 2025-06-12 07:50:37 | bacula-sd JobId 4126: End of Volume "Taco-F-0817"
at addr=107374155839 on device "FileChanger-Dev1"
(/mnt/nfs/locker/umsi-locker-backups/storage/). |
| 2025-06-12 07:50:37 | bacula-sd JobId 4126: Ready to read from volume
"Taco-F-0819" on File device "FileChanger-Dev1"
(/mnt/nfs/locker/umsi-locker-backups/storage/). |
| 2025-06-12 07:50:37 | bacula-sd JobId 4126: Forward spacing Volume
"Taco-F-0819" to addr=268 |
| 2025-06-12 07:55:05 | bacula-sd JobId 4126: End of medium on Volume
"Taco-F-0801" Bytes=107,374,156,038 Blocks=1,664,406 at 12-Jun-2025 07:55. |
| 2025-06-12 07:55:12 | bacula-sd JobId 4126: Job
Taco-Data-E.2025-06-11_21.15.00_22 is waiting. Cannot find any appendable
volumes.
Please use the "label" command to create a new Volume for:
Storage: "FileChanger-Dev4"
(/mnt/nfs/locker/umsi-locker-backups/storage/)
Pool: Taco-Full
Media type: File |
| 2025-06-12 08:55:15 | bacula-sd JobId 4126: Job
Taco-Data-E.2025-06-11_21.15.00_22 is waiting. Cannot find any appendable
volumes.
Please use the "label" command to create a new Volume for:
Storage: "FileChanger-Dev4"
(/mnt/nfs/locker/umsi-locker-backups/storage/)
Pool: Taco-Full
Media type: File |
| 2025-06-12 10:24:13 | bacula-sd JobId 4126: askdir.c:878 Job was stopped
by the user. |
| 2025-06-12 10:24:13 | bacula-dir JobId 4126: Error: Unable to copy
objects ERR= |
| 2025-06-12 10:24:13 | bacula-dir JobId 4126: Bacula bacula-dir 15.0.3
(25Mar25):
Build OS: x86_64-pc-linux-gnu ubuntu 24.04
JobId: 4126
Job: Taco-Data-E.2025-06-11_21.15.00_22
Backup Level: Virtual Full
Client: "taco" 15.0.3 (25Mar25)
x86_64-pc-linux-gnu,ubuntu,22.04
FileSet: "Taco-Data-E" 2025-05-16 16:19:24
Pool: "Taco-Full" (From Pool's NextPool resource)
Catalog: "MyCatalog" (From Client resource)
Storage: "FileChanger" (From Pool's NextPool resource)
Scheduled time: 11-Jun-2025 21:15:00
Start time: 03-Jun-2025 23:47:44
End time: 03-Jun-2025 23:49:31
Elapsed time: 3 hours 45 mins 4 secs
Priority: 10
SD Files Written: 0
SD Bytes Written: 0 (0 B)
Rate: 0.0 KB/s
Volume name(s):
Taco-F-0782|Taco-F-0786|Taco-F-0789|Taco-F-0791|Taco-F-0796|Taco-F-0798|Taco-F-0801
Volume Session Id: 79
Volume Session Time: 1749606283
Last Volume Bytes: 107,374,156,038 (107.3 GB)
SD Errors: 0
SD termination status: Canceled
Termination: Backup Canceled |
+---------------------+----------------------------------------------------------------------------------------------------+
On Thu, Jun 12, 2025 at 12:13 PM Bill Arlofski via Bacula-users <
[email protected]> wrote:
> On 6/12/25 9:24 AM, John Lockard wrote:
> >
> > I've seen this a couple of times, and haven't created a small job to
> test, but it appears that when I'm running a
> > VirtualFull, and it fails for whatever reason, the next time a job for
> that FileSet runs it gets elevated to a Full and
> > states that there is no previous Full.
> >
> > Is this a normal reaction to a Failed Virtual Full?
> >
> > Thanks,
> > -John
>
> Hello John,
>
> My first guess is that your original Full may have simply fallen out of
> its retention period, and it is just a coincidence
> that you get this backup level upgrade after a failed VFull. :)
>
> A failed VFull does nothing to the original Full in the catalog, so this
> is strange.
>
> What is the exact message about the job being upgraded?
>
> Any chance the Fileset had been edited in this timeline too?
>
> If you are using a semi-recent version of Bacula, the job summary has some
> details about the last time a fileset was modified.
>
> I guess I am saying we will need some
> more information. :)
>
>
> Best regards,
> Bill
>
> --
> Bill Arlofski
> [email protected]
> _______________________________________________
> Bacula-users mailing list
> [email protected]
> https://lists.sourceforge.net/lists/listinfo/bacula-users
>
_______________________________________________
Bacula-users mailing list
[email protected]
https://lists.sourceforge.net/lists/listinfo/bacula-users
