Restore Job Failed - Error e000848c

[Tim_ESI]

Trying to do a test restore of a single message on a exchange 2007 mailbox, recieveing the error "e000848c - Unable to attach to a resource. Make sure that all selected resources exist and are online, and then try again."

Backup jobs seem to run fine, just not the restoring.


Tried the following link:
http://www.symantec.com/business/support/knowledge_base_sli.jsp?umi=EvtID=V-79-57344-33932


But none of them seem to apply to my problem.  The target restore is the same exchange server and storage groups.
Any suggestions are welcome. Thanks.


Tim

[Anser_Arif]

hi,

Please let me know,

1. are you trying to restore to Recovery Storage Group on the Exchange server?

2. Are the backups GRT backups?

3. Are the backups on tape/disk?

[Tim_ESI]

Hi I got the problem resolved from phone support, but to answer your questions:

1. are you trying to restore to Recovery Storage Group on the Exchange server?

No.  Restoring to a messages in a single mailbox on the same exchange server.

2. Are the backups GRT backups?

Yes

3. Are the backups on tape/disk?

Backups on disk.

The solution to the problem was that the backup account did not have a mailbox. Thanks.

Tim

[Niklas_Svensson]

I'm borrowing this thread because I have the same problem as you Tim. I’m trying to restore a single message to an ex2007 sp1 mailbox. My backup is on disk and with GRT.

Backup exec 12 running on win 2003 sp2 and exchange 2007 sp1 on windows 2008.

My backup user already had a mailbox and I get the error "e000848c" anyway. I have checked all the permissions and everything else I can find in the knowledgebase. Here are the last pieces from the debug log.

[5680] 06/12/08 13:28:51 Using Rem Provider for Restore.

[5680] 06/12/08 13:28:51 Logon: m_lpMAPISession->Logon returned 80040115

[5680] 06/12/08 13:28:51 AttachToDLE(\\NTSRVEX2\Microsoft Information Store\Storage Group 1)()( -536836984)

[5680] 06/12/08 13:28:51 sis temp path=C:\Windows\TEMP\

[5680] 06/12/08 13:28:51 calling CreateRemMAPIPRov

[5680] 06/12/08 13:28:51 Using Rem Provider for Restore.

[5680] 06/12/08 13:28:51 Logon: m_lpMAPISession->Logon returned 80040115

[5680] 06/12/08 13:28:51 AttachToDLE(-536836984)[\\NTSRVEX2\Microsoft Information Store\Storage Group 1]

[5680] 06/12/08 13:28:51 startRestore: CreateBSD() failed!

[5680] 06/12/08 13:28:51 Restore Job Stop(10)  - Thu Jun 12 13:28:51 2008

[5680] 06/12/08 13:28:51 @@@@@@@MyCloseSocket called with sockfd = 1084(0x43c)                         retval = 0

[5680] 06/12/08 13:28:51 @@@@@@@MyCloseSocket called with sockfd = 3212(0xc8c)                          retval = 0

Any more solutions that I can try?

[dfabricni]

Hi,

I'm having the same problem when trying to do a restore of a single mailbox item.

I have Exchange Server 2007 SP1 on Windows Server 2008 and a Symantec Backup Exec 12 on Windows Server 2003 R2 SP2 32bit.

This is from the remote agent log on the Exchange server:

[3800] 06/15/08 21:32:56 FS_BlowOutMachine: returning 0
[3800] 06/15/08 21:32:56 sis temp path=C:\Windows\TEMP\
[3800] 06/15/08 21:32:56 calling CreateRemMAPIPRov
[3800] 06/15/08 21:32:59 Using Rem Provider for Restore.
[3800] 06/15/08 21:33:00 Logon: m_lpMAPISession->Logon returned 80040115
[3800] 06/15/08 21:33:00 AttachToDLE(-536836984)[\\MAIL.hoteli-novi.local\Microsoft Information Store\First Storage Group]
[3800] 06/15/08 21:33:00 startRestore: CreateBSD() failed!
[3800] 06/15/08 21:33:00 Restore Job Stop(10)  - Sun Jun 15 21:33:00 2008

I have a mailbox for backup exec account created and it is functional. The account is also domain admin and Exchange Server Organization Adminsitrator. MAPI is enabled on the mailbox and on all the other mailboxes.

