Solved: Backup VMWARE fail system call failed(11)

Anderson_Santos · ‎12-27-2013

Log:

10:36:51.505 [1700.5012] <2> logparams: N:\Program Files\Veritas\NetBackup\bin\bpfis.exe delete -nbu -id APPLICATION-NETBACKUP_1388147638 -bpstart_to 300 -bpend_to 300 -clnt serv-media01.central.iamspe.com.br -status 150 -S serv-master-emm.central.iamspe.com.br -noserverstate -jobid 118037 -copy 1 -fim VMware_v2:Virtual_machine_backup=2,nameuse=1,snapact=2,file_system_optimization=1,trantype=san:nbd,exclude_swap=1,disable_quiesce=0,drive_selection=0,skipnodisk=0,post_events=0,vsrvr=vw-vcenter-01.central.iamspe.com.br,uuid=502da106-fa66-9c9d-ded3-64cde5f97a0c

10:36:51.505 [1700.5012] <2> bpfis main: received FIM as [260] VMware_v2:Virtual_machine_backup=2,nameuse=1,snapact=2,file_system_optimization=1,trantype=san:nbd,exclude_swap=1,disable_quiesce=0,drive_selection=0,skipnodisk=0,post_events=0,vsrvr=vw-vcenter-01.central.iamspe.com.br,uuid=502da106-fa66-9c9d-ded3-64cde5f97a0c

10:36:51.505 [1700.5012] <4> bpfis: Starting keep alive thread.

10:36:51.505 [1700.5012] <4> bpfis: Acquiring snapshot lock:retry count [0]

10:36:51.505 [1700.5012] <2> getFileName: FileName is [N:\Program Files\Veritas\NetBackup\online_util\fi_cntl\APPLICATION-NETBACKUP_1388147638_copy1.lock]

10:36:51.505 [1700.5012] <2> readSnapshotLockFile: Read type[0] refcount[1]

10:36:51.505 [1700.5012] <2> readSnapshotLockFile: Read Flags [0] [0]

10:36:51.505 [1700.5012] <2> readSnapshotLockFile: Read Last addReffernt Time [Wed Dec 31 22:00:00 1969

]

10:36:51.505 [1700.5012] <2> readSnapshotLockFile: Read hostname []

10:36:51.505 [1700.5012] <2> readSnapshotLockFile: Read jobid[118037] pid [2548]

10:36:51.505 [1700.5012] <2> bprd_isJobActive: client [serv-media01.central.iamspe.com.br] master[serv-master-emm.central.iamspe.com.br]

10:36:51.505 [1700.5012] <2> vnet_pbxConnect: pbxConnectEx Succeeded

10:36:51.521 [1700.5012] <2> logconnections: BPRD CONNECT FROM 10.146.20.19.56864 TO 10.146.20.20.1556 fd = 564

10:36:51.521 [1700.5012] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2

10:36:51.614 [1700.5012] <2> isJobActive: bprd_isJobActive returned [0] for jobid[118037]

10:36:51.614 [1700.5012] <2> vnet_pbxConnect: pbxConnectEx Succeeded

10:36:51.614 [1700.5012] <2> logconnections: BPRD CONNECT FROM 10.146.20.19.56865 TO 10.146.20.20.1556 fd = 588

10:36:51.614 [1700.5012] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2

10:36:51.692 [1700.5012] <4> bpfis: INF - BACKUP START 1700

10:36:51.708 [1700.5012] <2> vnet_pbxConnect: pbxConnectEx Succeeded

10:36:51.708 [1700.5012] <2> logconnections: BPRD CONNECT FROM 10.146.20.19.56866 TO 10.146.20.20.1556 fd = 604

10:36:51.708 [1700.5012] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2

10:36:51.817 [1700.5012] <8> bpfis: WRN - VfMS error 10; see following messages:

10:36:51.817 [1700.5012] <8> bpfis: WRN - Non-fatal method error was reported

10:36:51.817 [1700.5012] <8> bpfis: WRN - vfm_configure_fi_one: method: FlashSnap, type: FIM, function: FlashSnap_init

10:36:51.817 [1700.5012] <8> bpfis: WRN - VfMS method error 3; see following message:

10:36:51.817 [1700.5012] <8> bpfis: WRN - FlashSnap_init: Veritas Volume Manager not installed.

10:36:51.817 [1700.5012] <8> bpfis: WRN - VfMS error 10; see following messages:

10:36:51.817 [1700.5012] <8> bpfis: WRN - Non-fatal method error was reported

10:36:51.817 [1700.5012] <8> bpfis: WRN - vfm_configure_fi_one: method: vxvm, type: FIM, function: vxvm_init

10:36:51.817 [1700.5012] <8> bpfis: WRN - VfMS method error 3; see following message:

10:36:51.817 [1700.5012] <8> bpfis: WRN - vxvm_init: Veritas Volume Manager not installed.

10:36:51.833 [1700.5012] <4> bpfis: INF - Thawing ALL_LOCAL_DRIVES using snapshot method VMware_v2.

10:36:51.833 [1700.5012] <2> vnet_pbxConnect: pbxConnectEx Succeeded

10:36:51.833 [1700.5012] <2> logconnections: BPRD CONNECT FROM 10.146.20.19.56867 TO 10.146.20.20.1556 fd = 616

10:36:51.833 [1700.5012] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2

10:37:00.302 [1700.5012] <4> bpfis: INF - do_thaw return value: 0

10:37:00.302 [1700.5012] <4> bpfis: INF - Failed to delete mount point N:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.APPLICATION-NETBACKUP_1388147638.1.0.NBU_DATA.xml; errno=22: Invalid argument

10:37:00.302 [1700.5012] <2> vnet_pbxConnect: pbxConnectEx Succeeded

10:37:00.302 [1700.5012] <2> logconnections: BPRD CONNECT FROM 10.146.20.19.56880 TO 10.146.20.20.1556 fd = 596

10:37:00.302 [1700.5012] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2

10:37:00.380 [1700.5012] <4> bpfis: Starting keep alive thread.

10:37:00.380 [1700.5012] <4> bpfis: Delete reference: status [0]

10:37:00.380 [1700.5012] <2> writeSnapshotLockFile: Writing type[2] refcount[0]

10:37:00.380 [1700.5012] <2> writeSnapshotLockFile: Writing Flags [0] [0]

10:37:00.380 [1700.5012] <2> writeSnapshotLockFile: Writing Last addReffernt Time [Wed Dec 31 22:00:00 1969

]

10:37:00.380 [1700.5012] <2> writeSnapshotLockFile: Writing Last Hostname []

10:37:00.411 [1700.5012] <2> getFileName: FileName is [N:\Program Files\Veritas\NetBackup\online_util\fi_cntl\APPLICATION-NETBACKUP_1388147638_copy1.lock]

10:37:00.411 [1700.5012] <2> unlockSnapshot: file[N:\Program Files\Veritas\NetBackup\online_util\fi_cntl\APPLICATION-NETBACKUP_1388147638_copy1.lock] : zero reference count

10:37:00.411 [1700.5012] <4> bpfis: Released snapshot lock: status [0]

10:37:00.411 [1700.5012] <4> bpfis: INF - EXIT STATUS 0: the requested operation was successfully completed

10:37:00.411 [1700.5012] <16> bpfis: Error -1 sending <EXIT STATUS 0: the requested operation was successfully completed> to server

10:37:00.786 [3300.2540] <2> logparams: N:\Program Files\Veritas\NetBackup\bin\bpfis.exe delete -nbu -id APPLICATION-NETBACKUP_1388147638 -bpstart_to 300 -bpend_to 300 -clnt serv-media01.central.iamspe.com.br -S serv-master-emm.central.iamspe.com.br -jobid 118037 -copy 1

10:37:00.786 [3300.2540] <4> bpfis: Starting keep alive thread.

10:37:00.786 [3300.2540] <4> bpfis: Acquiring snapshot lock:retry count [0]

10:37:00.786 [3300.2540] <2> getFileName: FileName is [N:\Program Files\Veritas\NetBackup\online_util\fi_cntl\APPLICATION-NETBACKUP_1388147638_copy1.lock]

10:37:00.786 [3300.2540] <2> readSnapshotLockFile: Read type[2] refcount[0]

10:37:00.786 [3300.2540] <2> readSnapshotLockFile: Read Flags [0] [0]

10:37:00.786 [3300.2540] <2> readSnapshotLockFile: Read Last addReffernt Time [Wed Dec 31 22:00:00 1969

]

10:37:00.786 [3300.2540] <2> readSnapshotLockFile: Read hostname []

10:37:00.802 [3300.2540] <2> vnet_pbxConnect: pbxConnectEx Succeeded

10:37:00.802 [3300.2540] <2> logconnections: BPRD CONNECT FROM 10.146.20.19.56882 TO 10.146.20.20.1556 fd = 564

10:37:00.802 [3300.2540] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2

10:37:01.974 [3300.2540] <2> vnet_pbxConnect: pbxConnectEx Succeeded

10:37:01.974 [3300.2540] <2> logconnections: BPRD CONNECT FROM 10.146.20.19.56885 TO 10.146.20.20.1556 fd = 592

10:37:01.974 [3300.2540] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2

10:37:02.067 [3300.2540] <4> bpfis: INF - BACKUP START 3300

10:37:02.067 [3300.2540] <2> vnet_pbxConnect: pbxConnectEx Succeeded

10:37:02.067 [3300.2540] <2> logconnections: BPRD CONNECT FROM 10.146.20.19.56886 TO 10.146.20.20.1556 fd = 608

10:37:02.067 [3300.2540] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2

10:37:02.192 [3300.2540] <8> bpfis: WRN - VfMS error 10; see following messages:

10:37:02.192 [3300.2540] <8> bpfis: WRN - Non-fatal method error was reported

10:37:02.192 [3300.2540] <8> bpfis: WRN - vfm_configure_fi_one: method: FlashSnap, type: FIM, function: FlashSnap_init

10:37:02.192 [3300.2540] <8> bpfis: WRN - VfMS method error 3; see following message:

10:37:02.192 [3300.2540] <8> bpfis: WRN - FlashSnap_init: Veritas Volume Manager not installed.

10:37:02.192 [3300.2540] <8> bpfis: WRN - VfMS error 10; see following messages:

10:37:02.192 [3300.2540] <8> bpfis: WRN - Non-fatal method error was reported

10:37:02.192 [3300.2540] <8> bpfis: WRN - vfm_configure_fi_one: method: vxvm, type: FIM, function: vxvm_init

10:37:02.192 [3300.2540] <8> bpfis: WRN - VfMS method error 3; see following message:

10:37:02.192 [3300.2540] <8> bpfis: WRN - vxvm_init: Veritas Volume Manager not installed.

10:37:02.192 [3300.2540] <4> bpfis: INF - Thawing ALL_LOCAL_DRIVES using snapshot method VMware_v2.

10:37:02.208 [3300.2540] <2> vnet_pbxConnect: pbxConnectEx Succeeded

10:37:02.208 [3300.2540] <2> logconnections: BPRD CONNECT FROM 10.146.20.19.56887 TO 10.146.20.20.1556 fd = 620

10:37:02.208 [3300.2540] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2

10:37:24.396 [3300.2540] <4> bpfis: INF - do_thaw return value: 0

10:37:24.396 [3300.2540] <4> bpfis: INF - Failed to delete mount point N:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.APPLICATION-NETBACKUP_1388147638.1.0.NBU_DATA.xml; errno=22: Invalid argument

10:37:24.411 [3300.2540] <2> vnet_pbxConnect: pbxConnectEx Succeeded

10:37:24.411 [3300.2540] <2> logconnections: BPRD CONNECT FROM 10.146.20.19.56892 TO 10.146.20.20.1556 fd = 600

10:37:24.411 [3300.2540] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2

10:37:24.490 [3300.2540] <4> bpfis: Starting keep alive thread.

10:37:24.490 [3300.2540] <2> deleteReference: reference not found in the list

10:37:24.490 [3300.2540] <4> bpfis: Delete reference: status [-4]

10:37:24.490 [3300.2540] <2> writeSnapshotLockFile: Writing type[2] refcount[0]

10:37:24.490 [3300.2540] <2> writeSnapshotLockFile: Writing Flags [0] [0]

10:37:24.490 [3300.2540] <2> writeSnapshotLockFile: Writing Last addReffernt Time [Wed Dec 31 22:00:00 1969

]

10:37:24.490 [3300.2540] <2> writeSnapshotLockFile: Writing Last Hostname []

10:37:24.521 [3300.2540] <2> getFileName: FileName is [N:\Program Files\Veritas\NetBackup\online_util\fi_cntl\APPLICATION-NETBACKUP_1388147638_copy1.lock]

10:37:24.521 [3300.2540] <2> unlockSnapshot: file[N:\Program Files\Veritas\NetBackup\online_util\fi_cntl\APPLICATION-NETBACKUP_1388147638_copy1.lock] : zero reference count

10:37:24.521 [3300.2540] <4> bpfis: Released snapshot lock: status [0]

10:37:24.521 [3300.2540] <4> bpfis: INF - EXIT STATUS 0: the requested operation was successfully completed

10:37:53.818 [4052.3972] <2> logparams: N:\Program Files\Veritas\NetBackup\bin\bpfis.exe verify -nbu -pt 40 -backup_copy 0 -block_incr -fim VMware_v2:Virtual_machine_backup=2,nameuse=1,snapact=2,file_system_optimization=1,trantype=nbd,exclude_swap=1,disable_quiesce=0,drive_selection=0,skipnodisk=0,post_events=0 -vm_client APPLICATION-NETBACKUP

10:37:53.818 [4052.3972] <2> bpfis main: received FIM as [172] VMware_v2:Virtual_machine_backup=2,nameuse=1,snapact=2,file_system_optimization=1,trantype=nbd,exclude_swap=1,disable_quiesce=0,drive_selection=0,skipnodisk=0,post_events=0

10:37:53.818 [4052.3972] <4> bpfis: Starting keep alive thread.

10:37:53.833 [4052.3972] <2> vnet_pbxConnect: pbxConnectEx Succeeded

10:37:53.833 [4052.3972] <2> logconnections: BPRD CONNECT FROM 10.146.20.19.56901 TO 10.146.20.20.1556 fd = 564

10:37:53.833 [4052.3972] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2

10:37:53.912 [4052.3972] <4> bpfis: INF - BACKUP START 4052

10:37:53.912 [4052.3972] <2> bpfis main: receive filelist:<ALL_LOCAL_DRIVES>

10:37:53.912 [4052.3972] <2> check_special_names: got path entry as :<ALL_LOCAL_DRIVES>

10:37:53.912 [4052.3972] <2> check_special_names: after conversion returning :<ALL_LOCAL_DRIVES>

10:37:53.912 [4052.3972] <2> bpfis main: receive filelist:<CONTINUE>

10:37:53.912 [4052.3972] <2> check_special_names: got path entry as :<CONTINUE>

10:37:53.912 [4052.3972] <2> check_special_names: after conversion returning :<CONTINUE>

10:37:53.927 [4052.3972] <2> vnet_pbxConnect: pbxConnectEx Succeeded

10:37:53.927 [4052.3972] <2> logconnections: BPRD CONNECT FROM 10.146.20.19.56902 TO 10.146.20.20.1556 fd = 600

10:37:53.927 [4052.3972] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2

10:37:54.037 [4052.3972] <8> bpfis: WRN - VfMS error 10; see following messages:

10:37:54.037 [4052.3972] <8> bpfis: WRN - Non-fatal method error was reported

10:37:54.037 [4052.3972] <8> bpfis: WRN - vfm_configure_fi_one: method: FlashSnap, type: FIM, function: FlashSnap_init

10:37:54.037 [4052.3972] <8> bpfis: WRN - VfMS method error 3; see following message:

10:37:54.037 [4052.3972] <8> bpfis: WRN - FlashSnap_init: Veritas Volume Manager not installed.

10:37:54.037 [4052.3972] <8> bpfis: WRN - VfMS error 10; see following messages:

10:37:54.037 [4052.3972] <8> bpfis: WRN - Non-fatal method error was reported

10:37:54.037 [4052.3972] <8> bpfis: WRN - vfm_configure_fi_one: method: vxvm, type: FIM, function: vxvm_init

10:37:54.037 [4052.3972] <8> bpfis: WRN - VfMS method error 3; see following message:

10:37:54.037 [4052.3972] <8> bpfis: WRN - vxvm_init: Veritas Volume Manager not installed.

10:37:54.052 [4052.3972] <4> bpfis: INF - FIS_ID=1388147873

10:37:54.052 [4052.3972] <4> bpfis: INF - Testing freeze of ALL_LOCAL_DRIVES using snapshot method VMware_v2.

10:37:54.052 [4052.3972] <4> bpfis: INF - Created mount point N:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_ALL_LOCAL_DRIVES_4052_-1

10:37:54.052 [4052.3972] <2> vnet_pbxConnect: pbxConnectEx Succeeded

10:37:54.052 [4052.3972] <2> logconnections: BPRD CONNECT FROM 10.146.20.19.56903 TO 10.146.20.20.1556 fd = 612

10:37:54.052 [4052.3972] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2

10:37:54.130 [4052.3972] <2> vnet_pbxConnect: pbxConnectEx Succeeded

10:37:54.130 [4052.3972] <2> logconnections: BPRD CONNECT FROM 10.146.20.19.56904 TO 10.146.20.20.1556 fd = 604

10:37:54.130 [4052.3972] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2

10:37:54.474 [4052.3972] <2> get_VM_names_VMware: get_VM_names_VMware: read for vw-vcenter-01.central.iamspe.com.br

10:37:55.193 [4052.3972] <4> bpfis: INF - Deleted mount point N:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_ALL_LOCAL_DRIVES_4052_-1

10:37:55.193 [4052.3972] <4> bpfis: Starting keep alive thread.

10:37:55.193 [4052.3972] <4> bpfis: INF - EXIT STATUS 0: the requested operation was successfully completed

RamNagalla · ‎01-03-2014

Do you have any VMs successfull from this media server & backup host?

does it ever worked?

what is the Vcenter version and Backup host OS?

see the below one once...

Error:-

bpbrm Exit: unexpected termination of client APPLICATION-TFS

https://www-secure.symantec.com/connect/forums/netbackup-75-vm-backup-unexpected-termination-client-...

PS:- going forward pleaes attach the logs to the post.. do not paste on the forum post.

View solution in original post

sri_vani · ‎12-27-2013

The log you have provided is for successful backup--

10:37:55.193 [4052.3972] <4> bpfis: INF - EXIT STATUS 0: the requested operation was successfully completed

We dont see anything related to system call failed(11) error on the provided log.

