05:30:27.844 [7176.460] <2> bpbrm main: INITIATING (VERBOSE = 0): version NetBackup 7.5 0
05:30:27.860 [7176.460] <2> logparams: -backup -S xq2 -c xq1 -ct 15 -ru root -cl Job_testdb -sched testdb -bt 1402403427 -dt 0 -st 0 -b xq1_1402403427 -mediasvr xq2 -jobid 1143 -jobgrpid 1143 -masterversion 750000 -maxfrag 0 -bpstart_time 1402403727 -p 0 -use_ofb -use_otm -xbsa -nbu -jm -secure 1 -kl 28 -rg root -fso -hqdb -connect_options 16974338 
05:30:27.860 [7176.460] <2> verify_client: bpbrm.c.37676: db_getCLIENT failed for CLIENT: xq1
05:30:27.860 [7176.460] <2> verify_client: bpbrm.c.37842: db_getCLIENT failed: 227 227 0x000000e3
05:30:27.860 [7176.460] <8> file_to_cache_item: [vnet_addrinfo.c:6555] fopen() failed ERRNO=2 FILE=C:\Program Files\Veritas\NetBackup\var\host_cache\115\743f0315+0,1,a,0,1,0+xq1.txt
05:30:27.860 [7176.460] <8> file_to_cache_item: [vnet_addrinfo.c:6555] fopen() failed ERRNO=2 FILE=C:\Program Files\Veritas\NetBackup\var\host_cache\115\743f0315+veritas_pbx,1,400,2,1,0+xq1.txt
05:30:27.876 [7176.460] <8> file_to_cache_item: [vnet_addrinfo.c:6555] fopen() failed ERRNO=2 FILE=C:\Program Files\Veritas\NetBackup\var\host_cache\118\57d5dd18+0,1,a,0,1,0+100.112.2.140.txt
05:30:27.876 [7176.460] <8> file_to_cache_item: [vnet_addrinfo.c:6555] fopen() failed ERRNO=2 FILE=C:\Program Files\Veritas\NetBackup\var\host_cache\115\743f0315+vnetd,1,400,2,1,0+xq1.txt
05:30:27.876 [7176.460] <8> file_to_cache_item: [vnet_addrinfo.c:6555] fopen() failed ERRNO=2 FILE=C:\Program Files\Veritas\NetBackup\var\host_cache\115\743f0315+bpcd,1,400,2,1,0+xq1.txt
05:30:27.891 [7176.460] <2> vnet_pbxConnect: pbxConnectEx Succeeded
05:30:27.891 [7176.460] <2> logconnections: BPCD CONNECT FROM 100.112.2.141.52600 TO 100.112.2.140.1556 fd = 512
05:30:27.891 [7176.460] <8> file_to_cache_item: [vnet_addrinfo.c:6555] fopen() failed ERRNO=2 FILE=C:\Program Files\Veritas\NetBackup\var\host_cache\118\57d5dd18+veritas_pbx,1,400,2,1,0+100.112.2.140.txt
05:30:27.891 [7176.460] <8> file_to_cache_item: [vnet_addrinfo.c:6555] fopen() failed ERRNO=2 FILE=C:\Program Files\Veritas\NetBackup\var\host_cache\118\57d5dd18+vnetd,1,400,2,1,0+100.112.2.140.txt
05:30:27.891 [7176.460] <2> vnet_pbxConnect: pbxConnectEx Succeeded
05:30:28.000 [7176.460] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 100.112.2.141.52601 TO 100.112.2.140.1556 fd = 524
05:30:28.000 [7176.460] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa
05:30:28.219 [7176.460] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string 61431
05:30:28.999 [7176.460] <2> bpbrm start_bpcd_stat: DATA_SOCK from bpcr = 512
05:30:28.999 [7176.460] <2> bpbrm start_bpcd_stat: NAME_SOCK from bpcr = 524
05:30:29.202 [7176.460] <2> bpcr_get_version_rqst: bpcd version: 07500000
05:30:29.592 [7176.460] <2> bpbrm create_events: created suspend event name Global\NetBackup Suspend Event, pid: 7176
05:30:29.592 [7176.460] <2> job_connect: SO_KEEPALIVE set on socket 548 for client xq2
05:30:29.592 [7176.460] <2> logconnections: BPJOBD CONNECT FROM 100.112.2.141.52602 TO 100.112.2.141.13723 fd = 548
05:30:29.592 [7176.460] <2> job_authenticate_connection: ignoring VxSS authentication check for now...
05:30:29.592 [7176.460] <2> job_connect: Connected to the host xq2 contype 53 jobid <1143> socket <548>
05:30:29.592 [7176.460] <2> job_connect: Connected on port 52602
05:30:29.607 [7176.460] <2> vnet_pbxConnect: pbxConnectEx Succeeded
05:30:29.607 [7176.460] <2> logconnections: BPCD CONNECT FROM 100.112.2.141.52603 TO 100.112.2.140.1556 fd = 564
05:30:29.623 [7176.460] <2> vnet_pbxConnect: pbxConnectEx Succeeded
05:30:29.701 [7176.460] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 100.112.2.141.52604 TO 100.112.2.140.1556 fd = 572
05:30:29.701 [7176.460] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa
05:30:29.919 [7176.460] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string 61434
05:30:30.777 [7176.460] <2> bpcr_get_version_rqst: bpcd version: 07500000
05:30:30.980 [7176.460] <2> bpcr_get_charset_rqst: bpcd charset: 00000001
05:30:30.980 [7176.460] <2> ConnectionCache::connectAndCache: Acquiring new connection for host xq2, query type 89
05:30:30.980 [7176.460] <2> logconnections: BPDBM CONNECT FROM 100.112.2.141.52605 TO 100.112.2.141.13721 fd = 572
05:30:30.980 [7176.460] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2
05:30:31.042 [7176.460] <2> db_end: Need to collect reply
05:30:31.105 [7176.460] <2> vnet_pbxConnect: pbxConnectEx Succeeded
05:30:31.105 [7176.460] <2> logconnections: BPCD CONNECT FROM 100.112.2.141.52606 TO 100.112.2.140.1556 fd = 568
05:30:31.120 [7176.460] <2> vnet_pbxConnect: pbxConnectEx Succeeded
05:30:31.198 [7176.460] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 100.112.2.141.52607 TO 100.112.2.140.1556 fd = 572
05:30:31.198 [7176.460] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa
05:30:31.417 [7176.460] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string 61436
05:30:32.088 [7176.460] <2> bpbrm start_bpcd_stat: DATA_SOCK from bpcr = 568
05:30:32.088 [7176.460] <2> bpbrm start_bpcd_stat: NAME_SOCK from bpcr = 572
05:30:32.088 [7176.460] <2> bpbrm main: received bpcd success message
05:30:33.226 [7176.460] <2> bpbrm main: client_pid=4080
05:30:33.226 [7176.460] <2> bpbrm main: from client xq1: read client start message
05:30:33.226 [7176.460] <2> bpbrm write_continue_backup: wrote CONTINUE BACKUP on COMM_SOCK <568>
05:30:33.226 [7176.460] <2> write_file_names: buffering file name 'C:\Program Files\Veritas\NetBackup\DbExt\MsSql\fan\testdb.bch' for output
05:30:33.226 [7176.460] <2> write_file_names: successfully wrote buffer to COMM_SOCK
05:30:33.226 [7176.460] <2> bpbrm main: wrote CONTINUE on COMM_SOCK
05:30:33.226 [7176.460] <2> bpbrm main: closing COMM_SOCK
05:33:54.857 [7176.460] <16> bpbrm main: from client xq1: ERR - command failed: none of the requested files were backed up (2)
05:33:54.857 [7176.460] <16> bpbrm main: from client xq1: ERR - bphdb exit status = 2: none of the requested files were backed up
05:33:54.857 [7176.460] <2> bpbrm main: client xq1 EXIT STATUS = 2: none of the requested files were backed up
05:33:54.857 [7176.460] <2> bpbrm Exit: attempting to send mail to root on xq1
05:33:54.857 [7176.460] <2> vnet_pbxConnect: pbxConnectEx Succeeded
05:33:54.857 [7176.460] <2> logconnections: BPCD CONNECT FROM 100.112.2.141.52680 TO 100.112.2.140.1556 fd = 484
05:33:54.872 [7176.460] <2> vnet_pbxConnect: pbxConnectEx Succeeded
05:33:54.950 [7176.460] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 100.112.2.141.52681 TO 100.112.2.140.1556 fd = 496
05:33:54.950 [7176.460] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa
05:33:55.169 [7176.460] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string 61543
05:33:55.824 [7176.460] <2> bpbrm Exit: OUT_SOCK from bpcr = 484
05:33:55.824 [7176.460] <2> bpbrm Exit: IN_SOCK from bpcr = 496
05:33:56.027 [7176.460] <2> bpcr_get_version_rqst: bpcd version: 07500000
05:33:56.245 [7176.460] <2> bpcr_get_version_rqst: bpcd version: 07500000
05:33:56.916 [7176.460] <2> bpcr_get_version_rqst: bpcd version: 07500000
05:33:57.353 [7176.460] <2> bpbrm Exit: client backup EXIT STATUS 2: none of the requested files were backed up
05:43:57.860 [8084.8000] <2> bpbrm main: INITIATING (VERBOSE = 0): version NetBackup 7.5 0
05:43:57.876 [8084.8000] <2> logparams: -backup -S xq2 -c xq1 -ct 15 -ru root -cl Job_testdb -sched testdb -bt 1402404237 -dt 0 -st 0 -b xq1_1402404237 -mediasvr xq2 -jobid 1143 -jobgrpid 1143 -masterversion 750000 -maxfrag 0 -bpstart_time 1402404537 -p 0 -use_ofb -use_otm -xbsa -nbu -jm -secure 1 -kl 28 -rg root -fso -hqdb -connect_options 16974338 
05:43:57.876 [8084.8000] <2> verify_client: bpbrm.c.37676: db_getCLIENT failed for CLIENT: xq1
05:43:57.876 [8084.8000] <2> verify_client: bpbrm.c.37842: db_getCLIENT failed: 227 227 0x000000e3
05:43:57.891 [8084.8000] <2> vnet_pbxConnect: pbxConnectEx Succeeded
05:43:57.891 [8084.8000] <2> logconnections: BPCD CONNECT FROM 100.112.2.141.52774 TO 100.112.2.140.1556 fd = 480
05:43:57.891 [8084.8000] <2> vnet_pbxConnect: pbxConnectEx Succeeded
05:43:57.969 [8084.8000] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 100.112.2.141.52775 TO 100.112.2.140.1556 fd = 500
05:43:57.969 [8084.8000] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa
05:43:58.188 [8084.8000] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string 61606
05:43:58.843 [8084.8000] <2> bpbrm start_bpcd_stat: DATA_SOCK from bpcr = 480
05:43:58.843 [8084.8000] <2> bpbrm start_bpcd_stat: NAME_SOCK from bpcr = 500
05:43:59.061 [8084.8000] <2> bpcr_get_version_rqst: bpcd version: 07500000
05:43:59.467 [8084.8000] <2> bpbrm create_events: created suspend event name Global\NetBackup Suspend Event, pid: 8084
05:43:59.467 [8084.8000] <2> job_connect: SO_KEEPALIVE set on socket 524 for client xq2
05:43:59.467 [8084.8000] <2> logconnections: BPJOBD CONNECT FROM 100.112.2.141.52776 TO 100.112.2.141.13723 fd = 524
05:43:59.467 [8084.8000] <2> job_authenticate_connection: ignoring VxSS authentication check for now...
05:43:59.467 [8084.8000] <2> job_connect: Connected to the host xq2 contype 53 jobid <1143> socket <524>
05:43:59.467 [8084.8000] <2> job_connect: Connected on port 52776
05:43:59.483 [8084.8000] <2> vnet_pbxConnect: pbxConnectEx Succeeded
05:43:59.483 [8084.8000] <2> logconnections: BPCD CONNECT FROM 100.112.2.141.52777 TO 100.112.2.140.1556 fd = 540
05:43:59.498 [8084.8000] <2> vnet_pbxConnect: pbxConnectEx Succeeded
05:43:59.576 [8084.8000] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 100.112.2.141.52778 TO 100.112.2.140.1556 fd = 544
05:43:59.576 [8084.8000] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa
05:43:59.795 [8084.8000] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string 61608
05:44:00.668 [8084.8000] <2> bpcr_get_version_rqst: bpcd version: 07500000
05:44:00.887 [8084.8000] <2> bpcr_get_charset_rqst: bpcd charset: 00000001
05:44:00.887 [8084.8000] <2> ConnectionCache::connectAndCache: Acquiring new connection for host xq2, query type 89
05:44:00.887 [8084.8000] <2> logconnections: BPDBM CONNECT FROM 100.112.2.141.52779 TO 100.112.2.141.13721 fd = 544
05:44:00.887 [8084.8000] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2
05:44:00.949 [8084.8000] <2> db_end: Need to collect reply
05:44:01.011 [8084.8000] <2> vnet_pbxConnect: pbxConnectEx Succeeded
05:44:01.011 [8084.8000] <2> logconnections: BPCD CONNECT FROM 100.112.2.141.52780 TO 100.112.2.140.1556 fd = 544
05:44:01.027 [8084.8000] <2> vnet_pbxConnect: pbxConnectEx Succeeded
05:44:01.105 [8084.8000] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 100.112.2.141.52781 TO 100.112.2.140.1556 fd = 540
05:44:01.105 [8084.8000] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa
05:44:01.323 [8084.8000] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string 61610
05:44:01.994 [8084.8000] <2> bpbrm start_bpcd_stat: DATA_SOCK from bpcr = 544
05:44:01.994 [8084.8000] <2> bpbrm start_bpcd_stat: NAME_SOCK from bpcr = 540
05:44:01.994 [8084.8000] <2> bpbrm main: received bpcd success message
05:44:03.149 [8084.8000] <2> bpbrm main: client_pid=5592
05:44:03.149 [8084.8000] <2> bpbrm main: from client xq1: read client start message
05:44:03.149 [8084.8000] <2> bpbrm write_continue_backup: wrote CONTINUE BACKUP on COMM_SOCK <544>
05:44:03.149 [8084.8000] <2> write_file_names: buffering file name 'C:\Program Files\Veritas\NetBackup\DbExt\MsSql\fan\testdb.bch' for output
05:44:03.149 [8084.8000] <2> write_file_names: successfully wrote buffer to COMM_SOCK
05:44:03.149 [8084.8000] <2> bpbrm main: wrote CONTINUE on COMM_SOCK
05:44:03.149 [8084.8000] <2> bpbrm main: closing COMM_SOCK
05:44:41.712 [8084.8000] <16> bpbrm main: from client xq1: ERR - command failed: none of the requested files were backed up (2)
05:44:41.712 [8084.8000] <16> bpbrm main: from client xq1: ERR - bphdb exit status = 2: none of the requested files were backed up
05:44:41.712 [8084.8000] <2> bpbrm main: client xq1 EXIT STATUS = 2: none of the requested files were backed up
05:44:41.712 [8084.8000] <2> bpbrm Exit: attempting to send mail to root on xq1
05:44:41.728 [8084.8000] <2> vnet_pbxConnect: pbxConnectEx Succeeded
05:44:41.728 [8084.8000] <2> logconnections: BPCD CONNECT FROM 100.112.2.141.52825 TO 100.112.2.140.1556 fd = 544
05:44:41.743 [8084.8000] <2> vnet_pbxConnect: pbxConnectEx Succeeded
05:44:41.821 [8084.8000] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 100.112.2.141.52826 TO 100.112.2.140.1556 fd = 548
05:44:41.821 [8084.8000] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa
05:44:42.040 [8084.8000] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string 61668
05:44:42.710 [8084.8000] <2> bpbrm Exit: OUT_SOCK from bpcr = 544
05:44:42.710 [8084.8000] <2> bpbrm Exit: IN_SOCK from bpcr = 548
05:44:42.929 [8084.8000] <2> bpcr_get_version_rqst: bpcd version: 07500000
05:44:43.147 [8084.8000] <2> bpcr_get_version_rqst: bpcd version: 07500000
05:44:43.818 [8084.8000] <2> bpcr_get_version_rqst: bpcd version: 07500000
05:44:44.270 [8084.8000] <2> bpbrm Exit: client backup EXIT STATUS 2: none of the requested files were backed up
09:59:01.269 [7840.7544] <2> bpbrm main: INITIATING (VERBOSE = 0): version NetBackup 7.5 0
09:59:01.269 [7840.7544] <2> logparams: -backup -S xq2 -c xq1 -ct 15 -ru root -cl Job_testdb -sched testdb -bt 1402419540 -dt 0 -st 0 -b xq1_1402419540 -mediasvr xq2 -jobid 1146 -jobgrpid 1146 -masterversion 750000 -maxfrag 0 -bpstart_time 1402419841 -p 0 -use_ofb -use_otm -jm -secure 1 -kl 28 -rg other -fso -hqdb -connect_options 16974338 
09:59:01.284 [7840.7544] <2> verify_client: bpbrm.c.37676: db_getCLIENT failed for CLIENT: xq1
09:59:01.284 [7840.7544] <2> verify_client: bpbrm.c.37842: db_getCLIENT failed: 227 227 0x000000e3
09:59:01.284 [7840.7544] <8> file_to_cache_item: [vnet_addrinfo.c:6555] fopen() failed ERRNO=2 FILE=C:\Program Files\Veritas\NetBackup\var\host_cache\115\743f0315+veritas_pbx,1,400,2,1,0+xq1.txt
09:59:01.284 [7840.7544] <8> file_to_cache_item: [vnet_addrinfo.c:6555] fopen() failed ERRNO=2 FILE=C:\Program Files\Veritas\NetBackup\var\host_cache\118\57d5dd18+0,1,a,0,1,0+100.112.2.140.txt
09:59:01.284 [7840.7544] <8> file_to_cache_item: [vnet_addrinfo.c:6555] fopen() failed ERRNO=2 FILE=C:\Program Files\Veritas\NetBackup\var\host_cache\115\743f0315+vnetd,1,400,2,1,0+xq1.txt
09:59:01.284 [7840.7544] <8> file_to_cache_item: [vnet_addrinfo.c:6555] fopen() failed ERRNO=2 FILE=C:\Program Files\Veritas\NetBackup\var\host_cache\115\743f0315+bpcd,1,400,2,1,0+xq1.txt
09:59:01.300 [7840.7544] <2> vnet_pbxConnect: pbxConnectEx Succeeded
09:59:01.300 [7840.7544] <2> logconnections: BPCD CONNECT FROM 100.112.2.141.55107 TO 100.112.2.140.1556 fd = 504
09:59:01.300 [7840.7544] <8> file_to_cache_item: [vnet_addrinfo.c:6555] fopen() failed ERRNO=2 FILE=C:\Program Files\Veritas\NetBackup\var\host_cache\118\57d5dd18+veritas_pbx,1,400,2,1,0+100.112.2.140.txt
09:59:01.300 [7840.7544] <8> file_to_cache_item: [vnet_addrinfo.c:6555] fopen() failed ERRNO=2 FILE=C:\Program Files\Veritas\NetBackup\var\host_cache\118\57d5dd18+vnetd,1,400,2,1,0+100.112.2.140.txt
09:59:01.316 [7840.7544] <2> vnet_pbxConnect: pbxConnectEx Succeeded
09:59:01.409 [7840.7544] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 100.112.2.141.55108 TO 100.112.2.140.1556 fd = 524
09:59:01.409 [7840.7544] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa
09:59:01.643 [7840.7544] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string 63206
09:59:02.423 [7840.7544] <2> bpbrm start_bpcd_stat: DATA_SOCK from bpcr = 504
09:59:02.423 [7840.7544] <2> bpbrm start_bpcd_stat: NAME_SOCK from bpcr = 524
09:59:02.626 [7840.7544] <2> bpcr_get_version_rqst: bpcd version: 07500000
09:59:03.016 [7840.7544] <2> bpbrm create_events: created suspend event name Global\NetBackup Suspend Event, pid: 7840
09:59:03.016 [7840.7544] <2> job_connect: SO_KEEPALIVE set on socket 548 for client xq2
09:59:03.016 [7840.7544] <2> logconnections: BPJOBD CONNECT FROM 100.112.2.141.55109 TO 100.112.2.141.13723 fd = 548
09:59:03.016 [7840.7544] <2> job_authenticate_connection: ignoring VxSS authentication check for now...
09:59:03.016 [7840.7544] <2> job_connect: Connected to the host xq2 contype 53 jobid <1146> socket <548>
09:59:03.016 [7840.7544] <2> job_connect: Connected on port 55109
09:59:03.032 [7840.7544] <2> vnet_pbxConnect: pbxConnectEx Succeeded
09:59:03.032 [7840.7544] <2> logconnections: BPCD CONNECT FROM 100.112.2.141.55110 TO 100.112.2.140.1556 fd = 564
09:59:03.047 [7840.7544] <2> vnet_pbxConnect: pbxConnectEx Succeeded
09:59:03.125 [7840.7544] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 100.112.2.141.55111 TO 100.112.2.140.1556 fd = 568
09:59:03.125 [7840.7544] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa
09:59:03.328 [7840.7544] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string 63209
09:59:04.217 [7840.7544] <2> bpcr_get_version_rqst: bpcd version: 07500000
09:59:04.436 [7840.7544] <2> bpcr_get_charset_rqst: bpcd charset: 00000001
09:59:04.436 [7840.7544] <2> ConnectionCache::connectAndCache: Acquiring new connection for host xq2, query type 89
09:59:04.436 [7840.7544] <2> logconnections: BPDBM CONNECT FROM 100.112.2.141.55112 TO 100.112.2.141.13721 fd = 568
09:59:04.436 [7840.7544] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2
09:59:04.529 [7840.7544] <2> db_end: Need to collect reply
09:59:04.592 [7840.7544] <2> vnet_pbxConnect: pbxConnectEx Succeeded
09:59:04.592 [7840.7544] <2> logconnections: BPCD CONNECT FROM 100.112.2.141.55113 TO 100.112.2.140.1556 fd = 564
09:59:04.607 [7840.7544] <2> vnet_pbxConnect: pbxConnectEx Succeeded
09:59:04.685 [7840.7544] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 100.112.2.141.55114 TO 100.112.2.140.1556 fd = 572
09:59:04.685 [7840.7544] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa
09:59:04.904 [7840.7544] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string 63212
09:59:05.574 [7840.7544] <2> bpbrm start_bpcd_stat: DATA_SOCK from bpcr = 564
09:59:05.574 [7840.7544] <2> bpbrm start_bpcd_stat: NAME_SOCK from bpcr = 572
09:59:05.574 [7840.7544] <2> bpbrm main: received bpcd success message
09:59:06.698 [7840.7544] <2> bpbrm main: client_pid=7308
09:59:06.698 [7840.7544] <2> bpbrm main: from client xq1: read client start message
09:59:06.698 [7840.7544] <2> bpbrm write_continue_backup: wrote CONTINUE BACKUP on COMM_SOCK <564>
09:59:06.698 [7840.7544] <2> write_file_names: buffering file name 'C:\Program Files\Veritas\NetBackup\DbExt\MsSql\fan\testdb.bch' for output
09:59:06.698 [7840.7544] <2> write_file_names: successfully wrote buffer to COMM_SOCK
09:59:06.698 [7840.7544] <2> bpbrm main: wrote CONTINUE on COMM_SOCK
09:59:06.698 [7840.7544] <2> bpbrm main: closing COMM_SOCK
09:59:18.429 [8228.7172] <2> bpbrm main: INITIATING (VERBOSE = 0): version NetBackup 7.5 0
09:59:18.429 [8228.7172] <2> logparams: -backup -S xq2 -c xq1 -ct 15 -ru SYSTEM -cl Job_testdb -sched Default-Application-Backup -bt 1402419558 -dt 0 -st 2 -b xq1_1402419558 -mediasvr xq2 -jobid 1147 -jobgrpid 1146 -masterversion 750000 -maxfrag 524288 -bpstart_time 1402419858 -reqid -1401819349 -mt 0 -to 0 -stunit stor_testdb -rl 3 -rp 2678400 -eari 0 -p G:\MSSQL\testdb -mst 1 -flags 0 -use_ofb -use_otm -jm -secure 1 -kl 28 -rg SYSTEM -fso -hqdb -cfc -L /C/ProgramĄ Files/Veritas/NetBackup/Logs/user_ops/mssql/logs/0610114095955-8072-8208-000-000-prg -connect_options 16974338 
09:59:18.429 [8228.7172] <2> verify_client: bpbrm.c.37676: db_getCLIENT failed for CLIENT: xq1
09:59:18.429 [8228.7172] <2> verify_client: bpbrm.c.37842: db_getCLIENT failed: 227 227 0x000000e3
09:59:18.444 [8228.7172] <2> vnet_pbxConnect: pbxConnectEx Succeeded
09:59:18.444 [8228.7172] <2> logconnections: BPCD CONNECT FROM 100.112.2.141.55135 TO 100.112.2.140.1556 fd = 480
09:59:18.460 [8228.7172] <2> vnet_pbxConnect: pbxConnectEx Succeeded
09:59:18.538 [8228.7172] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 100.112.2.141.55136 TO 100.112.2.140.1556 fd = 500
09:59:18.538 [8228.7172] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa
09:59:18.756 [8228.7172] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string 63239
09:59:19.427 [8228.7172] <2> bpbrm start_bpcd_stat: DATA_SOCK from bpcr = 480
09:59:19.427 [8228.7172] <2> bpbrm start_bpcd_stat: NAME_SOCK from bpcr = 500
09:59:19.630 [8228.7172] <2> bpcr_get_version_rqst: bpcd version: 07500000
09:59:20.036 [8228.7172] <2> bpbrm create_events: created suspend event name Global\NetBackup Suspend Event, pid: 8228
09:59:20.036 [8228.7172] <2> job_connect: SO_KEEPALIVE set on socket 524 for client xq2
09:59:20.036 [8228.7172] <2> logconnections: BPJOBD CONNECT FROM 100.112.2.141.55138 TO 100.112.2.141.13723 fd = 524
09:59:20.036 [8228.7172] <2> job_authenticate_connection: ignoring VxSS authentication check for now...
09:59:20.036 [8228.7172] <2> job_connect: Connected to the host xq2 contype 53 jobid <1147> socket <524>
09:59:20.036 [8228.7172] <2> job_connect: Connected on port 55138
09:59:20.051 [8228.7172] <2> vnet_pbxConnect: pbxConnectEx Succeeded
09:59:20.051 [8228.7172] <2> logconnections: BPCD CONNECT FROM 100.112.2.141.55139 TO 100.112.2.140.1556 fd = 540
09:59:20.051 [8228.7172] <2> vnet_pbxConnect: pbxConnectEx Succeeded
09:59:20.129 [8228.7172] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 100.112.2.141.55140 TO 100.112.2.140.1556 fd = 544
09:59:20.129 [8228.7172] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa
09:59:20.348 [8228.7172] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string 63242
09:59:21.237 [8228.7172] <2> bpcr_get_version_rqst: bpcd version: 07500000
09:59:21.440 [8228.7172] <2> bpcr_get_charset_rqst: bpcd charset: 00000001
09:59:21.440 [8228.7172] <2> ConnectionCache::connectAndCache: Acquiring new connection for host xq2, query type 89
09:59:21.440 [8228.7172] <2> logconnections: BPDBM CONNECT FROM 100.112.2.141.55141 TO 100.112.2.141.13721 fd = 544
09:59:21.440 [8228.7172] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2
09:59:21.533 [8228.7172] <2> db_end: Need to collect reply
09:59:21.596 [8228.7172] <2> vnet_pbxConnect: pbxConnectEx Succeeded
09:59:21.674 [8228.7172] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 100.112.2.141.55142 TO 100.112.2.140.1556 fd = 540
09:59:21.674 [8228.7172] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa
09:59:21.876 [8228.7172] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string 63245
09:59:22.095 [8228.7172] <2> bpbrm listen_for_client: HOT_SQL_SERVER_DB_BACKUP
09:59:22.095 [8228.7172] <2> vnet_pbxConnect: pbxConnectEx Succeeded
09:59:22.173 [8228.7172] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 100.112.2.141.55143 TO 100.112.2.140.1556 fd = 548
09:59:22.173 [8228.7172] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa
09:59:22.376 [8228.7172] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string 63246
09:59:22.594 [8228.7172] <2> vnet_pbxConnect: pbxConnectEx Succeeded
09:59:22.656 [8228.7172] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 100.112.2.141.55144 TO 100.112.2.140.1556 fd = 552
09:59:22.672 [8228.7172] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa
09:59:22.890 [8228.7172] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string 63247
09:59:23.109 [8228.7172] <2> vnet_pbxConnect: pbxConnectEx Succeeded
09:59:23.109 [8228.7172] <2> logconnections: BPCD CONNECT FROM 100.112.2.141.55145 TO 100.112.2.140.1556 fd = 556
09:59:23.109 [8228.7172] <2> vnet_pbxConnect: pbxConnectEx Succeeded
09:59:23.187 [8228.7172] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 100.112.2.141.55146 TO 100.112.2.140.1556 fd = 560
09:59:23.187 [8228.7172] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa
09:59:23.405 [8228.7172] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string 63248
09:59:24.060 [8228.7172] <2> bpbrm send_info_via_progress_file: CLIENT_CMD_SOCK from bpcr = 556
09:59:24.060 [8228.7172] <2> bpbrm send_info_via_progress_file: CLIENT_STAT_SOCK from bpcr = 560
09:59:24.466 [8228.7172] <2> bpcr_get_peername_rqst: Server peername length = 3
09:59:24.669 [8228.7172] <2> bpbrm write_msg_to_progress_file: INF - Data socket = xq2.IPC:63246;bc311b6a72216b42f0593223d0854f95;11;300

