Video Screencast Help

Issue with backup of Isilon NAS with Backup Exec 2010 R3. NDMP Option used.

Created: 08 Sep 2011 • Updated: 08 Sep 2011 | 2 comments
Scotte76's picture

Hi All,

 

I have been trying to back up our Isilon NAS node with the NDMP option on BE 2010R3. It connects and runs for about 60 GBS of data. This is backing up to a Disk array on the BE Media server.

 

I then get an error Media server has lost the network connection to the remote agent.

 

Here is the SGmon.exe log from Backup exec.

 

BENETNS:  [07/09/11 10:04:53] [4444]     Beginning agent database grooming.
BENETNS:  [07/09/11 10:04:53] [4444]     Agent database grooming complete.
BENGINE:  [07/09/11 10:05:00] [5592]     [loops]              - NDMP File History Notification: Add File
BENGINE:  [07/09/11 10:05:00] [5592]     [loops]              - Received fh_add_file request: 398 entries.
BENGINE:  [07/09/11 10:05:02] [5592]     [loops]              - NDMP File History Notification: Add File
BENGINE:  [07/09/11 10:05:02] [5592]     [loops]              - Received fh_add_file request: 389 entries.
BENGINE:  [07/09/11 10:05:14] [5592]     [ndmp\ndmpcomm]      - ndmp_readit: Caught message on closed connection. Socket 0x75c len 0x0
BENGINE:  [07/09/11 10:05:14] [5592]     [ndmp\ndmpcomm]      - ndmp_readit: ErrorCode :: 0 :
BENGINE:  [07/09/11 10:05:14] [5592]     [ndmp]               - NDMPServerRequester::PollNDMPServer: NDMP control connection lost.
BENGINE:  [07/09/11 10:05:14] [5592]     [tpfmt]              - BEWSNdmpMover: MoverAbort called.
BENGINE:  [07/09/11 10:05:14] [4752]     [tpfmt]              - BEWSNdmpMover: Mover aborted.
BENGINE:  [07/09/11 10:05:14] [4752]     [tpfmt]              - BEWSNdmpMover: Mover halting. ndmpError = 0, internal_error = 0, mover_halt_reason = ABORTED
BENGINE:  [07/09/11 10:05:14] [4752]     - BEWSNdmpMover: Switching state ACTIVE ==> HALTED
BENGINE:  [07/09/11 10:05:14] [4752]     [tpfmt]              - BEWSNdmpMover: Mover halted.
BENGINE:  [07/09/11 10:05:14] [5592]     [loops]              - NDMP Log Message: Mover: Received abort.
BENGINE:  [07/09/11 10:05:14] [5592]     [loops]              - NDMP Notify Mover halted: Aborted.
BENGINE:  [07/09/11 10:05:14] [5592]     [tpfmt]              - BEWSNdmpMover:MoverGetState called.
BENGINE:  [07/09/11 10:05:14] [5592]     [tpfmt]              - ===   Mover State (partial dump)   ===
BENGINE:  [07/09/11 10:05:14] [5592]     [tpfmt]              -  mode          : READ
BENGINE:  [07/09/11 10:05:14] [5592]     [tpfmt]              -  state         : HALTED
BENGINE:  [07/09/11 10:05:14] [5592]     [tpfmt]              -  pause_reason  : N/A
BENGINE:  [07/09/11 10:05:14] [5592]     [tpfmt]              -  halt_reason   : ABORTED
BENGINE:  [07/09/11 10:05:14] [5592]     [tpfmt]              -  bytes_moved   : 63371608064
BENGINE:  [07/09/11 10:05:14] [5592]     [tpfmt]              -  record_size   : 65536
BENGINE:  [07/09/11 10:05:14] [5592]     [tpfmt]              -  record_num    : 966974
BENGINE:  [07/09/11 10:05:14] [5592]     [tpfmt]              -  window_offset : 0
BENGINE:  [07/09/11 10:05:14] [5592]     [tpfmt]              -  window_length : 18446744073709551615
BENGINE:  [07/09/11 10:05:14] [5592]     [tpfmt]              - ===   END   ===
BENGINE:  [07/09/11 10:05:14] [5592]     [loops]              - NASBackupBSDProcessor:UpdateCatalogFragSize: Bytes moved this tape = 63371608064
BENGINE:  [07/09/11 10:05:14] [5592]     [ndmp\ndmpcomm]      - ndmp_readit: Caught message on closed connection. Socket 0x75c len 0x0
BENGINE:  [07/09/11 10:05:14] [5592]     [ndmp\ndmpcomm]      - ndmp_readit: ErrorCode :: 0 :
BENGINE:  [07/09/11 10:05:14] [5592]     [ndmp\ndmpcomm]      - ndmp_readit: Caught message on closed connection. Socket 0x75c len 0xffffffff
BENGINE:  [07/09/11 10:05:14] [5592]     [ndmp\ndmpcomm]      - ndmp_readit: ErrorCode :: 10053 : An established connection was aborted by the software in your host machine.
BENGINE:  [07/09/11 10:05:14] [5592]     [ndmp\ndmpcomm]      - ERROR: ndmpcSendRequest->connection error
BENGINE:  [07/09/11 10:05:14] [5592]     [ndmp\ndmpcomm]      - ERROR: ndmpSendRequest failed:
BENGINE:  [07/09/11 10:05:14] [5592]     [ndmp\ndmpcomm]      - ndmp_writeit: NDMP Socket Error when trying to write message. Socket 0x75c len 0x1c
BENGINE:  [07/09/11 10:05:14] [5592]     [ndmp\ndmpcomm]      - ndmp_writeit: ErrorCode :: 10053 : An established connection was aborted by the software in your host machine.
BENGINE:  [07/09/11 10:05:14] [5592]     [ndmp\ndmpcomm]      - ndmp_readit: Caught message on closed connection. Socket 0x75c len 0xffffffff
BENGINE:  [07/09/11 10:05:14] [5592]     [ndmp\ndmpcomm]      - ndmp_readit: ErrorCode :: 10053 : An established connection was aborted by the software in your host machine.
BENGINE:  [07/09/11 10:05:14] [5592]     [ndmp\ndmpcomm]      - ndmp_readit: Caught message on closed connection. Socket 0x75c len 0xffffffff
BENGINE:  [07/09/11 10:05:14] [5592]     [ndmp\ndmpcomm]      - ndmp_readit: ErrorCode :: 10053 : An established connection was aborted by the software in your host machine.
BENGINE:  [07/09/11 10:05:14] [5592]     [ndmp\ndmpcomm]      - ERROR: ndmpcSendRequest->connection error
BENGINE:  [07/09/11 10:05:14] [5592]     [ndmp\ndmpcomm]      - ERROR: ndmpSendRequest failed:
BENGINE:  [07/09/11 10:05:14] [5592]     - BEWSNdmpMover: Switching state HALTED ==> IDLE
BENGINE:  [07/09/11 10:05:14] [5592]     [server]             - BackupMessageHandlerForBackupJob::process( eng_msgs::close_catalog & msg ) called
BENGINE:  [07/09/11 10:05:14] [5592]     [server]             - Not: 'm_bCatalogDrivenBackup && CatBuildVector[engine_id] && GetCarryFwdCatalogInfoForRemainingObjs( )'
BENGINE:  [07/09/11 10:05:14] [5592]     [server]             - called CatBuildVector[engine_id]->GetErrorCondition() successfully
BENGINE:  [07/09/11 10:05:14] [5592]     [server]             - BackupMessageHandler::CDB_EndCurrentCatalogOperation(  ) -- entered
BENGINE:  [07/09/11 10:05:14] [5592]     [server]             - BackupMessageHandler::CDB_EndCurrentCatalogOperation(  ) is calling PerBSDDataVector[engine_id]->EndCatOperation( )
BENGINE:  [07/09/11 10:05:14] [5592]     [server]             - EndCatOperation succeeded
BENGINE:  [07/09/11 10:05:14] [5592]     [server]             - BackupMessageHandler::CDB_EndCurrentCatalogOperation(  ) -- returning
BENGINE:  [07/09/11 10:05:14] [5592]     [server]             - calling CatBuildVector[engine_id]->FinishBackup( ) for non-cat drive backup
BENGINE:  [07/09/11 10:05:16] [5592]     [tpfmt]              - WriteEndSet( 1 ) returning 0x00000000
BENGINE:  [07/09/11 10:05:18] [5592]     [tpfmt]              - WriteEndSet( 1 ) returning 0x00000000
BENGINE:  [07/09/11 10:05:18] [5592]     [tpfmt]              - WriteEndSet( 0 ) returning 0x00000000
PVLSVR:   [07/09/11 10:05:18] [4744]     PvlEntityDatabase::QueryEntity()

            ParentEntityType = NULL_ENTITY_TYPE

            ParentEntityGuid = {00000000-0000-0000-0000-000000000000}

            EntityType = MEDIA_ENTITY_TYPE

            EntityGuid = {DD3C4D21-63E7-468B-890E-530A96684EA1}

            EntityName = ""

            EntityFlags = 0x00000000
