cancel
Showing results for 
Search instead for 
Did you mean: 

Exchange Backup fails to complete running groupshield 6.0

Rehan_Shaikh
Level 3
After installing GroupShield 6.0 (with all updates including service pack 3) backupexec fails to complete exchange backups. (exchange 2003)

Pre groupshield the back up job took only 2 hours to backup 16GB of data. Now the job runs over 25 hours and still doesn't complete. (data is still the same size).

I have tried stopping groupshield services whilst running the backup but this has no effect on the backup time.

Please can someone help
4 REPLIES 4

Sharvari_Deshmu
Level 6
Hello,

It is advisable to stop the anti-virus when running the backup jobs.

With what error does the job fail or job hangs on which status?

Also please check the technote below:
http://support.veritas.com/docs/241858
http://support.veritas.com/docs/264851



Thanks,

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.

Rehan_Shaikh
Level 3
The backup job is started at 20:00 hours so the exchange maintenance interval would not conflict.

The problem has only occurred after the installation of groupshield on the exchange server. Before the installation of groupshield the mailbox backups completed without hanging.

The hanging now is over 45 hours, that’s the longest I have left the job running to see if it completes. The job was still unable to complete and I was force to cancel the job as other backup job where getting queued up.

As a test I uninstalled groupshield from the exchange server and ran the backup job and it worked fine. However, I don’t want to have me exchange servers running without groupshield present. (upon re-installing groupshield I experienced the same problem, the backup job would not complete)

I have not tried article http://support.veritas.com/docs/264851, as it is no practical to run this on our 4 exchange servers before the start of a job and then after.

I’m also speaking to McAfee regarding this issue, but as of yet they have no solution to this as well.

priya_khire
Level 6
Hello,

Check if you get any errors in the Windows event logs or in the alerts tab within Backup Exec. You can run an sgmon before the job as per the following technote:

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

Run the job after this and paste the sgmon job log that indicates errors.

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.

Regards.

Rehan_Shaikh
Level 3
Log file generated

