Forum Discussion

DiegoAlejandroR's avatar
5 years ago

Duplications failed with code 191 - NetBackup 8.1

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.

2 Replies

  • 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.

     

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

    We need to see what happened shortly after 5pm.