Re: restore from vtapes written by amvault

Hello,

Am 09.10.20 um 01:31 schrieb Nathan Stratton Treadway:
> It looks like amfetchdump should be creating a
> "$logdir/fetchdump.$timestamp" log file.  If so, does that include any
> mention of opening the vtape changer and/or detecting storage names?

A logfile like log.20201008195900.0 ist created. No fetchdump.$timestamp
file.

$ cat log.20201008195900.0
INFO amfetchdump fetchdump pid 4503

There is another logfile:
/tmp/amanda/server/be-full/amfetchdump.20201009083605.debug

$ cat /tmp/amanda/server/be-full/amfetchdump.20201009083605.debug
Fr Okt 09 08:36:05.594766041 2020: pid 2215: thd-0x1bab4f0: amfetchdump:
pid 2215 ruid 33 euid 33 version 3.5.1: start at Fri Oct  9 08:36:05 2020
Fr Okt 09 08:36:05.594871851 2020: pid 2215: thd-0x1bab4f0: amfetchdump:
Arguments: -d vtape be-full svr ^/$ 20000119
Fr Okt 09 08:36:05.595963551 2020: pid 2215: thd-0x1bab4f0: amfetchdump:
reading config file /var/lib/amanda/be-full/amanda.conf
Fr Okt 09 08:36:05.596291148 2020: pid 2215: thd-0x1bab4f0: amfetchdump:
reading config file /var/lib/amanda/amanda.conf.main
Fr Okt 09 08:36:05.605085245 2020: pid 2215: thd-0x1bab4f0: amfetchdump:
pid 2215 ruid 33 euid 33 version 3.5.1: rename at Fri Oct  9 08:36:05 2020
Fr Okt 09 08:36:05.608700988 2020: pid 2215: thd-0x1bab4f0: amfetchdump:
beginning trace log: /var/lib/amanda/be-full/log/log.20201009083605.0
Fr Okt 09 08:36:05.624978043 2020: pid 2215: thd-0x1bab4f0: amfetchdump:
chg-disk: Dir /var/lib/vtape/be-full
Fr Okt 09 08:36:05.625018252 2020: pid 2215: thd-0x1bab4f0: amfetchdump:
chg-disk: Using statefile '/var/lib/vtape/be-full/state'
Fr Okt 09 08:36:05.839215832 2020: pid 2215: thd-0x1bab4f0: amfetchdump:
/opt/amanda/lib/amanda/perl/Amanda/Restore.pm:1303:success:4900002 1
volume(s) needed for restoration
The following volumes are needed: BE-full-00

