Media Mount Failed

[deleteitall]

I am new to our back up system (Veritas Bacup Exec 9.1) running on our server. Over the past 2 weeks the back up appears to be finishing but gives us the following error messages:

"Media Mount Failed
User canceled a Physical Volume Library operation.

The media operation was terminated by the user.

Back up completed on 12/14/2004 at 2:30:03"

"Completed Status: Canceled"

We are using 20gb data cartridge 4mm with "Hardware compression enabled" A total of 21gb is backed up.

What should we do?

 

[SQLBill]

Stop cancelling the job? If you aren't cancelling it, you need to track down who is cancelling it. Where do you see that error message? If it's not in the job history, have you checked the job's history/log? (Double click on the job and select the Job Log tab). Have you checked the Windows Event Viewer Logs for related messages?

-SQLBill

Posting advice: FAQ481-4875

 

[deleteitall]

SQLBill,

Thank you for your response.

No one has access to the server to cancel anything. The server, or Veritas backup exec, is canceling the process itself.

The error merssage not only comes across in the e-mail but also is in the Job Log.

Here's is what comes across via e-mail:

"(Server: "SDISERVER") (Job: "Daily") The job was canceled by user System."

Here's what the JOB LOG looks like for yesterdays' backup:

Completed status: Canceled Expand AllCollapse All
Job Information
Job server: SDISERVER
Job name: Daily
Job started: Wednesday, December 15, 2004 at 11:00:04 PM
Job type: Backup
Job Log: BEX00259.xml


Device and Media Information
Drive Name: DELL 1
Media Label: 4MM000027
Media GUID: {0F7ABCDB-EB4C-449B-8226-053B428F2B01}
Overwrite Protected Until: 12/31/9999 12:00:00 AM
Appendable Until: 12/31/9999 12:00:00 AM
Targeted Media Set Name: Media Set 1


Job Operation - Backup
Backup Options
Media operation - overwrite.
Hardware compression enabled.


Starting Pre Job Command < c:\navstop.bat >

Server - SDISERVER

Set Information - \\SDISERVER\Microsoft Exchange Mailboxes
Backup Set Information
Family Name: "Media created 12/15/2004 11:00:04 PM"
Backup of "\\SDISERVER\Microsoft Exchange Mailboxes "
Backup set #1 on storage media #1
Backup set description: "Daily"
Backup Type: FULL - Back up messages - Reset archive bit
Microsoft Exchange Server Agent: Started


Backup started on 12/15/2004 at 11:00:21 PM.
Backup completed on 12/15/2004 at 11:35:42 PM.

Backup Set Summary
Backed up 53030 mail message(s) in 824 folder(s) in 16 mailbox(es)
Processed 3,300,408,235 bytes in 35 minutes and 21 seconds.
Throughput rate: 89.0 MB/min


Set Information - \\SDISERVER\Microsoft Information Store\First Storage Group
Backup Set Information
Family Name: "Media created 12/15/2004 11:00:04 PM"
Backup of "\\SDISERVER\Microsoft Information Store\First Storage Group "
Backup set #2 on storage media #1
Backup set description: "Daily"
Backup Type: FULL - Database & Logs (flush committed logs)
Microsoft Exchange Server Agent: Started


Backup started on 12/15/2004 at 11:35:43 PM.

Backup Set Detail Information
Log Files


Backup completed on 12/15/2004 at 11:54:14 PM.

Backup Set Summary
Backed up 2 Exchange Server store(s)
Backed up 0 Exchange Server log(s)
Processed 3,870,466,960 bytes in 18 minutes and 31 seconds.
Throughput rate: 199 MB/min



Set Information - D: Data
Backup Set Information
Family Name: "Media created 12/15/2004 11:00:04 PM"
Backup of "D: Data"
Backup set #3 on storage media #1
Backup set description: "Daily"
Backup Type: FULL - Back Up Files - Reset Archive Bit


Backup started on 12/15/2004 at 11:54:14 PM.

Backup Set Detail Information
Media mount failed.
User canceled a Physical Volume Library operation.

The media operation was terminated by the user.


Backup completed on 12/16/2004 at 2:30:07 AM.

