I would start by using "bls -v -V T00109L4 /dev/nsa0" (the command line utility) to see what is on the T00109L4 tape.
Can you repeat it with a small backup to a tape that contains nothing else? __Martin >>>>> On Tue, 23 Jul 2019 14:37:46 +0200, Kai Gallasch said: > > 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 > _______________________________________________ Bacula-users mailing list Bacula-users@lists.sourceforge.net https://lists.sourceforge.net/lists/listinfo/bacula-users