VMWARE - ER-11 - is generally seen when adequate space is not available on VM while taking the backup.

make sure VM has enough space on the disk

Anderson_Santos · ‎12-27-2013

Log:

file: N:\Program Files\Veritas\NetBackup\logs\BPBKAR\122713.LOG

12:47:25.795: [3572.4464] <4> ov_log::OVInit: INF - Starting log file: N:\Program Files\Veritas\NetBackup\logs\BPBKAR\122713.LOG

12:47:25.795: [3572.4464] <4> ov_log::OVInit: GENERAL Log Level: 0

12:47:25.795: [3572.4464] <4> ov_log::OVInit: TCP Log Level: 0

12:47:25.795: [3572.4464] <4> ov_log::OVInit: INF - the log mutex: 388

BPBKAR NetBackup Backup/Archive 7.5GA [Feb 9 2013]

12:47:25.826: [3572.4464] <2> WinMain: DAT - _pgmptr = 'N:\Program Files\Veritas\NetBackup\bin\bpbkar32.exe'

12:47:25.826: [3572.4464] <2> WinMain: DAT - lpCmdLine = '-r 2678400 -ru root -dt 0 -to 0 -bpstart_time 0 -clnt APPLICATION-NETBACKUP -class VMWARE-ESXi-09 -sched Semanal -st FULL -bpstart_to 300 -bpend_to 300 -read_to 1200 -block_incr -keyword BACKUPÀ VIAÀ À¨LANÀ© -streams 1 -bt 1388155635 -prev_bli_incr 1386595800 -last_bli_full 1386595800 -blks_per_buffer 255 -use_otm -use_ofb -b APPLICATION-NETBACKUP_1388155635 -kl 28 -shm -fso -ifr -pid 1844 -mediasvr serv-media01.central.iamspe.com.br -bt 1388155635 -t 0 -fi -S serv-master-emm.central.iamspe.com.br -fim NONE -ct 40 '

12:47:25.841: [3572.4464] <2> date_debug: DAT - timezone: E. South America Standard Time, offset=10800, dst: E. South America Daylight Time

12:47:25.841: [3572.4464] <2> date_debug: DAT - current time: 1388155645, 27/12/2013 12:47:25

12:47:25.841: [3572.4464] <2> date_debug: DAT - 01/01/94 UCT: 757382400, 31/12/1993 22:00:00

12:47:25.841: [3572.4464] <2> date_debug: DAT - 07/01/94 UCT: 773020800, 30/06/1994 22:00:00

12:47:25.841: [3572.4464] <2> WinMain: DAT - standard input handle = 352

12:47:25.841: [3572.4464] <2> WinMain: DAT - standard input handle is NOT resilient socket.

12:47:25.841: [3572.4464] <2> WinMain: DAT - standard output handle = 400

12:47:25.841: [3572.4464] <2> WinMain: DAT - standard output handle is NOT resilient socket.

12:47:25.841: [3572.4464] <2> WinMain: DAT - standard error handle = 384

12:47:25.841: [3572.4464] <2> WinMain: DAT - standard error handle is NOT resilient socket.

12:47:25.841: [3572.4464] <8> tar_process_backup_args: WRN - invalid option: -block_incr

12:47:25.841: [3572.4464] <8> tar_process_backup_args: WRN - invalid option: -keyword BACKUPÀ VIAÀ À¨LANÀ©

12:47:25.841: [3572.4464] <8> tar_process_backup_args: WRN - invalid option: -streams 1

12:47:25.841: [3572.4464] <8> tar_process_backup_args: WRN - invalid option: -bt 1388155635

12:47:25.841: [3572.4464] <8> tar_process_backup_args: WRN - invalid option: -prev_bli_incr 1386595800

12:47:25.841: [3572.4464] <8> tar_process_backup_args: WRN - invalid option: -last_bli_full 1386595800

12:47:25.841: [3572.4464] <8> tar_process_backup_args: WRN - invalid option: -pid 1844

12:47:25.841: [3572.4464] <8> tar_process_backup_args: WRN - invalid option: -mediasvr serv-media01.central.iamspe.com.br

12:47:25.841: [3572.4464] <8> tar_process_backup_args: WRN - invalid option: -bt 1388155635

12:47:25.841: [3572.4464] <8> tar_process_backup_args: WRN - invalid option: -t 0

12:47:25.841: [3572.4464] <4> tar_backup_tfi::create: INF - change time comparison:<disabled>

12:47:25.841: [3572.4464] <4> tar_backup::V_SetupJobData: INF - dwJobData: ffffffff

12:47:25.841: [3572.4464] <4> tar_backup::V_SetupJobData: INF - dwJob: ffffffff

12:47:25.873: [3572.4464] <4> ncfLogConfiguration: INF - Module: libncf, Release: 7.5.0.5 , Build: 02/09/2013 19:43:10 CST (20130209)

12:47:25.873: [3572.4464] <4> ncfLogConfiguration: INF - Windows version: 6.1.7601, Platform: 2 (Service Pack 1), Service pack: 1.0, Suite: 274, Product type: 3

12:47:25.888: [3572.4464] <4> ncfLogConfiguration: INF - Process architecture: 9, Page size: 4096, Process type: 4, Process level: 8664, Processor revision: 15

12:47:25.904: [3572.4464] <4> NcfString::logLocale: INF - Current code page: WINDOWS: 1252 (ANSI - Latin I), Max. character width: 1, Substitution character: 0x3F [?], Unicode substitution character: 0x003f [?.]

12:47:25.904: [3572.4464] <4> dos_backup::V_PreProcessing: INF - backup privileges enabled, previous = 0

12:47:25.904: [3572.4464] <4> dos_backup::V_PreProcessing: INF - restore privileges enabled, previous = 0

12:47:25.904: [3572.4464] <4> dos_backup::V_PreProcessing: INF - security privileges enabled, previous = 0

12:47:25.904: [3572.4464] <4> dos_backup::V_PreProcessing: INF - tcb privileges enabled, previous = 0

12:47:25.904: [3572.4464] <4> dos_backup::V_PreProcessing: INF - create token privileges enabled, previous = 0

12:47:25.904: [3572.4464] <4> dos_backup::V_PreProcessing: INF - user name: root

12:47:25.904: [3572.4464] <4> dos_backup::V_PreProcessing: INF - no access token: scheduled backup

12:47:25.904: [3572.4464] <2> ov_log::V_GlobalLog: WRN - ubsDetermineExchangeVersion(): RegOpenKeyEx() failed for HKLM\SOFTWARE\MICROSOFT\ExchangeServer\v14\Setup - 0x2.

12:47:25.904: [3572.4464] <2> ov_log::V_GlobalLog: INF - BEDS_Init() Enter InitFlags:0x0

12:47:25.998: [3572.4464] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: C:

12:47:25.998: [3572.4464] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: N:

12:47:25.998: [3572.4464] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Microsoft Terminal Services

12:47:25.998: [3572.4464] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Microsoft Windows Network

12:47:25.998: [3572.4464] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: System?State

12:47:25.998: [3572.4464] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Active Directory Application Mode

12:47:28.576: [3572.4464] <2> tar_base::V_vTarMsgW: INF - BACKUP START 3572

12:47:28.576: [3572.4464] <2> tar_backup::V_SetupProcessContinue: TAR - CONTINUE BACKUP received

12:47:28.576: [3572.4464] <2> tar_base::V_vTarMsgW: INF - CONTINUE BACKUP message received

12:47:28.623: [3572.4464] <2> tar_backup_tfi::setupFileDirectives: TAR - backup filename = FIS_VM_BI_ATTRS_FILE=\online_util\fi_cntl\bpfis.fim.APPLICATION-NETBACKUP_1388155588.1.0.NBU_DATA.xml

12:47:28.623: [3572.4464] <2> tar_backup_tfi::setupFileDirectives: TAR - backup filename = BACKUP \\.\N:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.APPLICATION-NETBACKUP_1388155588.1.0.NBU_DATA.xml USING \\.\C:\ OPTIONS:FITYPE=MIRROR,FSTYPE=NTFS

12:47:28.623: [3572.4920] <4> tar_base::keepaliveThread: INF - keepalive thread is active with an interval of 60 seconds

12:47:29.763: [3572.4464] <2> NBJournalDataCollection::NBJournalDataCollection: INF - volume guid:<\\?\Volume{fcfbd2c9-dda4-11e1-bede-806e6f6e6963}\>

12:47:29.763: [3572.4464] <2> NBJournalDataCollection::NBJournalDataCollection: INF - mounted at:<C:\>:<\\?\Volume{fcfbd2c9-dda4-11e1-bede-806e6f6e6963}\>

12:47:29.763: [3572.4464] <2> NBJournalDataCollection::NBJournalDataCollection: INF - not adding to collection, volume:<\\?\Volume{fcfbd2c9-dda4-11e1-bede-806e6f6e6963}\>:<C:\> is configured to not use the change journal

12:47:29.763: [3572.4464] <2> NBJournalDataCollection::NBJournalDataCollection: INF - volume guid:<\\?\Volume{fcfbd2ca-dda4-11e1-bede-806e6f6e6963}\>

12:47:29.763: [3572.4464] <2> NBJournalDataCollection::NBJournalDataCollection: INF - mounted at:<N:\>:<\\?\Volume{fcfbd2ca-dda4-11e1-bede-806e6f6e6963}\>

12:47:29.763: [3572.4464] <2> NBJournalDataCollection::NBJournalDataCollection: INF - not adding to collection, volume:<\\?\Volume{fcfbd2ca-dda4-11e1-bede-806e6f6e6963}\>:<N:\> is configured to not use the change journal

12:47:29.763: [3572.4464] <4> dos_backup::V_Initialize: INF - created change journal collection

12:47:29.763: [3572.4464] <2> tar_backup_vxbsa::add: INF - called with 'FIS_VM_BI_ATTRS_FILE=\online_util\fi_cntl\bpfis.fim.APPLICATION-NETBACKUP_1388155588.1.0.NBU_DATA.xml'

12:47:29.763: [3572.4464] <2> tar_backup_vxbsa::add: INF - called with 'BACKUP \\.\N:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.APPLICATION-NETBACKUP_1388155588.1.0.NBU_DATA.xml USING \\.\C:\ OPTIONS:FITYPE=MIRROR,FSTYPE=NTFS'

12:47:29.763: [3572.4464] <4> V_Snapshot::V_Snapshot_AddMappings: INF - input parameters:

source path:<\\.\N:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.APPLICATION-NETBACKUP_1388155588.1.0.NBU_DATA.xml>

snap path:<\\.\C:\>

mount path:<(null)>

12:47:29.763: [3572.4464] <4> V_Snapshot::V_GetSourceVolume: INF - input parameters:

source:<\\.\N:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.APPLICATION-NETBACKUP_1388155588.1.0.NBU_DATA.xml>

snapshot:<\\.\C:\>

12:47:29.763: [3572.4464] <4> V_Snapshot::V_Snapshot_AddMappings: INF - Volume Snapshot Mapping: \\?\Volume{fcfbd2ca-dda4-11e1-bede-806e6f6e6963}\ --> C:

12:47:29.763: [3572.4464] <2> _expand_root: INF - checking for root expansion:<\\.\N:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.APPLICATION-NETBACKUP_1388155588.1.0.NBU_DATA.xml>

12:47:29.763: [3572.4464] <2> _fix_registry: INF - checking for registry expansion:<\\.\N:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.APPLICATION-NETBACKUP_1388155588.1.0.NBU_DATA.xml>

12:47:29.763: [3572.4464] <2> _validate_change_journal_use: ERR - change journal not supported for <\\.\N:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.APPLICATION-NETBACKUP_1388155588.1.0.NBU_DATA.xml>, prevent change journal usage

12:47:29.763: [3572.4464] <4> dos_backup::V_VerifyFileList: INF - UBS Local Type for '\\.\N:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.APPLICATION-NETBACKUP_1388155588.1.0.NBU_DATA.xml' --> 00000000

12:47:41.513: [3572.4464] <4> backup_create: INF - NetBackup Temp Directory: 'N:\Program Files\Veritas\\NetBackup\Temp'

12:47:41.513: [3572.4464] <4> tar_backup_tfi::backup_arg_state: INF - remember start error count: 0

12:47:41.513: [3572.4464] <4> tar_backup_tfi::backup_arg_state: INF - remember start fatal count: 0

12:47:41.513: [3572.4464] <4> dos_backup::tfs_scanstart: INF - starting scan for file directive:<\\.\N:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.APPLICATION-NETBACKUP_1388155588.1.0.NBU_DATA.xml>

12:47:41.513: [3572.4464] <4> dos_backup::tfs_scanstart: INF - starting scan using name:<\\.\N:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.APPLICATION-NETBACKUP_1388155588.1.0.NBU_DATA.xml>

12:47:41.513: [3572.4464] <4> dos_backup::tfs_scanstart: INF - in 'backup' mode

12:47:41.513: [3572.4464] <4> dos_backup::tfs_raw_start: INF - checking for: N:\

12:47:41.513: [3572.4464] <4> V_DetermineMountInfo: INF - Checking Volume \\?\Volume{fcfbd2ca-dda4-11e1-bede-806e6f6e6963}\ for '\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.APPLICATION-NETBACKUP_1388155588.1.0.NBU_DATA.xml\'

12:47:41.513: [3572.4464] <4> V_DetermineMountInfo: INF - Adding NTFS Volume \\.\N: ==> \\?\Volume{fcfbd2ca-dda4-11e1-bede-806e6f6e6963}\

12:47:41.513: [3572.4464] <4> dos_backup::tfs_raw_start: INF - RAW: \\.\

12:47:41.513: [3572.4464] <4> dos_backup::tfs_raw_start: INF - RAW: N:

12:47:41.513: [3572.4464] <4> tar_backup_tfi::flash_start_state: FlashBackup of Raw partition

12:47:41.529: [3572.4464] <4> tar_backup_tfi::flash_start_state: FlashBackup of Raw partition isFlashBackup 0

12:47:41.529: [3572.4464] <4> tar_backup_tfi::flash_start_state: FlashBackup of virtual machine with file N:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.APPLICATION-NETBACKUP_1388155588.1.0.NBU_DATA.xml

12:47:41.560: [3572.4464] <4> ncfLogConfiguration: INF - Module: libncf, Release: 7.5.0.5 , Build: 02/09/2013 19:43:10 CST (20130209)

12:47:41.576: [3572.4464] <4> ncfLogConfiguration: INF - Windows version: 6.1.7601, Platform: 2 (Service Pack 1), Service pack: 1.0, Suite: 274, Product type: 3

12:47:41.592: [3572.4464] <4> ncfLogConfiguration: INF - Process architecture: 9, Page size: 4096, Process type: 4, Process level: 8664, Processor revision: 15

12:47:41.592: [3572.4464] <4> NcfString::logLocale: INF - Current code page: WINDOWS: 1252 (ANSI - Latin I), Max. character width: 1, Substitution character: 0x3F [?], Unicode substitution character: 0x003f [?.]

12:47:41.592: [3572.4464] <4> tar_backup::backup_done_state: INF - number of file directives not found: 0

12:47:41.592: [3572.4464] <4> tar_backup::backup_done_state: INF - number of file directives found: 1

12:47:41.592: [3572.4920] <4> tar_base::keepaliveThread: INF - keepalive thread terminating (reason: WAIT_OBJECT_0)

12:47:41.592: [3572.4464] <4> tar_base::stopKeepaliveThread: INF - keepalive thread has exited. (reason: WAIT_OBJECT_0)

12:47:41.592: [3572.4464] <4> send_msg_to_monitor: INF - in send_msg_to_monitor()...

12:47:41.592: [3572.4464] <2> tar_base::V_vTarMsgW: INF - EXIT STATUS 11: system call failed

12:47:41.592: [3572.4464] <4> tar_backup::backup_done_state: INF - Not waiting for server status

12:47:41.592: [3572.4464] <4> dos_backup::tfs_reset: INF - Snapshot deletion start

12:47:41.592: [3572.4464] <4> OVStopCmd: INF - EXIT - status = 0

12:47:41.592: [3572.4464] <2> tar_base::V_Close: closing...

12:47:41.592: [3572.4464] <4> dos_backup::tfs_reset: INF - Snapshot deletion start

12:47:41.592: [3572.4464] <2> ov_log::V_GlobalLog: INF - BEDS_Term(): enter - InitFlags:0x00000101

12:47:41.592: [3572.4464] <2> ov_log::V_GlobalLog: INF - BEDS_Term(): ubs specifics: 0x001d0000

12:47:41.638: [3572.4464] <16> dtcp_read: TCP - failure: recv socket (352) (TCP 10053: Software caused connection abort)

12:47:42.638: [3572.4464] <16> dtcp_read: TCP - failure: recv socket (352) (TCP 10053: Software caused connection abort)

12:47:43.638: [3572.4464] <16> dtcp_read: TCP - failure: recv socket (352) (TCP 10053: Software caused connection abort)

12:47:44.638: [3572.4464] <16> dtcp_read: TCP - failure: recv socket (352) (TCP 10053: Software caused connection abort)

12:47:44.638: [3572.4464] <4> OVShutdown: INF - Finished process

12:47:44.654: [3572.4464] <4> WinMain: INF - Exiting N:\Program Files\Veritas\NetBackup\bin\bpbkar32.exe

12:47:46.654: [3572.4464] <4> ov_log::OVClose: INF - Closing log file: N:\Program Files\Veritas\NetBackup\logs\BPBKAR\122713.LOG

RamNagalla · ‎12-27-2013

There are Mulitple Tech note on this.. please check if they are related...

http://www.symantec.com/business/support/index?page=content&id=TECH168372

http://www.symantec.com/business/support/index?page=content&id=TECH73597

Anderson_Santos · ‎12-27-2013

ok I'll check

Anderson_Santos · ‎01-02-2014

log 31/12/2013

00:00:36.116 [3752.4180] <2> nbjm_media_request: Passing job control to NBJM, type WRITE/1

00:00:36.116 [3752.4180] <2> nbjm_media_request: old_media_id = , media_id = NULL

00:00:36.116 [3752.4180] <2> Orb::init: Enabling ORBNativeCharCodeSet UTF-8(Orb.cpp:594)

00:00:36.116 [3752.4180] <2> Orb::init: Created anon service name: NB_3752_851260420(Orb.cpp:697)

00:00:36.116 [3752.4180] <2> Orb::init: endpointvalue is : pbxiop://1556:NB_3752_851260420(Orb.cpp:714)

