NDMP Veeam DMA connects successfully but NdmpGetFileSystemInfo requests times out
Applies to
- ONTAP 9
- Veeam Backup and Replication
- Network Data Management Protocol (NDMP)
- Maximum Transmission Unit (MTU) 9000
Issue
- Veeam successfully connects and authenticates with the ONTAP 9 cluster but the NDMP connection times out with no error seen on the ONTAP 9 cluster
- Veeam Error:
Error [cluster1-NDMP] Failed to rescan NDMP server Error: Failed to call RPC function 'NdmpGetFileSystemInfo': Timed out to process command: 'Get file system information 'NDMP error:NDMP_TIMEOUT_ERR.
- NDMP Log confirms the FS Info is been send:
Fri May 21 2021 14:47:12 +02:00 [kern_ndmpd:info:5151] [53607] DEBUG: DMA<<S V4 sequence=7 (0x7)
Fri May 21 2021 14:47:12 +02:00 [kern_ndmpd:info:5151] [53607] DEBUG: Time_stamp=0x60a7abd0 (May 21 14:47:12 2021)
Fri May 21 2021 14:47:12 +02:00 [kern_ndmpd:info:5151] [53607] DEBUG: message type=1 (NDMP4_MESSAGE_REPLY)
Fri May 21 2021 14:47:12 +02:00 [kern_ndmpd:info:5151] [53607] DEBUG: message_code=0x105 (NDMP4_CONFIG_GET_FS_INFO)
Fri May 21 2021 14:47:12 +02:00 [kern_ndmpd:info:5151] [53607] DEBUG: reply_sequence=5 (0x5)
Fri May 21 2021 14:47:12 +02:00 [kern_ndmpd:info:5151] [53607] DEBUG: error_code=0 (NDMP4_NO_ERR)
Fri May 21 2021 14:47:12 +02:00 [kern_ndmpd:info:5151] [53607] DEBUG: fs_info_len=13 (0xd)
Fri May 21 2021 14:47:12 +02:00 [kern_ndmpd:info:5151] [53607] DEBUG: fs_info[0] unsupported=0x0 ()
Fri May 21 2021 14:47:12 +02:00 [kern_ndmpd:info:5151] [53607] DEBUG: fs_info[0] fs_type='WAFL' fs_logical='/SVM1/VOL1' fs_physical=''
Fri May 21 2021 14:47:12 +02:00 [kern_ndmpd:info:5151] [53607] DEBUG: fs_info[0] total_size=107374182400 (0x1900000000) used_size=100000 (0xf422db000) avail_size=100000 (0x9bdd25000) total_inodes=100000 (0x2f7fff) used_inodes=144 (0x90) fs_status='online'
Fri May 21 2021 14:47:12 +02:00 [kern_ndmpd:info:5151] [53607] DEBUG: fs_info[0] fs_env_len=3 (0x3)
Fri May 21 2021 14:47:12 +02:00 [kern_ndmpd:info:5151] [53607] DEBUG: fs_info[0] fs_env[0] name='LOCAL' value='Y'
Fri May 21 2021 14:47:12 +02:00 [kern_ndmpd:info:5151] [53607] DEBUG: fs_info[0] fs_env[1] name='TYPE' value='WAFL'
Fri May 21 2021 14:47:12 +02:00 [kern_ndmpd:info:5151] [53607] DEBUG: fs_info[0] fs_env[2] name='VSERVER_ROOT' value='N'
Fri May 21 2021 14:47:12 +02:00 [kern_ndmpd:info:5151] [53607] DEBUG: fs_info[1] unsupported=0x0 ()
Fri May 21 2021 14:47:12 +02:00 [kern_ndmpd:info:5151] [53607] DEBUG: fs_info[1] fs_type='WAFL' fs_logical='/SVM1/VOL2' fs_physical=''
Fri May 21 2021 14:47:12 +02:00 [kern_ndmpd:info:5151] [53607] DEBUG: fs_info[1] total_size=132607115264 (0x1ee0000000) used_size=100000 (0x19dc221000) avail_size=100000 (0x503ddf000) total_inodes=100000 (0x1e66658) used_inodes=471693 (0x7328d) fs_status='online'
Fri May 21 2021 14:47:12 +02:00 [kern_ndmpd:info:5151] [53607] DEBUG: fs_info[1] fs_env_len=3 (0x3)
Fri May 21 2021 14:47:12 +02:00 [kern_ndmpd:info:5151] [53607] DEBUG: fs_info[1] fs_env[0] name='LOCAL' value='Y'
Fri May 21 2021 14:47:12 +02:00 [kern_ndmpd:info:5151] [53607] DEBUG: fs_info[1] fs_env[1] name='TYPE' value='WAFL'
Fri May 21 2021 14:47:12 +02:00 [kern_ndmpd:info:5151] [53607] DEBUG: fs_info[1] fs_env[2] name='VSERVER_ROOT' value='N'
Fri May 21 2021 14:47:12 +02:00 [kern_ndmpd:info:5151] [53607] DEBUG: fs_info[2] unsupported=0x0 ()
- Packet Traces revealed the Cluster send the FS_info to the DMA but this packet never gets acknowledged before session closure
- At the end we observed a [PSH,ACK] form the DMA which confirmed that the DMA is still at ACK number 357 from before the FS_INFO packet