Backup Set Summary
Backed up 26895 files in 1932 directories.
Processed 21,165,676,511 bytes in 1 hour, 33 minutes, and 40 seconds.
Throughput rate: 215 MB/min


Job Completion Status
Job ended: Thursday, December 16, 2004 at 2:30:07 AM
Completed status: Canceled
Final error: 0x0 -
Final error category: Other Errors


Errors
Click an error below to locate it in the job log
Backup - D: DataMedia mount failed.
User canceled a Physical Volume Library operation.

The media operation was terminated by the user.

Are we getting backed up? Can we stop this madness? This only strted happening around November 27th and each day it is the same thing.

Thanks for your assistance.

 

[SQLBill]

You need to find out what is causing this:

Media mount failed.

One other point is that the backup job that is being cancelled is the backup of Data and this failed because it couldn't mount the media.

Now that I know neither you or anyone else is stopping the job; other possibilities are:

tape is full and BE can't find a second tape. The job can't overwrite this tape as it's protected until 12/31/9999. It can only append to the tape. But normally you see an error about providing another tape.

If these are separate jobs, BE could be trying to do two jobs at one time.

It's my guess you don't have two tape drives and BE is trying to do two jobs at one time. Take a look at these entries:

Backup completed on 12/15/2004 at 11:54:14 PM.
and then this one follows:
Backup started on 12/15/2004 at 11:54:14 PM.

The exact same second one completes, one starts. They could be 'bumping' into each other and one is just finishing with the tape when the next one tries to access it.

-SQLBill

 

[deleteitall]

SQLBill,

I thought you may be on to something with the time of completion versus the time of start of a back up operation so I looked back at the successful job logs.

Here is the job log from the last successful backup we had:
____________________________
Job server: SDISERVER
Job name: Daily
Job started: Wednesday, November 17, 2004 at 11:00:05 PM
Job type: Backup
Job Log: BEX00211.xml


Device and Media Information
Drive Name: DELL 1
Media Label: 4MM000012
Media GUID: {B3566618-D259-4944-A11B-2EF4C600A240}
Overwrite Protected Until: 12/31/9999 12:00:00 AM
Appendable Until: 12/31/9999 12:00:00 AM
Targeted Media Set Name: Media Set 1


Job Operation - Backup
Backup Options
Media operation - overwrite.
Hardware compression enabled.


Starting Pre Job Command < c:\navstop.bat >

Server - SDISERVER

Set Information - \\SDISERVER\Microsoft Exchange Mailboxes
Backup Set Information
Family Name: "Media created 11/17/2004 11:00:05 PM"
Backup of "\\SDISERVER\Microsoft Exchange Mailboxes "
Backup set #1 on storage media #1
Backup set description: "Daily"
Backup Type: FULL - Back up messages - Reset archive bit
Microsoft Exchange Server Agent: Started


Backup started on 11/17/2004 at 11:00:22 PM.
Backup completed on 11/18/2004 at 12:21:04 AM.

Backup Set Summary
Backed up 51223 mail message(s) in 830 folder(s) in 17 mailbox(es)
Processed 3,192,631,351 bytes in 1 hour, 20 minutes, and 42 seconds.
Throughput rate: 37.7 MB/min

Set Information - \\SDISERVER\Microsoft Information Store\First Storage Group
Backup Set Information
Family Name: "Media created 11/17/2004 11:00:05 PM"
Backup of "\\SDISERVER\Microsoft Information Store\First Storage Group "
Backup set #2 on storage media #1
Backup set description: "Daily"
Backup Type: FULL - Database & Logs (flush committed logs)
Microsoft Exchange Server Agent: Started


Backup started on 11/18/2004 at 12:21:04 AM.

Backup Set Detail Information
Log Files


Backup completed on 11/18/2004 at 12:39:03 AM.

Backup Set Summary
Backed up 2 Exchange Server store(s)
Backed up 0 Exchange Server log(s)
Processed 3,719,472,016 bytes in 17 minutes and 59 seconds.
Throughput rate: 197 MB/min


