cancel
Showing results for 
Search instead for 
Did you mean: 

Duplications failed with code 191 - NetBackup 8.1

DiegoAlejandroR
Level 2

Dear contributors,

Hoping that you are well, I write this post because I have a problem with Duplications, I am new to this NetBackup world, so maybe it is a simple mistake. Unfortunately all Duplications have begun to fail .. I need help.

- NetBackup 5230

- Appliance ver. 3.1

- NetBackup ver. 8.1

LOG:

_____________________________________________________________________

Jan 30, 2020 2:09:12 PM - requesting resource  LCM_CLESCNBU20010-hcart-robot-tld-1
Jan 30, 2020 2:09:12 PM - Info nbrb (pid=281640) Limit has been reached for the logical resource LCM_CLESCNBU20010-hcart-robot-tld-1
Jan 30, 2020 2:58:55 PM - granted resource  LCM_CLESCNBU20010-hcart-robot-tld-1
Jan 30, 2020 2:58:55 PM - started process RUNCMD (pid=304107)
Jan 30, 2020 2:58:56 PM - begin Duplicate
Jan 30, 2020 2:58:56 PM - requesting resource  CLESCNBU20010-hcart-robot-tld-1
Jan 30, 2020 2:58:56 PM - requesting resource  @aaaab
Jan 30, 2020 2:58:56 PM - reserving resource @aaaab
Jan 30, 2020 2:58:56 PM - awaiting resource CLESCNBU20010-hcart-robot-tld-1. No drives are available.
Jan 30, 2020 2:58:56 PM - ended process 0 (pid=304107)
Jan 30, 2020 2:59:25 PM - awaiting resource CLESCNBU20010-hcart-robot-tld-1. Waiting for resources. 
          Reason: Drives are in use, Media server: CLESCNBU20010.dys.corp, 
          Robot Type(Number): TLD(1), Media ID: N/A, Drive Name: N/A, 
          Volume Pool: Pool_Semanal, Storage Unit: CLESCNBU20010-hcart-robot-tld-1, Drive Scan Host: N/A, 
          Disk Pool: N/A, Disk Volume: N/A 
Jan 30, 2020 2:59:26 PM - awaiting resource CLESCNBU20010-hcart-robot-tld-1. No drives are available.
Jan 30, 2020 3:15:21 PM - awaiting resource CLESCNBU20010-hcart-robot-tld-1. Waiting for resources. 
          Reason: Drives are in use, Media server: CLESCNBU20010.dys.corp, 
          Robot Type(Number): TLD(1), Media ID: N/A, Drive Name: N/A, 
          Volume Pool: Pool_Semanal, Storage Unit: CLESCNBU20010-hcart-robot-tld-1, Drive Scan Host: N/A, 
          Disk Pool: N/A, Disk Volume: N/A 
Jan 30, 2020 3:15:23 PM - awaiting resource CLESCNBU20010-hcart-robot-tld-1. No drives are available.
Jan 30, 2020 3:15:39 PM - awaiting resource CLESCNBU20010-hcart-robot-tld-1. Waiting for resources. 
          Reason: Drives are in use, Media server: CLESCNBU20010.dys.corp, 
          Robot Type(Number): TLD(1), Media ID: N/A, Drive Name: N/A, 
          Volume Pool: Pool_Semanal, Storage Unit: CLESCNBU20010-hcart-robot-tld-1, Drive Scan Host: N/A, 
          Disk Pool: N/A, Disk Volume: N/A 
