cancel
Turn on suggestions
Auto-suggest helps you quickly narrow down your search results by suggesting possible matches as you type.
Showing results for 
Search instead for 
Did you mean: 
Veritas.com
Support

Backup Exec hangs when expanding Server in selection list

Go to solution
SuperBeta
Level 3

‎11-11-2014 08:32 AM

I am trying to get the backup exec agent installed and working on a domain controller to back it up with Backup Exec 2010 R3 SP4. The Media server (we will call it Server1) and the remote server (we will call it Server2) are both running Windows Server 2008 R2 Standard and they both happen to be AD domain controllers. Server2 used to be a Backup Exec 2010 R3 media server, but I have uninstalled the software (using Control Panel > Uninstall a Program). After uninstalling it, I pushed the RAWS agent from Server1 to Server2 using the server name (we will say that is Server2.DOMAIN.COM). The install is successful and the service is running. On Server1, the new Server2 entry shows up in my selection list under favorites, but any interaction with it will lock up the Backup Exec program no matter how long I wait. After 15 seconds or so, an error is generated in Server1's Event Log under Application. The Error is: Event ID 1002 "Application Hang" - 

The program BkupExec.exe version 13.0.5204.1270 stopped interacting with Windows and was closed. To see if more information about the problem is available, check the problem history in the Action Center control panel.
 Process ID: 1418
 Start Time: 01cffdc6d2e9bdc3
 Termination Time: 6
 Application Path: D:\ProgramFiles\BackupExec\BkupExec.exe
 Report Id: 93364944-69ba-11e4-be7f-f01fafe1ccad

This error is generated every time I try to expand Server2 in the selection list.

Things I have already checked:
I have already checked for the trusted relationship entry in Server2's Agent Utility. It is present and appears correct.
I have also turned off the firewalls on both servers to rule those out. Both servers are on the same subnet, same switch even. Server1's IP is 192.168.1.52, Server2's IP is 192.168.1.51.
I also verified DNS is resolving correctly between the servers. I even put the IP of Server2 in Server1's HOSTS file to be sure.
I did reboot the servers after uninstalling BE2010 on Server2 as well as another reboot after installing the RAWS agent.

After some research, it seems useful to have SGMON output to see where the breakdown in communication is. Here is the output I get from the Media Server, with all boxes checked in SGMON. The capture starts just before I click to expand Server2's selection list entry. 

***************************************************************************
*****                    SGMon log for \\\\Server1                    *****
*****                    Local Offset =05:00                          *****
***************************************************************************
BEREMOTE: [11/11/14 10:49:56] [7712]     [fsys\shared]        - FS_ResolveDevName: [\\Server2.DOMAIN.COM]
BEREMOTE: [11/11/14 10:49:56] [7712]     [fsys\mb2]           - MB2-Resolve returned 1
BEREMOTE: [11/11/14 10:49:56] [7712]     [fsys\notes2]        - Notes Agent:CLNFileSystem::ResolveDeviceName
BEREMOTE: [11/11/14 10:49:56] [7712]     [fsys\adc]           - ADC_ResolveDeviceName: pid = 6240, checking \\Server2.DOMAIN.COM
BEREMOTE: [11/11/14 10:49:56] [7712]     [beutil]             - ApplyRegExp(): Invalid input (\\Server2.DOMAIN.COM). Parsing Failed.
BEREMOTE: [11/11/14 10:49:56] [7712]     [beutil]             - GoodEvName(): Invalid EV device (\\Server2.DOMAIN.COM).
BEREMOTE: [11/11/14 10:49:56] [7712]     [beutil]             - ApplyRegExp(): Invalid input (\\Server2.DOMAIN.COM). Parsing Failed.
BEREMOTE: [11/11/14 10:49:56] [7712]     [beutil]             - GoodEvName(): Invalid EV device (\\Server2.DOMAIN.COM).
BEREMOTE: [11/11/14 10:49:56] [7712]     [beutil]             - Input Error (e000fe23) for Type: (43)
BEREMOTE: [11/11/14 10:49:56] [7712]     [fsys\ev]            - EVM_ResolveDeviceName: Function Enter
BEREMOTE: [11/11/14 10:49:56] [7712]     [beutil]             - ApplyRegExp(): Invalid input (\\Server2.DOMAIN.COM). Parsing Failed.
BEREMOTE: [11/11/14 10:49:56] [7712]     [fsys\ev]            - EVM_ResolveDeviceName: Invalid device name (\\Server2.DOMAIN.COM). Parsing Failed.
BEREMOTE: [11/11/14 10:49:56] [7712]     [fsys\ev]            - EVM_ResolveDeviceName: Function Exit
BEREMOTE: [11/11/14 10:49:56] [7712]     [fsys\ntfs]          - Not valid device name : \\Server2.DOMAIN.COM
BEREMOTE: [11/11/14 10:49:56] [7712]     [dsss\rpc]           - RPCDsSession::GetLoginCredentials: Connecting to BEM API as user: DOMAIN\administrator
BESERVER: [11/11/14 10:49:56] [3928]     -1 Client requested key (1415721161).
BESERVER: [11/11/14 10:49:56] [3928]     "Cluster" key does not appear to be present in the registry
BESERVER: [11/11/14 10:49:56] [3928]     Failed to open Microsoft cluster ()
BESERVER: [11/11/14 10:49:56] [3928]     VCS cluster keys do not appear to be present in the registry
BESERVER: [11/11/14 10:49:56] [3928]     Failed to open VCS cluster ()
BESERVER: [11/11/14 10:49:56] [3928]     01 Server Configuration: Client added: 9
BESERVER: [11/11/14 10:49:56] [3928]     -1 Client 'Server1' connected('','DOMAIN\administrator'): 0xd844570
BESERVER: [11/11/14 10:49:56] [3928]     01 Server Configuration: Client removed: 8
BESERVER: [11/11/14 10:49:56] [3928]     -1 Client 'Server1' Disconnected:0xd844570
BEREMOTE: [11/11/14 10:49:56] [7712]     [ndmp\ndmpcomm]      - Could not resolve the "bews-ndmp" or the "ndmp" service, error code: 10109, using port 10000
BEREMOTE: [11/11/14 10:49:56] [7712]     "Cluster" key does not appear to be present in the registry
BEREMOTE: [11/11/14 10:49:56] [7712]     Failed to open Microsoft cluster ()
BEREMOTE: [11/11/14 10:49:56] [7712]     VCS cluster keys do not appear to be present in the registry
BEREMOTE: [11/11/14 10:49:56] [7712]     Failed to open VCS cluster ()
BEREMOTE: [11/11/14 10:49:56] [7712]     [ndmp\ndmpcomm]      - ndmpConnectEx: Querying the neighbour advertisement cache to discover information on 'Server2.DOMAIN.COM' ...
BENETNS:  [11/11/14 10:49:56] [2148]     NRDS API - client connected.
BENETNS:  [11/11/14 10:49:56] [2148]     Connecting to BE Database.
BESERVER: [11/11/14 10:49:56] [3928]     -1 Client requested key (1415721161).
BESERVER: [11/11/14 10:49:56] [3928]     01 Server Configuration: Client added: 9
BESERVER: [11/11/14 10:49:56] [3928]     -1 Client 'Server1' connected('','DOMAIN\BackupAdmin'): 0xd844570
BENETNS:  [11/11/14 10:49:56] [2148]     Successfully connected to BE Database.
BENETNS:  [11/11/14 10:49:56] [2148]     Reading agent database record for Server2.DOMAIN.COM.
BENETNS:  [11/11/14 10:49:56] [2148]     Found agent record 2 for Server2.DOMAIN.COM.
BESERVER: [11/11/14 10:49:56] [3928]     01 Server Configuration: Client removed: 8
BESERVER: [11/11/14 10:49:56] [3928]     -1 Client 'Server1' Disconnected:0xd844570
BENETNS:  [11/11/14 10:49:56] [2148]     Disconnected from BE Database.
BENETNS:  [11/11/14 10:49:56] [2148]     NRDS API - client disconnected.
BEREMOTE: [11/11/14 10:49:56] [7712]     [ndmp\ndmpcomm]      - ndmpConnectEx : Control Connection information: A connection was established between end-points 192.168.1.52:50229 and 192.168.1.51:10000.
BEREMOTE: [11/11/14 10:51:02] [6544]     [ndmp\ndmpsrvr]      - Freeing unsued COM libraries

