Flashbackup error code 13

[rookie11]

HI folks

I have a client server win 2003, running NBU 7.1 on it. I m using flashbackup option on it. everday we get error 13 on it .plz help

bpfis log error ;

22:39:58.857 [2204.5360] <32> fis_rebuild_from_db: FTL - cannot open C:\Program Files\VERITAS\NetBackup\online_util\fi_cntl\bpfis.fim.clientbox.com_1335749382.0

bpbkar log:

9:34:24.376 PM: [1484.3032] <4> ov_log::OVInit: INF - Starting log file: C:\Program Files\VERITAS\NetBackup\logs\BPBKAR\042912.LOG

9:34:24.376 PM: [1484.3032] <4> ov_log::OVInit: GENERAL Log Level: 1

9:34:24.376 PM: [1484.3032] <4> ov_log::OVInit: TCP Log Level: 1

9:34:24.376 PM: [1484.3032] <4> ov_log::OVInit: INF - the log mutex: 1592

BPBKAR  NetBackup Backup/Archive  7.1GA  [Feb  3 2011]

Copyright © 1993 - 2011 Symantec Corporation, All Rights Reserved.

All Rights Reserved.

9:34:24.407 PM: [1484.3032] <4> dtcp_initialize: TCP - WINSOCK.DLL Information:

9:34:24.407 PM: [1484.3032] <4> dtcp_initialize: TCP -  Version: 2.0

9:34:24.407 PM: [1484.3032] <4> dtcp_initialize: TCP -  Highest supported version: 2.2

9:34:24.407 PM: [1484.3032] <4> dtcp_initialize: TCP -  Description: WinSock 2.0

9:34:24.407 PM: [1484.3032] <4> dtcp_initialize: TCP -  System Status: Running

9:34:24.407 PM: [1484.3032] <4> dtcp_initialize: TCP -  Max Sockets: 0

9:34:24.407 PM: [1484.3032] <2> WinMain: DAT - _pgmptr = 'C:\Program Files\VERITAS\NetBackup\bin\bpbkar32.exe'

9:34:24.407 PM: [1484.3032] <2> WinMain: DAT - lpCmdLine = '-r 3628800 -ru root -dt 0 -to 0 -clnt clientbox.com -class clientbox_policy -sched Full -st FULL -bpstart_to 300 -bpend_to 300 -read_to 500 -stream_count 1 -stream_number 1 -jobgrpid 267625 -blks_per_buffer 512 -use_otm -fso -ifr -pid 6388 -mediasvr media.com -bt 1335749425 -t 0 -b clientbox.com_1335749425 -kl 35 -fi -S master.com -fim NONE -ct 29 -use_ofb '

9:34:24.407 PM: [1484.3032] <2> WinMain: DAT - INI: drive=C, dir=\Program Files\VERITAS\NetBackup

9:34:24.407 PM: [1484.3032] <2> WinMain: DAT - LOG: drive=C, dir=\Program Files\VERITAS\NetBackup\logs

9:34:24.407 PM: [1484.3032] <2> WinMain: DAT - EXE: drive=C, dir=\Program Files\VERITAS\NetBackup\bin

9:34:24.407 PM: [1484.3032] <2> date_debug: DAT - timezone: Eastern Standard Time, offset=18000, dst: Eastern Daylight Time

9:34:24.407 PM: [1484.3032] <2> date_debug: DAT - current time: 1335749664, 4/29/2012 9:34:24 PM

9:34:24.407 PM: [1484.3032] <2> date_debug: DAT - 01/01/94 UCT:  757382400, 12/31/1993 8:00:00 PM

9:34:24.407 PM: [1484.3032] <2> date_debug: DAT - 07/01/94 UCT:  773020800, 6/30/1994 8:00:00 PM

9:34:24.407 PM: [1484.3032] <2> WinMain: DAT - standard input handle = 396

9:34:24.407 PM: [1484.3032] <2> WinMain: DAT - standard output handle = 1800

9:34:24.407 PM: [1484.3032] <2> WinMain: DAT - standard error handle = 1732

9:34:24.407 PM: [1484.3032] <2> dtcp_setsocksize: TCP - setsockopt success on SO_RCVBUF: (socket: 396) (size: 33792)

9:34:24.407 PM: [1484.3032] <2> dtcp_setsocksize: TCP - setsockopt success on SO_SNDBUF: (socket: 396) (size: 33792)

9:34:24.407 PM: [1484.3032] <2> dtcp_setsocksize: TCP - setsockopt success on SO_RCVBUF: (socket: 1800) (size: 33792)

9:34:24.407 PM: [1484.3032] <2> dtcp_setsocksize: TCP - setsockopt success on SO_SNDBUF: (socket: 1800) (size: 33792)

9:34:24.407 PM: [1484.3032] <2> dtcp_setsocksize: TCP - setsockopt success on SO_RCVBUF: (socket: 1732) (size: 32768)

9:34:24.407 PM: [1484.3032] <2> dtcp_setsocksize: TCP - setsockopt success on SO_SNDBUF: (socket: 1732) (size: 32768)

9:34:24.595 PM: [1484.3032] <8> tar_process_backup_args: WRN - invalid option: -pid 6388

9:34:24.595 PM: [1484.3032] <8> tar_process_backup_args: WRN - invalid option: -mediasvr media.com

9:34:24.595 PM: [1484.3032] <8> tar_process_backup_args: WRN - invalid option: -bt 1335749425

9:34:24.595 PM: [1484.3032] <8> tar_process_backup_args: WRN - invalid option: -t 0

9:34:24.595 PM: [1484.3032] <4> tar_backup::V_SetupJobData: INF - dwJobData: ffffffff

9:34:24.595 PM: [1484.3032] <4> tar_backup::V_SetupJobData: INF -     dwJob: ffffffff

9:34:24.611 PM: [1484.3032] <4> tar_backup_tfi::cleanupLogs: INF - Purging log files from: C:\Program Files\VERITAS\NetBackup\logs\user_ops

9:34:24.720 PM: [1484.3032] <4> ncfLogConfiguration: INF - Module: libncf, Release: 7.1 , Build: 02/03/2011 12:50:00 CST (20110203)

9:34:24.751 PM: [1484.3032] <4> ncfLogConfiguration: INF - Windows version: 5.2.3790, Platform: 2 (Service Pack 2), Service pack: 2.0, Suite: 274, Product type: 3

9:34:24.767 PM: [1484.3032] <4> ncfLogConfiguration: INF - Process architecture: 0, Page size: 4096, Process type: 2, Process level: 586, Processor revision: 15

9:34:24.798 PM: [1484.3032] <4> NcfString::logLocale: INF - Current code page: WINDOWS: 1252  (ANSI - Latin I), Max. character width: 1, Substitution character: 0x3F [?], Unicode substitution character: 0x003f [?.]

9:34:24.814 PM: [1484.3032] <4> ImpersonatePlatform::logImpersonation: INF - Running as user 'SYSTEM@NT AUTHORITY', primary group 'SYSTEM@NT AUTHORITY'

9:34:24.829 PM: [1484.3032] <4> ImpersonatePlatform::logImpersonation: INF - New object owner 'Administrators@BUILTIN'

9:34:24.861 PM: [1484.3032] <4> ImpersonatePlatform::logImpersonation: INF - Member of group 'SYSTEM@NT AUTHORITY'

9:34:24.876 PM: [1484.3032] <4> ImpersonatePlatform::logImpersonation: INF - Member of group 'Administrators@BUILTIN [SE_GROUP_ENABLED_BY_DEFAULT SE_GROUP_ENABLED SE_GROUP_OWNER]'

9:34:24.907 PM: [1484.3032] <4> ImpersonatePlatform::logImpersonation: INF - Member of group 'Everyone [SE_GROUP_MANDATORY SE_GROUP_ENABLED_BY_DEFAULT SE_GROUP_ENABLED]'

9:34:24.923 PM: [1484.3032] <4> ImpersonatePlatform::logImpersonation: INF - Member of group 'Authenticated Users@NT AUTHORITY [SE_GROUP_MANDATORY SE_GROUP_ENABLED_BY_DEFAULT SE_GROUP_ENABLED]'

9:34:24.939 PM: [1484.3032] <4> ImpersonatePlatform::logImpersonation: INF - Running with privilege 'SeTcbPrivilege'

9:34:24.954 PM: [1484.3032] <4> ImpersonatePlatform::logImpersonation: INF - Running with privilege 'SeCreateGlobalPrivilege'

9:34:24.986 PM: [1484.3032] <4> ImpersonatePlatform::logImpersonation: INF - Running with privilege 'SeTakeOwnershipPrivilege'

9:34:25.001 PM: [1484.3032] <4> ImpersonatePlatform::logImpersonation: INF - Running with privilege 'SeCreatePagefilePrivilege'

9:34:25.032 PM: [1484.3032] <4> ImpersonatePlatform::logImpersonation: INF - Running with privilege 'SeLockMemoryPrivilege'

9:34:25.048 PM: [1484.3032] <4> ImpersonatePlatform::logImpersonation: INF - Running with privilege 'SeAssignPrimaryTokenPrivilege'

9:34:25.064 PM: [1484.3032] <4> ImpersonatePlatform::logImpersonation: INF - Running with privilege 'SeIncreaseQuotaPrivilege'

9:34:25.079 PM: [1484.3032] <4> ImpersonatePlatform::logImpersonation: INF - Running with privilege 'SeIncreaseBasePriorityPrivilege'

9:34:25.095 PM: [1484.3032] <4> ImpersonatePlatform::logImpersonation: INF - Running with privilege 'SeCreatePermanentPrivilege'

9:34:25.126 PM: [1484.3032] <4> ImpersonatePlatform::logImpersonation: INF - Running with privilege 'SeDebugPrivilege'

9:34:25.142 PM: [1484.3032] <4> ImpersonatePlatform::logImpersonation: INF - Running with privilege 'SeAuditPrivilege'

9:34:25.157 PM: [1484.3032] <4> ImpersonatePlatform::logImpersonation: INF - Running with privilege 'SeSecurityPrivilege'

9:34:25.189 PM: [1484.3032] <4> ImpersonatePlatform::logImpersonation: INF - Running with privilege 'SeSystemEnvironmentPrivilege'

9:34:25.204 PM: [1484.3032] <4> ImpersonatePlatform::logImpersonation: INF - Running with privilege 'SeChangeNotifyPrivilege'

9:34:25.220 PM: [1484.3032] <4> ImpersonatePlatform::logImpersonation: INF - Running with privilege 'SeBackupPrivilege'

9:34:25.251 PM: [1484.3032] <4> ImpersonatePlatform::logImpersonation: INF - Running with privilege 'SeRestorePrivilege'

9:34:25.267 PM: [1484.3032] <4> ImpersonatePlatform::logImpersonation: INF - Running with privilege 'SeShutdownPrivilege'

9:34:25.298 PM: [1484.3032] <4> ImpersonatePlatform::logImpersonation: INF - Running with privilege 'SeLoadDriverPrivilege'

9:34:25.314 PM: [1484.3032] <4> ImpersonatePlatform::logImpersonation: INF - Running with privilege 'SeProfileSingleProcessPrivilege'

9:34:25.329 PM: [1484.3032] <4> ImpersonatePlatform::logImpersonation: INF - Running with privilege 'SeSystemtimePrivilege'

9:34:25.361 PM: [1484.3032] <4> ImpersonatePlatform::logImpersonation: INF - Running with privilege 'SeUndockPrivilege'

9:34:25.376 PM: [1484.3032] <4> ImpersonatePlatform::logImpersonation: INF - Running with privilege 'SeManageVolumePrivilege'

9:34:25.392 PM: [1484.3032] <4> ImpersonatePlatform::logImpersonation: INF - Running with privilege 'SeImpersonatePrivilege'

9:34:25.407 PM: [1484.3032] <4> ImpersonatePlatform::logImpersonation: INF - Environment variable =C:=C:\Program Files\VERITAS\NetBackup

9:34:25.423 PM: [1484.3032] <4> ImpersonatePlatform::logImpersonation: INF - Environment variable ALLUSERSPROFILE=C:\Documents and Settings\All Users

9:34:25.439 PM: [1484.3032] <4> ImpersonatePlatform::logImpersonation: INF - Environment variable ClusterLog=C:\WINDOWS\Cluster\cluster.log

9:34:25.470 PM: [1484.3032] <4> ImpersonatePlatform::logImpersonation: INF - Environment variable CommonProgramFiles=C:\Program Files\Common Files

9:34:25.486 PM: [1484.3032] <4> ImpersonatePlatform::logImpersonation: INF - Environment variable COMPUTERNAME=clientbox

9:34:25.501 PM: [1484.3032] <4> ImpersonatePlatform::logImpersonation: INF - Environment variable ComSpec=C:\WINDOWS\system32\cmd.exe

9:34:25.517 PM: [1484.3032] <4> ImpersonatePlatform::logImpersonation: INF - Environment variable DEFLOGDIR=C:\Documents and Settings\All Users\Application Data\McAfee\DesktopProtection

9:34:25.548 PM: [1484.3032] <4> ImpersonatePlatform::logImpersonation: INF - Environment variable FP_NO_HOST_CHECK=NO

9:34:25.564 PM: [1484.3032] <4> ImpersonatePlatform::logImpersonation: INF - Environment variable IS_NETBACKUP_DAEMON=YES