PVLSVR:   [07/09/11 10:05:18] [4744]     PvlEntityDatabase::QueryEntity() : SUCCESS!
PVLSVR:   [07/09/11 10:05:18] [0552]     PvlEntityDatabase::UpdateEntity()

            ParentEntityType = NULL_ENTITY_TYPE

            ParentEntityGuid = {00000000-0000-0000-0000-000000000000}

            EntityType = MEDIA_ENTITY_TYPE

            Media = {DD3C4D21-63E7-468B-890E-530A96684EA1}, "B00496"

            EntityFlags = 0x00830000
PVLSVR:   [07/09/11 10:05:18] [0552]     PvlEntityDatabase::UpdateEntity() : SUCCESS!
BENGINE:  [07/09/11 10:05:18] [5592]     [tpfmt]              - HARDWARE COMPRESSION ===> Setting compression off.
BENGINE:  [07/09/11 10:05:18] [5592]     [tpfmt]              - TF_CloseSet
BENGINE:  [07/09/11 10:05:18] [5592]     [tpfmt]              - BEWSNdmpTapeServer : Destroying Mover object (0x166E1AF0).
BENGINE:  [07/09/11 10:05:18] [5592]     [ndmp\ndmpcomm]      - ndmp_writeit: NDMP Socket Error when trying to write message. Socket 0x75c len 0x34
BENGINE:  [07/09/11 10:05:18] [5592]     [ndmp\ndmpcomm]      - ndmp_writeit: ErrorCode :: 10053 : An established connection was aborted by the software in your host machine.
PVLSVR:   [07/09/11 10:05:18] [0552]     AdammSession::Execute( ADAMM_SESSION_EXECUTE_MOVER_RELEASE )

            Session = {BA4D5B16-930C-4F38-928B-3678889B1E4D}
