Requesting resource vtl nobelium

Parent Job

12/03/2011 17:30:02 - Info nbjm (pid=14821) starting backup job (jobid=3347300) for client ersgisxpvwa04, policy VM_PAASHA_T2_079, schedule Incr_backup

12/03/2011 17:30:02 - Info nbjm (pid=14821) requesting STANDARD_RESOURCE resources from RB for backup job (jobid=3347300, request id:{B9850DE8-1E06-11E1-A719-00212856E460})

12/03/2011 17:30:02 - requesting resource VTL_nobelium

12/03/2011 17:30:02 - requesting resource bluejay.NBU_CLIENT.MAXJOBS.ersgisxpvwa04

12/03/2011 17:30:02 - requesting resource bluejay.NBU_POLICY.MAXJOBS.VM_PAASHA_T2_079

12/03/2011 17:30:12 - awaiting resource VTL_nobelium. Maximum job count has been reached for the storage unit.

12/03/2011 17:55:57 - awaiting resource VTL_nobelium. Waiting for resources.

Reason: Drives are in use, Media server: nobelium,

Robot Type(Number): TLD(1), Media ID: N/A, Drive Name: N/A,

Volume Pool: NON_Replicated, Storage Unit: VTL_nobelium, Drive Scan Host: N/A,

Disk Pool: N/A, Disk Volume: N/A

12/03/2011 17:56:48 - awaiting resource VTL_nobelium. Maximum job count has been reached for the storage unit.

12/03/2011 18:11:22 - awaiting resource VTL_nobelium. Waiting for resources.

Reason: Drives are in use, Media server: nobelium,

Robot Type(Number): TLD(1), Media ID: N/A, Drive Name: N/A,

Volume Pool: NON_Replicated, Storage Unit: VTL_nobelium, Drive Scan Host: N/A,

Disk Pool: N/A, Disk Volume: N/A

12/03/2011 18:13:17 - Info nbrb (pid=14792) Limit has been reached for the logical resource bluejay.NBU_POLICY.MAXJOBS.VM_PAASHA_T2_079

12/03/2011 18:19:14 - awaiting resource VTL_nobelium. Maximum job count has been reached for the storage unit.

12/03/2011 18:25:01 - awaiting resource VTL_nobelium. Waiting for resources.

Reason: Drives are in use, Media server: nobelium,

Robot Type(Number): TLD(1), Media ID: N/A, Drive Name: N/A,

Volume Pool: NON_Replicated, Storage Unit: VTL_nobelium, Drive Scan Host: N/A,

Disk Pool: N/A, Disk Volume: N/A

12/03/2011 18:26:13 - awaiting resource VTL_nobelium. Maximum job count has been reached for the storage unit.

12/03/2011 18:36:01 - awaiting resource VTL_nobelium. Waiting for resources.

Reason: Drives are in use, Media server: nobelium,

Robot Type(Number): TLD(1), Media ID: N/A, Drive Name: N/A,

Volume Pool: NON_Replicated, Storage Unit: VTL_nobelium, Drive Scan Host: N/A,

Disk Pool: N/A, Disk Volume: N/A

12/03/2011 18:38:24 - awaiting resource VTL_nobelium. Maximum job count has been reached for the storage unit.

12/03/2011 18:47:51 - Info nbrb (pid=14792) Limit has been reached for the logical resource bluejay.NBU_POLICY.MAXJOBS.VM_PAASHA_T2_079

12/03/2011 19:09:28 - granted resource bluejay.NBU_CLIENT.MAXJOBS.ersgisxpvwa04

12/03/2011 19:09:28 - granted resource bluejay.NBU_POLICY.MAXJOBS.VM_PAASHA_T2_079

12/03/2011 19:09:28 - granted resource X35366

12/03/2011 19:09:28 - granted resource IBM.ULTRIUM-TD4.332

12/03/2011 19:09:28 - granted resource VTL_nobelium

12/03/2011 19:09:28 - estimated 1603537 kbytes needed

12/03/2011 19:09:28 - begin Parent Job

12/03/2011 19:09:28 - begin Flash Backup Windows: Start Notify Script

12/03/2011 19:09:28 - Info RUNCMD (pid=13890) started

12/03/2011 19:09:28 - Info RUNCMD (pid=13890) exiting with status: 0

Operation Status: 0

12/03/2011 19:09:28 - end Flash Backup Windows: Start Notify Script; elapsed time 0:00:00

12/03/2011 19:09:28 - begin Flash Backup Windows: Step By Condition

Operation Status: 0

12/03/2011 19:09:28 - end Flash Backup Windows: Step By Condition; elapsed time 0:00:00

12/03/2011 19:09:28 - begin Flash Backup Windows: Read File List

Operation Status: 0

12/03/2011 19:09:28 - end Flash Backup Windows: Read File List; elapsed time 0:00:00

12/03/2011 19:09:28 - begin Flash Backup Windows: Create Snapshot

12/03/2011 19:09:29 - started process bpbrm (pid=4464)