9:34:25.579 PM: [1484.3032] <4> ImpersonatePlatform::logImpersonation: INF - Environment variable NUMBER_OF_PROCESSORS=2

9:34:25.611 PM: [1484.3032] <4> ImpersonatePlatform::logImpersonation: INF - Environment variable OS=Windows_NT

9:34:25.626 PM: [1484.3032] <4> ImpersonatePlatform::logImpersonation: INF - Environment variable Path=C:\Program Files\EMC\PowerPath\;E:\oracle\product\10.2.0\client_1;C:\WINDOWS\system32;C:\WINDOWS;C:\WINDOWS\System32\Wbem;C:\PROGRA~1\BMCSOF~1\Patrol3\bin;C:\Program Files\IBM\Director\bin;C:\Program Files\Common Files\IBM\ICC\cimom\bin

9:34:25.642 PM: [1484.3032] <4> ImpersonatePlatform::logImpersonation: INF - Environment variable PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH

9:34:25.657 PM: [1484.3032] <4> ImpersonatePlatform::logImpersonation: INF - Environment variable PATROL_HOME=C:\PROGRA~1\BMCSOF~1\Patrol3\

9:34:25.689 PM: [1484.3032] <4> ImpersonatePlatform::logImpersonation: INF - Environment variable PATROL_TEMP=C:\PROGRA~1\BMCSOF~1\Patrol3\tmp

9:34:25.704 PM: [1484.3032] <4> ImpersonatePlatform::logImpersonation: INF - Environment variable Powmig Path=C:\Program Files\EMC\PowerPath\

9:34:25.720 PM: [1484.3032] <4> ImpersonatePlatform::logImpersonation: INF - Environment variable PROCESSOR_ARCHITECTURE=x86

9:34:25.736 PM: [1484.3032] <4> ImpersonatePlatform::logImpersonation: INF - Environment variable PROCESSOR_IDENTIFIER=x86 Family 15 Model 4 Stepping 1, GenuineIntel

9:34:25.751 PM: [1484.3032] <4> ImpersonatePlatform::logImpersonation: INF - Environment variable PROCESSOR_LEVEL=15

9:34:25.767 PM: [1484.3032] <4> ImpersonatePlatform::logImpersonation: INF - Environment variable PROCESSOR_REVISION=0401

9:34:25.798 PM: [1484.3032] <4> ImpersonatePlatform::logImpersonation: INF - Environment variable ProgramFiles=C:\Program Files

9:34:25.814 PM: [1484.3032] <4> ImpersonatePlatform::logImpersonation: INF - Environment variable STREAM_COUNT=1

9:34:25.829 PM: [1484.3032] <4> ImpersonatePlatform::logImpersonation: INF - Environment variable STREAM_NUMBER=1

9:34:25.861 PM: [1484.3032] <4> ImpersonatePlatform::logImpersonation: INF - Environment variable STREAM_PID=1484

9:34:25.876 PM: [1484.3032] <4> ImpersonatePlatform::logImpersonation: INF - Environment variable SystemDrive=C:

9:34:25.907 PM: [1484.3032] <4> ImpersonatePlatform::logImpersonation: INF - Environment variable SystemRoot=C:\WINDOWS

9:34:25.939 PM: [1484.3032] <4> ImpersonatePlatform::logImpersonation: INF - Environment variable TEMP=C:\WINDOWS\TEMP

9:34:25.954 PM: [1484.3032] <4> ImpersonatePlatform::logImpersonation: INF - Environment variable TMP=C:\WINDOWS\TEMP

9:34:25.986 PM: [1484.3032] <4> ImpersonatePlatform::logImpersonation: INF - Environment variable USERPROFILE=C:\Documents and Settings\Default User

9:34:26.017 PM: [1484.3032] <4> ImpersonatePlatform::logImpersonation: INF - Environment variable VSEDEFLOGDIR=C:\Documents and Settings\All Users\Application Data\McAfee\DesktopProtection

9:34:26.032 PM: [1484.3032] <4> ImpersonatePlatform::logImpersonation: INF - Environment variable windir=C:\WINDOWS

9:34:26.032 PM: [1484.3032] <4> dos_backup::V_PreProcessing: INF - backup privileges enabled, previous = 0

9:34:26.032 PM: [1484.3032] <4> dos_backup::V_PreProcessing: INF - restore privileges enabled, previous = 0

9:34:26.032 PM: [1484.3032] <4> dos_backup::V_PreProcessing: INF - security privileges enabled, previous = 0

9:34:26.032 PM: [1484.3032] <4> dos_backup::V_PreProcessing: INF - tcb privileges enabled, previous = 0

9:34:26.032 PM: [1484.3032] <4> dos_backup::V_PreProcessing: INF - create token privileges enabled, previous = 0

9:34:26.032 PM: [1484.3032] <4> dos_backup::V_PreProcessing: INF - user name: root

9:34:26.032 PM: [1484.3032] <4> dos_backup::V_PreProcessing: INF - no access token: scheduled backup

9:34:26.048 PM: [1484.3032] <2> ov_log::V_GlobalLog: WRN - ubsDetermineExchangeVersion(): RegOpenKeyEx() failed for HKLM\SOFTWARE\MICROSOFT\ExchangeServer\v14\Setup - 0x2.

9:34:26.048 PM: [1484.3032] <2> ov_log::V_GlobalLog: INF - BEDS_Init() Enter InitFlags:0x0

9:34:26.079 PM: [1484.3032] <2> ComputerNameMgr::setName: DBG - Changing computer name to clientbox (../ComputerName.cpp:92)

9:34:27.204 PM: [1484.3032] <2> ov_log::V_GlobalLog: INF - BEDS_Init() BEDS debgging will be enabled - dwDebugLevel = 1.

9:34:27.407 PM: [1484.3032] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: C:

9:34:27.407 PM: [1484.3032] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: E:

9:34:27.407 PM: [1484.3032] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Microsoft Terminal Services

9:34:27.407 PM: [1484.3032] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Microsoft Windows Network

9:34:27.407 PM: [1484.3032] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Web Client Network

9:34:27.407 PM: [1484.3032] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Shadow?Copy?Components

9:34:27.407 PM: [1484.3032] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: System?State

9:34:27.407 PM: [1484.3032] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Active Directory Application Mode

9:34:28.001 PM: [1484.3032] <2> ov_log::V_GlobalLog: WRN - BEDS_Init() FS_BlowOutMachine() Failed! (0x34:You were not connected because a duplicate name exists on the network. Go to System in Control Panel to change the computer name and try again.

)

9:34:28.017 PM: [1484.3032] <2> BEDSContext::setProdID(): DBG - CMS_SetProd(1) (../BEDSContext.cpp:287)

9:34:28.017 PM: [1484.3032] <2> BEDSContext::_discover(): DBG - Computer Name clientbox (../BEDSContext.cpp:498)

9:34:28.017 PM: [1484.3032] <2> BEDSContext::_discover(): DBG - Unable to resolve_key for Lotus Notes Plug-in (../BEDSContext.cpp:558)

9:34:28.017 PM: [1484.3032] <2> BEDSContext::_discover(): DBG - Found registry key for Oracle Plug-in (../BEDSContext.cpp:574)

9:34:28.017 PM: [1484.3032] <2> BEDSContext::_discover(): DBG - Unable to locate info for DB2 Plug-in (../BEDSContext.cpp:619)

9:34:28.032 PM: [1484.3032] <2> SubContextBEDS_Impl::_initialize(): DBG - BEDS is initialized! (../SubContextBEDS.cpp:790)

9:34:28.032 PM: [1484.3032] <2> _dumpDLEInfo(): DBG - Device Name : C: (../SubContextBEDS.cpp:180)

9:34:28.032 PM: [1484.3032] <2> _dumpDLEInfo(): DBG - Device Name : E: (../SubContextBEDS.cpp:180)

9:34:28.032 PM: [1484.3032] <2> _dumpDLEInfo(): DBG - Device Name : Microsoft Terminal Services (../SubContextBEDS.cpp:180)

9:34:28.032 PM: [1484.3032] <2> _dumpDLEInfo(): DBG - Device Name : Microsoft Windows Network (../SubContextBEDS.cpp:180)

9:34:28.032 PM: [1484.3032] <2> _dumpDLEInfo(): DBG - Device Name : Web Client Network (../SubContextBEDS.cpp:180)

9:34:28.032 PM: [1484.3032] <2> _dumpDLEInfo(): DBG - Device Name : Shadow?Copy?Components (../SubContextBEDS.cpp:180)

9:34:28.032 PM: [1484.3032] <2> _dumpDLEInfo(): DBG - Device Name : System?State (../SubContextBEDS.cpp:180)

9:34:28.032 PM: [1484.3032] <2> _dumpDLEInfo(): DBG - Device Name : Active Directory Application Mode (../SubContextBEDS.cpp:180)

9:34:28.032 PM: [1484.3032] <2> tar_base::V_vTarMsgW: INF - Inform when done

9:34:28.032 PM: [1484.3032] <2> tar_base::V_vTarMsgW: INF - Echo keepalives

9:34:28.079 PM: [1484.3032] <2> tar_base::V_vTarMsgW: INF - BACKUP START 1484

9:34:28.079 PM: [1484.3032] <2> tar_base::V_vTarMsgW: INF - BACKUP 4/29/2012 9:34:28 PM clientbox.com clientbox_policy Full FULL 

9:34:28.236 PM: [1484.3032] <2> tar_backup::V_SetupProcessContinue: TAR - CONTINUE BACKUP received

9:34:28.236 PM: [1484.3032] <2> tar_base::V_vTarMsgW: INF - CONTINUE BACKUP message received

9:34:28.501 PM: [1484.3032] <2> tar_backup_tfi::setupFileDirectives: TAR - backup filename = BACKUP \\.\E:\ USING \\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy23\ OPTIONS:ALT_PATH_PREFIX=C:\Program Files\VERITAS\NetBackup\temp\_vrts_frzn_img_4212,FITYPE=MIRROR,MNTPOINT=\\.\E:\,FSTYPE=NTFS

9:34:28.501 PM: [1484.3032] <4> tar_base::startKeepaliveThread: INF - keepalive thread started

9:34:28.501 PM: [1484.4068] <4> tar_base::keepaliveThread: INF - keepalive thread is active with an interval of 60 seconds

9:34:28.501 PM: [1484.4068] <4> tar_base::keepaliveThread: INF - sending keepalive

9:34:29.564 PM: [1484.3032] <2> tar_backup_vxbsa::add: INF - called with 'BACKUP \\.\E:\ USING \\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy23\ OPTIONS:ALT_PATH_PREFIX=C:\Program Files\VERITAS\NetBackup\temp\_vrts_frzn_img_4212,FITYPE=MIRROR,MNTPOINT=\\.\E:\,FSTYPE=NTFS'

9:34:29.564 PM: [1484.3032] <4> V_Snapshot::V_Snapshot_AddMappings: INF - V_Snapshot_AddMappings input parameters: 

 source path = "\\.\E:\"

 snap path = "\\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy23\"

 mount path = "\\.\E:\"

9:34:29.564 PM: [1484.3032] <4> V_Snapshot::V_GetSourceVolume: INF - source = "\\.\E:\"

 snapshot = "\\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy23\"

9:34:29.579 PM: [1484.3032] <4> V_Snapshot::V_Snapshot_AddMappings: INF - Volume Snapshot Mapping: \\?\Volume{9fa7306e-6f78-4a09-8f84-94536a5fd38f}\ --> GLOBALROOT\Device\HarddiskVolumeShadowCopy23

9:34:29.579 PM: [1484.3032] <4> dos_backup::V_VerifyFileSystem: INF - Verifying: \\.\E:\

9:34:29.595 PM: [1484.3032] <4> dos_backup::V_VerifyFileList: INF - UBS Local Type for '\\.\E:\' --> 00000000

9:34:34.157 PM: [1484.3032] <2> ov_log::V_GlobalLog: INF - Successfully attached to Device 'Shadow?Copy?Components' BackupReason:0x800

9:34:34.892 PM: [1484.3032] <2> Packer::open(): DBG - Started Backup... (../Packer.cpp:275)

9:34:34.892 PM: [1484.3032] <4> backup_create: INF - NetBackup Temp Directory: 'C:\Program Files\VERITAS\\NetBackup\Temp'

9:34:34.892 PM: [1484.3032] <2> tar_base::V_vTarMsgW: INF - Estimate:-1 -1

9:34:34.892 PM: [1484.3032] <4> dos_backup::tfs_raw_start: INF - checking for: E:\

9:34:34.892 PM: [1484.3032] <4> V_DetermineMountInfo: INF - Checking Volume \\?\Volume{9fa7306e-6f78-4a09-8f84-94536a5fd38f}\ for '\'

9:34:34.892 PM: [1484.3032] <4> V_DetermineMountInfo: INF -   Adding NTFS Volume \\.\E: ==> \\?\Volume{9fa7306e-6f78-4a09-8f84-94536a5fd38f}\

9:34:34.892 PM: [1484.3032] <4> dos_backup::tfs_raw_start: INF - RAW: \\.\

9:34:34.892 PM: [1484.3032] <4> dos_backup::tfs_raw_start: INF - RAW: E:

