Exchange Brick Level ARCserve 11.0 Problems 2

[ServerStorm]

Hi Everyone,

I have spent the last several days attempting to get Brick Level Back-ups working on our network. I just recently assumed responsibilies for our company back-up using ARCserve, and as this is the first time I have worked with it, I am by no means an expert or even skilled ARCserve user. I have read the online manual and over the past several days read many older to new posts and support documents around Brick Level back-ups and their problems/solutions.

My environment is as such:

ARCserve 11.0 installed on (ICECUBE) a Windows 2000 standard server with service pack 4.0. This server replicates Active Directory for our PDC.

ARCserver components that are installed are:

CA Backup Agent for Open Files
CA BrightStor Database Engine
CA BrightStor Discovery Service
CA BrightStor Domain Server
CA BrightStor Job Engine
CA BrightStor Service Controller
CA BrightStor Tape Engine
CA License Client
CA Remote Procedure Call Server

Our Exchange Server is (Lightning). It is a Windows 2003 standard server, service pack 1 running Exchange 2003.

ARCserve components that are installed are:

CA backup Agent for Open Files
CA BrighStor Backup Agent RPC Server

I have been able to successufully back up files and the Microsoft Exchange Server - Database Level(IS); however I cannot seem to get the Brick Level working. I receive the following error when attempting back-up:

E8601
Failed to connect agent. (AGENT=dbaexchis, EC=DBagent RPC Server is not started or not available.)

I have checked that the 'CA BrightStor Backup Agent RPC Server' is started.

When I make a job, I can, under 'Source' select Individual Mailboxes and Public Folders, however when I attempt to 'Start' a job, and the Security and Agent Information appears I cannot get the Brick Level agent to accept a username or password.

When I select 'Agent' and then the 'Use Agent' box, it asks for a user and password; when I attempt to input user account and corresponding password information the Security window appears again with a blank password field. I have entered the username like:

river/administrtor
river.mydomain.com/administrator
administrator

On the Lightning server, where Exchange and the Backup Agent reside I have ensured that the Backup Agent for Microsoft Exchange Configuration has been configured:

TCP/IP Port Number 6070
Number of 64KB blocks in each TCP/IP data transfer 1

Brick Level Coniguration
I select the 'Configure a Backup Agent Service Account to use with Brick Level' check-box. I enter this info:

'Mailbox (alias)' administrator
(I have created an administrator mailbox)
'Service Account' administrator
'Password' admin's password (double checked)
'Confirm Password' admin's password again
'Domain of Account' RIVER

I then select finish.

I have ensured that the administrator accounts belongs to all the groups that are needed to access the Exchange and Directory Objects. I have also reinstalled the Exchange Backup Agent as I read that several times that this can help.

I would very much appreciate your help in pointing me in the right direction.

Truly,
ServerStorm

 

[speshalyst]

HI,

Brick Level Coniguration
I select the 'Configure a Backup Agent Service Account to use with Brick Level' check-box. I enter this info:

'Mailbox (alias)' administrator
(I have created an administrator mailbox)
'Service Account' administrator
'Password' admin's password (double checked)
'Confirm Password' admin's password again
'Domain of Account' RIVER

I then select finish.

After you clicked Finish , did you get the message - configuration successful ?

If so, open the "services" on the Exchange server, and go to the Backup Agent RPC service.Stop it.
Open the properties of this service and go to the Log on tab. Set it to log as the administrator.

restart the service.

Now try to expand the brick level from the BAB manager



So it Shall be Written!
So it Shall be Done!!

 

[ServerStorm]

Hi speshalyst

I did receive a 'successful configuration' screen after configuring the connector.

I forgot to mention that I had gone into the Exchange Back-up Agent Service and configured the domain user's account here - the same that I set in the Exchange Back-up Agent Configuration.

I did however re-do this step as you suggested, and I still get the same result. I can select the Brick Level and its' properties in the 'Source', However if I choose 'Start' and am presented with the 'Security and Agent Information' window that has the Security... and Agent... buttons, I can see that the Exchange Agent is not set. I then click on Agent and presented with 'Agent Information' screen. On this screen I select 'Use Agent' and the Security window appears. This is where it repeatedly asks for the User Name and Password.

Truly,
ServerStorm

 

[myauto]

suggest to check the CA BrightStor Backup Agent RPC Server service account
and can you provider me the brick level backup active log in agent installation directory on exchange server

 

[ServerStorm]

Hi myauto,

I checked the service account and it was ./administrator. I had specifically set it two days ago to domain/administrator so I don't know why it went back to the local administrator account? I reset it to domain/administrator, restarted the service and then checked it again, and it had retained the domain/administrator setting.

I am not completely sure what the proper log file is so here is dbaexch.log found in the agent installation directory:

12/12/05 18:07 Connect Exchange Backup Agent
12/12/05 18:08 <First Storage Group> in Full Backup Mode
12/12/05 19:01 <First Storage Group> removed from Backup Mode
12/12/05 19:01 Disconnect Exchange Backup Agent
12/12/05 23:25 Connect Exchange Backup Agent
12/12/05 23:25 Disconnect Exchange Backup Agent
12/12/05 23:25 Connect Exchange Backup Agent
12/12/05 23:25 Disconnect Exchange Backup Agent
12/12/05 23:25 Connect Exchange Backup Agent
12/12/05 23:25 Disconnect Exchange Backup Agent
12/12/05 23:33 Connect Exchange Backup Agent
12/12/05 23:33 Disconnect Exchange Backup Agent
12/13/05 00:15 Connect Exchange Backup Agent
12/13/05 00:16 <First Storage Group> in Incremental Backup Mode
12/13/05 00:18 <First Storage Group> removed from Backup Mode
12/13/05 00:18 Disconnect Exchange Backup Agent
12/13/05 02:06 Connect Exchange Backup Agent
12/13/05 02:06 Disconnect Exchange Backup Agent
12/13/05 02:08 Connect Exchange Backup Agent
12/13/05 02:08 Disconnect Exchange Backup Agent
12/13/05 03:07 Connect Exchange Backup Agent
12/13/05 03:07 <First Storage Group> in Incremental Backup Mode
12/13/05 03:08 <First Storage Group> removed from Backup Mode
12/13/05 03:08 Disconnect Exchange Backup Agent
12/14/05 18:04 Connect Exchange Backup Agent
12/14/05 18:04 Disconnect Exchange Backup Agent
12/14/05 18:04 Connect Exchange Backup Agent
12/14/05 18:04 Disconnect Exchange Backup Agent
12/14/05 18:05 Connect Exchange Backup Agent
12/14/05 18:05 <First Storage Group> in Incremental Backup Mode
12/14/05 18:10 <First Storage Group> removed from Backup Mode
12/14/05 18:10 Disconnect Exchange Backup Agent
12/15/05 18:05 Connect Exchange Backup Agent
12/15/05 18:05 Disconnect Exchange Backup Agent
12/15/05 18:05 Connect Exchange Backup Agent
12/15/05 18:06 Disconnect Exchange Backup Agent
12/15/05 18:06 Connect Exchange Backup Agent
12/15/05 18:06 <First Storage Group> in Incremental Backup Mode
12/15/05 18:06 <First Storage Group> removed from Backup Mode
12/15/05 18:07 Disconnect Exchange Backup Agent
12/17/05 02:50 Connect Exchange Backup Agent
12/17/05 02:50 Disconnect Exchange Backup Agent
12/17/05 02:50 Connect Exchange Backup Agent
12/17/05 02:50 Disconnect Exchange Backup Agent
12/17/05 04:20 Connect Exchange Backup Agent
12/17/05 04:22 <First Storage Group> in Full Backup Mode
12/17/05 05:20 <First Storage Group> removed from Backup Mode
12/17/05 05:21 Disconnect Exchange Backup Agent
12/19/05 18:06 Connect Exchange Backup Agent
12/19/05 18:06 Disconnect Exchange Backup Agent
12/19/05 18:06 Connect Exchange Backup Agent
12/19/05 18:06 Disconnect Exchange Backup Agent
12/19/05 18:07 Connect Exchange Backup Agent
12/19/05 18:07 <First Storage Group> in Incremental Backup Mode
12/19/05 18:07 <First Storage Group> removed from Backup Mode
12/19/05 18:07 Disconnect Exchange Backup Agent


You can see that it had not recorded anything in January, yet all the work I have done has been done in January?

