Mandi! Josh Fisher via Bacula-users
In chel di` si favelave...
> It would be a bug if Bacula is trying to mount a volume not inchanger, but
Some more logs... In a local site someone friday forgot to change the
cartdrige. Was cartdrige 3.
Current situation:
*list media pool=VEN-PS-PSPVE3RDXPool
Automatically selected Catalog: BaculaLNF
Using Catalog "BaculaLNF"
+---------+---------------------+-----------+---------+-----------------+----------+--------------+---------+------+-----------+-----------+---------+----------+---------------------+-----------+
| mediaid | volumename | volstatus | enabled | volbytes |
volfiles | volretention | recycle | slot | inchanger | mediatype | voltype |
volparts | lastwritten | expiresin |
+---------+---------------------+-----------+---------+-----------------+----------+--------------+---------+------+-----------+-----------+---------+----------+---------------------+-----------+
| 799 | PSPVE3RDX_0000_0000 | Used | 1 | 953,333,148,993 |
221 | 1,728,000 | 1 | 0 | 0 | RDX | 1 |
0 | 2024-03-02 03:21:16 | 0 |
| 800 | PSPVE3RDX_0000_0001 | Used | 1 | 175,133,242,582 |
40 | 1,728,000 | 1 | 0 | 0 | RDX | 1 |
0 | 2024-03-07 00:17:59 | 139,146 |
| 801 | PSPVE3RDX_0000_0002 | Used | 1 | 241 |
0 | 1,728,000 | 1 | 0 | 0 | RDX | 1 |
0 | 2024-02-29 23:02:20 | 0 |
| 802 | PSPVE3RDX_0000_0003 | Used | 1 | 184,212,419,765 |
42 | 1,728,000 | 1 | 0 | 0 | RDX | 1 |
0 | 2024-03-03 00:33:34 | 0 |
| 803 | PSPVE3RDX_0000_0004 | Used | 1 | 184,495,440,008 |
42 | 1,728,000 | 1 | 0 | 0 | RDX | 1 |
0 | 2024-03-04 00:35:10 | 0 |
| 804 | PSPVE3RDX_0000_0005 | Used | 1 | 185,379,436,171 |
43 | 1,728,000 | 1 | 0 | 0 | RDX | 1 |
0 | 2024-03-05 00:34:35 | 0 |
| 805 | PSPVE3RDX_0000_0006 | Used | 1 | 185,251,434,340 |
43 | 1,728,000 | 1 | 0 | 0 | RDX | 1 |
0 | 2024-03-06 00:34:37 | 53,744 |
| 811 | PSPVE3RDX_0001_0000 | Used | 1 | 955,217,562,058 |
222 | 1,728,000 | 1 | 0 | 0 | RDX | 1 |
0 | 2024-03-09 00:12:57 | 311,644 |
| 812 | PSPVE3RDX_0001_0001 | Used | 1 | 185,102,461,821 |
43 | 1,728,000 | 1 | 0 | 0 | RDX | 1 |
0 | 2024-03-09 23:59:22 | 397,229 |
| 813 | PSPVE3RDX_0001_0002 | Used | 1 | 185,101,930,892 |
43 | 1,728,000 | 1 | 0 | 0 | RDX | 1 |
0 | 2024-03-10 23:54:45 | 483,352 |
| 814 | PSPVE3RDX_0001_0003 | Used | 1 | 185,011,713,135 |
43 | 1,728,000 | 1 | 0 | 0 | RDX | 1 |
0 | 2024-03-12 00:04:23 | 566,730 |
| 815 | PSPVE3RDX_0001_0004 | Used | 1 | 184,559,761,454 |
42 | 1,728,000 | 1 | 0 | 0 | RDX | 1 |
0 | 2024-03-13 00:06:34 | 653,261 |
| 816 | PSPVE3RDX_0001_0005 | Used | 1 | 172,811,713,748 |
40 | 1,728,000 | 1 | 0 | 0 | RDX | 1 |
0 | 2024-03-13 23:49:09 | 738,616 |
| 817 | PSPVE3RDX_0001_0006 | Used | 1 | 241 |
0 | 1,728,000 | 1 | 0 | 0 | RDX | 1 |
0 | | 0 |
| 826 | PSPVE3RDX_0002_0000 | Used | 1 | 843,288,831,988 |
196 | 1,728,000 | 1 | 1 | 1 | RDX | 1 |
0 | 2024-03-15 23:26:53 | 910,080 |
| 827 | PSPVE3RDX_0002_0001 | Used | 1 | 73,656,253,125 |
17 | 1,728,000 | 1 | 2 | 1 | RDX | 1 |
0 | 2024-03-16 23:24:40 | 996,347 |
| 828 | PSPVE3RDX_0002_0002 | Used | 1 | 124,449,804,394 |
28 | 1,728,000 | 1 | 3 | 1 | RDX | 1 |
0 | 2024-03-17 23:33:13 | 1,083,260 |
| 829 | PSPVE3RDX_0002_0003 | Used | 1 | 73,587,452,944 |
17 | 1,728,000 | 1 | 4 | 1 | RDX | 1 |
0 | 2024-03-18 23:25:01 | 1,169,168 |
| 830 | PSPVE3RDX_0002_0004 | Used | 1 | 74,050,230,884 |
17 | 1,728,000 | 1 | 5 | 1 | RDX | 1 |
0 | 2024-03-19 23:25:30 | 1,255,597 |
| 831 | PSPVE3RDX_0002_0005 | Used | 1 | 73,596,170,099 |
17 | 1,728,000 | 1 | 6 | 1 | RDX | 1 |
0 | 2024-03-20 23:26:11 | 1,342,038 |
| 832 | PSPVE3RDX_0002_0006 | Append | 1 | 241 |
0 | 1,728,000 | 1 | 7 | 1 | RDX | 1 |
0 | | 0 |
+---------+---------------------+-----------+---------+-----------------+----------+--------------+---------+------+-----------+-----------+---------+----------+---------------------+-----------+
so, there's no cartdrige inserted, but still bacula 'feel' that cartdrige 3
IS inserted (note 'inchanger').
Also, there was a volume still available, so probably bacula does not try to
purge and mount purgeable volume from cartdrige 1.
I've tried manually:
root@pspve3:~# vchanger /etc/vchanger/PSPVE3RDX.conf REFRESH
in log:
Mar 25 09:38:32: [22749]: magazine 0 is not mounted
Mar 25 09:38:32: [22749]: magazine 1 is not mounted
Mar 25 09:38:32: [22749]: magazine 2 is not mounted
Mar 25 09:38:32: [22749]: saved dynamic configuration (max used slot: 10)
Mar 25 09:38:32: [22749]: drive 0 previously unloaded
Mar 25 09:38:32: [22749]: ==== preforming REFRESH command
and nothing change on volume states.
I've done an:
update volume=PSPVE3RDX_0002_0006 volstatus=Used
and then forced a backup:
run job=VEN-PS-PSPVE3 level=Incremental
Bacula log:
25-Mar 09:45 lnfbacula-dir JobId 18716: Start Backup JobId 18716,
Job=VEN-CN-BTS-Station1522.2024-03-25_08.00.00_47
25-Mar 09:45 lnfbacula-dir JobId 18716: Using Device "MultiFileStorage0" to
write.
25-Mar 09:46 lnfbacula-dir JobId 18726: Start Backup JobId 18726,
Job=VEN-PS-PSPVE3.2024-03-25_09.46.10_20
25-Mar 09:46 lnfbacula-dir JobId 18726: There are no more Jobs associated with
Volume "PSPVE3RDX_0000_0002". Marking it purged.
25-Mar 09:46 lnfbacula-dir JobId 18726: All records pruned from Volume
"PSPVE3RDX_0000_0002"; marking it "Purged"
25-Mar 09:46 lnfbacula-dir JobId 18726: Recycled volume "PSPVE3RDX_0000_0002"
25-Mar 09:46 lnfbacula-dir JobId 18726: Using Device "RDXStorage1" to write.
25-Mar 09:46 lnfbacula-dir JobId 18726: Sending Accurate information to the FD.
so effectively bacula is trying to use an 'inchanger=0' volume.
Job is currentrly 'stalled'; client status:
*status client=ven-ps-pspve3-fd
Connecting to Client ven-ps-pspve3-fd at pspve3.ps.lnf.it:9102
pspve3-fd Version: 9.4.2 (04 February 2019) x86_64-pc-linux-gnu debian 10.5
Daemon started 08-Mar-24 12:32. Jobs: run=17 running=0.
Heap: heap=18,446,744,073,709,543,424 smbytes=688,469,151
max_bytes=688,470,257 bufs=823 max_bufs=1,129,031
Sizes: boffset_t=8 size_t=8 debug=0 trace=0 mode=0,0 bwlimit=0kB/s
Plugin: bpipe-fd.so
Running Jobs:
JobId 18726 Job VEN-PS-PSPVE3.2024-03-25_09.46.10_20 is running.
Incremental Backup Job started: 25-Mar-24 09:46
Files=0 Bytes=0 AveBytes/sec=0 LastBytes/sec=0 Errors=0
Bwlimit=0 ReadBytes=0
Files: Examined=0 Backed up=0
SDReadSeqNo=6 fd=5 SDtls=0
Director connected at: 25-Mar-24 13:16
storage status:
*status storage=PSPVE3RDX
Connecting to Storage daemon PSPVE3RDX at pspve3.ps.lnf.it:9103
pspve3-sd Version: 9.4.2 (04 February 2019) x86_64-pc-linux-gnu debian 10.5
Daemon started 08-Mar-24 12:32. Jobs: run=17, running=0.
Heap: heap=0 smbytes=581,347 max_bytes=1,156,822 bufs=169 max_bufs=267
Sizes: boffset_t=8 size_t=8 int32_t=4 int64_t=8 mode=0,0 newbsr=0
Res: ndevices=3 nautochgr=1
Running Jobs:
Writing: Incremental Backup job VEN-PS-PSPVE3 JobId=18726 Volume=""
pool="VEN-PS-PSPVE3RDXPool" device="RDXStorage1"
(/var/spool/vchanger/PSPVE3RDX/1)
spooling=0 despooling=0 despool_wait=0
Files=0 Bytes=0 AveBytes/sec=0 LastBytes/sec=0
FDReadSeqNo=6 in_msg=6 out_msg=5 fd=5
====
Jobs waiting to reserve a drive:
====
Terminated Jobs:
[...]
====
Device status:
Autochanger "RDXAutochanger" with devices:
"RDXStorage0" (/var/spool/vchanger/PSPVE3RDX/0)
"RDXStorage1" (/var/spool/vchanger/PSPVE3RDX/1)
"RDXStorage2" (/var/spool/vchanger/PSPVE3RDX/2)
Device File: "RDXStorage0" (/var/spool/vchanger/PSPVE3RDX/0) is not open.
Drive 0 is not loaded.
==
Device File: "RDXStorage1" (/var/spool/vchanger/PSPVE3RDX/1) is not open.
Device is being initialized.
Drive 1 is not loaded.
==
Device File: "RDXStorage2" (/var/spool/vchanger/PSPVE3RDX/2) is not open.
Slot 6 was last loaded in drive 2.
==
====
Used Volume status:
Reserved volume: PSPVE3RDX_0002_0006 on File device "RDXStorage1"
(/var/spool/vchanger/PSPVE3RDX/1)
Reader=0 writers=0 reserves=1 volinuse=1 worm=0
====
Attr spooling: 0 active jobs, 0 bytes; 13 total jobs, 209,715,224 max bytes.
Thanks.
--
...buffoni che campate di versi senza forza
avrete soldi e gloria, ma non avete scorza; (F. Guccini)
_______________________________________________
Bacula-users mailing list
Bacula-users@lists.sourceforge.net
https://lists.sourceforge.net/lists/listinfo/bacula-users