9:34:34.892 PM: [1484.3032] <4> tar_backup_tfi::flash_start_state: FlashBackup of Raw partition 

9:34:34.907 PM: [1484.3032] <4> tar_backup_tfi::flash_start_state: FlashBackup of Raw partition isFlashBackup 0 

9:34:34.923 PM: [1484.3032] <2> tar_backup_tfi::flash_start_state: FlashBackup of Raw partition \\.\E:\,

 Frozen Device \\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy23\'

9:34:42.314 PM: [1484.3032] <2> tar_base::V_vTarMsgW: INF - FIML startup time = 7

9:34:42.314 PM: [1484.3032] <2> tar_base::V_vTarMsgW: INF - Windows filesystem

9:34:45.610 PM: [1484.3032] <2> tar_base::V_vTarMsgW: INF - arg list: rawdisk E:\ inctime 0 Startblk 0

9:34:45.610 PM: [1484.3032] <2> tar_base::V_vTarMsgW: INF - clientname clientbox.com, buid clientbox.com_1335749425, filnum 0

9:34:45.610 PM: [1484.3032] <2> tar_base::V_vTarMsgW: INF - File System Name to catalog E:\.

9:34:45.610 PM: [1484.3032] <2> tar_base::V_vTarMsgW: INF - iDataLength == 0

9:34:45.610 PM: [1484.3032] <2> tar_base::V_vTarMsgW: INF - File System Name to catalog E:\.

9:35:28.500 PM: [1484.4068] <4> tar_base::keepaliveThread: INF - sending keepalive

9:36:28.500 PM: [1484.4068] <4> tar_base::keepaliveThread: INF - sending keepalive

9:37:29.421 PM: [1484.4068] <4> tar_base::keepaliveThread: INF - sending keepalive

9:38:29.420 PM: [1484.4068] <4> tar_base::keepaliveThread: INF - sending keepalive

9:39:29.419 PM: [1484.4068] <4> tar_base::keepaliveThread: INF - sending keepalive

9:40:29.418 PM: [1484.4068] <4> tar_base::keepaliveThread: INF - sending keepalive

9:41:29.418 PM: [1484.4068] <4> tar_base::keepaliveThread: INF - sending keepalive

9:42:29.417 PM: [1484.4068] <4> tar_base::keepaliveThread: INF - sending keepalive

9:43:29.416 PM: [1484.4068] <4> tar_base::keepaliveThread: INF - sending keepalive

9:44:29.415 PM: [1484.4068] <4> tar_base::keepaliveThread: INF - sending keepalive

9:45:29.415 PM: [1484.4068] <4> tar_base::keepaliveThread: INF - sending keepalive

9:46:29.414 PM: [1484.4068] <4> tar_base::keepaliveThread: INF - sending keepalive

9:47:29.413 PM: [1484.4068] <4> tar_base::keepaliveThread: INF - sending keepalive

9:48:29.412 PM: [1484.4068] <4> tar_base::keepaliveThread: INF - sending keepalive

9:49:29.412 PM: [1484.4068] <4> tar_base::keepaliveThread: INF - sending keepalive

9:50:29.411 PM: [1484.4068] <4> tar_base::keepaliveThread: INF - sending keepalive

9:51:29.410 PM: [1484.4068] <4> tar_base::keepaliveThread: INF - sending keepalive

9:52:29.409 PM: [1484.4068] <4> tar_base::keepaliveThread: INF - sending keepalive

9:53:29.408 PM: [1484.4068] <4> tar_base::keepaliveThread: INF - sending keepalive

9:54:29.408 PM: [1484.4068] <4> tar_base::keepaliveThread: INF - sending keepalive

9:55:29.407 PM: [1484.4068] <4> tar_base::keepaliveThread: INF - sending keepalive

9:56:29.406 PM: [1484.4068] <4> tar_base::keepaliveThread: INF - sending keepalive

9:57:30.015 PM: [1484.4068] <4> tar_base::keepaliveThread: INF - sending keepalive

9:58:30.014 PM: [1484.4068] <4> tar_base::keepaliveThread: INF - sending keepalive

9:59:30.013 PM: [1484.4068] <4> tar_base::keepaliveThread: INF - sending keepalive

10:00:30.012 PM: [1484.4068] <4> tar_base::keepaliveThread: INF - sending keepalive

10:01:30.012 PM: [1484.4068] <4> tar_base::keepaliveThread: INF - sending keepalive

10:02:30.011 PM: [1484.4068] <4> tar_base::keepaliveThread: INF - sending keepalive

10:03:30.010 PM: [1484.4068] <4> tar_base::keepaliveThread: INF - sending keepalive

10:04:30.150 PM: [1484.4068] <4> tar_base::keepaliveThread: INF - sending keepalive

10:05:30.149 PM: [1484.4068] <4> tar_base::keepaliveThread: INF - sending keepalive

10:06:30.148 PM: [1484.4068] <4> tar_base::keepaliveThread: INF - sending keepalive

10:07:30.148 PM: [1484.4068] <4> tar_base::keepaliveThread: INF - sending keepalive

10:08:30.147 PM: [1484.4068] <4> tar_base::keepaliveThread: INF - sending keepalive

10:09:30.146 PM: [1484.4068] <4> tar_base::keepaliveThread: INF - sending keepalive

10:10:30.145 PM: [1484.4068] <4> tar_base::keepaliveThread: INF - sending keepalive

10:11:30.145 PM: [1484.4068] <4> tar_base::keepaliveThread: INF - sending keepalive

10:12:30.144 PM: [1484.4068] <4> tar_base::keepaliveThread: INF - sending keepalive

10:13:30.143 PM: [1484.4068] <4> tar_base::keepaliveThread: INF - sending keepalive

10:14:30.142 PM: [1484.4068] <4> tar_base::keepaliveThread: INF - sending keepalive

10:15:30.142 PM: [1484.4068] <4> tar_base::keepaliveThread: INF - sending keepalive

10:16:31.958 PM: [1484.4068] <4> tar_base::keepaliveThread: INF - sending keepalive

10:17:31.945 PM: [1484.4068] <4> tar_base::keepaliveThread: INF - sending keepalive

10:18:31.933 PM: [1484.4068] <4> tar_base::keepaliveThread: INF - sending keepalive

10:19:31.920 PM: [1484.4068] <4> tar_base::keepaliveThread: INF - sending keepalive

10:20:31.907 PM: [1484.4068] <4> tar_base::keepaliveThread: INF - sending keepalive

10:21:31.902 PM: [1484.4068] <4> tar_base::keepaliveThread: INF - sending keepalive

10:22:32.162 PM: [1484.4068] <4> tar_base::keepaliveThread: INF - sending keepalive

10:23:32.157 PM: [1484.4068] <4> tar_base::keepaliveThread: INF - sending keepalive

10:24:32.152 PM: [1484.4068] <4> tar_base::keepaliveThread: INF - sending keepalive

10:25:33.850 PM: [1484.4068] <4> tar_base::keepaliveThread: INF - sending keepalive

10:26:33.848 PM: [1484.4068] <4> tar_base::keepaliveThread: INF - sending keepalive

10:27:34.064 PM: [1484.4068] <4> tar_base::keepaliveThread: INF - sending keepalive

10:28:34.062 PM: [1484.4068] <4> tar_base::keepaliveThread: INF - sending keepalive

10:29:34.060 PM: [1484.4068] <4> tar_base::keepaliveThread: INF - sending keepalive

10:30:34.057 PM: [1484.4068] <4> tar_base::keepaliveThread: INF - sending keepalive

10:31:34.056 PM: [1484.4068] <4> tar_base::keepaliveThread: INF - sending keepalive

10:32:34.054 PM: [1484.4068] <4> tar_base::keepaliveThread: INF - sending keepalive

10:33:34.396 PM: [1484.4068] <4> tar_base::keepaliveThread: INF - sending keepalive

10:34:34.395 PM: [1484.4068] <4> tar_base::keepaliveThread: INF - sending keepalive

10:35:34.393 PM: [1484.4068] <4> tar_base::keepaliveThread: INF - sending keepalive

10:36:34.705 PM: [1484.4068] <4> tar_base::keepaliveThread: INF - sending keepalive

10:37:34.704 PM: [1484.4068] <4> tar_base::keepaliveThread: INF - sending keepalive

10:38:34.734 PM: [1484.4068] <4> tar_base::keepaliveThread: INF - sending keepalive

10:39:14.295 PM: [1484.3032] <2> tar_base::V_vTarMsgW: INF - File System Name to catalog E:\.

10:39:14.295 PM: [1484.3032] <2> tar_base::V_vTarMsgW: INF - File System Name to catalog E:\.

10:39:14.295 PM: [1484.3032] <2> tar_base::V_vTarMsgW: INF - Map completion time: 3869

10:39:14.295 PM: [1484.3032] <2> tar_base::V_vTarMsgW: INF - scan_fs time: 3869

10:39:14.374 PM: [1484.3032] <2> tar_base::V_vTarMsgW: INF - VxMS Performance stats: 

10:39:14.374 PM: [1484.3032] <2> tar_base::V_vTarMsgW: INF - Write Buffer Time: 0, Get Buffer Time: 0

10:39:14.374 PM: [1484.3032] <2> tar_base::V_vTarMsgW: INF - Get Metadata Time: 270, Write Catalog Time: 566

10:39:14.374 PM: [1484.3032] <2> tar_base::V_vTarMsgW: INF - Create Names Time: 437, Put Tape Time: 626

10:39:14.374 PM: [1484.3032] <2> tar_base::V_vTarMsgW: INF - Build Map Time: 11, Extract Stat Time: 52

10:39:14.374 PM: [1484.3032] <2> tar_base::V_vTarMsgW: INF - Build Image Time: 1500, Next Index Time: 2354

10:39:14.374 PM: [1484.3032] <2> tar_backup_tfi::flash_start_state: TAR - writing file 13412221 '\\.\E:'

10:39:14.405 PM: [1484.3032] <4> dos_backup::tfs_readopen: INF - Snapshot name - GLOBALROOT\Device\HarddiskVolumeShadowCopy23

10:39:15.405 PM: [1484.3032] <2> tar_base::V_vTarMsgW: WRN - can't open raw device: \\.\E: (\\.\GLOBALROOT\Device\HarddiskVolumeShadowCopy23) (WIN32 2: The system cannot find the file specified. )

10:39:15.405 PM: [1484.3032] <4> tar_backup::backup_done_state: INF - number of file directives not found: 0

10:39:15.405 PM: [1484.3032] <4> tar_backup::backup_done_state: INF -     number of file directives found: 1

10:39:15.405 PM: [1484.3032] <2> tar_base::V_vTarMsgW: INF - Client completed sending data for backup

10:39:15.405 PM: [1484.3032] <4> tar_base::stopKeepaliveThread: INF - waiting for keepalive thread to exit ...

10:39:15.405 PM: [1484.4068] <4> tar_base::keepaliveThread: INF - keepalive thread terminating (reason: WAIT_OBJECT_0)

10:39:15.420 PM: [1484.3032] <4> tar_base::stopKeepaliveThread: INF - keepalive thread has exited. (reason: WAIT_OBJECT_0)

10:39:15.420 PM: [1484.3032] <2> tar_base::V_vTarMsgW: INF - EXIT STATUS 13: file read failed

10:39:15.420 PM: [1484.3032] <4> tar_backup::backup_done_state: INF - Not waiting for server status

10:39:15.420 PM: [1484.3032] <4> dos_backup::tfs_reset: INF - Snapshot deletion start

10:39:15.420 PM: [1484.3032] <4> ov_log::OVLoop: Timestamp

10:39:15.420 PM: [1484.3032] <4> OVStopCmd: INF - EXIT - status = 0

10:39:15.420 PM: [1484.3032] <4> OVStopCmd: INF - Shutdown stderr connection

10:39:15.420 PM: [1484.3032] <2> dtcp_shutdown: TCP - success: shutdown socket (1732)

10:39:15.420 PM: [1484.3032] <4> OVStopCmd: INF - Shutdown stdout connection

10:39:15.420 PM: [1484.3032] <2> dtcp_shutdown: TCP - success: shutdown socket (1800)

10:39:15.420 PM: [1484.3032] <4> OVStopCmd: INF - Shutdown stdin connection

10:39:15.420 PM: [1484.3032] <2> dtcp_shutdown: TCP - success: shutdown socket (396)

10:39:15.420 PM: [1484.3032] <4> OVStopCmd: INF - Shutdown wait started

10:39:15.420 PM: [1484.3032] <2> tar_base::V_Close: closing...

10:39:15.420 PM: [1484.3032] <4> dos_backup::tfs_reset: INF - Snapshot deletion start

10:39:15.452 PM: [1484.3032] <2> TransporterRemote::write[2](): DBG -  | An Exception of type [SocketWriteException] has occured at: |   Module: @(#) $Source: src/ncf/tfi/lib/TransporterRemote.cpp,v $ $Revision: 1.54 $ , Function: TransporterRemote::write[2](), Line: 321 |   Local Address: [0.0.0.0]:0 |   Remote Address: [0.0.0.0]:0 |   OS Error: 10053 (An established connection was aborted by the software in your host machine.

) |   Expected bytes: 1536 |  (../TransporterRemote.cpp:321)

10:39:15.483 PM: [1484.3032] <2> ov_log::V_GlobalLog: INF - BEDS_Term(): enter - InitFlags:0x00000101