The other log is the ExchVerify.log:
12/12/05,11:51:34 : ExchGeServerName() trying for Exchange 2000
12/12/05,11:51:34 : Exchange Server Name from Registry is[LIGHTNING]
12/12/05,11:51:34 : OpenSCManager() successful with all ACCESS
12/12/05,11:51:34 : OpenService() failed : errCode[1060] - errMsg[The specified service does not exist as an installed service. (0x424)]
12/12/05,11:51:34 : ERROR: ExchRemoveService() failed: return[4]
12/12/05,11:51:34 : ExchVerify() Calling ExchInstallService() to install the verification service
12/12/05,11:51:34 : servicePath = "C:\Program Files\CA\BrightStor ARCserve Backup Agent for Exchange\VService.exe"
12/12/05,11:51:34 : OpenSCManager() successful with all ACCESS
12/12/05,11:51:34 : CreateService() [Exchange Agent Setup Verification Service] successfully installed
12/12/05,11:51:34 : ExchInstallService() successfully finished
12/12/05,11:51:34 : OpenSCManager() successful with all ACCESS
12/12/05,11:51:34 : OpenService() [ExchVerification] successful with all ACCESS
12/12/05,11:51:34 : Service start pending
12/12/05,11:51:37 : StartService() SUCCESSFUL
12/12/05,11:51:37 : ExchStartService() successfully finished
12/12/05,11:51:37 : CallNamedPipeA() successful
12/12/05,11:51:37 : ---> Authentication result: [Authentication successful: Setup Input is GOOD!!!]
12/12/05,11:51:37 : ExchPerformVerification() return[0]
12/12/05,11:51:37 : OpenSCManager() successful with all ACCESS
12/12/05,11:51:37 : OpenService() [ExchVerification] successful with all ACCESS
12/12/05,11:51:37 : ControlService() Stopping service [Exchange Agent Setup Verification Service]......
12/12/05,11:51:38 : Service [Exchange Agent Setup Verification Service] stopped
12/12/05,11:51:38 : DeleteService() [Exchange Agent Setup Verification Service] removed
12/12/05,11:51:38 : ExchRemoveService() successfully finished
12/13/05,00:40:06 : ExchGeServerName() trying for Exchange 2000
12/13/05,00:40:06 : Exchange Server Name from Registry is[LIGHTNING]
12/13/05,00:40:06 : OpenSCManager() successful with all ACCESS
12/13/05,00:40:06 : OpenService() failed : errCode[1060] - errMsg[The specified service does not exist as an installed service. (0x424)]
12/13/05,00:40:06 : ERROR: ExchRemoveService() failed: return[4]
12/13/05,00:40:06 : ExchVerify() Calling ExchInstallService() to install the verification service
12/13/05,00:40:06 : servicePath = "C:\Program Files\CA\BrightStor ARCserve Backup Agent for Exchange\VService.exe"
12/13/05,00:40:06 : OpenSCManager() successful with all ACCESS
12/13/05,00:40:06 : CreateService() [Exchange Agent Setup Verification Service] successfully installed
12/13/05,00:40:06 : ExchInstallService() successfully finished
12/13/05,00:40:06 : OpenSCManager() successful with all ACCESS
12/13/05,00:40:06 : OpenService() [ExchVerification] successful with all ACCESS
12/13/05,00:40:06 : Service start pending
12/13/05,00:40:06 : RegisterServiceCtrlHandler() successful
12/13/05,00:40:06 : ERROR: EnablePrivilege() AdjustTokenPrivileges Failed-1300
12/13/05,00:40:06 : ERROR: ExchSetAllPrivilege() EnablePrivilege SE_CREATE_TOKEN_NAME Failed-183
12/13/05,00:40:06 : ExchSetAllPrivilege() return error code[0]
12/13/05,00:40:06 : Trying to start Service now ......
12/13/05,00:40:06 : CreateEvent() successful
12/13/05,00:40:06 : CreateNamedPipe() successful
12/13/05,00:40:08 : StartService() SUCCESSFUL
12/13/05,00:40:08 : ExchStartService() successfully finished
12/13/05,00:40:08 : ExchAuthenticate() Begin --------------------------
12/13/05,00:40:08 : ExchAuthenticate() called with NTServerName:[LIGHTNING] NTDomainName[river.isisdigital.com] adminMailbox:[Administrator] adminLoginName:[Administrator]
12/13/05,00:40:08 : Calling ExchCreateProfile() to create the Profile[EXCH_AUTHENTICATE_PROFILE26441134452408]
12/13/05,00:40:08 : The Windows NT system directory of the local server is:[C:\WINDOWS\system32]
12/13/05,00:40:08 : GetFileVersionInfoSize() for [C:\WINDOWS\system32\MAPI32.DLL] successful: return Size[2212]
12/13/05,00:40:08 : VerQueryValue() return version [6:5:7226:0]
12/13/05,00:40:08 : Doing LogonUser() and Impersonate
12/13/05,00:40:08 : LogonUser() successful to Domain[river.isisdigital.com] as userName[Administrator]
12/13/05,00:40:08 : ImpersonateLoggedOnUser() successful
12/13/05,00:40:08 : ERROR: OpenToken() OpenProcess Failed-5
12/13/05,00:40:08 : ExchSetAllPrivilege() return error code[0]
12/13/05,00:40:08 : MAPIInitialize() successful
12/13/05,00:40:08 : MAPIAdminProfiles() successful
12/13/05,00:40:08 : CreateProfile() profile[EXCH_AUTHENTICATE_PROFILE26441134452408] successful
12/13/05,00:40:08 : AdminServices() successful
12/13/05,00:40:08 : CreateMsgService() for msgService[MSEMS] successful
12/13/05,00:40:08 : GetMsgServiceTable() successful
12/13/05,00:40:08 : HrQueryAllRows() successful, with [1] rows returned
12/13/05,00:40:08 : ExchCheckPropertyTag() successful: Property Tag correctly match
12/13/05,00:40:08 : microsoft Exchange Service found in row [0]
12/13/05,00:40:09 : ConfigureMsgService() successful
12/13/05,00:40:09 : ExchCreateProfile() return [0]
12/13/05,00:40:09 : MAPI is initialized: cleanup resource
12/13/05,00:40:09 : Return back from impersonation
12/13/05,00:40:09 : RevertToSelf() successful
12/13/05,00:40:09 : CloseHandle() successful
12/13/05,00:40:09 : ExchCreateProfile() profile[EXCH_AUTHENTICATE_PROFILE26441134452408] successful
12/13/05,00:40:09 : The Windows NT system directory of the local server is:[C:\WINDOWS\system32]
12/13/05,00:40:09 : GetFileVersionInfoSize() for [C:\WINDOWS\system32\MAPI32.DLL] successful: return Size[2212]
12/13/05,00:40:09 : VerQueryValue() return version [6:5:7226:0]
12/13/05,00:40:09 : Doing LogonUser() and Impersonate
12/13/05,00:40:09 : LogonUser() successful to Domain[river.isisdigital.com] as userName[Administrator]
12/13/05,00:40:09 : ImpersonateLoggedOnUser() successful
12/13/05,00:40:09 : ERROR: OpenToken() OpenProcess Failed-5
12/13/05,00:40:09 : ExchSetAllPrivilege() return error code[0]
12/13/05,00:40:09 : MAPIInitialize() successful
12/13/05,00:40:09 : MAPILogonEX() successful
12/13/05,00:40:09 : MAPIAdminProfiles() successful
12/13/05,00:40:09 : Profile [EXCH_AUTHENTICATE_PROFILE26441134452408] is deleted
12/13/05,00:40:09 : ExchLogonEX() successful connectID[7937936]
12/13/05,00:40:09 : HrMAPIFindDefaultMsgStore() successful
12/13/05,00:40:09 : OpenMsgStore() successful
12/13/05,00:40:09 : HrGetServerDN() successful: servername [/o=isisdigital/ou=First Administrative Group/cn=Configuration/cn=Servers/cn=LIGHTNING]
12/13/05,00:40:09 : QueryInterface() successful
12/13/05,00:40:09 : ExchGetManageStoreInterface() successful
12/13/05,00:40:09 : GetMailboxTable() successful
12/13/05,00:40:09 : HrQueryAllRows() successful with [28] rows returned
12/13/05,00:40:09 : ExchEstimate: Process number required to handle the server is [1]
12/13/05,00:40:09 : StoreLogoff() successful
12/13/05,00:40:09 : Cleanup Handle for Exchange Server 4.0
12/13/05,00:40:09 : Return back from impersonation
12/13/05,00:40:09 : RevertToSelf() successful
12/13/05,00:40:09 : CloseHandle() successful
12/13/05,00:40:09 : ExchCheckAccess() successful
12/13/05,00:40:09 : ExchAuthenticate() successful
12/13/05,00:40:09 : CallNamedPipeA() successful
12/13/05,00:40:09 : ---> Authentication result: [Authentication successful: Setup Input is GOOD!!!]
12/13/05,00:40:09 : ExchPerformVerification() return[0]
12/13/05,00:40:09 : OpenSCManager() successful with all ACCESS
12/13/05,00:40:09 : OpenService() [ExchVerification] successful with all ACCESS
12/13/05,00:40:09 : ExchServiceControl() called with ControlCode[1]
12/13/05,00:40:09 : Service is started.
12/13/05,00:40:09 : ControlService() Stopping service [Exchange Agent Setup Verification Service]......
12/13/05,00:40:09 : StartServiceCtrlDispatcher() successful
12/13/05,00:40:10 : Service [Exchange Agent Setup Verification Service] stopped
12/13/05,00:40:10 : DeleteService() [Exchange Agent Setup Verification Service] removed
12/13/05,00:40:10 : ExchRemoveService() successfully finished
12/13/05,00:40:59 : ExchGeServerName() trying for Exchange 2000
12/13/05,00:40:59 : Exchange Server Name from Registry is[LIGHTNING]
12/13/05,00:40:59 : OpenSCManager() successful with all ACCESS
12/13/05,00:40:59 : OpenService() failed : errCode[1060] - errMsg[The specified service does not exist as an installed service. (0x424)]
12/13/05,00:40:59 : ERROR: ExchRemoveService() failed: return[4]
12/13/05,00:40:59 : ExchVerify() Calling ExchInstallService() to install the verification service
12/13/05,00:40:59 : servicePath = "C:\Program Files\CA\BrightStor ARCserve Backup Agent for Exchange\VService.exe"
12/13/05,00:40:59 : OpenSCManager() successful with all ACCESS
12/13/05,00:40:59 : CreateService() [Exchange Agent Setup Verification Service] successfully installed
12/13/05,00:40:59 : ExchInstallService() successfully finished
12/13/05,00:40:59 : OpenSCManager() successful with all ACCESS
12/13/05,00:40:59 : OpenService() [ExchVerification] successful with all ACCESS
12/13/05,00:40:59 : RegisterServiceCtrlHandler() successful
12/13/05,00:40:59 : Service start pending
12/13/05,00:40:59 : ERROR: EnablePrivilege() AdjustTokenPrivileges Failed-1300
12/13/05,00:40:59 : ERROR: ExchSetAllPrivilege() EnablePrivilege SE_CREATE_TOKEN_NAME Failed-183
12/13/05,00:40:59 : ExchSetAllPrivilege() return error code[0]
12/13/05,00:40:59 : Trying to start Service now ......
12/13/05,00:40:59 : CreateEvent() successful
12/13/05,00:40:59 : CreateNamedPipe() successful
12/13/05,00:41:01 : ExchServiceControl() called with ControlCode[4]
12/13/05,00:41:02 : StartService() SUCCESSFUL
12/13/05,00:41:02 : ExchStartService() successfully finished
12/13/05,00:41:02 : ExchAuthenticate() Begin --------------------------
12/13/05,00:41:02 : ExchAuthenticate() called with NTServerName:[LIGHTNING] NTDomainName[river.isisdigital.com] adminMailbox:[Administrator] adminLoginName:[Administrator]
12/13/05,00:41:02 : Calling ExchCreateProfile() to create the Profile[EXCH_AUTHENTICATE_PROFILE16161134452462]
12/13/05,00:41:02 : The Windows NT system directory of the local server is:[C:\WINDOWS\system32]
12/13/05,00:41:02 : GetFileVersionInfoSize() for [C:\WINDOWS\system32\MAPI32.DLL] successful: return Size[2212]
12/13/05,00:41:02 : VerQueryValue() return version [6:5:7226:0]
12/13/05,00:41:02 : Doing LogonUser() and Impersonate
12/13/05,00:41:02 : LogonUser() successful to Domain[river.isisdigital.com] as userName[Administrator]
12/13/05,00:41:02 : ImpersonateLoggedOnUser() successful
12/13/05,00:41:02 : ERROR: OpenToken() OpenProcess Failed-5
12/13/05,00:41:02 : ExchSetAllPrivilege() return error code[0]
12/13/05,00:41:02 : MAPIInitialize() successful
12/13/05,00:41:02 : MAPIAdminProfiles() successful
12/13/05,00:41:02 : CreateProfile() profile[EXCH_AUTHENTICATE_PROFILE16161134452462] successful
12/13/05,00:41:02 : AdminServices() successful
12/13/05,00:41:02 : CreateMsgService() for msgService[MSEMS] successful
12/13/05,00:41:02 : GetMsgServiceTable() successful
12/13/05,00:41:02 : HrQueryAllRows() successful, with [1] rows returned
12/13/05,00:41:02 : ExchCheckPropertyTag() successful: Property Tag correctly match
12/13/05,00:41:02 : microsoft Exchange Service found in row [0]
12/13/05,00:41:02 : ConfigureMsgService() successful
12/13/05,00:41:02 : ExchCreateProfile() return [0]
12/13/05,00:41:02 : MAPI is initialized: cleanup resource
12/13/05,00:41:02 : Return back from impersonation
12/13/05,00:41:02 : RevertToSelf() successful
12/13/05,00:41:02 : CloseHandle() successful
12/13/05,00:41:02 : ExchCreateProfile() profile[EXCH_AUTHENTICATE_PROFILE16161134452462] successful
12/13/05,00:41:02 : The Windows NT system directory of the local server is:[C:\WINDOWS\system32]
12/13/05,00:41:02 : GetFileVersionInfoSize() for [C:\WINDOWS\system32\MAPI32.DLL] successful: return Size[2212]
12/13/05,00:41:02 : VerQueryValue() return version [6:5:7226:0]
12/13/05,00:41:02 : Doing LogonUser() and Impersonate
12/13/05,00:41:02 : LogonUser() successful to Domain[river.isisdigital.com] as userName[Administrator]
12/13/05,00:41:02 : ImpersonateLoggedOnUser() successful
12/13/05,00:41:02 : ERROR: OpenToken() OpenProcess Failed-5
12/13/05,00:41:02 : ExchSetAllPrivilege() return error code[0]
12/13/05,00:41:02 : MAPIInitialize() successful
12/13/05,00:41:02 : MAPILogonEX() successful
12/13/05,00:41:02 : MAPIAdminProfiles() successful
12/13/05,00:41:02 : Profile [EXCH_AUTHENTICATE_PROFILE16161134452462] is deleted
12/13/05,00:41:02 : ExchLogonEX() successful connectID[7937936]
12/13/05,00:41:02 : HrMAPIFindDefaultMsgStore() successful
12/13/05,00:41:02 : OpenMsgStore() successful
12/13/05,00:41:02 : HrGetServerDN() successful: servername [/o=isisdigital/ou=First Administrative Group/cn=Configuration/cn=Servers/cn=LIGHTNING]
12/13/05,00:41:02 : QueryInterface() successful
12/13/05,00:41:02 : ExchGetManageStoreInterface() successful
12/13/05,00:41:02 : GetMailboxTable() successful
12/13/05,00:41:02 : HrQueryAllRows() successful with [28] rows returned
12/13/05,00:41:02 : ExchEstimate: Process number required to handle the server is [1]
12/13/05,00:41:02 : StoreLogoff() successful
12/13/05,00:41:02 : Cleanup Handle for Exchange Server 4.0
12/13/05,00:41:02 : Return back from impersonation
12/13/05,00:41:02 : RevertToSelf() successful
12/13/05,00:41:02 : CloseHandle() successful
12/13/05,00:41:02 : ExchCheckAccess() successful
12/13/05,00:41:02 : ExchAuthenticate() successful
12/13/05,00:41:02 : CallNamedPipeA() successful
12/13/05,00:41:02 : ---> Authentication result: [Authentication successful: Setup Input is GOOD!!!]
12/13/05,00:41:02 : ExchPerformVerification() return[0]
12/13/05,00:41:02 : OpenSCManager() successful with all ACCESS
12/13/05,00:41:02 : OpenService() [ExchVerification] successful with all ACCESS
12/13/05,00:41:02 : ExchServiceControl() called with ControlCode[1]
12/13/05,00:41:02 : Service is started.
12/13/05,00:41:02 : ControlService() Stopping service [Exchange Agent Setup Verification Service]......
12/13/05,00:41:02 : StartServiceCtrlDispatcher() successful
12/13/05,00:41:03 : Service [Exchange Agent Setup Verification Service] stopped
12/13/05,00:41:03 : DeleteService() [Exchange Agent Setup Verification Service] removed
12/13/05,00:41:03 : ExchRemoveService() successfully finished
12/13/05,01:40:19 : ExchGeServerName() trying for Exchange 2000
12/13/05,01:40:19 : Exchange Server Name from Registry is[LIGHTNING]
12/13/05,01:40:19 : OpenSCManager() successful with all ACCESS
12/13/05,01:40:19 : OpenService() failed : errCode[1060] - errMsg[The specified service does not exist as an installed service. (0x424)]
12/13/05,01:40:19 : ERROR: ExchRemoveService() failed: return[4]
12/13/05,01:40:19 : ExchVerify() Calling ExchInstallService() to install the verification service
12/13/05,01:40:19 : servicePath = "C:\Program Files\CA\BrightStor ARCserve Backup Agent for Exchange\VService.exe"
12/13/05,01:40:19 : OpenSCManager() successful with all ACCESS
12/13/05,01:40:19 : CreateService() [Exchange Agent Setup Verification Service] successfully installed
12/13/05,01:40:19 : ExchInstallService() successfully finished
12/13/05,01:40:19 : OpenSCManager() successful with all ACCESS
12/13/05,01:40:19 : OpenService() [ExchVerification] successful with all ACCESS
12/13/05,01:40:19 : Service start pending
12/13/05,01:40:19 : RegisterServiceCtrlHandler() successful
12/13/05,01:40:19 : ERROR: EnablePrivilege() AdjustTokenPrivileges Failed-1300
12/13/05,01:40:19 : ERROR: ExchSetAllPrivilege() EnablePrivilege SE_CREATE_TOKEN_NAME Failed-183
12/13/05,01:40:19 : ExchSetAllPrivilege() return error code[0]
12/13/05,01:40:19 : Trying to start Service now ......
12/13/05,01:40:19 : CreateEvent() successful
12/13/05,01:40:19 : CreateNamedPipe() successful
12/13/05,01:40:22 : StartService() SUCCESSFUL
12/13/05,01:40:22 : ExchStartService() successfully finished
12/13/05,01:40:22 : ExchAuthenticate() Begin --------------------------
12/13/05,01:40:22 : ExchAuthenticate() called with NTServerName:[LIGHTNING] NTDomainName[river] adminMailbox:[Administrator] adminLoginName:[Administrator]
12/13/05,01:40:22 : Calling ExchCreateProfile() to create the Profile[EXCH_AUTHENTICATE_PROFILE35161134456022]
12/13/05,01:40:22 : The Windows NT system directory of the local server is:[C:\WINDOWS\system32]
12/13/05,01:40:22 : GetFileVersionInfoSize() for [C:\WINDOWS\system32\MAPI32.DLL] successful: return Size[2212]
12/13/05,01:40:22 : VerQueryValue() return version [6:5:7226:0]
12/13/05,01:40:22 : Doing LogonUser() and Impersonate
12/13/05,01:40:22 : LogonUser() successful to Domain[river] as userName[Administrator]
12/13/05,01:40:22 : ImpersonateLoggedOnUser() successful
12/13/05,01:40:22 : ERROR: OpenToken() OpenProcess Failed-5
12/13/05,01:40:22 : ExchSetAllPrivilege() return error code[0]
12/13/05,01:40:22 : MAPIInitialize() successful
12/13/05,01:40:22 : MAPIAdminProfiles() successful
12/13/05,01:40:22 : CreateProfile() profile[EXCH_AUTHENTICATE_PROFILE35161134456022] successful
12/13/05,01:40:22 : AdminServices() successful
12/13/05,01:40:22 : CreateMsgService() for msgService[MSEMS] successful
12/13/05,01:40:22 : GetMsgServiceTable() successful
12/13/05,01:40:22 : HrQueryAllRows() successful, with [1] rows returned
12/13/05,01:40:22 : ExchCheckPropertyTag() successful: Property Tag correctly match
12/13/05,01:40:22 : microsoft Exchange Service found in row [0]
12/13/05,01:40:23 : ConfigureMsgService() successful
12/13/05,01:40:23 : ExchCreateProfile() return [0]
12/13/05,01:40:23 : MAPI is initialized: cleanup resource
12/13/05,01:40:23 : Return back from impersonation
12/13/05,01:40:23 : RevertToSelf() successful
12/13/05,01:40:23 : CloseHandle() successful
12/13/05,01:40:23 : ExchCreateProfile() profile[EXCH_AUTHENTICATE_PROFILE35161134456022] successful
12/13/05,01:40:23 : The Windows NT system directory of the local server is:[C:\WINDOWS\system32]
12/13/05,01:40:23 : GetFileVersionInfoSize() for [C:\WINDOWS\system32\MAPI32.DLL] successful: return Size[2212]
12/13/05,01:40:23 : VerQueryValue() return version [6:5:7226:0]
12/13/05,01:40:23 : Doing LogonUser() and Impersonate
12/13/05,01:40:23 : LogonUser() successful to Domain[river] as userName[Administrator]
12/13/05,01:40:23 : ImpersonateLoggedOnUser() successful
12/13/05,01:40:23 : ERROR: OpenToken() OpenProcess Failed-5
12/13/05,01:40:23 : ExchSetAllPrivilege() return error code[0]
12/13/05,01:40:23 : MAPIInitialize() successful
12/13/05,01:40:23 : MAPILogonEX() successful
12/13/05,01:40:23 : MAPIAdminProfiles() successful
12/13/05,01:40:23 : Profile [EXCH_AUTHENTICATE_PROFILE35161134456022] is deleted
12/13/05,01:40:23 : ExchLogonEX() successful connectID[7937936]
12/13/05,01:40:23 : HrMAPIFindDefaultMsgStore() successful
12/13/05,01:40:23 : OpenMsgStore() successful
12/13/05,01:40:23 : HrGetServerDN() successful: servername [/o=isisdigital/ou=First Administrative Group/cn=Configuration/cn=Servers/cn=LIGHTNING]
12/13/05,01:40:23 : QueryInterface() successful
12/13/05,01:40:23 : ExchGetManageStoreInterface() successful
12/13/05,01:40:23 : GetMailboxTable() successful
12/13/05,01:40:23 : HrQueryAllRows() successful with [28] rows returned
12/13/05,01:40:23 : ExchEstimate: Process number required to handle the server is [1]
12/13/05,01:40:23 : StoreLogoff() successful
12/13/05,01:40:23 : Cleanup Handle for Exchange Server 4.0
12/13/05,01:40:23 : Return back from impersonation
12/13/05,01:40:23 : RevertToSelf() successful
12/13/05,01:40:23 : CloseHandle() successful
12/13/05,01:40:23 : ExchCheckAccess() successful
12/13/05,01:40:23 : ExchAuthenticate() successful
12/13/05,01:40:23 : CallNamedPipeA() successful
12/13/05,01:40:23 : ---> Authentication result: [Authentication successful: Setup Input is GOOD!!!]
12/13/05,01:40:23 : ExchPerformVerification() return[0]
12/13/05,01:40:23 : OpenSCManager() successful with all ACCESS
12/13/05,01:40:23 : OpenService() [ExchVerification] successful with all ACCESS
12/13/05,01:40:23 : ExchServiceControl() called with ControlCode[1]
12/13/05,01:40:23 : Service is started.
12/13/05,01:40:23 : ControlService() Stopping service [Exchange Agent Setup Verification Service]......
12/13/05,01:40:23 : StartServiceCtrlDispatcher() successful
12/13/05,01:40:24 : Service [Exchange Agent Setup Verification Service] stopped
12/13/05,01:40:24 : DeleteService() [Exchange Agent Setup Verification Service] removed
12/13/05,01:40:24 : ExchRemoveService() successfully finished
12/13/05,01:43:05 : ExchGeServerName() trying for Exchange 2000
12/13/05,01:43:05 : Exchange Server Name from Registry is[LIGHTNING]
12/13/05,01:43:05 : OpenSCManager() successful with all ACCESS
12/13/05,01:43:05 : OpenService() failed : errCode[1060] - errMsg[The specified service does not exist as an installed service. (0x424)]
12/13/05,01:43:05 : ERROR: ExchRemoveService() failed: return[4]
12/13/05,01:43:05 : ExchVerify() Calling ExchInstallService() to install the verification service
12/13/05,01:43:05 : servicePath = "C:\Program Files\CA\BrightStor ARCserve Backup Agent for Exchange\VService.exe"
12/13/05,01:43:05 : OpenSCManager() successful with all ACCESS
12/13/05,01:43:05 : CreateService() [Exchange Agent Setup Verification Service] successfully installed
12/13/05,01:43:05 : ExchInstallService() successfully finished
12/13/05,01:43:05 : OpenSCManager() successful with all ACCESS
12/13/05,01:43:05 : OpenService() [ExchVerification] successful with all ACCESS
12/13/05,01:43:05 : RegisterServiceCtrlHandler() successful
12/13/05,01:43:05 : Service start pending
12/13/05,01:43:05 : ERROR: EnablePrivilege() AdjustTokenPrivileges Failed-1300
12/13/05,01:43:05 : ERROR: ExchSetAllPrivilege() EnablePrivilege SE_CREATE_TOKEN_NAME Failed-183
12/13/05,01:43:05 : ExchSetAllPrivilege() return error code[0]
12/13/05,01:43:05 : Trying to start Service now ......
12/13/05,01:43:05 : CreateEvent() successful
12/13/05,01:43:05 : CreateNamedPipe() successful
12/13/05,01:43:08 : StartService() SUCCESSFUL
12/13/05,01:43:08 : ExchStartService() successfully finished
12/13/05,01:43:08 : ExchAuthenticate() Begin --------------------------
12/13/05,01:43:08 : ExchAuthenticate() called with NTServerName:[LIGHTNING] NTDomainName[river] adminMailbox:[administrator] adminLoginName:[administrator]
12/13/05,01:43:08 : Calling ExchCreateProfile() to create the Profile[EXCH_AUTHENTICATE_PROFILE50241134456188]
12/13/05,01:43:08 : The Windows NT system directory of the local server is:[C:\WINDOWS\system32]
12/13/05,01:43:08 : GetFileVersionInfoSize() for [C:\WINDOWS\system32\MAPI32.DLL] successful: return Size[2212]
12/13/05,01:43:08 : VerQueryValue() return version [6:5:7226:0]
12/13/05,01:43:08 : Doing LogonUser() and Impersonate
12/13/05,01:43:08 : LogonUser() successful to Domain[river] as userName[administrator]
12/13/05,01:43:08 : ImpersonateLoggedOnUser() successful
12/13/05,01:43:08 : ERROR: OpenToken() OpenProcess Failed-5
12/13/05,01:43:08 : ExchSetAllPrivilege() return error code[0]
12/13/05,01:43:08 : MAPIInitialize() successful
12/13/05,01:43:08 : MAPIAdminProfiles() successful
12/13/05,01:43:08 : CreateProfile() profile[EXCH_AUTHENTICATE_PROFILE50241134456188] successful
12/13/05,01:43:08 : AdminServices() successful
12/13/05,01:43:08 : CreateMsgService() for msgService[MSEMS] successful
12/13/05,01:43:08 : GetMsgServiceTable() successful
12/13/05,01:43:08 : HrQueryAllRows() successful, with [1] rows returned
12/13/05,01:43:08 : ExchCheckPropertyTag() successful: Property Tag correctly match
12/13/05,01:43:08 : microsoft Exchange Service found in row [0]
12/13/05,01:43:08 : ConfigureMsgService() successful
12/13/05,01:43:08 : ExchCreateProfile() return [0]
12/13/05,01:43:08 : MAPI is initialized: cleanup resource
12/13/05,01:43:08 : Return back from impersonation
12/13/05,01:43:08 : RevertToSelf() successful
12/13/05,01:43:08 : CloseHandle() successful
12/13/05,01:43:08 : ExchCreateProfile() profile[EXCH_AUTHENTICATE_PROFILE50241134456188] successful
12/13/05,01:43:08 : The Windows NT system directory of the local server is:[C:\WINDOWS\system32]
12/13/05,01:43:08 : GetFileVersionInfoSize() for [C:\WINDOWS\system32\MAPI32.DLL] successful: return Size[2212]
12/13/05,01:43:08 : VerQueryValue() return version [6:5:7226:0]
12/13/05,01:43:08 : Doing LogonUser() and Impersonate
12/13/05,01:43:08 : LogonUser() successful to Domain[river] as userName[administrator]
12/13/05,01:43:08 : ImpersonateLoggedOnUser() successful
12/13/05,01:43:08 : ERROR: OpenToken() OpenProcess Failed-5
12/13/05,01:43:08 : ExchSetAllPrivilege() return error code[0]
12/13/05,01:43:08 : MAPIInitialize() successful
12/13/05,01:43:08 : MAPILogonEX() successful
12/13/05,01:43:08 : MAPIAdminProfiles() successful
12/13/05,01:43:08 : Profile [EXCH_AUTHENTICATE_PROFILE50241134456188] is deleted
12/13/05,01:43:08 : ExchLogonEX() successful connectID[7937936]
12/13/05,01:43:08 : HrMAPIFindDefaultMsgStore() successful
12/13/05,01:43:08 : OpenMsgStore() successful
12/13/05,01:43:08 : HrGetServerDN() successful: servername [/o=isisdigital/ou=First Administrative Group/cn=Configuration/cn=Servers/cn=LIGHTNING]
12/13/05,01:43:08 : QueryInterface() successful
12/13/05,01:43:08 : ExchGetManageStoreInterface() successful
12/13/05,01:43:08 : GetMailboxTable() successful
12/13/05,01:43:08 : HrQueryAllRows() successful with [28] rows returned
12/13/05,01:43:08 : ExchEstimate: Process number required to handle the server is [1]
12/13/05,01:43:08 : StoreLogoff() successful
12/13/05,01:43:08 : Cleanup Handle for Exchange Server 4.0
12/13/05,01:43:08 : Return back from impersonation
12/13/05,01:43:08 : RevertToSelf() successful
12/13/05,01:43:08 : CloseHandle() successful
12/13/05,01:43:08 : ExchCheckAccess() successful
12/13/05,01:43:08 : ExchAuthenticate() successful
12/13/05,01:43:08 : CallNamedPipeA() successful
12/13/05,01:43:08 : ---> Authentication result: [Authentication successful: Setup Input is GOOD!!!]
12/13/05,01:43:08 : ExchPerformVerification() return[0]
12/13/05,01:43:08 : OpenSCManager() successful with all ACCESS
12/13/05,01:43:08 : OpenService() [ExchVerification] successful with all ACCESS
12/13/05,01:43:08 : ExchServiceControl() called with ControlCode[1]
12/13/05,01:43:08 : Service is started.
12/13/05,01:43:08 : ControlService() Stopping service [Exchange Agent Setup Verification Service]......
12/13/05,01:43:08 : StartServiceCtrlDispatcher() successful
12/13/05,01:43:09 : Service [Exchange Agent Setup Verification Service] stopped
12/13/05,01:43:09 : DeleteService() [Exchange Agent Setup Verification Service] removed
12/13/05,01:43:09 : ExchRemoveService() successfully finished
12/20/05,15:38:37 : ExchGeServerName() trying for Exchange 2000
12/20/05,15:38:37 : Exchange Server Name from Registry is[LIGHTNING]
12/20/05,15:38:37 : OpenSCManager() successful with all ACCESS
12/20/05,15:38:37 : OpenService() failed : errCode[1060] - errMsg[The specified service does not exist as an installed service. (0x424)]
12/20/05,15:38:37 : ERROR: ExchRemoveService() failed: return[4]
12/20/05,15:38:37 : ExchVerify() Calling ExchInstallService() to install the verification service
12/20/05,15:38:37 : servicePath = "C:\Program Files\CA\BrightStor ARCserve Backup Agent for Exchange\VService.exe"
12/20/05,15:38:37 : OpenSCManager() successful with all ACCESS
12/20/05,15:38:37 : CreateService() [Exchange Agent Setup Verification Service] successfully installed
12/20/05,15:38:37 : ExchInstallService() successfully finished
12/20/05,15:38:37 : OpenSCManager() successful with all ACCESS
12/20/05,15:38:37 : OpenService() [ExchVerification] successful with all ACCESS
12/20/05,15:38:37 : Service start pending
12/20/05,15:38:37 : RegisterServiceCtrlHandler() successful
12/20/05,15:38:37 : ERROR: EnablePrivilege() AdjustTokenPrivileges Failed-1300
12/20/05,15:38:37 : ERROR: ExchSetAllPrivilege() EnablePrivilege SE_CREATE_TOKEN_NAME Failed-183
12/20/05,15:38:37 : ExchSetAllPrivilege() return error code[0]
12/20/05,15:38:37 : Trying to start Service now ......
12/20/05,15:38:37 : CreateEvent() successful
12/20/05,15:38:37 : CreateNamedPipe() successful
12/20/05,15:38:39 : StartService() SUCCESSFUL
12/20/05,15:38:39 : ExchStartService() successfully finished
12/20/05,15:38:39 : ExchAuthenticate() Begin --------------------------
12/20/05,15:38:39 : ExchAuthenticate() called with NTServerName:[LIGHTNING] NTDomainName[river] adminMailbox:[administrator] adminLoginName:[administrator]
12/20/05,15:38:39 : Calling ExchCreateProfile() to create the Profile[EXCH_AUTHENTICATE_PROFILE47401135111119]
12/20/05,15:38:39 : The Windows NT system directory of the local server is:[C:\WINDOWS\system32]
12/20/05,15:38:39 : GetFileVersionInfoSize() for [C:\WINDOWS\system32\MAPI32.DLL] successful: return Size[2212]
12/20/05,15:38:39 : VerQueryValue() return version [6:5:7226:0]
12/20/05,15:38:39 : Doing LogonUser() and Impersonate
12/20/05,15:38:39 : LogonUser() successful to Domain[river] as userName[administrator]
12/20/05,15:38:39 : ImpersonateLoggedOnUser() successful
12/20/05,15:38:39 : ERROR: OpenToken() OpenProcess Failed-5
12/20/05,15:38:39 : ExchSetAllPrivilege() return error code[0]
12/20/05,15:38:39 : MAPIInitialize() successful
12/20/05,15:38:39 : MAPIAdminProfiles() successful
12/20/05,15:38:39 : CreateProfile() profile[EXCH_AUTHENTICATE_PROFILE47401135111119] successful
12/20/05,15:38:39 : AdminServices() successful
12/20/05,15:38:39 : CreateMsgService() for msgService[MSEMS] successful
12/20/05,15:38:39 : GetMsgServiceTable() successful
12/20/05,15:38:39 : HrQueryAllRows() successful, with [1] rows returned
12/20/05,15:38:39 : ExchCheckPropertyTag() successful: Property Tag correctly match
12/20/05,15:38:39 : microsoft Exchange Service found in row [0]
12/20/05,15:38:39 : ConfigureMsgService() successful
12/20/05,15:38:39 : ExchCreateProfile() return [0]
12/20/05,15:38:39 : MAPI is initialized: cleanup resource
12/20/05,15:38:39 : Return back from impersonation
12/20/05,15:38:40 : RevertToSelf() successful
12/20/05,15:38:40 : CloseHandle() successful
12/20/05,15:38:40 : ExchCreateProfile() profile[EXCH_AUTHENTICATE_PROFILE47401135111119] successful
12/20/05,15:38:40 : The Windows NT system directory of the local server is:[C:\WINDOWS\system32]
12/20/05,15:38:40 : GetFileVersionInfoSize() for [C:\WINDOWS\system32\MAPI32.DLL] successful: return Size[2212]
12/20/05,15:38:40 : VerQueryValue() return version [6:5:7226:0]
12/20/05,15:38:40 : Doing LogonUser() and Impersonate
12/20/05,15:38:40 : LogonUser() successful to Domain[river] as userName[administrator]
12/20/05,15:38:40 : ImpersonateLoggedOnUser() successful
12/20/05,15:38:40 : ERROR: OpenToken() OpenProcess Failed-5
12/20/05,15:38:40 : ExchSetAllPrivilege() return error code[0]
12/20/05,15:38:40 : MAPIInitialize() successful
12/20/05,15:38:40 : MAPILogonEX() successful
12/20/05,15:38:40 : MAPIAdminProfiles() successful
12/20/05,15:38:40 : Profile [EXCH_AUTHENTICATE_PROFILE47401135111119] is deleted
12/20/05,15:38:40 : ExchLogonEX() successful connectID[7937936]
12/20/05,15:38:40 : HrMAPIFindDefaultMsgStore() successful
12/20/05,15:38:40 : OpenMsgStore() successful
12/20/05,15:38:40 : HrGetServerDN() successful: servername [/o=isisdigital/ou=First Administrative Group/cn=Configuration/cn=Servers/cn=LIGHTNING]
12/20/05,15:38:40 : QueryInterface() successful
12/20/05,15:38:40 : ExchGetManageStoreInterface() successful
12/20/05,15:38:40 : GetMailboxTable() successful
12/20/05,15:38:40 : HrQueryAllRows() successful with [29] rows returned
12/20/05,15:38:40 : ExchEstimate: Process number required to handle the server is [1]
12/20/05,15:38:40 : StoreLogoff() successful
12/20/05,15:38:40 : Cleanup Handle for Exchange Server 4.0
12/20/05,15:38:40 : Return back from impersonation
12/20/05,15:38:40 : RevertToSelf() successful
12/20/05,15:38:40 : CloseHandle() successful
12/20/05,15:38:40 : ExchCheckAccess() successful
12/20/05,15:38:40 : ExchAuthenticate() successful
12/20/05,15:38:40 : CallNamedPipeA() successful
12/20/05,15:38:40 : ---> Authentication result: [Authentication successful: Setup Input is GOOD!!!]
12/20/05,15:38:40 : ExchPerformVerification() return[0]
12/20/05,15:38:40 : OpenSCManager() successful with all ACCESS
12/20/05,15:38:40 : OpenService() [ExchVerification] successful with all ACCESS
12/20/05,15:38:40 : ExchServiceControl() called with ControlCode[1]
12/20/05,15:38:40 : Service is started.
12/20/05,15:38:40 : ControlService() Stopping service [Exchange Agent Setup Verification Service]......
12/20/05,15:38:40 : StartServiceCtrlDispatcher() successful
12/20/05,15:38:41 : Service [Exchange Agent Setup Verification Service] stopped
12/20/05,15:38:41 : DeleteService() [Exchange Agent Setup Verification Service] removed
12/20/05,15:38:41 : ExchRemoveService() successfully finished
01/09/06,10:22:28 : ExchGeServerName() trying for Exchange 2000
01/09/06,10:22:28 : Exchange Server Name from Registry is[LIGHTNING]
01/09/06,10:22:28 : OpenSCManager() successful with all ACCESS
01/09/06,10:22:28 : OpenService() failed : errCode[1060] - errMsg[The specified service does not exist as an installed service. (0x424)]
01/09/06,10:22:28 : ERROR: ExchRemoveService() failed: return[4]
01/09/06,10:22:28 : ExchVerify() Calling ExchInstallService() to install the verification service
01/09/06,10:22:28 : servicePath = "C:\Program Files\CA\BrightStor ARCserve Backup Agent for Exchange\VService.exe"
01/09/06,10:22:28 : OpenSCManager() successful with all ACCESS
01/09/06,10:22:28 : CreateService() [Exchange Agent Setup Verification Service] successfully installed
01/09/06,10:22:28 : ExchInstallService() successfully finished
01/09/06,10:22:28 : OpenSCManager() successful with all ACCESS
01/09/06,10:22:28 : OpenService() [ExchVerification] successful with all ACCESS
01/09/06,10:22:28 : Service start pending
01/09/06,10:22:28 : RegisterServiceCtrlHandler() successful
01/09/06,10:22:28 : ERROR: EnablePrivilege() AdjustTokenPrivileges Failed-1300
01/09/06,10:22:28 : ERROR: ExchSetAllPrivilege() EnablePrivilege SE_CREATE_TOKEN_NAME Failed-183
01/09/06,10:22:28 : ExchSetAllPrivilege() return error code[0]
01/09/06,10:22:28 : Trying to start Service now ......
01/09/06,10:22:28 : CreateEvent() successful
01/09/06,10:22:28 : CreateNamedPipe() successful
01/09/06,10:22:31 : StartService() SUCCESSFUL
01/09/06,10:22:31 : ExchStartService() successfully finished
01/09/06,10:22:31 : ExchAuthenticate() Begin --------------------------
01/09/06,10:22:31 : ExchAuthenticate() called with NTServerName:[LIGHTNING] NTDomainName[river.isisdigital.com] adminMailbox:[administrator] adminLoginName:[administrator]
01/09/06,10:22:31 : Calling ExchCreateProfile() to create the Profile[EXCH_AUTHENTICATE_PROFILE73521136820151]
01/09/06,10:22:31 : The Windows NT system directory of the local server is:[C:\WINDOWS\system32]
01/09/06,10:22:31 : GetFileVersionInfoSize() for [C:\WINDOWS\system32\MAPI32.DLL] successful: return Size[2212]
01/09/06,10:22:31 : VerQueryValue() return version [6:5:7226:0]
01/09/06,10:22:31 : Doing LogonUser() and Impersonate
01/09/06,10:22:31 : LogonUser() successful to Domain[river.isisdigital.com] as userName[administrator]
01/09/06,10:22:31 : ImpersonateLoggedOnUser() successful
01/09/06,10:22:31 : ERROR: OpenToken() OpenProcess Failed-5
01/09/06,10:22:31 : ExchSetAllPrivilege() return error code[0]
01/09/06,10:22:31 : MAPIInitialize() successful
01/09/06,10:22:31 : MAPIAdminProfiles() successful
01/09/06,10:22:31 : CreateProfile() profile[EXCH_AUTHENTICATE_PROFILE73521136820151] successful
01/09/06,10:22:31 : AdminServices() successful
01/09/06,10:22:32 : CreateMsgService() for msgService[MSEMS] successful
01/09/06,10:22:32 : GetMsgServiceTable() successful
01/09/06,10:22:32 : HrQueryAllRows() successful, with [1] rows returned
01/09/06,10:22:32 : ExchCheckPropertyTag() successful: Property Tag correctly match
01/09/06,10:22:32 : microsoft Exchange Service found in row [0]
01/09/06,10:22:32 : ConfigureMsgService() successful
01/09/06,10:22:32 : ExchCreateProfile() return [0]
01/09/06,10:22:32 : MAPI is initialized: cleanup resource
01/09/06,10:22:32 : Return back from impersonation
01/09/06,10:22:32 : RevertToSelf() successful
01/09/06,10:22:32 : CloseHandle() successful
01/09/06,10:22:32 : ExchCreateProfile() profile[EXCH_AUTHENTICATE_PROFILE73521136820151] successful
01/09/06,10:22:32 : The Windows NT system directory of the local server is:[C:\WINDOWS\system32]
01/09/06,10:22:32 : GetFileVersionInfoSize() for [C:\WINDOWS\system32\MAPI32.DLL] successful: return Size[2212]
01/09/06,10:22:32 : VerQueryValue() return version [6:5:7226:0]
01/09/06,10:22:32 : Doing LogonUser() and Impersonate
01/09/06,10:22:32 : LogonUser() successful to Domain[river.isisdigital.com] as userName[administrator]
01/09/06,10:22:32 : ImpersonateLoggedOnUser() successful
01/09/06,10:22:32 : ERROR: OpenToken() OpenProcess Failed-5
01/09/06,10:22:32 : ExchSetAllPrivilege() return error code[0]
01/09/06,10:22:32 : MAPIInitialize() successful
01/09/06,10:22:32 : MAPILogonEX() successful
01/09/06,10:22:32 : MAPIAdminProfiles() successful
01/09/06,10:22:32 : Profile [EXCH_AUTHENTICATE_PROFILE73521136820151] is deleted
01/09/06,10:22:32 : ExchLogonEX() successful connectID[7937936]
01/09/06,10:22:32 : HrMAPIFindDefaultMsgStore() successful
01/09/06,10:22:32 : OpenMsgStore() successful
01/09/06,10:22:32 : HrGetServerDN() successful: servername [/o=isisdigital/ou=First Administrative Group/cn=Configuration/cn=Servers/cn=LIGHTNING]
01/09/06,10:22:32 : QueryInterface() successful
01/09/06,10:22:32 : ExchGetManageStoreInterface() successful
01/09/06,10:22:32 : GetMailboxTable() successful
01/09/06,10:22:32 : HrQueryAllRows() successful with [30] rows returned
01/09/06,10:22:32 : ExchEstimate: Process number required to handle the server is [1]
01/09/06,10:22:32 : StoreLogoff() successful
01/09/06,10:22:32 : Cleanup Handle for Exchange Server 4.0
01/09/06,10:22:32 : Return back from impersonation
01/09/06,10:22:32 : RevertToSelf() successful
01/09/06,10:22:32 : CloseHandle() successful
01/09/06,10:22:32 : ExchCheckAccess() successful
01/09/06,10:22:32 : ExchAuthenticate() successful
01/09/06,10:22:32 : CallNamedPipeA() successful
01/09/06,10:22:32 : ---> Authentication result: [Authentication successful: Setup Input is GOOD!!!]
01/09/06,10:22:32 : ExchPerformVerification() return[0]
01/09/06,10:22:32 : OpenSCManager() successful with all ACCESS
01/09/06,10:22:32 : OpenService() [ExchVerification] successful with all ACCESS
01/09/06,10:22:32 : ExchServiceControl() called with ControlCode[1]
01/09/06,10:22:32 : ControlService() Stopping service [Exchange Agent Setup Verification Service]......
01/09/06,10:22:32 : Service is started.
01/09/06,10:22:32 : StartServiceCtrlDispatcher() successful
01/09/06,10:22:33 : Service [Exchange Agent Setup Verification Service] stopped
01/09/06,10:22:33 : DeleteService() [Exchange Agent Setup Verification Service] removed
01/09/06,10:22:33 : ExchRemoveService() successfully finished
01/09/06,11:27:35 : ExchGeServerName() trying for Exchange 2000
01/09/06,11:27:35 : Exchange Server Name from Registry is[LIGHTNING]
01/09/06,11:27:35 : OpenSCManager() successful with all ACCESS
01/09/06,11:27:35 : OpenService() failed : errCode[1060] - errMsg[The specified service does not exist as an installed service. (0x424)]
01/09/06,11:27:35 : ERROR: ExchRemoveService() failed: return[4]
01/09/06,11:27:35 : ExchVerify() Calling ExchInstallService() to install the verification service
01/09/06,11:27:35 : servicePath = "C:\Program Files\CA\BrightStor ARCserve Backup Agent for Exchange\VService.exe"
01/09/06,11:27:35 : OpenSCManager() successful with all ACCESS
01/09/06,11:27:35 : CreateService() [Exchange Agent Setup Verification Service] successfully installed
01/09/06,11:27:35 : ExchInstallService() successfully finished
01/09/06,11:27:35 : OpenSCManager() successful with all ACCESS
01/09/06,11:27:35 : OpenService() [ExchVerification] successful with all ACCESS
01/09/06,11:27:35 : RegisterServiceCtrlHandler() successful
01/09/06,11:27:35 : Service start pending
01/09/06,11:27:35 : ERROR: EnablePrivilege() AdjustTokenPrivileges Failed-1300
01/09/06,11:27:35 : ERROR: ExchSetAllPrivilege() EnablePrivilege SE_CREATE_TOKEN_NAME Failed-183
01/09/06,11:27:35 : ExchSetAllPrivilege() return error code[0]
01/09/06,11:27:35 : Trying to start Service now ......
01/09/06,11:27:35 : CreateEvent() successful
01/09/06,11:27:35 : CreateNamedPipe() successful
01/09/06,11:27:38 : StartService() SUCCESSFUL
01/09/06,11:27:38 : ExchStartService() successfully finished
01/09/06,11:27:38 : ExchAuthenticate() Begin --------------------------
01/09/06,11:27:38 : ExchAuthenticate() called with NTServerName:[LIGHTNING] NTDomainName[river.isisdigital.com] adminMailbox:[administrator] adminLoginName:[administrator]
01/09/06,11:27:38 : Calling ExchCreateProfile() to create the Profile[EXCH_AUTHENTICATE_PROFILE79121136824058]
01/09/06,11:27:38 : The Windows NT system directory of the local server is:[C:\WINDOWS\system32]
01/09/06,11:27:38 : GetFileVersionInfoSize() for [C:\WINDOWS\system32\MAPI32.DLL] successful: return Size[2212]
01/09/06,11:27:38 : VerQueryValue() return version [6:5:7226:0]
01/09/06,11:27:38 : Doing LogonUser() and Impersonate
01/09/06,11:27:38 : LogonUser() successful to Domain[river.isisdigital.com] as userName[administrator]
01/09/06,11:27:38 : ImpersonateLoggedOnUser() successful
01/09/06,11:27:38 : ERROR: OpenToken() OpenProcess Failed-5
01/09/06,11:27:38 : ExchSetAllPrivilege() return error code[0]
01/09/06,11:27:38 : MAPIInitialize() successful
01/09/06,11:27:38 : MAPIAdminProfiles() successful
01/09/06,11:27:38 : CreateProfile() profile[EXCH_AUTHENTICATE_PROFILE79121136824058] successful
01/09/06,11:27:38 : AdminServices() successful
01/09/06,11:27:38 : CreateMsgService() for msgService[MSEMS] successful
01/09/06,11:27:38 : GetMsgServiceTable() successful
01/09/06,11:27:38 : HrQueryAllRows() successful, with [1] rows returned
01/09/06,11:27:38 : ExchCheckPropertyTag() successful: Property Tag correctly match
01/09/06,11:27:38 : microsoft Exchange Service found in row [0]
01/09/06,11:27:38 : ConfigureMsgService() successful
01/09/06,11:27:38 : ExchCreateProfile() return [0]
01/09/06,11:27:38 : MAPI is initialized: cleanup resource
01/09/06,11:27:38 : Return back from impersonation
01/09/06,11:27:38 : RevertToSelf() successful
01/09/06,11:27:38 : CloseHandle() successful
01/09/06,11:27:38 : ExchCreateProfile() profile[EXCH_AUTHENTICATE_PROFILE79121136824058] successful
01/09/06,11:27:38 : The Windows NT system directory of the local server is:[C:\WINDOWS\system32]
01/09/06,11:27:38 : GetFileVersionInfoSize() for [C:\WINDOWS\system32\MAPI32.DLL] successful: return Size[2212]
01/09/06,11:27:38 : VerQueryValue() return version [6:5:7226:0]
01/09/06,11:27:38 : Doing LogonUser() and Impersonate
01/09/06,11:27:38 : LogonUser() successful to Domain[river.isisdigital.com] as userName[administrator]
01/09/06,11:27:38 : ImpersonateLoggedOnUser() successful
01/09/06,11:27:38 : ERROR: OpenToken() OpenProcess Failed-5
01/09/06,11:27:38 : ExchSetAllPrivilege() return error code[0]
01/09/06,11:27:38 : MAPIInitialize() successful
01/09/06,11:27:38 : MAPILogonEX() successful
01/09/06,11:27:38 : MAPIAdminProfiles() successful
01/09/06,11:27:38 : Profile [EXCH_AUTHENTICATE_PROFILE79121136824058] is deleted
01/09/06,11:27:38 : ExchLogonEX() successful connectID[7937936]
01/09/06,11:27:38 : HrMAPIFindDefaultMsgStore() successful
01/09/06,11:27:38 : OpenMsgStore() successful
01/09/06,11:27:38 : HrGetServerDN() successful: servername [/o=isisdigital/ou=First Administrative Group/cn=Configuration/cn=Servers/cn=LIGHTNING]
01/09/06,11:27:38 : QueryInterface() successful
01/09/06,11:27:38 : ExchGetManageStoreInterface() successful
01/09/06,11:27:38 : GetMailboxTable() successful
01/09/06,11:27:38 : HrQueryAllRows() successful with [30] rows returned
01/09/06,11:27:38 : ExchEstimate: Process number required to handle the server is [1]
01/09/06,11:27:38 : StoreLogoff() successful
01/09/06,11:27:38 : Cleanup Handle for Exchange Server 4.0
01/09/06,11:27:38 : Return back from impersonation
01/09/06,11:27:38 : RevertToSelf() successful
01/09/06,11:27:38 : CloseHandle() successful
01/09/06,11:27:38 : ExchCheckAccess() successful
01/09/06,11:27:38 : ExchAuthenticate() successful
01/09/06,11:27:38 : CallNamedPipeA() successful
01/09/06,11:27:38 : ---> Authentication result: [Authentication successful: Setup Input is GOOD!!!]
01/09/06,11:27:38 : ExchPerformVerification() return[0]
01/09/06,11:27:38 : OpenSCManager() successful with all ACCESS
01/09/06,11:27:38 : OpenService() [ExchVerification] successful with all ACCESS
01/09/06,11:27:38 : ExchServiceControl() called with ControlCode[1]
01/09/06,11:27:38 : Service is started.
01/09/06,11:27:38 : ControlService() Stopping service [Exchange Agent Setup Verification Service]......
01/09/06,11:27:38 : StartServiceCtrlDispatcher() successful
01/09/06,11:27:39 : Service [Exchange Agent Setup Verification Service] stopped
01/09/06,11:27:39 : DeleteService() [Exchange Agent Setup Verification Service] removed
01/09/06,11:27:39 : ExchRemoveService() successfully finished
01/09/06,13:16:17 : ExchGeServerName() trying for Exchange 2000
01/09/06,13:16:17 : Exchange Server Name from Registry is[LIGHTNING]
01/09/06,13:16:17 : OpenSCManager() successful with all ACCESS
01/09/06,13:16:17 : OpenService() failed : errCode[1060] - errMsg[The specified service does not exist as an installed service. (0x424)]
01/09/06,13:16:17 : ERROR: ExchRemoveService() failed: return[4]
01/09/06,13:16:17 : ExchVerify() Calling ExchInstallService() to install the verification service
01/09/06,13:16:17 : servicePath = "C:\Program Files\CA\BrightStor ARCserve Backup Agent for Exchange\VService.exe"
01/09/06,13:16:17 : OpenSCManager() successful with all ACCESS
01/09/06,13:16:17 : CreateService() [Exchange Agent Setup Verification Service] successfully installed
01/09/06,13:16:17 : ExchInstallService() successfully finished
01/09/06,13:16:17 : OpenSCManager() successful with all ACCESS
01/09/06,13:16:18 : OpenService() [ExchVerification] successful with all ACCESS
01/09/06,13:16:18 : RegisterServiceCtrlHandler() successful
01/09/06,13:16:18 : Service start pending
01/09/06,13:16:18 : ERROR: EnablePrivilege() AdjustTokenPrivileges Failed-1300
01/09/06,13:16:18 : ERROR: ExchSetAllPrivilege() EnablePrivilege SE_CREATE_TOKEN_NAME Failed-183
01/09/06,13:16:18 : ExchSetAllPrivilege() return error code[0]
01/09/06,13:16:18 : Trying to start Service now ......
01/09/06,13:16:18 : CreateEvent() successful
01/09/06,13:16:18 : CreateNamedPipe() successful
01/09/06,13:16:21 : StartService() SUCCESSFUL
01/09/06,13:16:21 : ExchStartService() successfully finished
01/09/06,13:16:21 : ExchAuthenticate() Begin --------------------------
01/09/06,13:16:21 : ExchAuthenticate() called with NTServerName:[LIGHTNING] NTDomainName[river] adminMailbox:[administrator] adminLoginName:[administrator]
01/09/06,13:16:21 : Calling ExchCreateProfile() to create the Profile[EXCH_AUTHENTICATE_PROFILE62521136830581]
01/09/06,13:16:21 : The Windows NT system directory of the local server is:[C:\WINDOWS\system32]
01/09/06,13:16:21 : GetFileVersionInfoSize() for [C:\WINDOWS\system32\MAPI32.DLL] successful: return Size[2212]
01/09/06,13:16:21 : VerQueryValue() return version [6:5:7226:0]
01/09/06,13:16:21 : Doing LogonUser() and Impersonate
01/09/06,13:16:21 : LogonUser() successful to Domain[river] as userName[administrator]
01/09/06,13:16:21 : ImpersonateLoggedOnUser() successful
01/09/06,13:16:21 : ERROR: OpenToken() OpenProcess Failed-5
01/09/06,13:16:21 : ExchSetAllPrivilege() return error code[0]
01/09/06,13:16:21 : MAPIInitialize() successful
01/09/06,13:16:21 : MAPIAdminProfiles() successful
01/09/06,13:16:21 : CreateProfile() profile[EXCH_AUTHENTICATE_PROFILE62521136830581] successful
01/09/06,13:16:21 : AdminServices() successful
01/09/06,13:16:21 : CreateMsgService() for msgService[MSEMS] successful
01/09/06,13:16:21 : GetMsgServiceTable() successful
01/09/06,13:16:21 : HrQueryAllRows() successful, with [1] rows returned
01/09/06,13:16:21 : ExchCheckPropertyTag() successful: Property Tag correctly match
01/09/06,13:16:21 : microsoft Exchange Service found in row [0]
01/09/06,13:16:21 : ConfigureMsgService() successful
01/09/06,13:16:21 : ExchCreateProfile() return [0]
01/09/06,13:16:21 : MAPI is initialized: cleanup resource
01/09/06,13:16:21 : Return back from impersonation
01/09/06,13:16:21 : RevertToSelf() successful
01/09/06,13:16:21 : CloseHandle() successful
01/09/06,13:16:21 : ExchCreateProfile() profile[EXCH_AUTHENTICATE_PROFILE62521136830581] successful
01/09/06,13:16:21 : The Windows NT system directory of the local server is:[C:\WINDOWS\system32]
01/09/06,13:16:21 : GetFileVersionInfoSize() for [C:\WINDOWS\system32\MAPI32.DLL] successful: return Size[2212]
01/09/06,13:16:21 : VerQueryValue() return version [6:5:7226:0]
01/09/06,13:16:21 : Doing LogonUser() and Impersonate
01/09/06,13:16:21 : LogonUser() successful to Domain[river] as userName[administrator]
01/09/06,13:16:21 : ImpersonateLoggedOnUser() successful
01/09/06,13:16:21 : ERROR: OpenToken() OpenProcess Failed-5
01/09/06,13:16:21 : ExchSetAllPrivilege() return error code[0]
01/09/06,13:16:21 : MAPIInitialize() successful
01/09/06,13:16:21 : MAPILogonEX() successful
01/09/06,13:16:21 : MAPIAdminProfiles() successful
01/09/06,13:16:21 : Profile [EXCH_AUTHENTICATE_PROFILE62521136830581] is deleted
01/09/06,13:16:21 : ExchLogonEX() successful connectID[7937936]
01/09/06,13:16:21 : HrMAPIFindDefaultMsgStore() successful
01/09/06,13:16:21 : OpenMsgStore() successful
01/09/06,13:16:21 : HrGetServerDN() successful: servername [/o=isisdigital/ou=First Administrative Group/cn=Configuration/cn=Servers/cn=LIGHTNING]
01/09/06,13:16:21 : QueryInterface() successful
01/09/06,13:16:21 : ExchGetManageStoreInterface() successful
01/09/06,13:16:21 : GetMailboxTable() successful
01/09/06,13:16:21 : HrQueryAllRows() successful with [30] rows returned
01/09/06,13:16:21 : ExchEstimate: Process number required to handle the server is [1]
01/09/06,13:16:21 : StoreLogoff() successful
01/09/06,13:16:21 : Cleanup Handle for Exchange Server 4.0
01/09/06,13:16:21 : Return back from impersonation
01/09/06,13:16:21 : RevertToSelf() successful
01/09/06,13:16:21 : CloseHandle() successful
01/09/06,13:16:21 : ExchCheckAccess() successful
01/09/06,13:16:21 : ExchAuthenticate() successful
01/09/06,13:16:21 : CallNamedPipeA() successful
01/09/06,13:16:21 : ---> Authentication result: [Authentication successful: Setup Input is GOOD!!!]
01/09/06,13:16:21 : ExchPerformVerification() return[0]
01/09/06,13:16:21 : OpenSCManager() successful with all ACCESS
01/09/06,13:16:21 : OpenService() [ExchVerification] successful with all ACCESS
01/09/06,13:16:21 : ExchServiceControl() called with ControlCode[1]
01/09/06,13:16:21 : Service is started.
01/09/06,13:16:21 : ControlService() Stopping service [Exchange Agent Setup Verification Service]......
01/09/06,13:16:21 : StartServiceCtrlDispatcher() successful
01/09/06,13:16:22 : Service [Exchange Agent Setup Verification Service] stopped
01/09/06,13:16:22 : DeleteService() [Exchange Agent Setup Verification Service] removed
01/09/06,13:16:22 : ExchRemoveService() successfully finished
01/09/06,13:35:30 : ExchGeServerName() trying for Exchange 2000
01/09/06,13:35:30 : Exchange Server Name from Registry is[LIGHTNING]
01/09/06,13:35:30 : OpenSCManager() successful with all ACCESS
01/09/06,13:35:30 : OpenService() failed : errCode[1060] - errMsg[The specified service does not exist as an installed service. (0x424)]
01/09/06,13:35:30 : ERROR: ExchRemoveService() failed: return[4]
01/09/06,13:35:30 : ExchVerify() Calling ExchInstallService() to install the verification service
01/09/06,13:35:30 : servicePath = "C:\Program Files\CA\BrightStor ARCserve Backup Agent for Exchange\VService.exe"
01/09/06,13:35:30 : OpenSCManager() successful with all ACCESS
01/09/06,13:35:30 : CreateService() [Exchange Agent Setup Verification Service] successfully installed
01/09/06,13:35:30 : ExchInstallService() successfully finished
01/09/06,13:35:30 : OpenSCManager() successful with all ACCESS
01/09/06,13:35:30 : OpenService() [ExchVerification] successful with all ACCESS
01/09/06,13:35:30 : RegisterServiceCtrlHandler() successful
01/09/06,13:35:30 : Service start pending
01/09/06,13:35:30 : ERROR: EnablePrivilege() AdjustTokenPrivileges Failed-1300
01/09/06,13:35:30 : ERROR: ExchSetAllPrivilege() EnablePrivilege SE_CREATE_TOKEN_NAME Failed-183
01/09/06,13:35:30 : ExchSetAllPrivilege() return error code[0]
01/09/06,13:35:30 : Trying to start Service now ......
01/09/06,13:35:30 : CreateEvent() successful
01/09/06,13:35:30 : CreateNamedPipe() successful
01/09/06,13:35:33 : StartService() SUCCESSFUL
01/09/06,13:35:33 : ExchStartService() successfully finished
01/09/06,13:35:33 : ExchAuthenticate() Begin --------------------------
01/09/06,13:35:33 : ExchAuthenticate() called with NTServerName:[LIGHTNING] NTDomainName[river.isisdigital.com] adminMailbox:[administrator] adminLoginName:[administrator]
01/09/06,13:35:33 : Calling ExchCreateProfile() to create the Profile[EXCH_AUTHENTICATE_PROFILE66521136831733]
01/09/06,13:35:33 : The Windows NT system directory of the local server is:[C:\WINDOWS\system32]
01/09/06,13:35:33 : GetFileVersionInfoSize() for [C:\WINDOWS\system32\MAPI32.DLL] successful: return Size[2212]
01/09/06,13:35:33 : VerQueryValue() return version [6:5:7226:0]
01/09/06,13:35:33 : Doing LogonUser() and Impersonate
01/09/06,13:35:33 : LogonUser() successful to Domain[river.isisdigital.com] as userName[administrator]
01/09/06,13:35:33 : ImpersonateLoggedOnUser() successful
01/09/06,13:35:33 : ERROR: OpenToken() OpenProcess Failed-5
01/09/06,13:35:33 : ExchSetAllPrivilege() return error code[0]
01/09/06,13:35:33 : MAPIInitialize() successful
01/09/06,13:35:33 : MAPIAdminProfiles() successful
01/09/06,13:35:33 : CreateProfile() profile[EXCH_AUTHENTICATE_PROFILE66521136831733] successful
01/09/06,13:35:33 : AdminServices() successful
01/09/06,13:35:33 : CreateMsgService() for msgService[MSEMS] successful
01/09/06,13:35:33 : GetMsgServiceTable() successful
01/09/06,13:35:33 : HrQueryAllRows() successful, with [1] rows returned
01/09/06,13:35:33 : ExchCheckPropertyTag() successful: Property Tag correctly match
01/09/06,13:35:33 : microsoft Exchange Service found in row [0]
01/09/06,13:35:33 : ConfigureMsgService() successful
01/09/06,13:35:33 : ExchCreateProfile() return [0]
01/09/06,13:35:33 : MAPI is initialized: cleanup resource
01/09/06,13:35:33 : Return back from impersonation
01/09/06,13:35:33 : RevertToSelf() successful
01/09/06,13:35:33 : CloseHandle() successful
01/09/06,13:35:33 : ExchCreateProfile() profile[EXCH_AUTHENTICATE_PROFILE66521136831733] successful
01/09/06,13:35:33 : The Windows NT system directory of the local server is:[C:\WINDOWS\system32]
01/09/06,13:35:33 : GetFileVersionInfoSize() for [C:\WINDOWS\system32\MAPI32.DLL] successful: return Size[2212]
01/09/06,13:35:33 : VerQueryValue() return version [6:5:7226:0]
01/09/06,13:35:33 : Doing LogonUser() and Impersonate
01/09/06,13:35:34 : LogonUser() successful to Domain[river.isisdigital.com] as userName[administrator]
01/09/06,13:35:34 : ImpersonateLoggedOnUser() successful
01/09/06,13:35:34 : ERROR: OpenToken() OpenProcess Failed-5
01/09/06,13:35:34 : ExchSetAllPrivilege() return error code[0]
01/09/06,13:35:34 : MAPIInitialize() successful
01/09/06,13:35:34 : MAPILogonEX() successful
01/09/06,13:35:34 : MAPIAdminProfiles() successful
01/09/06,13:35:34 : Profile [EXCH_AUTHENTICATE_PROFILE66521136831733] is deleted
01/09/06,13:35:34 : ExchLogonEX() successful connectID[7937936]
01/09/06,13:35:34 : HrMAPIFindDefaultMsgStore() successful
01/09/06,13:35:34 : OpenMsgStore() successful
01/09/06,13:35:34 : HrGetServerDN() successful: servername [/o=isisdigital/ou=First Administrative Group/cn=Configuration/cn=Servers/cn=LIGHTNING]
01/09/06,13:35:34 : QueryInterface() successful
01/09/06,13:35:34 : ExchGetManageStoreInterface() successful
01/09/06,13:35:34 : GetMailboxTable() successful
01/09/06,13:35:34 : HrQueryAllRows() successful with [30] rows returned
01/09/06,13:35:34 : ExchEstimate: Process number required to handle the server is [1]
01/09/06,13:35:34 : StoreLogoff() successful
01/09/06,13:35:34 : Cleanup Handle for Exchange Server 4.0
01/09/06,13:35:34 : Return back from impersonation
01/09/06,13:35:34 : RevertToSelf() successful
01/09/06,13:35:34 : CloseHandle() successful
01/09/06,13:35:34 : ExchCheckAccess() successful
01/09/06,13:35:34 : ExchAuthenticate() successful
01/09/06,13:35:34 : CallNamedPipeA() successful
01/09/06,13:35:34 : ---> Authentication result: [Authentication successful: Setup Input is GOOD!!!]
01/09/06,13:35:34 : ExchPerformVerification() return[0]
01/09/06,13:35:34 : OpenSCManager() successful with all ACCESS
01/09/06,13:35:34 : OpenService() [ExchVerification] successful with all ACCESS
01/09/06,13:35:34 : ExchServiceControl() called with ControlCode[1]
01/09/06,13:35:34 : Service is started.
01/09/06,13:35:34 : ControlService() Stopping service [Exchange Agent Setup Verification Service]......
01/09/06,13:35:34 : StartServiceCtrlDispatcher() successful
01/09/06,13:35:35 : Service [Exchange Agent Setup Verification Service] stopped
01/09/06,13:35:35 : DeleteService() [Exchange Agent Setup Verification Service] removed
01/09/06,13:35:35 : ExchRemoveService() successfully finished
01/09/06,14:31:09 : ExchGeServerName() trying for Exchange 2000
01/09/06,14:31:09 : Exchange Server Name from Registry is[LIGHTNING]
01/09/06,14:31:09 : OpenSCManager() successful with all ACCESS
01/09/06,14:31:09 : OpenService() failed : errCode[1060] - errMsg[The specified service does not exist as an installed service. (0x424)]
01/09/06,14:31:09 : ERROR: ExchRemoveService() failed: return[4]
01/09/06,14:31:09 : ExchVerify() Calling ExchInstallService() to install the verification service
01/09/06,14:31:09 : servicePath = "C:\Program Files\CA\BrightStor ARCserve Backup Agent for Exchange\VService.exe"
01/09/06,14:31:09 : OpenSCManager() successful with all ACCESS
01/09/06,14:31:09 : CreateService() [Exchange Agent Setup Verification Service] successfully installed
01/09/06,14:31:09 : ExchInstallService() successfully finished
01/09/06,14:31:09 : OpenSCManager() successful with all ACCESS
01/09/06,14:31:09 : OpenService() [ExchVerification] successful with all ACCESS
01/09/06,14:31:09 : RegisterServiceCtrlHandler() successful
01/09/06,14:31:09 : Service start pending
01/09/06,14:31:09 : ERROR: EnablePrivilege() AdjustTokenPrivileges Failed-1300
01/09/06,14:31:09 : ERROR: ExchSetAllPrivilege() EnablePrivilege SE_CREATE_TOKEN_NAME Failed-183
01/09/06,14:31:09 : ExchSetAllPrivilege() return error code[0]
01/09/06,14:31:09 : Trying to start Service now ......
01/09/06,14:31:09 : CreateEvent() successful
01/09/06,14:31:09 : CreateNamedPipe() successful
01/09/06,14:31:12 : StartService() SUCCESSFUL
01/09/06,14:31:12 : ExchStartService() successfully finished
01/09/06,14:31:12 : ExchAuthenticate() Begin --------------------------
01/09/06,14:31:12 : ExchAuthenticate() called with NTServerName:[LIGHTNING] NTDomainName[RIVER] adminMailbox:[administrator] adminLoginName:[administrator]
01/09/06,14:31:12 : Calling ExchCreateProfile() to create the Profile[EXCH_AUTHENTICATE_PROFILE73161136835072]
01/09/06,14:31:12 : The Windows NT system directory of the local server is:[C:\WINDOWS\system32]
01/09/06,14:31:12 : GetFileVersionInfoSize() for [C:\WINDOWS\system32\MAPI32.DLL] successful: return Size[2212]
01/09/06,14:31:12 : VerQueryValue() return version [6:5:7226:0]
01/09/06,14:31:12 : Doing LogonUser() and Impersonate
01/09/06,14:31:12 : LogonUser() successful to Domain[RIVER] as userName[administrator]
01/09/06,14:31:12 : ImpersonateLoggedOnUser() successful
01/09/06,14:31:12 : ERROR: OpenToken() OpenProcess Failed-5
01/09/06,14:31:12 : ExchSetAllPrivilege() return error code[0]
01/09/06,14:31:12 : MAPIInitialize() successful
01/09/06,14:31:12 : MAPIAdminProfiles() successful
01/09/06,14:31:12 : CreateProfile() profile[EXCH_AUTHENTICATE_PROFILE73161136835072] successful
01/09/06,14:31:12 : AdminServices() successful
01/09/06,14:31:12 : CreateMsgService() for msgService[MSEMS] successful
01/09/06,14:31:12 : GetMsgServiceTable() successful
01/09/06,14:31:12 : HrQueryAllRows() successful, with [1] rows returned
01/09/06,14:31:12 : ExchCheckPropertyTag() successful: Property Tag correctly match
01/09/06,14:31:12 : microsoft Exchange Service found in row [0]
01/09/06,14:31:12 : ConfigureMsgService() successful
01/09/06,14:31:12 : ExchCreateProfile() return [0]
01/09/06,14:31:12 : MAPI is initialized: cleanup resource
01/09/06,14:31:12 : Return back from impersonation
01/09/06,14:31:12 : RevertToSelf() successful
01/09/06,14:31:12 : CloseHandle() successful
01/09/06,14:31:12 : ExchCreateProfile() profile[EXCH_AUTHENTICATE_PROFILE73161136835072] successful
01/09/06,14:31:12 : The Windows NT system directory of the local server is:[C:\WINDOWS\system32]
01/09/06,14:31:12 : GetFileVersionInfoSize()

 