Fr Okt 09 08:36:08.174733052 2020: pid 2215: thd-0x1bab4f0: amfetchdump:
Amanda::Recovery::Clerk: loading volume 'BE-full-00'
Fr Okt 09 08:36:08.174965611 2020: pid 2215: thd-0x1bab4f0: amfetchdump:
find_volume labeled 'BE-full-00'
Fr Okt 09 08:36:08.195919769 2020: pid 2215: thd-0x1bab4f0: amfetchdump:
parse_inventory: load slot 6
Fr Okt 09 08:36:08.196089927 2020: pid 2215: thd-0x1bab4f0: amfetchdump:
/opt/amanda/lib/amanda/perl/Amanda/Recovery/Scan.pm:420:info:1200000 slot 6
Fr Okt 09 08:36:08.200003707 2020: pid 2215: thd-0x1bab4f0: amfetchdump:
dir_name: /var/lib/vtape/be-full/slot6/
Fr Okt 09 08:36:08.201052481 2020: pid 2215: thd-0x1bab4f0: amfetchdump:
Device file:/var/lib/vtape/be-full/slot6 error = 'File 0 not found'
Fr Okt 09 08:36:08.201109545 2020: pid 2215: thd-0x1bab4f0: amfetchdump:
Device file:/var/lib/vtape/be-full/slot6 setting status flag(s):
DEVICE_STATUS_VOLUME_UNLABELED
Fr Okt 09 08:36:08.203808430 2020: pid 2215: thd-0x1bab4f0: amfetchdump:
/opt/amanda/lib/amanda/perl/Amanda/Recovery/Scan.pm:471:error:1200002
File 0 not found
Fr Okt 09 08:36:08.204280888 2020: pid 2215: thd-0x1bab4f0: amfetchdump:
new Amanda::Changer::Error: type='fatal', message='File 0 not found'
Fr Okt 09 08:36:08.221645777 2020: pid 2215: thd-0x1bab4f0: amfetchdump:
parse_inventory: load slot 7
Fr Okt 09 08:36:08.221763588 2020: pid 2215: thd-0x1bab4f0: amfetchdump:
/opt/amanda/lib/amanda/perl/Amanda/Recovery/Scan.pm:420:info:1200000 slot 7
Fr Okt 09 08:36:08.225548944 2020: pid 2215: thd-0x1bab4f0: amfetchdump:
dir_name: /var/lib/vtape/be-full/slot7/
Fr Okt 09 08:36:08.226464125 2020: pid 2215: thd-0x1bab4f0: amfetchdump:
Device file:/var/lib/vtape/be-full/slot7 error = 'File 0 not found'
Fr Okt 09 08:36:08.226503803 2020: pid 2215: thd-0x1bab4f0: amfetchdump:
Device file:/var/lib/vtape/be-full/slot7 setting status flag(s):
DEVICE_STATUS_VOLUME_UNLABELED
Fr Okt 09 08:36:08.229226167 2020: pid 2215: thd-0x1bab4f0: amfetchdump:
/opt/amanda/lib/amanda/perl/Amanda/Recovery/Scan.pm:471:error:1200002
File 0 not found
Fr Okt 09 08:36:08.229572359 2020: pid 2215: thd-0x1bab4f0: amfetchdump:
new Amanda::Changer::Error: type='fatal', message='File 0 not found'
Fr Okt 09 08:36:14.765428839 2020: pid 2215: thd-0x1bab4f0: amfetchdump:
new Amanda::Changer::Error: type='fatal', message='Aborted by user'
Fr Okt 09 08:36:14.765978065 2020: pid 2215: thd-0x1bab4f0: amfetchdump:
/opt/amanda/lib/amanda/perl/Amanda/Restore.pm:1398:error:4900045 Aborted
by user
Fr Okt 09 08:36:14.766222642 2020: pid 2215: thd-0x1bab4f0: amfetchdump:
/opt/amanda/lib/amanda/perl/Amanda/Restore.pm:2174:error:4900068 Aborted
by user
Fr Okt 09 08:36:14.767321030 2020: pid 2215: thd-0x1bab4f0: amfetchdump:
ru_utime   : 0
Fr Okt 09 08:36:14.767354700 2020: pid 2215: thd-0x1bab4f0: amfetchdump:
ru_stime   : 0
Fr Okt 09 08:36:14.767361174 2020: pid 2215: thd-0x1bab4f0: amfetchdump:
ru_maxrss  : 29200
Fr Okt 09 08:36:14.767382936 2020: pid 2215: thd-0x1bab4f0: amfetchdump:
ru_ixrss   : 0
Fr Okt 09 08:36:14.767388566 2020: pid 2215: thd-0x1bab4f0: amfetchdump:
ru_idrss   : 0
Fr Okt 09 08:36:14.767393604 2020: pid 2215: thd-0x1bab4f0: amfetchdump:
ru_isrss   : 0
Fr Okt 09 08:36:14.767398516 2020: pid 2215: thd-0x1bab4f0: amfetchdump:
ru_minflt  : 7808
Fr Okt 09 08:36:14.767403402 2020: pid 2215: thd-0x1bab4f0: amfetchdump:
ru_majflt  : 0
Fr Okt 09 08:36:14.767408241 2020: pid 2215: thd-0x1bab4f0: amfetchdump:
ru_nswap   : 0
Fr Okt 09 08:36:14.767413154 2020: pid 2215: thd-0x1bab4f0: amfetchdump:
ru_inblock : 64
Fr Okt 09 08:36:14.767418118 2020: pid 2215: thd-0x1bab4f0: amfetchdump:
ru_oublock : 104
Fr Okt 09 08:36:14.767422987 2020: pid 2215: thd-0x1bab4f0: amfetchdump:
ru_msgsnd  : 0
Fr Okt 09 08:36:14.767427812 2020: pid 2215: thd-0x1bab4f0: amfetchdump:
ru_msgrcv  : 0
Fr Okt 09 08:36:14.767432573 2020: pid 2215: thd-0x1bab4f0: amfetchdump:
ru_nsignals: 0
Fr Okt 09 08:36:14.767437422 2020: pid 2215: thd-0x1bab4f0: amfetchdump:
ru_nvcsw   : 156
Fr Okt 09 08:36:14.767442292 2020: pid 2215: thd-0x1bab4f0: amfetchdump:
ru_nivcsw  : 22
Fr Okt 09 08:36:14.774185300 2020: pid 2215: thd-0x1bab4f0: amfetchdump:
pid 2215 finish time Fri Oct  9 08:36:14 2020

