Backup delayed or stopped on "bpfis: backup started".

[amutto]

Hello all,
Below you will find a job detail of a backup that I had to suspend (I'll cancel it by sure) because of this job was taking long time.

You'll see below on bold and italic the line where the backup stopped before I had to suspend it.
Do you have any suggestions or test that I should do it in order to find a solution?

Netbackup version is 7.6.0.3 and the client is a windows XP.

Thank you in advance.
Ale

13/10/2014 18:00:03 - Info nbjm (pid=8323186) starting backup job (jobid=469227) for client PM397, policy PCs-Especiales, schedule Incr
13/10/2014 18:00:03 - Info nbjm (pid=8323186) requesting STANDARD_RESOURCE resources from RB for backup job (jobid=469227, request id:{E7104E4C-531B-11E4-A431-6E143E950000})
13/10/2014 18:00:03 - requesting resource pm-nbmedia-0-4
13/10/2014 18:00:03 - requesting resource pm-nbmaster.NBU_CLIENT.MAXJOBS.PM397
13/10/2014 18:00:03 - requesting resource pm-nbmaster.NBU_POLICY.MAXJOBS.PCs-Especiales
13/10/2014 18:00:04 - awaiting resource pm-nbmedia-0-4. No drives are available.
13/10/2014 18:11:13 - granted resource  pm-nbmaster.NBU_CLIENT.MAXJOBS.PM397
13/10/2014 18:11:13 - granted resource  pm-nbmaster.NBU_POLICY.MAXJOBS.PCs-Especiales
13/10/2014 18:11:13 - granted resource  000387
13/10/2014 18:11:13 - granted resource  IBM.ULT3580-HH5.000
13/10/2014 18:11:13 - granted resource  pm-nbmedia-hcart-robot-tld-0
13/10/2014 18:11:14 - estimated 613300 kbytes needed
13/10/2014 18:11:14 - begin Parent Job
13/10/2014 18:11:14 - begin Snapshot: Start Notify Script
13/10/2014 18:11:14 - Info RUNCMD (pid=16318620) started
13/10/2014 18:11:14 - Info RUNCMD (pid=16318620) exiting with status: 0
Operation Status: 0
13/10/2014 18:11:14 - end Snapshot: Start Notify Script; elapsed time 0:00:00
13/10/2014 18:11:14 - begin Snapshot: Step By Condition
Operation Status: 0
13/10/2014 18:11:14 - end Snapshot: Step By Condition; elapsed time 0:00:00
13/10/2014 18:11:14 - begin Snapshot: Stream Discovery
13/10/2014 18:11:16 - Info bpmount (pid=3100) started
13/10/2014 18:11:16 - Info bpmount (pid=3100) exiting with status: 0
Operation Status: 0
13/10/2014 18:11:16 - end Snapshot: Stream Discovery; elapsed time 0:00:02
13/10/2014 18:11:16 - begin Snapshot: Read File List
Operation Status: 0
13/10/2014 18:11:16 - end Snapshot: Read File List; elapsed time 0:00:00
13/10/2014 18:11:16 - begin Snapshot: Create Snapshot
13/10/2014 18:11:18 - started process bpbrm (pid=6232)
13/10/2014 18:11:24 - Info bpbrm (pid=6232) PM397 is the host to backup data from
13/10/2014 18:11:24 - Info bpbrm (pid=6232) reading file list for client
13/10/2014 18:11:24 - Info bpbrm (pid=6232) start bpfis on client
13/10/2014 18:11:26 - Info bpbrm (pid=6232) Starting create snapshot processing
13/10/2014 18:11:31 - Info bpfis (pid=3680) Backup started
16/10/2014 08:06:43 - Info bpfis (pid=3680) done. status: 157: suspend requested by administrator
16/10/2014 08:06:43 - end writing
Operation Status: 150
16/10/2014 08:06:43 - end Snapshot: Create Snapshot; elapsed time 61:55:27
16/10/2014 08:06:43 - begin Snapshot: Stop On Error
Operation Status: 0
16/10/2014 08:06:43 - end Snapshot: Stop On Error; elapsed time 0:00:00
16/10/2014 08:06:43 - begin Snapshot: End Notify Script
16/10/2014 08:06:44 - Info RUNCMD (pid=17432788) started
16/10/2014 08:06:44 - Info RUNCMD (pid=17432788) exiting with status: 0
Operation Status: 150
16/10/2014 08:06:44 - end Snapshot: End Notify Script; elapsed time 0:00:01
16/10/2014 08:06:44 - begin Snapshot: Stop On Error
Operation Status: 0
16/10/2014 08:06:44 - end Snapshot: Stop On Error; elapsed time 0:00:00
Operation Status: 150
16/10/2014 08:06:44 - end Parent Job; elapsed time 61:55:30
suspend requested by administrator  (157)

[sksujeet]

What vesion of NBU client you have on XP?

http://www.symantec.com/docs/TECH202053

[amutto]

NBU Client is 7.6.0.3. SO: Windows XP SP3 and the Snapshot provider is setup on VSS.

According to the http://www.symantec.com/docs/TECH202053, we have to change the snapshot provider to VSP. 

I'll try to change it, I keep you post.

Thanks,

Ale

[amutto]

Hi all,

I already change the Snapshot provider that mention avobe but the problem is still happen.

Any other suggestions?

In addition, I'll see that some backups ended well, but those were the few.

You'll see below on bold and italic the line where the backup stopped before I had to suspend it.

17/10/2014 18:00:00 - Info nbjm (pid=8323186) starting backup job (jobid=470950) for client PM397, policy PCs-Especiales, schedule Incr
17/10/2014 18:00:00 - Info nbjm (pid=8323186) requesting STANDARD_RESOURCE resources from RB for backup job (jobid=470950, request id:{8EC814C2-5640-11E4-92A7-6D0158D80000})
17/10/2014 18:00:00 - requesting resource pm-nbmedia-0-4
17/10/2014 18:00:00 - requesting resource pm-nbmaster.NBU_CLIENT.MAXJOBS.PM397
17/10/2014 18:00:00 - requesting resource pm-nbmaster.NBU_POLICY.MAXJOBS.PCs-Especiales
17/10/2014 18:00:00 - granted resource  pm-nbmaster.NBU_CLIENT.MAXJOBS.PM397
17/10/2014 18:00:00 - granted resource  pm-nbmaster.NBU_POLICY.MAXJOBS.PCs-Especiales
17/10/2014 18:00:00 - granted resource  000230
17/10/2014 18:00:00 - granted resource  IBM.ULT3580-TD4.000
17/10/2014 18:00:00 - granted resource  pm-nbmedia-hcart-robot-tld-0
17/10/2014 18:00:00 - estimated 613300 kbytes needed
17/10/2014 18:00:00 - begin Parent Job
17/10/2014 18:00:00 - begin Snapshot: Start Notify Script
17/10/2014 18:00:01 - Info RUNCMD (pid=18874562) started
17/10/2014 18:00:01 - Info RUNCMD (pid=18874562) exiting with status: 0
Operation Status: 0
17/10/2014 18:00:01 - end Snapshot: Start Notify Script; elapsed time 0:00:01
17/10/2014 18:00:01 - begin Snapshot: Step By Condition
Operation Status: 0
17/10/2014 18:00:01 - end Snapshot: Step By Condition; elapsed time 0:00:00
17/10/2014 18:00:01 - begin Snapshot: Stream Discovery
17/10/2014 18:00:03 - Info bpmount (pid=2392) started
17/10/2014 18:00:03 - Info bpmount (pid=2392) exiting with status: 0
Operation Status: 0
17/10/2014 18:00:03 - end Snapshot: Stream Discovery; elapsed time 0:00:02
17/10/2014 18:00:03 - begin Snapshot: Read File List
Operation Status: 0
17/10/2014 18:00:03 - end Snapshot: Read File List; elapsed time 0:00:00
17/10/2014 18:00:03 - begin Snapshot: Create Snapshot
17/10/2014 18:00:05 - started process bpbrm (pid=5544)
17/10/2014 18:00:11 - Info bpbrm (pid=5544) PM397 is the host to backup data from
17/10/2014 18:00:11 - Info bpbrm (pid=5544) reading file list for client
17/10/2014 18:00:11 - Info bpbrm (pid=5544) start bpfis on client
17/10/2014 18:00:12 - Info bpbrm (pid=5544) Starting create snapshot processing
17/10/2014 18:00:17 - Info bpfis (pid=2120) Backup started
20/10/2014 08:00:55 - Info bpfis (pid=2120) done. status: 150: termination requested by administrator
20/10/2014 08:00:55 - end writing
Operation Status: 150
20/10/2014 08:00:55 - end Snapshot: Create Snapshot; elapsed time 62:00:52
20/10/2014 08:00:55 - begin Snapshot: Stop On Error
Operation Status: 0
20/10/2014 08:00:55 - end Snapshot: Stop On Error; elapsed time 0:00:00
20/10/2014 08:00:55 - begin Snapshot: End Notify Script
20/10/2014 08:00:56 - Info RUNCMD (pid=14024744) started
20/10/2014 08:00:56 - Info RUNCMD (pid=14024744) exiting with status: 0
Operation Status: 150
20/10/2014 08:00:56 - end Snapshot: End Notify Script; elapsed time 0:00:01
20/10/2014 08:00:56 - begin Snapshot: Stop On Error
Operation Status: 0
20/10/2014 08:00:56 - end Snapshot: Stop On Error; elapsed time 0:00:00
Operation Status: 150
20/10/2014 08:00:56 - end Parent Job; elapsed time 62:00:56
termination requested by administrator  (150)

[Marianne · Accepted solution]

VSS/ Open File Backup is not supported for Windows XP clients.

See this note in NBU OS CL http://www.symantec.com/docs/TECH76648 :

3. NetBackup 7.x does not support Open File Backup on this platform. It is supported with back level NetBackup 6.x clients.
 

This means you will have to disable Open File Backup for this client in Host Properties -> Master server -> Client Attributes.
Add Client name and then disable WOFB.

[amutto]

Hi Marianne,

By now the backup works great doing the changes that you mentioned above. Also I found in that document that the dedupe should be at the client for Windows XP.

Thanks

Alejandro