Mandi! Josh Fisher via Bacula-users
In chel di` si favelave...
>> This is the Easter weekend in italy, so backup will fail in most of my
>> sites; i'm enabling debug for sites, i'll come back here on monday...
> When the magazine is ejected and no magazine is in drive, the output of
> 'list media' command from bconsole should be saved to see if it shows all
> volumes to be not in changer, if that is possible for you.
This is not the case. This morning, many sites are in this situation:
*list media pool=VEN-VI-VIPVE2RDXPool
Automatically selected Catalog: BaculaLNF
Using Catalog "BaculaLNF"
+---------+---------------------+-----------+---------+-----------------+----------+--------------+---------+------+-----------+-----------+---------+----------+---------------------+-----------+
| mediaid | volumename | volstatus | enabled | volbytes |
volfiles | volretention | recycle | slot | inchanger | mediatype | voltype |
volparts | lastwritten | expiresin |
+---------+---------------------+-----------+---------+-----------------+----------+--------------+---------+------+-----------+-----------+---------+----------+---------------------+-----------+
| 65 | VIPVE2RDX_0000_0000 | Used | 1 | 31,662,315,256 |
7 | 1,728,000 | 1 | 1 | 1 | RDX | 1 |
0 | 2024-03-27 23:19:24 | 1,260,715 |
| 66 | VIPVE2RDX_0000_0001 | Used | 1 | 252,325,621,886 |
58 | 1,728,000 | 1 | 2 | 1 | RDX | 1 |
0 | 2024-03-22 23:14:40 | 828,431 |
| 67 | VIPVE2RDX_0000_0002 | Used | 1 | 30,667,314,171 |
7 | 1,728,000 | 1 | 3 | 1 | RDX | 1 |
0 | 2024-03-25 23:18:10 | 1,087,841 |
| 68 | VIPVE2RDX_0000_0003 | Used | 1 | 30,831,948,368 |
7 | 1,728,000 | 1 | 4 | 1 | RDX | 1 |
0 | 2024-03-26 23:18:37 | 1,174,268 |
| 69 | VIPVE2RDX_0000_0004 | Used | 1 | 30,341,914,783 |
7 | 1,728,000 | 1 | 5 | 1 | RDX | 1 |
0 | 2024-03-23 23:27:48 | 915,619 |
| 70 | VIPVE2RDX_0000_0005 | Used | 1 | 30,470,135,772 |
7 | 1,728,000 | 1 | 6 | 1 | RDX | 1 |
0 | 2024-03-24 23:19:36 | 1,001,527 |
| 71 | VIPVE2RDX_0000_0006 | Used | 1 | 144,018,213,730 |
33 | 1,728,000 | 1 | 7 | 1 | RDX | 1 |
0 | 2024-03-24 02:22:19 | 926,090 |
| 72 | VIPVE2RDX_0000_0007 | Used | 1 | 30,743,038,072 |
7 | 1,728,000 | 1 | 8 | 1 | RDX | 1 |
0 | 2024-03-28 23:17:54 | 1,347,025 |
| 135 | VIPVE2RDX_0001_0000 | Error | 1 | 1 |
0 | 1,728,000 | 1 | 0 | 0 | RDX | 1 |
0 | 2024-03-12 23:17:57 | 0 |
| 136 | VIPVE2RDX_0001_0001 | Error | 1 | 1 |
0 | 1,728,000 | 1 | 0 | 0 | RDX | 1 |
0 | 2024-03-11 23:18:58 | 0 |
| 137 | VIPVE2RDX_0001_0002 | Used | 1 | 30,524,652,597 |
7 | 1,728,000 | 1 | 0 | 0 | RDX | 1 |
0 | 2024-03-13 23:18:41 | 51,072 |
| 138 | VIPVE2RDX_0001_0003 | Used | 1 | 30,264,937,403 |
7 | 1,728,000 | 1 | 0 | 0 | RDX | 1 |
0 | 2024-03-14 23:18:48 | 137,479 |
| 139 | VIPVE2RDX_0001_0004 | Error | 1 | 1 |
0 | 1,728,000 | 1 | 0 | 0 | RDX | 1 |
0 | 2024-03-08 23:12:06 | 0 |
| 140 | VIPVE2RDX_0001_0005 | Error | 1 | 1 |
0 | 1,728,000 | 1 | 0 | 0 | RDX | 1 |
0 | 2024-03-10 23:19:45 | 0 |
| 141 | VIPVE2RDX_0001_0006 | Error | 1 | 1 |
0 | 1,728,000 | 1 | 0 | 0 | RDX | 1 |
0 | 2024-03-10 02:24:11 | 0 |
| 142 | VIPVE2RDX_0001_0007 | Error | 1 | 1 |
0 | 1,728,000 | 1 | 0 | 0 | RDX | 1 |
0 | 2024-03-09 23:25:38 | 0 |
| 205 | VIPVE2RDX_0002_0000 | Used | 1 | 30,086,601,942 |
7 | 1,728,000 | 1 | 0 | 0 | RDX | 1 |
0 | 2024-03-16 23:30:30 | 310,981 |
| 206 | VIPVE2RDX_0002_0001 | Used | 1 | 30,398,466,009 |
7 | 1,728,000 | 1 | 0 | 0 | RDX | 1 |
0 | 2024-03-20 23:18:28 | 655,859 |
| 207 | VIPVE2RDX_0002_0002 | Used | 1 | 30,536,528,886 |
7 | 1,728,000 | 1 | 0 | 0 | RDX | 1 |
0 | 2024-03-21 23:17:39 | 742,210 |
| 208 | VIPVE2RDX_0002_0003 | Used | 1 | 30,217,188,484 |
7 | 1,728,000 | 1 | 0 | 0 | RDX | 1 |
0 | 2024-03-17 23:20:13 | 396,764 |
| 209 | VIPVE2RDX_0002_0004 | Used | 1 | 30,344,690,967 |
7 | 1,728,000 | 1 | 0 | 0 | RDX | 1 |
0 | 2024-03-18 23:17:21 | 482,992 |
| 210 | VIPVE2RDX_0002_0005 | Used | 1 | 30,405,358,265 |
7 | 1,728,000 | 1 | 0 | 0 | RDX | 1 |
0 | 2024-03-19 23:18:09 | 569,440 |
| 211 | VIPVE2RDX_0002_0006 | Used | 1 | 144,064,882,478 |
33 | 1,728,000 | 1 | 0 | 0 | RDX | 1 |
0 | 2024-03-17 02:21:05 | 321,216 |
| 212 | VIPVE2RDX_0002_0007 | Used | 1 | 251,922,284,283 |
58 | 1,728,000 | 1 | 0 | 0 | RDX | 1 |
0 | 2024-03-15 23:15:10 | 223,661 |
+---------+---------------------+-----------+---------+-----------------+----------+--------------+---------+------+-----------+-----------+---------+----------+---------------------+-----------+
Note: cartdrige 1 (0) still 'inchanger', and volumes (purged) on cartdrige 2
(1) in error (see next log).
So, trying to determine what happened... cartdrige 1 (0) ejected on friday
morning:
Mar 29 07:00:02: [30941]: restored state of magazine 0
Mar 29 07:00:02: [30941]: filesystem ea70e0c4-8076-4448-9b7e-4bb268a56c18 has
udev assigned device /dev/sdc1
Mar 29 07:00:02: [30941]: filesystem ea70e0c4-8076-4448-9b7e-4bb268a56c18
(device /dev/sdc1) mounted at /mnt/vchanger/ea70e0c4-8076-4448-9b7e-4bb268a56c18
Mar 29 07:00:02: [30941]: magazine 0 has 10 volumes on
/mnt/vchanger/ea70e0c4-8076-4448-9b7e-4bb268a56c18
Mar 29 07:00:02: [30941]: 10 volumes on magazine 0 assigned slots 1-10
Mar 29 07:00:02: [30941]: magazine 1 is not mounted
Mar 29 07:00:02: [30941]: magazine 2 is not mounted
Mar 29 07:00:02: [30941]: saved state of magazine 0
Mar 29 07:00:02: [30941]: saved dynamic configuration (max used slot: 10)
Mar 29 07:00:02: [30941]: found symlink for drive 0 ->
/mnt/vchanger/ea70e0c4-8076-4448-9b7e-4bb268a56c18/VIPVE2RDX_0000_0001
Mar 29 07:00:02: [30941]: drive 0 previously loaded from slot 2
(VIPVE2RDX_0000_0001)
Mar 29 07:00:02: [30941]: found symlink for drive 1 ->
/mnt/vchanger/ea70e0c4-8076-4448-9b7e-4bb268a56c18/VIPVE2RDX_0000_0006
Mar 29 07:00:02: [30941]: drive 1 previously loaded from slot 7
(VIPVE2RDX_0000_0006)
Mar 29 07:00:02: [30941]: found symlink for drive 2 ->
/mnt/vchanger/ea70e0c4-8076-4448-9b7e-4bb268a56c18/VIPVE2RDX_0000_0007
Mar 29 07:00:02: [30941]: drive 2 previously loaded from slot 8
(VIPVE2RDX_0000_0007)
Mar 29 07:00:02: [30941]: ==== preforming UNLOAD command
Mar 29 07:00:02: [30941]: deleted symlink for drive 0
Mar 29 07:00:02: [30941]: deleted state file for drive 0
Mar 29 07:00:02: [30941]: unloaded drive 0
Mar 29 07:00:02: [30941]: SUCCESS unloading slot 2 from drive 0
Mar 29 07:00:05: [31075]: restored state of magazine 0
Mar 29 07:00:05: [31075]: filesystem ea70e0c4-8076-4448-9b7e-4bb268a56c18 has
udev assigned device /dev/sdc1
Mar 29 07:00:05: [31075]: device /dev/sdc1 not found in system mounts,
searching all udev device aliases
Mar 29 07:00:05: [31075]: filesystem ea70e0c4-8076-4448-9b7e-4bb268a56c18
(device /dev/sdc1) not mounted
Mar 29 07:00:05: [31075]: magazine 0 is not mounted
Mar 29 07:00:05: [31075]: update slots needed. magazine 0 no longer mounted;
previous: 10 volumes in slots 1-10
Mar 29 07:00:05: [31075]: magazine 1 is not mounted
Mar 29 07:00:05: [31075]: magazine 2 is not mounted
Mar 29 07:00:05: [31075]: saved dynamic configuration (max used slot: 10)
Mar 29 07:00:05: [31075]: drive 0 previously unloaded
Mar 29 07:00:05: [31075]: volume VIPVE2RDX_0000_0006 no longer available,
unloading drive 1
Mar 29 07:00:05: [31075]: deleted symlink for drive 1
Mar 29 07:00:05: [31075]: volume VIPVE2RDX_0000_0007 no longer available,
unloading drive 2
Mar 29 07:00:05: [31075]: deleted symlink for drive 2
Mar 29 07:00:05: [31075]: ==== preforming REFRESH command
Mar 29 07:00:05: [31075]: running '/usr/sbin/bconsole -n -u 30'
Mar 29 07:00:05: [31075]: popen: child stdin uses pipe (4 -> 5)
Mar 29 07:00:05: [31075]: popen: child stdout uses pipe (6 -> 7)
Mar 29 07:00:05: [31075]: popen: forking now
Mar 29 07:00:05: [31075]: popen: parent closing pipe ends 4,7,-1 used by child
Mar 29 07:00:05: [31075]: popen: parent writes child's stdin to 5
Mar 29 07:00:05: [31075]: popen: parent reads child's stdout from 6
Mar 29 07:00:05: [31075]: popen: parent returning pid=31076 of child
Mar 29 07:00:05: [31075]: sending bconsole command 'update slots
storage="VIPVE2RDX" drive="0"'
Mar 29 07:00:05: [31076]: popen: child closing pipe ends 5,6,-1 used by parent
Mar 29 07:00:05: [31076]: popen: child will read stdin from 4
Mar 29 07:00:05: [31076]: popen: child will write stdout to 7
Mar 29 07:00:05: [31076]: popen: child executing '/usr/sbin/bconsole'
Mar 29 07:00:06: [31079]: filesystem ea70e0c4-8076-4448-9b7e-4bb268a56c18 has
udev assigned device /dev/sdc1
Mar 29 07:00:06: [31079]: device /dev/sdc1 not found in system mounts,
searching all udev device aliases
Mar 29 07:00:06: [31079]: filesystem ea70e0c4-8076-4448-9b7e-4bb268a56c18
(device /dev/sdc1) not mounted
Mar 29 07:00:06: [31079]: magazine 0 is not mounted
Mar 29 07:00:06: [31079]: magazine 1 is not mounted
Mar 29 07:00:06: [31079]: magazine 2 is not mounted
Mar 29 07:00:06: [31079]: saved dynamic configuration (max used slot: 10)
Mar 29 07:00:06: [31079]: drive 0 previously unloaded
Mar 29 07:00:06: [31079]: ==== preforming SLOTS command
Mar 29 07:00:06: [31079]: SUCCESS reporting 10 slots
Mar 29 07:00:06: [31081]: filesystem ea70e0c4-8076-4448-9b7e-4bb268a56c18 has
udev assigned device /dev/sdc1
Mar 29 07:00:06: [31081]: device /dev/sdc1 not found in system mounts,
searching all udev device aliases
Mar 29 07:00:06: [31081]: filesystem ea70e0c4-8076-4448-9b7e-4bb268a56c18
(device /dev/sdc1) not mounted
Mar 29 07:00:06: [31081]: magazine 0 is not mounted
Mar 29 07:00:06: [31081]: magazine 1 is not mounted
Mar 29 07:00:06: [31081]: magazine 2 is not mounted
Mar 29 07:00:06: [31081]: saved dynamic configuration (max used slot: 10)
Mar 29 07:00:06: [31081]: drive 0 previously unloaded
Mar 29 07:00:06: [31081]: ==== preforming LOADED command
Mar 29 07:00:06: [31081]: SUCCESS reporting drive 0 loaded from slot 0
Mar 29 07:00:06: [31096]: filesystem ea70e0c4-8076-4448-9b7e-4bb268a56c18 has
udev assigned device /dev/sdc1
Mar 29 07:00:06: [31096]: device /dev/sdc1 not found in system mounts,
searching all udev device aliases
Mar 29 07:00:06: [31096]: filesystem ea70e0c4-8076-4448-9b7e-4bb268a56c18
(device /dev/sdc1) not mounted
Mar 29 07:00:06: [31096]: magazine 0 is not mounted
Mar 29 07:00:06: [31096]: magazine 1 is not mounted
Mar 29 07:00:06: [31096]: magazine 2 is not mounted
Mar 29 07:00:06: [31096]: saved dynamic configuration (max used slot: 10)
Mar 29 07:00:06: [31096]: drive 0 previously unloaded
Mar 29 07:00:06: [31096]: ==== preforming LIST command
Mar 29 07:00:06: [31096]: SUCCESS sent list to stdout
Mar 29 07:00:06: [31075]: bconsole: bconsole terminated normally
Mar 29 07:00:06: [31075]: bconsole output:
Connecting to Director bacula.lnf.it:9101
1000 OK: 103 lnfbacula-dir Version: 9.4.2 (04 February 2019)
Enter a period to cancel a command.
update slots storage="VIPVE2RDX" drive="0"
Automatically selected Catalog: BaculaLNF
Using Catalog "BaculaLNF"
Connecting to Storage daemon VIPVE2RDX at vipve2.vi.lnf.it:9103 ...
3306 Issuing autochanger "slots" command.
Device "RDXAutochanger" has 10 slots.
Connecting to Storage daemon VIPVE2RDX at vipve2.vi.lnf.it:9103 ...
3306 Issuing autochanger "list" command.
No Volumes found to label, or no barcodes.
You have messages.
Mar 29 07:00:06: [31075]: bconsole update slots command success
Mar 29 07:00:14: [31214]: magazine 0 is not mounted
Mar 29 07:00:14: [31214]: magazine 1 is not mounted
Mar 29 07:00:14: [31214]: magazine 2 is not mounted
Mar 29 07:00:14: [31214]: saved dynamic configuration (max used slot: 10)
Mar 29 07:00:14: [31214]: drive 0 previously unloaded
Mar 29 07:00:14: [31214]: ==== preforming REFRESH command
At friday late evening, full backup start:
29-Mar 20:00 lnfbacula-dir JobId 18922: Start Backup JobId 18922,
Job=VEN-VI-Bisato.2024-03-29_20.00.00_33
29-Mar 20:01 lnfbacula-dir JobId 18922: There are no more Jobs associated with
Volume "VIPVE2RDX_0001_0004". Marking it purged.
29-Mar 20:01 lnfbacula-dir JobId 18922: All records pruned from Volume
"VIPVE2RDX_0001_0004"; marking it "Purged"
29-Mar 20:01 lnfbacula-dir JobId 18922: Recycled volume "VIPVE2RDX_0001_0004"
29-Mar 20:01 lnfbacula-dir JobId 18922: Using Device "RDXStorage1" to write.
29-Mar 20:00 ven-vi-bisato-fd JobId 18922: shell command: run
ClientRunBeforeJob "/usr/local/sbin/sysbackup"
29-Mar 20:00 ven-vi-bisato-fd JobId 18922: ClientRunBeforeJob: mysqldump: Got
error: 1045: Access denied for user 'root'@'localhost' (using password: NO)
when trying to connect
29-Mar 20:06 vipve2-sd JobId 18922: No slot defined in catalog (slot=0) for
Volume "VIPVE2RDX_0001_0004" on "RDXStorage1" (/var/spool/vchanger/VIPVE2RDX/1).
29-Mar 20:06 vipve2-sd JobId 18922: Cartridge change or "update slots" may be
required.
29-Mar 20:06 vipve2-sd JobId 18922: Warning: mount.c:215 Open of File device
"RDXStorage1" (/var/spool/vchanger/VIPVE2RDX/1) Volume "VIPVE2RDX_0001_0004"
failed: ERR=file_dev.c:190 Could not
open(/var/spool/vchanger/VIPVE2RDX/1,OPEN_READ_WRITE,0640): ERR=No such file or
directory
29-Mar 20:06 vipve2-sd JobId 18922: Marking Volume "VIPVE2RDX_0001_0004" in
Error in Catalog.
29-Mar 20:06 vipve2-sd JobId 18922: Job VEN-VI-Bisato.2024-03-29_20.00.00_33 is
waiting. Cannot find any appendable volumes.
Please use the "label" command to create a new Volume for:
Storage: "RDXStorage1" (/var/spool/vchanger/VIPVE2RDX/1)
Pool: VEN-VI-VIPVE2RDXPool
Media type: RDX
29-Mar 21:06 vipve2-sd JobId 18922: Job VEN-VI-Bisato.2024-03-29_20.00.00_33 is
waiting. Cannot find any appendable volumes.
Please use the "label" command to create a new Volume for:
Storage: "RDXStorage1" (/var/spool/vchanger/VIPVE2RDX/1)
Pool: VEN-VI-VIPVE2RDXPool
Media type: RDX
29-Mar 23:06 vipve2-sd JobId 18922: Job VEN-VI-Bisato.2024-03-29_20.00.00_33 is
waiting. Cannot find any appendable volumes.
Please use the "label" command to create a new Volume for:
Storage: "RDXStorage1" (/var/spool/vchanger/VIPVE2RDX/1)
Pool: VEN-VI-VIPVE2RDXPool
Media type: RDX
29-Mar 23:26 lnfbacula-dir JobId 18922: There are no more Jobs associated with
Volume "VIPVE2RDX_0001_0007". Marking it purged.
29-Mar 23:26 lnfbacula-dir JobId 18922: All records pruned from Volume
"VIPVE2RDX_0001_0007"; marking it "Purged"
29-Mar 23:26 lnfbacula-dir JobId 18922: Recycled volume "VIPVE2RDX_0001_0007"
29-Mar 23:26 vipve2-sd JobId 18922: Warning: mount.c:215 Open of File device
"RDXStorage1" (/var/spool/vchanger/VIPVE2RDX/1) Volume "VIPVE2RDX_0001_0007"
failed: ERR=file_dev.c:190 Could not
open(/var/spool/vchanger/VIPVE2RDX/1,OPEN_READ_WRITE,0640): ERR=No such file or
directory
29-Mar 23:26 vipve2-sd JobId 18922: Marking Volume "VIPVE2RDX_0001_0007" in
Error in Catalog.
29-Mar 23:26 vipve2-sd JobId 18922: Job VEN-VI-Bisato.2024-03-29_20.00.00_33 is
waiting. Cannot find any appendable volumes.
Please use the "label" command to create a new Volume for:
Storage: "RDXStorage1" (/var/spool/vchanger/VIPVE2RDX/1)
Pool: VEN-VI-VIPVE2RDXPool
Media type: RDX
30-Mar 02:27 lnfbacula-dir JobId 18922: There are no more Jobs associated with
Volume "VIPVE2RDX_0001_0006". Marking it purged.
30-Mar 02:27 lnfbacula-dir JobId 18922: All records pruned from Volume
"VIPVE2RDX_0001_0006"; marking it "Purged"
30-Mar 02:27 lnfbacula-dir JobId 18922: Recycled volume "VIPVE2RDX_0001_0006"
30-Mar 02:27 vipve2-sd JobId 18922: Warning: mount.c:215 Open of File device
"RDXStorage1" (/var/spool/vchanger/VIPVE2RDX/1) Volume "VIPVE2RDX_0001_0006"
failed: ERR=file_dev.c:190 Could not
open(/var/spool/vchanger/VIPVE2RDX/1,OPEN_READ_WRITE,0640): ERR=No such file or
directory
30-Mar 02:27 vipve2-sd JobId 18922: Marking Volume "VIPVE2RDX_0001_0006" in
Error in Catalog.
30-Mar 02:27 vipve2-sd JobId 18922: Job VEN-VI-Bisato.2024-03-29_20.00.00_33 is
waiting. Cannot find any appendable volumes.
Please use the "label" command to create a new Volume for:
Storage: "RDXStorage1" (/var/spool/vchanger/VIPVE2RDX/1)
Pool: VEN-VI-VIPVE2RDXPool
Media type: RDX
30-Mar 03:06 vipve2-sd JobId 18922: Job VEN-VI-Bisato.2024-03-29_20.00.00_33 is
waiting. Cannot find any appendable volumes.
Please use the "label" command to create a new Volume for:
Storage: "RDXStorage1" (/var/spool/vchanger/VIPVE2RDX/1)
Pool: VEN-VI-VIPVE2RDXPool
Media type: RDX
30-Mar 11:06 vipve2-sd JobId 18922: Job VEN-VI-Bisato.2024-03-29_20.00.00_33 is
waiting. Cannot find any appendable volumes.
Please use the "label" command to create a new Volume for:
Storage: "RDXStorage1" (/var/spool/vchanger/VIPVE2RDX/1)
Pool: VEN-VI-VIPVE2RDXPool
Media type: RDX
30-Mar 23:22 lnfbacula-dir JobId 18922: There are no more Jobs associated with
Volume "VIPVE2RDX_0001_0005". Marking it purged.
30-Mar 23:22 lnfbacula-dir JobId 18922: All records pruned from Volume
"VIPVE2RDX_0001_0005"; marking it "Purged"
30-Mar 23:22 lnfbacula-dir JobId 18922: Recycled volume "VIPVE2RDX_0001_0005"
30-Mar 23:22 vipve2-sd JobId 18922: Warning: mount.c:215 Open of File device
"RDXStorage1" (/var/spool/vchanger/VIPVE2RDX/1) Volume "VIPVE2RDX_0001_0005"
failed: ERR=file_dev.c:190 Could not
open(/var/spool/vchanger/VIPVE2RDX/1,OPEN_READ_WRITE,0640): ERR=No such file or
directory
30-Mar 23:22 vipve2-sd JobId 18922: Marking Volume "VIPVE2RDX_0001_0005" in
Error in Catalog.
30-Mar 23:22 vipve2-sd JobId 18922: Job VEN-VI-Bisato.2024-03-29_20.00.00_33 is
waiting. Cannot find any appendable volumes.
Please use the "label" command to create a new Volume for:
Storage: "RDXStorage1" (/var/spool/vchanger/VIPVE2RDX/1)
Pool: VEN-VI-VIPVE2RDXPool
Media type: RDX
31-Mar 04:07 vipve2-sd JobId 18922: Job VEN-VI-Bisato.2024-03-29_20.00.00_33 is
waiting. Cannot find any appendable volumes.
Please use the "label" command to create a new Volume for:
Storage: "RDXStorage1" (/var/spool/vchanger/VIPVE2RDX/1)
Pool: VEN-VI-VIPVE2RDXPool
Media type: RDX
31-Mar 21:01 vipve2-sd JobId 18922: Error: bsock.c:278 Bsock send while
terminated=1 on call to client:10.22.1.1:36585
31-Mar 21:00 lnfbacula-dir JobId 18922: Fatal error: Max run sched time
exceeded. Job canceled.
31-Mar 21:00 lnfbacula-dir JobId 18922: Bacula lnfbacula-dir 9.4.2 (04Feb19):
Build OS: x86_64-pc-linux-gnu debian 10.5
JobId: 18922
Job: VEN-VI-Bisato.2024-03-29_20.00.00_33
Backup Level: Full
Client: "ven-vi-bisato-fd" 5.0.2 (28Apr10)
x86_64-pc-linux-gnu,debian,6.0.10
FileSet: "DebianBackup" 2023-02-24 20:00:01
Pool: "VEN-VI-VIPVE2RDXPool" (From Job resource)
Catalog: "BaculaLNF" (From Client resource)
Storage: "VIPVE2RDX" (From Job resource)
Scheduled time: 29-Mar-2024 20:00:00
Start time: 29-Mar-2024 20:06:45
End time: 31-Mar-2024 21:00:49
Elapsed time: 1 day 23 hours 54 mins 4 secs
Priority: 10
FD Files Written: 0
SD Files Written: 0
FD Bytes Written: 0 (0 B)
SD Bytes Written: 0 (0 B)
Rate: 0.0 KB/s
Software Compression: None
Comm Line Compression: None
Snapshot/VSS: no
Encryption: no
Accurate: no
Volume name(s):
Volume Session Id: 175
Volume Session Time: 1697884416
Last Volume Bytes: 0 (0 B)
Non-fatal FD errors: 1
SD Errors: 0
FD termination status: Canceled
SD termination status: Canceled
Termination: Backup Canceled
No corresponding vchanger logs, seems vchanger get not called at all.
So, seems that:
1) when i unmount the drive on friday morning, vchanger does not update slot
status; on tuesday morning still bacula consider 'inchanger' the volumes on
drive umounted on friday.
2) bacula try to mount volumes that are on disks without the 'inchanger'
property, clearly failing.
I hope can be useful. Thanks.
--
Firma il contratto, non farti pregare
se vuoi far parte delle persone serie (E. Bennato)
_______________________________________________
Bacula-users mailing list
Bacula-users@lists.sourceforge.net
https://lists.sourceforge.net/lists/listinfo/bacula-users
