NDMP DAR restores of single file failing with bad seek to offset error
Applies to
- ONTAP 9
- Data ONTAP 8 7-Mode
- NDMP DAR restore
- NetVault
Issue
When restoring large NDMP backups and the requested file spans multiple tapes, the DAR restores go into a loop and failed with a bad seek to offset error.
- Bad seek to offset error is seen on the
Backup.gz.
Example:
rst Wed Apr 14 16:27:01 KST 2021 /vol/ndmptest/ Start (Level 0, NDMP)rst Wed Apr 14 16:27:01 KST 2021 /vol/ndmptest/ Options (b=64, d, y, H)rst Wed Apr 14 16:27:01 KST 2021 /vol/ndmptest/ Tape_open (ndmp)rst Wed Apr 14 19:03:32 KST 2021 /vol/ndmptest/ Error (Bad seek to offset: 14015553116160)rst Wed Apr 14 19:03:33 KST 2021 /vol/ndmptest/ Tape_close (ndmp)rst Wed Apr 14 19:03:33 KST 2021 /vol/ndmptest/ Abort (17 files, 22065 MB)- Mover paused on EOF at offset and WINDOWS length invalid -- changing to INFINITY warnings are reported on
NDMPD.gz
Example:
Wed Apr 14 2021 16:27:01 +09:00 [kern_ndmpd:info:4147] [33431] NOTICE: DIRECT ACCESS RECOVERY (DAR) requestedWed Apr 14 2021 16:27:01 +09:00 [kern_ndmpd:info:4147] [33431] INFO: DATA: START RECOVER request: nlist size 1Wed Apr 14 2021 16:27:01 +09:00 [kern_ndmpd:info:4147] [33431] INFO: DATA notification state 1 (SESS_STATE: LISTENING) event 10 (EVENT: STATE CHANGE)Wed Apr 14 2021 16:27:01 +09:00 [kern_ndmpd:info:4147] [33431] INFO: MOVER: notification state 1 (SESS_STATE: LISTENING) event 10 (EVENT: STATE CHANGE)Wed Apr 14 2021 16:27:01 +09:00 [kern_ndmpd:info:4147] [33431] INFO: DATA notification state 4 (SESS_STATE: ACTIVE) event 10 (EVENT: STATE CHANGE)Wed Apr 14 2021 16:27:01 +09:00 [kern_ndmpd:info:4147] [33431] INFO: MOVER: notification state 4 (SESS_STATE: ACTIVE) event 10 (EVENT: STATE CHANGE)Wed Apr 14 2021 18:20:02 +09:00 [kern_ndmpd:info:4147] [33431] INFO: DATA notification state 3 (SESS_STATE: PAUSED) event 8 (EVENT: SEEK)Wed Apr 14 2021 18:25:22 +09:00 [kern_ndmpd:info:4147] [33431] INFO: MOVER: signaling EOF at 6754681946112 to 14015553077248 on SEEK FSRWed Apr 14 2021 18:25:22 +09:00 [kern_ndmpd:info:4147] [33431] INFO: MOVER pause on 'EOF' at offset 14015553077248......Wed Apr 14 2021 19:03:16 +09:00 [kern_ndmpd:info:4147] [33431] INFO: MOVER: signaling EOF at 0 to 14015553077248 on SEEK FSRWed Apr 14 2021 19:03:16 +09:00 [kern_ndmpd:info:4147] [33431] INFO: MOVER pause on 'EOF' at offset 14015553077248Wed Apr 14 2021 19:03:25 +09:00 [kern_ndmpd:info:4147] [33431] WARN: MOVER: WINDOW length 6754681946112 (0x624b26b0000) invalid -- changing to INFINITYWed Apr 14 2021 19:03:25 +09:00 [kern_ndmpd:info:4147] [33431] INFO: MOVER: READ CONTINUE: L:-1 O:0 record 0Wed Apr 14 2021 19:03:25 +09:00 [kern_ndmpd:info:4147] [33431] INFO: DATA notification state 3 (SESS_STATE: PAUSED) event 8 (EVENT: SEEK)Wed Apr 14 2021 19:03:25 +09:00 [kern_ndmpd:info:4147] [33431] INFO: MOVER: notification state 3 (SESS_STATE: PAUSED) event 8 (EVENT: SEEK)Wed Apr 14 2021 19:03:32 +09:00 [kern_ndmpd:info:4147] [33431] INFO: MOVER: received ABORT command