Regards,

Dinko Fabricni

[Anser_Arif]

hi,

please make sure the latest version of CDO/MAPI version is installed on media server and exchange server.

**Important** Make sure the Backup Exec services are running under the same account which is used to do a restore.

Also, make sure the user you are restoring is present in the exchange server.

[Troubleshooter_]

hi

i've the same problem,  alltough i have the last CADO/MAPI version1.2.1

(http://www.microsoft.com/downloads/details.aspx?FamilyID=E17E7F31-079A-43A9-BFF2-0A110307611E&displa...) and the backupexec service are running with the same account.

Any help will be appreciated.

[Troubleshooter_]

Dear BackupExec Freaks,

Dou you have any idea why a restoring a single mail from backupexec 12.0 Backup Exchange 07 GRT Backup doesn't work allthough i followed this following things.

BackupMedia Server = Win2008 32bit Server

Exchange Server = Win2008 64 bit Server

Installation the same CADO on Backup and Exchange Server Exchange Management Tools on BackupServer (Service Pack1 the same version as the Exchange server)

I followed this following link and create a username called backupexec this have the required rights. This user will used for the remoteagent access (backup and restore) and for all Backupexec services. The User has an Mailbox on and is not hidden from the Global Address list.

http://seer.entsupport.symantec.com/docs/288370.htm http://seer.entsupport.symantec.com

/docs/298300.htm

The Exchange GRT Backup are on disk and completly succeded. The Error on the Exchange RA ist the

following:

184] 07/21/08 17:49:07 End of List

[4184] 07/21/08 17:49:07 FS_BlowOutMachine:

returning 0 [4184] 07/21/08 17:49:07 sis temp path=C:\Windows\TEMP\

[4184] 07/21/08 17:49:07 calling CreateRemMAPIPRov

[4184] 07/21/08 17:49:07 Using Rem Provider for Restore.

[4184] 07/21/08 17:49:07 Logon: m_lpMAPISession->Logon returned 80040115

[4184] 07/21/08 17:49:07 AttachToDLE(-536836984)[\\EXCHANGESERVERNAME\Microsoft Information Store\First Storage Group]

[4184] 07/21/08 17:49:07 startRestore: CreateBSD() failed! [4184] 07/21/08 17:49:07 Restore Job Stop(10) - Mon Jul 21 17:49:07 2008

Any Help will be appreciated.

[Tom_Steuver]

What are you referring to when you say that the backup account did not have a mailbox?  I'm having the same problem trying to restore to the same mailbox on the same Exchange server.

[Troubleshooter_]

As i know the account which you use for the backup should have a mailbox account on this exchange server and it must be shown in the global address list.

I've tested all things but i was ineffective.

[Danny_Arnold]

I have the same problem trying to restore a single user.  Done all the checks and double checks like everyone else.  Funny part is if I do a restore of the entire Mailbox instead of just one user or one message, everything restores correctly.

We need an answer on this as the whole point in GRT is to be able to restore a single user or message without having to restore the entire Mailbox.

[twlucas]

Was there ever any resolution on this issue? I'm experiencing the same problem. I've run through all the listed trouble shooting and I'm still unable to restore a single item or mailbox.

[alkeksibus]

After uncountable hours of trials I´ve finally failed to restore
a single mail from a Backup-to-Disk folder on Backup Exec for Windows 12.5 Rev 2213
into a Exchange 2007 First Storage Group Mailbox Database, continually ending
up with error E000848c - unable to attach a resource.

Our setup is exchange 2007 on windows sbs 2008 server and backup exec on a different
windows 2008 server (with only Backup-to-Disc folders as backup media on locally attached RAID, drive E: )

I´ve applied the latest updates for BE, disabled IPv6 on both machines
as proposed in https://www-secure.symantec.com/connect/forums/ex07-w2k8-grt-unable-restore-anyone-have-working
entered the machine names in hosts file, I´ve used restore wizard and not, tried different options
for exchange redirections, deleted an installed backup-to-disk devices again, installed same MAPI Version
on both severs, still no go.
Backup Mailbox Database is always successful, restore never.

I´ve enabled SGMon debug output with all options and it alway shows an ERROR 10 : "Invalid Device" entry,
but it is unclear to me what device is meant by that very entry, see below.
Any small hints in which direction to investigate further are welcome..


