di14:50:00.512 [9136.8680] <4> bpbrm main: logging will continue into new log at midnight 14:50:00.512 [9136.8680] <2> bpbrm main: INITIATING (VERBOSE = 3): version NetBackup 8.1.2 2018082718 14:50:00.512 [9136.8680] <2> logparams: -backup -backup_copy 0 -b cute_cats.abc.net_1603695028 -bt 1603695028 -c cute_cats.abc.net -cl VMware_Backup -ct 40 -dt 0 -fso -jobid 219837 -jobgrpid 219837 -masterversion 810000 -mediasvr mediaserver02.abc.net -mpx 0 -nbu -p NONE -ru root -rg other -sched Full -st 1 -S masterserver01.abc.net -stream_count 2 -stream_number 1 -snaponly -secure 1 -vm_proxy mediaserver02.abc.net -block_incr -prev_bli_incr 0 -last_bli_full 0 -use_ofb -use_otm -fi -fim VMware_v2:skipnodisk=0,post_events=1,multi_org=0,Virtual_machine_backup=2,continue_discovery=0,exclude_swap=1,nameuse=0,tags_unset=0,ignore_irvm=1,rLim=10,snapact=2,enable_quiesce_failover=0,drive_selection=0,file_system_optimization=1,disable_quiesce=0,trantype=san:hotadd:nbd:nbdssl,rHz=10,rTO=0 -nbu 14:50:00.512 [9136.8680] <2> parse_arguments: DBG - Snapshot method [297] - VMware_v2:skipnodisk=0,post_events=1,multi_org=0,Virtual_machine_backup=2,continue_discovery=0,exclude_swap=1,nameuse=0,tags_unset=0,ignore_irvm=1,rLim=10,snapact=2,enable_quiesce_failover=0,drive_selection=0,file_system_optimization=1,disable_quiesce=0,trantype=san:hotadd:nbd:nbdssl,rHz=10,rTO=0 14:50:00.512 [9136.8680] <2> ConnectionCache::connectAndCache: Acquiring new connection for host masterserver01.abc.net, query type 223 14:50:00.512 [9136.8680] <2> vnet_pbxConnect_ex: pbxConnectExEx Succeeded 14:50:00.512 [9136.8680] <4> create_user_group_id_marker_WIN: connecting to named pipe:\\.\pipe\{2B2C3C29-7B07-4446-9618-1DA0B4741464}:OUTBOUND 14:50:00.512 [9136.8680] <4> create_user_group_id_marker_WIN: successfully connected to server named pipe 14:50:00.605 [9136.8680] <2> logconnections: PROXY CONNECT FROM 10.20.36.21.62273 TO 10.20.34.38.1556 fd = 720 14:50:00.605 [9136.8680] <2> logconnections: BPDBM CONNECT FROM 127.0.0.1.62275 TO 127.0.0.1.62276 fd = 720 14:50:00.605 [9136.8680] <2> db_CLIENTsend: reset client protocol version from 0 to 9 14:50:00.621 [9136.8680] <2> db_getCLIENT: db_CLIENTreceive: no entity was found 227 14:50:00.621 [9136.8680] <2> check_dynamic_client_and_set_tss: Client entry for client cute_cats.abc.net not found in client DB 14:50:00.637 [9136.8680] <2> vnet_pbxConnect_ex: pbxConnectExEx Succeeded 14:50:00.637 [9136.8680] <4> create_user_group_id_marker_WIN: connecting to named pipe:\\.\pipe\{D91046ED-D86D-430A-B4CA-A1344EE8234A}:OUTBOUND 14:50:00.637 [9136.8680] <4> create_user_group_id_marker_WIN: successfully connected to server named pipe 14:50:00.933 [9136.8680] <2> logconnections: PROXY CONNECT FROM 10.20.36.21.62277 TO 10.20.34.38.1556 fd = 724 14:50:00.933 [9136.8680] <2> logconnections: BPRD CONNECT FROM 127.0.0.1.62279 TO 127.0.0.1.62280 fd = 724 14:50:01.043 [9136.8680] <4> getUnameInfoData: Windows OS Version - MajorVersion****= 10 - MinorVersion****= 0 14:50:01.043 [9136.8680] <2> brm_update_local_resiliency: changed = 0 14:50:01.043 [9136.8680] <2> bpbrm main: Cleaning cs_cache for client:[mediaserver02.abc.net] older than:[30] sec 14:50:01.043 [9136.8680] <2> bpbrm create_events: created suspend event name Global\NetBackup Suspend Event, pid: 9136 14:50:01.043 [9136.8680] <2> bpbrm main: max_entries_per_add = 95000 14:50:01.043 [9136.8680] <2> bpbrm main: max_files_per_cache = 50000 14:50:01.043 [9136.8680] <2> ConnectionCache::connectAndCache: Acquiring new connection for host masterserver01.abc.net, query type 223 14:50:01.058 [9136.8680] <2> vnet_pbxConnect_ex: pbxConnectExEx Succeeded 14:50:01.058 [9136.8680] <4> create_user_group_id_marker_WIN: connecting to named pipe:\\.\pipe\{BA4264B3-66FD-4020-B0F3-EE2FD9B54C88}:OUTBOUND 14:50:01.058 [9136.8680] <4> create_user_group_id_marker_WIN: successfully connected to server named pipe 14:50:01.152 [9136.8680] <2> logconnections: PROXY CONNECT FROM 10.20.36.21.62281 TO 10.20.34.38.1556 fd = 748 14:50:01.152 [9136.8680] <2> logconnections: BPDBM CONNECT FROM 127.0.0.1.62283 TO 127.0.0.1.62284 fd = 748 14:50:01.152 [9136.8680] <2> db_CLIENTsend: reset client protocol version from 0 to 9 14:50:01.152 [9136.8680] <2> db_end: Need to collect reply 14:50:01.183 [9136.8680] <2> logconnections: BPCD CONNECT FROM 10.20.36.21.62285 TO 10.20.36.21.13782 fd = 748 14:50:01.277 [9136.8680] <2> bpcr_get_version_rqst: bpcd version: 08100000 14:50:01.277 [9136.8680] <2> bpcr_get_version_rqst: bpcd version: 08100000 14:50:01.277 [9136.8680] <2> local_getHostInfo: CLIENT_CMD_SOCK from bpcr = 748 14:50:01.277 [9136.8680] <2> bpcr_get_version_rqst: bpcd version: 08100000 14:50:01.308 [9136.8680] <2> local_getHostInfo: hostname comparison failed 14:50:01.308 [9136.8680] <2> bpcr_get_version_rqst: bpcd version: 08100000 14:50:01.308 [9136.8680] <2> local_getHostInfo: rqstType == BPCD_GET_NB_VERSION_RQST 14:50:01.308 [9136.8680] <2> bpcr_get_platform_rqst: Server platform length = 7 14:50:01.308 [9136.8680] <2> xlatePlatformName: my version = 810000, client version = 810000, check current list first 14:50:01.308 [9136.8680] <2> xlatePlatformName: BUILD_PLATFORM name to xlate = win_x64 14:50:01.308 [9136.8680] <2> local_getHostInfo: platform of client mediaserver02.abc.net is PC-x64, WindowsXP 14:50:01.308 [9136.8680] <2> bpcr_get_uname_rqst: bpcr.c: 3593, entering bpcr_get_uname_rqst 14:50:01.308 [9136.8680] <2> bpcr_get_uname_rqst: bpcr.c: 3624 count=28, buffer=UNAME_SYSNAME = Windows2016 14:50:01.308 [9136.8680] <2> bpcr_get_uname_rqst: bpcr.c: 3638, utsname->sysname=Windows2016 14:50:01.308 [9136.8680] <2> bpcr_get_uname_rqst: bpcr.c: 3624 count=31, buffer=UNAME_NODENAME = MEDIASERVER02 14:50:01.308 [9136.8680] <2> bpcr_get_uname_rqst: bpcr.c: 3624 count=19, buffer=UNAME_RELEASE = 10 14:50:01.308 [9136.8680] <2> bpcr_get_uname_rqst: bpcr.c: 3657, utsname->nodename=MEDIASERVER02 14:50:01.308 [9136.8680] <2> bpcr_get_uname_rqst: bpcr.c: 3624 count=18, buffer=UNAME_VERSION = 0 14:50:01.308 [9136.8680] <2> bpcr_get_uname_rqst: bpcr.c: 3657, utsname->nodename=MEDIASERVER02 14:50:01.308 [9136.8680] <2> bpcr_get_uname_rqst: bpcr.c: 3674, utsname->release=10 14:50:01.308 [9136.8680] <2> bpcr_get_uname_rqst: bpcr.c: 3624 count=52, buffer=UNAME_MACHINE = Unknown - Unknown - Revision x5504 14:50:01.308 [9136.8680] <2> bpcr_get_uname_rqst: bpcr.c: 3657, utsname->nodename=MEDIASERVER02 14:50:01.308 [9136.8680] <2> bpcr_get_uname_rqst: bpcr.c: 3674, utsname->release=10 14:50:01.308 [9136.8680] <2> bpcr_get_uname_rqst: bpcr.c: 3691, utsname->version=0 14:50:01.308 [9136.8680] <2> bpcr_get_uname_rqst: bpcr.c: 3624 count=3, buffer=END 14:50:01.308 [9136.8680] <2> bpcr_get_uname_rqst: bpcr.c: 3657, utsname->nodename=MEDIASERVER02 14:50:01.308 [9136.8680] <2> bpcr_get_uname_rqst: bpcr.c: 3674, utsname->release=10 14:50:01.308 [9136.8680] <2> bpcr_get_uname_rqst: bpcr.c: 3691, utsname->version=0 14:50:01.308 [9136.8680] <2> bpcr_get_uname_rqst: bpcr.c: 3708, utsname->machine=Unknown - Unknown - Revision x5504 14:50:01.308 [9136.8680] <2> bpcr_get_uname_rqst: bpcr.c: 3732, return success 14:50:01.308 [9136.8680] <2> local_getHostInfo: d:\nb\8.1.2\src\nb\dblib\dbmisc.c: 1748, uname -sr=Windows2016 10 14:50:01.308 [9136.8680] <2> bpcr_get_version_rqst: bpcd version: 08100000 14:50:01.308 [9136.8680] <2> getPatchLevel: CLIENT_CMD_SOCK from bpcr = 748 14:50:01.308 [9136.8680] <2> getPatchLevel: got client socket 14:50:01.308 [9136.8680] <2> getPatchLevel: file num = 4, file size = 107 14:50:01.308 [9136.8680] <2> bpcr_read_rqst: completed send of file number 4 14:50:01.308 [9136.8680] <2> bpcr_read_rqst: completed send of transfer_count = 16383 14:50:01.308 [9136.8680] <2> bpcr_read_rqst: Before readclientstatus on socket 732 14:50:01.308 [9136.8680] <2> bpcr_read_rqst: Num bytes to be sent by client = 107 14:50:01.308 [9136.8680] <2> getPatchLevel: total chars read = 107 14:50:01.308 [9136.8680] <2> getPatchLevel: level = >8.1.2< 14:50:01.308 [9136.8680] <2> decode_fim_attr: decode_fim_attr: fim=VMware_v2:skipnodisk=0,post_events=1,multi_org=0,Virtual_machine_backup=2,continue_discovery=0,exclude_swap=1,nameuse=0,tags_unset=0,ignore_irvm=1,rLim=10,snapact=2,enable_quiesce_failover=0,drive_selection=0,file_system_optimization=1,disable_quiesce=0,trantype=san:hotadd:nbd:nbdssl,rHz=10,rTO=0, client version=810000 14:50:01.308 [9136.8680] <2> decode_fim_attr: decode_fim_attr: Virtual_machine_backup 2 bkup_time 1603695028 sched type 1 14:50:01.308 [9136.8680] <2> decode_fim_attr: decode_fim_attr: mapped fim: VMware_v2:skipnodisk=0,post_events=1,multi_org=0,Virtual_machine_backup=2,continue_discovery=0,exclude_swap=1,nameuse=0,tags_unset=0,ignore_irvm=1,rLim=10,snapact=2,enable_quiesce_failover=0,drive_selection=0,file_system_optimization=1,disable_quiesce=0,trantype=san:hotadd:nbd:nbdssl,rHz=10,rTO=0, rfi_backup 0, keep_fi 0 14:50:01.308 [9136.8680] <2> decode_fim_attr: decode_fim_attr: nameuse: 0 14:50:01.308 [9136.8680] <2> logconnections: BPCD CONNECT FROM 10.20.36.21.62287 TO 10.20.36.21.13782 fd = 732 14:50:01.402 [9136.8680] <2> bpcr_get_version_rqst: bpcd version: 08100000 14:50:01.402 [9136.8680] <2> bpcr_get_version_rqst: bpcd version: 08100000 14:50:01.402 [9136.8680] <2> ddns_check: Entering 14:50:01.402 [9136.8680] <2> bpbrm start_bpcd_stat: DATA_SOCK from bpcr = 732 14:50:01.402 [9136.8680] <2> bpbrm start_bpcd_stat: NAME_SOCK from bpcr = 740 14:50:01.402 [9136.8680] <2> local_getHostInfo: found TSS cached host mediaserver02.abc.net type 67 14:50:01.402 [9136.8680] <2> bpbrm main: Client NBU version: 810000 14:50:01.418 [9136.8680] <2> vnet_pbxConnect_ex: pbxConnectExEx Succeeded 14:50:01.418 [9136.8680] <4> create_user_group_id_marker_WIN: connecting to named pipe:\\.\pipe\{84EEE855-6F58-44DE-A45A-12EB8CE40687}:OUTBOUND 14:50:01.418 [9136.8680] <4> create_user_group_id_marker_WIN: successfully connected to server named pipe 14:50:01.449 [9136.8680] <2> job_connect: SO_KEEPALIVE set on socket 760 for client masterserver01.abc.net 14:50:01.449 [9136.8680] <2> logconnections: PROXY CONNECT FROM 10.20.36.21.62289 TO 10.20.34.38.1556 fd = 760 14:50:01.449 [9136.8680] <2> logconnections: BPJOBD CONNECT FROM 127.0.0.1.62291 TO 127.0.0.1.62292 fd = 760 14:50:01.449 [9136.8680] <2> job_authenticate_connection: VxSS authentication check for now... 14:50:01.465 [9136.8680] <2> job_connect: Connected to the host masterserver01.abc.net contype 53 jobid <219837> socket <760> 14:50:01.465 [9136.8680] <2> job_connect: Connected on port 62289 14:50:01.465 [9136.8680] <4> JobdSockList::RegisterSocket: Register socket (760). 14:50:01.465 [9136.8680] <2> logconnections: BPCD CONNECT FROM 10.20.36.21.62293 TO 10.20.36.21.13782 fd = 732 14:50:01.558 [9136.8680] <2> bpcr_get_version_rqst: bpcd version: 08100000 14:50:01.558 [9136.8680] <2> bpcr_get_version_rqst: bpcd version: 08100000 14:50:01.558 [9136.8680] <2> bpcr_get_version_rqst: bpcd version: 08100000 14:50:01.558 [9136.8680] <2> decode_fim_attr: decode_fim_attr: fim=VMware_v2:skipnodisk=0,post_events=1,multi_org=0,Virtual_machine_backup=2,continue_discovery=0,exclude_swap=1,nameuse=0,tags_unset=0,ignore_irvm=1,rLim=10,snapact=2,enable_quiesce_failover=0,drive_selection=0,file_system_optimization=1,disable_quiesce=0,trantype=san:hotadd:nbd:nbdssl,rHz=10,rTO=0, client version=810000 14:50:01.558 [9136.8680] <2> decode_fim_attr: decode_fim_attr: Virtual_machine_backup 2 bkup_time 1603695028 sched type 1 14:50:01.558 [9136.8680] <2> decode_fim_attr: decode_fim_attr: mapped fim: VMware_v2:skipnodisk=0,post_events=1,multi_org=0,Virtual_machine_backup=2,continue_discovery=0,exclude_swap=1,nameuse=0,tags_unset=0,ignore_irvm=1,rLim=10,snapact=2,enable_quiesce_failover=0,drive_selection=0,file_system_optimization=1,disable_quiesce=0,trantype=san:hotadd:nbd:nbdssl,rHz=10,rTO=0, rfi_backup 0, keep_fi 0 14:50:01.558 [9136.8680] <2> decode_fim_attr: decode_fim_attr: nameuse: 0 14:50:01.558 [9136.8680] <4> bpfis_start: Processing snapshot create request... 14:50:01.558 [9136.8680] <2> get_cached_client_version: Using cached version. CLNT=mediaserver02.abc.net V=135266304 Fetched=Mon Oct 26 14:50:01 2020 14:50:01.558 [9136.8680] <2> get_cached_client_version: Using cached version. CLNT=mediaserver02.abc.net V=135266304 Fetched=Mon Oct 26 14:50:01 2020 14:50:01.558 [9136.8680] <2> get_cached_client_version: Using cached version. CLNT=mediaserver02.abc.net V=135266304 Fetched=Mon Oct 26 14:50:01 2020 14:50:01.558 [9136.8680] <2> bpfis_start: DBG: BPFIS Commands [780] - /usr/openv/netbackup/bin/bpfis bpfis create -nbu -owner NBU -id cute_cats.abc.net_1603695028 -bpstart_to 300 -bpend_to 300 -backup_copy 0 -ru root -pt 40 -vm_type 2 -vm_client cute_cats.abc.net -clnt mediaserver02.abc.net -st INCR -block_incr -prev_bli_incr 0 -last_bli_full 0 -dt 0 -ru root -rg other -fim VMware_v2:skipnodisk=0,post_events=1,multi_org=0,Virtual_machine_backup=2,continue_discovery=0,exclude_swap=1,nameuse=0,tags_unset=0,ignore_irvm=1,rLim=10,snapact=2,enable_quiesce_failover=0,drive_selection=0,file_system_optimization=1,disable_quiesce=0,trantype=san:hotadd:nbd:nbdssl,rHz=10,rTO=0,nameuse=0 -class VMware_Backup -sched Full -fso -S masterserver01.abc.net -jobid 219837 -nbu_version 135266304 -application_consistent 1 14:50:01.574 [9136.8680] <2> logconnections: BPCD CONNECT FROM 10.20.36.21.62295 TO 10.20.36.21.13782 fd = 756 14:50:01.668 [9136.8680] <2> bpcr_get_version_rqst: bpcd version: 08100000 14:50:01.668 [9136.8680] <2> bpcr_get_version_rqst: bpcd version: 08100000 14:50:01.668 [9136.8680] <2> ddns_check: Entering 14:50:01.668 [9136.8680] <2> bpbrm start_bpcd_stat: DATA_SOCK from bpcr = 756 14:50:01.668 [9136.8680] <2> bpbrm start_bpcd_stat: NAME_SOCK from bpcr = 752 14:50:01.668 [9136.8680] <2> pfi_start_client: command = /usr/openv/netbackup/bin/bpfis bpfis create -nbu -owner NBU -id cute_cats.abc.net_1603695028 -bpstart_to 300 -bpend_to 300 -backup_copy 0 -ru root -pt 40 -vm_type 2 -vm_client cute_cats.abc.net -clnt mediaserver02.abc.net -st INCR -block_incr -prev_bli_incr 0 -last_bli_full 0 -dt 0 -ru root -rg other -fim VMware_v2:skipnodisk=0,post_events=1,multi_org=0,Virtual_machine_backup=2,continue_discovery=0,exclude_swap=1,nameuse=0,tags_unset=0,ignore_irvm=1,rLim=10,snapact=2,enable_quiesce_failover=0,drive_selection=0,file_system_optimization=1,disable_quiesce=0,trantype=san:hotadd:nbd:nbdssl,rHz=10,rTO=0,nameuse=0 -class VMware_Backup -sched Full -fso -S masterserver01.abc.net -jobid 219837 -nbu_version 135266304 -application_consistent 1 14:50:01.668 [9136.8680] <2> pfi_start_client: (d:\nb\8.1.2\src\nb\brm\bpbrm.c:27418) (Jobid: 219837) Using bpcr_fork_cmd_rqst to start bpfis 14:50:01.668 [9136.8680] <2> pfi_start_client: (d:\nb\8.1.2\src\nb\brm\bpbrm.c:27461) (Jobid: 219837) Connected to bpfis, data socket 756 is not proxied, name socket 752 is not proxied 14:50:01.668 [9136.8680] <2> pfi_start_client: received bpcd success message 14:50:02.543 [9136.8680] <2> pfi_start_client: read start message from mediaserver02.abc.net, msg= 14:50:02.543 [9136.8680] <2> write_file_names: buffering file name 'NEW_STREAM' for output 14:50:02.543 [9136.8680] <2> write_file_names: buffering file name 'ALL_LOCAL_DRIVES' for output 14:50:02.543 [9136.8680] <2> write_file_names: successfully wrote buffer to COMM_SOCK 14:50:02.543 [9136.8680] <2> bpbrm write_filelist: wrote CONTINUE on COMM_SOCK 14:50:02.574 [9136.8680] <2> get_bpfis_msg: bpfis buff: INF - FIS_ID=cute_cats.abc.net_1603695028 14:50:02.574 [9136.8680] <2> get_bpfis_msg: bpfis buff: INF - ACT=SNAPSHOT_METHOD 0 cute_cats.abc.net VMware_v2 14:50:05.512 [9136.8680] <2> get_bpfis_msg: bpfis buff: INF - vmwareLogger: Creating snapshot for vCenter server vcenter.abc.net, ESX host esx07.abc.net, BIOS UUID 4209f059-5b05-530b-e476-f0eec8dbd098, Instance UUID 50097829-2b6f-5dbd-ed7f-1c34b475a7dc, Display Name CUTE_CATS, Hostname CUTE_CATS 14:50:05.512 [9136.8680] <4> get_bpfis_msg: from client cute_cats.abc.net: INF - vmwareLogger: Creating snapshot for vCenter server vcenter.abc.net, ESX host esx07.abc.net, BIOS UUID 4209f059-5b05-530b-e476-f0eec8dbd098, Instance UUID 50097829-2b6f-5dbd-ed7f-1c34b475a7dc, Display Name CUTE_CATS, Hostname CUTE_CATS 14:50:05.528 [9136.8680] <2> get_bpfis_msg: bpfis buff: INF - vmwareLogger: Connection state of virtual machine: connected. 14:50:05.528 [9136.8680] <4> get_bpfis_msg: from client cute_cats.abc.net: INF - vmwareLogger: Connection state of virtual machine: connected. 14:50:07.278 [9136.8680] <2> get_bpfis_msg: bpfis buff: 14:50:07.278 [9136.8680] <2> get_bpfis_msg: bpfis buff: 14:50:07.278 [9136.8680] <2> get_bpfis_msg: bpfis buff: 14:50:08.293 [9136.8680] <2> get_bpfis_msg: bpfis buff: 14:50:09.309 [9136.8680] <2> get_bpfis_msg: bpfis buff: 14:50:10.325 [9136.8680] <2> get_bpfis_msg: bpfis buff: 14:50:11.340 [9136.8680] <2> get_bpfis_msg: bpfis buff: 14:50:12.356 [9136.8680] <2> get_bpfis_msg: bpfis buff: 14:50:13.372 [9136.8680] <2> get_bpfis_msg: bpfis buff: 14:50:14.388 [9136.8680] <2> get_bpfis_msg: bpfis buff: 14:50:15.403 [9136.8680] <2> get_bpfis_msg: bpfis buff: 14:50:16.419 [9136.8680] <2> get_bpfis_msg: bpfis buff: 14:50:17.435 [9136.8680] <2> get_bpfis_msg: bpfis buff: 14:50:18.450 [9136.8680] <2> get_bpfis_msg: bpfis buff: 14:50:19.466 [9136.8680] <2> get_bpfis_msg: bpfis buff: 14:50:20.482 [9136.8680] <2> get_bpfis_msg: bpfis buff: 14:50:21.497 [9136.8680] <2> get_bpfis_msg: bpfis buff: 14:50:22.513 [9136.8680] <2> get_bpfis_msg: bpfis buff: 14:50:23.529 [9136.8680] <2> get_bpfis_msg: bpfis buff: 14:50:24.545 [9136.8680] <2> get_bpfis_msg: bpfis buff: 14:50:25.560 [9136.8680] <2> get_bpfis_msg: bpfis buff: 14:50:26.576 [9136.8680] <2> get_bpfis_msg: bpfis buff: 14:50:27.592 [9136.8680] <2> get_bpfis_msg: bpfis buff: 14:50:28.607 [9136.8680] <2> get_bpfis_msg: bpfis buff: 14:50:29.623 [9136.8680] <2> get_bpfis_msg: bpfis buff: 14:50:29.764 [9136.8680] <2> get_bpfis_msg: bpfis buff: INF - REMAP FILE BACKUP NEW_STREAM 14:50:29.764 [9136.8680] <2> get_bpfis_msg: bpfis buff: INF - REMAP FILE BACKUP FIS_VM_BI_ATTRS_FILE=\online_util\fi_cntl\bpfis.fim.cute_cats.abc.net_1603695028.1.0.NBU_DATA.xml 14:50:29.764 [9136.8680] <2> get_bpfis_msg: bpfis buff: INF - REMAP FILE BACKUP \\.\D:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.cute_cats.abc.net_1603695028.1.0.NBU_DATA.xml USING \\.\C:\ OPTIONS:FITYPE=MIRROR,FSTYPE=NTFS 14:50:30.420 [9136.8680] <2> close_bpfis_connection: Confirmation sent to bpfis 14:50:30.420 [9136.8680] <2> close_bpfis_connection: Received bpfis acknowledgement 14:50:30.420 [9136.8680] <2> get_cached_client_version: Using cached version. CLNT=mediaserver02.abc.net V=135266304 Fetched=Mon Oct 26 14:50:30 2020 14:50:30.420 [9136.8680] <2> inform_client_of_status: COMM_SOCK == INVALID_SOCKET, 0 14:50:30.420 [9136.8680] <2> bpbrm Exit: client snapshot EXIT STATUS 0: the requested operation was successfully completed 14:50:30.451 [9136.8680] <4> JobdSockList::UnregisterSocket: Unregister socket (760). 14:50:30.451 [9136.8680] <2> job_monitoring_exex: ACK disconnect 14:50:30.451 [9136.8680] <2> job_disconnect: Disconnected 14:50:31.139 [7404.1340] <4> bpbrm main: logging will continue into new log at midnight 14:50:31.139 [7404.1340] <2> bpbrm main: INITIATING (VERBOSE = 3): version NetBackup 8.1.2 2018082718 14:50:31.139 [7404.1340] <2> logparams: -backup -S masterserver01.abc.net -c cute_cats.abc.net -ct 40 -ru root -cl VMware_Backup -sched Full -b cute_cats.abc.net_1603695059 -bt 1603695059 -dt 0 -st 1 -mediasvr mediaserver02.abc.net -jobid 219838 -jobgrpid 219837 -masterversion 812000 -maxfrag 51200 -bpstart_time 1603695360 -reqid -1603694446 -mt 0 -to 0 -stunit mediaserver02_disk_pool-stu -rl 1 -rp 1209600 -eari 0 -p PureDiskVolume -mst 6 -flags 2 -storagesvr mediaserver02.abc.net -sts_type PureDisk -block_incr -prev_bli_incr 0 -last_bli_full 0 -num_related_classes 1 -use_ofb -use_otm -fi -fim NONE -vm_type 2 -nbu -vm_proxy mediaserver02.abc.net -jm -secure 1 -kl 30 -rg other -fso -ifrfr -connect_options 16974338 14:50:31.139 [7404.1340] <2> parse_arguments: DBG - Snapshot method [4] - NONE 14:50:31.139 [7404.1340] <2> ConnectionCache::connectAndCache: Acquiring new connection for host masterserver01.abc.net, query type 223 14:50:31.154 [7404.1340] <2> vnet_pbxConnect_ex: pbxConnectExEx Succeeded 14:50:31.154 [7404.1340] <4> create_user_group_id_marker_WIN: connecting to named pipe:\\.\pipe\{3991124B-5D00-4A0A-B96C-CFEE182C3E0B}:OUTBOUND 14:50:31.154 [7404.1340] <4> create_user_group_id_marker_WIN: successfully connected to server named pipe 14:50:31.248 [7404.1340] <2> logconnections: PROXY CONNECT FROM 10.20.36.21.62392 TO 10.20.34.38.1556 fd = 764 14:50:31.248 [7404.1340] <2> logconnections: BPDBM CONNECT FROM 127.0.0.1.62394 TO 127.0.0.1.62395 fd = 764 14:50:31.248 [7404.1340] <2> db_CLIENTsend: reset client protocol version from 0 to 9 14:50:31.248 [7404.1340] <2> db_getCLIENT: db_CLIENTreceive: no entity was found 227 14:50:31.248 [7404.1340] <2> check_dynamic_client_and_set_tss: Client entry for client cute_cats.abc.net not found in client DB 14:50:31.279 [7404.1340] <2> vnet_pbxConnect_ex: pbxConnectExEx Succeeded 14:50:31.279 [7404.1340] <4> create_user_group_id_marker_WIN: connecting to named pipe:\\.\pipe\{FEE61400-B9EB-4F22-9117-3877C994255C}:OUTBOUND 14:50:31.279 [7404.1340] <4> create_user_group_id_marker_WIN: successfully connected to server named pipe 14:50:31.576 [7404.1340] <2> logconnections: PROXY CONNECT FROM 10.20.36.21.62396 TO 10.20.34.38.1556 fd = 768 14:50:31.576 [7404.1340] <2> logconnections: BPRD CONNECT FROM 127.0.0.1.62398 TO 127.0.0.1.62399 fd = 768 14:50:31.686 [7404.1340] <4> getUnameInfoData: Windows OS Version - MajorVersion****= 10 - MinorVersion****= 0 14:50:31.686 [7404.1340] <2> brm_update_local_resiliency: changed = 0 14:50:31.686 [7404.1340] <2> bpbrm main: Cleaning cs_cache for client:[mediaserver02.abc.net] older than:[30] sec 14:50:31.686 [7404.1340] <2> bpbrm create_events: created suspend event name Global\NetBackup Suspend Event, pid: 7404 14:50:31.686 [7404.1340] <2> bpbrm main: max_entries_per_add = 95000 14:50:31.686 [7404.1340] <2> bpbrm main: max_files_per_cache = 50000 14:50:31.686 [7404.1340] <2> ConnectionCache::connectAndCache: Acquiring new connection for host masterserver01.abc.net, query type 81 14:50:31.701 [7404.1340] <2> vnet_pbxConnect_ex: pbxConnectExEx Succeeded 14:50:31.701 [7404.1340] <4> create_user_group_id_marker_WIN: connecting to named pipe:\\.\pipe\{5677505A-3BF1-4D2F-B808-33CBE98B7BE6}:OUTBOUND 14:50:31.701 [7404.1340] <4> create_user_group_id_marker_WIN: successfully connected to server named pipe 14:50:31.795 [7404.1340] <2> logconnections: PROXY CONNECT FROM 10.20.36.21.62400 TO 10.20.34.38.1556 fd = 792 14:50:31.795 [7404.1340] <2> logconnections: BPDBM CONNECT FROM 127.0.0.1.62402 TO 127.0.0.1.62403 fd = 792 14:50:31.826 [7404.1340] <2> db_end: Need to collect reply 14:50:31.842 [7404.1340] <2> ConnectionCache::connectAndCache: Acquiring new connection for host masterserver01.abc.net, query type 89 14:50:31.842 [7404.1340] <2> vnet_pbxConnect_ex: pbxConnectExEx Succeeded 14:50:31.842 [7404.1340] <4> create_user_group_id_marker_WIN: connecting to named pipe:\\.\pipe\{B2550C98-1A34-4F5B-884D-60AB43907FB0}:OUTBOUND 14:50:31.842 [7404.1340] <4> create_user_group_id_marker_WIN: successfully connected to server named pipe 14:50:31.936 [7404.1340] <2> logconnections: PROXY CONNECT FROM 10.20.36.21.62404 TO 10.20.34.38.1556 fd = 796 14:50:31.936 [7404.1340] <2> logconnections: BPDBM CONNECT FROM 127.0.0.1.62406 TO 127.0.0.1.62407 fd = 796 14:50:31.936 [7404.1340] <2> db_end: Need to collect reply 14:50:31.998 [7404.1340] <2> bpbrm main: set vm_type = 2 14:50:31.998 [7404.1340] <2> ConnectionCache::connectAndCache: Acquiring new connection for host masterserver01.abc.net, query type 223 14:50:31.998 [7404.1340] <2> vnet_pbxConnect_ex: pbxConnectExEx Succeeded 14:50:31.998 [7404.1340] <4> create_user_group_id_marker_WIN: connecting to named pipe:\\.\pipe\{9DE236D8-8F7F-4B64-B7C4-85A4CEEACFE0}:OUTBOUND 14:50:31.998 [7404.1340] <4> create_user_group_id_marker_WIN: successfully connected to server named pipe 14:50:32.092 [7404.1340] <2> logconnections: PROXY CONNECT FROM 10.20.36.21.62408 TO 10.20.34.38.1556 fd = 800 14:50:32.092 [7404.1340] <2> logconnections: BPDBM CONNECT FROM 127.0.0.1.62410 TO 127.0.0.1.62411 fd = 800 14:50:32.092 [7404.1340] <2> db_CLIENTsend: reset client protocol version from 0 to 9 14:50:32.092 [7404.1340] <2> db_end: Need to collect reply 14:50:32.123 [7404.1340] <2> logconnections: BPCD CONNECT FROM 10.20.36.21.62412 TO 10.20.36.21.13782 fd = 800 14:50:32.233 [7404.1340] <2> bpcr_get_version_rqst: bpcd version: 08100000 14:50:32.233 [7404.1340] <2> bpcr_get_version_rqst: bpcd version: 08100000 14:50:32.233 [7404.1340] <2> local_getHostInfo: CLIENT_CMD_SOCK from bpcr = 800 14:50:32.233 [7404.1340] <2> bpcr_get_version_rqst: bpcd version: 08100000 14:50:32.248 [7404.1340] <2> local_getHostInfo: hostname comparison failed 14:50:32.248 [7404.1340] <2> bpcr_get_version_rqst: bpcd version: 08100000 14:50:32.248 [7404.1340] <2> local_getHostInfo: rqstType == BPCD_GET_NB_VERSION_RQST 14:50:32.248 [7404.1340] <2> bpcr_get_platform_rqst: Server platform length = 7 14:50:32.248 [7404.1340] <2> xlatePlatformName: my version = 810000, client version = 810000, check current list first 14:50:32.248 [7404.1340] <2> xlatePlatformName: BUILD_PLATFORM name to xlate = win_x64 14:50:32.248 [7404.1340] <2> local_getHostInfo: platform of client mediaserver02.abc.net is PC-x64, WindowsXP 14:50:32.248 [7404.1340] <2> bpcr_get_uname_rqst: bpcr.c: 3593, entering bpcr_get_uname_rqst 14:50:32.248 [7404.1340] <2> bpcr_get_uname_rqst: bpcr.c: 3624 count=28, buffer=UNAME_SYSNAME = Windows2016 14:50:32.248 [7404.1340] <2> bpcr_get_uname_rqst: bpcr.c: 3638, utsname->sysname=Windows2016 14:50:32.248 [7404.1340] <2> bpcr_get_uname_rqst: bpcr.c: 3624 count=31, buffer=UNAME_NODENAME = MEDIASERVER02 14:50:32.264 [7404.1340] <2> bpcr_get_uname_rqst: bpcr.c: 3624 count=19, buffer=UNAME_RELEASE = 10 14:50:32.264 [7404.1340] <2> bpcr_get_uname_rqst: bpcr.c: 3657, utsname->nodename=MEDIASERVER02 14:50:32.264 [7404.1340] <2> bpcr_get_uname_rqst: bpcr.c: 3624 count=18, buffer=UNAME_VERSION = 0 14:50:32.264 [7404.1340] <2> bpcr_get_uname_rqst: bpcr.c: 3657, utsname->nodename=MEDIASERVER02 14:50:32.264 [7404.1340] <2> bpcr_get_uname_rqst: bpcr.c: 3674, utsname->release=10 14:50:32.264 [7404.1340] <2> bpcr_get_uname_rqst: bpcr.c: 3624 count=52, buffer=UNAME_MACHINE = Unknown - Unknown - Revision x5504 14:50:32.264 [7404.1340] <2> bpcr_get_uname_rqst: bpcr.c: 3657, utsname->nodename=MEDIASERVER02 14:50:32.264 [7404.1340] <2> bpcr_get_uname_rqst: bpcr.c: 3674, utsname->release=10 14:50:32.264 [7404.1340] <2> bpcr_get_uname_rqst: bpcr.c: 3691, utsname->version=0 14:50:32.264 [7404.1340] <2> bpcr_get_uname_rqst: bpcr.c: 3624 count=3, buffer=END 14:50:32.264 [7404.1340] <2> bpcr_get_uname_rqst: bpcr.c: 3657, utsname->nodename=MEDIASERVER02 14:50:32.264 [7404.1340] <2> bpcr_get_uname_rqst: bpcr.c: 3674, utsname->release=10 14:50:32.264 [7404.1340] <2> bpcr_get_uname_rqst: bpcr.c: 3691, utsname->version=0 14:50:32.264 [7404.1340] <2> bpcr_get_uname_rqst: bpcr.c: 3708, utsname->machine=Unknown - Unknown - Revision x5504 14:50:32.264 [7404.1340] <2> bpcr_get_uname_rqst: bpcr.c: 3732, return success 14:50:32.264 [7404.1340] <2> local_getHostInfo: d:\nb\8.1.2\src\nb\dblib\dbmisc.c: 1748, uname -sr=Windows2016 10 14:50:32.264 [7404.1340] <2> bpcr_get_version_rqst: bpcd version: 08100000 14:50:32.264 [7404.1340] <2> getPatchLevel: CLIENT_CMD_SOCK from bpcr = 800 14:50:32.264 [7404.1340] <2> getPatchLevel: got client socket 14:50:32.264 [7404.1340] <2> getPatchLevel: file num = 4, file size = 107 14:50:32.264 [7404.1340] <2> bpcr_read_rqst: completed send of file number 4 14:50:32.264 [7404.1340] <2> bpcr_read_rqst: completed send of transfer_count = 16383 14:50:32.264 [7404.1340] <2> bpcr_read_rqst: Before readclientstatus on socket 796 14:50:32.264 [7404.1340] <2> bpcr_read_rqst: Num bytes to be sent by client = 107 14:50:32.264 [7404.1340] <2> getPatchLevel: total chars read = 107 14:50:32.264 [7404.1340] <2> getPatchLevel: level = >8.1.2< 14:50:32.264 [7404.1340] <2> decode_fim_attr: decode_fim_attr: fim=NONE, client version=810000 14:50:32.264 [7404.1340] <2> logconnections: BPCD CONNECT FROM 10.20.36.21.62414 TO 10.20.36.21.13782 fd = 796 14:50:32.358 [7404.1340] <2> bpcr_get_version_rqst: bpcd version: 08100000 14:50:32.358 [7404.1340] <2> bpcr_get_version_rqst: bpcd version: 08100000 14:50:32.358 [7404.1340] <2> ddns_check: Entering 14:50:32.358 [7404.1340] <2> bpbrm start_bpcd_stat: DATA_SOCK from bpcr = 796 14:50:32.358 [7404.1340] <2> bpbrm start_bpcd_stat: NAME_SOCK from bpcr = 792 14:50:32.358 [7404.1340] <2> 219838:bpbrm:7404:mediaserver02.abc.net: libsts load_plugins() 20/10/26 14:50:32: opening plugin directory D:\Program Files\Veritas\NetBackup\bin\ 14:50:32.358 [7404.1340] <2> 219838:bpbrm:7404:mediaserver02.abc.net: libsts openp() 20/10/26 14:50:32: opening module D:\Program Files\Veritas\NetBackup\bin\\libstspibasicdisk.dll 14:50:32.373 [7404.1340] <2> 219838:bpbrm:7404:mediaserver02.abc.net: libsts openp() 20/10/26 14:50:32: opening module D:\Program Files\Veritas\NetBackup\bin\\libstspicorba.dll 14:50:32.373 [7404.1340] <2> 219838:bpbrm:7404:mediaserver02.abc.net: libsts openp() 20/10/26 14:50:32: opening module D:\Program Files\Veritas\NetBackup\bin\\libstspicp.dll 14:50:32.373 [7404.1340] <2> 219838:bpbrm:7404:mediaserver02.abc.net: libsts openp() 20/10/26 14:50:32: opening module D:\Program Files\Veritas\NetBackup\bin\\libstspinearstore.dll 14:50:32.373 [7404.1340] <2> 219838:bpbrm:7404:mediaserver02.abc.net: libsts openp() 20/10/26 14:50:32: opening module D:\Program Files\Veritas\NetBackup\bin\\libstspishmem.dll 14:50:32.373 [7404.1340] <2> 219838:bpbrm:7404:mediaserver02.abc.net: libsts load_plugins() 20/10/26 14:50:32: opening plugin directory D:\Program Files\Veritas\NetBackup\bin\\ost-plugins 14:50:32.373 [7404.1340] <2> 219838:bpbrm:7404:mediaserver02.abc.net: libsts openp() 20/10/26 14:50:32: opening module D:\Program Files\Veritas\NetBackup\bin\\ost-plugins\libstspiamazon.dll 14:50:32.389 [7404.1340] <2> 219838:bpbrm:7404:mediaserver02.abc.net: libsts openp() 20/10/26 14:50:32: opening module D:\Program Files\Veritas\NetBackup\bin\\ost-plugins\libstspiatt.dll 14:50:32.389 [7404.1340] <2> 219838:bpbrm:7404:mediaserver02.abc.net: libsts openp() 20/10/26 14:50:32: opening module D:\Program Files\Veritas\NetBackup\bin\\ost-plugins\libstspiazure.dll 14:50:32.389 [7404.1340] <2> 219838:bpbrm:7404:mediaserver02.abc.net: libsts openp() 20/10/26 14:50:32: opening module D:\Program Files\Veritas\NetBackup\bin\\ost-plugins\libstspicompress.dll 14:50:32.389 [7404.1340] <2> 219838:bpbrm:7404:mediaserver02.abc.net: libsts openp() 20/10/26 14:50:32: opening module D:\Program Files\Veritas\NetBackup\bin\\ost-plugins\libstspiencrypt.dll 14:50:32.389 [7404.1340] <2> 219838:bpbrm:7404:mediaserver02.abc.net: libsts openp() 20/10/26 14:50:32: opening module D:\Program Files\Veritas\NetBackup\bin\\ost-plugins\libstspigateway.dll 14:50:32.389 [7404.1340] <2> 219838:bpbrm:7404:mediaserver02.abc.net: libsts openp() 20/10/26 14:50:32: opening module D:\Program Files\Veritas\NetBackup\bin\\ost-plugins\libstspimetering.dll 14:50:32.389 [7404.1340] <2> 219838:bpbrm:7404:mediaserver02.abc.net: libsts openp() 20/10/26 14:50:32: opening module D:\Program Files\Veritas\NetBackup\bin\\ost-plugins\libstspipd.dll 14:50:32.404 [7404.1340] <2> 219838:bpbrm:7404:mediaserver02.abc.net: libsts openp() 20/10/26 14:50:32: opening module D:\Program Files\Veritas\NetBackup\bin\\ost-plugins\libstspirackspace.dll 14:50:32.404 [7404.1340] <2> 219838:bpbrm:7404:mediaserver02.abc.net: libsts openp() 20/10/26 14:50:32: opening module D:\Program Files\Veritas\NetBackup\bin\\ost-plugins\libstspisoapwrapper.dll 14:50:32.404 [7404.1340] <2> 219838:bpbrm:7404:mediaserver02.abc.net: libsts openp() 20/10/26 14:50:32: opening module D:\Program Files\Veritas\NetBackup\bin\\ost-plugins\libstspiswift.dll 14:50:32.404 [7404.1340] <2> 219838:bpbrm:7404:mediaserver02.abc.net: libsts openp() 20/10/26 14:50:32: opening module D:\Program Files\Veritas\NetBackup\bin\\ost-plugins\libstspithrottling.dll 14:50:32.404 [7404.1340] <2> 219838:bpbrm:7404:mediaserver02.abc.net: libsts openp() 20/10/26 14:50:32: opening module D:\Program Files\Veritas\NetBackup\bin\\ost-plugins\libstspizapi.dll 14:50:32.404 [7404.1340] <4> 219838:bpbrm:7404:mediaserver02.abc.net: libsts setupp() 20/10/26 14:50:32: PureDisk:mediaserver02.abc.net claimed by D:\Program Files\Veritas\NetBackup\bin\\ost-plugins\libstspipd.dll 14:50:32.404 [7404.1340] <2> Orb::init: checking if NBAC is enabled(Orb.cpp:766) 14:50:32.404 [7404.1340] <2> EndpointSelector_R2::updateConfMembers: epsr2: bp.conf changed since last look, reloading(Endpoint_Selector.cpp:415) 14:50:32.404 [7404.1340] <2> EndpointSelectorFactory::EndpointSelectorFactory: Endpoint Selector model: 7.1 Flags: 0(Endpoint_Selector.cpp:2154) 14:50:32.404 [7404.1340] <2> EndpointSelector_R2::updateConfMembers: epsr2: bp.conf changed since last look, reloading(Endpoint_Selector.cpp:415) 14:50:32.404 [7404.1340] <2> InsecureEndpointSelectorFactory::InsecureEndpointSelectorFactory: Endpoint Selector model: 7.1 Flags: 0 [Ignoring BPCONF server list](Endpoint_Selector.cpp:2248) 14:50:32.404 [7404.1340] <2> checkService: found service: 00007FF8B30DC748(Orb.cpp:689) 14:50:32.420 [7404.1340] <8> checkService: Failed to find service: UTF8-Latin1_Factory in loaded service repository 14:50:32.420 [7404.1340] <2> Orb::getConfig: found service entry: name = veritas_pbx port = 1556 proto = tcp(Orb.cpp:592) 14:50:32.420 [7404.1340] <2> Orb::getConfig: cluster_name and required_interface not defined, using ANY(Orb.cpp:644) 14:50:32.420 [7404.1340] <2> Orb::init: Enabling ORBNativeCharCodeSet UTF-8(Orb.cpp:953) 14:50:32.420 [7404.1340] <2> Orb::init: added args has value of: (Orb.cpp:1106) 14:50:32.420 [7404.1340] <2> Orb::init: initializing ORB EMMlib_Orb with: [libstsemm] [-ORBDottedDecimalAddresses 0] [-ORBUseLocalMemoryPool 0] [-ORBSvcConfDirective] [static Resource_Factory '-ORBNativeCharCodeSet UTF-8'] [-ORBSvcConfDirective] [static PBXIOP_Factory '-enable_keepalive -reply_expected 25'] [-ORBSvcConfDirective] [static EndpointSelectorFactory ''] [-ORBSvcConfDirective] [static Resource_Factory '-ORBProtocolFactory PBXIOP_Factory'] [-ORBSvcConfDirective] [static Resource_Factory '-ORBProtocolFactory IIOP_Factory'] [-ORBDefaultInitRef] [] [-ORBSvcConfDirective] [static PBXIOP_Evaluator_Factory '-orb EMMlib_Orb'] [-ORBSvcConfDirective] [static Resource_Factory '-ORBConnectionCacheMax 1024'] [-ORBSvcConf] [nul] [-ORBSvcConfDirective] [static Server_Strategy_Factory '-ORBMaxRecvGIOPPayloadSize 268435456'](Orb.cpp:1166) 14:50:32.420 [7404.1340] <2> NBOrbInitializer::post_init: Adding interceptors(NBOrbInitializer.cpp:73) 14:50:32.420 [7404.1340] <2> Orb::init: caching EndpointSelectorFactory(Orb.cpp:1180) 14:50:32.420 [7404.1340] <2> Orb::setOrbTimeoutPolicy: setting ORB connection timeout policy: tv = 1800(Orb.cpp:1709) 14:50:32.420 [7404.1340] <2> Orb::activate: Client Orb called activate; Ignored(Orb.cpp:1440) 14:50:32.420 [7404.1340] <2> Orb::createInsecureObjectRef: corbaloc being used is: corbaloc:pbxiop:localhost:1556:tmpextension/tempobject(Orb.cpp:1645) 14:50:32.420 [7404.1340] <2> Orb::setOrbTimeoutPolicy: setting ORB connection timeout policy: tv = 60(Orb.cpp:1709) 14:50:32.420 [7404.1340] <2> Orb::setOrbConnectTimeout: timeout seconds: 60(Orb.cpp:1686) 14:50:32.420 [7404.1340] <2> Orb::setOrbTimeoutPolicy: setting ORB request timeout policy: tv = 1800(Orb.cpp:1709) 14:50:32.420 [7404.1340] <2> Orb::setOrbRequestTimeout: timeout seconds: 1800(Orb.cpp:1695) 14:50:32.420 [7404.1340] <2> Orb::connectToObjectRetries: Connection attempt #1 mW:60 rW:0 Insecure(masterserver01.abc.net:EMM/EMMServer)(OrbConnect.cpp:346) 14:50:32.420 [7404.1340] <2> Orb::createInsecureObjectRef: corbaloc being used is: corbaloc:pbxiop:masterserver01.abc.net:1556:EMM/EMMServer(Orb.cpp:1645) 14:50:32.420 [7404.1340] <2> EndpointSelector_R2::select_endpoint: epsr2: EPS Honoring Server List(Endpoint_Selector.cpp:565) 14:50:32.420 [7404.1340] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:5177] using interface ANY 14:50:32.420 [7404.1340] <2> EndpointSelector_R2::cachedHostLookup: epsr2: Hostname masterserver01.abc.net has 1 candidate addresses(Endpoint_Selector.cpp:1748) 14:50:32.420 [7404.1340] <2> EndpointSelector_R2::performCallUsingEndpointAndSourceBinding: epsr2: Target: 10.20.34.38, source: ANY(Endpoint_Selector.cpp:1027) 14:50:32.420 [7404.1340] <4> create_user_group_id_marker_WIN: connecting to named pipe:\\.\pipe\{636D6547-4EA1-4BB9-93FA-A005A4D17425}:OUTBOUND 14:50:32.420 [7404.1340] <4> create_user_group_id_marker_WIN: successfully connected to server named pipe 14:50:32.467 [7404.1340] <2> EndpointSelector_R2::performCallUsingEndpoint: epsr2: insecure resolver->try_connect(...) to host 10.20.34.38 SUCCESS(Endpoint_Selector.cpp:1254) 14:50:32.467 [7404.1340] <2> EndpointSelector_R2::select_endpoint: epsr2: EPS Honoring Server List(Endpoint_Selector.cpp:565) 14:50:32.467 [7404.1340] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Reusing endpoint, target: 10.20.34.38, source: ANY(Endpoint_Selector.cpp:719) 14:50:32.467 [7404.1340] <2> EndpointSelector_R2::performCallUsingEndpoint: epsr2: insecure resolver->try_connect(...) to host 10.20.34.38 SUCCESS(Endpoint_Selector.cpp:1254) 14:50:32.467 [7404.1340] <2> Orb::connectToObjectReference: connection created(OrbConnect.cpp:167) 14:50:32.467 [7404.1340] <2> EndpointSelector_R2::select_endpoint: epsr2: EPS Honoring Server List(Endpoint_Selector.cpp:565) 14:50:32.467 [7404.1340] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Reusing endpoint, target: 10.20.34.38, source: ANY(Endpoint_Selector.cpp:719) 14:50:32.467 [7404.1340] <2> EndpointSelector_R2::performCallUsingEndpoint: epsr2: insecure resolver->try_connect(...) to host 10.20.34.38 SUCCESS(Endpoint_Selector.cpp:1254) 14:50:32.483 [7404.1340] <2> EndpointSelector_R2::select_endpoint: epsr2: EPS Honoring Server List(Endpoint_Selector.cpp:565) 14:50:32.483 [7404.1340] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Reusing endpoint, target: 10.20.34.38, source: ANY(Endpoint_Selector.cpp:719) 14:50:32.483 [7404.1340] <2> EndpointSelector_R2::performCallUsingEndpoint: epsr2: insecure resolver->try_connect(...) to host 10.20.34.38 SUCCESS(Endpoint_Selector.cpp:1254) 14:50:32.483 [7404.1340] <2> EndpointSelector_R2::select_endpoint: epsr2: EPS Honoring Server List(Endpoint_Selector.cpp:565) 14:50:32.483 [7404.1340] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Reusing endpoint, target: 10.20.34.38, source: ANY(Endpoint_Selector.cpp:719) 14:50:32.483 [7404.1340] <2> EndpointSelector_R2::performCallUsingEndpoint: epsr2: insecure resolver->try_connect(...) to host 10.20.34.38 SUCCESS(Endpoint_Selector.cpp:1254) 14:50:32.483 [7404.1340] <2> EndpointSelector_R2::select_endpoint: epsr2: EPS Honoring Server List(Endpoint_Selector.cpp:565) 14:50:32.483 [7404.1340] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Reusing endpoint, target: 10.20.34.38, source: ANY(Endpoint_Selector.cpp:719) 14:50:32.483 [7404.1340] <2> EndpointSelector_R2::performCallUsingEndpoint: epsr2: insecure resolver->try_connect(...) to host 10.20.34.38 SUCCESS(Endpoint_Selector.cpp:1254) 14:50:32.483 [7404.1340] <2> Orb::destroyOrb: orb has reference count: 0(Orb.cpp:1901) 14:50:32.483 [7404.1340] <4> 219838:bpbrm:7404:mediaserver02.abc.net: libsts setupp() 20/10/26 14:50:32: PureDisk:mediaserver02.abc.net claimed by D:\Program Files\Veritas\NetBackup\bin\\ost-plugins\libstspipd.dll 14:50:32.498 [7404.1340] <4> 219838:bpbrm:7404:mediaserver02.abc.net: [INFO] PDSTS: add_mount: unable to get reconnect timeout value from configuration file . 14:50:32.639 [7404.1340] <4> 219838:bpbrm:7404:mediaserver02.abc.net: [INFO] PDSTS: tag_nbu_dsid: force SEGMENT_KSIZE to <128> 14:50:32.639 [7404.1340] <2> bpbrm main: storage_need_im_and_tir 0 14:50:32.655 [7404.1340] <2> local_getHostInfo: found TSS cached host mediaserver02.abc.net type 67 14:50:32.655 [7404.1340] <2> bpbrm main: Client NBU version: 810000 14:50:32.655 [7404.1340] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:5177] using interface ANY 14:50:32.655 [7404.1340] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:5177] using interface ANY 14:50:32.655 [7404.1340] <2> vnet_pbxConnect_ex: pbxConnectExEx Succeeded 14:50:32.655 [7404.1340] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO masterserver01.abc.net 10.20.34.38 bpjobd VIA pbx 14:50:32.655 [7404.1340] <4> create_user_group_id_marker_WIN: connecting to named pipe:\\.\pipe\{CCD64C8C-ED6F-4C08-8873-755CC9E2F599}:OUTBOUND 14:50:32.655 [7404.1340] <4> create_user_group_id_marker_WIN: successfully connected to server named pipe 14:50:32.686 [7404.1340] <2> job_connect: SO_KEEPALIVE set on socket 1264 for client masterserver01.abc.net 14:50:32.686 [7404.1340] <2> logconnections: PROXY CONNECT FROM 10.20.36.21.62443 TO 10.20.34.38.1556 fd = 1264 14:50:32.686 [7404.1340] <2> logconnections: BPJOBD CONNECT FROM 127.0.0.1.62445 TO 127.0.0.1.62446 fd = 1264 14:50:32.686 [7404.1340] <2> job_authenticate_connection: VxSS authentication check for now... 14:50:32.686 [7404.1340] <2> job_connect: Connected to the host masterserver01.abc.net contype 53 jobid <219838> socket <1264> 14:50:32.686 [7404.1340] <2> job_connect: Connected on port 62443 14:50:32.686 [7404.1340] <4> JobdSockList::RegisterSocket: Register socket (1264). 14:50:32.686 [7404.1340] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:5144] Local [strong] check, using interface ANY 14:50:32.686 [7404.1340] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO mediaserver02.abc.net 10.20.36.21 bpcd 14:50:32.686 [7404.1340] <2> logconnections: BPCD CONNECT FROM 10.20.36.21.62447 TO 10.20.36.21.13782 fd = 1056 14:50:32.795 [7404.1340] <2> bpcr_get_version_rqst: bpcd version: 08100000 14:50:32.795 [7404.1340] <2> bpcr_get_version_rqst: bpcd version: 08100000 14:50:32.795 [7404.1340] <2> bpcr_get_version_rqst: bpcd version: 08100000 14:50:32.795 [7404.1340] <2> decode_fim_attr: decode_fim_attr: fim=NONE, client version=810000 14:50:32.795 [7404.1340] <2> build_cmd_for_non_mpx_backup_archive_verify_import: send_3_0_client_params = 1 14:50:32.795 [7404.1340] <2> build_cmd_for_non_mpx_backup_archive_verify_import: backup_cmd = /usr/openv/netbackup/bin/bpbkar bpbkar32 -r 1209600 -dt 0 -to 0 -bpstart_time 1603695360 -clnt cute_cats.abc.net -class VMware_Backup -sched Full -st INCR -bpstart_to 300 -bpend_to 300 -read_to 1800 -ru root -blks_per_buffer 512 -use_otm -fso -ifr -pid 7404 -mediasvr mediaserver02.abc.net -bt 1603695059 -t 1 -b cute_cats.abc.net_1603695059 -kl 30 -fi -S masterserver01.abc.net -fim NONE -ct 40 14:50:32.795 [7404.1340] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:5144] Local [strong] check, using interface ANY 14:50:32.795 [7404.1340] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO mediaserver02.abc.net 10.20.36.21 bpcd 14:50:32.795 [7404.1340] <2> logconnections: BPCD CONNECT FROM 10.20.36.21.62449 TO 10.20.36.21.13782 fd = 1056 14:50:32.889 [7404.1340] <2> bpcr_get_version_rqst: bpcd version: 08100000 14:50:32.889 [7404.1340] <2> bpcr_get_version_rqst: bpcd version: 08100000 14:50:32.889 [7404.1340] <2> ddns_check: Entering 14:50:32.889 [7404.1340] <2> bpbrm start_bpcd_stat: DATA_SOCK from bpcr = 1056 14:50:32.889 [7404.1340] <2> bpbrm start_bpcd_stat: NAME_SOCK from bpcr = 1040 14:50:32.889 [7404.1340] <2> start_client_for_non_mpx_backup_archive_verify_import: Going to execute cmd on host 14:50:32.889 [7404.1340] <2> start_client_for_non_mpx_backup_archive_verify_import: (d:\nb\8.1.2\src\nb\brm\bpbrm.c:49197) (Jobid: 219838) Using bpcr_fork_cmd_proxy_rqst to start bpbkar32 14:50:32.889 [7404.1340] <2> start_client_for_non_mpx_backup_archive_verify_import: received bpcd success message 14:50:32.889 [7404.1340] <2> start_client_for_non_mpx_backup_archive_verify_import: (d:\nb\8.1.2\src\nb\brm\bpbrm.c:49226) (Jobid: 219838) Connected to bpbkar32, data socket 1056 is not proxied, name socket 1040 is not proxied, comm socket 1056 is not proxied 14:50:33.139 [7404.1340] <2> get_readline_type: Returning BPBRM_READ_INFORM_WHEN_DONE 14:50:33.139 [7404.1340] <2> non_mpx_backup_archive_verify_import: client_pid=1704 14:50:33.139 [7404.1340] <2> non_mpx_backup_archive_verify_import: from client cute_cats.abc.net: read client start message 14:50:33.139 [7404.1340] <2> bpbrm spawn_child: "D:\Program Files\Veritas\NetBackup\bin\bptm.exe" -w -pid 7404 -c cute_cats.abc.net -dpath PureDiskVolume -stunit mediaserver02_disk_pool-stu -cl VMware_Backup -bt 1603695059 -b cute_cats.abc.net_1603695059 -st 1 -cj 1 -reqid -1603694446 -vm 2 -jm -brm -hostname cute_cats.abc.net -ru root -rclnt cute_cats.abc.net -rclnthostname cute_cats.abc.net -rl 1 -rp 1209600 -sl Full -ct 40 -maxfrag 51200 -eari 0 -v -mediasvr mediaserver02.abc.net -ifr -nonrsvdports -connect_options 0x01020001 -jobid 219838 -jobgrpid 219837 -masterversion 812000 -bpbrm_shm_id Global\NetBackup_BPBRM_SHM_Path_12224343_7404_1340 -blks_per_buffer 512 -shm 14:50:33.139 [7404.1340] <2> bpbrm create_mm_terminate: created terminate event pid 8900 14:50:33.139 [7404.1340] <2> bpbrm create_mm_suspend: created suspend event pid 8900 14:50:33.139 [7404.1340] <2> bpbrm write_continue_backup: wrote CONTINUE BACKUP on COMM_SOCK <1056> 14:50:33.139 [7404.1340] <2> get_cached_client_version: Using cached version. CLNT=mediaserver02.abc.net V=135266304 Fetched=Mon Oct 26 14:50:33 2020 14:50:33.139 [7404.1340] <2> write_file_names: buffering file name 'FIS_VM_BI_ATTRS_FILE=\online_util\fi_cntl\bpfis.fim.cute_cats.abc.net_1603695028.1.0.NBU_DATA.xml' for output 14:50:33.139 [7404.1340] <2> write_file_names: buffering file name 'BACKUP \\.\D:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.cute_cats.abc.net_1603695028.1.0.NBU_DATA.xml USING \\.\C:\ OPTIONS:FITYPE=MIRROR,FSTYPE=NTFS' for output 14:50:33.139 [7404.1340] <2> write_file_names: successfully wrote buffer to COMM_SOCK 14:50:33.139 [7404.1340] <2> non_mpx_backup_archive_verify_import: wrote CONTINUE on COMM_SOCK 14:50:33.139 [7404.1340] <2> non_mpx_backup_archive_verify_import: Value of PFI = 0 14:50:33.139 [7404.1340] <2> non_mpx_backup_archive_verify_import: from client cute_cats.abc.net: INF - BACKUP 26/10/2020 2:50:33 PM cute_cats.abc.net VMware_Backup Full INCR 14:50:35.170 [7404.1340] <2> non_mpx_backup_archive_verify_import: ESTIMATE -1 -1 cute_cats.abc.net_1603695059 14:50:35.186 [7404.1340] <2> non_mpx_backup_archive_verify_import: client cute_cats.abc.net EXIT STATUS = 11: system call failed (line 47347) 14:50:35.186 [7404.1340] <2> bpbrm kill_child_process_Ex: start 14:50:39.858 [7404.1340] <2> bpbrm wait_for_child: start 14:50:39.858 [7404.1340] <2> bpbrm wait_for_child: child exit_status = 25 14:50:39.874 [7404.1340] <2> inform_client_of_status: INF - Server status = 11 14:50:39.874 [7404.1340] <2> put_string: cannot write data to network: An existing connection was forcibly closed by the remote host. 14:50:39.874 [7404.1340] <16> inform_client_of_status: could not send server status message to client 14:50:39.874 [7404.1340] <2> ConnectionCache::connectAndCache: Acquiring new connection for host masterserver01.abc.net, query type 1 14:50:39.874 [7404.1340] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:5177] using interface ANY 14:50:39.874 [7404.1340] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:5177] using interface ANY 14:50:39.889 [7404.1340] <2> vnet_pbxConnect_ex: pbxConnectExEx Succeeded 14:50:39.889 [7404.1340] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO masterserver01.abc.net 10.20.34.38 bpdbm VIA pbx 14:50:39.889 [7404.1340] <4> create_user_group_id_marker_WIN: connecting to named pipe:\\.\pipe\{969BA5C5-D96A-4123-96FA-6C86C2FE4E7C}:OUTBOUND 14:50:39.889 [7404.1340] <4> create_user_group_id_marker_WIN: successfully connected to server named pipe 14:50:39.983 [7404.1340] <2> logconnections: PROXY CONNECT FROM 10.20.36.21.62562 TO 10.20.34.38.1556 fd = 1196 14:50:39.983 [7404.1340] <2> logconnections: BPDBM CONNECT FROM 127.0.0.1.62564 TO 127.0.0.1.62565 fd = 1196 14:50:39.983 [7404.1340] <2> db_end: Need to collect reply 14:50:39.999 [7404.1340] <2> bpbrm Exit: sending signal=1 to client=cute_cats.abc.net, client_pid=1704 14:50:39.999 [7404.1340] <2> bpcr_send_signal: Ignoring connect_opts = 0x01030202 14:50:39.999 [7404.1340] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:5144] Local [strong] check, using interface ANY 14:50:39.999 [7404.1340] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO mediaserver02.abc.net 10.20.36.21 bpcd 14:50:39.999 [7404.1340] <2> logconnections: BPCD CONNECT FROM 10.20.36.21.62566 TO 10.20.36.21.13782 fd = 1056 14:50:40.093 [7404.1340] <2> bpcr_get_version_rqst: bpcd version: 08100000 14:50:40.093 [7404.1340] <2> bpcr_get_version_rqst: bpcd version: 08100000 14:50:40.093 [7404.1340] <2> bpcr_send_signal: CLIENT_CMD_SOCK from bpcr = 1056 14:50:40.093 [7404.1340] <2> bpcr_send_signal: CLIENT_STAT_SOCK from bpcr = 1176 14:50:41.186 [7404.1340] <2> bpbrm readline: EOF detected on socket 14:50:41.186 [7404.1340] <2> bpbrm Exit: readline buff , status = -3 14:50:41.186 [7404.1340] <32> bpbrm Exit: unexpected termination of client cute_cats.abc.net 14:50:41.186 [7404.1340] <2> ConnectionCache::connectAndCache: Acquiring new connection for host masterserver01.abc.net, query type 1 14:50:41.186 [7404.1340] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:5177] using interface ANY 14:50:41.186 [7404.1340] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:5177] using interface ANY 14:50:41.202 [7404.1340] <2> vnet_pbxConnect_ex: pbxConnectExEx Succeeded 14:50:41.202 [7404.1340] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO masterserver01.abc.net 10.20.34.38 bpdbm VIA pbx 14:50:41.202 [7404.1340] <4> create_user_group_id_marker_WIN: connecting to named pipe:\\.\pipe\{A30E0697-CA7F-482A-A6B5-A6EE37359AA3}:OUTBOUND 14:50:41.202 [7404.1340] <4> create_user_group_id_marker_WIN: successfully connected to server named pipe 14:50:41.296 [7404.1340] <2> logconnections: PROXY CONNECT FROM 10.20.36.21.62568 TO 10.20.34.38.1556 fd = 1272 14:50:41.296 [7404.1340] <2> logconnections: BPDBM CONNECT FROM 127.0.0.1.62570 TO 127.0.0.1.62571 fd = 1272 14:50:41.296 [7404.1340] <2> db_end: Need to collect reply 14:50:41.311 [7404.1340] <2> bpbrm Exit: attempting to send mail to root on mediaserver02.abc.net 14:50:41.311 [7404.1340] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:5144] Local [strong] check, using interface ANY 14:50:41.311 [7404.1340] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO mediaserver02.abc.net 10.20.36.21 bpcd 14:50:41.311 [7404.1340] <2> logconnections: BPCD CONNECT FROM 10.20.36.21.62572 TO 10.20.36.21.13782 fd = 1272 14:50:41.405 [7404.1340] <2> bpcr_get_version_rqst: bpcd version: 08100000 14:50:41.405 [7404.1340] <2> bpcr_get_version_rqst: bpcd version: 08100000 14:50:41.405 [7404.1340] <2> bpbrm Exit: OUT_SOCK from bpcr = 1272 14:50:41.405 [7404.1340] <2> bpbrm Exit: IN_SOCK from bpcr = 1176 14:50:41.405 [7404.1340] <2> bpcr_get_version_rqst: bpcd version: 08100000 14:50:41.405 [7404.1340] <2> bpcr_get_version_rqst: bpcd version: 08100000 14:50:41.436 [7404.1340] <2> bpcr_get_version_rqst: bpcd version: 08100000 14:50:41.452 [7404.1340] <2> bpbrm Exit: client snapshot EXIT STATUS 11: system call failed 14:50:41.468 [7404.1340] <4> JobdSockList::UnregisterSocket: Unregister socket (1264). 14:50:41.468 [7404.1340] <2> job_monitoring_exex: ACK disconnect 14:50:41.483 [7404.1340] <2> job_disconnect: Disconnected 14:50:42.077 [5720.5340] <4> bpbrm main: logging will continue into new log at midnight 14:50:42.077 [5720.5340] <2> bpbrm main: INITIATING (VERBOSE = 3): version NetBackup 8.1.2 2018082718 14:50:42.077 [5720.5340] <2> logparams: -c cute_cats.abc.net -cl VMware_Backup -ct 40 -id cute_cats.abc.net_1603695028 -nbu -sched Full -st 1 -secure 1 -S masterserver01.abc.net -block_incr -prev_bli_incr 0 -last_bli_full 0 -use_ofb -use_otm -fi -fim VMware_v2:skipnodisk=0,post_events=1,multi_org=0,Virtual_machine_backup=2,continue_discovery=0,exclude_swap=1,nameuse=0,tags_unset=0,ignore_irvm=1,rLim=10,snapact=2,enable_quiesce_failover=0,drive_selection=0,file_system_optimization=1,disable_quiesce=0,trantype=san:hotadd:nbd:nbdssl,rHz=10,rTO=0 -nbu -jobid 219837 -jobgrpid 219837 -vm_proxy mediaserver02.abc.net -status 11 -b cute_cats.abc.net_1603695028 -rp 1209600 -rtype 0 14:50:42.077 [5720.5340] <2> parse_arguments: DBG - Snapshot method [297] - VMware_v2:skipnodisk=0,post_events=1,multi_org=0,Virtual_machine_backup=2,continue_discovery=0,exclude_swap=1,nameuse=0,tags_unset=0,ignore_irvm=1,rLim=10,snapact=2,enable_quiesce_failover=0,drive_selection=0,file_system_optimization=1,disable_quiesce=0,trantype=san:hotadd:nbd:nbdssl,rHz=10,rTO=0 14:50:42.077 [5720.5340] <2> ConnectionCache::connectAndCache: Acquiring new connection for host masterserver01.abc.net, query type 223 14:50:42.077 [5720.5340] <2> vnet_pbxConnect_ex: pbxConnectExEx Succeeded 14:50:42.077 [5720.5340] <4> create_user_group_id_marker_WIN: connecting to named pipe:\\.\pipe\{3DE5743E-1016-4932-B249-B937B2313BAC}:OUTBOUND 14:50:42.077 [5720.5340] <4> create_user_group_id_marker_WIN: successfully connected to server named pipe 14:50:42.171 [5720.5340] <2> logconnections: PROXY CONNECT FROM 10.20.36.21.62586 TO 10.20.34.38.1556 fd = 720 14:50:42.171 [5720.5340] <2> logconnections: BPDBM CONNECT FROM 127.0.0.1.62588 TO 127.0.0.1.62589 fd = 720 14:50:42.171 [5720.5340] <2> db_CLIENTsend: reset client protocol version from 0 to 9 14:50:42.187 [5720.5340] <2> db_getCLIENT: db_CLIENTreceive: no entity was found 227 14:50:42.187 [5720.5340] <2> check_dynamic_client_and_set_tss: Client entry for client cute_cats.abc.net not found in client DB 14:50:42.187 [5720.5340] <2> vnet_pbxConnect_ex: pbxConnectExEx Succeeded 14:50:42.202 [5720.5340] <4> create_user_group_id_marker_WIN: connecting to named pipe:\\.\pipe\{2A34E1FF-CBE1-4F95-BCC8-7EB9792E6FB6}:OUTBOUND 14:50:42.202 [5720.5340] <4> create_user_group_id_marker_WIN: successfully connected to server named pipe 14:50:42.483 [5720.5340] <2> logconnections: PROXY CONNECT FROM 10.20.36.21.62590 TO 10.20.34.38.1556 fd = 724 14:50:42.483 [5720.5340] <2> logconnections: BPRD CONNECT FROM 127.0.0.1.62592 TO 127.0.0.1.62593 fd = 724 14:50:42.608 [5720.5340] <4> getUnameInfoData: Windows OS Version - MajorVersion****= 10 - MinorVersion****= 0 14:50:42.608 [5720.5340] <2> brm_update_local_resiliency: changed = 0 14:50:42.608 [5720.5340] <2> bpbrm main: Cleaning cs_cache for client:[mediaserver02.abc.net] older than:[30] sec 14:50:42.608 [5720.5340] <2> bpbrm create_events: created suspend event name Global\NetBackup Suspend Event, pid: 5720 14:50:42.608 [5720.5340] <2> bpbrm main: max_entries_per_add = 95000 14:50:42.608 [5720.5340] <2> bpbrm main: max_files_per_cache = 50000 14:50:42.608 [5720.5340] <2> ConnectionCache::connectAndCache: Acquiring new connection for host masterserver01.abc.net, query type 223 14:50:42.608 [5720.5340] <2> vnet_pbxConnect_ex: pbxConnectExEx Succeeded 14:50:42.624 [5720.5340] <4> create_user_group_id_marker_WIN: connecting to named pipe:\\.\pipe\{2574A3A0-4604-4386-A3A7-ADCBF4E98B5E}:OUTBOUND 14:50:42.624 [5720.5340] <4> create_user_group_id_marker_WIN: successfully connected to server named pipe 14:50:42.702 [5720.5340] <2> logconnections: PROXY CONNECT FROM 10.20.36.21.62594 TO 10.20.34.38.1556 fd = 748 14:50:42.702 [5720.5340] <2> logconnections: BPDBM CONNECT FROM 127.0.0.1.62596 TO 127.0.0.1.62597 fd = 748 14:50:42.702 [5720.5340] <2> db_CLIENTsend: reset client protocol version from 0 to 9 14:50:42.718 [5720.5340] <2> db_end: Need to collect reply 14:50:42.733 [5720.5340] <2> logconnections: BPCD CONNECT FROM 10.20.36.21.62598 TO 10.20.36.21.13782 fd = 748 14:50:42.843 [5720.5340] <2> bpcr_get_version_rqst: bpcd version: 08100000 14:50:42.843 [5720.5340] <2> bpcr_get_version_rqst: bpcd version: 08100000 14:50:42.843 [5720.5340] <2> local_getHostInfo: CLIENT_CMD_SOCK from bpcr = 748 14:50:42.843 [5720.5340] <2> bpcr_get_version_rqst: bpcd version: 08100000 14:50:42.858 [5720.5340] <2> local_getHostInfo: hostname comparison failed 14:50:42.858 [5720.5340] <2> bpcr_get_version_rqst: bpcd version: 08100000 14:50:42.858 [5720.5340] <2> local_getHostInfo: rqstType == BPCD_GET_NB_VERSION_RQST 14:50:42.858 [5720.5340] <2> bpcr_get_platform_rqst: Server platform length = 7 14:50:42.858 [5720.5340] <2> xlatePlatformName: my version = 810000, client version = 810000, check current list first 14:50:42.858 [5720.5340] <2> xlatePlatformName: BUILD_PLATFORM name to xlate = win_x64 14:50:42.858 [5720.5340] <2> local_getHostInfo: platform of client mediaserver02.abc.net is PC-x64, WindowsXP 14:50:42.858 [5720.5340] <2> bpcr_get_uname_rqst: bpcr.c: 3593, entering bpcr_get_uname_rqst 14:50:42.858 [5720.5340] <2> bpcr_get_uname_rqst: bpcr.c: 3624 count=28, buffer=UNAME_SYSNAME = Windows2016 14:50:42.858 [5720.5340] <2> bpcr_get_uname_rqst: bpcr.c: 3638, utsname->sysname=Windows2016 14:50:42.858 [5720.5340] <2> bpcr_get_uname_rqst: bpcr.c: 3624 count=31, buffer=UNAME_NODENAME = MEDIASERVER02 14:50:42.858 [5720.5340] <2> bpcr_get_uname_rqst: bpcr.c: 3624 count=19, buffer=UNAME_RELEASE = 10 14:50:42.858 [5720.5340] <2> bpcr_get_uname_rqst: bpcr.c: 3657, utsname->nodename=MEDIASERVER02 14:50:42.858 [5720.5340] <2> bpcr_get_uname_rqst: bpcr.c: 3624 count=18, buffer=UNAME_VERSION = 0 14:50:42.858 [5720.5340] <2> bpcr_get_uname_rqst: bpcr.c: 3657, utsname->nodename=MEDIASERVER02 14:50:42.858 [5720.5340] <2> bpcr_get_uname_rqst: bpcr.c: 3674, utsname->release=10 14:50:42.858 [5720.5340] <2> bpcr_get_uname_rqst: bpcr.c: 3624 count=52, buffer=UNAME_MACHINE = Unknown - Unknown - Revision x5504 14:50:42.858 [5720.5340] <2> bpcr_get_uname_rqst: bpcr.c: 3657, utsname->nodename=MEDIASERVER02 14:50:42.858 [5720.5340] <2> bpcr_get_uname_rqst: bpcr.c: 3674, utsname->release=10 14:50:42.858 [5720.5340] <2> bpcr_get_uname_rqst: bpcr.c: 3691, utsname->version=0 14:50:42.858 [5720.5340] <2> bpcr_get_uname_rqst: bpcr.c: 3624 count=3, buffer=END 14:50:42.858 [5720.5340] <2> bpcr_get_uname_rqst: bpcr.c: 3657, utsname->nodename=MEDIASERVER02 14:50:42.858 [5720.5340] <2> bpcr_get_uname_rqst: bpcr.c: 3674, utsname->release=10 14:50:42.858 [5720.5340] <2> bpcr_get_uname_rqst: bpcr.c: 3691, utsname->version=0 14:50:42.858 [5720.5340] <2> bpcr_get_uname_rqst: bpcr.c: 3708, utsname->machine=Unknown - Unknown - Revision x5504 14:50:42.858 [5720.5340] <2> bpcr_get_uname_rqst: bpcr.c: 3732, return success 14:50:42.858 [5720.5340] <2> local_getHostInfo: d:\nb\8.1.2\src\nb\dblib\dbmisc.c: 1748, uname -sr=Windows2016 10 14:50:42.858 [5720.5340] <2> bpcr_get_version_rqst: bpcd version: 08100000 14:50:42.858 [5720.5340] <2> getPatchLevel: CLIENT_CMD_SOCK from bpcr = 748 14:50:42.874 [5720.5340] <2> getPatchLevel: got client socket 14:50:42.874 [5720.5340] <2> getPatchLevel: file num = 4, file size = 107 14:50:42.874 [5720.5340] <2> bpcr_read_rqst: completed send of file number 4 14:50:42.874 [5720.5340] <2> bpcr_read_rqst: completed send of transfer_count = 16383 14:50:42.874 [5720.5340] <2> bpcr_read_rqst: Before readclientstatus on socket 732 14:50:42.874 [5720.5340] <2> bpcr_read_rqst: Num bytes to be sent by client = 107 14:50:42.874 [5720.5340] <2> getPatchLevel: total chars read = 107 14:50:42.874 [5720.5340] <2> getPatchLevel: level = >8.1.2< 14:50:42.874 [5720.5340] <2> decode_fim_attr: decode_fim_attr: fim=VMware_v2:skipnodisk=0,post_events=1,multi_org=0,Virtual_machine_backup=2,continue_discovery=0,exclude_swap=1,nameuse=0,tags_unset=0,ignore_irvm=1,rLim=10,snapact=2,enable_quiesce_failover=0,drive_selection=0,file_system_optimization=1,disable_quiesce=0,trantype=san:hotadd:nbd:nbdssl,rHz=10,rTO=0, client version=810000 14:50:42.874 [5720.5340] <2> decode_fim_attr: decode_fim_attr: Virtual_machine_backup 2 bkup_time 0 sched type 1 14:50:42.874 [5720.5340] <2> decode_fim_attr: decode_fim_attr: mapped fim: VMware_v2:skipnodisk=0,post_events=1,multi_org=0,Virtual_machine_backup=2,continue_discovery=0,exclude_swap=1,nameuse=0,tags_unset=0,ignore_irvm=1,rLim=10,snapact=2,enable_quiesce_failover=0,drive_selection=0,file_system_optimization=1,disable_quiesce=0,trantype=san:hotadd:nbd:nbdssl,rHz=10,rTO=0, rfi_backup 0, keep_fi 0 14:50:42.874 [5720.5340] <2> decode_fim_attr: decode_fim_attr: nameuse: 0 14:50:42.874 [5720.5340] <2> logconnections: BPCD CONNECT FROM 10.20.36.21.62600 TO 10.20.36.21.13782 fd = 732 14:50:42.968 [5720.5340] <2> bpcr_get_version_rqst: bpcd version: 08100000 14:50:42.968 [5720.5340] <2> bpcr_get_version_rqst: bpcd version: 08100000 14:50:42.968 [5720.5340] <2> ddns_check: Entering 14:50:42.968 [5720.5340] <2> bpbrm start_bpcd_stat: DATA_SOCK from bpcr = 732 14:50:42.968 [5720.5340] <2> bpbrm start_bpcd_stat: NAME_SOCK from bpcr = 740 14:50:42.968 [5720.5340] <2> decode_fim_attr: decode_fim_attr: fim=VMware_v2:skipnodisk=0,post_events=1,multi_org=0,Virtual_machine_backup=2,continue_discovery=0,exclude_swap=1,nameuse=0,tags_unset=0,ignore_irvm=1,rLim=10,snapact=2,enable_quiesce_failover=0,drive_selection=0,file_system_optimization=1,disable_quiesce=0,trantype=san:hotadd:nbd:nbdssl,rHz=10,rTO=0, client version=810000 14:50:42.968 [5720.5340] <2> decode_fim_attr: decode_fim_attr: Virtual_machine_backup 2 bkup_time 0 sched type 1 14:50:42.968 [5720.5340] <2> decode_fim_attr: decode_fim_attr: mapped fim: VMware_v2:skipnodisk=0,post_events=1,multi_org=0,Virtual_machine_backup=2,continue_discovery=0,exclude_swap=1,nameuse=0,tags_unset=0,ignore_irvm=1,rLim=10,snapact=2,enable_quiesce_failover=0,drive_selection=0,file_system_optimization=1,disable_quiesce=0,trantype=san:hotadd:nbd:nbdssl,rHz=10,rTO=0, rfi_backup 0, keep_fi 0 14:50:42.968 [5720.5340] <2> decode_fim_attr: decode_fim_attr: nameuse: 0 14:50:42.968 [5720.5340] <2> inform_client_of_status: COMM_SOCK == INVALID_SOCKET, 0 14:50:42.968 [5720.5340] <2> bpbrm Exit: backup_status: 11 14:50:42.968 [5720.5340] <2> is_snapshot_import_deport_supported: Import/Deport supported flag [0] for fim [VMware_v2:skipnodisk=0,post_events=1,multi_org=0,Virtual_machine_backup=2,continue_discovery=0,exclude_swap=1,nameuse=0,tags_unset=0,ignore_irvm=1,rLim=10,snapact=2,enable_quiesce_failover=0,drive_selection=0,file_system_optimization=1,disable_quiesce=0,trantype=san:hotadd:nbd:nbdssl,rHz=10,rTO=0] cnum [1] 14:50:42.968 [5720.5340] <2> bpfis_cleanup: Import deport supported details: fis_id cute_cats.abc.net_1603695028, copy #1, import deport suppored flag 0, importPending flag 0 14:50:42.968 [5720.5340] <2> logconnections: BPCD CONNECT FROM 10.20.36.21.62602 TO 10.20.36.21.13782 fd = 740 14:50:43.062 [5720.5340] <2> bpcr_get_version_rqst: bpcd version: 08100000 14:50:43.062 [5720.5340] <2> bpcr_get_version_rqst: bpcd version: 08100000 14:50:43.062 [5720.5340] <2> bpcr_get_version_rqst: bpcd version: 08100000 14:50:43.077 [5720.5340] <2> vnet_pbxConnect_ex: pbxConnectExEx Succeeded 14:50:43.077 [5720.5340] <4> create_user_group_id_marker_WIN: connecting to named pipe:\\.\pipe\{09047E43-266A-44FC-8B2E-0B9D139A8DF6}:OUTBOUND 14:50:43.077 [5720.5340] <4> create_user_group_id_marker_WIN: successfully connected to server named pipe 14:50:43.108 [5720.5340] <2> job_connect: SO_KEEPALIVE set on socket 752 for client masterserver01.abc.net 14:50:43.124 [5720.5340] <2> logconnections: PROXY CONNECT FROM 10.20.36.21.62604 TO 10.20.34.38.1556 fd = 752 14:50:43.124 [5720.5340] <2> logconnections: BPJOBD CONNECT FROM 127.0.0.1.62606 TO 127.0.0.1.62607 fd = 752 14:50:43.124 [5720.5340] <2> job_authenticate_connection: VxSS authentication check for now... 14:50:43.124 [5720.5340] <2> job_connect: Connected to the host masterserver01.abc.net contype 53 jobid <219837> socket <752> 14:50:43.124 [5720.5340] <2> job_connect: Connected on port 62604 14:50:43.124 [5720.5340] <4> JobdSockList::RegisterSocket: Register socket (752). 14:50:43.124 [5720.5340] <2> logconnections: BPCD CONNECT FROM 10.20.36.21.62608 TO 10.20.36.21.13782 fd = 740 14:50:43.218 [5720.5340] <2> bpcr_get_version_rqst: bpcd version: 08100000 14:50:43.218 [5720.5340] <2> bpcr_get_version_rqst: bpcd version: 08100000 14:50:43.218 [5720.5340] <2> ddns_check: Entering 14:50:43.218 [5720.5340] <2> bpbrm start_bpcd_stat: DATA_SOCK from bpcr = 740 14:50:43.218 [5720.5340] <2> bpbrm start_bpcd_stat: NAME_SOCK from bpcr = 732 14:50:43.218 [5720.5340] <2> pfi_start_client: command = /usr/openv/netbackup/bin/bpfis bpfis delete -nbu -id cute_cats.abc.net_1603695028 -bpstart_to 300 -bpend_to 300 -clnt mediaserver02.abc.net -jobgrpid 219837 -jobid 219837 -copy 1 -rp 1209600 -rtype 0 -backupid cute_cats.abc.net_1603695028 -status 11 -S masterserver01.abc.net -noserverstate -fim VMware_v2:skipnodisk=0,post_events=1,multi_org=0,Virtual_machine_backup=2,continue_discovery=0,exclude_swap=1,nameuse=0,tags_unset=0,ignore_irvm=1,rLim=10,snapact=2,enable_quiesce_failover=0,drive_selection=0,file_system_optimization=1,disable_quiesce=0,trantype=san:hotadd:nbd:nbdssl,rHz=10,rTO=0 14:50:43.218 [5720.5340] <2> pfi_start_client: (d:\nb\8.1.2\src\nb\brm\bpbrm.c:27418) (Jobid: 219837) Using bpcr_fork_cmd_rqst to start bpfis 14:50:43.218 [5720.5340] <2> pfi_start_client: (d:\nb\8.1.2\src\nb\brm\bpbrm.c:27461) (Jobid: 219837) Connected to bpfis, data socket 740 is not proxied, name socket 732 is not proxied 14:50:43.218 [5720.5340] <2> pfi_start_client: received bpcd success message 14:50:43.452 [5720.5340] <2> pfi_start_client: read start message from mediaserver02.abc.net, msg= 14:50:48.375 [5720.5340] <2> bpbrm Exit: client snapshot EXIT STATUS 0: the requested operation was successfully completed 14:50:48.390 [5720.5340] <4> JobdSockList::UnregisterSocket: Unregister socket (752). 14:50:48.390 [5720.5340] <2> job_monitoring_exex: ACK disconnect 14:50:48.390 [5720.5340] <2> job_disconnect: Disconnected