10:39:15.483 PM: [1484.3032] <2> ov_log::V_GlobalLog: INF - BEDS_Term(): ubs specifics: 0x001d0000

10:39:15.764 PM: [1484.3032] <16> dtcp_read: TCP - failure: recv socket (396) (TCP 10053: Software caused connection abort)

10:39:16.764 PM: [1484.3032] <16> dtcp_read: TCP - failure: recv socket (396) (TCP 10053: Software caused connection abort)

10:39:17.764 PM: [1484.3032] <16> dtcp_read: TCP - failure: recv socket (396) (TCP 10053: Software caused connection abort)

10:39:18.764 PM: [1484.3032] <16> dtcp_read: TCP - failure: recv socket (396) (TCP 10053: Software caused connection abort)

10:39:19.764 PM: [1484.3032] <16> dtcp_read: TCP - failure: recv socket (396) (TCP 10053: Software caused connection abort)

10:39:20.764 PM: [1484.3032] <16> dtcp_read: TCP - failure: recv socket (396) (TCP 10053: Software caused connection abort)

10:39:21.764 PM: [1484.3032] <16> dtcp_read: TCP - failure: recv socket (396) (TCP 10053: Software caused connection abort)

10:39:22.764 PM: [1484.3032] <16> dtcp_read: TCP - failure: recv socket (396) (TCP 10053: Software caused connection abort)

10:39:23.764 PM: [1484.3032] <16> dtcp_read: TCP - failure: recv socket (396) (TCP 10053: Software caused connection abort)

10:39:24.764 PM: [1484.3032] <16> dtcp_read: TCP - failure: recv socket (396) (TCP 10053: Software caused connection abort)

10:39:25.764 PM: [1484.3032] <16> dtcp_read: TCP - failure: recv socket (396) (TCP 10053: Software caused connection abort)

10:39:26.764 PM: [1484.3032] <16> dtcp_read: TCP - failure: recv socket (396) (TCP 10053: Software caused connection abort)

10:39:27.764 PM: [1484.3032] <16> dtcp_read: TCP - failure: recv socket (396) (TCP 10053: Software caused connection abort)

10:39:28.764 PM: [1484.3032] <16> dtcp_read: TCP - failure: recv socket (396) (TCP 10053: Software caused connection abort)

10:39:29.764 PM: [1484.3032] <16> dtcp_read: TCP - failure: recv socket (396) (TCP 10053: Software caused connection abort)

10:39:30.764 PM: [1484.3032] <16> dtcp_read: TCP - failure: recv socket (396) (TCP 10053: Software caused connection abort)

10:39:31.764 PM: [1484.3032] <16> dtcp_read: TCP - failure: recv socket (396) (TCP 10053: Software caused connection abort)

10:39:32.764 PM: [1484.3032] <16> dtcp_read: TCP - failure: recv socket (396) (TCP 10053: Software caused connection abort)

10:39:33.764 PM: [1484.3032] <16> dtcp_read: TCP - failure: recv socket (396) (TCP 10053: Software caused connection abort)

10:39:33.764 PM: [1484.3032] <16> dtcp_read: TCP - failure: recv socket (1732) (TCP 10058: Can't send after socket shutdown)

10:39:33.764 PM: [1484.3032] <4> OVShutdown: INF - Closing stderr connection

10:39:33.764 PM: [1484.3032] <2> dtcp_close: TCP - success: close socket (1732)

10:39:33.764 PM: [1484.3032] <4> OVShutdown: INF - Closing stdout connection

10:39:33.764 PM: [1484.3032] <2> dtcp_close: TCP - success: close socket (1800)

10:39:33.764 PM: [1484.3032] <4> OVShutdown: INF - Closing stdin connection

10:39:33.764 PM: [1484.3032] <2> dtcp_close: TCP - success: close socket (396)

10:39:33.764 PM: [1484.3032] <4> OVShutdown: INF - Finished process

10:39:33.795 PM: [1484.3032] <4> WinMain: INF - Exiting C:\Program Files\VERITAS\NetBackup\bin\bpbkar32.exe

10:39:35.795 PM: [1484.3032] <4> ov_log::OVClose: INF - Closing log file: C:\Program Files\VERITAS\NetBackup\logs\BPBKAR\042912.LOG

[Marianne]

Please post entire bpfis log as attachment. The error that you posted is normally a result of something else that failed earlier in the process.

We see the following in bpbkar log:

10:39:15.405 PM: [1484.3032] <2> tar_base::V_vTarMsgW: WRN - can't open raw device: \\.\E: (\\.\GLOBALROOT\Device\HarddiskVolumeShadowCopy23) (WIN32 2: The system cannot find the file specified. )

We need to see in bpfis log what happened with the VSS snapshot of E-drive.

[rookie11]

bpfis log:

21:33:40.158 [4212.4300] <2> logparams: C:\Program Files\VERITAS\NetBackup\bin\bpfis.exe create -nbu -owner NBU -id clientbox.com_1335749382 -bpstart_to 300 -bpend_to 300 -backup_copy 0 -ru root -pt 29 -clnt clientbox.com -rg other -fim auto -class clientbox_policy -sched Full -fso -S master.com 

21:33:40.158 [4212.4300] <2> bpfis main: received FIM as [4] auto

21:33:40.174 [4212.4300] <2> ParseConfigExA: Unknown configuration option on line 32: VssOrphanage = {781b67e1-3f01-4e6c-928b-01ab7699cefa}

21:33:40.174 [4212.4300] <2> ParseConfigExA: Unknown configuration option on line 33: VssOrphanage = {7cec446c-0317-4cc0-aae5-576e71ed2116}

21:33:40.174 [4212.4300] <2> ParseConfigExA: Unknown configuration option on line 34: VssOrphanage = {e87aefb7-8664-4121-8288-825ddbaf3b5f}

21:33:40.174 [4212.4300] <2> ParseConfigExA: Unknown configuration option on line 35: VssOrphanage = {9205a914-aee9-4f0a-943e-13b58fb07cdc}

21:33:40.174 [4212.4300] <2> ParseConfigExA: Unknown configuration option on line 36: VssOrphanage = {f3d3b4ab-8560-4d18-b5f2-b597150aad8d}

21:33:40.221 [4212.4300] <4> bpfis main: INF - BACKUP START 4212

21:33:40.221 [4212.4300] <2> bpfis main: receive filelist:<NEW_STREAM> 

21:33:40.236 [4212.4300] <2> onlfi_get_fl_opts: INF - GET_FLIST_OPTS=<NEW_STREAM>

21:33:40.236 [4212.4300] <2> bpfis main: receive filelist:<\\.\E:> 

21:33:40.236 [4212.4300] <2> onlfi_get_fl_opts: INF - GET_FLIST_OPTS=<\\.\E:\>

21:33:40.408 [4212.4300] <2> bpfis main: receive filelist:<CONTINUE> 

21:33:40.408 [4212.4300] <2> ol_initialize: INF - stream_in_control = clientbox_policy+4212+1, stream_name = clientbox_policy+4212+1, streams = 1

21:33:40.455 [4212.4300] <2> ParseConfigExA: Unknown configuration option on line 32: VssOrphanage = {781b67e1-3f01-4e6c-928b-01ab7699cefa}

21:33:40.455 [4212.4300] <2> ParseConfigExA: Unknown configuration option on line 33: VssOrphanage = {7cec446c-0317-4cc0-aae5-576e71ed2116}

21:33:40.455 [4212.4300] <2> ParseConfigExA: Unknown configuration option on line 34: VssOrphanage = {e87aefb7-8664-4121-8288-825ddbaf3b5f}

21:33:40.455 [4212.4300] <2> ParseConfigExA: Unknown configuration option on line 35: VssOrphanage = {9205a914-aee9-4f0a-943e-13b58fb07cdc}

21:33:40.455 [4212.4300] <2> ParseConfigExA: Unknown configuration option on line 36: VssOrphanage = {f3d3b4ab-8560-4d18-b5f2-b597150aad8d}

21:33:40.502 [4212.4300] <2> onlfi_vfms_logf: INF - Automatic Snapshot Method $Revision: 1.8 $

21:33:40.502 [4212.4300] <2> onlfi_add_to_fim_list: INF - snapshot method=auto

21:33:40.502 [4212.4300] <2> onlfi_vfms_logf: INF - vfm_configure_fi_one: starting VxFI

21:33:40.611 [4212.4300] <2> read_vfm_conf: options=0x2

21:33:40.611 [4212.4300] <2> read_vfm_conf: Reading file: C:\Program Files\VERITAS\NetBackup\vfm.conf

21:33:40.611 [4212.4300] <2> read_vfm_conf: Found FIM: auto

21:33:40.611 [4212.4300] <2> read_vfm_conf: Found library: C:\Program Files\VERITAS\NetBackup\bin\libfi_auto.dll

21:33:40.611 [4212.4300] <2> read_vfm_conf: Found FIM: Hyper-V

21:33:40.611 [4212.4300] <2> read_vfm_conf: Found library: C:\Program Files\VERITAS\NetBackup\bin\libvfms.dll

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found keyword nameuse

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found data type %dd

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found value specification hyperv_client_name_type_list

21:33:40.611 [4212.4300] <2> parse_value_specification: Value specification='hyperv_client_name_type_list'

21:33:40.611 [4212.4300] <2> parse_value_specification: Found default hyperv_client_name_type_list

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found prompt Client Name Selection

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found keyword Virtual_machine_backup

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found data type %dd

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found value specification hyperv_backup_type_list

21:33:40.611 [4212.4300] <2> parse_value_specification: Value specification='hyperv_backup_type_list'

21:33:40.611 [4212.4300] <2> parse_value_specification: Found default hyperv_backup_type_list

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found prompt Virtual Machine Backup

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found keyword prov_type

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found data type %dd

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found value specification vss_provider_type_list

21:33:40.611 [4212.4300] <2> parse_value_specification: Value specification='vss_provider_type_list'

21:33:40.611 [4212.4300] <2> parse_value_specification: Found default vss_provider_type_list

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found prompt Provider Type

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found keyword snap_attr

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found data type %dd

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found value specification snapshot_attribute_list

21:33:40.611 [4212.4300] <2> parse_value_specification: Value specification='snapshot_attribute_list'

21:33:40.611 [4212.4300] <2> parse_value_specification: Found default snapshot_attribute_list

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found prompt Snapshot Attribute

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found keyword allow_offline_backup

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found data type %b

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found value specification 1

21:33:40.611 [4212.4300] <2> parse_value_specification: Value specification='1'

21:33:40.611 [4212.4300] <2> parse_value_specification: Found default 1

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found prompt Allow Offline Backup of VM

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found keyword csv_timeout

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found data type %d

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found value specification 0-:0

21:33:40.611 [4212.4300] <2> parse_value_specification: Value specification='0-:0'

21:33:40.611 [4212.4300] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647)

21:33:40.611 [4212.4300] <2> parse_value_specification: Found default 0

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found prompt CSV Timeout in Minutes

21:33:40.611 [4212.4300] <2> map_integer_value: virtual_machine_supported=Hyper-V, converted value=2, status=1

21:33:40.611 [4212.4300] <2> read_vfm_conf: Found FIM: VSS

21:33:40.611 [4212.4300] <2> read_vfm_conf: Found library: C:\Program Files\VERITAS\NetBackup\bin\libvfms.dll

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found keyword prov_type

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found data type %d

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found value specification 0-3:0

21:33:40.611 [4212.4300] <2> parse_value_specification: Value specification='0-3:0'

21:33:40.611 [4212.4300] <2> parse_value_specification: Found default 0

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found prompt Provider Type (0-auto 1-system 2-software 3-hardware)

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found keyword snap_attr

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found data type %d

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found value specification 0-2:0

21:33:40.611 [4212.4300] <2> parse_value_specification: Value specification='0-2:0'

21:33:40.611 [4212.4300] <2> parse_value_specification: Found default 0

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found prompt Snapshot Attribute(0-unspecified 1-differential 2-plex)

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found keyword max_snapshots

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found data type %d

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found value specification 1-:1

21:33:40.611 [4212.4300] <2> parse_value_specification: Value specification='1-:1'

21:33:40.611 [4212.4300] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647)

21:33:40.611 [4212.4300] <2> parse_value_specification: Found default 1

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only)

21:33:40.611 [4212.4300] <2> read_vfm_conf: Found FIM: FlashSnap

21:33:40.611 [4212.4300] <2> read_vfm_conf: Found library: C:\Program Files\VERITAS\NetBackup\bin\libfi_FlashSnap.dll

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found keyword keep_fi

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found data type %b

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found value specification 0

21:33:40.611 [4212.4300] <2> parse_value_specification: Value specification='0'

21:33:40.611 [4212.4300] <2> parse_value_specification: Found default 0

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found prompt Keep snapshot after backup (not Instant Recovery)

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found keyword async_resync

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found data type %b

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found value specification 0

21:33:40.611 [4212.4300] <2> parse_value_specification: Value specification='0'

21:33:40.611 [4212.4300] <2> parse_value_specification: Found default 0

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found prompt Resynchronize mirror in background (not Instant Recovery)

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found keyword max_snapshots

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found data type %d

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found value specification 1-:1

21:33:40.611 [4212.4300] <2> parse_value_specification: Value specification='1-:1'