I have worked with Backup Exec alot and have never had this problem, even when dealing with these Server OS's and having BE2010 previously installed on a machine I want the agent setup on. 

 

 

1 ACCEPTED SOLUTION

Accepted Solutions

Go to solution
SuperBeta
Level 3

‎11-18-2014 08:23 AM

Just found my solution! Couldn't have done this without the help of the people on this site. Thanks so much for taking the time!

The solution:
On the client server, I was running Cerberus FTP which happens to use port 10000 for it's remote administration connection. As you may know, RAWS uses port 10000 for communication as well. Newer versions of this ftp software seems to use port 10001, but I'm not 100% certain this is always the case. It may check during installation if another application is using port 10000 and choose a different port. In this case, I had installed Cerberus first, and then added the RAWS client later, so if there was a port check, it didn't happen. It seems very clear that the Symantec product doesn't do any checks of this type. After I changed which port Cerberus was using, the RAWS agent started working and I could expand my selection list for that server without issue. 

https://www.cerberusftp.com/support/help/remote_settings.html

This simple issue has taken quite a bit of my time to figure out. It was an easy fix, but it would have gone a lot faster if I would have taken the time to list all of the services and 3rd party software I had running on the server and looked at those earlier on in the process. 

 

 

View solution in original post

0 Kudos
5 REPLIES 5

Go to solution
SuperBeta
Level 3

‎11-13-2014 06:40 AM

I'm still pulling my hair out with this issue. I really need to get this server backed up soon but I can't select anything in the selection list on the Media server without the whole program locking up. Any ideas on what's causing that would be very helpful. 

0 Kudos

Go to solution
VJware
Level 6
Employee Accredited Certified

‎11-14-2014 02:32 AM

Does this issue reoccur if you try to add and select the remote server via User Defined selections ?

Would probably need to enable "verbose" level of debugging and will need SGmon for both servers.

Are there any other relevant events apart from Event 1002 logged ?

 

0 Kudos

Go to solution
SuperBeta
Level 3

‎11-14-2014 06:28 AM

 

Yes, even though I can navigate to the servers shares via the start menu "\\server\sharename", if I add that path to User-defined selections the interface locks up in the same way. 

Here are the verbose level logs. I hope I did this correctly.

Media Server: 

