ANR1104E NAS Backup process 165 terminated

[combato]

Hi Pro's

I have problems backing up my NAS. I have virtualfilspaces setup and have a bunch of jobs that get "ANR1104E NAS Backup process XXX terminated". Some goes ok!

The error in actlog:
03/17/2015 13:04:19 ANR0515I Process 165 closed volume 001040L4. (SESSION:
24602, PROCESS: 165)
03/17/2015 13:04:19 ANR8336I Verifying label of NAS volume 001040L4 in drive
TAPE05 (rst4a). (SESSION: 24602, PROCESS: 165)
03/17/2015 13:05:11 ANR8468I NAS volume 001040L4 dismounted from drive TAPE05
(rst4a) in library MYRB001. (SESSION: 24602, PROCESS:
165)
03/17/2015 13:05:11 ANR1104E NAS Backup process 165 terminated - NAS datamover
MYFILER.DOMAIN.ORG reported NDMP session errors.
(SESSION: 24602, PROCESS: 165)
03/17/2015 13:05:11 ANR3616E An error occurred. More information might be
available in the dsmserv.err file. (SESSION: 24602,
PROCESS: 165)
03/17/2015 13:05:11 ANR0988I Process 165 for BACKUP NAS (FULL) running in the
BACKGROUND processed 297,060,270,080 bytes with a
completion state of FAILURE at 13:05:11. (SESSION: 24602,
PROCESS: 165)
03/17/2015 13:05:11 ANR1893E Process 165 for BACKUP NAS (FULL) completed with
a completion state of FAILURE. (SESSION: 24602, PROCESS:
165)
03/17/2015 13:05:16 ANR1341I Scratch volume 001040L4 has been deleted from
storage pool NDMPSTG. (SESSION: 24602)

