Turn on suggestions
Auto-suggest helps you quickly narrow down your search results by suggesting possible matches as you type.
Showing results for
- VOX
- Data Protection
- Backup Exec
- Slow loading of disk media
Options
- Subscribe to RSS Feed
- Mark Topic as New
- Mark Topic as Read
- Float this Topic for Current User
- Bookmark
- Subscribe
- Mute
- Printer Friendly Page
Slow loading of disk media
Options
- Mark as New
- Bookmark
- Subscribe
- Mute
- Subscribe to RSS Feed
- Permalink
- Report Inappropriate Content
01-28-2005 01:39 PM
We're backing up to USB hard disks with 9.1. As the number of files on the backup disks has increased, the media load time has become ridiculously large (many minutes). I imaged the backup server to essentially identical hardware and reproduced the problem. Then I upgraded to version 10 trial and found that v10 fixes the problem.
The question is, can version 9.1 be fixed or do we need to upgrade?
Our server is running XP workstation. XP and BE9.1 are fully patched.
The question is, can version 9.1 be fixed or do we need to upgrade?
Our server is running XP workstation. XP and BE9.1 are fully patched.
Labels:
10 REPLIES 10
Options
- Mark as New
- Bookmark
- Subscribe
- Mute
- Subscribe to RSS Feed
- Permalink
- Report Inappropriate Content
01-31-2005 01:59 AM
Hi,
There might be various reasons which could be the cause for this issue :
Refer to the following guidelines which would help you to overcome issue :
1. Please make sure that the antivirus protection is disabled during the backup process.
2. Ensure that all the hardware devices are working properly.
3. Ensure that there are no third party applications running during the backup process.
4.Test a backup job when the network activity is minimal and observe the results
5. Defrag the Server Partitions.
http://seer.support.veritas.com/docs/237444.htm
http://seer.support.veritas.com/docs/249090.htm
6. If multiple protocols are configured, remove all except TCP/IP
7. Force port speed and duplex mode on the network card and on hub/switch port.
8. Monitor the network for physical problems (Use management utilities for managed network equipment, Network monitor, Protocol analyzer, Event Viewer, etc.)
9.Make sure that you are using removable backup to disk folders for backups on the USB hard drive.
Also ensure following settings on the Backup Exec Server:
- Change the compression type to "Hardware if available, otherwise none" from Tools | Options
- Start the Backup again and check the speed.
Hope this helps.
There might be various reasons which could be the cause for this issue :
Refer to the following guidelines which would help you to overcome issue :
1. Please make sure that the antivirus protection is disabled during the backup process.
2. Ensure that all the hardware devices are working properly.
3. Ensure that there are no third party applications running during the backup process.
4.Test a backup job when the network activity is minimal and observe the results
5. Defrag the Server Partitions.
http://seer.support.veritas.com/docs/237444.htm
http://seer.support.veritas.com/docs/249090.htm
6. If multiple protocols are configured, remove all except TCP/IP
7. Force port speed and duplex mode on the network card and on hub/switch port.
8. Monitor the network for physical problems (Use management utilities for managed network equipment, Network monitor, Protocol analyzer, Event Viewer, etc.)
9.Make sure that you are using removable backup to disk folders for backups on the USB hard drive.
Also ensure following settings on the Backup Exec Server:
- Change the compression type to "Hardware if available, otherwise none" from Tools | Options
- Start the Backup again and check the speed.
Hope this helps.
Options
- Mark as New
- Bookmark
- Subscribe
- Mute
- Subscribe to RSS Feed
- Permalink
- Report Inappropriate Content
01-31-2005 08:54 AM
> 1. Please make sure that the antivirus protection is
> disabled during the backup process.
No antivirus software is installed.
> 2. Ensure that all the hardware devices are working
> properly.
>
> 3. Ensure that there are no third party applications
> running during the backup process.
The fact that ver 10 trial is not slow would seem to eliminate these.
> 4.Test a backup job when the network activity is
> minimal and observe the results
I should have mentioned that the slowness is seen backing up the local machine, so network is not an issue.
> 5. Defrag the Server Partitions.
Fragmentation is minimal and again, ver 10 is not slow.
> 6. If multiple protocols are configured, remove all
> except TCP/IP
>
> 7. Force port speed and duplex mode on the network
> card and on hub/switch port.
>
> 8. Monitor the network for physical problems (Use
> management utilities for managed network equipment,
> Network monitor, Protocol analyzer, Event Viewer,
> etc.)
It's slow without using the network.
> 9.Make sure that you are using removable backup to
> disk folders for backups on the USB hard drive.
We are using removable backup to disk folders
> - Change the compression type to "Hardware if
> available, otherwise none" from Tools | Options
We are using software compression in 9.1 and in 10 trial.
> disabled during the backup process.
No antivirus software is installed.
> 2. Ensure that all the hardware devices are working
> properly.
>
> 3. Ensure that there are no third party applications
> running during the backup process.
The fact that ver 10 trial is not slow would seem to eliminate these.
> 4.Test a backup job when the network activity is
> minimal and observe the results
I should have mentioned that the slowness is seen backing up the local machine, so network is not an issue.
> 5. Defrag the Server Partitions.
Fragmentation is minimal and again, ver 10 is not slow.
> 6. If multiple protocols are configured, remove all
> except TCP/IP
>
> 7. Force port speed and duplex mode on the network
> card and on hub/switch port.
>
> 8. Monitor the network for physical problems (Use
> management utilities for managed network equipment,
> Network monitor, Protocol analyzer, Event Viewer,
> etc.)
It's slow without using the network.
> 9.Make sure that you are using removable backup to
> disk folders for backups on the USB hard drive.
We are using removable backup to disk folders
> - Change the compression type to "Hardware if
> available, otherwise none" from Tools | Options
We are using software compression in 9.1 and in 10 trial.
Options
- Mark as New
- Bookmark
- Subscribe
- Mute
- Subscribe to RSS Feed
- Permalink
- Report Inappropriate Content
02-02-2005 01:49 AM
In order to troubleshoot the problem, kindly create another removable Backup to disk folder and observe the result.
http://seer.support.veritas.com/docs/253764.htm
-------------------------------------------------
Also please refer the following
In Backup Exec 9.x for Windows Servers the job logs are stored in XML format which improves flexibility (path program files\Veritas\Backup Exec\NT\Data folder). You can delete the unwanted .XML files (this will delete the job logs). Note that once you delete the .XML files then you cannot recover the respective job logs. (You can delete the job logs older than three weeks as they are not required for Restore operation)
As an additional information, kindly refer to the steps mentioned in the technote given below:-
1) Title:- How to compact the VERITAS Backup Exec (tm) DB database in Backup Exec 9.x for Windows Servers to shrink the database file
http://seer.support.veritas.com/docs/268316.htm
2) Title:-What is the purpose of " After 'x' days" option in Backup Exec for Windows NT/2000 Server?
http://seer.support.veritas.com/docs/229528.htm
We hope this helps.
http://seer.support.veritas.com/docs/253764.htm
-------------------------------------------------
Also please refer the following
In Backup Exec 9.x for Windows Servers the job logs are stored in XML format which improves flexibility (path program files\Veritas\Backup Exec\NT\Data folder). You can delete the unwanted .XML files (this will delete the job logs). Note that once you delete the .XML files then you cannot recover the respective job logs. (You can delete the job logs older than three weeks as they are not required for Restore operation)
As an additional information, kindly refer to the steps mentioned in the technote given below:-
1) Title:- How to compact the VERITAS Backup Exec (tm) DB database in Backup Exec 9.x for Windows Servers to shrink the database file
http://seer.support.veritas.com/docs/268316.htm
2) Title:-What is the purpose of "
http://seer.support.veritas.com/docs/229528.htm
We hope this helps.
Options
- Mark as New
- Bookmark
- Subscribe
- Mute
- Subscribe to RSS Feed
- Permalink
- Report Inappropriate Content
02-02-2005 02:00 PM
I ran SGMon and I think I see what is happening. When BE is displaying a loading media message, the log file shows messages from PvlChanger and PvlDrive mounting and dismounting media. The slot number is incremented for each set of messages until it finds an open slot. The slots I assume correspond to the 1GB pieces the backup is broken up into. Each slot check takes about a second. It's now counting up to around 235, and the count will only get larger since we're using 400GB drives. After 1GB is written, the check starts again from zero. It would work a lot better if it started the slot check at the last one used, but the way it's doing it now, it spends more time checking slots than it does backing up.
Options
- Mark as New
- Bookmark
- Subscribe
- Mute
- Subscribe to RSS Feed
- Permalink
- Report Inappropriate Content
02-04-2005 01:34 AM
Check for any Event ID's reported in the application logs.
If possible, rerun the backup jobs and paste the corresponding SGMON log in the post .
If possible, rerun the backup jobs and paste the corresponding SGMON log in the post .
Options
- Mark as New
- Bookmark
- Subscribe
- Mute
- Subscribe to RSS Feed
- Permalink
- Report Inappropriate Content
02-04-2005 07:43 AM
> Check for any Event ID's reported in the application
> logs.
The application log has only information messages and an error at the end of each nightly job for a few "corrupt files" because we don't have the open file option. (The slowness is seen backing up machines that have no open file errors, so is unrelated to this problem.)
> If possible, rerun the backup jobs and paste the
> corresponding SGMON log in the post .
Here's an example. Logging was turned on while the job status was "Running"; messages began appearing in the log when the status went to "Loading Media".
Capturing to C:\Program Files\VERITAS\Backup Exec\NT\logs\SGMon.log
bengine: 9f0 2/2/2005 16:25:59: WriteEndSet( 1 ) returning 0
bengine: 9f0 2/2/2005 16:25:59: WriteEndSet( 1 ) returning 0
bengine: 9f0 2/2/2005 16:25:59: WriteEndSet( 0 ) returning 0
bengine: 9f0 2/2/2005 16:25:59: RewindDrive mover ret = 0 (0x0)
bengine: 9f0 2/2/2005 16:25:59: ret_val = 0
bengine: 9f0 2/2/2005 16:25:59: Requested Set: ID = ffffffff Seq = -1 Set = -1
bengine: 9f0 2/2/2005 16:25:59: PositionAtSet( :( TF Msg = 12
bengine: 9f0 2/2/2005 16:25:59: RewindDrive mover ret = 0 (0x0)
bengine: 9f0 2/2/2005 16:25:59: ret_val = 0
bengine: 9f0 2/2/2005 16:25:59: TAPEALERT: Get TapeAlert Flags Return Code = 0X0
bengine: 9f0 2/2/2005 16:25:59: TAPEALERT: TapeAlert Device Flag = 0X0
bengine: 9f0 2/2/2005 16:25:59: TAPEALERT: TapeAlert Changer Flag = 0X0
bengine: 9f0 2/2/2005 16:25:59: TAPEALERT: Get TapeAlert Flags Return Code = 0X0
bengine: 9f0 2/2/2005 16:25:59: Entering TF_MountNewMedia ...
pvlsvr: 02/02/05 16:25:59 AdammSession::Execute( ADAMM_SESSION_EXECUTE_MOVER_RELEASE )
Session = {E97EA5A9-A018-475E-8E98-AE628DE1B53D}
bengine: 9f0 2/2/2005 16:25:59: TF_MountNewMedia: mediaId == NULL
pvlsvr: 02/02/05 16:25:59 AdammSession::Execute( ADAMM_SESSION_EXECUTE_MOVER_MOUNT_CONTINUATION )
Session = {E97EA5A9-A018-475E-8E98-AE628DE1B53D}
pvlsvr: 02/02/05 16:25:59 AdammSession::CheckTargetDeviceStatus()
Session = {E97EA5A9-A018-475E-8E98-AE628DE1B53D}
Job = {4411D913-DFD3-4FE5-BD60-F9C7E8D1F342}, "Tuesday even"
TargetDevice = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
State = 0003, ReservationID = 0000, AvailableMovers = 0000
pvlsvr: 02/02/05 16:25:59 AdammSession::DismountMedia()
Session = {E97EA5A9-A018-475E-8E98-AE628DE1B53D}
Library = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Slot = 0000
Drive = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Media = {6780D2EF-E407-4644-8F99-C7AB800435FA}, "B2D002372"
Side = 0001
pvlsvr: 02/02/05 16:25:59 PvlDrive::DismountMedia()
Session = {E97EA5A9-A018-475E-8E98-AE628DE1B53D}
Drive = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Slot = 0235
Side = 0001
beserver: 0x0fac 08-0202:162600:AdammAdminBO: start query - NULL PVL
pvlsvr: 02/02/05 16:26:00 PvlChanger::DismountMedia()
Session = {E97EA5A9-A018-475E-8E98-AE628DE1B53D}
Library = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Drive = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Slot = 0235
Side = 0001
beserver: 0x0fac 08-0202:162600:AdammAdminBO: end query.
pvlsvr: 02/02/05 16:26:01 PvlChanger::MountMedia()
Session = {E97EA5A9-A018-475E-8E98-AE628DE1B53D}
Library = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Drive = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Slot = 268369920
Side = 0001
pvlsvr: 02/02/05 16:26:01 PvlChanger::DismountMedia()
Session = {E97EA5A9-A018-475E-8E98-AE628DE1B53D}
Library = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Drive = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Slot = 268369920
Side = 0001
pvlsvr: 02/02/05 16:26:01 PvlDrive::MountMedia()
Session = {E97EA5A9-A018-475E-8E98-AE628DE1B53D}
Drive = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Slot = 0000
Side = 0001
pvlsvr: 02/02/05 16:26:01 PvlChanger::MountMedia()
Session = {E97EA5A9-A018-475E-8E98-AE628DE1B53D}
Library = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Drive = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Slot = 0000
Side = 0001
pvlsvr: 02/02/05 16:26:02 PvlDrive::DismountMedia()
Session = {E97EA5A9-A018-475E-8E98-AE628DE1B53D}
Drive = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Slot = 0000
Side = 0001
pvlsvr: 02/02/05 16:26:02 PvlChanger::DismountMedia()
Session = {E97EA5A9-A018-475E-8E98-AE628DE1B53D}
Library = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Drive = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Slot = 0000
Side = 0001
pvlsvr: 02/02/05 16:26:03 PvlDrive::MountMedia()
Session = {E97EA5A9-A018-475E-8E98-AE628DE1B53D}
Drive = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Slot = 0001
Side = 0001
pvlsvr: 02/02/05 16:26:03 PvlChanger::MountMedia()
Session = {E97EA5A9-A018-475E-8E98-AE628DE1B53D}
Library = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Drive = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Slot = 0001
Side = 0001
pvlsvr: 02/02/05 16:26:04 PvlDrive::DismountMedia()
Session = {E97EA5A9-A018-475E-8E98-AE628DE1B53D}
Drive = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Slot = 0001
Side = 0001
pvlsvr: 02/02/05 16:26:04 PvlChanger::DismountMedia()
Session = {E97EA5A9-A018-475E-8E98-AE628DE1B53D}
Library = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Drive = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Slot = 0001
Side = 0001
pvlsvr: 02/02/05 16:26:04 PvlDrive::MountMedia()
Session = {E97EA5A9-A018-475E-8E98-AE628DE1B53D}
Drive = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Slot = 0002
Side = 0001
pvlsvr: 02/02/05 16:26:04 PvlChanger::MountMedia()
Session = {E97EA5A9-A018-475E-8E98-AE628DE1B53D}
Library = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Drive = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Slot = 0002
Side = 0001
pvlsvr: 02/02/05 16:26:05 PvlDrive::DismountMedia()
Session = {E97EA5A9-A018-475E-8E98-AE628DE1B53D}
Drive = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Slot = 0002
Side = 0001
pvlsvr: 02/02/05 16:26:05 PvlChanger::DismountMedia()
Session = {E97EA5A9-A018-475E-8E98-AE628DE1B53D}
Library = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Drive = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Slot = 0002
Side = 0001
********* deleted similar lines as slot counts up
pvlsvr: 02/02/05 16:31:21 PvlDrive::MountMedia()
Session = {E97EA5A9-A018-475E-8E98-AE628DE1B53D}
Drive = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Slot = 0236
Side = 0001
pvlsvr: 02/02/05 16:31:21 PvlChanger::MountMedia()
Session = {E97EA5A9-A018-475E-8E98-AE628DE1B53D}
Library = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Drive = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Slot = 0236
Side = 0001
pvlsvr: 02/02/05 16:31:22 PvlDrive::DismountMedia()
Session = {E97EA5A9-A018-475E-8E98-AE628DE1B53D}
Drive = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Slot = 0236
Side = 0001
pvlsvr: 02/02/05 16:31:22 PvlChanger::DismountMedia()
Session = {E97EA5A9-A018-475E-8E98-AE628DE1B53D}
Library = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Drive = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Slot = 0236
Side = 0001
pvlsvr: 02/02/05 16:31:22 AdammSession::Execute( ADAMM_SESSION_EXECUTE_MOVER_MOUNT_CONTINUATION )
Session = {E97EA5A9-A018-475E-8E98-AE628DE1B53D}
ERROR = 0xA000810C (E_PVL_DRIVE_NOT_AVAILABLE)
pvlsvr: 02/02/05 16:31:37 AdammSession::Execute( ADAMM_SESSION_EXECUTE_MOVER_MOUNT_CONTINUATION )
Session = {E97EA5A9-A018-475E-8E98-AE628DE1B53D}
pvlsvr: 02/02/05 16:31:37 AdammSession::CheckTargetDeviceStatus()
Session = {E97EA5A9-A018-475E-8E98-AE628DE1B53D}
Job = {4411D913-DFD3-4FE5-BD60-F9C7E8D1F342}, "Tuesday even"
TargetDevice = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
State = 0003, ReservationID = 0000, AvailableMovers = 0001
pvlsvr: 02/02/05 16:31:38 AdammSession::MountMedia()
Session = {E97EA5A9-A018-475E-8E98-AE628DE1B53D}
Drive = {D2988BF1-B704-4A09-95A6-5643109C6B4A}
Slot = 0236
Side = 0000
pvlsvr: 02/02/05 16:31:38 PvlDrive::MountMedia()
Session = {E97EA5A9-A018-475E-8E98-AE628DE1B53D}
Drive = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Slot = 0236
Side = 0001
pvlsvr: 02/02/05 16:31:38 PvlChanger::MountMedia()
Session = {E97EA5A9-A018-475E-8E98-AE628DE1B53D}
Library = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Drive = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Slot = 0236
Side = 0001
pvlsvr: 02/02/05 16:31:39 AdammSession::MountMedia()
Session = {E97EA5A9-A018-475E-8E98-AE628DE1B53D}
Library = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Slot = 0000
Drive = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Media = {D1714A57-95EB-428C-8F7F-AE9EDD5C8D99}, "B2D002373"
Side = 0001
SUCCESS!
bengine: 9f0 2/2/2005 16:31:39: TF_MountNewMedia: IsChannelStatus == true. result = 00000000
bengine: 9f0 2/2/2005 16:31:39: TF_MountNewMedia succeeded!
bengine: 9f0 2/2/2005 16:31:39: TAPEALERT: Get TapeAlert Flags Return Code = 0X0
bengine: 9f0 2/2/2005 16:31:39: TAPEALERT: TapeAlert Device Flag = 0X0
bengine: 9f0 2/2/2005 16:31:39: TAPEALERT: TapeAlert Changer Flag = 0X0
bengine: 9f0 2/2/2005 16:31:39: TAPEALERT: Get TapeAlert Flags Return Code = 0X0
bengine: 9f0 2/2/2005 16:31:39: TAPEALERT: Get TapeAlert Flags Return Code = 0X0
bengine: 9f0 2/2/2005 16:31:39: Media Label: B2D002373
bengine: 9f0 2/2/2005 16:31:39: Media GUID: {D1714A57-95EB-428C-8F7F-AE9EDD5C8D99}
beserver: 0x0fac 08-0202:163139:AdammAdminBO: Get - NULL MEDIA
beserver: 0x0fac 08-0202:163139:AdammAdminBO: end get.
bengine: 9f0 2/2/2005 16:31:39: Overwrite Protected Until: 3/17/2005 9:31:39 PM
bengine: 9f0 2/2/2005 16:31:39: Appendable Until: 12/31/9999 12:00:00 AM
bengine: 9f0 2/2/2005 16:31:39: UI Msg = 8008
bengine: 9f0 2/2/2005 16:31:39: HARDWARE COMPRESSION ===> Compression is NOT configurable.
bengine: 9f0 2/2/2005 16:31:39: GET_DRV_INF: bsize = 1024
bengine: 9f0 2/2/2005 16:31:39: RewindDrive mover ret = 0 (0x0)
bengine: 9f0 2/2/2005 16:31:39: ret_val = 0
bengine: 9f0 2/2/2005 16:31:39: Attempting to VCB storage media
bengine: 9f0 2/2/2005 16:31:39: TpRead( :(
bengine: 9f0 2/2/2005 16:31:39: Error = 1101 Req = 6144 Got = 1024
bengine: 9f0 2/2/2005 16:31:39: HARDWARE COMPRESSION ===> Compression is NOT configurable.
bengine: 9f0 2/2/2005 16:31:39: GET_DRV_INF: bsize = 1024
bengine: 9f0 2/2/2005 16:31:39: FreeFormatEnv( cur_fmt=65535 )
bengine: 9f0 2/2/2005 16:31:39: SetupFormatEnv( fmt=0 )
bengine: 9f0 2/2/2005 16:31:39: TpRead( :(
bengine: 9f0 2/2/2005 16:31:39: Error = 1104 Req = 6144 Got = 0
bengine: 9f0 2/2/2005 16:31:39: RewindDrive mover ret = 0 (0x0)
bengine: 9f0 2/2/2005 16:31:39: ret_val = 0
bengine: 9f0 2/2/2005 16:31:39: PositionAtSet( :( TF Msg = 7
bengine: 9f0 2/2/2005 16:31:39: RewindDrive mover ret = 0 (0x0)
bengine: 9f0 2/2/2005 16:31:39: ret_val = 0
bengine: 9f0 2/2/2005 16:31:39: RewindDrive mover ret = 0 (0x0)
bengine: 9f0 2/2/2005 16:31:39: ret_val = 0
bengine: 9f0 2/2/2005 16:31:39: UI Msg = 8003
bengine: 9f0 2/2/2005 16:31:39: HARDWARE COMPRESSION ===> Compression is NOT configurable.
bengine: 9f0 2/2/2005 16:31:39: GET_DRV_INF: bsize = 1024
bengine: 9f0 2/2/2005 16:31:39: RewindDrive mover ret = 0 (0x0)
bengine: 9f0 2/2/2005 16:31:39: ret_val = 0
bengine: 9f0 2/2/2005 16:31:39: FreeFormatEnv( cur_fmt=0 )
bengine: 9f0 2/2/2005 16:31:39: F40_NDMPWriteContSet crossing lba = 1366624
bengine: 9f0 2/2/2005 16:31:39: B2D Buffered Writes ===> Setting buffering to default.
bengine: 9f0 2/2/2005 16:31:39: WriteEndSet( 1 ) returning 0
bengine: 9f0 2/2/2005 16:31:39: Current Block is = 3
bengine: db4 2/2/2005 16:31:41: TF_NDMPProcessOTCEOM crossing lba = 1366624
bengine: db4 2/2/2005 16:31:41: TF_NDMPProcessOTCEOM processing OTC EOM cont vcb lba = 1366620
*********** stopped logging here; status "Running"
> logs.
The application log has only information messages and an error at the end of each nightly job for a few "corrupt files" because we don't have the open file option. (The slowness is seen backing up machines that have no open file errors, so is unrelated to this problem.)
> If possible, rerun the backup jobs and paste the
> corresponding SGMON log in the post .
Here's an example. Logging was turned on while the job status was "Running"; messages began appearing in the log when the status went to "Loading Media".
Capturing to C:\Program Files\VERITAS\Backup Exec\NT\logs\SGMon.log
bengine: 9f0 2/2/2005 16:25:59: WriteEndSet( 1 ) returning 0
bengine: 9f0 2/2/2005 16:25:59: WriteEndSet( 1 ) returning 0
bengine: 9f0 2/2/2005 16:25:59: WriteEndSet( 0 ) returning 0
bengine: 9f0 2/2/2005 16:25:59: RewindDrive mover ret = 0 (0x0)
bengine: 9f0 2/2/2005 16:25:59: ret_val = 0
bengine: 9f0 2/2/2005 16:25:59: Requested Set: ID = ffffffff Seq = -1 Set = -1
bengine: 9f0 2/2/2005 16:25:59: PositionAtSet( :( TF Msg = 12
bengine: 9f0 2/2/2005 16:25:59: RewindDrive mover ret = 0 (0x0)
bengine: 9f0 2/2/2005 16:25:59: ret_val = 0
bengine: 9f0 2/2/2005 16:25:59: TAPEALERT: Get TapeAlert Flags Return Code = 0X0
bengine: 9f0 2/2/2005 16:25:59: TAPEALERT: TapeAlert Device Flag = 0X0
bengine: 9f0 2/2/2005 16:25:59: TAPEALERT: TapeAlert Changer Flag = 0X0
bengine: 9f0 2/2/2005 16:25:59: TAPEALERT: Get TapeAlert Flags Return Code = 0X0
bengine: 9f0 2/2/2005 16:25:59: Entering TF_MountNewMedia ...
pvlsvr: 02/02/05 16:25:59 AdammSession::Execute( ADAMM_SESSION_EXECUTE_MOVER_RELEASE )
Session = {E97EA5A9-A018-475E-8E98-AE628DE1B53D}
bengine: 9f0 2/2/2005 16:25:59: TF_MountNewMedia: mediaId == NULL
pvlsvr: 02/02/05 16:25:59 AdammSession::Execute( ADAMM_SESSION_EXECUTE_MOVER_MOUNT_CONTINUATION )
Session = {E97EA5A9-A018-475E-8E98-AE628DE1B53D}
pvlsvr: 02/02/05 16:25:59 AdammSession::CheckTargetDeviceStatus()
Session = {E97EA5A9-A018-475E-8E98-AE628DE1B53D}
Job = {4411D913-DFD3-4FE5-BD60-F9C7E8D1F342}, "Tuesday even"
TargetDevice = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
State = 0003, ReservationID = 0000, AvailableMovers = 0000
pvlsvr: 02/02/05 16:25:59 AdammSession::DismountMedia()
Session = {E97EA5A9-A018-475E-8E98-AE628DE1B53D}
Library = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Slot = 0000
Drive = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Media = {6780D2EF-E407-4644-8F99-C7AB800435FA}, "B2D002372"
Side = 0001
pvlsvr: 02/02/05 16:25:59 PvlDrive::DismountMedia()
Session = {E97EA5A9-A018-475E-8E98-AE628DE1B53D}
Drive = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Slot = 0235
Side = 0001
beserver: 0x0fac 08-0202:162600:AdammAdminBO: start query - NULL PVL
pvlsvr: 02/02/05 16:26:00 PvlChanger::DismountMedia()
Session = {E97EA5A9-A018-475E-8E98-AE628DE1B53D}
Library = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Drive = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Slot = 0235
Side = 0001
beserver: 0x0fac 08-0202:162600:AdammAdminBO: end query.
pvlsvr: 02/02/05 16:26:01 PvlChanger::MountMedia()
Session = {E97EA5A9-A018-475E-8E98-AE628DE1B53D}
Library = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Drive = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Slot = 268369920
Side = 0001
pvlsvr: 02/02/05 16:26:01 PvlChanger::DismountMedia()
Session = {E97EA5A9-A018-475E-8E98-AE628DE1B53D}
Library = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Drive = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Slot = 268369920
Side = 0001
pvlsvr: 02/02/05 16:26:01 PvlDrive::MountMedia()
Session = {E97EA5A9-A018-475E-8E98-AE628DE1B53D}
Drive = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Slot = 0000
Side = 0001
pvlsvr: 02/02/05 16:26:01 PvlChanger::MountMedia()
Session = {E97EA5A9-A018-475E-8E98-AE628DE1B53D}
Library = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Drive = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Slot = 0000
Side = 0001
pvlsvr: 02/02/05 16:26:02 PvlDrive::DismountMedia()
Session = {E97EA5A9-A018-475E-8E98-AE628DE1B53D}
Drive = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Slot = 0000
Side = 0001
pvlsvr: 02/02/05 16:26:02 PvlChanger::DismountMedia()
Session = {E97EA5A9-A018-475E-8E98-AE628DE1B53D}
Library = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Drive = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Slot = 0000
Side = 0001
pvlsvr: 02/02/05 16:26:03 PvlDrive::MountMedia()
Session = {E97EA5A9-A018-475E-8E98-AE628DE1B53D}
Drive = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Slot = 0001
Side = 0001
pvlsvr: 02/02/05 16:26:03 PvlChanger::MountMedia()
Session = {E97EA5A9-A018-475E-8E98-AE628DE1B53D}
Library = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Drive = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Slot = 0001
Side = 0001
pvlsvr: 02/02/05 16:26:04 PvlDrive::DismountMedia()
Session = {E97EA5A9-A018-475E-8E98-AE628DE1B53D}
Drive = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Slot = 0001
Side = 0001
pvlsvr: 02/02/05 16:26:04 PvlChanger::DismountMedia()
Session = {E97EA5A9-A018-475E-8E98-AE628DE1B53D}
Library = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Drive = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Slot = 0001
Side = 0001
pvlsvr: 02/02/05 16:26:04 PvlDrive::MountMedia()
Session = {E97EA5A9-A018-475E-8E98-AE628DE1B53D}
Drive = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Slot = 0002
Side = 0001
pvlsvr: 02/02/05 16:26:04 PvlChanger::MountMedia()
Session = {E97EA5A9-A018-475E-8E98-AE628DE1B53D}
Library = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Drive = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Slot = 0002
Side = 0001
pvlsvr: 02/02/05 16:26:05 PvlDrive::DismountMedia()
Session = {E97EA5A9-A018-475E-8E98-AE628DE1B53D}
Drive = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Slot = 0002
Side = 0001
pvlsvr: 02/02/05 16:26:05 PvlChanger::DismountMedia()
Session = {E97EA5A9-A018-475E-8E98-AE628DE1B53D}
Library = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Drive = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Slot = 0002
Side = 0001
********* deleted similar lines as slot counts up
pvlsvr: 02/02/05 16:31:21 PvlDrive::MountMedia()
Session = {E97EA5A9-A018-475E-8E98-AE628DE1B53D}
Drive = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Slot = 0236
Side = 0001
pvlsvr: 02/02/05 16:31:21 PvlChanger::MountMedia()
Session = {E97EA5A9-A018-475E-8E98-AE628DE1B53D}
Library = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Drive = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Slot = 0236
Side = 0001
pvlsvr: 02/02/05 16:31:22 PvlDrive::DismountMedia()
Session = {E97EA5A9-A018-475E-8E98-AE628DE1B53D}
Drive = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Slot = 0236
Side = 0001
pvlsvr: 02/02/05 16:31:22 PvlChanger::DismountMedia()
Session = {E97EA5A9-A018-475E-8E98-AE628DE1B53D}
Library = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Drive = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Slot = 0236
Side = 0001
pvlsvr: 02/02/05 16:31:22 AdammSession::Execute( ADAMM_SESSION_EXECUTE_MOVER_MOUNT_CONTINUATION )
Session = {E97EA5A9-A018-475E-8E98-AE628DE1B53D}
ERROR = 0xA000810C (E_PVL_DRIVE_NOT_AVAILABLE)
pvlsvr: 02/02/05 16:31:37 AdammSession::Execute( ADAMM_SESSION_EXECUTE_MOVER_MOUNT_CONTINUATION )
Session = {E97EA5A9-A018-475E-8E98-AE628DE1B53D}
pvlsvr: 02/02/05 16:31:37 AdammSession::CheckTargetDeviceStatus()
Session = {E97EA5A9-A018-475E-8E98-AE628DE1B53D}
Job = {4411D913-DFD3-4FE5-BD60-F9C7E8D1F342}, "Tuesday even"
TargetDevice = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
State = 0003, ReservationID = 0000, AvailableMovers = 0001
pvlsvr: 02/02/05 16:31:38 AdammSession::MountMedia()
Session = {E97EA5A9-A018-475E-8E98-AE628DE1B53D}
Drive = {D2988BF1-B704-4A09-95A6-5643109C6B4A}
Slot = 0236
Side = 0000
pvlsvr: 02/02/05 16:31:38 PvlDrive::MountMedia()
Session = {E97EA5A9-A018-475E-8E98-AE628DE1B53D}
Drive = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Slot = 0236
Side = 0001
pvlsvr: 02/02/05 16:31:38 PvlChanger::MountMedia()
Session = {E97EA5A9-A018-475E-8E98-AE628DE1B53D}
Library = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Drive = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Slot = 0236
Side = 0001
pvlsvr: 02/02/05 16:31:39 AdammSession::MountMedia()
Session = {E97EA5A9-A018-475E-8E98-AE628DE1B53D}
Library = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Slot = 0000
Drive = {D2988BF1-B704-4A09-95A6-5643109C6B4A}, "T - Tuesday"
Media = {D1714A57-95EB-428C-8F7F-AE9EDD5C8D99}, "B2D002373"
Side = 0001
SUCCESS!
bengine: 9f0 2/2/2005 16:31:39: TF_MountNewMedia: IsChannelStatus == true. result = 00000000
bengine: 9f0 2/2/2005 16:31:39: TF_MountNewMedia succeeded!
bengine: 9f0 2/2/2005 16:31:39: TAPEALERT: Get TapeAlert Flags Return Code = 0X0
bengine: 9f0 2/2/2005 16:31:39: TAPEALERT: TapeAlert Device Flag = 0X0
bengine: 9f0 2/2/2005 16:31:39: TAPEALERT: TapeAlert Changer Flag = 0X0
bengine: 9f0 2/2/2005 16:31:39: TAPEALERT: Get TapeAlert Flags Return Code = 0X0
bengine: 9f0 2/2/2005 16:31:39: TAPEALERT: Get TapeAlert Flags Return Code = 0X0
bengine: 9f0 2/2/2005 16:31:39: Media Label: B2D002373
bengine: 9f0 2/2/2005 16:31:39: Media GUID: {D1714A57-95EB-428C-8F7F-AE9EDD5C8D99}
beserver: 0x0fac 08-0202:163139:AdammAdminBO: Get - NULL MEDIA
beserver: 0x0fac 08-0202:163139:AdammAdminBO: end get.
bengine: 9f0 2/2/2005 16:31:39: Overwrite Protected Until: 3/17/2005 9:31:39 PM
bengine: 9f0 2/2/2005 16:31:39: Appendable Until: 12/31/9999 12:00:00 AM
bengine: 9f0 2/2/2005 16:31:39: UI Msg = 8008
bengine: 9f0 2/2/2005 16:31:39: HARDWARE COMPRESSION ===> Compression is NOT configurable.
bengine: 9f0 2/2/2005 16:31:39: GET_DRV_INF: bsize = 1024
bengine: 9f0 2/2/2005 16:31:39: RewindDrive mover ret = 0 (0x0)
bengine: 9f0 2/2/2005 16:31:39: ret_val = 0
bengine: 9f0 2/2/2005 16:31:39: Attempting to VCB storage media
bengine: 9f0 2/2/2005 16:31:39: TpRead( :(
bengine: 9f0 2/2/2005 16:31:39: Error = 1101 Req = 6144 Got = 1024
bengine: 9f0 2/2/2005 16:31:39: HARDWARE COMPRESSION ===> Compression is NOT configurable.
bengine: 9f0 2/2/2005 16:31:39: GET_DRV_INF: bsize = 1024
bengine: 9f0 2/2/2005 16:31:39: FreeFormatEnv( cur_fmt=65535 )
bengine: 9f0 2/2/2005 16:31:39: SetupFormatEnv( fmt=0 )
bengine: 9f0 2/2/2005 16:31:39: TpRead( :(
bengine: 9f0 2/2/2005 16:31:39: Error = 1104 Req = 6144 Got = 0
bengine: 9f0 2/2/2005 16:31:39: RewindDrive mover ret = 0 (0x0)
bengine: 9f0 2/2/2005 16:31:39: ret_val = 0
bengine: 9f0 2/2/2005 16:31:39: PositionAtSet( :( TF Msg = 7
bengine: 9f0 2/2/2005 16:31:39: RewindDrive mover ret = 0 (0x0)
bengine: 9f0 2/2/2005 16:31:39: ret_val = 0
bengine: 9f0 2/2/2005 16:31:39: RewindDrive mover ret = 0 (0x0)
bengine: 9f0 2/2/2005 16:31:39: ret_val = 0
bengine: 9f0 2/2/2005 16:31:39: UI Msg = 8003
bengine: 9f0 2/2/2005 16:31:39: HARDWARE COMPRESSION ===> Compression is NOT configurable.
bengine: 9f0 2/2/2005 16:31:39: GET_DRV_INF: bsize = 1024
bengine: 9f0 2/2/2005 16:31:39: RewindDrive mover ret = 0 (0x0)
bengine: 9f0 2/2/2005 16:31:39: ret_val = 0
bengine: 9f0 2/2/2005 16:31:39: FreeFormatEnv( cur_fmt=0 )
bengine: 9f0 2/2/2005 16:31:39: F40_NDMPWriteContSet crossing lba = 1366624
bengine: 9f0 2/2/2005 16:31:39: B2D Buffered Writes ===> Setting buffering to default.
bengine: 9f0 2/2/2005 16:31:39: WriteEndSet( 1 ) returning 0
bengine: 9f0 2/2/2005 16:31:39: Current Block is = 3
bengine: db4 2/2/2005 16:31:41: TF_NDMPProcessOTCEOM crossing lba = 1366624
bengine: db4 2/2/2005 16:31:41: TF_NDMPProcessOTCEOM processing OTC EOM cont vcb lba = 1366620
*********** stopped logging here; status "Running"
Options
- Mark as New
- Bookmark
- Subscribe
- Mute
- Subscribe to RSS Feed
- Permalink
- Report Inappropriate Content
02-04-2005 11:13 AM
I monitored a test backup using ver 10 trial. It seems to start the search for an available slot at the last number used, so the search time doesn't grow as the number of backup files increases.
Options
- Mark as New
- Bookmark
- Subscribe
- Mute
- Subscribe to RSS Feed
- Permalink
- Report Inappropriate Content
03-03-2005 04:04 AM
Using 9.1 with large backup-to-disk folder slows down backup in an incredible way...
Another hint that seeking goes the wrong way:
If you look at the media statistics of one backup job with lot of 1 Gb media files it shows that the newest media has a mount count for example: 2; media created at the begining of the backup job a media mount count of 500. At this time there were about 500 1 Gb files in the backup to disk folder!
So backup exec needs to mount all previous created media files to create a new 1 Gb file in one backup job...
Please correct this behaviour!!
Another hint that seeking goes the wrong way:
If you look at the media statistics of one backup job with lot of 1 Gb media files it shows that the newest media has a mount count for example: 2; media created at the begining of the backup job a media mount count of 500. At this time there were about 500 1 Gb files in the backup to disk folder!
So backup exec needs to mount all previous created media files to create a new 1 Gb file in one backup job...
Please correct this behaviour!!
Options
- Mark as New
- Bookmark
- Subscribe
- Mute
- Subscribe to RSS Feed
- Permalink
- Report Inappropriate Content
04-11-2005 07:38 AM
Hello,
I would like to know regarding your problem. Please update accordingly if it still persists.
Regards.
NOTE : If we do not receive your intimation within two business days, this post would be "assumed answered" and archived.
I would like to know regarding your problem. Please update accordingly if it still persists.
Regards.
NOTE : If we do not receive your intimation within two business days, this post would be "assumed answered" and archived.
Options
- Mark as New
- Bookmark
- Subscribe
- Mute
- Subscribe to RSS Feed
- Permalink
- Report Inappropriate Content
04-19-2005 04:37 AM
Archiving the post as per our previous reply
Related Content
- Tape library and drives firmware upgrade in NetBackup
- Disk volume is down Flex 5260 in NetBackup
- Duplicating tapes fails: INF - Cannot obtain resources for this job : error [167] in NetBackup
- Disk storage unit is full, but it's not full (netbackup 10.1.1) in NetBackup
- Google Cloud Platform S3 Object Locking and Immutability backups Now Available in NetBackup 10.4 in NetBackup
