12/12/2014 1:27:28 PM - Info nbjm(pid=4440) starting backup job (jobid=46923) for client MyCNode01, policy DC3_HYPER-V_TEST, schedule Full 12/12/2014 1:27:28 PM - Info nbjm(pid=4440) requesting STANDARD_RESOURCE resources from RB for backup job (jobid=46923, request id:{267FF904-F062-4645-9325-5F04A77896E0}) 12/12/2014 1:27:28 PM - requesting resource Master_BD 12/12/2014 1:27:28 PM - requesting resource issbma-dc3wpp02.torstarcorp.net.NBU_CLIENT.MAXJOBS.MyCNode01 12/12/2014 1:27:28 PM - requesting resource issbma-dc3wpp02.torstarcorp.net.NBU_POLICY.MAXJOBS.DC3_HYPER-V_TEST 12/12/2014 1:27:28 PM - granted resource issbma-dc3wpp02.torstarcorp.net.NBU_CLIENT.MAXJOBS.MyCNode01 12/12/2014 1:27:28 PM - granted resource issbma-dc3wpp02.torstarcorp.net.NBU_POLICY.MAXJOBS.DC3_HYPER-V_TEST 12/12/2014 1:27:28 PM - granted resource MediaID=@aaaab;Path=E:\Backups;MediaServer=issbma-dc3wpp02.torstarcorp.net 12/12/2014 1:27:28 PM - granted resource Master_BD 12/12/2014 1:27:28 PM - estimated 0 Kbytes needed 12/12/2014 1:27:28 PM - begin Parent Job 12/12/2014 1:27:28 PM - begin Hyper-V, Start Notify Script 12/12/2014 1:27:28 PM - Info RUNCMD(pid=8268) started 12/12/2014 1:27:28 PM - Info RUNCMD(pid=8268) exiting with status: 0 Status 0 12/12/2014 1:27:28 PM - end Hyper-V, Start Notify Script; elapsed time: 0:00:00 12/12/2014 1:27:28 PM - begin Hyper-V, Step By Condition Status 0 12/12/2014 1:27:28 PM - end Hyper-V, Step By Condition; elapsed time: 0:00:00 12/12/2014 1:27:28 PM - begin Hyper-V, Read File List Status 0 12/12/2014 1:27:28 PM - end Hyper-V, Read File List; elapsed time: 0:00:00 12/12/2014 1:27:28 PM - begin Hyper-V, Create Snapshot 12/12/2014 1:27:28 PM - started process bpbrm (5048) 12/12/2014 1:27:28 PM - started 12/12/2014 1:28:54 PM - Info bpbrm(pid=5048) MyCNode01 is the host to backup data from 12/12/2014 1:28:54 PM - Info bpbrm(pid=5048) reading file list for client 12/12/2014 1:28:54 PM - Info bpbrm(pid=5048) start bpfis on client 12/12/2014 1:28:59 PM - Info bpbrm(pid=5048) Starting create snapshot processing 12/12/2014 1:29:05 PM - Info bpfis(pid=7572) Backup started 12/12/2014 1:29:06 PM - Critical bpbrm(pid=5048) from client MyCNode01: FTL - vfm_freeze_commit: method: Hyper-V_v2, type: FIM, function: Hyper-V_v2_gentree 12/12/2014 1:29:06 PM - Critical bpbrm(pid=5048) from client MyCNode01: FTL - snapshot services: snapshot target analysis failed: provider-private error. 12/12/2014 1:29:06 PM - Critical bpbrm(pid=5048) from client MyCNode01: FTL - vfm_freeze_commit: method: Hyper-V_v2, type: FIM, function: Hyper-V_v2_gentree 12/12/2014 1:29:06 PM - Critical bpbrm(pid=5048) from client MyCNode01: FTL - snapshot services: snapshot target analysis failed: provider-private error. 12/12/2014 1:29:12 PM - Critical bpbrm(pid=5048) from client MyCNode01: FTL - do_reaccess: cannot open reaccess file C:\Program Files\VERITAS\NetBackup\online_util\fi_cntl\bpfis.fim.MyCNode01_1418408848.1.0.fiid 12/12/2014 1:29:12 PM - Critical bpbrm(pid=5048) from client MyCNode01: FTL - snapshot processing failed, status 4201 12/12/2014 1:29:12 PM - Critical bpbrm(pid=5048) from client MyCNode01: FTL - snapshot creation failed, status 4201 12/12/2014 1:29:12 PM - Warning bpbrm(pid=5048) from client MyCNode01: WRN - ALL_LOCAL_DRIVES is not frozen 12/12/2014 1:29:12 PM - Info bpfis(pid=7572) done. status: 4201 12/12/2014 1:29:12 PM - end Hyper-V, Create Snapshot; elapsed time: 0:01:44 12/12/2014 1:29:12 PM - Info bpfis(pid=7572) done. status: 4201: Incorrect snapshot method configuration or snapshot method not compatible for protecting backup selection entries. 12/12/2014 1:29:12 PM - end writing Status 4201 12/12/2014 1:29:12 PM - end Parent Job; elapsed time: 0:01:44 12/12/2014 1:29:12 PM - begin Hyper-V, Stop On Error Status 0 12/12/2014 1:29:12 PM - end Hyper-V, Stop On Error; elapsed time: 0:00:00 12/12/2014 1:29:12 PM - begin Hyper-V, Delete Snapshot 12/12/2014 1:29:12 PM - started process bpbrm (5976) 12/12/2014 1:30:25 PM - Info bpbrm(pid=5976) Starting delete snapshot processing 12/12/2014 1:30:35 PM - Info bpfis(pid=3348) Backup started 12/12/2014 1:30:35 PM - Critical bpbrm(pid=5976) from client MyCNode01: cannot open C:\Program Files\VERITAS\NetBackup\online_util\fi_cntl\bpfis.fim.MyCNode01_1418408848.1.0 12/12/2014 1:30:36 PM - Info bpfis(pid=3348) done. status: 4207 12/12/2014 1:30:36 PM - end Hyper-V, Delete Snapshot; elapsed time: 0:01:24 12/12/2014 1:30:36 PM - Info bpfis(pid=3348) done. status: 4207: Could not fetch snapshot metadata or state files 12/12/2014 1:30:36 PM - end writing Status 4207 12/12/2014 1:30:36 PM - end Parent Job; elapsed time: 0:03:08 12/12/2014 1:30:36 PM - begin Hyper-V, End Notify Script 12/12/2014 1:30:36 PM - Info RUNCMD(pid=6788) started 12/12/2014 1:30:36 PM - Info RUNCMD(pid=6788) exiting with status: 0 Status 0 12/12/2014 1:30:36 PM - end Hyper-V, End Notify Script; elapsed time: 0:00:00 Status 4201 12/12/2014 1:30:36 PM - end operation Incorrect snapshot method configuration or snapshot method not compatible for protecting backup selection entries.(4201)