[speshalyst]

Hi,


" On this screen I select 'Use Agent' and the Security window appears. This is where it repeatedly asks for the User Name and Password"

"Use Agent".. here the "agent" referred to is the Windows Client Agent and not the Exchange Backup Agent.

I presume that you are backing up the Exchange server through the Windows Client Agent tree on the Backup manager.

On the Backup manager, once you add your Exchange server under WindowsNT\2000\Xp Systems,
Right Click on the Exchange server level and put in the credentials that would allow you to gain access to the "exchange server"

Now expand the server on the BAB manager, you should be able to see all the drives and the Exchange database and brick level icons.

Now, right click onthe Brick level Icon and put in the credentials you had used to configure the exchange agent.

Expand the brick level. make your selections.

Start the backup. Do not make any modification on the agent and security . Just run the job.

Lets see how it goes.





So it Shall be Written!
So it Shall be Done!!

 

[ServerStorm]

Hi speshalyst

I did as you outlined. This time the backup finished without any Agent error. However, I don't think that the Brick-Level backed up. I say this as I tried to restore a calendar from one of the brick level accounts to a new exchange account (as a test) and when the restore operation ran it said that it could not find any sessions

These are the activities (from the Activity Log) that occured during the failed restore:


Information 01/11/2006 12:52:52 PM [JOBQUEUE]: Status changed from Active to Done. [Job No: 7] [Description: Steve_R]
Information 01/11/2006 12:52:31 PM 1539 Restore Operation Failed.
Information 01/11/2006 12:52:31 PM 1539 Number of Error(s)/Warning(s): 1/0
Information 01/11/2006 12:52:31 PM 1539 Average Throughput: 0 KB/min
Information 01/11/2006 12:52:31 PM 1539 Elapsed Time: 0s
Information 01/11/2006 12:52:31 PM 1539 0 Database(s)/Transaction Log(s) (0 KB) Restored to Disk.
Information 01/11/2006 12:52:31 PM 1539 0 Session(s) Found on Media.
Information 01/11/2006 12:52:31 PM 1539 ** Summary for Job **
E8601 01/11/2006 12:52:31 PM 1539 Failed to connect agent. (AGENT=\\LIGHTNING\dbaxchg2, EC=Invalid user ID or password was submitted or Database Server instance may be inaccessible. If user ID is a Windows domain account, please use Domain\Username.)