00:00:36.116 [3752.4180] <2> Orb::init: initializing ORB Default_DAEMON_Orb with: Unknown -ORBSvcConfDirective "-ORBDottedDecimalAddresses 0" -ORBSvcConfDirective "static Resource_Factory '-ORBNativeCharCodeSet UTF-8'" -ORBSvcConfDirective "static PBXIOP_Factory '-enable_keepalive'" -ORBSvcConfDirective "static EndpointSelectorFactory ''" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory PBXIOP_Factory'" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory IIOP_Factory'" -ORBDefaultInitRef '' -ORBSvcConfDirective "static PBXIOP_Evaluator_Factory '-orb Default_DAEMON_Orb'" -ORBSvcConfDirective "static Resource_Factory '-ORBConnectionCacheMax 1024 '" -ORBEndpoint pbxiop://1556:NB_3752_851260420 -ORBSvcConf nul -ORBSvcConfDirective "static Server_Strategy_Factory '-ORBMaxRecvGIOPPayloadSize 268435456'"(Orb.cpp:825)

00:00:36.116 [3752.4180] <2> Orb::init: caching EndpointSelectorFactory(Orb.cpp:840)

00:00:36.147 [3752.4180] <2> RequestInitialResources: returning

00:00:36.147 [3752.4180] <2> parse_resource_strings: MEDIADB 1 20908 O518L4 4000079 ------ 6 1385343043 1388454249 1390268649 1387626744 2693178560 1998 1906 10 4 3 0 1024 0 21045084 0

00:00:36.147 [3752.4180] <2> parse_resource_strings: Parsed message type 15, version 1, 21 parameters

00:00:36.147 [3752.4180] <2> parse_resource_strings: VOLUME 1 O518L4 4000079 MFO518L4 21dias HP 2150897368 6 8 0 29 0 {00000000-0000-0000-0000-000000000000} 0

00:00:36.147 [3752.4180] <2> parse_resource_strings: Parsed message type 16, version 1, 14 parameters

00:00:36.147 [3752.4180] <2> parse_resource_strings: DRIVE 3 HP.ULTRIUM4-SCSI.001 2000006 HU1152L8MB {5,0,0,0} -1 -1 -1 -1 -1 -1 -1 -1 *NULL* *NULL* *NULL* *NULL* 1 0 0 1 0 0

00:00:36.147 [3752.4180] <2> parse_resource_strings: Parsed message type 17, version 3, 23 parameters

00:00:36.147 [3752.4180] <2> parse_resource_strings: STORAGE 1 serv-media01-hcart-robot-tld-0 6 1048576 2 1 0 0 serv-media01.central.iamspe.com.br serv-media01.central.iamspe.com.br *NULL*

00:00:36.147 [3752.4180] <2> parse_resource_strings: Parsed message type 18, version 1, 11 parameters

00:00:36.147 [3752.4180] <2> parse_resource_strings: DISKGROUP 0 6 *NULL* 6 *NULL* 6 *NULL*

00:00:36.147 [3752.4180] <2> parse_resource_strings: Parsed message type 21, version 0, 7 parameters

00:00:36.147 [3752.4180] <2> parse_resource_strings: DISKVOLUME 0 6 *NULL* 6 *NULL* 0

00:00:36.147 [3752.4180] <2> parse_resource_strings: Parsed message type 22, version 0, 6 parameters

00:00:36.147 [3752.4180] <2> parse_resource_strings: DISKMOUNTPOINT 0 6 *NULL*

00:00:36.147 [3752.4180] <2> parse_resource_strings: Parsed message type 23, version 0, 3 parameters

00:00:36.147 [3752.4180] <2> nbjm_media_request: Job control returned to BPTM

00:00:36.147 [3752.4180] <2> io_init: bpbrm_handle = 1036, buffer address = 0x610000

00:00:36.147 [3752.4180] <2> io_init: found index: 0

00:00:36.147 [3752.4180] <2> read_config_file: using 131072 value from N:\Program Files\Veritas\NetBackup\db\config\SIZE_DATA_BUFFERS

00:00:36.147 [3752.4180] <2> io_init: using 131072 data buffer size

00:00:36.147 [3752.4180] <2> ConnectionCache::connectAndCache: Acquiring new connection for host serv-master-emm.central.iamspe.com.br, query type 223

00:00:36.163 [3752.4180] <2> vnet_pbxConnect: pbxConnectEx Succeeded

00:00:36.163 [3752.4180] <2> logconnections: BPDBM CONNECT FROM 10.146.20.19.52837 TO 10.146.20.20.1556 fd = 1032

00:00:36.163 [3752.4180] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2

00:00:36.225 [3752.4180] <2> db_CLIENTsend: reset client protocol version from 0 to 8

00:00:36.444 [3752.4180] <2> db_getCLIENT: db_CLIENTreceive: no entity was found 227

00:00:36.507 [3752.4180] <2> vnet_pbxConnect: pbxConnectEx Succeeded

00:00:36.507 [3752.4180] <2> logconnections: BPCD CONNECT FROM 10.146.20.19.52838 TO 10.146.19.111.1556 fd = 1060

00:00:36.522 [3752.4180] <2> vnet_pbxConnect: pbxConnectEx Succeeded

RamNagalla · ‎01-02-2014

where did you get this log?

did you check the T/N that are porvided above?

Anderson_Santos · ‎01-02-2014

register this is mediaserver the folder bptm, yes i realized but keeps failing

RamNagalla · ‎01-02-2014

the above log does not have specific error

please attach the bpbkar , bpfis & bpvmutil logs from the backup host for the failed job along with the detail status.

Anderson_Santos · ‎01-03-2014

bpbkar log mediaserver backup host:

11:18:41.689: [892.2436] <4> ov_log::OVInit: INF - Starting log file: N:\Program Files\Veritas\NetBackup\logs\BPBKAR\010314.LOG

11:18:41.689: [892.2436] <4> ov_log::OVInit: GENERAL Log Level: 0

11:18:41.689: [892.2436] <4> ov_log::OVInit: TCP Log Level: 0

11:18:41.689: [892.2436] <4> ov_log::OVInit: INF - the log mutex: 384

BPBKAR NetBackup Backup/Archive 7.5GA [Jun 5 2012]

11:18:41.721: [892.2436] <2> WinMain: DAT - _pgmptr = 'N:\Program Files\Veritas\NetBackup\bin\bpbkar32.exe'

11:18:41.721: [892.2436] <2> WinMain: DAT - lpCmdLine = '-r 2678400 -ru root -dt 0 -to 0 -bpstart_time 0 -clnt APPLICATION-TFS -class VMWARE-ESXi-03 -sched Semanal -st FULL -bpstart_to 300 -bpend_to 300 -read_to 1200 -block_incr -keyword BACKUPÀ VIAÀ À¨LANÀ© -streams 1 -bt 1388755109 -prev_bli_incr 1387016059 -last_bli_full 1387016059 -blks_per_buffer 255 -use_otm -use_ofb -b APPLICATION-TFS_1388755109 -kl 28 -shm -fso -ifr -pid 3264 -mediasvr serv-media01.central.iamspe.com.br -bt 1388755109 -t 0 -fi -S serv-master-emm.central.iamspe.com.br -fim NONE -ct 40 '

11:18:41.721: [892.2436] <2> date_debug: DAT - timezone: E. South America Standard Time, offset=10800, dst: E. South America Daylight Time

11:18:41.721: [892.2436] <2> date_debug: DAT - current time: 1388755121, 03/01/2014 11:18:41

11:18:41.721: [892.2436] <2> date_debug: DAT - 01/01/94 UCT: 757382400, 31/12/1993 22:00:00

11:18:41.721: [892.2436] <2> date_debug: DAT - 07/01/94 UCT: 773020800, 30/06/1994 22:00:00

11:18:41.721: [892.2436] <2> WinMain: DAT - standard input handle = 464

11:18:41.721: [892.2436] <2> WinMain: DAT - standard input handle is NOT resilient socket.

11:18:41.721: [892.2436] <2> WinMain: DAT - standard output handle = 396

11:18:41.736: [892.2436] <2> WinMain: DAT - standard output handle is NOT resilient socket.

11:18:41.736: [892.2436] <2> WinMain: DAT - standard error handle = 380

11:18:41.736: [892.2436] <2> WinMain: DAT - standard error handle is NOT resilient socket.

11:18:41.736: [892.2436] <8> tar_process_backup_args: WRN - invalid option: -block_incr

11:18:41.736: [892.2436] <8> tar_process_backup_args: WRN - invalid option: -keyword BACKUPÀ VIAÀ À¨LANÀ©

11:18:41.736: [892.2436] <8> tar_process_backup_args: WRN - invalid option: -streams 1

11:18:41.736: [892.2436] <8> tar_process_backup_args: WRN - invalid option: -bt 1388755109

11:18:41.736: [892.2436] <8> tar_process_backup_args: WRN - invalid option: -prev_bli_incr 1387016059

11:18:41.736: [892.2436] <8> tar_process_backup_args: WRN - invalid option: -last_bli_full 1387016059

11:18:41.736: [892.2436] <8> tar_process_backup_args: WRN - invalid option: -pid 3264

11:18:41.736: [892.2436] <8> tar_process_backup_args: WRN - invalid option: -mediasvr serv-media01.central.iamspe.com.br

11:18:41.736: [892.2436] <8> tar_process_backup_args: WRN - invalid option: -bt 1388755109

11:18:41.736: [892.2436] <8> tar_process_backup_args: WRN - invalid option: -t 0

11:18:41.736: [892.2436] <4> tar_backup_tfi::create: INF - change time comparison:<disabled>

11:18:41.736: [892.2436] <4> tar_backup::V_SetupJobData: INF - dwJobData: ffffffff

11:18:41.736: [892.2436] <4> tar_backup::V_SetupJobData: INF - dwJob: ffffffff

11:18:41.752: [892.2436] <4> ncfLogConfiguration: INF - Module: libncf, Release: 7.5.0.3 , Build: 06/05/2012 20:00:00 CDT (20120605)

11:18:41.768: [892.2436] <4> ncfLogConfiguration: INF - Windows version: 6.1.7601, Platform: 2 (Service Pack 1), Service pack: 1.0, Suite: 274, Product type: 3

11:18:41.783: [892.2436] <4> ncfLogConfiguration: INF - Process architecture: 9, Page size: 4096, Process type: 4, Process level: 8664, Processor revision: 15

11:18:41.783: [892.2436] <4> NcfString::logLocale: INF - Current code page: WINDOWS: 1252 (ANSI - Latin I), Max. character width: 1, Substitution character: 0x3F [?], Unicode substitution character: 0x003f [?.]

11:18:41.783: [892.2436] <4> dos_backup::V_PreProcessing: INF - backup privileges enabled, previous = 0

11:18:41.783: [892.2436] <4> dos_backup::V_PreProcessing: INF - restore privileges enabled, previous = 0

11:18:41.783: [892.2436] <4> dos_backup::V_PreProcessing: INF - security privileges enabled, previous = 0

11:18:41.783: [892.2436] <4> dos_backup::V_PreProcessing: INF - tcb privileges enabled, previous = 0

11:18:41.783: [892.2436] <4> dos_backup::V_PreProcessing: INF - create token privileges enabled, previous = 0

11:18:41.783: [892.2436] <4> dos_backup::V_PreProcessing: INF - user name: root

11:18:41.783: [892.2436] <4> dos_backup::V_PreProcessing: INF - no access token: scheduled backup

11:18:41.783: [892.2436] <2> ov_log::V_GlobalLog: WRN - ubsDetermineExchangeVersion(): RegOpenKeyEx() failed for HKLM\SOFTWARE\MICROSOFT\ExchangeServer\v14\Setup - 0x2.

11:18:41.783: [892.2436] <2> ov_log::V_GlobalLog: INF - BEDS_Init() Enter InitFlags:0x0

11:18:41.924: [892.2436] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: C:

11:18:41.924: [892.2436] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: N:

11:18:41.924: [892.2436] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Microsoft Terminal Services

11:18:41.924: [892.2436] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Microsoft Windows Network

11:18:41.924: [892.2436] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: System?State

11:18:41.924: [892.2436] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Active Directory Application Mode

11:18:48.689: [892.2436] <2> tar_base::V_vTarMsgW: INF - BACKUP START 892

11:18:48.689: [892.2436] <2> tar_backup::V_SetupProcessContinue: TAR - CONTINUE BACKUP received

11:18:48.689: [892.2436] <2> tar_base::V_vTarMsgW: INF - CONTINUE BACKUP message received

11:18:48.736: [892.2436] <2> tar_backup_tfi::setupFileDirectives: TAR - backup filename = FIS_VM_BI_ATTRS_FILE=\online_util\fi_cntl\bpfis.fim.APPLICATION-TFS_1388755041.1.0.NBU_DATA.xml

11:18:48.736: [892.2436] <2> tar_backup_tfi::setupFileDirectives: TAR - backup filename = BACKUP \\.\N:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.APPLICATION-TFS_1388755041.1.0.NBU_DATA.xml USING \\.\C:\ OPTIONS:FITYPE=MIRROR,FSTYPE=NTFS

11:18:48.736: [892.1888] <4> tar_base::keepaliveThread: INF - keepalive thread is active with an interval of 60 seconds

11:18:49.877: [892.2436] <2> NBJournalDataCollection::NBJournalDataCollection: INF - volume guid:<\\?\Volume{fcfbd2c9-dda4-11e1-bede-806e6f6e6963}\>

11:18:49.877: [892.2436] <2> NBJournalDataCollection::NBJournalDataCollection: INF - mounted at:<C:\>:<\\?\Volume{fcfbd2c9-dda4-11e1-bede-806e6f6e6963}\>

11:18:49.877: [892.2436] <2> NBJournalDataCollection::NBJournalDataCollection: INF - not adding to collection, volume:<\\?\Volume{fcfbd2c9-dda4-11e1-bede-806e6f6e6963}\>:<C:\> is configured to not use the change journal

11:18:49.877: [892.2436] <2> NBJournalDataCollection::NBJournalDataCollection: INF - volume guid:<\\?\Volume{fcfbd2ca-dda4-11e1-bede-806e6f6e6963}\>

11:18:49.877: [892.2436] <2> NBJournalDataCollection::NBJournalDataCollection: INF - mounted at:<N:\>:<\\?\Volume{fcfbd2ca-dda4-11e1-bede-806e6f6e6963}\>

11:18:49.877: [892.2436] <2> NBJournalDataCollection::NBJournalDataCollection: INF - not adding to collection, volume:<\\?\Volume{fcfbd2ca-dda4-11e1-bede-806e6f6e6963}\>:<N:\> is configured to not use the change journal

11:18:49.877: [892.2436] <4> dos_backup::V_Initialize: INF - created change journal collection

11:18:49.877: [892.2436] <2> tar_backup_vxbsa::add: INF - called with 'FIS_VM_BI_ATTRS_FILE=\online_util\fi_cntl\bpfis.fim.APPLICATION-TFS_1388755041.1.0.NBU_DATA.xml'

11:18:49.877: [892.2436] <2> tar_backup_vxbsa::add: INF - called with 'BACKUP \\.\N:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.APPLICATION-TFS_1388755041.1.0.NBU_DATA.xml USING \\.\C:\ OPTIONS:FITYPE=MIRROR,FSTYPE=NTFS'

11:18:49.877: [892.2436] <4> V_Snapshot::V_Snapshot_AddMappings: INF - input parameters:

source path:<\\.\N:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.APPLICATION-TFS_1388755041.1.0.NBU_DATA.xml>

snap path:<\\.\C:\>

mount path:<(null)>

11:18:49.877: [892.2436] <4> V_Snapshot::V_GetSourceVolume: INF - input parameters:

source:<\\.\N:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.APPLICATION-TFS_1388755041.1.0.NBU_DATA.xml>

snapshot:<\\.\C:\>

11:18:49.877: [892.2436] <4> V_Snapshot::V_Snapshot_AddMappings: INF - Volume Snapshot Mapping: \\?\Volume{fcfbd2ca-dda4-11e1-bede-806e6f6e6963}\ --> C:

11:18:49.877: [892.2436] <2> _expand_root: INF - checking for root expansion:<\\.\N:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.APPLICATION-TFS_1388755041.1.0.NBU_DATA.xml>

11:18:49.877: [892.2436] <2> _fix_registry: INF - checking for registry expansion:<\\.\N:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.APPLICATION-TFS_1388755041.1.0.NBU_DATA.xml>

11:18:49.877: [892.2436] <2> _validate_change_journal_use: ERR - change journal not supported for <\\.\N:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.APPLICATION-TFS_1388755041.1.0.NBU_DATA.xml>, prevent change journal usage

11:18:49.877: [892.2436] <4> dos_backup::V_VerifyFileList: INF - UBS Local Type for '\\.\N:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.APPLICATION-TFS_1388755041.1.0.NBU_DATA.xml' --> 00000000

11:18:53.111: [892.2436] <4> backup_create: INF - NetBackup Temp Directory: 'N:\Program Files\Veritas\\NetBackup\Temp'

11:18:53.111: [892.2436] <4> tar_backup_tfi::backup_arg_state: INF - remember start error count: 0

11:18:53.111: [892.2436] <4> tar_backup_tfi::backup_arg_state: INF - remember start fatal count: 0

11:18:53.111: [892.2436] <4> dos_backup::tfs_scanstart: INF - starting scan for file directive:<\\.\N:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.APPLICATION-TFS_1388755041.1.0.NBU_DATA.xml>

11:18:53.111: [892.2436] <4> dos_backup::tfs_scanstart: INF - starting scan using name:<\\.\N:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.APPLICATION-TFS_1388755041.1.0.NBU_DATA.xml>

11:18:53.111: [892.2436] <4> dos_backup::tfs_scanstart: INF - in 'backup' mode

11:18:53.111: [892.2436] <4> dos_backup::tfs_raw_start: INF - checking for: N:\

11:18:53.111: [892.2436] <4> V_DetermineMountInfo: INF - Checking Volume \\?\Volume{fcfbd2ca-dda4-11e1-bede-806e6f6e6963}\ for '\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.APPLICATION-TFS_1388755041.1.0.NBU_DATA.xml\'

11:18:53.111: [892.2436] <4> V_DetermineMountInfo: INF - Adding NTFS Volume \\.\N: ==> \\?\Volume{fcfbd2ca-dda4-11e1-bede-806e6f6e6963}\

11:18:53.111: [892.2436] <4> dos_backup::tfs_raw_start: INF - RAW: \\.\

11:18:53.111: [892.2436] <4> dos_backup::tfs_raw_start: INF - RAW: N:

11:18:53.111: [892.2436] <4> tar_backup_tfi::flash_start_state: FlashBackup of Raw partition

11:18:53.127: [892.2436] <4> tar_backup_tfi::flash_start_state: FlashBackup of Raw partition isFlashBackup 0

