the requested operation was partially successful(1)

[Ghustavo]

Multiple replication jobs are failing with status: the requested operation was partially successful (1).

I'm using SLP. They could help me in solving this case.

Master/Media: Windows Server 2012

NBU: 7.6.0.4

4/20/2018 1:55:33 PM - begin Duplicate
4/20/2018 1:55:33 PM - requesting resource LCM_Srvbkp03-trf1_LTO5
4/20/2018 1:55:33 PM - granted resource LCM_Srvbkp03-trf1_LTO5
4/20/2018 1:55:33 PM - started process RUNCMD (1308)
4/20/2018 1:55:33 PM - ended process 0 (1308)
4/20/2018 1:55:39 PM - requesting resource Srvbkp03-trf1_LTO5
4/20/2018 1:55:39 PM - requesting resource @aaabN
4/20/2018 1:55:39 PM - reserving resource @aaabN
4/20/2018 1:55:50 PM - reserved resource @aaabN
4/20/2018 1:55:50 PM - granted resource 511242
4/20/2018 1:55:50 PM - granted resource IBM.ULTRIUM-TD5.001
4/20/2018 1:55:50 PM - granted resource Srvbkp03-trf1_LTO5
4/20/2018 1:55:50 PM - granted resource MediaID=@aaabN;DiskVolume=PureDiskVolume;DiskPool=DedupPool_TRF1_02;Path=PureDiskVolume;StorageServer=srvbkp03-trf1.trf1.gov.br;MediaServer=srvbkp03-trf1.trf1.gov.br
4/20/2018 1:57:04 PM - Error bpduplicate(pid=1308) db_IMAGE() failed: database system error (220)
4/20/2018 1:57:14 PM - requesting resource @aaabN
4/20/2018 1:57:14 PM - granted resource MediaID=@aaabN;DiskVolume=PureDiskVolume;DiskPool=DedupPool_TRF1_02;Path=PureDiskVolume;StorageServer=srvbkp03-trf1.trf1.gov.br;MediaServer=srvbkp1-trf1
4/20/2018 1:57:16 PM - Error bpduplicate(pid=1308) db_IMAGE() failed: socket open failed (21)
4/20/2018 1:57:16 PM - Error bpduplicate(pid=1308) Status = no images were successfully processed.
4/20/2018 1:57:16 PM - Error bpduplicate(pid=1308) Duplicate of backupid srvdc5-trf1_1524096149 failed, database system error (220).
4/20/2018 1:57:16 PM - requesting resource @aaabN
4/20/2018 1:57:16 PM - granted resource MediaID=@aaabN;DiskVolume=PureDiskVolume;DiskPool=DedupPool_TRF1_02;Path=PureDiskVolume;StorageServer=srvbkp03-trf1.trf1.gov.br;MediaServer=srvbkp03-trf1.trf1.gov.br
4/20/2018 1:57:17 PM - Error bpduplicate(pid=1308) Duplicate of backupid srvdc5-trf1_1524184205 failed, socket open failed (21).
4/20/2018 1:57:17 PM - Info bpduplicate(pid=1308) window close behavior: Suspend
4/20/2018 1:57:17 PM - Info bptm(pid=9856) start
4/20/2018 1:57:17 PM - started process bptm (9856)
4/20/2018 1:57:17 PM - requesting resource Srvbkp03-trf1_LTO5
4/20/2018 1:57:18 PM - granted resource 511242
4/20/2018 1:57:18 PM - granted resource IBM.ULTRIUM-TD5.004
4/20/2018 1:57:18 PM - granted resource Srvbkp03-trf1_LTO5
4/20/2018 1:57:19 PM - Info bptm(pid=9856) start backup
4/20/2018 1:57:19 PM - Info bpdm(pid=10284) started
4/20/2018 1:57:19 PM - started process bpdm (10284)
4/20/2018 1:57:19 PM - Info bpdm(pid=10284) reading backup image
4/20/2018 1:57:20 PM - Info bpdm(pid=10284) using 64 data buffers
4/20/2018 1:57:20 PM - Info bpdm(pid=10284) requesting nbjm for media
4/20/2018 1:57:20 PM - Info bptm(pid=9856) Waiting for mount of media id 511242 (copy 2) on server srvbkp03-trf1.trf1.gov.br.
4/20/2018 1:57:20 PM - started process bptm (9856)
4/20/2018 1:57:20 PM - mounting 511242
4/20/2018 1:57:20 PM - Info bptm(pid=9856) INF - Waiting for mount of media id 511242 on server srvbkp03-trf1.trf1.gov.br for writing.
4/20/2018 1:57:20 PM - begin reading
4/20/2018 1:57:50 PM - Info bptm(pid=9856) media id 511242 mounted on drive index 2, drivepath {3,0,2,0}, drivename IBM.ULTRIUM-TD5.004, copy 2
4/20/2018 1:57:50 PM - Info bptm(pid=9856) INF - Waiting for positioning of media id 511242 on server srvbkp03-trf1.trf1.gov.br for writing.
4/20/2018 1:58:31 PM - Info bptm(pid=9856) waited for full buffer 0 times, delayed 0 times
4/20/2018 1:58:33 PM - end reading; read time: 0:01:13
4/20/2018 1:58:33 PM - Info bpdm(pid=10284) completed reading backup image
4/20/2018 1:58:34 PM - Info bpdm(pid=10284) using 64 data buffers
4/20/2018 1:58:34 PM - begin reading
4/20/2018 1:59:06 PM - Info bptm(pid=9856) waited for full buffer 1464 times, delayed 1693 times
4/20/2018 1:59:11 PM - end reading; read time: 0:00:37
4/20/2018 1:59:11 PM - Info bpdm(pid=10284) completed reading backup image
4/20/2018 1:59:12 PM - Info bpdm(pid=10284) using 64 data buffers
4/20/2018 1:59:12 PM - begin reading
4/20/2018 1:59:29 PM - Info bptm(pid=9856) waited for full buffer 1042 times, delayed 1070 times
4/20/2018 1:59:35 PM - end reading; read time: 0:00:23
4/20/2018 1:59:35 PM - Info bpdm(pid=10284) completed reading backup image
4/20/2018 1:59:36 PM - Info bpdm(pid=10284) using 64 data buffers
4/20/2018 1:59:36 PM - begin reading
4/20/2018 1:59:37 PM - Info bptm(pid=9856) waited for full buffer 0 times, delayed 0 times
4/20/2018 1:59:37 PM - end reading; read time: 0:00:01
4/20/2018 1:59:38 PM - Info bpdm(pid=10284) completed reading backup image
4/20/2018 1:59:39 PM - Info bpdm(pid=10284) using 64 data buffers
4/20/2018 1:59:39 PM - begin reading
4/20/2018 1:59:39 PM - Info bptm(pid=9856) waited for full buffer 0 times, delayed 0 times
4/20/2018 1:59:40 PM - end reading; read time: 0:00:01
4/20/2018 1:59:40 PM - Info bpdm(pid=10284) completed reading backup image
4/20/2018 1:59:41 PM - Info bpdm(pid=10284) using 64 data buffers
4/20/2018 1:59:41 PM - begin reading
4/20/2018 1:59:42 PM - Info bptm(pid=9856) waited for full buffer 0 times, delayed 0 times
4/20/2018 1:59:42 PM - end reading; read time: 0:00:01
4/20/2018 1:59:43 PM - Info bpdm(pid=10284) completed reading backup image
4/20/2018 1:59:44 PM - Info bpdm(pid=10284) using 64 data buffers
4/20/2018 1:59:44 PM - begin reading
4/20/2018 1:59:44 PM - Info bptm(pid=9856) waited for full buffer 0 times, delayed 0 times
4/20/2018 1:59:45 PM - end reading; read time: 0:00:01
4/20/2018 1:59:45 PM - Info bpdm(pid=10284) completed reading backup image
4/20/2018 1:59:46 PM - Info bpdm(pid=10284) using 64 data buffers
4/20/2018 1:59:46 PM - begin reading
4/20/2018 1:59:46 PM - Info bptm(pid=9856) waited for full buffer 0 times, delayed 0 times
4/20/2018 1:59:47 PM - end reading; read time: 0:00:01
4/20/2018 1:59:47 PM - Info bpdm(pid=10284) completed reading backup image
4/20/2018 1:59:48 PM - Info bpdm(pid=10284) using 64 data buffers
4/20/2018 1:59:48 PM - begin reading
4/20/2018 1:59:52 PM - Info bptm(pid=9856) waited for full buffer 182 times, delayed 194 times
4/20/2018 1:59:53 PM - end reading; read time: 0:00:05
4/20/2018 1:59:53 PM - Info bpdm(pid=10284) completed reading backup image
4/20/2018 1:59:54 PM - Info bpdm(pid=10284) using 64 data buffers
4/20/2018 1:59:54 PM - begin reading
4/20/2018 1:59:54 PM - Info bptm(pid=9856) waited for full buffer 8 times, delayed 8 times
4/20/2018 1:59:55 PM - end reading; read time: 0:00:01
4/20/2018 1:59:55 PM - Info bpdm(pid=10284) completed reading backup image
4/20/2018 1:59:56 PM - Info bpdm(pid=10284) using 64 data buffers
4/20/2018 1:59:56 PM - begin reading
4/20/2018 1:59:57 PM - Info bptm(pid=9856) waited for full buffer 0 times, delayed 0 times
4/20/2018 2:00:01 PM - end reading; read time: 0:00:05
4/20/2018 2:00:02 PM - Info bpdm(pid=10284) completed reading backup image
4/20/2018 2:00:03 PM - Info bpdm(pid=10284) using 64 data buffers
4/20/2018 2:00:03 PM - begin reading
4/20/2018 2:00:03 PM - Info bptm(pid=9856) waited for full buffer 7 times, delayed 7 times
4/20/2018 2:00:04 PM - end reading; read time: 0:00:01
4/20/2018 2:00:04 PM - Info bpdm(pid=10284) completed reading backup image
4/20/2018 2:00:05 PM - Info bpdm(pid=10284) using 64 data buffers
4/20/2018 2:00:06 PM - begin reading
4/20/2018 2:00:06 PM - Info bptm(pid=9856) waited for full buffer 9 times, delayed 9 times
4/20/2018 2:00:10 PM - Info bptm(pid=9856) EXITING with status 0 <----------
4/20/2018 2:00:11 PM - end reading; read time: 0:00:05
4/20/2018 2:00:11 PM - Info bpdm(pid=10284) completed reading backup image
4/20/2018 2:00:11 PM - Info bpdm(pid=10284) EXITING with status 0
4/20/2018 2:00:11 PM - Error bpduplicate(pid=1308) Status = successfully duplicated 13 of 15 images.
4/20/2018 2:00:11 PM - Error bpduplicate(pid=1308) Status = the requested operation was partially successful.
4/20/2018 2:00:11 PM - end Duplicate; elapsed time: 0:04:38
the requested operation was partially successful(1)

The job was successfully completed, but some files may have been
busy or inaccessible. See the problems report or the client's logs for more details.

[Michal_Mikulik1]

Hello,

this error occurs when primary backup was configured with granular recovery (GRT), but GRT phase of the backup was not successful.

So concentrate primarily on that error. The most likely cause is a missing permission or NFS misconfiguration.

regards

Michal