00:00:29.965 [5924.4108] <2> logparams: D:\Program Files\Veritas\NetBackup\bin\bpfis.exe create -nbu -owner NBU -id bountie.my.company.com_1451192423 -bpstart_to 300 -bpend_to 300 -backup_copy 0 -ru root -pt 13 -clnt bountie.my.company.com -fscp 1 -fscp_cksv 0 -rg root -class bountie.my.company.com -sched incremental -fso -S nbucore1 -jobid 3023294 -WOFB -fim VSS -nbu_version 123731970 -application_consistent 1 00:00:29.965 [5924.4108] <2> bpfis main: received FIM as [89523508043841539] 00:00:29.965 [5924.4108] <4> bpfis: Starting keep alive thread. 00:00:29.980 [5924.4108] <4> bpfis: INF - BACKUP START 5924 00:00:29.980 [5924.4108] <2> bpfis main: receive filelist: 00:00:29.980 [5924.4108] <2> check_special_names: got path entry as : 00:00:29.980 [5924.4108] <2> check_special_names: after conversion returning : 00:00:29.980 [5924.4108] <2> bpfis main: receive filelist: 00:00:29.980 [5924.4108] <2> check_special_names: got path entry as : 00:00:29.980 [5924.4108] <2> isWindowsDirective: directive detected : 00:00:29.980 [5924.4108] <2> check_special_names: after conversion returning : 00:00:29.980 [5924.4108] <2> bpfis main: receive filelist: 00:00:29.980 [5924.4108] <2> check_special_names: got path entry as : 00:00:29.980 [5924.4108] <2> check_special_names: after conversion returning : 00:00:29.980 [5924.4108] <2> bpfis main: receive filelist: 00:00:29.980 [5924.4108] <2> check_special_names: got path entry as : 00:00:29.980 [5924.4108] <2> check_special_names: GetVolNameForVolMntPt failed for : 00:00:29.980 [5924.4108] <2> check_special_names: after conversion returning : 00:00:29.980 [5924.4108] <2> bpfis main: receive filelist: 00:00:29.980 [5924.4108] <2> check_special_names: got path entry as : 00:00:29.980 [5924.4108] <2> check_special_names: after conversion returning : 00:00:29.980 [5924.4108] <2> bpfis main: receive filelist: 00:00:29.980 [5924.4108] <2> check_special_names: got path entry as : 00:00:29.980 [5924.4108] <2> check_special_names: GetVolNameForVolMntPt failed for : 00:00:29.980 [5924.4108] <2> check_special_names: after conversion returning : 00:00:29.980 [5924.4108] <2> bpfis main: receive filelist: 00:00:29.980 [5924.4108] <2> check_special_names: got path entry as : 00:00:29.980 [5924.4108] <2> check_special_names: after conversion returning : 00:00:30.371 [5924.4108] <8> bpfis: WRN - VfMS error 10; see following messages: 00:00:30.371 [5924.4108] <8> bpfis: WRN - Non-fatal method error was reported 00:00:30.371 [5924.4108] <8> bpfis: WRN - 00:00:30.371 [5924.4108] <8> bpfis: WRN - VfMS method error 0; see following message: 00:00:30.371 [5924.4108] <8> bpfis: WRN - 00:00:30.387 [5924.4108] <8> bpfis: WRN - VfMS error 10; see following messages: 00:00:30.387 [5924.4108] <8> bpfis: WRN - Non-fatal method error was reported 00:00:30.387 [5924.4108] <8> bpfis: WRN - 00:00:30.387 [5924.4108] <8> bpfis: WRN - VfMS method error 0; see following message: 00:00:30.387 [5924.4108] <8> bpfis: WRN - 00:00:30.449 [5924.4108] <4> bpfis: INF - FIS_ID=bountie.my.company.com_1451192423 00:00:30.449 [5924.4108] <4> bpfis: parameter client_type 13, fis_accl 1, fis_accl_cksv 0, SNAPSHOT_IS_HERE 1 00:00:30.449 [5924.4108] <4> bpfis: Volume guid list is empty, will not pause and flush NetBackup Change Journal databases 00:00:30.449 [5924.4108] <4> bpfis main: read_registry_dw_value for VSS_CONCURRENT_OPERATIONS: return status 2, return value 0 01:00:30.433 [5924.4108] <16> bpfis main: Failed to get VSS Snapshot Mutex lock!! 01:00:30.433 [5924.4108] <4> bpfis: INF - Thawing C:\ using snapshot method VSS. 01:00:30.433 [5924.4108] <8> bpfis: WRN - C:\ is not frozen 01:00:30.433 [5924.4108] <4> bpfis: INF - Thawing D:\ using snapshot method VSS. 01:00:30.433 [5924.4108] <8> bpfis: WRN - D:\ is not frozen 01:00:30.433 [5924.4108] <8> bpfis: WRN - snapshot delete returned status 20 01:00:30.433 [5924.4108] <4> bpfis: INF - BPFIS MESSAGE Routing to stream based backup for windows open file backup case, generating remap directive 01:00:30.433 [5924.4108] <4> bpfis: INF - REMAP FILE BACKUP NEW_STREAM 01:00:30.433 [5924.4108] <4> bpfis: INF - REMAP FILE BACKUP Shadow Copy Components:\ 01:00:30.433 [5924.4108] <4> bpfis: INF - REMAP FILE BACKUP NEW_STREAM 01:00:30.433 [5924.4108] <4> bpfis: INF - REMAP FILE BACKUP C:\ 01:00:30.433 [5924.4108] <4> bpfis: INF - REMAP FILE BACKUP NEW_STREAM 01:00:30.433 [5924.4108] <4> bpfis: INF - REMAP FILE BACKUP D:\ 01:00:30.433 [5924.4108] <2> getFileName: FileName is [D:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bountie.my.company.com_1451192423_copy1.lock] 01:00:30.433 [5924.4108] <2> getFileName: FileName is [D:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bountie.my.company.com_1451192423_copy1.lock] 01:00:30.433 [5924.4108] <2> addSnapshotReference: SnapShot Locked 01:00:30.433 [5924.4108] <2> addReference: Added refferent jobid[3023294] pid[5924] 01:00:30.433 [5924.4108] <2> writeSnapshotLockFile: Writing type[0] refcount[1] 01:00:30.433 [5924.4108] <2> writeSnapshotLockFile: Writing Flags [0] [0] 01:00:30.433 [5924.4108] <2> writeSnapshotLockFile: Writing Last addReffernt Time [Wed Dec 31 19:00:00 1969 ] 01:00:30.433 [5924.4108] <2> writeSnapshotLockFile: Writing Last Hostname [] 01:00:30.433 [5924.4108] <2> writeSnapshotLockFile: Writing jobid[3023294] pid [5924] 01:00:30.448 [5924.4108] <2> addSnapshotReference: SnapShot unlockLocked 01:00:30.448 [5924.4108] <4> bpfis: Starting keep alive thread. 01:00:30.448 [5924.4108] <4> bpfis: INF - EXIT STATUS 0: the requested operation was successfully completed 01:34:32.281 [5256.4544] <2> logparams: D:\Program Files\Veritas\NetBackup\bin\bpfis.exe delete -nbu -id bountie.my.company.com_1451192423 -bpstart_to 300 -bpend_to 300 -clnt bountie.my.company.com -jobgrpid 3023294 -jobid 3023294 -fscp 1 -fscp_cksv 0 -copy 1 -S nbucore1 01:34:32.281 [5256.4544] <4> bpfis: Starting keep alive thread. 01:34:32.281 [5256.4544] <4> bpfis: Acquiring snapshot lock:retry count [0] 01:34:32.281 [5256.4544] <2> getFileName: FileName is [D:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bountie.my.company.com_1451192423_copy1.lock] 01:34:32.281 [5256.4544] <2> getFileName: FileName is [D:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bountie.my.company.com_1451192423_copy1.lock] 01:34:32.281 [5256.4544] <2> readSnapshotLockFile: Read type[0] refcount[1] 01:34:32.281 [5256.4544] <2> readSnapshotLockFile: Read Flags [0] [0] 01:34:32.281 [5256.4544] <2> readSnapshotLockFile: Read Last addReffernt Time [Wed Dec 31 19:00:00 1969 ] 01:34:32.281 [5256.4544] <2> readSnapshotLockFile: Read hostname [] 01:34:32.281 [5256.4544] <2> readSnapshotLockFile: Read jobid[3023294] pid [5924] 01:34:32.281 [5256.4544] <2> bprd_isJobActive: client [bountie.my.company.com] master[nbv7.my.company.com] 01:34:32.375 [5256.4544] <2> vnet_pbxConnect: pbxConnectEx Succeeded 01:34:32.375 [5256.4544] <2> logconnections: BPRD CONNECT FROM 11.47.337.55.3145 TO 11.36.41.19.1556 fd = 1556 01:34:32.687 [5256.4544] <2> isJobActive: bprd_isJobActive returned [0] for jobid[3023294] 01:34:32.781 [5256.4544] <2> vnet_pbxConnect: pbxConnectEx Succeeded 01:34:32.781 [5256.4544] <2> logconnections: BPRD CONNECT FROM 11.47.337.55.3146 TO 11.36.41.19.1556 fd = 1556 01:34:33.219 [5256.4544] <4> bpfis: INF - BACKUP START 5256 01:34:33.219 [5256.4544] <32> bpfis: FTL - cannot open D:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.bountie.my.company.com_1451192423.1.0 01:34:33.219 [5256.4544] <4> bpfis: Starting keep alive thread. 01:34:33.219 [5256.4544] <4> bpfis: Delete reference: status [0] 01:34:33.219 [5256.4544] <2> writeSnapshotLockFile: Writing type[2] refcount[0] 01:34:33.219 [5256.4544] <2> writeSnapshotLockFile: Writing Flags [0] [0] 01:34:33.219 [5256.4544] <2> writeSnapshotLockFile: Writing Last addReffernt Time [Wed Dec 31 19:00:00 1969 ] 01:34:33.219 [5256.4544] <2> writeSnapshotLockFile: Writing Last Hostname [] 01:34:33.234 [5256.4544] <2> getFileName: FileName is [D:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bountie.my.company.com_1451192423_copy1.lock] 01:34:33.234 [5256.4544] <2> getFileName: FileName is [D:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bountie.my.company.com_1451192423_copy1.lock] 01:34:33.234 [5256.4544] <2> unlockSnapshot: file[D:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bountie.my.company.com_1451192423_copy1.lock] : zero reference count 01:34:33.234 [5256.4544] <4> bpfis: Released snapshot lock: status [0] 01:34:33.234 [5256.4544] <4> do_post_processing: Deleting snapshot lock files from client for id [bountie.my.company.com_1451192423] and copy_number [1] 01:34:33.234 [5256.4544] <2> getFileName: FileName is [D:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bountie.my.company.com_1451192423_copy1.lock] 01:34:33.234 [5256.4544] <2> getFileName: FileName is [D:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bountie.my.company.com_1451192423_copy1.lock] 01:34:33.234 [5256.4544] <2> deleteSnapshotLockFile: file[D:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bountie.my.company.com_1451192423_copy1.lock] deleted: status[0] 01:34:33.234 [5256.4544] <4> bpfis: INF - EXIT STATUS 4207: Could not fetch snapshot metadata or state files 01:34:40.890 [5680.5408] <2> logparams: D:\Program Files\Veritas\NetBackup\bin\bpfis.exe delete -nbu -id bountie.my.company.com_1451192423 -bpstart_to 300 -bpend_to 300 -clnt bountie.my.company.com -jobgrpid 3023294 -jobid 3023294 -copy 1 -S nbucore1 01:34:40.890 [5680.5408] <4> bpfis: Starting keep alive thread. 01:34:40.890 [5680.5408] <4> bpfis: Acquiring snapshot lock:retry count [0] 01:34:40.890 [5680.5408] <2> getFileName: FileName is [D:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bountie.my.company.com_1451192423_copy1.lock] 01:34:40.890 [5680.5408] <2> getFileName: FileName is [D:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bountie.my.company.com_1451192423_copy1.lock] 01:34:40.906 [5680.5408] <2> vnet_pbxConnect: pbxConnectEx Succeeded 01:34:40.906 [5680.5408] <2> logconnections: BPRD CONNECT FROM 11.47.337.55.3157 TO 11.36.41.19.1556 fd = 1580 01:34:41.406 [5680.5408] <4> bpfis: INF - BACKUP START 5680 01:34:41.406 [5680.5408] <32> bpfis: FTL - cannot open D:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.bountie.my.company.com_1451192423.1.0 01:34:41.406 [5680.5408] <4> bpfis: Starting keep alive thread. 01:34:41.406 [5680.5408] <2> deleteReference: reference not found in the list 01:34:41.406 [5680.5408] <4> bpfis: Delete reference: status [-4] 01:34:41.406 [5680.5408] <2> writeSnapshotLockFile: Writing type[2] refcount[0] 01:34:41.406 [5680.5408] <2> writeSnapshotLockFile: Writing Flags [0] [0] 01:34:41.406 [5680.5408] <2> writeSnapshotLockFile: Writing Last addReffernt Time [Wed Dec 31 19:00:00 1969 ] 01:34:41.406 [5680.5408] <2> writeSnapshotLockFile: Writing Last Hostname [] 01:34:41.406 [5680.5408] <2> getFileName: FileName is [D:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bountie.my.company.com_1451192423_copy1.lock] 01:34:41.406 [5680.5408] <2> getFileName: FileName is [D:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bountie.my.company.com_1451192423_copy1.lock] 01:34:41.406 [5680.5408] <2> unlockSnapshot: file[D:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bountie.my.company.com_1451192423_copy1.lock] : zero reference count 01:34:41.406 [5680.5408] <4> bpfis: Released snapshot lock: status [0] 01:34:41.406 [5680.5408] <4> do_post_processing: Deleting snapshot lock files from client for id [bountie.my.company.com_1451192423] and copy_number [1] 01:34:41.406 [5680.5408] <2> getFileName: FileName is [D:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bountie.my.company.com_1451192423_copy1.lock] 01:34:41.406 [5680.5408] <2> getFileName: FileName is [D:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bountie.my.company.com_1451192423_copy1.lock] 01:34:41.406 [5680.5408] <2> deleteSnapshotLockFile: file[D:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bountie.my.company.com_1451192423_copy1.lock] deleted: status[0] 01:34:41.406 [5680.5408] <4> bpfis: INF - EXIT STATUS 4207: Could not fetch snapshot metadata or state files 01:44:49.176 [4112.3912] <2> logparams: D:\Program Files\Veritas\NetBackup\bin\bpfis.exe create -nbu -owner NBU -id bountie.my.company.com_1451198680 -bpstart_to 300 -bpend_to 300 -backup_copy 0 -ru root -pt 13 -clnt bountie.my.company.com -fscp 1 -fscp_cksv 0 -rg root -class bountie.my.company.com -sched incremental -fso -S nbucore1 -jobid 3023641 -WOFB -fim VSS -nbu_version 123731970 -application_consistent 1 01:44:49.176 [4112.3912] <2> bpfis main: received FIM as [89523508043841539] 01:44:49.176 [4112.3912] <4> bpfis: Starting keep alive thread. 01:44:49.176 [4112.3912] <4> bpfis: INF - BACKUP START 4112 01:44:49.191 [4112.3912] <2> bpfis main: receive filelist: 01:44:49.191 [4112.3912] <2> check_special_names: got path entry as : 01:44:49.191 [4112.3912] <2> check_special_names: after conversion returning : 01:44:49.191 [4112.3912] <2> bpfis main: receive filelist: 01:44:49.191 [4112.3912] <2> check_special_names: got path entry as : 01:44:49.191 [4112.3912] <2> isWindowsDirective: directive detected : 01:44:49.191 [4112.3912] <2> check_special_names: after conversion returning : 01:44:49.191 [4112.3912] <2> bpfis main: receive filelist: 01:44:49.191 [4112.3912] <2> check_special_names: got path entry as : 01:44:49.191 [4112.3912] <2> check_special_names: after conversion returning : 01:44:49.191 [4112.3912] <2> bpfis main: receive filelist: 01:44:49.191 [4112.3912] <2> check_special_names: got path entry as : 01:44:49.191 [4112.3912] <2> check_special_names: GetVolNameForVolMntPt failed for : 01:44:49.191 [4112.3912] <2> check_special_names: after conversion returning : 01:44:49.191 [4112.3912] <2> bpfis main: receive filelist: 01:44:49.191 [4112.3912] <2> check_special_names: got path entry as : 01:44:49.191 [4112.3912] <2> check_special_names: after conversion returning : 01:44:49.191 [4112.3912] <2> bpfis main: receive filelist: 01:44:49.191 [4112.3912] <2> check_special_names: got path entry as : 01:44:49.191 [4112.3912] <2> check_special_names: GetVolNameForVolMntPt failed for : 01:44:49.191 [4112.3912] <2> check_special_names: after conversion returning : 01:44:49.191 [4112.3912] <2> bpfis main: receive filelist: 01:44:49.191 [4112.3912] <2> check_special_names: got path entry as : 01:44:49.191 [4112.3912] <2> check_special_names: after conversion returning : 01:44:49.238 [4112.3912] <8> bpfis: WRN - VfMS error 10; see following messages: 01:44:49.238 [4112.3912] <8> bpfis: WRN - Non-fatal method error was reported 01:44:49.238 [4112.3912] <8> bpfis: WRN - 01:44:49.238 [4112.3912] <8> bpfis: WRN - VfMS method error 0; see following message: 01:44:49.238 [4112.3912] <8> bpfis: WRN - 01:44:49.238 [4112.3912] <8> bpfis: WRN - VfMS error 10; see following messages: 01:44:49.238 [4112.3912] <8> bpfis: WRN - Non-fatal method error was reported 01:44:49.238 [4112.3912] <8> bpfis: WRN - 01:44:49.238 [4112.3912] <8> bpfis: WRN - VfMS method error 0; see following message: 01:44:49.238 [4112.3912] <8> bpfis: WRN - 01:44:49.379 [4112.3912] <4> bpfis: INF - FIS_ID=bountie.my.company.com_1451198680 01:44:49.379 [4112.3912] <4> bpfis: parameter client_type 13, fis_accl 1, fis_accl_cksv 0, SNAPSHOT_IS_HERE 1 01:44:49.379 [4112.3912] <4> bpfis: Volume guid list is empty, will not pause and flush NetBackup Change Journal databases 01:44:49.379 [4112.3912] <4> bpfis main: read_registry_dw_value for VSS_CONCURRENT_OPERATIONS: return status 2, return value 0 02:44:49.352 [4112.3912] <16> bpfis main: Failed to get VSS Snapshot Mutex lock!! 02:44:49.352 [4112.3912] <4> bpfis: INF - Thawing C:\ using snapshot method VSS. 02:44:49.352 [4112.3912] <8> bpfis: WRN - C:\ is not frozen 02:44:49.352 [4112.3912] <4> bpfis: INF - Thawing D:\ using snapshot method VSS. 02:44:49.352 [4112.3912] <8> bpfis: WRN - D:\ is not frozen 02:44:49.352 [4112.3912] <8> bpfis: WRN - snapshot delete returned status 20 02:44:49.352 [4112.3912] <4> bpfis: INF - BPFIS MESSAGE Routing to stream based backup for windows open file backup case, generating remap directive 02:44:49.352 [4112.3912] <4> bpfis: INF - REMAP FILE BACKUP NEW_STREAM 02:44:49.352 [4112.3912] <4> bpfis: INF - REMAP FILE BACKUP Shadow Copy Components:\ 02:44:49.352 [4112.3912] <4> bpfis: INF - REMAP FILE BACKUP NEW_STREAM 02:44:49.352 [4112.3912] <4> bpfis: INF - REMAP FILE BACKUP C:\ 02:44:49.352 [4112.3912] <4> bpfis: INF - REMAP FILE BACKUP NEW_STREAM 02:44:49.352 [4112.3912] <4> bpfis: INF - REMAP FILE BACKUP D:\ 02:44:49.352 [4112.3912] <2> getFileName: FileName is [D:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bountie.my.company.com_1451198680_copy1.lock] 02:44:49.352 [4112.3912] <2> getFileName: FileName is [D:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bountie.my.company.com_1451198680_copy1.lock] 02:44:49.352 [4112.3912] <2> addSnapshotReference: SnapShot Locked 02:44:49.352 [4112.3912] <2> addReference: Added refferent jobid[3023641] pid[4112] 02:44:49.352 [4112.3912] <2> writeSnapshotLockFile: Writing type[0] refcount[1] 02:44:49.352 [4112.3912] <2> writeSnapshotLockFile: Writing Flags [0] [0] 02:44:49.352 [4112.3912] <2> writeSnapshotLockFile: Writing Last addReffernt Time [Wed Dec 31 19:00:00 1969 ] 02:44:49.352 [4112.3912] <2> writeSnapshotLockFile: Writing Last Hostname [] 02:44:49.352 [4112.3912] <2> writeSnapshotLockFile: Writing jobid[3023641] pid [4112] 02:44:49.352 [4112.3912] <2> addSnapshotReference: SnapShot unlockLocked 02:44:49.368 [4112.3912] <4> bpfis: Starting keep alive thread. 02:44:49.368 [4112.3912] <4> bpfis: INF - EXIT STATUS 0: the requested operation was successfully completed 03:18:47.045 [4036.5500] <2> logparams: D:\Program Files\Veritas\NetBackup\bin\bpfis.exe delete -nbu -id bountie.my.company.com_1451198680 -bpstart_to 300 -bpend_to 300 -clnt bountie.my.company.com -jobgrpid 3023641 -jobid 3023641 -fscp 1 -fscp_cksv 0 -copy 1 -S nbucore1 03:18:47.045 [4036.5500] <4> bpfis: Starting keep alive thread. 03:18:47.045 [4036.5500] <4> bpfis: Acquiring snapshot lock:retry count [0] 03:18:47.045 [4036.5500] <2> getFileName: FileName is [D:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bountie.my.company.com_1451198680_copy1.lock] 03:18:47.045 [4036.5500] <2> getFileName: FileName is [D:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bountie.my.company.com_1451198680_copy1.lock] 03:18:47.045 [4036.5500] <2> readSnapshotLockFile: Read type[0] refcount[1] 03:18:47.045 [4036.5500] <2> readSnapshotLockFile: Read Flags [0] [0] 03:18:47.045 [4036.5500] <2> readSnapshotLockFile: Read Last addReffernt Time [Wed Dec 31 19:00:00 1969 ] 03:18:47.045 [4036.5500] <2> readSnapshotLockFile: Read hostname [] 03:18:47.045 [4036.5500] <2> readSnapshotLockFile: Read jobid[3023641] pid [4112] 03:18:47.045 [4036.5500] <2> bprd_isJobActive: client [bountie.my.company.com] master[nbv7.my.company.com] 03:18:47.154 [4036.5500] <2> vnet_pbxConnect: pbxConnectEx Succeeded 03:18:47.154 [4036.5500] <2> logconnections: BPRD CONNECT FROM 11.47.337.55.3690 TO 11.36.41.19.1556 fd = 1556 03:18:47.498 [4036.5500] <2> isJobActive: bprd_isJobActive returned [0] for jobid[3023641] 03:18:47.607 [4036.5500] <2> vnet_pbxConnect: pbxConnectEx Succeeded 03:18:47.607 [4036.5500] <2> logconnections: BPRD CONNECT FROM 11.47.337.55.3691 TO 11.36.41.19.1556 fd = 1556 03:18:48.029 [4036.5500] <4> bpfis: INF - BACKUP START 4036 03:18:48.029 [4036.5500] <32> bpfis: FTL - cannot open D:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.bountie.my.company.com_1451198680.1.0 03:18:48.029 [4036.5500] <4> bpfis: Starting keep alive thread. 03:18:48.029 [4036.5500] <4> bpfis: Delete reference: status [0] 03:18:48.029 [4036.5500] <2> writeSnapshotLockFile: Writing type[2] refcount[0] 03:18:48.029 [4036.5500] <2> writeSnapshotLockFile: Writing Flags [0] [0] 03:18:48.029 [4036.5500] <2> writeSnapshotLockFile: Writing Last addReffernt Time [Wed Dec 31 19:00:00 1969 ] 03:18:48.029 [4036.5500] <2> writeSnapshotLockFile: Writing Last Hostname [] 03:18:48.045 [4036.5500] <2> getFileName: FileName is [D:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bountie.my.company.com_1451198680_copy1.lock] 03:18:48.045 [4036.5500] <2> getFileName: FileName is [D:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bountie.my.company.com_1451198680_copy1.lock] 03:18:48.045 [4036.5500] <2> unlockSnapshot: file[D:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bountie.my.company.com_1451198680_copy1.lock] : zero reference count 03:18:48.045 [4036.5500] <4> bpfis: Released snapshot lock: status [0] 03:18:48.045 [4036.5500] <4> do_post_processing: Deleting snapshot lock files from client for id [bountie.my.company.com_1451198680] and copy_number [1] 03:18:48.045 [4036.5500] <2> getFileName: FileName is [D:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bountie.my.company.com_1451198680_copy1.lock] 03:18:48.045 [4036.5500] <2> getFileName: FileName is [D:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bountie.my.company.com_1451198680_copy1.lock] 03:18:48.060 [4036.5500] <2> deleteSnapshotLockFile: file[D:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bountie.my.company.com_1451198680_copy1.lock] deleted: status[0] 03:18:48.060 [4036.5500] <4> bpfis: INF - EXIT STATUS 4207: Could not fetch snapshot metadata or state files 03:18:53.029 [3484.2852] <2> logparams: D:\Program Files\Veritas\NetBackup\bin\bpfis.exe delete -nbu -id bountie.my.company.com_1451198680 -bpstart_to 300 -bpend_to 300 -clnt bountie.my.company.com -jobgrpid 3023641 -jobid 3023641 -copy 1 -S nbucore1 03:18:53.029 [3484.2852] <4> bpfis: Starting keep alive thread. 03:18:53.029 [3484.2852] <4> bpfis: Acquiring snapshot lock:retry count [0] 03:18:53.029 [3484.2852] <2> getFileName: FileName is [D:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bountie.my.company.com_1451198680_copy1.lock] 03:18:53.029 [3484.2852] <2> getFileName: FileName is [D:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bountie.my.company.com_1451198680_copy1.lock] 03:18:53.045 [3484.2852] <2> vnet_pbxConnect: pbxConnectEx Succeeded 03:18:53.045 [3484.2852] <2> logconnections: BPRD CONNECT FROM 11.47.337.55.3703 TO 11.36.41.19.1556 fd = 1568 03:18:53.498 [3484.2852] <4> bpfis: INF - BACKUP START 3484 03:18:53.498 [3484.2852] <32> bpfis: FTL - cannot open D:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.bountie.my.company.com_1451198680.1.0 03:18:53.498 [3484.2852] <4> bpfis: Starting keep alive thread. 03:18:53.498 [3484.2852] <2> deleteReference: reference not found in the list 03:18:53.498 [3484.2852] <4> bpfis: Delete reference: status [-4] 03:18:53.498 [3484.2852] <2> writeSnapshotLockFile: Writing type[2] refcount[0] 03:18:53.498 [3484.2852] <2> writeSnapshotLockFile: Writing Flags [0] [0] 03:18:53.513 [3484.2852] <2> writeSnapshotLockFile: Writing Last addReffernt Time [Wed Dec 31 19:00:00 1969 ] 03:18:53.513 [3484.2852] <2> writeSnapshotLockFile: Writing Last Hostname [] 03:18:53.513 [3484.2852] <2> getFileName: FileName is [D:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bountie.my.company.com_1451198680_copy1.lock] 03:18:53.513 [3484.2852] <2> getFileName: FileName is [D:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bountie.my.company.com_1451198680_copy1.lock] 03:18:53.513 [3484.2852] <2> unlockSnapshot: file[D:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bountie.my.company.com_1451198680_copy1.lock] : zero reference count 03:18:53.513 [3484.2852] <4> bpfis: Released snapshot lock: status [0] 03:18:53.513 [3484.2852] <4> do_post_processing: Deleting snapshot lock files from client for id [bountie.my.company.com_1451198680] and copy_number [1] 03:18:53.513 [3484.2852] <2> getFileName: FileName is [D:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bountie.my.company.com_1451198680_copy1.lock] 03:18:53.513 [3484.2852] <2> getFileName: FileName is [D:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bountie.my.company.com_1451198680_copy1.lock] 03:18:53.513 [3484.2852] <2> deleteSnapshotLockFile: file[D:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bountie.my.company.com_1451198680_copy1.lock] deleted: status[0] 03:18:53.513 [3484.2852] <4> bpfis: INF - EXIT STATUS 4207: Could not fetch snapshot metadata or state files