11:18:53.158: [892.2436] <4> tar_backup_tfi::flash_start_state: FlashBackup of virtual machine with file N:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.APPLICATION-TFS_1388755041.1.0.NBU_DATA.xml

11:18:53.174: [892.2436] <4> ncfLogConfiguration: INF - Module: libncf, Release: 7.5.0.3 , Build: 06/05/2012 20:00:00 CDT (20120605)

11:18:53.189: [892.2436] <4> ncfLogConfiguration: INF - Windows version: 6.1.7601, Platform: 2 (Service Pack 1), Service pack: 1.0, Suite: 274, Product type: 3

11:18:53.205: [892.2436] <4> ncfLogConfiguration: INF - Process architecture: 9, Page size: 4096, Process type: 4, Process level: 8664, Processor revision: 15

11:18:53.205: [892.2436] <4> NcfString::logLocale: INF - Current code page: WINDOWS: 1252 (ANSI - Latin I), Max. character width: 1, Substitution character: 0x3F [?], Unicode substitution character: 0x003f [?.]

11:18:53.205: [892.2436] <4> tar_backup::backup_done_state: INF - number of file directives not found: 0

11:18:53.205: [892.2436] <4> tar_backup::backup_done_state: INF - number of file directives found: 1

11:18:53.205: [892.1888] <4> tar_base::keepaliveThread: INF - keepalive thread terminating (reason: WAIT_OBJECT_0)

11:18:53.205: [892.2436] <4> tar_base::stopKeepaliveThread: INF - keepalive thread has exited. (reason: WAIT_OBJECT_0)

11:18:53.205: [892.2436] <4> send_msg_to_monitor: INF - in send_msg_to_monitor()...

11:18:53.205: [892.2436] <2> tar_base::V_vTarMsgW: INF - EXIT STATUS 11: system call failed

11:18:53.205: [892.2436] <4> tar_backup::backup_done_state: INF - Not waiting for server status

11:18:53.205: [892.2436] <4> dos_backup::tfs_reset: INF - Snapshot deletion start

11:18:53.205: [892.2436] <4> OVStopCmd: INF - EXIT - status = 0

11:18:53.205: [892.2436] <2> tar_base::V_Close: closing...

11:18:53.205: [892.2436] <4> dos_backup::tfs_reset: INF - Snapshot deletion start

11:18:53.205: [892.2436] <2> ov_log::V_GlobalLog: INF - BEDS_Term(): enter - InitFlags:0x00000101

11:18:53.205: [892.2436] <2> ov_log::V_GlobalLog: INF - BEDS_Term(): ubs specifics: 0x001d0000

11:18:53.252: [892.2436] <16> dtcp_read: TCP - failure: recv socket (464) (TCP 10053: Software caused connection abort)

11:18:54.252: [892.2436] <16> dtcp_read: TCP - failure: recv socket (464) (TCP 10053: Software caused connection abort)

11:18:55.252: [892.2436] <16> dtcp_read: TCP - failure: recv socket (464) (TCP 10053: Software caused connection abort)

11:18:56.252: [892.2436] <16> dtcp_read: TCP - failure: recv socket (464) (TCP 10053: Software caused connection abort)

11:18:57.252: [892.2436] <16> dtcp_read: TCP - failure: recv socket (464) (TCP 10053: Software caused connection abort)

11:18:57.252: [892.2436] <4> OVShutdown: INF - Finished process

11:18:57.252: [892.2436] <4> WinMain: INF - Exiting N:\Program Files\Veritas\NetBackup\bin\bpbkar32.exe

11:18:59.252: [892.2436] <4> ov_log::OVClose: INF - Closing log file: N:\Program Files\Veritas\NetBackup\logs\BPBKAR\010314.LOG

bpbrm log mediaserver backup host:

11:17:23.627 [3516.3604] <2> bpbrm main: INITIATING (VERBOSE = 0): version NetBackup 7.5 0

11:17:23.627 [3516.3604] <2> logparams: -backup -backup_copy 0 -b APPLICATION-TFS_1388755041 -bt 1388755041 -c APPLICATION-TFS -cl VMWARE-ESXi-03 -ct 40 -dt 0 -fso -jobid 119741 -jobgrpid 119741 -masterversion 750000 -mediasvr serv-media01.central.iamspe.com.br -mpx 0 -nbu -p NONE -ru root -rg other -sched Semanal -st 0 -S serv-master-emm.central.iamspe.com.br -stream_count 2 -stream_number 1 -snaponly -secure 1 -vm_proxy serv-media01.central.iamspe.com.br -block_incr -prev_bli_incr 1387016059 -last_bli_full 1387016059 -use_ofb -use_otm -fi -fim VMware_v2:Virtual_machine_backup=2,nameuse=1,snapact=2,file_system_optimization=1,trantype=nbd:san,exclude_swap=1,disable_quiesce=0,drive_selection=0,skipnodisk=0,post_events=1 -vm_proxy serv-media01.central.iamspe.com.br -nbu

11:17:23.627 [3516.3604] <2> bpbrm main: DBG - Snapshot method [176] - VMware_v2:Virtual_machine_backup=2,nameuse=1,snapact=2,file_system_optimization=1,trantype=nbd:san,exclude_swap=1,disable_quiesce=0,drive_selection=0,skipnodisk=0,post_events=1

11:17:23.627 [3516.3604] <2> vnet_pbxConnect: pbxConnectEx Succeeded

11:17:23.627 [3516.3604] <2> logconnections: BPRD CONNECT FROM 10.146.20.19.49927 TO 10.146.20.20.1556 fd = 492

11:17:23.627 [3516.3604] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2

11:17:23.799 [3516.3604] <2> brm_update_local_resiliency: changed = 0

11:17:23.799 [3516.3604] <2> ConnectionCache::connectAndCache: Acquiring new connection for host serv-master-emm.central.iamspe.com.br, query type 223

11:17:23.799 [3516.3604] <2> vnet_pbxConnect: pbxConnectEx Succeeded

11:17:23.814 [3516.3604] <2> logconnections: BPDBM CONNECT FROM 10.146.20.19.49928 TO 10.146.20.20.1556 fd = 492

11:17:23.814 [3516.3604] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2

11:17:23.877 [3516.3604] <2> db_CLIENTsend: reset client protocol version from 0 to 8

11:17:24.080 [3516.3604] <2> db_getCLIENT: db_CLIENTreceive: no entity was found 227

11:17:24.096 [3516.3604] <2> logconnections: BPCD CONNECT FROM 10.146.20.19.49929 TO 10.146.20.19.13782 fd = 496

11:17:24.127 [3516.3604] <2> local_getHostInfo: CLIENT_CMD_SOCK from bpcr = 496

11:17:24.127 [3516.3604] <2> bpcr_get_version_rqst: bpcd version: 07500003

11:17:24.158 [3516.3604] <2> local_getHostInfo: hostname comparison failed

11:17:24.158 [3516.3604] <2> bpcr_get_version_rqst: bpcd version: 07500003

11:17:24.158 [3516.3604] <2> local_getHostInfo: rqstType == BPCD_GET_NB_VERSION_RQST

11:17:24.158 [3516.3604] <2> bpcr_get_platform_rqst: Server platform length = 7

11:17:24.158 [3516.3604] <2> xlatePlatformName: my version = 750000, client version = 750000, check current list first

11:17:24.158 [3516.3604] <2> xlatePlatformName: BUILD_PLATFORM name to xlate = win_x64

11:17:24.158 [3516.3604] <2> local_getHostInfo: platform of client serv-media01.central.iamspe.com.br is PC-x64, WindowsXP

11:17:24.158 [3516.3604] <2> bpcr_get_uname_rqst: bpcr.c: 3662 count=28, buffer=UNAME_SYSNAME = Windows2008

11:17:24.158 [3516.3604] <2> bpcr_get_uname_rqst: bpcr.c: 3662 count=30, buffer=UNAME_NODENAME = SERV-MEDIA01

11:17:24.158 [3516.3604] <2> bpcr_get_uname_rqst: bpcr.c: 3662 count=18, buffer=UNAME_RELEASE = 6

11:17:24.158 [3516.3604] <2> bpcr_get_uname_rqst: bpcr.c: 3662 count=18, buffer=UNAME_VERSION = 1

11:17:24.158 [3516.3604] <2> bpcr_get_uname_rqst: bpcr.c: 3662 count=51, buffer=UNAME_MACHINE = Unknown - Unknown - Revision x40a

11:17:24.158 [3516.3604] <2> bpcr_get_uname_rqst: bpcr.c: 3662 count=3, buffer=END

11:17:24.158 [3516.3604] <2> local_getHostInfo: d:\nb\nb_7.5.0.3\src\nb\dblib\dbmisc.c: 1728, uname -sr=Windows2008 6

11:17:24.158 [3516.3604] <2> bpcr_get_version_rqst: bpcd version: 07500003

11:17:24.158 [3516.3604] <2> getPatchLevel: CLIENT_CMD_SOCK from bpcr = 496

11:17:24.158 [3516.3604] <2> getPatchLevel: got client socket

11:17:24.158 [3516.3604] <2> getPatchLevel: file num = 3, file size = 113

11:17:24.158 [3516.3604] <2> bpcr_read_rqst: completed send of file number 3

11:17:24.158 [3516.3604] <2> bpcr_read_rqst: completed send of transfer_count = 16383

11:17:24.158 [3516.3604] <2> bpcr_read_rqst: Before readclientstatus on socket 504

11:17:24.158 [3516.3604] <2> bpcr_read_rqst: Num bytes to be sent by client = 113

11:17:24.158 [3516.3604] <2> getPatchLevel: total chars read = 113

11:17:24.158 [3516.3604] <2> getPatchLevel: level = >7.5.0.3<

11:17:24.158 [3516.3604] <2> ConnectionCache::connectAndCache: Acquiring new connection for host serv-master-emm.central.iamspe.com.br, query type 223

11:17:24.158 [3516.3604] <2> vnet_pbxConnect: pbxConnectEx Succeeded

11:17:24.158 [3516.3604] <2> logconnections: BPDBM CONNECT FROM 10.146.20.19.49931 TO 10.146.20.20.1556 fd = 504

11:17:24.158 [3516.3604] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2

11:17:24.236 [3516.3604] <2> db_CLIENTsend: reset client protocol version from 0 to 8

11:17:24.439 [3516.3604] <2> db_getCLIENT: db_CLIENTreceive: no entity was found 227

11:17:24.439 [3516.3604] <2> ConnectionCache::connectAndCache: Acquiring new connection for host serv-master-emm.central.iamspe.com.br, query type 223

11:17:24.455 [3516.3604] <2> vnet_pbxConnect: pbxConnectEx Succeeded

11:17:24.455 [3516.3604] <2> logconnections: BPDBM CONNECT FROM 10.146.20.19.49932 TO 10.146.20.20.1556 fd = 492

11:17:24.455 [3516.3604] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2

11:17:24.518 [3516.3604] <2> db_CLIENTsend: reset client protocol version from 0 to 8

11:17:24.721 [3516.3604] <2> db_getCLIENT: db_CLIENTreceive: no entity was found 227

11:17:24.721 [3516.3604] <2> verify_client: bpbrm.c.37835: db_getCLIENT failed for CLIENT_HOSTNAME: serv-media01.central.iamspe.com.br

11:17:24.721 [3516.3604] <2> verify_client: bpbrm.c.37980: db_getCLIENT failed: 227 227 0x000000e3

11:17:24.721 [3516.3604] <2> local_getHostInfo: found TSS cached host serv-media01.central.iamspe.com.br type 67

11:17:24.721 [3516.3604] <2> bpbrm create_events: created suspend event name Global\NetBackup Suspend Event, pid: 3516

11:17:24.721 [3516.3604] <2> read_config_file: using 131072 value from N:\Program Files\Veritas\NetBackup\db\config\SIZE_DATA_BUFFERS

11:17:24.736 [3516.3604] <2> vnet_pbxConnect: pbxConnectEx Succeeded

11:17:24.736 [3516.3604] <2> job_connect: SO_KEEPALIVE set on socket 528 for client serv-master-emm.central.iamspe.com.br

11:17:24.736 [3516.3604] <2> logconnections: BPJOBD CONNECT FROM 10.146.20.19.49933 TO 10.146.20.20.1556 fd = 528

11:17:24.736 [3516.3604] <2> job_authenticate_connection: ignoring VxSS authentication check for now...

11:17:24.736 [3516.3604] <2> job_connect: Connected to the host serv-master-emm.central.iamspe.com.br contype 53 jobid <119741> socket <528>

11:17:24.736 [3516.3604] <2> job_connect: Connected on port 49933

11:17:24.736 [3516.3604] <4> bpfis_start: Processing snapshot create request...

11:17:24.736 [3516.3604] <4> vmware_blib_bpfis_state_cleanup: bpfis state files cleanup: "N:\Program Files\Veritas\NetBackup\bin\admincmd\bppficorr" -client serv-master-emm.central.iamspe.com.br -proxy_client serv-media01.central.iamspe.com.br -vm_client APPLICATION-TFS -policy VMWARE-ESXi-03 -vm_bli_cleanup <NUL >NUL 2>NUL

11:17:26.986 [3516.3604] <2> bpfis_start: DBG: BPFIS Commands [608] - /usr/openv/netbackup/bin/bpfis bpfis create -nbu -owner NBU -id APPLICATION-TFS_1388755041 -bpstart_to 300 -bpend_to 300 -backup_copy 0 -ru root -pt 40 -vm_type 2 -vm_client APPLICATION-TFS -clnt serv-media01.central.iamspe.com.br -st FULL -block_incr -prev_bli_incr 1387016059 -last_bli_full 1387016059 -dt 0 -rg other -fim VMware_v2:Virtual_machine_backup=2,nameuse=1,snapact=2,file_system_optimization=1,trantype=nbd:san,exclude_swap=1,disable_quiesce=0,drive_selection=0,skipnodisk=0,post_events=1,nameuse=1 -class VMWARE-ESXi-03 -sched Semanal -fso -S serv-master-emm.central.iamspe.com.br -jobid 119741

11:17:26.986 [3516.3604] <2> logconnections: BPCD CONNECT FROM 10.146.20.19.49937 TO 10.146.20.19.13782 fd = 536

11:17:27.033 [3516.3604] <2> bpbrm start_bpcd_stat: DATA_SOCK from bpcr = 536

11:17:27.033 [3516.3604] <2> bpbrm start_bpcd_stat: NAME_SOCK from bpcr = 544

11:17:27.033 [3516.3604] <2> pfi_start_client: command = /usr/openv/netbackup/bin/bpfis bpfis create -nbu -owner NBU -id APPLICATION-TFS_1388755041 -bpstart_to 300 -bpend_to 300 -backup_copy 0 -ru root -pt 40 -vm_type 2 -vm_client APPLICATION-TFS -clnt serv-media01.central.iamspe.com.br -st FULL -block_incr -prev_bli_incr 1387016059 -last_bli_full 1387016059 -dt 0 -rg other -fim VMware_v2:Virtual_machine_backup=2,nameuse=1,snapact=2,file_system_optimization=1,trantype=nbd:san,exclude_swap=1,disable_quiesce=0,drive_selection=0,skipnodisk=0,post_events=1,nameuse=1 -class VMWARE-ESXi-03 -sched Semanal -fso -S serv-master-emm.central.iamspe.com.br -jobid 119741

11:17:27.033 [3516.3604] <2> pfi_start_client: received bpcd success message

11:17:27.783 [3516.3604] <2> pfi_start_client: read start message from serv-media01.central.iamspe.com.br, msg=<INF - BACKUP START 2824>

11:17:27.783 [3516.3604] <2> write_file_names: buffering file name 'NEW_STREAM' for output

11:17:27.783 [3516.3604] <2> write_file_names: buffering file name 'ALL_LOCAL_DRIVES' for output

11:17:27.783 [3516.3604] <2> write_file_names: successfully wrote buffer to COMM_SOCK

11:17:27.783 [3516.3604] <2> bpbrm write_filelist: wrote CONTINUE on COMM_SOCK

11:18:29.127 [3516.3604] <2> logconnections: BPCD CONNECT FROM 10.146.20.19.49947 TO 10.146.20.19.13782 fd = 540

11:18:29.174 [3516.3604] <2> bpcr_get_version_rqst: bpcd version: 07500003

11:18:29.174 [3516.3604] <2> bpbrm Exit: client backup EXIT STATUS 0: the requested operation was successfully completed

11:18:30.611 [2848.3248] <2> bpbrm main: INITIATING (VERBOSE = 0): version NetBackup 7.5 0

11:18:30.611 [2848.3248] <2> logparams: -backup -S serv-master-emm.central.iamspe.com.br -c APPLICATION-TFS -ct 40 -ru root -cl VMWARE-ESXi-03 -sched Semanal -bt 1388755109 -dt 0 -st 0 -b APPLICATION-TFS_1388755109 -mediasvr serv-media01.central.iamspe.com.br -jobid 119742 -jobgrpid 119741 -masterversion 750000 -maxfrag 1048576 -bpstart_time 1388755410 -reqid -1388748149 -mt 2 -to 0 -stunit serv-media01-hcart-robot-tld-0 -rl 11 -rp 2678400 -eari 0 -cj 2 -D 6 -rt 8 -rn 0 -pool 31dias -mpx 6 -block_incr -prev_bli_incr 1387016059 -last_bli_full 1387016059 -num_related_classes 1 -use_ofb -use_otm -fi -fim NONE -vm_type 2 -vm_proxy serv-media01.central.iamspe.com.br -nbu -vm_proxy serv-media01.central.iamspe.com.br -jm -secure 1

11:18:30.611 [2848.3248] <2> bpbrm main: DBG - Snapshot method [4] - NONE

11:18:30.627 [2848.3248] <2> vnet_pbxConnect: pbxConnectEx Succeeded

11:18:30.627 [2848.3248] <2> logconnections: BPRD CONNECT FROM 10.146.20.19.49952 TO 10.146.20.20.1556 fd = 492

11:18:30.627 [2848.3248] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2

11:18:30.799 [2848.3248] <2> brm_update_local_resiliency: changed = 0

11:18:30.799 [2848.3248] <2> ConnectionCache::connectAndCache: Acquiring new connection for host serv-master-emm.central.iamspe.com.br, query type 81

11:18:30.799 [2848.3248] <2> vnet_pbxConnect: pbxConnectEx Succeeded

11:18:30.799 [2848.3248] <2> logconnections: BPDBM CONNECT FROM 10.146.20.19.49953 TO 10.146.20.20.1556 fd = 492

11:18:30.799 [2848.3248] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2

11:18:31.127 [2848.3248] <2> db_end: Need to collect reply