$ amtape -otpchanger=vtape be-full inventory
slot 1: label vBE-full-001 (current) (label do not match labelstr)
[retention-tapes]
slot 2: label vBE-full-002 (label do not match labelstr) [retention-no]
slot 3: label vBE-full-003 (label do not match labelstr) [retention-no]
slot 4: label vBE-full-004 (label do not match labelstr) [retention-no]
slot 5: label vBE-full-005 (label do not match labelstr) [retention-no]
slot 6: blank
slot 7: blank

$ amlabel be-full -ostorage=vtape slot 6
Reading label...
Found an empty tape.
Writing label 'vBE-full-006'...
Checking label...
Success!

$ amlabel be-full -ostorage=vtape slot 7
Reading label...
Found an empty tape.
Writing label 'vBE-full-007'...
Checking label...
Success!

$ amtape -otpchanger=vtape be-full inventory
slot 1: label vBE-full-001 (current) (label do not match labelstr)
[retention-tapes]
slot 2: label vBE-full-002 (label do not match labelstr) [retention-no]
slot 3: label vBE-full-003 (label do not match labelstr) [retention-no]
slot 4: label vBE-full-004 (label do not match labelstr) [retention-no]
slot 5: label vBE-full-005 (label do not match labelstr) [retention-no]
slot 6: label vBE-full-006 (label do not match labelstr) [retention-no]
slot 7: label vBE-full-007 (label do not match labelstr) [retention-no]
slot 8: blank
slot 9: blank

$ for i in $(seq 8 50); do amlabel be-full -ostorage=vtape slot $i; done