***************************************************************************
*****                    SGMon log for \\\\Server1                    *****
*****                    Local Offset =05:00                          *****
***************************************************************************
BEREMOTE: [11/14/14 09:16:59] [7904]     [fsys\shared]        - FS_ResolveDevName: [\\Server2.DOMAIN.COM]
BEREMOTE: [11/14/14 09:16:59] [7904]     [fsys\mb2]           - MB2-Resolve returned 1
BEREMOTE: [11/14/14 09:16:59] [7904]     [fsys\notes2]        - Notes Agent:CLNFileSystem::ResolveDeviceName
BEREMOTE: [11/14/14 09:16:59] [7904]     [fsys\adc]           - ADC_ResolveDeviceName: pid = 6240, checking \\Server2.DOMAIN.COM
BEREMOTE: [11/14/14 09:16:59] [7904]     [beutil]             - ApplyRegExp(): Invalid input (\\Server2.DOMAIN.COM). Parsing Failed.
BEREMOTE: [11/14/14 09:16:59] [7904]     [beutil]             - GoodEvName(): Invalid EV device (\\Server2.DOMAIN.COM).
BEREMOTE: [11/14/14 09:16:59] [7904]     [beutil]             - ApplyRegExp(): Invalid input (\\Server2.DOMAIN.COM). Parsing Failed.
BEREMOTE: [11/14/14 09:16:59] [7904]     [beutil]             - GoodEvName(): Invalid EV device (\\Server2.DOMAIN.COM).
BEREMOTE: [11/14/14 09:16:59] [7904]     [beutil]             - Input Error (e000fe23) for Type: (43)
BEREMOTE: [11/14/14 09:16:59] [7904]     [fsys\ev]            - EVM_ResolveDeviceName: Function Enter
BEREMOTE: [11/14/14 09:16:59] [7904]     [beutil]             - ApplyRegExp(): Invalid input (\\Server2.DOMAIN.COM). Parsing Failed.
BEREMOTE: [11/14/14 09:16:59] [7904]     [fsys\ev]            - EVM_ResolveDeviceName: Invalid device name (\\Server2.DOMAIN.COM). Parsing Failed.
BEREMOTE: [11/14/14 09:16:59] [7904]     [fsys\ev]            - EVM_ResolveDeviceName: Function Exit
BEREMOTE: [11/14/14 09:16:59] [7904]     [fsys\ntfs]          - Not valid device name : \\Server2.DOMAIN.COM
BEREMOTE: [11/14/14 09:16:59] [7904]     [dsss\rpc]           - RPCDsSession::GetLoginCredentials: Connecting to BEM API as user: DOMAIN\administrator
BESERVER: [11/14/14 09:16:59] [4024]     -1 Client requested key (1415974787).
BESERVER: [11/14/14 09:16:59] [4024]     01 Server Configuration: Client added: 9
BESERVER: [11/14/14 09:16:59] [4024]     -1 Client 'Server1' connected('','DOMAIN\administrator'): 0x4e00d30
BESERVER: [11/14/14 09:16:59] [4024]     01 Server Configuration: Client removed: 8
BESERVER: [11/14/14 09:16:59] [4024]     -1 Client 'Server1' Disconnected:0x4e00d30
BEREMOTE: [11/14/14 09:16:59] [7904]     [ndmp\ndmpcomm]      - Could not resolve the "bews-ndmp" or the "ndmp" service, error code: 10109, using port 10000
BEREMOTE: [11/14/14 09:16:59] [7904]     [ndmp\ndmpcomm]      - ndmpConnectEx: Querying the neighbour advertisement cache to discover information on 'Server2.DOMAIN.COM' ...
BENETNS:  [11/14/14 09:16:59] [7212]     NRDS API - client connected.
BENETNS:  [11/14/14 09:16:59] [7212]     Connecting to BE Database.
BESERVER: [11/14/14 09:16:59] [2940]     -1 Client requested key (1415974787).
BESERVER: [11/14/14 09:16:59] [2940]     01 Server Configuration: Client added: 9
BESERVER: [11/14/14 09:16:59] [2940]     -1 Client 'Server1' connected('','DOMAIN\BackupAdmin'): 0xd87db40
BENETNS:  [11/14/14 09:16:59] [7212]     Successfully connected to BE Database.
BENETNS:  [11/14/14 09:16:59] [7212]     Reading agent database record for Server2.DOMAIN.COM.
BENETNS:  [11/14/14 09:16:59] [7212]     Found agent record 2 for Server2.DOMAIN.COM.
BESERVER: [11/14/14 09:16:59] [2940]     01 Server Configuration: Client removed: 8
BESERVER: [11/14/14 09:16:59] [2940]     -1 Client 'Server1' Disconnected:0xd87db40
BENETNS:  [11/14/14 09:16:59] [7212]     Disconnected from BE Database.
BENETNS:  [11/14/14 09:16:59] [7212]     NRDS API - client disconnected.
BEREMOTE: [11/14/14 09:16:59] [7904]     [ndmp\ndmpcomm]      - ndmpConnectEx : Control Connection information: A connection was established between end-points 172.30.1.52:63213 and 172.30.1.51:10000.
MMC:      [11/14/14 09:17:12] [0000]     Buffer insufficient for RenderEvent for LiteRecord, Retrying with the correct buffer-122-The data area passed to a system call is too small
MMC:      [11/14/14 09:17:12] [0000]     Buffer insufficient for RenderEvent for LiteRecord, Retrying with the correct buffer-122-The data area passed to a system call is too small
MMC:      [11/14/14 09:17:12] [0000]     Buffer insufficient for RenderEvent for LiteRecord, Retrying with the correct buffer-122-The data area passed to a system call is too small
MMC:      [11/14/14 09:17:12] [0000]     Buffer insufficient for RenderEvent for LiteRecord, Retrying with the correct buffer-122-The data area passed to a system call is too small
MMC:      [11/14/14 09:17:12] [0000]     Trace Start:0End Index:26

 

And here is the verbose log from Server2

*************************************************************************
*****                    SGMon log for \\\\Server2                   *****
*****                    Local Offset =05:00                        *****
*************************************************************************
BEREMOTE: [11/14/14 09:17:26] [3472]     [ndmp\ndmpsrvr]      - Freeing unsued COM libraries

 

As for any other relavant events, I did find one. Not sure how useful it is. It always comes right before the 1002 event on the media server and is just an Information level message (not a red alert).

Fault bucket , type 0
Event Name: AppHangB1
Response: Not available
Cab Id: 0

Problem signature:
P1: BkupExec.exe
P2: 13.0.5204.1270
P3: 52c732c7
P4: 7710
P5: 256
P6: 
P7: 
P8: 
P9: 
P10: 

Attached files:

These files may be available here:
C:\Users\administrator.CIIERIE\AppData\Local\Microsoft\Windows\WER\ReportArchive\AppHang_BkupExec.exe_f67fcbdfb7abeb3ced9b46f9dbc3e0c1cc13c211_413f1d22