21:33:40.611 [4212.4300] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647)

21:33:40.611 [4212.4300] <2> parse_value_specification: Found default 1

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only)

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found keyword dg_split_retry_times

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found data type %d

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found value specification 1

21:33:40.611 [4212.4300] <2> parse_value_specification: Value specification='1'

21:33:40.611 [4212.4300] <2> parse_value_specification: Found default 1

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found prompt Number of times to retry disk group split

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found keyword dg_split_retry_wait_period

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found data type %d

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found value specification 60-:60

21:33:40.611 [4212.4300] <2> parse_value_specification: Value specification='60-:60'

21:33:40.611 [4212.4300] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647)

21:33:40.611 [4212.4300] <2> parse_value_specification: Found default 60

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found prompt Delay in seconds between disk group split retries

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found keyword num_sync_vols

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found data type %d

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found value specification 1-32:1

21:33:40.611 [4212.4300] <2> parse_value_specification: Value specification='1-32:1'

21:33:40.611 [4212.4300] <2> parse_value_specification: Found default 1

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found prompt Maximum number of volumes to resynchronize concurrently

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found keyword iosize

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found data type %d

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found value specification 1-128:1

21:33:40.611 [4212.4300] <2> parse_value_specification: Value specification='1-128:1'

21:33:40.611 [4212.4300] <2> parse_value_specification: Found default 1

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found prompt Sync region size in MB (UNIX only)

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found keyword slow

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found data type %d

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found value specification 0-:0

21:33:40.611 [4212.4300] <2> parse_value_specification: Value specification='0-:0'

21:33:40.611 [4212.4300] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647)

21:33:40.611 [4212.4300] <2> parse_value_specification: Found default 0

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found prompt Sync I/O delay in milliseconds (UNIX only)

21:33:40.611 [4212.4300] <2> read_vfm_conf: Found FIM: vxvm

21:33:40.611 [4212.4300] <2> read_vfm_conf: Found library: C:\Program Files\VERITAS\NetBackup\bin\libfi_vxvm.dll

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found keyword async_resync

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found data type %b

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found value specification 0

21:33:40.611 [4212.4300] <2> parse_value_specification: Value specification='0'

21:33:40.611 [4212.4300] <2> parse_value_specification: Found default 0

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found prompt Resynchronize mirror in background (not Instant Recovery)

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found keyword max_snapshots

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found data type %d

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found value specification 1-:1

21:33:40.611 [4212.4300] <2> parse_value_specification: Value specification='1-:1'

21:33:40.611 [4212.4300] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647)

21:33:40.611 [4212.4300] <2> parse_value_specification: Found default 1

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only)

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found keyword sync_wait

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found data type %b

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found value specification 1

21:33:40.611 [4212.4300] <2> parse_value_specification: Value specification='1'

21:33:40.611 [4212.4300] <2> parse_value_specification: Found default 1

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found prompt Wait for mirror sync completion (Data Mover backups only)

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found keyword num_sync_vols

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found data type %d

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found value specification 1-32:1

21:33:40.611 [4212.4300] <2> parse_value_specification: Value specification='1-32:1'

21:33:40.611 [4212.4300] <2> parse_value_specification: Found default 1

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found prompt Maximum number of volumes to resynchronize concurrently

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found keyword iosize

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found data type %d

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found value specification 1-128:1

21:33:40.611 [4212.4300] <2> parse_value_specification: Value specification='1-128:1'

21:33:40.611 [4212.4300] <2> parse_value_specification: Found default 1

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found prompt Sync region size in MB (UNIX only)

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found keyword slow

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found data type %d

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found value specification 0-:0

21:33:40.611 [4212.4300] <2> parse_value_specification: Value specification='0-:0'

21:33:40.611 [4212.4300] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647)

21:33:40.611 [4212.4300] <2> parse_value_specification: Found default 0

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found prompt Sync I/O delay in milliseconds (UNIX only)

21:33:40.611 [4212.4300] <2> read_vfm_conf: Found FIM: VMware

21:33:40.611 [4212.4300] <2> read_vfm_conf: Found library: C:\Program Files\VERITAS\NetBackup\bin\libfi_VMware.dll

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found keyword nameuse

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found data type %dd

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found value specification vmware_client_name_type_list

21:33:40.611 [4212.4300] <2> parse_value_specification: Value specification='vmware_client_name_type_list'

21:33:40.611 [4212.4300] <2> parse_value_specification: Found default vmware_client_name_type_list

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found prompt Client name selection

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found keyword Virtual_machine_backup

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found data type %dd

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found value specification vmware_backup_type_list

21:33:40.611 [4212.4300] <2> parse_value_specification: Value specification='vmware_backup_type_list'

21:33:40.611 [4212.4300] <2> parse_value_specification: Found default vmware_backup_type_list

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found prompt Virtual machine backup

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found keyword trantype

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found data type %dd

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found value specification VMware_transfer_list

21:33:40.611 [4212.4300] <2> parse_value_specification: Value specification='VMware_transfer_list'

21:33:40.611 [4212.4300] <2> parse_value_specification: Found default VMware_transfer_list

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found prompt Transfer type

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found keyword disable_quiesce

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found data type %dd

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found value specification enabled_disable_quiesce_list

21:33:40.611 [4212.4300] <2> parse_value_specification: Value specification='enabled_disable_quiesce_list'

21:33:40.611 [4212.4300] <2> parse_value_specification: Found default enabled_disable_quiesce_list

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found prompt Virtual machine quiesce

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found keyword file_system_optimization

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found data type %dd

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found value specification enabled_disabled_type_list

21:33:40.611 [4212.4300] <2> parse_value_specification: Value specification='enabled_disabled_type_list'

21:33:40.611 [4212.4300] <2> parse_value_specification: Found default enabled_disabled_type_list

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found prompt Exclude unused and deleted blocks

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found keyword snapact

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found data type %dd

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found value specification snapshot_handling_list

21:33:40.611 [4212.4300] <2> parse_value_specification: Value specification='snapshot_handling_list'

21:33:40.611 [4212.4300] <2> parse_value_specification: Found default snapshot_handling_list

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found prompt Existing snapshot handling

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found keyword vmmono

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found data type %dd

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found value specification disabled_enabled_type_list

21:33:40.611 [4212.4300] <2> parse_value_specification: Value specification='disabled_enabled_type_list'

21:33:40.611 [4212.4300] <2> parse_value_specification: Found default disabled_enabled_type_list

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found prompt Monolithic export (VCB only)

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found keyword mntpt

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found data type %s

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found prompt Snapshot mount point (VCB only)

21:33:40.611 [4212.4300] <2> map_integer_value: virtual_machine_supported=VMware, converted value=1, status=1

21:33:40.611 [4212.4300] <2> read_vfm_conf: Found FIM: NAS_Snapshot

21:33:40.611 [4212.4300] <2> read_vfm_conf: Found library: C:\Program Files\VERITAS\NetBackup\bin\libfi_NAS_Snapshot.dll

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found keyword max_snapshots

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found data type %d

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found value specification 1-:1

21:33:40.611 [4212.4300] <2> parse_value_specification: Value specification='1-:1'

21:33:40.611 [4212.4300] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647)

21:33:40.611 [4212.4300] <2> parse_value_specification: Found default 1

21:33:40.611 [4212.4300] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only)

21:33:40.611 [4212.4300] <2> read_vfm_conf: Found FIM: VSS_Writer

21:33:40.611 [4212.4300] <2> read_vfm_conf: Found library: C:\Program Files\VERITAS\NetBackup\bin\libfi_vss_writer.dll

21:33:40.611 [4212.4300] <2> onlfi_add_to_fim_list: INF - snapshot method=Hyper-V

21:33:40.611 [4212.4300] <2> onlfi_vfms_logf: INF - vfm_configure_fi_one: configuring VxFI credentials

21:33:40.642 [4212.4300] <2> file_to_addrinfo: ../../libvlibs/vnet_addrinfo.c.6635: 0: fopen() failed: 2 0x00000002

21:33:40.642 [4212.4300] <2> file_to_addrinfo: ../../libvlibs/vnet_addrinfo.c.6636: 0: fopen() failed: C:\Program Files\VERITAS\NetBackup\var\host_cache\176\4b10a376+veritas_pbx,1,0,2,1,0+master.com.txt

21:33:40.642 [4212.4300] <2> file_to_addrinfo: ../../libvlibs/vnet_addrinfo.c.6635: 0: fopen() failed: 2 0x00000002

21:33:40.642 [4212.4300] <2> file_to_addrinfo: ../../libvlibs/vnet_addrinfo.c.6636: 0: fopen() failed: C:\Program Files\VERITAS\NetBackup\var\host_cache\19e\77a099e+0,1,2,0,1,0+13.115.4.39.txt

21:33:40.642 [4212.4300] <2> file_to_addrinfo: ../../libvlibs/vnet_addrinfo.c.6635: 0: fopen() failed: 2 0x00000002

21:33:40.642 [4212.4300] <2> file_to_addrinfo: ../../libvlibs/vnet_addrinfo.c.6636: 0: fopen() failed: C:\Program Files\VERITAS\NetBackup\var\host_cache\176\4b10a376+vnetd,1,0,2,1,0+master.com.txt

21:33:40.642 [4212.4300] <2> file_to_addrinfo: ../../libvlibs/vnet_addrinfo.c.6635: 0: fopen() failed: 2 0x00000002

21:33:40.642 [4212.4300] <2> file_to_addrinfo: ../../libvlibs/vnet_addrinfo.c.6636: 0: fopen() failed: C:\Program Files\VERITAS\NetBackup\var\host_cache\176\4b10a376+bprd,1,0,2,1,0+master.com.txt

21:33:40.674 [4212.4300] <2> vnet_pbxConnect: pbxConnectEx Succeeded

21:33:40.674 [4212.4300] <2> logconnections: BPRD CONNECT FROM 13.118.27.36.3753 TO 13.115.4.39.1556 fd = 1556

21:33:41.424 [4212.4300] <2> bprd_read_text_file: Received status 131

21:33:41.439 [4212.4300] <2> onlfi_vfms_logf: INF - vfm_configure_fi_one: read credentials file failed

21:33:41.439 [4212.4300] <2> onlfi_add_to_fim_list: INF - snapshot method=VSS

21:33:41.471 [4212.4300] <8> onlfi_initialize_vfms: WRN - VfMS error 10; see following messages:

21:33:41.471 [4212.4300] <8> onlfi_initialize_vfms: WRN - Non-fatal method error was reported

21:33:41.471 [4212.4300] <8> onlfi_initialize_vfms: WRN - vfm_configure_fi_one: method: FlashSnap, type: FIM, function: FlashSnap_init

21:33:41.471 [4212.4300] <8> onlfi_initialize_vfms: WRN - VfMS method error 3; see following message:

21:33:41.471 [4212.4300] <8> onlfi_initialize_vfms: WRN - FlashSnap_init: Veritas Volume Manager not installed.

21:33:41.486 [4212.4300] <8> onlfi_initialize_vfms: WRN - VfMS error 10; see following messages:

21:33:41.486 [4212.4300] <8> onlfi_initialize_vfms: WRN - Non-fatal method error was reported

21:33:41.486 [4212.4300] <8> onlfi_initialize_vfms: WRN - vfm_configure_fi_one: method: vxvm, type: FIM, function: vxvm_init

21:33:41.486 [4212.4300] <8> onlfi_initialize_vfms: WRN - VfMS method error 3; see following message:

21:33:41.486 [4212.4300] <8> onlfi_initialize_vfms: WRN - vxvm_init: Veritas Volume Manager not installed.

21:33:41.767 [4212.4300] <2> onlfi_vfms_logf: INF - VMware Snapshot Method $Revision: 1.29.4.1 $

21:33:41.767 [4212.4300] <2> onlfi_vfms_logf: INF - VxVM FIM $Revision: 1.199 $

21:33:41.767 [4212.4300] <2> onlfi_vfms_logf: INF - pVfMS FIM config string parser $Revision: 1.9 $

21:33:41.767 [4212.4300] <2> onlfi_vfms_logf: INF - FlashSnap FIM $Revision: 1.199 $

21:33:41.767 [4212.4300] <2> onlfi_vfms_logf: INF - pVfMS FIM config string parser $Revision: 1.9 $

21:33:41.767 [4212.4300] <2> onlfi_add_to_fim_list: INF - snapshot method=VMware

