cancel
Showing results for 
Search instead for 
Did you mean: 

problem with jobs on deduplication volume

Peter26
Level 2

Hello.
I'm having a problem running jobs on the deduplication disk.
At startup, the server connects to the BE agent on the hyper-v host, passes the job parameters, but then depends on the status "execution" and the current operation "scan backup".
It has also been noticed that there are problems with tasks of the Exchange and Hyper-V types, but if the backup is launched via the BE agent or SQL type, then the tasks work fine.
Also, jobs of all types on Normal disk and Cassette are performed without problems.
Below are parts of the logs that may give a hint to the solution, but I could not find a solution.

[fsys\ntfs] - GetServerType for local machine
Win8DedupVolume::Initialize. Failed to get Volume Letter. Error code = 8004100E
[fsys\ntfs] - NTFS_SetupVolumeFlags:Failed to Initalized WMI for win8 with 8004100e.
[fsys\ntfs] - 'C:\' NTFS: {ENCRYPTION} {COMPRESSION} {SUPPORTS_ATTRIBUTES} {DATES} {ACCESS DATES} {DATA_SECURITY} {UNICODE} {CASE_PRESERVING} f=0x0B111479 x=0x06000000 volflags=0x03E700FF


[ndmp\ndmpsrvr] | Session 1 started
- sslOpen() : Opening SSL for: 00000000E5146C1F
- sslOpen(): certinfo = 00000000EB5E0F2F ; sslConn = 00000000E52ADD2F
- ERROR: processing request 0x100: connection not authorized.
[ndmp\ndmpcomm] - GetCertificateDataFolderPath: Not checking for cluster.
- SSL connection using version TLSv1.2
- SSL connection using cipher AES256-GCM-SHA384
[ndmp\ndmpsrvr] - Username for Logon: @user
+ impersonator.cpp (354):
| BELogonUser: beclass::IsThisMe() returned error: 87
+ impersonator.cpp (416):
| LogonType set = [LOGON32_LOGON_BATCH][0x4]
+ impersonator.cpp (431):
| LogonUser(LOGON32_LOGON_BATCH-0x4) success
+ impersonator.cpp (296):
| Successfully impersonated @user
+ impersonator.cpp (302):
WhoAmI( ) reports: @user
[ndmp\ndmpsrvr] - LogonuserError: 0
[ndmp\ndmpsrvr] - BEC_GetRmanOpsOraVersion: 0
[ndmp\ndmpcomm] - NrdsWatchThread: advertise Now!
[ndmp\ndmpcomm] - NrdsAdvertiserThread: negative (purge) advertisement cycle started.
[ndmp\ndmpcomm] - NrdsAdvertiserThread: no purge is pending.
[ndmp\ndmpsrvr] - sslClose() : Closing SSL for: 00000000E5146C1F
[ndmp\ndmpcomm] - NrdsAdvertiserThread: negative (purge) advertisement cycle complete. Waiting 240 minutes before advertising again.
[BESocket] - @@@@@@@MyCloseSocket called with sockfd = 1880(0x758)
retval = 0

"VRTSRV::\\ClusterName\Hyper-V?HA?VM\VmName" does not allow snapshots. An attempt will be made to back up the source volume directly

ADAMM mover: DeviceIoScsiDrive::ReadData[2]: xfer=65536
ADAMM mover: DeviceIoScsiDrive::ReadData[2]: 0 <-
ADAMM mover: DeviceIoScsiDrive::ReadData[2]: pbuf=0x000000000C3CF000, count=65536, pxfer=0x00000000670EFAE0 ->
ADAMM mover: DeviceIo: SCSI: CDB 00:00:04:00 Start 08:44:12.282 End 08:44:12.283
ADAMM mover: DeviceIo: SCSI: ASPI Status
SCSI_COMPLETED_WITHOUT_ERROR
ADAMM mover: DeviceIo: SCSI: Target Status
SCSISTAT_GOOD
ADAMM mover: DeviceIo: SCSI: HBA Status
HOST_NO_ERROR
ADAMM mover: DeviceIo: SCSI: Function
SRB_FUNCTION_EXECUTE_SCSI
ADAMM mover: DeviceIo: SCSI:
ADAMM mover: DeviceIo: SCSI: Raw CDB
ADAMM mover: DeviceIo: SCSI: 08 01 00 00 01 00
ADAMM mover: DeviceIo: SCSI:
ADAMM mover: DeviceIo: SCSI: CDB Operation
READ6
ADAMM mover: DeviceIo: SCSI: LUN
0
ADAMM mover: DeviceIo: SCSI: SILI
False
ADAMM mover: DeviceIo: SCSI: Fixed
True
ADAMM mover: DeviceIo: SCSI: Length
1
ADAMM mover: DeviceIo: SCSI: Control
0x00
ADAMM mover: DeviceIo: SCSI: Data
ADAMM mover: DeviceIo: SCSI: 46 49 4C 45 20 78 78 78 78 78 78 78 78 78 78 78 FILE xxxxxxxxxxx
ADAMM mover: DeviceIo: SCSI: 78 78 78 78 78 78 78 78 78 78 78 78 78 78 78 78 xxxxxxxxxxxxxxxx
ADAMM mover: DeviceIo: SCSI: 78 78 78 78 78 78 78 78 78 78 78 78 78 78 78 78 xxxxxxxxxxxxxxxx
ADAMM mover: DeviceIo: SCSI: 78 78 78 78 78 78 78 78 78 78 78 78 78 78 78 78 xxxxxxxxxxxxxxxx
ADAMM mover: DeviceIo: SCSI:
ADAMM mover: DeviceIo: SCSI:

WARNING [000001A69C10B690]: -1: mtstrm::server::FpCache::addDo: The fingerprint cache limit of 20971520 has been reached for this session. DO 276875ab159bf33b007554a7f54ca02ad9355ce2c49ed02e5225025466844d83 will not be cached.
WARNING [000001A69C10B690]: -1: mtstrm::server::FpCache::addDo: The fingerprint cache limit of 20971520 has been reached for this session. DO 276875ab159bf33b007554a7f54ca02ad9355ce2c49ed02e5225025466844d83 will not be cached.
WARNING [000001A69C10B690]: -1: mtstrm::server::FpCache::addDo: The fingerprint cache limit of 20971520 has been reached for this session. DO 276875ab159bf33b007554a7f54ca02ad9355ce2c49ed02e5225025466844d83 will not be cached.
ERR [000001A69C10B690]: 37: Cr_ErrnoException caught in processCloseSession: Cannot close session BEOST_1674331330 because no such session is open

15175 PVLSVR 0 1928 PvlChanger::ReleaseDevice() - changer reservation release successful!
Session = {4F204428-7A30-4DF4-85A2-A812942D2C29}
Library = {F33E94B2-CF48-4090-9AE1-99382879DFB9}, "Dedupe"
hr = SUCCESS! SUCCESS!
15218 PVLSVR 0 1928 PvlDrive::GetMediaStatus()
Session = {4F204428-7A30-4DF4-85A2-A812942D2C29}
Drive = {8A4DFDE6-AACE-43D3-BE63-BD3ACCBA87BF}, "Dedupe:6"
Elapsed Seconds = 0000, Sense Code = 0x00000000
SUCCESS!
15219 PVLSVR 0 1928 DeviceIo: beost: Warn: [WARN] PDSTS: impl_image_handle: can't determine NBU frag number for (BEOST_00000609)
15220 PVLSVR 0 1928 DeviceIo: beost: Warn: [WARN] PDSTS: pd_locality_config: could not parse date from BEOST_00000609.tmp, the locality stream ID will not be set
15221 PVLSVR 0 1928 DeviceIo: beost: Warn: [WARN] PDSTS: sscanf_string: IMG_FORMAT= has no value.
15222 PVLSVR 0 1928 DeviceIo: beost: Warn: [WARN] PDSTS: sscanf_string: IMG_ISID_DPAID= has no value.
15223 PVLSVR 0 1928 DeviceIo: beost: Warn: [WARN] PDSTS: sscanf_string: IMG_ISID_DPAVER= has no value.
15224 PVLSVR 0 1928 DeviceIo: beost: Warn: [WARN] PDSTS: sscanf_string: IMG_NBUINFO_BCKPID_MASTER_SERVER= has no value.
15225 PVLSVR 0 1928 DeviceIo: beost: Warn: [WARN] PDSTS: sscanf_string: IMG_NBUINFO_CLIENT= has no value.
15226 PVLSVR 0 1928 DeviceIo: beost: Warn: [WARN] PDSTS: sscanf_string: IMG_NBUINFO_SLPNAME= has no value.
15227 PVLSVR 0 1928 DeviceIo: ostutil: sts_open_image BEOST_00000609 0 0.000000 seconds
15228 PVLSVR 0 1928 DeviceIo: ostutil: sts_get_image_prop BEOST_00000609 BEOST 0 0.000000 seconds
15229 PVLSVR 0 1928 DeviceIo: beost: Warn: [WARN] PDSTS: impl_image_handle: can't determine NBU frag number for (BEOST_00000609)
15230 PVLSVR 0 1928 DeviceIo: beost: Warn: [WARN] PDSTS: pd_locality_config: could not parse date from BEOST_00000609.tmp, the locality stream ID will not be set
15231 PVLSVR 0 1928 DeviceIo: beost: Warn: [WARN] PDSTS: sscanf_string: IMG_FORMAT= has no value.
15232 PVLSVR 0 1928 DeviceIo: beost: Warn: [WARN] PDSTS: sscanf_string: IMG_ISID_DPAID= has no value.
15233 PVLSVR 0 1928 DeviceIo: beost: Warn: [WARN] PDSTS: sscanf_string: IMG_ISID_DPAVER= has no value.
15234 PVLSVR 0 1928 DeviceIo: beost: Warn: [WARN] PDSTS: sscanf_string: IMG_NBUINFO_BCKPID_MASTER_SERVER= has no value.
15235 PVLSVR 0 1928 DeviceIo: beost: Warn: [WARN] PDSTS: sscanf_string: IMG_NBUINFO_CLIENT= has no value.
15236 PVLSVR 0 1928 DeviceIo: beost: Warn: [WARN] PDSTS: sscanf_string: IMG_NBUINFO_SLPNAME= has no value.
15237 PVLSVR 0 1928 DeviceIo: ostutil: sts_open_image BEOST_00000609 0 0.000000 seconds
15238 PVLSVR 0 1928 PvlDrive::ReadMediaHeader() - Media Guid = {00000004-0000-0000-0000-000000000000} Label = "" Slot = 0608 Header = ""

Please HELP

 

2 REPLIES 2

kf2013
Moderator
Moderator
   VIP   

so the problem only happened on Exchange VM, right?

 

Backup Exec 21 Best Practices (veritas.com)

  • Veritas recommends that you do not send an incremental GRT-enabled Exchange backup to a deduplication disk storage device. The transaction logs contain primarily unique data that does not deduplicate well. For best results, create a backup definition that runs a full backup of Exchange to a deduplication disk storage device, and then runs an incremental backup to a disk storage device.

Peter26
Level 2

Thank you for your reply.
Yes, that's right, but my problem is that the tasks on the deduplication disk hang at 0kb in the "Running - backup scan" status, while the same tasks are running without problems on a normal volume.