09:59:24.872 [8228.7172] <2> bpbrm write_msg_to_progress_file: INF - Name socket = xq2.IPC:63247;77e3ea48094e31279b2354bfec265b48;11;300

09:59:25.090 [8228.7172] <2> bpbrm write_msg_to_progress_file: INF - Job id = 1147

09:59:25.308 [8228.7172] <2> bpbrm write_msg_to_progress_file: INF - Backup id = xq1_1402419558

09:59:25.511 [8228.7172] <2> bpbrm write_msg_to_progress_file: INF - Backup time = 1402419558

09:59:25.730 [8228.7172] <2> bpbrm write_msg_to_progress_file: INF - Policy name = Job_testdb

09:59:25.932 [8228.7172] <2> bpbrm write_msg_to_progress_file: INF - Snapshot = 0

09:59:26.151 [8228.7172] <2> bpbrm write_msg_to_progress_file: INF - Frozen image = 0

09:59:26.369 [8228.7172] <2> bpbrm write_msg_to_progress_file: INF - Backup copy = 0

09:59:26.572 [8228.7172] <2> bpbrm write_msg_to_progress_file: INF - Master server = xq2

09:59:26.790 [8228.7172] <2> bpbrm write_msg_to_progress_file: INF - Media server = xq2

09:59:26.993 [8228.7172] <2> bpbrm write_msg_to_progress_file: INF - Multiplexing = 0

