Restore vm Hyper-V

[Ewe]

Hello, 

Someone can help me?  I would restore an entire VM but i've this issue

Feb 17, 2020 3:21:06 PM - begin Restore

Feb 17, 2020 3:21:07 PM - media needed: L60041

Feb 17, 2020 3:21:07 PM - restoring from image VM3DX04_1579492505

Feb 17, 2020 3:21:07 PM - Info bprd (pid=39105) Found (487,136) files in (5) images for Restore Job ID 156677.xxx

Feb 17, 2020 3:21:07 PM - Info bprd (pid=39105) Restoring from copy 1 of image created Mon Jan 20 04:55:05 2020 from policy B_A8_VAL_INFRA_HV_CLD_M1_UXBKP06

Feb 17, 2020 3:21:24 PM - Info bptm (pid=14957) start

Feb 17, 2020 3:21:24 PM - started process bptm (pid=14957)

Feb 17, 2020 3:21:24 PM - Info bptm (pid=14957) reading backup image

Feb 17, 2020 3:21:24 PM - Info bptm (pid=14957) using 30 data buffers

Feb 17, 2020 3:21:24 PM - Info bptm (pid=14957) spawning a child process

Feb 17, 2020 3:21:24 PM - Info bptm (pid=14957) child pid: 14968

Feb 17, 2020 3:21:25 PM - requesting resource  L60041

Feb 17, 2020 3:21:26 PM - Info bptm (pid=14957) Waiting for mount of media id L60041 (copy 1) on server uxbkp06.backup.ec.sogeti.

Feb 17, 2020 3:21:26 PM - started process bptm (pid=14957)

Feb 17, 2020 3:21:26 PM - mounting L60041

Feb 17, 2020 3:21:26 PM - Info bptm (pid=14957) INF - Waiting for mount of media id L60041 on server uxbkp06.backup.ec.sogeti for reading.

Feb 17, 2020 3:21:26 PM - granted resource  L60041

Feb 17, 2020 3:21:26 PM - granted resource  HP.ULTRIUM6-SCSI.010

Feb 17, 2020 3:22:43 PM - mounted L60041; mount time: 0:01:17

Feb 17, 2020 3:22:43 PM - Info bptm (pid=14957) L60041

Feb 17, 2020 3:22:43 PM - Info bptm (pid=14957) INF - Waiting for positioning of media id L60041 on server uxbkp06.backup.ec.sogeti for reading.

Feb 17, 2020 3:22:43 PM - positioning L60041 to file 593

Feb 17, 2020 3:24:30 PM - positioned L60041; position time: 0:01:47

Feb 17, 2020 3:24:30 PM - begin reading

Feb 17, 2020 3:25:00 PM - Info bptm (pid=14957) waited for empty buffer 0 times, delayed 0 times

Feb 17, 2020 3:25:00 PM - end reading; read time: 0:00:30

Feb 17, 2020 3:25:00 PM - Info bptm (pid=14957) completed reading backup image

Feb 17, 2020 3:25:00 PM - Info bptm (pid=14957) EXITING with status 0 <----------

Feb 17, 2020 3:25:05 PM - Info bpbrm (pid=16115) HVCLD11 is the host to restore to

Feb 17, 2020 3:25:06 PM - Info bpbrm (pid=16115) reading file list for client

Feb 17, 2020 3:25:20 PM - connecting

Feb 17, 2020 3:25:20 PM - Info bpbrm (pid=16115) starting bptm

Feb 17, 2020 3:25:23 PM - Info tar (pid=4352) Restore started

Feb 17, 2020 3:25:23 PM - connected; connect time: 0:00:00

Feb 17, 2020 3:25:23 PM - Info bpbrm (pid=16115) bptm pid: 16660

Feb 17, 2020 3:25:23 PM - Info bptm (pid=16660) start

Feb 17, 2020 3:25:24 PM - started process bptm (pid=16660)