--------

The E8601 error indicates account/login problems again. I attempted the restore 3 times:

1) user = administrator password = *******
2) user = River\administrator password = ******
3) user = River\emtest password = *******

In viewing the Activity Log when the backup process was complete I only saw records of the Private and Pub Information stores being backed up, but I did not see anything relative to individual brick accounts. However as you properly surmised I am attempting to set the Brick Level Backup through the BAB. After the backup completed, I checked to ensure that the Brick Level Backup was still selected in the BAB and that I could still see the accounts that I was trying to backup. I was able to see that the accounts were selected. By the sheer matter that I was able to access and select/deselect brick level accounts would mean that the permisions are set correctly.

Here is the complete activity log of the backup that you asked me to complete:

Information 01/11/2006 11:59:06 AM [JOBQUEUE]: Status changed from Active to Ready [Job No: 3] [Description: Wed_Test]
Information 01/11/2006 11:59:06 AM [JOBQUEUE]: Rescheduled for 1/12/06 at 11:53 AM [Job No: 3] [Description: Wed_Test]
Information 01/11/2006 11:59:06 AM 1536 Reschedule Backup Job for 1/12/06 at 11:53 AM.
Information 01/11/2006 11:58:47 AM 1536 20 [CAT] WEDNESDAY [ID:3E06,SESSION:20] is merged.(files=1552)
Information 01/11/2006 11:58:42 AM 1536 19 [CAT] WEDNESDAY [ID:3E06,SESSION:19] is merged.(files=8)
Information 01/11/2006 11:58:42 AM 1536 18 [CAT] WEDNESDAY [ID:3E06,SESSION:18] is merged.(files=586)
Information 01/11/2006 11:58:41 AM 1536 17 [CAT] WEDNESDAY [ID:3E06,SESSION:17] is merged.(files=0)
Information 01/11/2006 11:58:40 AM 1536 16 [CAT] WEDNESDAY [ID:3E06,SESSION:16] is merged.(files=0)
Information 01/11/2006 11:58:40 AM 1536 Backup Operation Failed.
Information 01/11/2006 11:58:40 AM 1536 \\GMATEER\quickbooks
Information 01/11/2006 11:58:40 AM 1536 Missed Nodes/Volumes:
Information 01/11/2006 11:58:40 AM 1536 Total MB processed..................... 264.92
Information 01/11/2006 11:58:40 AM 1536 Total Files backed up.................. 656
Information 01/11/2006 11:58:40 AM 1536 Total Directories backed up............ 2390
Information 01/11/2006 11:58:40 AM 1536 -- Failed and Canceled status.......... 1
Information 01/11/2006 11:58:40 AM 1536 -- Complete and Incomplete status...... 10
Information 01/11/2006 11:58:40 AM 1536 Total Volumes to be backed up.......... 11
Information 01/11/2006 11:58:40 AM 1536 -- Failed and Canceled status.......... 1
Information 01/11/2006 11:58:40 AM 1536 -- Complete and Incomplete status...... 3
Information 01/11/2006 11:58:40 AM 1536 Total Nodes to be backed up............ 4
Information 01/11/2006 11:58:40 AM 1536 Serial No [Seq 1]...................... 1000002
Information 01/11/2006 11:58:40 AM 1536 Total number of sessions............... 10
Information 01/11/2006 11:58:40 AM 1536 Media ID............................... 3E06
Information 01/11/2006 11:58:40 AM 1536 Media Name............................. WEDNESDAY
Information 01/11/2006 11:58:40 AM 1536 Media Pool............................. GROUP1
Information 01/11/2006 11:58:40 AM 1536 Device Group........................... GROUP1
Information 01/11/2006 11:58:40 AM 1536 *** Backup Summary for Job ID 1536 ***
Information 01/11/2006 11:58:39 AM 1536 15 [CAT] WEDNESDAY [ID:3E06,SESSION:15] is merged.(files=0)
Information 01/11/2006 11:58:39 AM 1536 14 [CAT] WEDNESDAY [ID:3E06,SESSION:14] is merged.(files=0)
Information 01/11/2006 11:58:38 AM 1536 13 [CAT] WEDNESDAY [ID:3E06,SESSION:13] is merged.(files=39)
Information 01/11/2006 11:58:38 AM 1536 12 [CAT] WEDNESDAY [ID:3E06,SESSION:12] is merged.(files=0)
Information 01/11/2006 11:58:37 AM 1536 11 [CAT] WEDNESDAY [ID:3E06,SESSION:11] is merged.(files=0)
Information 01/11/2006 11:58:06 AM 1536 5. <BLANK>
Information 01/11/2006 11:58:06 AM 1536 4. FRIDAY [S/N:1000014,SEQ:3] GROUP1
Information 01/11/2006 11:58:06 AM 1536 3. FRIDAY [S/N:1000013,SEQ:2] GROUP1
Information 01/11/2006 11:58:06 AM 1536 2. FRIDAY [S/N:1000019,SEQ:1] GROUP1
Information 01/11/2006 11:58:06 AM 1536 1. THURSDAY [S/N:1000018,SEQ:1] GROUP1
Information 01/11/2006 11:58:06 AM 1536 The following media can be used in device group GROUP1 for next job on date 1/12/06:
Information 01/11/2006 11:58:06 AM 1536 Number of Error(s)/Warning(s): 0/5
Information 01/11/2006 11:58:06 AM 1536 Average Throughput: 108.80 MB/min
Information 01/11/2006 11:58:06 AM 1536 Elapsed Time: 2m 26s
Information 01/11/2006 11:58:06 AM 1536 264.75 MB Written to Media.
Information 01/11/2006 11:58:06 AM 1536 31 Database(s)/Transaction Log(s) (46.20 MB) Backed Up to Media.
Information 01/11/2006 11:58:06 AM 1536 1,755 Directories 586 File(s) (206.67 MB) Backed Up to Media.
Information 01/11/2006 11:58:06 AM 1536 7 Session(s).
Information 01/11/2006 11:58:06 AM 1536 ** Summary for \\LIGHTNING **
Information 01/11/2006 11:58:06 AM 1536 20 Average Throughput: 13.41 MB/min
Information 01/11/2006 11:58:06 AM 1536 20 Elapsed Time: 45s
Information 01/11/2006 11:58:06 AM 1536 20 10.06 MB Written to Media.
Information 01/11/2006 11:58:06 AM 1536 20 30 Database(s)/Transaction Log(s) (6.19 MB) Backed Up to Media.
Information 01/11/2006 11:57:17 AM 1536 20 Backup Session 20 on Media WEDNESDAY Serial # 1000002
Information 01/11/2006 11:57:17 AM 1536 20 Source Directory: dbaxchg2
Information 01/11/2006 11:57:17 AM 1536 dbaxchg2 Version 11.0 Build 2670 12-13-2003 Microsoft Exchange Server 2K/up
Information 01/11/2006 11:57:15 AM 1536 19 Average Throughput: 400.62 MB/min
Information 01/11/2006 11:57:15 AM 1536 19 Elapsed Time: 6s
Information 01/11/2006 11:57:15 AM 1536 19 40.06 MB Written to Media.
Information 01/11/2006 11:57:15 AM 1536 19 1 Database(s)/Transaction Log(s) (40.00 MB) Backed Up to Media.
Information 01/11/2006 11:57:03 AM 1536 19 Backup Session 19 on Media WEDNESDAY Serial # 1000002
Information 01/11/2006 11:57:03 AM 1536 19 Source Directory: dbaexchis\First Storage Group
Information 01/11/2006 11:57:01 AM 1536 Connected with Exchange database agent at \\LIGHTNING.
Information 01/11/2006 11:57:01 AM 1536 dbaexchis Version 11.0 Build 2670 12-13-2003 Microsoft Exchange Server 2K/up
Information 01/11/2006 11:57:00 AM 1536 Note: Disconnect from \\LIGHTNING\Users.
Information 01/11/2006 11:57:00 AM 1536 18 Average Throughput: 167.69 MB/min
Information 01/11/2006 11:57:00 AM 1536 18 Elapsed Time: 1m 15s
Information 01/11/2006 11:57:00 AM 1536 18 209.62 MB Written to Media.
Information 01/11/2006 11:57:00 AM 1536 18 154 Directories 586 File(s) (206.14 MB) Backed Up to Media.
Information 01/11/2006 11:57:00 AM 1536 18 Catalog File Backed Up
Information 01/11/2006 11:55:38 AM 1536 18 Backup Session 18 on Media WEDNESDAY Serial # 1000002
Information 01/11/2006 11:55:38 AM 1536 18 Source Directory: \\LIGHTNING\Users
W3032 01/11/2006 11:55:37 AM 1536 Compression is disabled due to hardware data compression.
Information 01/11/2006 11:55:37 AM 1536 Note: Connect to \\LIGHTNING\Users as river\administrator.
Information 01/11/2006 11:55:37 AM 1536 Note: Disconnect from \\LIGHTNING\Software.
Information 01/11/2006 11:55:37 AM 1536 17 Average Throughput: 16.25 MB/min
Information 01/11/2006 11:55:37 AM 1536 17 Elapsed Time: 6s
Information 01/11/2006 11:55:37 AM 1536 17 1.62 MB Written to Media.
Information 01/11/2006 11:55:37 AM 1536 17 531 Directories 0 File(s) (44 KB) Backed Up to Media.
Information 01/11/2006 11:55:37 AM 1536 17 Catalog File Backed Up
Information 01/11/2006 11:55:28 AM 1536 17 Backup Session 17 on Media WEDNESDAY Serial # 1000002
Information 01/11/2006 11:55:28 AM 1536 17 Source Directory: \\LIGHTNING\Software
W3032 01/11/2006 11:55:25 AM 1536 Compression is disabled due to hardware data compression.
Information 01/11/2006 11:55:25 AM 1536 Note: Connect to \\LIGHTNING\Software as river\administrator.
Information 01/11/2006 11:55:25 AM 1536 Note: Disconnect from \\LIGHTNING\DropBox.
Information 01/11/2006 11:55:24 AM 1536 16 Average Throughput: 15.93 MB/min
Information 01/11/2006 11:55:24 AM 1536 16 Elapsed Time: 12s
Information 01/11/2006 11:55:24 AM 1536 16 3.18 MB Written to Media.
Information 01/11/2006 11:55:24 AM 1536 16 1,037 Directories 0 File(s) (93 KB) Backed Up to Media.
Information 01/11/2006 11:55:24 AM 1536 16 Catalog File Backed Up
Information 01/11/2006 11:55:09 AM 1536 16 Backup Session 16 on Media WEDNESDAY Serial # 1000002
Information 01/11/2006 11:55:09 AM 1536 16 Source Directory: \\LIGHTNING\DropBox
W3032 01/11/2006 11:55:02 AM 1536 Compression is disabled due to hardware data compression.
Information 01/11/2006 11:55:02 AM 1536 Note: Connect to \\LIGHTNING\DropBox as river\administrator.
Information 01/11/2006 11:55:02 AM 1536 Note: Disconnect from \\LIGHTNING\E$.
Information 01/11/2006 11:55:02 AM 1536 15 Average Throughput: 3.75 MB/min
Information 01/11/2006 11:55:02 AM 1536 15 Elapsed Time: 1s
Information 01/11/2006 11:55:02 AM 1536 15 64 KB Written to Media.
Information 01/11/2006 11:55:02 AM 1536 15 1 Directories 0 File(s) (1 KB) Backed Up to Media.
Information 01/11/2006 11:55:02 AM 1536 15 Catalog File Backed Up
Information 01/11/2006 11:54:59 AM 1536 15 Backup Session 15 on Media WEDNESDAY Serial # 1000002
Information 01/11/2006 11:54:59 AM 1536 15 Source Directory: \\LIGHTNING\E$\System Volume Information
W3032 01/11/2006 11:54:57 AM 1536 Compression is disabled due to hardware data compression.
Information 01/11/2006 11:54:57 AM 1536 Note: Connect to \\LIGHTNING\E$ as river\administrator.
Information 01/11/2006 11:54:57 AM 1536 Note: Disconnect from \\LIGHTNING\C$.
Information 01/11/2006 11:54:57 AM 1536 14 Average Throughput: 7.50 MB/min
Information 01/11/2006 11:54:57 AM 1536 14 Elapsed Time: 1s
Information 01/11/2006 11:54:57 AM 1536 14 128 KB Written to Media.
Information 01/11/2006 11:54:57 AM 1536 14 32 Directories 0 File(s) (3 KB) Backed Up to Media.
Information 01/11/2006 11:54:57 AM 1536 14 Catalog File Backed Up
Information 01/11/2006 11:54:54 AM 1536 14 Backup Session 14 on Media WEDNESDAY Serial # 1000002
Information 01/11/2006 11:54:54 AM 1536 14 Source Directory: \\LIGHTNING\C$
W3032 01/11/2006 11:54:52 AM 1536 Compression is disabled due to hardware data compression.
Information 01/11/2006 11:54:52 AM 1536 Note: Connect to \\LIGHTNING\C$ as river\administrator.
Information 01/11/2006 11:54:52 AM 1536 Number of Error(s)/Warning(s): 0/1
Information 01/11/2006 11:54:52 AM 1536 Average Throughput: 38.25 MB/min
Information 01/11/2006 11:54:52 AM 1536 Elapsed Time: 10s
Information 01/11/2006 11:54:52 AM 1536 6.37 MB Written to Media.
Information 01/11/2006 11:54:52 AM 1536 133 Directories 39 File(s) (5.80 MB) Backed Up to Media.
Information 01/11/2006 11:54:52 AM 1536 2 Session(s).
Information 01/11/2006 11:54:52 AM 1536 ** Summary for \\HAIL **
Information 01/11/2006 11:54:50 AM 1536 13 39 file(s) 6.20 MB sent by agent @ 21.37 MB/min
Information 01/11/2006 11:54:40 AM 1536 13 Approximately 39 file(s) 5.78 MB will be processed.
Information 01/11/2006 11:54:32 AM 1536 13 Backup Session 13 on Media WEDNESDAY Serial # 1000002
Information 01/11/2006 11:54:32 AM 1536 13 Source Directory: G:\VSS
Information 01/11/2006 11:54:32 AM 1536 12 Backup G:\VSS to WEDNESDAY sequence 1 session 13
Information 01/11/2006 11:54:30 AM 1536 12 0 file(s) 22 KB sent by agent @ 2,727 KB/min
Information 01/11/2006 11:54:30 AM 1536 12 Approximately 0 file(s) 0 KB will be processed.
Information 01/11/2006 11:54:29 AM 1536 12 Backup Session 12 on Media WEDNESDAY Serial # 1000002
Information 01/11/2006 11:54:29 AM 1536 12 Source Directory: G:\MSSQL2K\MSSQL\BACKUP
Information 01/11/2006 11:54:29 AM 1536 12 Backup G:\MSSQL2K\MSSQL\BACKUP to WEDNESDAY sequence 1 session 12
Information 01/11/2006 11:54:29 AM 1536 12 BrightStor ARCserve Backup Client Agent for Windows is version 11.0, build 2670...
W3032 01/11/2006 11:54:29 AM 1536 12 Compression is disabled due to hardware data compression.
Information 01/11/2006 11:54:29 AM 1536 Connected with client agent at \\HAIL. (User = administrator)
Information 01/11/2006 11:54:29 AM 1536 Number of Error(s)/Warning(s): 0/1
Information 01/11/2006 11:54:29 AM 1536 Average Throughput: 15.62 MB/min
Information 01/11/2006 11:54:29 AM 1536 Elapsed Time: 6s
Information 01/11/2006 11:54:29 AM 1536 1.56 MB Written to Media.
Information 01/11/2006 11:54:29 AM 1536 502 Directories 0 File(s) (28 KB) Backed Up to Media.
Information 01/11/2006 11:54:29 AM 1536 1 Session(s).
Information 01/11/2006 11:54:29 AM 1536 ** Summary for \\CHILL **
Information 01/11/2006 11:54:25 AM 1536 11 0 file(s) 1.26 MB sent by agent @ 6.36 MB/min
Information 01/11/2006 11:54:19 AM 1536 11 Approximately 0 file(s) 0 KB will be processed.
Information 01/11/2006 11:54:13 AM 1536 11 Backup Session 11 on Media WEDNESDAY Serial # 1000002
Information 01/11/2006 11:54:13 AM 1536 11 Source Directory: D:
Information 01/11/2006 11:54:13 AM 1536 11 Backup D: to WEDNESDAY sequence 1 session 11
Information 01/11/2006 11:54:13 AM 1536 11 BrightStor ARCserve Backup Client Agent for Windows is version 11.0, build 2670...
W3032 01/11/2006 11:54:13 AM 1536 11 Compression is disabled due to hardware data compression.
Information 01/11/2006 11:54:13 AM 1536 Connected with client agent at \\CHILL. (User = administrator)
Information 01/11/2006 11:54:13 AM 1536 Average Throughput: 0 KB/min
Information 01/11/2006 11:54:13 AM 1536 Elapsed Time: 0s
Information 01/11/2006 11:54:13 AM 1536 0 Session(s).
Information 01/11/2006 11:54:13 AM 1536 ** Summary for \\GMATEER **
AW0000 01/11/2006 11:54:13 AM 1536 11 Skip invalid shared or volume quickbooks
Information 01/11/2006 11:54:13 AM 1536 11 BrightStor ARCserve Backup Client Agent for Windows is version 11.0, build 2670...
W3032 01/11/2006 11:54:13 AM 1536 11 Compression is disabled due to hardware data compression.
Information 01/11/2006 11:54:13 AM 1536 Connected with client agent at \\GMATEER. (User = ILEX\Administrator)
Information 01/11/2006 11:54:08 AM 1536 Backup Method: Incremental.
Information 01/11/2006 11:54:06 AM 1536 Description: Wed_Test.
Information 01/11/2006 11:54:06 AM 1536 Start Backup Operation. (QUEUE=1, JOB=3)
Information 01/11/2006 11:54:06 AM 1536 Run Backup Job Scheduled for 1/11/06 at 11:54 AM.
Information 01/11/2006 11:54:05 AM [JOBQUEUE]: Status changed from Ready to Active [Job No: 3] [Description: Wed_Test]
Information 01/11/2006 11:53:51 AM [JOBQUEUE]: Rescheduled for 1/11/06 at 11:54 AM [Job No: 3] [Description: Wed_Test]
Information 01/11/2006 11:52:37 AM [JOBQUEUE]: Modified [Job No: 3] [Description: Wed_Test]
Information 01/11/2006 11:52:37 AM [JOBQUEUE]: Status changed from Hold to Ready [Job No: 3] [Description: Wed_Test]
Information 01/11/2006 11:52:37 AM [JOBQUEUE]: Rescheduled for 1/12/06 at 11:53 AM [Job No: 3] [Description: Wed_Test]
Information 01/11/2006 11:52:21 AM [JOBQUEUE]: Status changed from Ready to Hold [Job No: 3] [Description: Wed_Test]
Information 01/11/2006 11:51:33 AM [JOBQUEUE]: Rescheduled for 1/12/06 at 11:52 AM [Job No: 3] [Description: Wed_Test]
Information 01/11/2006 11:38:17 AM [JOBQUEUE]: Modified [Job No: 3] [Description: Wed_Test]
Information 01/11/2006 11:38:17 AM [JOBQUEUE]: Status changed from Hold to Ready [Job No: 3] [Description: Wed_Test]
Information 01/11/2006 11:38:17 AM [JOBQUEUE]: Rescheduled for 1/12/06 at 11:40 AM [Job No: 3] [Description: Wed_Test]

