I have always managed to get performance stats with level 0 logs.
I have posted a bpbkar log from a Windows client below. This is the part that tells us read spead on the client:
11:29:01.485 AM: [7660.6584] <2> tar_base::backup_finish: TAR - backup: file data: 967486464 bytes 86 gigabytes
11:29:01.485 AM: [7660.6584] <2> tar_base::backup_finish: TAR - backup: image data: 967493632 bytes 86 gigabytes
11:29:01.485 AM: [7660.6584] <2> tar_base::backup_finish: TAR - backup: elapsed time: 1638 secs 56965375 bps
Test was done using this command on the client:
bpbkar32 -nocont E:\NTBackups > NUL
bptm log on media servers also have the 'waited for full / empty ....' at the end of backups using level 0 logging.
We have recently seen major backup improvements by disabling TCP chimney as per this TechNote:
http://seer.entsupport.symantec.com/docs/304578.htm
bpbkar log:
11:01:41.548 AM: [7660.6584] <4> ov_log::OVInit: INF - Starting log file: C:\Program Files\VERITAS\NetBackup\logs\BPBKAR\112609.LOG
11:01:41.548 AM: [7660.6584] <4> ov_log::OVInit: GENERAL Log Level: 0
11:01:41.548 AM: [7660.6584] <4> ov_log::OVInit: TCP Log Level: 0
....
11:01:41.563 AM: [7660.6584] <2> WinMain: DAT - _pgmptr = 'C:\Program Files\VERITAS\NetBackup\bin\bpbkar32.exe'
11:01:41.563 AM: [7660.6584] <2> WinMain: DAT - lpCmdLine = '-nocont E:\NTBackups '
11:01:41.563 AM: [7660.6584] <2> date_debug: DAT - timezone: South Africa Standard Time, offset=-7200, dst: NONE
11:01:41.563 AM: [7660.6584] <2> date_debug: DAT - current time: 1259226101, 11/26/2009 11:01:41 AM
11:01:41.563 AM: [7660.6584] <2> date_debug: DAT - 01/01/94 UCT: 757382400, 1/1/1994 2:00:00 AM
11:01:41.563 AM: [7660.6584] <2> date_debug: DAT - 07/01/94 UCT: 773020800, 7/1/1994 2:00:00 AM
11:01:41.563 AM: [7660.6584] <2> WinMain: DAT - standard input handle = -1
11:01:41.563 AM: [7660.6584] <4> WinMain: INF - not using socket for standard input handle
11:01:41.563 AM: [7660.6584] <2> WinMain: DAT - standard output handle = 16
11:01:41.563 AM: [7660.6584] <4> WinMain: INF - not using socket for standard output handle
11:01:41.563 AM: [7660.6584] <2> WinMain: DAT - standard error handle = -1
11:01:41.563 AM: [7660.6584] <4> WinMain: INF - not using socket for standard error handle
11:01:41.673 AM: [7660.6584] <4> tar_backup::V_SetupJobData: INF - dwJobData: ffffffff
11:01:41.673 AM: [7660.6584] <4> tar_backup::V_SetupJobData: INF - dwJob: ffffffff
11:01:41.673 AM: [7660.6584] <4> dos_backup::V_PreProcessing: INF - backup privileges enabled, previous = 0
11:01:41.673 AM: [7660.6584] <4> dos_backup::V_PreProcessing: INF - restore privileges enabled, previous = 0
11:01:41.673 AM: [7660.6584] <4> dos_backup::V_PreProcessing: INF - security privileges enabled, previous = 0
11:01:41.673 AM: [7660.6584] <4> dos_backup::V_PreProcessing: INF - tcb privileges enabled, previous = 0
11:01:41.673 AM: [7660.6584] <4> dos_backup::V_PreProcessing: INF - create token privileges enabled, previous = 0
11:01:41.673 AM: [7660.6584] <4> dos_backup::V_PreProcessing: INF - user name:
11:01:41.673 AM: [7660.6584] <4> dos_backup::V_PreProcessing: INF - no access token: scheduled backup
11:01:41.673 AM: [7660.6584] <2> tar_backup::V_SetupFileDirectives: TAR - backup filename = E:\NTBackups
11:01:41.688 AM: [7660.6584] <2> tar_base::V_vTarMsgW: INF - Open File Backup license is not installed on the server. Open File Backup usage will be disabled.
11:01:41.704 AM: [7660.6584] <2> ov_log::V_GlobalLog: INF - BEDS_Init() Enter InitFlags:0x0
11:01:42.001 AM: [7660.6584] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: C:
11:01:42.001 AM: [7660.6584] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: D:
11:01:42.001 AM: [7660.6584] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: E:
11:01:42.001 AM: [7660.6584] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Microsoft Terminal Services
11:01:42.001 AM: [7660.6584] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Microsoft Windows Network
11:01:42.001 AM: [7660.6584] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Web Client Network
11:01:42.001 AM: [7660.6584] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Shadow?Copy?Components
11:01:43.938 AM: [7660.6584] <4> backup_create: INF - NetBackup Temp Directory: 'C:\Program Files\VERITAS\\NetBackup\Temp'
11:29:01.485 AM: [7660.6584] <2> tar_base::backup_finish: TAR - backup: 2 files
11:29:01.485 AM: [7660.6584] <2> tar_base::backup_finish: TAR - backup: file data: 967486464 bytes 86 gigabytes
11:29:01.485 AM: [7660.6584] <2> tar_base::backup_finish: TAR - backup: image data: 967493632 bytes 86 gigabytes
11:29:01.485 AM: [7660.6584] <2> tar_base::backup_finish: TAR - backup: elapsed time: 1638 secs 56965375 bps
11:29:01.501 AM: [7660.6584] <2> tar_base::V_vTarMsgW: INF - EXIT STATUS 0: the requested operation was successfully completed
11:29:01.501 AM: [7660.6584] <4> OVStopCmd: INF - EXIT - status = 0
11:29:01.517 AM: [7660.6584] <2> ov_log::V_GlobalLog: INF - BEDS_Term() Enter InitFlags:0x101
11:29:01.563 AM: [7660.6584] <4> OVShutdown: INF - Finished process
11:29:01.563 AM: [7660.6584] <4> WinMain: INF - Exiting C:\Program Files\VERITAS\NetBackup\bin\bpbkar32.exe
11:29:03.563 AM: [7660.6584] <4> ov_log::OVClose: INF - Closing log file: C:\Program Files\VERITAS\NetBackup\logs\BPBKAR\112609.LOG