11:18:31.127 [2848.3248] <2> ConnectionCache::connectAndCache: Acquiring new connection for host serv-master-emm.central.iamspe.com.br, query type 89

11:18:31.143 [2848.3248] <2> vnet_pbxConnect: pbxConnectEx Succeeded

11:18:31.143 [2848.3248] <2> logconnections: BPDBM CONNECT FROM 10.146.20.19.49954 TO 10.146.20.20.1556 fd = 496

11:18:31.143 [2848.3248] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2

11:18:31.205 [2848.3248] <2> db_end: Need to collect reply

11:18:31.471 [2848.3248] <2> ConnectionCache::connectAndCache: Acquiring new connection for host serv-master-emm.central.iamspe.com.br, query type 223

11:18:31.486 [2848.3248] <2> vnet_pbxConnect: pbxConnectEx Succeeded

11:18:31.486 [2848.3248] <2> logconnections: BPDBM CONNECT FROM 10.146.20.19.49955 TO 10.146.20.20.1556 fd = 496

11:18:31.486 [2848.3248] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2

11:18:31.549 [2848.3248] <2> db_CLIENTsend: reset client protocol version from 0 to 8

11:18:31.752 [2848.3248] <2> db_getCLIENT: db_CLIENTreceive: no entity was found 227

11:18:31.752 [2848.3248] <2> logconnections: BPCD CONNECT FROM 10.146.20.19.49956 TO 10.146.20.19.13782 fd = 496

11:18:31.799 [2848.3248] <2> local_getHostInfo: CLIENT_CMD_SOCK from bpcr = 496

11:18:31.799 [2848.3248] <2> bpcr_get_version_rqst: bpcd version: 07500003

11:18:31.830 [2848.3248] <2> local_getHostInfo: hostname comparison failed

11:18:31.830 [2848.3248] <2> bpcr_get_version_rqst: bpcd version: 07500003

11:18:31.830 [2848.3248] <2> local_getHostInfo: rqstType == BPCD_GET_NB_VERSION_RQST

11:18:31.830 [2848.3248] <2> bpcr_get_platform_rqst: Server platform length = 7

11:18:31.830 [2848.3248] <2> xlatePlatformName: my version = 750000, client version = 750000, check current list first

11:18:31.830 [2848.3248] <2> xlatePlatformName: BUILD_PLATFORM name to xlate = win_x64

11:18:31.830 [2848.3248] <2> local_getHostInfo: platform of client serv-media01.central.iamspe.com.br is PC-x64, WindowsXP

11:18:31.830 [2848.3248] <2> bpcr_get_uname_rqst: bpcr.c: 3662 count=28, buffer=UNAME_SYSNAME = Windows2008

11:18:31.830 [2848.3248] <2> bpcr_get_uname_rqst: bpcr.c: 3662 count=30, buffer=UNAME_NODENAME = SERV-MEDIA01

11:18:31.830 [2848.3248] <2> bpcr_get_uname_rqst: bpcr.c: 3662 count=18, buffer=UNAME_RELEASE = 6

11:18:31.830 [2848.3248] <2> bpcr_get_uname_rqst: bpcr.c: 3662 count=18, buffer=UNAME_VERSION = 1

11:18:31.830 [2848.3248] <2> bpcr_get_uname_rqst: bpcr.c: 3662 count=51, buffer=UNAME_MACHINE = Unknown - Unknown - Revision x40a

11:18:31.830 [2848.3248] <2> bpcr_get_uname_rqst: bpcr.c: 3662 count=3, buffer=END

11:18:31.830 [2848.3248] <2> local_getHostInfo: d:\nb\nb_7.5.0.3\src\nb\dblib\dbmisc.c: 1728, uname -sr=Windows2008 6

11:18:31.830 [2848.3248] <2> bpcr_get_version_rqst: bpcd version: 07500003

11:18:31.830 [2848.3248] <2> getPatchLevel: CLIENT_CMD_SOCK from bpcr = 496

11:18:31.830 [2848.3248] <2> getPatchLevel: got client socket

11:18:31.830 [2848.3248] <2> getPatchLevel: file num = 3, file size = 113

11:18:31.830 [2848.3248] <2> bpcr_read_rqst: completed send of file number 3

11:18:31.830 [2848.3248] <2> bpcr_read_rqst: completed send of transfer_count = 16383

11:18:31.830 [2848.3248] <2> bpcr_read_rqst: Before readclientstatus on socket 504

11:18:31.830 [2848.3248] <2> bpcr_read_rqst: Num bytes to be sent by client = 113

11:18:31.830 [2848.3248] <2> getPatchLevel: total chars read = 113

11:18:31.830 [2848.3248] <2> getPatchLevel: level = >7.5.0.3<

11:18:31.830 [2848.3248] <2> ConnectionCache::connectAndCache: Acquiring new connection for host serv-master-emm.central.iamspe.com.br, query type 223

11:18:31.830 [2848.3248] <2> vnet_pbxConnect: pbxConnectEx Succeeded

11:18:31.830 [2848.3248] <2> logconnections: BPDBM CONNECT FROM 10.146.20.19.49958 TO 10.146.20.20.1556 fd = 504

11:18:31.830 [2848.3248] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2

11:18:31.908 [2848.3248] <2> db_CLIENTsend: reset client protocol version from 0 to 8

11:18:32.111 [2848.3248] <2> db_getCLIENT: db_CLIENTreceive: no entity was found 227

11:18:32.111 [2848.3248] <2> ConnectionCache::connectAndCache: Acquiring new connection for host serv-master-emm.central.iamspe.com.br, query type 223

11:18:32.127 [2848.3248] <2> vnet_pbxConnect: pbxConnectEx Succeeded

11:18:32.127 [2848.3248] <2> logconnections: BPDBM CONNECT FROM 10.146.20.19.49959 TO 10.146.20.20.1556 fd = 492

11:18:32.127 [2848.3248] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2

11:18:32.189 [2848.3248] <2> db_CLIENTsend: reset client protocol version from 0 to 8

11:18:32.393 [2848.3248] <2> db_getCLIENT: db_CLIENTreceive: no entity was found 227

11:18:32.393 [2848.3248] <2> verify_client: bpbrm.c.37835: db_getCLIENT failed for CLIENT_HOSTNAME: serv-media01.central.iamspe.com.br

11:18:32.393 [2848.3248] <2> verify_client: bpbrm.c.37980: db_getCLIENT failed: 227 227 0x000000e3

11:18:32.393 [2848.3248] <2> local_getHostInfo: found TSS cached host serv-media01.central.iamspe.com.br type 67

11:18:32.393 [2848.3248] <2> bpbrm create_events: created suspend event name Global\NetBackup Suspend Event, pid: 2848

11:18:32.393 [2848.3248] <2> read_config_file: using 131072 value from N:\Program Files\Veritas\NetBackup\db\config\SIZE_DATA_BUFFERS

11:18:32.393 [2848.3248] <2> ConnectionCache::connectAndCache: Acquiring new connection for host serv-master-emm.central.iamspe.com.br, query type 223

11:18:32.408 [2848.3248] <2> vnet_pbxConnect: pbxConnectEx Succeeded

11:18:32.408 [2848.3248] <2> logconnections: BPDBM CONNECT FROM 10.146.20.19.49960 TO 10.146.20.20.1556 fd = 532

11:18:32.408 [2848.3248] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2

11:18:32.455 [2848.3248] <2> db_CLIENTsend: reset client protocol version from 0 to 8

11:18:32.674 [2848.3248] <2> db_getCLIENT: db_CLIENTreceive: no entity was found 227

11:18:32.674 [2848.3248] <2> logconnections: BPCD CONNECT FROM 10.146.20.19.49961 TO 10.146.20.19.13782 fd = 532

11:18:32.721 [2848.3248] <2> bpbrm start_bpcd_stat: DATA_SOCK from bpcr = 532

11:18:32.721 [2848.3248] <2> bpbrm start_bpcd_stat: NAME_SOCK from bpcr = 544

11:18:32.721 [2848.3248] <2> vnet_pbxConnect: pbxConnectEx Succeeded

11:18:32.721 [2848.3248] <2> job_connect: SO_KEEPALIVE set on socket 540 for client serv-master-emm.central.iamspe.com.br

11:18:32.721 [2848.3248] <2> logconnections: BPJOBD CONNECT FROM 10.146.20.19.49963 TO 10.146.20.20.1556 fd = 540

11:18:32.721 [2848.3248] <2> job_authenticate_connection: ignoring VxSS authentication check for now...

11:18:32.721 [2848.3248] <2> job_connect: Connected to the host serv-master-emm.central.iamspe.com.br contype 53 jobid <119742> socket <540>

11:18:32.721 [2848.3248] <2> job_connect: Connected on port 49963

11:18:32.721 [2848.3248] <2> bpbrm multiplexed_backup: Started media manager using bpcd successfully

11:18:32.721 [2848.3248] <2> bpbrm multiplexed_backup: calling read_media_msg for media manager PID.

11:18:32.939 [2848.3248] <2> bpbrm read_media_msg: read from media manager: PID of bpxm = 4004

11:18:32.939 [2848.3248] <2> bpbrm create_mm_terminate: created terminate event pid 4004

11:18:32.939 [2848.3248] <2> bpbrm create_mm_suspend: created suspend event pid 4004

11:18:32.939 [2848.3248] <2> bpbrm read_parent_msg: read from parent START BACKUP -c APPLICATION-TFS -ct 40 -ru root -cl VMWARE-ESXi-03 -sched Semanal -bt 1388755109 -dt 0 -st 0 -b APPLICATION-TFS_1388755109 -jobid 119742 -jobgrpid 119741 -rl 11 -rp 2678400 -block_incr -prev_bli_incr 1387016059 -last_bli_full 1387016059 -num_related_classes 1 -use_ofb -use_otm -fi -fim NONE -vm_type 2 -vm_proxy serv-media01.central.iamspe.com.br -nbu -secure 1 -kl 28 -rg other -fso -ifrfr -keyword BACKUP��VIA��LAN�� -connect_options 16974338

11:18:32.939 [2848.3248] <2> bpbrm process_start_backup_msg: processing START BACKUP message.

11:18:32.939 [2848.3248] <2> bpbrm process_start_backup_msg: DBG - Snapshot method [4] - NONE

11:18:32.939 [2848.3248] <2> ConnectionCache::connectAndCache: Acquiring new connection for host serv-master-emm.central.iamspe.com.br, query type 223

11:18:32.939 [2848.3248] <2> vnet_pbxConnect: pbxConnectEx Succeeded

11:18:32.939 [2848.3248] <2> logconnections: BPDBM CONNECT FROM 10.146.20.19.49973 TO 10.146.20.20.1556 fd = 560

11:18:32.939 [2848.3248] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2

11:18:32.986 [2848.3248] <2> db_CLIENTsend: reset client protocol version from 0 to 8

11:18:33.189 [2848.3248] <2> db_getCLIENT: db_CLIENTreceive: no entity was found 227

11:18:33.189 [2848.3248] <2> ConnectionCache::connectAndCache: Acquiring new connection for host serv-master-emm.central.iamspe.com.br, query type 223

11:18:33.205 [2848.3248] <2> vnet_pbxConnect: pbxConnectEx Succeeded

11:18:33.205 [2848.3248] <2> logconnections: BPDBM CONNECT FROM 10.146.20.19.49974 TO 10.146.20.20.1556 fd = 560

11:18:33.205 [2848.3248] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2

11:18:33.268 [2848.3248] <2> db_CLIENTsend: reset client protocol version from 0 to 8

11:18:33.502 [2848.3248] <2> db_getCLIENT: db_CLIENTreceive: no entity was found 227

11:18:33.502 [2848.3248] <2> verify_client: bpbrm.c.37835: db_getCLIENT failed for CLIENT_HOSTNAME: serv-media01.central.iamspe.com.br

11:18:33.502 [2848.3248] <2> verify_client: bpbrm.c.37980: db_getCLIENT failed: 227 227 0x000000e3

11:18:33.502 [2848.3248] <2> local_getHostInfo: found TSS cached host serv-media01.central.iamspe.com.br type 67

11:18:33.502 [2848.3248] <2> ConnectionCache::connectAndCache: Acquiring new connection for host serv-master-emm.central.iamspe.com.br, query type 81

11:18:33.502 [2848.3248] <2> vnet_pbxConnect: pbxConnectEx Succeeded

11:18:33.502 [2848.3248] <2> logconnections: BPDBM CONNECT FROM 10.146.20.19.49975 TO 10.146.20.20.1556 fd = 560

11:18:33.502 [2848.3248] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2

11:18:33.830 [2848.3248] <2> db_end: Need to collect reply

11:18:33.830 [2848.3248] <2> ConnectionCache::connectAndCache: Acquiring new connection for host serv-master-emm.central.iamspe.com.br, query type 89

11:18:33.830 [2848.3248] <2> vnet_pbxConnect: pbxConnectEx Succeeded

11:18:33.830 [2848.3248] <2> logconnections: BPDBM CONNECT FROM 10.146.20.19.49976 TO 10.146.20.20.1556 fd = 564

11:18:33.830 [2848.3248] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2

11:18:33.908 [2848.3248] <2> db_end: Need to collect reply

11:18:34.189 [2848.3248] <2> bpbrm tell_mm_to_start_backup: telling media manager to start

11:18:34.189 [2848.3248] <2> bpbrm multiplexed_backup: calling read_media_msg for DATASOCKET.

11:18:35.283 [2848.3248] <2> bpbrm read_media_msg: read from media manager: DATASOCKET APPLICATION-TFS_1388755109 2915

11:18:35.283 [2848.3248] <2> bpbrm multiplexed_backup: DATASOCKET = 2915 for client APPLICATION-TFS

11:18:35.283 [2848.3248] <2> bpbrm spawn_brm_child: spawning child

11:18:35.283 [2848.3248] <2> bpbrm spawn_brm_child: bpbrm child pid = 3264.3160

11:18:35.393 [3264.3160] <2> bpbrm main: INITIATING (VERBOSE = 0): version NetBackup 7.5 0

11:18:35.393 [3264.3160] <2> logparams: -backup -S serv-master-emm.central.iamspe.com.br -c APPLICATION-TFS -ct 40 -ru root -cl VMWARE-ESXi-03 -sched Semanal -bt 1388755109 -dt 0 -st 0 -b APPLICATION-TFS_1388755109 -mediasvr serv-media01.central.iamspe.com.br -jobid 119742 -jobgrpid 119741 -masterversion 750000 -maxfrag 1048576 -bpstart_time 1388755410 -reqid -1388748149 -mt 2 -to 0 -stunit serv-media01-hcart-robot-tld-0 -rl 11 -rp 2678400 -eari 0 -cj 2 -D 6 -rt 8 -rn 0 -pool 31dias -mpx 6 -block_incr -prev_bli_incr 1387016059 -last_bli_full 1387016059 -num_related_classes 1 -use_ofb -use_otm -fi -fim NONE -vm_type 2 -vm_proxy serv-media01.central.iamspe.com.br -nbu -vm_proxy serv-media01.central.iamspe.com.br -jm -secure 1 -brmchild 1 2915 0 0 528 START BACKUP -c APPLICATION-TFS -ct 40 -ru root -cl VMWARE-ESXi-03 -sched Semanal -bt 1388755109 -dt 0 -st 0 -b APPLICATION-TFS_1388755109 -jobid 119742 -jobgrpid 119741 -rl 11 -rp 2678400 -block_incr -prev_bli_incr 1387016059 -last_bli_full 1387...

11:18:35.408 [3264.3160] <2> bpbrm main: DBG - Snapshot method [4] - NONE

11:18:35.408 [3264.3160] <2> vnet_pbxConnect: pbxConnectEx Succeeded

11:18:35.408 [3264.3160] <2> logconnections: BPRD CONNECT FROM 10.146.20.19.49981 TO 10.146.20.20.1556 fd = 496

11:18:35.408 [3264.3160] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2

11:18:35.580 [3264.3160] <2> brm_update_local_resiliency: changed = 0

11:18:35.580 [3264.3160] <2> ConnectionCache::connectAndCache: Acquiring new connection for host serv-master-emm.central.iamspe.com.br, query type 81

11:18:35.580 [3264.3160] <2> vnet_pbxConnect: pbxConnectEx Succeeded

11:18:35.580 [3264.3160] <2> logconnections: BPDBM CONNECT FROM 10.146.20.19.49982 TO 10.146.20.20.1556 fd = 496

11:18:35.580 [3264.3160] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2

11:18:35.924 [3264.3160] <2> db_end: Need to collect reply

11:18:35.924 [3264.3160] <2> ConnectionCache::connectAndCache: Acquiring new connection for host serv-master-emm.central.iamspe.com.br, query type 89

11:18:35.924 [3264.3160] <2> vnet_pbxConnect: pbxConnectEx Succeeded

11:18:35.924 [3264.3160] <2> logconnections: BPDBM CONNECT FROM 10.146.20.19.49983 TO 10.146.20.20.1556 fd = 500

11:18:35.924 [3264.3160] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2

11:18:36.002 [3264.3160] <2> db_end: Need to collect reply

11:18:36.268 [3264.3160] <2> ConnectionCache::connectAndCache: Acquiring new connection for host serv-master-emm.central.iamspe.com.br, query type 223

11:18:36.268 [3264.3160] <2> vnet_pbxConnect: pbxConnectEx Succeeded

11:18:36.268 [3264.3160] <2> logconnections: BPDBM CONNECT FROM 10.146.20.19.49984 TO 10.146.20.20.1556 fd = 500

11:18:36.268 [3264.3160] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2

11:18:36.346 [3264.3160] <2> db_CLIENTsend: reset client protocol version from 0 to 8

11:18:36.549 [3264.3160] <2> db_getCLIENT: db_CLIENTreceive: no entity was found 227

11:18:36.549 [3264.3160] <2> logconnections: BPCD CONNECT FROM 10.146.20.19.49985 TO 10.146.20.19.13782 fd = 500

11:18:36.596 [3264.3160] <2> local_getHostInfo: CLIENT_CMD_SOCK from bpcr = 500

11:18:36.596 [3264.3160] <2> bpcr_get_version_rqst: bpcd version: 07500003

11:18:36.611 [3264.3160] <2> local_getHostInfo: hostname comparison failed

11:18:36.611 [3264.3160] <2> bpcr_get_version_rqst: bpcd version: 07500003

11:18:36.611 [3264.3160] <2> local_getHostInfo: rqstType == BPCD_GET_NB_VERSION_RQST

11:18:36.611 [3264.3160] <2> bpcr_get_platform_rqst: Server platform length = 7

11:18:36.611 [3264.3160] <2> xlatePlatformName: my version = 750000, client version = 750000, check current list first