09:59:27.196 [8228.7172] <2> bpbrm write_msg_to_progress_file: INF - New data socket = xq2.IPC:63245;dbc718c935a5bb1f9c9526df12a887da;11;300

09:59:27.399 [8228.7172] <2> bpbrm write_msg_to_progress_file: INF - Use shared memory = 0

09:59:27.602 [8228.7172] <2> bpbrm write_msg_to_progress_file: INF - Compression = 0

09:59:27.804 [8228.7172] <2> bpbrm write_msg_to_progress_file: INF - Encrypt = 0

09:59:28.007 [8228.7172] <2> bpbrm write_msg_to_progress_file: INF - Keep logs = 28

09:59:28.210 [8228.7172] <2> bpbrm write_msg_to_progress_file: INF - Client read timeout = 300

09:59:28.428 [8228.7172] <2> bpbrm write_msg_to_progress_file: INF - Media mount timeout = 0

09:59:28.896 [8228.7172] <2> bpbrm listen_for_client: DATA_SOCK for client = 548
09:59:28.896 [8228.7172] <2> bpbrm listen_for_client: NAME_SOCK for client = 552
09:59:29.318 [8228.7172] <2> bpbrm main: client_pid=8072
09:59:29.318 [8228.7172] <2> bpbrm main: from client xq1: read client start message
09:59:29.318 [8228.7172] <2> bpbrm spawn_child: "C:\Program Files\Veritas\NetBackup\bin\bptm.exe" -w -pid 8228 -c xq1 -dpath G:\MSSQL\testdb -stunit stor_testdb -cl Job_testdb -bt 1402419558 -b xq1_1402419558 -st 2 -cj 1 -reqid -1401819349 -jm -brm -hostname xq1 -L /C/ProgramĄ Files/Veritas/NetBackup/Logs/user_ops/mssql/logs/0610114095955-8072-8208-000-000-prg -ru SYSTEM -rclnt xq1 -rclnthostname xq1 -rl 3 -rp 2678400 -sl Default-Application-Backup -ct 15 -maxfrag 524288 -eari 0 -mediasvr xq2 -no_callback -connect_options 0x01010100 -jobid 1147 -jobgrpid 1146 -masterversion 750000 -bpbrm_shm_id Global\NetBackup_BPBRM_SHM_Path_601834197_8228_7172 -blks_per_buffer 512 
09:59:29.333 [8228.7172] <2> bpbrm create_mm_terminate: created terminate event pid 8988
09:59:29.333 [8228.7172] <2> bpbrm create_mm_suspend: created suspend event pid 8988
09:59:29.333 [8228.7172] <2> bpbrm write_continue_backup: wrote CONTINUE BACKUP on COMM_SOCK <548>
09:59:29.333 [8228.7172] <2> write_file_names: buffering file name '/XQ1.MSSQL7.XQ1.db.test.~.7.001of001.20140610095956..C' for output
09:59:29.333 [8228.7172] <2> write_file_names: successfully wrote buffer to COMM_SOCK
09:59:29.333 [8228.7172] <2> bpbrm main: wrote CONTINUE on COMM_SOCK
09:59:29.333 [8228.7172] <2> bpbrm main: closing DATA_SOCK
09:59:29.333 [8228.7172] <2> bpbrm main: closing COMM_SOCK
09:59:33.358 [8228.7172] <2> bpbrm mm_sig: received ready signal from media manager
09:59:34.528 [8228.7172] <2> bpbrm main: client xq1 EXIT STATUS = 0: the requested operation was successfully completed
09:59:34.528 [8228.7172] <2> ConnectionCache::connectAndCache: Acquiring new connection for host xq2, query type 78
09:59:34.528 [8228.7172] <2> logconnections: BPDBM CONNECT FROM 100.112.2.141.55174 TO 100.112.2.141.13721 fd = 548
09:59:34.528 [8228.7172] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2
09:59:34.606 [8228.7172] <2> db_end: Need to collect reply
09:59:34.700 [8228.7172] <2> bpbrm wait_for_child: start
09:59:35.183 [8228.7172] <2> bpbrm wait_for_child: child exit_status = 0
09:59:35.183 [8228.7172] <2> bpbrm wait_for_child: child exit normal
09:59:35.183 [8228.7172] <2> bpbrm main: validating image for client xq1
09:59:35.183 [8228.7172] <2> ConnectionCache::connectAndCache: Acquiring new connection for host xq2, query type 74
09:59:35.183 [8228.7172] <2> logconnections: BPDBM CONNECT FROM 100.112.2.141.55176 TO 100.112.2.141.13721 fd = 556
09:59:35.183 [8228.7172] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2
09:59:35.246 [8228.7172] <2> db_end: Need to collect reply
09:59:35.370 [8228.7172] <2> bpbrm Exit: client backup EXIT STATUS 0: the requested operation was successfully completed
09:59:39.083 [7840.7544] <2> ConnectionCache::connectAndCache: Acquiring new connection for host xq2, query type 78
09:59:39.099 [7840.7544] <2> logconnections: BPDBM CONNECT FROM 100.112.2.141.55186 TO 100.112.2.141.13721 fd = 568
09:59:39.099 [7840.7544] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2
09:59:39.161 [7840.7544] <2> db_end: Need to collect reply
09:59:39.224 [7840.7544] <2> bpbrm main: validating image for client xq1
09:59:39.224 [7840.7544] <2> ConnectionCache::connectAndCache: Acquiring new connection for host xq2, query type 74
09:59:39.224 [7840.7544] <2> logconnections: BPDBM CONNECT FROM 100.112.2.141.55188 TO 100.112.2.141.13721 fd = 568
09:59:39.224 [7840.7544] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2
09:59:39.270 [7840.7544] <2> db_end: Need to collect reply
09:59:39.348 [7840.7544] <2> bpbrm Exit: attempting to send mail to root on xq1
09:59:39.364 [7840.7544] <2> vnet_pbxConnect: pbxConnectEx Succeeded
09:59:39.364 [7840.7544] <2> logconnections: BPCD CONNECT FROM 100.112.2.141.55189 TO 100.112.2.140.1556 fd = 572
09:59:39.380 [7840.7544] <2> vnet_pbxConnect: pbxConnectEx Succeeded
09:59:39.458 [7840.7544] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 100.112.2.141.55190 TO 100.112.2.140.1556 fd = 568
09:59:39.458 [7840.7544] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa
09:59:39.660 [7840.7544] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string 63268
09:59:40.300 [7840.7544] <2> bpbrm Exit: OUT_SOCK from bpcr = 572
09:59:40.300 [7840.7544] <2> bpbrm Exit: IN_SOCK from bpcr = 568
09:59:40.503 [7840.7544] <2> bpcr_get_version_rqst: bpcd version: 07500000
09:59:40.721 [7840.7544] <2> bpcr_get_version_rqst: bpcd version: 07500000
09:59:41.376 [7840.7544] <2> bpcr_get_version_rqst: bpcd version: 07500000
09:59:41.829 [7840.7544] <2> bpbrm Exit: client backup EXIT STATUS 0: the requested operation was successfully completed
10:00:28.426 [6004.8848] <2> bpbrm main: INITIATING (VERBOSE = 0): version NetBackup 7.5 0
10:00:28.426 [6004.8848] <2> logparams: -backup -S xq2 -c xq1 -ct 15 -ru root -cl Job_testdb -sched testdb -bt 1402419628 -dt 0 -st 0 -b xq1_1402419628 -mediasvr xq2 -jobid 1148 -jobgrpid 1148 -masterversion 750000 -maxfrag 0 -bpstart_time 1402419928 -p 0 -use_ofb -use_otm -xbsa -nbu -jm -secure 1 -kl 28 -rg other -fso -hqdb -connect_options 16974338 
10:00:28.426 [6004.8848] <2> verify_client: bpbrm.c.37676: db_getCLIENT failed for CLIENT: xq1
10:00:28.426 [6004.8848] <2> verify_client: bpbrm.c.37842: db_getCLIENT failed: 227 227 0x000000e3
10:00:28.442 [6004.8848] <2> vnet_pbxConnect: pbxConnectEx Succeeded
10:00:28.442 [6004.8848] <2> logconnections: BPCD CONNECT FROM 100.112.2.141.55223 TO 100.112.2.140.1556 fd = 480
10:00:28.457 [6004.8848] <2> vnet_pbxConnect: pbxConnectEx Succeeded
10:00:28.535 [6004.8848] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 100.112.2.141.55224 TO 100.112.2.140.1556 fd = 500
10:00:28.535 [6004.8848] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa
10:00:28.785 [6004.8848] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string 63276
10:00:29.456 [6004.8848] <2> bpbrm start_bpcd_stat: DATA_SOCK from bpcr = 480
10:00:29.456 [6004.8848] <2> bpbrm start_bpcd_stat: NAME_SOCK from bpcr = 500
10:00:29.658 [6004.8848] <2> bpcr_get_version_rqst: bpcd version: 07500000
10:00:30.048 [6004.8848] <2> bpbrm create_events: created suspend event name Global\NetBackup Suspend Event, pid: 6004
10:00:30.048 [6004.8848] <2> job_connect: SO_KEEPALIVE set on socket 524 for client xq2
10:00:30.048 [6004.8848] <2> logconnections: BPJOBD CONNECT FROM 100.112.2.141.55225 TO 100.112.2.141.13723 fd = 524
10:00:30.048 [6004.8848] <2> job_authenticate_connection: ignoring VxSS authentication check for now...
10:00:30.048 [6004.8848] <2> job_connect: Connected to the host xq2 contype 53 jobid <1148> socket <524>
10:00:30.048 [6004.8848] <2> job_connect: Connected on port 55225
10:00:30.064 [6004.8848] <2> vnet_pbxConnect: pbxConnectEx Succeeded
10:00:30.064 [6004.8848] <2> logconnections: BPCD CONNECT FROM 100.112.2.141.55226 TO 100.112.2.140.1556 fd = 536
10:00:30.080 [6004.8848] <2> vnet_pbxConnect: pbxConnectEx Succeeded
10:00:30.158 [6004.8848] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 100.112.2.141.55227 TO 100.112.2.140.1556 fd = 544
10:00:30.158 [6004.8848] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa
10:00:30.376 [6004.8848] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string 63278
10:00:31.250 [6004.8848] <2> bpcr_get_version_rqst: bpcd version: 07500000
10:00:31.468 [6004.8848] <2> bpcr_get_charset_rqst: bpcd charset: 00000001
10:00:31.484 [6004.8848] <2> ConnectionCache::connectAndCache: Acquiring new connection for host xq2, query type 89
10:00:31.484 [6004.8848] <2> logconnections: BPDBM CONNECT FROM 100.112.2.141.55228 TO 100.112.2.141.13721 fd = 544
10:00:31.484 [6004.8848] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2
10:00:31.562 [6004.8848] <2> db_end: Need to collect reply
10:00:31.640 [6004.8848] <2> vnet_pbxConnect: pbxConnectEx Succeeded
10:00:31.640 [6004.8848] <2> logconnections: BPCD CONNECT FROM 100.112.2.141.55229 TO 100.112.2.140.1556 fd = 540
10:00:31.655 [6004.8848] <2> vnet_pbxConnect: pbxConnectEx Succeeded
10:00:31.733 [6004.8848] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 100.112.2.141.55230 TO 100.112.2.140.1556 fd = 544
10:00:31.733 [6004.8848] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa
10:00:31.936 [6004.8848] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string 63280
10:00:32.607 [6004.8848] <2> bpbrm start_bpcd_stat: DATA_SOCK from bpcr = 540
10:00:32.607 [6004.8848] <2> bpbrm start_bpcd_stat: NAME_SOCK from bpcr = 544
10:00:32.607 [6004.8848] <2> bpbrm main: received bpcd success message
10:00:33.714 [6004.8848] <2> bpbrm main: client_pid=7924
10:00:33.714 [6004.8848] <2> bpbrm main: from client xq1: read client start message
10:00:33.714 [6004.8848] <2> bpbrm write_continue_backup: wrote CONTINUE BACKUP on COMM_SOCK <540>
10:00:33.714 [6004.8848] <2> write_file_names: buffering file name 'C:\Program Files\Veritas\NetBackup\DbExt\MsSql\fan\testdb.bch' for output
10:00:33.714 [6004.8848] <2> write_file_names: successfully wrote buffer to COMM_SOCK
10:00:33.714 [6004.8848] <2> bpbrm main: wrote CONTINUE on COMM_SOCK
10:00:33.714 [6004.8848] <2> bpbrm main: closing COMM_SOCK
10:02:58.857 [7580.8324] <2> bpbrm main: INITIATING (VERBOSE = 0): version NetBackup 7.5 0
10:02:58.857 [7580.8324] <2> logparams: -backup -S xq2 -c xq1 -ct 15 -ru SYSTEM -cl Job_testdb -sched Default-Application-Backup -bt 1402419778 -dt 0 -st 2 -b xq1_1402419778 -mediasvr xq2 -jobid 1150 -jobgrpid 1148 -masterversion 750000 -maxfrag 524288 -bpstart_time 1402420078 -reqid -1401819352 -mt 0 -to 0 -stunit stor_testdb -rl 3 -rp 2678400 -eari 0 -p G:\MSSQL\testdb -mst 1 -flags 0 -use_ofb -use_otm -fi -fim auto -xbsa -nbu -jm -secure 1 -kl 28 -rg SYSTEM -fso -keyword /XQ1.MSSQL7.XQ1.db.test.Į¾.7.001of001.20140610100122..C -hqdb -cfc -PCB -L /C/ProgramĄ Files/Veritas/NetBackup/Logs/user_ops/mssql/logs/0610114100121-6324-8752-000-000-prg -connect_options 16974338 
10:02:58.857 [7580.8324] <2> bpbrm main: DBG - Snapshot method [4] - auto
10:02:58.857 [7580.8324] <2> verify_client: bpbrm.c.37676: db_getCLIENT failed for CLIENT: xq1
10:02:58.857 [7580.8324] <2> verify_client: bpbrm.c.37842: db_getCLIENT failed: 227 227 0x000000e3
10:02:58.873 [7580.8324] <2> vnet_pbxConnect: pbxConnectEx Succeeded
10:02:58.873 [7580.8324] <2> logconnections: BPCD CONNECT FROM 100.112.2.141.55290 TO 100.112.2.140.1556 fd = 480
10:02:58.888 [7580.8324] <2> vnet_pbxConnect: pbxConnectEx Succeeded
10:02:58.966 [7580.8324] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 100.112.2.141.55291 TO 100.112.2.140.1556 fd = 504
10:02:58.966 [7580.8324] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa
10:02:59.185 [7580.8324] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string 63447
10:02:59.840 [7580.8324] <2> bpbrm start_bpcd_stat: DATA_SOCK from bpcr = 480
10:02:59.840 [7580.8324] <2> bpbrm start_bpcd_stat: NAME_SOCK from bpcr = 504
10:03:00.043 [7580.8324] <2> bpcr_get_version_rqst: bpcd version: 07500000
10:03:00.246 [7580.8324] <2> vnet_pbxConnect: pbxConnectEx Succeeded
10:03:00.246 [7580.8324] <2> logconnections: BPCD CONNECT FROM 100.112.2.141.55293 TO 100.112.2.140.1556 fd = 504
10:03:00.261 [7580.8324] <2> vnet_pbxConnect: pbxConnectEx Succeeded
10:03:00.339 [7580.8324] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 100.112.2.141.55294 TO 100.112.2.140.1556 fd = 496
10:03:00.339 [7580.8324] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa
10:03:00.558 [7580.8324] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string 63450
10:03:01.228 [7580.8324] <2> local_getHostInfo: CLIENT_CMD_SOCK from bpcr = 504
10:03:01.431 [7580.8324] <2> bpcr_get_version_rqst: bpcd version: 07500000
10:03:01.634 [7580.8324] <2> local_getHostInfo: hostname comparison failed
10:03:01.837 [7580.8324] <2> bpcr_get_version_rqst: bpcd version: 07500000
10:03:01.837 [7580.8324] <2> local_getHostInfo: rqstType == BPCD_GET_NB_VERSION_RQST
10:03:02.242 [7580.8324] <2> bpcr_get_platform_rqst: Server platform length = 7
10:03:02.242 [7580.8324] <2> xlatePlatformName: my version = 750000, client version = 750000, check current list first
10:03:02.242 [7580.8324] <2> xlatePlatformName: BUILD_PLATFORM name to xlate = win_x64
10:03:02.242 [7580.8324] <2> local_getHostInfo: platform of client xq1 is PC-x64, WindowsXP
10:03:02.461 [7580.8324] <2> bpcr_get_uname_rqst: bpcr.c: 3662 count=28, buffer=UNAME_SYSNAME = Windows2008 
10:03:02.461 [7580.8324] <2> bpcr_get_uname_rqst: bpcr.c: 3662 count=21, buffer=UNAME_NODENAME = XQ1 
10:03:02.461 [7580.8324] <2> bpcr_get_uname_rqst: bpcr.c: 3662 count=18, buffer=UNAME_RELEASE = 6 
10:03:02.461 [7580.8324] <2> bpcr_get_uname_rqst: bpcr.c: 3662 count=18, buffer=UNAME_VERSION = 1 
10:03:02.461 [7580.8324] <2> bpcr_get_uname_rqst: bpcr.c: 3662 count=52, buffer=UNAME_MACHINE = Unknown - Unknown -  Revision x1a05 
10:03:02.461 [7580.8324] <2> bpcr_get_uname_rqst: bpcr.c: 3662 count=3, buffer=END
10:03:02.461 [7580.8324] <2> local_getHostInfo: d:\nb\nb_7.5\src\nb\dblib\dbmisc.c: 1726, uname -sr=Windows2008 6 