Set Information - D: Data
Backup Set Information
Family Name: "Media created 11/17/2004 11:00:05 PM"
Backup of "D: Data"
Backup set #3 on storage media #1
Backup set description: "Daily"
Backup Type: FULL - Back Up Files - Reset Archive Bit

Backup started on 11/18/2004 at 12:39:04 AM.
Backup completed on 11/18/2004 at 2:16:32 AM.

Backup Set Summary
Backed up 27278 files in 1955 directories.
Processed 21,523,498,591 bytes in 1 hour, 37 minutes, and 28 seconds.
Throughput rate: 211 MB/min


Set Information - \\SDISERVER\Microsoft Exchange Public Folders
Backup Set Information
Family Name: "Media created 11/17/2004 11:00:05 PM"
Backup of "\\SDISERVER\Microsoft Exchange Public Folders "
Backup set #4 on storage media #1
Backup set description: "Daily"
Backup Type: FULL - Back up messages - Reset archive bit
Microsoft Exchange Server Agent: Started


Backup started on 11/18/2004 at 2:16:33 AM.
Backup completed on 11/18/2004 at 2:16:35 AM.

Backup Set Summary
Backed up 0 mail message(s) in 1 folder(s) in 0 mailbox(es)
Processed 2,226 bytes in 2 seconds.
Throughput rate: 0.064 MB/min


Set Information - C: Dell Server
Backup Set Information
Family Name: "Media created 11/17/2004 11:00:05 PM"
Backup of "C: Dell Server"
Backup set #5 on storage media #1
Backup set description: "Daily"
Backup Type: FULL - Back Up Files - Reset Archive Bit


Backup started on 11/18/2004 at 2:16:36 AM.
Backup completed on 11/18/2004 at 2:16:43 AM.

Backup Set Summary
Backed up 308 files in 53 directories.
Processed 11,095,315 bytes in 7 seconds.
Throughput rate: 90.7 MB/min

Set Information - \\SDISERVER\System?State
Backup Set Information
Family Name: "Media created 11/17/2004 11:00:05 PM"
Backup of "\\SDISERVER\System?State "
Backup set #6 on storage media #1
Backup set description: "Daily"
Backup Type: FULL - Back Up Files - Reset Archive Bit

Backup started on 11/18/2004 at 2:16:44 AM.

Backup Set Detail Information
Drive Name: DELL 1
Media Label: 4MM000022
Media GUID: {28F6257F-BD02-4A36-A966-37DEC6D3A719}
Overwrite Protected Until: 12/31/9999 12:00:00 AM
Appendable Until: 12/31/9999 12:00:00 AM
Targeted Media Set Name: Media Set 1
Backup set #6 on storage media #2


Backup completed on 11/18/2004 at 7:57:54 AM.

Backup Set Summary
Backed up 2017 files in 96 directories.
Processed 307,918,692 bytes in 1 minute and 44 seconds.
Throughput rate: 169 MB/min

Job Completion Status
Job ended: Thursday, November 18, 2004 at 7:58:20 AM
Completed status: Successful
******end

Now here is the first CANCELLED job log:

_____________
Job server: SDISERVER
Job name: Daily
Job started: Thursday, November 18, 2004 at 11:00:00 PM
Job type: Backup
Job Log: BEX00213.xml


Device and Media Information
Drive Name: DELL 1
Media Label: 4MM000022
Media GUID: {28F6257F-BD02-4A36-A966-37DEC6D3A719}
Overwrite Protected Until: 12/31/9999 12:00:00 AM
Appendable Until: 12/31/9999 12:00:00 AM
Targeted Media Set Name: Media Set 1


Job Operation - Backup
Backup Options
Media operation - overwrite.
Hardware compression enabled.


Starting Pre Job Command < c:\navstop.bat >

Server - SDISERVER

Set Information - \\SDISERVER\Microsoft Exchange Mailboxes
Backup Set Information
Family Name: "Media created 11/18/2004 11:00:01 PM"
Backup of "\\SDISERVER\Microsoft Exchange Mailboxes "
Backup set #1 on storage media #1
Backup set description: "Daily"
Backup Type: FULL - Back up messages - Reset archive bit
Microsoft Exchange Server Agent: Started