11:18:36.611 [3264.3160] <2> xlatePlatformName: BUILD_PLATFORM name to xlate = win_x64

11:18:36.611 [3264.3160] <2> local_getHostInfo: platform of client serv-media01.central.iamspe.com.br is PC-x64, WindowsXP

11:18:36.611 [3264.3160] <2> bpcr_get_uname_rqst: bpcr.c: 3662 count=28, buffer=UNAME_SYSNAME = Windows2008

11:18:36.611 [3264.3160] <2> bpcr_get_uname_rqst: bpcr.c: 3662 count=30, buffer=UNAME_NODENAME = SERV-MEDIA01

11:18:36.611 [3264.3160] <2> bpcr_get_uname_rqst: bpcr.c: 3662 count=18, buffer=UNAME_RELEASE = 6

11:18:36.611 [3264.3160] <2> bpcr_get_uname_rqst: bpcr.c: 3662 count=18, buffer=UNAME_VERSION = 1

11:18:36.611 [3264.3160] <2> bpcr_get_uname_rqst: bpcr.c: 3662 count=51, buffer=UNAME_MACHINE = Unknown - Unknown - Revision x40a

11:18:36.611 [3264.3160] <2> bpcr_get_uname_rqst: bpcr.c: 3662 count=3, buffer=END

11:18:36.611 [3264.3160] <2> local_getHostInfo: d:\nb\nb_7.5.0.3\src\nb\dblib\dbmisc.c: 1728, uname -sr=Windows2008 6

11:18:36.611 [3264.3160] <2> bpcr_get_version_rqst: bpcd version: 07500003

11:18:36.627 [3264.3160] <2> getPatchLevel: CLIENT_CMD_SOCK from bpcr = 500

11:18:36.627 [3264.3160] <2> getPatchLevel: got client socket

11:18:36.627 [3264.3160] <2> getPatchLevel: file num = 3, file size = 113

11:18:36.627 [3264.3160] <2> bpcr_read_rqst: completed send of file number 3

11:18:36.627 [3264.3160] <2> bpcr_read_rqst: completed send of transfer_count = 16383

11:18:36.627 [3264.3160] <2> bpcr_read_rqst: Before readclientstatus on socket 508

11:18:36.627 [3264.3160] <2> bpcr_read_rqst: Num bytes to be sent by client = 113

11:18:36.627 [3264.3160] <2> getPatchLevel: total chars read = 113

11:18:36.627 [3264.3160] <2> getPatchLevel: level = >7.5.0.3<

11:18:36.627 [3264.3160] <2> ConnectionCache::connectAndCache: Acquiring new connection for host serv-master-emm.central.iamspe.com.br, query type 223

11:18:36.627 [3264.3160] <2> vnet_pbxConnect: pbxConnectEx Succeeded

11:18:36.627 [3264.3160] <2> logconnections: BPDBM CONNECT FROM 10.146.20.19.49987 TO 10.146.20.20.1556 fd = 508

11:18:36.627 [3264.3160] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2

11:18:36.689 [3264.3160] <2> db_CLIENTsend: reset client protocol version from 0 to 8

11:18:36.908 [3264.3160] <2> db_getCLIENT: db_CLIENTreceive: no entity was found 227

11:18:36.908 [3264.3160] <2> ConnectionCache::connectAndCache: Acquiring new connection for host serv-master-emm.central.iamspe.com.br, query type 223

11:18:36.908 [3264.3160] <2> vnet_pbxConnect: pbxConnectEx Succeeded

11:18:36.908 [3264.3160] <2> logconnections: BPDBM CONNECT FROM 10.146.20.19.49988 TO 10.146.20.20.1556 fd = 496

11:18:36.908 [3264.3160] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2

11:18:36.986 [3264.3160] <2> db_CLIENTsend: reset client protocol version from 0 to 8

11:18:37.189 [3264.3160] <2> db_getCLIENT: db_CLIENTreceive: no entity was found 227

11:18:37.189 [3264.3160] <2> verify_client: bpbrm.c.37835: db_getCLIENT failed for CLIENT_HOSTNAME: serv-media01.central.iamspe.com.br

11:18:37.189 [3264.3160] <2> verify_client: bpbrm.c.37980: db_getCLIENT failed: 227 227 0x000000e3

11:18:37.189 [3264.3160] <2> local_getHostInfo: found TSS cached host serv-media01.central.iamspe.com.br type 67

11:18:37.189 [3264.3160] <2> bpbrm create_events: created suspend event name Global\NetBackup Suspend Event, pid: 3264

11:18:37.189 [3264.3160] <2> read_config_file: using 131072 value from N:\Program Files\Veritas\NetBackup\db\config\SIZE_DATA_BUFFERS

11:18:37.189 [3264.3160] <2> bpbrm process_start_backup_msg: processing START BACKUP message.

11:18:37.189 [3264.3160] <2> bpbrm process_start_backup_msg: DBG - Snapshot method [4] - NONE

11:18:37.189 [3264.3160] <2> ConnectionCache::connectAndCache: Acquiring new connection for host serv-master-emm.central.iamspe.com.br, query type 223

11:18:37.189 [3264.3160] <2> vnet_pbxConnect: pbxConnectEx Succeeded

11:18:37.189 [3264.3160] <2> logconnections: BPDBM CONNECT FROM 10.146.20.19.49989 TO 10.146.20.20.1556 fd = 552

11:18:37.189 [3264.3160] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2

11:18:37.268 [3264.3160] <2> db_CLIENTsend: reset client protocol version from 0 to 8

11:18:37.471 [3264.3160] <2> db_getCLIENT: db_CLIENTreceive: no entity was found 227

11:18:37.471 [3264.3160] <2> ConnectionCache::connectAndCache: Acquiring new connection for host serv-master-emm.central.iamspe.com.br, query type 223

11:18:37.471 [3264.3160] <2> vnet_pbxConnect: pbxConnectEx Succeeded

11:18:37.471 [3264.3160] <2> logconnections: BPDBM CONNECT FROM 10.146.20.19.49990 TO 10.146.20.20.1556 fd = 552

11:18:37.471 [3264.3160] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2

11:18:37.518 [3264.3160] <2> db_CLIENTsend: reset client protocol version from 0 to 8

11:18:37.721 [3264.3160] <2> db_getCLIENT: db_CLIENTreceive: no entity was found 227

11:18:37.721 [3264.3160] <2> verify_client: bpbrm.c.37835: db_getCLIENT failed for CLIENT_HOSTNAME: serv-media01.central.iamspe.com.br

11:18:37.721 [3264.3160] <2> verify_client: bpbrm.c.37980: db_getCLIENT failed: 227 227 0x000000e3

11:18:37.721 [3264.3160] <2> local_getHostInfo: found TSS cached host serv-media01.central.iamspe.com.br type 67

11:18:37.721 [3264.3160] <2> ConnectionCache::connectAndCache: Acquiring new connection for host serv-master-emm.central.iamspe.com.br, query type 81

11:18:37.721 [3264.3160] <2> vnet_pbxConnect: pbxConnectEx Succeeded

11:18:37.721 [3264.3160] <2> logconnections: BPDBM CONNECT FROM 10.146.20.19.49991 TO 10.146.20.20.1556 fd = 552

11:18:37.721 [3264.3160] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2

11:18:38.064 [3264.3160] <2> db_end: Need to collect reply

11:18:38.064 [3264.3160] <2> ConnectionCache::connectAndCache: Acquiring new connection for host serv-master-emm.central.iamspe.com.br, query type 89

11:18:38.064 [3264.3160] <2> vnet_pbxConnect: pbxConnectEx Succeeded

11:18:38.064 [3264.3160] <2> logconnections: BPDBM CONNECT FROM 10.146.20.19.49992 TO 10.146.20.20.1556 fd = 552

11:18:38.064 [3264.3160] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2

11:18:38.127 [3264.3160] <2> db_end: Need to collect reply

11:18:38.408 [3264.3160] <2> bpbrm read_filelist: reading filelist.

11:18:38.408 [3264.3160] <2> bpbrm read_filelist: filepath from bpsched: FILEPATH APPLICATION-TFS_1388755109 FIS_VM_BI_ATTRS_FILE=\online_util\fi_cntl\bpfis.fim.APPLICATION-TFS_1388755041.1.0.NBU_DATA.xml

11:18:38.408 [3264.3160] <2> bpbrm read_filelist: filepath from bpsched: FILEPATH APPLICATION-TFS_1388755109 BACKUP \\.\N:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.APPLICATION-TFS_1388755041.1.0.NBU_DATA.xml USING \\.\C:\ OPTIONS:FITYPE=MIRROR,FSTYPE=NTFS

11:18:38.408 [3264.3160] <2> bpbrm read_filelist: filepath from bpsched: CONTINUE APPLICATION-TFS_1388755109

11:18:38.408 [3264.3160] <2> bpbrm send_bpsched_connecting_msg: sending bpsched msg: CONNECTING TO CLIENT FOR APPLICATION-TFS_1388755109

11:18:38.408 [3264.3160] <2> vnet_pbxConnect: pbxConnectEx Succeeded

11:18:38.408 [3264.3160] <2> job_connect: SO_KEEPALIVE set on socket 556 for client serv-master-emm.central.iamspe.com.br

11:18:38.408 [3264.3160] <2> logconnections: BPJOBD CONNECT FROM 10.146.20.19.49993 TO 10.146.20.20.1556 fd = 556

11:18:38.408 [3264.3160] <2> job_authenticate_connection: ignoring VxSS authentication check for now...

11:18:38.408 [3264.3160] <2> job_connect: Connected to the host serv-master-emm.central.iamspe.com.br contype 53 jobid <119742> socket <556>

11:18:38.408 [3264.3160] <2> job_connect: Connected on port 49993

11:18:40.971 [3264.3160] <8> retry_getaddrinfo_for_real: [vnet_addrinfo.c:1063] getaddrinfo() failed RV=11001 NAME=APPLICATION-TFS SVC=0

11:18:40.971 [3264.3160] <8> retry_getaddrinfo: [vnet_addrinfo.c:920] retry_getaddrinfo_for_real failed RV=11001 NAME=APPLICATION-TFS SVC=0

11:18:40.971 [3264.3160] <8> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1626] retry_getaddrinfo() failed RV=11001 NAME=APPLICATION-TFS SVC=NULL

11:18:40.971 [3264.3160] <8> vnet_cached_getaddrinfo: [vnet_addrinfo.c:1275] vnet_cached_getaddrinfo_and_update() failed 6 0x6

11:18:40.971 [3264.3160] <8> vnet_same_host_and_update: [vnet_addrinfo.c:2841] vnet_cached_getaddrinfo() failed STAT=6 RV=11001 NAME1=APPLICATION-TFS

11:18:40.971 [3264.3160] <2> logconnections: BPCD CONNECT FROM 10.146.20.19.49994 TO 10.146.20.19.13782 fd = 572

11:18:41.002 [3264.3160] <2> bpcr_get_clientname_rqst: Server clientname length = 34

11:18:41.002 [3264.3160] <2> bpbrm start_bpcd_stat: DATA_SOCK from bpcr = 572

11:18:41.002 [3264.3160] <2> bpbrm start_bpcd_stat: NAME_SOCK from bpcr = 580

11:18:41.002 [3264.3160] <2> bpbrm handle_backup: calling bpcr_get_socket_rqst3.

11:18:41.002 [3264.3160] <4> bpbrm handle_backup: Calling bpfis to create snapshot

11:18:41.002 [3264.3160] <2> MNG: backup_cmd = /usr/openv/netbackup/bin/bpbkar bpbkar32 -r 2678400 -ru root -dt 0 -to 0 -bpstart_time 0 -clnt APPLICATION-TFS -class VMWARE-ESXi-03 -sched Semanal -st FULL -bpstart_to 300 -bpend_to 300 -read_to 1200 -block_incr -keyword BACKUP��VIA��LAN�� -streams 1 -bt 1388755109 -prev_bli_incr 1387016059 -last_bli_full 1387016059 -blks_per_buffer 255 -use_otm -use_ofb -b APPLICATION-TFS_1388755109 -kl 28 -shm -fso -ifr -pid 3264 -mediasvr serv-media01.central.iamspe.com.br -bt 1388755109 -t 0 -fi -S serv-master-emm.central.iamspe.com.br -fim NONE -ct 40

11:18:41.002 [3264.3160] <2> bpbrm handle_backup: forking client backup

11:18:41.002 [3264.3160] <2> bpbrm send_bpsched_connected_msg: sending bpsched msg: CONNECTED TO CLIENT FOR APPLICATION-TFS_1388755109

11:18:41.408 [2848.3248] <2> bpbrm read_media_msg: read from media manager: MOUNT O515L4 0

11:18:41.408 [2848.3248] <2> bpbrm send_parent_msg: MOUNT O515L4 0

11:18:48.689 [3264.3160] <2> get_readline_type: Returning BPBRM_READ_INFORM_WHEN_DONE

11:18:48.689 [3264.3160] <2> bpbrm read_backup_start: client_pid=892

11:18:48.689 [3264.3160] <2> bpbrm read_backup_start: from client APPLICATION-TFS: read client start message

11:18:48.689 [3264.3160] <2> bpbrm write_continue_backup: wrote CONTINUE BACKUP on COMM_SOCK <572>

11:18:48.689 [3264.3160] <2> logconnections: BPCD CONNECT FROM 10.146.20.19.49999 TO 10.146.20.19.13782 fd = 576

11:18:48.736 [3264.3160] <2> bpcr_get_version_rqst: bpcd version: 07500003

11:18:48.736 [3264.3160] <2> write_file_names: buffering file name 'FIS_VM_BI_ATTRS_FILE=\online_util\fi_cntl\bpfis.fim.APPLICATION-TFS_1388755041.1.0.NBU_DATA.xml' for output

11:18:48.736 [3264.3160] <2> write_file_names: buffering file name 'BACKUP \\.\N:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.APPLICATION-TFS_1388755041.1.0.NBU_DATA.xml USING \\.\C:\ OPTIONS:FITYPE=MIRROR,FSTYPE=NTFS' for output

11:18:48.736 [3264.3160] <2> write_file_names: successfully wrote buffer to COMM_SOCK

11:18:48.736 [3264.3160] <2> bpbrm write_filelist: wrote CONTINUE on COMM_SOCK

11:18:53.111 [3264.3160] <2> bpbrm handle_backup: ESTIMATE -1 -1 APPLICATION-TFS_1388755109

11:18:53.205 [3264.3160] <2> bpbrm handle_backup: client APPLICATION-TFS EXIT STATUS = 11: system call failed

11:18:53.205 [3264.3160] <2> inform_client_of_status: INF - Server status = 11

11:18:55.908 [3264.3160] <2> bpbrm Exit: sending signal=1 to client=APPLICATION-TFS, client_pid=892

11:18:55.908 [3264.3160] <2> bpcr_send_signal: Ignoring connect_opts = 0x01030202

11:18:55.908 [3264.3160] <2> ConnectionCache::connectAndCache: Acquiring new connection for host serv-master-emm.central.iamspe.com.br, query type 223

11:18:55.908 [3264.3160] <2> vnet_pbxConnect: pbxConnectEx Succeeded

11:18:55.908 [3264.3160] <2> logconnections: BPDBM CONNECT FROM 10.146.20.19.50001 TO 10.146.20.20.1556 fd = 568

11:18:55.908 [3264.3160] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2

11:18:55.971 [3264.3160] <2> db_CLIENTsend: reset client protocol version from 0 to 8

11:18:56.174 [3264.3160] <2> db_getCLIENT: db_CLIENTreceive: no entity was found 227

11:18:56.174 [3264.3160] <2> logconnections: BPCD CONNECT FROM 10.146.20.19.50002 TO 10.146.20.19.13782 fd = 568

11:18:56.221 [3264.3160] <2> bpcr_send_signal: CLIENT_CMD_SOCK from bpcr = 568

11:18:56.221 [3264.3160] <2> bpcr_send_signal: CLIENT_STAT_SOCK from bpcr = 584

11:18:56.221 [3264.3160] <2> bpbrm readline: EOF detected on socket

11:18:56.221 [3264.3160] <32> bpbrm Exit: unexpected termination of client APPLICATION-TFS

11:18:56.221 [3264.3160] <2> ConnectionCache::connectAndCache: Acquiring new connection for host serv-master-emm.central.iamspe.com.br, query type 1

11:18:56.236 [3264.3160] <2> vnet_pbxConnect: pbxConnectEx Succeeded

11:18:56.236 [3264.3160] <2> logconnections: BPDBM CONNECT FROM 10.146.20.19.50004 TO 10.146.20.20.1556 fd = 584

11:18:56.236 [3264.3160] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2

11:18:56.268 [3264.3160] <2> db_end: Need to collect reply

11:18:56.408 [2848.3248] <2> bpbrm brm_child_done: child done, status 11

11:18:56.408 [2848.3248] <2> bpbrm brm_child_done: child 3264 exited with status 11: system call failed

11:18:56.408 [2848.3248] <2> bpbrm send_status_to_parent: bpbrm child is done, but the media manager child is not.

11:18:56.408 [2848.3248] <2> bpbrm tell_mm: sending media manager msg: STOP BACKUP APPLICATION-TFS_1388755109

11:19:01.408 [2848.3248] <2> bpbrm read_media_msg: read from media manager: EXIT APPLICATION-TFS_1388755109 150

11:19:01.408 [2848.3248] <2> bpbrm process_media_msg: media manager for backup id APPLICATION-TFS_1388755109 exited with status 150: termination requested by administrator

11:19:01.408 [2848.3248] <2> bpbrm send_status_to_parent: EXIT APPLICATION-TFS_1388755109 11 sent to parent process for jobid = 119742.

11:19:01.408 [2848.3248] <2> bpbrm mail_status: attempting to send mail to root on APPLICATION-TFS

11:19:01.408 [2848.3248] <8> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1585] in failed file cache ERR=11001 NAME=APPLICATION-TFS SVC=NULL

11:19:01.408 [2848.3248] <8> vnet_cached_getaddrinfo: [vnet_addrinfo.c:1275] vnet_cached_getaddrinfo_and_update() failed 6 0x6

11:19:01.408 [2848.3248] <8> vnet_same_host_and_update: [vnet_addrinfo.c:2841] vnet_cached_getaddrinfo() failed STAT=6 RV=11001 NAME1=APPLICATION-TFS

11:19:01.408 [2848.3248] <2> logconnections: BPCD CONNECT FROM 10.146.20.19.50005 TO 10.146.20.19.13782 fd = 568

11:19:01.455 [2848.3248] <2> bpcr_get_clientname_rqst: Server clientname length = 34

11:19:01.455 [2848.3248] <2> bpbrm mail_status: OUT_SOCK from bpcr = 568

