11-27-2007 10:19 PM
3:15:14.598 AM: [7960.8904] <4> ov_log::OVInit: INF - Starting log file: C:\Program Files\VERITAS\NetBackup\logs\BPBKAR\112707.LOG3:15:14.598 AM: [7960.8904] <4> ov_log::OVInit: GENERAL Log Level: 23:15:14.598 AM: [7960.8904] <4> ov_log::OVInit: TCP Log Level: 03:15:14.598 AM: [7960.8904] <4> ov_log::OVInit: INF - the log mutex: 1964BPBKAR NetBackup Backup/Archive 5.0GA [Nov 11 2005]Copyright 1993 - 2004 VERITAS Software CorporationAll Rights Reserved.3:15:14.598 AM: [7960.8904] <2> WinMain: DAT - _pgmptr = 'C:\Program Files\VERITAS\NetBackup\bin\bpbkar32.exe'3:15:14.598 AM: [7960.8904] <2> WinMain: DAT - lpCmdLine = '-r 3024000 -ru root -dt 0 -to 0 -clnt pluto -class Wintel_TestDEV_System_Sunday -sched Daily_Incremental -st INCR -bpstart_to 2200 -bpend_to 900 -read_to 2300 -ckpt_time 900 -blks_per_buffer 511 -use_otm -use_ofb -b pluto_1196154896 -kl 3 -fso -WOFB_enabled -WOFB_fim 0 -WOFB_usage 0 -WOFB_error 0 -ct 13 '3:15:14.598 AM: [7960.8904] <2> WinMain: DAT - INI: drive=C, dir=\Program Files\VERITAS\NetBackup3:15:14.598 AM: [7960.8904] <2> WinMain: DAT - LOG: drive=C, dir=\Program Files\VERITAS\NetBackup\logs3:15:14.598 AM: [7960.8904] <2> WinMain: DAT - EXE: drive=C, dir=\Program Files\VERITAS\NetBackup\bin3:15:14.598 AM: [7960.8904] <2> date_debug: DAT - timezone: Central Standard Time, offset=21600, dst: Central Daylight Time3:15:14.598 AM: [7960.8904] <2> date_debug: DAT - current time: 1196154914, 11/27/2007 3:15:14 AM3:15:14.598 AM: [7960.8904] <2> date_debug: DAT - 01/01/94 UCT: 757382400, 12/31/1993 6:00:00 PM3:15:14.598 AM: [7960.8904] <2> date_debug: DAT - 07/01/94 UCT: 773020800, 6/30/1994 6:00:00 PM3:15:14.598 AM: [7960.8904] <2> WinMain: DAT - standard input handle = 3643:15:14.614 AM: [7960.8904] <2> WinMain: DAT - standard output handle = 18923:15:14.614 AM: [7960.8904] <2> WinMain: DAT - standard error handle = 19203:15:14.989 AM: [7960.8904] <4> tar_backup::V_SetupJobData: INF - dwJobData: ffffffff3:15:14.989 AM: [7960.8904] <4> tar_backup::V_SetupJobData: INF - dwJob: ffffffff3:15:14.989 AM: [7960.8904] <4> tar_backup::OVRemoveLogs: INF - Purging log files from: C:\Program Files\VERITAS\NetBackup\logs\admin3:15:15.004 AM: [7960.8904] <4> tar_backup::OVRemoveLogs: INF - Purging log files from: C:\Program Files\VERITAS\NetBackup\logs\AltPath3:15:15.004 AM: [7960.8904] <4> tar_backup::OVRemoveLogs: INF - Purging log files from: C:\Program Files\VERITAS\NetBackup\logs\bparchive3:15:15.004 AM: [7960.8904] <4> tar_backup::OVRemoveLogs: INF - Purging log files from: C:\Program Files\VERITAS\NetBackup\logs\bpbackup3:15:15.004 AM: [7960.8904] <4> tar_backup::OVRemoveLogs: INF - Purging log files from: C:\Program Files\VERITAS\NetBackup\logs\bpbkar3:15:15.004 AM: [7960.8904] <4> tar_backup::OVRemoveLogs: INF - Purging log files from: C:\Program Files\VERITAS\NetBackup\logs\bpbrm3:15:15.004 AM: [7960.8904] <4> tar_backup::OVRemoveLogs: INF - Purging log files from: C:\Program Files\VERITAS\NetBackup\logs\bpbrmds3:15:15.004 AM: [7960.8904] <4> tar_backup::OVRemoveLogs: INF - Purging log files from: C:\Program Files\VERITAS\NetBackup\logs\bpbrmvlt3:15:15.004 AM: [7960.8904] <4> tar_backup::OVRemoveLogs: INF - Purging log files from: C:\Program Files\VERITAS\NetBackup\logs\bpcd3:15:15.004 AM: [7960.8904] <4> tar_backup::OVRemoveLogs: INF - Purging log files from: C:\Program Files\VERITAS\NetBackup\logs\bpcoord3:15:15.004 AM: [7960.8904] <4> tar_backup::OVRemoveLogs: INF - Purging log files from: C:\Program Files\VERITAS\NetBackup\logs\bpdbjobs3:15:15.004 AM: [7960.8904] <4> tar_backup::OVRemoveLogs: INF - Purging log files from: C:\Program Files\VERITAS\NetBackup\logs\bpdbm3:15:15.004 AM: [7960.8904] <4> tar_backup::OVRemoveLogs: INF - Purging log files from: C:\Program Files\VERITAS\NetBackup\logs\bpdbsbora3:15:15.004 AM: [7960.8904] <4> tar_backup::OVRemoveLogs: INF - Purging log files from: C:\Program Files\VERITAS\NetBackup\logs\bpdm3:15:15.004 AM: [7960.8904] <4> tar_backup::OVRemoveLogs: INF - Purging log files from: C:\Program Files\VERITAS\NetBackup\logs\bpdynamicclient3:15:15.004 AM: [7960.8904] <4> tar_backup::OVRemoveLogs: INF - Purging log files from: C:\Program Files\VERITAS\NetBackup\logs\bpfilter3:15:15.004 AM: [7960.8904] <4> tar_backup::OVRemoveLogs: INF - Purging log files from: C:\Program Files\VERITAS\NetBackup\logs\bpfis3:15:15.004 AM: [7960.8904] <4> tar_backup::OVRemoveLogs: INF - Purging log files from: C:\Program Files\VERITAS\NetBackup\logs\bpfsmap3:15:15.004 AM: [7960.8904] <4> tar_backup::OVRemoveLogs: INF - Purging log files from: C:\Program Files\VERITAS\NetBackup\logs\bphdb3:15:15.004 AM: [7960.8904] <4> tar_backup::OVRemoveLogs: INF - Purging log files from: C:\Program Files\VERITAS\NetBackup\logs\bpinetd3:15:15.004 AM: [7960.8904] <4> tar_backup::OVRemoveLogs: INF - Purging log files from: C:\Program Files\VERITAS\NetBackup\logs\bpjava-msvc3:15:15.004 AM: [7960.8904] <4> tar_backup::OVRemoveLogs: INF - Purging log files from: C:\Program Files\VERITAS\NetBackup\logs\bpjava-susvc3:15:15.004 AM: [7960.8904] <4> tar_backup::OVRemoveLogs: INF - Purging log files from: C:\Program Files\VERITAS\NetBackup\logs\bpjava-usvc3:15:15.004 AM: [7960.8904] <4> tar_backup::OVRemoveLogs: INF - Purging log files from: C:\Program Files\VERITAS\NetBackup\logs\bpjobd3:15:15.004 AM: [7960.8904] <4> tar_backup::OVRemoveLogs: INF - Purging log files from: C:\Program Files\VERITAS\NetBackup\logs\bpkeyutil3:15:15.020 AM: [7960.8904] <4> tar_backup::OVRemoveLogs: INF - Purging log files from: C:\Program Files\VERITAS\NetBackup\logs\bplist3:15:15.020 AM: [7960.8904] <4> tar_backup::OVRemoveLogs: INF - Purging log files from: C:\Program Files\VERITAS\NetBackup\logs\bpmount3:15:15.020 AM: [7960.8904] <4> tar_backup::OVRemoveLogs: INF - Purging log files from: C:\Program Files\VERITAS\NetBackup\logs\bpnbat3:15:15.020 AM: [7960.8904] <4> tar_backup::OVRemoveLogs: INF - Purging log files from: C:\Program Files\VERITAS\NetBackup\logs\bporaexp3:15:15.020 AM: [7960.8904] <4> tar_backup::OVRemoveLogs: INF - Purging log files from: C:\Program Files\VERITAS\NetBackup\logs\bporaimp3:15:15.020 AM: [7960.8904] <4> tar_backup::OVRemoveLogs: INF - Purging log files from: C:\Program Files\VERITAS\NetBackup\logs\bppfi3:15:15.020 AM: [7960.8904] <4> tar_backup::OVRemoveLogs: INF - Purging log files from: C:\Program Files\VERITAS\NetBackup\logs\bprd3:15:15.020 AM: [7960.8904] <4> tar_backup::OVRemoveLogs: INF - Purging log files from: C:\Program Files\VERITAS\NetBackup\logs\bprestore3:15:15.020 AM: [7960.8904] <4> tar_backup::OVRemoveLogs: INF - Purging log files from: C:\Program Files\VERITAS\NetBackup\logs\bpsched3:15:15.020 AM: [7960.8904] <4> tar_backup::OVRemoveLogs: INF - Purging log files from: C:\Program Files\VERITAS\NetBackup\logs\bpsynth3:15:15.020 AM: [7960.8904] <4> tar_backup::OVRemoveLogs: INF - Purging log files from: C:\Program Files\VERITAS\NetBackup\logs\bptm3:15:15.020 AM: [7960.8904] <4> tar_backup::OVRemoveLogs: INF - Purging log files from: C:\Program Files\VERITAS\NetBackup\logs\dbclient3:15:15.020 AM: [7960.8904] <4> tar_backup::OVRemoveLogs: INF - Purging log files from: C:\Program Files\VERITAS\NetBackup\logs\mtfrd3:15:15.020 AM: [7960.8904] <4> tar_backup::OVRemoveLogs: INF - Purging log files from: C:\Program Files\VERITAS\NetBackup\logs\nbwin3:15:15.020 AM: [7960.8904] <4> tar_backup::OVRemoveLogs: INF - Purging log files from: C:\Program Files\VERITAS\NetBackup\logs\online_util3:15:15.020 AM: [7960.8904] <4> tar_backup::OVRemoveLogs: INF - Purging log files from: C:\Program Files\VERITAS\NetBackup\logs\tar3:15:15.020 AM: [7960.8904] <4> tar_backup::OVRemoveLogs: INF - Purging log files from: C:\Program Files\VERITAS\NetBackup\logs\user_ops3:15:15.051 AM: [7960.8904] <4> tar_backup::OVRemoveLogs: INF - Purging log files from: C:\Program Files\VERITAS\NetBackup\logs\vault3:15:15.051 AM: [7960.8904] <4> tar_backup::OVRemoveLogs: INF - Purging log files from: C:\Program Files\VERITAS\NetBackup\logs\vnetd3:15:15.051 AM: [7960.8904] <4> tar_backup::OVRemoveLogs: INF - Purging log files from: C:\Program Files\VERITAS\NetBackup\logs\vopied3:15:15.051 AM: [7960.8904] <4> dos_backup::V_PreProcessing: INF - backup privileges enabled, previous = 03:15:15.051 AM: [7960.8904] <4> dos_backup::V_PreProcessing: INF - restore privileges enabled, previous = 03:15:15.051 AM: [7960.8904] <4> dos_backup::V_PreProcessing: INF - security privileges enabled, previous = 03:15:15.051 AM: [7960.8904] <4> dos_backup::V_PreProcessing: INF - tcb privileges enabled, previous = 03:15:15.051 AM: [7960.8904] <4> dos_backup::V_PreProcessing: INF - create token privileges enabled, previous = 03:15:15.051 AM: [7960.8904] <4> dos_backup::V_PreProcessing: INF - user name: root3:15:15.051 AM: [7960.8904] <4> dos_backup::V_PreProcessing: INF - no access token: scheduled backup3:15:15.051 AM: [7960.8904] <2> tar_backup::backup_create: TAR - Backup started at 11/27/2007 3:15:15 AM3:15:15.051 AM: [7960.8904] <2> tar_base::V_vTarMsgW: INF - Inform when done3:15:15.051 AM: [7960.8904] <2> tar_base::V_vTarMsgW: INF - Echo keepalives3:15:15.051 AM: [7960.8904] <2> tar_base::V_vTarMsgW: INF - BACKUP START3:15:15.051 AM: [7960.8904] <2> tar_base::V_vTarMsgW: TRV - BACKUP 11/27/2007 3:15:15 AM pluto Wintel_TestDEV_System_Sunday Daily_Incremental INCR 3:15:15.192 AM: [7960.8904] <2> tar_backup::V_SetupProcessContinue: TAR - CONTINUE BACKUP received3:15:15.192 AM: [7960.8904] <2> tar_backup::V_SetupFileDirectives: TAR - Processing filename list3:15:15.348 AM: [7960.8904] <2> tar_backup::V_SetupFileDirectives: TAR - backup filename = SET SNAP_ID=pluto_11960741113:15:15.348 AM: [7960.8904] <2> tar_backup::V_SetupFileDirectives: TAR - backup filename = SET SNAP_ID=pluto_11960777073:15:15.348 AM: [7960.8904] <2> tar_backup::V_SetupFileDirectives: TAR - backup filename = ALL_LOCAL_DRIVES3:15:16.411 AM: [7960.8904] <4> V_Snapshot::V_Snapshot_Query: INF - Attempting to query volume snapshots: bpfis query -id pluto_11961548963:15:17.489 AM: [7960.8904] <4> V_Snapshot::V_Snapshot_ParseBpfisOutput: INF - Snapshot creation, FIS_ID: pluto_11961548963:15:17.536 AM: [7960.8904] <2> ov_log::V_GlobalLog: INF - BEDS_Init() Enter InitFlags:0x03:15:17.536 AM: [7960.8904] <2> ov_log::V_GlobalLog: INF - BEDS BackupMethod(1)3:15:17.536 AM: [7960.8904] <2> ov_log::V_GlobalLog: INF - BEC_SetRestoreForceRecovery(0)3:15:17.536 AM: [7960.8904] <2> ov_log::V_GlobalLog: INF - BEC_SetXchgNoLossRestore(0) BEC_SetEseNoLossRestore(0)3:15:17.536 AM: [7960.8904] <2> ov_log::V_GlobalLog: INF - BEC_SetSingleInstanceBackupForMessageAttachments(0)3:15:17.536 AM: [7960.8904] <2> ov_log::V_GlobalLog: INF - BEC_SetEseMountAfterRestore(0)3:15:17.536 AM: [7960.8904] <2> ov_log::V_GlobalLog: INF - BEC_SetEseLastBackupSet(0)3:15:17.536 AM: [7960.8904] <2> ov_log::V_GlobalLog: INF - BEC_SetRestoreSecurity(1)3:15:17.536 AM: [7960.8904] <2> ov_log::V_GlobalLog: INF - BEC_SetSqlDatabaseRecover(1)3:15:17.536 AM: [7960.8904] <2> ov_log::V_GlobalLog: INF - BEC_SetSqlRestStandBy(0)3:15:17.536 AM: [7960.8904] <2> ov_log::V_GlobalLog: INF - BEC_SetSqlReplaceDatabase(0)3:15:17.536 AM: [7960.8904] <2> ov_log::V_GlobalLog: INF - BEC_SetSqlAutoMaster(0)3:15:17.536 AM: [7960.8904] <2> ov_log::V_GlobalLog: INF - BEC_SetPostRestDBCC(2)3:15:17.536 AM: [7960.8904] <2> ov_log::V_GlobalLog: INF - BEC_SetSqlDefaultDrive(67)3:15:17.536 AM: [7960.8904] <2> ov_log::V_GlobalLog: INF - BEC_SetSqlUseDefaultDrive(1)3:15:17.536 AM: [7960.8904] <2> ov_log::V_GlobalLog: INF - BEC_SetSqlAllToDefault(1)3:15:17.536 AM: [7960.8904] <2> ov_log::V_GlobalLog: INF - UseDefaultInstanceDirectory(0)3:15:17.536 AM: [7960.8904] <2> ov_log::V_GlobalLog: INF - BEC_SetSqlPointInTimeRestore(0)3:15:17.536 AM: [7960.8904] <2> ov_log::V_GlobalLog: INF - BEC_SetSqlPointInTimeRestoreTime(0.000000)3:15:17.536 AM: [7960.8904] <2> ov_log::V_GlobalLog: INF - BEC_SetSqlLogMarkRestore(0)3:15:17.536 AM: [7960.8904] <2> ov_log::V_GlobalLog: INF - BEC_SetSqlLogMarkName(NULL)3:15:17.536 AM: [7960.8904] <2> ov_log::V_GlobalLog: INF - BEC_SetSqlLogMarkStopBefore(0)3:15:17.536 AM: [7960.8904] <2> ov_log::V_GlobalLog: INF - BEC_SetSqlLogMarkAfterTime(0)3:15:17.536 AM: [7960.8904] <2> ov_log::V_GlobalLog: INF - BEC_SetSqlLogMarkRestoreTime(0.000000)3:15:17.536 AM: [7960.8904] <2> ov_log::V_GlobalLog: INF - BEC_SetRestoreXchgPrivate(1)3:15:17.536 AM: [7960.8904] <2> ov_log::V_GlobalLog: INF - BEC_SetRestoreXchgPublic(1)3:15:17.536 AM: [7960.8904] <2> ov_log::V_GlobalLog: INF - SetVirtualNameForNodeName(pluto)3:15:17.536 AM: [7960.8904] <2> ov_log::V_GlobalLog: INF - FS_InitFileSys3:15:17.676 AM: [7960.8904] <2> ov_log::V_GlobalLog: INF - loaded nbbedsshadow.dll3:15:20.411 AM: [7960.8904] <2> ov_log::V_GlobalLog: INF - loaded nbbedsnt.dll3:15:20.504 AM: [7960.8904] <2> ov_log::V_GlobalLog: INF - loaded nbbedssql2.dll3:15:20.504 AM: [7960.8904] <2> ov_log::V_GlobalLog: INF - Initializing FSs3:15:21.333 AM: [7960.8904] <2> ov_log::V_GlobalLog: INF - SQL2_FindDrives - the default SQL Service is not running on pluto.3:15:21.333 AM: [7960.8904] <2> ov_log::V_GlobalLog: INF - SQL2_FindDrives - checking named instance SQLEXPRESS.3:15:21.333 AM: [7960.8904] <2> ov_log::V_GlobalLog: INF - SQL2_CreateDLE - creating pluto\SQLEXPRESS.3:15:21.333 AM: [7960.8904] <2> ov_log::V_GlobalLog: INF - Device Shadow—Copy–Components Dle platform 2 major version 5 minor version 2 build 37903:15:21.333 AM: [7960.8904] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: C:3:15:21.333 AM: [7960.8904] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: D:3:15:21.333 AM: [7960.8904] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Microsoft Terminal Services3:15:21.333 AM: [7960.8904] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Microsoft Windows Network3:15:21.333 AM: [7960.8904] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: VMware Shared Folders3:15:21.333 AM: [7960.8904] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Web Client Network3:15:21.333 AM: [7960.8904] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: pluto\SQLEXPRESS3:15:21.333 AM: [7960.8904] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Shadow˜Copy™Components3:15:21.333 AM: [7960.8904] <4> dos_backup::V_VerifyFileSystem: INF - Verifying: :\3:15:21.348 AM: [7960.8904] <4> dos_backup::V_VerifyFileSystem: INF - Added: C:\3:15:21.348 AM: [7960.8904] <4> dos_backup::V_VerifyFileSystem: INF - Added: D:\3:15:21.348 AM: [7960.8904] <4> dos_backup::V_VerifyFileSystem: INF - Added: Shadow Copy Components:\3:15:21.348 AM: [7960.8904] <4> dos_backup::V_VerifyFileSystem: INF - Verifying: C:\3:15:21.348 AM: [7960.8904] <4> dos_backup::V_VerifyFileSystem: INF - Verifying: D:\3:15:21.348 AM: [7960.8904] <4> dos_backup::V_VerifyFileSystem: INF - Verifying: Shadow Copy Components:\3:15:21.348 AM: [7960.8904] <4> dos_backup::V_VerifyFileList: INF - UBS Local Type for 'C:' --> 100200023:15:21.348 AM: [7960.8904] <4> dos_backup::V_VerifyFileList: INF - UBS Local Type for 'D:' --> 100200023:15:21.348 AM: [7960.8904] <2> ov_log::V_GlobalLog: INF - v_beds::V_FindFirst() ENTER Name:Shadow Copy Components: Mode:03:15:21.348 AM: [7960.8904] <2> ov_log::V_GlobalLog: INF - bedsShadowCopyInit():ENTER (Reason:1)3:15:21.348 AM: [7960.8904] <2> ov_log::V_GlobalLog: INF - bedsShadowCopyInit():Found Resource DLE for 'Shadow?Copy?Components'3:15:21.348 AM: [7960.8904] <2> ov_log::V_GlobalLog: DBG - v_beds::V_FindFirst() Device:Shadow Copy Components: Obj: Pattern:(null)3:35:27.879 AM: [7960.8904] <2> ov_log::V_GlobalLog: INF - Informational: Writer:OSearch VSS Writer Logical Path:null Component:ContentIndex_OSearch_SSP1DEV not in supported Writer list3:35:27.879 AM: [7960.8904] <2> ov_log::V_GlobalLog: INF - Informational: Writer:SPSearch VSS Writer Logical Path:null Component:ContentIndex_SPSearch not in supported Writer list3:35:27.879 AM: [7960.8904] <2> ov_log::V_GlobalLog: INF - brUtil::brUtil( img ) Constructor 3:35:27.879 AM: [7960.8904] <2> ov_log::V_GlobalLog: INF - Successfully attached to Device 'Shadow?Copy?Components' BackupReason:0x13:35:27.973 AM: [7960.8904] <2> ov_log::V_GlobalLog: INF - Informational status CODE (2) returned calling FindFirstFile for C:\WINNT\system32\perf?00?.bak when getting additional files3:35:28.035 AM: [7960.8904] <2> ov_log::V_GlobalLog: INF - Status FS_NOT_FOUND (FFFFFE08) returned determining if Writer OSearch VSS Writer should be displayed when generating logical directory tree3:35:28.035 AM: [7960.8904] <2> ov_log::V_GlobalLog: INF - v_beds::V_FindFirst() EXIT Name:Shadow Copy Components: bRC:true LastError:0x0:3:35:28.035 AM: [7960.8904] <4> dos_backup::V_VerifyFileList: INF - UBS Local Type for 'Shadow Copy Components:' --> 100200273:35:28.035 AM: [7960.8904] <2> ov_log::V_GlobalLog: INF - v_beds::V_FindFirst() ENTER Name:Shadow Copy Components Mode:03:35:28.035 AM: [7960.8904] <2> ov_log::V_GlobalLog: DBG - v_beds::V_FindFirst() Device:Shadow Copy Components Obj: Pattern:(null)3:35:28.051 AM: [7960.8904] <2> ov_log::V_GlobalLog: INF - brUtil::brUtil Destructor 3:35:28.051 AM: [7960.8904] <2> ov_log::V_GlobalLog: INF - Successfully deattached from Device 'Shadow?Copy?Components' BackupReason:0x13:35:28.051 AM: [7960.8904] <2> ov_log::V_GlobalLog: INF - Status RPC_E_TOO_LATE (80010119) returned initializing COM when initializing shadow copy
11-28-2007 05:09 AM
11-28-2007 11:30 PM
11-29-2007 06:05 AM