21:33:41.799 [4212.4300] <2> onlfi_vfms_logf: INF - @(#) $Source: src/vfms/fim/nas_snapshot/nas_snapshot.c,v $ $Revision: 1.100 $ 

21:33:41.799 [4212.4300] <2> onlfi_vfms_logf: INF - pVfMS FIM config string parser $Revision: 1.9 $

21:33:41.799 [4212.4300] <2> onlfi_add_to_fim_list: INF - snapshot method=NAS_Snapshot

21:33:41.877 [4212.4300] <2> onlfi_vfms_logf: INF - VSS_Writer FIM $Revision: 1.47 $

21:33:41.877 [4212.4300] <2> onlfi_vfms_logf: INF - pVfMS FIM config string parser $Revision: 1.9 $

21:33:41.877 [4212.4300] <2> onlfi_add_to_fim_list: INF - snapshot method=VSS_Writer

21:33:41.877 [4212.4300] <4> bpfis main: INF - FIS_ID=clientbox.com_1335749382

21:33:41.877 [4212.4300] <2> onlfi_process_fs_list: INF - processing file=C:\Program Files\VERITAS\NetBackup\temp\clientbox_policy+4212+1.std_filelist

21:33:41.892 [4212.4300] <2> devtomp: INF - matched GUID \\?\Volume{9fa7306e-6f78-4a09-8f84-94536a5fd38f} to device \Device\HarddiskVolume3

21:33:41.892 [4212.4300] <2> onlfi_process_fs_list_fs_entry: INF - Adding file system \\.\E:\ to list

21:33:41.892 [4212.4300] <2> vfm_get_policy_snapshot_list: Snapshot method Hyper-V not included, method is a virtual machine method and policy is not a virtual machine policy

21:33:41.892 [4212.4300] <2> vfm_get_policy_snapshot_list: Snapshot method FlashSnap not included, does not support Local capability

21:33:41.892 [4212.4300] <2> vfm_get_policy_snapshot_list: Snapshot method VMware not included, does not support Local capability

21:33:41.892 [4212.4300] <2> vfm_get_policy_snapshot_list: Snapshot method VMware not included, method is a virtual machine method and policy is not a virtual machine policy

21:33:41.892 [4212.4300] <2> vfm_get_policy_snapshot_list: Snapshot method NAS_Snapshot not included, does not support Local capability

21:33:41.892 [4212.4300] <2> vfm_get_policy_snapshot_list: Snapshot method NAS_Snapshot not included, does not support policy type 29

21:33:41.892 [4212.4300] <2> vfm_get_policy_snapshot_list: Snapshot method VSS_Writer not included, does not support policy type 29

21:33:41.892 [4212.4300] <4> onlfi_freeze_fim: INF - Testing freeze of \\.\E:\ using snapshot method VSS.

21:33:41.892 [4212.4300] <4> create_mount_point: INF - Created mount point C:\Program Files\VERITAS\NetBackup\temp\_vrts_frzn_img___._E__4212

21:33:41.908 [4212.4300] <2> onlfi_vfms_logf: INF - vfm_freeze_commit: config=

21:33:42.471 [4212.4300] <2> onlfi_vfms_logf: INF - snapshot services: emcclariionfi:Sun Apr 29 2012 21:33:42.471000 <Thread id - 4300> Unable to import any login credentials for any appliances.

21:33:42.736 [4212.4300] <2> onlfi_vfms_logf: INF - snapshot services: hitachifi:Sun Apr 29 2012 21:33:42.736000 <Thread id - 4300> CHitachiCommandIntf::init() : Error in dlopen of C:\windows\system32\libsvrrm.dll : The specified module could not be found.

21:33:42.736 [4212.4300] <2> onlfi_vfms_logf: INF - snapshot services: hitachifi:Sun Apr 29 2012 21:33:42.736000 <Thread id - 4300> CHitachiPlugin::init : Library RMLIB init failed

21:33:42.861 [4212.4300] <2> onlfi_vfms_logf: INF - snapshot services: hpevafi:Sun Apr 29 2012 21:33:42.861000 <Thread id - 4300> CHpEvaPlugin::init: CLI tool is not installed.

21:33:42.924 [4212.4300] <2> onlfi_vfms_logf: INF - snapshot services: hpmsafi:Sun Apr 29 2012 21:33:42.924000 <Thread id - 4300> No array management credentials are available in configuration file.

21:33:43.049 [4212.4300] <2> onlfi_vfms_logf: INF - snapshot services: ibmtsfi:Sun Apr 29 2012 21:33:43.049000 <Thread id - 4300> DSCLI not found at path=[C:\Program Files\IBM\DSCLI\]

21:33:43.267 [4212.4300] <2> onlfi_vfms_logf: INF - snapshot services: vss:Sun Apr 29 2012 21:33:43.267000 <Thread id - 4300> check_service_status - STATUS=[1]

21:33:43.502 [4212.4300] <2> onlfi_vfms_logf: INF - snapshot services: vss:Sun Apr 29 2012 21:33:43.502000 <Thread id - 4300> Starting service checking status - STATUS=[2]

21:33:44.502 [4212.4300] <2> onlfi_vfms_logf: INF - snapshot services: vss:Sun Apr 29 2012 21:33:44.502000 <Thread id - 4300> Staring Service - SERVICE_START_PENDING checking status - STATUS=[4]

21:33:46.142 [4212.4300] <2> onlfi_vfms_logf: INF - snapshot services: devicefi:Sun Apr 29 2012 21:33:46.142000 <Thread id - 4300> libscsitool: ERROR: sending SCSI CMD - ScsiStatus: 0x2

21:33:46.142 [4212.4300] <2> onlfi_vfms_logf: INF - snapshot services: devicefi:Sun Apr 29 2012 21:33:46.142000 <Thread id - 4300> libscsitool: ERROR: CScsiTool::SendCdbToDevice() failed.

21:33:46.236 [4212.4300] <2> onlfi_vfms_logf: INF - snapshot services: vxfi_analyze fitype=[vss-autoselect-snapshot]

21:33:46.236 [4212.4300] <2> onlfi_vfms_logf: INF - 

21:33:46.252 [4212.4300] <4> delete_mount_point: INF - Deleted mount point C:\Program Files\VERITAS\NetBackup\temp\_vrts_frzn_img___._E__4212

21:33:46.252 [4212.4300] <4> onlfi_add_to_fsmap_list2: INF - Auto-selected snapshot method VSS for \\.\E:\

21:33:46.267 [4212.4300] <2> onlfi_freeze_prepare: INF - snapshot method=auto

21:33:46.267 [4212.4300] <2> onlfi_freeze_prepare: INF - snapshot method=Hyper-V

21:33:46.267 [4212.4300] <2> onlfi_freeze_prepare: INF - snapshot method=VSS

21:33:46.267 [4212.4300] <4> onlfi_fim_dev_info: INF - Preparing freeze of \\.\E:\ using snapshot method VSS.

21:33:46.267 [4212.4300] <4> create_mount_point: INF - Created mount point C:\Program Files\VERITAS\NetBackup\temp\_vrts_frzn_img___._E__4212

21:33:46.267 [4212.4300] <2> onlfi_freeze_prepare: INF - snapshot method=VMware

21:33:46.267 [4212.4300] <2> onlfi_freeze_prepare: INF - snapshot method=NAS_Snapshot

21:33:46.267 [4212.4300] <2> onlfi_freeze_prepare: INF - snapshot method=VSS_Writer

21:33:46.267 [4212.4300] <2> onlfi_freeze: INF - snapshot method=auto

21:33:46.267 [4212.4300] <2> onlfi_freeze: INF - snapshot method=Hyper-V

21:33:46.267 [4212.4300] <2> onlfi_freeze_commit: INF - snapshot method=Hyper-V

21:33:46.267 [4212.4300] <2> onlfi_freeze: INF - snapshot method=VSS

21:33:46.267 [4212.4300] <2> onlfi_freeze_commit: INF - snapshot method=VSS

21:33:46.267 [4212.4300] <2> onlfi_vfms_logf: INF - vfm_freeze_commit: config=

21:33:46.330 [4212.4300] <2> onlfi_vfms_logf: INF - snapshot services: vxfi_analyze fitype=[vss-autoselect-snapshot]

21:33:46.330 [4212.4300] <2> onlfi_vfms_logf: INF - 

21:34:03.720 [4212.4300] <2> onlfi_vfms_logf: INF - vfm_freeze_commit: vfm_method_errno=[0]

21:34:03.720 [4212.4300] <2> onlfi_vfms_logf: INF - snapshot services: Entering vfi_build_alttree

21:34:03.720 [4212.4300] <2> onlfi_vfms_logf: INF - snapshot services: Exiting vfi_build_alttree with 0

21:34:03.798 [4212.4300] <2> onlfi_vfms_logf: INF - snapshot services: vxfi_fiid =[<?xml version="1.0" encoding="UTF-16" standalone="no" ?><FIID xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" xsi:noNamespaceSchemaLocation="C:\Program Files\Common Files\Symantec Shared\VxFI\4\\Schema\vfiid_schema.xsd"><Node isGroup="0"><objid>root<!--objid--></objid><type>2050<!--type--></type><Node isGroup="0"><objid>E:\<!--objid--></objid><type>2050<!--type--></type><accessPoint>C:\Program Files\VERITAS\NetBackup\temp\_vrts_frzn_img___._E__4212<!--accessPoint--></accessPoint><nodeId>E:\<!--nodeId--></nodeId><providerPrefix>winfsys<!--providerPrefix--></providerPrefix><Node isGroup="0"><objid>&lt;vss>\\?\Volume{9fa7306e-6f78-4a09-8f84-94536a5fd38f}<!--objid--></objid><type>4098<!--type--></type><fi_flags>9472<!--fi_flags--></fi_flags><tmpobjid>&lt;ShadowCopyID>{FBF66B37-2798-4B7A-B529-5ABAA5CBF430}<!--tmpobjid--></tmpobjid><altobjid>\\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy23<!--altobjid--></altobjid><interposed_parent>E:\<!--interposed_parent--></interposed_parent><interposed>\\?\Volume{9fa7306e-6f78-4a09-8f84-94536a5fd38f}<!--interposed--></interposed><nodeId>&lt;vss>\\?\Volume{9fa7306e-6f78-4a09-8f84-94536a5fd38f}<!--nodeId--></nodeId><providerPrefix>vss<!--providerPrefix--></providerPrefix><pluginBinaryData>PABTAE4AQQBQAFMASABPAFQAXwBJAEQAPgB7AEYAQgBGADYANgBCADMANwAt

21:34:03.814 [4212.4300] <4> delete_mount_point: INF - Deleted mount point C:\Program Files\VERITAS\NetBackup\temp\_vrts_frzn_img___._E__4212

21:34:03.814 [4212.4300] <2> onlfi_fim_clone: INF - cloning object \\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy23 on 

21:34:03.814 [4212.4300] <2> onlfi_fim_clone: INF - clone done

21:34:03.814 [4212.4300] <2> onlfi_freeze: INF - snapshot method=VMware

21:34:03.814 [4212.4300] <2> onlfi_freeze: INF - snapshot method=NAS_Snapshot

21:34:03.814 [4212.4300] <2> onlfi_freeze_commit: INF - snapshot method=NAS_Snapshot

21:34:03.814 [4212.4300] <2> onlfi_freeze: INF - snapshot method=VSS_Writer

21:34:03.814 [4212.4300] <2> onlfi_freeze_commit: INF - snapshot method=VSS_Writer

21:34:03.814 [4212.4300] <2> onlfi_fi_fs_unlock: INF - buffered logs are flushed

21:34:03.814 [4212.4300] <2> onlfi_dump_fim_list2: INF - fimlist_rec[0].fim_name=auto

21:34:03.814 [4212.4300] <2> onlfi_dump_fim_list2: INF - fimlist_rec[1].fim_name=Hyper-V

21:34:03.814 [4212.4300] <2> onlfi_dump_fim_list2: INF - fimlist_rec[3].fim_name=VMware

21:34:03.814 [4212.4300] <2> onlfi_dump_fim_list2: INF - fimlist_rec[4].fim_name=NAS_Snapshot

21:34:03.814 [4212.4300] <2> onlfi_dump_fim_list2: INF - fimlist_rec[5].fim_name=VSS_Writer

21:34:03.814 [4212.4300] <4> remap_file_name: INF - REMAP FILE BACKUP NEW_STREAM

21:34:03.814 [4212.4300] <4> remap_file_name: INF - REMAP FILE BACKUP \\.\E:\ USING \\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy23\ OPTIONS:ALT_PATH_PREFIX=C:\Program Files\VERITAS\NetBackup\temp\_vrts_frzn_img_4212,FITYPE=MIRROR,MNTPOINT=\\.\E:\,FSTYPE=NTFS

21:34:04.064 [4212.4300] <2> vnet_pbxConnect: pbxConnectEx Succeeded

21:34:04.064 [4212.4300] <2> logconnections: BPRD CONNECT FROM 13.118.27.36.3758 TO 13.115.4.39.1556 fd = 1572

21:34:05.408 [4212.4300] <4> bpfis Exit: INF - EXIT STATUS 0: the requested operation was successfully completed

21:34:07.408 [4212.4300] <2> bpfis Exit: INF - Close of stdout complete

22:39:41.373 [4708.4712] <2> logparams: C:\Program Files\VERITAS\NetBackup\bin\bpfis.exe delete -nbu -id clientbox.com_1335749382 -bpstart_to 300 -bpend_to 300 -clnt clientbox.com -S master.com 

22:39:41.373 [4708.4712] <2> ParseConfigExA: Unknown configuration option on line 32: VssOrphanage = {781b67e1-3f01-4e6c-928b-01ab7699cefa}

22:39:41.389 [4708.4712] <2> ParseConfigExA: Unknown configuration option on line 33: VssOrphanage = {7cec446c-0317-4cc0-aae5-576e71ed2116}

22:39:41.389 [4708.4712] <2> ParseConfigExA: Unknown configuration option on line 34: VssOrphanage = {e87aefb7-8664-4121-8288-825ddbaf3b5f}

22:39:41.389 [4708.4712] <2> ParseConfigExA: Unknown configuration option on line 35: VssOrphanage = {9205a914-aee9-4f0a-943e-13b58fb07cdc}

22:39:41.389 [4708.4712] <2> ParseConfigExA: Unknown configuration option on line 36: VssOrphanage = {f3d3b4ab-8560-4d18-b5f2-b597150aad8d}

22:39:41.404 [4708.4712] <2> ParseConfigExA: Unknown configuration option on line 32: VssOrphanage = {781b67e1-3f01-4e6c-928b-01ab7699cefa}

22:39:41.404 [4708.4712] <2> ParseConfigExA: Unknown configuration option on line 33: VssOrphanage = {7cec446c-0317-4cc0-aae5-576e71ed2116}

22:39:41.404 [4708.4712] <2> ParseConfigExA: Unknown configuration option on line 34: VssOrphanage = {e87aefb7-8664-4121-8288-825ddbaf3b5f}

22:39:41.404 [4708.4712] <2> ParseConfigExA: Unknown configuration option on line 35: VssOrphanage = {9205a914-aee9-4f0a-943e-13b58fb07cdc}

22:39:41.404 [4708.4712] <2> ParseConfigExA: Unknown configuration option on line 36: VssOrphanage = {f3d3b4ab-8560-4d18-b5f2-b597150aad8d}

22:39:41.451 [4708.4712] <2> vnet_pbxConnect: pbxConnectEx Succeeded

22:39:41.451 [4708.4712] <2> logconnections: BPRD CONNECT FROM 13.118.27.36.3996 TO 13.115.4.39.1556 fd = 1612

22:39:43.014 [4708.4712] <4> bpfis main: INF - BACKUP START 4708

22:39:43.014 [4708.4712] <2> ol_initialize: INF - stream_in_control = clientbox_policy+d4212+1, stream_name = clientbox_policy+d4212+1, streams = 1

22:39:43.123 [4708.4712] <2> onlfi_vfms_logf: INF - Automatic Snapshot Method $Revision: 1.8 $

22:39:43.123 [4708.4712] <2> onlfi_add_to_fim_list: INF - snapshot method=auto

22:39:43.123 [4708.4712] <2> onlfi_vfms_logf: INF - vfm_configure_fi_one: starting VxFI

22:39:43.185 [4708.4712] <2> read_vfm_conf: options=0x2

22:39:43.185 [4708.4712] <2> read_vfm_conf: Reading file: C:\Program Files\VERITAS\NetBackup\vfm.conf

22:39:43.185 [4708.4712] <2> read_vfm_conf: Found FIM: auto

22:39:43.185 [4708.4712] <2> read_vfm_conf: Found library: C:\Program Files\VERITAS\NetBackup\bin\libfi_auto.dll

22:39:43.185 [4708.4712] <2> read_vfm_conf: Found FIM: Hyper-V

22:39:43.185 [4708.4712] <2> read_vfm_conf: Found library: C:\Program Files\VERITAS\NetBackup\bin\libvfms.dll

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found keyword nameuse

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found data type %dd

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found value specification hyperv_client_name_type_list

22:39:43.185 [4708.4712] <2> parse_value_specification: Value specification='hyperv_client_name_type_list'

22:39:43.185 [4708.4712] <2> parse_value_specification: Found default hyperv_client_name_type_list

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found prompt Client Name Selection

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found keyword Virtual_machine_backup

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found data type %dd

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found value specification hyperv_backup_type_list

22:39:43.185 [4708.4712] <2> parse_value_specification: Value specification='hyperv_backup_type_list'

22:39:43.185 [4708.4712] <2> parse_value_specification: Found default hyperv_backup_type_list

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found prompt Virtual Machine Backup

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found keyword prov_type

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found data type %dd

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found value specification vss_provider_type_list

22:39:43.185 [4708.4712] <2> parse_value_specification: Value specification='vss_provider_type_list'

22:39:43.185 [4708.4712] <2> parse_value_specification: Found default vss_provider_type_list

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found prompt Provider Type

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found keyword snap_attr

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found data type %dd

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found value specification snapshot_attribute_list

22:39:43.185 [4708.4712] <2> parse_value_specification: Value specification='snapshot_attribute_list'

22:39:43.185 [4708.4712] <2> parse_value_specification: Found default snapshot_attribute_list

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found prompt Snapshot Attribute

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found keyword allow_offline_backup

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found data type %b

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found value specification 1

22:39:43.185 [4708.4712] <2> parse_value_specification: Value specification='1'

22:39:43.185 [4708.4712] <2> parse_value_specification: Found default 1

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found prompt Allow Offline Backup of VM

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found keyword csv_timeout

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found data type %d

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found value specification 0-:0

22:39:43.185 [4708.4712] <2> parse_value_specification: Value specification='0-:0'

22:39:43.185 [4708.4712] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647)

22:39:43.185 [4708.4712] <2> parse_value_specification: Found default 0

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found prompt CSV Timeout in Minutes

22:39:43.185 [4708.4712] <2> map_integer_value: virtual_machine_supported=Hyper-V, converted value=2, status=1

22:39:43.185 [4708.4712] <2> read_vfm_conf: Found FIM: VSS

22:39:43.185 [4708.4712] <2> read_vfm_conf: Found library: C:\Program Files\VERITAS\NetBackup\bin\libvfms.dll

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found keyword prov_type

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found data type %d

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found value specification 0-3:0

22:39:43.185 [4708.4712] <2> parse_value_specification: Value specification='0-3:0'

22:39:43.185 [4708.4712] <2> parse_value_specification: Found default 0

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found prompt Provider Type (0-auto 1-system 2-software 3-hardware)

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found keyword snap_attr

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found data type %d

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found value specification 0-2:0

22:39:43.185 [4708.4712] <2> parse_value_specification: Value specification='0-2:0'

22:39:43.185 [4708.4712] <2> parse_value_specification: Found default 0

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found prompt Snapshot Attribute(0-unspecified 1-differential 2-plex)

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found keyword max_snapshots

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found data type %d

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found value specification 1-:1

22:39:43.185 [4708.4712] <2> parse_value_specification: Value specification='1-:1'

22:39:43.185 [4708.4712] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647)