Thanks so much for your help.

Truly,
ServerStorm

 

[mcbs25]

I´m having the same problem...

I have 1 Server W2000 SP4 with BA 11.0 (I update now to 11.1 SP1) and 1 Server w2003 SP1 with BA Agent for Exchange for my exchange 2003.

When i try to configure the agent to use brick level they gave me a message: "failed to configure your server"
When i was on exchange 5.5 i used the same configuration and i never had any problem.

 

[speshalyst]

HI,

Looking at the log, session 20 looks to be the brick level session....

Information 01/11/2006 11:58:06 AM 1536 20 Average Throughput: 13.41 MB/min
Information 01/11/2006 11:58:06 AM 1536 20 Elapsed Time: 45s
Information 01/11/2006 11:58:06 AM 1536 20 10.06 MB Written to Media.
Information 01/11/2006 11:58:06 AM 1536 20
30 Database(s)/Transaction Log(s) (6.19 MB) Backed Up to Media.
Information 01/11/2006 11:57:17 AM 1536 20
Backup Session 20 on Media WEDNESDAY Serial # 1000002
Information 01/11/2006 11:57:17 AM 1536 20

Source Directory: dbaxchg2



So it Shall be Written!
So it Shall be Done!!

 

[ServerStorm]

Hi speshalyst