BENGINE:  [07/09/11 10:05:18] [5592]     [tpfmt]              - Setting rewind flag to true for older device.  This will likely degrade the performance of the current job.
BESERVER: [07/09/11 10:05:20] [0996]     05 AdammAdminBO::Execute() - ADAMM_ADMIN_MEDIA_SUMMARY.
BESERVER: [07/09/11 10:05:20] [0996]     "Cluster" key does not appear to be present in the registry
BESERVER: [07/09/11 10:05:20] [0996]     Failed to open Microsoft cluster ()
BESERVER: [07/09/11 10:05:20] [0996]     VCS cluster keys do not appear to be present in the registry
BESERVER: [07/09/11 10:05:20] [0996]     Failed to open VCS cluster ()
PVLSVR:   [07/09/11 10:05:20] [4744]     PvlEntityDatabase::QueryEntity()

            ParentEntityType = NULL_ENTITY_TYPE

            ParentEntityGuid = {00000000-0000-0000-0000-000000000000}

            EntityType = 0031

            EntityGuid = {00000000-0000-0000-0000-000000000000}

            EntityName = "

            EntityFlags = 0x00000000
BESERVER: [07/09/11 10:05:20] [0996]     05 AdammAdminBO::Execute() - hr = 0x0
BENGINE:  [07/09/11 10:06:08] [5592]     [tpfmt]              - RewindDrive mover ret = 0 (0x0)
BENGINE:  [07/09/11 10:06:08] [5592]     [tpfmt]              -   ret_val = 0
PVLSVR:   [07/09/11 10:06:08] [4744]     AdammSession::Execute( ADAMM_SESSION_EXECUTE_MOVER_RELEASE )

            Session = {BA4D5B16-930C-4F38-928B-3678889B1E4D}
BENGINE:  [07/09/11 10:06:08] [5592]     [tpfmt]              - TF_PutChannel( ) 04AAABC0
BENGINE:  [07/09/11 10:06:08] [5592]     [tapealrt]           - TAPEALERT: Get TapeAlert Flags Return Code = 0X0
BENGINE:  [07/09/11 10:06:08] [5592]     [tapealrt]           - TAPEALERT: TapeAlert Device Flag  = 0X0
BENGINE:  [07/09/11 10:06:08] [5592]     [tapealrt]           - TAPEALERT: TapeAlert Changer Flag = 0X0
BENGINE:  [07/09/11 10:06:08] [5592]     [tapealrt]           - TAPEALERT: Get TapeAlert Flags Return Code = 0X0
BENGINE:  [07/09/11 10:06:08] [5592]     [tapealrt]           - TAPEALERT: Get TapeAlert Flags Return Code = 0X0
PVLSVR:   [07/09/11 10:06:08] [4744]     PvlEntityDatabase::UpdateEntity()

            ParentEntityType = NULL_ENTITY_TYPE

            ParentEntityGuid = {00000000-0000-0000-0000-000000000000}

            EntityType = MEDIA_ENTITY_TYPE

            Media = {DD3C4D21-63E7-468B-890E-530A96684EA1}, ""

            EntityFlags = 0x008C0000