11:19:01.455 [2848.3248] <2> bpbrm mail_status: IN_SOCK from bpcr = 592

11:19:01.455 [2848.3248] <2> bpcr_get_version_rqst: bpcd version: 07500003

11:19:01.471 [2848.3248] <2> bpcr_get_version_rqst: bpcd version: 07500003

11:19:01.502 [2848.3248] <2> vnet_pbxConnect: pbxConnectEx Succeeded

11:19:01.502 [2848.3248] <2> logconnections: BPCD CONNECT FROM 10.146.20.19.50007 TO 10.146.20.20.1556 fd = 576

11:19:01.502 [2848.3248] <2> vnet_pbxConnect: pbxConnectEx Succeeded

11:19:01.533 [2848.3248] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 10.146.20.19.50008 TO 10.146.20.20.1556 fd = 600

11:19:01.533 [2848.3248] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa

11:19:01.736 [2848.3248] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string 51476

11:19:02.768 [2848.3248] <2> bpbrm read_parent_msg: read from parent TERMINATE

11:19:02.768 [2848.3248] <2> bpbrm tell_mm: sending media manager msg: TERMINATE

11:19:29.580 [1644.2940] <2> bpbrm main: INITIATING (VERBOSE = 0): version NetBackup 7.5 0

11:19:29.596 [1644.2940] <2> logparams: -c APPLICATION-TFS -cl VMWARE-ESXi-03 -ct 40 -id APPLICATION-TFS_1388755041 -nbu -sched Semanal -st 0 -secure 1 -S serv-master-emm.central.iamspe.com.br -block_incr -prev_bli_incr 1387016059 -last_bli_full 1387016059 -use_ofb -use_otm -fi -fim VMware_v2:Virtual_machine_backup=2,nameuse=1,snapact=2,file_system_optimization=1,trantype=nbd:san,exclude_swap=1,disable_quiesce=0,drive_selection=0,skipnodisk=0,post_events=1 -vm_proxy serv-media01.central.iamspe.com.br -nbu -jobid 119741 -jobgrpid 119741 -vm_proxy serv-media01.central.iamspe.com.br -status 150

11:19:29.596 [1644.2940] <2> bpbrm main: DBG - Snapshot method [176] - VMware_v2:Virtual_machine_backup=2,nameuse=1,snapact=2,file_system_optimization=1,trantype=nbd:san,exclude_swap=1,disable_quiesce=0,drive_selection=0,skipnodisk=0,post_events=1

11:19:29.611 [1644.2940] <2> vnet_pbxConnect: pbxConnectEx Succeeded

11:19:29.611 [1644.2940] <2> logconnections: BPRD CONNECT FROM 10.146.20.19.50023 TO 10.146.20.20.1556 fd = 492

11:19:29.611 [1644.2940] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2

11:19:29.783 [1644.2940] <2> brm_update_local_resiliency: changed = 0

11:19:29.783 [1644.2940] <2> ConnectionCache::connectAndCache: Acquiring new connection for host serv-master-emm.central.iamspe.com.br, query type 223

11:19:29.799 [1644.2940] <2> vnet_pbxConnect: pbxConnectEx Succeeded

11:19:29.799 [1644.2940] <2> logconnections: BPDBM CONNECT FROM 10.146.20.19.50024 TO 10.146.20.20.1556 fd = 496

11:19:29.799 [1644.2940] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2

11:19:29.861 [1644.2940] <2> db_CLIENTsend: reset client protocol version from 0 to 8

11:19:30.064 [1644.2940] <2> db_getCLIENT: db_CLIENTreceive: no entity was found 227

11:19:30.080 [1644.2940] <2> logconnections: BPCD CONNECT FROM 10.146.20.19.50025 TO 10.146.20.19.13782 fd = 496

11:19:30.127 [1644.2940] <2> local_getHostInfo: CLIENT_CMD_SOCK from bpcr = 496

11:19:30.127 [1644.2940] <2> bpcr_get_version_rqst: bpcd version: 07500003

11:19:30.143 [1644.2940] <2> local_getHostInfo: hostname comparison failed

11:19:30.143 [1644.2940] <2> bpcr_get_version_rqst: bpcd version: 07500003

11:19:30.143 [1644.2940] <2> local_getHostInfo: rqstType == BPCD_GET_NB_VERSION_RQST

11:19:30.143 [1644.2940] <2> bpcr_get_platform_rqst: Server platform length = 7

11:19:30.143 [1644.2940] <2> xlatePlatformName: my version = 750000, client version = 750000, check current list first

11:19:30.143 [1644.2940] <2> xlatePlatformName: BUILD_PLATFORM name to xlate = win_x64

11:19:30.143 [1644.2940] <2> local_getHostInfo: platform of client serv-media01.central.iamspe.com.br is PC-x64, WindowsXP

11:19:30.143 [1644.2940] <2> bpcr_get_uname_rqst: bpcr.c: 3662 count=28, buffer=UNAME_SYSNAME = Windows2008

11:19:30.143 [1644.2940] <2> bpcr_get_uname_rqst: bpcr.c: 3662 count=30, buffer=UNAME_NODENAME = SERV-MEDIA01

11:19:30.143 [1644.2940] <2> bpcr_get_uname_rqst: bpcr.c: 3662 count=18, buffer=UNAME_RELEASE = 6

11:19:30.143 [1644.2940] <2> bpcr_get_uname_rqst: bpcr.c: 3662 count=18, buffer=UNAME_VERSION = 1

11:19:30.143 [1644.2940] <2> bpcr_get_uname_rqst: bpcr.c: 3662 count=51, buffer=UNAME_MACHINE = Unknown - Unknown - Revision x40a

11:19:30.143 [1644.2940] <2> bpcr_get_uname_rqst: bpcr.c: 3662 count=3, buffer=END

11:19:30.143 [1644.2940] <2> local_getHostInfo: d:\nb\nb_7.5.0.3\src\nb\dblib\dbmisc.c: 1728, uname -sr=Windows2008 6

11:19:30.143 [1644.2940] <2> bpcr_get_version_rqst: bpcd version: 07500003

11:19:30.158 [1644.2940] <2> getPatchLevel: CLIENT_CMD_SOCK from bpcr = 496

11:19:30.158 [1644.2940] <2> getPatchLevel: got client socket

11:19:30.158 [1644.2940] <2> getPatchLevel: file num = 3, file size = 113

11:19:30.158 [1644.2940] <2> bpcr_read_rqst: completed send of file number 3

11:19:30.158 [1644.2940] <2> bpcr_read_rqst: completed send of transfer_count = 16383

11:19:30.158 [1644.2940] <2> bpcr_read_rqst: Before readclientstatus on socket 504

11:19:30.158 [1644.2940] <2> bpcr_read_rqst: Num bytes to be sent by client = 113

11:19:30.158 [1644.2940] <2> getPatchLevel: total chars read = 113

11:19:30.158 [1644.2940] <2> getPatchLevel: level = >7.5.0.3<

11:19:30.158 [1644.2940] <2> ConnectionCache::connectAndCache: Acquiring new connection for host serv-master-emm.central.iamspe.com.br, query type 223

11:19:30.174 [1644.2940] <2> vnet_pbxConnect: pbxConnectEx Succeeded

11:19:30.174 [1644.2940] <2> logconnections: BPDBM CONNECT FROM 10.146.20.19.50027 TO 10.146.20.20.1556 fd = 504

11:19:30.174 [1644.2940] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2

11:19:30.236 [1644.2940] <2> db_CLIENTsend: reset client protocol version from 0 to 8

11:19:30.439 [1644.2940] <2> db_getCLIENT: db_CLIENTreceive: no entity was found 227

11:19:30.439 [1644.2940] <2> ConnectionCache::connectAndCache: Acquiring new connection for host serv-master-emm.central.iamspe.com.br, query type 223

11:19:30.455 [1644.2940] <2> vnet_pbxConnect: pbxConnectEx Succeeded

11:19:30.455 [1644.2940] <2> logconnections: BPDBM CONNECT FROM 10.146.20.19.50028 TO 10.146.20.20.1556 fd = 504

11:19:30.455 [1644.2940] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2

11:19:30.518 [1644.2940] <2> db_CLIENTsend: reset client protocol version from 0 to 8

11:19:30.721 [1644.2940] <2> db_getCLIENT: db_CLIENTreceive: no entity was found 227

11:19:30.721 [1644.2940] <2> verify_client: bpbrm.c.37835: db_getCLIENT failed for CLIENT_HOSTNAME: serv-media01.central.iamspe.com.br

11:19:30.721 [1644.2940] <2> verify_client: bpbrm.c.37980: db_getCLIENT failed: 227 227 0x000000e3

11:19:30.721 [1644.2940] <2> bpbrm create_events: created suspend event name Global\NetBackup Suspend Event, pid: 1644

11:19:30.721 [1644.2940] <2> bpbrm Exit: backup_status: 150

11:19:30.736 [1644.2940] <2> vnet_pbxConnect: pbxConnectEx Succeeded

11:19:30.736 [1644.2940] <2> logconnections: BPRD CONNECT FROM 10.146.20.19.50030 TO 10.146.20.20.1556 fd = 524

11:19:30.736 [1644.2940] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2

11:19:30.814 [1644.2940] <2> vnet_pbxConnect: pbxConnectEx Succeeded

11:19:30.814 [1644.2940] <2> job_connect: SO_KEEPALIVE set on socket 524 for client serv-master-emm.central.iamspe.com.br

11:19:30.814 [1644.2940] <2> logconnections: BPJOBD CONNECT FROM 10.146.20.19.50031 TO 10.146.20.20.1556 fd = 524

11:19:30.814 [1644.2940] <2> job_authenticate_connection: ignoring VxSS authentication check for now...

11:19:30.814 [1644.2940] <2> job_connect: Connected to the host serv-master-emm.central.iamspe.com.br contype 53 jobid <119741> socket <524>

11:19:30.814 [1644.2940] <2> job_connect: Connected on port 50031

11:19:30.814 [1644.2940] <2> logconnections: BPCD CONNECT FROM 10.146.20.19.50032 TO 10.146.20.19.13782 fd = 520

11:19:30.861 [1644.2940] <2> bpbrm start_bpcd_stat: DATA_SOCK from bpcr = 520

11:19:30.861 [1644.2940] <2> bpbrm start_bpcd_stat: NAME_SOCK from bpcr = 536

11:19:30.861 [1644.2940] <2> pfi_start_client: command = /usr/openv/netbackup/bin/bpfis bpfis delete -nbu -id APPLICATION-TFS_1388755041 -bpstart_to 300 -bpend_to 300 -clnt serv-media01.central.iamspe.com.br -status 150 -S serv-master-emm.central.iamspe.com.br -noserverstate -jobid 119741 -copy 1 -fim VMware_v2:Virtual_machine_backup=2,nameuse=1,snapact=2,file_system_optimization=1,trantype=nbd:san,exclude_swap=1,disable_quiesce=0,drive_selection=0,skipnodisk=0,post_events=1

11:19:30.861 [1644.2940] <2> pfi_start_client: received bpcd success message

11:19:31.814 [1644.2940] <2> pfi_start_client: read start message from serv-media01.central.iamspe.com.br, msg=<INF - BACKUP START 4028>

11:19:36.814 [1644.2940] <2> bpbrm check_for_terminate: unexpected terminate

11:19:36.814 [1644.2940] <2> bpbrm kill_child_process_Ex: start

11:19:38.377 [3576.1140] <2> bpbrm main: INITIATING (VERBOSE = 0): version NetBackup 7.5 0

11:19:38.377 [3576.1140] <2> logparams: -c APPLICATION-TFS -cl VMWARE-ESXi-03 -ct 40 -id APPLICATION-TFS_1388755041 -nbu -sched Semanal -st 0 -secure 1 -S serv-master-emm.central.iamspe.com.br -jobid 119741 -jobgrpid 119741 -vm_proxy serv-media01.central.iamspe.com.br -status 0

11:19:38.377 [3576.1140] <2> vnet_pbxConnect: pbxConnectEx Succeeded

11:19:38.377 [3576.1140] <2> logconnections: BPRD CONNECT FROM 10.146.20.19.50044 TO 10.146.20.20.1556 fd = 492

11:19:38.377 [3576.1140] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2

11:19:38.549 [3576.1140] <2> brm_update_local_resiliency: changed = 0

11:19:38.564 [3576.1140] <2> ConnectionCache::connectAndCache: Acquiring new connection for host serv-master-emm.central.iamspe.com.br, query type 223

11:19:38.564 [3576.1140] <2> vnet_pbxConnect: pbxConnectEx Succeeded

11:19:38.564 [3576.1140] <2> logconnections: BPDBM CONNECT FROM 10.146.20.19.50045 TO 10.146.20.20.1556 fd = 492

11:19:38.564 [3576.1140] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2

11:19:38.627 [3576.1140] <2> db_CLIENTsend: reset client protocol version from 0 to 8

11:19:38.814 [3576.1140] <2> db_getCLIENT: db_CLIENTreceive: no entity was found 227

11:19:38.814 [3576.1140] <2> ConnectionCache::connectAndCache: Acquiring new connection for host serv-master-emm.central.iamspe.com.br, query type 223

11:19:38.830 [3576.1140] <2> vnet_pbxConnect: pbxConnectEx Succeeded

11:19:38.830 [3576.1140] <2> logconnections: BPDBM CONNECT FROM 10.146.20.19.50046 TO 10.146.20.20.1556 fd = 496

11:19:38.830 [3576.1140] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2

11:19:38.893 [3576.1140] <2> db_CLIENTsend: reset client protocol version from 0 to 8

11:19:39.096 [3576.1140] <2> db_getCLIENT: db_CLIENTreceive: no entity was found 227

11:19:39.096 [3576.1140] <2> verify_client: bpbrm.c.37835: db_getCLIENT failed for CLIENT_HOSTNAME: serv-media01.central.iamspe.com.br

11:19:39.096 [3576.1140] <2> verify_client: bpbrm.c.37980: db_getCLIENT failed: 227 227 0x000000e3

11:19:39.096 [3576.1140] <2> bpbrm create_events: created suspend event name Global\NetBackup Suspend Event, pid: 3576

11:19:39.096 [3576.1140] <2> bpbrm Exit: backup_status: 0

11:19:39.111 [3576.1140] <2> vnet_pbxConnect: pbxConnectEx Succeeded

11:19:39.111 [3576.1140] <2> job_connect: SO_KEEPALIVE set on socket 520 for client serv-master-emm.central.iamspe.com.br

11:19:39.111 [3576.1140] <2> logconnections: BPJOBD CONNECT FROM 10.146.20.19.50047 TO 10.146.20.20.1556 fd = 520

11:19:39.111 [3576.1140] <2> job_authenticate_connection: ignoring VxSS authentication check for now...

11:19:39.111 [3576.1140] <2> job_connect: Connected to the host serv-master-emm.central.iamspe.com.br contype 53 jobid <119741> socket <520>

11:19:39.111 [3576.1140] <2> job_connect: Connected on port 50047

11:19:39.111 [3576.1140] <2> logconnections: BPCD CONNECT FROM 10.146.20.19.50048 TO 10.146.20.19.13782 fd = 528

11:19:39.158 [3576.1140] <2> bpbrm start_bpcd_stat: DATA_SOCK from bpcr = 528

11:19:39.158 [3576.1140] <2> bpbrm start_bpcd_stat: NAME_SOCK from bpcr = 536

11:19:39.158 [3576.1140] <2> pfi_start_client: command = /usr/openv/netbackup/bin/bpfis bpfis delete -nbu -id APPLICATION-TFS_1388755041 -bpstart_to 300 -bpend_to 300 -clnt serv-media01.central.iamspe.com.br -S serv-master-emm.central.iamspe.com.br -jobid 119741 -copy 1

11:19:39.158 [3576.1140] <2> pfi_start_client: received bpcd success message

11:19:40.299 [2892.3372] <2> bpbrm main: INITIATING (VERBOSE = 0): version NetBackup 7.5 0

11:19:40.299 [2892.3372] <2> logparams: -c APPLICATION-TFS -cl VMWARE-ESXi-03 -ct 40 -id APPLICATION-TFS_1388755041 -nbu -sched Semanal -st 0 -secure 1 -S serv-master-emm.central.iamspe.com.br -jobid 119741 -jobgrpid 119741 -vm_proxy serv-media01.central.iamspe.com.br -status 0

11:19:40.299 [2892.3372] <2> vnet_pbxConnect: pbxConnectEx Succeeded

11:19:40.299 [2892.3372] <2> logconnections: BPRD CONNECT FROM 10.146.20.19.50056 TO 10.146.20.20.1556 fd = 492

11:19:40.299 [2892.3372] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2

11:19:40.330 [1832.4036] <2> bpbrm main: INITIATING (VERBOSE = 0): version NetBackup 7.5 0

11:19:40.330 [1832.4036] <2> logparams: -c APPLICATION-TFS -cl VMWARE-ESXi-03 -ct 40 -id APPLICATION-TFS_1388755041 -nbu -sched Semanal -st 0 -secure 1 -S serv-master-emm.central.iamspe.com.br -jobid 119741 -jobgrpid 119741 -vm_proxy serv-media01.central.iamspe.com.br -status 0

11:19:40.330 [1832.4036] <2> vnet_pbxConnect: pbxConnectEx Succeeded

11:19:40.330 [1832.4036] <2> logconnections: BPRD CONNECT FROM 10.146.20.19.50057 TO 10.146.20.20.1556 fd = 492

11:19:40.330 [1832.4036] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2

11:19:40.471 [2892.3372] <2> brm_update_local_resiliency: changed = 0

11:19:40.471 [2892.3372] <2> ConnectionCache::connectAndCache: Acquiring new connection for host serv-master-emm.central.iamspe.com.br, query type 223

11:19:40.486 [2892.3372] <2> vnet_pbxConnect: pbxConnectEx Succeeded

11:19:40.486 [2892.3372] <2> logconnections: BPDBM CONNECT FROM 10.146.20.19.50058 TO 10.146.20.20.1556 fd = 492

11:19:40.486 [2892.3372] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2

11:19:40.518 [1832.4036] <2> brm_update_local_resiliency: changed = 0

11:19:40.518 [1832.4036] <2> ConnectionCache::connectAndCache: Acquiring new connection for host serv-master-emm.central.iamspe.com.br, query type 223

11:19:40.518 [1832.4036] <2> vnet_pbxConnect: pbxConnectEx Succeeded

11:19:40.518 [1832.4036] <2> logconnections: BPDBM CONNECT FROM 10.146.20.19.50059 TO 10.146.20.20.1556 fd = 492

11:19:40.518 [1832.4036] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2