You are correct I learned that it is easier to see this in the Job Log (see below) as it clearly indicates that the brick-level was succesful.

I very much appreciate you sticking with this and my newbyism to ARCserve!

There are still a few things I don't understand and am hoping that you can clarify them?

1) I know that the brick level mail-boxes are far larger than the 76.74 Mb that is listed as succesfully backed-up? Is this because the rotation is set up as a Mon - Thurs incremental and Friday Full, and ARCserve automaticall performs only an incremental and on Friday I should see the hundreds of megabytes for the full mailboxes?

2) If it succesfully backed up the brick level than why did my restore fail? I know too general of a question, so here is a little more detail:
a) I first created a test Exchange mailbox and initialized
it by sending it an email
b) I then selected one of the user accounts as source
c) I then entered - on three attempts the password for the
user account and tried restoring - it failed. So I did
it again an used the administrators account info and
again it failed to restore.

Can you see anywhere that I may have messed up?

I know that his question should probably be in a new thread, but if you feel it still ties in enough then I would very much appreciate your expetise on this one.

ServerStorm

Description.................. Wed_Test
Total Database(s)............ 1
Total Skip(s)................ 0
Total Size (Disk)............ 3 KB
Total Size (DB).............. 90.01 MB
Total Size (Media)........... 90.12 MB
Elapsed Time................. 27s
Average Throughput........... 200.27 MB/min
Session Status............... Finished
N8640
dbaxchg2 Version 11.0 Build 2670 12-13-2003 Microsoft Exchange Server 2K/up


