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

Beremote Crash!!! Can't restore data!!

Russ_Fagan
Level 3

‎11-11-2005 11:42 AM

BE 10.0 beremote crashes 20G into a 135G restore. I just applied service pack 3 of BE 10. My backup server is W2K3 and the target machine is also W2K3. Faulting module is ndmpsrvr.dll. Besides removing the agent and reinstalling it, I've also reinstalled the OS twice and HAVE to get this server back online today. I'm removing my SCSI contoller now to place in the file server to take the remote agent out of the loop. I shouldn't have to do this.

This is Symantec's last chance before I go to another product. I fought this very same issue with another server this summer and had to break my restore into a folder by folder operation. This behavior is NOT acceptable. I'm working on a holiday to replace file server and have to fight with an expensive product that DOES NOT WORK!!!!

Worst thing to ever happen to Veritas, getting bought by Symantec!!!!Message was edited by:
Russ Fagan
0 Kudos
9 REPLIES 9

Russ_Fagan
Level 3

‎11-15-2005 08:17 AM

I'm not suprised that no one has responded to this. I was a little harsh on Symantec. I completed my server replacement after many hours of frustration by using xcopy. Backup Exec did not/would not complete the restore. What should have been a 3 hour restore was a 17 hour day full of frustration. I even removed my tape controller from my backup server and installed it into the new file server. I then installed Backup Exec on the server and cataloged my tapes and tried to restore. It failed in exactly the same way. If it was a media problem, tell me it's a media problem. A crash of beremote and a Communications Failure does nothing to help me understand why it was failing. My boss has now asked me to either figure out why it failed and correct it or to recommend another solution.
0 Kudos

Renuka_-
Level 6
Employee

‎11-16-2005 09:24 AM

Hello,
Please check that the remote agent is running under the local system account, and if there are any alerts during the backup.Does this happen for all backups or for a particular one.
NOTE : If we do not receive your reply within two business days, this post would be marked assumed answeredand would be moved toanswered questions pool.
0 Kudos

Russ_Fagan
Level 3

‎11-17-2005 03:42 PM

It is the local system account.

No alerts during the backup at all. It was with one particular backup, always at 19.6G of 135G within 25-27 minutes of starting the restore. I've since backed up and restored that same set of data with a different set of tapes. My guess is bad media, but it would be nice to know what the actual error was without having it crash the remote agent (requiring manually restarting the service or rebooting).
0 Kudos

Deepali_Badave
Level 6
Employee

‎11-18-2005 06:45 AM

Hello,

Put the remote agent service in debug mode:

Please refer the following technote:

http://seer.support.veritas.com/docs/254212.htm

NOTE : If we do not receive your reply within two business days, this post would be marked assumed answered and would be moved to answered questions pool.
0 Kudos

Russ_Fagan
Level 3

‎11-21-2005 09:52 AM

Same crash of service: 45 minutes to crash instead of 25, but at 19.6G of the restore.

Beremote log:

11/21/05 08:57:36 Opening debug log C:\Program Files\VERITAS\Backup Exec\RANT\logs\TURDY-beremote00.log
11/21/05 08:57:36 CleanupOrphanedSnapshots(). Lauching cleanup thread..
11/21/05 08:57:36 CleanupOrphanedSnapshots(). Cleanup thread lauched. Exiting.
11/21/05 08:57:36 Windows Firewall does not appear to be installed.
11/21/05 08:57:36 FS_InitFileSys
11/21/05 08:57:36 loaded bedsnt5.dll
11/21/05 08:57:36 loaded bedssql2.dll
11/21/05 08:57:36 loaded bedsxchg.dll
11/21/05 08:57:36 loaded bedsxese.dll
11/21/05 08:57:37 loaded bedsmbox.dll
11/21/05 08:57:37 bedspush.dll could not be loaded: The specified module could not be found.
11/21/05 08:57:37 loaded bedsnote.dll
11/21/05 08:57:37 loaded bedsmdoc.dll
11/21/05 08:57:37 loaded bedssps2.dll
11/21/05 08:57:37 loaded bedsupfs.dll
11/21/05 08:57:37 loaded bedsshadow.dll
11/21/05 08:57:37 loaded bedsoffhost.dll
11/21/05 08:57:37 bedsvx.dll could not be loaded: The specified module could not be found.
11/21/05 08:57:37 bedsorcl.dll could not be loaded: The specified module could not be found.
11/21/05 08:57:37 bedsagnt.dll could not be loaded: The specified module could not be found.
11/21/05 08:57:37 loaded bedssms.dll
11/21/05 08:57:37 loaded bedssmsp.dll
11/21/05 08:57:37 loaded bedsra.dll
11/21/05 08:57:37 Initializing FSs
11/21/05 08:57:37 This is NOT an Exchange server.
11/21/05 08:57:37 FS 12 failed to initialize: -536805818
11/21/05 08:57:37 unloading bedsmbox.dll
11/21/05 08:57:37 Notes Agent: NOTES_InitFileSys
11/21/05 08:57:37 Notes Agent: Lotus Notes not installed.
11/21/05 08:57:37 first load attempt of esebcli2.dll failed: The specified module could not be found.
11/21/05 08:57:37 second load attempt failed from path = 'esebcli2.dll': The specified module could not be found.
11/21/05 08:57:37 third load attempt failed from path = '': The specified module could not be found.
11/21/05 08:57:37 Looking for esebcli2.dll in sysetm path
11/21/05 08:57:37 never found EseBcli2.dll
11/21/05 08:57:37 esebcli2.dll not loaded
11/21/05 08:57:37 Listening on port 10000
11/21/05 08:57:37 Agent browser advertisement cycle complete. Advertisement interval is 60 minutes.
11/21/05 08:57:38 Running... 'Q' to stop
11/21/05 09:02:26 ndmpRun: Control Connection Accepted : connection established between IP 10.24.0.101, port 10000 and IP 10.24.0.198, port 2897
11/21/05 09:02:26 Successfully impersonated RSD\veritas.logon
11/21/05 09:02:26 WhoAmI( ) reports: RSD\veritas.logon
11/21/05 09:02:31 Media Server has initiated the connection for data transfer
11/21/05 09:02:31 Remote Agent is listening for the Data Connection at:
IP : 10.24.0.101
Port : 1890
11/21/05 09:02:31 dataAcceptConnection : Data Connection information : connection established between IP 10.24.0.101, port 1890 and IP 10.24.0.198, port 2902
11/21/05 09:02:31 ENV: USERNAME=
11/21/05 09:02:31 ENV: NOPASSWORD=
11/21/05 09:02:31 ENV: USERNAME=RSD\veritas.logon
11/21/05 09:02:31 ENV: PASSWORD=
11/21/05 09:02:31 ENV: INITIATOR_SID=0@D0000000DE0000=HX26TCM^3dSGf= 11/21/05 09:02:31 ENV: PRESCANDLE=0
11/21/05 09:02:31 ENV: OPER_TYPE=5
11/21/05 09:02:31 ENV: BLOCKSIZE=10000
11/21/05 09:02:31 ENV: SSETRESERVED=0
11/21/05 09:02:31 ENV: BU_SQL_PRE_BKUP_DBCC_TYPE=0
11/21/05 09:02:31 ENV: BU_SQL_POST_BKUP_DBCC_TYPE=0
11/21/05 09:02:31 ENV: REST_SQL_POST_REST_DBCC_TYPE=2
11/21/05 09:02:31 ENV: LAST_SET=1
11/21/05 09:02:31 ENV: READ_FORMAT=0
11/21/05 09:02:31 ENV: BU_REGISTRY=1
11/21/05 09:02:31 ENV: BU_INUSE=1
11/21/05 09:02:31 ENV: BU_MIGRATED=0
11/21/05 09:02:31 ENV: BU_EXPANDED=0
11/21/05 09:02:31 ENV: PROCESS_EMPTY=0
11/21/05 09:02:31 ENV: SWCOMPMODE=0
11/21/05 09:02:31 ENV: SWCOMPLEVEL=0
11/21/05 09:02:31 ENV: SWCOMPTHRESH=10
11/21/05 09:02:31 ENV: BACKUPTYPE=1
11/21/05 09:02:31 ENV: BU_SPS_METHOD=1
11/21/05 09:02:31 ENV: BU_SQL_METHOD=0
11/21/05 09:02:31 ENV: BU_SQL_NO_RECOVER=0
11/21/05 09:02:31 ENV: BU_SQL_STANDBY=0
11/21/05 09:02:31 ENV: BU_SQL_ALWAYS_SNAP=0
11/21/05 09:02:31 ENV: BU_XCH_METHOD=1
11/21/05 09:02:31 ENV: BU_MBOX_METHOD=1
11/21/05 09:02:31 ENV: BU_MBOX_SIS=1
11/21/05 09:02:31 ENV: BU_NTFS_SIS=1
11/21/05 09:02:31 ENV: AA_PRIORITY=0
11/21/05 09:02:31 ENV: BU_RSM=0
11/21/05 09:02:31 ENV: BU_EXCLUDE_ACTIVE_FILES=1
11/21/05 09:02:31 ENV: TRAVERSE_LOCAL_MP=1
11/21/05 09:02:31 ENV: TRAVERSE_REMOTE_MP=0
11/21/05 09:02:31 ENV: DO_SNAPSHOT=0
11/21/05 09:02:31 ENV: SQL_FORCE_NP=0
11/21/05 09:02:31 ENV: SQL_OVERRIDE_FGDEPS=0
11/21/05 09:02:31 ENV: SQL_ALERT_FGDEP_OVERRIDE=1
11/21/05 09:02:31 ENV: BU_SFC_WITH_SS=1
11/21/05 09:02:31 ENV: BU_REMOTE_STORAGE_HSM=0
11/21/05 09:02:31 ENV: SKIP_OPEN_FILES=0
11/21/05 09:02:31 ENV: WAIT_SKIP=1e
11/21/05 09:02:31 ENV: HIDDEN=1
11/21/05 09:02:31 ENV: PROC_SYSTEM_FILES=1
11/21/05 09:02:31 ENV: BU_LDM_STATE=0
11/21/05 09:02:31 ENV: BU_DISK_QUOTA=0
11/21/05 09:02:31 ENV: BU_IN_DOC_FMT=0
11/21/05 09:02:31 ENV: REST_REGISTRY=0
11/21/05 09:02:31 ENV: REST_HW_REGISTRY=0
11/21/05 09:02:31 ENV: IGNORE_TREE_RESTORE=0
11/21/05 09:02:31 ENV: REST_SECURITY=1
11/21/05 09:02:31 ENV: VOL_RESTRICTIONS=0
11/21/05 09:02:31 ENV: REST_XCH_PUBLIC=1
11/21/05 09:02:31 ENV: REST_MBOX_USER_FLAG=0
11/21/05 09:02:31 ENV: REST_MBOX_USER_PASSWORD=
11/21/05 09:02:31 ENV: MEDIA_SERVER=BS01
11/21/05 09:02:31 ENV: SPS_SERVER_LIST=
11/21/05 09:02:31 ENV: SPS_COMMIT_RESTORE=1
11/21/05 09:02:31 ENV: BU_SPS_PRE_BKUP_DBCC_TYPE=0
11/21/05 09:02:31 ENV: BU_SPS_POST_BKUP_DBCC_TYPE=0
11/21/05 09:02:31 ENV: REST_SPS_POST_REST_DBCC_TYPE=2
11/21/05 09:02:31 ENV: CONTINUE_ON_SPS_DBCC_ERROR=0
11/21/05 09:02:31 ENV: JOBNAME=Restore 00224
11/21/05 09:02:31 ENV: REST_XCH_PRIVATE=1
11/21/05 09:02:31 ENV: REST_REMOTE_HSM=0
11/21/05 09:02:31 ENV: REST_RSM=0
11/21/05 09:02:31 ENV: REST_DISK_QUOTA=0
11/21/05 09:02:31 ENV: REST_JUNC_AS_DIR=0
11/21/05 09:02:31 ENV: REST_PRIM_REPLICA=0
11/21/05 09:02:31 ENV: REST_TERMINAL_SERVICES=0
11/21/05 09:02:31 ENV: REST_WMI=0
11/21/05 09:02:31 ENV: REST_CLUSTER_QUORUM_DB=0
11/21/05 09:02:31 ENV: REST_FORCE_RECOVERY=0
11/21/05 09:02:31 ENV: REST_EXISTING=3
11/21/05 09:02:31 ENV: REST_XCH_NO_LOSS=1
11/21/05 09:02:31 ENV: REST_ESE_MOUNT=1
11/21/05 09:02:31 ENV: REST_ESE_DISMOUNT=0
11/21/05 09:02:31 ENV: REST_ESE_LOG_PATH=C:\TEMP
11/21/05 09:02:31 ENV: REST_ESE_LAST_SET=1
11/21/05 09:02:31 ENV: REST_ESE_NO_LOSS=1
11/21/05 09:02:31 ENV: REST_ESE_CONSISTENCY_CHECK=1
11/21/05 09:02:31 ENV: REST_ESE_CONTINUE_CONSISTENCY_CHECK=0
11/21/05 09:02:31 ENV: REST_SQL_DATABASE_OVERWRITE=0
11/21/05 09:02:31 ENV: REST_SQL_POINT_IN_TIME=0
11/21/05 09:02:31 ENV: REST_SQL_POINT_IN_TIME_DATE=3375
11/21/05 09:02:31 ENV: REST_SQL_POINT_IN_TIME_TIME=473d
11/21/05 09:02:31 ENV: BU_SQL_PRE_BKUP_DBCC=0
11/21/05 09:02:31 ENV: BU_SQL_POST_BKUP_DBCC=0
11/21/05 09:02:31 ENV: REST_SQL_POST_REST_DBCC=2
11/21/05 09:02:31 ENV: CONTINUE_ON_DBCC_ERROR=1
11/21/05 09:02:31 ENV: REST_SQL_AUTO_MASTER=0
11/21/05 09:02:31 ENV: REST_SQL_DATABASE_STANDBY=0
11/21/05 09:02:31 ENV: REST_SQL_DATABASE_FORCERESTORE=0
11/21/05 09:02:31 ENV: REST_SQL_DATABASE_RECOVER=1
11/21/05 09:02:31 ENV: REST_SQL_LOG_MARK_RESTORE=0
11/21/05 09:02:31 ENV: REST_SQL_LOG_MARK_NAME=
11/21/05 09:02:31 ENV: REST_SQL_LOG_MARK_STOP_BEFORE=1
11/21/05 09:02:31 ENV: REST_SQL_LOG_MARK_AFTER_TIME=0
11/21/05 09:02:31 ENV: REST_SQL_LOG_MARK_DATE=3375
11/21/05 09:02:31 ENV: REST_SQL_LOG_MARK_TIME=473d
11/21/05 09:02:31 ENV: REST_SQL_DEF_INSTANCE_DIR=0
11/21/05 09:02:31 ENV: REST_SQL_USE_DEFAULT_DRIVE=1
11/21/05 09:02:31 ENV: REST_SQL_ALL_TO_DEFAULT_DRIVE=0
11/21/05 09:02:31 ENV: REST_SQL_DEFAULT_DRIVE=43
11/21/05 09:02:31 ENV: ARCHIVE_PRESERVE_TREE=0
11/21/05 09:02:31 ENV: UTILITY_PARTITION_OEMIDS=
11/21/05 09:02:31 ENV: APPLY_CPR_TO_BACKUPSET=0
11/21/05 09:02:31 ENV: IMAGE_METHOD=0
11/21/05 09:02:31 ENV: GUARANTEED_FROZEN_IMAGE=0
11/21/05 09:02:31 ENV: FULL_IMAGE_METHOD=0
11/21/05 09:02:31 ENV: TESTRUN_JOB_OPTIONS=0
11/21/05 09:02:31 ENV: USE_CHANGE_JOURNAL=0
11/21/05 09:02:31 ENV: DOMINO_BACKUP_METHOD=1
11/21/05 09:02:31 ENV: DOMINO_BACKUP_RECYCLE_LOGFILE=0
11/21/05 09:02:31 ENV: DOMINO_LAST_BACKUP_DATE=0
11/21/05 09:02:31 ENV: DOMINO_LAST_BACKUP_TIME=0
11/21/05 09:02:31 ENV: DOMINO_MAX_TRANLOG_EXTENTS=46
11/21/05 09:02:31 ENV: DOMINO_RESTORE_DBRECOVEROPTION=0
11/21/05 09:02:31 ENV: DOMINO_RESTORE_WAITINUSE=0
11/21/05 09:02:31 ENV: DOMINO_RESTORE_MSECSFORWAIT=1e
11/21/05 09:02:31 ENV: DOMINO_RESTORE_POINTINTIMERESTORE=0
11/21/05 09:02:31 ENV: DOMINO_RESTORE_PITTIME_DATE=3375
11/21/05 09:02:31 ENV: DOMINO_RESTORE_PITTIME_TIME=473d
11/21/05 09:02:31 ENV: SAP_JOB_FLAG=0
11/21/05 09:02:31 ENV: IMAGE_DBLK_TYPE=0
11/21/05 09:02:31 ENV: PROC_CHECKSUM_STRM=1
11/21/05 09:02:31 ENV: DOMINO_OFFLINE_BACKUP=0
11/21/05 09:02:31 ENV: ENABLE_IDR_INFO_GATHERING=1
11/21/05 09:02:31 ENV: IMAGE_OPTION_AUTHORIZED=0
11/21/05 09:02:31 ENV: GRFS_TIMEOUT=5a
11/21/05 09:02:31 ENV: DO_OFF_HOST_BACKUP=0
11/21/05 09:02:31 ENV: OFF_HOST_BACKUP_OPER_TYPE=0
11/21/05 09:02:31 ENV: OFF_HOST_METADATA_COLL_NAME=
11/21/05 09:02:31 ENV: PRESERVE_CTIME=0
11/21/05 09:02:31 ENV: FOLLOW_SYMDIRS=0
11/21/05 09:02:31 ENV: INCLUDE_REMOTE=0
11/21/05 09:02:31 ENV: NO_NFS_LOCKING=0
11/21/05 09:02:31 ENV: FOLLOW_MOUNTPOINTS=1
11/21/05 09:02:31 ENV: TRUE_IMAGE_BACKUP=0
11/21/05 09:02:31 ENV: NDMP_PROP_PORT_RANGE_ENABLED=0
11/21/05 09:02:31 ENV: NDMP_PORT_RANGE=
11/21/05 09:02:31 ENV: REVERSE_CONNECTION=1
11/21/05 09:02:31 ENV: SNAPSHOT_ID={00000000-0000-0000-0000-000000000000}
11/21/05 09:02:31 ENV: OFF_HOST_SNAPSHOT_ID={00000000-0000-0000-0000-000000000000}
11/21/05 09:02:31 ENV: DEVICENAME="\\TURDY\D:"
11/21/05 09:02:31 ENV: DEVICE_OS_ID=e
11/21/05 09:02:31 ENV: DEVICE_OS_VER=3
11/21/05 09:02:31 ENV: DEVICE_MAINTYPE=1
11/21/05 09:02:31 ENV: DEVICE_SUBTYPE=4
11/21/05 09:02:31 ENV: FILESYSTEM="\\TURDY\D:"
11/21/05 09:02:31 ENV: JOBNAME=Restore 00224
11/21/05 09:02:31 ENV: REST_ESE_LOG_PATH=C:\TEMP
11/21/05 09:02:31 ENV: REST_SQL_LOG_MARK_NAME=
11/21/05 09:02:31 ModifiedOnly is 0
11/21/05 09:02:31 SWCompMode is 0
11/21/05 09:02:31 ENV: MEDIA_SERVER=BS01
11/21/05 09:02:31 ENV: SPS_SERVER_LIST=
11/21/05 09:02:31 ENV: SPS_COMMIT_RESTORE=1
11/21/05 09:02:31 ENV: GRFS_TIMEOUT=5a
11/21/05 09:02:31 ENV: DO_OFF_HOST_BACKUP=0
11/21/05 09:02:31 ENV: OFF_HOST_BACKUP_OPER_TYPE=0
11/21/05 09:02:31 ENV: OFF_HOST_METADATA_COLL_NAME=
11/21/05 09:02:31 ENV: PRESERVE_CTIME=0
11/21/05 09:02:31 ENV: FOLLOW_SYMDIRS=0
11/21/05 09:02:31 ENV: INCLUDE_REMOTE=0
11/21/05 09:02:31 ENV: NO_NFS_LOCKING=0
11/21/05 09:02:31 ENV: FOLLOW_MOUNTPOINTS=1
11/21/05 09:02:31 ENV: TRUE_IMAGE_BACKUP=0
11/21/05 09:02:31
SMSAPIShim: Couldn't load BESMDR.DLL. No SMS support is loaded.
11/21/05 09:02:31 SQL2_FindDrives - the default SQL Service is not running on TURDY.
11/21/05 09:02:31 Device Shadow?Copy?Components Dle platform 2 major version 5 minor version 2 build 3790
11/21/05 09:02:31 AddESE_DLEs( )
11/21/05 09:02:31 first load attempt of esebcli2.dll failed: The specified module could not be found.
11/21/05 09:02:31 second load attempt failed from path = 'esebcli2.dll': The specified module could not be found.
11/21/05 09:02:31 third load attempt failed from path = '': The specified module could not be found.
11/21/05 09:02:31 Looking for esebcli2.dll in sysetm path
11/21/05 09:02:31 never found EseBcli2.dll
11/21/05 09:02:31 esebcli2.dll not loaded
11/21/05 09:02:31 Utility Partition/Partition's found on this system.
11/21/05 09:02:31 FS_CreateTempDLE
11/21/05 09:02:31 0 = 1
11/21/05 09:02:31 1 = 1
11/21/05 09:02:31 2 = 1
11/21/05 09:02:31 3 = 1
11/21/05 09:02:31 4 = 1
11/21/05 09:02:31 5 = 1
11/21/05 09:02:31 6 = 1
11/21/05 09:02:31 7 = 0
11/21/05 09:02:31 Error 0 opening cluster on \\TURDY
11/21/05 09:02:31 Error 0 on NetShareEnum of \\TURDY, level 1
11/21/05 09:02:31 NTFS_SurrogateCalling: TURDY
11/21/05 09:02:31 Error opening cluster on TURDY:
11/21/05 09:02:31 Enumerating local drives
11/21/05 09:02:31 Creating DLEs:
11/21/05 09:02:31 \\TURDY\C:
11/21/05 09:02:31 \\TURDY\D:
11/21/05 09:02:31 \\TURDY\E:
11/21/05 09:02:31 \\TURDY\F:
11/21/05 09:02:31 SQL2_SurrogateCalling - the default SQL Service is not running on TURDY.
11/21/05 09:02:31 Device Shadow?Copy?Components Dle platform 2 major version 5 minor version 2 build 3790
11/21/05 09:02:31 AddESE_DLEs( )
11/21/05 09:02:31 first load attempt of esebcli2.dll failed: The specified module could not be found.
11/21/05 09:02:31 second load attempt failed from path = 'esebcli2.dll': The specified module could not be found.
11/21/05 09:02:31 third load attempt failed from path = '': The specified module could not be found.
11/21/05 09:02:31 Looking for esebcli2.dll in sysetm path
11/21/05 09:02:31 never found EseBcli2.dll
11/21/05 09:02:31 esebcli2.dll not loaded
11/21/05 09:02:31 Utility Partition/Partition's found on this system.
11/21/05 09:02:31 Attach to \\TURDY
11/21/05 09:02:31 Entering SetupLocalShareInfo
11/21/05 09:02:31 Informational: Not enumerating DFS
11/21/05 09:02:31 Attach to \\TURDY\D:
11/21/05 09:02:31 Detach from \\TURDY
11/21/05 09:02:31 LBA: 0 Type: 0
11/21/05 09:02:31 line = "\\TURDY\D:\*.*",s,v0,t0,l0,n0,f0
11/21/05 09:02:31 dir =
11/21/05 09:02:31 file = *.*
11/21/05 09:02:31 oper = Include
11/21/05 09:02:31 subs = True
11/21/05 09:02:31 wild = True
11/21/05 09:02:31 attr_on_mask = 0
11/21/05 09:02:31 attr_off_mask = 0
11/21/05 09:02:31 Target LBA: 0 Type: 0
11/21/05 09:02:31 LBA: -1 Type: 2
11/21/05 09:02:31 Adding Single LBA: -1 Type: 2
11/21/05 09:02:31 AddFsExcludes:
11/21/05 09:02:31 BSD for \\TURDY\D:
11/21/05 09:02:31 + \*.* /s
11/21/05 09:02:31 INFORMATIONAL: REG_MULTI_SZ registry value "SYSTEM\CurrentControlSet\Control\BackupRestore\FilesNotToBackup\DRM" lacks proper termination
11/21/05 09:02:31 FilesNotToBackup and Hardcoded exclusion files:
11/21/05 09:02:31 C:\WINNT\Cluster\MajorityNodeSet_FileMutex
11/21/05 09:02:31 C:\WINNT\Cluster\MNS*\*.* /s
11/21/05 09:02:31 C:\WINNT\csc\* /s
11/21/05 09:02:31 C:\Documents and Settings\Default User\index.dat /s
11/21/05 09:02:31 D:\Pagefile.sys
11/21/05 09:02:31 C:\WINNT\netlogon.chg
11/21/05 09:02:31 D:\hiberfil.sys
11/21/05 09:02:31 C:\WINNT\Tasks\schedlgu.txt
11/21/05 09:02:31 C:\WINNT\TEMP\* /s
11/21/05 09:02:31 C:\WINNT\debug\*
11/21/05 09:02:31 C:\WINNT\system32\MSDtc\MSDTC.LOG
11/21/05 09:02:31 C:\WINNT\system32\MSDtc\trace\dtctrace.log
11/21/05 09:02:31 C:\WINNT\repair\asr.log
11/21/05 09:02:31 C:\WINNT\repair\asr.err
11/21/05 09:02:31 C:\WINNT\Registration\*.clb
11/21/05 09:02:31 D:\*.crmlog /s
11/21/05 09:02:31 D:\System Volume Information\MountPointManagerRemoteDatabase
11/21/05 09:02:31 D:\System Volume Information\*{3808876B-C176-4e48-B7AE-04046E6CC752} /s
11/21/05 09:02:31 D:\System Volume Information\*.{7cc467ef-6865-4831-853f-2a4817fd1bca}DB
11/21/05 09:02:31 D:\System Volume Information\*.{7cc467ef-6865-4831-853f-2a4817fd1bca}ALT
11/21/05 09:02:31 C:\WINNT\System32\CatRoot2\* /s
11/21/05 09:02:31 C:\Documents and Settings\All Users\DRM\* /s
11/21/05 09:02:31 C:\Documents and Settings\All Users\Application Data\Microsoft\Network\Downloader\*
11/21/05 09:02:31 C:\WINNT\SoftwareDistribution\* /s
11/21/05 09:02:31 D:\win386.swp /s
11/21/05 09:02:31 D:\pagefile.sys /s
11/21/05 09:02:31 D:\386spart.par
11/21/05 09:02:31 D:\ea data. sf
11/21/05 09:02:31 D:\SSH??P*.SSS /s
11/21/05 09:02:31 D:\SSH?P*.SSS /s
11/21/05 09:02:31 D:\SIS COMMON STORE\*.* /s
11/21/05 09:02:31 D:\dblspace.*
11/21/05 09:02:31 D:\drvspace.*
11/21/05 09:02:31 D:\stacvol.*
11/21/05 09:02:31 D:\_swap_.mem
11/21/05 09:02:31 D:\_BEVspCacheFile_*.VSP /s
11/21/05 09:02:31 D:\system\q_*.* /s
11/21/05 09:02:31 D:\system\*.q /s
11/21/05 09:02:31 D:\system\btrieve.trn
11/21/05 09:02:31 D:\cdrom$$.rom\*.* /s
11/21/05 09:02:31 D:\desktop.afp\*.*
11/21/05 09:02:31 D:\snapshot\pool*.dat /s
11/21/05 09:02:31 D:\Backup Exec AOFO Store\* /s
11/21/05 09:02:31 BSD for \\TURDY\D:
11/21/05 09:02:31 + \*.* /s
11/21/05 09:02:31 - \Pagefile.sys
11/21/05 09:02:31 - \hiberfil.sys
11/21/05 09:02:31 - \*.crmlog /s
11/21/05 09:02:31 - \System Volume Information\MountPointManagerRemoteDatabase
11/21/05 09:02:31 - \System Volume Information\*{3808876B-C176-4e48-B7AE-04046E6CC752} /s
11/21/05 09:02:31 - \System Volume Information\*.{7cc467ef-6865-4831-853f-2a4817fd1bca}DB
11/21/05 09:02:31 - \System Volume Information\*.{7cc467ef-6865-4831-853f-2a4817fd1bca}ALT
11/21/05 09:02:31 - \win386.swp /s
11/21/05 09:02:31 - \pagefile.sys /s
11/21/05 09:02:31 - \386spart.par
11/21/05 09:02:31 - \ea data. sf
11/21/05 09:02:31 - \SSH??P*.SSS /s
11/21/05 09:02:31 - \SSH?P*.SSS /s
11/21/05 09:02:31 - \SIS COMMON STORE\*.* /s
11/21/05 09:02:31 - \dblspace.*
11/21/05 09:02:31 - \drvspace.*
11/21/05 09:02:31 - \stacvol.*
11/21/05 09:02:31 - \_swap_.mem
11/21/05 09:02:31 - \_BEVspCacheFile_*.VSP /s
11/21/05 09:02:31 - \system\q_*.* /s
11/21/05 09:02:31 - \system\*.q /s
11/21/05 09:02:31 - \system\btrieve.trn
11/21/05 09:02:31 - \cdrom$$.rom\*.* /s
11/21/05 09:02:31 - \desktop.afp\*.*
11/21/05 09:02:31 - \snapshot\pool*.dat /s
11/21/05 09:02:31 - \Backup Exec AOFO Store\* /s
11/21/05 09:02:31 Detach from \\TURDY\D:
11/21/05 09:02:31 Job Start - Mon Nov 21 09:02:31 2005
11/21/05 09:02:31 Entering SetupLocalShareInfo
11/21/05 09:02:31 ExtendedCopy: ExtendedCopy Debug Level key not found
11/21/05 09:02:31 ExtendedCopy: Setting ExtendedCopy Debug Level to 0
11/21/05 09:02:31 Informational: Enumerating DFS
11/21/05 09:02:31 Attach to \\TURDY\D:
11/21/05 09:02:31 TF_OpenTape()
11/21/05 09:02:31 TF_GetDriveContext ...
11/21/05 09:02:31 TF_GetDriveContext returning 0, context = 103C530
11/21/05 09:02:31 Allocated 10 buffers, size 65536 bytes, total used: 656200
11/21/05 09:02:31 TF_OpenSet()
11/21/05 09:02:31 SetupFormatEnv( fmt=0 )
11/21/05 09:02:31 End of TF_OpenSet() ret_val = 0, num buffers = 10
11/21/05 09:02:31 SendRangeRequest: offset = 0 (0x0) length = -1 (0xFFFFFFFFFFFFFFFF)