$ amtape -otpchanger=vtape be-full inventory
slot 1: label vBE-full-001 (current) (label do not match labelstr)
[retention-tapes]
slot 2: label vBE-full-002 (label do not match labelstr) [retention-no]
slot 3: label vBE-full-003 (label do not match labelstr) [retention-no]
slot 4: label vBE-full-004 (label do not match labelstr) [retention-no]
slot 5: label vBE-full-005 (label do not match labelstr) [retention-no]
slot 6: label vBE-full-006 (label do not match labelstr) [retention-no]
slot 7: label vBE-full-007 (label do not match labelstr) [retention-no]
slot 8: label vBE-full-008 (label do not match labelstr) [retention-no]
slot 9: label vBE-full-009 (label do not match labelstr) [retention-no]
slot 10: label vBE-full-010 (label do not match labelstr) [retention-no]
slot 11: label vBE-full-011 (label do not match labelstr) [retention-no]
slot 12: label vBE-full-012 (label do not match labelstr) [retention-no]
slot 13: label vBE-full-013 (label do not match labelstr) [retention-no]
slot 14: label vBE-full-014 (label do not match labelstr) [retention-no]
slot 15: label vBE-full-015 (label do not match labelstr) [retention-no]
slot 16: label vBE-full-016 (label do not match labelstr) [retention-no]
slot 17: label vBE-full-017 (label do not match labelstr) [retention-no]
slot 18: label vBE-full-018 (label do not match labelstr) [retention-no]
slot 19: label vBE-full-019 (label do not match labelstr) [retention-no]
slot 20: label vBE-full-020 (label do not match labelstr) [retention-no]
slot 21: label vBE-full-021 (label do not match labelstr) [retention-no]
slot 22: label vBE-full-022 (label do not match labelstr) [retention-no]
slot 23: label vBE-full-023 (label do not match labelstr) [retention-no]
slot 24: label vBE-full-024 (label do not match labelstr) [retention-no]
slot 25: label vBE-full-025 (label do not match labelstr) [retention-no]
slot 26: label vBE-full-026 (label do not match labelstr) [retention-no]
slot 27: label vBE-full-027 (label do not match labelstr) [retention-no]
slot 28: label vBE-full-028 (label do not match labelstr) [retention-no]
slot 29: label vBE-full-029 (label do not match labelstr) [retention-no]
slot 30: label vBE-full-030 (label do not match labelstr) [retention-no]
slot 31: label vBE-full-031 (label do not match labelstr) [retention-no]
slot 32: label vBE-full-032 (label do not match labelstr) [retention-no]
slot 33: label vBE-full-033 (label do not match labelstr) [retention-no]
slot 34: label vBE-full-034 (label do not match labelstr) [retention-no]
slot 35: label vBE-full-035 (label do not match labelstr) [retention-no]
slot 36: label vBE-full-036 (label do not match labelstr) [retention-no]
slot 37: label vBE-full-037 (label do not match labelstr) [retention-no]
slot 38: label vBE-full-038 (label do not match labelstr) [retention-no]
slot 39: label vBE-full-039 (label do not match labelstr) [retention-no]
slot 40: label vBE-full-040 (label do not match labelstr) [retention-no]
slot 41: label vBE-full-041 (label do not match labelstr) [retention-no]
slot 42: label vBE-full-042 (label do not match labelstr) [retention-no]
slot 43: label vBE-full-043 (label do not match labelstr) [retention-no]
slot 44: label vBE-full-044 (label do not match labelstr) [retention-no]
slot 45: label vBE-full-045 (label do not match labelstr) [retention-no]
slot 46: label vBE-full-046 (label do not match labelstr) [retention-no]
slot 47: label vBE-full-047 (label do not match labelstr) [retention-no]
slot 48: label vBE-full-048 (label do not match labelstr) [retention-no]
slot 49: label vBE-full-049 (label do not match labelstr) [retention-no]
slot 50: label vBE-full-050 (label do not match labelstr) [retention-no]

$ amfetchdump -d vtape be-full svr '^/$' 20000119
1 volume(s) needed for restoration
The following volumes are needed: BE-full-00

Press enter when ready

Source Volume 'BE-full-00' not found

Insert volume labeled 'BE-full-00' in vtape
and press enter, or ^D to abort.

Source Volume 'BE-full-00' not found

Insert volume labeled 'BE-full-00' in vtape
and press enter, or ^D to abort.
Aborted by user

$ cat /tmp/amanda/server/be-full/amfetchdump.20201009084412.debug
Fr Okt 09 08:44:12.902069556 2020: pid 2305: thd-0x17b74f0: amfetchdump:
pid 2305 ruid 33 euid 33 version 3.5.1: start at Fri Oct  9 08:44:12 2020
Fr Okt 09 08:44:12.902186865 2020: pid 2305: thd-0x17b74f0: amfetchdump:
Arguments: -d vtape be-full svr ^/$ 20000119
Fr Okt 09 08:44:12.903262319 2020: pid 2305: thd-0x17b74f0: amfetchdump:
reading config file /var/lib/amanda/be-full/amanda.conf
Fr Okt 09 08:44:12.903584365 2020: pid 2305: thd-0x17b74f0: amfetchdump:
reading config file /var/lib/amanda/amanda.conf.main
Fr Okt 09 08:44:12.912355971 2020: pid 2305: thd-0x17b74f0: amfetchdump:
pid 2305 ruid 33 euid 33 version 3.5.1: rename at Fri Oct  9 08:44:12 2020
Fr Okt 09 08:44:12.916694313 2020: pid 2305: thd-0x17b74f0: amfetchdump:
beginning trace log: /var/lib/amanda/be-full/log/log.20201009084412.0
Fr Okt 09 08:44:12.933689453 2020: pid 2305: thd-0x17b74f0: amfetchdump:
chg-disk: Dir /var/lib/vtape/be-full
Fr Okt 09 08:44:12.933730647 2020: pid 2305: thd-0x17b74f0: amfetchdump:
chg-disk: Using statefile '/var/lib/vtape/be-full/state'
Fr Okt 09 08:44:13.147896187 2020: pid 2305: thd-0x17b74f0: amfetchdump:
/opt/amanda/lib/amanda/perl/Amanda/Restore.pm:1303:success:4900002 1
volume(s) needed for restoration
The following volumes are needed: BE-full-00