10:03:02.664 [7580.8324] <2> bpcr_get_version_rqst: bpcd version: 07500000
10:03:03.100 [7580.8324] <2> getPatchLevel: CLIENT_CMD_SOCK from bpcr = 504
10:03:03.303 [7580.8324] <2> getPatchLevel: got client socket
10:03:03.303 [7580.8324] <2> getPatchLevel: file num = 4, file size = 109
10:03:03.303 [7580.8324] <2> bpcr_read_rqst: completed send of file number 4
10:03:03.303 [7580.8324] <2> bpcr_read_rqst: completed send of transfer_count = 16383
10:03:03.303 [7580.8324] <2> bpcr_read_rqst: Before readclientstatus on socket 496
10:03:03.522 [7580.8324] <2> bpcr_read_rqst: Num bytes to be sent by client = 109
10:03:03.522 [7580.8324] <2> getPatchLevel: total chars read = 109
10:03:03.522 [7580.8324] <2> getPatchLevel: level = >7.5<
10:03:03.865 [7580.8324] <2> bpbrm create_events: created suspend event name Global\NetBackup Suspend Event, pid: 7580
10:03:03.865 [7580.8324] <2> job_connect: SO_KEEPALIVE set on socket 524 for client xq2
10:03:03.865 [7580.8324] <2> logconnections: BPJOBD CONNECT FROM 100.112.2.141.55295 TO 100.112.2.141.13723 fd = 524
10:03:03.865 [7580.8324] <2> job_authenticate_connection: ignoring VxSS authentication check for now...
10:03:03.865 [7580.8324] <2> job_connect: Connected to the host xq2 contype 53 jobid <1150> socket <524>
10:03:03.865 [7580.8324] <2> job_connect: Connected on port 55295
10:03:03.865 [7580.8324] <2> vnet_pbxConnect: pbxConnectEx Succeeded
10:03:03.865 [7580.8324] <2> logconnections: BPCD CONNECT FROM 100.112.2.141.55296 TO 100.112.2.140.1556 fd = 540
10:03:03.896 [7580.8324] <2> vnet_pbxConnect: pbxConnectEx Succeeded
10:03:03.974 [7580.8324] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 100.112.2.141.55297 TO 100.112.2.140.1556 fd = 544
10:03:03.974 [7580.8324] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa
10:03:04.192 [7580.8324] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string 63452
10:03:05.050 [7580.8324] <2> bpcr_get_version_rqst: bpcd version: 07500000
10:03:05.253 [7580.8324] <2> bpcr_get_charset_rqst: bpcd charset: 00000001
10:03:05.253 [7580.8324] <2> ConnectionCache::connectAndCache: Acquiring new connection for host xq2, query type 89
10:03:05.253 [7580.8324] <2> logconnections: BPDBM CONNECT FROM 100.112.2.141.55298 TO 100.112.2.141.13721 fd = 544
10:03:05.253 [7580.8324] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2
10:03:05.347 [7580.8324] <2> db_end: Need to collect reply
10:03:05.409 [7580.8324] <2> vnet_pbxConnect: pbxConnectEx Succeeded
10:03:05.487 [7580.8324] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 100.112.2.141.55299 TO 100.112.2.140.1556 fd = 540
10:03:05.487 [7580.8324] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa
10:03:05.706 [7580.8324] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string 63455
10:03:05.924 [7580.8324] <2> bpbrm listen_for_client: HOT_SQL_SERVER_DB_BACKUP
10:03:05.924 [7580.8324] <2> vnet_pbxConnect: pbxConnectEx Succeeded
10:03:06.002 [7580.8324] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 100.112.2.141.55300 TO 100.112.2.140.1556 fd = 548
10:03:06.002 [7580.8324] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa
10:03:06.205 [7580.8324] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string 63456
10:03:06.423 [7580.8324] <2> vnet_pbxConnect: pbxConnectEx Succeeded
10:03:06.501 [7580.8324] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 100.112.2.141.55301 TO 100.112.2.140.1556 fd = 552
10:03:06.501 [7580.8324] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa
10:03:06.704 [7580.8324] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string 63457
10:03:06.922 [7580.8324] <2> vnet_pbxConnect: pbxConnectEx Succeeded
10:03:06.922 [7580.8324] <2> logconnections: BPCD CONNECT FROM 100.112.2.141.55302 TO 100.112.2.140.1556 fd = 556
10:03:06.938 [7580.8324] <2> vnet_pbxConnect: pbxConnectEx Succeeded
10:03:07.016 [7580.8324] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 100.112.2.141.55303 TO 100.112.2.140.1556 fd = 560
10:03:07.016 [7580.8324] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa
10:03:07.219 [7580.8324] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string 63458
10:03:07.874 [7580.8324] <2> bpbrm send_info_via_progress_file: CLIENT_CMD_SOCK from bpcr = 556
10:03:07.874 [7580.8324] <2> bpbrm send_info_via_progress_file: CLIENT_STAT_SOCK from bpcr = 560
10:03:08.295 [7580.8324] <2> bpcr_get_peername_rqst: Server peername length = 3
10:03:08.498 [7580.8324] <2> bpbrm write_msg_to_progress_file: INF - Data socket = xq2.IPC:63456;d8a8523c1dac3362acfecf0a60d14e51;11;300