Job No....................... 3
Job ID....................... 1542
Workstation.................. LIGHTNING
Source....................... dbaxchg2
Target....................... THURSDAY, ID 9ED2, Sequence #1, S/N 1000018
Session...................... 10
Start Time................... 1/12/06 12:01 PM


Sequence..................... 1
Workstation.................. LIGHTNING
Path......................... dbaxchg2
Owner Name................... caroot
Session Type................. Backup Agent
Session Method............... Backup Exchange Brick level Incremental.
No Registry
Directories Backed Up
Description.................. Wed_Test
\Individual Mailboxes\Administrator
\Individual Mailboxes\Bryan Toreja
\Individual Mailboxes\Carmela Wynne
\Individual Mailboxes\Casey Sheahan
\Individual Mailboxes\Eric West
\Individual Mailboxes\Greg Yantz
\Individual Mailboxes\Ian MacDonald
\Individual Mailboxes\Ian Rogers
\Individual Mailboxes\Ian Wood
\Individual Mailboxes\Jeff Suggitt
\Individual Mailboxes\Joanna Kirkwood
\Individual Mailboxes\Joanne Degrace
\Individual Mailboxes\John Paskvalin
\Individual Mailboxes\Karen Nagy
\Individual Mailboxes\Kelly Houle
\Individual Mailboxes\Kelly Muicy
\Individual Mailboxes\Mandeep Ippolito
\Individual Mailboxes\Matt Klassen
\Individual Mailboxes\Michael Klassen
\Individual Mailboxes\Nadeem Mahmood
\Individual Mailboxes\Nico Tai
\Individual Mailboxes\Samar El-Farram
\Individual Mailboxes\Steve Browning
\Individual Mailboxes\Wendy Smith
\Public Folders\Company Contacts
\Public Folders\Contact
\Public Folders\Data Verification
\Public Folders\Employees
\Public Folders\Employees on Contract
\Public Folders\Translations