12/03/2011 19:09:30 - Info bpbrm (pid=4464) ersgisxpvwa04 is the host to backup data from

12/03/2011 19:09:30 - Info bpbrm (pid=4464) reading file list from client

12/03/2011 19:09:30 - Info bpbrm (pid=4464) start bpfis on client

12/03/2011 19:09:30 - begin Create Snapshot

12/03/2011 19:09:30 - Info bpfis (pid=7784) Backup started

12/03/2011 19:09:30 - snapshot backup of client ersgisxpvwa04 using method VMware

12/03/2011 19:50:58 - Info bpfis (pid=7784) done. status: 0 VM log shows snapshot request time 19:50:01

12/03/2011 19:50:58 - end Flash Backup Windows: Create Snapshot; elapsed time 0:41:30

12/03/2011 19:50:58 - end writing

Operation Status: 0

12/03/2011 19:50:58 - end Parent Job; elapsed time 0:41:30

12/03/2011 19:50:58 - begin Flash Backup Windows: Policy Execution Manager Preprocessed

Operation Status: 0

12/03/2011 19:56:13 - end Flash Backup Windows: Policy Execution Manager Preprocessed; elapsed time 0:05:15

12/03/2011 19:56:13 - begin Flash Backup Windows: Validate Image

Operation Status: 0

12/03/2011 19:56:13 - end Flash Backup Windows: Validate Image; elapsed time 0:00:00

12/03/2011 19:56:13 - begin Flash Backup Windows: Delete Snapshot

12/03/2011 19:56:14 - started process bpbrm (pid=3224)

12/03/2011 19:56:52 - end writing

Operation Status: 0

12/03/2011 19:56:52 - end Flash Backup Windows: Delete Snapshot; elapsed time 0:00:39

12/03/2011 19:56:52 - begin Flash Backup Windows: Delete Snapshot On Exit

Operation Status: 0

12/03/2011 19:56:52 - end Flash Backup Windows: Delete Snapshot On Exit; elapsed time 0:00:00

12/03/2011 19:56:52 - begin Flash Backup Windows: End Notify Script

12/03/2011 19:56:52 - Info RUNCMD (pid=24960) started

12/03/2011 19:56:52 - Info RUNCMD (pid=24960) exiting with status: 0

Operation Status: 0

12/03/2011 19:56:52 - end Flash Backup Windows: End Notify Script; elapsed time 0:00:00

Operation Status: 0

12/03/2011 19:56:52 - end Create Snapshot; elapsed time 0:47:22

the requested operation was successfully completed (0)
Child job

12/03/2011 19:50:58 - Info nbjm (pid=14821) starting backup job (jobid=3348174) for client ersgisxpvwa04, policy VM_PAASHA_T2_079, schedule Incr_backup

12/03/2011 19:50:58 - estimated 1603537 kbytes needed

12/03/2011 19:50:58 - Info nbjm (pid=14821) started backup job for client ersgisxpvwa04, policy VM_PAASHA_T2_079, schedule Incr_backup on storage unit VTL_nobelium

12/03/2011 19:50:59 - Info bpbrm (pid=5520) ersgisxpvwa04 is the host to backup data from

12/03/2011 19:50:59 - Info bpbrm (pid=5520) reading file list from client

12/03/2011 19:50:59 - started process bpbrm (pid=5520)

12/03/2011 19:51:00 - Info bpbrm (pid=5520) starting bpbkar32 on client

12/03/2011 19:51:00 - connecting

12/03/2011 19:51:00 - connected; connect time: 0:00:00

12/03/2011 19:51:42 - Info bpbkar32 (pid=7904) Backup started

12/03/2011 19:51:42 - Info bpbkar32 (pid=7904) CONTINUE BACKUP received.

12/03/2011 19:51:42 - Info bptm (pid=1700) start

12/03/2011 19:52:48 - Info bptm (pid=1700) using 65536 data buffer size

12/03/2011 19:52:48 - Info bptm (pid=1700) setting receive network buffer to 263168 bytes

12/03/2011 19:52:48 - Info bptm (pid=1700) using 30 data buffers

12/03/2011 19:52:48 - Info bptm (pid=1700) start backup

12/03/2011 19:52:48 - Info bptm (pid=1700) media id X35366 mounted on drive index 25, drivepath {7,0,4,7}, drivename IBM.ULTRIUM-TD4.332, copy 1

12/03/2011 19:52:48 - mounted X35366

12/03/2011 19:52:48 - positioning X35366 to file 41

12/03/2011 19:52:49 - positioned X35366; position time: 0:00:01

12/03/2011 19:52:49 - begin writing

12/03/2011 19:56:12 - Info bptm (pid=1700) waited for full buffer 3509 times, delayed 12499 times

12/03/2011 19:56:12 - Info bpbkar32 (pid=7904) bpbkar waited 46 times for empty buffer, delayed 46 times.

12/03/2011 19:56:13 - Info bpbrm (pid=5520) validating image for client ersgisxpvwa04

12/03/2011 19:56:13 - end writing; write time: 0:03:24

the requested operation was successfully completed (0)