10:03:08.701 [7580.8324] <2> bpbrm write_msg_to_progress_file: INF - Name socket = xq2.IPC:63457;2dba011d9e73df3c43bb612c34497f59;11;300

10:03:08.904 [7580.8324] <2> bpbrm write_msg_to_progress_file: INF - Job id = 1150

10:03:09.106 [7580.8324] <2> bpbrm write_msg_to_progress_file: INF - Backup id = xq1_1402419778

10:03:09.309 [7580.8324] <2> bpbrm write_msg_to_progress_file: INF - Backup time = 1402419778

10:03:09.512 [7580.8324] <2> bpbrm write_msg_to_progress_file: INF - Policy name = Job_testdb

10:03:09.715 [7580.8324] <2> bpbrm write_msg_to_progress_file: INF - Snapshot = 1

10:03:09.918 [7580.8324] <2> bpbrm write_msg_to_progress_file: INF - Frozen image = 1

10:03:10.120 [7580.8324] <2> bpbrm write_msg_to_progress_file: INF - Snapshot method argument prefix auto

10:03:10.323 [7580.8324] <2> bpbrm write_msg_to_progress_file: INF - Backup copy = 0

10:03:10.526 [7580.8324] <2> bpbrm write_msg_to_progress_file: INF - Master server = xq2

