I upgraded from Bacula 9.0.2 to 9.0.3 (compile from source).
After this I got error messages about waiting to reserve a device which
can be seen in lines 3,4 & 5 in the following message output.
11-Aug 04:29 venus-dir JobId 11710: shell command: run BeforeJob
"/opt/bacula/etc/make_catalog_backup bacula bacula"
11-Aug 04:30 venus-dir JobId 11710: Start Backup JobId 11710,
Job=BackupCatalog.2017-08-11_01.15.00_20
11-Aug 04:44 venus-sd JobId 11710: JobId=11710, Job
BackupCatalog.2017-08-11_01.15.00_20 waiting to reserve a device.
11-Aug 04:49 venus-sd JobId 11710: JobId=11710, Job
BackupCatalog.2017-08-11_01.15.00_20 waiting to reserve a device.
11-Aug 04:54 venus-sd JobId 11710: JobId=11710, Job
BackupCatalog.2017-08-11_01.15.00_20 waiting to reserve a device.
11-Aug 04:56 venus-dir JobId 11710: There are no more Jobs associated with Volume
"venus-fd-Cat-11". Marking it purged.
11-Aug 04:56 venus-dir JobId 11710: All records pruned from Volume "venus-fd-Cat-11";
marking it "Purged"
11-Aug 04:56 venus-dir JobId 11710: Recycled volume "venus-fd-Cat-11"
11-Aug 04:56 venus-dir JobId 11710: Using Device "FileStorage" to write.
11-Aug 04:56 venus-sd JobId 11710: Recycled volume "venus-fd-Cat-11" on File device
"FileStorage" (/mnt/Backup_Disk), all previous data lost.
11-Aug 04:56 venus-dir JobId 11710: Max Volume jobs=1 exceeded. Marking Volume
"venus-fd-Cat-11" as Used.
11-Aug 04:56 venus-sd JobId 11710: Elapsed time=00:00:03, Transfer rate=33.88 M
Bytes/second
11-Aug 04:56 venus-dir JobId 11710: Max Volume jobs=1 exceeded. Marking Volume
"venus-fd-Cat-11" as Used.
11-Aug 04:56 venus-sd JobId 11710: Sending spooled attrs to the Director.
Despooling 232 bytes ...
11-Aug 04:56 venus-dir JobId 11710: Bacula venus-dir 9.0.3 (08Aug17):
Build OS: x86_64-pc-linux-gnu ubuntu 14.04
JobId: 11710
Job: BackupCatalog.2017-08-11_01.15.00_20
Backup Level: Full
Client: "venus-fd" 9.0.3 (08Aug17)
x86_64-pc-linux-gnu,ubuntu,14.04
FileSet: "Catalog" 2016-08-21 01:15:00
Pool: "Cat-Pool" (From Job resource)
Catalog: "MyCatalog" (From Client resource)
Storage: "FileStorage" (From Job resource)
Scheduled time: 11-Aug-2017 01:15:00
Start time: 11-Aug-2017 04:56:15
End time: 11-Aug-2017 04:56:18
Elapsed time: 3 secs
Priority: 11
FD Files Written: 1
SD Files Written: 1
FD Bytes Written: 101,643,651 (101.6 MB)
SD Bytes Written: 101,643,767 (101.6 MB)
Rate: 33881.2 KB/s
Software Compression: None
Comm Line Compression: 47.2% 1.9:1
Snapshot/VSS: no
Encryption: no
Accurate: no
Volume name(s): venus-fd-Cat-11
Volume Session Id: 59
Volume Session Time: 1502211541
Last Volume Bytes: 101,719,765 (101.7 MB)
Non-fatal FD errors: 0
SD Errors: 0
FD termination status: OK
SD termination status: OK
Termination: Backup OK
11-Aug 04:56 venus-dir JobId 11710: Begin pruning Jobs older than 5 months .
11-Aug 04:56 venus-dir JobId 11710: No Jobs found to prune.
11-Aug 04:56 venus-dir JobId 11710: Begin pruning Files.
11-Aug 04:56 venus-dir JobId 11710: No Files found to prune.
11-Aug 04:56 venus-dir JobId 11710: End auto prune.
11-Aug 04:56 venus-dir JobId 11710: shell command: run AfterJob
"/opt/bacula/etc/delete_catalog_backup"
Additionally I got a separate message for each one of these three lines.
The same thing is happening with every one of my File Daemons. All
systems were updated to 9.0.3.
Reverting back to 9.0.2 these waiting to reserve a device messages stop
occurring as seen in the message for the same Catalog Backup event the
following night.
12-Aug 01:15 venus-dir JobId 11714: shell command: run BeforeJob
"/opt/bacula/etc/make_catalog_backup bacula bacula"
12-Aug 01:16 venus-dir JobId 11714: Start Backup JobId 11714,
Job=BackupCatalog.2017-08-12_01.15.00_07
12-Aug 01:16 venus-dir JobId 11714: There are no more Jobs associated with Volume
"venus-fd-Cat-12". Marking it purged.
12-Aug 01:16 venus-dir JobId 11714: All records pruned from Volume "venus-fd-Cat-12";
marking it "Purged"
12-Aug 01:16 venus-dir JobId 11714: Recycled volume "venus-fd-Cat-12"
12-Aug 01:16 venus-dir JobId 11714: Using Device "FileStorage" to write.
12-Aug 01:16 venus-sd JobId 11714: Recycled volume "venus-fd-Cat-12" on File device
"FileStorage" (/mnt/Backup_Disk), all previous data lost.
12-Aug 01:16 venus-dir JobId 11714: Max Volume jobs=1 exceeded. Marking Volume
"venus-fd-Cat-12" as Used.
12-Aug 01:16 venus-sd JobId 11714: Elapsed time=00:00:03, Transfer rate=34.89 M
Bytes/second
12-Aug 01:16 venus-dir JobId 11714: Max Volume jobs=1 exceeded. Marking Volume
"venus-fd-Cat-12" as Used.
12-Aug 01:16 venus-sd JobId 11714: Sending spooled attrs to the Director.
Despooling 232 bytes ...
12-Aug 01:16 venus-dir JobId 11714: Bacula venus-dir 9.0.2 (24Jul17):
Build OS: x86_64-pc-linux-gnu ubuntu 14.04
JobId: 11714
Job: BackupCatalog.2017-08-12_01.15.00_07
Backup Level: Full
Client: "venus-fd" 9.0.2 (24Jul17)
x86_64-pc-linux-gnu,ubuntu,14.04
FileSet: "Catalog" 2016-08-21 01:15:00
Pool: "Cat-Pool" (From Job resource)
Catalog: "MyCatalog" (From Client resource)
Storage: "FileStorage" (From Job resource)
Scheduled time: 12-Aug-2017 01:15:00
Start time: 12-Aug-2017 01:16:04
End time: 12-Aug-2017 01:16:07
Elapsed time: 3 secs
Priority: 11
FD Files Written: 1
SD Files Written: 1
FD Bytes Written: 104,674,186 (104.6 MB)
SD Bytes Written: 104,674,302 (104.6 MB)
Rate: 34891.4 KB/s
Software Compression: None
Comm Line Compression: 47.3% 1.9:1
Snapshot/VSS: no
Encryption: no
Accurate: no
Volume name(s): venus-fd-Cat-12
Volume Session Id: 12
Volume Session Time: 1502475864
Last Volume Bytes: 104,752,556 (104.7 MB)
Non-fatal FD errors: 0
SD Errors: 0
FD termination status: OK
SD termination status: OK
Termination: Backup OK
12-Aug 01:16 venus-dir JobId 11714: Begin pruning Jobs older than 5 months .
12-Aug 01:16 venus-dir JobId 11714: No Jobs found to prune.
12-Aug 01:16 venus-dir JobId 11714: Begin pruning Files.
12-Aug 01:16 venus-dir JobId 11714: No Files found to prune.
12-Aug 01:16 venus-dir JobId 11714: End auto prune.
12-Aug 01:16 venus-dir JobId 11714: shell command: run AfterJob
"/opt/bacula/etc/delete_catalog_backup"
Any thoughts on what is causing this.
My Bacula setup is running with a Hard Disk for storage. Nothing was
changed in any of the conf files for this update.
If any more information or debug data is required, let me know and I
will collect it.
Best Regards,
Adolf Belka.
--
Sent from my Desktop Computer
------------------------------------------------------------------------------
Check out the vibrant tech community on one of the world's most
engaging tech sites, Slashdot.org! http://sdm.link/slashdot
_______________________________________________
Bacula-users mailing list
Bacula-users@lists.sourceforge.net
https://lists.sourceforge.net/lists/listinfo/bacula-users
