12:18:36.556 [9056.2976] <2> bpdbm: Maximum stdio handles: 2048 12:18:36.556 [9056.2976] <2> daemon_proxy_proto: Preparing to do daemon protocol for (10.102.185.76:13721 <- 10.102.185.76:55981) 12:18:36.556 [9056.2976] <2> logconnections: BPDBM ACCEPT FROM 10.102.185.76.55981 TO 10.102.185.76.13721 fd = 864 12:18:36.556 [9056.2976] <4> bpdbm: VERBOSE = 4 12:18:36.556 [9056.2976] <2> db_getrequest_vxss: Entering 12:18:36.556 [9056.2976] <2> image_db: Entering 12:18:36.572 [9056.2976] <2> db_FLISTunmarshal: fl_num_files = 0 12:18:36.572 [9056.2976] <2> db_logfilesrec: qtype: 79, protocol_version 14, backup_id: ?, client: soslmlc01.internal.financecorp.biz, view_id: 0 12:18:36.572 [9056.2976] <2> db_logfilesrec: policy: ? client_type: 16 version: 0 12:18:36.572 [9056.2976] <2> db_logfilesrec: fl_peername soslmls07.internal.financecorp.biz 12:18:36.572 [9056.2976] <2> db_logfilesrec: date1: Fri Feb 08 22:02:01 2019 (1549659721) 12:18:36.572 [9056.2976] <2> db_logfilesrec: date2: Fri Feb 08 22:08:24 2019 (1549660104) 12:18:36.572 [9056.2976] <2> db_logfilesrec: recursion lvl 1 12:18:36.572 [9056.2976] <2> db_logfilesrec: path sep / 12:18:36.572 [9056.2976] <2> db_logfilesrec: swvers ? comp 0 encry 0 opt 0x80000402 opt_ext 0xc0 nfiles 0 12:18:36.572 [9056.2976] <2> db_logfilesrec: username unknown 12:18:36.572 [9056.2976] <2> db_logfilesrec: groupname unknown 12:18:36.572 [9056.2976] <2> image_db: Q_IMAGE_LIST_FILES 12:18:36.572 [9056.2976] <2> ImageQueryBase::init: Query retry <10>, delay <10> sec 12:18:36.572 [9056.2976] <2> DbmOdbcConnect::DbmOdbcConnect: ENTERING 12:18:36.572 [9056.2976] <2> read_legacy_touch_file: Found D:\Program Files\Veritas\NetBackupDB\data\vxdbms.conf; requested from (../vxdbms_conf.cpp.1509). 12:18:36.618 [9056.2976] <2> DbmOdbcConnect::initialize_odbc: Connecting with 12:18:36.618 [9056.2976] <2> DbmOdbcConnect::setIsolationLevel: Isolation level is already 1 12:18:36.618 [9056.2976] <2> DbmOdbcConnect::beginTransaction: (-1) transaction started 12:18:36.618 [9056.2976] <2> strip_mutually_exclusive_options: file list options = 0x80000402 12:18:36.618 [9056.2976] <2> has_wildcards: found '[' 12:18:36.618 [9056.2976] <2> ImageListFiles::executeQuery: pattern = /Microsoft Exchange Database Availability Groups/soslmlc01/Microsoft Information Store/MBX13_NO/Database/Marian Agh [K404186]/ 12:18:36.618 [9056.2976] <2> ImageListFiles::executeQuery: wildcard_pattern 0 path_independent 0 pattern_strlen 126 12:18:36.618 [9056.2976] <2> ImageListFiles::executeQuery: original_pattern = /Microsoft Exchange Database Availability Groups/soslmlc01/Microsoft Information Store/MBX13_NO/Database/Marian Agh \[K404186\]/ 12:18:36.618 [9056.2976] <2> ImageListFiles::executeQuery: device_num = 0 12:18:36.618 [9056.2976] <2> DbmOdbcConnect::set_connection_id: -1 -> 37867 12:18:36.618 [9056.2976] <2> queryMachineKey: (37867.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:18:36.618 [9056.2976] <2> queryMachineKey: (37867.1) key for soslbck02 MachineType 3 = '1000002' 12:18:36.618 [9056.2976] <2> getMasterServerKey: Got machine key 1000002 for master server soslbck02 12:18:36.618 [9056.2976] <2> DbmClient::get_client_info_for_alias: (37867.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, 'soslmlc01.internal.financecorp.biz'> 12:18:36.618 [9056.2976] <2> DbmClient::get_client_info_for_alias: (37867.2) Fetched ClientInfo: ClientMachineName='soslmlc01.internal.financecorp.biz' 12:18:36.618 [9056.2976] <2> ImageListFiles::executeQuery: start: 1549659721 end: 1549660104 12:18:36.618 [9056.2976] <2> ImageSearch::start: started 12:18:36.618 [9056.2976] <2> get_emm_machine_conf: (37867.3) Executing "SELECT Value FROM EMM_Main.EMM_MachineConfig WITH (READUNCOMMITTED) WHERE MachineKey = 1000002 AND Name = ? " Bindings <'LIST_FS_IMAGE_HEADERS'> 12:18:36.618 [9056.2976] <2> get_emm_machine_conf: (37867.3) LIST_FS_IMAGE_HEADERS = '0' 12:18:36.618 [9056.2976] <4> FileEnumerator: EMM_Config entry LIST_FS_IMAGE_HEADERS = NO - skipping flat files 12:18:36.618 [9056.2976] <2> DBEnumerator: (37867.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 <'soslmlc01.internal.financecorp.biz'> 12:18:36.618 [9056.2976] <2> DBEnumerator: (37867.4) ClientKey = '369' 12:18:36.618 [9056.2976] <2> DBEnumerator: constructed (0) 12:18:36.618 [9056.2976] <2> DBEnumerator::set_time_interval_for_client: short query (383) 12:18:36.618 [9056.2976] <2> DBEnumerator::move_next: (37867.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:18:36.618 [9056.2976] <2> DBEnumerator::move_next: r ASC FOR READ ONLY" Bindings <369, 1549659721, 1549660104> 12:18:36.634 [9056.2976] <2> fetch_DBM_AllImageCopyFrag: Entering 12:18:36.634 [9056.2976] <2> fetch_DBM_AllImageCopyFrag: Success fetching (10) records from join of DBM_Image, DbmImageCopy, DbmImageFragment 12:18:36.634 [9056.2976] <2> load_next_combined_image: (.4) Image {IK=4970212, MSK=1000002, PBK=0, PIK=0, CIK=0, FBIK=0, CSSK=0, C='soslmlc01.internal.financecorp.biz', CK=369, PN='prod-app-exchdb-grt_part1', BT=1549660104, VID=0, ST=0, CT=16, PV=14, ST=0, ET=0, ST=1549659632, KB=63398081, 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=1547, EXP=1552338504, NF=236, ESI=0, RP=0, IFRFR=0, IDL=0, FSO=0, PBIT=0, BIFT=0, SN=6, BC=0, BS=0, JID=2953845, NR=0, RE=0, PFI=0, IA=16, C='root', SN='weekly', PC='', KP='', FF='prod-app-exchdb-grt_part1_1549660104_FULL.f', FFS=105309, SV='', OD='', EKB=1263199316, VM=0, IS=0, RT=1549660104, LBT=1549660104, CCS=1, BMR=1, OMS='', OMSI='00000000-0000-0000-0000-000000000000', SSN='OSL-BF-Fw_1m_03_exch_weekly', SSS=2, SSI=0, TIP=1549661690, 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:18:36.634 [9056.2976] <2> load_next_combined_image: (.4) Copy {ICK=6743752, IK=4970212, CN=1, SI=1, CT=0, ET=1552338504, MPX=0, RL=65539, CD=1549661651, DF=1, KMS='',TTK=1552338504, RES='stu_disk_soslbck001', CS=3, JID=2953845, ET=0, RC=0, LRT=1549661651, LDT='', SS=0, DDT=0, ELT=1552338504, IR=0, MP=0, H=0, VC=0, EC=0} 12:18:36.634 [9056.2976] <2> load_next_combined_image: (.4) Fragment {IFK=12037646, IK=4970212, ICK=6743752, 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:18:36.634 [9056.2976] <2> DBEnumerator::move_next: Got image soslmlc01.internal.financecorp.biz_1549660104 12:18:36.634 [9056.2976] <2> get_DBM_ImageFragment: Entering 12:18:36.634 [9056.2976] <2> get_DBM_ImageFragment: (37867.5) Executing "SELECT...FROM DBM_Main.DBM_ImageFragment Frag WHERE ImageKey = ? AND CopyNumber = ? AND FragmentNumber = ? FOR READ ONLY" Bindings <4970212, 1, 1> 12:18:36.634 [9056.2976] <2> fetch_DBM_ImageFragments: (.5) Fragment {IFK=12037646, IK=4970212, ICK=6743752, 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:18:36.634 [9056.2976] <2> open_virtual_file_rec: Entering 12:18:36.634 [9056.2976] <2> strcmp_match_file_list: Entering 12:18:36.634 [9056.2976] <2> strcmp_match_file_list: pattern:/Microsoft Exchange Database Availability Groups/soslmlc01/Microsoft Information Store/MBX13_NO/Database/Marian Agh [K404186]/ level:6 path_independent:0 fl_option:0x80000402 12:18:36.634 [9056.2976] <2> ImageListFiles::executeQuery: selected 0 total_selected 0 12:18:36.634 [9056.2976] <2> ~ExtraObjRecordIndexCache: 0 hits, 0 misses 12:18:36.634 [9056.2976] <2> ~IndexRefCache: ExtraObject cache: 0 in-use hits, 0 unused hits, 0 misses 12:18:36.634 [9056.2976] <2> ~IndexRefCache: NDMP cache: 0 in-use hits, 0 unused hits, 0 misses 12:18:36.634 [9056.2976] <2> ~IndexRefCache: File cache: 0 in-use hits, 0 unused hits, 1 misses 12:18:36.634 [9056.2976] <2> ~IndexRefCache: Directory cache: 0 in-use hits, 3 unused hits, 5 misses 12:18:36.634 [9056.2976] <2> ~IndexRefCache: Record cache: 0 in-use hits, 0 unused hits, 0 misses 12:18:36.634 [9056.2976] <2> load_next_combined_image: (.4) Image {IK=4970211, MSK=1000002, PBK=0, PIK=0, CIK=0, FBIK=0, CSSK=0, C='soslmlc01.internal.financecorp.biz', CK=369, PN='prod-app-exchdb-grt_part1', BT=1549660103, VID=0, ST=0, CT=16, PV=14, ST=0, ET=0, ST=1549659632, KB=10926132, 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=407, EXP=1552338503, NF=230, ESI=0, RP=0, IFRFR=0, IDL=0, FSO=0, PBIT=0, BIFT=0, SN=5, BC=0, BS=0, JID=2953844, NR=0, RE=0, PFI=0, IA=16, C='root', SN='weekly', PC='', KP='', FF='prod-app-exchdb-grt_part1_1549660103_FULL.f', FFS=91623, SV='', OD='', EKB=13583985, VM=0, IS=0, RT=1549660103, LBT=1549660103, CCS=1, BMR=1, OMS='', OMSI='00000000-0000-0000-0000-000000000000', SSN='OSL-BF-Fw_1m_03_exch_weekly', SSS=2, SSI=0, TIP=1549660787, 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:18:36.634 [9056.2976] <2> load_next_combined_image: (.4) Copy {ICK=6743751, IK=4970211, CN=1, SI=1, CT=0, ET=1552338503, MPX=0, RL=65539, CD=1549660510, DF=1, KMS='',TTK=1552338503, RES='stu_disk_soslbck001', CS=3, JID=2953844, ET=0, RC=0, LRT=1549660510, LDT='', SS=0, DDT=0, ELT=1552338503, IR=0, MP=0, H=0, VC=0, EC=0} 12:18:36.650 [9056.2976] <2> load_next_combined_image: (.4) Fragment {IFK=12037643, IK=4970211, ICK=6743751, CN=1, FN=1, RC=0, MID='@aaaao', MSK=1000034, SUT=0, SST=6, FS=1, FSZ=11180441600, 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=1, MSN=0, ME='', SCMH=''} 12:18:36.650 [9056.2976] <2> DBEnumerator::move_next: Got image soslmlc01.internal.financecorp.biz_1549660103 12:18:36.650 [9056.2976] <2> get_DBM_ImageFragment: Entering 12:18:36.650 [9056.2976] <2> get_DBM_ImageFragment: (37867.6) Executing "SELECT...FROM DBM_Main.DBM_ImageFragment Frag WHERE ImageKey = ? AND CopyNumber = ? AND FragmentNumber = ? FOR READ ONLY" Bindings <4970211, 1, 1> 12:18:36.650 [9056.2976] <2> fetch_DBM_ImageFragments: (.6) Fragment {IFK=12037643, IK=4970211, ICK=6743751, CN=1, FN=1, RC=0, MID='@aaaao', MSK=1000034, SUT=0, SST=6, FS=1, FSZ=11180441600, 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=1, MSN=0, ME='', SCMH=''} 12:18:36.650 [9056.2976] <2> open_virtual_file_rec: Entering 12:18:36.650 [9056.2976] <2> strcmp_match_file_list: Entering 12:18:36.650 [9056.2976] <2> strcmp_match_file_list: pattern:/Microsoft Exchange Database Availability Groups/soslmlc01/Microsoft Information Store/MBX13_NO/Database/Marian Agh [K404186]/ level:6 path_independent:0 fl_option:0x80000402 12:18:36.650 [9056.2976] <2> ImageListFiles::executeQuery: selected 0 total_selected 0 12:18:36.650 [9056.2976] <2> ~ExtraObjRecordIndexCache: 0 hits, 0 misses 12:18:36.650 [9056.2976] <2> ~IndexRefCache: ExtraObject cache: 0 in-use hits, 0 unused hits, 0 misses 12:18:36.650 [9056.2976] <2> ~IndexRefCache: NDMP cache: 0 in-use hits, 0 unused hits, 0 misses 12:18:36.650 [9056.2976] <2> ~IndexRefCache: File cache: 0 in-use hits, 0 unused hits, 1 misses 12:18:36.650 [9056.2976] <2> ~IndexRefCache: Directory cache: 0 in-use hits, 3 unused hits, 5 misses 12:18:36.650 [9056.2976] <2> ~IndexRefCache: Record cache: 0 in-use hits, 0 unused hits, 0 misses 12:18:36.650 [9056.2976] <2> load_next_combined_image: (.4) Image {IK=4970209, MSK=1000002, PBK=0, PIK=0, CIK=0, FBIK=0, CSSK=0, C='soslmlc01.internal.financecorp.biz', CK=369, PN='prod-app-exchdb-grt_part1', BT=1549660102, VID=0, ST=0, CT=16, PV=14, ST=0, ET=0, ST=1549659632, KB=1923346114, 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=48653, EXP=1552338502, NF=22191, ESI=0, RP=0, IFRFR=0, IDL=0, FSO=0, PBIT=0, BIFT=0, SN=4, BC=0, BS=0, JID=2953843, NR=0, RE=0, PFI=0, IA=16, C='root', SN='weekly', PC='', KP='', FF='prod-app-exchdb-grt_part1_1549660102_FULL.f', FFS=10235515, SV='', OD='', EKB=2331592879, VM=0, IS=0, RT=1549660102, LBT=1549660102, CCS=1, BMR=1, OMS='', OMSI='00000000-0000-0000-0000-000000000000', SSN='OSL-BF-Fw_1m_03_exch_weekly', SSS=2, SSI=0, TIP=1549708900, 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:18:36.650 [9056.2976] <2> load_next_combined_image: (.4) Copy {ICK=6743750, IK=4970209, CN=1, SI=1, CT=0, ET=1552338502, MPX=0, RL=65539, CD=1549708755, DF=1, KMS='',TTK=1552338502, RES='stu_disk_soslbck001', CS=3, JID=2953843, ET=0, RC=0, LRT=1549708755, LDT='', SS=0, DDT=0, ELT=1552338502, IR=0, MP=0, H=0, VC=0, EC=0} 12:18:36.650 [9056.2976] <2> load_next_combined_image: (.4) Fragment {IFK=12037642, IK=4970209, ICK=6743750, 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:18:36.650 [9056.2976] <2> DBEnumerator::move_next: Got image soslmlc01.internal.financecorp.biz_1549660102 12:18:36.650 [9056.2976] <2> get_DBM_ImageFragment: Entering 12:18:36.650 [9056.2976] <2> get_DBM_ImageFragment: (37867.7) Executing "SELECT...FROM DBM_Main.DBM_ImageFragment Frag WHERE ImageKey = ? AND CopyNumber = ? AND FragmentNumber = ? FOR READ ONLY" Bindings <4970209, 1, 1> 12:18:36.650 [9056.2976] <2> fetch_DBM_ImageFragments: (.7) Fragment {IFK=12037642, IK=4970209, ICK=6743750, 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:18:36.650 [9056.2976] <2> open_virtual_file_rec: Entering 12:18:36.650 [9056.2976] <2> strcmp_match_file_list: Entering 12:18:36.650 [9056.2976] <2> strcmp_match_file_list: pattern:/Microsoft Exchange Database Availability Groups/soslmlc01/Microsoft Information Store/MBX13_NO/Database/Marian Agh [K404186]/ level:6 path_independent:0 fl_option:0x80000402 12:18:36.650 [9056.2976] <2> _query_client: fields: 1 catalogued_level: 1 data: 33216 root root 404228144 1549659976 1549659976 1549659976 1 5 1 PfI 2097154 128 144 C:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.soslmlc0 12:18:36.650 [9056.2976] <4> _query_client: reset socks 12:18:36.665 [9056.2976] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:5160] Local [strong] check, using interface ANY 12:18:36.681 [9056.2976] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO soslbck02 10.102.185.76 bpjobd 12:18:36.681 [9056.2976] <2> job_connect: SO_KEEPALIVE set on socket 752 for client soslbck02 12:18:36.681 [9056.2976] <2> logconnections: BPJOBD CONNECT FROM 10.102.185.76.55982 TO 10.102.185.76.13723 fd = 752 12:18:36.681 [9056.2976] <2> job_authenticate_connection: VxSS authentication check for now... 12:18:36.681 [9056.2976] <2> job_connect: Connected to the host soslbck02 contype 53 jobid <0> socket <752> 12:18:36.681 [9056.2976] <2> job_connect: Connected on port 55982 12:18:36.681 [9056.2976] <4> JobdSockList::RegisterSocket: Register socket (752). 12:18:37.882 [9056.2976] <2> DbmMachine::getMachineName: (37867.8) Executing "SELECT MachinePrimaryName FROM EMM_Main.EMM_Machine WITH (READUNCOMMITTED) WHERE MachineKey = ? FOR READ ONLY" Bindings <1000034> 12:18:37.882 [9056.2976] <2> DbmMachine::getMachineName: (37867.8) MachineName = 'soslbck001' 12:18:37.882 [9056.2976] <2> DbmMachine::getMachineName: Fetched name soslbck001 for key 1000034 12:18:37.882 [9056.2976] <2> RequestInitialResources: started 12:18:37.898 [9056.2976] <2> hookCurlCallback: hooking curl callback into library:D:\Program Files\Veritas\NetBackup\bin\libnbcurl.dll(Orb.cpp:738) 12:18:37.898 [9056.2976] <2> init_nbcurllib: Performing nbopenssl_init() before initGlobalCurl() 12:18:37.898 [9056.2976] <4> NBClientCURL::initGlobalCurl: Performing NB_curl_global_init() ONLY ONCE per process 12:18:37.898 [9056.2976] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:5160] Local [strong] check, using interface ANY 12:18:37.913 [9056.2976] <2> packageInitialResourceRequest: started 12:18:37.913 [9056.2976] <2> packageInitialResourceRequest: retVal = 0 12:18:37.913 [9056.2976] <2> RequestInitialResources: setting up callback and calling requestResources 12:18:37.913 [9056.2976] <2> RequestInitialResources: BpxmiV->requestResourcesWithTimeout returns 0 12:18:38.022 [9056.2976] <2> resourcesAvailable: m_allocationSeq.length() == 1 12:18:38.022 [9056.2976] <2> resourcesAvailable: thisAllocation->mdar.Bsr.BptmStrings.length() == 7 12:18:38.022 [9056.2976] <2> resourcesAvailable: MEDIADB 1 1328229 @aaaao 14 *NULL* 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 12:18:38.022 [9056.2976] <2> resourcesAvailable: VOLUME 1 @aaaao 14 *NULL* *NULL* *NULL* *NULL* 0 0 0 0 0 {00000000-0000-0000-0000-000000000000} 0 12:18:38.022 [9056.2976] <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:18:38.022 [9056.2976] <2> resourcesAvailable: STORAGE 1 *NULL* 0 0 0 0 0 0 soslbck001 soslbck001 *NULL* 12:18:38.022 [9056.2976] <2> resourcesAvailable: DISKGROUP 0 8 PureDisk 10 soslbck001 18 dp_disk_soslbck001 12:18:38.022 [9056.2976] <2> resourcesAvailable: DISKVOLUME 0 6 @aaaao 14 PureDiskVolume 0 12:18:38.022 [9056.2976] <2> resourcesAvailable: DISKMOUNTPOINT 0 14 PureDiskVolume 12:18:38.022 [9056.2976] <2> packageInitialResourceRequestResult: totalNumberOfAllocations == 1 12:18:38.022 [9056.2976] <2> packageInitialResourceRequestResult: m_allocationSeq.length=1 12:18:38.022 [9056.2976] <2> packageInitialResourceRequestResult: retVal = 0 12:18:38.022 [9056.2976] <2> RequestInitialResources: retVal = 0 emmStatus = 0 12:18:38.022 [9056.2976] <2> RequestInitialResources: returning 12:18:38.022 [9056.2976] <2> populateBptmOpaqueStrings: packaging bptm string [MEDIADB 1 1328229 @aaaao 14 *NULL* 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0], length 66 12:18:38.022 [9056.2976] <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:18:38.022 [9056.2976] <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:18:38.022 [9056.2976] <2> populateBptmOpaqueStrings: packaging bptm string [STORAGE 1 *NULL* 0 0 0 0 0 0 soslbck001 soslbck001 *NULL*], length 57 12:18:38.022 [9056.2976] <2> populateBptmOpaqueStrings: packaging bptm string [DISKGROUP 0 8 PureDisk 10 soslbck001 18 dp_disk_soslbck001], length 58 12:18:38.022 [9056.2976] <2> populateBptmOpaqueStrings: packaging bptm string [DISKVOLUME 0 6 @aaaao 14 PureDiskVolume 0], length 41 12:18:38.022 [9056.2976] <2> populateBptmOpaqueStrings: packaging bptm string [DISKMOUNTPOINT 0 14 PureDiskVolume], length 34 12:18:38.022 [9056.2976] <2> jmcomm_PrintMediaResults: requestStatus is 0 12:18:38.022 [9056.2976] <2> jmcomm_PrintMediaResults: allocationId : {12197675-28CF-4104-B8B4-1D7EB2B6B362} 12:18:38.022 [9056.2976] <2> jmcomm_PrintMediaResults: results.mdr_AllocationKey : 1328229 12:18:38.022 [9056.2976] <2> jmcomm_PrintMediaResults: results.mdr_TpReqFileName : 12:18:38.022 [9056.2976] <2> jmcomm_PrintMediaResults: results.mdar_MediaServer: soslbck001 12:18:38.022 [9056.2976] <2> jmcomm_PrintMediaResults: results.mdar_MediaServerVersion : 810000 12:18:38.022 [9056.2976] <2> jmcomm_PrintMediaResults: results.mdar_MediaServerClusterName: 12:18:38.022 [9056.2976] <2> jmcomm_PrintMediaResults: results.mdar_MediaKey : 14 12:18:38.022 [9056.2976] <2> jmcomm_PrintMediaResults: results.mdar_MediaId : @aaaao 12:18:38.022 [9056.2976] <2> jmcomm_PrintMediaResults: results.mdar_DriveKey : 0 12:18:38.022 [9056.2976] <2> jmcomm_PrintMediaResults: results.mdar_DriveName : 12:18:38.022 [9056.2976] <2> jmcomm_PrintMediaResults: results.mdar_PrimaryPath : 12:18:38.022 [9056.2976] <2> jmcomm_PrintMediaResults: results.mdar_PoolName : 12:18:38.022 [9056.2976] <2> jmcomm_PrintMediaResults: results.mdar_NdmpControlHost : 12:18:38.022 [9056.2976] <2> jmcomm_PrintMediaResults: results.mdar_NdmpUserName : 12:18:38.022 [9056.2976] <2> jmcomm_PrintMediaResults: results.mdar_NdmpClientUserName : 12:18:38.022 [9056.2976] <2> jmcomm_PrintMediaResults: results.mdar_RetentionLevel : 0 12:18:38.022 [9056.2976] <2> jmcomm_PrintMediaResults: results.mdar_PolicyType : 2 12:18:38.022 [9056.2976] <2> jmcomm_PrintMediaResults: results.mdar_JobType : 2 12:18:38.022 [9056.2976] <2> jmcomm_PrintMediaResults: results.mdar_MasterServer : soslbck02 12:18:38.022 [9056.2976] <2> jmcomm_PrintMediaResults: Bptm message : [MEDIADB 1 1328229 @aaaao 14 *NULL* 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] 12:18:38.022 [9056.2976] <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:18:38.022 [9056.2976] <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:18:38.022 [9056.2976] <2> jmcomm_PrintMediaResults: Bptm message : [STORAGE 1 *NULL* 0 0 0 0 0 0 soslbck001 soslbck001 *NULL*] 12:18:38.022 [9056.2976] <2> jmcomm_PrintMediaResults: Bptm message : [DISKGROUP 0 8 PureDisk 10 soslbck001 18 dp_disk_soslbck001] 12:18:38.022 [9056.2976] <2> jmcomm_PrintMediaResults: Bptm message : [DISKVOLUME 0 6 @aaaao 14 PureDiskVolume 0] 12:18:38.022 [9056.2976] <2> jmcomm_PrintMediaResults: Bptm message : [DISKMOUNTPOINT 0 14 PureDiskVolume] 12:18:38.022 [9056.2976] <2> dbm_start_nbfsd_and_open_nblbc_connection: Invoking nbfsd on media server soslbck001 12:18:38.022 [9056.2976] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:5193] using interface ANY 12:18:38.022 [9056.2976] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:5193] using interface ANY 12:18:38.038 [9056.2976] <2> vnet_pbxConnect_ex: pbxConnectExEx Succeeded 12:18:38.038 [9056.2976] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO soslbck001 10.47.54.6 bpcd VIA pbx 12:18:38.054 [9056.2976] <2> logconnections: PROXY CONNECT FROM 10.102.185.76.56012 TO 10.47.54.6.1556 fd = 1152 12:18:38.054 [9056.2976] <2> logconnections: BPCD CONNECT FROM 127.0.0.1.56014 TO 127.0.0.1.56015 fd = 1152 12:18:38.054 [9056.2976] <2> vnet_connect_to_vnetd_bpcd: js_bpcd_info: 0000000001C5D9D0 12:18:38.054 [9056.2976] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:5193] using interface ANY 12:18:38.054 [9056.2976] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:5193] using interface ANY 12:18:38.069 [9056.2976] <2> vnet_pbxConnect_ex: pbxConnectExEx Succeeded 12:18:38.116 [9056.2976] <8> do_pbx_service: [vnet_connect.c:2579] via PBX VNETD CONNECT FROM 10.102.185.76.56018 TO 10.47.54.6.1556 fd = 1156 12:18:38.116 [9056.2976] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO soslbck001 10.47.54.6 vnetd VIA pbx 12:18:38.116 [9056.2976] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:458] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa 12:18:38.132 [9056.2976] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:483] ipc_string /usr/openv/var/tmp/vnet-30753553253518369165000003128-T1qAHC 12:18:38.132 [9056.2976] <2> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:494] hash_str1 6fc1f8c48b045140d4ba7088d27ab8f7 12:18:38.241 [9056.2976] <2> bpcr_get_version_rqst: bpcd version: 08100000 12:18:38.288 [9056.2976] <2> bpcr_get_version_rqst: bpcd version: 08100000 12:18:39.426 [9056.2976] <2> dbm_open_client_connection: host = soslmls07.internal.financecorp.biz 12:18:39.426 [9056.2976] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:5193] using interface ANY 12:18:39.426 [9056.2976] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:5193] using interface ANY 12:18:39.442 [9056.2976] <2> vnet_pbxConnect_ex: pbxConnectExEx Succeeded 12:18:39.442 [9056.2976] <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:18:39.536 [9056.2976] <2> logconnections: PROXY CONNECT FROM 10.102.185.76.56044 TO 10.102.184.163.1556 fd = 1152 12:18:39.536 [9056.2976] <2> logconnections: BPCD CONNECT FROM 127.0.0.1.56046 TO 127.0.0.1.56047 fd = 1152 12:18:39.536 [9056.2976] <2> vnet_connect_to_vnetd_bpcd: js_bpcd_info: 0000000001C5D6D0 12:18:39.536 [9056.2976] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:5193] using interface ANY 12:18:39.536 [9056.2976] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:5193] using interface ANY 12:18:39.551 [9056.2976] <2> vnet_pbxConnect_ex: pbxConnectExEx Succeeded 12:18:39.629 [9056.2976] <8> do_pbx_service: [vnet_connect.c:2579] via PBX VNETD CONNECT FROM 10.102.185.76.56048 TO 10.102.184.163.1556 fd = 1168 12:18:39.629 [9056.2976] <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:18:39.629 [9056.2976] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:458] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa 12:18:39.645 [9056.2976] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:483] ipc_string 14779 12:18:39.645 [9056.2976] <2> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:494] hash_str1 e99e9732cbd013d12a9692493ed341ba 12:18:39.707 [9056.2976] <2> bpcr_get_version_rqst: bpcd version: 08100000 12:18:39.707 [9056.2976] <2> dbm_open_client_connection: client socket from bpcr = 1152 12:18:39.707 [9056.2976] <2> dbm_query_client: mount point = ignore 12:18:39.707 [9056.2976] <2> dbm_query_client: mount device = ignore 12:18:39.707 [9056.2976] <2> dbm_query_client: frag_id = ignore 12:18:39.707 [9056.2976] <2> dbm_query_client: fs_type = ignore 12:18:39.707 [9056.2976] <2> dbm_query_client: search_pattern = /Microsoft Exchange Database Availability Groups/soslmlc01/Microsoft Information Store/MBX13_NO/Database 12:18:39.707 [9056.2976] <2> dbm_query_client: path = /Microsoft Exchange Database Availability Groups/soslmlc01/Microsoft Information Store/MBX13_NO/Database/Marian Agh [K404186]/ 12:18:39.707 [9056.2976] <2> dbm_query_client: depth = 1 12:18:39.707 [9056.2976] <2> dbm_query_client: dirs_only = 0 12:18:39.707 [9056.2976] <2> dbm_query_client: copy_num = 1 12:18:39.707 [9056.2976] <2> dbm_query_client: browsing depth 1 12:18:39.723 [9056.2976] <2> bpcr_get_version_rqst: bpcd version: 08100000 12:18:39.754 [9056.2976] <2> bpcr_get_fileinfo_rqst: bpcr read status 0 from bpcd 12:18:39.754 [9056.2976] <2> bpcr_get_string_pentry: Incorrect value found in the config file 12:19:42.889 [9056.2976] <2> bpcr_get_string_pentry: Error detected: ERROR 13 12:19:42.889 [9056.2976] <2> ImageListFiles::executeQuery: selected 0 total_selected 0 12:19:42.889 [9056.2976] <2> ~ExtraObjRecordIndexCache: 0 hits, 1 misses 12:19:42.889 [9056.2976] <2> ~IndexRefCache: ExtraObject cache: 0 in-use hits, 0 unused hits, 2 misses 12:19:42.889 [9056.2976] <2> ~IndexRefCache: NDMP cache: 0 in-use hits, 0 unused hits, 0 misses 12:19:42.889 [9056.2976] <2> ~IndexRefCache: File cache: 0 in-use hits, 3 unused hits, 4 misses 12:19:42.889 [9056.2976] <2> ~IndexRefCache: Directory cache: 0 in-use hits, 9 unused hits, 7 misses 12:19:42.889 [9056.2976] <2> ~IndexRefCache: Record cache: 3 in-use hits, 1 unused hits, 6 misses 12:19:42.889 [9056.2976] <4> JobdSockList::UnregisterSocket: Unregister socket (752). 12:19:42.889 [9056.2976] <2> job_monitoring_exex: ACK disconnect 12:19:42.905 [9056.2976] <2> job_disconnect: Disconnected 12:19:42.905 [9056.2976] <2> dbm_close_client_connection: sending disconnect request to bpcd 12:19:42.920 [9056.2976] <2> bpcr_disconnect_rqst: bpcr received the following message: 12:19:42.920 [9056.2976] <2> bpcr_disconnect_rqst: bpcr received the following message: 12:19:42.920 [9056.2976] <2> dbm_cleanup_client_connection: Entering 12:19:42.920 [9056.2976] <2> jmcomm_ReleaseAllResourcesForJob: releaseAllResources returns [0] 12:19:42.920 [9056.2976] <2> jmcomm_ReleaseAllResourcesForJob: returning 12:19:42.920 [9056.2976] <2> load_next_combined_image: (.4) Image {IK=4970208, MSK=1000002, PBK=0, PIK=0, CIK=0, FBIK=0, CSSK=0, C='soslmlc01.internal.financecorp.biz', CK=369, PN='prod-app-exchdb-grt_part1', BT=1549660101, VID=0, ST=0, CT=16, PV=14, ST=0, ET=0, ST=1549659632, KB=1208356892, 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=28935, EXP=1552338501, NF=12253, ESI=0, RP=0, IFRFR=0, IDL=0, FSO=0, PBIT=0, BIFT=0, SN=3, BC=0, BS=0, JID=2953842, NR=0, RE=0, PFI=0, IA=16, C='root', SN='weekly', PC='', KP='', FF='prod-app-exchdb-grt_part1_1549660101_FULL.f', FFS=5656250, SV='', OD='', EKB=1463999299, VM=0, IS=0, RT=1549660101, LBT=1549660101, CCS=1, BMR=1, OMS='', OMSI='00000000-0000-0000-0000-000000000000', SSN='OSL-BF-Fw_1m_03_exch_weekly', SSS=2, SSI=0, TIP=1549689057, 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:19:42.920 [9056.2976] <2> load_next_combined_image: (.4) Copy {ICK=6743748, IK=4970208, CN=1, SI=1, CT=0, ET=1552338501, MPX=0, RL=65539, CD=1549689036, DF=1, KMS='',TTK=1552338501, RES='stu_disk_soslbck001', CS=3, JID=2953842, ET=0, RC=0, LRT=1549689036, LDT='', SS=0, DDT=0, ELT=1552338501, IR=0, MP=0, H=0, VC=0, EC=0} 12:19:42.920 [9056.2976] <2> load_next_combined_image: (.4) Fragment {IFK=12037640, IK=4970208, ICK=6743748, 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:19:42.920 [9056.2976] <2> DBEnumerator::move_next: Got image soslmlc01.internal.financecorp.biz_1549660101 12:19:42.920 [9056.2976] <2> get_DBM_ImageFragment: Entering 12:19:42.920 [9056.2976] <2> get_DBM_ImageFragment: (37867.9) Executing "SELECT...FROM DBM_Main.DBM_ImageFragment Frag WHERE ImageKey = ? AND CopyNumber = ? AND FragmentNumber = ? FOR READ ONLY" Bindings <4970208, 1, 1> 12:19:42.920 [9056.2976] <2> fetch_DBM_ImageFragments: (.9) Fragment {IFK=12037640, IK=4970208, ICK=6743748, 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:19:42.920 [9056.2976] <2> open_virtual_file_rec: Entering 12:19:42.920 [9056.2976] <2> strcmp_match_file_list: Entering 12:19:42.920 [9056.2976] <2> strcmp_match_file_list: pattern:/Microsoft Exchange Database Availability Groups/soslmlc01/Microsoft Information Store/MBX13_NO/Database/Marian Agh [K404186]/ level:6 path_independent:0 fl_option:0x80000402 12:19:42.920 [9056.2976] <2> ImageListFiles::executeQuery: selected 0 total_selected 0 12:19:42.920 [9056.2976] <2> ~ExtraObjRecordIndexCache: 0 hits, 0 misses 12:19:42.920 [9056.2976] <2> ~IndexRefCache: ExtraObject cache: 0 in-use hits, 0 unused hits, 0 misses 12:19:42.920 [9056.2976] <2> ~IndexRefCache: NDMP cache: 0 in-use hits, 0 unused hits, 0 misses 12:19:42.920 [9056.2976] <2> ~IndexRefCache: File cache: 0 in-use hits, 0 unused hits, 1 misses 12:19:42.920 [9056.2976] <2> ~IndexRefCache: Directory cache: 0 in-use hits, 3 unused hits, 5 misses 12:19:42.920 [9056.2976] <2> ~IndexRefCache: Record cache: 0 in-use hits, 0 unused hits, 0 misses 12:19:42.920 [9056.2976] <2> load_next_combined_image: (.4) Image {IK=4970207, MSK=1000002, PBK=0, PIK=0, CIK=0, FBIK=0, CSSK=0, C='soslmlc01.internal.financecorp.biz', CK=369, PN='prod-app-exchdb-grt_part1', BT=1549660100, VID=0, ST=0, CT=16, PV=14, ST=0, ET=0, ST=1549659632, KB=3321714711, 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=49471, EXP=1552338500, NF=3916, ESI=0, RP=0, IFRFR=0, IDL=0, FSO=0, PBIT=0, BIFT=0, SN=2, BC=0, BS=0, JID=2953841, NR=0, RE=0, PFI=0, IA=16, C='root', SN='weekly', PC='', KP='', FF='prod-app-exchdb-grt_part1_1549660100_FULL.f', FFS=1780868, SV='', OD='', EKB=4018770850, VM=0, IS=0, RT=1549660100, LBT=1549660100, CCS=1, BMR=1, OMS='', OMSI='00000000-0000-0000-0000-000000000000', SSN='OSL-BF-Fw_1m_03_exch_weekly', SSS=2, SSI=0, TIP=1549709802, 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:19:42.920 [9056.2976] <2> load_next_combined_image: (.4) Copy {ICK=6743749, IK=4970207, CN=1, SI=1, CT=0, ET=1552338500, MPX=0, RL=65539, CD=1549709571, DF=1, KMS='',TTK=1552338500, RES='stu_disk_soslbck001', CS=3, JID=2953841, ET=0, RC=0, LRT=1549709571, LDT='', SS=0, DDT=0, ELT=1552338500, IR=0, MP=0, H=0, VC=0, EC=0} 12:19:42.920 [9056.2976] <2> load_next_combined_image: (.4) Fragment {IFK=12037641, IK=4970207, ICK=6743749, 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:19:42.920 [9056.2976] <2> DBEnumerator::move_next: Got image soslmlc01.internal.financecorp.biz_1549660100 12:19:42.920 [9056.2976] <2> get_DBM_ImageFragment: Entering 12:19:42.920 [9056.2976] <2> get_DBM_ImageFragment: (37867.10) Executing "SELECT...FROM DBM_Main.DBM_ImageFragment Frag WHERE ImageKey = ? AND CopyNumber = ? AND FragmentNumber = ? FOR READ ONLY" Bindings <4970207, 1, 1> 12:19:42.920 [9056.2976] <2> fetch_DBM_ImageFragments: (.10) Fragment {IFK=12037641, IK=4970207, ICK=6743749, 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:19:42.920 [9056.2976] <2> open_virtual_file_rec: Entering 12:19:42.936 [9056.2976] <2> strcmp_match_file_list: Entering 12:19:42.936 [9056.2976] <2> strcmp_match_file_list: pattern:/Microsoft Exchange Database Availability Groups/soslmlc01/Microsoft Information Store/MBX13_NO/Database/Marian Agh [K404186]/ level:6 path_independent:0 fl_option:0x80000402 12:19:42.936 [9056.2976] <2> ImageListFiles::executeQuery: selected 0 total_selected 0 12:19:42.936 [9056.2976] <2> ~ExtraObjRecordIndexCache: 0 hits, 0 misses 12:19:42.936 [9056.2976] <2> ~IndexRefCache: ExtraObject cache: 0 in-use hits, 0 unused hits, 0 misses 12:19:42.936 [9056.2976] <2> ~IndexRefCache: NDMP cache: 0 in-use hits, 0 unused hits, 0 misses 12:19:42.936 [9056.2976] <2> ~IndexRefCache: File cache: 0 in-use hits, 0 unused hits, 1 misses 12:19:42.936 [9056.2976] <2> ~IndexRefCache: Directory cache: 0 in-use hits, 3 unused hits, 5 misses 12:19:42.936 [9056.2976] <2> ~IndexRefCache: Record cache: 0 in-use hits, 0 unused hits, 0 misses 12:19:42.936 [9056.2976] <2> load_next_combined_image: (.4) Image {IK=4970206, MSK=1000002, PBK=0, PIK=0, CIK=0, FBIK=0, CSSK=0, C='soslmlc01.internal.financecorp.biz', CK=369, PN='prod-app-exchdb-grt_part1', BT=1549660099, VID=0, ST=0, CT=16, PV=14, ST=0, ET=0, ST=1549659632, KB=989464122, 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=18845, EXP=1552338499, NF=1345, ESI=0, RP=0, IFRFR=0, IDL=0, FSO=0, PBIT=0, BIFT=0, SN=1, BC=0, BS=0, JID=2953840, NR=0, RE=0, PFI=0, IA=16, C='root', SN='weekly', PC='', KP='', FF='prod-app-exchdb-grt_part1_1549660099_FULL.f', FFS=580092, SV='', OD='', EKB=1178577950, VM=0, IS=0, RT=1549660099, LBT=1549660099, CCS=1, BMR=1, OMS='', OMSI='00000000-0000-0000-0000-000000000000', SSN='OSL-BF-Fw_1m_03_exch_weekly', SSS=2, SSI=0, TIP=1549679132, 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:19:42.936 [9056.2976] <2> load_next_combined_image: (.4) Copy {ICK=6743747, IK=4970206, CN=1, SI=1, CT=0, ET=1552338499, MPX=0, RL=65539, CD=1549678944, DF=1, KMS='',TTK=1552338499, RES='stu_disk_soslbck001', CS=3, JID=2953840, ET=0, RC=0, LRT=1549678944, LDT='', SS=0, DDT=0, ELT=1552338499, IR=0, MP=0, H=0, VC=0, EC=0} 12:19:42.936 [9056.2976] <2> load_next_combined_image: (.4) Fragment {IFK=12037639, IK=4970206, ICK=6743747, 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:19:42.936 [9056.2976] <2> DBEnumerator::move_next: Got image soslmlc01.internal.financecorp.biz_1549660099 12:19:42.936 [9056.2976] <2> get_DBM_ImageFragment: Entering 12:19:42.936 [9056.2976] <2> get_DBM_ImageFragment: (37867.11) Executing "SELECT...FROM DBM_Main.DBM_ImageFragment Frag WHERE ImageKey = ? AND CopyNumber = ? AND FragmentNumber = ? FOR READ ONLY" Bindings <4970206, 1, 1> 12:19:42.936 [9056.2976] <2> fetch_DBM_ImageFragments: (.11) Fragment {IFK=12037639, IK=4970206, ICK=6743747, 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:19:42.936 [9056.2976] <2> open_virtual_file_rec: Entering 12:19:42.936 [9056.2976] <2> strcmp_match_file_list: Entering 12:19:42.936 [9056.2976] <2> strcmp_match_file_list: pattern:/Microsoft Exchange Database Availability Groups/soslmlc01/Microsoft Information Store/MBX13_NO/Database/Marian Agh [K404186]/ level:6 path_independent:0 fl_option:0x80000402 12:19:42.936 [9056.2976] <2> ImageListFiles::executeQuery: selected 0 total_selected 0 12:19:42.936 [9056.2976] <2> ~ExtraObjRecordIndexCache: 0 hits, 0 misses 12:19:42.936 [9056.2976] <2> ~IndexRefCache: ExtraObject cache: 0 in-use hits, 0 unused hits, 0 misses 12:19:42.936 [9056.2976] <2> ~IndexRefCache: NDMP cache: 0 in-use hits, 0 unused hits, 0 misses 12:19:42.936 [9056.2976] <2> ~IndexRefCache: File cache: 0 in-use hits, 0 unused hits, 1 misses 12:19:42.936 [9056.2976] <2> ~IndexRefCache: Directory cache: 0 in-use hits, 3 unused hits, 5 misses 12:19:42.936 [9056.2976] <2> ~IndexRefCache: Record cache: 0 in-use hits, 0 unused hits, 0 misses 12:19:42.936 [9056.2976] <2> load_next_combined_image: (.4) Image {IK=4970194, MSK=1000002, PBK=0, PIK=0, CIK=0, FBIK=0, CSSK=0, C='soslmlc01.internal.financecorp.biz', CK=369, PN='prod-app-exchdb-grt_part1', BT=1549659950, VID=0, ST=0, CT=16, PV=14, ST=0, ET=0, ST=1549659633, KB=1052973803, 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=36814, EXP=1552338350, NF=5355, ESI=0, RP=0, IFRFR=0, IDL=0, FSO=0, PBIT=0, BIFT=0, SN=7, BC=0, BS=0, JID=2953832, NR=0, RE=0, PFI=0, IA=16, C='root', SN='weekly', PC='', KP='', FF='prod-app-exchdb-grt_part1_1549659950_FULL.f', FFS=2247626, SV='', OD='', EKB=1262991160, VM=0, IS=0, RT=1549659950, LBT=1549659950, CCS=1, BMR=1, OMS='', OMSI='00000000-0000-0000-0000-000000000000', SSN='OSL-BF-Fw_1m_03_exch_weekly', SSS=2, SSI=0, TIP=1549696874, 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:19:42.936 [9056.2976] <2> load_next_combined_image: (.4) Copy {ICK=6743732, IK=4970194, CN=1, SI=1, CT=0, ET=1552338350, MPX=0, RL=65539, CD=1549696764, DF=1, KMS='',TTK=1552338350, RES='stu_disk_soslbck001', CS=3, JID=2953832, ET=0, RC=0, LRT=1549696764, LDT='', SS=0, DDT=0, ELT=1552338350, IR=0, MP=0, H=0, VC=0, EC=0} 12:19:42.936 [9056.2976] <2> load_next_combined_image: (.4) Fragment {IFK=12037595, IK=4970194, ICK=6743732, 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:19:42.936 [9056.2976] <2> DBEnumerator::move_next: Got image soslmlc01.internal.financecorp.biz_1549659950 12:19:42.936 [9056.2976] <2> get_DBM_ImageFragment: Entering 12:19:42.936 [9056.2976] <2> get_DBM_ImageFragment: (37867.12) Executing "SELECT...FROM DBM_Main.DBM_ImageFragment Frag WHERE ImageKey = ? AND CopyNumber = ? AND FragmentNumber = ? FOR READ ONLY" Bindings <4970194, 1, 1> 12:19:42.936 [9056.2976] <2> fetch_DBM_ImageFragments: (.12) Fragment {IFK=12037595, IK=4970194, ICK=6743732, 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:19:42.936 [9056.2976] <2> open_virtual_file_rec: Entering 12:19:42.936 [9056.2976] <2> strcmp_match_file_list: Entering 12:19:42.936 [9056.2976] <2> strcmp_match_file_list: pattern:/Microsoft Exchange Database Availability Groups/soslmlc01/Microsoft Information Store/MBX13_NO/Database/Marian Agh [K404186]/ level:6 path_independent:0 fl_option:0x80000402 12:19:42.936 [9056.2976] <2> ImageListFiles::executeQuery: selected 0 total_selected 0 12:19:42.936 [9056.2976] <2> ~ExtraObjRecordIndexCache: 0 hits, 0 misses 12:19:42.936 [9056.2976] <2> ~IndexRefCache: ExtraObject cache: 0 in-use hits, 0 unused hits, 0 misses 12:19:42.936 [9056.2976] <2> ~IndexRefCache: NDMP cache: 0 in-use hits, 0 unused hits, 0 misses 12:19:42.936 [9056.2976] <2> ~IndexRefCache: File cache: 0 in-use hits, 0 unused hits, 1 misses 12:19:42.936 [9056.2976] <2> ~IndexRefCache: Directory cache: 0 in-use hits, 3 unused hits, 5 misses 12:19:42.936 [9056.2976] <2> ~IndexRefCache: Record cache: 0 in-use hits, 0 unused hits, 0 misses 12:19:42.936 [9056.2976] <2> load_next_combined_image: (.4) Image {IK=4970188, MSK=1000002, PBK=0, PIK=0, CIK=0, FBIK=0, CSSK=0, C='soslmlc01.internal.financecorp.biz', CK=369, PN='prod-app-exchdb-grt_part2', BT=1549659865, VID=0, ST=0, CT=16, PV=14, ST=0, ET=0, ST=1549659629, KB=340582223, 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=4761, EXP=1552338265, NF=4965, ESI=0, RP=0, IFRFR=0, IDL=0, FSO=0, PBIT=0, BIFT=0, SN=2, BC=0, BS=0, JID=2953827, NR=0, RE=0, PFI=0, IA=16, C='root', SN='weekly', PC='', KP='', FF='prod-app-exchdb-grt_part2_1549659865_FULL.f', FFS=2263647, SV='', OD='', EKB=406285570, VM=0, IS=0, RT=1549659865, LBT=1549659865, CCS=1, BMR=1, OMS='', OMSI='00000000-0000-0000-0000-000000000000', SSN='OSL-BF-Fw_1m_03_exch_weekly', SSS=2, SSI=0, TIP=1549664696, 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:19:42.936 [9056.2976] <2> load_next_combined_image: (.4) Copy {ICK=6743725, IK=4970188, CN=1, SI=1, CT=0, ET=1552338265, MPX=0, RL=65539, CD=1549664626, DF=1, KMS='',TTK=1552338265, RES='stu_disk_soslbck001', CS=3, JID=2953827, ET=0, RC=0, LRT=1549664626, LDT='', SS=0, DDT=0, ELT=1552338265, IR=0, MP=0, H=0, VC=0, EC=0} 12:19:42.936 [9056.2976] <2> load_next_combined_image: (.4) Fragment {IFK=12037580, IK=4970188, ICK=6743725, 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:19:42.936 [9056.2976] <2> DBEnumerator::move_next: Got image soslmlc01.internal.financecorp.biz_1549659865 12:19:42.936 [9056.2976] <2> get_DBM_ImageFragment: Entering 12:19:42.936 [9056.2976] <2> get_DBM_ImageFragment: (37867.13) Executing "SELECT...FROM DBM_Main.DBM_ImageFragment Frag WHERE ImageKey = ? AND CopyNumber = ? AND FragmentNumber = ? FOR READ ONLY" Bindings <4970188, 1, 1> 12:19:42.936 [9056.2976] <2> fetch_DBM_ImageFragments: (.13) Fragment {IFK=12037580, IK=4970188, ICK=6743725, 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:19:42.936 [9056.2976] <2> open_virtual_file_rec: Entering 12:19:42.936 [9056.2976] <2> strcmp_match_file_list: Entering 12:19:42.936 [9056.2976] <2> strcmp_match_file_list: pattern:/Microsoft Exchange Database Availability Groups/soslmlc01/Microsoft Information Store/MBX13_NO/Database/Marian Agh [K404186]/ level:6 path_independent:0 fl_option:0x80000402 12:19:42.936 [9056.2976] <2> ImageListFiles::executeQuery: selected 0 total_selected 0 12:19:42.936 [9056.2976] <2> ~ExtraObjRecordIndexCache: 0 hits, 0 misses 12:19:42.936 [9056.2976] <2> ~IndexRefCache: ExtraObject cache: 0 in-use hits, 0 unused hits, 0 misses 12:19:42.936 [9056.2976] <2> ~IndexRefCache: NDMP cache: 0 in-use hits, 0 unused hits, 0 misses 12:19:42.936 [9056.2976] <2> ~IndexRefCache: File cache: 0 in-use hits, 0 unused hits, 1 misses 12:19:42.936 [9056.2976] <2> ~IndexRefCache: Directory cache: 0 in-use hits, 3 unused hits, 5 misses 12:19:42.936 [9056.2976] <2> ~IndexRefCache: Record cache: 0 in-use hits, 0 unused hits, 0 misses 12:19:42.951 [9056.2976] <2> load_next_combined_image: (.4) Image {IK=4970187, MSK=1000002, PBK=0, PIK=0, CIK=0, FBIK=0, CSSK=0, C='soslmlc01.internal.financecorp.biz', CK=369, PN='prod-app-exchdb-grt_part2', BT=1549659864, VID=0, ST=0, CT=16, PV=14, ST=0, ET=0, ST=1549659629, KB=2395649710, 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=19685, EXP=1552338264, NF=789, ESI=0, RP=0, IFRFR=0, IDL=0, FSO=0, PBIT=0, BIFT=0, SN=1, BC=0, BS=0, JID=2953826, NR=0, RE=0, PFI=0, IA=16, C='root', SN='weekly', PC='', KP='', FF='prod-app-exchdb-grt_part2_1549659864_FULL.f', FFS=264058, SV='', OD='', EKB=2863254424, VM=0, IS=0, RT=1549659864, LBT=1549659864, CCS=1, BMR=1, OMS='', OMSI='00000000-0000-0000-0000-000000000000', SSN='OSL-BF-Fw_1m_03_exch_weekly', SSS=2, SSI=0, TIP=1549679733, 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:19:42.951 [9056.2976] <2> load_next_combined_image: (.4) Copy {ICK=6743726, IK=4970187, CN=1, SI=1, CT=0, ET=1552338264, MPX=0, RL=65539, CD=1549679549, DF=1, KMS='',TTK=1552338264, RES='stu_disk_soslbck001', CS=3, JID=2953826, ET=0, RC=0, LRT=1549679549, LDT='', SS=0, DDT=0, ELT=1552338264, IR=0, MP=0, H=0, VC=0, EC=0} 12:19:42.951 [9056.2976] <2> load_next_combined_image: (.4) Fragment {IFK=12037581, IK=4970187, ICK=6743726, 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:19:42.951 [9056.2976] <2> DBEnumerator::move_next: Got image soslmlc01.internal.financecorp.biz_1549659864 12:19:42.951 [9056.2976] <2> get_DBM_ImageFragment: Entering 12:19:42.951 [9056.2976] <2> get_DBM_ImageFragment: (37867.14) Executing "SELECT...FROM DBM_Main.DBM_ImageFragment Frag WHERE ImageKey = ? AND CopyNumber = ? AND FragmentNumber = ? FOR READ ONLY" Bindings <4970187, 1, 1> 12:19:42.951 [9056.2976] <2> fetch_DBM_ImageFragments: (.14) Fragment {IFK=12037581, IK=4970187, ICK=6743726, 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:19:42.951 [9056.2976] <2> open_virtual_file_rec: Entering 12:19:42.951 [9056.2976] <2> strcmp_match_file_list: Entering 12:19:42.951 [9056.2976] <2> strcmp_match_file_list: pattern:/Microsoft Exchange Database Availability Groups/soslmlc01/Microsoft Information Store/MBX13_NO/Database/Marian Agh [K404186]/ level:6 path_independent:0 fl_option:0x80000402 12:19:42.951 [9056.2976] <2> ImageListFiles::executeQuery: selected 0 total_selected 0 12:19:42.951 [9056.2976] <2> ~ExtraObjRecordIndexCache: 0 hits, 0 misses 12:19:42.951 [9056.2976] <2> ~IndexRefCache: ExtraObject cache: 0 in-use hits, 0 unused hits, 0 misses 12:19:42.951 [9056.2976] <2> ~IndexRefCache: NDMP cache: 0 in-use hits, 0 unused hits, 0 misses 12:19:42.951 [9056.2976] <2> ~IndexRefCache: File cache: 0 in-use hits, 0 unused hits, 1 misses 12:19:42.951 [9056.2976] <2> ~IndexRefCache: Directory cache: 0 in-use hits, 3 unused hits, 5 misses 12:19:42.951 [9056.2976] <2> ~IndexRefCache: Record cache: 0 in-use hits, 0 unused hits, 0 misses 12:19:42.951 [9056.2976] <2> fetch_DBM_AllImageCopyFrag: Entering 12:19:42.951 [9056.2976] <2> fetch_DBM_AllImageCopyFrag: Success fetching (1) records from join of DBM_Image, DbmImageCopy, DbmImageFragment 12:19:42.951 [9056.2976] <2> load_next_combined_image: (.4) Image {IK=4970175, MSK=1000002, PBK=0, PIK=0, CIK=0, FBIK=0, CSSK=0, C='soslmlc01.internal.financecorp.biz', CK=369, PN='prod-app-exchdb-grt_part3', BT=1549659762, VID=0, ST=0, CT=16, PV=14, ST=0, ET=0, ST=1549659628, KB=1522808623, 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=16884, EXP=1552338162, NF=11856, ESI=0, RP=0, IFRFR=0, IDL=0, FSO=0, PBIT=0, BIFT=0, SN=2, BC=0, BS=0, JID=2953815, NR=0, RE=0, PFI=0, IA=16, C='root', SN='weekly', PC='', KP='', FF='prod-app-exchdb-grt_part3_1549659762_FULL.f', FFS=5399480, SV='', OD='', EKB=1819692400, VM=0, IS=0, RT=1549659762, LBT=1549659762, CCS=1, BMR=1, OMS='', OMSI='00000000-0000-0000-0000-000000000000', SSN='OSL-BF-Fw_1m_03_exch_weekly', SSS=2, SSI=0, TIP=1549676727, 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:19:42.951 [9056.2976] <2> load_next_combined_image: (.4) Copy {ICK=6743702, IK=4970175, CN=1, SI=1, CT=0, ET=1552338162, MPX=0, RL=65539, CD=1549676646, DF=1, KMS='',TTK=1552338162, RES='stu_disk_soslbck001', CS=3, JID=2953815, ET=0, RC=0, LRT=1549676646, LDT='', SS=0, DDT=0, ELT=1552338162, IR=0, MP=0, H=0, VC=0, EC=0} 12:19:42.951 [9056.2976] <2> load_next_combined_image: (.4) Fragment {IFK=12037551, IK=4970175, ICK=6743702, 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:19:42.951 [9056.2976] <2> DBEnumerator::move_next: Got image soslmlc01.internal.financecorp.biz_1549659762 12:19:42.951 [9056.2976] <2> get_DBM_ImageFragment: Entering 12:19:42.951 [9056.2976] <2> get_DBM_ImageFragment: (37867.15) Executing "SELECT...FROM DBM_Main.DBM_ImageFragment Frag WHERE ImageKey = ? AND CopyNumber = ? AND FragmentNumber = ? FOR READ ONLY" Bindings <4970175, 1, 1> 12:19:42.951 [9056.2976] <2> fetch_DBM_ImageFragments: (.15) Fragment {IFK=12037551, IK=4970175, ICK=6743702, 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:19:42.951 [9056.2976] <2> open_virtual_file_rec: Entering 12:19:42.951 [9056.2976] <2> strcmp_match_file_list: Entering 12:19:42.951 [9056.2976] <2> strcmp_match_file_list: pattern:/Microsoft Exchange Database Availability Groups/soslmlc01/Microsoft Information Store/MBX13_NO/Database/Marian Agh [K404186]/ level:6 path_independent:0 fl_option:0x80000402 12:19:42.951 [9056.2976] <2> ImageListFiles::executeQuery: selected 0 total_selected 0 12:19:42.951 [9056.2976] <2> ~ExtraObjRecordIndexCache: 0 hits, 0 misses 12:19:42.951 [9056.2976] <2> ~IndexRefCache: ExtraObject cache: 0 in-use hits, 0 unused hits, 0 misses 12:19:42.951 [9056.2976] <2> ~IndexRefCache: NDMP cache: 0 in-use hits, 0 unused hits, 0 misses 12:19:42.951 [9056.2976] <2> ~IndexRefCache: File cache: 0 in-use hits, 0 unused hits, 1 misses 12:19:42.951 [9056.2976] <2> ~IndexRefCache: Directory cache: 0 in-use hits, 3 unused hits, 5 misses 12:19:42.951 [9056.2976] <2> ~IndexRefCache: Record cache: 0 in-use hits, 0 unused hits, 0 misses 12:19:42.951 [9056.2976] <2> fetch_DBM_AllImageCopyFrag: Entering 12:19:42.951 [9056.2976] <2> fetch_DBM_AllImageCopyFrag: No data found when fetching from Join of DBM_Image, DbmImageCopy, DbmImageFragment 12:19:42.951 [9056.2976] <2> load_next_combined_image: (.4) Image {IK=4970167, MSK=1000002, PBK=0, PIK=0, CIK=0, FBIK=0, CSSK=0, C='soslmlc01.internal.financecorp.biz', CK=369, PN='prod-app-exchdb-grt_part3', BT=1549659721, VID=0, ST=0, CT=16, PV=14, ST=0, ET=0, ST=1549659626, KB=1381623858, 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=28690, EXP=1552338121, NF=873, ESI=0, RP=0, IFRFR=0, IDL=0, FSO=0, PBIT=0, BIFT=0, SN=1, BC=0, BS=0, JID=2953808, NR=0, RE=0, PFI=0, IA=16, C='root', SN='weekly', PC='', KP='', FF='prod-app-exchdb-grt_part3_1549659721_FULL.f', FFS=333140, SV='', OD='', EKB=1643850901, VM=0, IS=0, RT=1549659721, LBT=1549659721, CCS=1, BMR=1, OMS='', OMSI='00000000-0000-0000-0000-000000000000', SSN='OSL-BF-Fw_1m_03_exch_weekly', SSS=2, SSI=0, TIP=1549688455, 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:19:42.951 [9056.2976] <2> load_next_combined_image: (.4) Copy {ICK=6743693, IK=4970167, CN=1, SI=1, CT=0, ET=1552338121, MPX=0, RL=65539, CD=1549688411, DF=1, KMS='',TTK=1552338121, RES='stu_disk_soslbck001', CS=3, JID=2953808, ET=0, RC=0, LRT=1549688411, LDT='', SS=0, DDT=0, ELT=1552338121, IR=0, MP=0, H=0, VC=0, EC=0} 12:19:42.951 [9056.2976] <2> load_next_combined_image: (.4) Fragment {IFK=12037532, IK=4970167, ICK=6743693, 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:19:42.951 [9056.2976] <2> DBEnumerator::move_next: Got image soslmlc01.internal.financecorp.biz_1549659721 12:19:42.951 [9056.2976] <2> get_DBM_ImageFragment: Entering 12:19:42.951 [9056.2976] <2> get_DBM_ImageFragment: (37867.16) Executing "SELECT...FROM DBM_Main.DBM_ImageFragment Frag WHERE ImageKey = ? AND CopyNumber = ? AND FragmentNumber = ? FOR READ ONLY" Bindings <4970167, 1, 1> 12:19:42.951 [9056.2976] <2> fetch_DBM_ImageFragments: (.16) Fragment {IFK=12037532, IK=4970167, ICK=6743693, 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:19:42.951 [9056.2976] <2> open_virtual_file_rec: Entering 12:19:42.951 [9056.2976] <2> strcmp_match_file_list: Entering 12:19:42.951 [9056.2976] <2> strcmp_match_file_list: pattern:/Microsoft Exchange Database Availability Groups/soslmlc01/Microsoft Information Store/MBX13_NO/Database/Marian Agh [K404186]/ level:6 path_independent:0 fl_option:0x80000402 12:19:42.951 [9056.2976] <2> ImageListFiles::executeQuery: selected 0 total_selected 0 12:19:42.951 [9056.2976] <2> ~ExtraObjRecordIndexCache: 0 hits, 0 misses 12:19:42.951 [9056.2976] <2> ~IndexRefCache: ExtraObject cache: 0 in-use hits, 0 unused hits, 0 misses 12:19:42.951 [9056.2976] <2> ~IndexRefCache: NDMP cache: 0 in-use hits, 0 unused hits, 0 misses 12:19:42.951 [9056.2976] <2> ~IndexRefCache: File cache: 0 in-use hits, 0 unused hits, 1 misses 12:19:42.951 [9056.2976] <2> ~IndexRefCache: Directory cache: 0 in-use hits, 3 unused hits, 5 misses 12:19:42.951 [9056.2976] <2> ~IndexRefCache: Record cache: 0 in-use hits, 0 unused hits, 0 misses 12:19:42.951 [9056.2976] <2> DBEnumerator::move_next: Fetched 91 rows this query 12:19:42.951 [9056.2976] <2> DBEnumerator::move_next: Reached the end of the client list 12:19:42.951 [9056.2976] <2> DbmOdbcConnect::commitTransaction: (37867) success - view count 0 12:19:42.951 [9056.2976] <2> DbmOdbcConnect::~DbmOdbcConnect: (37867) Exiting 12:19:42.983 [9056.2976] <2> db_sendSTATUS: Status:227 12:19:42.983 [9056.2976] <2> process_request: request complete: exit status 227 no entity was found; query type: 79