***************************************************************************
***** SGMon log for \\WIN-AJKJI1W8JNP *****
***************************************************************************
BECAT: [06.19.09 01:16:39] [0000] BECAT : [06/19/09 01:16:39]:[1708:2616]:[3]: SELECT * FROM ImageObjectView where 1=1 AND MediaFamilyGuid = {29A6C8C3-9750-44E3-AE3F-874BE89C6390} AND ImageNumber = 1 ORDER BY MediaFamilyGuid , ImageNumber , MediaNumber
BECAT: [06.19.09 01:16:39] [0000] BECAT : [06/19/09 01:16:39]:[1708:2616]:[3]: Number of records returned: 1
BECAT: [06.19.09 01:16:39] [0000] BECAT : [06/19/09 01:16:39]:[1708:2616]:[3]: out : devName = \\FF002.f1onlinede.local\Microsoft Information Store\First Storage Group
BECAT: [06.19.09 01:16:39] [0000] BECAT : [06/19/09 01:16:39]:[1708:2616]:[3]: ReleaseConnection ...
BECAT: [06.19.09 01:16:39] [0000] BECAT : [06/19/09 01:16:39]:[1708:2616]:[3]: Reset connection ...
BENGINE: [06.19.09 01:16:39] [0000] [2616] 06/19/09 01:16:39 Queried Device: \\FF002.f1onlinede.local\Microsoft Information Store\First Storage Group
BENGINE: [06.19.09 01:16:39] [0000] [2616] 06/19/09 01:16:39 Setting target path: []
BENGINE: [06.19.09 01:16:39] [0000] [2616] 06/19/09 01:16:39 Exit SetTargetDeviceName - deviceName: [\\FF002.f1onlinede.local\Microsoft Information Store\First Storage Group]
BENGINE: [06.19.09 01:16:39] [0000] [2616] 06/19/09 01:16:39 ValidatePDILocation: Validating PDI location: [E:\mail-xchg-full\IMG000005]. Temp path: [C:\TEMP]
BECAT: [06.19.09 01:16:39] [0000] BECAT : [06/19/09 01:16:39]:[1708:2616]:[3]: in : imageGuid({29A6C8C3-9750-44E3-AE3F-874BE89C6390})
BECAT: [06.19.09 01:16:39] [0000] BECAT : [06/19/09 01:16:39]:[1708:2616]:[3]: Get a Connection from Pool : Size(4)
BECAT: [06.19.09 01:16:39] [0000] BECAT : [06/19/09 01:16:39]:[1708:2616]:[3]: status(0) connectServerComponent : in (0) -- current 0
BECAT: [06.19.09 01:16:39] [0000] BECAT : [06/19/09 01:16:39]:[1708:2616]:[3]: ValidateConnection : 1
BECAT: [06.19.09 01:16:39] [0000] BECAT : [06/19/09 01:16:39]:[1708:2616]:[3]: Input Parameters for Image_Fragments_View : [26]({29A6C8C3-9750-44E3-AE3F-874BE89C6390})
BECAT: [06.19.09 01:16:39] [0000] BECAT : [06/19/09 01:16:39]:[1708:2616]:[3]: SELECT * FROM ImageObjectView where 1=1 AND ImageGUID = {29A6C8C3-9750-44E3-AE3F-874BE89C6390} ORDER BY MediaFamilyGuid , ImageNumber , MediaNumber
BECAT: [06.19.09 01:16:39] [0000] BECAT : [06/19/09 01:16:39]:[1708:2616]:[3]: Number of records returned: 1
BECAT: [06.19.09 01:16:39] [0000] BECAT : [06/19/09 01:16:39]:[1708:2616]:[3]: ReleaseConnection ...
BECAT: [06.19.09 01:16:39] [0000] BECAT : [06/19/09 01:16:39]:[1708:2616]:[3]: Reset connection ...
PVLSVR: [06.19.09 01:16:39] [0000] [3660] 06/19/09 01:16:39.179 PvlEntityDatabase::QueryEntity() ParentEntityType = NULL_ENTITY_TYPE ParentEntityGuid = {00000000-0000-0000-0000-000000000000} EntityType = DEVICE_ENTITY_TYPE EntityGuid = {914123B6-C3C8-4C98-9F3A-2FFE8734DA94} EntityName = "" EntityFlags = 0x00000000
BENGINE: [06.19.09 01:16:39] [0000] [2616] 06/19/09 01:16:39 Media Label: IMG000005
BESERVER: [06.19.09 01:16:39] [0000] [3668] 06/19/09 01:16:39 12 AdammAdminBO::Get() - Parent = NULL , Entity = MEDIA
PVLSVR: [06.19.09 01:16:39] [0000] [3660] 06/19/09 01:16:39.179 PvlEntityDatabase::QueryEntity() ParentEntityType = NULL_ENTITY_TYPE ParentEntityGuid = {00000000-0000-0000-0000-000000000000} EntityType = MEDIA_ENTITY_TYPE EntityGuid = {29A6C8C3-9750-44E3-AE3F-874BE89C6390} EntityName = "" EntityFlags = 0x00000000
PVLSVR: [06.19.09 01:16:39] [0000] [3660] 06/19/09 01:16:39.179 PvlEntityDatabase::QueryEntity() : SUCCESS!
BESERVER: [06.19.09 01:16:39] [0000] [3668] 06/19/09 01:16:39 12 AdammAdminBO::Get() - hr = 0x0
BENGINE: [06.19.09 01:16:39] [0000] [2616] 06/19/09 01:16:39 Trying to create backup BSD \\FF002.f1onlinede.local\Microsoft Information Store\First Storage Group for PDI restore
BENGINE: [06.19.09 01:16:39] [0000] [2616] 06/19/09 01:16:39 Failed to Load AllowExcludes key, We will update DR file evenif there are userExcludes. Error: 5
BENGINE: [06.19.09 01:16:39] [0000] [2616] 06/19/09 01:16:39 NDMPAgentConnector:SetupConnection(): Data Server = WIN-AJKJI1W8JNP
BENGINE: [06.19.09 01:16:39] [0000] [2616] 06/19/09 01:16:39 Could not resolve the "bews-ndmp" or the "ndmp" service, error code: 10109, using port 10000
BENGINE: [06.19.09 01:16:39] [0000] [2616] 06/19/09 01:16:39 ndmpConnectEx: 'WIN-AJKJI1W8JNP' resolves to the local machine itself. So, we will try and connect to a loopback address ...
BENGINE: [06.19.09 01:16:39] [0000] [2616] 06/19/09 01:16:39 ndmpConnectEx : Control Connection information: A connection was established between end-points 127.0.0.1:51876 and 127.0.0.1:10000.
BEREMOTE: [06.19.09 01:16:39] [0000] [2488] 06/19/09 01:16:39 ndmpRun: Control connection accepted : connection established between end-points 127.0.0.1:10000 and 127.0.0.1:51876
BENGINE: [06.19.09 01:16:39] [0000] [2616] 06/19/09 01:16:39 NDMP version 3 connection CONNECTED
BENGINE: [06.19.09 01:16:39] [0000] [2616] 06/19/09 01:16:39 NDMPAgentConnector::AuthenticateClient(): Using SSPI authentication with SLA/DLA as a fallback
BENGINE: [06.19.09 01:16:39] [0000] [2616] 06/19/09 01:16:39 NDMPAgentConnector::ConnectAuthUseSSPIasDefault(): Attempting SSPI authentication...
BENGINE: [06.19.09 01:16:39] [0000] [2616] 06/19/09 01:16:39 NDMP_SSPI: InitializeSecurityContext status 0x00090312 in 0 out 64
BEREMOTE: [06.19.09 01:16:39] [0000] [2504] 06/19/09 01:16:39 NDMP_SSPI: AcceptSecurityContext status 0x00090312 in 64 out 238
BENGINE: [06.19.09 01:16:39] [0000] [2616] 06/19/09 01:16:39 NDMP_SSPI: InitializeSecurityContext status 0x00000000 in 238 out 88
BEREMOTE: [06.19.09 01:16:39] [0000] [2504] 06/19/09 01:16:39 NDMP_SSPI: AcceptSecurityContext status 0x00000000 in 88 out 0
BEREMOTE: [06.19.09 01:16:39] [0000] [2504] 06/19/09 01:16:39 NDMP_SSPI: Security Package Name: NTLM
BEREMOTE: [06.19.09 01:16:39] [0000] [2504] 06/19/09 01:16:39 NDMP_SSPI: client authenticated as Administrator
BEREMOTE: [06.19.09 01:16:39] [0000] [2504] 06/19/09 01:16:39 CreateAnonymousSID: sid is S-1-5-7
BEREMOTE: [06.19.09 01:16:39] [0000] [2504] 06/19/09 01:16:39 IsUserAnonymous() returns FALSE
BENGINE: [06.19.09 01:16:39] [0000] [2616] 06/19/09 01:16:39 NDMP_SSPI: client authenticated with server
BENGINE: [06.19.09 01:16:39] [0000] [2616] 06/19/09 01:16:39 NDMPAgentConnector::ConnectAuthUseSSPIasDefault(): SSPI authentication success.
BEREMOTE: [06.19.09 01:16:39] [0000] [2504] 06/19/09 01:16:39 ndmpdDataConnect: RAWS is attempting data connection on IP : 10.12.96.140 Port : 20239
BEREMOTE: [06.19.09 01:16:39] [0000] [2504] 06/19/09 01:16:39 ndmpdDataConnect : Data Connection information: A connection was established between end-points 10.12.103.20:51877 and 10.12.96.140:20239.
BENGINE: [06.19.09 01:16:39] [0000] [2616] 06/19/09 01:16:39 PDIRestoreBSDProcessor::EstablishPDIDataConnection() - data connection established successfully.
BENGINE: [06.19.09 01:16:39] [0000] [2616] 06/19/09 01:16:39 PDIRestoreBSDProcessor::ProcessPDIDataTransfer: Starting data transfer between FF002.f1onlinede.local and WIN-AJKJI1W8JNP
BEREMOTE: [06.19.09 01:16:39] [0000] [1424] 06/19/09 01:16:39 DS_CloseSelectionService
BEREMOTE: [06.19.09 01:16:39] [0000] [3928] 06/19/09 01:16:39 [no resource for 3894]
BEREMOTE: [06.19.09 01:16:39] [0000] [3928] 06/19/09 01:16:39 EdbDatabase::~EdbDatabase() -> cached column map count: 67
BEREMOTE: [06.19.09 01:16:39] [0000] [3928] 06/19/09 01:16:39 EdbDatabase::~EdbDatabase() -> cached common map count: 1026
BEREMOTE: [06.19.09 01:16:39] [0000] [3928] 06/19/09 01:16:39 Terminating instance Email-xchg-fullIMG000005vdb_2009_06_18_1834_09 .
BENGINE: [06.19.09 01:16:40] [0000] [2616] 06/19/09 01:16:40 ERROR: 10 Error: Invalid device
BENGINE: [06.19.09 01:16:40] [0000] [2616] 06/19/09 01:16:40 Enter RestoreMessageHandler::GetMachineName
BECAT: [06.19.09 01:16:40] [0000] BECAT : [06/19/09 01:16:40]:[1708:2616]:[3]: in : inFamilyGuid({29A6C8C3-9750-44E3-AE3F-874BE89C6390}), familyId(986908353), setNum(1)
BECAT: [06.19.09 01:16:40] [0000] BECAT : [06/19/09 01:16:40]:[1708:2616]:[3]: Get a Connection from Pool : Size(4)
BECAT: [06.19.09 01:16:40] [0000] BECAT : [06/19/09 01:16:40]:[1708:2616]:[3]: status(0) connectServerComponent : in (0) -- current 0
BECAT: [06.19.09 01:16:40] [0000] BECAT : [06/19/09 01:16:40]:[1708:2616]:[3]: ValidateConnection : 1
BECAT: [06.19.09 01:16:40] [0000] BECAT : [06/19/09 01:16:40]:[1708:2616]:[3]: Input Parameters for BackupSet_ByResource_View : [1]({29A6C8C3-9750-44E3-AE3F-874BE89C6390}),[3](1)
BECAT: [06.19.09 01:16:40] [0000] BECAT : [06/19/09 01:16:40]:[1708:2616]:[3]: SELECT * FROM ImageObjectView where 1=1 AND MediaFamilyGuid = {29A6C8C3-9750-44E3-AE3F-874BE89C6390} AND ImageNumber = 1 ORDER BY MediaFamilyGuid , ImageNumber , MediaNumber
BECAT: [06.19.09 01:16:40] [0000] BECAT : [06/19/09 01:16:40]:[1708:2616]:[3]: Number of records returned: 1
BECAT: [06.19.09 01:16:40] [0000] BECAT : [06/19/09 01:16:40]:[1708:2616]:[3]: out : devName = \\FF002.f1onlinede.local\Microsoft Information Store\First Storage Group
BECAT: [06.19.09 01:16:40] [0000] BECAT : [06/19/09 01:16:40]:[1708:2616]:[3]: ReleaseConnection ...
BECAT: [06.19.09 01:16:40] [0000] BECAT : [06/19/09 01:16:40]:[1708:2616]:[3]: Reset connection ...
BENGINE: [06.19.09 01:16:40] [0000] [2616] 06/19/09 01:16:40 Exit GetMachineName - Machine name: [FF002.f1onlinede.local]
BENGINE: [06.19.09 01:16:40] [0000] [2616] 06/19/09 01:16:40 NDMPAgentConnector::EndConnection(): Data Server = WIN-AJKJI1W8JNP
BEREMOTE: [06.19.09 01:16:40] [0000] [2504] 06/19/09 01:16:40 NDMP_SSPI: reverted to user SYSTEM
BEREMOTE: [06.19.09 01:16:40] [0000] [2504] 06/19/09 01:16:40 @@@@@@@MyCloseSocket called with sockfd = 2264(0x8d8) retval = 0
BEREMOTE: [06.19.09 01:16:40] [0000] [2504] 06/19/09 01:16:40 @@@@@@@MyCloseSocket called with sockfd = 1288(0x508) retval = 0
BENGINE: [06.19.09 01:16:40] [0000] [2616] 06/19/09 01:16:40 NDMPAgentConnector::EndConnection(): Data Server = FF002.f1onlinede.local
BENGINE: [06.19.09 01:16:40] [0000] [2616] 06/19/09 01:16:40 Removing 'Restore 00141' from status update list
BENGINE: [06.19.09 01:16:40] [0000] [2616] 06/19/09 01:16:40 Updating status for: 'Restore 00141' (0x18 0xB)
BENGINE: [06.19.09 01:16:40] [0000] [2616] 06/19/09 01:16:40 Status for: 'Restore 00141' updated
BENGINE: [06.19.09 01:16:40] [0000] [2616] 06/19/09 01:16:40 Merging of BE / VSR Job Logs not necessary for this Type of Job
BENGINE: [06.19.09 01:16:40] [0000] [2616] 06/19/09 01:16:40 Ending job 'Restore 00141' with error status (-536836980)
BESERVER: [06.19.09 01:16:40] [0000] [3668] 06/19/09 01:16:40 17 JobManager::HandleEndJob() called
BESERVER: [06.19.09 01:16:40] [0000] [2784] 06/19/09 01:16:40 -1 ActiveState::doEndEvent( :( 'Restore 00141'
BESERVER: [06.19.09 01:16:40] [0000] [2784] 06/19/09 01:16:40 17 JobManager::DoJobCompletionTasks(Restore 00141): GetFinalJobStatus()=6
BENGINE: [06.19.09 01:16:40] [0000] [2616] 06/19/09 01:16:40 Job thread terminating
BESERVER: [06.19.09 01:16:40] [0000] [2784] 06/19/09 01:16:40 17 JobManager::DoJobCompletionTasks() Handling normal JOB_STATE_ERROR
BESERVER: [06.19.09 01:16:40] [0000] [2784] 06/19/09 01:16:40 17 JobManager::DoJobCompletionTasks() Finished building JOB_STATE_ERROR message
BESERVER: [06.19.09 01:16:40] [0000] [3668] 06/19/09 01:16:40 01 Server Configuration: Client removed: 7
BESERVER: [06.19.09 01:16:40] [0000] [3668] 06/19/09 01:16:40 -1 Client 'WIN-AJKJI1W8JNP' Disconnected:0x89bded0
BESERVER: [06.19.09 01:16:40] [0000] [2784] 06/19/09 01:16:40 18 Alert Job Failed, Notify: Yes, EventLog: Yes, Trap: No
BESERVER: [06.19.09 01:16:40] [0000] [2784] 06/19/09 01:16:40 18 Doing Notify
BESERVER: [06.19.09 01:16:40] [0000] [2784] 06/19/09 01:16:40 -1 ActiveState::doEndEvent( :( CJobManager::DoJobCompletionTasks() returned 0x0
BESERVER: [06.19.09 01:16:40] [0000] [3940] 06/19/09 01:16:40 18 Notify Return Code:0, batch:14, index:0
BESERVER: [06.19.09 01:16:40] [0000] [3940] 06/19/09 01:16:40 18 Notify Return Code:0, batch:14, index:1
BEREMOTE: [06.19.09 01:16:40] [0000] [3928] 06/19/09 01:16:40 [no resource for 3894]
BEREMOTE: [06.19.09 01:16:40] [0000] [3928] 06/19/09 01:16:40 RA_RemoveDLE: DestroyDLE()
BEREMOTE: [06.19.09 01:16:40] [0000] [3928] 06/19/09 01:16:40 RA_RemoveDLE: DestroyDLE()
BEREMOTE: [06.19.09 01:16:40] [0000] [3928] 06/19/09 01:16:40 RA_RemoveDLE: DestroyDLE()
BEREMOTE: [06.19.09 01:16:40] [0000] [3928] 06/19/09 01:16:40 RA_RemoveDLE: DestroyDLE()
BEREMOTE: [06.19.09 01:16:40] [0000] [3928] 06/19/09 01:16:40 DsWorkerThread terminating with exit code 0
BEREMOTE: [06.19.09 01:16:40] [0000] [1424] 06/19/09 01:16:40 DS_CloseSelectionService
BEREMOTE: [06.19.09 01:16:40] [0000] [1424] 06/19/09 01:16:40 @@@@@@@MyCloseSocket called with sockfd = 1416(0x588) retval = 0
BEREMOTE: [06.19.09 01:16:40] [0000] [3748] 06/19/09 01:16:40 RA_RemoveDLE: DestroyDLE()
BEREMOTE: [06.19.09 01:16:40] [0000] [3748] 06/19/09 01:16:40 RA_RemoveDLE: DestroyDLE()
BEREMOTE: [06.19.09 01:16:40] [0000] [3748] 06/19/09 01:16:40 RA_RemoveDLE: DestroyDLE()
BEREMOTE: [06.19.09 01:16:40] [0000] [3748] 06/19/09 01:16:40 RA_RemoveDLE: DestroyDLE()
BEREMOTE: [06.19.09 01:16:40] [0000] [3748] 06/19/09 01:16:40 DsWorkerThread terminating with exit code 0
BESERVER: [06.19.09 01:16:40] [0000] [3668] 06/19/09 01:16:40 -1 Registered virtual array rowset(33): Client:WIN-AJKJI1W8JNP, Type:MD_OBJTYPE_JOBHISTORYVIEW
BKUPEXEC: [06.19.09 01:16:40] [0000] UIVirtualObjectView(JobHistoryView) Queried 500 items: 0 to 499
BESERVER: [06.19.09 01:16:40] [0000] [3668] 06/19/09 01:16:40 -1 Released virtual array rowset(32).
BESERVER: [06.19.09 01:16:40] [0000] [3668] 06/19/09 01:16:40 -1 Released virtual array rowset(30).
BKUPEXEC: [06.19.09 01:16:40] [0000] 06.19.09 01:16:40 [0014] [0000932] [Info] VSN received: type = VSN_TYPE_ALERT_UPDATE
BKUPEXEC: [06.19.09 01:16:40] [0000] 06.19.09 01:16:40 [0014] [0000933] [Debug] VSN is an ADD
BKUPEXEC: [06.19.09 01:16:40] [0000] 06.19.09 01:16:40 [0014] [0000934] [Debug] VSN added to queues
BKUPEXEC: [06.19.09 01:16:40] [0000] 06.19.09 01:16:40 [0014] [0000935] [Info] VSN received: type = VSN_TYPE_COMPJOB_UPDATE
BKUPEXEC: [06.19.09 01:16:40] [0000] 06.19.09 01:16:40 [0014] [0000936] [Debug] VSN is an ADD
BKUPEXEC: [06.19.09 01:16:40] [0000] 06.19.09 01:16:40 [0014] [0000937] [Debug] VSN added to queues
BKUPEXEC: [06.19.09 01:16:40] [0000] 06.19.09 01:16:40 [0014] [0000938] [Info] VSN received: type = VSN_TYPE_ACTJOBEX_UPDATE
BKUPEXEC: [06.19.09 01:16:40] [0000] 06.19.09 01:16:40 [0014] [0000939] [Debug] VSN is a REMOVE
BKUPEXEC: [06.19.09 01:16:40] [0000] 06.19.09 01:16:40 [0014] [0000940] [Debug] VSN added to queues
BESERVER: [06.19.09 01:16:43] [0000] [3032] 06/19/09 01:16:43 17 CJobManagerBO::Query QUERY_JOBSETUP_MONITOR
BKUPEXEC: [06.19.09 01:16:43] [0000] 06.19.09 01:16:43 [0014] [0000941] [Info] VSN received: type = VSN_TYPE_SCHJOB_UPDATE
BKUPEXEC: [06.19.09 01:16:43] [0000] 06.19.09 01:16:43 [0014] [0000942] [Debug] VSN is a MODIFY
BKUPEXEC: [06.19.09 01:16:43] [0000] 06.19.09 01:16:43 [0014] [0000943] [Debug] VSN added to queues
BESERVER: [06.19.09 01:16:48] [0000] [3032] 06/19/09 01:16:48 17 JobManager: Sending History Update VSN, count:1.
BKUPEXEC: [06.19.09 01:16:49] [0000] 06.19.09 01:16:48 [0014] [0000944] [Info] VSN received: type = VSN_TYPE_JOBHIST_UPDATE
BKUPEXEC: [06.19.09 01:16:49] [0000] 06.19.09 01:16:48 [0014] [0000945] [Debug] VSN is a MODIFY
BKUPEXEC: [06.19.09 01:16:49] [0000] 06.19.09 01:16:48 [0014] [0000946] [Debug] No VSN payload - do a reset
BKUPEXEC: [06.19.09 01:16:49] [0000] 06.19.09 01:16:48 [0014] [0000947] [Debug] VSN added to queues
BESERVER: [06.19.09 01:17:06] [0000] [3668] 06/19/09 01:17:06 -1 Registered virtual array rowset(34): Client:WIN-AJKJI1W8JNP, Type:MD_OBJTYPE_JOBHISTORYVIEW
BKUPEXEC: [06.19.09 01:17:06] [0000] UIVirtualObjectView(JobHistoryView) Queried 500 items: 0 to 499
BESERVER: [06.19.09 01:17:07] [0000] [3668] 06/19/09 01:17:06 -1 Released virtual array rowset(33).
BECAT: [06.19.09 01:17:21] [0000] BECAT : [06/19/09 01:17:21]:[1708:2584]:[3]: Get a Connection from Pool : Size(4)
BECAT: [06.19.09 01:17:21] [0000] BECAT : [06/19/09 01:17:21]:[1708:2584]:[3]: status(0) connectServerComponent : in (0) -- current 0
BECAT: [06.19.09 01:17:21] [0000] BECAT : [06/19/09 01:17:21]:[1708:2584]:[3]: ValidateConnection : 1
BECAT: [06.19.09 01:17:21] [0000] BECAT : [06/19/09 01:17:21]:[1708:2584]:[3]: SELECT * FROM CatImageFileDeleteTable
BECAT: [06.19.09 01:17:21] [0000] BECAT : [06/19/09 01:17:21]:[1708:2584]:[3]: Number of records returned: 0
BECAT: [06.19.09 01:17:21] [0000] BECAT : [06/19/09 01:17:21]:[1708:2584]:[3]: Cleanup images : 0
BECAT: [06.19.09 01:17:21] [0000] BECAT : [06/19/09 01:17:21]:[1708:2584]:[3]: ReleaseConnection ...
BECAT: [06.19.09 01:17:21] [0000] BECAT : [06/19/09 01:17:21]:[1708:2584]:[3]: Reset connection ...

[mario_g1]

I had the same problem, tried everything said above but it didn't help.

I solved problem this way:

1. installing SP3 for backup exec 12.5 and latest hotfixes
2. completley unistall and then install remote agent on exchange 2007 server