Backup started on 11/18/2004 at 11:00:17 PM.
Backup completed on 11/18/2004 at 11:34:19 PM.

Backup Set Summary
Backed up 51400 mail message(s) in 830 folder(s) in 17 mailbox(es)
Processed 3,197,328,990 bytes in 34 minutes and 2 seconds.
Throughput rate: 89.6 MB/min


Set Information - \\SDISERVER\Microsoft Information Store\First Storage Group
Backup Set Information
Family Name: "Media created 11/18/2004 11:00:01 PM"
Backup of "\\SDISERVER\Microsoft Information Store\First Storage Group "
Backup set #2 on storage media #1
Backup set description: "Daily"
Backup Type: FULL - Database & Logs (flush committed logs)
Microsoft Exchange Server Agent: Started


Backup started on 11/18/2004 at 11:34:21 PM.

Backup Set Detail Information
Log Files


Backup completed on 11/18/2004 at 11:52:22 PM.

Backup Set Summary
Backed up 2 Exchange Server store(s)
Backed up 0 Exchange Server log(s)
Processed 3,724,714,994 bytes in 18 minutes and 1 second.
Throughput rate: 197 MB/min


Set Information - D: Data
Backup Set Information
Family Name: "Media created 11/18/2004 11:00:01 PM"
Backup of "D: Data"
Backup set #3 on storage media #1
Backup set description: "Daily"
Backup Type: FULL - Back Up Files - Reset Archive Bit


Backup started on 11/18/2004 at 11:52:23 PM.
Backup completed on 11/19/2004 at 1:26:51 AM.

Backup Set Summary
Backed up 27285 files in 1955 directories.
Processed 21,546,446,797 bytes in 1 hour, 34 minutes, and 28 seconds.
Throughput rate: 218 MB/min


Set Information - \\SDISERVER\Microsoft Exchange Public Folders
Backup Set Information
Family Name: "Media created 11/18/2004 11:00:01 PM"
Backup of "\\SDISERVER\Microsoft Exchange Public Folders "
Backup set #4 on storage media #1
Backup set description: "Daily"
Backup Type: FULL - Back up messages - Reset archive bit
Microsoft Exchange Server Agent: Started


Backup started on 11/19/2004 at 1:26:53 AM.
Backup completed on 11/19/2004 at 1:26:55 AM.

Backup Set Summary
Backed up 0 mail message(s) in 1 folder(s) in 0 mailbox(es)
Processed 2,226 bytes in 2 seconds.
Throughput rate: 0.064 MB/min


Set Information - C: Dell Server
Backup Set Information
Family Name: "Media created 11/18/2004 11:00:01 PM"
Backup of "C: Dell Server"
Backup set #5 on storage media #1
Backup set description: "Daily"
Backup Type: FULL - Back Up Files - Reset Archive Bit


Backup started on 11/19/2004 at 1:26:56 AM.
Backup completed on 11/19/2004 at 1:27:03 AM.

Backup Set Summary
Backed up 308 files in 53 directories.
Processed 11,095,315 bytes in 7 seconds.
Throughput rate: 90.7 MB/min

Set Information - \\SDISERVER\System?State
Backup Set Information
Family Name: "Media created 11/18/2004 11:00:01 PM"
Backup of "\\SDISERVER\System?State "
Backup set #6 on storage media #1
Backup set description: "Daily"
Backup Type: FULL - Back Up Files - Reset Archive Bit


Backup started on 11/19/2004 at 1:27:04 AM.

Backup Set Detail Information
Media mount failed.
User canceled a Physical Volume Library operation.

The media operation was terminated by the user.


Backup completed on 11/19/2004 at 3:30:07 AM.

Backup Set Summary
Backed up 1057 files in 48 directories.
Processed 134,269,730 bytes in 48 seconds.
Throughput rate: 160 MB/min

Job Completion Status
Job ended: Friday, November 19, 2004 at 3:30:07 AM
Completed status: Canceled
Final error: 0x0 -
Final error category: Other Errors


Errors
Click an error below to locate it in the job log
Backup - \\SDISERVER\System?State Media mount failed.
User canceled a Physical Volume Library operation.

The media operation was terminated by the user.

******end************

