"No prior or suitable Full backup found in catalog. Doing FULL backup" means
that it didn't find a Full backup for some reason.

The output from the following SQL command might be useful (maybe some other
Name= if you have a more recent example for a different job):

SELECT StartTime, JobID, Job, PriorJob, JobStatus, Type, Level, ClientId FROM 
Job WHERE Name='Taco-Data-E' ORDER BY StartTime DESC;

__Martin


>>>>> On Thu, 12 Jun 2025 14:42:24 -0400, John Lockard said:
> 
> 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 
> "http://si-scott.miserver.it.umich.edu:9103/"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 
> "http://taco.si.umich.edu:9102/"taco.si.umich.edu:9102 with TLS               
>   |
> | 2025-06-12 10:24:40 | taco JobId 4129: Connected to Storage at 
> "http://si-scott.miserver.it.umich.edu:9103/"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 
> "http://si-scott.miserver.it.umich.edu:9103/"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 
> <"mailto:bacula-users@lists.sourceforge.net"bacula-users@lists.sourceforge.net>
>  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
> "mailto:w...@protonmail.com"w...@protonmail.com
> 
> _______________________________________________
> 
> Bacula-users mailing list
> "mailto:Bacula-users@lists.sourceforge.net"Bacula-users@lists.sourceforge.net
> "https://lists.sourceforge.net/lists/listinfo/bacula-users"https://lists.sourceforge.net/lists/listinfo/bacula-users
> 
> 


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

Reply via email to