BE Engine Log:
11/21/05 11:12:20 FS_InitFileSys
11/21/05 11:12:20 Initializing FSs
11/21/05 11:12:20 Initializing SNMP traps
11/21/05 11:12:20 Catalog path: C:\Program Files\VERITAS\Backup Exec\NT\Catalogs\
11/21/05 11:12:20 Data path: C:\Program Files\VERITAS\Backup Exec\NT\Data\
11/21/05 11:12:20 Log file root: BEX
11/21/05 11:12:20 Software Edition: SOFTWARE_EDITION_DATACENTER_MULTI
11/21/05 11:12:20 NSE: 0
11/21/05 11:12:20 Authorized Agents:
11/21/05 11:12:20 AUTH_AGENT_UNIX
11/21/05 11:12:20 AUTH_AGENT_MAC
11/21/05 11:12:20 AUTH_AGENT_NT
11/21/05 11:12:20 AUTH_AGENT_XCH
11/21/05 11:12:20 AUTH_AGENT_ESE
11/21/05 11:12:20 AUTH_AGENT_NETWARE
11/21/05 11:12:20 WinNT information: 5.2 (3790) SP 1.0 "Service Pack 1"
11/21/05 11:12:20 WinNT ProductType: 3 "ServerNT"
11/21/05 11:12:20 WinNT ProductSuite: 0x0012
11/21/05 11:12:20 Enterprise
11/21/05 11:12:20 Terminal Server
11/21/05 11:12:20 JobStatus thread starting
11/21/05 11:12:20 Server listening on RPC endpoint 'BackupExecJobRunner'
11/21/05 11:12:20 Running... 'Q' to stop
11/21/05 11:12:47 JobEngine::LaunchJob
11/21/05 11:12:47 RunJobWithGuidAndServer( {49697470-57F7-4C1D-BD62-1DC113DA053F}, BS01 ) = 0
11/21/05 11:12:47 Thread for job Restore 00228 ({49697470-57F7-4C1D-BD62-1DC113DA053F}) starting
11/21/05 11:12:47 Checking for Evaluation expiration.
11/21/05 11:12:47 ENGSCRPT: ConvertDeviceName osId = 0Xe
11/21/05 11:12:47 ENGSCRPT: IN - oldDeviceName = \\TURDY\D:
11/21/05 11:12:47 ENGSCRPT: OUT - newName = \\TURDY\D:
11/21/05 11:15:45 DeviceManager: incoming event fired
11/21/05 11:15:45 DeviceManager: processing pending requests
11/21/05 11:15:45 DeviceManager: query cache miss
11/21/05 11:15:45 Available and held drives from the pool
11/21/05 11:15:45 DELL 2 {ac74e59c-933b-40ee-b2a1-cb3bd777f613}
11/21/05 11:15:45 Drives not in use by the Engine
11/21/05 11:15:45 DELL 2 {ac74e59c-933b-40ee-b2a1-cb3bd777f613}
11/21/05 11:15:45 Drives available (not in use and not held)
11/21/05 11:15:45 DELL 2 {ac74e59c-933b-40ee-b2a1-cb3bd777f613}
11/21/05 11:15:45 Handing out session {636dc5b3-cfc6-44ca-abc9-b8728f1c361d} based on available drive:
11/21/05 11:15:45 DELL 2 {ac74e59c-933b-40ee-b2a1-cb3bd777f613}
11/21/05 11:15:45 DeviceManager: going to sleep for 900000 msecs
11/21/05 11:15:45 Job::Mount: acquired device
11/21/05 11:15:45 Job::Mount: mounting tape
11/21/05 11:16:42 Updating session {636dc5b3-cfc6-44ca-abc9-b8728f1c361d} with drive DELL 2 {ac74e59c-933b-40ee-b2a1-cb3bd777f613}
11/21/05 11:16:42 Job::Mount: media mounted
11/21/05 11:16:42 Media Label: 04 NOV 05 - Tape 5
11/21/05 11:16:42 Media Label: {8bf2fca4-3a5a-4760-9e7f-c959148b7419}
11/21/05 11:16:42 Overwrite Protected Until: 12/30/9999 4:00:00 PM
11/21/05 11:16:42 Appendable Until: 12/30/9999 4:00:00 PM
11/21/05 11:16:42 Block size: 65536
11/21/05 11:16:42 Buffer size: 65536
11/21/05 11:16:42 Num buffers: 10
11/21/05 11:16:42 High water: 0
11/21/05 11:16:42 TAPEALERT: Get TapeAlert Flags Return Code = 0X0
11/21/05 11:16:42 TAPEALERT: TapeAlert Device Flag = 0X0
11/21/05 11:16:42 TAPEALERT: TapeAlert Changer Flag = 0X0
11/21/05 11:16:42 TAPEALERT: Get TapeAlert Flags Return Code = 0X0
11/21/05 11:16:42 TAPEALERT: Get TapeAlert Flags Return Code = 0X0
11/21/05 11:16:43 Informational: Subnet Address 10.24.0.0 specified
11/21/05 11:16:43 Informational: Subnet Mask 255.255.255.0 input
11/21/05 11:16:43 Informational: Local network address 10.24.0.198 returned
11/21/05 11:16:43 Informational: Local network address 10.24.0.198 obtained in the subnet specified
11/21/05 11:16:43 ndmpConnect : Control Connection information : connection established between IP 10.24.0.198, port 3092 and IP 10.24.0.101, port 10000
11/21/05 11:16:43 NDMP version 3 connection CONNECTED
11/21/05 11:16:44 TF_OpenTape()
11/21/05 11:16:44 TF_GetDriveContext ...
sessionGuid = {636dc5b3-cfc6-44ca-abc9-b8728f1c361d}
initAsync = 1
11/21/05 11:16:45 HARDWARE COMPRESSION ===> Compression is configurable.
11/21/05 11:16:45 GET_DRV_INF: bsize = 65536
11/21/05 11:16:45 HARDWARE COMPRESSION ===> Setting compression off.
11/21/05 11:16:45 TF_GetDriveContext returning 0, context = 1C48FB8
11/21/05 11:16:45 Allocated 2 buffers, size 65536 bytes, total used: 131256
11/21/05 11:16:45 HARDWARE COMPRESSION ===> Compression is configurable.
11/21/05 11:16:45 GET_DRV_INF: bsize = 65536
11/21/05 11:16:45 Media Server to initiate connection for data transfer
11/21/05 11:16:45 Path =
11/21/05 11:16:45 FileName = *.*
11/21/05 11:16:45 Single = 0, incSubdirs = 1, isImage = 0
11/21/05 11:16:46 Start LBA = 344, End LBA = -1, Start Seq = 1
11/21/05 11:16:46 Path =
11/21/05 11:16:46 FileName =
11/21/05 11:16:46 Single = 1, incSubdirs = 1, isImage = 0
11/21/05 11:16:46 Start LBA = 2, End LBA = 344, Start Seq = 1
11/21/05 11:16:46 TF_OpenSet( )
11/21/05 11:16:46 FreeFormatEnv( cur_fmt=65535 )
11/21/05 11:16:46 RewindDrive mover ret = 0 (0x0)
11/21/05 11:16:46 ret_val = 0
11/21/05 11:16:46 Requested Set: ID = 336a9432 Seq = 1 Set = 2
11/21/05 11:16:46 Attempting to VCB storage media
11/21/05 11:16:46 TpRead( :(
11/21/05 11:16:47 Error = 0 Req = 65536 Got = 65536
11/21/05 11:16:47 HARDWARE COMPRESSION ===> Compression is configurable.
11/21/05 11:16:47 GET_DRV_INF: bsize = 65536
11/21/05 11:16:47 FreeFormatEnv( cur_fmt=65535 )
11/21/05 11:16:47 SetupFormatEnv( fmt=0 )
11/21/05 11:16:47 TpRead( :(
11/21/05 11:16:47 Error = 0 Req = 65536 Got = 65536
11/21/05 11:16:47 ReadThisSet( cur_fmt=0 )
11/21/05 11:16:47 ReadThisSet() return=0
11/21/05 11:16:47 Current VCB: ID = 336a9432 Seq = 1 Set = 1
11/21/05 11:16:47 End of TF_OpenSet: Ret_val = 0 Buffs = 2 HiWater = 7
11/21/05 11:16:48 Informational: Subnet Address 10.24.0.0 specified
11/21/05 11:16:48 Informational: Subnet Mask 255.255.255.0 input
11/21/05 11:16:48 Informational: Local network address 10.24.0.198 returned
11/21/05 11:16:48 Informational: Local network address 10.24.0.198 obtained in the subnet specified
11/21/05 11:16:48 TF_InitMediaServerReverseConnection : Data Connection information : connection established between IP 10.24.0.198, port 3096 and IP 10.24.0.101, port 2273
11/21/05 11:16:48 GotoBlock: VCB_PBA = 2023, VCB_LBA = 0, LBA = 0,
11/21/05 11:16:48 PBA = 2023, offset = 0
11/21/05 11:16:54 AddDataExtent: startLba = 0
11/21/05 11:16:54 endLba = -1
11/21/05 11:16:54 nlbaElem.startSeq = 1
11/21/05 11:16:54 nlbaElem.startLba = 0
11/21/05 11:16:54 nlbaElem.endLba = -1
11/21/05 11:30:45 DeviceManager: timeout event fired
11/21/05 11:30:45 DeviceManager: processing pending requests
11/21/05 11:30:45 DeviceManager: going to sleep for 900000 msecs
11/21/05 11:45:45 DeviceManager: timeout event fired
11/21/05 11:45:45 DeviceManager: processing pending requests
11/21/05 11:45:45 DeviceManager: going to sleep for 900000 msecs
11/21/05 12:00:45 DeviceManager: timeout event fired
11/21/05 12:00:45 DeviceManager: processing pending requests
11/21/05 12:00:45 DeviceManager: going to sleep for 900000 msecs
11/21/05 12:15:41 ERROR: ndmpcSendRequest->connection error
11/21/05 12:15:41 ERROR: ndmpSendRequest failed:
11/21/05 12:15:41 NDMPEngine::PollDataServer(). dataGetDataState failed with NDMP_CONNECT_ERR !!
11/21/05 12:15:41 TF_NDMPGetResult(): MediaServer thread done, channel->dsError is 10054, returning FS_COMM_FAILURE
11/21/05 12:15:41 ERROR: ndmpcSendRequest->connection error
11/21/05 12:15:41 ERROR: ndmpSendRequest failed:
11/21/05 12:15:41 TF_CloseSet
11/21/05 12:15:45 DeviceManager: timeout event fired
11/21/05 12:15:45 DeviceManager: processing pending requests
11/21/05 12:15:45 DeviceManager: going to sleep for 900000 msecs
11/21/05 12:17:09 RewindDrive mover ret = 0 (0x0)
11/21/05 12:17:09 ret_val = 0
11/21/05 12:17:09 TAPEALERT: Get TapeAlert Flags Return Code = 0X0
11/21/05 12:17:09 TAPEALERT: TapeAlert Device Flag = 0X0
11/21/05 12:17:09 TAPEALERT: TapeAlert Changer Flag = 0X0
11/21/05 12:17:09 TAPEALERT: Get TapeAlert Flags Return Code = 0X0
11/21/05 12:17:10 TF_FreeDriveContext( 1C48FB8 )
11/21/05 12:17:10 TF_FreeTapeBuffers: from 2 to 0 buffers
11/21/05 12:17:10 FreeFormatEnv( cur_fmt=0 )
11/21/05 12:18:01 DeviceManager: dismount event fired
11/21/05 12:18:01 DeviceManager: processing pending requests
11/21/05 12:18:01 DeviceManager: going to sleep for 900000 msecs
11/21/05 12:18:01 Merging of BE / VSR Job Logs not necessary for this Type of Job
11/21/05 12:18:01 Job thread terminating
11/21/05 12:33:01 DeviceManager: timeout event fired
11/21/05 12:33:01 DeviceManager: processing pending requests
11/21/05 12:33:01 DeviceManager: going to sleep for 900000 msecs


Message was edited by:
Russ FaganMessage was edited by:
Russ Fagan
0 Kudos

Russ_Fagan
Level 3

‎11-21-2005 02:29 PM

Ran again with SGMON running: same results.

bengine: 11/21/05 14:24:29 ERROR: ndmpcSendRequest->connection error
bengine: 11/21/05 14:24:29 ERROR: ndmpSendRequest failed:
bengine: 11/21/05 14:24:29 NDMPEngine::PollDataServer(). dataGetDataState failed with NDMP_CONNECT_ERR !!
bengine: 11/21/05 14:24:29 TF_NDMPGetResult(): MediaServer thread done, channel->dsError is 10054, returning FS_COMM_FAILURE
bengine: 11/21/05 14:24:30 ERROR: ndmpcSendRequest->connection error
bengine: 11/21/05 14:24:30 ERROR: ndmpSendRequest failed:
bengine: 11/21/05 14:24:30 TF_CloseSet
0 Kudos

Russ_Fagan
Level 3

‎11-22-2005 01:17 PM

Similar results now with another backup/restore operation. Does anyone restore data consistantly with BE??
0 Kudos

Russ_Fagan
Level 3

‎11-23-2005 12:32 PM

The fact that I'm the only one responding to my own questions and that this can get buried 6 pages deep in a day tells me two things, a LOT of people use Veritas and a LOT of people have problems. One is good, one is bad. I just replaced a file server and then upgraded the storage on the old one so that I can regularly restore data to speed recovery if I have to. If I can't consistanly restore the data, why am I using this product?
0 Kudos

Ashutosh_Tamhan
Level 6

‎01-13-2006 09:35 AM

Hello Russ

Compare the file versions of beremote.exe on both the remote as well as the media server!

Also make sure that your remote agents are running under LSA!

You get this error code from ndmp: Probably that is because the beremote is crashing !
>net helpmsg 10054

An existing connection was forcibly closed by the remote host.



Regards,
Ashutosh

NOTE : If we do not receive your reply within two business days, this post would be marked assumed answered and would be moved to answered questions pool.
0 Kudos