22:39:43.185 [4708.4712] <2> parse_value_specification: Found default 1

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only)

22:39:43.185 [4708.4712] <2> read_vfm_conf: Found FIM: FlashSnap

22:39:43.185 [4708.4712] <2> read_vfm_conf: Found library: C:\Program Files\VERITAS\NetBackup\bin\libfi_FlashSnap.dll

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found keyword keep_fi

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found data type %b

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found value specification 0

22:39:43.185 [4708.4712] <2> parse_value_specification: Value specification='0'

22:39:43.185 [4708.4712] <2> parse_value_specification: Found default 0

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found prompt Keep snapshot after backup (not Instant Recovery)

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found keyword async_resync

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found data type %b

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found value specification 0

22:39:43.185 [4708.4712] <2> parse_value_specification: Value specification='0'

22:39:43.185 [4708.4712] <2> parse_value_specification: Found default 0

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found prompt Resynchronize mirror in background (not Instant Recovery)

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found keyword max_snapshots

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found data type %d

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found value specification 1-:1

22:39:43.185 [4708.4712] <2> parse_value_specification: Value specification='1-:1'

22:39:43.185 [4708.4712] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647)

22:39:43.185 [4708.4712] <2> parse_value_specification: Found default 1

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only)

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found keyword dg_split_retry_times

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found data type %d

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found value specification 1

22:39:43.185 [4708.4712] <2> parse_value_specification: Value specification='1'

22:39:43.185 [4708.4712] <2> parse_value_specification: Found default 1

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found prompt Number of times to retry disk group split

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found keyword dg_split_retry_wait_period

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found data type %d

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found value specification 60-:60

22:39:43.185 [4708.4712] <2> parse_value_specification: Value specification='60-:60'

22:39:43.185 [4708.4712] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647)

22:39:43.185 [4708.4712] <2> parse_value_specification: Found default 60

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found prompt Delay in seconds between disk group split retries

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found keyword num_sync_vols

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found data type %d

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found value specification 1-32:1

22:39:43.185 [4708.4712] <2> parse_value_specification: Value specification='1-32:1'

22:39:43.185 [4708.4712] <2> parse_value_specification: Found default 1

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found prompt Maximum number of volumes to resynchronize concurrently

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found keyword iosize

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found data type %d

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found value specification 1-128:1

22:39:43.185 [4708.4712] <2> parse_value_specification: Value specification='1-128:1'

22:39:43.185 [4708.4712] <2> parse_value_specification: Found default 1

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found prompt Sync region size in MB (UNIX only)

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found keyword slow

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found data type %d

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found value specification 0-:0

22:39:43.185 [4708.4712] <2> parse_value_specification: Value specification='0-:0'

22:39:43.185 [4708.4712] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647)

22:39:43.185 [4708.4712] <2> parse_value_specification: Found default 0

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found prompt Sync I/O delay in milliseconds (UNIX only)

22:39:43.185 [4708.4712] <2> read_vfm_conf: Found FIM: vxvm

22:39:43.185 [4708.4712] <2> read_vfm_conf: Found library: C:\Program Files\VERITAS\NetBackup\bin\libfi_vxvm.dll

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found keyword async_resync

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found data type %b

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found value specification 0

22:39:43.185 [4708.4712] <2> parse_value_specification: Value specification='0'

22:39:43.185 [4708.4712] <2> parse_value_specification: Found default 0

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found prompt Resynchronize mirror in background (not Instant Recovery)

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found keyword max_snapshots

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found data type %d

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found value specification 1-:1

22:39:43.185 [4708.4712] <2> parse_value_specification: Value specification='1-:1'

22:39:43.185 [4708.4712] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647)

22:39:43.185 [4708.4712] <2> parse_value_specification: Found default 1

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only)

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found keyword sync_wait

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found data type %b

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found value specification 1

22:39:43.185 [4708.4712] <2> parse_value_specification: Value specification='1'

22:39:43.185 [4708.4712] <2> parse_value_specification: Found default 1

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found prompt Wait for mirror sync completion (Data Mover backups only)

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found keyword num_sync_vols

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found data type %d

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found value specification 1-32:1

22:39:43.185 [4708.4712] <2> parse_value_specification: Value specification='1-32:1'

22:39:43.185 [4708.4712] <2> parse_value_specification: Found default 1

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found prompt Maximum number of volumes to resynchronize concurrently

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found keyword iosize

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found data type %d

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found value specification 1-128:1

22:39:43.185 [4708.4712] <2> parse_value_specification: Value specification='1-128:1'

22:39:43.185 [4708.4712] <2> parse_value_specification: Found default 1

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found prompt Sync region size in MB (UNIX only)

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found keyword slow

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found data type %d

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found value specification 0-:0

22:39:43.185 [4708.4712] <2> parse_value_specification: Value specification='0-:0'

22:39:43.185 [4708.4712] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647)

22:39:43.185 [4708.4712] <2> parse_value_specification: Found default 0

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found prompt Sync I/O delay in milliseconds (UNIX only)

22:39:43.185 [4708.4712] <2> read_vfm_conf: Found FIM: VMware

22:39:43.185 [4708.4712] <2> read_vfm_conf: Found library: C:\Program Files\VERITAS\NetBackup\bin\libfi_VMware.dll

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found keyword nameuse

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found data type %dd

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found value specification vmware_client_name_type_list

22:39:43.185 [4708.4712] <2> parse_value_specification: Value specification='vmware_client_name_type_list'

22:39:43.185 [4708.4712] <2> parse_value_specification: Found default vmware_client_name_type_list

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found prompt Client name selection

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found keyword Virtual_machine_backup

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found data type %dd

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found value specification vmware_backup_type_list

22:39:43.185 [4708.4712] <2> parse_value_specification: Value specification='vmware_backup_type_list'

22:39:43.185 [4708.4712] <2> parse_value_specification: Found default vmware_backup_type_list

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found prompt Virtual machine backup

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found keyword trantype

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found data type %dd

22:39:43.185 [4708.4712] <2> parse_ssm_parms: Found value specification VMware_transfer_list

22:39:43.185 [4708.4712] <2> parse_value_specification: Value specification='VMware_transfer_list'

22:39:43.201 [4708.4712] <2> parse_value_specification: Found default VMware_transfer_list

22:39:43.201 [4708.4712] <2> parse_ssm_parms: Found prompt Transfer type

22:39:43.201 [4708.4712] <2> parse_ssm_parms: Found keyword disable_quiesce

22:39:43.201 [4708.4712] <2> parse_ssm_parms: Found data type %dd

22:39:43.201 [4708.4712] <2> parse_ssm_parms: Found value specification enabled_disable_quiesce_list

22:39:43.201 [4708.4712] <2> parse_value_specification: Value specification='enabled_disable_quiesce_list'

22:39:43.201 [4708.4712] <2> parse_value_specification: Found default enabled_disable_quiesce_list

22:39:43.201 [4708.4712] <2> parse_ssm_parms: Found prompt Virtual machine quiesce

22:39:43.201 [4708.4712] <2> parse_ssm_parms: Found keyword file_system_optimization

22:39:43.201 [4708.4712] <2> parse_ssm_parms: Found data type %dd

22:39:43.201 [4708.4712] <2> parse_ssm_parms: Found value specification enabled_disabled_type_list

22:39:43.201 [4708.4712] <2> parse_value_specification: Value specification='enabled_disabled_type_list'