Analysis symbol: 
Rechecking for solution: 0
Report Id: 06d98ecb-6c03-11e4-be7f-f01fafe1ccad
Report Status: 0

 

 

0 Kudos

Go to solution
SuperBeta
Level 3

‎11-17-2014 01:07 PM

Okay it seems my verbose logging wasn't really as verbose as necessary. I checked the necessary registry keys to crank up the verbosity, so I'm hoping we have enough info here. 

Verbose log from Media server when trying to expand server selection list:

BESERVER: [11/17/14 15:28:20] [4744]     -1 PRINO Start(101124): Object:MD_OBJTYPE_LOGINACCOUNT, Call:MID_GETOBJECT, Local:no
BESERVER: [11/17/14 15:28:20] [4744]     -1 PRINO Start(101125): Object:MD_OBJTYPE_OWNERSHIP, Call:MID_QUERYOBJECTS, Local:yes
BESERVER: [11/17/14 15:28:20] [4744]     -1 SQLLog(101126):CBESession::CBESession(0x00000000163279B0): TransactionID:00000000-0000-0000-0000-000000000000.  m_lRefCount:1
BESERVER: [11/17/14 15:28:20] [4744]     -1 SQLLog(101127):CSQLCommand::Execute sql=SELECT [OwnershipID],[OwnershipName],[DefaultLoginAccountID],[FKey],[UserSID],[TimeStamp]  FROM  [DBO].[Ownership] a1  WHERE [UserSID]=? hr=000000 m_QueryTimeoutInSeconds=30
BESERVER: [11/17/14 15:28:20] [4744]     -1 SQLLog(101128):CBESession::Release(0x00000000163279B0): TransactionID:00000000-0000-0000-0000-000000000000.  Before decrement m_lRefCount:1
BESERVER: [11/17/14 15:28:20] [4744]     -1 SQLLog(101129):CBESession::~CBESession(0x00000000163279B0): TransactionID:00000000-0000-0000-0000-000000000000.  m_lRefCount:0
BESERVER: [11/17/14 15:28:20] [4744]     -1 PRINO End(101125): Time:0, nRet=000000
BESERVER: [11/17/14 15:28:20] [4744]     -1 SQLLog(101130):CBESession::CBESession(0x000000000D84ABC0): TransactionID:00000000-0000-0000-0000-000000000000.  m_lRefCount:1
BESERVER: [11/17/14 15:28:20] [4744]     -1 SQLLog(101131):CSQLCommand::Execute sql=SELECT [ID],[AccountName],[AccountPassword],[Description],[OwnershipID],[CommonOrPrivate],[Notes],[FKey],[IsPasswordNull],[DjmType],[IsHidden],[TimeStamp]  FROM  [DBO].[LoginAccounts] a1  WHERE [ID]=? hr=000000 m_QueryTimeoutInSeconds=30
BESERVER: [11/17/14 15:28:20] [4744]     -1 SQLLog(101132):CBESession::Release(0x000000000D84ABC0): TransactionID:00000000-0000-0000-0000-000000000000.  Before decrement m_lRefCount:1
BESERVER: [11/17/14 15:28:20] [4744]     -1 SQLLog(101133):CBESession::~CBESession(0x000000000D84ABC0): TransactionID:00000000-0000-0000-0000-000000000000.  m_lRefCount:0
BESERVER: [11/17/14 15:28:20] [4744]     -1 PRINO Start(101134): Object:MD_OBJTYPE_OWNERSHIP, Call:MID_GETOBJECT, Local:yes
BESERVER: [11/17/14 15:28:20] [4744]     -1 SQLLog(101135):CBESession::CBESession(0x000000000D84ABC0): TransactionID:00000000-0000-0000-0000-000000000000.  m_lRefCount:1
BESERVER: [11/17/14 15:28:20] [4744]     -1 SQLLog(101136):CSQLCommand::Execute sql=SELECT [OwnershipID],[OwnershipName],[DefaultLoginAccountID],[FKey],[UserSID],[TimeStamp]  FROM  [DBO].[Ownership] a1  WHERE [OwnershipID]=? hr=000000 m_QueryTimeoutInSeconds=30
BESERVER: [11/17/14 15:28:20] [4744]     -1 SQLLog(101137):CBESession::Release(0x000000000D84ABC0): TransactionID:00000000-0000-0000-0000-000000000000.  Before decrement m_lRefCount:1
BESERVER: [11/17/14 15:28:20] [4744]     -1 SQLLog(101138):CBESession::~CBESession(0x000000000D84ABC0): TransactionID:00000000-0000-0000-0000-000000000000.  m_lRefCount:0
BESERVER: [11/17/14 15:28:20] [4744]     -1 PRINO End(101134): Time:0, nRet=000000
BESERVER: [11/17/14 15:28:20] [4744]     -1 PRINO End(101124): Time:0, nRet=000000
BEREMOTE: [11/17/14 15:28:20] [1428]     [fsys\shared]        - FS_ResolveDevName: [\\Server2.DOMAIN.COM]
BEREMOTE: [11/17/14 15:28:20] [1428]     [fsys\mb2]           - MB2-Resolve returned 1
BEREMOTE: [11/17/14 15:28:20] [1428]     [fsys\notes2]        - Notes Agent:CLNFileSystem::ResolveDeviceName
BEREMOTE: [11/17/14 15:28:20] [1428]     [fsys\adc]           - ADC_ResolveDeviceName: pid = 6240, checking \\Server2.DOMAIN.COM
BEREMOTE: [11/17/14 15:28:20] [1428]     [beutil]             - ApplyRegExp(): Invalid input (\\Server2.DOMAIN.COM). Parsing Failed.
BEREMOTE: [11/17/14 15:28:20] [1428]     [beutil]             - GoodEvName(): Invalid EV device (\\Server2.DOMAIN.COM).
BEREMOTE: [11/17/14 15:28:20] [1428]     [beutil]             - ApplyRegExp(): Invalid input (\\Server2.DOMAIN.COM). Parsing Failed.
BEREMOTE: [11/17/14 15:28:20] [1428]     [beutil]             - GoodEvName(): Invalid EV device (\\Server2.DOMAIN.COM).
BEREMOTE: [11/17/14 15:28:20] [1428]     [beutil]             - Input Error (e000fe23) for Type: (43)
BEREMOTE: [11/17/14 15:28:20] [1428]     [fsys\ev]            - EVM_ResolveDeviceName: Function Enter
BEREMOTE: [11/17/14 15:28:20] [1428]     [beutil]             - ApplyRegExp(): Invalid input (\\Server2.DOMAIN.COM). Parsing Failed.
BEREMOTE: [11/17/14 15:28:20] [1428]     [fsys\ev]            - EVM_ResolveDeviceName: Invalid device name (\\Server2.DOMAIN.COM). Parsing Failed.
BEREMOTE: [11/17/14 15:28:20] [1428]     [fsys\ev]            - EVM_ResolveDeviceName: Function Exit
BEREMOTE: [11/17/14 15:28:20] [1428]     [fsys\ntfs]          - Not valid device name : \\Server2.DOMAIN.COM
BEREMOTE: [11/17/14 15:28:20] [1428]     [dsss\rpc]           - RPCDsSession::GetLoginCredentials: Connecting to BEM API as user: DOMAIN\administrator
BESERVER: [11/17/14 15:28:20] [4744]     -1 Client requested key (1416256271).
BESERVER: [11/17/14 15:28:20] [4744]     "Cluster" key does not appear to be present in the registry
BESERVER: [11/17/14 15:28:20] [4744]     Failed to open Microsoft cluster ()
BESERVER: [11/17/14 15:28:20] [4744]     VCS cluster keys do not appear to be present in the registry
BESERVER: [11/17/14 15:28:20] [4744]     Failed to open VCS cluster ()
BESERVER: [11/17/14 15:28:20] [4744]     -1 PRINO Start(101139): Object:MD_OBJTYPE_OWNERSHIP, Call:MID_QUERYOBJECTS, Local:yes
BESERVER: [11/17/14 15:28:20] [4744]     -1 SQLLog(101140):CBESession::CBESession(0x00000000163279B0): TransactionID:00000000-0000-0000-0000-000000000000.  m_lRefCount:1
BESERVER: [11/17/14 15:28:20] [4744]     -1 SQLLog(101141):CSQLCommand::Execute sql=SELECT [OwnershipID],[OwnershipName],[DefaultLoginAccountID],[FKey],[UserSID],[TimeStamp]  FROM  [DBO].[Ownership] a1  WHERE [UserSID]=? hr=000000 m_QueryTimeoutInSeconds=30
BESERVER: [11/17/14 15:28:20] [4744]     -1 SQLLog(101142):CBESession::Release(0x00000000163279B0): TransactionID:00000000-0000-0000-0000-000000000000.  Before decrement m_lRefCount:1
BESERVER: [11/17/14 15:28:20] [4744]     -1 SQLLog(101143):CBESession::~CBESession(0x00000000163279B0): TransactionID:00000000-0000-0000-0000-000000000000.  m_lRefCount:0
BESERVER: [11/17/14 15:28:20] [4744]     -1 PRINO End(101139): Time:15, nRet=000000
BESERVER: [11/17/14 15:28:20] [4744]     01 Server Configuration: Client added: 9
BESERVER: [11/17/14 15:28:20] [4744]     -1 Client 'Server1' connected('','DOMAIN\administrator'): 0x1618bb90
BESERVER: [11/17/14 15:28:20] [4744]     -1 PRINO Start(101144): Object:MD_OBJTYPE_LOGINACCOUNT, Call:MID_GETOBJECT, Local:no
BESERVER: [11/17/14 15:28:20] [4744]     -1 SQLLog(101145):CBESession::CBESession(0x000000000D84ABC0): TransactionID:00000000-0000-0000-0000-000000000000.  m_lRefCount:1
BESERVER: [11/17/14 15:28:20] [4744]     -1 SQLLog(101146):CSQLCommand::Execute sql=SELECT [ID],[AccountName],[AccountPassword],[Description],[OwnershipID],[CommonOrPrivate],[Notes],[FKey],[IsPasswordNull],[DjmType],[IsHidden],[TimeStamp]  FROM  [DBO].[LoginAccounts] a1  WHERE [ID]=? hr=000000 m_QueryTimeoutInSeconds=30
BESERVER: [11/17/14 15:28:20] [4744]     -1 SQLLog(101147):CBESession::Release(0x000000000D84ABC0): TransactionID:00000000-0000-0000-0000-000000000000.  Before decrement m_lRefCount:1
BESERVER: [11/17/14 15:28:20] [4744]     -1 SQLLog(101148):CBESession::~CBESession(0x000000000D84ABC0): TransactionID:00000000-0000-0000-0000-000000000000.  m_lRefCount:0
BESERVER: [11/17/14 15:28:20] [4744]     -1 PRINO Start(101149): Object:MD_OBJTYPE_OWNERSHIP, Call:MID_GETOBJECT, Local:yes
BESERVER: [11/17/14 15:28:20] [4744]     -1 SQLLog(101150):CBESession::CBESession(0x000000000D84ABC0): TransactionID:00000000-0000-0000-0000-000000000000.  m_lRefCount:1
BESERVER: [11/17/14 15:28:20] [4744]     -1 SQLLog(101151):CSQLCommand::Execute sql=SELECT [OwnershipID],[OwnershipName],[DefaultLoginAccountID],[FKey],[UserSID],[TimeStamp]  FROM  [DBO].[Ownership] a1  WHERE [OwnershipID]=? hr=000000 m_QueryTimeoutInSeconds=30
BESERVER: [11/17/14 15:28:20] [4744]     -1 SQLLog(101152):CBESession::Release(0x000000000D84ABC0): TransactionID:00000000-0000-0000-0000-000000000000.  Before decrement m_lRefCount:1
BESERVER: [11/17/14 15:28:20] [4744]     -1 SQLLog(101153):CBESession::~CBESession(0x000000000D84ABC0): TransactionID:00000000-0000-0000-0000-000000000000.  m_lRefCount:0
BESERVER: [11/17/14 15:28:20] [4744]     -1 PRINO End(101149): Time:0, nRet=000000
BESERVER: [11/17/14 15:28:20] [4744]     -1 PRINO End(101144): Time:0, nRet=000000
BESERVER: [11/17/14 15:28:20] [4744]     00 VSN EndWait: Server1
BESERVER: [11/17/14 15:28:20] [4744]     01 Server Configuration: Client removed: 8
BESERVER: [11/17/14 15:28:20] [8040]     -1 PRINO End(0): Time:9750, nRet=000000
BESERVER: [11/17/14 15:28:20] [4744]     -1 Client 'Server1' Disconnected:0x1618bb90
BESERVER: [11/17/14 15:28:20] [5824]     -1 PRINO End(0): Time:9750, nRet=000000
BESERVER: [11/17/14 15:28:20] [5824]     -1 PRINO Start(101154): Object:MD_OBJTYPE_VSNMONITOR, Call:10001, Local:yes
BESERVER: [11/17/14 15:28:20] [5824]     00 VSN Wait: Local
BESERVER: [11/17/14 15:28:20] [2548]     -1 PRINO End(0): Time:9750, nRet=000000
BESERVER: [11/17/14 15:28:20] [1268]     -1 PRINO End(0): Time:9750, nRet=000000
BESERVER: [11/17/14 15:28:20] [2548]     -1 PRINO Start(101155): Object:MD_OBJTYPE_VSNMONITOR, Call:10001, Local:no
BESERVER: [11/17/14 15:28:20] [2548]     00 VSN Wait: Server1
BESERVER: [11/17/14 15:28:20] [2548]     00 VSN Wait: Server1
BESERVER: [11/17/14 15:28:20] [1268]     -1 PRINO Start(101156): Object:MD_OBJTYPE_VSNMONITOR, Call:10001, Local:no
BESERVER: [11/17/14 15:28:20] [1268]     00 VSN Wait: Server1
BESERVER: [11/17/14 15:28:20] [4744]     -1 PRINO Start(101157): Object:MD_OBJTYPE_VSNMONITOR, Call:10001, Local:no
BESERVER: [11/17/14 15:28:20] [4744]     00 VSN Wait: Server1
BESERVER: [11/17/14 15:28:20] [4744]     00 VSN Wait: Server1
BEREMOTE: [11/17/14 15:28:20] [1428]     [ndmp\ndmpcomm]      - Could not resolve the "bews-ndmp" or the "ndmp" service, error code: 10109, using port 10000
BEREMOTE: [11/17/14 15:28:20] [1428]     "Cluster" key does not appear to be present in the registry
BEREMOTE: [11/17/14 15:28:20] [1428]     Failed to open Microsoft cluster ()
BEREMOTE: [11/17/14 15:28:20] [1428]     VCS cluster keys do not appear to be present in the registry
BEREMOTE: [11/17/14 15:28:20] [1428]     Failed to open VCS cluster ()
BEREMOTE: [11/17/14 15:28:20] [1428]     [ndmp\ndmpcomm]      - ndmpConnectEx: Querying the neighbour advertisement cache to discover information on 'Server2.DOMAIN.COM' ...
BENETNS:  [11/17/14 15:28:20] [7824]     NRDS API - client connected.
BENETNS:  [11/17/14 15:28:20] [7824]     Connecting to BE Database.
BESERVER: [11/17/14 15:28:20] [8040]     -1 Client requested key (1416256271).
BESERVER: [11/17/14 15:28:20] [8040]     -1 PRINO Start(101158): Object:MD_OBJTYPE_OWNERSHIP, Call:MID_QUERYOBJECTS, Local:yes
BESERVER: [11/17/14 15:28:20] [8040]     -1 SQLLog(101159):CBESession::CBESession(0x000000000D815D50): TransactionID:00000000-0000-0000-0000-000000000000.  m_lRefCount:1
BESERVER: [11/17/14 15:28:20] [8040]     -1 SQLLog(101160):CSQLCommand::Execute sql=SELECT [OwnershipID],[OwnershipName],[DefaultLoginAccountID],[FKey],[UserSID],[TimeStamp]  FROM  [DBO].[Ownership] a1  WHERE [UserSID]=? hr=000000 m_QueryTimeoutInSeconds=30
BESERVER: [11/17/14 15:28:20] [8040]     -1 SQLLog(101161):CBESession::Release(0x000000000D815D50): TransactionID:00000000-0000-0000-0000-000000000000.  Before decrement m_lRefCount:1
BESERVER: [11/17/14 15:28:20] [8040]     -1 SQLLog(101162):CBESession::~CBESession(0x000000000D815D50): TransactionID:00000000-0000-0000-0000-000000000000.  m_lRefCount:0
BESERVER: [11/17/14 15:28:20] [8040]     -1 PRINO End(101158): Time:0, nRet=000000
BESERVER: [11/17/14 15:28:20] [8040]     01 Server Configuration: Client added: 9
BESERVER: [11/17/14 15:28:20] [8040]     -1 Client 'Server1' connected('','DOMAIN\BackupAdmin'): 0xd847990
BENETNS:  [11/17/14 15:28:20] [7824]     Successfully connected to BE Database.
BENETNS:  [11/17/14 15:28:20] [7824]     Reading agent database record for Server2.DOMAIN.COM.
BESERVER: [11/17/14 15:28:20] [8040]     -1 PRINO Start(101163): Object:MD_OBJTYPE_NRDS_AGENT, Call:MID_GETOBJECT, Local:no
BESERVER: [11/17/14 15:28:20] [8040]     31 CNrdsBO: Get Agent
BESERVER: [11/17/14 15:28:20] [8040]     -1 SQLLog(101164):CBESession::CBESession(0x000000000D815D50): TransactionID:00000000-0000-0000-0000-000000000000.  m_lRefCount:1
BESERVER: [11/17/14 15:28:20] [8040]     -1 SQLLog(101165):CSQLCommand::Execute sql=SELECT [NrdsAgentKey],[IsOwnedAgent],[DLEInfoData],[PlatformData],[MiscData],[Heartbeat],[Tombstone],[ResModified],[IsResClockChanged],[ServerModified],[ServerName],[AgentName],[NetAddress],[XmlData]  FROM  [DBO].[NrdsAgent] a1  WHERE [NrdsAgentKey]=? hr=000000 m_QueryTimeoutInSeconds=30
BESERVER: [11/17/14 15:28:20] [8040]     -1 SQLLog(101166):CBESession::Release(0x000000000D815D50): TransactionID:00000000-0000-0000-0000-000000000000.  Before decrement m_lRefCount:1
BESERVER: [11/17/14 15:28:20] [8040]     -1 SQLLog(101167):CBESession::~CBESession(0x000000000D815D50): TransactionID:00000000-0000-0000-0000-000000000000.  m_lRefCount:0
BESERVER: [11/17/14 15:28:20] [8040]     31 CNrdsBO: CopyNrdsAgentRecord AgentName=Server2.DOMAIN.COM NrdsAgentKey=2 XmlData:

    	<Computer><FQDN>Server2.DOMAIN.COM</FQDN><NetBIOS>Server2</NetBIOS><ClusterResource>0</ClusterResource><osDescription>Microsoft Windows Server 2008 R2 Standard</osDescription><osServicePackDescription>Service Pack 1</osServicePackDescription><osVersion>6.1</osVersion><osBuild>7601</osBuild><osPlatform>2</osPlatform><osServicePackVersion>1.0</osServicePackVersion><osSuiteMask>272</osSuiteMask><osProductType>2</osProductType><osProductInfo>7</osProductInfo><osServerR2>0</osServerR2><osMediaCenter>0</osMediaCenter><osStarter>0</osStarter><osTabletPC>0</osTabletPC><ProcessorArchitecture>9</ProcessorArchitecture><ProcessorType>8664</ProcessorType><ProcessorCount>8</ProcessorCount><volumes><volume><id>\\?\Volume{3363f1bd-781c-11e0-ae6e-806e6f6e6963}\</id><mountPoints/><Clustered>0</Clustered><ClusterSharedVolume>0</ClusterSharedVolume><type>3</type><device>\Device\HarddiskVolume1</device><label>System Reserved</label><fileSystem>NTFS</fileSystem><free>75362304</free><size>104857600</size></volume><volume><id>\\?\Volume{3363f1be-781c-11e0-ae6e-806e6f6e6963}\</id><mountPoints><mountPoint>C:\</mountPoint></mountPoints><Clustered>0</Clustered><ClusterSharedVolume>0</ClusterSharedVolume><type>3</type><device>\Device\HarddiskVolume2</device><label/><fileSystem>NTFS</fileSystem><free>232710901760</free><size>318191435776</size></volume><volume><id>\\?\Volume{502cda33-7c99-11e0-8a00-842b2b080f90}\</id><mountPoints><mountPoint>D:\</mountPoint></mountPoints><Clustered>0</Clustered><ClusterSharedVolume>0</ClusterSharedVolume><type>3</type><device>\Device\HarddiskVolume3</device><label>Data</label><fileSystem>NTFS</fileSystem><free>306939105280</free><size>629145600000</size></volume><volume><id>\\?\Volume{11164b00-c927-11e0-8b22-806e6f6e6963}\</id><mountPoints><mountPoint>E:\</mountPoint></mountPoints><Clustered>0</Clustered><ClusterSharedVolume>0</ClusterSharedVolume><type>2</type><device>\Device\HarddiskVolume4</device></volume><volume><id>\\?\Volume{3363f1c1-781c-11e0-ae6e-806e6f6e6963}\</id><mountPoints><mountPoint>F:\</mountPoint></mountPoints><Clustered>0</Clustered><ClusterSharedVolume>0</ClusterSharedVolume><type>5</type><device>\Device\CdRom0</device></volume></volumes><Applications><EnterpriseVault><EVVersion/><CAVersion/><DAVersion/></EnterpriseVault></Applications><beVersion>13.0.5204.1270</beVersion><bePatchList><bePatch>{520E22FC-264F-42C7-80A7-3FC12303ED77}</bePatch><bePatch>{443FD682-5373-40C4-A97B-BEDBBE96E077}</bePatch><bePatch>{772FBAFB-79EA-4BD1-8CA7-2D17CAB28C59}</bePatch></bePatchList><beRunning64Bit>1</beRunning64Bit><AdvertiseSecure>1</AdvertiseSecure><AdvertiseGMT>1416255905</AdvertiseGMT><AdvertiseGMTText>Mon Nov 17 20:25:05 2014</AdvertiseGMTText></Computer>