10:03:10.729 [7580.8324] <2> bpbrm write_msg_to_progress_file: INF - Media server = xq2

10:03:10.932 [7580.8324] <2> bpbrm write_msg_to_progress_file: INF - Multiplexing = 0

10:03:11.134 [7580.8324] <2> bpbrm write_msg_to_progress_file: INF - New data socket = xq2.IPC:63455;24a58d654302a9834e966ef88bbd3769;11;300

10:03:11.337 [7580.8324] <2> bpbrm write_msg_to_progress_file: INF - Encrypt = 0

10:03:11.540 [7580.8324] <2> bpbrm write_msg_to_progress_file: INF - Use shared memory = 0

10:03:11.743 [7580.8324] <2> bpbrm write_msg_to_progress_file: INF - Compression = 0

10:03:11.946 [7580.8324] <2> bpbrm write_msg_to_progress_file: INF - Encrypt = 0

10:03:12.148 [7580.8324] <2> bpbrm write_msg_to_progress_file: INF - Keep logs = 28

10:03:12.351 [7580.8324] <2> bpbrm write_msg_to_progress_file: INF - Delta time = 0

10:03:12.554 [7580.8324] <2> bpbrm write_msg_to_progress_file: INF - Client name = xq1

10:03:12.757 [7580.8324] <2> bpbrm write_msg_to_progress_file: INF - Class name = Job_testdb

10:03:12.975 [7580.8324] <2> bpbrm write_msg_to_progress_file: INF - Schedule name = Default-Application-Backup

10:03:13.178 [7580.8324] <2> bpbrm write_msg_to_progress_file: INF - Schedule type = UBAK

10:03:13.381 [7580.8324] <2> bpbrm write_msg_to_progress_file: INF - Bpstart timeout = 300

10:03:13.584 [7580.8324] <2> bpbrm write_msg_to_progress_file: INF - Bpend timeout = 300

10:03:13.786 [7580.8324] <2> bpbrm write_msg_to_progress_file: INF - Block incremental = 0

10:03:13.989 [7580.8324] <2> bpbrm write_msg_to_progress_file: INF - Previous block incremental time = 0

10:03:14.192 [7580.8324] <2> bpbrm write_msg_to_progress_file: INF - Previous block incremental full = 0

10:03:14.395 [7580.8324] <2> bpbrm write_msg_to_progress_file: INF - Filesystem only = 1

10:03:14.598 [7580.8324] <2> bpbrm write_msg_to_progress_file: INF - Keyword = /XQ1.MSSQL7.XQ1.db.test.Į¾.7.001of001.20140610100122..C

10:03:14.800 [7580.8324] <2> bpbrm write_msg_to_progress_file: INF - Client read timeout = 300

10:03:15.003 [7580.8324] <2> bpbrm write_msg_to_progress_file: INF - Media mount timeout = 0