22:39:43.201 [4708.4712] <2> parse_value_specification: Found default enabled_disabled_type_list

22:39:43.201 [4708.4712] <2> parse_ssm_parms: Found prompt Exclude unused and deleted blocks

22:39:43.201 [4708.4712] <2> parse_ssm_parms: Found keyword snapact

22:39:43.201 [4708.4712] <2> parse_ssm_parms: Found data type %dd

22:39:43.201 [4708.4712] <2> parse_ssm_parms: Found value specification snapshot_handling_list

22:39:43.201 [4708.4712] <2> parse_value_specification: Value specification='snapshot_handling_list'

22:39:43.201 [4708.4712] <2> parse_value_specification: Found default snapshot_handling_list

22:39:43.201 [4708.4712] <2> parse_ssm_parms: Found prompt Existing snapshot handling

22:39:43.201 [4708.4712] <2> parse_ssm_parms: Found keyword vmmono

22:39:43.201 [4708.4712] <2> parse_ssm_parms: Found data type %dd

22:39:43.201 [4708.4712] <2> parse_ssm_parms: Found value specification disabled_enabled_type_list

22:39:43.201 [4708.4712] <2> parse_value_specification: Value specification='disabled_enabled_type_list'

22:39:43.201 [4708.4712] <2> parse_value_specification: Found default disabled_enabled_type_list

22:39:43.201 [4708.4712] <2> parse_ssm_parms: Found prompt Monolithic export (VCB only)

22:39:43.201 [4708.4712] <2> parse_ssm_parms: Found keyword mntpt

22:39:43.201 [4708.4712] <2> parse_ssm_parms: Found data type %s

22:39:43.201 [4708.4712] <2> parse_ssm_parms: Found prompt Snapshot mount point (VCB only)

22:39:43.201 [4708.4712] <2> map_integer_value: virtual_machine_supported=VMware, converted value=1, status=1

22:39:43.201 [4708.4712] <2> read_vfm_conf: Found FIM: NAS_Snapshot

22:39:43.201 [4708.4712] <2> read_vfm_conf: Found library: C:\Program Files\VERITAS\NetBackup\bin\libfi_NAS_Snapshot.dll

22:39:43.201 [4708.4712] <2> parse_ssm_parms: Found keyword max_snapshots

22:39:43.201 [4708.4712] <2> parse_ssm_parms: Found data type %d

22:39:43.201 [4708.4712] <2> parse_ssm_parms: Found value specification 1-:1

22:39:43.201 [4708.4712] <2> parse_value_specification: Value specification='1-:1'

22:39:43.201 [4708.4712] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647)

22:39:43.201 [4708.4712] <2> parse_value_specification: Found default 1

22:39:43.201 [4708.4712] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only)

22:39:43.201 [4708.4712] <2> read_vfm_conf: Found FIM: VSS_Writer

22:39:43.201 [4708.4712] <2> read_vfm_conf: Found library: C:\Program Files\VERITAS\NetBackup\bin\libfi_vss_writer.dll

22:39:43.201 [4708.4712] <2> onlfi_add_to_fim_list: INF - snapshot method=Hyper-V

22:39:43.201 [4708.4712] <2> onlfi_vfms_logf: INF - vfm_configure_fi_one: configuring VxFI credentials

22:39:43.217 [4708.4712] <2> vnet_pbxConnect: pbxConnectEx Succeeded

22:39:43.217 [4708.4712] <2> logconnections: BPRD CONNECT FROM 13.118.27.36.3999 TO 13.115.4.39.1556 fd = 1556

22:39:43.967 [4708.4712] <2> bprd_read_text_file: Received status 131

22:39:43.967 [4708.4712] <2> onlfi_vfms_logf: INF - vfm_configure_fi_one: read credentials file failed

22:39:43.967 [4708.4712] <2> onlfi_add_to_fim_list: INF - snapshot method=VSS

22:39:43.998 [4708.4712] <8> onlfi_initialize_vfms: WRN - VfMS error 10; see following messages:

22:39:43.998 [4708.4712] <8> onlfi_initialize_vfms: WRN - Non-fatal method error was reported

22:39:43.998 [4708.4712] <8> onlfi_initialize_vfms: WRN - vfm_configure_fi_one: method: FlashSnap, type: FIM, function: FlashSnap_init

22:39:43.998 [4708.4712] <8> onlfi_initialize_vfms: WRN - VfMS method error 3; see following message:

22:39:43.998 [4708.4712] <8> onlfi_initialize_vfms: WRN - FlashSnap_init: Veritas Volume Manager not installed.

22:39:44.029 [4708.4712] <8> onlfi_initialize_vfms: WRN - VfMS error 10; see following messages:

22:39:44.029 [4708.4712] <8> onlfi_initialize_vfms: WRN - Non-fatal method error was reported

22:39:44.029 [4708.4712] <8> onlfi_initialize_vfms: WRN - vfm_configure_fi_one: method: vxvm, type: FIM, function: vxvm_init

22:39:44.029 [4708.4712] <8> onlfi_initialize_vfms: WRN - VfMS method error 3; see following message:

22:39:44.029 [4708.4712] <8> onlfi_initialize_vfms: WRN - vxvm_init: Veritas Volume Manager not installed.

22:39:44.170 [4708.4712] <2> onlfi_vfms_logf: INF - VMware Snapshot Method $Revision: 1.29.4.1 $

22:39:44.170 [4708.4712] <2> onlfi_vfms_logf: INF - VxVM FIM $Revision: 1.199 $

22:39:44.170 [4708.4712] <2> onlfi_vfms_logf: INF - pVfMS FIM config string parser $Revision: 1.9 $

22:39:44.170 [4708.4712] <2> onlfi_vfms_logf: INF - FlashSnap FIM $Revision: 1.199 $

22:39:44.170 [4708.4712] <2> onlfi_vfms_logf: INF - pVfMS FIM config string parser $Revision: 1.9 $

22:39:44.170 [4708.4712] <2> onlfi_add_to_fim_list: INF - snapshot method=VMware

22:39:44.201 [4708.4712] <2> onlfi_vfms_logf: INF - @(#) $Source: src/vfms/fim/nas_snapshot/nas_snapshot.c,v $ $Revision: 1.100 $ 

22:39:44.217 [4708.4712] <2> onlfi_vfms_logf: INF - pVfMS FIM config string parser $Revision: 1.9 $

22:39:44.217 [4708.4712] <2> onlfi_add_to_fim_list: INF - snapshot method=NAS_Snapshot

22:39:44.295 [4708.4712] <2> onlfi_vfms_logf: INF - VSS_Writer FIM $Revision: 1.47 $

22:39:44.295 [4708.4712] <2> onlfi_vfms_logf: INF - pVfMS FIM config string parser $Revision: 1.9 $

22:39:44.295 [4708.4712] <2> onlfi_add_to_fim_list: INF - snapshot method=VSS_Writer

22:39:44.310 [4708.4712] <2> onlfi_add_to_fim_list: INF - snapshot method=VSS

22:39:44.310 [4708.4712] <2> onlfi_thaw: INF - fim=VSS

22:39:44.310 [4708.4712] <4> onlfi_thaw: INF - Thawing \\.\E:\ using snapshot method VSS.

22:39:44.310 [4708.4712] <2> onlfi_vfms_logf: INF - vfm_thaw: delete snapshot ...

22:39:44.404 [4708.4712] <2> onlfi_vfms_logf: INF - snapshot services: Entering vfi_reaccess_tree

22:39:45.045 [4708.4712] <2> onlfi_vfms_logf: INF - snapshot services: emcclariionfi:Sun Apr 29 2012 22:39:45.045000 <Thread id - 4712> Unable to import any login credentials for any appliances.

22:39:45.185 [4708.4712] <2> onlfi_vfms_logf: INF - snapshot services: hitachifi:Sun Apr 29 2012 22:39:45.185000 <Thread id - 4712> CHitachiCommandIntf::init() : Error in dlopen of C:\windows\system32\libsvrrm.dll : The specified module could not be found.

22:39:45.185 [4708.4712] <2> onlfi_vfms_logf: INF - snapshot services: hitachifi:Sun Apr 29 2012 22:39:45.185000 <Thread id - 4712> CHitachiPlugin::init : Library RMLIB init failed

22:39:45.279 [4708.4712] <2> onlfi_vfms_logf: INF - snapshot services: hpevafi:Sun Apr 29 2012 22:39:45.279000 <Thread id - 4712> CHpEvaPlugin::init: CLI tool is not installed.

22:39:45.342 [4708.4712] <2> onlfi_vfms_logf: INF - snapshot services: hpmsafi:Sun Apr 29 2012 22:39:45.342000 <Thread id - 4712> No array management credentials are available in configuration file.

22:39:45.435 [4708.4712] <2> onlfi_vfms_logf: INF - snapshot services: ibmtsfi:Sun Apr 29 2012 22:39:45.435000 <Thread id - 4712> DSCLI not found at path=[C:\Program Files\IBM\DSCLI\]

22:39:45.607 [4708.4712] <2> onlfi_vfms_logf: INF - snapshot services: vss:Sun Apr 29 2012 22:39:45.607000 <Thread id - 4712> check_service_status - STATUS=[4]

22:39:45.982 [4708.4712] <2> onlfi_vfms_logf: INF - snapshot services: Exiting vfi_reaccess_tree with 0

22:39:45.982 [4708.4712] <4> onlfi_thaw: INF - do_thaw return value: 0

22:39:45.998 [4708.4712] <2> fis_delete_id: INF - removing C:\Program Files\VERITAS\NetBackup\online_util\fi_cntl\bpfis.fim.clientbox.com_1335749382.0

22:39:45.998 [4708.4712] <2> fis_delete_id: INF - removing C:\Program Files\VERITAS\NetBackup\online_util\fi_cntl\bpfis.fim.clientbox.com_1335749382.0.fiid

22:39:45.998 [4708.4712] <2> vnet_pbxConnect: pbxConnectEx Succeeded

22:39:45.998 [4708.4712] <2> logconnections: BPRD CONNECT FROM 13.118.27.36.4002 TO 13.115.4.39.1556 fd = 1500

22:39:46.404 [4708.4712] <4> bpfis Exit: INF - EXIT STATUS 0: the requested operation was successfully completed

22:39:48.404 [4708.4712] <2> bpfis Exit: INF - Close of stdout complete

22:39:57.638 [2204.5360] <2> logparams: C:\Program Files\VERITAS\NetBackup\bin\bpfis.exe delete -nbu -id clientbox.com_1335749382 -bpstart_to 300 -bpend_to 300 -clnt clientbox.com -S master.com 

22:39:57.638 [2204.5360] <2> ParseConfigExA: Unknown configuration option on line 32: VssOrphanage = {781b67e1-3f01-4e6c-928b-01ab7699cefa}

22:39:57.638 [2204.5360] <2> ParseConfigExA: Unknown configuration option on line 33: VssOrphanage = {7cec446c-0317-4cc0-aae5-576e71ed2116}

22:39:57.638 [2204.5360] <2> ParseConfigExA: Unknown configuration option on line 34: VssOrphanage = {e87aefb7-8664-4121-8288-825ddbaf3b5f}

22:39:57.638 [2204.5360] <2> ParseConfigExA: Unknown configuration option on line 35: VssOrphanage = {9205a914-aee9-4f0a-943e-13b58fb07cdc}

22:39:57.638 [2204.5360] <2> ParseConfigExA: Unknown configuration option on line 36: VssOrphanage = {f3d3b4ab-8560-4d18-b5f2-b597150aad8d}

22:39:57.638 [2204.5360] <2> ParseConfigExA: Unknown configuration option on line 32: VssOrphanage = {781b67e1-3f01-4e6c-928b-01ab7699cefa}

22:39:57.638 [2204.5360] <2> ParseConfigExA: Unknown configuration option on line 33: VssOrphanage = {7cec446c-0317-4cc0-aae5-576e71ed2116}

22:39:57.638 [2204.5360] <2> ParseConfigExA: Unknown configuration option on line 34: VssOrphanage = {e87aefb7-8664-4121-8288-825ddbaf3b5f}

22:39:57.638 [2204.5360] <2> ParseConfigExA: Unknown configuration option on line 35: VssOrphanage = {9205a914-aee9-4f0a-943e-13b58fb07cdc}

22:39:57.638 [2204.5360] <2> ParseConfigExA: Unknown configuration option on line 36: VssOrphanage = {f3d3b4ab-8560-4d18-b5f2-b597150aad8d}

22:39:57.654 [2204.5360] <2> vnet_pbxConnect: pbxConnectEx Succeeded

22:39:57.654 [2204.5360] <2> logconnections: BPRD CONNECT FROM clientbox ip add.4011 TO master ip add.1556 fd = 1616

22:39:58.857 [2204.5360] <4> bpfis main: INF - BACKUP START 2204

22:39:58.857 [2204.5360] <32> fis_rebuild_from_db: FTL - cannot open C:\Program Files\VERITAS\NetBackup\online_util\fi_cntl\bpfis.fim.clientbox.com_1335749382.0

22:39:58.857 [2204.5360] <4> bpfis Exit: INF - EXIT STATUS 0: the requested operation was successfully completed

22:39:58.857 [2204.5360] <2> bpfis Exit: INF - Close of stdout complete