Snapshot errror in Enterprise Vault backup

[Nials]

Enterprise Vault 9.02, Windows Server 2008 x64, NetBackup 7.5.0.7 client.

Same NetBackup and Windows version in master server 

This is error I'm receiving in bpfis log when open partition backup is made.

10:35:44.081 [21132.31160] <4> onlfi_thaw: INF - Thawing E:\ using snapshot method VSS.
10:35:44.081 [21132.31160] <2> do_thaw: INF - do_thaw: parent: thaw flag on
10:35:44.081 [21132.31160] <2> onlfi_vfms_logf: INF - vfm_thaw: delete snapshot ...
10:35:44.081 [21132.31160] <4> onlfi_thaw: INF - do_thaw return value: 0
10:35:44.081 [21132.31160] <4> delete_mount_point: INF - Deleted mount point C:\Program Files\VERITAS\NetBackup\temp\_vrts_frzn_img_E__21132_1
10:35:44.081 [21132.31160] <2> onlfi_thaw: INF - fim=VSS_Writer
10:35:44.081 [21132.31160] <2> onlfi_thaw: INF - fim=auto
10:35:44.081 [21132.31160] <2> onlfi_thaw: INF - fim=Hyper-V
10:35:44.081 [21132.31160] <2> onlfi_thaw: INF - fim=Hyper-V_v2
10:35:44.081 [21132.31160] <2> onlfi_thaw: INF - fim=OST_FIM
10:35:44.081 [21132.31160] <2> onlfi_thaw: INF - fim=RealTime
10:35:44.081 [21132.31160] <2> onlfi_thaw: INF - fim=VMware
10:35:44.081 [21132.31160] <2> onlfi_thaw: INF - fim=VMware_v2
10:35:44.081 [21132.31160] <2> onlfi_thaw: INF - fim=NAS_Snapshot
10:35:44.081 [21132.31160] <2> onlfi_thaw: INF - resyncing VSS object
10:35:44.081 [21132.31160] <2> log_fs_dev_rec: INF - f_type=0 (F_REG)
10:35:44.081 [21132.31160] <2> log_fs_dev_rec: INF - fs_name=E:\
10:35:44.081 [21132.31160] <2> log_fs_dev_rec: INF - fs_type=NTFS
10:35:44.081 [21132.31160] <2> log_fs_dev_rec: INF - mnt_device=\Device\HarddiskVolume2
10:35:44.081 [21132.31160] <2> log_fs_dev_rec: INF - raw_device=
10:35:44.081 [21132.31160] <2> log_fs_dev_rec: INF - volume_group=
10:35:44.081 [21132.31160] <2> log_fs_dev_rec: INF - fi_fs_name=C:\Program Files\VERITAS\NetBackup\temp\_vrts_frzn_img_E__21132_1
10:35:44.081 [21132.31160] <2> log_fs_dev_rec: INF - fi_host=
10:35:44.081 [21132.31160] <2> log_fs_dev_rec: INF - fi_host_fs_name=
10:35:44.081 [21132.31160] <2> log_fs_dev_rec: INF - fi_mnt_device=
10:35:44.081 [21132.31160] <2> log_fs_dev_rec: INF - fi_raw_device=
10:35:44.081 [21132.31160] <2> log_fs_dev_rec: INF - fi_args= [size = 41] prov_type=0,snap_attr=0,max_snapshots=128
10:35:44.081 [21132.31160] <2> log_fs_dev_rec: INF - fi_handle=0000000000D59080
10:35:44.081 [21132.31160] <2> log_fs_dev_rec: INF - mnt_flag=0
10:35:44.081 [21132.31160] <2> log_fs_dev_rec: INF - fi_flag=1
10:35:44.081 [21132.31160] <2> log_fs_dev_rec: INF - fm_flag=0
10:35:44.081 [21132.31160] <2> log_fs_dev_rec: INF - cs_flag=0
10:35:44.081 [21132.31160] <2> bpfis main: FTL - snapshot processing failed, status 156
10:35:44.081 [21132.31160] <4> try_vss_unlockmutex: VSS Snapshot Mutex Unlock
10:35:44.081 [21132.31160] <2> bpfis main: snapshot creation successful
10:35:44.081 [21132.31160] <16> bpfis main: FTL - snapshot creation failed, status 156
10:35:44.081 [21132.31160] <2> onlfi_thaw: INF - fim=VSS
10:35:44.081 [21132.31160] <4> onlfi_thaw: INF - Thawing E:\ using snapshot method VSS.
10:35:44.081 [21132.31160] <8> onlfi_thaw: WRN - E:\ is not frozen
10:35:44.081 [21132.31160] <2> onlfi_thaw: INF - fim=VSS_Writer
10:35:44.081 [21132.31160] <2> onlfi_thaw: INF - fim=auto
10:35:44.081 [21132.31160] <2> onlfi_thaw: INF - fim=Hyper-V
10:35:44.081 [21132.31160] <2> onlfi_thaw: INF - fim=Hyper-V_v2
10:35:44.081 [21132.31160] <2> onlfi_thaw: INF - fim=OST_FIM
10:35:44.081 [21132.31160] <2> onlfi_thaw: INF - fim=RealTime
10:35:44.081 [21132.31160] <2> onlfi_thaw: INF - fim=VMware
10:35:44.097 [21132.31160] <2> onlfi_thaw: INF - fim=VMware_v2
10:35:44.097 [21132.31160] <2> onlfi_thaw: INF - fim=NAS_Snapshot
10:35:44.097 [21132.31160] <8> bpfis Exit: WRN - snapshot delete returned status 20

[eduncan]

The bpfis log seems related to a snap delete and not a creation. So it does tell us why you got a status 156.

[Nials]

What I have to do to resolve the issue?

[Marianne]

We cannot tell you how to resolve the issue.
It seems you have only posted part of the log. And we are missing a whole lot of background info.

Your NBU and EV versions are both very old. Can we assume that backups have been working fine for a long time?
If so, it makes sense that nothing has changed at NBU or EV level, right?

You need to find out what has changed. Check for VSS errors in Event viewer logs. Check if there is sufficient space for VSS snapshots.

[watsons]

As pointed out by Marianne, you need to check if the snapshot was there in the first place.

The following only tell us that snapshot deletion was not working, but we need to find out if the snapshot was created successfully. If it wasn't created, a deletion would definitely fail.

10:35:44.081 [21132.31160] <4> onlfi_thaw: INF - Thawing E:\ using snapshot method VSS.
10:35:44.081 [21132.31160] <2> do_thaw: INF - do_thaw: parent: thaw flag on
10:35:44.081 [21132.31160] <2> onlfi_vfms_logf: INF - vfm_thaw: delete snapshot ...

So look at the snapshot job detaills or logs and see what happened before that, in fact, before your backup job starts. Snapshot creation happens before an actual backup.

[Nials]

Hello Marianne.

This is the first time I'm trying to make backup, so I don't know if it was working before. The NetBackup policy is for Open Partition and Fingerprint. Attached is the configuration of VSS shadow storage for disk it is making the snapshot. The size of the open partition I'm making backup is 76.7 GB while free space on that disk is 590 GB. 

Thanks for your fast response.