Backup fails - Normal is ok, it's Copy where it fails - Backup Type Copy - The requested media faile

Discussion in 'Windows Small Business Server' started by Derek, Aug 14, 2008.

  1. Derek

    Derek Guest

    Hi Group,

    Please can you help with a backup problem? It's failed 3 nights in a row,
    never had a problem at this site before.

    It's a HP DAT24 USB tape drive, the tape is changed every day, and a "full"
    backup is done each night of system + selected directories.

    No software changes for about 1 week before we started to have problems, MS
    Updates were applied about 1 week before, and some more updates are waiting
    to be installed. Not doing anything until we get this sorted first. None
    of the updates look related to NTbackup, but there are updates to the
    Exchange service pack 2 if that matters.

    I'm not on site at present but I do have the log files. The 1st night and
    the 3rd night are more or less the same in that they do the Backup Type
    Normal ok and then fail after first attempt at Backup Type Copy. But the
    2nd night's log file is different (much shorter) and the client reports that
    the next morning the tape was already ejected when he went to change it.

    Summary log:
    *********************
    Backup Type: Copy
    Backup started on 12/08/2008 at 23:09.
    The requested media failed to mount. The operation was aborted.
    The operation was ended.
    Backup completed on 12/08/2008 at 23:12.
    Directories: 94
    Files: 2174
    Bytes: 408,492,096
    Time: 2 minutes and 45 seconds
    ----------------------
    The operation did not successfully complete.
    ****************************
    End of Summary Log

    Here are the full log files for night 1 and 3, log file 2 just says the
    media couldn't be found (must have been ejected) but I will add log file 2
    at the end.


    *********************
    *** LOG FILE 1 ***

    12/08/2008 22:00
    -------------------------------
    Date: 12/08/2008
    Time: 22:00
    User: SYSTEM
    -------------------------------

    Backup Runner started.
    NTMS session started successfully.
    EnumerateNtmsObject(NTMS_LIBRARY) succeeded.
    Will enumerate on 8 media libraries found.
    GetNtmsObjectInformation(NtmsLibraryInfo) succeeded.
    GetNtmsObjectInformation(NtmsLibraryInfo) succeeded.
    Found an enabled library.
    InventoryNtmsLibrary(NtmsInventoryOmid) succeeded.
    Identify Media completed
    EnumerateNtmsObject(NTMS_PHYSICAL_MEDIA) succeeded.
    Will enumerate on 0 media types found.
    GetNtmsObjectInformation(NtmsLibraryInfo) succeeded.
    GetNtmsObjectInformation(NtmsLibraryInfo) succeeded.
    GetNtmsObjectInformation(NtmsLibraryInfo) succeeded.
    GetNtmsObjectInformation(NtmsLibraryInfo) succeeded.
    GetNtmsObjectInformation(NtmsLibraryInfo) succeeded.
    GetNtmsObjectInformation(NtmsLibraryInfo) succeeded.
    Found an enabled library.
    InventoryNtmsLibrary(NtmsInventoryOmid) succeeded.
    Identify Media completed
    EnumerateNtmsObject(NTMS_PHYSICAL_MEDIA) succeeded.
    Will enumerate on 1 media types found.
    GetNtmsObjectInformation(NtmsPhysicalMediaInfo) succeeded.
    GetNtmsObjectInformation(NtmsMediaTypeInfo) succeeded.
    Found a tape media type in the library (4mm DDS).
    Launching NTBackup: ntbackup.exe backup "@E:\Program Files\Microsoft Windows
    Small Business Server\Backup\Small Business Backup Script.bks" /d "SBS
    Backup created on 12/08/2008 at 22:00" /v:yes /r:no /rs:no /hc:eek:n /m normal
    /j "Small Business Server Backup Job" /l:s /p "4mm DDS" /UM
    NTBACKUP LOG FILE: C:\Documents and Settings\SBS Backup User\Local
    Settings\Application Data\Microsoft\Windows NT\NTBackup\data\backup09.log
    =====================<BEGIN NTBACKUP LOG FILE>=====================
    Backup Status
    Operation: Backup
    Active backup destination: 4mm DDS
    Media name: "Media created 12/08/2008 at 22:00"

    Volume shadow copy creation: Attempt 1.
    Backup (via shadow copy) of "C: System"
    Backup set #1 on media #1
    Backup description: "SBS Backup created on 12/08/2008 at 22:00"
    Media name: "Media created 12/08/2008 at 22:00"

    Backup Type: Normal

    Backup started on 12/08/2008 at 22:01.
    Backup completed on 12/08/2008 at 22:25.
    Directories: 2578
    Files: 29396
    Bytes: 7,455,993,817
    Time: 23 minutes and 32 seconds
    Backup (via shadow copy) of "D: Data"
    Backup set #2 on media #1
    Backup description: "SBS Backup created on 12/08/2008 at 22:00"
    Media name: "Media created 12/08/2008 at 22:00"

    Backup Type: Normal

    Backup started on 12/08/2008 at 22:25.
    Backup completed on 12/08/2008 at 23:00.
    Directories: 851
    Files: 12829
    Bytes: 9,826,147,981
    Time: 35 minutes and 8 seconds
    Backup (via shadow copy) of "E: New Volume"
    Backup set #3 on media #1
    Backup description: "SBS Backup created on 12/08/2008 at 22:00"
    Media name: "Media created 12/08/2008 at 22:00"

    Backup Type: Normal

    Backup started on 12/08/2008 at 23:00.
    Backup completed on 12/08/2008 at 23:08.
    Directories: 650
    Files: 10973
    Bytes: 1,683,759,800
    Time: 7 minutes and 54 seconds
    Backup of "SBS2003BG\Microsoft Information Store\First Storage Group"
    Backup set #4 on media #1
    Backup description: "SBS Backup created on 12/08/2008 at 22:00"
    Media name: "Media created 12/08/2008 at 22:00"

    Backup Type: Normal

    Backup started on 12/08/2008 at 23:08.
    Backup completed on 12/08/2008 at 23:09.
    Directories: 4
    Files: 8
    Bytes: 346,072,148
    Time: 1 minute and 34 seconds
    Backup (via shadow copy) of "System State"
    Backup set #5 on media #1
    Backup description: "SBS Backup created on 12/08/2008 at 22:00"
    Media name: "Media created 12/08/2008 at 22:00"

    Backup Type: Copy

    Backup started on 12/08/2008 at 23:09.
    The requested media failed to mount. The operation was aborted.
    The operation was ended.
    Backup completed on 12/08/2008 at 23:12.
    Directories: 94
    Files: 2174
    Bytes: 408,492,096
    Time: 2 minutes and 45 seconds

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

    The operation did not successfully complete.

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

    =======================<END NTBACKUP LOG FILE>=====================
    NTBackup finished the backup with errors.

    For more information about failed backups, see the article on
    troubleshooting your backup at the following Web page:
    http://go.microsoft.com/fwlink/?LinkId=18414

    Backup ended at 12 August 2008 23:12
    Backup Runner finished.


    *********************
    *** END OF LOG FILE 1 ***
    *********************


    *********************
    *** LOG FILE 2 ***
    *********************


    13/08/2008 02:00
    -------------------------------
    Date: 13/08/2008
    Time: 02:00
    User: Administrator
    -------------------------------

    Backup Runner started.
    NTMS session started successfully.
    EnumerateNtmsObject(NTMS_LIBRARY) succeeded.
    Will enumerate on 8 media libraries found.
    GetNtmsObjectInformation(NtmsLibraryInfo) succeeded.
    GetNtmsObjectInformation(NtmsLibraryInfo) succeeded.
    Found an enabled library.
    InventoryNtmsLibrary(NtmsInventoryOmid) succeeded.
    Identify Media completed
    EnumerateNtmsObject(NTMS_PHYSICAL_MEDIA) succeeded.
    Will enumerate on 0 media types found.
    GetNtmsObjectInformation(NtmsLibraryInfo) succeeded.
    GetNtmsObjectInformation(NtmsLibraryInfo) succeeded.
    GetNtmsObjectInformation(NtmsLibraryInfo) succeeded.
    GetNtmsObjectInformation(NtmsLibraryInfo) succeeded.
    GetNtmsObjectInformation(NtmsLibraryInfo) succeeded.
    GetNtmsObjectInformation(NtmsLibraryInfo) succeeded.
    Found an enabled library.
    InventoryNtmsLibrary(NtmsInventoryOmid) succeeded.
    Identify Media completed
    EnumerateNtmsObject(NTMS_PHYSICAL_MEDIA) succeeded.
    Will enumerate on 0 media types found.
    Unable to detect tape drive or tape media.
    GetTapeMediaPoolName failed. (0x80070458)
    GetBackupDestination failed. (0x80070458)
    LaunchNTBackup failed. (0x80070458)
    NTBackup finished the backup with errors.

    For more information about failed backups, see the article on
    troubleshooting your backup at the following Web page:
    http://go.microsoft.com/fwlink/?LinkId=18414

    Backup ended at 13 August 2008 02:00
    Backup error finished with error code 0X80070458.


    *********************
    *** END OF LOG FILE 2 ***
    *********************


    *********************
    *** LOG FILE 3 ***
    *********************


    13/08/2008 22:00
    -------------------------------
    Date: 13/08/2008
    Time: 22:00
    User: SYSTEM
    -------------------------------

    Backup Runner started.
    NTMS session started successfully.
    EnumerateNtmsObject(NTMS_LIBRARY) succeeded.
    Will enumerate on 8 media libraries found.
    GetNtmsObjectInformation(NtmsLibraryInfo) succeeded.
    GetNtmsObjectInformation(NtmsLibraryInfo) succeeded.
    Found an enabled library.
    InventoryNtmsLibrary(NtmsInventoryOmid) succeeded.
    Identify Media completed
    EnumerateNtmsObject(NTMS_PHYSICAL_MEDIA) succeeded.
    Will enumerate on 0 media types found.
    GetNtmsObjectInformation(NtmsLibraryInfo) succeeded.
    GetNtmsObjectInformation(NtmsLibraryInfo) succeeded.
    GetNtmsObjectInformation(NtmsLibraryInfo) succeeded.
    GetNtmsObjectInformation(NtmsLibraryInfo) succeeded.
    GetNtmsObjectInformation(NtmsLibraryInfo) succeeded.
    GetNtmsObjectInformation(NtmsLibraryInfo) succeeded.
    Found an enabled library.
    InventoryNtmsLibrary(NtmsInventoryOmid) succeeded.
    Identify Media completed
    EnumerateNtmsObject(NTMS_PHYSICAL_MEDIA) succeeded.
    Will enumerate on 1 media types found.
    GetNtmsObjectInformation(NtmsPhysicalMediaInfo) succeeded.
    GetNtmsObjectInformation(NtmsMediaTypeInfo) succeeded.
    Found a tape media type in the library (4mm DDS).
    Launching NTBackup: ntbackup.exe backup "@E:\Program Files\Microsoft Windows
    Small Business Server\Backup\Small Business Backup Script.bks" /d "SBS
    Backup created on 13/08/2008 at 22:00" /v:yes /r:no /rs:no /hc:eek:n /m normal
    /j "Small Business Server Backup Job" /l:s /p "4mm DDS" /UM
    NTBACKUP LOG FILE: C:\Documents and Settings\SBS Backup User\Local
    Settings\Application Data\Microsoft\Windows NT\NTBackup\data\backup10.log
    =====================<BEGIN NTBACKUP LOG FILE>=====================
    Backup Status
    Operation: Backup
    Active backup destination: 4mm DDS
    Media name: "Media created 13/08/2008 at 22:00"

    Volume shadow copy creation: Attempt 1.
    Backup (via shadow copy) of "C: System"
    Backup set #1 on media #1
    Backup description: "SBS Backup created on 13/08/2008 at 22:00"
    Media name: "Media created 13/08/2008 at 22:00"

    Backup Type: Normal

    Backup started on 13/08/2008 at 22:01.
    Backup completed on 13/08/2008 at 22:25.
    Directories: 2579
    Files: 29435
    Bytes: 7,456,093,700
    Time: 23 minutes and 28 seconds
    Backup (via shadow copy) of "D: Data"
    Backup set #2 on media #1
    Backup description: "SBS Backup created on 13/08/2008 at 22:00"
    Media name: "Media created 13/08/2008 at 22:00"

    Backup Type: Normal

    Backup started on 13/08/2008 at 22:25.
    Backup completed on 13/08/2008 at 23:00.
    Directories: 851
    Files: 12829
    Bytes: 9,829,462,934
    Time: 34 minutes and 55 seconds
    Backup (via shadow copy) of "E: New Volume"
    Backup set #3 on media #1
    Backup description: "SBS Backup created on 13/08/2008 at 22:00"
    Media name: "Media created 13/08/2008 at 22:00"

    Backup Type: Normal

    Backup started on 13/08/2008 at 23:00.
    Backup completed on 13/08/2008 at 23:08.
    Directories: 650
    Files: 10965
    Bytes: 1,641,011,100
    Time: 7 minutes and 55 seconds
    Backup of "SBS2003BG\Microsoft Information Store\First Storage Group"
    Backup set #4 on media #1
    Backup description: "SBS Backup created on 13/08/2008 at 22:00"
    Media name: "Media created 13/08/2008 at 22:00"

    Backup Type: Normal

    Backup started on 13/08/2008 at 23:08.
    Backup completed on 13/08/2008 at 23:09.
    Directories: 4
    Files: 5
    Bytes: 333,488,942
    Time: 1 minute and 29 seconds
    Backup (via shadow copy) of "System State"
    Backup set #5 on media #1
    Backup description: "SBS Backup created on 13/08/2008 at 22:00"
    Media name: "Media created 13/08/2008 at 22:00"

    Backup Type: Copy

    Backup started on 13/08/2008 at 23:09.
    The requested media failed to mount. The operation was aborted.
    The operation was ended.
    Backup completed on 13/08/2008 at 23:12.
    Directories: 142
    Files: 2657
    Bytes: 487,824,335
    Time: 3 minutes and 0 seconds

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

    The operation did not successfully complete.

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

    =======================<END NTBACKUP LOG FILE>=====================
    NTBackup finished the backup with errors.

    For more information about failed backups, see the article on
    troubleshooting your backup at the following Web page:
    http://go.microsoft.com/fwlink/?LinkId=18414

    Backup ended at 13 August 2008 23:12
    Backup Runner finished.


    *********************
    *** END OF LOG FILE 3 ***
    *********************
     
    Derek, Aug 14, 2008
    #1
    1. Advertisements

  2. Derek

    Derek Guest

    Further,

    I have checked the event logs to see how it works on a good night and it
    seems to me that it should be starting this:
    Begin Backup of 'SBS2003BG\Microsoft Information Store\First Storage Group'

    Event Type: Information
    Event Source: NTBackup
    Event Category: None
    Event ID: 8000
    Date: 08/08/2008
    Time: 23:07:48
    User: N/A
    Computer: SBS2003BG
    Description:
    Begin Backup of 'SBS2003BG\Microsoft Information Store\First Storage Group'

    Verify: On
    Mode: Append
    Type: Normal

    For more information, see Help and Support Center at
    http://go.microsoft.com/fwlink/events.asp.

    Could this be pointing to the updates needed for Exchange? In the meantime
    while waiting for one of you to respond I will contact my friend and tell
    him we should apply the patches and restart the server because that may fix
    it, the patches need to be applied soon anyway.

    I look forward to the replies of the group, thanks.
     
    Derek, Aug 14, 2008
    #2
    1. Advertisements

  3. Marina Roos [SBS-MVP], Aug 14, 2008
    #3
  4. Hi Derek,

    From the looks of the logs, you're using hardware compression with your
    tapes. The HP DAT24 has a normal capacity of 12GB. With hardware
    compression, the theoretical capacity would be 24GB. However, this
    "extended" capacity is never reached with compression, so you end up with
    significantly less than 24GB when you employ compression (something like
    1.3-1.6 times the normal capacity). Assuming 1.6, that would give you about
    19GB per tape maximum.

    If I'm reading this correctly, your logs indicate that you're trying to back
    up more than 19.7GB of data. So, I suspect you're simply running out of
    space on the tapes.

    At this point, I would recommend moving to at least two alternating external
    ESATA or USB drives for backups.
     
    Merv Porter [SBS-MVP], Aug 14, 2008
    #4
  5. Derek

    Derek Guest

    Thanks Merv and Marina, you were both right.

    I did some housekeeping and have got the size down for now. We can look
    into bigger tapes, or another way of backing up that will let us take the
    data off site each day.

    Regards.

     
    Derek, Aug 15, 2008
    #5
    1. Advertisements

Ask a Question

Want to reply to this thread or ask your own question?

You'll need to choose a username for the site, which only take a couple of moments (here). After that, you can post your question and our members will help you out.