BESERVER: [11/17/14 15:28:20] [8040]     -1 PRINO End(101163): Time:16, nRet=000000
BENETNS:  [11/17/14 15:28:20] [7824]     Found agent record 2 for Server2.DOMAIN.COM.
BESERVER: [11/17/14 15:28:20] [8040]     00 VSN EndWait: Server1
BESERVER: [11/17/14 15:28:20] [8040]     01 Server Configuration: Client removed: 8
BESERVER: [11/17/14 15:28:20] [5824]     -1 PRINO End(101154): Time:16, nRet=000000
BESERVER: [11/17/14 15:28:20] [4744]     -1 PRINO End(101157): Time:16, nRet=000000
BESERVER: [11/17/14 15:28:20] [1268]     -1 PRINO End(101156): Time:16, nRet=000000
BESERVER: [11/17/14 15:28:20] [2548]     -1 PRINO End(101155): Time:16, nRet=000000
BESERVER: [11/17/14 15:28:20] [8040]     -1 Client 'Server1' Disconnected:0xd847990
BESERVER: [11/17/14 15:28:20] [5824]     -1 PRINO Start(101168): Object:MD_OBJTYPE_VSNMONITOR, Call:10001, Local:yes
BESERVER: [11/17/14 15:28:20] [4744]     -1 PRINO Start(101169): Object:MD_OBJTYPE_VSNMONITOR, Call:10001, Local:no
BENETNS:  [11/17/14 15:28:20] [7824]     Disconnected from BE Database.
BESERVER: [11/17/14 15:28:20] [2548]     -1 PRINO Start(101171): Object:MD_OBJTYPE_VSNMONITOR, Call:10001, Local:no
BESERVER: [11/17/14 15:28:20] [5824]     00 VSN Wait: Local
BENETNS:  [11/17/14 15:28:20] [7824]     NRDS API - client disconnected.
BESERVER: [11/17/14 15:28:20] [1268]     -1 PRINO Start(101170): Object:MD_OBJTYPE_VSNMONITOR, Call:10001, Local:no
BESERVER: [11/17/14 15:28:20] [4744]     00 VSN Wait: Server1
BESERVER: [11/17/14 15:28:20] [2548]     00 VSN Wait: Server1
BESERVER: [11/17/14 15:28:20] [1268]     00 VSN Wait: Server1
BESERVER: [11/17/14 15:28:20] [4744]     00 VSN Wait: Server1
BESERVER: [11/17/14 15:28:20] [2548]     00 VSN Wait: Server1
BEREMOTE: [11/17/14 15:28:20] [1428]     [ndmp\ndmpcomm]      - ndmpConnectEx : Control Connection information: A connection was established between end-points 172.30.1.52:54289 and 172.30.1.51:10000.
BESERVER: [11/17/14 15:28:21] [7188]     16 JobManager: DoProcessingCycle starts
BESERVER: [11/17/14 15:28:21] [7188]     16 JobManager: ProcessScheduleList starts
BESERVER: [11/17/14 15:28:21] [7188]     -1 EvaluateSchedule(Server1 Monthly Full Backup (First Friday)) dtNowUTC=41960.853021, dtNextDueDate=41979.166667, dtStartWindow=41960.166667, dtEndWindow=41961.166713
BESERVER: [11/17/14 15:28:21] [7188]     -1 EvaluateSchedule(Server1 Daily Incrimental Backup (Mon-Thurs)) dtNowUTC=41960.853021, dtNextDueDate=41961.166667, dtStartWindow=41960.166667, dtEndWindow=41961.166713
BESERVER: [11/17/14 15:28:21] [7188]     -1 EvaluateSchedule(Server1 Weekly Full Backup (2nd-5th Friday)) dtNowUTC=41960.853021, dtNextDueDate=41965.166667, dtStartWindow=41960.166667, dtEndWindow=41961.166713
BESERVER: [11/17/14 15:28:21] [7188]     16 JobManager: ProcessScheduleList ends
BESERVER: [11/17/14 15:28:21] [7188]     16 JobManager: DispatchReadyTasks starts
BESERVER: [11/17/14 15:28:21] [7188]     -1 Dispatcher::doProcess( ) no ready jobs
BESERVER: [11/17/14 15:28:21] [7188]     16 JobManager: DispatchReadyTasks ends
BESERVER: [11/17/14 15:28:21] [7188]     16 JobManager: AbortTimedJobs starts
BESERVER: [11/17/14 15:28:21] [7188]     16 JobManager: AbortTimedJobs ends
BESERVER: [11/17/14 15:28:21] [7188]     16 JobManager: CheckForDeadJobs starts
BESERVER: [11/17/14 15:28:21] [7188]     16 JobManager: CheckForDeadJobs ends
BESERVER: [11/17/14 15:28:21] [7188]     -1 CJobManagerBO::CheckCPSNotificationMonitor() Checking if CPS is installed
BESERVER: [11/17/14 15:28:21] [7188]     -1 CJobManagerBO::CheckCPSNotificationMonitor() Checking if CPS is installed
BESERVER: [11/17/14 15:28:21] [7188]     -1 Event: Global\RxService CREATED
BESERVER: [11/17/14 15:28:21] [7188]     16 JobManager: FlushCache starts
BESERVER: [11/17/14 15:28:21] [7188]     16 JobManager: FlushCache ends
BESERVER: [11/17/14 15:28:21] [7188]     16 JobManager: DoProcessingCycle, elapsed time=3 msec

 

I'm not sure how to set the verbosity on the agent side. If that's important to see, perhaps someone could direct me to some instructions on setting that up.

0 Kudos

Go to solution
SuperBeta
Level 3

‎11-18-2014 08:23 AM

Just found my solution! Couldn't have done this without the help of the people on this site. Thanks so much for taking the time!

The solution:
On the client server, I was running Cerberus FTP which happens to use port 10000 for it's remote administration connection. As you may know, RAWS uses port 10000 for communication as well. Newer versions of this ftp software seems to use port 10001, but I'm not 100% certain this is always the case. It may check during installation if another application is using port 10000 and choose a different port. In this case, I had installed Cerberus first, and then added the RAWS client later, so if there was a port check, it didn't happen. It seems very clear that the Symantec product doesn't do any checks of this type. After I changed which port Cerberus was using, the RAWS agent started working and I could expand my selection list for that server without issue. 

https://www.cerberusftp.com/support/help/remote_settings.html

This simple issue has taken quite a bit of my time to figure out. It was an easy fix, but it would have gone a lot faster if I would have taken the time to list all of the services and 3rd party software I had running on the server and looked at those earlier on in the process. 

 

 

0 Kudos