Forum Discussion

Anderson_Santos's avatar
11 years ago

Backup VMWARE fail system call failed(11)

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> 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> 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> 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> 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

13 Replies

  • My backup host is Mediaserver Netbackup 7.5.0.3 with Windows 2008 R2 Enterprise 64 bits

    Configuration:

    DISKPART> list disk
     
      Disk ###  Status         Size     Free     Dyn  Gpt
      --------  -------------  -------  -------  ---  ---
      Disk 0    Online          273 GB      0 B
      Disk 1    Online          700 GB      0 B        *
      Disk 2    Online          700 GB      0 B        *
      Disk 3    Online          700 GB      0 B        *
      Disk 4    Online          700 GB      0 B        *
      Disk 5    Online          700 GB      0 B        *
      Disk 6    Online          700 GB      0 B        *
  • 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-system-call-failed-11

     

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

  • Do you have any VMs successfull from this media server & backup host?
    no i have
    does it ever worked?
    yes, worked
    what is the Vcenter version and Backup host OS?
    Vcenter version 5.0 and Backup host OS Windows 2008 R2 Enterprise