cancel
Showing results forΒ 
Search instead forΒ 
Did you mean:Β 

Duplication task takes long time to start & finish

EAKN
Not applicable

We have the below envirment,

1- Windows Server 2008 R2 as the media server.

2- Symantec NetBackup version 7.5.0.3

3- DELL TL2000 tape library with 2 drives.

4- EMC SAN Storage.

We are using storage lifecycle policy to perform backup to disk then deplication to tape. The backup process start immediattly as per the scheduled and finished successfuly. Then the deplication job is supposed to start automatically as the window to run the job is unrestricted.

I have the below two issues,

1- The duplication job requires very extended time to start. It takes not less than 30 minutes to start the duplication process.

2- When the duplication starts it takes very long time to finish. The below shows a snapshot form the activity monitor. I noticed that the delay appears on the (Waiting for positioning of media id 0009L6 on server JFO-BACK01 for writing.) step as below.

2/15/2015 11:31:00 AM - requesting resource LCM_JFO-BACK01-hcart3-robot-tld-0
12/15/2015 11:31:00 AM - granted resource LCM_JFO-BACK01-hcart3-robot-tld-0
12/15/2015 11:31:00 AM - started process RUNCMD (8480)
12/15/2015 11:31:00 AM - ended process 0 (8480)
12/15/2015 11:31:01 AM - begin Duplicate
12/15/2015 11:31:02 AM - requesting resource JFO-BACK01-hcart3-robot-tld-0
12/15/2015 11:31:02 AM - requesting resource @aaaac
12/15/2015 11:31:02 AM - reserving resource @aaaac
12/15/2015 11:31:02 AM - reserved resource @aaaac
12/15/2015 11:31:02 AM - granted resource 0007L6
12/15/2015 11:31:02 AM - granted resource IBM.ULT3580-HH6.001
12/15/2015 11:31:02 AM - granted resource JFO-BACK01-hcart3-robot-tld-0
12/15/2015 11:31:02 AM - granted resource MediaID=@aaaac;DiskVolume=PureDiskVolume;DiskPool=DDup-Pool;Path=PureDiskVolume;StorageServer=JFO-BA...
12/15/2015 11:37:20 AM - Info bpdbm(pid=8468) catalogued 504 entries         
12/15/2015 11:37:21 AM - Info bpduplicate(pid=8480) window close behavior: Suspend        
12/15/2015 11:37:21 AM - Info bptm(pid=632) start           
12/15/2015 11:37:21 AM - started process bptm (632)
12/15/2015 11:37:22 AM - requesting resource JFO-BACK01-hcart3-robot-tld-0
12/15/2015 11:37:22 AM - granted resource 0009L6
12/15/2015 11:37:22 AM - granted resource IBM.ULT3580-HH6.000
12/15/2015 11:37:22 AM - granted resource JFO-BACK01-hcart3-robot-tld-0
12/15/2015 11:37:22 AM - Info bptm(pid=632) start backup          
12/15/2015 11:37:23 AM - Info bpdm(pid=8368) started           
12/15/2015 11:37:23 AM - started process bpdm (8368)
12/15/2015 11:37:23 AM - Info bpdm(pid=8368) reading backup image         
12/15/2015 11:37:23 AM - Info bpdm(pid=8368) using 30 data buffers        
12/15/2015 11:37:23 AM - Info bpdm(pid=8368) requesting nbjm for media        
12/15/2015 11:37:23 AM - Info bptm(pid=632) Waiting for mount of media id 0009L6 (copy 2) on server JFO-BACK01.
12/15/2015 11:37:23 AM - started process bptm (632)
12/15/2015 11:37:23 AM - mounting 0009L6
12/15/2015 11:37:23 AM - Info bptm(pid=632) INF - Waiting for mount of media id 0009L6 on server JFO-BACK01 for writing.
12/15/2015 11:37:23 AM - requesting resource @aaaac
12/15/2015 11:37:24 AM - granted resource MediaID=@aaaac;DiskVolume=PureDiskVolume;DiskPool=DDup-Pool;Path=PureDiskVolume;StorageServer=JFO-BA...
12/15/2015 11:37:25 AM - begin reading
12/15/2015 11:38:13 AM - Info bptm(pid=632) media id 0009L6 mounted on drive index 1, drivepath {5,0,3,0}, drivename IBM.ULT3580-HH6.000, copy 2
12/15/2015 11:38:13 AM - Info bptm(pid=632) INF - Waiting for positioning of media id 0009L6 on server JFO-BACK01 for writing.
12/15/2015 12:36:45 PM - end reading; read time: 0:59:20
12/15/2015 12:36:45 PM - begin reading
12/15/2015 12:36:45 PM - Info bptm(pid=632) waited for full buffer 11475 times, delayed 220651 times   
12/15/2015 12:36:52 PM - Info bptm(pid=632) EXITING with status 0 <----------       
12/15/2015 12:36:52 PM - end reading; read time: 0:00:07
12/15/2015 12:36:53 PM - Info bpdm(pid=8368) completed reading backup image        
12/15/2015 12:36:53 PM - Info bpdm(pid=8368) EXITING with status 0        
12/15/2015 12:36:53 PM - Info JFO-BACK01(pid=8368) StorageServer=PureDisk:JFO-BACK01; Report=PDDO Stats for (JFO-BACK01): read: 7020859 KB, CR received: 5804315 KB, CR received over FC: 0 KB, dedup: 0.0%
12/15/2015 12:36:53 PM - end Duplicate; elapsed time: 1:05:52
the requested operation was successfully completed(0)

0 REPLIES 0