11:19:40.549 [2892.3372] <2> db_CLIENTsend: reset client protocol version from 0 to 8

11:19:40.596 [1832.4036] <2> db_CLIENTsend: reset client protocol version from 0 to 8

11:19:40.752 [2892.3372] <2> db_getCLIENT: db_CLIENTreceive: no entity was found 227

11:19:40.752 [2892.3372] <2> ConnectionCache::connectAndCache: Acquiring new connection for host serv-master-emm.central.iamspe.com.br, query type 223

11:19:40.768 [2892.3372] <2> vnet_pbxConnect: pbxConnectEx Succeeded

11:19:40.768 [2892.3372] <2> logconnections: BPDBM CONNECT FROM 10.146.20.19.50060 TO 10.146.20.20.1556 fd = 496

11:19:40.768 [2892.3372] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2

11:19:40.799 [1832.4036] <2> db_getCLIENT: db_CLIENTreceive: no entity was found 227

11:19:40.799 [1832.4036] <2> ConnectionCache::connectAndCache: Acquiring new connection for host serv-master-emm.central.iamspe.com.br, query type 223

11:19:40.799 [1832.4036] <2> vnet_pbxConnect: pbxConnectEx Succeeded

11:19:40.799 [1832.4036] <2> logconnections: BPDBM CONNECT FROM 10.146.20.19.50061 TO 10.146.20.20.1556 fd = 496

11:19:40.799 [1832.4036] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2

11:19:40.830 [2892.3372] <2> db_CLIENTsend: reset client protocol version from 0 to 8

11:19:40.877 [1832.4036] <2> db_CLIENTsend: reset client protocol version from 0 to 8

11:19:41.033 [2892.3372] <2> db_getCLIENT: db_CLIENTreceive: no entity was found 227

11:19:41.033 [2892.3372] <2> verify_client: bpbrm.c.37835: db_getCLIENT failed for CLIENT_HOSTNAME: serv-media01.central.iamspe.com.br

11:19:41.033 [2892.3372] <2> verify_client: bpbrm.c.37980: db_getCLIENT failed: 227 227 0x000000e3

11:19:41.033 [2892.3372] <2> bpbrm create_events: created suspend event name Global\NetBackup Suspend Event, pid: 2892

11:19:41.033 [2892.3372] <2> bpbrm Exit: backup_status: 0

11:19:41.049 [2892.3372] <2> vnet_pbxConnect: pbxConnectEx Succeeded

11:19:41.049 [2892.3372] <2> job_connect: SO_KEEPALIVE set on socket 520 for client serv-master-emm.central.iamspe.com.br

11:19:41.049 [2892.3372] <2> logconnections: BPJOBD CONNECT FROM 10.146.20.19.50062 TO 10.146.20.20.1556 fd = 520

11:19:41.049 [2892.3372] <2> job_authenticate_connection: ignoring VxSS authentication check for now...

11:19:41.049 [2892.3372] <2> job_connect: Connected to the host serv-master-emm.central.iamspe.com.br contype 53 jobid <119741> socket <520>

11:19:41.049 [2892.3372] <2> job_connect: Connected on port 50062

11:19:41.049 [2892.3372] <2> logconnections: BPCD CONNECT FROM 10.146.20.19.50063 TO 10.146.20.19.13782 fd = 524

11:19:41.080 [1832.4036] <2> db_getCLIENT: db_CLIENTreceive: no entity was found 227

11:19:41.080 [1832.4036] <2> verify_client: bpbrm.c.37835: db_getCLIENT failed for CLIENT_HOSTNAME: serv-media01.central.iamspe.com.br

11:19:41.080 [1832.4036] <2> verify_client: bpbrm.c.37980: db_getCLIENT failed: 227 227 0x000000e3

11:19:41.080 [1832.4036] <2> bpbrm create_events: created suspend event name Global\NetBackup Suspend Event, pid: 1832

11:19:41.080 [1832.4036] <2> bpbrm Exit: backup_status: 0

11:19:41.096 [2892.3372] <2> bpbrm start_bpcd_stat: DATA_SOCK from bpcr = 524

11:19:41.096 [2892.3372] <2> bpbrm start_bpcd_stat: NAME_SOCK from bpcr = 536

11:19:41.096 [2892.3372] <2> pfi_start_client: command = /usr/openv/netbackup/bin/bpfis bpfis delete -nbu -id APPLICATION-TFS_1388755041 -bpstart_to 300 -bpend_to 300 -clnt serv-media01.central.iamspe.com.br -S serv-master-emm.central.iamspe.com.br -jobid 119741 -copy 1

11:19:41.096 [2892.3372] <2> pfi_start_client: received bpcd success message

11:19:41.096 [1832.4036] <2> vnet_pbxConnect: pbxConnectEx Succeeded

11:19:41.096 [1832.4036] <2> job_connect: SO_KEEPALIVE set on socket 520 for client serv-master-emm.central.iamspe.com.br

11:19:41.096 [1832.4036] <2> logconnections: BPJOBD CONNECT FROM 10.146.20.19.50064 TO 10.146.20.20.1556 fd = 520

11:19:41.096 [1832.4036] <2> job_authenticate_connection: ignoring VxSS authentication check for now...

11:19:41.096 [1832.4036] <2> job_connect: Connected to the host serv-master-emm.central.iamspe.com.br contype 53 jobid <119741> socket <520>

11:19:41.096 [1832.4036] <2> job_connect: Connected on port 50064

11:19:41.096 [1832.4036] <2> logconnections: BPCD CONNECT FROM 10.146.20.19.50066 TO 10.146.20.19.13782 fd = 528

11:19:41.143 [1832.4036] <2> bpbrm start_bpcd_stat: DATA_SOCK from bpcr = 528

11:19:41.143 [1832.4036] <2> bpbrm start_bpcd_stat: NAME_SOCK from bpcr = 536

11:19:41.143 [1832.4036] <2> pfi_start_client: command = /usr/openv/netbackup/bin/bpfis bpfis delete -nbu -id APPLICATION-TFS_1388755041 -bpstart_to 300 -bpend_to 300 -clnt serv-media01.central.iamspe.com.br -S serv-master-emm.central.iamspe.com.br -jobid 119741 -copy 1

11:19:41.143 [1832.4036] <2> pfi_start_client: received bpcd success message

11:19:55.596 [3576.1140] <2> pfi_start_client: read start message from serv-media01.central.iamspe.com.br, msg=<INF - BACKUP START 3464>

11:20:05.205 [1764.3244] <2> bpbrm main: INITIATING (VERBOSE = 0): version NetBackup 7.5 0

11:20:05.205 [1764.3244] <2> logparams: -c APPLICATION-TFS -cl VMWARE-ESXi-03 -ct 40 -id APPLICATION-TFS_1388755041 -nbu -sched Semanal -st 0 -secure 1 -S serv-master-emm.central.iamspe.com.br -jobid 119741 -jobgrpid 119741 -vm_proxy serv-media01.central.iamspe.com.br -status 0

11:20:05.221 [1764.3244] <2> vnet_pbxConnect: pbxConnectEx Succeeded

11:20:05.221 [1764.3244] <2> logconnections: BPRD CONNECT FROM 10.146.20.19.50080 TO 10.146.20.20.1556 fd = 492

11:20:05.221 [1764.3244] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2

11:20:05.393 [1764.3244] <2> brm_update_local_resiliency: changed = 0

11:20:05.393 [1764.3244] <2> ConnectionCache::connectAndCache: Acquiring new connection for host serv-master-emm.central.iamspe.com.br, query type 223

11:20:05.393 [1764.3244] <2> vnet_pbxConnect: pbxConnectEx Succeeded

11:20:05.393 [1764.3244] <2> logconnections: BPDBM CONNECT FROM 10.146.20.19.50081 TO 10.146.20.20.1556 fd = 496

11:20:05.393 [1764.3244] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2

11:20:05.471 [1764.3244] <2> db_CLIENTsend: reset client protocol version from 0 to 8

11:20:05.674 [1764.3244] <2> db_getCLIENT: db_CLIENTreceive: no entity was found 227

11:20:05.674 [1764.3244] <2> ConnectionCache::connectAndCache: Acquiring new connection for host serv-master-emm.central.iamspe.com.br, query type 223

11:20:05.674 [1764.3244] <2> vnet_pbxConnect: pbxConnectEx Succeeded

11:20:05.674 [1764.3244] <2> logconnections: BPDBM CONNECT FROM 10.146.20.19.50082 TO 10.146.20.20.1556 fd = 492

11:20:05.674 [1764.3244] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2

11:20:05.752 [1764.3244] <2> db_CLIENTsend: reset client protocol version from 0 to 8

11:20:05.955 [1764.3244] <2> db_getCLIENT: db_CLIENTreceive: no entity was found 227

11:20:05.955 [1764.3244] <2> verify_client: bpbrm.c.37835: db_getCLIENT failed for CLIENT_HOSTNAME: serv-media01.central.iamspe.com.br

11:20:05.955 [1764.3244] <2> verify_client: bpbrm.c.37980: db_getCLIENT failed: 227 227 0x000000e3

11:20:05.955 [1764.3244] <2> bpbrm create_events: created suspend event name Global\NetBackup Suspend Event, pid: 1764

11:20:05.955 [1764.3244] <2> bpbrm Exit: backup_status: 0

11:20:05.955 [1764.3244] <2> vnet_pbxConnect: pbxConnectEx Succeeded

11:20:05.955 [1764.3244] <2> job_connect: SO_KEEPALIVE set on socket 520 for client serv-master-emm.central.iamspe.com.br

11:20:05.955 [1764.3244] <2> logconnections: BPJOBD CONNECT FROM 10.146.20.19.50083 TO 10.146.20.20.1556 fd = 520

11:20:05.955 [1764.3244] <2> job_authenticate_connection: ignoring VxSS authentication check for now...

11:20:05.955 [1764.3244] <2> job_connect: Connected to the host serv-master-emm.central.iamspe.com.br contype 53 jobid <119741> socket <520>

11:20:05.955 [1764.3244] <2> job_connect: Connected on port 50083

can someone help me?

Anderson_Santos · ‎01-03-2014

log bpvmutil do backup host:

09:06:55.160 [4064.4408] <2> logparams: N:\Program Files\Veritas\NetBackup\bin\bpVMutil.exe 0 0 _NONE_

09:06:55.160 [4064.4408] virt_test_log: log function is called

09:06:55.160 [4064.4408] <2> bpVMutil main: cmd = 0 ESXMount = 0 ESXserver = _NONE_, flags = 0

09:06:55.160 [4064.4408] checkBackupRegEntry: searching configuration for Name

09:06:55.160 [4064.4408] RegOpenKey failed with 2

09:06:55.160 [4064.4408] checkBackupRegEntry: searching configuration for Type

09:06:55.160 [4064.4408] RegOpenKey failed with 2

09:06:55.176 [4064.4408] <2> vnet_pbxConnect: pbxConnectEx Succeeded

09:06:55.176 [4064.4408] <2> logconnections: BPRD CONNECT FROM 10.146.20.19.56241 TO 10.146.20.20.1556 fd = 384

09:06:55.176 [4064.4408] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2

09:06:55.551 [4064.4408] checkBackupRegEntry: searching configuration for excludeVMservers

09:06:55.551 [4064.4408] RegOpenKey failed with 2

09:06:55.551 [4064.4408] format_virtualserver_cred: read 1 vw-vcenter-01.central.iamspe.com.br Netbackup 2b36e02f5176bc4df96e71e4779710fbde141d53ffb37fa599d358852c23fb9b201119d146facd5fe09ec66830dcc4b90bf4260c4066303740981c89fad82e18 21018 443

09:06:55.551 [4064.4408] <2> bpVMutil main: Saving vw-vcenter-01.central.iamspe.com.br guests to C:\Windows\TEMP\VMutl-04064388747215160408000000000-a04408

09:06:55.551 [4064.4408] <2> get_VM_names_VMware: get_VM_names_VMware: read for vw-vcenter-01.central.iamspe.com.br

09:06:55.551 [4064.4408] read cache file = N:\Program Files\Veritas\NetBackup\online_util\fi_cntl\vcbnames_vw-vcenter-01.central.iamspe.com.br_Any

09:06:55.551 [4064.4408] read_guests returned -1 and 0

09:06:55.551 [4064.4408] checkBackupRegEntry: searching configuration for xmlCacheLimit

09:06:55.551 [4064.4408] RegOpenKey failed with 2

09:06:55.551 [4064.4408] get_vSphere_VMs: get_id_lock returned 0: -> wait lock timeout value: 15 minutes -> WRITE LOCK vw-vcenter-01.central.iamspe.com.brvview_lock fd = 252 -> lockname = vw-vcenter-01.central.iamspe.com.brvview_lock

09:06:55.551 [4064.4408] getvSphereVmView: Getting VM's for vw-vcenter-01.central.iamspe.com.br

09:06:55.551 [4064.4408] refreshViewCache: N:\Program Files\Veritas\NetBackup\online_util\fi_cntl\vw-vcenter-01.central.iamspe.com.br.xml over 28800 seconds old, rebuilding

09:06:55.551 [4064.4408] checkBackupRegEntry: searching configuration for connecttimeout

09:06:55.551 [4064.4408] RegOpenKey failed with 2

09:06:55.551 [4064.4408] vSphereConnect: ConnectTimeout: 300 secs

09:06:55.551 [4064.4408] vSphereConnect: connecting to https://vw-vcenter-01.central.iamspe.com.br:443/sdk

09:06:55.551 [4064.4408] dovSphereDisconnect: disconnect for vw-vcenter-01.central.iamspe.com.br completed with flags 0 and status 0

09:06:56.019 [4064.4408] checkBackupRegEntry: searching configuration for jobtimeout

09:06:56.019 [4064.4408] RegOpenKey failed with 2

09:06:56.019 [4064.4408] checkBackupRegEntry: searching configuration for poweroptimeout

09:06:56.019 [4064.4408] RegOpenKey failed with 2

09:06:56.019 [4064.4408] checkBackupRegEntry: searching configuration for snapshottimeout

09:06:56.019 [4064.4408] RegOpenKey failed with 2

09:06:56.019 [4064.4408] checkBackupRegEntry: searching configuration for registertimeout

09:06:56.019 [4064.4408] RegOpenKey failed with 2

09:06:56.019 [4064.4408] checkBackupRegEntry: searching configuration for browsetimeout

09:06:56.019 [4064.4408] RegOpenKey failed with 2

09:06:56.019 [4064.4408] checkBackupRegEntry: searching configuration for refreshtimeout

09:06:56.019 [4064.4408] RegOpenKey failed with 2

09:06:56.019 [4064.4408] SetTimeoutsAndLogging: JobTimeout: 900 secs

09:06:56.019 [4064.4408] SetTimeoutsAndLogging: PowerOpTimeout: 900 secs

09:06:56.019 [4064.4408] SetTimeoutsAndLogging: SnapshotTimeout: 900 secs

09:06:56.019 [4064.4408] SetTimeoutsAndLogging: RegisterTimeout: 180 secs

09:06:56.019 [4064.4408] SetTimeoutsAndLogging: BrowseTimeout: 3 secs

09:06:56.019 [4064.4408] SetTimeoutsAndLogging: RefreshTimeout: 3 secs

09:06:56.019 [4064.4408] checkBackupRegEntry: searching configuration for vmcloglevel

09:06:56.019 [4064.4408] RegOpenKey failed with 2

09:06:56.019 [4064.4408] SetTimeoutsAndLogging: vmcloglevel=0

09:06:56.113 [4064.4408] vmwareLogger: LoginAPI: key = 52c46421-a32b-5f09-4a85-13feeab1090a

09:06:56.113 [4064.4408] dovSphereLogin: sApiType = VirtualCenter

09:06:56.113 [4064.4408] dovSphereLogin: version = 5.0

09:06:56.113 [4064.4408] dovSphereLogin: sBuild = 623373

09:06:56.113 [4064.4408] dovSphereLogin: full name = VMware vCenter Server 5.0.0 build-623373

09:06:56.113 [4064.4408] dovSphereLogin: sName = VMware vCenter Server

09:06:56.113 [4064.4408] dovSphereLogin: sOsType = win32-x64

09:06:56.113 [4064.4408] dovSphereLogin: sProductLineId = vpx

09:06:56.113 [4064.4408] dovSphereLogin: sVersion = 5.0.0

09:06:56.113 [4064.4408] dovSphereLogin: sServerCurrentTime = Fri Jan 03 09:06:57 2014

09:06:56.113 [4064.4408] dovSphereLogin: login for vw-vcenter-01.central.iamspe.com.br completed with flags 70 and status 0

09:06:56.113 [4064.4408] vmwareLogger: BuildDatabases: Building databases

09:06:58.894 [4064.4408] vmwareLogger: RetrieveHostContents: Skipping host <10.146.18.12> because state is notResponding

09:06:58.894 [4064.4408] vmwareLogger: RetrieveHostContents: Skipping host <10.146.18.11> because state is notResponding

09:06:59.066 [4064.4408] checkBackupRegEntry: searching configuration for disableIPResolution

09:06:59.066 [4064.4408] RegOpenKey failed with 2

09:06:59.066 [4064.4408] checkBackupRegEntry: searching configuration for UseNicIpaddress

09:06:59.066 [4064.4408] RegOpenKey failed with 2

09:06:59.301 [4064.4408] getVMsFromProxyView: ipaddress

09:06:59.301 [4064.4408] getVMsFromProxyView: hostname serv-iamspe-tm.central.iamspe.com.br

09:06:59.301 [4064.4408] getVMsFromProxyView: snapshot count = 0

09:06:59.301 [4064.4408] getVMsFromProxyView: discarding invalid IP address

09:06:59.301 [4064.4408] getVMsFromProxyView: vm is template

09:06:59.301 [4064.4408] getVMsFromProxyView: snapshot count = 0

09:06:59.301 [4064.4408] getVMsFromProxyView: ipaddress

09:06:59.301 [4064.4408] getVMsFromProxyView: hostname serv-3par-opsys.central.iamspe.com.br

09:06:59.301 [4064.4408] getVMsFromProxyView: snapshot count = 0

09:06:59.301 [4064.4408] getVMsFromProxyView: ipaddress

09:07:03.801 [4064.4408] <8> retry_getnameinfo: [vnet_addrinfo.c:1151] getnameinfo() failed 11001 0x2af9

09:07:03.801 [4064.4408] <8> vnet_cached_getnameinfo: [vnet_addrinfo.c:1900] retry_getnameinfo() failed RV=11001 IPSTR=172.30.16.35 PORT=0

09:07:03.801 [4064.4408] getVMsFromProxyView: vnet_cached_getnameinfo for 172.30.16.35 failed with status of 11001