Jan 30, 2020 3:15:39 PM - awaiting resource CLESCNBU20010-hcart-robot-tld-1. No drives are available.
Jan 30, 2020 3:26:28 PM - resource @aaaab reserved
Jan 30, 2020 3:26:28 PM - granted resource  WC0179
Jan 30, 2020 3:26:28 PM - granted resource  HP.ULTRIUM7-SCSI.001
Jan 30, 2020 3:26:28 PM - granted resource  CLESCNBU20010-hcart-robot-tld-1
Jan 30, 2020 3:26:28 PM - granted resource  MediaID=@aaaab;DiskVolume=PureDiskVolume;DiskPool=dp_disk_CLESCNBU20010;Path=PureDiskVolume;StorageServer=CLESCNBU20010.dys.corp;MediaServer=CLESCNBU20010.dys.corp
Jan 30, 2020 3:26:29 PM - Info bpduplicate (pid=304107) window close behavior: Suspend
Jan 30, 2020 3:26:29 PM - Info bptm (pid=314191) start
Jan 30, 2020 3:26:29 PM - started process bptm (pid=314191)
Jan 30, 2020 3:26:30 PM - Info bptm (pid=314191) start backup
Jan 30, 2020 3:26:30 PM - Info bpdm (pid=314250) started
Jan 30, 2020 3:26:30 PM - started process bpdm (pid=314250)
Jan 30, 2020 3:26:30 PM - Info bpdm (pid=314250) reading backup image
Jan 30, 2020 3:26:30 PM - Info bpdm (pid=314250) using 30 data buffers
Jan 30, 2020 3:26:30 PM - Info bpdm (pid=314250) requesting nbjm for media
Jan 30, 2020 3:26:30 PM - Info bptm (pid=314191) media id WC0179 mounted on drive index 1, drivepath /dev/nst0, drivename HP.ULTRIUM7-SCSI.001, copy 2
Jan 30, 2020 3:26:30 PM - Info bptm (pid=314191) INF - Waiting for positioning of media id WC0179 on server CLESCNBU20010.dys.corp for writing.
Jan 30, 2020 3:26:32 PM - begin reading
Jan 30, 2020 3:38:11 PM - end reading; read time: 0:11:39
Jan 30, 2020 3:38:11 PM - begin reading
Jan 30, 2020 3:49:03 PM - end reading; read time: 0:10:52
Jan 30, 2020 3:49:03 PM - begin reading
Jan 30, 2020 3:59:49 PM - end reading; read time: 0:10:46
Jan 30, 2020 3:59:49 PM - begin reading
Jan 30, 2020 4:11:49 PM - end reading; read time: 0:12:00
Jan 30, 2020 4:11:49 PM - begin reading
Jan 30, 2020 4:22:58 PM - end reading; read time: 0:11:09
Jan 30, 2020 4:22:58 PM - begin reading
Jan 30, 2020 4:36:42 PM - end reading; read time: 0:13:44
Jan 30, 2020 4:36:42 PM - begin reading
Jan 30, 2020 4:46:01 PM - end reading; read time: 0:09:19
Jan 30, 2020 4:46:01 PM - begin reading
Jan 30, 2020 4:56:42 PM - end reading; read time: 0:10:41
Jan 30, 2020 4:56:42 PM - begin reading
Jan 30, 2020 5:06:46 PM - end reading; read time: 0:10:04
Jan 30, 2020 5:06:46 PM - begin reading
Jan 30, 2020 5:06:46 PM - Critical bpdm (pid=314250) Storage Server Error: (Storage server: PureDisk:CLESCNBU20010.dys.corp) PdvfsRead: Failed to read from spoold (Connection reset by peer). Ensure storage server services are running and operational. V-454-19
Jan 30, 2020 5:06:46 PM - Critical bpdm (pid=314250) sts_read_image failed: error 2060019 error occurred on network socket
Jan 30, 2020 5:06:46 PM - Critical bpdm (pid=314250) image read failed: error 2060019: error occurred on network socket
Jan 30, 2020 5:06:46 PM - Error bpdm (pid=314250) cannot read image from disk, Invalid argument
Jan 30, 2020 5:06:48 PM - Error bptm (pid=314191) media manager terminated by parent process
Jan 30, 2020 5:06:51 PM - Info CLESCNBU20010.dys.corp (pid=314250) StorageServer=PureDisk:CLESCNBU20010.dys.corp; Report=PDDO Stats for (CLESCNBU20010.dys.corp): read: 460800078 KB, CR received: 342190618 KB, CR received over FC: 0 KB, dedup: 0.0%
Jan 30, 2020 5:07:19 PM - Error bpduplicate (pid=304107) host CLESCNBU20010.dys.corp backup id dysf-ex10mbx1.dys.corp_1580004201 read failed, media read error (85).
Jan 30, 2020 5:07:19 PM - Error bpduplicate (pid=304107) host CLESCNBU20010.dys.corp backupid dysf-ex10mbx1.dys.corp_1580004201 write failed, media manager killed by signal (82).
Jan 30, 2020 5:07:20 PM - Error bpduplicate (pid=304107) Duplicate of backupid dysf-ex10mbx1.dys.corp_1580004201 failed, media manager killed by signal (82).
Jan 30, 2020 5:07:20 PM - Error bpduplicate (pid=304107) Status = no images were successfully processed.
Jan 30, 2020 5:07:20 PM - end Duplicate; elapsed time 2:08:24
no images were successfully processed  (191)

_____________________________________________________________________

Please indicate if you need any more details.

_________________
Diego Reyes Salazar
Backup Adm.
TIVIT Chile
2 REPLIES 2

Marianne
Moderator
Moderator
Partner    VIP    Accredited Certified

Please copy bpdm log for 30 January on CLESCNBU20010 to bpdm.txt and upload here. 

We need to see what happened shortly after 5pm. 

Genericus
Moderator
Moderator
   VIP   

Determine the image having this issue, like dysf-ex10mbx1.dys.corp_1580004201, and try to manually duplicate it.

It is possible the initial image is corrupted.

I had a sporadic issue with this a few years ago. I was never able to figure it out, but it has not happened since I upgraded to 8.1.2.

 

NetBackup 9.1.0.1 on Solaris 11, writing to Data Domain 9800 7.7.4.0
duplicating via SLP to LTO5 & LTO8 in SL8500 via ACSLS