PVLSVR:   [07/09/11 10:06:08] [4744]     PvlEntityDatabase::UpdateEntity() : SUCCESS!
BENGINE:  [07/09/11 10:06:08] [4516]     [server]             - DeviceManager: incoming event fired
BENGINE:  [07/09/11 10:06:08] [4516]     [server]             - DeviceManager: Executing ReturnDeviceCall from thread 5592.
BENGINE:  [07/09/11 10:06:08] [4516]     [server]             - Updating session {ba4d5b16-930c-4f38-928b-3678889b1e4d} with drive IBM 1 {dd2a560b-e3e7-4c0c-b3e0-86465fb6cf0c}
BENGINE:  [07/09/11 10:06:08] [4516]     [server]             - DeviceManager: processing pending requests
BENGINE:  [07/09/11 10:06:08] [4516]     [server]             - DeviceManager: going to sleep for 61000 msecs
BENGINE:  [07/09/11 10:06:08] [5592]     [server]             - Removing 'OXF-Isilon - Monthly-OXF-Isilon-Isilon Monthly' from status update list
BENGINE:  [07/09/11 10:06:08] [5592]     [server]             - Updating status for: 'OXF-Isilon - Monthly-OXF-Isilon-Isilon Monthly' (0x18 0x0)
BENGINE:  [07/09/11 10:06:08] [5592]     [server]             - Status for: 'OXF-Isilon - Monthly-OXF-Isilon-Isilon Monthly' updated
BENGINE:  [07/09/11 10:06:08] [5592]     [server]             - BackupJob::MergeBEVSRJobLogsIfNecessary: No VSR log file found, no merging necessary
BENGINE:  [07/09/11 10:06:08] [5592]     [server]             - Ending job 'OXF-Isilon - Monthly-OXF-Isilon-Isilon Monthly' with error status (-536805635)
BESERVER: [07/09/11 10:06:08] [1932]     16 JobManager::HandleEndJob() called
BESERVER: [07/09/11 10:06:08] [3320]     -1 ActiveState::doEndEvent( ): 'OXF-Isilon - Monthly-OXF-Isilon-Isilon Monthly'
BESERVER: [07/09/11 10:06:08] [3320]     16 JobManager::DoJobCompletionTasks(OXF-Isilon - Monthly-OXF-Isilon-Isilon Monthly): GetFinalJobStatus()=6
BESERVER: [07/09/11 10:06:08] [3320]     16 JobManager::DoJobCompletionTasks() Handling normal JOB_STATE_ERROR
BENGINE:  [07/09/11 10:06:08] [5592]     [server]             - Job thread terminating
BESERVER: [07/09/11 10:06:08] [3320]     16 JobManager::DoJobCompletionTasks() Finished building JOB_STATE_ERROR message
BESERVER: [07/09/11 10:06:08] [1932]     01 Server Configuration: Client removed: 9
BESERVER: [07/09/11 10:06:08] [1932]     -1 Client 'OXF-AS-BACKUP2' Disconnected:0x4856270
BESERVER: [07/09/11 10:06:08] [3320]     17 Alert Job Failed, Notify: No, EventLog: Yes, Trap: No
BESERVER: [07/09/11 10:06:08] [3320]     17 Adhoc notification request: Backup Exec Alert: Job Failed (Server: "OXF-AS-BACKUP2") (Job: "OXF-Isilon - Monthly-OXF-Isilon-Isilon Monthly")
BESERVER: [07/09/11 10:06:08] [3320]     22 CJobLogBO::transformXmlLog ends.  Transformed String Size=12267
BESERVER: [07/09/11 10:06:08] [3320]     17 Doing Notify
BESERVER: [07/09/11 10:06:08] [4660]     17 Notify Return Code:0, batch:3, index:0
BESERVER: [07/09/11 10:06:08] [1932]     15 GetSummary: SJ=7 SEJ=0 CJ=0 FJ=2 UM=0 BB=-2040267197 MJ=0 RecoveredJ=0 ResumedJ=0 FeatureRecommend=0
BESERVER: [07/09/11 10:06:08] [3320]     -1 ActiveState::doEndEvent( ): CJobManager::DoJobCompletionTasks() returned 0x0
BESERVER: [07/09/11 10:06:09] [1932]     05 AdammAdminBO::Execute() - ADAMM_ADMIN_MEDIA_SUMMARY.
PVLSVR:   [07/09/11 10:06:09] [4744]     PvlEntityDatabase::QueryEntity()

            ParentEntityType = NULL_ENTITY_TYPE

            ParentEntityGuid = {00000000-0000-0000-0000-000000000000}

            EntityType = 0031

            EntityGuid = {00000000-0000-0000-0000-000000000000}

            EntityName = "

            EntityFlags = 0x00000000

 

Any help would be arrpeciated.

 

Thanks

 

Scott

Symantec Certified Specialist

Backup Exec 2010

Comments 2 CommentsJump to latest comment

Scotte76's picture

Hi Rahul,

 

Thanks for the reply, but I have already seen and used this document. I have already applied these settings before I posted the above.

Kind Regards

 

Scott McFadzean

Senior Desktop Support Analyst/Backups Support Engineer