11:34:13.593 [7920.7924] <2> read_next_image: calling db_IMAGEreceive() for next image 11:34:13.593 [7920.7924] <2> db_logimagerec: protocol version 11 qtype 82 vers 0 client infosec.eub.kz policy Sharepoint_DIB 11:34:13.593 [7920.7924] <2> db_logimagerec: sched type FULL (0) 11:34:13.593 [7920.7924] <2> db_logimagerec: sched label Full 11:34:13.593 [7920.7924] <2> db_logimagerec: options 0x8002000 compress 0 encrypt 0 Kbytes 0 11:34:13.593 [7920.7924] <2> db_logimagerec: backup copy: Standard (0) 11:34:13.593 [7920.7924] <2> db_logimagerec: is quiesced 1 11:34:13.593 [7920.7924] <2> db_logimagerec: slp operations mask 255 11:34:13.593 [7920.7924] <2> db_logimagerec: is scheduled 1 11:34:13.593 [7920.7924] <2> DbmOdbcView::DbmOdbcView: (42.3223) Created 11:34:13.593 [7920.7924] <2> DbmOdbcConnect::isConnected: (42.3223) Executing "SELECT 'connection_test'" Bindings <> 11:34:13.593 [7920.7924] <2> DbmOdbcConnect::isConnected: SQL_SUCCESS 11:34:13.593 [7920.7924] <2> DbmOdbcView::~DbmOdbcView: (42.3223) Exiting 11:34:13.593 [7920.7924] <2> persistent_queries: Q_IMAGE_LAST_BACKUP 11:34:13.593 [7920.7924] <2> ImageQueryBase::init: Query retry <10>, delay <10> sec 11:34:13.593 [7920.7924] <2> DbmOdbcConnect::setIsolationLevel: Isolation level is already 1 11:34:13.593 [7920.7924] <2> DbmOdbcConnect::beginTransaction: (42) transaction started 11:34:13.593 [7920.7924] <2> ImageLastBackup::executeQuery: Entering 11:34:13.593 [7920.7924] <2> ImageSearch::start: started 11:34:13.593 [7920.7924] <2> CompositeCriterion::get_date_range: no date range out of 3 criteria 11:34:13.593 [7920.7924] <2> CompositeCriterion::get_date_range: no date range out of 2 criteria 11:34:13.593 [7920.7924] <2> CompositeCriterion::get_date_range: no date range out of 11 criteria 11:34:13.593 [7920.7924] <2> CompositeCriterion::get_client_list: no clients requested from 3 criteria 11:34:13.593 [7920.7924] <2> CompositeCriterion::get_client_list: no clients requested from 2 criteria 11:34:13.593 [7920.7924] <2> CompositeCriterion::get_client_list: got 1 clients out of 11 criteria 11:34:13.593 [7920.7924] <2> getMasterServerKey: Cached Key 1000002 for master server kzwnb01.eub.kz 11:34:13.593 [7920.7924] <2> DbmOdbcView::DbmOdbcView: (42.3224) Created 11:34:13.593 [7920.7924] <2> get_emm_machine_conf: (42.3224) Executing "SELECT Value FROM EMM_Main.EMM_MachineConfig WITH (READUNCOMMITTED) WHERE MachineKey = 1000002 AND Name = 'LIST_FS_IMAGE_HEADERS'" Bindings <> 11:34:13.593 [7920.7924] <2> get_emm_machine_conf: SQL_SUCCESS 11:34:13.593 [7920.7924] <2> get_emm_machine_conf: (42.3224) LIST_FS_IMAGE_HEADERS = '0' 11:34:13.593 [7920.7924] <2> DbmOdbcView::~DbmOdbcView: (42.3224) Exiting 11:34:13.593 [7920.7924] <4> FileEnumerator: EMM_Config entry LIST_FS_IMAGE_HEADERS = NO - skipping flat files 11:34:13.593 [7920.7924] <2> CompositeCriterion::get_date_range: no date range out of 3 criteria 11:34:13.593 [7920.7924] <2> CompositeCriterion::get_date_range: no date range out of 2 criteria 11:34:13.593 [7920.7924] <2> CompositeCriterion::get_date_range: no date range out of 11 criteria 11:34:13.593 [7920.7924] <2> getMasterServerKey: Cached Key 1000002 for master server kzwnb01.eub.kz 11:34:13.593 [7920.7924] <2> DbmOdbcView::DbmOdbcView: (42.3225) Created 11:34:13.593 [7920.7924] <2> CompositeCriterion::query_per_client: got result of true out of 11 criteria 11:34:13.593 [7920.7924] <2> CompositeCriterion::get_client_list: no clients requested from 3 criteria 11:34:13.593 [7920.7924] <2> CompositeCriterion::get_client_list: no clients requested from 2 criteria 11:34:13.593 [7920.7924] <2> CompositeCriterion::get_client_list: got 1 clients out of 11 criteria 11:34:13.593 [7920.7924] <2> DBEnumerator: (42.3225) 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 <'infosec.eub.kz'> 11:34:13.593 [7920.7924] <2> DBEnumerator: SQL_SUCCESS 11:34:13.593 [7920.7924] <2> DBEnumerator: (42.3225) ClientKey = '92' 11:34:13.593 [7920.7924] <2> DBEnumerator: (42.3225) No more data for query 11:34:13.593 [7920.7924] <2> DBEnumerator: constructed (0) 11:34:13.593 [7920.7924] <2> DBEnumerator::move_next: (42.3225) Executing "SELECT... FROM DBM_Main.DBM_Image AS Image WHERE Image.MasterServerKey = 1000002 AND Image.ClientKey = ? AND (Image.BackupTime BETWEEN ? AND ?) AND (PolicyName = ?) AND (ScheduleType = 0) AND ((NOT (ImageAttribute >= 200)) OR (ImageAttribute = 201) OR (Image.NumChildren <> 0)) AND (BackupTime <= Expiration OR Expiration = 2147483647) AND (NOT (ImageType = 1)) AND (NOT (ImageType = 4)) AND (Image.RetentionLevel >= 0) AND (ScheduleName = ?) AND ((Image.NumChildren = 0 AND Image.ParentImageKey IS NULL) OR (Image.NumChildren != 0 AND (EXISTS (SELECT A.ImageKey FROM DBM_Image A, DBM_ImageCopy B WHERE A.ParentImageKey = Image.ImageKey AND B.ImageKey = A.ImageKey AND B.DataFormat = 2) OR ((SELECT COUNT(*) FROM DBM_Image C WHERE C.ParentImageKey = Image.ImageKey) = Image.NumChildren)))) ORDER BY Image.BackupTime DESC FOR READ ONLY" Bindings <92, 0, 2147483647, 'Sharepoint_D 11:34:13.593 [7920.7924] <2> DBEnumerator::move_next: IB', 'Full'> 11:34:13.609 [7920.7924] <2> DBEnumerator::move_next: SQL_SUCCESS 11:34:13.609 [7920.7924] <4> fetch_DBM_Images: (.3225) No data found when fetching DBM_Image 11:34:13.609 [7920.7924] <2> DBEnumerator::move_next: Fetched 0 rows this query 11:34:13.609 [7920.7924] <2> DBEnumerator::move_next: Reached the end of the client list 11:34:13.609 [7920.7924] <2> DBEnumerator::move_next: New state: DONE_STATE 11:34:13.609 [7920.7924] <2> FileEnumerator::move_next: New state: DONE_STATE 11:34:13.609 [7920.7924] <2> DBFileMergeEnumerator::move_next: New state: DONE_STATE 11:34:13.609 [7920.7924] <2> DbmOdbcView::~DbmOdbcView: (42.3225) Exiting 11:34:13.609 [7920.7924] <2> DbmOdbcConnect::commitTransaction: (42) success - view count 0 11:34:13.609 [7920.7924] <2> persistent_queries: request complete: exit status 227 no entity was found; query type: 82 11:34:13.609 [7920.7924] <2> read_next_image: calling db_IMAGEreceive() for next image 11:34:13.625 [7920.7924] <2> db_logimagerec: protocol version 11 qtype 82 vers 0 client infosec.eub.kz policy Sharepoint_DIB 11:34:13.625 [7920.7924] <2> db_logimagerec: sched type INCR (1) 11:34:13.625 [7920.7924] <2> db_logimagerec: sched label Differential-Inc 11:34:13.625 [7920.7924] <2> db_logimagerec: options 0x8002000 compress 0 encrypt 0 Kbytes 0 11:34:13.625 [7920.7924] <2> db_logimagerec: backup copy: Standard (0) 11:34:13.625 [7920.7924] <2> db_logimagerec: is quiesced 1 11:34:13.625 [7920.7924] <2> db_logimagerec: slp operations mask 255 11:34:13.625 [7920.7924] <2> db_logimagerec: is scheduled 1 11:34:13.625 [7920.7924] <2> DbmOdbcView::DbmOdbcView: (42.3226) Created 11:34:13.625 [7920.7924] <2> DbmOdbcConnect::isConnected: (42.3226) Executing "SELECT 'connection_test'" Bindings <> 11:34:13.625 [7920.7924] <2> DbmOdbcConnect::isConnected: SQL_SUCCESS 11:34:13.625 [7920.7924] <2> DbmOdbcView::~DbmOdbcView: (42.3226) Exiting 11:34:13.625 [7920.7924] <2> persistent_queries: Q_IMAGE_LAST_BACKUP 11:34:13.625 [7920.7924] <2> ImageQueryBase::init: Query retry <10>, delay <10> sec 11:34:13.625 [7920.7924] <2> DbmOdbcConnect::setIsolationLevel: Isolation level is already 1 11:34:13.625 [7920.7924] <2> DbmOdbcConnect::beginTransaction: (42) transaction started 11:34:13.625 [7920.7924] <2> ImageLastBackup::executeQuery: Entering 11:34:13.625 [7920.7924] <2> ImageSearch::start: started 11:34:13.625 [7920.7924] <2> CompositeCriterion::get_date_range: no date range out of 3 criteria 11:34:13.625 [7920.7924] <2> CompositeCriterion::get_date_range: no date range out of 2 criteria 11:34:13.625 [7920.7924] <2> CompositeCriterion::get_date_range: no date range out of 11 criteria 11:34:13.625 [7920.7924] <2> CompositeCriterion::get_client_list: no clients requested from 3 criteria 11:34:13.625 [7920.7924] <2> CompositeCriterion::get_client_list: no clients requested from 2 criteria 11:34:13.625 [7920.7924] <2> CompositeCriterion::get_client_list: got 1 clients out of 11 criteria 11:34:13.625 [7920.7924] <2> getMasterServerKey: Cached Key 1000002 for master server kzwnb01.eub.kz 11:34:13.625 [7920.7924] <2> DbmOdbcView::DbmOdbcView: (42.3227) Created 11:34:13.625 [7920.7924] <2> get_emm_machine_conf: (42.3227) Executing "SELECT Value FROM EMM_Main.EMM_MachineConfig WITH (READUNCOMMITTED) WHERE MachineKey = 1000002 AND Name = 'LIST_FS_IMAGE_HEADERS'" Bindings <> 11:34:13.625 [7920.7924] <2> get_emm_machine_conf: SQL_SUCCESS 11:34:13.625 [7920.7924] <2> get_emm_machine_conf: (42.3227) LIST_FS_IMAGE_HEADERS = '0' 11:34:13.625 [7920.7924] <2> DbmOdbcView::~DbmOdbcView: (42.3227) Exiting 11:34:13.625 [7920.7924] <4> FileEnumerator: EMM_Config entry LIST_FS_IMAGE_HEADERS = NO - skipping flat files 11:34:13.625 [7920.7924] <2> CompositeCriterion::get_date_range: no date range out of 3 criteria 11:34:13.625 [7920.7924] <2> CompositeCriterion::get_date_range: no date range out of 2 criteria 11:34:13.625 [7920.7924] <2> CompositeCriterion::get_date_range: no date range out of 11 criteria 11:34:13.625 [7920.7924] <2> getMasterServerKey: Cached Key 1000002 for master server kzwnb01.eub.kz 11:34:13.625 [7920.7924] <2> DbmOdbcView::DbmOdbcView: (42.3228) Created 11:34:13.625 [7920.7924] <2> CompositeCriterion::query_per_client: got result of true out of 11 criteria 11:34:13.625 [7920.7924] <2> CompositeCriterion::get_client_list: no clients requested from 3 criteria 11:34:13.625 [7920.7924] <2> CompositeCriterion::get_client_list: no clients requested from 2 criteria 11:34:13.625 [7920.7924] <2> CompositeCriterion::get_client_list: got 1 clients out of 11 criteria 11:34:13.625 [7920.7924] <2> DBEnumerator: (42.3228) 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 <'infosec.eub.kz'> 11:34:13.625 [7920.7924] <2> DBEnumerator: SQL_SUCCESS 11:34:13.625 [7920.7924] <2> DBEnumerator: (42.3228) ClientKey = '92' 11:34:13.625 [7920.7924] <2> DBEnumerator: (42.3228) No more data for query 11:34:13.625 [7920.7924] <2> DBEnumerator: constructed (0) 11:34:13.625 [7920.7924] <2> DBEnumerator::move_next: (42.3228) Executing "SELECT... FROM DBM_Main.DBM_Image AS Image WHERE Image.MasterServerKey = 1000002 AND Image.ClientKey = ? AND (Image.BackupTime BETWEEN ? AND ?) AND (PolicyName = ?) AND (ScheduleType = 1) AND ((NOT (ImageAttribute >= 200)) OR (ImageAttribute = 201) OR (Image.NumChildren <> 0)) AND (BackupTime <= Expiration OR Expiration = 2147483647) AND (NOT (ImageType = 1)) AND (NOT (ImageType = 4)) AND (Image.RetentionLevel >= 0) AND (ScheduleName = ?) AND ((Image.NumChildren = 0 AND Image.ParentImageKey IS NULL) OR (Image.NumChildren != 0 AND (EXISTS (SELECT A.ImageKey FROM DBM_Image A, DBM_ImageCopy B WHERE A.ParentImageKey = Image.ImageKey AND B.ImageKey = A.ImageKey AND B.DataFormat = 2) OR ((SELECT COUNT(*) FROM DBM_Image C WHERE C.ParentImageKey = Image.ImageKey) = Image.NumChildren)))) ORDER BY Image.BackupTime DESC FOR READ ONLY" Bindings <92, 0, 2147483647, 'Sharepoint_D 11:34:13.625 [7920.7924] <2> DBEnumerator::move_next: IB', 'Differential-Inc'> 11:34:13.625 [7920.7924] <2> DBEnumerator::move_next: SQL_SUCCESS 11:34:13.625 [7920.7924] <4> fetch_DBM_Images: (.3228) No data found when fetching DBM_Image 11:34:13.625 [7920.7924] <2> DBEnumerator::move_next: Fetched 0 rows this query 11:34:13.625 [7920.7924] <2> DBEnumerator::move_next: Reached the end of the client list 11:34:13.625 [7920.7924] <2> DBEnumerator::move_next: New state: DONE_STATE 11:34:13.625 [7920.7924] <2> FileEnumerator::move_next: New state: DONE_STATE 11:34:13.625 [7920.7924] <2> DBFileMergeEnumerator::move_next: New state: DONE_STATE 11:34:13.625 [7920.7924] <2> DbmOdbcView::~DbmOdbcView: (42.3228) Exiting 11:34:13.625 [7920.7924] <2> DbmOdbcConnect::commitTransaction: (42) success - view count 0 11:34:13.625 [7920.7924] <2> persistent_queries: request complete: exit status 227 no entity was found; query type: 82 11:34:14.030 [7180.7184] <2> listen_loop: socket fd from accept() is 804 11:34:14.030 [7180.7184] <2> listen_loop: Locale info: message=RUS, ctype=RUS, time=RUS, collate=RUS, num=RUS 11:34:14.124 [9252.2864] <2> read_debug_clients: unable to open config file 'C:\Program Files\Veritas\NetBackup\db\config\CLIENT_DEBUG_LIST' 11:34:14.124 [9252.2864] <4> RedirectNBLoggerToLegacyLog: Setting up redirection of VxUL messages to legacy log 11:34:14.124 [9252.2864] <4> RedirectNBLoggerToLegacyLog: Verbosity from VxUL configuration: 6 11:34:14.124 [9252.2864] <4> RedirectNBLoggerToLegacyLog: logmsg verbosity set: 6 11:34:14.124 [9252.2864] <2> GetParentProcessHandle: OpenProcess(7180) failed: 5. Attempting token adjustment... 11:34:14.124 [9252.2864] <2> GetParentProcessHandle: Got process handle after enabling debug privilege 11:34:14.139 [9252.2864] <2> logconnections: BPDBM ACCEPT FROM 172.20.2.42.54122 TO 172.20.2.42.13721 fd = 804 11:34:14.139 [9252.2864] <2> init_resilient_cache: [vnet_nbrntd.c:867] Initialize resilient cache. 0 0x0 11:34:14.139 [9252.2864] <2> vnet_pcache_init_table: [vnet_private.c:212] starting cache size 200 0xc8 11:34:14.139 [9252.2864] <2> vnet_cached_getnameinfo: [vnet_addrinfo.c:1922] found via getnameinfo OUR_HOST=KZWNB01.eub.kz IPSTR=172.20.2.42 11:34:14.139 [9252.2864] <4> bpdbm: VERBOSE = 5 11:34:14.139 [9252.2864] <2> db_getrequest_vxss: Entering 11:34:14.139 [9252.2864] <2> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:506] VxSS magic=1041669, remote_vxss=71 11:34:14.139 [9252.2864] <2> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:563] Ignoring VxSS authentication 2 0x2 11:34:14.139 [9252.2864] <2> image_db: Entering 11:34:14.139 [9252.2864] <2> db_logimagerec: protocol version 11 qtype 71 vers 0 client infosec.eub.kz policy Sharepoint_DIB 11:34:14.139 [9252.2864] <2> db_logimagerec: backup time 04/04/16 11:34:14 (1459748054) 11:34:14.139 [9252.2864] <2> db_logimagerec: sched type FULL (0) 11:34:14.139 [9252.2864] <2> db_logimagerec: backup id infosec.eub.kz_1459748054 11:34:14.139 [9252.2864] <2> db_logimagerec: client type 8 ret level 1 elapsed 0 sec 11:34:14.139 [9252.2864] <2> db_logimagerec: sched label Full 11:34:14.139 [9252.2864] <2> db_logimagerec: creator root 11:34:14.139 [9252.2864] <2> db_logimagerec: Scheduler pid 7680 11:34:14.139 [9252.2864] <2> db_logimagerec: backup copy: Standard (0) 11:34:14.139 [9252.2864] <2> db_logimagerec: job id: 403924 num resumes 0 resume expiration 01/01/70 06:00:00 (0) 11:34:14.139 [9252.2864] <2> db_logimagerec: image_attrib 201 11:34:14.139 [9252.2864] <2> db_logimagerec: run time 1459748054 11:34:14.139 [9252.2864] <2> db_logimagerec: last birthtime 1459748054 11:34:14.139 [9252.2864] <2> db_logimagerec: bmr info 1 11:34:14.139 [9252.2864] <2> db_logimagerec: is quiesced 1 11:34:14.139 [9252.2864] <2> db_logimagerec: slp operations mask 255 11:34:14.139 [9252.2864] <2> db_logimagerec: is scheduled 1 11:34:14.139 [9252.2864] <2> image_db: Q_IMAGE_NEW 11:34:14.139 [9252.2864] <2> ImageQueryBase::init: Query retry <10>, delay <10> sec 11:34:14.139 [9252.2864] <2> DbmOdbcConnect::DbmOdbcConnect: ENTERING 11:34:14.202 [9252.2864] <2> DbmOdbcConnect::initialize_odbc: Connecting with 11:34:14.202 [9252.2864] <2> DbmOdbcConnect::setIsolationLevel: (-1) Isolation Level=<4> (REPEATABLE_READ) 11:34:14.202 [9252.2864] <2> DbmOdbcConnect::beginTransaction: (-1) transaction started 11:34:14.202 [9252.2864] <2> ImageNew::ImageNew: Entering 11:34:14.202 [9252.2864] <2> parse_backup_id: parsing backupid = infosec.eub.kz_1459748054 11:34:14.202 [9252.2864] <2> ImageNew::executeQuery: Entering 11:34:14.202 [9252.2864] <2> DbmOdbcView::DbmOdbcView: (-1.1) Executing "SELECT @@SPID" Bindings <> 11:34:14.202 [9252.2864] <2> DbmOdbcView::DbmOdbcView: SQL_SUCCESS 11:34:14.202 [9252.2864] <2> DbmOdbcView::DbmOdbcView: (-1.1) ConnectionId = '286803' 11:34:14.202 [9252.2864] <2> DbmOdbcConnect::set_connection_id: -1 -> 286803 11:34:14.202 [9252.2864] <2> DbmOdbcView::DbmOdbcView: (286803.1) Created 11:34:14.202 [9252.2864] <2> build_DBM_Unique_Image_Info(imageRec): Entering 11:34:14.202 [9252.2864] <2> parse_backup_id: parsing backupid = infosec.eub.kz_1459748054 11:34:14.202 [9252.2864] <2> DbmOdbcView::DbmOdbcView: (286803.2) Created 11:34:14.202 [9252.2864] <2> queryMachineKey: (286803.2) 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 <'kzwnb01.eub.kz'> 11:34:14.202 [9252.2864] <2> queryMachineKey: SQL_SUCCESS 11:34:14.202 [9252.2864] <2> queryMachineKey: (286803.2) key for kzwnb01.eub.kz MachineType 3 = '1000002' 11:34:14.202 [9252.2864] <2> getMasterServerKey: Got machine key 1000002 for master server kzwnb01.eub.kz 11:34:14.202 [9252.2864] <2> DbmOdbcView::~DbmOdbcView: (286803.2) Exiting 11:34:14.202 [9252.2864] <2> DbmImage::get_image_key: Entering 11:34:14.202 [9252.2864] <2> DbmImage::get_DBM_ImageKey: (286803.1) Executing "SELECT ImageKey FROM DBM_Main.DBM_Image Image WITH (XLOCK) LEFT OUTER JOIN DBM_Main.DBM_ClientAlias Alias ON Alias.ClientKey = Image.ClientKey WHERE (Image.MasterServerKey = ?) AND ((Alias.MasterServerKey = Image.MasterServerKey) OR (Alias.MasterServerKey IS NULL)) AND ((Alias.ClientAliasName = LOWER(?)) OR (Alias.ClientAliasName IS NULL AND Image.ClientMachineName = ?)) AND (BackupTime = ?) AND (ViewID = ?)" Bindings <1000002, 'infosec.eub.kz', 'infosec.eub.kz', 1459748054, 0> 11:34:14.202 [9252.2864] <2> DbmImage::get_DBM_ImageKey: SQL_SUCCESS 11:34:14.202 [9252.2864] <2> DbmImage::get_DBM_ImageKey: (286803.1) No more data for query 11:34:14.202 [9252.2864] <4> DbmImage::get_DBM_ImageKey: MasterServerKey = < 1000002 > ClientMachineName = < infosec.eub.kz > BackupTime = < 1459748054 > ViewID = < 0 > 11:34:14.202 [9252.2864] <2> getMasterServerKey: Cached Key 1000002 for master server kzwnb01.eub.kz 11:34:14.202 [9252.2864] <2> DbmOdbcView::DbmOdbcView: (286803.3) Created 11:34:14.202 [9252.2864] <2> get_emm_machine_conf: (286803.3) Executing "SELECT Value FROM EMM_Main.EMM_MachineConfig WITH (READUNCOMMITTED) WHERE MachineKey = 1000002 AND Name = 'LIST_FS_IMAGE_HEADERS'" Bindings <> 11:34:14.202 [9252.2864] <2> get_emm_machine_conf: SQL_SUCCESS 11:34:14.202 [9252.2864] <2> get_emm_machine_conf: (286803.3) LIST_FS_IMAGE_HEADERS = '0' 11:34:14.202 [9252.2864] <2> DbmOdbcView::~DbmOdbcView: (286803.3) Exiting 11:34:14.202 [9252.2864] <2> ImageNew::executeQuery: No need to do PREV_BACKUP_IMAGE process 11:34:14.202 [9252.2864] <2> DbmOdbcConnect::setIsolationLevel: Isolation level is already 4 11:34:14.202 [9252.2864] <2> getMasterServerKey: Cached Key 1000002 for master server kzwnb01.eub.kz 11:34:14.202 [9252.2864] <2> DbmClient::get_client_key: Entering 11:34:14.202 [9252.2864] <2> DbmOdbcView::DbmOdbcView: (286803.4) Created 11:34:14.202 [9252.2864] <2> DbmClient::get_client_key: (286803.4) Executing "SELECT ClientKey FROM DBM_Main.DBM_Client WHERE MasterServerKey = ? AND UPPER(ClientMachineName) = UPPER(?) FOR READ ONLY" Bindings <1000002, 'infosec.eub.kz'> 11:34:14.202 [9252.2864] <2> DbmClient::get_client_key: SQL_SUCCESS 11:34:14.202 [9252.2864] <2> DbmClient::get_client_key: (286803.4) ClientKey = '92' 11:34:14.202 [9252.2864] <2> DbmClient::insert_DBM_ClientAlias: Entering 11:34:14.202 [9252.2864] <2> getMasterServerKey: Cached Key 1000002 for master server kzwnb01.eub.kz 11:34:14.202 [9252.2864] <2> DbmClient::insert_DBM_ClientAlias: (286803.4) Executing "INSERT INTO DBM_Main.DBM_ClientAlias (MasterServerKey, ClientAliasName, ClientKey) VALUES (?, ?, ?)" Bindings <1000002, 'infosec.eub.kz', 92> 11:34:14.202 [9252.2864] <2> DbmClient::insert_DBM_ClientAlias: SQL_INDEX_NOT_UNIQUE 11:34:14.202 [9252.2864] <4> DbmClient::insert_DBM_ClientAlias: ClientAlias already exists (name = infosec.eub.kz) (key = 92) 11:34:14.202 [9252.2864] <2> DbmOdbcConnect::commitTransaction: (286803) success - view count 2 11:34:14.202 [9252.2864] <2> DbmOdbcConnect::beginTransaction: (286803) transaction started 11:34:14.202 [9252.2864] <2> DbmOdbcConnect::setIsolationLevel: Isolation level is already 4 11:34:14.202 [9252.2864] <2> DbmOdbcView::~DbmOdbcView: (286803.4) Exiting 11:34:14.202 [9252.2864] <2> build_DBM_Unique_Image_Info(imageRec): Entering 11:34:14.202 [9252.2864] <2> parse_backup_id: parsing backupid = infosec.eub.kz_1459748054 11:34:14.202 [9252.2864] <2> getMasterServerKey: Cached Key 1000002 for master server kzwnb01.eub.kz 11:34:14.202 [9252.2864] <2> insert_DBM_Image: Entering 11:34:14.202 [9252.2864] <2> insert_DBM_Image: (286803.1) Executing "INSERT INTO DBM_Main.DBM_Image (ClientKey, MasterServerKey, ClientMachineName, PolicyName, BackupTime, ViewID, ScheduleType, ClientType, ProtocolVersion, StartTime, EndTime, SnapTime, KiloBytes, NumberFragments, NumberCopies, NumberSnapshotDevices, ImageVersion, RetentionLevel, Compression, Encryption, FilesFileCompressed, Mpx, TirInfo, TirExpiration, PrimaryCopy, ImageType, ElapsedTime, Expiration, NumberFiles, ExtSecInfo, RequestPID, IndividualFileRestoreFromRaw, ImageDumpLevel, FileSystemOnly, PrevBlkIncrTime, BlkIncrFullTime, StreamNumber, BackupCopy, BackupStatus, JobID, NumResumes, ResumeExpiration, PfiType, ImageAttribute, Creator, ScheduleName, ProxyClient, KeywordPhrase, FilesFile, FilesFileSize, SoftwareVersion, ObjectDescriptor, EstimatedKiloBytes, VmType, IsSynthetic, RunTime, LastBirthTime, ClientCharacterSet, BmrInfo, OriginMasterServer, StorageServiceN 11:34:14.202 [9252.2864] <2> insert_DBM_Image: ame, StorageServiceState, StorageServiceIndexOffset, TimeInProcess, ClassificationID, StorageServiceVersionNumber, RequiredExpirationDate, ImportFromReplicaTime, IrEnabled, IsQuiesced, SLPOperationsMask, IsScheduled, HasDependees, HasDependents, ValidationCount, ExpirationCount, IndexingStatus) VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?)" Bindings <92, 1000002, 'infosec.eub.kz', 'Sharepoint_DIB', 1459748054, 0, 0, 8, 11, 0, 0, 0, 0, 0, 0, 0, 10, 1, 0, 0, 0, 0, 16, 0, 0, 0, 0, 0, 0, 0, 7680, 0, 0, 0, 0, 0, -1, 0, 0, 403924, 0, 0, 0, 201, 'root', 'Full', '', '', 'Sharepoint_DIB_1459748054_FULL.f', 0, '', '', 0, 0, 0, 1459748054, 1459748054, 0, 1, '', '', 0, 0, 0, '', 0, 0, 0, 0, 1, 255, 1, 0, 0, 0, 11:34:14.202 [9252.2864] <2> insert_DBM_Image: 0, 0> 11:34:14.217 [9252.2864] <2> insert_DBM_Image: SQL_SUCCESS 11:34:14.217 [9252.2864] <2> insert_DBM_Image: MasterServerKey = < 1000002 > PreviousBackupImageKey = < 0 > ParentImageKey = < 0 > CatArcImageKey = < 0 > FullBackupImageKey = < 0 > CSScheduleKey = < 0 > ClientMachineName = < infosec.eub.kz > ClientKey = < 92 > PolicyName = < Sharepoint_DIB > BackupTime = < 1459748054 > ViewID = < 0 > ScheduleType = < 0 > ClientType = < 8 > ProtocolVersion = < 11 > StartTime = < 0 > EndTime = < 0 > SnapTime = < 0 > KiloBytes = < 0 > NumberFragments = < 0 > NumberCopies = < 0 > NumberSnapshotDevices = < 0 > ImageVersion = < 10 > RetentionLevel = < 1 > Compression = < 0 > Encryption = < 0 > FilesFileCompressed = < 0 > Mpx = < 0 > TirInfo = < 16 > TirExpiration = < 0 > PrimaryCopy = < 0 > ImageType = < 0 > ElapsedTime = < 0 > Expiration = < 0 > NumberFiles = < 0 > ExtSecInfo = < 0 > RequestPID = < 7680 > IndividualFileRestoreFromRaw = < 0 > ImageDumpLevel = < 0 > Fi 11:34:14.217 [9252.2864] <2> insert_DBM_Image: leSystemOnly = < 0 > PrevBlkIncrTime = < 0 > BlkIncrFullTime = < 0 > StreamNumber = < -1 > BackupCopy = < 0 > BackupStatus = < 0 > JobID = < 403924 > NumResumes = < 0 > ResumeExpiration = < 0 > PfiType = < 0 > ImageAttribute = < 201 > Creator = < root > ScheduleName = < Full > ProxyClient = < NULL > KeywordPhrase = < NULL > FilesFile = < Sharepoint_DIB_1459748054_FULL.f > FilesFileSize = < 0 > SoftwareVersion = < NULL > ObjectDescriptor = < NULL > EstimatedKiloBytes = < 0 > VmType = < 0 > IsSynthetic = < 0 > RunTime = < 1459748054 > LastBirthTime = < 1459748054 > ClientCharacterSet = < 0 > BmrInfo = < 1 > OriginMasterServer = < NULL > OriginMasterServerID = < NULL > StorageServiceName = < NULL > StorageServiceState = < 0 > StorageServiceIndexOffset = < 0 > TimeInProcess = < 0 > ClassificationID = < NULL > StorageServiceVersionNumber = < 0 > RequiredExpira 11:34:14.217 [9252.2864] <2> insert_DBM_Image: tionDate = < 0 > ImportFromReplicaTime = < 0 > IrEnabled = < 0 > IsQuiesced = < 1 > SLPOperationsMask = < 255 > IsScheduled = < 1 > HasDependees = < 0 > HasDependents = < 0 > ValidationCount = < 0 > ExpirationCount = < 0 > IndexingStatus = < 0 > 11:34:14.217 [9252.2864] <2> insert_DBM_Image: (286803.1) Executing "SELECT @@Identity" Bindings <> 11:34:14.217 [9252.2864] <2> insert_DBM_Image: SQL_SUCCESS 11:34:14.217 [9252.2864] <2> insert_DBM_Image: (286803.1) imageKey = '399103' 11:34:14.217 [9252.2864] <2> insert_image_rec_into_NBDB: insert_DBM_Image returns imageKey = 399103 11:34:14.217 [9252.2864] <2> ImageNew::executeQuery: insert_image_rec_into_NBDB returns imageKey = 399103 11:34:14.233 [9252.2864] <2> lock_file_by_path: Acquired db_Imglock(WRITE) 780 in C:\Program Files\Veritas\NetBackup\db\images\infosec.eub.kz\1459000000\Sharepoint_DIB_1459748054_FULL.lck 11:34:14.233 [9252.2864] <2> ImageNew::executeQuery: Deleting C:\Program Files\Veritas\NetBackup\db\images\infosec.eub.kz\1459000000\Sharepoint_DIB_1459748054_FULL.f 11:34:14.233 [9252.2864] <2> db_ImgUnlock: db_ImgUnlock(780) unlocked 11:34:14.233 [9252.2864] <2> ImageNew::~ImageNew: Exiting 11:34:14.233 [9252.2864] <2> DbmOdbcView::~DbmOdbcView: (286803.1) Exiting 11:34:14.233 [9252.2864] <2> DbmOdbcConnect::commitTransaction: (286803) success - view count 0 11:34:14.233 [9252.2864] <2> DbmOdbcConnect::~DbmOdbcConnect: (286803) Exiting 11:34:14.264 [9252.2864] <2> process_request: request complete: exit status 0 ; query type: 71 11:34:16.230 [7180.7184] <2> listen_loop: socket fd from accept() is 804 11:34:16.230 [7180.7184] <2> listen_loop: Locale info: message=RUS, ctype=RUS, time=RUS, collate=RUS, num=RUS 11:34:16.292 [8800.8076] <2> read_debug_clients: unable to open config file 'C:\Program Files\Veritas\NetBackup\db\config\CLIENT_DEBUG_LIST' 11:34:16.292 [8800.8076] <4> RedirectNBLoggerToLegacyLog: Setting up redirection of VxUL messages to legacy log 11:34:16.308 [8800.8076] <4> RedirectNBLoggerToLegacyLog: Verbosity from VxUL configuration: 6 11:34:16.308 [8800.8076] <4> RedirectNBLoggerToLegacyLog: logmsg verbosity set: 6 11:34:16.308 [8800.8076] <2> GetParentProcessHandle: OpenProcess(7180) failed: 5. Attempting token adjustment... 11:34:16.308 [8800.8076] <2> GetParentProcessHandle: Got process handle after enabling debug privilege 11:34:16.308 [7180.7184] <2> listen_loop: socket fd from accept() is 804 11:34:16.308 [7180.7184] <2> listen_loop: Locale info: message=RUS, ctype=RUS, time=RUS, collate=RUS, num=RUS 11:34:16.308 [8800.8076] <2> logconnections: BPDBM ACCEPT FROM 172.20.2.42.54131 TO 172.20.2.42.13721 fd = 804 11:34:16.308 [8800.8076] <2> init_resilient_cache: [vnet_nbrntd.c:867] Initialize resilient cache. 0 0x0 11:34:16.308 [8800.8076] <2> vnet_pcache_init_table: [vnet_private.c:212] starting cache size 200 0xc8 11:34:16.308 [8800.8076] <2> vnet_cached_getnameinfo: [vnet_addrinfo.c:1922] found via getnameinfo OUR_HOST=KZWNB01.eub.kz IPSTR=172.20.2.42 11:34:16.308 [8800.8076] <4> bpdbm: VERBOSE = 5 11:34:16.308 [8800.8076] <2> db_getrequest_vxss: Entering 11:34:16.308 [8800.8076] <2> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:506] VxSS magic=1041669, remote_vxss=67 11:34:16.308 [8800.8076] <2> vnet_check_vxss_server_magic: [vnet_vxss_helper.c:563] Ignoring VxSS authentication 2 0x2 11:34:16.308 [8800.8076] <2> image_db: Entering 11:34:16.308 [8800.8076] <2> db_logimagerec: protocol version 11 qtype 67 vers 0 client kzsbpm2rdbms policy ? 11:34:16.308 [8800.8076] <2> db_logimagerec: date1 12/26/95 06:00:00 (819936000) 11:34:16.308 [8800.8076] <2> db_logimagerec: date2 04/05/16 11:34:11 (1459834451) 11:34:16.308 [8800.8076] <2> db_logimagerec: sched type FULL (0) 11:34:16.308 [8800.8076] <2> db_logimagerec: name1 /1lr1j6nn_1_2 11:34:16.308 [8800.8076] <2> db_logimagerec: client type 4 ret level 0 elapsed 0 sec 11:34:16.308 [8800.8076] <2> db_logimagerec: backup copy: Standard (0) 11:34:16.308 [8800.8076] <2> db_logimagerec: is quiesced 1 11:34:16.308 [8800.8076] <2> db_logimagerec: slp operations mask 255 11:34:16.308 [8800.8076] <2> db_logimagerec: is scheduled 1 11:34:16.308 [8800.8076] <2> image_db: Q_IMAGE_BY_FILE 11:34:16.308 [8800.8076] <2> ImageQueryBase::init: Query retry <10>, delay <10> sec 11:34:16.308 [8800.8076] <2> DbmOdbcConnect::DbmOdbcConnect: ENTERING