10:03:15.518 [7580.8324] <2> bpbrm listen_for_client: DATA_SOCK for client = 548
10:03:15.518 [7580.8324] <2> bpbrm listen_for_client: NAME_SOCK for client = 552
10:03:15.518 [7580.8324] <2> bpbrm main: from client xq1: INF - START vxbsa_file_backup 1 bpbkar32 -L C:\ProgramĄ Files\Veritas\NetBackup\Logs\user_ops\mssql\logs\0610114100121-6324-8752-000-000-prg -IEL -PCB C:\ProgramĄ Files\Veritas\NetBackup\Logs\user_ops\dbext\logs\vxbsa.1402419684.6324.8848.files.1 -ct 15 -fi -dt 0 -to 0 -read_to 300 -clnt xq1 -class Job_testdb -sched Default-Application-Backup -st UBAK -S xq2 -bpstart_to 300 -bpend_to 300 -fso -b xq1_1402419778 -kl 28
10:03:15.518 [7580.8324] <2> bpbrm write_continue_backup: wrote CONTINUE BACKUP on COMM_SOCK <548>
10:03:15.518 [7580.8324] <2> write_file_names: buffering file name '/XQ1.MSSQL7.XQ1.db.test.~.7.001of001.20140610100122..C' for output
10:03:15.518 [7580.8324] <2> write_file_names: successfully wrote buffer to COMM_SOCK
10:03:15.518 [7580.8324] <2> bpbrm write_filelist: wrote CONTINUE on COMM_SOCK
10:03:15.518 [7580.8324] <2> vnet_pbxConnect: pbxConnectEx Succeeded
10:03:15.518 [7580.8324] <2> logconnections: BPCD CONNECT FROM 100.112.2.141.55305 TO 100.112.2.140.1556 fd = 560
10:03:15.549 [7580.8324] <2> vnet_pbxConnect: pbxConnectEx Succeeded
10:03:15.643 [7580.8324] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 100.112.2.141.55306 TO 100.112.2.140.1556 fd = 556
10:03:15.643 [7580.8324] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa
10:03:15.846 [7580.8324] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string 63464
10:03:16.516 [7580.8324] <2> bpbrm start_bpcd_stat: DATA_SOCK from bpcr = 560
10:03:16.516 [7580.8324] <2> bpbrm start_bpcd_stat: NAME_SOCK from bpcr = 556
10:03:16.516 [7580.8324] <2> pfi_start_client: command = /usr/openv/netbackup/bin/bpbkar bpbkar32 -L C:\ProgramĄ Files\Veritas\NetBackup\Logs\user_ops\mssql\logs\0610114100121-6324-8752-000-000-prg -IEL -PCB C:\ProgramĄ Files\Veritas\NetBackup\Logs\user_ops\dbext\logs\vxbsa.1402419684.6324.8848.files.1 -ct 15 -fi -dt 0 -to 0 -read_to 300 -clnt xq1 -class Job_testdb -sched Default-Application-Backup -st UBAK -S xq2 -bpstart_to 300 -bpend_to 300 -fso -b xq1_1402419778 -kl 28
10:03:16.516 [7580.8324] <2> pfi_start_client: received bpcd success message
10:03:19.839 [7580.8324] <2> get_readline_type: Returning BPBRM_READ_INFORM_WHEN_DONE
10:03:19.839 [7580.8324] <2> pfi_start_client: read Inform when done message from xq1
10:03:20.058 [7580.8324] <2> pfi_start_client: read Echo Keepalives message from xq1
10:03:20.058 [7580.8324] <2> pfi_start_client: read start message from xq1, msg=<INF - BACKUP START 8020>
10:03:20.058 [7580.8324] <2> bpbrm main: client_pid=6324
10:03:20.058 [7580.8324] <2> bpbrm main: from client xq1: read client start message
10:03:20.058 [7580.8324] <2> bpbrm spawn_child: "C:\Program Files\Veritas\NetBackup\bin\bptm.exe" -w -pid 7580 -c xq1 -dpath G:\MSSQL\testdb -stunit stor_testdb -cl Job_testdb -bt 1402419778 -b xq1_1402419778 -st 2 -cj 1 -reqid -1401819352 -jm -brm -hostname xq1 -L /C/ProgramĄ Files/Veritas/NetBackup/Logs/user_ops/mssql/logs/0610114100121-6324-8752-000-000-prg -ru SYSTEM -rclnt xq1 -rclnthostname xq1 -rl 3 -rp 2678400 -sl Default-Application-Backup -ct 15 -maxfrag 524288 -eari 0 -mediasvr xq2 -no_callback -connect_options 0x01010100 -jobid 1150 -jobgrpid 1148 -masterversion 750000 -bpbrm_shm_id Global\NetBackup_BPBRM_SHM_Path_602058028_7580_8324 -blks_per_buffer 512 
10:03:20.058 [7580.8324] <2> bpbrm create_mm_terminate: created terminate event pid 8796
10:03:20.058 [7580.8324] <2> bpbrm create_mm_suspend: created suspend event pid 8796
10:03:20.058 [7580.8324] <2> bpbrm write_continue_backup: wrote CONTINUE BACKUP on COMM_SOCK <560>
10:03:20.058 [7580.8324] <2> write_file_names: buffering file name '/XQ1.MSSQL7.XQ1.db.test.~.7.001of001.20140610100122..C' for output
10:03:20.058 [7580.8324] <2> write_file_names: successfully wrote buffer to COMM_SOCK
10:03:20.058 [7580.8324] <2> bpbrm main: wrote CONTINUE on COMM_SOCK
10:03:20.058 [7580.8324] <2> bpbrm main: closing DATA_SOCK
10:03:20.260 [7580.8324] <2> bpbrm main: from client xq1: INF - Open File Backup license is not installed on the server.  Open File Backup usage will be disabled.
10:03:20.526 [7580.8324] <2> bpbrm main: ESTIMATE 3072 5 xq1_1402419778
10:03:23.708 [7580.8324] <2> bpbrm mm_sig: received ready signal from media manager
10:03:23.958 [7580.8324] <2> bpbrm main: client xq1 EXIT STATUS = 0: the requested operation was successfully completed
10:03:23.958 [7580.8324] <2> ConnectionCache::connectAndCache: Acquiring new connection for host xq2, query type 78
10:03:23.958 [7580.8324] <2> logconnections: BPDBM CONNECT FROM 100.112.2.141.55329 TO 100.112.2.141.13721 fd = 544
10:03:23.958 [7580.8324] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2
10:03:24.004 [7580.8324] <2> db_end: Need to collect reply
10:03:24.067 [7580.8324] <2> bpbrm wait_for_child: start
10:03:24.114 [7580.8324] <2> bpbrm wait_for_child: child exit_status = 0
10:03:24.114 [7580.8324] <2> bpbrm wait_for_child: child exit normal
10:03:24.114 [7580.8324] <2> bpbrm main: validating image for client xq1
10:03:24.114 [7580.8324] <2> ConnectionCache::connectAndCache: Acquiring new connection for host xq2, query type 74
10:03:24.114 [7580.8324] <2> logconnections: BPDBM CONNECT FROM 100.112.2.141.55330 TO 100.112.2.141.13721 fd = 568
10:03:24.114 [7580.8324] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2
10:03:24.160 [7580.8324] <2> db_end: Need to collect reply
10:03:24.285 [7580.8324] <2> inform_client_of_status: INF - Server status = 0
10:03:24.644 [7580.8324] <2> bpbrm Exit: EXIT STATUS 0:
10:03:24.644 [7580.8324] <2> bpbrm Exit: sending dbclient :<EXIT STATUS 0:>
10:03:24.644 [7580.8324] <2> bpbrm Exit: client backup EXIT STATUS 0: the requested operation was successfully completed
10:08:05.756 [6004.8848] <2> ConnectionCache::connectAndCache: Acquiring new connection for host xq2, query type 78
10:08:05.756 [6004.8848] <2> logconnections: BPDBM CONNECT FROM 100.112.2.141.55404 TO 100.112.2.141.13721 fd = 492
10:08:05.756 [6004.8848] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2
10:08:05.819 [6004.8848] <2> db_end: Need to collect reply
10:08:05.897 [6004.8848] <2> bpbrm main: validating image for client xq1
10:08:05.897 [6004.8848] <2> ConnectionCache::connectAndCache: Acquiring new connection for host xq2, query type 74
10:08:05.897 [6004.8848] <2> logconnections: BPDBM CONNECT FROM 100.112.2.141.55405 TO 100.112.2.141.13721 fd = 492
10:08:05.897 [6004.8848] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2
10:08:05.975 [6004.8848] <2> db_end: Need to collect reply
10:08:06.053 [6004.8848] <2> bpbrm Exit: attempting to send mail to root on xq1
10:08:06.068 [6004.8848] <2> vnet_pbxConnect: pbxConnectEx Succeeded
10:08:06.068 [6004.8848] <2> logconnections: BPCD CONNECT FROM 100.112.2.141.55406 TO 100.112.2.140.1556 fd = 544
10:08:06.084 [6004.8848] <2> vnet_pbxConnect: pbxConnectEx Succeeded
10:08:06.162 [6004.8848] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 100.112.2.141.55407 TO 100.112.2.140.1556 fd = 492
10:08:06.162 [6004.8848] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa
10:08:06.443 [6004.8848] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string 63550
10:08:07.098 [6004.8848] <2> bpbrm Exit: OUT_SOCK from bpcr = 544
10:08:07.098 [6004.8848] <2> bpbrm Exit: IN_SOCK from bpcr = 492
10:08:07.316 [6004.8848] <2> bpcr_get_version_rqst: bpcd version: 07500000
10:08:07.519 [6004.8848] <2> bpcr_get_version_rqst: bpcd version: 07500000
10:08:07.925 [6004.8848] <2> bpcr_get_version_rqst: bpcd version: 07500000
10:08:08.128 [6004.8848] <2> bpbrm Exit: client backup EXIT STATUS 0: the requested operation was successfully completed
10:20:57.412 [8292.6332] <2> bpbrm main: INITIATING (VERBOSE = 0): version NetBackup 7.5 0
10:20:57.412 [8292.6332] <2> logparams: -backup -S xq2 -c xq1 -ct 15 -ru root -cl Job_testdb -sched testdb -bt 1402420857 -dt 0 -st 0 -b xq1_1402420857 -mediasvr xq2 -jobid 1152 -jobgrpid 1152 -masterversion 750000 -maxfrag 0 -bpstart_time 1402421157 -p 0 -use_ofb -use_otm -xbsa -nbu -jm -secure 1 -kl 28 -rg other -fso -hqdb -connect_options 16974338 
10:20:57.412 [8292.6332] <2> verify_client: bpbrm.c.37676: db_getCLIENT failed for CLIENT: xq1
10:20:57.412 [8292.6332] <2> verify_client: bpbrm.c.37842: db_getCLIENT failed: 227 227 0x000000e3
10:20:57.427 [8292.6332] <2> vnet_pbxConnect: pbxConnectEx Succeeded
10:20:57.427 [8292.6332] <2> logconnections: BPCD CONNECT FROM 100.112.2.141.55597 TO 100.112.2.140.1556 fd = 480
10:20:57.443 [8292.6332] <2> vnet_pbxConnect: pbxConnectEx Succeeded
10:20:57.521 [8292.6332] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 100.112.2.141.55598 TO 100.112.2.140.1556 fd = 500
10:20:57.521 [8292.6332] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa
10:20:57.739 [8292.6332] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string 63633
10:20:58.379 [8292.6332] <2> bpbrm start_bpcd_stat: DATA_SOCK from bpcr = 480
10:20:58.379 [8292.6332] <2> bpbrm start_bpcd_stat: NAME_SOCK from bpcr = 500
10:20:58.582 [8292.6332] <2> bpcr_get_version_rqst: bpcd version: 07500000
10:20:58.987 [8292.6332] <2> bpbrm create_events: created suspend event name Global\NetBackup Suspend Event, pid: 8292
10:20:58.987 [8292.6332] <2> job_connect: SO_KEEPALIVE set on socket 524 for client xq2
10:20:58.987 [8292.6332] <2> logconnections: BPJOBD CONNECT FROM 100.112.2.141.55599 TO 100.112.2.141.13723 fd = 524
10:20:58.987 [8292.6332] <2> job_authenticate_connection: ignoring VxSS authentication check for now...
10:20:58.987 [8292.6332] <2> job_connect: Connected to the host xq2 contype 53 jobid <1152> socket <524>
10:20:58.987 [8292.6332] <2> job_connect: Connected on port 55599
10:20:59.003 [8292.6332] <2> vnet_pbxConnect: pbxConnectEx Succeeded
10:20:59.003 [8292.6332] <2> logconnections: BPCD CONNECT FROM 100.112.2.141.55600 TO 100.112.2.140.1556 fd = 540
10:20:59.003 [8292.6332] <2> vnet_pbxConnect: pbxConnectEx Succeeded
10:20:59.097 [8292.6332] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 100.112.2.141.55601 TO 100.112.2.140.1556 fd = 544
10:20:59.097 [8292.6332] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa
10:20:59.315 [8292.6332] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string 63635
10:21:00.173 [8292.6332] <2> bpcr_get_version_rqst: bpcd version: 07500000
10:21:00.376 [8292.6332] <2> bpcr_get_charset_rqst: bpcd charset: 00000001
10:21:00.376 [8292.6332] <2> ConnectionCache::connectAndCache: Acquiring new connection for host xq2, query type 89
10:21:00.376 [8292.6332] <2> logconnections: BPDBM CONNECT FROM 100.112.2.141.55602 TO 100.112.2.141.13721 fd = 544
10:21:00.376 [8292.6332] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2
10:21:00.454 [8292.6332] <2> db_end: Need to collect reply
10:21:00.532 [8292.6332] <2> vnet_pbxConnect: pbxConnectEx Succeeded
10:21:00.532 [8292.6332] <2> logconnections: BPCD CONNECT FROM 100.112.2.141.55603 TO 100.112.2.140.1556 fd = 540
10:21:00.547 [8292.6332] <2> vnet_pbxConnect: pbxConnectEx Succeeded
10:21:00.625 [8292.6332] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 100.112.2.141.55605 TO 100.112.2.140.1556 fd = 544
10:21:00.625 [8292.6332] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa
10:21:00.844 [8292.6332] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string 63637
10:21:01.483 [8292.6332] <2> bpbrm start_bpcd_stat: DATA_SOCK from bpcr = 540
10:21:01.483 [8292.6332] <2> bpbrm start_bpcd_stat: NAME_SOCK from bpcr = 544
10:21:01.483 [8292.6332] <2> bpbrm main: received bpcd success message
10:21:02.591 [8292.6332] <2> bpbrm main: client_pid=7808
10:21:02.591 [8292.6332] <2> bpbrm main: from client xq1: read client start message
10:21:02.591 [8292.6332] <2> bpbrm write_continue_backup: wrote CONTINUE BACKUP on COMM_SOCK <540>
10:21:02.591 [8292.6332] <2> write_file_names: buffering file name 'C:\Program Files\Veritas\NetBackup\DbExt\MsSql\fan\testdb.bch' for output
10:21:02.591 [8292.6332] <2> write_file_names: successfully wrote buffer to COMM_SOCK
10:21:02.591 [8292.6332] <2> bpbrm main: wrote CONTINUE on COMM_SOCK
10:21:02.591 [8292.6332] <2> bpbrm main: closing COMM_SOCK
10:24:22.786 [8292.6332] <16> bpbrm main: from client xq1: ERR - command failed: none of the requested files were backed up (2)
10:24:22.786 [8292.6332] <16> bpbrm main: from client xq1: ERR - bphdb exit status = 2: none of the requested files were backed up
10:24:22.786 [8292.6332] <2> bpbrm main: client xq1 EXIT STATUS = 2: none of the requested files were backed up
10:24:22.786 [8292.6332] <2> bpbrm Exit: attempting to send mail to root on xq1
10:24:22.802 [8292.6332] <2> vnet_pbxConnect: pbxConnectEx Succeeded
10:24:22.802 [8292.6332] <2> logconnections: BPCD CONNECT FROM 100.112.2.141.55671 TO 100.112.2.140.1556 fd = 540
10:24:22.817 [8292.6332] <2> vnet_pbxConnect: pbxConnectEx Succeeded
10:24:22.895 [8292.6332] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 100.112.2.141.55672 TO 100.112.2.140.1556 fd = 552
10:24:22.895 [8292.6332] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa
10:24:23.114 [8292.6332] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string 63742
10:24:23.753 [8292.6332] <2> bpbrm Exit: OUT_SOCK from bpcr = 540
10:24:23.753 [8292.6332] <2> bpbrm Exit: IN_SOCK from bpcr = 552
10:24:23.956 [8292.6332] <2> bpcr_get_version_rqst: bpcd version: 07500000
10:24:24.175 [8292.6332] <2> bpcr_get_version_rqst: bpcd version: 07500000
10:24:24.845 [8292.6332] <2> bpcr_get_version_rqst: bpcd version: 07500000
10:24:25.298 [8292.6332] <2> bpbrm Exit: client backup EXIT STATUS 2: none of the requested files were backed up
12:16:42.872 [7440.8540] <2> bpbrm main: INITIATING (VERBOSE = 0): version NetBackup 7.5 0
12:16:42.887 [7440.8540] <2> logparams: -backup -S xq2 -c xq1 -ct 15 -ru root -cl Job_testdb -sched testdb -bt 1402427802 -dt 0 -st 0 -b xq1_1402427802 -mediasvr xq2 -jobid 1160 -jobgrpid 1160 -masterversion 750000 -maxfrag 0 -bpstart_time 1402428102 -p 0 -use_ofb -use_otm -xbsa -nbu -jm -secure 1 -kl 28 -rg other -fso -hqdb -connect_options 16974338 
12:16:42.887 [7440.8540] <2> verify_client: bpbrm.c.37676: db_getCLIENT failed for CLIENT: xq1
12:16:42.887 [7440.8540] <2> verify_client: bpbrm.c.37842: db_getCLIENT failed: 227 227 0x000000e3
12:16:42.903 [7440.8540] <2> vnet_pbxConnect: pbxConnectEx Succeeded
12:16:42.903 [7440.8540] <2> logconnections: BPCD CONNECT FROM 100.112.2.141.56850 TO 100.112.2.140.1556 fd = 480
12:16:42.918 [7440.8540] <2> vnet_pbxConnect: pbxConnectEx Succeeded
12:16:42.996 [7440.8540] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 100.112.2.141.56851 TO 100.112.2.140.1556 fd = 504
12:16:42.996 [7440.8540] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa
12:16:43.215 [7440.8540] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string 64687
12:16:43.854 [7440.8540] <2> bpbrm start_bpcd_stat: DATA_SOCK from bpcr = 480
12:16:43.854 [7440.8540] <2> bpbrm start_bpcd_stat: NAME_SOCK from bpcr = 504
12:16:44.057 [7440.8540] <2> bpcr_get_version_rqst: bpcd version: 07500000
12:16:44.260 [7440.8540] <2> bpbrm create_events: created suspend event name Global\NetBackup Suspend Event, pid: 7440
12:16:44.260 [7440.8540] <2> job_connect: SO_KEEPALIVE set on socket 524 for client xq2
12:16:44.260 [7440.8540] <2> logconnections: BPJOBD CONNECT FROM 100.112.2.141.56852 TO 100.112.2.141.13723 fd = 524
12:16:44.260 [7440.8540] <2> job_authenticate_connection: ignoring VxSS authentication check for now...
12:16:44.260 [7440.8540] <2> job_connect: Connected to the host xq2 contype 53 jobid <1160> socket <524>
12:16:44.260 [7440.8540] <2> job_connect: Connected on port 56852
12:16:44.276 [7440.8540] <2> vnet_pbxConnect: pbxConnectEx Succeeded
12:16:44.276 [7440.8540] <2> logconnections: BPCD CONNECT FROM 100.112.2.141.56853 TO 100.112.2.140.1556 fd = 540
12:16:44.276 [7440.8540] <2> vnet_pbxConnect: pbxConnectEx Succeeded
12:16:44.369 [7440.8540] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 100.112.2.141.56854 TO 100.112.2.140.1556 fd = 544
12:16:44.369 [7440.8540] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa
12:16:44.588 [7440.8540] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string 64690
12:16:45.446 [7440.8540] <2> bpcr_get_version_rqst: bpcd version: 07500000
12:16:45.648 [7440.8540] <2> bpcr_get_charset_rqst: bpcd charset: 00000001
12:16:45.648 [7440.8540] <2> ConnectionCache::connectAndCache: Acquiring new connection for host xq2, query type 89
12:16:45.648 [7440.8540] <2> logconnections: BPDBM CONNECT FROM 100.112.2.141.56855 TO 100.112.2.141.13721 fd = 544
12:16:45.648 [7440.8540] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2
12:16:45.742 [7440.8540] <2> db_end: Need to collect reply
12:16:45.804 [7440.8540] <2> vnet_pbxConnect: pbxConnectEx Succeeded
12:16:45.804 [7440.8540] <2> logconnections: BPCD CONNECT FROM 100.112.2.141.56856 TO 100.112.2.140.1556 fd = 540
12:16:45.820 [7440.8540] <2> vnet_pbxConnect: pbxConnectEx Succeeded
12:16:45.898 [7440.8540] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 100.112.2.141.56857 TO 100.112.2.140.1556 fd = 544
12:16:45.898 [7440.8540] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa
12:16:46.116 [7440.8540] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string 64692
12:16:46.787 [7440.8540] <2> bpbrm start_bpcd_stat: DATA_SOCK from bpcr = 540
12:16:46.787 [7440.8540] <2> bpbrm start_bpcd_stat: NAME_SOCK from bpcr = 544
12:16:46.787 [7440.8540] <2> bpbrm main: received bpcd success message
12:16:47.942 [7440.8540] <2> bpbrm main: client_pid=4660
12:16:47.942 [7440.8540] <2> bpbrm main: from client xq1: read client start message
12:16:47.942 [7440.8540] <2> bpbrm write_continue_backup: wrote CONTINUE BACKUP on COMM_SOCK <540>
12:16:47.942 [7440.8540] <2> write_file_names: buffering file name 'C:\Program Files\Veritas\NetBackup\DbExt\MsSql\fan\testdb.bch' for output
12:16:47.942 [7440.8540] <2> write_file_names: successfully wrote buffer to COMM_SOCK
12:16:47.942 [7440.8540] <2> bpbrm main: wrote CONTINUE on COMM_SOCK
12:16:47.942 [7440.8540] <2> bpbrm main: closing COMM_SOCK
12:20:09.166 [7440.8540] <16> bpbrm main: from client xq1: ERR - command failed: none of the requested files were backed up (2)
12:20:09.166 [7440.8540] <16> bpbrm main: from client xq1: ERR - bphdb exit status = 2: none of the requested files were backed up
12:20:09.166 [7440.8540] <2> bpbrm main: client xq1 EXIT STATUS = 2: none of the requested files were backed up
12:20:09.166 [7440.8540] <2> bpbrm Exit: attempting to send mail to root on xq1
12:20:09.166 [7440.8540] <2> vnet_pbxConnect: pbxConnectEx Succeeded
12:20:09.166 [7440.8540] <2> logconnections: BPCD CONNECT FROM 100.112.2.141.56934 TO 100.112.2.140.1556 fd = 548
12:20:09.182 [7440.8540] <2> vnet_pbxConnect: pbxConnectEx Succeeded
12:20:09.260 [7440.8540] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 100.112.2.141.56935 TO 100.112.2.140.1556 fd = 360
12:20:09.260 [7440.8540] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa
12:20:09.478 [7440.8540] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string 64799
12:20:10.149 [7440.8540] <2> bpbrm Exit: OUT_SOCK from bpcr = 548
12:20:10.149 [7440.8540] <2> bpbrm Exit: IN_SOCK from bpcr = 360
12:20:10.368 [7440.8540] <2> bpcr_get_version_rqst: bpcd version: 07500000
12:20:10.586 [7440.8540] <2> bpcr_get_version_rqst: bpcd version: 07500000
12:20:11.007 [7440.8540] <2> bpcr_get_version_rqst: bpcd version: 07500000
12:20:11.460 [7440.8540] <2> bpbrm Exit: client backup EXIT STATUS 2: none of the requested files were backed up