Fr Okt 09 08:44:14.920748722 2020: pid 2305: thd-0x17b74f0: amfetchdump:
Amanda::Recovery::Clerk: loading volume 'BE-full-00'
Fr Okt 09 08:44:14.920987667 2020: pid 2305: thd-0x17b74f0: amfetchdump:
find_volume labeled 'BE-full-00'
Fr Okt 09 08:44:14.986967075 2020: pid 2305: thd-0x17b74f0: amfetchdump:
new Amanda::Changer::Error: type='failed', reason='notfound',
message='Source Volume 'BE-full-00' not found'
Fr Okt 09 08:44:17.900787803 2020: pid 2305: thd-0x17b74f0: amfetchdump:
new Amanda::Changer::Error: type='failed', reason='notfound',
message='Source Volume 'BE-full-00' not found'
Fr Okt 09 08:44:19.261430096 2020: pid 2305: thd-0x17b74f0: amfetchdump:
new Amanda::Changer::Error: type='fatal', message='Aborted by user'
Fr Okt 09 08:44:19.262046170 2020: pid 2305: thd-0x17b74f0: amfetchdump:
/opt/amanda/lib/amanda/perl/Amanda/Restore.pm:1398:error:4900045 Aborted
by user
Fr Okt 09 08:44:19.262296397 2020: pid 2305: thd-0x17b74f0: amfetchdump:
/opt/amanda/lib/amanda/perl/Amanda/Restore.pm:2174:error:4900068 Aborted
by user
Fr Okt 09 08:44:19.263480254 2020: pid 2305: thd-0x17b74f0: amfetchdump:
ru_utime   : 0
Fr Okt 09 08:44:19.263511642 2020: pid 2305: thd-0x17b74f0: amfetchdump:
ru_stime   : 0
Fr Okt 09 08:44:19.263518028 2020: pid 2305: thd-0x17b74f0: amfetchdump:
ru_maxrss  : 29252
Fr Okt 09 08:44:19.263523866 2020: pid 2305: thd-0x17b74f0: amfetchdump:
ru_ixrss   : 0
Fr Okt 09 08:44:19.263529620 2020: pid 2305: thd-0x17b74f0: amfetchdump:
ru_idrss   : 0
Fr Okt 09 08:44:19.263535109 2020: pid 2305: thd-0x17b74f0: amfetchdump:
ru_isrss   : 0
Fr Okt 09 08:44:19.263540372 2020: pid 2305: thd-0x17b74f0: amfetchdump:
ru_minflt  : 7823
Fr Okt 09 08:44:19.263545653 2020: pid 2305: thd-0x17b74f0: amfetchdump:
ru_majflt  : 0
Fr Okt 09 08:44:19.263550948 2020: pid 2305: thd-0x17b74f0: amfetchdump:
ru_nswap   : 0
Fr Okt 09 08:44:19.263556266 2020: pid 2305: thd-0x17b74f0: amfetchdump:
ru_inblock : 16
Fr Okt 09 08:44:19.263561590 2020: pid 2305: thd-0x17b74f0: amfetchdump:
ru_oublock : 56
Fr Okt 09 08:44:19.263566844 2020: pid 2305: thd-0x17b74f0: amfetchdump:
ru_msgsnd  : 0
Fr Okt 09 08:44:19.263572053 2020: pid 2305: thd-0x17b74f0: amfetchdump:
ru_msgrcv  : 0
Fr Okt 09 08:44:19.263577242 2020: pid 2305: thd-0x17b74f0: amfetchdump:
ru_nsignals: 0
Fr Okt 09 08:44:19.263582507 2020: pid 2305: thd-0x17b74f0: amfetchdump:
ru_nvcsw   : 243
Fr Okt 09 08:44:19.263587801 2020: pid 2305: thd-0x17b74f0: amfetchdump:
ru_nivcsw  : 12
Fr Okt 09 08:44:19.270378786 2020: pid 2305: thd-0x17b74f0: amfetchdump:
pid 2305 finish time Fri Oct  9 08:44:19 2020