NDMP tape backups fail with the error "Message from Write Dirnet: Interrupted system call."
Applies to
- ONTAP 9
- NDMP
- NetVault
Issue
- NDMP Backup sometime fails.
- The following errors are logged in
backup.log:
dmp Mon Jul 14 00:45:26 JST 2025 /svm/vol1 Log_msg (DIRNET send work, failed to add to the queue, error=3.)
dmp Mon Jul 14 00:45:26 JST 2025 /svm/vol1 Log_msg (Write DIRNET for BKP ID=1445, type=3 failed with error = 4.)
dmp Mon Jul 14 00:45:26 JST 2025 /svm/vol1 Error (Message from Write Dirnet: Interrupted system call)
- Other errors are also occurring in the same volume.
dmp Mon Jul 28 00:52:16 JST 2025 /svm1/vol1 Log_msg (Write DIRNET for BKP ID=1688, type=3 interrupted while at waiting for max inflight. Error = Broken pipe.)
dmp Mon Jul 28 00:52:16 JST 2025 /svm1/vol1 Error (Write to socket failed)
dmp Mon Jul 28 00:52:16 JST 2025 /svm1/vol1 Tape_close (ndmp)
dmp Mon Jul 28 00:52:16 JST 2025 /svm1/vol1 Log_msg (Flush DIRNET called on error = -1.)
dmp Mon Jul 28 00:52:16 JST 2025 /svm1/vol1 Log_msg (Flush DIRNET for BKP ID=1688, type=3 interrupted while waiting for min inflight. Error = Broken pipe.)
dmp Mon Jul 28 00:52:16 JST 2025 /svm1/vol1 Log_msg (Flush DIRNET for BKP ID=1688, type=3 interrupted while waiting for min inflight. Error = Broken pipe.)
dmp Mon Jul 28 00:52:16 JST 2025/svm1/vol1 Log_msg (Flush DIRNET for BKP ID=1688, type=3 interrupted while waiting for min inflight. Error = Broken pipe.)
dmp Mon Jul 28 00:52:16 JST 2025 /svm1/vol1 Log_msg (Flush DIRNET for BKP ID=1688, type=3 interrupted while waiting for min inflight. Error = Broken pipe.)
dmp Mon Jul 28 00:52:16 JST 2025 /svm1/vol1 Log_msg (Flush DIRNET for BKP ID=1688, type=3 interrupted while waiting for min inflight. Error = Broken pipe.)
dmp Mon Jul 28 00:52:16 JST 2025 /svm1/vol1 Log_msg (Flush DIRNET for BKP ID=1688, type=3 interrupted while waiting for min inflight. Error = Broken pipe.)
dmp Mon Jul 28 00:52:16 JST 2025 /svm1/vol1 Log_msg (Flush DIRNET for BKP ID=1688, type=3 interrupted while waiting for min inflight. Error = Broken pipe.)
dmp Mon Jul 28 00:52:16 JST 2025 /svm1/vol1 Log_msg (Flush DIRNET for BKP ID=1688, type=3 interrupted while waiting for min inflight. Error = Broken pipe.)
dmp Mon Jul 28 00:52:16 JST 2025 /svm1/vol1 Log_msg (Flush DIRNET for BKP ID=1688, type=3 interrupted while waiting for min inflight. Error = Broken pipe.)
dmp Mon Jul 28 00:52:16 JST 2025 /svm1/vol1 Log_msg (Flush DIRNET for BKP ID=1688, type=3 interrupted while waiting for min inflight. Error = Broken pipe.)
dmp Mon Jul 28 00:52:16 JST 2025 /svm1/vol1 Log_msg (Flush DIRNET for BKP ID=1688, type=3 interrupted while waiting for min inflight. Error = Broken pipe.)
dmp Mon Jul 28 00:52:16 JST 2025 /svm1/vol1 Log_msg (Flush DIRNET for BKP ID=1688, type=3 interrupted while waiting for min inflight. Error = Broken pipe.)
dmp Mon Jul 28 00:52:16 JST 2025 /svm1/vol1 Log_msg (Flush DIRNET for BKP ID=1688, type=3 interrupted while waiting for min inflight. Error = Broken pipe.)
dmp Mon Jul 28 00:52:16 JST 2025 /svm1/vol1 Log_msg (Flush DIRNET for BKP ID=1688, type=3 interrupted while waiting for min inflight. Error = Broken pipe.)
dmp Mon Jul 28 00:52:17 JST 2025 /svm1/vol1 Abort (286953 MB, 3037 secs, 94 MBps)
dmp Mon Jul 28 00:52:17 JST 2025 /svm1/vol1 Error (DUMP IS ABORTED)
- In the
ndmpd.log, after the start backup request is processed and the state changes toDATA notification state 4 (SESS_STATE: ACTIVE), the session is abruptly aborted.
Mon Jul 14 2025 00:01:45 +09:00 [kern_ndmpd:info:7257] [67257] INFO: DATA notification state 1 (SESS_STATE: LISTENING) event 10 (EVENT: STATE CHANGE)
Mon Jul 14 2025 00:01:45 +09:00 [kern_ndmpd:info:7257] [67257] INFO: DATA notification state 4 (SESS_STATE: ACTIVE) event 10 (EVENT: STATE CHANGE)
Mon Jul 14 2025 00:45:28 +09:00 [kern_ndmpd:info:7257] [67257] INFO: DATA notification state 5 (SESS_STATE: HALTING) event 2 (EVENT: ABORTED)
Mon Jul 14 2025 00:45:28 +09:00 [kern_ndmpd:info:7257] [67257] ERROR: DATA: Operation terminated: EVENT: ABORTED (for svm1/vol1)
Mon Jul 28 2025 00:01:39 +09:00 [kern_ndmpd:info:7257] [35562] INFO: DATA notification state 1 (SESS_STATE: LISTENING) event 10 (EVENT: STATE CHANGE)
Mon Jul 28 2025 00:01:39 +09:00 [kern_ndmpd:info:7257] [35562] INFO: DATA notification state 4 (SESS_STATE: ACTIVE) event 10 (EVENT: STATE CHANGE)
Mon Jul 28 2025 00:52:19 +09:00 [kern_ndmpd:info:7257] [35562] INFO: DATA notification state 5 (SESS_STATE: HALTING) event 9 (EVENT: INTERNAL ERROR)
Mon Jul 28 2025 00:52:19 +09:00 [kern_ndmpd:info:7257] [35562] ERROR: DATA: Operation terminated: EVENT: INTERNAL ERROR (for /svm1/vol1)
- In the
sktrace.log, the following errors are recorded
NDMPD_LOG bkp_dirnet_rcv_upcall:DIRNET rcv upcall got socket error with ID=2004 error=32.
NDMPD_LOG bkp_abort_dirnet:In abort dirnet with mpq socket error [0x20]