Total Database(s)............ 30
Total Skip(s)................ 0
Total Size (Disk)............ 400 KB
Total Size (DB).............. 76.74 MB
Total Size (Media)........... 80.68 MB
Elapsed Time................. 1m 42s
Average Throughput........... 47.46 MB/min
Session Status............... Finished

 

[vschumpy]

dbaxchg2 Version 11.0 Build 2670 12-13-2003 Microsoft Exchange Server 2K/up

Suggest you patch this up to the latest level - this is an r11.0 agent unpatched:

http://supportconnect.ca.com/sc/redir.jsp?reqPage=search&searchID=TEC378659

 

[ServerStorm]

Hi vschumpy

Thanks for the info - great article. As I assumed this job and no documentation was left behind I did not know if the company still is under warranty or can download patches. When I called CA, they did not even have a record of the company, yet the company did provide me with a sheet that had the license agreement, serial numbers and offical stickers of authentic software so I can only sermise that they purchased it. CA would not let me download until I was able to prove that they bought the software. I have sent a copy of the info that I have and am awaiting their approval. This is also important so that I can be listed for updates and the like.

I appreciate you providing this article as I can at least proceed patching -- thanks!

 

[ServerStorm]

Hey speshalyst, myauto, vschumpy and others,

Just wanted to let you know that I was finally able to do a complete Brick-level back-up and restore! Thanks so much for all your help - especial thanks to speshalyst!

I will have to wait till Saturday to test a full back-up and restore, as I was only able to perfore a restore of incremental days - the version history only went back to Wednesday.

For those interested here is a very specific summary of the things that I needed to do to get this working - this may be of intrest to mcbs25, who previously posted of having similar problems.

[ol]
[li]Ensure that all services including the exchange backup agent service had the domain administrators account set. This meant going onto the Exchange server, accessing the agent, and on the second screen of the wizard, I added the administrator account info. It has to display a message saying something like 'Please wait while the account is verified'[/li]
[li]Go to the server running ARCSERVE manager and access a job (select modify). Under the source tab I right selected the brick level exchange 2003 under the 'Network'. I then selected 'Security' and entered username = administrator and password = *******. It is important to note that on my server it would NOT take the domain\user format, rather it just used user.[/li]
[li]Once the security settings were accepted then the brick level became expandable in the BAB (network directory selection tree). For here I was able to select the Exchange accounts that I wanted to have backed-up.[/li]
[li]As I had already verified the job details hundreds of times in the last few days, I skipped directly to 'start' the job I had just configured in the last step. When the [/li]
[li]When the Security and Agent Information screen appeared I checked that all the correct UserNames and Passwords were set for the different shares. This is VERY IMPORTANT: don't try to set the Agent security for the Brick Level backups on this screen! If you succeeded earlier in selecting the Brick Level accounts in the 'source' tree then you LEAVE IT ALONE on this 'Security and Agent Information' screen. Yes it will show that there is no IP assigned, but this does not matter. If you select the Microsoft Exchange Backup Agent object on this screen and select Security or Agent it WILL INTERFERE with the security that you set in step 2 and you will have to go back to step 2.[/li]
[li]I then scheduled the job and it worked like a charm[/li]
[/ol]

Thanks again - all of you that helped, you ROCK!

Truly,
ServerStorm