Dsmserv.err log:
[03-17-2015 13:03:13.802][ FFDC_MSG_FROM_NDMP_DEVICE ]: (ndlog.c:128) DUMP: Tue Mar 17 13:03:57 2015 : We have written 29
[03-17-2015 13:03:13.802][ FFDC_MSG_FROM_NDMP_DEVICE ]: (ndlog.c:128) 0097669 KB.
[03-17-2015 13:03:14.231][ FFDC_MSG_FROM_NDMP_DEVICE ]: (ndlog.c:128) DUMP: DUMP IS ABORTED
[03-17-2015 13:03:30.376][ FFDC_MSG_FROM_NDMP_DEVICE ]: (ndlog.c:128) DATA: Backup terminated: EVENT: BAD_MEDIA (for /vol
[03-17-2015 13:03:30.376][ FFDC_MSG_FROM_NDMP_DEVICE ]: (ndlog.c:128) /vol_nfs7/.snapshot/yea
[03-17-2015 13:05:54.171][ FFDC_MSG_FROM_NDMP_DEVICE ]: (ndlog.c:128) DUMP: Tue Mar 17 13:06:38 2015 : We have written 30

ndmpd.log:
00000052.000ac8a6 0cecdf95 Tue Mar 17 2015 12:59:11 +01:00 [kern_ndmpd:info:1951] [37261] INFO: MOVER: query 297059680256 bytes, 4532771 records 849.53KB/s (avg 14.38MB/s) (0) rst4a
00000052.000ac8ae 0cece3ce Tue Mar 17 2015 13:00:59 +01:00 [kern_ndmpd:info:1951] [37259] INFO: DATA: query 306141727744 bytes in DATA_OP_BACKUP (0)
00000052.000ac8af 0cece3ec Tue Mar 17 2015 13:01:02 +01:00 [kern_ndmpd:info:1951] [37259] INFO: MOVER: query 306146639872 bytes, 4671427 records 5.51MB/s (avg 14.68MB/s) (0) rst2a
00000052.000ac8b0 0cece538 Tue Mar 17 2015 13:01:35 +01:00 [kern_ndmpd:info:1951] [37261] INFO: DATA: query 297060008960 bytes in DATA_OP_BACKUP (0)
00000052.000ac8b2 0ceceb68 Tue Mar 17 2015 13:04:14 +01:00 [kern_ndmpd:info:1951] [37261] INFO: DATA notification state 5 (SESS_STATE: HALTING) event 6 (EVENT: BAD_MEDIA) (-0:00:01)
00000052.000ac8b3 0ceceb68 Tue Mar 17 2015 13:04:14 +01:00 [kern_ndmpd:info:1951] [37261] ERROR: DATA: Backup terminated: EVENT: BAD_MEDIA (for /vol/vol_nfs7/.snapshot/yearly)
00000052.000ac8b4 0ceceb68 Tue Mar 17 2015 13:04:14 +01:00 [kern_ndmpd:info:1951] [37261] INFO: MOVER: signal HALT CLOSE (abort) (-0:00:01)
00000052.000ac8b5 0ceceb68 Tue Mar 17 2015 13:04:14 +01:00 [kern_ndmpd:info:1951] [37261] INFO: MOVER halt on 'closed': MOVER operation successful (CLOSE) (-0:00:01)
00000052.000ac8b6 0ceceb68 Tue Mar 17 2015 13:04:14 +01:00 [kern_ndmpd:info:1951] [37261] INFO: DATA: query 297060307968 bytes in DATA_OP_HALT_UNKNOWN (0)
00000052.000ac8b7 0ceceb68 Tue Mar 17 2015 13:04:14 +01:00 [kern_ndmpd:info:1951] [37261] INFO: MOVER: query 297060270080 bytes, 4532780 records 1.90KB/s (avg 14.16MB/s) (0) rst4a
00000052.000ac8b8 0ceceb9c Tue Mar 17 2015 13:04:19 +01:00 [kern_ndmpd:info:1951] [37261] INFO: MOVER: received ABORT command
00000052.000ac8b9 0ceceb9c Tue Mar 17 2015 13:04:19 +01:00 [kern_ndmpd:info:1951] [37261] ERROR: MOVER: Rejecting ABORT request for MOVER_OP_HALT_CLOSE state.
00000052.000ac8ba 0ceceb9c Tue Mar 17 2015 13:04:19 +01:00 [kern_ndmpd:info:1951] [37261] INFO: DATA: received ABORT command
00000052.000ac8bb 0ceceb9c Tue Mar 17 2015 13:04:19 +01:00 [kern_ndmpd:info:1951] [37261] ERROR: DATA: Rejecting ABORT request for DATA_OP_HALT_UNKNOWN state.
00000052.000ac8bc 0ceceb9c Tue Mar 17 2015 13:04:19 +01:00 [kern_ndmpd:info:1951] [37261] INFO: DATA: received STOP command
00000052.000ac8bd 0ceceb9c Tue Mar 17 2015 13:04:19 +01:00 [kern_ndmpd:info:1951] [37261] INFO: SCSI: TAPE MTIO REWIND 1 rst4a
00000052.000ac8be 0cececcf Tue Mar 17 2015 13:04:49 +01:00 [kern_ndmpd:info:1951] [37261] INFO: SCSI: TAPE MTIO BSF 1 rst4a
00000052.000ac8bf 0cececcf Tue Mar 17 2015 13:04:50 +01:00 [kern_ndmpd:info:1951] [37261] INFO: SCSI: TAPE MTIO UNLOAD 1 rst4a (-0:00:01)
00000052.000ac8c0 0cececcf Tue Mar 17 2015 13:04:50 +01:00 [kern_ndmpd:info:1951] [37261] NOTICE: SCSI: UNLOAD: rst4a REWINDING (-0:00:01)
00000052.000ac8c1 0cececcf Tue Mar 17 2015 13:04:50 +01:00 [kern_ndmpd:info:1951] [37261] NOTICE: SCSI: UNLOAD: rst4a UNLOADING... (-0:00:01)

/C

 

[marclant]

You should open a call with your NAS vendor. TSM is reporting the errors sent to it by the filer during the backup, which as see in the ndmp.log is:

00000052.000ac8b2 0ceceb68 Tue Mar 17 2015 13:04:14 +01:00 [kern_ndmpd:info:1951] [37261] INFO: DATA notification state 5 (SESS_STATE: HALTING) event 6 (EVENT: BAD_MEDIA) (-0:00:01)
00000052.000ac8b3 0ceceb68 Tue Mar 17 2015 13:04:14 +01:00 [kern_ndmpd:info:1951] [37261] ERROR: DATA: Backup terminated: EVENT: BAD_MEDIA (for /vol/vol_nfs7/.snapshot/yearly)

You could also try Googling: ndmp event bad_media to see if you get any hits.