***************************************************************************** ***** SGMon log for \\\\EMDSERVER ***** ***** Local Offset =12:00 ***** ***************************************************************************** MANAGEME: [11/21/13 06:42:25] [0004] [Error] Exception caught in RSSService:GetLatest() failed to load feed: http://www.symantec.com/connect/item-feeds/blog/8361/feed/all/all Exception:System.Net.WebException The remote name could not be resolved: 'www.symantec.com' Stack Trace: at System.Net.HttpWebRequest.GetResponse() at BackupExec.UI.ViewModel.RssService.GetLatest() MANAGEME: [11/21/13 06:42:25] [0025] [Error] Exception caught in RSSService:GetLatest() failed to load feed: http://www.symantec.com/connect/item-feeds/sc_forum/711%2C1383/feed/all/all Exception:System.Net.WebException The remote name could not be resolved: 'www.symantec.com' Stack Trace: at System.Net.HttpWebRequest.GetResponse() at BackupExec.UI.ViewModel.RssService.GetLatest() MANAGEME: [11/21/13 06:42:25] [0016] [Error] Exception caught in RSSService:GetLatest() failed to load feed: http://www.symantec.com/connect/fr/item-feeds/event/711/feed/all/all Exception:System.Net.WebException The remote name could not be resolved: 'www.symantec.com' Stack Trace: at System.Net.HttpWebRequest.GetResponse() at BackupExec.UI.ViewModel.RssService.GetLatest() MANAGEME: [11/21/13 06:42:25] [0026] [Error] Exception caught in RSSService:GetLatest() failed to load feed: http://www.symantec.com/connect/item-feeds/idea/711%2C1383/feed/all/all Exception:System.Net.WebException The remote name could not be resolved: 'www.symantec.com' Stack Trace: at System.Net.HttpWebRequest.GetResponse() at BackupExec.UI.ViewModel.RssService.GetLatest() MANAGEME: [11/21/13 06:42:30] [0016] 11/21 06:42:30.117[MetaData ] AllocateMetaDataConnection : 544589520 MANAGEME: [11/21/13 06:42:30] [4684] BECAT : [3576]:[5]: odbcConn (000000002075C6D0) MANAGEME: [11/21/13 06:42:30] [4684] BECAT : [3576]:[5]: odbcConn (000000002075C6D0) - gain lock MANAGEME: [11/21/13 06:42:30] [4684] BECAT : [3576]:[5]: odbcConn (000000002075C6D0) - add as new connection MANAGEME: [11/21/13 06:42:30] [4684] BECAT : [3576]:[5]: odbcConn (000000002075C6D0) - done MANAGEME: [11/21/13 06:42:30] [4684] BECAT : [3576]:[5]: BindColumns : maxRowCount = 100, RowSize = 16 MANAGEME: [11/21/13 06:42:30] [4684] BECAT : [3576]:[5]: SQL connection: 29338fe0 select * from ControlInfo MANAGEME: [11/21/13 06:42:30] [4684] BECAT : [3576]:[4]: Input Parameters for RestoreType_View : [11](16777216),[3](EMDServer.primarycare.local) MANAGEME: [11/21/13 06:42:30] [4684] BECAT : [3576]:[4]: Select * from dbo.[CAT_fGetRestoreTypeForRestoreTypeView]( EMDServer.primarycare.local, EMDServer, EMDServer.primarycare.local, 0 ) MANAGEME: [11/21/13 06:42:30] [4684] BECAT : [3576]:[5]: SQL connection: 29338fe0 Select * from dbo.[CAT_fGetRestoreTypeForRestoreTypeView]( ?, ?, ?, ? ) MANAGEME: [11/21/13 06:42:30] [4684] BECAT : [3576]:[4]: result(0) numRetry(300) MANAGEME: [11/21/13 06:42:30] [4684] BECAT : [3576]:[5]: GetRecordCount MANAGEME: [11/21/13 06:42:30] [4684] BECAT : [3576]:[5]: GetRecordCount MANAGEME: [11/21/13 06:42:30] [4684] BECAT : [3576]:[4]: Number of records returned: 3 MANAGEME: [11/21/13 06:42:30] [4684] BECAT : [3576]:[5]: GetRecordCount MANAGEME: [11/21/13 06:42:30] [4684] BECAT : [3576]:[5]: GetRecordCount MANAGEME: [11/21/13 06:42:30] [4684] BECAT : [3576]:[4]: InOut Parameters for RestoreType_View : No values MANAGEME: [11/21/13 06:42:30] [4684] BECAT : [3576]:[4]: Output Parameters for RestoreType_View : No values MANAGEME: [11/21/13 06:42:30] [4684] BECAT : [3576]:[5]: m_sqlqueryTTL( 0 ) MANAGEME: [11/21/13 06:42:30] [4684] BECAT : [3576]:[5]: return 0 MANAGEME: [11/21/13 06:42:30] [4684] BECAT : [3576]:[5]: Current row (0) : [12](1),[3](14) MANAGEME: [11/21/13 06:42:30] [4684] BECAT : [3576]:[5]: Current row (1) : [12](2),[3](43) MANAGEME: [11/21/13 06:42:30] [4684] BECAT : [3576]:[5]: Current row (2) : [12](3),[3](59) MANAGEME: [11/21/13 06:42:30] [4684] BECAT : [3576]:[4]: SQLQuery::RowCount(3) for RestoreType_View MANAGEME: [11/21/13 06:42:30] [4684] BECAT : [3576]:[3]: SQLQuery (RestoreType_View) Time Spend = 0 ms - ReturnedCount (3) MANAGEME: [11/21/13 06:42:30] [0010] 11/21 06:42:30.217[MetaData ] QueryMetaData : 544589520 MANAGEME: [11/21/13 06:42:30] [0010] 11/21 06:42:30.217[MetaData ] QueryMetaData starts: MDQ_RestoreType_View MANAGEME: [11/21/13 06:42:30] [0010] 11/21 06:42:30.217[MetaData ] MetaDataQuery ResourceContainerID is passed, need to query database MANAGEME: [11/21/13 06:42:30] [0010] 11/21 06:42:30.221[MetaData ] QueryMetaData ends: MDQ_RestoreType_View rc=0 numrows=3 BESERVER: [11/21/13 06:42:43] [5940] -1 SecurityBO: Successfully setup RAWS certs. BESERVER: [11/21/13 06:42:56] [3572] -1 SetServiceStatus: state: SERVICE_RUNNING check point: 0 wait hint: 0 BENETNS: [11/21/13 06:43:29] [4084] 2013-11-21T06:43:29.134 - Beginning agent database grooming. BENETNS: [11/21/13 06:43:29] [4084] 2013-11-21T06:43:29.134 - Agent database grooming complete. BESERVER: [11/21/13 06:43:41] [3572] -1 SetServiceStatus: state: SERVICE_RUNNING check point: 0 wait hint: 0 BESERVER: [11/21/13 06:43:43] [5872] -1 SecurityBO: Successfully setup RAWS certs. MMC: [11/21/13 06:44:18] [0000] 4900: [CBS] IsCacheStillGood: True. BESERVER: [11/21/13 06:44:26] [3572] -1 SetServiceStatus: state: SERVICE_RUNNING check point: 0 wait hint: 0 BESERVER: [11/21/13 06:44:44] [1568] -1 SecurityBO: Successfully setup RAWS certs. MANAGEME: [11/21/13 06:44:56] [5688] BECAT : [3576]:[5]: odbcConn (000000002075C6D0) MANAGEME: [11/21/13 06:44:56] [5688] BECAT : [3576]:[5]: odbcConn (000000002075C6D0) - gain lock MANAGEME: [11/21/13 06:44:56] [5688] BECAT : [3576]:[5]: odbcConn (000000002075C6D0) - done MANAGEME: [11/21/13 06:44:56] [5688] BECAT : [3576]:[5]: BindColumns : maxRowCount = 100, RowSize = 8 MANAGEME: [11/21/13 06:44:56] [5688] BECAT : [3576]:[5]: SQL connection: 29338fe0 select * from ControlInfo MANAGEME: [11/21/13 06:44:56] [5688] BECAT : [3576]:[4]: Input Parameters for RestoreSubType_View : [4](3),[3](EMDServer.primarycare.local) MANAGEME: [11/21/13 06:44:56] [5688] BECAT : [3576]:[4]: select * from fCatGetRestoreSubType ( EMDServer.primarycare.local, EMDServer, EMDServer.primarycare.local, 3, 0 ) MANAGEME: [11/21/13 06:44:56] [5688] BECAT : [3576]:[5]: SQL connection: 29338fe0 select * from fCatGetRestoreSubType ( ?, ?, ?, ?, ? ) MANAGEME: [11/21/13 06:44:56] [5688] BECAT : [3576]:[4]: result(0) numRetry(300) MANAGEME: [11/21/13 06:44:56] [5688] BECAT : [3576]:[5]: GetRecordCount MANAGEME: [11/21/13 06:44:56] [5688] BECAT : [3576]:[5]: GetRecordCount MANAGEME: [11/21/13 06:44:56] [5688] BECAT : [3576]:[4]: Number of records returned: 1 MANAGEME: [11/21/13 06:44:56] [5688] BECAT : [3576]:[5]: GetRecordCount MANAGEME: [11/21/13 06:44:56] [5688] BECAT : [3576]:[5]: GetRecordCount MANAGEME: [11/21/13 06:44:56] [5688] BECAT : [3576]:[4]: InOut Parameters for RestoreSubType_View : No values MANAGEME: [11/21/13 06:44:56] [5688] BECAT : [3576]:[4]: Output Parameters for RestoreSubType_View : No values MANAGEME: [11/21/13 06:44:56] [5688] BECAT : [3576]:[5]: m_sqlqueryTTL( 0 ) MANAGEME: [11/21/13 06:44:56] [5688] BECAT : [3576]:[5]: return 0 MANAGEME: [11/21/13 06:44:56] [5688] BECAT : [3576]:[5]: Current row (0) : [13](3) MANAGEME: [11/21/13 06:44:56] [5688] BECAT : [3576]:[4]: SQLQuery::RowCount(1) for RestoreSubType_View MANAGEME: [11/21/13 06:44:56] [5688] BECAT : [3576]:[3]: SQLQuery (RestoreSubType_View) Time Spend = 0 ms - ReturnedCount (1) MANAGEME: [11/21/13 06:44:56] [0024] 11/21 06:44:56.294[MetaData ] QueryMetaData : 544589520 MANAGEME: [11/21/13 06:44:56] [0024] 11/21 06:44:56.294[MetaData ] QueryMetaData starts: MDQ_RestoreSubType_View MANAGEME: [11/21/13 06:44:56] [0024] 11/21 06:44:56.294[MetaData ] MetaDataQuery ResourceContainerID is passed, need to query database MANAGEME: [11/21/13 06:44:56] [0024] 11/21 06:44:56.298[MetaData ] QueryMetaData ends: MDQ_RestoreSubType_View rc=0 numrows=1 MANAGEME: [11/21/13 06:45:03] [0000] Asynchronous task 004ed386-21ee-4e05-88ad-e390826147c1 starting 'QueryMetaDataAsyncWorker' MANAGEME: [11/21/13 06:45:03] [5296] BECAT : [3576]:[5]: odbcConn (000000002075C6D0) MANAGEME: [11/21/13 06:45:03] [5296] BECAT : [3576]:[5]: odbcConn (000000002075C6D0) - gain lock MANAGEME: [11/21/13 06:45:03] [5296] BECAT : [3576]:[5]: odbcConn (000000002075C6D0) - done MANAGEME: [11/21/13 06:45:03] [5296] BECAT : [3576]:[5]: BindColumns : maxRowCount = 500, RowSize = 16464 MANAGEME: [11/21/13 06:45:03] [0027] 11/21 06:45:03.069[MetaData ] QueryMetaDataStreamAsync : clientId(00000000-0000-0000-0000-000000000000) asyncId(004ed386-21ee-4e05-88ad-e390826147c1) MANAGEME: [11/21/13 06:45:03] [0027] 11/21 06:45:03.069[MetaData ] QueryMetaData : 544589520 MANAGEME: [11/21/13 06:45:03] [0027] 11/21 06:45:03.069[MetaData ] QueryMetaData starts: MDQ_Resource_View MANAGEME: [11/21/13 06:45:03] [0027] 11/21 06:45:03.069[MetaData ] MetaDataQuery ResourceContainerID is passed, need to query database MANAGEME: [11/21/13 06:45:03] [5296] BECAT : [3576]:[5]: SQL connection: 29338fe0 select * from ControlInfo MANAGEME: [11/21/13 06:45:03] [5296] BECAT : [3576]:[4]: Input Parameters for Resource_View : [4](3),[5](1),[11](16777216),[3](EMDServer.primarycare.local) MANAGEME: [11/21/13 06:45:03] [5296] BECAT : [3576]:[4]: Select DISTINCT CatResourceNameNode.NodeID, CatResourceNameNode.ParentID, CatResourceNameNode.ResourceID, CatResourceNameNode.Type, CatResourceNameNode.Subtype, CatResourceNameNode.Attribute, CatResourceNameNode.ResourceNameNode, CatResource.OsID, CatResource.OsVer , CatResource.ResourceName as DeviceName from CatResourceNameNode left join CatResource on CatResourceNameNode.ResourceId = CatResource.ResourceId where ParentID in (select DISTINCT NodeID from CatResourceNameNode where (ResourceNameNode LIKE EMDServer.primarycare.local OR ResourceNameNode = EMDServer ) AND ParentID = 0 AND (Type != 22 and Type != 35 and type != 41 and type != 42) ) AND (RestoreType = 3 or RestoreType is NULL ) AND ( Type = 28 OR Type = 15 OR Type = 30 OR Type = 31 ) ORDER BY ResourceNameNode MANAGEME: [11/21/13 06:45:03] [5296] BECAT : [3576]:[5]: SQL connection: 29338fe0 Select DISTINCT CatResourceNameNode.NodeID, CatResourceNameNode.ParentID, CatResourceNameNode.ResourceID, CatResourceNameNode.Type, CatResourceNameNode.Subtype, CatResourceNameNode.Attribute, CatResourceNameNode.ResourceNameNode, CatResource.OsID, CatResource.OsVer , CatResource.ResourceName as DeviceName from CatResourceNameNode left join CatResource on CatResourceNameNode.ResourceId = CatResource.ResourceId where ParentID in (select DISTINCT NodeID from CatResourceNameNode where (ResourceNameNode LIKE ? OR ResourceNameNode = ? ) AND ParentID = 0 AND (Type != ? and Type != ? and type != ? and type != ?) ) AND (RestoreType = ? or RestoreType is NULL ) AND ( Type = ? OR Type = ? OR Type = ? OR Type = ? ) ORDER BY ResourceNameNode MANAGEME: [11/21/13 06:45:03] [5296] BECAT : [3576]:[4]: result(0) numRetry(300) MANAGEME: [11/21/13 06:45:03] [5296] BECAT : [3576]:[5]: GetRecordCount MANAGEME: [11/21/13 06:45:03] [5296] BECAT : [3576]:[5]: GetRecordCount MANAGEME: [11/21/13 06:45:03] [5296] BECAT : [3576]:[4]: Number of records returned: 1 MANAGEME: [11/21/13 06:45:03] [5296] BECAT : [3576]:[5]: GetRecordCount MANAGEME: [11/21/13 06:45:03] [5296] BECAT : [3576]:[5]: GetRecordCount MANAGEME: [11/21/13 06:45:03] [5296] BECAT : [3576]:[4]: InOut Parameters for Resource_View : No values MANAGEME: [11/21/13 06:45:03] [5296] BECAT : [3576]:[4]: Output Parameters for Resource_View : No values MANAGEME: [11/21/13 06:45:03] [5296] BECAT : [3576]:[5]: m_sqlqueryTTL( 0 ) MANAGEME: [11/21/13 06:45:03] [5296] BECAT : [3576]:[5]: return 0 MANAGEME: [11/21/13 06:45:03] [5296] BECAT : [3576]:[5]: Current row (0) : [2](0),[3](59),[4](0),[6](\\EMDServer.primarycare.local\System?State),[9](28),[10](1),[1](3),[5](),[11](0),[7](1) MANAGEME: [11/21/13 06:45:03] [5296] BECAT : [3576]:[4]: SQLQuery::RowCount(1) for Resource_View MANAGEME: [11/21/13 06:45:03] [5296] BECAT : [3576]:[3]: SQLQuery (Resource_View) Time Spend = 0 ms - ReturnedCount (1) MANAGEME: [11/21/13 06:45:03] [0000] Asynchronous task 004ed386-21ee-4e05-88ad-e390826147c1 completing 'QueryMetaDataAsyncWorker' MANAGEME: [11/21/13 06:45:03] [0000] Task AsynchronousMethodTask completed MANAGEME: [11/21/13 06:45:03] [0027] 11/21 06:45:03.098[MetaData ] QueryMetaData ends: MDQ_Resource_View rc=0 numrows=1 MANAGEME: [11/21/13 06:45:06] [0000] Asynchronous task 11feef07-c281-4a94-8e7f-6142f516292b starting 'QueryMetaDataAsyncWorker' MANAGEME: [11/21/13 06:45:06] [5296] BECAT : [3576]:[5]: odbcConn (000000002075C6D0) MANAGEME: [11/21/13 06:45:06] [5296] BECAT : [3576]:[5]: odbcConn (000000002075C6D0) - gain lock MANAGEME: [11/21/13 06:45:06] [5296] BECAT : [3576]:[5]: odbcConn (000000002075C6D0) - done MANAGEME: [11/21/13 06:45:06] [5296] BECAT : [3576]:[5]: BindColumns : maxRowCount = 500, RowSize = 2864 MANAGEME: [11/21/13 06:45:06] [5296] BECAT : [3576]:[5]: SQL connection: 29338fe0 select * from ControlInfo MANAGEME: [11/21/13 06:45:06] [5296] BECAT : [3576]:[4]: Input Parameters for BackupSet_ByResource_View : [5](1),[201](1382443200),[180](1385121599),[161](64),[175](3),[181](1),[71](5) MANAGEME: [11/21/13 06:45:06] [5296] BECAT : [3576]:[4]: SELECT *, 0 FROM ImageObjectView where 1=1 AND ResourceId = 1 AND (FragmentState != 1 or FragmentState is NULL) AND RestoreType = 3 AND ( RestoreSubType & 1 = 1 ) AND ( BackupTimeUTC = 0 OR BackupTimeUTC >= 1382443200 )AND ( BackupTimeUTC = 0 OR BackupTimeUTC <= 1385121599 ) AND ExpirationPolicyID != '9D094A9C-BBBB-402c-A35D-8A46984A19D2' AND ExtStatus & 536870912 = 0 ORDER BY BackupTimeUTC DESC , BackupType DESC MANAGEME: [11/21/13 06:45:06] [5296] BECAT : [3576]:[5]: SQL connection: 29338fe0 SELECT *, 0 FROM ImageObjectView where 1=1 AND ResourceId = ? AND (FragmentState != 1 or FragmentState is NULL) AND RestoreType = ? AND ( RestoreSubType & ? = ? ) AND ( BackupTimeUTC = 0 OR BackupTimeUTC >= ? )AND ( BackupTimeUTC = 0 OR BackupTimeUTC <= ? ) AND ExpirationPolicyID != '9D094A9C-BBBB-402c-A35D-8A46984A19D2' AND ExtStatus & ? = 0 ORDER BY BackupTimeUTC DESC , BackupType DESC MANAGEME: [11/21/13 06:45:06] [5296] BECAT : [3576]:[4]: result(0) numRetry(300) MANAGEME: [11/21/13 06:45:06] [5296] BECAT : [3576]:[5]: GetRecordCount MANAGEME: [11/21/13 06:45:06] [5296] BECAT : [3576]:[5]: GetRecordCount MANAGEME: [11/21/13 06:45:06] [5296] BECAT : [3576]:[4]: Number of records returned: 1 MANAGEME: [11/21/13 06:45:06] [5296] BECAT : [3576]:[5]: GetRecordCount MANAGEME: [11/21/13 06:45:06] [5296] BECAT : [3576]:[5]: GetRecordCount MANAGEME: [11/21/13 06:45:06] [5296] BECAT : [3576]:[5]: CatDaoIterator:: Reset() return 1 MANAGEME: [11/21/13 06:45:06] [5296] BECAT : [3576]:[5]: return 1 MANAGEME: [11/21/13 06:45:06] [5296] BECAT : [3576]:[4]: InOut Parameters for BackupSet_ByResource_View : No values MANAGEME: [11/21/13 06:45:06] [5296] BECAT : [3576]:[4]: Output Parameters for BackupSet_ByResource_View : No values MANAGEME: [11/21/13 06:45:06] [5296] BECAT : [3576]:[5]: m_sqlqueryTTL( 0 ) MANAGEME: [11/21/13 06:45:06] [5296] BECAT : [3576]:[5]: return 0 MANAGEME: [11/21/13 06:45:06] [5296] BECAT : [3576]:[5]: imageFromXML( 0 ) MANAGEME: [11/21/13 06:45:06] [5296] BECAT : [3576]:[5]: adding index to cache : 1 - 1 MANAGEME: [11/21/13 06:45:06] [5296] BECAT : [3576]:[5]: return ( 1 ) MANAGEME: [11/21/13 06:45:06] [5296] BECAT : [3576]:[5]: Current row (0) : [7](59),[8](0),[11](\\EMDServer.primarycare.local\System?State),[57](28),[58](1),[80](1384945096),[9](4097),[66](),[68](0),[69](0),[39](0),[65](),[60](1),[27](EMDSERVER.PRIMARYCARE.LOCAL),[6](5),[55](117571587),[1](1131693004),[3](1),[54]({75678231-04FD-4260-8C07-449F246CA607}),[2]({DCF8E563-58F0-48A6-89D2-8D2A2AA3A5C5}),[53]({75678231-04FD-4260-8C07-449F246CA607}),[72]({8A4B272A-28D1-452C-A531-4F10497688B8}),[116]({8A4B272A-28D1-452C-A531-4F10497688B8}),[23](LTO000005),[103](0),[13](4),[81](0),[122](1),[121]() MANAGEME: [11/21/13 06:45:06] [5296] BECAT : [3576]:[5]: imageFromXML( 0 ) MANAGEME: [11/21/13 06:45:06] [5296] BECAT : [3576]:[5]: return ( 0 ) MANAGEME: [11/21/13 06:45:06] [5296] BECAT : [3576]:[4]: SQLQuery::RowCount(1) for BackupSet_ByResource_View MANAGEME: [11/21/13 06:45:06] [5296] BECAT : [3576]:[3]: SQLQuery (BackupSet_ByResource_View) Time Spend = 0 ms - ReturnedCount (1) MANAGEME: [11/21/13 06:45:06] [0000] Asynchronous task 11feef07-c281-4a94-8e7f-6142f516292b completing 'QueryMetaDataAsyncWorker' MANAGEME: [11/21/13 06:45:06] [0000] Task AsynchronousMethodTask completed MANAGEME: [11/21/13 06:45:06] [0005] 11/21 06:45:06.062[MetaData ] QueryMetaDataStreamAsync : clientId(00000000-0000-0000-0000-000000000000) asyncId(11feef07-c281-4a94-8e7f-6142f516292b) MANAGEME: [11/21/13 06:45:06] [0027] 11/21 06:45:06.062[MetaData ] QueryMetaData : 544589520 MANAGEME: [11/21/13 06:45:06] [0027] 11/21 06:45:06.062[MetaData ] QueryMetaData starts: MDQ_BackupSet_ByResource_View MANAGEME: [11/21/13 06:45:06] [0027] 11/21 06:45:06.069[MetaData ] QueryMetaData ends: MDQ_BackupSet_ByResource_View rc=0 numrows=1 BESERVER: [11/21/13 06:45:11] [3572] -1 SetServiceStatus: state: SERVICE_RUNNING check point: 0 wait hint: 0 MANAGEME: [11/21/13 06:45:15] [0024] 11/21 06:45:14.626[MetaData ] AllocateMetaDataConnection : 544591120 MANAGEME: [11/21/13 06:45:15] [0000] Asynchronous task 18c18546-5b6a-4e6b-b3e6-8f76a771d9d5 starting 'QueryMetaDataAsyncWorker' MANAGEME: [11/21/13 06:45:14] [5696] BECAT : [3576]:[5]: odbcConn (000000002075CD10) MANAGEME: [11/21/13 06:45:14] [5696] BECAT : [3576]:[5]: odbcConn (000000002075CD10) - gain lock MANAGEME: [11/21/13 06:45:14] [5696] BECAT : [3576]:[5]: odbcConn (000000002075CD10) - add as new connection MANAGEME: [11/21/13 06:45:14] [5696] BECAT : [3576]:[5]: odbcConn (000000002075CD10) - done MANAGEME: [11/21/13 06:45:14] [5696] BECAT : [3576]:[5]: BindColumns : maxRowCount = 500, RowSize = 11048 MANAGEME: [11/21/13 06:45:14] [5696] BECAT : [3576]:[4]: Input Parameters for PhysicalSetsFromLogicalSelection : [27]({75678231-04FD-4260-8C07-449F246CA607}),[175](3),[181](1),[38](0),[214](+\*.*),[215](\),[216](),[219](0),[201](1382443200),[180](1385121599),[9](\\EMDServer.primarycare.local\System?State),[51](EMDServer.primarycare.local),[62]({10011001-1001-1001-0101-010101010101}) MANAGEME: [11/21/13 06:45:14] [5696] BECAT : [3576]:[4]: Query SP PhysicalSetsFromLogicalSelection. MANAGEME: [11/21/13 06:45:14] [5696] BECAT : [3576]:[5]: SQL connection: 29338fe0 select * from ControlInfo MANAGEME: [11/21/13 06:45:14] [5696] BECAT : [3576]:[4]: SELECT * FROM dbo.[CATGetPhysicalSetsFromLogicalSelection]( {75678231-04FD-4260-8C07-449F246CA607},0,0,0,1382443200,1 ) ORDER BY BackupTimeUTC, ExpirationTimeUTC MANAGEME: [11/21/13 06:45:14] [5696] BECAT : [3576]:[5]: SQL connection: 29338fe0 SELECT * FROM dbo.[CATGetPhysicalSetsFromLogicalSelection]( ?,?,?,?,?,? ) ORDER BY BackupTimeUTC, ExpirationTimeUTC MANAGEME: [11/21/13 06:45:15] [0016] 11/21 06:45:14.670[MetaData ] QueryMetaDataStreamAsync : clientId(00000000-0000-0000-0000-000000000000) asyncId(18c18546-5b6a-4e6b-b3e6-8f76a771d9d5) MANAGEME: [11/21/13 06:45:15] [0029] 11/21 06:45:14.670[MetaData ] QueryMetaData : 544591120 MANAGEME: [11/21/13 06:45:15] [0029] 11/21 06:45:14.670[MetaData ] QueryMetaData starts: MDQ_PhysicalSetsFromLogicalSelection MANAGEME: [11/21/13 06:45:14] [5696] BECAT : [3576]:[4]: result(0) numRetry(300) MANAGEME: [11/21/13 06:45:14] [5696] BECAT : [3576]:[5]: GetRecordCount MANAGEME: [11/21/13 06:45:14] [5696] BECAT : [3576]:[5]: GetRecordCount MANAGEME: [11/21/13 06:45:14] [5696] BECAT : [3576]:[4]: Number of records returned: 1 MANAGEME: [11/21/13 06:45:14] [5696] BECAT : [3576]:[5]: GetRecordCount MANAGEME: [11/21/13 06:45:14] [5696] BECAT : [3576]:[5]: GetRecordCount MANAGEME: [11/21/13 06:45:14] [5696] BECAT : [3576]:[5]: CatDaoIterator:: Reset() return 1 MANAGEME: [11/21/13 06:45:14] [5696] BECAT : [3576]:[5]: return 1 MANAGEME: [11/21/13 06:45:14] [5696] BECAT : [3576]:[5]: imageFromXML( 0 ) MANAGEME: [11/21/13 06:45:14] [5696] BECAT : [3576]:[5]: adding index to cache : 1 - 1 MANAGEME: [11/21/13 06:45:14] [5696] BECAT : [3576]:[5]: return ( 1 ) MANAGEME: [11/21/13 06:45:14] [5696] BECAT : [3576]:[5]: imageFromXML( 0 ) MANAGEME: [11/21/13 06:45:14] [5696] BECAT : [3576]:[5]: return ( 0 ) MANAGEME: [11/21/13 06:45:14] [5696] BECAT : [3576]:[5]: Calculate copy preference starts. MANAGEME: [11/21/13 06:45:14] [5696] BECAT : [3576]:[5]: Calculate copy preference ends: rtnVal( 0 ) MANAGEME: [11/21/13 06:45:14] [5696] BECAT : [3576]:[4]: Query SP PhysicalSetsFromLogicalSelection: complete, 1 records returned. MANAGEME: [11/21/13 06:45:14] [5696] BECAT : [3576]:[4]: InOut Parameters for PhysicalSetsFromLogicalSelection : No values MANAGEME: [11/21/13 06:45:14] [5696] BECAT : [3576]:[4]: Output Parameters for PhysicalSetsFromLogicalSelection : No values MANAGEME: [11/21/13 06:45:14] [5696] BECAT : [3576]:[5]: m_sqlqueryTTL( 0 ) MANAGEME: [11/21/13 06:45:14] [5696] BECAT : [3576]:[5]: return 0 MANAGEME: [11/21/13 06:45:14] [5696] BECAT : [3576]:[5]: Current row (0) : [7](59),[8](0),[11](\\EMDServer.primarycare.local\System?State),[57](28),[58](1),[55](118095875),[9](4097),[27](EMDSERVER.PRIMARYCARE.LOCAL),[3](1),[54]({75678231-04FD-4260-8C07-449F246CA607}),[1](1131693004),[2]({DCF8E563-58F0-48A6-89D2-8D2A2AA3A5C5}),[6](5),[112](2),[13](4),[80](1384945096),[117](+\*.*),[53]({75678231-04FD-4260-8C07-449F246CA607}),[118]({24586468-35F7-4634-9915-8C71679D288B};),[84]({97B5EAAB-7C0A-4800-9872-6D5DC643478C}),[66](),[69](0),[68](0),[39](0),[65](),[114](1),[62](),[104]({00000000-0000-0000-0000-000000000000}) MANAGEME: [11/21/13 06:45:14] [5696] BECAT : [3576]:[4]: SQLQuery::RowCount(1) for PhysicalSetsFromLogicalSelection MANAGEME: [11/21/13 06:45:14] [5696] BECAT : [3576]:[3]: SQLQuery (PhysicalSetsFromLogicalSelection) Time Spend = 0 ms - ReturnedCount (1) MANAGEME: [11/21/13 06:45:15] [0000] Asynchronous task 18c18546-5b6a-4e6b-b3e6-8f76a771d9d5 completing 'QueryMetaDataAsyncWorker' MANAGEME: [11/21/13 06:45:15] [0000] Task AsynchronousMethodTask completed MANAGEME: [11/21/13 06:45:15] [0029] 11/21 06:45:14.698[MetaData ] QueryMetaData ends: MDQ_PhysicalSetsFromLogicalSelection rc=0 numrows=1 MANAGEME: [11/21/13 06:45:29] [0000] Asynchronous task f7f105e4-efc2-4d01-9ff3-ae85b47f08b6 starting 'QueryMetaDataAsyncWorker' MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[5]: odbcConn (000000002075C6D0) MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[5]: odbcConn (000000002075C6D0) - gain lock MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[5]: odbcConn (000000002075C6D0) - done MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[5]: BindColumns : maxRowCount = 500, RowSize = 8392 MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[4]: Input Parameters for FileCentricFileHistory : [175](3),[181](1),[62]({10011001-1001-1001-0101-010101010101}),[48](1),[65](0),[26]({75678231-04FD-4260-8C07-449F246CA607}),[27]({75678231-04FD-4260-8C07-449F246CA607}) MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[5]: SQL connection: 29338fe0 select * from ControlInfo MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[4]: {CALL CatSynthFilteredImageSet( {75678231-04FD-4260-8C07-449F246CA607} )} MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[5]: SQL connection: 29338fe0 {CALL CatSynthFilteredImageSet( ? )} MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[4]: ErrorCode(1) ErrorMsg([Microsoft][ODBC SQL Server Driver]Cursor type changed) MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[4]: result(1) numRetry(300) MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[5]: GetRecordCount MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[5]: GetRecordCount MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[4]: Number of records returned: 1 MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[5]: GetRecordCount MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[5]: GetRecordCount MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[5]: CatDaoIterator:: Reset() return 1 MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[5]: return 1 MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[5]: imageFromXML( 0 ) MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[5]: adding index to cache : 1 - 1 MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[5]: return ( 1 ) MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[5]: m_image(000000002089B210) MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[5]: OSId(59) PluginId(16) ImageFileName({DCF8E563-58F0-48A6-89D2-8D2A2AA3A5C5}_5.xml) MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[5]: pluginID(16) remoteQueryTTL(2) MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[4]: Lock FH file(EMDSERVER\{DCF8E563-58F0-48A6-89D2-8D2A2AA3A5C5}_5.fh) MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[4]: Lock IMG file(EMDSERVER\{DCF8E563-58F0-48A6-89D2-8D2A2AA3A5C5}_5.xml) MANAGEME: [11/21/13 06:45:29] [0025] 11/21 06:45:28.915[MetaData ] QueryMetaDataStreamAsync : clientId(00000000-0000-0000-0000-000000000000) asyncId(f7f105e4-efc2-4d01-9ff3-ae85b47f08b6) MANAGEME: [11/21/13 06:45:29] [0025] 11/21 06:45:28.916[MetaData ] QueryMetaData : 544589520 MANAGEME: [11/21/13 06:45:29] [0025] 11/21 06:45:28.916[MetaData ] QueryMetaData starts: MDQ_FileCentricFileHistory MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[2]: Return CAT_SUCCESS (00000000293511E0) MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[5]: imageFromXML( 0 ) MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[5]: return ( 0 ) MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[5]: m_image(0000000000000000) MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[5]: OSId(0) PluginId(0) ImageFileName() MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[5]: Total objects found: 16 MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[4]: InOut Parameters for FileCentricFileHistory : No values MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[4]: Output Parameters for FileCentricFileHistory : No values MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[5]: m_sqlqueryTTL( 0 ) MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[5]: return 0 MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[5]: THEFHFileSystemObject::SetValue : name: Active Directory MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[5]: Current row (0) : [80](65),[7](59),[8](0),[15](Active Directory),[75](11),[24](13),[21](2176),[81](0),[20](2097162),[45](0),[82](4613456),[40](0),[13](40),[1](1131693004),[3](1),[1054]({75678231-04FD-4260-8C07-449F246CA607}),[2]({DCF8E563-58F0-48A6-89D2-8D2A2AA3A5C5}),[1053]({75678231-04FD-4260-8C07-449F246CA607}),[1057](28),[1058](1),[1081](0),[130](0) MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[5]: THEFHFileSystemObject::SetValue : name: Automated System Recovery MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[5]: Current row (1) : [80](65),[7](59),[8](0),[15](Automated System Recovery),[75](11),[24](13),[21](2176),[81](0),[20](2097162),[45](0),[82](15154590),[40](0),[13](4),[1](1131693004),[3](1),[1054]({75678231-04FD-4260-8C07-449F246CA607}),[2]({DCF8E563-58F0-48A6-89D2-8D2A2AA3A5C5}),[1053]({75678231-04FD-4260-8C07-449F246CA607}),[1057](28),[1058](1),[1081](0),[130](0) MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[5]: THEFHFileSystemObject::SetValue : name: COM+ Class Registration Database MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[5]: Current row (2) : [80](65),[7](59),[8](0),[15](COM+ Class Registration Database),[75](11),[24](13),[21](2176),[81](0),[20](2097162),[45](0),[82](1071164),[40](0),[13](7),[1](1131693004),[3](1),[1054]({75678231-04FD-4260-8C07-449F246CA607}),[2]({DCF8E563-58F0-48A6-89D2-8D2A2AA3A5C5}),[1053]({75678231-04FD-4260-8C07-449F246CA607}),[1057](28),[1058](1),[1081](0),[130](0) MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[5]: THEFHFileSystemObject::SetValue : name: Dynamic Host Configuration Protocol MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[5]: Current row (3) : [80](65),[7](59),[8](0),[15](Dynamic Host Configuration Protocol),[75](11),[24](13),[21](2176),[81](0),[20](2097162),[45](0),[82](3162112),[40](0),[13](9),[1](1131693004),[3](1),[1054]({75678231-04FD-4260-8C07-449F246CA607}),[2]({DCF8E563-58F0-48A6-89D2-8D2A2AA3A5C5}),[1053]({75678231-04FD-4260-8C07-449F246CA607}),[1057](28),[1058](1),[1081](0),[130](0) MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[5]: THEFHFileSystemObject::SetValue : name: Internet Information Services MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[5]: Current row (4) : [80](65),[7](59),[8](0),[15](Internet Information Services),[75](11),[24](13),[21](2176),[81](0),[20](2097162),[45](0),[82](465027),[40](0),[13](12),[1](1131693004),[3](1),[1054]({75678231-04FD-4260-8C07-449F246CA607}),[2]({DCF8E563-58F0-48A6-89D2-8D2A2AA3A5C5}),[1053]({75678231-04FD-4260-8C07-449F246CA607}),[1057](28),[1058](1),[1081](0),[130](0) MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[5]: THEFHFileSystemObject::SetValue : name: Network Policy Server MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[5]: Current row (5) : [80](65),[7](59),[8](0),[15](Network Policy Server),[75](11),[24](13),[21](2176),[81](0),[20](2097162),[45](0),[82](142510),[40](0),[13](15),[1](1131693004),[3](1),[1054]({75678231-04FD-4260-8C07-449F246CA607}),[2]({DCF8E563-58F0-48A6-89D2-8D2A2AA3A5C5}),[1053]({75678231-04FD-4260-8C07-449F246CA607}),[1057](28),[1058](1),[1081](0),[130](0) MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[5]: THEFHFileSystemObject::SetValue : name: Performance Counter MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[5]: Current row (6) : [80](65),[7](59),[8](0),[15](Performance Counter),[75](11),[24](13),[21](2176),[81](0),[20](2097162),[45](0),[82](8570526),[40](0),[13](17),[1](1131693004),[3](1),[1054]({75678231-04FD-4260-8C07-449F246CA607}),[2]({DCF8E563-58F0-48A6-89D2-8D2A2AA3A5C5}),[1053]({75678231-04FD-4260-8C07-449F246CA607}),[1057](28),[1058](1),[1081](0),[130](0) MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[5]: THEFHFileSystemObject::SetValue : name: Registry MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[5]: Current row (7) : [80](65),[7](59),[8](0),[15](Registry),[75](11),[24](13),[21](2176),[81](0),[20](2097162),[45](0),[82](189792256),[40](0),[13](19),[1](1131693004),[3](1),[1054]({75678231-04FD-4260-8C07-449F246CA607}),[2]({DCF8E563-58F0-48A6-89D2-8D2A2AA3A5C5}),[1053]({75678231-04FD-4260-8C07-449F246CA607}),[1057](28),[1058](1),[1081](0),[130](0) MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[5]: THEFHFileSystemObject::SetValue : name: System Files MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[5]: Current row (8) : [80](65),[7](59),[8](0),[15](System Files),[75](11),[24](13),[21](2176),[81](0),[20](2097162),[45](0),[82](21686200646),[40](0),[13](21),[1](1131693004),[3](1),[1054]({75678231-04FD-4260-8C07-449F246CA607}),[2]({DCF8E563-58F0-48A6-89D2-8D2A2AA3A5C5}),[1053]({75678231-04FD-4260-8C07-449F246CA607}),[1057](28),[1058](1),[1081](0),[130](0) MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[5]: THEFHFileSystemObject::SetValue : name: SYSVOL MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[5]: Current row (9) : [80](65),[7](59),[8](0),[15](SYSVOL),[75](11),[24](13),[21](2176),[81](0),[20](2097162),[45](0),[82](14072),[40](0),[13](23),[1](1131693004),[3](1),[1054]({75678231-04FD-4260-8C07-449F246CA607}),[2]({DCF8E563-58F0-48A6-89D2-8D2A2AA3A5C5}),[1053]({75678231-04FD-4260-8C07-449F246CA607}),[1057](28),[1058](1),[1081](0),[130](0) MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[5]: THEFHFileSystemObject::SetValue : name: Task Scheduler MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[5]: Current row (10) : [80](65),[7](59),[8](0),[15](Task Scheduler),[75](11),[24](13),[21](2176),[81](0),[20](2097162),[45](0),[82](181968),[40](0),[13](26),[1](1131693004),[3](1),[1054]({75678231-04FD-4260-8C07-449F246CA607}),[2]({DCF8E563-58F0-48A6-89D2-8D2A2AA3A5C5}),[1053]({75678231-04FD-4260-8C07-449F246CA607}),[1057](28),[1058](1),[1081](0),[130](0) MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[5]: THEFHFileSystemObject::SetValue : name: Terminal Server Licensing MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[5]: Current row (11) : [80](65),[7](59),[8](0),[15](Terminal Server Licensing),[75](11),[24](13),[21](2176),[81](0),[20](2097162),[45](0),[82](6307840),[40](0),[13](28),[1](1131693004),[3](1),[1054]({75678231-04FD-4260-8C07-449F246CA607}),[2]({DCF8E563-58F0-48A6-89D2-8D2A2AA3A5C5}),[1053]({75678231-04FD-4260-8C07-449F246CA607}),[1057](28),[1058](1),[1081](0),[130](0) MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[5]: THEFHFileSystemObject::SetValue : name: Terminal Services Gateway MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[5]: Current row (12) : [80](65),[7](59),[8](0),[15](Terminal Services Gateway),[75](11),[24](13),[21](2176),[81](0),[20](2097162),[45](0),[82](1384),[40](0),[13](31),[1](1131693004),[3](1),[1054]({75678231-04FD-4260-8C07-449F246CA607}),[2]({DCF8E563-58F0-48A6-89D2-8D2A2AA3A5C5}),[1053]({75678231-04FD-4260-8C07-449F246CA607}),[1057](28),[1058](1),[1081](0),[130](0) MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[5]: THEFHFileSystemObject::SetValue : name: VSS Express Writer Store MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[5]: Current row (13) : [80](65),[7](59),[8](0),[15](VSS Express Writer Store),[75](11),[24](13),[21](2176),[81](0),[20](2097162),[45](0),[82](12420),[40](0),[13](33),[1](1131693004),[3](1),[1054]({75678231-04FD-4260-8C07-449F246CA607}),[2]({DCF8E563-58F0-48A6-89D2-8D2A2AA3A5C5}),[1053]({75678231-04FD-4260-8C07-449F246CA607}),[1057](28),[1058](1),[1081](0),[130](0) MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[5]: THEFHFileSystemObject::SetValue : name: Windows Internet Name Service MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[5]: Current row (14) : [80](65),[7](59),[8](0),[15](Windows Internet Name Service),[75](11),[24](13),[21](2176),[81](0),[20](2097162),[45](0),[82](5259264),[40](0),[13](35),[1](1131693004),[3](1),[1054]({75678231-04FD-4260-8C07-449F246CA607}),[2]({DCF8E563-58F0-48A6-89D2-8D2A2AA3A5C5}),[1053]({75678231-04FD-4260-8C07-449F246CA607}),[1057](28),[1058](1),[1081](0),[130](0) MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[5]: THEFHFileSystemObject::SetValue : name: Windows Management Instrumentation MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[5]: Current row (15) : [80](65),[7](59),[8](0),[15](Windows Management Instrumentation),[75](11),[24](13),[21](2176),[81](0),[20](2097162),[45](0),[82](50812240),[40](0),[13](38),[1](1131693004),[3](1),[1054]({75678231-04FD-4260-8C07-449F246CA607}),[2]({DCF8E563-58F0-48A6-89D2-8D2A2AA3A5C5}),[1053]({75678231-04FD-4260-8C07-449F246CA607}),[1057](28),[1058](1),[1081](0),[130](0) MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[4]: SQLQuery::RowCount(0) for FileCentricFileHistory MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[3]: SQLQuery (FileCentricFileHistory) Time Spend = 0 ms - ReturnedCount (16) MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[5]: fileState = 0 MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[4]: rc = 0 MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[5]: fileState = 0 MANAGEME: [11/21/13 06:45:28] [5668] BECAT : [3576]:[4]: rc = 0 MANAGEME: [11/21/13 06:45:29] [0000] Asynchronous task f7f105e4-efc2-4d01-9ff3-ae85b47f08b6 completing 'QueryMetaDataAsyncWorker' MANAGEME: [11/21/13 06:45:29] [0000] Task AsynchronousMethodTask completed MANAGEME: [11/21/13 06:45:29] [0025] 11/21 06:45:28.970[MetaData ] QueryMetaData ends: MDQ_FileCentricFileHistory rc=0 numrows=16 BESERVER: [11/21/13 06:45:44] [5488] -1 SecurityBO: Successfully setup RAWS certs. MANAGEME: [11/21/13 06:45:44] [0029] 11/21 06:45:44.446[MetaData ] AllocateMetaDataConnection : 544589872 MANAGEME: [11/21/13 06:45:44] [0000] Asynchronous task 7056bc19-a925-40a6-8c53-d8c6039b0bfb starting 'QueryMetaDataAsyncWorker' MANAGEME: [11/21/13 06:45:44] [4684] BECAT : [3576]:[5]: odbcConn (000000002075C830) MANAGEME: [11/21/13 06:45:44] [4684] BECAT : [3576]:[5]: odbcConn (000000002075C830) - gain lock MANAGEME: [11/21/13 06:45:44] [4684] BECAT : [3576]:[5]: odbcConn (000000002075C830) - add as new connection MANAGEME: [11/21/13 06:45:44] [4684] BECAT : [3576]:[5]: odbcConn (000000002075C830) - done MANAGEME: [11/21/13 06:45:44] [4684] BECAT : [3576]:[5]: BindColumns : maxRowCount = 500, RowSize = 11048 MANAGEME: [11/21/13 06:45:44] [0028] 11/21 06:45:44.489[MetaData ] QueryMetaDataStreamAsync : clientId(00000000-0000-0000-0000-000000000000) asyncId(7056bc19-a925-40a6-8c53-d8c6039b0bfb) MANAGEME: [11/21/13 06:45:44] [0010] 11/21 06:45:44.489[MetaData ] QueryMetaData : 544589872 MANAGEME: [11/21/13 06:45:44] [0010] 11/21 06:45:44.489[MetaData ] QueryMetaData starts: MDQ_PhysicalSetsFromLogicalSelection MANAGEME: [11/21/13 06:45:44] [4684] BECAT : [3576]:[4]: Input Parameters for PhysicalSetsFromLogicalSelection : [27]({75678231-04FD-4260-8C07-449F246CA607}),[175](3),[181](1),[38](0),[214](+\*.*),[215](\),[216](),[219](0),[201](1382443200),[180](1385121599),[9](\\EMDServer.primarycare.local\System?State),[51](EMDServer.primarycare.local),[62]({10011001-1001-1001-0101-010101010101}) MANAGEME: [11/21/13 06:45:44] [4684] BECAT : [3576]:[4]: Query SP PhysicalSetsFromLogicalSelection. MANAGEME: [11/21/13 06:45:44] [4684] BECAT : [3576]:[5]: SQL connection: 29338fe0 select * from ControlInfo MANAGEME: [11/21/13 06:45:44] [4684] BECAT : [3576]:[4]: SELECT * FROM dbo.[CATGetPhysicalSetsFromLogicalSelection]( {75678231-04FD-4260-8C07-449F246CA607},0,0,0,1382443200,1 ) ORDER BY BackupTimeUTC, ExpirationTimeUTC MANAGEME: [11/21/13 06:45:44] [4684] BECAT : [3576]:[5]: SQL connection: 29338fe0 SELECT * FROM dbo.[CATGetPhysicalSetsFromLogicalSelection]( ?,?,?,?,?,? ) ORDER BY BackupTimeUTC, ExpirationTimeUTC MANAGEME: [11/21/13 06:45:44] [4684] BECAT : [3576]:[4]: result(0) numRetry(300) MANAGEME: [11/21/13 06:45:44] [4684] BECAT : [3576]:[5]: GetRecordCount MANAGEME: [11/21/13 06:45:44] [4684] BECAT : [3576]:[5]: GetRecordCount MANAGEME: [11/21/13 06:45:44] [4684] BECAT : [3576]:[4]: Number of records returned: 1 MANAGEME: [11/21/13 06:45:44] [4684] BECAT : [3576]:[5]: GetRecordCount MANAGEME: [11/21/13 06:45:44] [4684] BECAT : [3576]:[5]: GetRecordCount MANAGEME: [11/21/13 06:45:44] [4684] BECAT : [3576]:[5]: CatDaoIterator:: Reset() return 1 MANAGEME: [11/21/13 06:45:44] [4684] BECAT : [3576]:[5]: return 1 MANAGEME: [11/21/13 06:45:44] [4684] BECAT : [3576]:[5]: imageFromXML( 0 ) MANAGEME: [11/21/13 06:45:44] [4684] BECAT : [3576]:[5]: adding index to cache : 1 - 1 MANAGEME: [11/21/13 06:45:44] [4684] BECAT : [3576]:[5]: return ( 1 ) MANAGEME: [11/21/13 06:45:44] [4684] BECAT : [3576]:[5]: imageFromXML( 0 ) MANAGEME: [11/21/13 06:45:44] [4684] BECAT : [3576]:[5]: return ( 0 ) MANAGEME: [11/21/13 06:45:44] [4684] BECAT : [3576]:[5]: Calculate copy preference starts. MANAGEME: [11/21/13 06:45:44] [4684] BECAT : [3576]:[5]: Calculate copy preference ends: rtnVal( 0 ) MANAGEME: [11/21/13 06:45:44] [4684] BECAT : [3576]:[4]: Query SP PhysicalSetsFromLogicalSelection: complete, 1 records returned. MANAGEME: [11/21/13 06:45:44] [4684] BECAT : [3576]:[4]: InOut Parameters for PhysicalSetsFromLogicalSelection : No values MANAGEME: [11/21/13 06:45:44] [4684] BECAT : [3576]:[4]: Output Parameters for PhysicalSetsFromLogicalSelection : No values MANAGEME: [11/21/13 06:45:44] [4684] BECAT : [3576]:[5]: m_sqlqueryTTL( 0 ) MANAGEME: [11/21/13 06:45:44] [4684] BECAT : [3576]:[5]: return 0 MANAGEME: [11/21/13 06:45:44] [4684] BECAT : [3576]:[5]: Current row (0) : [7](59),[8](0),[11](\\EMDServer.primarycare.local\System?State),[57](28),[58](1),[55](118095875),[9](4097),[27](EMDSERVER.PRIMARYCARE.LOCAL),[3](1),[54]({75678231-04FD-4260-8C07-449F246CA607}),[1](1131693004),[2]({DCF8E563-58F0-48A6-89D2-8D2A2AA3A5C5}),[6](5),[112](2),[13](4),[80](1384945096),[117](+\*.*),[53]({75678231-04FD-4260-8C07-449F246CA607}),[118]({24586468-35F7-4634-9915-8C71679D288B};),[84]({97B5EAAB-7C0A-4800-9872-6D5DC643478C}),[66](),[69](0),[68](0),[39](0),[65](),[114](1),[62](),[104]({00000000-0000-0000-0000-000000000000}) MANAGEME: [11/21/13 06:45:44] [4684] BECAT : [3576]:[4]: SQLQuery::RowCount(1) for PhysicalSetsFromLogicalSelection MANAGEME: [11/21/13 06:45:44] [4684] BECAT : [3576]:[3]: SQLQuery (PhysicalSetsFromLogicalSelection) Time Spend = 0 ms - ReturnedCount (1) MANAGEME: [11/21/13 06:45:44] [0000] Asynchronous task 7056bc19-a925-40a6-8c53-d8c6039b0bfb completing 'QueryMetaDataAsyncWorker' MANAGEME: [11/21/13 06:45:44] [0000] Task AsynchronousMethodTask completed MANAGEME: [11/21/13 06:45:44] [0010] 11/21 06:45:44.510[MetaData ] QueryMetaData ends: MDQ_PhysicalSetsFromLogicalSelection rc=0 numrows=1 BESERVER: [11/21/13 06:45:57] [3572] -1 SetServiceStatus: state: SERVICE_RUNNING check point: 0 wait hint: 0 BECAT: [11/21/13 06:46:05] [4076] SQL connection: 8018fd0 select * from ControlInfo BECAT: [11/21/13 06:46:05] [4076] SELECT * FROM CatImageFileDeleteTable BECAT: [11/21/13 06:46:05] [4076] SQL connection: 8018fd0 SELECT * FROM CatImageFileDeleteTable BECAT: [11/21/13 06:46:05] [4076] result(0) numRetry(300) BECAT: [11/21/13 06:46:05] [4076] GetRecordCount BECAT: [11/21/13 06:46:05] [4076] GetRecordCount BECAT: [11/21/13 06:46:05] [4076] Number of records returned: 0 BECAT: [11/21/13 06:46:05] [4076] GetRecordCount BECAT: [11/21/13 06:46:05] [4076] Cleanup images : 0 MMC: [11/21/13 06:46:18] [0000] 4900: [CBS] IsCacheStillGood: True. BEREMOTE: [11/21/13 06:46:18] [2116] 2013-11-21T06:46:18.487 [ndmp\ndmpsrvr] - Freeing unsued COM libraries PVLSVR: [11/21/13 06:46:23] [2088] [FreespaceServiceThreadProc] processing Unconfigured disk storage 0001 PVLSVR: [11/21/13 06:46:23] [3528] [FreespaceServiceThreadProc] processing Unconfigured disk storage 0002 BECAT: [11/21/13 06:46:23] [4076] VSNType(1037) verbHint(102) BECAT: [11/21/13 06:46:23] [4076] VSNType(1037) verbHint(102) BENGINE: [11/21/13 06:46:29] [3400] 2013-11-21T06:46:29.670 [server] - Freeing unsued COM libraries BESERVER: [11/21/13 06:46:42] [3572] -1 SetServiceStatus: state: SERVICE_RUNNING check point: 0 wait hint: 0 BESERVER: [11/21/13 06:46:45] [4496] -1 SecurityBO: Successfully setup RAWS certs. BESERVER: [11/21/13 06:47:27] [3572] -1 SetServiceStatus: state: SERVICE_RUNNING check point: 0 wait hint: 0 BESERVER: [11/21/13 06:47:45] [4976] -1 SecurityBO: Successfully setup RAWS certs. MANAGEME: [11/21/13 06:47:51] [0024] 11/21 06:47:51.344[Job ] ProcessAsCentralizedRestore() DeviceManager.IsCASO()==false BESERVER: [11/21/13 06:47:51] [4308] "Cluster" key is not present in the registry BESERVER: [11/21/13 06:47:51] [4308] Did not detect Microsoft cluster () BESERVER: [11/21/13 06:47:51] [4308] VCS cluster keys do not appear to be present in the registry BESERVER: [11/21/13 06:47:51] [4308] Did not detect VCS cluster () BECAT: [11/21/13 06:47:51] [4076] 0000000005482450 - # of connections : 1 BECAT: [11/21/13 06:47:51] [4076] odbcConn (0000000005482450) BECAT: [11/21/13 06:47:51] [4076] odbcConn (0000000005482450) - gain lock BECAT: [11/21/13 06:47:51] [4076] odbcConn (0000000005482450) - add as new connection BECAT: [11/21/13 06:47:51] [4076] odbcConn (0000000005482450) - done BECAT: [11/21/13 06:47:51] [4076] Input Parameters for BackupSet_View : [1]({DCF8E563-58F0-48A6-89D2-8D2A2AA3A5C5}),[3](5),[227](0) BECAT: [11/21/13 06:47:51] [4076] SQL connection: 8018fd0 select * from ControlInfo BECAT: [11/21/13 06:47:51] [4076] SELECT *, 0 FROM ImageObjectView where 1=1 AND MediaFamilyGuid = {DCF8E563-58F0-48A6-89D2-8D2A2AA3A5C5} AND ImageNumber = 5 AND ExpirationPolicyID != '9D094A9C-BBBB-402c-A35D-8A46984A19D2' AND ExtStatus & 536870912 = 0 ORDER BY BackupTimeUTC DESC BECAT: [11/21/13 06:47:51] [4076] SQL connection: 8018fd0 SELECT *, 0 FROM ImageObjectView where 1=1 AND MediaFamilyGuid = ? AND ImageNumber = ? AND ExpirationPolicyID != '9D094A9C-BBBB-402c-A35D-8A46984A19D2' AND ExtStatus & ? = 0 ORDER BY BackupTimeUTC DESC BECAT: [11/21/13 06:47:51] [4076] result(0) numRetry(300) BECAT: [11/21/13 06:47:51] [4076] GetRecordCount BECAT: [11/21/13 06:47:51] [4076] GetRecordCount BECAT: [11/21/13 06:47:51] [4076] Number of records returned: 1 BECAT: [11/21/13 06:47:51] [4076] GetRecordCount BECAT: [11/21/13 06:47:51] [4076] GetRecordCount BECAT: [11/21/13 06:47:51] [4076] CatDaoIterator:: Reset() return 1 BECAT: [11/21/13 06:47:51] [4076] return 1 BECAT: [11/21/13 06:47:51] [4076] InOut Parameters for BackupSet_View : No values BECAT: [11/21/13 06:47:51] [4076] Output Parameters for BackupSet_View : No values BECAT: [11/21/13 06:47:51] [4076] m_sqlqueryTTL( 0 ) BECAT: [11/21/13 06:47:51] [4076] return 0 BECAT: [11/21/13 06:47:51] [4076] imageFromXML( 0 ) BECAT: [11/21/13 06:47:51] [4076] adding index to cache : 1 - 1 BECAT: [11/21/13 06:47:51] [4076] return ( 1 ) BECAT: [11/21/13 06:47:51] [4076] Current row (0) : [3](1),[50](0),[4]({24586468-35F7-4634-9915-8C71679D288B}),[5](1),[6](5),[13](4),[16](530503477),[17](5),[7](59),[8](0),[9](4097),[55](117571587),[11](\\EMDServer.primarycare.local\System?State),[22](),[10](EMDServer.primarycare.local Backup 00001-Full),[23](LTO000005),[57](28),[58](1),[59](8),[60](1),[53]({75678231-04FD-4260-8C07-449F246CA607}),[54]({75678231-04FD-4260-8C07-449F246CA607}),[39](0),[65](),[66](),[67](0),[68](0),[69](0),[85](),[86](),[87](),[70]({C0913FA4-F468-4537-9D5F-56EDB1AE7317}),[72]({8A4B272A-28D1-452C-A531-4F10497688B8}),[73]({75678231-04FD-4260-8C07-449F246CA607}),[52]({DCF8E563-58F0-48A6-89D2-8D2A2AA3A5C5}_5.xml),[77](1580),[80](1384945096),[81](0),[28](EMDSERVER) BECAT: [11/21/13 06:47:51] [4076] imageFromXML( 0 ) BECAT: [11/21/13 06:47:51] [4076] return ( 0 ) BECAT: [11/21/13 06:47:51] [4076] imageFromXML( 0 ) BECAT: [11/21/13 06:47:51] [4076] return ( 0 ) BECAT: [11/21/13 06:47:51] [4076] SQLQuery (BackupSet_View) Time Spend = 0 ms - ReturnedCount (1) BECAT: [11/21/13 06:47:51] [4076] free connection (0000000005482450) - # of connections (1) BECAT: [11/21/13 06:47:51] [4076] odbcConn (0000000005482450) BECAT: [11/21/13 06:47:51] [4076] odbcConn (0000000005482450) - gain lock BECAT: [11/21/13 06:47:51] [4076] odbcConn (0000000005482450) - done BECAT: [11/21/13 06:47:51] [4076] free connection (0000000000000000) - # of connections (0) - done BESERVER: [11/21/13 06:47:51] [4308] 10 TryToFindMatchingServerAndResourceIDs, Attempting to find match on Server:\\EMDServer.primarycare.local. Row:1. BESERVER: [11/21/13 06:47:51] [4308] 10 TryToFindMatchingServerAndResourceIDs, Exact match, for: \\EMDServer.primarycare.local. Row:1. BESERVER: [11/21/13 06:47:51] [4308] 10 TryToFindMatchingServerAndResourceIDs, Attempting to find match on Server:\\EMDServer.primarycare.local. Row:1. BESERVER: [11/21/13 06:47:51] [4308] 10 TryToFindMatchingServerAndResourceIDs, Exact match, for: \\EMDServer.primarycare.local. Row:1. BESERVER: [11/21/13 06:47:51] [4308] -1 CJobManagerBO::Insert(CBemJobInst) Name=EMDServer.primarycare.local Restore 00003, TaskType=600, ObjectID=00000000-0000-0000-0000-000000000000, DJMType=0, HasTaskDef=0 MANAGEME: [11/21/13 06:47:51] [0024] 11/21 06:47:51.388[Job ] Solution:InsertAdhocSolution() using Job Instance BESERVER: [11/21/13 06:47:51] [3668] -1 Job Manager: LoadJob() request completed BESERVER: [11/21/13 06:47:51] [4308] -1 JobManagerBO::SendJobInstanceNotification Message VSN_TYPE_JOBVIEW_UPDATE jobInstanceID:610721984, Hint Verb :100, reallySendModify :0 MANAGEME: [11/21/13 06:47:52] [5668] BECAT : [3576]:[5]: odbcConn (000000002075C6D0) MANAGEME: [11/21/13 06:47:52] [5668] BECAT : [3576]:[5]: odbcConn (000000002075C6D0) - gain lock MANAGEME: [11/21/13 06:47:52] [5668] BECAT : [3576]:[5]: odbcConn (000000002075C6D0) - done MANAGEME: [11/21/13 06:47:52] [0025] 11/21 06:47:52.439[MetaData ] ReleaseMetaDataConnection : 544589520 MANAGEME: [11/21/13 06:47:52] [0012] [Warn] VSN: Solution Action: Add - empty object list. PVLSVR: [11/21/13 06:47:54] [4296] PvlEntityDatabase::QueryEntity() ParentEntityType = NULL_ENTITY_TYPE ParentEntityGuid = {00000000-0000-0000-0000-000000000000} EntityType = MACHINE_ENTITY_TYPE EntityGuid = {D13FB10E-8E07-4697-9B59-70AA62997894} EntityName = " EntityFlags = 0x00008000 PVLSVR: [11/21/13 06:47:54] [4296] PvlEntityDatabase::QueryEntity() ParentEntityType = NULL_ENTITY_TYPE ParentEntityGuid = {00000000-0000-0000-0000-000000000000} EntityType = VAULT_ENTITY_TYPE EntityGuid = {00000000-0000-0000-0000-000000000000} EntityName = " EntityFlags = 0x00008000 PVLSVR: [11/21/13 06:47:54] [5828] PvlEntityDatabase::QueryEntity() ParentEntityType = NULL_ENTITY_TYPE ParentEntityGuid = {00000000-0000-0000-0000-000000000000} EntityType = STORAGE_ARRAY_ENTITY_TYPE EntityGuid = {00000000-0000-0000-0000-000000000000} EntityName = " EntityFlags = 0x00008000 PVLSVR: [11/21/13 06:47:54] [5828] PvlEntityDatabase::QueryEntity() ParentEntityType = NULL_ENTITY_TYPE ParentEntityGuid = {00000000-0000-0000-0000-000000000000} EntityType = DEVICE_POOL_ENTITY_TYPE EntityGuid = {00000000-0000-0000-0000-000000000000} EntityName = " EntityFlags = 0x00008000 PVLSVR: [11/21/13 06:47:54] [5828] PvlEntityDatabase::QueryEntity() ParentEntityType = MACHINE_ENTITY_TYPE ParentEntityGuid = {D13FB10E-8E07-4697-9B59-70AA62997894} EntityType = DRIVE_ENTITY_TYPE EntityGuid = {00000000-0000-0000-0000-000000000000} EntityName = " EntityFlags = 0x00008000 PVLSVR: [11/21/13 06:47:54] [5828] PvlEntityDatabase::QueryEntity() ParentEntityType = MACHINE_ENTITY_TYPE ParentEntityGuid = {D13FB10E-8E07-4697-9B59-70AA62997894} EntityType = BACKUP_TO_DISK_ENTITY_TYPE EntityGuid = {00000000-0000-0000-0000-000000000000} EntityName = " EntityFlags = 0x00008000 PVLSVR: [11/21/13 06:47:54] [5828] PvlEntityDatabase::QueryEntity() ParentEntityType = MACHINE_ENTITY_TYPE ParentEntityGuid = {D13FB10E-8E07-4697-9B59-70AA62997894} EntityType = DRIVE_POOL_DRIVE_ENTITY_TYPE EntityGuid = {00000000-0000-0000-0000-000000000000} EntityName = " EntityFlags = 0x00008000 PVLSVR: [11/21/13 06:47:54] [5828] PvlEntityDatabase::QueryEntity() ParentEntityType = MACHINE_ENTITY_TYPE ParentEntityGuid = {D13FB10E-8E07-4697-9B59-70AA62997894} EntityType = LIBRARY_ENTITY_TYPE EntityGuid = {00000000-0000-0000-0000-000000000000} EntityName = " EntityFlags = 0x00008000 BESERVER: [11/21/13 06:47:54] [3948] 05 AdammAdminBO::Get() - Parent = MACHINE , Entity = DEVICE PVLSVR: [11/21/13 06:47:54] [5828] PvlEntityDatabase::QueryEntity() ParentEntityType = MACHINE_ENTITY_TYPE ParentEntityGuid = {00000000-0000-0000-0000-000000000000} EntityType = DEVICE_ENTITY_TYPE EntityGuid = {97B5EAAB-7C0A-4800-9872-6D5DC643478C} EntityName = "" EntityFlags = 0x00000000 BESERVER: [11/21/13 06:47:54] [3948] 05 AdammAdminBO::Get() - hr = 0x0 BECAT: [11/21/13 06:47:54] [4076] 0000000005482450 - # of connections : 1 BECAT: [11/21/13 06:47:54] [4076] odbcConn (0000000005482450) BECAT: [11/21/13 06:47:54] [4076] odbcConn (0000000005482450) - gain lock BECAT: [11/21/13 06:47:54] [4076] odbcConn (0000000005482450) - add as new connection BECAT: [11/21/13 06:47:54] [4076] odbcConn (0000000005482450) - done BESERVER: [11/21/13 06:47:54] [3948] -1 SetPreferredMachineFromSourceImageID() Calling MSGetPreferredServer.Execute(), Counter(0) BECAT: [11/21/13 06:47:54] [4076] Input Parameters for BackupSet_View : [26]({75678231-04FD-4260-8C07-449F246CA607}),[227](0) BECAT: [11/21/13 06:47:54] [4076] SQL connection: 8018fd0 select * from ControlInfo BECAT: [11/21/13 06:47:54] [4076] SELECT *, 0 FROM ImageObjectView where 1=1 AND ImageGUID = {75678231-04FD-4260-8C07-449F246CA607} AND ExpirationPolicyID != '9D094A9C-BBBB-402c-A35D-8A46984A19D2' AND ExtStatus & 536870912 = 0 ORDER BY BackupTimeUTC DESC BECAT: [11/21/13 06:47:54] [4076] SQL connection: 8018fd0 SELECT *, 0 FROM ImageObjectView where 1=1 AND ImageGUID = ? AND ExpirationPolicyID != '9D094A9C-BBBB-402c-A35D-8A46984A19D2' AND ExtStatus & ? = 0 ORDER BY BackupTimeUTC DESC BECAT: [11/21/13 06:47:54] [4076] result(0) numRetry(300) BECAT: [11/21/13 06:47:54] [4076] GetRecordCount BECAT: [11/21/13 06:47:54] [4076] GetRecordCount BECAT: [11/21/13 06:47:54] [4076] Number of records returned: 1 BECAT: [11/21/13 06:47:54] [4076] GetRecordCount BECAT: [11/21/13 06:47:54] [4076] GetRecordCount BECAT: [11/21/13 06:47:54] [4076] CatDaoIterator:: Reset() return 1 BECAT: [11/21/13 06:47:54] [4076] return 1 BECAT: [11/21/13 06:47:54] [4076] InOut Parameters for BackupSet_View : No values BECAT: [11/21/13 06:47:54] [4076] Output Parameters for BackupSet_View : No values BECAT: [11/21/13 06:47:54] [4076] m_sqlqueryTTL( 0 ) BECAT: [11/21/13 06:47:54] [4076] return 0 BECAT: [11/21/13 06:47:54] [4076] imageFromXML( 0 ) BECAT: [11/21/13 06:47:54] [4076] adding index to cache : 1 - 1 BECAT: [11/21/13 06:47:54] [4076] return ( 1 ) BECAT: [11/21/13 06:47:54] [4076] Current row (0) : [119](EMDSERVER),[120](2) BECAT: [11/21/13 06:47:54] [4076] SQLQuery (BackupSet_View) Time Spend = 0 ms - ReturnedCount (1) BECAT: [11/21/13 06:47:54] [4076] free connection (0000000005482450) - # of connections (1) BECAT: [11/21/13 06:47:54] [4076] odbcConn (0000000005482450) BECAT: [11/21/13 06:47:54] [4076] odbcConn (0000000005482450) - gain lock BECAT: [11/21/13 06:47:54] [4076] odbcConn (0000000005482450) - done BECAT: [11/21/13 06:47:54] [4076] free connection (0000000000000000) - # of connections (0) - done BESERVER: [11/21/13 06:47:54] [3948] -1 CJob::dispatchTask(EMDServer.primarycare.local Restore 00003): GetIsJobRunningAsCheckpointRestart()==false, ExecutionID=JobHistoryID=7E038157-597C-4D99-8052-F95ED9FB18C1 BESERVER: [11/21/13 06:47:54] [3672] -1 ActiveState::doDispatch( ): 'EMDServer.primarycare.local Restore 00003' BESERVER: [11/21/13 06:47:54] [3672] -1 LocalSvr, runTask():{3f8beb0d-997b-4251-b830-6c07e3427c7f} BESERVER: [11/21/13 06:47:54] [3672] -1 BengineWrapper runtask() start BESERVER: [11/21/13 06:47:54] [3672] -1 BengineWrapper JobEngine.Open() OK BESERVER: [11/21/13 06:47:54] [3948] 16 CJobManagerBO::Query QUERY_JOBSETUP_MONITOR BESERVER: [11/21/13 06:47:54] [4308] -1 Client requested key (1385059848). BESERVER: [11/21/13 06:47:54] [4308] 01 Server Configuration: Client added: 21 BESERVER: [11/21/13 06:47:54] [4308] -1 Client 'EMDSERVER' connected('','EMDSERVER\Administrator'): 0x7703880 BESERVER: [11/21/13 06:47:54] [4308] 12 Start GetResourceContainerIDUsingScriptDetailData BESERVER: [11/21/13 06:47:54] [4308] 12 GetResourceContainerIDUsingScriptDetailData using ScriptID is returning:ResourceContainer:{6d6c067a-ffc1-43d2-be6b-db709c7b0c47},InternalName:\\EMDServer.primarycare.local, for ScriptID:{30bcc1d9-7d68-4978-b1b4-c0e5e0de1444} BESERVER: [11/21/13 06:47:54] [4308] 12 Start GetResourceContainerIDUsingScriptDetailData BESERVER: [11/21/13 06:47:54] [4308] 12 GetResourceContainerIDUsingScriptDetailData using ResourceID is returning:ResourceContainer:{6d6c067a-ffc1-43d2-be6b-db709c7b0c47},InternalName:\\EMDServer.primarycare.local, for ResourceID:{ab0fbeef-0f10-4789-844e-c4dd684a0b0b} BESERVER: [11/21/13 06:47:54] [3948] 16 FlushCache Message VSN_TYPE_JOBVIEW_UPDATE jobViewQuery Sent size :1 BESERVER: [11/21/13 06:47:54] [4308] 12 CredentialData BEGIN : Just before going to engine, Local Job 1. BESERVER: [11/21/13 06:47:54] [4308] 12 \\EMDServer.primarycare.local, \\EMDServer.primarycare.local, EMDSERVER\Administrator, LogonID:{10011001-1001-1001-0101-010101010101}, ResID:{00000000-0000-0000-0000-000000000000}, ResConID:{6d6c067a-ffc1-43d2-be6b-db709c7b0c47}, Flags:0, DataChanged:0. BESERVER: [11/21/13 06:47:54] [4308] 12 6D6C067A-FFC1-43D2-BE6B-DB709C7B0C47, \\EMDServer.primarycare.local\C:, EMDSERVER\Administrator, LogonID:{10011001-1001-1001-0101-010101010101}, ResID:{fb07e513-737c-4bc4-9fe8-e71ab96a1092}, ResConID:{6d6c067a-ffc1-43d2-be6b-db709c7b0c47}, Flags:8, DataChanged:0. BESERVER: [11/21/13 06:47:54] [4308] 12 6D6C067A-FFC1-43D2-BE6B-DB709C7B0C47, \\EMDServer.primarycare.local\E:, EMDSERVER\Administrator, LogonID:{10011001-1001-1001-0101-010101010101}, ResID:{ca274df1-c729-427d-b566-5aaccf78933c}, ResConID:{6d6c067a-ffc1-43d2-be6b-db709c7b0c47}, Flags:8, DataChanged:0. BESERVER: [11/21/13 06:47:54] [4308] 12 6D6C067A-FFC1-43D2-BE6B-DB709C7B0C47, EMDServer.primarycare.local\Microsoft SQL Server Instances, EMDSERVER\Administrator, LogonID:{10011001-1001-1001-0101-010101010101}, ResID:{c47c10b9-f556-4577-a9d7-71e386184f48}, ResConID:{6d6c067a-ffc1-43d2-be6b-db709c7b0c47}, Flags:8, DataChanged:0. BESERVER: [11/21/13 06:47:54] [4308] 12 6D6C067A-FFC1-43D2-BE6B-DB709C7B0C47, \\EMDServer.primarycare.local\System?State, EMDSERVER\Administrator, LogonID:{10011001-1001-1001-0101-010101010101}, ResID:{ab0fbeef-0f10-4789-844e-c4dd684a0b0b}, ResConID:{6d6c067a-ffc1-43d2-be6b-db709c7b0c47}, Flags:8, DataChanged:0. BESERVER: [11/21/13 06:47:54] [4308] 12 CredentialData END : Just before going to engine, Local Job 1. BESERVER: [11/21/13 06:47:54] [4308] 12 Match found, Scripts DeviceSelectionName:\\EMDServer.primarycare.local, Cred ResourceName:\\EMDServer.primarycare.local, Scripts ResourceName:, Row:0, RecType:1. BESERVER: [11/21/13 06:47:54] [4308] 12 Match found, Scripts DeviceSelectionName:\\EMDServer.primarycare.local\System?State, Cred ResourceName:\\EMDServer.primarycare.local\System?State, Scripts ResourceName:\\EMDServer.primarycare.local\System?State, Row:1, RecType:0. BENGINE: [11/21/13 06:47:55] [4300] 2013-11-21T06:47:54.729 [server] + jobengine.cpp (774): BENGINE: [11/21/13 06:47:55] [4300] 2013-11-21T06:47:54.729 [server] | Launching job {3F8BEB0D-997B-4251-B830-6C07E3427C7F} BENGINE: [11/21/13 06:47:55] [4300] 2013-11-21T06:47:54.729 [server] - RunJobWithGuidAndServer( {3F8BEB0D-997B-4251-B830-6C07E3427C7F}, EMDSERVER ) = 0 BESERVER: [11/21/13 06:47:54] [3672] -1 BengineWrapper JobEngine.RunJobWithGuidAndServer() rc=0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.729 [server] + jobengine.cpp (947): BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.729 [server] | Thread for job EMDServer.primarycare.local Restore 00003 ({3F8BEB0D-997B-4251-B830-6C07E3427C7F}) starting BESERVER: [11/21/13 06:47:54] [3672] -1 JobManager: BengineWrapper RunJob() done BESERVER: [11/21/13 06:47:54] [3672] 12 LocalSvr, RunTask: EngineHandedJob SUCCESS, jobid:{3f8beb0d-997b-4251-b830-6c07e3427c7f}. BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.741 [engapi] + serialhelper.cpp (235): BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.741 [engapi] | softwareEdition = 8 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.742 [engapi] + serialhelper.cpp (237): BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.742 [engapi] | agentMask = 1900346992 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.742 [engapi] + serialhelper.cpp (239): BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.742 [engapi] | agentMask2 = 0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.742 [engapi] + serialhelper.cpp (242): BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.742 [engapi] | installedMask = 1910820540 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.742 [engapi] + serialhelper.cpp (244): BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.742 [engapi] | installedMask2 = 417 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.749 [engapi] + serialhelper.cpp (248): BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.749 [engapi] | Eval = False BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.749 [engapi] + serialhelper.cpp (250): BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.749 [engapi] | EvalDaysRemaining = 0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.750 [ndmp\ndmpclient] - viaNetwork enabled 0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.750 [ndmp\ndmpclient] - viaNetwork useAny 0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.750 [ndmp\ndmpclient] - viaNetwork protocol 0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.750 [ndmp\ndmpclient] - viaNetwork subnet ---><--- BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.750 [ndmp\ndmpclient] - viaNetwork MAC ---><--- BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.750 [ndmp\ndmpclient] - viaNetwork IPv4 Enabled 0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.750 [ndmp\ndmpclient] - viaNetwork IPv4 subnetAddress 0.0.0.0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.750 [ndmp\ndmpclient] - viaNetwork IPv4 subnetMask 0.0.0.0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.750 [ndmp\ndmpclient] - viaNetwork IPv4 autoObtain 0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.750 [ndmp\ndmpclient] - viaNetwork IPv4 physical BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.750 [ndmp\ndmpclient] - viaNetwork selectedProtocol 0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.750 [ndmp\ndmpclient] - viaNetwork InterfaceIndexProtocol 0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.750 [ndmp\ndmpclient] - viaNetwork InterfaceIndex 0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.750 [ndmp\ndmpclient] - viaNetwork subnet BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.750 [ndmp\ndmpclient] - viaNetwork PrefixLength 0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.750 [ndmp\ndmpclient] - viaNetwork ipAddr BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.750 [ndmp\ndmpclient] - viaNetwork FallBackOptions 0xF Protocol Tunnel NIC Subnet BESERVER: [11/21/13 06:47:54] [4308] -1 Client requested key (1385059848). BESERVER: [11/21/13 06:47:54] [4308] 01 Server Configuration: Client added: 22 BESERVER: [11/21/13 06:47:54] [4308] -1 Client 'EMDSERVER' connected('','EMDSERVER\Administrator'): 0x77036e0 BESERVER: [11/21/13 06:47:54] [4308] 01 CServerConfigBO::Query(CBemSharepointFarm* pObj...) use ResourceContainer BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.754 [engapi] + loginacct.cpp (304): BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.754 [engapi] | Adding credentials for resource: '\\EMDSERVER'. BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.756 [engapi] + loginacct.cpp (304): BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.756 [engapi] | Adding credentials for resource: '\\EMDServer.primarycare.local'. BESERVER: [11/21/13 06:47:54] [4308] 01 Server Configuration: Client removed: 21 BESERVER: [11/21/13 06:47:54] [4308] -1 Client 'EMDSERVER' Disconnected:0x77036e0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.756 [engapi] - ***************************************************************** BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.756 [engapi] - Load LJ Credential List - (5) BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.756 [engapi] - ***************************************************************** BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.756 [engapi] - Record - 0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.756 [engapi] - resource - \\EMDServer.primarycare.local BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.756 [engapi] - account name - EMDSERVER\Administrator BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.756 [engapi] - account ID - 10011001-1001-1001-0101-010101010101 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.756 [engapi] - resource ID - 00000000-0000-0000-0000-000000000000 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.756 [engapi] - resource Container ID - 6D6C067A-FFC1-43D2-BE6B-DB709C7B0C47 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.756 [engapi] - server - \\EMDServer.primarycare.local BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.756 [engapi] - server ID - 81AB6F0B-4601-4642-8088-DB4F7DC612BE BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.756 [engapi] - resource OS ID - 0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.756 [engapi] - resource OS Ver - 0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.756 [engapi] - resource Type - 0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.756 [engapi] - resource Sub Type - 0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.757 [engapi] - flags - 0x0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.757 [engapi] - update date time - 11/21/2013 6:12:14 PM BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.757 [engapi] - Adding to run-time credentials list BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.757 [engapi] - A password is included BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.757 [engapi] - This is a normal resource record. BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.757 [engapi] + loginacct.cpp (315): BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.757 [engapi] | Replacing credentials for resource: '\\EMDServer.primarycare.local'. BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.757 [engapi] - This is a server record. Adding the credential information with the server name(s) and a blank resource name. BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.757 [engapi] + loginacct.cpp (304): BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.757 [engapi] | Adding credentials for resource: ''. BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.757 [engapi] + loginacct.cpp (315): BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.757 [engapi] | Replacing credentials for resource: '\\EMDServer.primarycare.local'. BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.757 [engapi] + loginacct.cpp (304): BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.757 [engapi] | Adding credentials for resource: 'EMDServer.primarycare.local'. BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.757 [engapi] - ***************************************************************** BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.757 [engapi] - Record - 1 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.757 [engapi] - resource - \\EMDServer.primarycare.local\C: BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.757 [engapi] - account name - EMDSERVER\Administrator BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.757 [engapi] - account ID - 10011001-1001-1001-0101-010101010101 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.757 [engapi] - resource ID - FB07E513-737C-4BC4-9FE8-E71AB96A1092 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.757 [engapi] - resource Container ID - 6D6C067A-FFC1-43D2-BE6B-DB709C7B0C47 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.757 [engapi] - server - 6D6C067A-FFC1-43D2-BE6B-DB709C7B0C47 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.757 [engapi] - server ID - 81AB6F0B-4601-4642-8088-DB4F7DC612BE BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.757 [engapi] - resource OS ID - 14 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.757 [engapi] - resource OS Ver - 4 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.757 [engapi] - resource Type - 1 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.757 [engapi] - resource Sub Type - 4 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.758 [engapi] - flags - 0x8 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.758 [engapi] - update date time - 11/21/2013 6:06:57 PM BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.758 [engapi] - Adding to run-time credentials list BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.758 [engapi] - A password is included BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.758 [engapi] - This is a normal resource record. BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.758 [engapi] + loginacct.cpp (304): BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.758 [engapi] | Adding credentials for resource: '\\EMDServer.primarycare.local\C:'. BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.758 [engapi] - ***************************************************************** BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.758 [engapi] - Record - 2 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.758 [engapi] - resource - \\EMDServer.primarycare.local\E: BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.758 [engapi] - account name - EMDSERVER\Administrator BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.758 [engapi] - account ID - 10011001-1001-1001-0101-010101010101 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.758 [engapi] - resource ID - CA274DF1-C729-427D-B566-5AACCF78933C BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.758 [engapi] - resource Container ID - 6D6C067A-FFC1-43D2-BE6B-DB709C7B0C47 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.758 [engapi] - server - 6D6C067A-FFC1-43D2-BE6B-DB709C7B0C47 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.758 [engapi] - server ID - 81AB6F0B-4601-4642-8088-DB4F7DC612BE BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.758 [engapi] - resource OS ID - 14 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.758 [engapi] - resource OS Ver - 4 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.758 [engapi] - resource Type - 1 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.758 [engapi] - resource Sub Type - 4 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.758 [engapi] - flags - 0x8 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.758 [engapi] - update date time - 11/21/2013 6:06:57 PM BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.758 [engapi] - Adding to run-time credentials list BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.758 [engapi] - A password is included BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.758 [engapi] - This is a normal resource record. BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.758 [engapi] + loginacct.cpp (304): BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.758 [engapi] | Adding credentials for resource: '\\EMDServer.primarycare.local\E:'. BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.759 [engapi] - ***************************************************************** BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.759 [engapi] - Record - 3 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.759 [engapi] - resource - EMDServer.primarycare.local\Microsoft SQL Server Instances BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.759 [engapi] - account name - EMDSERVER\Administrator BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.759 [engapi] - account ID - 10011001-1001-1001-0101-010101010101 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.759 [engapi] - resource ID - C47C10B9-F556-4577-A9D7-71E386184F48 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.759 [engapi] - resource Container ID - 6D6C067A-FFC1-43D2-BE6B-DB709C7B0C47 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.759 [engapi] - server - 6D6C067A-FFC1-43D2-BE6B-DB709C7B0C47 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.759 [engapi] - server ID - 81AB6F0B-4601-4642-8088-DB4F7DC612BE BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.759 [engapi] - resource OS ID - 43 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.759 [engapi] - resource OS Ver - 1 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.759 [engapi] - resource Type - 17 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.759 [engapi] - resource Sub Type - 3 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.759 [engapi] - flags - 0x8 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.759 [engapi] - update date time - 11/21/2013 6:06:57 PM BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.759 [engapi] - Adding to run-time credentials list BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.759 [engapi] - A password is included BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.759 [engapi] - This is a normal resource record. BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.759 [engapi] + loginacct.cpp (304): BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.759 [engapi] | Adding credentials for resource: 'EMDServer.primarycare.local\Microsoft SQL Server Instances'. BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.759 [engapi] - ***************************************************************** BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.759 [engapi] - Record - 4 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.759 [engapi] - resource - \\EMDServer.primarycare.local\System?State BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.759 [engapi] - account name - EMDSERVER\Administrator BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.759 [engapi] - account ID - 10011001-1001-1001-0101-010101010101 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.759 [engapi] - resource ID - AB0FBEEF-0F10-4789-844E-C4DD684A0B0B BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.759 [engapi] - resource Container ID - 6D6C067A-FFC1-43D2-BE6B-DB709C7B0C47 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.759 [engapi] - server - 6D6C067A-FFC1-43D2-BE6B-DB709C7B0C47 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.760 [engapi] - server ID - 81AB6F0B-4601-4642-8088-DB4F7DC612BE BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.760 [engapi] - resource OS ID - 59 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.760 [engapi] - resource OS Ver - 0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.760 [engapi] - resource Type - 28 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.760 [engapi] - resource Sub Type - 1 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.760 [engapi] - flags - 0x8 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.760 [engapi] - update date time - 11/21/2013 6:06:57 PM BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.760 [engapi] - Adding to run-time credentials list BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.760 [engapi] - A password is included BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.760 [engapi] - This is a normal resource record. BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.760 [engapi] + loginacct.cpp (304): BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.760 [engapi] | Adding credentials for resource: '\\EMDServer.primarycare.local\System?State'. BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.760 [engapi] - ***************************************************************** BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.760 [engapi] - ***************************************************************** BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.760 [engapi] - Dump Login Account List (8) - After Credentials List Load BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.760 [engapi] - ***************************************************************** BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.760 [engapi] - Record BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.760 [engapi] - resource - \\EMDSERVER BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.760 [engapi] - userName - EMDSERVER\Administrator BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.760 [engapi] - account ID - 10011001-1001-1001-0101-010101010101 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.760 [engapi] - resource ID - 00000000-0000-0000-0000-000000000000 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.760 [engapi] - resource Container ID - 00000000-0000-0000-0000-000000000000 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.760 [engapi] - server ID - 00000000-0000-0000-0000-000000000000 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.760 [engapi] - resourceOSId - 0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.760 [engapi] - resourceOSVer - 0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.760 [engapi] - resourceType - 0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.760 [engapi] - resourceSubType - 0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.760 [engapi] - login_flags - 0x0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.760 [engapi] - login_time - 0x0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.761 [engapi] - bestFitRec - 0x0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.761 [engapi] - password included BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.761 [engapi] - ***************************************************************** BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.761 [engapi] - Record BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.761 [engapi] - resource - \\EMDServer.primarycare.local BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.761 [engapi] - userName - EMDSERVER\Administrator BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.761 [engapi] - account ID - 10011001-1001-1001-0101-010101010101 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.761 [engapi] - resource ID - 00000000-0000-0000-0000-000000000000 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.761 [engapi] - resource Container ID - 00000000-0000-0000-0000-000000000000 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.761 [engapi] - server ID - 00000000-0000-0000-0000-000000000000 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.761 [engapi] - resourceOSId - 0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.761 [engapi] - resourceOSVer - 0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.761 [engapi] - resourceType - 0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.761 [engapi] - resourceSubType - 0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.761 [engapi] - login_flags - 0x0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.761 [engapi] - login_time - 0x0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.761 [engapi] - bestFitRec - 0x0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.761 [engapi] - password included BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.761 [engapi] - ***************************************************************** BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.761 [engapi] - Record BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.761 [engapi] - resource - BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.761 [engapi] - userName - EMDSERVER\Administrator BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.761 [engapi] - account ID - 10011001-1001-1001-0101-010101010101 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.761 [engapi] - resource ID - 00000000-0000-0000-0000-000000000000 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.761 [engapi] - resource Container ID - 6D6C067A-FFC1-43D2-BE6B-DB709C7B0C47 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.761 [engapi] - server ID - 81AB6F0B-4601-4642-8088-DB4F7DC612BE BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.761 [engapi] - resourceOSId - 0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.761 [engapi] - resourceOSVer - 0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.761 [engapi] - resourceType - 0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.761 [engapi] - resourceSubType - 0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.761 [engapi] - login_flags - 0x0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.762 [engapi] - login_time - 0x528e4cfe BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.762 [engapi] - bestFitRec - 0x0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.762 [engapi] - password included BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.762 [engapi] - ***************************************************************** BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.762 [engapi] - Record BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.762 [engapi] - resource - EMDServer.primarycare.local BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.762 [engapi] - userName - EMDSERVER\Administrator BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.762 [engapi] - account ID - 10011001-1001-1001-0101-010101010101 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.762 [engapi] - resource ID - 00000000-0000-0000-0000-000000000000 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.762 [engapi] - resource Container ID - 6D6C067A-FFC1-43D2-BE6B-DB709C7B0C47 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.762 [engapi] - server ID - 81AB6F0B-4601-4642-8088-DB4F7DC612BE BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.762 [engapi] - resourceOSId - 0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.762 [engapi] - resourceOSVer - 0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.762 [engapi] - resourceType - 0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.762 [engapi] - resourceSubType - 0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.762 [engapi] - login_flags - 0x0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.762 [engapi] - login_time - 0x528e4cfe BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.762 [engapi] - bestFitRec - 0x0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.762 [engapi] - password included BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.762 [engapi] - ***************************************************************** BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.762 [engapi] - Record BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.762 [engapi] - resource - \\EMDServer.primarycare.local\C: BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.762 [engapi] - userName - EMDSERVER\Administrator BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.762 [engapi] - account ID - 10011001-1001-1001-0101-010101010101 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.762 [engapi] - resource ID - FB07E513-737C-4BC4-9FE8-E71AB96A1092 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.762 [engapi] - resource Container ID - 6D6C067A-FFC1-43D2-BE6B-DB709C7B0C47 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.762 [engapi] - server ID - 81AB6F0B-4601-4642-8088-DB4F7DC612BE BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.762 [engapi] - resourceOSId - 14 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.762 [engapi] - resourceOSVer - 4 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.763 [engapi] - resourceType - 1 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.763 [engapi] - resourceSubType - 4 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.763 [engapi] - login_flags - 0x8 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.763 [engapi] - login_time - 0x528e4bc1 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.763 [engapi] - bestFitRec - 0x0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.763 [engapi] - password included BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.763 [engapi] - ***************************************************************** BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.763 [engapi] - Record BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.763 [engapi] - resource - \\EMDServer.primarycare.local\E: BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.763 [engapi] - userName - EMDSERVER\Administrator BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.763 [engapi] - account ID - 10011001-1001-1001-0101-010101010101 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.763 [engapi] - resource ID - CA274DF1-C729-427D-B566-5AACCF78933C BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.763 [engapi] - resource Container ID - 6D6C067A-FFC1-43D2-BE6B-DB709C7B0C47 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.763 [engapi] - server ID - 81AB6F0B-4601-4642-8088-DB4F7DC612BE BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.763 [engapi] - resourceOSId - 14 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.763 [engapi] - resourceOSVer - 4 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.763 [engapi] - resourceType - 1 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.763 [engapi] - resourceSubType - 4 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.763 [engapi] - login_flags - 0x8 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.763 [engapi] - login_time - 0x528e4bc1 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.763 [engapi] - bestFitRec - 0x0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.763 [engapi] - password included BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.763 [engapi] - ***************************************************************** BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.763 [engapi] - Record BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.763 [engapi] - resource - EMDServer.primarycare.local\Microsoft SQL Server Instances BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.763 [engapi] - userName - EMDSERVER\Administrator BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.763 [engapi] - account ID - 10011001-1001-1001-0101-010101010101 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.763 [engapi] - resource ID - C47C10B9-F556-4577-A9D7-71E386184F48 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.763 [engapi] - resource Container ID - 6D6C067A-FFC1-43D2-BE6B-DB709C7B0C47 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.763 [engapi] - server ID - 81AB6F0B-4601-4642-8088-DB4F7DC612BE BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.764 [engapi] - resourceOSId - 43 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.764 [engapi] - resourceOSVer - 1 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.764 [engapi] - resourceType - 17 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.764 [engapi] - resourceSubType - 3 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.764 [engapi] - login_flags - 0x8 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.764 [engapi] - login_time - 0x528e4bc1 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.764 [engapi] - bestFitRec - 0x0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.764 [engapi] - password included BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.764 [engapi] - ***************************************************************** BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.764 [engapi] - Record BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.764 [engapi] - resource - \\EMDServer.primarycare.local\System?State BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.764 [engapi] - userName - EMDSERVER\Administrator BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.764 [engapi] - account ID - 10011001-1001-1001-0101-010101010101 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.764 [engapi] - resource ID - AB0FBEEF-0F10-4789-844E-C4DD684A0B0B BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.764 [engapi] - resource Container ID - 6D6C067A-FFC1-43D2-BE6B-DB709C7B0C47 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.764 [engapi] - server ID - 81AB6F0B-4601-4642-8088-DB4F7DC612BE BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.764 [engapi] - resourceOSId - 59 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.764 [engapi] - resourceOSVer - 0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.764 [engapi] - resourceType - 28 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.764 [engapi] - resourceSubType - 1 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.764 [engapi] - login_flags - 0x8 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.764 [engapi] - login_time - 0x528e4bc1 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.764 [engapi] - bestFitRec - 0x0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.764 [engapi] - password included BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.764 [engapi] - ***************************************************************** BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.764 [engapi] - ENG_GetResourceContainerIDFromLJCredentials() returning 6D6C067A-FFC1-43D2-BE6B-DB709C7B0C47 BENGINE: [11/21/13 06:47:55] [3388] 2013-11-21T06:47:54.764 [server] - Updating status for: 'EMDServer.primarycare.local Restore 00003' (0x0 0x0) BENGINE: [11/21/13 06:47:55] [3388] 2013-11-21T06:47:54.765 [server] - Status for: 'EMDServer.primarycare.local Restore 00003' updated BENGINE: [11/21/13 06:47:54] [4840] "Cluster" key is not present in the registry BENGINE: [11/21/13 06:47:54] [4840] Did not detect Microsoft cluster () BENGINE: [11/21/13 06:47:54] [4840] VCS cluster keys do not appear to be present in the registry BENGINE: [11/21/13 06:47:54] [4840] Did not detect VCS cluster () BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.773 [server] - Checking for Evaluation expiration. BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.773 [engapi] - ***************************************************************** BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.773 [engapi] - * ENGSCRPT: Loadng JOB_SELECTIONS script 2 detail entries task - 600 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.773 [engapi] - * BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.773 [engapi] - * BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.773 - Informational: SSP is disabled (default) in 'SetSspConfigItems:3814' BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.779 [engapi] - FindLoginCredentials() looking for resource BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.779 [engapi] - base credentials set to - EMDSERVER\Administrator BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.779 [engapi] - base credentials GUID set to - 10011001-1001-1001-0101-010101010101 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.779 [engapi] - ***************************************************************** BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.779 [engapi] - * Script Line 0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.779 [engapi] - * BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.779 [engapi] - * resource - \\EMDServer.primarycare.local BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.779 [engapi] - * account name - EMDSERVER\Administrator BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.779 [engapi] - * account ID - 10011001-1001-1001-0101-010101010101 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.779 [engapi] - * resource ID - 00000000-0000-0000-0000-000000000000 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.779 [engapi] - * oper - 0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.779 [engapi] - * DevTp/Sub - -1--1 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.779 [engapi] - * OSId/Ver - 0-0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.779 [engapi] - * flags - 0x0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.779 [engapi] - * A password is included BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.779 [engapi] - Preventing Credentials Only record from getting itno the script. BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.779 [engapi] - ***************************************************************** BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.779 [engapi] - * Script Line 1 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.779 [engapi] - * BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.779 [engapi] - * resource - \\EMDServer.primarycare.local\System?State BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.779 [engapi] - * account name - (null) BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.779 [engapi] - * account ID - 00000000-0000-0000-0000-000000000000 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.779 [engapi] - * resource ID - AB0FBEEF-0F10-4789-844E-C4DD684A0B0B BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.780 [engapi] - * oper - 1 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.780 [engapi] - * DevTp/Sub - 28-1 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.780 [engapi] - * OSId/Ver - 59-0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.780 [engapi] - * flags - 0x1 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.780 [engapi] - * No password Found BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.780 [engapi] - FindLoginCredentials() attempting match to GUID = AB0FBEEF-0F10-4789-844E-C4DD684A0B0B BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.780 [engapi] - FindLoginCredentials() found by GUID BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.780 [engapi] - * BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.780 [engapi] - * Credentials retrieved from cred list (in place of script creds) BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.780 [engapi] - * BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.780 [engapi] - * resource - \\EMDServer.primarycare.local\System?State BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.780 [engapi] - * account name - EMDSERVER\Administrator BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.780 [engapi] - * account ID - 10011001-1001-1001-0101-010101010101 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.780 [engapi] - * A password is included BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.780 [engapi] - ENGSCRPT: ConvertDeviceName osId = 0X3b BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.780 [engapi] - ENGSCRPT: IN - oldDeviceName = \\EMDServer.primarycare.local\System?State BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.780 [engapi] - ENGSCRPT: OUT - newName = \\EMDServer.primarycare.local\System?State BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.780 [engapi] - * Task Specific Operations (0x258) BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.780 [engapi] - * Configuring Job BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.780 [engapi] - * Configuring TASK_RESTORE BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.780 [engapi] - * Done with task specific configuration BESERVER: [11/21/13 06:47:54] [4308] -1 Client requested key (1385059848). BESERVER: [11/21/13 06:47:54] [4308] 01 Server Configuration: Client added: 22 BESERVER: [11/21/13 06:47:54] [4308] -1 Client 'EMDSERVER' connected('','EMDSERVER\Administrator'): 0x77036e0 BECAT: [11/21/13 06:47:54] [4076] 00000000054828B0 - # of connections : 1 BECAT: [11/21/13 06:47:54] [4076] odbcConn (00000000054828B0) BECAT: [11/21/13 06:47:54] [4076] odbcConn (00000000054828B0) - gain lock BECAT: [11/21/13 06:47:54] [4076] odbcConn (00000000054828B0) - add as new connection BECAT: [11/21/13 06:47:54] [4076] odbcConn (00000000054828B0) - done BECAT: [11/21/13 06:47:54] [4076] Input Parameters for BackupSet_View : [1]({DCF8E563-58F0-48A6-89D2-8D2A2AA3A5C5}),[3](5),[227](0) BECAT: [11/21/13 06:47:54] [4076] SQL connection: 8018fd0 select * from ControlInfo BECAT: [11/21/13 06:47:54] [4076] SELECT *, 0 FROM ImageObjectView where 1=1 AND MediaFamilyGuid = {DCF8E563-58F0-48A6-89D2-8D2A2AA3A5C5} AND ImageNumber = 5 AND ExpirationPolicyID != '9D094A9C-BBBB-402c-A35D-8A46984A19D2' AND ExtStatus & 536870912 = 0 ORDER BY BackupTimeUTC DESC BECAT: [11/21/13 06:47:54] [4076] SQL connection: 8018fd0 SELECT *, 0 FROM ImageObjectView where 1=1 AND MediaFamilyGuid = ? AND ImageNumber = ? AND ExpirationPolicyID != '9D094A9C-BBBB-402c-A35D-8A46984A19D2' AND ExtStatus & ? = 0 ORDER BY BackupTimeUTC DESC BECAT: [11/21/13 06:47:54] [4076] result(0) numRetry(300) BECAT: [11/21/13 06:47:54] [4076] GetRecordCount BECAT: [11/21/13 06:47:54] [4076] GetRecordCount BECAT: [11/21/13 06:47:54] [4076] Number of records returned: 1 BECAT: [11/21/13 06:47:54] [4076] GetRecordCount BECAT: [11/21/13 06:47:54] [4076] GetRecordCount BECAT: [11/21/13 06:47:54] [4076] CatDaoIterator:: Reset() return 1 BECAT: [11/21/13 06:47:54] [4076] return 1 BECAT: [11/21/13 06:47:54] [4076] InOut Parameters for BackupSet_View : No values BECAT: [11/21/13 06:47:54] [4076] Output Parameters for BackupSet_View : No values BECAT: [11/21/13 06:47:54] [4076] m_sqlqueryTTL( 0 ) BECAT: [11/21/13 06:47:54] [4076] return 0 BECAT: [11/21/13 06:47:54] [4076] imageFromXML( 0 ) BECAT: [11/21/13 06:47:54] [4076] adding index to cache : 1 - 1 BECAT: [11/21/13 06:47:54] [4076] return ( 1 ) BECAT: [11/21/13 06:47:54] [4076] Current row (0) : [3](1),[50](0),[4]({24586468-35F7-4634-9915-8C71679D288B}),[5](1),[6](5),[13](4),[16](530503477),[17](5),[7](59),[8](0),[9](4097),[55](117571587),[11](\\EMDServer.primarycare.local\System?State),[22](),[10](EMDServer.primarycare.local Backup 00001-Full),[23](LTO000005),[57](28),[58](1),[59](8),[60](1),[53]({75678231-04FD-4260-8C07-449F246CA607}),[54]({75678231-04FD-4260-8C07-449F246CA607}),[39](0),[65](),[66](),[67](0),[68](0),[69](0),[85](),[86](),[87](),[70]({C0913FA4-F468-4537-9D5F-56EDB1AE7317}),[72]({8A4B272A-28D1-452C-A531-4F10497688B8}),[73]({75678231-04FD-4260-8C07-449F246CA607}),[52]({DCF8E563-58F0-48A6-89D2-8D2A2AA3A5C5}_5.xml),[77](1580),[80](1384945096),[81](0),[28](EMDSERVER) BECAT: [11/21/13 06:47:54] [4076] imageFromXML( 0 ) BECAT: [11/21/13 06:47:54] [4076] return ( 0 ) BECAT: [11/21/13 06:47:54] [4076] imageFromXML( 0 ) BECAT: [11/21/13 06:47:54] [4076] return ( 0 ) BECAT: [11/21/13 06:47:54] [4076] SQLQuery (BackupSet_View) Time Spend = 0 ms - ReturnedCount (1) BECAT: [11/21/13 06:47:54] [4076] free connection (00000000054828B0) - # of connections (1) BECAT: [11/21/13 06:47:54] [4076] odbcConn (00000000054828B0) BECAT: [11/21/13 06:47:54] [4076] odbcConn (00000000054828B0) - gain lock BECAT: [11/21/13 06:47:54] [4076] odbcConn (00000000054828B0) - done BECAT: [11/21/13 06:47:54] [4076] free connection (0000000000000000) - # of connections (0) - done BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.802 [engapi] - * sibling count - 1 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.802 [engapi] - BSD date: 11:20:2013 time: 10:58:16 BESERVER: [11/21/13 06:47:54] [4308] 01 Server Configuration: Client removed: 21 BESERVER: [11/21/13 06:47:54] [4308] -1 Client 'EMDSERVER' Disconnected:0x77036e0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.802 [engapi] - ENG_ReadInJob() Done loading script items ret_val - 0x0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.802 [engapi] - * Added BSD (0x8134680) BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.802 [engapi] + loginacct.cpp (315): BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.802 [engapi] | Replacing credentials for resource: '\\EMDServer.primarycare.local\System?State'. BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.805 [engapi] - * BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.805 [engapi] - * Done loading script items ret_val - 0x0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.805 [engapi] - ***************************************************************** BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.805 [engapi] - (15073288)ENG_ReadInJob: Adjusting ExecView BSDs BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.805 [engapi] - (15073288)ENG_ReadInJob: Handling redirected restore as necessary. BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.805 [engapi] - (3156)ENG_ReadInJob: Returning 0x0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.805 [engapi] - ***************************************************************** BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.805 [engapi] - Dump Login Account List (8) - After SCR_LoadScript() BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.805 [engapi] - ***************************************************************** BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.805 [engapi] - Record BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.805 [engapi] - resource - \\EMDSERVER BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.805 [engapi] - userName - EMDSERVER\Administrator BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.805 [engapi] - account ID - 10011001-1001-1001-0101-010101010101 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.805 [engapi] - resource ID - 00000000-0000-0000-0000-000000000000 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.805 [engapi] - resource Container ID - 00000000-0000-0000-0000-000000000000 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.805 [engapi] - server ID - 00000000-0000-0000-0000-000000000000 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.805 [engapi] - resourceOSId - 0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.805 [engapi] - resourceOSVer - 0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.805 [engapi] - resourceType - 0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.805 [engapi] - resourceSubType - 0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.805 [engapi] - login_flags - 0x0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.805 [engapi] - login_time - 0x0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.805 [engapi] - bestFitRec - 0x0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.805 [engapi] - password included BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.805 [engapi] - ***************************************************************** BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.806 [engapi] - Record BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.806 [engapi] - resource - \\EMDServer.primarycare.local BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.806 [engapi] - userName - EMDSERVER\Administrator BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.806 [engapi] - account ID - 10011001-1001-1001-0101-010101010101 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.806 [engapi] - resource ID - 00000000-0000-0000-0000-000000000000 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.806 [engapi] - resource Container ID - 00000000-0000-0000-0000-000000000000 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.806 [engapi] - server ID - 00000000-0000-0000-0000-000000000000 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.806 [engapi] - resourceOSId - 0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.806 [engapi] - resourceOSVer - 0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.806 [engapi] - resourceType - 0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.806 [engapi] - resourceSubType - 0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.806 [engapi] - login_flags - 0x0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.806 [engapi] - login_time - 0x0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.806 [engapi] - bestFitRec - 0x0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.806 [engapi] - password included BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.806 [engapi] - ***************************************************************** BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.806 [engapi] - Record BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.806 [engapi] - resource - BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.806 [engapi] - userName - EMDSERVER\Administrator BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.806 [engapi] - account ID - 10011001-1001-1001-0101-010101010101 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.806 [engapi] - resource ID - 00000000-0000-0000-0000-000000000000 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.806 [engapi] - resource Container ID - 6D6C067A-FFC1-43D2-BE6B-DB709C7B0C47 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.806 [engapi] - server ID - 81AB6F0B-4601-4642-8088-DB4F7DC612BE BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.806 [engapi] - resourceOSId - 0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.806 [engapi] - resourceOSVer - 0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.806 [engapi] - resourceType - 0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.806 [engapi] - resourceSubType - 0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.806 [engapi] - login_flags - 0x0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.806 [engapi] - login_time - 0x528e4cfe BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.806 [engapi] - bestFitRec - 0x0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.807 [engapi] - password included BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.807 [engapi] - ***************************************************************** BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.807 [engapi] - Record BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.807 [engapi] - resource - EMDServer.primarycare.local BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.807 [engapi] - userName - EMDSERVER\Administrator BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.807 [engapi] - account ID - 10011001-1001-1001-0101-010101010101 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.807 [engapi] - resource ID - 00000000-0000-0000-0000-000000000000 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.807 [engapi] - resource Container ID - 6D6C067A-FFC1-43D2-BE6B-DB709C7B0C47 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.807 [engapi] - server ID - 81AB6F0B-4601-4642-8088-DB4F7DC612BE BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.807 [engapi] - resourceOSId - 0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.807 [engapi] - resourceOSVer - 0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.807 [engapi] - resourceType - 0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.807 [engapi] - resourceSubType - 0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.807 [engapi] - login_flags - 0x0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.807 [engapi] - login_time - 0x528e4cfe BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.807 [engapi] - bestFitRec - 0x0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.807 [engapi] - password included BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.807 [engapi] - ***************************************************************** BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.807 [engapi] - Record BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.807 [engapi] - resource - \\EMDServer.primarycare.local\C: BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.807 [engapi] - userName - EMDSERVER\Administrator BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.807 [engapi] - account ID - 10011001-1001-1001-0101-010101010101 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.807 [engapi] - resource ID - FB07E513-737C-4BC4-9FE8-E71AB96A1092 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.807 [engapi] - resource Container ID - 6D6C067A-FFC1-43D2-BE6B-DB709C7B0C47 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.807 [engapi] - server ID - 81AB6F0B-4601-4642-8088-DB4F7DC612BE BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.807 [engapi] - resourceOSId - 14 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.807 [engapi] - resourceOSVer - 4 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.807 [engapi] - resourceType - 1 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.807 [engapi] - resourceSubType - 4 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.808 [engapi] - login_flags - 0x8 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.808 [engapi] - login_time - 0x528e4bc1 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.808 [engapi] - bestFitRec - 0x0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.808 [engapi] - password included BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.808 [engapi] - ***************************************************************** BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.808 [engapi] - Record BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.808 [engapi] - resource - \\EMDServer.primarycare.local\E: BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.808 [engapi] - userName - EMDSERVER\Administrator BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.808 [engapi] - account ID - 10011001-1001-1001-0101-010101010101 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.808 [engapi] - resource ID - CA274DF1-C729-427D-B566-5AACCF78933C BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.808 [engapi] - resource Container ID - 6D6C067A-FFC1-43D2-BE6B-DB709C7B0C47 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.808 [engapi] - server ID - 81AB6F0B-4601-4642-8088-DB4F7DC612BE BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.808 [engapi] - resourceOSId - 14 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.808 [engapi] - resourceOSVer - 4 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.808 [engapi] - resourceType - 1 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.808 [engapi] - resourceSubType - 4 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.808 [engapi] - login_flags - 0x8 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.808 [engapi] - login_time - 0x528e4bc1 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.808 [engapi] - bestFitRec - 0x0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.808 [engapi] - password included BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.808 [engapi] - ***************************************************************** BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.808 [engapi] - Record BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.808 [engapi] - resource - EMDServer.primarycare.local\Microsoft SQL Server Instances BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.808 [engapi] - userName - EMDSERVER\Administrator BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.808 [engapi] - account ID - 10011001-1001-1001-0101-010101010101 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.808 [engapi] - resource ID - C47C10B9-F556-4577-A9D7-71E386184F48 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.808 [engapi] - resource Container ID - 6D6C067A-FFC1-43D2-BE6B-DB709C7B0C47 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.808 [engapi] - server ID - 81AB6F0B-4601-4642-8088-DB4F7DC612BE BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.808 [engapi] - resourceOSId - 43 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.808 [engapi] - resourceOSVer - 1 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.809 [engapi] - resourceType - 17 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.809 [engapi] - resourceSubType - 3 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.809 [engapi] - login_flags - 0x8 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.809 [engapi] - login_time - 0x528e4bc1 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.809 [engapi] - bestFitRec - 0x0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.809 [engapi] - password included BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.809 [engapi] - ***************************************************************** BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.809 [engapi] - Record BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.809 [engapi] - resource - \\EMDServer.primarycare.local\System?State BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.809 [engapi] - userName - EMDSERVER\Administrator BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.809 [engapi] - account ID - 10011001-1001-1001-0101-010101010101 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.809 [engapi] - resource ID - AB0FBEEF-0F10-4789-844E-C4DD684A0B0B BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.809 [engapi] - resource Container ID - 6D6C067A-FFC1-43D2-BE6B-DB709C7B0C47 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.809 [engapi] - server ID - 81AB6F0B-4601-4642-8088-DB4F7DC612BE BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.809 [engapi] - resourceOSId - 59 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.809 [engapi] - resourceOSVer - 0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.809 [engapi] - resourceType - 28 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.809 [engapi] - resourceSubType - 1 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.809 [engapi] - login_flags - 0x8 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.809 [engapi] - login_time - 0x528e4bc1 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.809 [engapi] - bestFitRec - 0x0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.809 [engapi] - password included BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.809 [engapi] - ***************************************************************** BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.809 [server] - Job::RemoveInvalidBSDs() - 0x0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.815 [server] + restorejob.cpp (1531): BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.815 [server] | Setting system state encapsulated files to 'always overwrite existing'. BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.815 [server] + restorejob.cpp (1543): BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:54.815 [server] | Setting system state components to 'always overwrite existing'. BECAT: [11/21/13 06:47:54] [4076] in : imageGuid({75678231-04FD-4260-8C07-449F246CA607}) BECAT: [11/21/13 06:47:54] [4076] SQL connection: 8018fd0 select * from ControlInfo BECAT: [11/21/13 06:47:54] [4076] Input Parameters for Image_Fragments_View : [26]({75678231-04FD-4260-8C07-449F246CA607}),[160](1),[227](0) BECAT: [11/21/13 06:47:54] [4076] SELECT *, 0 FROM ImageObjectView where 1=1 AND ImageGUID = {75678231-04FD-4260-8C07-449F246CA607} AND ExpirationPolicyID != '9D094A9C-BBBB-402c-A35D-8A46984A19D2' AND ExtStatus & 536870912 = 0 ORDER BY BackupTimeUTC DESC BECAT: [11/21/13 06:47:54] [4076] SQL connection: 8018fd0 SELECT *, 0 FROM ImageObjectView where 1=1 AND ImageGUID = ? AND ExpirationPolicyID != '9D094A9C-BBBB-402c-A35D-8A46984A19D2' AND ExtStatus & ? = 0 ORDER BY BackupTimeUTC DESC BECAT: [11/21/13 06:47:54] [4076] result(0) numRetry(300) BECAT: [11/21/13 06:47:54] [4076] GetRecordCount BECAT: [11/21/13 06:47:54] [4076] GetRecordCount BECAT: [11/21/13 06:47:54] [4076] Number of records returned: 1 BECAT: [11/21/13 06:47:54] [4076] GetRecordCount BECAT: [11/21/13 06:47:54] [4076] GetRecordCount BECAT: [11/21/13 06:47:54] [4076] CatDaoIterator:: Reset() return 1 BECAT: [11/21/13 06:47:54] [4076] return 1 BECAT: [11/21/13 06:47:54] [4076] CatDaoIterator:: Reset() return 1 BECAT: [11/21/13 06:47:54] [4076] return 1 BECAT: [11/21/13 06:47:54] [4076] InOut Parameters for Image_Fragments_View : No values BECAT: [11/21/13 06:47:54] [4076] Output Parameters for Image_Fragments_View : No values BECAT: [11/21/13 06:47:54] [4076] m_sqlqueryTTL( 0 ) BECAT: [11/21/13 06:47:54] [4076] return 0 BECAT: [11/21/13 06:47:54] [4076] Execute return 0 BECAT: [11/21/13 06:47:54] [4076] imageFromXML( 0 ) BECAT: [11/21/13 06:47:54] [4076] adding index to cache : 1 - 1 BECAT: [11/21/13 06:47:54] [4076] return ( 1 ) BECAT: [11/21/13 06:47:54] [4076] Current row (0) : [62](),[63](),[59](8),[2]({DCF8E563-58F0-48A6-89D2-8D2A2AA3A5C5}),[1](1131693004),[4]({24586468-35F7-4634-9915-8C71679D288B}),[3](1),[5](1),[6](5),[75](22192128000),[76](0),[81](0),[82](11395),[90](11395),[83](-1),[91](-1),[84]({97B5EAAB-7C0A-4800-9872-6D5DC643478C}),[88](),[104]({00000000-0000-0000-0000-000000000000}),[89](0),[55](117571587) BECAT: [11/21/13 06:47:54] [4076] imageFromXML( 0 ) BECAT: [11/21/13 06:47:54] [4076] return ( 0 ) BECAT: [11/21/13 06:47:54] [4076] rc( 100 ), vector size( 1 ) BECAT: [11/21/13 06:47:54] [4076] SQLQuery (Image_Fragments_View) Time Spend = 0 ms - ReturnedCount (0) BECAT: [11/21/13 06:47:54] [4076] in : imageGuid({75678231-04FD-4260-8C07-449F246CA607}) BECAT: [11/21/13 06:47:54] [4076] SQL connection: 8018fd0 select * from ControlInfo BECAT: [11/21/13 06:47:54] [4076] Input Parameters for Image_Fragments_View : [26]({75678231-04FD-4260-8C07-449F246CA607}),[160](1),[227](0) BECAT: [11/21/13 06:47:54] [4076] SELECT *, 0 FROM ImageObjectView where 1=1 AND ImageGUID = {75678231-04FD-4260-8C07-449F246CA607} AND ExpirationPolicyID != '9D094A9C-BBBB-402c-A35D-8A46984A19D2' AND ExtStatus & 536870912 = 0 ORDER BY BackupTimeUTC DESC BECAT: [11/21/13 06:47:54] [4076] SQL connection: 8018fd0 SELECT *, 0 FROM ImageObjectView where 1=1 AND ImageGUID = ? AND ExpirationPolicyID != '9D094A9C-BBBB-402c-A35D-8A46984A19D2' AND ExtStatus & ? = 0 ORDER BY BackupTimeUTC DESC BECAT: [11/21/13 06:47:54] [4076] result(0) numRetry(300) BECAT: [11/21/13 06:47:54] [4076] GetRecordCount BECAT: [11/21/13 06:47:54] [4076] GetRecordCount BECAT: [11/21/13 06:47:54] [4076] Number of records returned: 1 BECAT: [11/21/13 06:47:54] [4076] GetRecordCount BECAT: [11/21/13 06:47:54] [4076] GetRecordCount BECAT: [11/21/13 06:47:54] [4076] CatDaoIterator:: Reset() return 1 BECAT: [11/21/13 06:47:54] [4076] return 1 BECAT: [11/21/13 06:47:54] [4076] CatDaoIterator:: Reset() return 1 BECAT: [11/21/13 06:47:54] [4076] return 1 BECAT: [11/21/13 06:47:54] [4076] InOut Parameters for Image_Fragments_View : No values BECAT: [11/21/13 06:47:54] [4076] Output Parameters for Image_Fragments_View : No values BECAT: [11/21/13 06:47:54] [4076] m_sqlqueryTTL( 0 ) BECAT: [11/21/13 06:47:54] [4076] return 0 BECAT: [11/21/13 06:47:54] [4076] Execute return 0 BECAT: [11/21/13 06:47:54] [4076] imageFromXML( 0 ) BECAT: [11/21/13 06:47:54] [4076] adding index to cache : 1 - 1 BECAT: [11/21/13 06:47:54] [4076] return ( 1 ) BECAT: [11/21/13 06:47:54] [4076] Current row (0) : [62](),[63](),[59](8),[2]({DCF8E563-58F0-48A6-89D2-8D2A2AA3A5C5}),[1](1131693004),[4]({24586468-35F7-4634-9915-8C71679D288B}),[3](1),[5](1),[6](5),[75](22192128000),[76](0),[81](0),[82](11395),[90](11395),[83](-1),[91](-1),[84]({97B5EAAB-7C0A-4800-9872-6D5DC643478C}),[88](),[104]({00000000-0000-0000-0000-000000000000}),[89](0) BECAT: [11/21/13 06:47:54] [4076] imageFromXML( 0 ) BECAT: [11/21/13 06:47:54] [4076] return ( 0 ) BECAT: [11/21/13 06:47:54] [4076] rc( 100 ), vector size( 1 ) BECAT: [11/21/13 06:47:54] [4076] SQLQuery (Image_Fragments_View) Time Spend = 0 ms - ReturnedCount (0) BECAT: [11/21/13 06:47:54] [4076] in : inGuid({75678231-04FD-4260-8C07-449F246CA607}), iteratorType(1), guidType(3) BECAT: [11/21/13 06:47:54] [4076] Input Parameters for BackupSet_View : [28](0),[68](1),[87](2),[227](0),[26]({75678231-04FD-4260-8C07-449F246CA607}),[71](8) BECAT: [11/21/13 06:47:54] [4076] SQL connection: 8018fd0 select * from ControlInfo BECAT: [11/21/13 06:47:54] [4076] SELECT *, 0 FROM ImageObjectView where 1=1 AND ImageGUID = {75678231-04FD-4260-8C07-449F246CA607} AND ExpirationPolicyID != '9D094A9C-BBBB-402c-A35D-8A46984A19D2' ORDER BY BackupTimeUTC DESC BECAT: [11/21/13 06:47:54] [4076] SQL connection: 8018fd0 SELECT *, 0 FROM ImageObjectView where 1=1 AND ImageGUID = ? AND ExpirationPolicyID != '9D094A9C-BBBB-402c-A35D-8A46984A19D2' ORDER BY BackupTimeUTC DESC BECAT: [11/21/13 06:47:54] [4076] result(0) numRetry(300) BECAT: [11/21/13 06:47:54] [4076] GetRecordCount BECAT: [11/21/13 06:47:54] [4076] GetRecordCount BECAT: [11/21/13 06:47:54] [4076] Number of records returned: 1 BECAT: [11/21/13 06:47:54] [4076] GetRecordCount BECAT: [11/21/13 06:47:54] [4076] GetRecordCount BECAT: [11/21/13 06:47:54] [4076] CatDaoIterator:: Reset() return 1 BECAT: [11/21/13 06:47:54] [4076] return 1 BECAT: [11/21/13 06:47:54] [4076] InOut Parameters for BackupSet_View : No values BECAT: [11/21/13 06:47:54] [4076] Output Parameters for BackupSet_View : No values BECAT: [11/21/13 06:47:54] [4076] m_sqlqueryTTL( 0 ) BECAT: [11/21/13 06:47:54] [4076] return 0 BECAT: [11/21/13 06:47:54] [4076] imageFromXML( 0 ) BECAT: [11/21/13 06:47:54] [4076] adding index to cache : 1 - 1 BECAT: [11/21/13 06:47:54] [4076] return ( 1 ) BECAT: [11/21/13 06:47:54] [4076] Current row (0) : [53]({75678231-04FD-4260-8C07-449F246CA607}),[2]({DCF8E563-58F0-48A6-89D2-8D2A2AA3A5C5}),[3](1),[6](5),[46](9322371),[1](1131693004),[9](4097),[55](117571587),[92]({00000000-0000-0000-C7E6-7B685CDFEB40}),[93]({687BE6C7-DF5C-40EB-9E69-D3D0195A8E47}),[31]({1F9ED735-0005-0000-0000-000000000000}),[116]({8A4B272A-28D1-452C-A531-4F10497688B8}),[14](2016),[15](15864),[18](0),[19](0),[94](0),[95](0),[96]({00000000-0000-0000-0000-000000000000}),[97]({00000000-0000-0000-0000-000000000000}),[98](0),[99](0),[100]({00000000-0000-0000-0000-000000000000}),[13](4),[7](59),[8](0),[111]( ),[72]({8A4B272A-28D1-452C-A531-4F10497688B8}) BECAT: [11/21/13 06:47:54] [4076] imageFromXML( 0 ) BECAT: [11/21/13 06:47:54] [4076] return ( 0 ) BECAT: [11/21/13 06:47:54] [4076] return (0) BECAT: [11/21/13 06:47:54] [4076] SQLQuery (BackupSet_View) Time Spend = 0 ms - ReturnedCount (1) BECAT: [11/21/13 06:47:54] [4076] in : ScanCatalogedSets ... BECAT: [11/21/13 06:47:54] [4076] SQL connection: 8018fd0 select * from ControlInfo BECAT: [11/21/13 06:47:54] [4076] Input Parameters for BackupSet_ByResource_View : [1]({DCF8E563-58F0-48A6-89D2-8D2A2AA3A5C5}),[3](5) BECAT: [11/21/13 06:47:54] [4076] SELECT *, 0 FROM ImageObjectView where 1=1 AND MediaFamilyGuid = {DCF8E563-58F0-48A6-89D2-8D2A2AA3A5C5} AND ImageNumber = 5 AND ExpirationPolicyID != '9D094A9C-BBBB-402c-A35D-8A46984A19D2' AND ExtStatus & 536870912 = 0 ORDER BY BackupTimeUTC DESC BECAT: [11/21/13 06:47:54] [4076] SQL connection: 8018fd0 SELECT *, 0 FROM ImageObjectView where 1=1 AND MediaFamilyGuid = ? AND ImageNumber = ? AND ExpirationPolicyID != '9D094A9C-BBBB-402c-A35D-8A46984A19D2' AND ExtStatus & ? = 0 ORDER BY BackupTimeUTC DESC BECAT: [11/21/13 06:47:54] [4076] result(0) numRetry(300) BECAT: [11/21/13 06:47:54] [4076] GetRecordCount BECAT: [11/21/13 06:47:54] [4076] GetRecordCount BECAT: [11/21/13 06:47:54] [4076] Number of records returned: 1 BECAT: [11/21/13 06:47:54] [4076] GetRecordCount BECAT: [11/21/13 06:47:54] [4076] GetRecordCount BECAT: [11/21/13 06:47:54] [4076] CatDaoIterator:: Reset() return 1 BECAT: [11/21/13 06:47:54] [4076] return 1 BECAT: [11/21/13 06:47:54] [4076] InOut Parameters for BackupSet_ByResource_View : No values BECAT: [11/21/13 06:47:54] [4076] Output Parameters for BackupSet_ByResource_View : No values BECAT: [11/21/13 06:47:54] [4076] m_sqlqueryTTL( 0 ) BECAT: [11/21/13 06:47:54] [4076] return 0 BECAT: [11/21/13 06:47:54] [4076] imageFromXML( 0 ) BECAT: [11/21/13 06:47:54] [4076] adding index to cache : 1 - 1 BECAT: [11/21/13 06:47:54] [4076] return ( 1 ) BECAT: [11/21/13 06:47:54] [4076] Current row (0) : [7](59),[18](0),[16](530503477),[17](5),[9](4097),[32](5) BECAT: [11/21/13 06:47:54] [4076] Input Parameters for FileHistory : [1]({DCF8E563-58F0-48A6-89D2-8D2A2AA3A5C5}),[3](5),[12](),[13](*.*),[92](1) BECAT: [11/21/13 06:47:54] [4076] SQL connection: 808aa80 select * from ControlInfo BECAT: [11/21/13 06:47:54] [4076] SELECT *, 0 FROM ImageObjectView where 1=1 AND MediaFamilyGuid = {DCF8E563-58F0-48A6-89D2-8D2A2AA3A5C5} AND ImageNumber = 5 AND ExpirationPolicyID != '9D094A9C-BBBB-402c-A35D-8A46984A19D2' AND ExtStatus & 536870912 = 0 ORDER BY BackupTimeUTC DESC BECAT: [11/21/13 06:47:54] [4076] SQL connection: 808aa80 SELECT *, 0 FROM ImageObjectView where 1=1 AND MediaFamilyGuid = ? AND ImageNumber = ? AND ExpirationPolicyID != '9D094A9C-BBBB-402c-A35D-8A46984A19D2' AND ExtStatus & ? = 0 ORDER BY BackupTimeUTC DESC BECAT: [11/21/13 06:47:54] [4076] result(0) numRetry(300) BECAT: [11/21/13 06:47:54] [4076] GetRecordCount BECAT: [11/21/13 06:47:54] [4076] GetRecordCount BECAT: [11/21/13 06:47:54] [4076] Number of records returned: 1 BECAT: [11/21/13 06:47:54] [4076] GetRecordCount BECAT: [11/21/13 06:47:54] [4076] GetRecordCount BECAT: [11/21/13 06:47:54] [4076] CatDaoIterator:: Reset() return 1 BECAT: [11/21/13 06:47:54] [4076] return 1 BECAT: [11/21/13 06:47:54] [4076] imageFromXML( 0 ) BECAT: [11/21/13 06:47:54] [4076] adding index to cache : 1 - 1 BECAT: [11/21/13 06:47:54] [4076] return ( 1 ) BECAT: [11/21/13 06:47:54] [4076] m_image(000000000825A7B0) BECAT: [11/21/13 06:47:54] [4076] OSId(59) PluginId(16) ImageFileName({DCF8E563-58F0-48A6-89D2-8D2A2AA3A5C5}_5.xml) BECAT: [11/21/13 06:47:54] [4076] pluginID(16) remoteQueryTTL(2) BECAT: [11/21/13 06:47:54] [4076] Lock FH file(EMDSERVER\{DCF8E563-58F0-48A6-89D2-8D2A2AA3A5C5}_5.fh) BECAT: [11/21/13 06:47:54] [4076] Lock IMG file(EMDSERVER\{DCF8E563-58F0-48A6-89D2-8D2A2AA3A5C5}_5.xml) BECAT: [11/21/13 06:47:54] [4076] Return CAT_SUCCESS (00000000080AD330) BECAT: [11/21/13 06:47:55] [4076] imageFromXML( 0 ) BECAT: [11/21/13 06:47:55] [4076] return ( 0 ) BECAT: [11/21/13 06:47:55] [4076] m_image(0000000000000000) BECAT: [11/21/13 06:47:55] [4076] OSId(0) PluginId(0) ImageFileName() BECAT: [11/21/13 06:47:55] [4076] Total objects found: 0 BECAT: [11/21/13 06:47:55] [4076] InOut Parameters for FileHistory : No values BECAT: [11/21/13 06:47:55] [4076] Output Parameters for FileHistory : No values BECAT: [11/21/13 06:47:55] [4076] m_sqlqueryTTL( 0 ) BECAT: [11/21/13 06:47:55] [4076] return 0 BECAT: [11/21/13 06:47:55] [4076] fileState = 0 BECAT: [11/21/13 06:47:55] [4076] rc = 0 BECAT: [11/21/13 06:47:55] [4076] fileState = 0 BECAT: [11/21/13 06:47:55] [4076] rc = 0 BECAT: [11/21/13 06:47:55] [4076] SQLQuery (FileHistory) Time Spend = 0 ms - ReturnedCount (0) BECAT: [11/21/13 06:47:55] [4076] SQLQuery (BackupSet_ByResource_View) Time Spend = 0 ms - ReturnedCount (0) BECAT: [11/21/13 06:47:55] [4076] result = : 0 BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:55.163 [loops] - NDMPEngine::Run BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:55.164 [server] - Enter RestoreMessageHandler::SetTargetDeviceName BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:55.164 [server] - OS ID is [0x3b] BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:55.164 [server] - Target OS ID is [0x3b] BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:55.164 [server] - Device Subtype is [0x1] BECAT: [11/21/13 06:47:55] [4076] in : inFamilyGuid({DCF8E563-58F0-48A6-89D2-8D2A2AA3A5C5}), familyId(1131693004), setNum(5) BECAT: [11/21/13 06:47:55] [4076] SQL connection: 8018fd0 select * from ControlInfo BECAT: [11/21/13 06:47:55] [4076] Input Parameters for BackupSet_ByResource_View : [1]({DCF8E563-58F0-48A6-89D2-8D2A2AA3A5C5}),[3](5) BECAT: [11/21/13 06:47:55] [4076] SELECT *, 0 FROM ImageObjectView where 1=1 AND MediaFamilyGuid = {DCF8E563-58F0-48A6-89D2-8D2A2AA3A5C5} AND ImageNumber = 5 AND ExpirationPolicyID != '9D094A9C-BBBB-402c-A35D-8A46984A19D2' AND ExtStatus & 536870912 = 0 ORDER BY BackupTimeUTC DESC BECAT: [11/21/13 06:47:55] [4076] SQL connection: 8018fd0 SELECT *, 0 FROM ImageObjectView where 1=1 AND MediaFamilyGuid = ? AND ImageNumber = ? AND ExpirationPolicyID != '9D094A9C-BBBB-402c-A35D-8A46984A19D2' AND ExtStatus & ? = 0 ORDER BY BackupTimeUTC DESC BECAT: [11/21/13 06:47:55] [4076] result(0) numRetry(300) BECAT: [11/21/13 06:47:55] [4076] GetRecordCount BECAT: [11/21/13 06:47:55] [4076] GetRecordCount BECAT: [11/21/13 06:47:55] [4076] Number of records returned: 1 BECAT: [11/21/13 06:47:55] [4076] GetRecordCount BECAT: [11/21/13 06:47:55] [4076] GetRecordCount BECAT: [11/21/13 06:47:55] [4076] CatDaoIterator:: Reset() return 1 BECAT: [11/21/13 06:47:55] [4076] return 1 BECAT: [11/21/13 06:47:55] [4076] InOut Parameters for BackupSet_ByResource_View : No values BECAT: [11/21/13 06:47:55] [4076] Output Parameters for BackupSet_ByResource_View : No values BECAT: [11/21/13 06:47:55] [4076] m_sqlqueryTTL( 0 ) BECAT: [11/21/13 06:47:55] [4076] return 0 BECAT: [11/21/13 06:47:55] [4076] imageFromXML( 0 ) BECAT: [11/21/13 06:47:55] [4076] adding index to cache : 1 - 1 BECAT: [11/21/13 06:47:55] [4076] return ( 1 ) BECAT: [11/21/13 06:47:55] [4076] Current row (0) : [49](\\EMDServer.primarycare.local\System?State) BECAT: [11/21/13 06:47:55] [4076] out : devName = \\EMDServer.primarycare.local\System?State BECAT: [11/21/13 06:47:55] [4076] SQLQuery (BackupSet_ByResource_View) Time Spend = 0 ms - ReturnedCount (0) BENGINE: [11/21/13 06:47:55] [4840] 2013-11-21T06:47:55.169 [server] - Queried Device: \\EMDServer.primarycare.local\System?State BENGINE: [11/21/13 06:47:56] [4840] 2013-11-21T06:47:55.169 [server] - Exit SetTargetDeviceName - deviceName: [\\EMDServer.primarycare.local\System?State] BENGINE: [11/21/13 06:47:56] [4840] 2013-11-21T06:47:55.169 [loops] - In CustomReordereBSD. BENGINE: [11/21/13 06:47:56] [4840] 2013-11-21T06:47:55.169 [loops] - (1300)NDMPEngine::Run Looping through BSDs BENGINE: [11/21/13 06:47:56] [4840] 2013-11-21T06:47:55.169 [server] - Enter RestoreMessageHandler::GetMachineName BECAT: [11/21/13 06:47:55] [4076] in : inFamilyGuid({DCF8E563-58F0-48A6-89D2-8D2A2AA3A5C5}), familyId(1131693004), setNum(5) BECAT: [11/21/13 06:47:55] [4076] SQL connection: 8018fd0 select * from ControlInfo BECAT: [11/21/13 06:47:55] [4076] Input Parameters for BackupSet_ByResource_View : [1]({DCF8E563-58F0-48A6-89D2-8D2A2AA3A5C5}),[3](5) BECAT: [11/21/13 06:47:55] [4076] SELECT *, 0 FROM ImageObjectView where 1=1 AND MediaFamilyGuid = {DCF8E563-58F0-48A6-89D2-8D2A2AA3A5C5} AND ImageNumber = 5 AND ExpirationPolicyID != '9D094A9C-BBBB-402c-A35D-8A46984A19D2' AND ExtStatus & 536870912 = 0 ORDER BY BackupTimeUTC DESC BECAT: [11/21/13 06:47:55] [4076] SQL connection: 8018fd0 SELECT *, 0 FROM ImageObjectView where 1=1 AND MediaFamilyGuid = ? AND ImageNumber = ? AND ExpirationPolicyID != '9D094A9C-BBBB-402c-A35D-8A46984A19D2' AND ExtStatus & ? = 0 ORDER BY BackupTimeUTC DESC BECAT: [11/21/13 06:47:55] [4076] result(0) numRetry(300) BECAT: [11/21/13 06:47:55] [4076] GetRecordCount BECAT: [11/21/13 06:47:55] [4076] GetRecordCount BECAT: [11/21/13 06:47:55] [4076] Number of records returned: 1 BECAT: [11/21/13 06:47:55] [4076] GetRecordCount BECAT: [11/21/13 06:47:55] [4076] GetRecordCount BECAT: [11/21/13 06:47:55] [4076] CatDaoIterator:: Reset() return 1 BECAT: [11/21/13 06:47:55] [4076] return 1 BECAT: [11/21/13 06:47:55] [4076] InOut Parameters for BackupSet_ByResource_View : No values BECAT: [11/21/13 06:47:55] [4076] Output Parameters for BackupSet_ByResource_View : No values BECAT: [11/21/13 06:47:55] [4076] m_sqlqueryTTL( 0 ) BECAT: [11/21/13 06:47:55] [4076] return 0 BECAT: [11/21/13 06:47:55] [4076] imageFromXML( 0 ) BECAT: [11/21/13 06:47:55] [4076] adding index to cache : 1 - 1 BECAT: [11/21/13 06:47:55] [4076] return ( 1 ) BECAT: [11/21/13 06:47:55] [4076] Current row (0) : [49](\\EMDServer.primarycare.local\System?State) BECAT: [11/21/13 06:47:55] [4076] out : devName = \\EMDServer.primarycare.local\System?State BECAT: [11/21/13 06:47:55] [4076] SQLQuery (BackupSet_ByResource_View) Time Spend = 0 ms - ReturnedCount (0) BENGINE: [11/21/13 06:47:56] [4840] 2013-11-21T06:47:55.174 [server] - Exit GetMachineName - Machine name: [EMDServer.primarycare.local] BENGINE: [11/21/13 06:47:56] [4840] 2013-11-21T06:47:55.174 [server] - Enter RestoreMessageHandler::GetMachineName BECAT: [11/21/13 06:47:55] [4076] in : inFamilyGuid({DCF8E563-58F0-48A6-89D2-8D2A2AA3A5C5}), familyId(1131693004), setNum(5) BECAT: [11/21/13 06:47:55] [4076] SQL connection: 8018fd0 select * from ControlInfo BECAT: [11/21/13 06:47:55] [4076] Input Parameters for BackupSet_ByResource_View : [1]({DCF8E563-58F0-48A6-89D2-8D2A2AA3A5C5}),[3](5) BECAT: [11/21/13 06:47:55] [4076] SELECT *, 0 FROM ImageObjectView where 1=1 AND MediaFamilyGuid = {DCF8E563-58F0-48A6-89D2-8D2A2AA3A5C5} AND ImageNumber = 5 AND ExpirationPolicyID != '9D094A9C-BBBB-402c-A35D-8A46984A19D2' AND ExtStatus & 536870912 = 0 ORDER BY BackupTimeUTC DESC BECAT: [11/21/13 06:47:55] [4076] SQL connection: 8018fd0 SELECT *, 0 FROM ImageObjectView where 1=1 AND MediaFamilyGuid = ? AND ImageNumber = ? AND ExpirationPolicyID != '9D094A9C-BBBB-402c-A35D-8A46984A19D2' AND ExtStatus & ? = 0 ORDER BY BackupTimeUTC DESC BECAT: [11/21/13 06:47:55] [4076] result(0) numRetry(300) BECAT: [11/21/13 06:47:55] [4076] GetRecordCount BECAT: [11/21/13 06:47:55] [4076] GetRecordCount BECAT: [11/21/13 06:47:55] [4076] Number of records returned: 1 BECAT: [11/21/13 06:47:55] [4076] GetRecordCount BECAT: [11/21/13 06:47:55] [4076] GetRecordCount BECAT: [11/21/13 06:47:55] [4076] CatDaoIterator:: Reset() return 1 BECAT: [11/21/13 06:47:55] [4076] return 1 BECAT: [11/21/13 06:47:55] [4076] InOut Parameters for BackupSet_ByResource_View : No values BECAT: [11/21/13 06:47:55] [4076] Output Parameters for BackupSet_ByResource_View : No values BECAT: [11/21/13 06:47:55] [4076] m_sqlqueryTTL( 0 ) BECAT: [11/21/13 06:47:55] [4076] return 0 BECAT: [11/21/13 06:47:55] [4076] imageFromXML( 0 ) BECAT: [11/21/13 06:47:55] [4076] adding index to cache : 1 - 1 BECAT: [11/21/13 06:47:55] [4076] return ( 1 ) BECAT: [11/21/13 06:47:55] [4076] Current row (0) : [49](\\EMDServer.primarycare.local\System?State) BECAT: [11/21/13 06:47:55] [4076] out : devName = \\EMDServer.primarycare.local\System?State BECAT: [11/21/13 06:47:55] [4076] SQLQuery (BackupSet_ByResource_View) Time Spend = 0 ms - ReturnedCount (0) BENGINE: [11/21/13 06:47:56] [4840] 2013-11-21T06:47:55.179 [server] - Exit GetMachineName - Machine name: [EMDServer.primarycare.local] BENGINE: [11/21/13 06:47:56] [4840] 2013-11-21T06:47:55.179 [loops] - NDMPAgentConnector:SetupConnection(): Data Server = EMDServer.primarycare.local BENGINE: [11/21/13 06:47:56] [4840] 2013-11-21T06:47:55.180 [ndmp\ndmpcomm] - Could not resolve the "bews-ndmp" or the "ndmp" service, error code: 10109, using port 10000 BENGINE: [11/21/13 06:47:56] [4840] 2013-11-21T06:47:55.180 [ndmp\ndmpcomm] - ndmpConnectEx: 'EMDServer.primarycare.local' resolves to the local machine itself. So, we will try and connect to a loopback address ... BENGINE: [11/21/13 06:47:56] [4840] 2013-11-21T06:47:55.180 [ndmp\ndmpcomm] - ndmpConnectEx : Control Connection information: A connection was established between end-points [::1]:49260 and [::1]:10000. BEREMOTE: [11/21/13 06:47:56] [2112] 2013-11-21T06:47:55.180 [ndmp\ndmpcomm] - ndmpRun: Control connection accepted : connection established between end-points [::1]:10000 and [::1]:49260 BENGINE: [11/21/13 06:47:56] [4840] 2013-11-21T06:47:55.181 [ndmp\ndmpclient] - NDMP version 3 connection CONNECTED BEREMOTE: [11/21/13 06:47:56] [6024] 2013-11-21T06:47:55.190 [ndmp\ndmpsrvr] - sslOpen() : Opening SSL for: 00000000E174480F BEREMOTE: [11/21/13 06:47:56] [6024] 2013-11-21T06:47:55.190 [ndmp\ndmpsrvr] - sslOpen(): certinfo = 00000000EB1241DF ; sslConn = 00000000E92384BF BENGINE: [11/21/13 06:47:56] [4840] 2013-11-21T06:47:55.190 [engapi] - FindLoginCredentials() looking for resource \\EMDServer.primarycare.local\System?State BENGINE: [11/21/13 06:47:56] [4840] 2013-11-21T06:47:55.190 [ndmp] + ndmp_client_env.cpp (7518): BENGINE: [11/21/13 06:47:56] [4840] 2013-11-21T06:47:55.190 [ndmp] | Found credentials for resourse: '\\EMDServer.primarycare.local\System?State'. BENGINE: [11/21/13 06:47:56] [4840] 2013-11-21T06:47:55.190 [engapi] - FindLoginCredentials() looking for resource \\EMDServer.primarycare.local BENGINE: [11/21/13 06:47:56] [4840] 2013-11-21T06:47:55.190 [ndmp] + ndmp_client_env.cpp (7727): BENGINE: [11/21/13 06:47:56] [4840] 2013-11-21T06:47:55.190 [ndmp] | Found credentials for resourse: '\\EMDServer.primarycare.local'. BENGINE: [11/21/13 06:47:56] [4840] 2013-11-21T06:47:55.191 [ndmp\ndmpclient] - connectClientAuth(): agent is on local loopback, skipping SSL connection... BEREMOTE: [11/21/13 06:47:56] [0000] BECryptoInit: BECrypto non-FIPS mode successfully enabled. BENGINE: [11/21/13 06:47:56] [0000] BECryptoInit: BECrypto non-FIPS mode successfully enabled. BEREMOTE: [11/21/13 06:47:56] [6024] 2013-11-21T06:47:55.202 [ndmp\ndmpsrvr] - ndmpdConnectClientAuth(): agent is on local loopback, skipping SSL connection... BEREMOTE: [11/21/13 06:47:56] [6024] 2013-11-21T06:47:55.204 [ndmp\ndmpsrvr] - Username for Logon: EMDSERVER\Administrator BEREMOTE: [11/21/13 06:47:55] [6024] "Cluster" key is not present in the registry BEREMOTE: [11/21/13 06:47:55] [6024] Did not detect Microsoft cluster () BEREMOTE: [11/21/13 06:47:55] [6024] VCS cluster keys do not appear to be present in the registry BEREMOTE: [11/21/13 06:47:55] [6024] Did not detect VCS cluster () BEREMOTE: [11/21/13 06:47:56] [6024] 2013-11-21T06:47:55.204 + impersonator.cpp (478): BEREMOTE: [11/21/13 06:47:56] [6024] 2013-11-21T06:47:55.204 | LogonType set = [LOGON32_LOGON_BATCH][0x4] BEREMOTE: [11/21/13 06:47:56] [6024] 2013-11-21T06:47:55.205 + impersonator.cpp (493): BEREMOTE: [11/21/13 06:47:56] [6024] 2013-11-21T06:47:55.205 | LogonUser(LOGON32_LOGON_BATCH-0x4) success BEREMOTE: [11/21/13 06:47:56] [6024] 2013-11-21T06:47:55.205 + impersonator.cpp (358): BEREMOTE: [11/21/13 06:47:56] [6024] 2013-11-21T06:47:55.205 | Successfully impersonated EMDSERVER\Administrator BEREMOTE: [11/21/13 06:47:56] [6024] 2013-11-21T06:47:55.205 + impersonator.cpp (364): BEREMOTE: [11/21/13 06:47:56] [6024] 2013-11-21T06:47:55.205 | WhoAmI( ) reports: EMDSERVER\Administrator BEREMOTE: [11/21/13 06:47:56] [6024] 2013-11-21T06:47:55.205 [ndmp\ndmpsrvr] - LogonuserError: 0 BENGINE: [11/21/13 06:47:56] [4840] 2013-11-21T06:47:55.208 [loops] - This BSD does not require PDI restore. Performing traditional backup BECAT: [11/21/13 06:47:55] [4076] in : inFamilyGuid({DCF8E563-58F0-48A6-89D2-8D2A2AA3A5C5}), familyId(1131693004), setNum(5) BECAT: [11/21/13 06:47:55] [4076] SQL connection: 8018fd0 select * from ControlInfo BECAT: [11/21/13 06:47:55] [4076] Input Parameters for Image_Fragments_View : [1]({DCF8E563-58F0-48A6-89D2-8D2A2AA3A5C5}),[3](5),[227](0) BECAT: [11/21/13 06:47:55] [4076] SELECT *, 0 FROM ImageObjectView where 1=1 AND MediaFamilyGuid = {DCF8E563-58F0-48A6-89D2-8D2A2AA3A5C5} AND ImageNumber = 5 AND ExpirationPolicyID != '9D094A9C-BBBB-402c-A35D-8A46984A19D2' AND ExtStatus & 536870912 = 0 ORDER BY BackupTimeUTC DESC BECAT: [11/21/13 06:47:55] [4076] SQL connection: 8018fd0 SELECT *, 0 FROM ImageObjectView where 1=1 AND MediaFamilyGuid = ? AND ImageNumber = ? AND ExpirationPolicyID != '9D094A9C-BBBB-402c-A35D-8A46984A19D2' AND ExtStatus & ? = 0 ORDER BY BackupTimeUTC DESC BECAT: [11/21/13 06:47:55] [4076] result(0) numRetry(300) BECAT: [11/21/13 06:47:55] [4076] GetRecordCount BECAT: [11/21/13 06:47:55] [4076] GetRecordCount BECAT: [11/21/13 06:47:55] [4076] Number of records returned: 1 BECAT: [11/21/13 06:47:55] [4076] GetRecordCount BECAT: [11/21/13 06:47:55] [4076] GetRecordCount BECAT: [11/21/13 06:47:55] [4076] CatDaoIterator:: Reset() return 1 BECAT: [11/21/13 06:47:55] [4076] return 1 BECAT: [11/21/13 06:47:55] [4076] InOut Parameters for Image_Fragments_View : No values BECAT: [11/21/13 06:47:55] [4076] Output Parameters for Image_Fragments_View : No values BECAT: [11/21/13 06:47:55] [4076] m_sqlqueryTTL( 0 ) BECAT: [11/21/13 06:47:55] [4076] return 0 BECAT: [11/21/13 06:47:55] [4076] imageFromXML( 0 ) BECAT: [11/21/13 06:47:55] [4076] adding index to cache : 1 - 1 BECAT: [11/21/13 06:47:55] [4076] return ( 1 ) BECAT: [11/21/13 06:47:55] [4076] Current row (0) : [4]({24586468-35F7-4634-9915-8C71679D288B}),[5](1),[81](0) BECAT: [11/21/13 06:47:55] [4076] result(0) mediaId({24586468-35F7-4634-9915-8C71679D288B}) mediaSide(1) BECAT: [11/21/13 06:47:55] [4076] SQLQuery (Image_Fragments_View) Time Spend = 0 ms - ReturnedCount (0) BENGINE: [11/21/13 06:47:56] [3588] 2013-11-21T06:47:55.213 [server] - DeviceManager: incoming event fired BENGINE: [11/21/13 06:47:56] [3588] 2013-11-21T06:47:55.213 [server] - DeviceManager: Executing RequestDeviceCall from thread 4840. BENGINE: [11/21/13 06:47:56] [3588] 2013-11-21T06:47:55.213 [server] - DeviceManager: processing pending requests BENGINE: [11/21/13 06:47:56] [3588] 2013-11-21T06:47:55.213 [server] - DeviceManager: Processing device request for thread 4840. PVLSVR: [11/21/13 06:47:55] [5828] PvlEntityDatabase::QueryEntity() ParentEntityType = NULL_ENTITY_TYPE ParentEntityGuid = {00000000-0000-0000-0000-000000000000} EntityType = MEDIA_ENTITY_TYPE EntityGuid = {24586468-35F7-4634-9915-8C71679D288B} EntityName = "" EntityFlags = 0x00000000 PVLSVR: [11/21/13 06:47:55] [5828] PvlEntityDatabase::QueryEntity() : SUCCESS! BENGINE: [11/21/13 06:47:56] [3588] 2013-11-21T06:47:55.215 [server] - DeviceManager: query cache miss PVLSVR: [11/21/13 06:47:55] [5828] PvlEntityDatabase::QueryEntity() ParentEntityType = DRIVE_POOL_ENTITY_TYPE ParentEntityGuid = {97B5EAAB-7C0A-4800-9872-6D5DC643478C} EntityType = NULL_ENTITY_TYPE EntityGuid = {00000000-0000-0000-0000-000000000000} EntityName = "" EntityFlags = 0x00000000 BENGINE: [11/21/13 06:47:56] [3588] 2013-11-21T06:47:55.217 [server] - Available and held drives from the pool BENGINE: [11/21/13 06:47:56] [3588] 2013-11-21T06:47:55.217 [server] - Tape drive 0001 {97b5eaab-7c0a-4800-9872-6d5dc643478c} BENGINE: [11/21/13 06:47:56] [3588] 2013-11-21T06:47:55.217 [server] - Drives not in use by the Engine BENGINE: [11/21/13 06:47:56] [3588] 2013-11-21T06:47:55.217 [server] - Tape drive 0001 {97b5eaab-7c0a-4800-9872-6d5dc643478c} BENGINE: [11/21/13 06:47:56] [3588] 2013-11-21T06:47:55.217 [server] - Drives available (not in use and not held) BENGINE: [11/21/13 06:47:56] [3588] 2013-11-21T06:47:55.217 [server] - Tape drive 0001 {97b5eaab-7c0a-4800-9872-6d5dc643478c} PVLSVR: [11/21/13 06:47:55] [5828] AdammSession::Execute( ADAMM_SESSION_EXECUTE_SESSION_SET_TARGET_DEVICE_GUID ) Session = {D41F4B48-6DFA-4D84-9E90-A4E23D95F22D} TargetDevice = {97B5EAAB-7C0A-4800-9872-6D5DC643478C}, "Tape drive 0001" (Drive) BENGINE: [11/21/13 06:47:56] [3588] 2013-11-21T06:47:55.219 [server] - DeviceManager: Handing out session {d41f4b48-6dfa-4d84-9e90-a4e23d95f22d} based on available drive: BENGINE: [11/21/13 06:47:56] [3588] 2013-11-21T06:47:55.219 [server] - Tape drive 0001 {97b5eaab-7c0a-4800-9872-6d5dc643478c} BENGINE: [11/21/13 06:47:56] [3588] 2013-11-21T06:47:55.219 [server] - DeviceManager: returned result to caller 0x00000000 BENGINE: [11/21/13 06:47:56] [3588] 2013-11-21T06:47:55.219 [server] - DeviceManager: going to sleep for 900000 msecs PVLSVR: [11/21/13 06:47:55] [5828] AdammSession::Execute( ADAMM_SESSION_EXECUTE_SESSION_SET_JOB_INFO ) Session = {D41F4B48-6DFA-4D84-9E90-A4E23D95F22D} Job = {3F8BEB0D-997B-4251-B830-6C07E3427C7F}, "EMDServer.primarycare.local Restore 00003" PVLSVR: [11/21/13 06:47:55] [5828] AdammSession::Execute( ADAMM_SESSION_EXECUTE_SESSION_SET_TARGET_DEVICE_HOST ) Session = {D41F4B48-6DFA-4D84-9E90-A4E23D95F22D} Device Host = "EMDServer.primarycare.local" BENGINE: [11/21/13 06:47:56] [4840] 2013-11-21T06:47:55.219 [server] - Job::Mount: acquired device BENGINE: [11/21/13 06:47:56] [4840] 2013-11-21T06:47:55.219 [server] - Job::Mount: mounting tape BENGINE: [11/21/13 06:47:56] [4840] 2013-11-21T06:47:55.219 [server] + storagedevice.cpp (332): BENGINE: [11/21/13 06:47:56] [4840] 2013-11-21T06:47:55.219 [server] | No media exclude GUIDs. BENGINE: [11/21/13 06:47:56] [4840] 2013-11-21T06:47:55.219 [server] + storagedevice.cpp (340): BENGINE: [11/21/13 06:47:56] [4840] 2013-11-21T06:47:55.219 [server] | DoMount Execute: CommandCode=0x20105;TargetDeviceGuid={97B5EAAB-7C0A-4800-9872-6D5DC643478C};TargetMediaGuid={24586468-35F7-4634-9915-8C71679D288B};CommandFlags=0x1 PVLSVR: [11/21/13 06:47:55] [5828] AdammSession::Execute( ADAMM_SESSION_EXECUTE_MOVER_MOUNT_SPECIFIC ) Session = {D41F4B48-6DFA-4D84-9E90-A4E23D95F22D} PVLSVR: [11/21/13 06:47:55] [5828] AdammSession::Execute( ADAMM_SESSION_EXECUTE_SESSION_SET_TARGET_DEVICE_GUID ) Session = {D41F4B48-6DFA-4D84-9E90-A4E23D95F22D} TargetDevice = {97B5EAAB-7C0A-4800-9872-6D5DC643478C}, "Tape drive 0001" (Drive) PVLSVR: [11/21/13 06:47:55] [5828] AdammSession::CheckTargetDeviceStatus() Session = {D41F4B48-6DFA-4D84-9E90-A4E23D95F22D} Job = {3F8BEB0D-997B-4251-B830-6C07E3427C7F}, "EMDServer.primarycare.local Restore 00003" TargetDevice = {97B5EAAB-7C0A-4800-9872-6D5DC643478C}, "Tape drive 0001" State = 0003, ReservationID = 0000, AvailableMovers = 0001 PVLSVR: [11/21/13 06:47:55] [5828] AdammSession::MountSpecificMedia() Session = {D41F4B48-6DFA-4D84-9E90-A4E23D95F22D} Requested Media = {24586468-35F7-4634-9915-8C71679D288B} BECAT: [11/21/13 06:47:55] [4076] VSNType(1031) verbHint(102) PVLSVR: [11/21/13 06:47:55] [5828] AdammSession::MountSpecificMedia() Session = {D41F4B48-6DFA-4D84-9E90-A4E23D95F22D} Media = {24586468-35F7-4634-9915-8C71679D288B}, "LTO000005" Location = {97B5EAAB-7C0A-4800-9872-6D5DC643478C}, "Tape drive 0001" LocationIndex = 0000 Side = 0001 BECAT: [11/21/13 06:47:55] [4076] VSNType(1008) verbHint(102) BECAT: [11/21/13 06:47:55] [4076] VSNType(1008) verbHint(102) PVLSVR: [11/21/13 06:47:55] [5828] AdammSession::MountMedia() Session = {D41F4B48-6DFA-4D84-9E90-A4E23D95F22D} Drive = {97B5EAAB-7C0A-4800-9872-6D5DC643478C} Slot = 0000 Side = 0001 PVLSVR: [11/21/13 06:47:55] [5828] PvlDrive::MountMedia() Session = {D41F4B48-6DFA-4D84-9E90-A4E23D95F22D} Drive = {97B5EAAB-7C0A-4800-9872-6D5DC643478C}, "Tape drive 0001" Slot = 0000 Side = 0001 MANAGEME: [11/21/13 06:47:56] [0012] [Warn] VSN: Solution Action: Modify - empty object list. BESERVER: [11/21/13 06:47:59] [3948] 16 CJobManagerBO::Query QUERY_JOBSETUP_MONITOR BESERVER: [11/21/13 06:47:59] [3948] 16 FlushCache Message VSN_TYPE_JOBVIEW_UPDATE jobViewQuery Sent size :1 BENGINE: [11/21/13 06:48:00] [3388] 2013-11-21T06:47:59.765 [server] - Updating status for: 'EMDServer.primarycare.local Restore 00003' (0x0 0x9) BENGINE: [11/21/13 06:48:00] [3388] 2013-11-21T06:47:59.765 [server] - Status for: 'EMDServer.primarycare.local Restore 00003' updated PVLSVR: [11/21/13 06:48:05] [5828] PvlDrive::ReadMediaHeader() - Media Guid = {24586468-35F7-4634-9915-8C71679D288B} Label = "LTO000005" Slot = 0000 Header = "MTF Media Label|1.0|VERITAS|PVL|2013/07/11.01:00:05|LTO000005|1|{24586468-35F7-4634-9915-8C71679D288B}||" BECAT: [11/21/13 06:48:05] [4076] VSNType(1007) verbHint(102) BECAT: [11/21/13 06:48:05] [4076] MediaGuid({24586468-35F7-4634-9915-8C71679D288B}) MediaLabel(LTO000005) MediaSetGuid({00000009-0004-0000-0000-000000000000}) BECAT: [11/21/13 06:48:05] [4076] mediaGuid({24586468-35F7-4634-9915-8C71679D288B}) mediaLabel(LTO000005) mediaSetGuid({00000009-0004-0000-0000-000000000000}) BECAT: [11/21/13 06:48:05] [4076] mediaGuid({24586468-35F7-4634-9915-8C71679D288B}) mediaLabel(LTO000005) mediaSetGuid({00000009-0004-0000-0000-000000000000}) BECAT: [11/21/13 06:48:05] [4076] SQL connection: 8018fd0 select * from ControlInfo BECAT: [11/21/13 06:48:05] [4076] {CALL UpdateCatMediaInfo( ?, ?, ?, ?) } BECAT: [11/21/13 06:48:05] [4076] {CALL UpdateCatMediaInfo( {24586468-35F7-4634-9915-8C71679D288B}, LTO000005, {00000009-0004-0000-0000-000000000000}, 0) } BECAT: [11/21/13 06:48:05] [4076] SQL connection: 8018fd0 {CALL UpdateCatMediaInfo( ?, ?, ?, ?) } BECAT: [11/21/13 06:48:05] [4076] result(0) numRetry(300) BECAT: [11/21/13 06:48:05] [4076] isUpdated(0) PVLSVR: [11/21/13 06:48:05] [5828] PvlDrive::MountMedia() - Establish connection Session = {D41F4B48-6DFA-4D84-9E90-A4E23D95F22D} Drive = {97B5EAAB-7C0A-4800-9872-6D5DC643478C}, "Tape drive 0001" BECAT: [11/21/13 06:48:05] [4076] VSNType(1031) verbHint(102) PVLSVR: [11/21/13 06:48:05] [5828] AdammSession::MountMedia() Session = {D41F4B48-6DFA-4D84-9E90-A4E23D95F22D} Library = {00000000-0000-0000-0000-000000000000}, "" Slot = 0000 Drive = {97B5EAAB-7C0A-4800-9872-6D5DC643478C}, "Tape drive 0001" Media = {24586468-35F7-4634-9915-8C71679D288B}, "LTO000005" Side = 0001 SUCCESS! PVLSVR: [11/21/13 06:48:05] [5828] PvlSession::MountSpecificMedia() - SUCCESS! - 0010 seconds, 0000 (0002) SQL seconds BENGINE: [11/21/13 06:48:05] [4840] 2013-11-21T06:48:05.631 [server] + storagedevice.cpp (345): BENGINE: [11/21/13 06:48:05] [4840] 2013-11-21T06:48:05.631 [server] | DoMount Execute returned: 0x0 BENGINE: [11/21/13 06:48:05] [4840] 2013-11-21T06:48:05.631 [server] + storagedevice.cpp (349): BENGINE: [11/21/13 06:48:05] [4840] 2013-11-21T06:48:05.631 [server] | Updating session after mount BENGINE: [11/21/13 06:48:05] [4840] 2013-11-21T06:48:05.633 [server] + storagedevice.cpp (708): BENGINE: [11/21/13 06:48:05] [4840] 2013-11-21T06:48:05.633 [server] | Setting removable media flag to true BENGINE: [11/21/13 06:48:05] [4840] 2013-11-21T06:48:05.633 [server] - Updating session {d41f4b48-6dfa-4d84-9e90-a4e23d95f22d} with drive Tape drive 0001 {97b5eaab-7c0a-4800-9872-6d5dc643478c} BENGINE: [11/21/13 06:48:05] [4840] 2013-11-21T06:48:05.633 [server] + storagedevice.cpp (351): BENGINE: [11/21/13 06:48:05] [4840] 2013-11-21T06:48:05.633 [server] | DoMount session update returned: 0x0 BENGINE: [11/21/13 06:48:05] [4840] 2013-11-21T06:48:05.633 [server] - Job::Mount: media mounted BENGINE: [11/21/13 06:48:05] [4840] 2013-11-21T06:48:05.646 [server] - Job::GetTargetNdmpServerName(): Execute(ADAMM_MOVER_EXECUTE_GET_SERVER_INFO) No server found. PVLSVR: [11/21/13 06:48:05] [5828] AdammSession::Execute( ADAMM_SESSION_EXECUTE_MOVER_RELEASE ) Session = {D41F4B48-6DFA-4D84-9E90-A4E23D95F22D} BENGINE: [11/21/13 06:48:05] [4840] 2013-11-21T06:48:05.646 [server] - Media Label: LTO000005 BENGINE: [11/21/13 06:48:05] [4840] 2013-11-21T06:48:05.646 [server] - Media Label: {24586468-35f7-4634-9915-8c71679d288b} BENGINE: [12/30/99 12:00:00] [4840] 2013-11-21T06:48:05.647 [server] - Overwrite Protected Until: PM BENGINE: [11/21/13 06:48:05] [4840] 2013-11-21T06:48:05.647 [server] - Appendable Until: Non Appendable Media BENGINE: [11/21/13 06:48:05] [4840] 2013-11-21T06:48:05.647 [server] - Tape drive 0001 Block size: 65536 BENGINE: [11/21/13 06:48:05] [4840] 2013-11-21T06:48:05.647 [server] - Tape drive 0001 Buffer size: 65536 BENGINE: [11/21/13 06:48:05] [4840] 2013-11-21T06:48:05.647 [server] - Tape drive 0001 Num buffers: 10 BENGINE: [11/21/13 06:48:05] [4840] 2013-11-21T06:48:05.647 [server] - Tape drive 0001 High water: 0 BENGINE: [11/21/13 06:48:05] [4840] 2013-11-21T06:48:05.649 [tapealrt] - TAPEALERT: Get TapeAlert Flags Return Code = 0X0 BENGINE: [11/21/13 06:48:05] [4840] 2013-11-21T06:48:05.656 [tapealrt] - TAPEALERT: TapeAlert Device Flag = 0X0 BENGINE: [11/21/13 06:48:05] [4840] 2013-11-21T06:48:05.656 [tapealrt] - TAPEALERT: TapeAlert Changer Flag = 0X0 BENGINE: [11/21/13 06:48:05] [4840] 2013-11-21T06:48:05.657 [tpfmt] - TF_OpenTape() BENGINE: [11/21/13 06:48:05] [4840] 2013-11-21T06:48:05.657 [tpfmt] - TF_GetChannel( ) reusing channel 08096430 BENGINE: [11/21/13 06:48:05] [4840] 2013-11-21T06:48:05.657 [tpfmt] - TF_GetDriveContext ... sessionGuid = {d41f4b48-6dfa-4d84-9e90-a4e23d95f22d} initAsync = 1 BENGINE: [11/21/13 06:48:05] [4840] 2013-11-21T06:48:05.671 [tpfmt] - TF_MoverAsyncInit(): Started ProcessRequest thread 3832 BENGINE: [11/21/13 06:48:05] [4840] 2013-11-21T06:48:05.692 [tpfmt] - HARDWARE COMPRESSION ===> Compression is configurable. BENGINE: [11/21/13 06:48:05] [4840] 2013-11-21T06:48:05.692 [tpfmt] - HARDWARE ENCRYPTION ===> Encryption is NOT supported. BENGINE: [11/21/13 06:48:05] [4840] 2013-11-21T06:48:05.692 [tpfmt] - GET_DRV_INF: containerGUID = {00000000-0000-0000-0000-000000000000} BENGINE: [11/21/13 06:48:05] [4840] 2013-11-21T06:48:05.692 [tpfmt] - GET_DRV_INF: bsize = 65536 BENGINE: [11/21/13 06:48:05] [4840] 2013-11-21T06:48:05.692 [tpfmt] - GET_DRV_INF: preferredBlockSize = 65536 BENGINE: [11/21/13 06:48:05] [4840] 2013-11-21T06:48:05.692 [tpfmt] - HARDWARE COMPRESSION ===> Setting compression off. BENGINE: [11/21/13 06:48:05] [4840] 2013-11-21T06:48:05.698 [tpfmt] - TF_GetDriveContext returning 0x00000000, context = 824A688 BENGINE: [11/21/13 06:48:05] [4840] 2013-11-21T06:48:05.698 [tpfmt] - Allocated 2 buffers, size 65536 bytes, total used: 131328 BENGINE: [11/21/13 06:48:05] [4840] 2013-11-21T06:48:05.705 [tpfmt] - HARDWARE COMPRESSION ===> Compression is configurable. BENGINE: [11/21/13 06:48:05] [4840] 2013-11-21T06:48:05.705 [tpfmt] - HARDWARE ENCRYPTION ===> Encryption is NOT supported. BENGINE: [11/21/13 06:48:05] [4840] 2013-11-21T06:48:05.705 [tpfmt] - GET_DRV_INF: containerGUID = {00000000-0000-0000-0000-000000000000} BENGINE: [11/21/13 06:48:05] [4840] 2013-11-21T06:48:05.705 [tpfmt] - GET_DRV_INF: bsize = 65536 BENGINE: [11/21/13 06:48:05] [4840] 2013-11-21T06:48:05.705 [tpfmt] - GET_DRV_INF: preferredBlockSize = 65536 BENGINE: [11/21/13 06:48:05] [4840] 2013-11-21T06:48:05.705 [server] - Enter RestoreMessageHandler::SetTargetDeviceName BENGINE: [11/21/13 06:48:05] [4840] 2013-11-21T06:48:05.705 [server] - OS ID is [0x3b] BENGINE: [11/21/13 06:48:05] [4840] 2013-11-21T06:48:05.705 [server] - Target OS ID is [0x3b] BENGINE: [11/21/13 06:48:05] [4840] 2013-11-21T06:48:05.705 [server] - Device Subtype is [0x1] BECAT: [11/21/13 06:48:05] [4076] in : inFamilyGuid({DCF8E563-58F0-48A6-89D2-8D2A2AA3A5C5}), familyId(1131693004), setNum(5) BECAT: [11/21/13 06:48:05] [4076] SQL connection: 8018fd0 select * from ControlInfo BECAT: [11/21/13 06:48:05] [4076] Input Parameters for BackupSet_ByResource_View : [1]({DCF8E563-58F0-48A6-89D2-8D2A2AA3A5C5}),[3](5) BECAT: [11/21/13 06:48:05] [4076] SELECT *, 0 FROM ImageObjectView where 1=1 AND MediaFamilyGuid = {DCF8E563-58F0-48A6-89D2-8D2A2AA3A5C5} AND ImageNumber = 5 AND ExpirationPolicyID != '9D094A9C-BBBB-402c-A35D-8A46984A19D2' AND ExtStatus & 536870912 = 0 ORDER BY BackupTimeUTC DESC BECAT: [11/21/13 06:48:05] [4076] SQL connection: 8018fd0 SELECT *, 0 FROM ImageObjectView where 1=1 AND MediaFamilyGuid = ? AND ImageNumber = ? AND ExpirationPolicyID != '9D094A9C-BBBB-402c-A35D-8A46984A19D2' AND ExtStatus & ? = 0 ORDER BY BackupTimeUTC DESC BECAT: [11/21/13 06:48:05] [4076] result(0) numRetry(300) BECAT: [11/21/13 06:48:05] [4076] GetRecordCount BECAT: [11/21/13 06:48:05] [4076] GetRecordCount BECAT: [11/21/13 06:48:05] [4076] Number of records returned: 1 BECAT: [11/21/13 06:48:05] [4076] GetRecordCount BECAT: [11/21/13 06:48:05] [4076] GetRecordCount BECAT: [11/21/13 06:48:05] [4076] CatDaoIterator:: Reset() return 1 BECAT: [11/21/13 06:48:05] [4076] return 1 BECAT: [11/21/13 06:48:05] [4076] InOut Parameters for BackupSet_ByResource_View : No values BECAT: [11/21/13 06:48:05] [4076] Output Parameters for BackupSet_ByResource_View : No values BECAT: [11/21/13 06:48:05] [4076] m_sqlqueryTTL( 0 ) BECAT: [11/21/13 06:48:05] [4076] return 0 BECAT: [11/21/13 06:48:05] [4076] imageFromXML( 0 ) BECAT: [11/21/13 06:48:05] [4076] adding index to cache : 1 - 1 BECAT: [11/21/13 06:48:05] [4076] return ( 1 ) BECAT: [11/21/13 06:48:05] [4076] Current row (0) : [49](\\EMDServer.primarycare.local\System?State) BECAT: [11/21/13 06:48:05] [4076] out : devName = \\EMDServer.primarycare.local\System?State BECAT: [11/21/13 06:48:05] [4076] SQLQuery (BackupSet_ByResource_View) Time Spend = 0 ms - ReturnedCount (0) BENGINE: [11/21/13 06:48:05] [4840] 2013-11-21T06:48:05.710 [server] - Queried Device: \\EMDServer.primarycare.local\System?State BENGINE: [11/21/13 06:48:05] [4840] 2013-11-21T06:48:05.710 [server] - Setting target path: [] BENGINE: [11/21/13 06:48:05] [4840] 2013-11-21T06:48:05.710 [server] - Exit SetTargetDeviceName - deviceName: [\\EMDServer.primarycare.local\System?State] BENGINE: [11/21/13 06:48:05] [4840] 2013-11-21T06:48:05.711 [loops] - DeviceIsNT4Server: agent info: [Major Version=6 Minor Version=1 Build Number=7601 ServicePack Major=1 ServicePack Minor=0 SuiteMask=272 ProductType=3 ProcessorType=AMD64]. BENGINE: [11/21/13 06:48:05] [4840] 2013-11-21T06:48:05.711 [loops] - DeviceIsIA64Server: agent info: [Major Version=6 Minor Version=1 Build Number=7601 ServicePack Major=1 ServicePack Minor=0 SuiteMask=272 ProductType=3 ProcessorType=AMD64]. BENGINE: [11/21/13 06:48:05] [4840] 2013-11-21T06:48:05.711 [loops] - DeviceIsW2kServer: agent info: [Major Version=6 Minor Version=1 Build Number=7601 ServicePack Major=1 ServicePack Minor=0 SuiteMask=272 ProductType=3 ProcessorType=AMD64]. BECAT: [11/21/13 06:48:05] [4076] in : inFamilyGuid({DCF8E563-58F0-48A6-89D2-8D2A2AA3A5C5}) backupSetNumber(5) BECAT: [11/21/13 06:48:05] [4076] in : familyGuid({DCF8E563-58F0-48A6-89D2-8D2A2AA3A5C5}), backupSetNumber(5) BECAT: [11/21/13 06:48:05] [4076] SQL connection: 8018fd0 select * from ControlInfo BECAT: [11/21/13 06:48:05] [4076] Input Parameters for Image_Fragments_View : [1]({DCF8E563-58F0-48A6-89D2-8D2A2AA3A5C5}),[3](5),[227](0) BECAT: [11/21/13 06:48:05] [4076] SELECT *, 0 FROM ImageObjectView where 1=1 AND MediaFamilyGuid = {DCF8E563-58F0-48A6-89D2-8D2A2AA3A5C5} AND ImageNumber = 5 AND ExpirationPolicyID != '9D094A9C-BBBB-402c-A35D-8A46984A19D2' AND ExtStatus & 536870912 = 0 ORDER BY BackupTimeUTC DESC BECAT: [11/21/13 06:48:05] [4076] SQL connection: 8018fd0 SELECT *, 0 FROM ImageObjectView where 1=1 AND MediaFamilyGuid = ? AND ImageNumber = ? AND ExpirationPolicyID != '9D094A9C-BBBB-402c-A35D-8A46984A19D2' AND ExtStatus & ? = 0 ORDER BY BackupTimeUTC DESC BECAT: [11/21/13 06:48:05] [4076] result(0) numRetry(300) BECAT: [11/21/13 06:48:05] [4076] GetRecordCount BECAT: [11/21/13 06:48:05] [4076] GetRecordCount BECAT: [11/21/13 06:48:05] [4076] Number of records returned: 1 BECAT: [11/21/13 06:48:05] [4076] GetRecordCount BECAT: [11/21/13 06:48:05] [4076] GetRecordCount BECAT: [11/21/13 06:48:05] [4076] CatDaoIterator:: Reset() return 1 BECAT: [11/21/13 06:48:05] [4076] return 1 BECAT: [11/21/13 06:48:05] [4076] InOut Parameters for Image_Fragments_View : No values BECAT: [11/21/13 06:48:05] [4076] Output Parameters for Image_Fragments_View : No values BECAT: [11/21/13 06:48:05] [4076] m_sqlqueryTTL( 0 ) BECAT: [11/21/13 06:48:05] [4076] return 0 BECAT: [11/21/13 06:48:05] [4076] imageFromXML( 0 ) BECAT: [11/21/13 06:48:05] [4076] adding index to cache : 1 - 1 BECAT: [11/21/13 06:48:05] [4076] return ( 1 ) BECAT: [11/21/13 06:48:05] [4076] Current row (0) : [3](1),[37](255),[46](9322371),[81](0) BECAT: [11/21/13 06:48:05] [4076] out: result(0) mediaNumber(1), fddVersion(255), vcbPba(9322371) BECAT: [11/21/13 06:48:05] [4076] SQLQuery (Image_Fragments_View) Time Spend = 0 ms - ReturnedCount (0) BENGINE: [11/21/13 06:48:05] [4840] 2013-11-21T06:48:05.716 [tpfmt] - TF_OpenSet( ) BENGINE: [11/21/13 06:48:05] [4840] 2013-11-21T06:48:05.719 [tpfmt] - FreeFormatEnv( cur_fmt=65535 ) BENGINE: [11/21/13 06:48:10] [4840] 2013-11-21T06:48:10.487 [tpfmt] - RewindDrive mover ret = 0 (0x0) BENGINE: [11/21/13 06:48:10] [4840] 2013-11-21T06:48:10.487 [tpfmt] - ret_val = 0 BENGINE: [11/21/13 06:48:10] [4840] 2013-11-21T06:48:10.492 [tpfmt] - Requested Set: ID = 437443cc Seq = 1 Set = 5 BENGINE: [11/21/13 06:48:10] [4840] 2013-11-21T06:48:10.492 [tpfmt] - Attempting to VCB storage media BENGINE: [11/21/13 06:48:10] [4840] 2013-11-21T06:48:10.492 [tpfmt] - TpRead( ): BENGINE: [11/21/13 06:48:10] [4840] 2013-11-21T06:48:10.514 [tpfmt] - Error = 0 Req = 65536 Got = 65536 BENGINE: [11/21/13 06:48:10] [4840] 2013-11-21T06:48:10.524 [tpfmt] - HARDWARE COMPRESSION ===> Compression is configurable. BENGINE: [11/21/13 06:48:10] [4840] 2013-11-21T06:48:10.524 [tpfmt] - HARDWARE ENCRYPTION ===> Encryption is NOT supported. BENGINE: [11/21/13 06:48:10] [4840] 2013-11-21T06:48:10.525 [tpfmt] - GET_DRV_INF: containerGUID = {00000000-0000-0000-0000-000000000000} BENGINE: [11/21/13 06:48:10] [4840] 2013-11-21T06:48:10.525 [tpfmt] - GET_DRV_INF: bsize = 65536 BENGINE: [11/21/13 06:48:10] [4840] 2013-11-21T06:48:10.525 [tpfmt] - GET_DRV_INF: preferredBlockSize = 65536 BENGINE: [11/21/13 06:48:10] [4840] 2013-11-21T06:48:10.525 [tpfmt] - FreeFormatEnv( cur_fmt=65535 ) BENGINE: [11/21/13 06:48:10] [4840] 2013-11-21T06:48:10.525 [tpfmt] - SetupFormatEnv( fmt=0 ) BENGINE: [11/21/13 06:48:10] [4840] 2013-11-21T06:48:10.525 [tpfmt] - Current media tape header was written by "Symantec Backup Exec (TM) 14.0.1798". BENGINE: [11/21/13 06:48:10] [4840] 2013-11-21T06:48:10.534 [tpfmt] - TpRead( ): BENGINE: [11/21/13 06:48:10] [4840] 2013-11-21T06:48:10.539 [tpfmt] - Error = 0 Req = 65536 Got = 65536 BENGINE: [11/21/13 06:48:10] [4840] 2013-11-21T06:48:10.539 [tpfmt] - ReadThisSet( cur_fmt=0 ) BENGINE: [11/21/13 06:48:10] [4840] 2013-11-21T06:48:10.539 [tpfmt] - Set 1 was written by version 14.0. BENGINE: [11/21/13 06:48:10] [4840] 2013-11-21T06:48:10.540 [tpfmt] - ReadThisSet() return=0x00000000 BENGINE: [11/21/13 06:48:10] [4840] 2013-11-21T06:48:10.540 [tpfmt] - Current VCB: ID = 437443cc Seq = 1 Set = 1 BENGINE: [11/21/13 06:48:10] [4840] 2013-11-21T06:48:10.540 [tpfmt] - End of TF_OpenSet: Ret_val = 0x00000000 Buffs = 2 HiWater = 0 BENGINE: [11/21/13 06:48:10] [4840] 2013-11-21T06:48:10.540 [engapi] - FindLoginCredentials() looking for resource \\EMDServer.primarycare.local\System?State BENGINE: [11/21/13 06:48:10] [4840] 2013-11-21T06:48:10.540 [ndmp] + ndmp_client_env.cpp (7518): BENGINE: [11/21/13 06:48:10] [4840] 2013-11-21T06:48:10.540 [ndmp] | Found credentials for resourse: '\\EMDServer.primarycare.local\System?State'. BENGINE: [11/21/13 06:48:10] [4840] 2013-11-21T06:48:10.540 [engapi] - FindLoginCredentials() looking for resource \\EMDServer.primarycare.local BENGINE: [11/21/13 06:48:10] [4840] 2013-11-21T06:48:10.540 [ndmp] + ndmp_client_env.cpp (7727): BENGINE: [11/21/13 06:48:10] [4840] 2013-11-21T06:48:10.540 [ndmp] | Found credentials for resourse: '\\EMDServer.primarycare.local'. BENGINE: [11/21/13 06:48:10] [4840] 2013-11-21T06:48:10.540 [loops] - Media Server to initiate connection for data transfer BENGINE: [11/21/13 06:48:10] [4840] 2013-11-21T06:48:10.541 [loops] + basebsdprocessor.cpp (1506): BENGINE: [11/21/13 06:48:10] [4840] 2013-11-21T06:48:10.541 [loops] | Querying for additional data streams BENGINE: [11/21/13 06:48:10] [4840] 2013-11-21T06:48:10.541 [loops] + basebsdprocessor.cpp (1510): BENGINE: [11/21/13 06:48:10] [4840] 2013-11-21T06:48:10.541 [loops] | No additional data streams requested. BENGINE: [11/21/13 06:48:10] [4840] 2013-11-21T06:48:10.541 [loops] + basebsdprocessor.cpp (1521): BENGINE: [11/21/13 06:48:10] [4840] 2013-11-21T06:48:10.541 [loops] | Setting up reverse data connection(s) BEREMOTE: [11/21/13 06:48:10] [6024] 2013-11-21T06:48:10.541 [BESocket] - @@@@@@@MyCloseSocket called with sockfd = 1032(0x408) retval = 0 BEREMOTE: [11/21/13 06:48:10] [6024] 2013-11-21T06:48:10.541 [ndmp\ndmpcomm] - dataAcceptConnection : Data Connection information: A connection was established between end-points [::1]:49261 and [::1]:49262. BENGINE: [11/21/13 06:48:10] [4840] 2013-11-21T06:48:10.541 [ndmp\ndmpcomm] - TF_InitMediaServerReverseConnection : Data Connection information: A connection was established between end-points [::1]:49262 and [::1]:49261. BEREMOTE: [11/21/13 06:48:10] [6024] 2013-11-21T06:48:10.543 [ndmp\ndmpsrvr] - ENV: USERNAME=EMDSERVER\Administrator BEREMOTE: [11/21/13 06:48:10] [6024] 2013-11-21T06:48:10.543 [ndmp\ndmpsrvr] - ENV: PASSWORD= BEREMOTE: [11/21/13 06:48:10] [6024] 2013-11-21T06:48:10.543 [ndmp\ndmpsrvr] - ENV: USERNAME=EMDSERVER\Administrator BEREMOTE: [11/21/13 06:48:10] [6024] 2013-11-21T06:48:10.543 [ndmp\ndmpsrvr] - ENV: PASSWORD= BEREMOTE: [11/21/13 06:48:10] [6024] 2013-11-21T06:48:10.543 [ndmp\ndmpsrvr] - ENV: INITIATOR_SID=0@D0000000DE0000