More than enough space on the tape. The successful job had more data backedup than the cancelled.

We only have one tape Back up unit.

If indeed it is the timing issue where one process ends and the other one starts too early, how does one remedy this?

HELP..

Thanks

 

[SQLBill]

Well, you showed me it's not a timing issue. The last failed job had 1 second in between the previous completion and the start of the new job. While the Data backups that work don't have any time between jobs (previous job ends at the same second the Data starts).

Plus it's not the same backup job failing. One time it was Data, the last time it's Backup of "\\SDISERVER\System?State " that failed.

Okay, check the Windows Event Viewer logs for these specific time frames:

15/11:54:14 PM to 16/02:30:07 AM
and
19/1:27:04 AM to 19/03:30:07 AM

Look for any error messages in Application Log and System Log that might be related to this issue.

How used are these tapes? Are the tapes in a library or do you manually put them into the tape device? Since the media is set to not allow overwrite until the year 9999, these tapes could be getting full and some might have less space than others. You might want to check the amount of space used on each tape, compare all the tapes and see if the ones failing have less space available.

-SQLBill

 

[deleteitall]

SQLBill,

Reviewed the Windows Event Viewer logs (application and system logs) and the only ERROR or suspect event occurs at the end of the tape back up. Here is the message it gives:

"No mover was available to close out the last backup set"

The tapes are about 1.5 years old. Each day we replace the tape. There is a tape for ever day of the month so each tape is only used 12 times per year.

All tapes were started at exactly the same time frame. All the same kind and size.

All data is written over so each tape always has fresh data not appended.

 

[SQLBill]

Have you Googled that error message? Finding out what a 'mover' is might solve this issue. It almost sounds like it's trying to use a robotic arm (part of a tape library/autoloader) to move a tape before putting a new one in. But you only have a single tape device. So a 'mover' must be something else, maybe in the tape device.

-SQLBill

 

[Phoenix235]

I had a similar problem here and it was the tapes that was damaged, have you tried a new tape?

 

[deleteitall]

SQLBill,

Here is a copy of the Job Setup properties. Maybe you will spot some kind of notification that needs a response. If there is such a notification and it is waiting for a response and never gets one, it cancels the backup.

Also, if it were the tape being damaged or old, why does it do it for a new tape inserted into the drive every day? Does not make sense that this is related to the issue.

Daily Properties: Daily


Source


--------------------------------------------------------------------------------

Selections



Selection list name: Daily
Selection list description:


Selections/Resource Order

Selections
SDISERVER\Microsoft Exchange Mailboxes\*.* /SUBDIR
SDISERVER\Microsoft Exchange Mailboxes\Robert B [rb]\*.* /SUBDIR /EXCLUDE
SDISERVER\Microsoft Exchange Mailboxes\Art G [art]\*.* /SUBDIR /EXCLUDE
SDISERVER\Microsoft Exchange Mailboxes\Guy K [guyk]\*.* /SUBDIR /EXCLUDE
SDISERVER\Microsoft Exchange Mailboxes\Peter B [pb]\*.* /SUBDIR /EXCLUDE
SDISERVER\First Storage Group\*.* /SUBDIR
D:\*.* /SUBDIR
D:\msdownld.tmp\*.* /SUBDIR /EXCLUDE
D:\RECYCLER\*.* /SUBDIR /EXCLUDE
D:\WUTemp\*.* /SUBDIR /EXCLUDE
D:\Program Files\Exchsrvr\*.* /SUBDIR /EXCLUDE
D:\Program Files\Symantec\*.* /SUBDIR /EXCLUDE
D:\Program Files\sav\*.* /SUBDIR /EXCLUDE
D:\Program Files\VERITAS\*.* /SUBDIR /EXCLUDE
D:\9511e0c5ec5d2d453accf412a139\*.* /SUBDIR /EXCLUDE
D:\15153ec821d401a3b5a2a30dbd5d\*.* /SUBDIR /EXCLUDE
D:\I386\*.* /SUBDIR /EXCLUDE
D:\Users Shared Folders\art\*.* /SUBDIR /EXCLUDE
D:\Users Shared Folders\guyk\*.* /SUBDIR /EXCLUDE
D:\Users Shared Folders\boyd\*.* /SUBDIR /EXCLUDE
SDISERVER\Microsoft Exchange Public Folders\*.* /SUBDIR
C:\*.* /SUBDIR
C:\Config.Msi\*.* /SUBDIR /EXCLUDE
C:\Dell\*.* /SUBDIR /EXCLUDE
C:\Documents and Settings\*.* /SUBDIR /EXCLUDE
C:\Program Files\*.* /SUBDIR /EXCLUDE
C:\RECYCLER\*.* /SUBDIR /EXCLUDE
C:\System Volume Information\*.* /SUBDIR /EXCLUDE
C:\temp\*.* /SUBDIR /EXCLUDE
C:\WINNT\*.* /SUBDIR /EXCLUDE
SDISERVER\System State\*.* /SUBDIR


