12:16:01.546 [10800.9748] <2> bpdbm: Maximum stdio handles: 2048 12:16:01.546 [10800.9748] <2> daemon_proxy_proto: Preparing to do daemon protocol for (10.102.185.76:13721 <- 10.102.185.76:55765) 12:16:01.561 [10800.9748] <2> logconnections: BPDBM ACCEPT FROM 10.102.185.76.55765 TO 10.102.185.76.13721 fd = 756 12:16:01.561 [10800.9748] <4> bpdbm: VERBOSE = 4 12:16:01.561 [10800.9748] <2> db_getrequest_vxss: Entering 12:16:01.561 [10800.9748] <2> image_db: Entering 12:16:01.561 [10800.9748] <2> db_FLISTunmarshal: fl_num_files = 0 12:16:01.561 [10800.9748] <2> db_logfilesrec: qtype: 79, protocol_version 14, backup_id: ?, client: soslmls07.internal.financecorp.biz, view_id: 0 12:16:01.561 [10800.9748] <2> db_logfilesrec: policy: ? client_type: 16 version: 0 12:16:01.561 [10800.9748] <2> db_logfilesrec: fl_peername soslmls07.internal.financecorp.biz 12:16:01.561 [10800.9748] <2> db_logfilesrec: date1: Tue Mar 19 14:05:28 2019 (1553000728) 12:16:01.561 [10800.9748] <2> db_logfilesrec: date2: Tue Mar 19 14:05:28 2019 (1553000728) 12:16:01.561 [10800.9748] <2> db_logfilesrec: recursion lvl 1 12:16:01.561 [10800.9748] <2> db_logfilesrec: path sep / 12:16:01.561 [10800.9748] <2> db_logfilesrec: swvers ? comp 0 encry 0 opt 0x80000402 opt_ext 0xc0 nfiles 0 12:16:01.561 [10800.9748] <2> db_logfilesrec: username unknown 12:16:01.561 [10800.9748] <2> db_logfilesrec: groupname unknown 12:16:01.561 [10800.9748] <2> image_db: Q_IMAGE_LIST_FILES 12:16:01.561 [10800.9748] <2> ImageQueryBase::init: Query retry <10>, delay <10> sec 12:16:01.561 [10800.9748] <2> DbmOdbcConnect::DbmOdbcConnect: ENTERING 12:16:01.561 [10800.9748] <2> read_legacy_touch_file: Found D:\Program Files\Veritas\NetBackupDB\data\vxdbms.conf; requested from (../vxdbms_conf.cpp.1509). 12:16:01.624 [10800.9748] <2> DbmOdbcConnect::initialize_odbc: Connecting with 12:16:01.624 [10800.9748] <2> DbmOdbcConnect::setIsolationLevel: Isolation level is already 1 12:16:01.624 [10800.9748] <2> DbmOdbcConnect::beginTransaction: (-1) transaction started 12:16:01.624 [10800.9748] <2> strip_mutually_exclusive_options: file list options = 0x80000402 12:16:01.624 [10800.9748] <2> has_wildcards: found '[' 12:16:01.624 [10800.9748] <2> ImageListFiles::executeQuery: pattern = /Microsoft Exchange Database Availability Groups/soslmls07/Microsoft Information Store/MBX13_Test/Database/Backup Mailbox1 [backupmailbox1]/ 12:16:01.624 [10800.9748] <2> ImageListFiles::executeQuery: wildcard_pattern 0 path_independent 0 pattern_strlen 140 12:16:01.624 [10800.9748] <2> ImageListFiles::executeQuery: original_pattern = /Microsoft Exchange Database Availability Groups/soslmls07/Microsoft Information Store/MBX13_Test/Database/Backup Mailbox1 \[backupmailbox1\]/ 12:16:01.624 [10800.9748] <2> ImageListFiles::executeQuery: device_num = 7274606 12:16:01.624 [10800.9748] <2> DbmOdbcConnect::set_connection_id: -1 -> 37852 12:16:01.624 [10800.9748] <2> queryMachineKey: (37852.1) Executing "SELECT machine.ParentKey FROM EMM_Main.EMM_MachineAlias mAlias WITH (READUNCOMMITTED) JOIN (SELECT MachineKey, ParentKey FROM EMM_Main.EMM_Machine WITH (READUNCOMMITTED)) AS machine on machine.MachineKey = mAlias.MachineKey WHERE mAlias.MachineNbuType IN (3, 5) AND UPPER(mAlias.MachineAliasName) = UPPER(?) FOR READ ONLY" Bindings <'soslbck02'> 12:16:01.624 [10800.9748] <2> queryMachineKey: (37852.1) key for soslbck02 MachineType 3 = '1000002' 12:16:01.624 [10800.9748] <2> getMasterServerKey: Got machine key 1000002 for master server soslbck02 12:16:01.624 [10800.9748] <2> DbmClient::get_client_info_for_alias: (37852.2) Executing "SELECT DBM_Main.DBM_Client.ClientMachineName FROM DBM_Main.DBM_Client, DBM_Main.DBM_ClientAlias WHERE DBM_Main.DBM_ClientAlias.MasterServerKey = ? AND ClientAliasName = LOWER(?) AND DBM_ClientAlias.ClientKey = DBM_Client.ClientKey FOR READ ONLY" Bindings <1000002, 'soslmls07.internal.financecorp.biz'> 12:16:01.624 [10800.9748] <2> DbmClient::get_client_info_for_alias: (37852.2) Fetched ClientInfo: ClientMachineName='soslmls07.internal.financecorp.biz' 12:16:01.624 [10800.9748] <2> ImageListFiles::executeQuery: start: 1553000728 end: 1553000728 12:16:01.624 [10800.9748] <2> ImageSearch::start: started 12:16:01.624 [10800.9748] <2> get_emm_machine_conf: (37852.3) Executing "SELECT Value FROM EMM_Main.EMM_MachineConfig WITH (READUNCOMMITTED) WHERE MachineKey = 1000002 AND Name = ? " Bindings <'LIST_FS_IMAGE_HEADERS'> 12:16:01.624 [10800.9748] <2> get_emm_machine_conf: (37852.3) LIST_FS_IMAGE_HEADERS = '0' 12:16:01.624 [10800.9748] <4> FileEnumerator: EMM_Config entry LIST_FS_IMAGE_HEADERS = NO - skipping flat files 12:16:01.624 [10800.9748] <2> DBEnumerator: (37852.4) Executing "SELECT DISTINCT DbmClient.ClientKey, DbmClient.ClientMachineName FROM DBM_Main.DBM_ClientAlias Alias JOIN DBM_Main.DBM_Client DbmClient ON Alias.ClientKey = DbmClient.ClientKey WHERE Alias.MasterServerKey = 1000002 AND Alias.ClientAliasName IN (LOWER(?)) ORDER BY DbmClient.ClientMachineName ASC" Bindings <'soslmls07.internal.financecorp.biz'> 12:16:01.624 [10800.9748] <2> DBEnumerator: (37852.4) ClientKey = '415' 12:16:01.624 [10800.9748] <2> DBEnumerator: constructed (0) 12:16:01.624 [10800.9748] <2> DBEnumerator::set_time_interval_for_client: short query (0) 12:16:01.624 [10800.9748] <2> DBEnumerator::move_next: (37852.4) Executing "SELECT... FROM DBM_Main.DBM_Image AS Image WITH(FASTFIRSTROW) LEFT OUTER JOIN DBM_Main.DBM_IMAGECOPY Copy ON Image.ImageKey = Copy.ImageKey LEFT OUTER JOIN DBM_Main.DBM_IMAGEFRAGMENT Frag ON Copy.ImageCopyKey = Frag.ImageCopyKey WHERE (Image.MasterServerKey = 1000002) AND (Image.ClientKey = ?) AND (Image.BackupTime BETWEEN ? AND ?) AND ((ScheduleType <> 3) AND (BackupTime <= Expiration OR Expiration = 2147483647) AND (NOT ((ImageType = 1) OR (ImageType = 4))) AND (ClientType = 16) AND (FilesFile <> '') AND (NOT (ImageType = 3)) AND (NOT (ImageType = 5)) AND (NOT (ImageAttribute >= 200)) AND (NOT ((TirInfo & 512) = 512))) AND ((Copy.ImageCopyKey IS NULL) OR (Copy.CopyNumber = Image.PrimaryCopy)) AND ((Frag.FragmentNumber = 1 OR Frag.FragmentNumber IS NULL) OR (Image.PfiType <> 0)) ORDER BY Image.BackupTime DESC, Image.ViewID ASC, Copy.CopyNumber ASC, Frag.FragmentNumbe 12:16:01.624 [10800.9748] <2> DBEnumerator::move_next: r ASC FOR READ ONLY" Bindings <415, 1553000728, 1553000728> 12:16:01.639 [10800.9748] <2> fetch_DBM_AllImageCopyFrag: Entering 12:16:01.639 [10800.9748] <2> fetch_DBM_AllImageCopyFrag: Success fetching (1) records from join of DBM_Image, DbmImageCopy, DbmImageFragment 12:16:01.639 [10800.9748] <2> fetch_DBM_AllImageCopyFrag: Entering 12:16:01.639 [10800.9748] <2> fetch_DBM_AllImageCopyFrag: No data found when fetching from Join of DBM_Image, DbmImageCopy, DbmImageFragment 12:16:01.639 [10800.9748] <2> load_next_combined_image: (.4) Image {IK=5174378, MSK=1000002, PBK=0, PIK=0, CIK=0, FBIK=0, CSSK=0, C='soslmls07.internal.financecorp.biz', CK=415, PN='prod-app-exchdb-grt-part4', BT=1553000728, VID=0, ST=0, CT=16, PV=14, ST=0, ET=0, ST=1553000628, KB=64859586, NF=1, NC=1, NS=0, IV=13, RL=3, C=0, E=0, FFC=0, MPX=0, TI=0, TE=0, PC=1, IT=0, ET=923, EXP=1555679128, NF=1527, ESI=0, RP=0, IFRFR=0, IDL=0, FSO=0, PBIT=0, BIFT=0, SN=1, BC=0, BS=0, JID=3021022, NR=0, RE=0, PFI=0, IA=16, C='root', SN='weekly', PC='', KP='', FF='prod-app-exchdb-grt-part4_1553000728_FULL.f', FFS=721978, SV='', OD='', EKB=0, VM=0, IS=0, RT=1553000728, LBT=1553000728, CCS=1, BMR=1, OMS='', OMSI='00000000-0000-0000-0000-000000000000', SSN='OSL-BF-Fw_1m_03_exch_weekly', SSS=2, SSI=0, TIP=1553001661, CID='', SSVN=2, RED=0, IFRT=0, IE=0, INQ=1, INSD=255, INS=1, HDE=0, HDD=0, VC=0, EC=0, IS=0, NC=0, IF=0, DH=, AT=, KBDT=0} 12:16:01.639 [10800.9748] <2> load_next_combined_image: (.4) Copy {ICK=6987887, IK=5174378, CN=1, SI=1, CT=0, ET=1555679128, MPX=0, RL=65539, CD=1553001651, DF=1, KMS='',TTK=1555679128, RES='stu_disk_soslbck001', CS=3, JID=3021022, ET=0, RC=0, LRT=1553001651, LDT='', SS=0, DDT=0, ELT=1555679128, IR=0, MP=0, H=0, VC=0, EC=0} 12:16:01.639 [10800.9748] <2> load_next_combined_image: (.4) Fragment {IFK=12504392, IK=5174378, ICK=6987887, CN=1, FN=1, RC=0, MID='@aaaao', MSK=1000034, SUT=0, SST=6, FS=1, FSZ=52428800000, FID='@aaaao', D=0, FN=0, BS=262144, O=0, MD=0, DWO=-1, FF=4, MD='1;PureDisk;soslbck001;dp_disk_soslbck001;PureDiskVolume;0', FCP=0, MSN=0, ME='', SCMH=''} 12:16:01.639 [10800.9748] <2> DBEnumerator::move_next: Got image soslmls07.internal.financecorp.biz_1553000728 12:16:01.639 [10800.9748] <2> get_DBM_ImageFragment: Entering 12:16:01.639 [10800.9748] <2> get_DBM_ImageFragment: (37852.5) Executing "SELECT...FROM DBM_Main.DBM_ImageFragment Frag WHERE ImageKey = ? AND CopyNumber = ? AND FragmentNumber = ? FOR READ ONLY" Bindings <5174378, 1, 1> 12:16:01.639 [10800.9748] <2> fetch_DBM_ImageFragments: (.5) Fragment {IFK=12504392, IK=5174378, ICK=6987887, CN=1, FN=1, RC=0, MID='@aaaao', MSK=1000034, SUT=0, SST=6, FS=1, FSZ=52428800000, FID='@aaaao', D=0, FN=0, BS=262144, O=0, MD=0, DWO=-1, FF=4, MD='1;PureDisk;soslbck001;dp_disk_soslbck001;PureDiskVolume;0', FCP=0, MSN=0, ME='', SCMH=''} 12:16:01.639 [10800.9748] <2> open_virtual_file_rec: Entering 12:16:01.655 [10800.9748] <2> strcmp_match_file_list: Entering 12:16:01.655 [10800.9748] <2> strcmp_match_file_list: pattern:/Microsoft Exchange Database Availability Groups/soslmls07/Microsoft Information Store/MBX13_Test/Database/Backup Mailbox1 [backupmailbox1]/ level:6 path_independent:0 fl_option:0x80000402 12:16:01.655 [10800.9748] <2> _query_client: fields: 1 catalogued_level: 1 data: 33216 root root 402687920 1553000719 1553000719 1553000719 1 5 1 PfI 2097154 128 144 C:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.soslmls07.internal.financecorp.biz_1553000628.1.0.MIS_SG1_Database.MetaData.txt 0 GRAN_CAT_DEPTH 1 12:16:01.655 [10800.9748] <4> _query_client: reset socks 12:16:01.655 [10800.9748] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:5160] Local [strong] check, using interface ANY 12:16:01.655 [10800.9748] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO soslbck02 10.102.185.76 bpjobd 12:16:01.655 [10800.9748] <2> job_connect: SO_KEEPALIVE set on socket 760 for client soslbck02 12:16:01.655 [10800.9748] <2> logconnections: BPJOBD CONNECT FROM 10.102.185.76.55766 TO 10.102.185.76.13723 fd = 760 12:16:01.655 [10800.9748] <2> job_authenticate_connection: VxSS authentication check for now... 12:16:01.655 [10800.9748] <2> job_connect: Connected to the host soslbck02 contype 53 jobid <0> socket <760> 12:16:01.655 [10800.9748] <2> job_connect: Connected on port 55766 12:16:01.655 [10800.9748] <4> JobdSockList::RegisterSocket: Register socket (760). 12:16:03.669 [10800.9748] <2> DbmMachine::getMachineName: (37852.6) Executing "SELECT MachinePrimaryName FROM EMM_Main.EMM_Machine WITH (READUNCOMMITTED) WHERE MachineKey = ? FOR READ ONLY" Bindings <1000034> 12:16:03.669 [10800.9748] <2> DbmMachine::getMachineName: (37852.6) MachineName = 'soslbck001' 12:16:03.669 [10800.9748] <2> DbmMachine::getMachineName: Fetched name soslbck001 for key 1000034 12:16:03.669 [10800.9748] <2> RequestInitialResources: started 12:16:03.669 [10800.9748] <2> hookCurlCallback: hooking curl callback into library:D:\Program Files\Veritas\NetBackup\bin\libnbcurl.dll(Orb.cpp:738) 12:16:03.669 [10800.9748] <2> init_nbcurllib: Performing nbopenssl_init() before initGlobalCurl() 12:16:03.669 [10800.9748] <4> NBClientCURL::initGlobalCurl: Performing NB_curl_global_init() ONLY ONCE per process 12:16:03.685 [10800.9748] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:5160] Local [strong] check, using interface ANY 12:16:03.685 [10800.9748] <2> packageInitialResourceRequest: started 12:16:03.685 [10800.9748] <2> packageInitialResourceRequest: retVal = 0 12:16:03.685 [10800.9748] <2> RequestInitialResources: setting up callback and calling requestResources 12:16:03.700 [10800.9748] <2> RequestInitialResources: BpxmiV->requestResourcesWithTimeout returns 0 12:16:03.778 [10800.9748] <2> resourcesAvailable: m_allocationSeq.length() == 1 12:16:03.778 [10800.9748] <2> resourcesAvailable: thisAllocation->mdar.Bsr.BptmStrings.length() == 7 12:16:03.778 [10800.9748] <2> resourcesAvailable: MEDIADB 1 1328227 @aaaao 14 *NULL* 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 12:16:03.778 [10800.9748] <2> resourcesAvailable: VOLUME 1 @aaaao 14 *NULL* *NULL* *NULL* *NULL* 0 0 0 0 0 {00000000-0000-0000-0000-000000000000} 0 12:16:03.778 [10800.9748] <2> resourcesAvailable: DRIVE 4 *NULL* 0 *NULL* *NULL* 0 0 0 0 0 0 0 0 *NULL* *NULL* *NULL* *NULL* 0 0 0 0 0 0 12:16:03.778 [10800.9748] <2> resourcesAvailable: STORAGE 1 *NULL* 0 0 0 0 0 0 soslbck001 soslbck001 *NULL* 12:16:03.778 [10800.9748] <2> resourcesAvailable: DISKGROUP 0 8 PureDisk 10 soslbck001 18 dp_disk_soslbck001 12:16:03.778 [10800.9748] <2> resourcesAvailable: DISKVOLUME 0 6 @aaaao 14 PureDiskVolume 0 12:16:03.778 [10800.9748] <2> resourcesAvailable: DISKMOUNTPOINT 0 14 PureDiskVolume 12:16:03.778 [10800.9748] <2> packageInitialResourceRequestResult: totalNumberOfAllocations == 1 12:16:03.778 [10800.9748] <2> packageInitialResourceRequestResult: m_allocationSeq.length=1 12:16:03.778 [10800.9748] <2> packageInitialResourceRequestResult: retVal = 0 12:16:03.778 [10800.9748] <2> RequestInitialResources: retVal = 0 emmStatus = 0 12:16:03.778 [10800.9748] <2> RequestInitialResources: returning 12:16:03.778 [10800.9748] <2> populateBptmOpaqueStrings: packaging bptm string [MEDIADB 1 1328227 @aaaao 14 *NULL* 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0], length 66 12:16:03.778 [10800.9748] <2> populateBptmOpaqueStrings: packaging bptm string [VOLUME 1 @aaaao 14 *NULL* *NULL* *NULL* *NULL* 0 0 0 0 0 {00000000-0000-0000-0000-000000000000} 0], length 97 12:16:03.778 [10800.9748] <2> populateBptmOpaqueStrings: packaging bptm string [DRIVE 4 *NULL* 0 *NULL* *NULL* 0 0 0 0 0 0 0 0 *NULL* *NULL* *NULL* *NULL* 0 0 0 0 0 0], length 87 12:16:03.778 [10800.9748] <2> populateBptmOpaqueStrings: packaging bptm string [STORAGE 1 *NULL* 0 0 0 0 0 0 soslbck001 soslbck001 *NULL*], length 57 12:16:03.778 [10800.9748] <2> populateBptmOpaqueStrings: packaging bptm string [DISKGROUP 0 8 PureDisk 10 soslbck001 18 dp_disk_soslbck001], length 58 12:16:03.778 [10800.9748] <2> populateBptmOpaqueStrings: packaging bptm string [DISKVOLUME 0 6 @aaaao 14 PureDiskVolume 0], length 41 12:16:03.778 [10800.9748] <2> populateBptmOpaqueStrings: packaging bptm string [DISKMOUNTPOINT 0 14 PureDiskVolume], length 34 12:16:03.778 [10800.9748] <2> jmcomm_PrintMediaResults: requestStatus is 0 12:16:03.778 [10800.9748] <2> jmcomm_PrintMediaResults: allocationId : {7AC93E06-7491-460F-9902-6CBB3D410831} 12:16:03.778 [10800.9748] <2> jmcomm_PrintMediaResults: results.mdr_AllocationKey : 1328227 12:16:03.778 [10800.9748] <2> jmcomm_PrintMediaResults: results.mdr_TpReqFileName : 12:16:03.778 [10800.9748] <2> jmcomm_PrintMediaResults: results.mdar_MediaServer: soslbck001 12:16:03.778 [10800.9748] <2> jmcomm_PrintMediaResults: results.mdar_MediaServerVersion : 810000 12:16:03.778 [10800.9748] <2> jmcomm_PrintMediaResults: results.mdar_MediaServerClusterName: 12:16:03.778 [10800.9748] <2> jmcomm_PrintMediaResults: results.mdar_MediaKey : 14 12:16:03.778 [10800.9748] <2> jmcomm_PrintMediaResults: results.mdar_MediaId : @aaaao 12:16:03.778 [10800.9748] <2> jmcomm_PrintMediaResults: results.mdar_DriveKey : 0 12:16:03.778 [10800.9748] <2> jmcomm_PrintMediaResults: results.mdar_DriveName : 12:16:03.778 [10800.9748] <2> jmcomm_PrintMediaResults: results.mdar_PrimaryPath : 12:16:03.778 [10800.9748] <2> jmcomm_PrintMediaResults: results.mdar_PoolName : 12:16:03.778 [10800.9748] <2> jmcomm_PrintMediaResults: results.mdar_NdmpControlHost : 12:16:03.778 [10800.9748] <2> jmcomm_PrintMediaResults: results.mdar_NdmpUserName : 12:16:03.778 [10800.9748] <2> jmcomm_PrintMediaResults: results.mdar_NdmpClientUserName : 12:16:03.778 [10800.9748] <2> jmcomm_PrintMediaResults: results.mdar_RetentionLevel : 0 12:16:03.778 [10800.9748] <2> jmcomm_PrintMediaResults: results.mdar_PolicyType : 2 12:16:03.778 [10800.9748] <2> jmcomm_PrintMediaResults: results.mdar_JobType : 2 12:16:03.778 [10800.9748] <2> jmcomm_PrintMediaResults: results.mdar_MasterServer : soslbck02 12:16:03.778 [10800.9748] <2> jmcomm_PrintMediaResults: Bptm message : [MEDIADB 1 1328227 @aaaao 14 *NULL* 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] 12:16:03.778 [10800.9748] <2> jmcomm_PrintMediaResults: Bptm message : [VOLUME 1 @aaaao 14 *NULL* *NULL* *NULL* *NULL* 0 0 0 0 0 {00000000-0000-0000-0000-000000000000} 0] 12:16:03.778 [10800.9748] <2> jmcomm_PrintMediaResults: Bptm message : [DRIVE 4 *NULL* 0 *NULL* *NULL* 0 0 0 0 0 0 0 0 *NULL* *NULL* *NULL* *NULL* 0 0 0 0 0 0 ] 12:16:03.778 [10800.9748] <2> jmcomm_PrintMediaResults: Bptm message : [STORAGE 1 *NULL* 0 0 0 0 0 0 soslbck001 soslbck001 *NULL*] 12:16:03.778 [10800.9748] <2> jmcomm_PrintMediaResults: Bptm message : [DISKGROUP 0 8 PureDisk 10 soslbck001 18 dp_disk_soslbck001] 12:16:03.778 [10800.9748] <2> jmcomm_PrintMediaResults: Bptm message : [DISKVOLUME 0 6 @aaaao 14 PureDiskVolume 0] 12:16:03.778 [10800.9748] <2> jmcomm_PrintMediaResults: Bptm message : [DISKMOUNTPOINT 0 14 PureDiskVolume] 12:16:03.778 [10800.9748] <2> dbm_start_nbfsd_and_open_nblbc_connection: Invoking nbfsd on media server soslbck001 12:16:03.778 [10800.9748] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:5193] using interface ANY 12:16:03.778 [10800.9748] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:5193] using interface ANY 12:16:03.794 [10800.9748] <2> vnet_pbxConnect_ex: pbxConnectExEx Succeeded 12:16:03.794 [10800.9748] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO soslbck001 10.47.54.6 bpcd VIA pbx 12:16:03.810 [10800.9748] <2> logconnections: PROXY CONNECT FROM 10.102.185.76.55774 TO 10.47.54.6.1556 fd = 1132 12:16:03.810 [10800.9748] <2> logconnections: BPCD CONNECT FROM 127.0.0.1.55776 TO 127.0.0.1.55777 fd = 1132 12:16:03.810 [10800.9748] <2> vnet_connect_to_vnetd_bpcd: js_bpcd_info: 0000000001E2C060 12:16:03.810 [10800.9748] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:5193] using interface ANY 12:16:03.810 [10800.9748] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:5193] using interface ANY 12:16:03.825 [10800.9748] <2> vnet_pbxConnect_ex: pbxConnectExEx Succeeded 12:16:03.872 [10800.9748] <8> do_pbx_service: [vnet_connect.c:2579] via PBX VNETD CONNECT FROM 10.102.185.76.55778 TO 10.47.54.6.1556 fd = 1144 12:16:03.872 [10800.9748] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO soslbck001 10.47.54.6 vnetd VIA pbx 12:16:03.872 [10800.9748] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:458] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa 12:16:03.888 [10800.9748] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:483] ipc_string /usr/openv/var/tmp/vnet-27752553253364124176000003128-DxZ6q2 12:16:03.888 [10800.9748] <2> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:494] hash_str1 13148b462567c103a59332b93525b2de 12:16:04.012 [10800.9748] <2> bpcr_get_version_rqst: bpcd version: 08100000 12:16:04.075 [10800.9748] <2> bpcr_get_version_rqst: bpcd version: 08100000 12:16:05.229 [10800.9748] <2> dbm_open_client_connection: host = soslmls07.internal.financecorp.biz 12:16:05.229 [10800.9748] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:5193] using interface ANY 12:16:05.229 [10800.9748] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:5193] using interface ANY 12:16:05.229 [10800.9748] <2> vnet_pbxConnect_ex: pbxConnectExEx Succeeded 12:16:05.229 [10800.9748] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO soslmls07.internal.financecorp.biz 10.102.184.163 bpcd VIA pbx 12:16:05.338 [10800.9748] <2> logconnections: PROXY CONNECT FROM 10.102.185.76.55782 TO 10.102.184.163.1556 fd = 1128 12:16:05.338 [10800.9748] <2> logconnections: BPCD CONNECT FROM 127.0.0.1.55784 TO 127.0.0.1.55785 fd = 1128 12:16:05.338 [10800.9748] <2> vnet_connect_to_vnetd_bpcd: js_bpcd_info: 0000000001E2B760 12:16:05.338 [10800.9748] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:5193] using interface ANY 12:16:05.338 [10800.9748] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:5193] using interface ANY 12:16:05.354 [10800.9748] <2> vnet_pbxConnect_ex: pbxConnectExEx Succeeded 12:16:05.448 [10800.9748] <8> do_pbx_service: [vnet_connect.c:2579] via PBX VNETD CONNECT FROM 10.102.185.76.55786 TO 10.102.184.163.1556 fd = 748 12:16:05.448 [10800.9748] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO soslmls07.internal.financecorp.biz 10.102.184.163 vnetd VIA pbx 12:16:05.448 [10800.9748] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:458] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa 12:16:05.448 [10800.9748] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:483] ipc_string 14390 12:16:05.479 [10800.9748] <2> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:494] hash_str1 0b23f14ce51a06f1523be334db340093 12:16:05.526 [10800.9748] <2> bpcr_get_version_rqst: bpcd version: 08100000 12:16:05.526 [10800.9748] <2> dbm_open_client_connection: client socket from bpcr = 1128 12:16:05.526 [10800.9748] <2> dbm_query_client: mount point = ignore 12:16:05.526 [10800.9748] <2> dbm_query_client: mount device = ignore 12:16:05.526 [10800.9748] <2> dbm_query_client: frag_id = ignore 12:16:05.526 [10800.9748] <2> dbm_query_client: fs_type = ignore 12:16:05.526 [10800.9748] <2> dbm_query_client: search_pattern = /Microsoft Exchange Database Availability Groups/soslmls07/Microsoft Information Store/MBX13_Test/Database 12:16:05.526 [10800.9748] <2> dbm_query_client: path = /Microsoft Exchange Database Availability Groups/soslmls07/Microsoft Information Store/MBX13_Test/Database/Backup Mailbox1 [backupmailbox1]/ 12:16:05.526 [10800.9748] <2> dbm_query_client: depth = 1 12:16:05.526 [10800.9748] <2> dbm_query_client: dirs_only = 0 12:16:05.526 [10800.9748] <2> dbm_query_client: copy_num = 1 12:16:05.526 [10800.9748] <2> dbm_query_client: browsing depth 1 12:16:05.541 [10800.9748] <2> bpcr_get_version_rqst: bpcd version: 08100000 12:16:05.572 [10800.9748] <2> bpcr_get_fileinfo_rqst: bpcr read status 0 from bpcd 12:16:05.572 [10800.9748] <2> bpcr_get_string_pentry: Incorrect value found in the config file 12:17:08.661 [10800.9748] <2> bpcr_get_string_pentry: Error detected: ERROR 13 12:17:08.661 [10800.9748] <2> ImageListFiles::executeQuery: selected 0 total_selected 0 12:17:08.661 [10800.9748] <2> ~ExtraObjRecordIndexCache: 0 hits, 1 misses 12:17:08.661 [10800.9748] <2> ~IndexRefCache: ExtraObject cache: 0 in-use hits, 0 unused hits, 2 misses 12:17:08.661 [10800.9748] <2> ~IndexRefCache: NDMP cache: 0 in-use hits, 0 unused hits, 0 misses 12:17:08.661 [10800.9748] <2> ~IndexRefCache: File cache: 0 in-use hits, 3 unused hits, 3 misses 12:17:08.661 [10800.9748] <2> ~IndexRefCache: Directory cache: 0 in-use hits, 9 unused hits, 7 misses 12:17:08.661 [10800.9748] <2> ~IndexRefCache: Record cache: 3 in-use hits, 1 unused hits, 6 misses 12:17:08.661 [10800.9748] <4> JobdSockList::UnregisterSocket: Unregister socket (760). 12:17:08.661 [10800.9748] <2> job_monitoring_exex: ACK disconnect 12:17:08.661 [10800.9748] <2> job_disconnect: Disconnected 12:17:08.661 [10800.9748] <2> dbm_close_client_connection: sending disconnect request to bpcd 12:17:08.677 [10800.9748] <2> bpcr_disconnect_rqst: bpcr received the following message: 12:17:08.677 [10800.9748] <2> bpcr_disconnect_rqst: bpcr received the following message: 12:17:08.677 [10800.9748] <2> dbm_cleanup_client_connection: Entering 12:17:08.677 [10800.9748] <2> jmcomm_ReleaseAllResourcesForJob: releaseAllResources returns [0] 12:17:08.677 [10800.9748] <2> jmcomm_ReleaseAllResourcesForJob: returning 12:17:08.677 [10800.9748] <2> DBEnumerator::move_next: Fetched 0 rows this query 12:17:08.677 [10800.9748] <2> DBEnumerator::move_next: Reached the end of the client list 12:17:08.677 [10800.9748] <2> DbmOdbcConnect::commitTransaction: (37852) success - view count 0 12:17:08.677 [10800.9748] <2> DbmOdbcConnect::~DbmOdbcConnect: (37852) Exiting 12:17:08.693 [10800.9748] <2> db_sendSTATUS: Status:220 12:17:08.693 [10800.9748] <2> process_request: request complete: exit status 220 database system error; query type: 79