beserver: 0x0b18 08-0921:091118:AdammAdminBO: start query - NULL PVL
pvlsvr: 09/21/05 09:11:18 AdammSession::MountMedia()
Session = {EFB664EF-EE8A-46EB-8557-D4F2A7946DB1}
Library = {E2E37208-B842-412B-ADF3-D114A140CA74}, "IBM 1"
Slot = 0015
Drive = {37396603-700B-4CA0-B40A-3441480CECEF}, "HP 1"
Media = {004A1BB2-3013-4A5B-B303-017CDB1B25E8}, "F-TUE-EMAIL"
Side = 0001
SUCCESS!
beserver: 0x0b18 08-0921:091118:AdammAdminBO: end query.
bengine: 1054 21/09/2005 09:11:18: Block size: 65536
bengine: 1054 21/09/2005 09:11:18: Buffer size: 65536
bengine: 1054 21/09/2005 09:11:18: Num buffers: 10
bengine: 1054 21/09/2005 09:11:18: High water: 0
bengine: 1054 21/09/2005 09:11:18: BackupJob: medium successfully mounted
bengine: 1054 21/09/2005 09:11:18: Media Label: F-TUE-EMAIL
bengine: 1054 21/09/2005 09:11:18: Media GUID: {004A1BB2-3013-4A5B-B303-017CDB1B25E8}
bengine: 1054 21/09/2005 09:11:18: Overwrite Protected Until: 21/09/2005 13:11:18
bengine: 1054 21/09/2005 09:11:18: Appendable Until: 21/09/2005 09:11:18
bengine: 1054 21/09/2005 09:11:18: TAPEALERT: Get TapeAlert Flags Return Code = 0X0
bengine: 1054 21/09/2005 09:11:18: TAPEALERT: TapeAlert Device Flag = 0X0
bengine: 1054 21/09/2005 09:11:18: TAPEALERT: TapeAlert Changer Flag = 0X0
bengine: 1054 21/09/2005 09:11:18: TAPEALERT: Get TapeAlert Flags Return Code = 0X0
bengine: 1054 21/09/2005 09:11:18: TAPEALERT: Get TapeAlert Flags Return Code = 0X0
bengine: 1054 21/09/2005 09:11:18: SetupNDMPConnection(RANT)
bengine: 1054 21/09/2005 09:11:18: SetupNDMPConnection('CMP-XCH-003' Use Auth: True)
bengine: 1054 21/09/2005 09:11:18: NDMP version 3 connection CONNECTED
bengine: 1054 21/09/2005 09:11:18: SetupNDMPConnection(CMP-XCH-003) connection open Success
bengine: 1054 21/09/2005 09:11:18: SetupNDMPConnection(CMP-XCH-003) Find User Name & Password
bengine: 1054 21/09/2005 09:11:18: ndmpcGetUsernamePassword('\\CMP-XCH-003\Microsoft Information Store\Ceme Staff')
bengine: 1054 21/09/2005 09:11:18: ndmpcGetUsernamePassword('\\CMP-XCH-003\Microsoft Information Store\Ceme Staff') Not Found
bengine: 1054 21/09/2005 09:11:18: ndmpcGetUsernamePassword('\\CMP-XCH-003')
bengine: 1054 21/09/2005 09:11:18: ndmpcGetUsernamePassword('\\CMP-XCH-003') Found
bengine: 1054 21/09/2005 09:11:18: SetupNDMPConnection(CMP-XCH-003): connectClientAuth()
bengine: 1054 21/09/2005 09:11:18: ndmpcSnapshotPrepare: Warning. OSId: 38 is not snappable. Skipped.
bengine: 1054 21/09/2005 09:11:18: ndmpcSnapshotPrepare: Warning. OSId: 32 is not snappable. Skipped.
bengine: 1054 21/09/2005 09:11:18: ndmpcSnapshotPrepare: Warning. OSId: 46 is not snappable. Skipped.
bengine: 1054 21/09/2005 09:11:18: ndmpcSnapshotPrepare: Warning. OSId: 32 is not snappable. Skipped.
bengine: 1054 21/09/2005 09:11:18: ndmpcSnapshotPrepare: Warning. OSId: 35 is not snappable. Skipped.
bengine: 1054 21/09/2005 09:11:18: ndmpcSnapshotPrepare: Warning. No devices to snap. Returning with NDMP_SNAPSHOT_NO_DEVICES2SNAP
bengine: 1054 21/09/2005 09:11:18: TF_OpenTape()
bengine: 1054 21/09/2005 09:11:18: TF_GetDriveContext ...
sessionId = \\CMP-BAK-001\SESSION\{EFB664EF-EE8A-46EB-8557-D4F2A7946DB1}
initAsync = 1
bengine: 1054 21/09/2005 09:11:19: HARDWARE COMPRESSION ===> Compression is configurable.
bengine: 1054 21/09/2005 09:11:19: GET_DRV_INF: bsize = 65536
bengine: 1054 21/09/2005 09:11:19: HARDWARE COMPRESSION ===> Setting compression off.
bengine: 1054 21/09/2005 09:11:20: TF_GetDriveContext returning 0, context = 1A480F8
bengine: 1054 21/09/2005 09:11:20: Allocated 2 buffers, size 65536 bytes, total used: 131256
bengine: 1054 21/09/2005 09:11:20: HARDWARE COMPRESSION ===> Compression is configurable.
bengine: 1054 21/09/2005 09:11:20: GET_DRV_INF: bsize = 65536
bengine: 1054 21/09/2005 09:11:20: NDMPEngine::Run(): calling ProcessBSD()
bengine: 1054 21/09/2005 09:11:20: TF_OpenSet( )
bengine: 1054 21/09/2005 09:11:20: FreeFormatEnv( cur_fmt=65535 )
bengine: 1054 21/09/2005 09:11:20: RewindDrive mover ret = 0 (0x0)
bengine: 1054 21/09/2005 09:11:20: ret_val = 0
bengine: 1054 21/09/2005 09:11:20: Requested Set: ID = ffffffff Seq = -1 Set = -1
bengine: 1054 21/09/2005 09:11:20: Attempting to VCB storage media
bengine: 1054 21/09/2005 09:11:20: TpRead( :(
bengine: 1054 21/09/2005 09:11:20: Error = 0 Req = 65536 Got = 65536
bengine: 1054 21/09/2005 09:11:21: HARDWARE COMPRESSION ===> Compression is configurable.
bengine: 1054 21/09/2005 09:11:21: GET_DRV_INF: bsize = 65536
bengine: 1054 21/09/2005 09:11:21: FreeFormatEnv( cur_fmt=65535 )
bengine: 1054 21/09/2005 09:11:21: SetupFormatEnv( fmt=0 )
bengine: 1054 21/09/2005 09:11:21: TpRead( :(
bengine: 1054 21/09/2005 09:11:21: Error = 0 Req = 65536 Got = 65536
bengine: 1054 21/09/2005 09:11:21: ReadThisSet( cur_fmt=0 )
bengine: 1054 21/09/2005 09:11:21: ReadThisSet() return=0
bengine: 1054 21/09/2005 09:11:21: Current VCB: ID = 331ea019 Seq = 1 Set = 1
bengine: 1054 21/09/2005 09:11:21: PositionAtSet( :( TF Msg = 1
bengine: 1054 21/09/2005 09:11:21: UI Msg = 8003
bengine: 1054 21/09/2005 09:11:21: HARDWARE COMPRESSION ===> Compression is configurable.
bengine: 1054 21/09/2005 09:11:21: GET_DRV_INF: bsize = 65536
bengine: 1054 21/09/2005 09:11:25: RewindDrive mover ret = 0 (0x0)
bengine: 1054 21/09/2005 09:11:25: ret_val = 0
bengine: 1054 21/09/2005 09:11:25: SetupFormatEnv( fmt=0 )
bengine: 1054 21/09/2005 09:11:25: End of TF_OpenSet: Ret_val = 0 Buffs = 2 HiWater = 0
bengine: 1054 21/09/2005 09:11:25: HARDWARE COMPRESSION ===> Setting compression on.
bengine: 1054 21/09/2005 09:11:26: WriteEndSet( 1 ) returning 0
bengine: 1054 21/09/2005 09:11:30: Current Block is = 3
bengine: 1054 21/09/2005 09:11:30: ndmpcGetUsernamePassword('\\CMP-XCH-003\Microsoft Information Store\Ceme Staff')
bengine: 1054 21/09/2005 09:11:30: ndmpcGetUsernamePassword('\\CMP-XCH-003\Microsoft Information Store\Ceme Staff') Not Found
bengine: 1054 21/09/2005 09:11:30: ndmpcGetUsernamePassword('\\CMP-XCH-003')
bengine: 1054 21/09/2005 09:11:30: ndmpcGetUsernamePassword('\\CMP-XCH-003') Found
bengine: 1054 21/09/2005 09:11:30: OpenListenSocket: Media server IP address: 5502020a
bengine: 1054 21/09/2005 09:11:30: OpenListenSocket: Media server port: c904
bengine: 1054 21/09/2005 09:11:37:
dataStartBackup: ndmpSendRequest returned: 0x0, 0
beserver: 0x058c 08-0921:091137:AdammAdminBO: Get - NULL MEDIA
beserver: 0x058c 08-0921:091137:AdammAdminBO: end get.
bengine: 1054 21/09/2005 09:17:22: data halted: SUCCESSFUL
bengine: 1054 21/09/2005 09:17:22: NDMPEngine: Shutting down.
bengine: 1054 21/09/2005 09:17:22: TF_NDMPGetResult(): MediaServer thread done, returning TFLE 0
bengine: 1054 21/09/2005 09:17:22: WriteEndSet( 1 ) returning 0
bengine: 1054 21/09/2005 09:17:29: WriteEndSet( 1 ) returning 0
bengine: 1054 21/09/2005 09:17:32: WriteEndSet( 0 ) returning 0
bengine: 1054 21/09/2005 09:17:32: HARDWARE COMPRESSION ===> Setting compression off.
bengine: 1054 21/09/2005 09:17:35: TF_CloseSet
bengine: 1054 21/09/2005 09:17:35: NDMPEngine::Run(): ProcessBSD() returned 0
bengine: 1054 21/09/2005 09:17:35: NDMPEngine::Run(): calling ProcessBSD()
bengine: 1054 21/09/2005 09:17:35: TF_OpenSet( )
bengine: 1054 21/09/2005 09:17:35: Requested Set: ID = ffffffff Seq = -1 Set = -1
bengine: 1054 21/09/2005 09:17:35: Current VCB: ID = 3335496c Seq = 1 Set = 1
bengine: 1054 21/09/2005 09:17:35: PositionAtSet( :( TF Msg = 2
bengine: 1054 21/09/2005 09:17:35: UI Msg = 8002
bengine: 1054 21/09/2005 09:17:35: HARDWARE COMPRESSION ===> Compression is configurable.
bengine: 1054 21/09/2005 09:17:35: GET_DRV_INF: bsize = 65536
bengine: 1054 21/09/2005 09:17:35: SetupFormatEnv( fmt=0 )
bengine: 1054 21/09/2005 09:17:35: End of TF_OpenSet: Ret_val = 0 Buffs = 2 HiWater = 0
bengine: 1054 21/09/2005 09:17:35: HARDWARE COMPRESSION ===> Setting compression on.
bengine: 1054 21/09/2005 09:17:35: Current Block is = 1c9ca
bengine: 1054 21/09/2005 09:17:35: ndmpcGetUsernamePassword('\\CMP-XCH-003\Microsoft Exchange Public Folders')
bengine: 1054 21/09/2005 09:17:35: ndmpcGetUsernamePassword('\\CMP-XCH-003\Microsoft Exchange Public Folders') Found
bengine: 1054 21/09/2005 09:17:35: ndmpcGetUsernamePassword('\\CMP-XCH-003')
bengine: 1054 21/09/2005 09:17:35: ndmpcGetUsernamePassword('\\CMP-XCH-003') Found
bengine: 1054 21/09/2005 09:17:35: OpenListenSocket: Media server IP address: 5502020a
bengine: 1054 21/09/2005 09:17:35: OpenListenSocket: Media server port: ca04
bengine: 1054 21/09/2005 09:17:44:
dataStartBackup: ndmpSendRequest returned: 0x0, 0
bengine: 1054 21/09/2005 09:17:54: data halted: SUCCESSFUL
bengine: 1054 21/09/2005 09:17:54: NDMPEngine: Shutting down.
bengine: 1054 21/09/2005 09:17:54: TF_NDMPGetResult(): MediaServer thread done, returning TFLE 0
bengine: 1054 21/09/2005 09:17:56: WriteEndSet( 1 ) returning 0
bengine: 1054 21/09/2005 09:18:01: WriteEndSet( 1 ) returning 0
bengine: 1054 21/09/2005 09:18:03: WriteEndSet( 0 ) returning 0
bengine: 1054 21/09/2005 09:18:03: HARDWARE COMPRESSION ===> Setting compression off.
bengine: 1054 21/09/2005 09:18:06: TF_CloseSet
bengine: 1054 21/09/2005 09:18:06: NDMPEngine::Run(): ProcessBSD() returned 0
bengine: 1054 21/09/2005 09:18:06: NDMPEngine::Run(): calling ProcessBSD()
bengine: 1054 21/09/2005 09:18:06: TF_OpenSet( )
bengine: 1054 21/09/2005 09:18:06: Requested Set: ID = ffffffff Seq = -1 Set = -1
bengine: 1054 21/09/2005 09:18:06: Current VCB: ID = 3335496c Seq = 1 Set = 2
bengine: 1054 21/09/2005 09:18:06: PositionAtSet( :( TF Msg = 2
bengine: 1054 21/09/2005 09:18:06: UI Msg = 8002
bengine: 1054 21/09/2005 09:18:06: HARDWARE COMPRESSION ===> Compression is configurable.
bengine: 1054 21/09/2005 09:18:06: GET_DRV_INF: bsize = 65536
bengine: 1054 21/09/2005 09:18:06: SetupFormatEnv( fmt=0 )
bengine: 1054 21/09/2005 09:18:06: End of TF_OpenSet: Ret_val = 0 Buffs = 2 HiWater = 0
bengine: 1054 21/09/2005 09:18:06: HARDWARE COMPRESSION ===> Setting compression on.
bengine: 1054 21/09/2005 09:18:07: Current Block is = 1ca2d
bengine: 1054 21/09/2005 09:18:07: ndmpcGetUsernamePassword('\\CMP-XCH-003\')
bengine: 1054 21/09/2005 09:18:07: ndmpcGetUsernamePassword('\\CMP-XCH-003\') Not Found
bengine: 1054 21/09/2005 09:18:07: ndmpcGetUsernamePassword('\\CMP-XCH-003')
bengine: 1054 21/09/2005 09:18:07: ndmpcGetUsernamePassword('\\CMP-XCH-003') Found
bengine: 1054 21/09/2005 09:18:07: OpenListenSocket: Media server IP address: 5502020a
bengine: 1054 21/09/2005 09:18:07: OpenListenSocket: Media server port: cb04
bengine: 1054 21/09/2005 09:18:14:
dataStartBackup: ndmpSendRequest returned: 0x0, 0
bengine: 1054 21/09/2005 09:18:18: TF_NDMPGetResult(): MediaServer thread done, returning TFLE 0
bengine: 1054 21/09/2005 09:18:18: NDMPEngine::MessagePumpAndWaitForResults(): TF_NDMPGetResult() returned 0
bengine: 1054 21/09/2005 09:18:18: data halted: SUCCESSFUL
bengine: 1054 21/09/2005 09:18:18: NDMPEngine: Shutting down.
bengine: 1054 21/09/2005 09:18:21: WriteEndSet( 1 ) returning 0
bengine: 1054 21/09/2005 09:18:27: WriteEndSet( 1 ) returning 0
bengine: 1054 21/09/2005 09:18:29: WriteEndSet( 0 ) returning 0
bengine: 1054 21/09/2005 09:18:29: HARDWARE COMPRESSION ===> Setting compression off.
bengine:
Deleting null tmpFile : C:\Program Files\VERITAS\Backup Exec\NT\Catalogs\FIL5BF.tmp
bengine: 1054 21/09/2005 09:18:32: TF_CloseSet
bengine: 1054 21/09/2005 09:18:32: NDMPEngine::Run(): ProcessBSD() returned 0
bengine: 1054 21/09/2005 09:18:32: NDMPEngine::Run(): calling ProcessBSD()
bengine: 1054 21/09/2005 09:18:32: TF_OpenSet( )
bengine: 1054 21/09/2005 09:18:32: Requested Set: ID = ffffffff Seq = -1 Set = -1
bengine: 1054 21/09/2005 09:18:32: Current VCB: ID = 3335496c Seq = 1 Set = 3
bengine: 1054 21/09/2005 09:18:32: PositionAtSet( :( TF Msg = 2
bengine: 1054 21/09/2005 09:18:32: UI Msg = 8002
bengine: 1054 21/09/2005 09:18:32: HARDWARE COMPRESSION ===> Compression is configurable.
bengine: 1054 21/09/2005 09:18:32: GET_DRV_INF: bsize = 65536
bengine: 1054 21/09/2005 09:18:32: SetupFormatEnv( fmt=0 )
bengine: 1054 21/09/2005 09:18:32: End of TF_OpenSet: Ret_val = 0 Buffs = 2 HiWater = 0
bengine: 1054 21/09/2005 09:18:32: HARDWARE COMPRESSION ===> Setting compression on.
bengine: 1054 21/09/2005 09:18:33: Current Block is = 1cda3
bengine: 1054 21/09/2005 09:18:33: ndmpcGetUsernamePassword('\\CMP-XCH-003\Microsoft Exchange Mailboxes')
bengine: 1054 21/09/2005 09:18:33: ndmpcGetUsernamePassword('\\CMP-XCH-003\Microsoft Exchange Mailboxes') Found
bengine: 1054 21/09/2005 09:18:33: ndmpcGetUsernamePassword('\\CMP-XCH-003')
bengine: 1054 21/09/2005 09:18:33: ndmpcGetUsernamePassword('\\CMP-XCH-003') Found
bengine: 1054 21/09/2005 09:18:33: OpenListenSocket: Media server IP address: 5502020a
bengine: 1054 21/09/2005 09:18:33: OpenListenSocket: Media server port: cc04
bengine: 1054 21/09/2005 09:18:41:
dataStartBackup: ndmpSendRequest returned: 0x0, 0
bengine: ac0 21/09/2005 09:25:43: DeviceManager: timeout event fired
bengine: ac0 21/09/2005 09:25:43: DeviceManager: processing pending requests
bengine: ac0 21/09/2005 09:25:43: DeviceManager: going to sleep for 900000 msecs
bengine: ac0 21/09/2005 09:41:01: DeviceManager: timeout event fired
bengine: ac0 21/09/2005 09:41:01: DeviceManager: processing pending requests
bengine: ac0 21/09/2005 09:41:01: DeviceManager: going to sleep for 900000 msecs
bengine: ac0 21/09/2005 09:56:05: DeviceManager: timeout event fired
bengine: ac0 21/09/2005 09:56:05: DeviceManager: processing pending requests
bengine: ac0 21/09/2005 09:56:05: DeviceManager: going to sleep for 900000 msecs
bengine: ac0 21/09/2005 10:10:50: DeviceManager: timeout event fired
bengine: ac0 21/09/2005 10:10:50: DeviceManager: processing pending requests
bengine: ac0 21/09/2005 10:10:50: DeviceManager: going to sleep for 900000 msecs
bengine: ac0 21/09/2005 10:25:45: DeviceManager: timeout event fired
bengine: ac0 21/09/2005 10:25:45: DeviceManager: processing pending requests
bengine: ac0 21/09/2005 10:25:45: DeviceManager: going to sleep for 900000 msecs
bengine: ac0 21/09/2005 10:40:45: DeviceManager: timeout event fired
bengine: ac0 21/09/2005 10:40:45: DeviceManager: processing pending requests
bengine: ac0 21/09/2005 10:40:45: DeviceManager: going to sleep for 900000 msecs
bengine: ac0 21/09/2005 10:55:45: DeviceManager: timeout event fired
bengine: ac0 21/09/2005 10:55:45: DeviceManager: processing pending requests
bengine: ac0 21/09/2005 10:55:45: DeviceManager: going to sleep for 900000 msecs
bengine: ac0 21/09/2005 11:10:46: DeviceManager: timeout event fired
bengine: ac0 21/09/2005 11:10:46: DeviceManager: processing pending requests
bengine: ac0 21/09/2005 11:10:46: DeviceManager: going to sleep for 900000 msecs
bengine: 1054 21/09/2005 11:15:37: data halted: SUCCESSFUL
bengine: 1054 21/09/2005 11:15:37: NDMPEngine: Shutting down.
bengine: 1054 21/09/2005 11:15:37: TF_NDMPGetResult(): MediaServer thread done, returning TFLE 0
bengine: 1054 21/09/2005 11:15:37: WriteEndSet( 1 ) returning 0
bengine: 1054 21/09/2005 11:15:45: WriteEndSet( 1 ) returning 0
bengine: 1054 21/09/2005 11:15:47: WriteEndSet( 0 ) returning 0
bengine: 1054 21/09/2005 11:15:47: HARDWARE COMPRESSION ===> Setting compression off.
bengine: 1054 21/09/2005 11:15:53: TF_CloseSet
bengine: 1054 21/09/2005 11:15:53: NDMPEngine::Run(): ProcessBSD() returned 0
bengine: 1054 21/09/2005 11:15:53: NDMPEngine::Run(): calling ProcessBSD()
bengine: 1054 21/09/2005 11:15:53: TF_OpenSet( )
bengine: 1054 21/09/2005 11:15:53: Requested Set: ID = ffffffff Seq = -1 Set = -1
bengine: 1054 21/09/2005 11:15:53: Current VCB: ID = 3335496c Seq = 1 Set = 4
bengine: 1054 21/09/2005 11:15:53: PositionAtSet( :( TF Msg = 2
bengine: 1054 21/09/2005 11:15:53: UI Msg = 8002
bengine: 1054 21/09/2005 11:15:53: HARDWARE COMPRESSION ===> Compression is configurable.
bengine: 1054 21/09/2005 11:15:53: GET_DRV_INF: bsize = 65536
bengine: 1054 21/09/2005 11:15:53: SetupFormatEnv( fmt=0 )
bengine: 1054 21/09/2005 11:15:53: End of TF_OpenSet: Ret_val = 0 Buffs = 2 HiWater = 0
bengine: 1054 21/09/2005 11:15:53: HARDWARE COMPRESSION ===> Setting compression on.
bengine: 1054 21/09/2005 11:15:53: Current Block is = 37b42
bengine: 1054 21/09/2005 11:15:53: ndmpcGetUsernamePassword('\\CMP-XCH-003\System?State')
bengine: 1054 21/09/2005 11:15:53: ndmpcGetUsernamePassword('\\CMP-XCH-003\System?State') Not Found
bengine: 1054 21/09/2005 11:15:53: ndmpcGetUsernamePassword('\\CMP-XCH-003')
bengine: 1054 21/09/2005 11:15:53: ndmpcGetUsernamePassword('\\CMP-XCH-003') Found
bengine: 1054 21/09/2005 11:15:53: OpenListenSocket: Media server IP address: 5502020a
bengine: 1054 21/09/2005 11:15:53: OpenListenSocket: Media server port: cd04
bengine: 1054 21/09/2005 11:16:02:
dataStartBackup: ndmpSendRequest returned: 0x0, 0
bengine: 1054 21/09/2005 11:16:40: TF_NDMPGetResult(): MediaServer thread done, returning TFLE 0
bengine: 1054 21/09/2005 11:16:40: NDMPEngine::MessagePumpAndWaitForResults(): TF_NDMPGetResult() returned 0
bengine: 1054 21/09/2005 11:16:40: data halted: SUCCESSFUL
bengine: 1054 21/09/2005 11:16:40: NDMPEngine: Shutting down.
bengine: 1054 21/09/2005 11:16:42: WriteEndSet( 1 ) returning 0
bengine: 1054 21/09/2005 11:16:47: WriteEndSet( 1 ) returning 0
bengine: 1054 21/09/2005 11:16:50: WriteEndSet( 0 ) returning 0
bengine: 1054 21/09/2005 11:16:50: HARDWARE COMPRESSION ===> Setting compression off.
bengine: 1054 21/09/2005 11:16:53: TF_CloseSet
bengine: 1054 21/09/2005 11:16:53: NDMPEngine::Run(): ProcessBSD() returned 0
bengine: 1054 21/09/2005 11:16:53: SetupNDMPConnection(RANT)
bengine: 1054 21/09/2005 11:16:53: SetupNDMPConnection('CMP-XCH-C02' Use Auth: True)
bengine: 1054 21/09/2005 11:16:53: NDMP version 3 connection CONNECTED
bengine: 1054 21/09/2005 11:16:53: SetupNDMPConnection(CMP-XCH-C02) connection open Success
bengine: 1054 21/09/2005 11:16:53: SetupNDMPConnection(CMP-XCH-C02) Find User Name & Password
bengine: 1054 21/09/2005 11:16:53: ndmpcGetUsernamePassword('\\CMP-XCH-C02\Microsoft Information Store\CEME')
bengine: 1054 21/09/2005 11:16:53: ndmpcGetUsernamePassword('\\CMP-XCH-C02\Microsoft Information Store\CEME') Not Found
bengine: 1054 21/09/2005 11:16:53: ndmpcGetUsernamePassword('\\CMP-XCH-C02')
bengine: 1054 21/09/2005 11:16:53: ndmpcGetUsernamePassword('\\CMP-XCH-C02') Found
bengine: 1054 21/09/2005 11:16:53: SetupNDMPConnection(CMP-XCH-C02): connectClientAuth()
bengine: 1054 21/09/2005 11:16:53: ndmpcSnapshotPrepare: Warning. OSId: 38 is not snappable. Skipped.
bengine: 1054 21/09/2005 11:16:53: ndmpcSnapshotPrepare: Warning. OSId: 32 is not snappable. Skipped.
bengine: 1054 21/09/2005 11:16:53: ndmpcSnapshotPrepare: Warning. OSId: 38 is not snappable. Skipped.
bengine: 1054 21/09/2005 11:16:53: ndmpcSnapshotPrepare: Warning. OSId: 32 is not snappable. Skipped.
bengine: 1054 21/09/2005 11:16:53: ndmpcSnapshotPrepare: Warning. No devices to snap. Returning with NDMP_SNAPSHOT_NO_DEVICES2SNAP
bengine: 1054 21/09/2005 11:16:53: NDMPEngine::Run(): calling ProcessBSD()
bengine: 1054 21/09/2005 11:16:53: TF_OpenSet( )
bengine: 1054 21/09/2005 11:16:53: Requested Set: ID = ffffffff Seq = -1 Set = -1
bengine: 1054 21/09/2005 11:16:53: Current VCB: ID = 3335496c Seq = 1 Set = 5
bengine: 1054 21/09/2005 11:16:53: PositionAtSet( :( TF Msg = 2
bengine: 1054 21/09/2005 11:16:53: UI Msg = 8002
bengine: 1054 21/09/2005 11:16:53: HARDWARE COMPRESSION ===> Compression is configurable.
bengine: 1054 21/09/2005 11:16:53: GET_DRV_INF: bsize = 65536
bengine: 1054 21/09/2005 11:16:53: SetupFormatEnv( fmt=0 )
bengine: 1054 21/09/2005 11:16:53: End of TF_OpenSet: Ret_val = 0 Buffs = 2 HiWater = 0
bengine: 1054 21/09/2005 11:16:53: HARDWARE COMPRESSION ===> Setting compression on.
bengine: 1054 21/09/2005 11:16:54: Current Block is = 38ae5
bengine: 1054 21/09/2005 11:16:54: ndmpcGetUsernamePassword('\\CMP-XCH-C02\Microsoft Information Store\CEME')
bengine: 1054 21/09/2005 11:16:54: ndmpcGetUsernamePassword('\\CMP-XCH-C02\Microsoft Information Store\CEME') Not Found
bengine: 1054 21/09/2005 11:16:54: ndmpcGetUsernamePassword('\\CMP-XCH-C02')
bengine: 1054 21/09/2005 11:16:54: ndmpcGetUsernamePassword('\\CMP-XCH-C02') Found
bengine: 1054 21/09/2005 11:16:54: OpenListenSocket: Media server IP address: 5502020a
bengine: 1054 21/09/2005 11:16:54: OpenListenSocket: Media server port: cf04
bengine: 1054 21/09/2005 11:16:58:
dataStartBackup: ndmpSendRequest returned: 0x0, 0
bengine: 1054 21/09/2005 11:20:42: data halted: SUCCESSFUL
bengine: 1054 21/09/2005 11:20:42: NDMPEngine: Shutting down.
bengine: 1054 21/09/2005 11:20:42: TF_NDMPGetResult(): MediaServer thread done, returning TFLE 0
bengine: 1054 21/09/2005 11:20:44: WriteEndSet( 1 ) returning 0
bengine: 1054 21/09/2005 11:20:49: WriteEndSet( 1 ) returning 0
bengine: 1054 21/09/2005 11:20:52: WriteEndSet( 0 ) returning 0
bengine: 1054 21/09/2005 11:20:52: HARDWARE COMPRESSION ===> Setting compression off.
bengine: 1054 21/09/2005 11:20:55: TF_CloseSet
bengine: 1054 21/09/2005 11:20:55: NDMPEngine::Run(): ProcessBSD() returned 0
bengine: 1054 21/09/2005 11:20:55: NDMPEngine::Run(): calling ProcessBSD()
bengine: 1054 21/09/2005 11:20:55: TF_OpenSet( )
bengine: 1054 21/09/2005 11:20:55: Requested Set: ID = ffffffff Seq = -1 Set = -1
bengine: 1054 21/09/2005 11:20:55: Current VCB: ID = 3335496c Seq = 1 Set = 6
bengine: 1054 21/09/2005 11:20:55: PositionAtSet( :( TF Msg = 2
bengine: 1054 21/09/2005 11:20:55: UI Msg = 8002
bengine: 1054 21/09/2005 11:20:55: HARDWARE COMPRESSION ===> Compression is configurable.
bengine: 1054 21/09/2005 11:20:55: GET_DRV_INF: bsize = 65536
bengine: 1054 21/09/2005 11:20:55: SetupFormatEnv( fmt=0 )
bengine: 1054 21/09/2005 11:20:55: End of TF_OpenSet: Ret_val = 0 Buffs = 2 HiWater = 0
bengine: 1054 21/09/2005 11:20:55: HARDWARE COMPRESSION ===> Setting compression on.
bengine: 1054 21/09/2005 11:20:55: Current Block is = 3e81e
bengine: 1054 21/09/2005 11:20:55: ndmpcGetUsernamePassword('\\CMP-XCH-C02\Microsoft Exchange Public Folders')
bengine: 1054 21/09/2005 11:20:55: ndmpcGetUsernamePassword('\\CMP-XCH-C02\Microsoft Exchange Public Folders') Not Found
bengine: 1054 21/09/2005 11:20:55: ndmpcGetUsernamePassword('\\CMP-XCH-C02')
bengine: 1054 21/09/2005 11:20:55: ndmpcGetUsernamePassword('\\CMP-XCH-C02') Found
bengine: 1054 21/09/2005 11:20:55: OpenListenSocket: Media server IP address: 5502020a
bengine: 1054 21/09/2005 11:20:55: OpenListenSocket: Media server port: d004
bengine: 1054 21/09/2005 11:20:59:
dataStartBackup: ndmpSendRequest returned: 0x0, 0
bengine: 1054 21/09/2005 11:21:23: data halted: SUCCESSFUL
bengine: 1054 21/09/2005 11:21:23: NDMPEngine: Shutting down.
bengine: 1054 21/09/2005 11:21:23: TF_NDMPGetResult(): MediaServer thread done, returning TFLE 0
bengine: 1054 21/09/2005 11:21:25: WriteEndSet( 1 ) returning 0
bengine: 1054 21/09/2005 11:21:30: WriteEndSet( 1 ) returning 0
bengine: 1054 21/09/2005 11:21:33: WriteEndSet( 0 ) returning 0
bengine: 1054 21/09/2005 11:21:33: HARDWARE COMPRESSION ===> Setting compression off.
bengine: 1054 21/09/2005 11:21:36: TF_CloseSet
bengine: 1054 21/09/2005 11:21:36: NDMPEngine::Run(): ProcessBSD() returned 0
bengine: 1054 21/09/2005 11:21:36: NDMPEngine::Run(): calling ProcessBSD()
bengine: 1054 21/09/2005 11:21:36: TF_OpenSet( )
bengine: 1054 21/09/2005 11:21:36: Requested Set: ID = ffffffff Seq = -1 Set = -1
bengine: 1054 21/09/2005 11:21:36: Current VCB: ID = 3335496c Seq = 1 Set = 7
bengine: 1054 21/09/2005 11:21:36: PositionAtSet( :( TF Msg = 2
bengine: 1054 21/09/2005 11:21:36: UI Msg = 8002
bengine: 1054 21/09/2005 11:21:36: HARDWARE COMPRESSION ===> Compression is configurable.
bengine: 1054 21/09/2005 11:21:36: GET_DRV_INF: bsize = 65536
bengine: 1054 21/09/2005 11:21:36: SetupFormatEnv( fmt=0 )
bengine: 1054 21/09/2005 11:21:36: End of TF_OpenSet: Ret_val = 0 Buffs = 2 HiWater = 0
bengine: 1054 21/09/2005 11:21:36: HARDWARE COMPRESSION ===> Setting compression on.
bengine: 1054 21/09/2005 11:21:37: Current Block is = 3e881
bengine: 1054 21/09/2005 11:21:37: ndmpcGetUsernamePassword('\\CMP-XCH-C02\Microsoft Site Replication Service\SRS Storage')
bengine: 1054 21/09/2005 11:21:37: ndmpcGetUsernamePassword('\\CMP-XCH-C02\Microsoft Site Replication Service\SRS Storage') Not Found
bengine: 1054 21/09/2005 11:21:37: ndmpcGetUsernamePassword('\\CMP-XCH-C02')
bengine: 1054 21/09/2005 11:21:37: ndmpcGetUsernamePassword('\\CMP-XCH-C02') Found
bengine: 1054 21/09/2005 11:21:37: OpenListenSocket: Media server IP address: 5502020a
bengine: 1054 21/09/2005 11:21:37: OpenListenSocket: Media server port: d104
bengine: 1054 21/09/2005 11:21:41:
dataStartBackup: ndmpSendRequest returned: 0x0, 0
bengine: 1054 21/09/2005 11:21:41: ERROR: Error: Invalid device
bengine: 1054 21/09/2005 11:21:41:
dataStartBackup: ERROR returning: 0x10, 16
bengine: 1054 21/09/2005 11:21:43: data halted: ABORTED
bengine: 1054 21/09/2005 11:21:45: TF_CloseSet
bengine: 1054 21/09/2005 11:21:45: NDMPEngine::Run(): ProcessBSD() returned 0
bengine: 1054 21/09/2005 11:21:45: NDMPEngine::Run(): calling ProcessBSD()
bengine: 1054 21/09/2005 11:21:45: TF_OpenSet( )
bengine: 1054 21/09/2005 11:21:45: Requested Set: ID = ffffffff Seq = -1 Set = -1
bengine: 1054 21/09/2005 11:21:45: Current VCB: ID = 3335496c Seq = 1 Set = 7
bengine: 1054 21/09/2005 11:21:45: PositionAtSet( :( TF Msg = 3
bengine: 1054 21/09/2005 11:21:45: UI Msg = 800f
bengine: 1054 21/09/2005 11:21:45: HARDWARE COMPRESSION ===> Compression is configurable.
bengine: 1054 21/09/2005 11:21:45: GET_DRV_INF: bsize = 65536
bengine: 1054 21/09/2005 11:21:45: Deleting left over Set Map!!!
bengine: 1054 21/09/2005 11:21:45: F40_SeekEOD starting seek...
bengine: 1054 21/09/2005 11:21:45: F40_SeekEOD calling OTC_GetPrevSM with expect_sm = 1 ...
bengine: 1054 21/09/2005 11:21:45: OTC_GetPrevSM spacing back two filemarks...
bengine: 1054 21/09/2005 11:21:46: OTC_GetPrevSM: ESET position = 256121
bengine: 1054 21/09/2005 11:21:46: OTC_GetPrevSM calling OTC_SMtoFile...
bengine: 1054 21/09/2005 11:21:46: SetupFormatEnv( fmt=0 )
bengine: 1054 21/09/2005 11:21:46: End of TF_OpenSet: Ret_val = 0 Buffs = 2 HiWater = 0
bengine: 1054 21/09/2005 11:21:46: Deleting left over FDD!!!
bengine: 1054 21/09/2005 11:21:46: HARDWARE COMPRESSION ===> Setting compression on.
bengine: 1054 21/09/2005 11:21:46: Current Block is = 3e881
bengine: 1054 21/09/2005 11:21:46: ndmpcGetUsernamePassword('\\CMP-XCH-C02\Microsoft Exchange Mailboxes')
bengine: 1054 21/09/2005 11:21:46: ndmpcGetUsernamePassword('\\CMP-XCH-C02\Microsoft Exchange Mailboxes') Found
bengine: 1054 21/09/2005 11:21:46: ndmpcGetUsernamePassword('\\CMP-XCH-C02')
bengine: 1054 21/09/2005 11:21:46: ndmpcGetUsernamePassword('\\CMP-XCH-C02') Found
bengine: 1054 21/09/2005 11:21:46: OpenListenSocket: Media server IP address: 5502020a
bengine: 1054 21/09/2005 11:21:46: OpenListenSocket: Media server port: d204
bengine: 1054 21/09/2005 11:21:50:
dataStartBackup: ndmpSendRequest returned: 0x0, 0
bengine: ac0 21/09/2005 11:25:45: DeviceManager: timeout event fired
bengine: ac0 21/09/2005 11:25:45: DeviceManager: processing pending requests
bengine: ac0 21/09/2005 11:25:45: DeviceManager: going to sleep for 900000 msecs
bengine: ac0 21/09/2005 11:40:46: DeviceManager: timeout event fired
bengine: ac0 21/09/2005 11:40:46: DeviceManager: processing pending requests
bengine: ac0 21/09/2005 11:40:46: DeviceManager: going to sleep for 900000 msecs
bengine: ac0 21/09/2005 11:55:46: DeviceManager: timeout event fired
bengine: ac0 21/09/2005 11:55:46: DeviceManager: processing pending requests
bengine: ac0 21/09/2005 11:55:46: DeviceManager: going to sleep for 900000 msecs
bengine: ac0 21/09/2005 12:10:46: DeviceManager: timeout event fired
bengine: ac0 21/09/2005 12:10:46: DeviceManager: processing pending requests
bengine: ac0 21/09/2005 12:10:46: DeviceManager: going to sleep for 900000 msecs
bengine: ac0 21/09/2005 12:25:46: DeviceManager: timeout event fired
bengine: ac0 21/09/2005 12:25:46: DeviceManager: processing pending requests
bengine: ac0 21/09/2005 12:25:46: DeviceManager: going to sleep for 900000 msecs