Hi.
For long years I have been running Bacula Server on FreeBSD connected to
a 60 Slot tape library. Some weeks ago I noticed that restoring was not
possible any more. Backup jobs run successfully, but the restore fails
with "Restore OK", but no files restored.
This is really dangerous for users that blindly expect that a restore
will work, if the backup run was successful! In situations when you need
your backup the most, you find out too late that you cannot restore.
FreeBSD 11.2-RELEASE-p11
bacula9-server-9.4.3
postgresql95-client-9.5.18
postgresql95-server-9.5.18
What I already tried:
- dbcheck (All)
- reindexing postgres db
- relabel tapes, restart with a fresh postgresdb
- raise debug level
- start director in debug mode (-d 200) to find the problem
- try to restore single files locally on the backup server
- try to restore single files remotely on the client
- try to do full restores of last successful backup of a client
- disable all backup clients but one.
How can I debug this? The config did not change for years.
Please find below some output of the a failed restore run.
Any help appreciated.
Kai.
Select the Client (1-65): ns.free.de
Expected a positive integer, got: ns.free.de
Select the Client (1-65): 37
Automatically selected FileSet: ns.free.de-fileset
+-------+-------+----------+-------------+---------------------+------------+
| jobid | level | jobfiles | jobbytes | starttime |
volumename |
+-------+-------+----------+-------------+---------------------+------------+
| 643 | F | 29,551 | 944,425,507 | 2019-07-21 02:00:03 |
T00109L4 |
| 707 | I | 86 | 208,334,401 | 2019-07-22 01:23:04 |
T00109L4 |
| 772 | I | 97 | 209,640,913 | 2019-07-23 01:23:04 |
T00109L4 |
+-------+-------+----------+-------------+---------------------+------------+
You have selected the following JobIds: 643,707,772
Building directory tree for JobId(s) 643,707,772 ...
++++++++++++++++++++++++++++++++++++++++++++++
27,528 files inserted into the tree.
You are now entering file selection mode where you add (mark) and
remove (unmark) files to be restored. No files are initially added, unless
you used the "all" keyword on the command line.
Enter "done" to leave this mode.
cwd is: /
$ cd etc
cwd is: /etc/
$ add resolv.conf
1 file marked.
$ done
Bootstrap records written to /bacula/workdir/singsing-dir.restore.5.bsr
The Job will require the following (*=>InChanger):
Volume(s) Storage(s) SD Device(s)
===========================================================================
*T00109L4 LTO-3 Drive-1
Volumes marked with "*" are in the Autochanger.
1 file selected to be restored.
Using Catalog "MyCatalog"
Run Restore job
JobName: RestoreFiles
Bootstrap: /bacula/workdir/singsing-dir.restore.5.bsr
Where: /var/spool/bacula/restore
Replace: Always
FileSet: singsing.mgmt.free.de-fileset
Backup Client: ns.free.de
Restore Client: ns.free.de
Storage: LTO-3
When: 2019-07-23 12:29:07
Catalog: MyCatalog
Priority: 10
Plugin Options: *None*
OK to run? (yes/mod/no): mod
Parameters to modify:
1: Level
2: Storage
3: Job
4: FileSet
5: Restore Client
6: When
7: Priority
8: Bootstrap
9: Where
10: File Relocation
11: Replace
12: JobId
13: Plugin Options
Select parameter to modify (1-13): 9
Please enter the full path prefix for restore (/ for none): /tmp
Run Restore job
JobName: RestoreFiles
Bootstrap: /bacula/workdir/singsing-dir.restore.5.bsr
Where: /tmp
Replace: Always
FileSet: singsing.mgmt.free.de-fileset
Backup Client: ns.free.de
Restore Client: ns.free.de
Storage: LTO-3
When: 2019-07-23 12:29:07
Catalog: MyCatalog
Priority: 10
Plugin Options: *None*
OK to run? (yes/mod/no): yes
Job queued. JobId=793
*
You have messages.
23-Jul 12:35 singsing-dir JobId 793: Start Restore Job
RestoreFiles.2019-07-23_12.35.18_14
23-Jul 12:35 singsing-dir JobId 793: Restoring files from JobId(s)
643,707,772
23-Jul 12:35 singsing-dir JobId 793: Using Device "Drive-1" to read.
23-Jul 12:37 singsing-sd JobId 793: Ready to read from volume "T00109L4"
on Tape device "Drive-1" (/dev/nsa0).
23-Jul 12:37 singsing-sd JobId 793: Forward spacing Volume "T00109L4" to
addr=72:12932
23-Jul 12:39 singsing-sd JobId 793: End of Volume "T00109L4" at
addr=0:129024 on device "Drive-1" (/dev/nsa0).
23-Jul 12:39 singsing-sd JobId 793: Elapsed time=00:02:13, Transfer
rate=0 Bytes/second
23-Jul 12:39 singsing-dir JobId 793: Bacula singsing-dir 9.4.3 (03May19):
Build OS: amd64-portbld-freebsd11.2 freebsd 11.2-RELEASE-p11
JobId: 793
Job: RestoreFiles.2019-07-23_12.35.18_14
Restore Client: ns.free.de
Where: /tmp
Replace: Always
Start time: 23-Jul-2019 12:35:20
End time: 23-Jul-2019 12:39:20
Elapsed time: 4 mins
Files Expected: 1
Files Restored: 0
Bytes Restored: 0 (0 B)
Rate: 0.0 KB/s
FD Errors: 0
FD termination status: OK
SD termination status: OK
Termination: Restore OK -- warning file count mismatch
23-Jul 12:39 singsing-dir JobId 793: Begin pruning Jobs older than 3
months .
23-Jul 12:39 singsing-dir JobId 793: No Jobs found to prune.
23-Jul 12:39 singsing-dir JobId 793: Begin pruning Files.
23-Jul 12:39 singsing-dir JobId 793: No Files found to prune.
23-Jul 12:39 singsing-dir JobId 793: End auto prune.
_______________________________________________
Bacula-users mailing list
Bacula-users@lists.sourceforge.net
https://lists.sourceforge.net/lists/listinfo/bacula-users