Resource Credentials

Resources Resource Credentials
SDISERVER System Logon Account
Microsoft Exchange Mailboxes
First Storage Group
D:
SDISERVER\Microsoft Exchange Public Folders System Logon Account
C:
System State





Destination


--------------------------------------------------------------------------------

Device and Media

Device: All Drives (SDISERVER)
Media set: Media Set 1
Family name: Media created 12/21/2004 8:11:41 AM
When this job begins: Overwrite media


Eject media after job completes: Yes
Write checksums to media: Yes
Retension media before backup: No
Password protect media: No




Settings


--------------------------------------------------------------------------------

General

Job name: Daily
Job priority: Medium
Backup set description: Daily
Backup method for files: FULL - Back Up Files - Reset Archive Bit
Compression type: Hardware [if available, otherwise none]


Verify after backup completes: No

Advanced

Set Remote Agent priority: Normal Priority
Open file backup when Advanced Open File Option is not used: With a lock


Enable single instance backup for NTFS volumes: Yes
Back up files and directories by following junction points: Yes
Back up data in Remote Storage: No

Pre/Post Commands

Pre-job command: c:\navstop.bat
Post-job command: c:\navstart.bat
Run these commands: On this media server
Cancel command if not completed within: 30 minutes


Run job only if pre-job command is successful: Yes
Run post-job command only if pre-job command is successful: Yes
Run post-job command even if job fails: Yes
Allow pre and post-job commands to be successful only if completed with a return code of zero: No

Advanced Open File

Use Advanced Open File Option: No


Exchange

Exchange server backup method: FULL - Database & Logs (flush committed logs)
Mailbox backup method: FULL - Back up messages - Reset archive bit


Enable single instance backup for message attachments: No

Notification

Include job log with a notification to an e-mail or printer recipient: Yes





Frequency


--------------------------------------------------------------------------------

Schedule

Run according to schedule


Start no earlier than 11:00:00 PM and no later than 10:59:59 PM
Make the schedule go into effect on 7/23/2004

Schedule backup to run

On Monday, Tuesday, Wednesday, Thursday, Friday of the first week
On Monday, Tuesday, Wednesday, Thursday, Friday of the second week
On Monday, Tuesday, Wednesday, Thursday, Friday of the third week
On Monday, Tuesday, Wednesday, Thursday, Friday of the fourth week
On Monday, Tuesday, Wednesday, Thursday, Friday of the last week


Submit job on hold: No
Enable automatic cancellation: No

***************end***

Happy Holidays

 

[SQLBill]

Here's two things to change and test....

Change this:

Pre/Post Commands

Pre-job command: c:\navstop.bat
Post-job command: c:\navstart.bat
Run these commands: On this media server
Cancel command if not completed within: 30 minutes

Change the last line to 60 minutes.
If that doesn't make a difference change this to no and manually eject the tape:

Eject media after job completes: Yes


-SQLBill

 

[deleteitall]

SQL Bill,

We figured it out. It is evident that the tapes were filling up or within 20MB of being full. This was too close for the backup so it indeed was canceling based on this.

A simple change to what was being backed up each evening to lower the data requiring back up and whamo...all fixed.

Thank you for your assistance.

Even though we all felt, on our end, there was plenty of room on the tapes, we were fooled by the reports. Best to check physical evidence than rely on reports.

Merry Christmas