Feb 17, 2020 3:25:24 PM - Info bptm (pid=16660) reading backup image

Feb 17, 2020 3:25:24 PM - Info bptm (pid=16660) using 30 data buffers

Feb 17, 2020 3:25:24 PM - Info bptm (pid=16660) spawning a child process

Feb 17, 2020 3:25:24 PM - Info bptm (pid=16660) child pid: 16662

Feb 17, 2020 3:25:25 PM - requesting resource  L60041

Feb 17, 2020 3:25:25 PM - granted resource  L60041

Feb 17, 2020 3:25:25 PM - granted resource  HP.ULTRIUM6-SCSI.010

Feb 17, 2020 3:25:26 PM - mounted L60041

Feb 17, 2020 3:25:26 PM - Info bptm (pid=16660) L60041

Feb 17, 2020 3:25:26 PM - Info bptm (pid=16660) INF - Waiting for positioning of media id L60041 on server uxbkp06.backup.ec.sogeti for reading.

Feb 17, 2020 3:25:26 PM - positioning L60041 to file 593

Feb 17, 2020 3:27:02 PM - positioned L60041; position time: 0:01:36

Feb 17, 2020 3:27:03 PM - begin reading

Feb 17, 2020 3:30:41 PM - Critical bpbrm (pid=16115) from client HVCLD11: FTL - Virtual machine restore: file create failed

Feb 17, 2020 3:40:33 PM - Info bptm (pid=16660) waited for empty buffer 53 times, delayed 12523 times

Feb 17, 2020 3:40:33 PM - end reading; read time: 0:13:30

Feb 17, 2020 3:40:34 PM - positioning L60041 to file 595

Feb 17, 2020 3:40:34 PM - positioned L60041; position time: 0:00:00

Feb 17, 2020 3:40:34 PM - begin reading

Feb 17, 2020 3:41:10 PM - Info bptm (pid=16660) waited for empty buffer 250 times, delayed 250 times

Feb 17, 2020 3:41:11 PM - end reading; read time: 0:00:37

Feb 17, 2020 3:41:11 PM - positioning L60041 to file 597

Feb 17, 2020 3:41:11 PM - positioned L60041; position time: 0:00:00

Feb 17, 2020 3:41:11 PM - begin reading

Feb 17, 2020 3:43:45 PM - Info bptm (pid=16660) waited for empty buffer 5067 times, delayed 5069 times

Feb 17, 2020 3:43:45 PM - Critical bpbrm (pid=16115) from client HVCLD11: FTL - Final Hyper-V snapshot processing failed

Feb 17, 2020 3:43:45 PM - end reading; read time: 0:02:34

Feb 17, 2020 3:43:45 PM - Info tar (pid=4352) done. status 5

Feb 17, 2020 3:43:45 PM - Info bptm (pid=16660) completed reading backup image

Feb 17, 2020 3:43:45 PM - Info bptm (pid=16660) EXITING with status 0 <----------

Feb 17, 2020 3:43:45 PM - Info tar (pid=4352) done. status: 185

Feb 17, 2020 3:43:45 PM - Info tar (pid=4352) done. status: 185: tar did not find all the files to be restored

Feb 17, 2020 3:43:45 PM - Error bpbrm (pid=16115) client restore EXIT STATUS 185: tar did not find all the files to be restored

Feb 17, 2020 3:43:46 PM - restored from image VM3DX04_1579492505; restore time: 0:22:39

Feb 17, 2020 3:43:46 PM - end Restore; elapsed time 0:22:40

Hyper-V policy restore error  (2821)

  I don't understand why. I tried to restore only files vhd, avhd, xml same issue

[Marianne]

Hi @Ewe 

Can you please share are the steps that you have followed from the beginning of browse up to where you start the restore?
Please take screenshots and upload as attachments.

If I may ask - have you read the restore section in NBU for Hyper-V manual?