Video Screencast Help

tape drive dropping offline in the middle of a job

Created: 02 Oct 2012 | 25 comments

The issue we’re seeing is as follows: Backup Exec 2010 R3 SP2: We have a Tandberg Storage Library T24 with two HP LTO5 drives in it. It’s attached by fiber to the Q-Logic 8Gb FC Dual-port HBA. We have 2 backup jobs that run nightly. One goes directly to tape and the other goes to disk and then to tape. Tandberg has swapped out both tape drives and the tape library. We’ve tried different fiber cables and now we’ve swapped out the Q-Logic card. One of the tape drives drops offline in the middle of the disk-to-tape portion of the job. I am unable to find WHY it drops anywhere. I’ve verified the SCSI configuration and I do not see any problems. I’m not seeing the tell-tale Event ID 9 or 11 that would suggest a problem.

If I restart BE services, I can rerun the duplicate to tape job fine, but it fails again at it's next scheduled run.

I'm about to pull my hair out! Any help would be greatly appreciated.

Comments 25 CommentsJump to latest comment

Julie Barnes's picture

These are the errors that appear in my application log when the drive drops offline. Maybe someone can make good sense of it.

 

Log Name:      Application
Source:        Backup Exec
Date:          10/2/2012 2:48:02 AM
Event ID:      58053
Task Category: None
Level:         Error
Keywords:      Classic
User:          N/A
Computer:      SvrTape.mcc.local
Description:
Backup Exec Alert: Device Error
(Server: "SVRTAPE") The drive hardware is offline.

Please confirm that the drive hardware is powered on and properly cabled.

 For more information, click the following link:
http://eventlookup.veritas.com/eventlookup/EventLookup.jhtml
Event Xml:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
  <System>
    <Provider Name="Backup Exec" />
    <EventID Qualifiers="8192">58053</EventID>
    <Level>2</Level>
    <Task>0</Task>
    <Keywords>0x80000000000000</Keywords>
    <TimeCreated SystemTime="2012-10-02T07:48:02.000000000Z" />
    <EventRecordID>35815</EventRecordID>
    <Channel>Application</Channel>
    <Computer>SvrTape.mcc.local</Computer>
    <Security />
  </System>
  <EventData>
    <Data>Backup Exec Alert: Device Error
(Server: "SVRTAPE") The drive hardware is offline.

Please confirm that the drive hardware is powered on and properly cabled.</Data>
  </EventData>
</Event>

Log Name:      Application
Source:        Backup Exec
Date:          10/2/2012 2:47:57 AM
Event ID:      34113
Task Category: None
Level:         Error
Keywords:      Classic
User:          N/A
Computer:      SvrTape.mcc.local
Description:
Backup Exec Alert: Job Failed
(Server: "SVRTAPE") (Job: "Financial Full Backup-Financial Backup Job-Duplicate Financial Backup Sets") Financial Full Backup-Financial Backup Job-Duplicate Financial Backup Sets -- The job failed with the following error: The device timed out.
 

 For more information, click the following link:
http://eventlookup.veritas.com/eventlookup/EventLookup.jhtml
Event Xml:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
  <System>
    <Provider Name="Backup Exec" />
    <EventID Qualifiers="8192">34113</EventID>
    <Level>2</Level>
    <Task>0</Task>
    <Keywords>0x80000000000000</Keywords>
    <TimeCreated SystemTime="2012-10-02T07:47:57.000000000Z" />
    <EventRecordID>35814</EventRecordID>
    <Channel>Application</Channel>
    <Computer>SvrTape.mcc.local</Computer>
    <Security />
  </System>
  <EventData>
    <Data>Backup Exec Alert: Job Failed
(Server: "SVRTAPE") (Job: "Financial Full Backup-Financial Backup Job-Duplicate Financial Backup Sets") Financial Full Backup-Financial Backup Job-Duplicate Financial Backup Sets -- The job failed with the following error: The device timed out.
</Data>
  </EventData>
</Event>

Log Name:      Application
Source:        Backup Exec
Date:          10/2/2012 2:47:57 AM
Event ID:      58053
Task Category: None
Level:         Error
Keywords:      Classic
User:          N/A
Computer:      SvrTape.mcc.local
Description:
Backup Exec Alert: Device Error
(Server: "SVRTAPE") The drive hardware is offline.

Please confirm that the drive hardware is powered on and properly cabled.

 For more information, click the following link:
http://eventlookup.veritas.com/eventlookup/EventLookup.jhtml
Event Xml:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
  <System>
    <Provider Name="Backup Exec" />
    <EventID Qualifiers="8192">58053</EventID>
    <Level>2</Level>
    <Task>0</Task>
    <Keywords>0x80000000000000</Keywords>
    <TimeCreated SystemTime="2012-10-02T07:47:57.000000000Z" />
    <EventRecordID>35813</EventRecordID>
    <Channel>Application</Channel>
    <Computer>SvrTape.mcc.local</Computer>
    <Security />
  </System>
  <EventData>
    <Data>Backup Exec Alert: Device Error
(Server: "SVRTAPE") The drive hardware is offline.

Please confirm that the drive hardware is powered on and properly cabled.</Data>
  </EventData>
</Event>

Log Name:      Application
Source:        Backup Exec
Date:          10/2/2012 2:47:51 AM
Event ID:      33152
Task Category: (1)
Level:         Error
Keywords:      Classic
User:          N/A
Computer:      SvrTape.mcc.local
Description:
[2784] 10/02/12 02:47:51 Adamm Mover Error: DeviceIo: 05:00:00:00 - Refresh handle on "\\.\Tape0", SCSI cmd 1a, new handle 788, error 55

%2
%3
%4
%5
%6
%7
%8
Event Xml:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
  <System>
    <Provider Name="Backup Exec" />
    <EventID Qualifiers="8192">33152</EventID>
    <Level>2</Level>
    <Task>1</Task>
    <Keywords>0x80000000000000</Keywords>
    <TimeCreated SystemTime="2012-10-02T07:47:51.000000000Z" />
    <EventRecordID>35812</EventRecordID>
    <Channel>Application</Channel>
    <Computer>SvrTape.mcc.local</Computer>
    <Security />
  </System>
  <EventData>
    <Data>[2784] 10/02/12 02:47:51 Adamm Mover Error: DeviceIo: 05:00:00:00 - Refresh handle on "\\.\Tape0", SCSI cmd 1a, new handle 788, error 55
</Data>
  </EventData>
</Event>

Log Name:      Application
Source:        Backup Exec
Date:          10/2/2012 2:47:51 AM
Event ID:      33152
Task Category: (1)
Level:         Error
Keywords:      Classic
User:          N/A
Computer:      SvrTape.mcc.local
Description:
[2784] 10/02/12 02:47:51 Adamm Mover Error: DeviceIo: 05:00:00:00 - Refresh handle on "\\.\Tape0", SCSI cmd 01, new handle 788, error 0

%2
%3
%4
%5
%6
%7
%8
Event Xml:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
  <System>
    <Provider Name="Backup Exec" />
    <EventID Qualifiers="8192">33152</EventID>
    <Level>2</Level>
    <Task>1</Task>
    <Keywords>0x80000000000000</Keywords>
    <TimeCreated SystemTime="2012-10-02T07:47:51.000000000Z" />
    <EventRecordID>35811</EventRecordID>
    <Channel>Application</Channel>
    <Computer>SvrTape.mcc.local</Computer>
    <Security />
  </System>
  <EventData>
    <Data>[2784] 10/02/12 02:47:51 Adamm Mover Error: DeviceIo: 05:00:00:00 - Refresh handle on "\\.\Tape0", SCSI cmd 01, new handle 788, error 0
</Data>
  </EventData>
</Event>

Log Name:      Application
Source:        Backup Exec
Date:          10/2/2012 2:47:51 AM
Event ID:      57665
Task Category: None
Level:         Error
Keywords:      Classic
User:          N/A
Computer:      SvrTape.mcc.local
Description:
Storage device "HP 0003" reported an error on a request to write data to media.

Error reported:
This operation returned because the timeout period expired.
.

 For more information, click the following link:
http://eventlookup.veritas.com/eventlookup/EventLookup.jhtml
Event Xml:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
  <System>
    <Provider Name="Backup Exec" />
    <EventID Qualifiers="8192">57665</EventID>
    <Level>2</Level>
    <Task>0</Task>
    <Keywords>0x80000000000000</Keywords>
    <TimeCreated SystemTime="2012-10-02T07:47:51.000000000Z" />
    <EventRecordID>35810</EventRecordID>
    <Channel>Application</Channel>
    <Computer>SvrTape.mcc.local</Computer>
    <Security />
  </System>
  <EventData>
    <Data>HP 0003</Data>
    <Data>write data to media</Data>
    <Data>This operation returned because the timeout period expired.
</Data>
    <Binary>B4050000F08400E0000001000000000093030000</Binary>
  </EventData>
</Event>

Log Name:      Application
Source:        Backup Exec
Date:          10/2/2012 2:47:51 AM
Event ID:      33152
Task Category: (1)
Level:         Error
Keywords:      Classic
User:          N/A
Computer:      SvrTape.mcc.local
Description:
[5340] 10/02/12 02:47:51 Adamm Mover Error: DeviceIo: 05:00:00:00 - Refresh handle on "\\.\Tape0", SCSI cmd 1a, new handle 858, error 0

%2
%3
%4
%5
%6
%7
%8
Event Xml:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
  <System>
    <Provider Name="Backup Exec" />
    <EventID Qualifiers="8192">33152</EventID>
    <Level>2</Level>
    <Task>1</Task>
    <Keywords>0x80000000000000</Keywords>
    <TimeCreated SystemTime="2012-10-02T07:47:51.000000000Z" />
    <EventRecordID>35809</EventRecordID>
    <Channel>Application</Channel>
    <Computer>SvrTape.mcc.local</Computer>
    <Security />
  </System>
  <EventData>
    <Data>[5340] 10/02/12 02:47:51 Adamm Mover Error: DeviceIo: 05:00:00:00 - Refresh handle on "\\.\Tape0", SCSI cmd 1a, new handle 858, error 0
</Data>
  </EventData>
</Event>

Log Name:      Application
Source:        Backup Exec
Date:          10/2/2012 2:47:51 AM
Event ID:      33152
Task Category: (1)
Level:         Error
Keywords:      Classic
User:          N/A
Computer:      SvrTape.mcc.local
Description:
[5340] 10/02/12 02:47:51 Adamm Mover Error: DeviceIo: 00:00:00:00 - Retry Logic: Failed to enable/disable compression on device: HP       Ultrium 5-SCSI 

%2
%3
%4
%5
%6
%7
%8
Event Xml:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
  <System>
    <Provider Name="Backup Exec" />
    <EventID Qualifiers="8192">33152</EventID>
    <Level>2</Level>
    <Task>1</Task>
    <Keywords>0x80000000000000</Keywords>
    <TimeCreated SystemTime="2012-10-02T07:47:51.000000000Z" />
    <EventRecordID>35808</EventRecordID>
    <Channel>Application</Channel>
    <Computer>SvrTape.mcc.local</Computer>
    <Security />
  </System>
  <EventData>
    <Data>[5340] 10/02/12 02:47:51 Adamm Mover Error: DeviceIo: 00:00:00:00 - Retry Logic: Failed to enable/disable compression on device: HP       Ultrium 5-SCSI 
</Data>
  </EventData>
</Event>

Log Name:      Application
Source:        Backup Exec
Date:          10/2/2012 2:47:51 AM
Event ID:      33152
Task Category: (1)
Level:         Error
Keywords:      Classic
User:          N/A
Computer:      SvrTape.mcc.local
Description:
[5340] 10/02/12 02:47:51 Adamm Mover Error: DeviceIo: 05:00:00:00 - Refresh handle on "\\.\Tape0", SCSI cmd 1a, new handle 858, error 0

%2
%3
%4
%5
%6
%7
%8
Event Xml:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
  <System>
    <Provider Name="Backup Exec" />
    <EventID Qualifiers="8192">33152</EventID>
    <Level>2</Level>
    <Task>1</Task>
    <Keywords>0x80000000000000</Keywords>
    <TimeCreated SystemTime="2012-10-02T07:47:51.000000000Z" />
    <EventRecordID>35807</EventRecordID>
    <Channel>Application</Channel>
    <Computer>SvrTape.mcc.local</Computer>
    <Security />
  </System>
  <EventData>
    <Data>[5340] 10/02/12 02:47:51 Adamm Mover Error: DeviceIo: 05:00:00:00 - Refresh handle on "\\.\Tape0", SCSI cmd 1a, new handle 858, error 0
</Data>
  </EventData>
</Event>

Log Name:      Application
Source:        Backup Exec
Date:          10/2/2012 2:47:51 AM
Event ID:      33152
Task Category: (1)
Level:         Error
Keywords:      Classic
User:          N/A
Computer:      SvrTape.mcc.local
Description:
[5340] 10/02/12 02:47:51 Adamm Mover Error: DeviceIo: 00:00:00:00 - Retry Logic: 2 TURs Failed on device: HP       Ultrium 5-SCSI 

%2
%3
%4
%5
%6
%7
%8
Event Xml:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
  <System>
    <Provider Name="Backup Exec" />
    <EventID Qualifiers="8192">33152</EventID>
    <Level>2</Level>
    <Task>1</Task>
    <Keywords>0x80000000000000</Keywords>
    <TimeCreated SystemTime="2012-10-02T07:47:51.000000000Z" />
    <EventRecordID>35806</EventRecordID>
    <Channel>Application</Channel>
    <Computer>SvrTape.mcc.local</Computer>
    <Security />
  </System>
  <EventData>
    <Data>[5340] 10/02/12 02:47:51 Adamm Mover Error: DeviceIo: 00:00:00:00 - Retry Logic: 2 TURs Failed on device: HP       Ultrium 5-SCSI 
</Data>
  </EventData>
</Event>

Log Name:      Application
Source:        Backup Exec
Date:          10/2/2012 2:47:51 AM
Event ID:      33152
Task Category: (1)
Level:         Error
Keywords:      Classic
User:          N/A
Computer:      SvrTape.mcc.local
Description:
[5340] 10/02/12 02:47:51 Adamm Mover Error: DeviceIo: 05:00:00:00 - Refresh handle on "\\.\Tape0", SCSI cmd 00, new handle 858, error 55

%2
%3
%4
%5
%6
%7
%8
Event Xml:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
  <System>
    <Provider Name="Backup Exec" />
    <EventID Qualifiers="8192">33152</EventID>
    <Level>2</Level>
    <Task>1</Task>
    <Keywords>0x80000000000000</Keywords>
    <TimeCreated SystemTime="2012-10-02T07:47:51.000000000Z" />
    <EventRecordID>35805</EventRecordID>
    <Channel>Application</Channel>
    <Computer>SvrTape.mcc.local</Computer>
    <Security />
  </System>
  <EventData>
    <Data>[5340] 10/02/12 02:47:51 Adamm Mover Error: DeviceIo: 05:00:00:00 - Refresh handle on "\\.\Tape0", SCSI cmd 00, new handle 858, error 55
</Data>
  </EventData>
</Event>

Log Name:      Application
Source:        Backup Exec
Date:          10/2/2012 2:47:51 AM
Event ID:      33152
Task Category: (1)
Level:         Error
Keywords:      Classic
User:          N/A
Computer:      SvrTape.mcc.local
Description:
[5340] 10/02/12 02:47:51 Adamm Mover Error: DeviceIo: 05:00:00:00 - Refresh handle on "\\.\Tape0", SCSI cmd 00, new handle 858, error 0

%2
%3
%4
%5
%6
%7
%8
Event Xml:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
  <System>
    <Provider Name="Backup Exec" />
    <EventID Qualifiers="8192">33152</EventID>
    <Level>2</Level>
    <Task>1</Task>
    <Keywords>0x80000000000000</Keywords>
    <TimeCreated SystemTime="2012-10-02T07:47:51.000000000Z" />
    <EventRecordID>35804</EventRecordID>
    <Channel>Application</Channel>
    <Computer>SvrTape.mcc.local</Computer>
    <Security />
  </System>
  <EventData>
    <Data>[5340] 10/02/12 02:47:51 Adamm Mover Error: DeviceIo: 05:00:00:00 - Refresh handle on "\\.\Tape0", SCSI cmd 00, new handle 858, error 0
</Data>
  </EventData>
</Event>

Log Name:      Application
Source:        Backup Exec
Date:          10/2/2012 2:47:51 AM
Event ID:      33152
Task Category: (1)
Level:         Error
Keywords:      Classic
User:          N/A
Computer:      SvrTape.mcc.local
Description:
[5340] 10/02/12 02:47:51 Adamm Mover Error: DeviceIo: 00:00:00:00 - Retry Logic: Retry logic was engaged on device: HP       Ultrium 5-SCSI 

%2
%3
%4
%5
%6
%7
%8
Event Xml:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
  <System>
    <Provider Name="Backup Exec" />
    <EventID Qualifiers="8192">33152</EventID>
    <Level>2</Level>
    <Task>1</Task>
    <Keywords>0x80000000000000</Keywords>
    <TimeCreated SystemTime="2012-10-02T07:47:51.000000000Z" />
    <EventRecordID>35803</EventRecordID>
    <Channel>Application</Channel>
    <Computer>SvrTape.mcc.local</Computer>
    <Security />
  </System>
  <EventData>
    <Data>[5340] 10/02/12 02:47:51 Adamm Mover Error: DeviceIo: 00:00:00:00 - Retry Logic: Retry logic was engaged on device: HP       Ultrium 5-SCSI 
</Data>
  </EventData>
</Event>

Log Name:      Application
Source:        Backup Exec
Date:          10/2/2012 2:42:51 AM
Event ID:      33152
Task Category: (1)
Level:         Error
Keywords:      Classic
User:          N/A
Computer:      SvrTape.mcc.local
Description:
[5340] 10/02/12 02:42:51 Adamm Mover Error: DeviceIo: 05:00:00:00 - Device error 1167 on "\\.\Tape0", SCSI cmd 0a, 1 total errors

%2
%3
%4
%5
%6
%7
%8
Event Xml:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
  <System>
    <Provider Name="Backup Exec" />
    <EventID Qualifiers="8192">33152</EventID>
    <Level>2</Level>
    <Task>1</Task>
    <Keywords>0x80000000000000</Keywords>
    <TimeCreated SystemTime="2012-10-02T07:42:51.000000000Z" />
    <EventRecordID>35802</EventRecordID>
    <Channel>Application</Channel>
    <Computer>SvrTape.mcc.local</Computer>
    <Security />
  </System>
  <EventData>
    <Data>[5340] 10/02/12 02:42:51 Adamm Mover Error: DeviceIo: 05:00:00:00 - Device error 1167 on "\\.\Tape0", SCSI cmd 0a, 1 total errors
</Data>
  </EventData>
</Event>

 

Larry Fine's picture

The adamm.log shows that command 34 (read_position) failed.  BE then took the drive offline.

[1764] 10/02/12 02:47:51.617 DeviceIo: 05:00:00:00 - Device error 55 on "\\.\Tape0", SCSI cmd 34, 1 total errors
[1764] 10/02/12 02:47:56.896 PvlDrive::DisableAccess() - ReserveDevice failed, offline device
       Drive = 1026 "HP 0003"
       ERROR = 0x0000001F (ERROR_GEN_FAILURE)

[1764] 10/02/12 02:47:56.903 PvlDrive::UpdateOnlineState()
       Drive = 1026 "HP 0003"
       ERROR = The device is offline!

 

The Windows event logs show communication issues.

[2784] 10/02/12 02:47:51 Adamm Mover Error: DeviceIo: 05:00:00:00 - Refresh handle on "\\.\Tape0", SCSI cmd 1a, new handle 788, error 55

Description:
Storage device "HP 0003" reported an error on a request to write data to media. Error reported:
This operation returned because the timeout period expired.

 

Do you have the latest drivers and firmware for yout QLogic card?

 

 

 

If you find this is a solution for the thread, please mark it as such.

Julie Barnes's picture

Yes. IBM verified that before they replaced my HBA last week.

Larry Fine's picture

Is it always the B2D2T job that has issues?  and the B2T job always works fine?

Is it always the same drive that goes offline?  Can you change the job to use the "good" drive as a test?

If you find this is a solution for the thread, please mark it as such.

Julie Barnes's picture

Job 1 = a backup straight to tape job - always works

Job 2 = D2D2T - worked fine for months. The D2D portion always works fine, but the D2T fails somewhere in the middle of the job when the drive goes offline for whatever reason.

I will swap the drives tonight to see what happens tonight. We did swap every combination of the previous drives, cables and HBA ports and the problem always followed the previous drive. Tandberg sent us a replacement drive and the problem persisted. That's when we went to IBM. They had us update the firmware on the QLogic HBA. Job still failed. IBM had us download and run their DSA utility. I didn't notice any obvious issues, but they sent us a replacement HBA to no avail.

Thanks, Larry. Will update with results.

Larry Fine's picture

Just a single BE server and no FC switch, right?

So, it seems you had the issue isolated to a single tape drive, but the replacement tape drive had the same issue?  That is weird, but it sounds like you are on the right tactic to try and identify the bad link in the chain.

If you find this is a solution for the thread, please mark it as such.

Julie Barnes's picture

That is correct. No FC switch. The tape library and both drives test out good with Tandberg's and HP's library and tape utilities. So I moved the QLogic card to a different slot...no change. Then swapped out QLogic HBA...no change. I don't think anything has changed within BE. This is why I'm pulling my hair out. Thanks again!

Julie Barnes's picture

Symantec tech support had me change my library to used "unknown driver" under devices. I did that and then followed the power down/up sequence as suggested. Only wanting to make one change at a time while testing, I did not swap drives yet. But I did not receive a "device not ready (event id 15)" in my system log last night.

Here are the errors from the application log:

Log Name:      Application
Source:        Backup Exec
Date:          10/3/2012 12:37:37 AM
Event ID:      58053
Task Category: None
Level:         Error
Keywords:      Classic
User:          N/A
Computer:      SvrTape.mcc.local
Description:
Backup Exec Alert: Device Error
(Server: "SVRTAPE") The drive hardware is offline.

Please confirm that the drive hardware is powered on and properly cabled.

 For more information, click the following link:
http://eventlookup.veritas.com/eventlookup/EventLo...
Event Xml:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
  <System>
    <Provider Name="Backup Exec" />
    <EventID Qualifiers="8192">58053</EventID>
    <Level>2</Level>
    <Task>0</Task>
    <Keywords>0x80000000000000</Keywords>
    <TimeCreated SystemTime="2012-10-03T05:37:37.000000000Z" />
    <EventRecordID>36232</EventRecordID>
    <Channel>Application</Channel>
    <Computer>SvrTape.mcc.local</Computer>
    <Security />
  </System>
  <EventData>
    <Data>Backup Exec Alert: Device Error
(Server: "SVRTAPE") The drive hardware is offline.

Please confirm that the drive hardware is powered on and properly cabled.</Data>
  </EventData>
</Event>

Log Name:      Application
Source:        Backup Exec
Date:          10/3/2012 12:37:32 AM
Event ID:      34113
Task Category: None
Level:         Error
Keywords:      Classic
User:          N/A
Computer:      SvrTape.mcc.local
Description:
Backup Exec Alert: Job Failed
(Server: "SVRTAPE") (Job: "Financial Full Backup-Financial Backup Job-Duplicate Financial Backup Sets") Financial Full Backup-Financial Backup Job-Duplicate Financial Backup Sets -- The job failed with the following error: The device timed out.
 

 For more information, click the following link:
http://eventlookup.veritas.com/eventlookup/EventLo...
Event Xml:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
  <System>
    <Provider Name="Backup Exec" />
    <EventID Qualifiers="8192">34113</EventID>
    <Level>2</Level>
    <Task>0</Task>
    <Keywords>0x80000000000000</Keywords>
    <TimeCreated SystemTime="2012-10-03T05:37:32.000000000Z" />
    <EventRecordID>36231</EventRecordID>
    <Channel>Application</Channel>
    <Computer>SvrTape.mcc.local</Computer>
    <Security />
  </System>
  <EventData>
    <Data>Backup Exec Alert: Job Failed
(Server: "SVRTAPE") (Job: "Financial Full Backup-Financial Backup Job-Duplicate Financial Backup Sets") Financial Full Backup-Financial Backup Job-Duplicate Financial Backup Sets -- The job failed with the following error: The device timed out.
</Data>
  </EventData>
</Event>

Log Name:      Application
Source:        Backup Exec
Date:          10/3/2012 12:37:32 AM
Event ID:      58053
Task Category: None
Level:         Error
Keywords:      Classic
User:          N/A
Computer:      SvrTape.mcc.local
Description:
Backup Exec Alert: Device Error
(Server: "SVRTAPE") The drive hardware is offline.

Please confirm that the drive hardware is powered on and properly cabled.

 For more information, click the following link:
http://eventlookup.veritas.com/eventlookup/EventLo...
Event Xml:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
  <System>
    <Provider Name="Backup Exec" />
    <EventID Qualifiers="8192">58053</EventID>
    <Level>2</Level>
    <Task>0</Task>
    <Keywords>0x80000000000000</Keywords>
    <TimeCreated SystemTime="2012-10-03T05:37:32.000000000Z" />
    <EventRecordID>36230</EventRecordID>
    <Channel>Application</Channel>
    <Computer>SvrTape.mcc.local</Computer>
    <Security />
  </System>
  <EventData>
    <Data>Backup Exec Alert: Device Error
(Server: "SVRTAPE") The drive hardware is offline.

Please confirm that the drive hardware is powered on and properly cabled.</Data>
  </EventData>
</Event>

Log Name:      Application
Source:        Backup Exec
Date:          10/3/2012 12:37:27 AM
Event ID:      33152
Task Category: (1)
Level:         Error
Keywords:      Classic
User:          N/A
Computer:      SvrTape.mcc.local
Description:
[6044] 10/03/12 00:37:27 Adamm Mover Error: DeviceIo: 05:00:00:00 - Refresh handle on "\\.\Tape0", SCSI cmd 1a, new handle 8c0, error 0

%2
%3
%4
%5
%6
%7
%8
Event Xml:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
  <System>
    <Provider Name="Backup Exec" />
    <EventID Qualifiers="8192">33152</EventID>
    <Level>2</Level>
    <Task>1</Task>
    <Keywords>0x80000000000000</Keywords>
    <TimeCreated SystemTime="2012-10-03T05:37:27.000000000Z" />
    <EventRecordID>36229</EventRecordID>
    <Channel>Application</Channel>
    <Computer>SvrTape.mcc.local</Computer>
    <Security />
  </System>
  <EventData>
    <Data>[6044] 10/03/12 00:37:27 Adamm Mover Error: DeviceIo: 05:00:00:00 - Refresh handle on "\\.\Tape0", SCSI cmd 1a, new handle 8c0, error 0
</Data>
  </EventData>
</Event>

Log Name:      Application
Source:        Backup Exec
Date:          10/3/2012 12:37:27 AM
Event ID:      33152
Task Category: (1)
Level:         Error
Keywords:      Classic
User:          N/A
Computer:      SvrTape.mcc.local
Description:
[6044] 10/03/12 00:37:27 Adamm Mover Error: DeviceIo: 05:00:00:00 - Device error 55 on "\\.\Tape0", SCSI cmd 01, 6 total errors

%2
%3
%4
%5
%6
%7
%8
Event Xml:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
  <System>
    <Provider Name="Backup Exec" />
    <EventID Qualifiers="8192">33152</EventID>
    <Level>2</Level>
    <Task>1</Task>
    <Keywords>0x80000000000000</Keywords>
    <TimeCreated SystemTime="2012-10-03T05:37:27.000000000Z" />
    <EventRecordID>36228</EventRecordID>
    <Channel>Application</Channel>
    <Computer>SvrTape.mcc.local</Computer>
    <Security />
  </System>
  <EventData>
    <Data>[6044] 10/03/12 00:37:27 Adamm Mover Error: DeviceIo: 05:00:00:00 - Device error 55 on "\\.\Tape0", SCSI cmd 01, 6 total errors
</Data>
  </EventData>
</Event>

Log Name:      Application
Source:        Backup Exec
Date:          10/3/2012 12:37:27 AM
Event ID:      33152
Task Category: (1)
Level:         Error
Keywords:      Classic
User:          N/A
Computer:      SvrTape.mcc.local
Description:
[6044] 10/03/12 00:37:27 Adamm Mover Error: DeviceIo: 05:00:00:00 - Refresh handle on "\\.\Tape0", SCSI cmd 01, new handle 8c0, error 0

%2
%3
%4
%5
%6
%7
%8
Event Xml:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
  <System>
    <Provider Name="Backup Exec" />
    <EventID Qualifiers="8192">33152</EventID>
    <Level>2</Level>
    <Task>1</Task>
    <Keywords>0x80000000000000</Keywords>
    <TimeCreated SystemTime="2012-10-03T05:37:27.000000000Z" />
    <EventRecordID>36227</EventRecordID>
    <Channel>Application</Channel>
    <Computer>SvrTape.mcc.local</Computer>
    <Security />
  </System>
  <EventData>
    <Data>[6044] 10/03/12 00:37:27 Adamm Mover Error: DeviceIo: 05:00:00:00 - Refresh handle on "\\.\Tape0", SCSI cmd 01, new handle 8c0, error 0
</Data>
  </EventData>
</Event>

Log Name:      Application
Source:        Backup Exec
Date:          10/3/2012 12:37:26 AM
Event ID:      57665
Task Category: None
Level:         Error
Keywords:      Classic
User:          N/A
Computer:      SvrTape.mcc.local
Description:
Storage device "HP 0003" reported an error on a request to write data to media.

Error reported:
This operation returned because the timeout period expired.
.

 For more information, click the following link:
http://eventlookup.veritas.com/eventlookup/EventLo...
Event Xml:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
  <System>
    <Provider Name="Backup Exec" />
    <EventID Qualifiers="8192">57665</EventID>
    <Level>2</Level>
    <Task>0</Task>
    <Keywords>0x80000000000000</Keywords>
    <TimeCreated SystemTime="2012-10-03T05:37:26.000000000Z" />
    <EventRecordID>36226</EventRecordID>
    <Channel>Application</Channel>
    <Computer>SvrTape.mcc.local</Computer>
    <Security />
  </System>
  <EventData>
    <Data>HP 0003</Data>
    <Data>write data to media</Data>
    <Data>This operation returned because the timeout period expired.
</Data>
    <Binary>B4050000F08400E0000001000000000093030000</Binary>
  </EventData>
</Event>

Log Name:      Application
Source:        Backup Exec
Date:          10/3/2012 12:37:26 AM
Event ID:      33152
Task Category: (1)
Level:         Error
Keywords:      Classic
User:          N/A
Computer:      SvrTape.mcc.local
Description:
[4616] 10/03/12 00:37:26 Adamm Mover Error: DeviceIo: 05:00:00:00 - Refresh handle on "\\.\Tape0", SCSI cmd 1a, new handle 748, error 0

%2
%3
%4
%5
%6
%7
%8
Event Xml:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
  <System>
    <Provider Name="Backup Exec" />
    <EventID Qualifiers="8192">33152</EventID>
    <Level>2</Level>
    <Task>1</Task>
    <Keywords>0x80000000000000</Keywords>
    <TimeCreated SystemTime="2012-10-03T05:37:26.000000000Z" />
    <EventRecordID>36225</EventRecordID>
    <Channel>Application</Channel>
    <Computer>SvrTape.mcc.local</Computer>
    <Security />
  </System>
  <EventData>
    <Data>[4616] 10/03/12 00:37:26 Adamm Mover Error: DeviceIo: 05:00:00:00 - Refresh handle on "\\.\Tape0", SCSI cmd 1a, new handle 748, error 0
</Data>
  </EventData>
</Event>

Log Name:      Application
Source:        Backup Exec
Date:          10/3/2012 12:37:26 AM
Event ID:      33152
Task Category: (1)
Level:         Error
Keywords:      Classic
User:          N/A
Computer:      SvrTape.mcc.local
Description:
[4616] 10/03/12 00:37:26 Adamm Mover Error: DeviceIo: 00:00:00:00 - Retry Logic: Failed to enable/disable compression on device: HP       Ultrium 5-SCSI  

%2
%3
%4
%5
%6
%7
%8
Event Xml:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
  <System>
    <Provider Name="Backup Exec" />
    <EventID Qualifiers="8192">33152</EventID>
    <Level>2</Level>
    <Task>1</Task>
    <Keywords>0x80000000000000</Keywords>
    <TimeCreated SystemTime="2012-10-03T05:37:26.000000000Z" />
    <EventRecordID>36224</EventRecordID>
    <Channel>Application</Channel>
    <Computer>SvrTape.mcc.local</Computer>
    <Security />
  </System>
  <EventData>
    <Data>[4616] 10/03/12 00:37:26 Adamm Mover Error: DeviceIo: 00:00:00:00 - Retry Logic: Failed to enable/disable compression on device: HP       Ultrium 5-SCSI  
</Data>
  </EventData>
</Event>

Log Name:      Application
Source:        Backup Exec
Date:          10/3/2012 12:37:26 AM
Event ID:      33152
Task Category: (1)
Level:         Error
Keywords:      Classic
User:          N/A
Computer:      SvrTape.mcc.local
Description:
[4616] 10/03/12 00:37:26 Adamm Mover Error: DeviceIo: 05:00:00:00 - Refresh handle on "\\.\Tape0", SCSI cmd 1a, new handle 748, error 0

%2
%3
%4
%5
%6
%7
%8
Event Xml:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
  <System>
    <Provider Name="Backup Exec" />
    <EventID Qualifiers="8192">33152</EventID>
    <Level>2</Level>
    <Task>1</Task>
    <Keywords>0x80000000000000</Keywords>
    <TimeCreated SystemTime="2012-10-03T05:37:26.000000000Z" />
    <EventRecordID>36223</EventRecordID>
    <Channel>Application</Channel>
    <Computer>SvrTape.mcc.local</Computer>
    <Security />
  </System>
  <EventData>
    <Data>[4616] 10/03/12 00:37:26 Adamm Mover Error: DeviceIo: 05:00:00:00 - Refresh handle on "\\.\Tape0", SCSI cmd 1a, new handle 748, error 0
</Data>
  </EventData>
</Event>

Log Name:      Application
Source:        Backup Exec
Date:          10/3/2012 12:37:26 AM
Event ID:      33152
Task Category: (1)
Level:         Error
Keywords:      Classic
User:          N/A
Computer:      SvrTape.mcc.local
Description:
[4616] 10/03/12 00:37:26 Adamm Mover Error: DeviceIo: 00:00:00:00 - Retry Logic: 2 TURs Failed on device: HP       Ultrium 5-SCSI  

%2
%3
%4
%5
%6
%7
%8
Event Xml:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
  <System>
    <Provider Name="Backup Exec" />
    <EventID Qualifiers="8192">33152</EventID>
    <Level>2</Level>
    <Task>1</Task>
    <Keywords>0x80000000000000</Keywords>
    <TimeCreated SystemTime="2012-10-03T05:37:26.000000000Z" />
    <EventRecordID>36222</EventRecordID>
    <Channel>Application</Channel>
    <Computer>SvrTape.mcc.local</Computer>
    <Security />
  </System>
  <EventData>
    <Data>[4616] 10/03/12 00:37:26 Adamm Mover Error: DeviceIo: 00:00:00:00 - Retry Logic: 2 TURs Failed on device: HP       Ultrium 5-SCSI  
</Data>
  </EventData>
</Event>

Log Name:      Application
Source:        Backup Exec
Date:          10/3/2012 12:37:26 AM
Event ID:      33152
Task Category: (1)
Level:         Error
Keywords:      Classic
User:          N/A
Computer:      SvrTape.mcc.local
Description:
[4616] 10/03/12 00:37:26 Adamm Mover Error: DeviceIo: 05:00:00:00 - Refresh handle on "\\.\Tape0", SCSI cmd 00, new handle 748, error 55

%2
%3
%4
%5
%6
%7
%8
Event Xml:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
  <System>
    <Provider Name="Backup Exec" />
    <EventID Qualifiers="8192">33152</EventID>
    <Level>2</Level>
    <Task>1</Task>
    <Keywords>0x80000000000000</Keywords>
    <TimeCreated SystemTime="2012-10-03T05:37:26.000000000Z" />
    <EventRecordID>36221</EventRecordID>
    <Channel>Application</Channel>
    <Computer>SvrTape.mcc.local</Computer>
    <Security />
  </System>
  <EventData>
    <Data>[4616] 10/03/12 00:37:26 Adamm Mover Error: DeviceIo: 05:00:00:00 - Refresh handle on "\\.\Tape0", SCSI cmd 00, new handle 748, error 55
</Data>
  </EventData>
</Event>

Log Name:      Application
Source:        Backup Exec
Date:          10/3/2012 12:37:26 AM
Event ID:      33152
Task Category: (1)
Level:         Error
Keywords:      Classic
User:          N/A
Computer:      SvrTape.mcc.local
Description:
[4616] 10/03/12 00:37:26 Adamm Mover Error: DeviceIo: 05:00:00:00 - Refresh handle on "\\.\Tape0", SCSI cmd 00, new handle 748, error 0

%2
%3
%4
%5
%6
%7
%8
Event Xml:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
  <System>
    <Provider Name="Backup Exec" />
    <EventID Qualifiers="8192">33152</EventID>
    <Level>2</Level>
    <Task>1</Task>
    <Keywords>0x80000000000000</Keywords>
    <TimeCreated SystemTime="2012-10-03T05:37:26.000000000Z" />
    <EventRecordID>36220</EventRecordID>
    <Channel>Application</Channel>
    <Computer>SvrTape.mcc.local</Computer>
    <Security />
  </System>
  <EventData>
    <Data>[4616] 10/03/12 00:37:26 Adamm Mover Error: DeviceIo: 05:00:00:00 - Refresh handle on "\\.\Tape0", SCSI cmd 00, new handle 748, error 0
</Data>
  </EventData>
</Event>

Log Name:      Application
Source:        Backup Exec
Date:          10/3/2012 12:37:26 AM
Event ID:      33152
Task Category: (1)
Level:         Error
Keywords:      Classic
User:          N/A
Computer:      SvrTape.mcc.local
Description:
[4616] 10/03/12 00:37:26 Adamm Mover Error: DeviceIo: 00:00:00:00 - Retry Logic: Retry logic was engaged on device: HP       Ultrium 5-SCSI  

%2
%3
%4
%5
%6
%7
%8
Event Xml:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
  <System>
    <Provider Name="Backup Exec" />
    <EventID Qualifiers="8192">33152</EventID>
    <Level>2</Level>
    <Task>1</Task>
    <Keywords>0x80000000000000</Keywords>
    <TimeCreated SystemTime="2012-10-03T05:37:26.000000000Z" />
    <EventRecordID>36219</EventRecordID>
    <Channel>Application</Channel>
    <Computer>SvrTape.mcc.local</Computer>
    <Security />
  </System>
  <EventData>
    <Data>[4616] 10/03/12 00:37:26 Adamm Mover Error: DeviceIo: 00:00:00:00 - Retry Logic: Retry logic was engaged on device: HP       Ultrium 5-SCSI  
</Data>
  </EventData>
</Event>

Log Name:      Application
Source:        Backup Exec
Date:          10/3/2012 12:32:26 AM
Event ID:      33152
Task Category: (1)
Level:         Error
Keywords:      Classic
User:          N/A
Computer:      SvrTape.mcc.local
Description:
[4616] 10/03/12 00:32:26 Adamm Mover Error: DeviceIo: 05:00:00:00 - Device error 1167 on "\\.\Tape0", SCSI cmd 0a, 1 total errors

%2
%3
%4
%5
%6
%7
%8
Event Xml:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
  <System>
    <Provider Name="Backup Exec" />
    <EventID Qualifiers="8192">33152</EventID>
    <Level>2</Level>
    <Task>1</Task>
    <Keywords>0x80000000000000</Keywords>
    <TimeCreated SystemTime="2012-10-03T05:32:26.000000000Z" />
    <EventRecordID>36218</EventRecordID>
    <Channel>Application</Channel>
    <Computer>SvrTape.mcc.local</Computer>
    <Security />
  </System>
  <EventData>
    <Data>[4616] 10/03/12 00:32:26 Adamm Mover Error: DeviceIo: 05:00:00:00 - Device error 1167 on "\\.\Tape0", SCSI cmd 0a, 1 total errors
</Data>
  </EventData>
</Event>

 

I'll continue to work with Symante Tech Support, but if someone here sees something, please let me know. As things stand now, I do plan to switch the drives around tonight. What's frustrating is that everything else works fine. I did numerous inventories, catalogs and restores yesterday using both drives. It only craps out on me while writing.

Thanks again!

Larry Fine's picture

re: [6044] 10/03/12 00:37:27 Adamm Mover Error: DeviceIo: 05:00:00:00 - Refresh handle on "\\.\Tape0", SCSI cmd 1a, new handle 8c0, error 0

 

the "refresh handle" indicates that the device went away from the operating system and everything after that is BE trying to find the device and recover.  That woud imply a hardware issue to me.

If you find this is a solution for the thread, please mark it as such.

Julie Barnes's picture

Will run library and tape diagnostics again. They all ran fine last week and no errors in system log. I'm not sure what's more frustrating...the hardware or the software. Thanks, Larry.

Jaydeep S's picture

As a help in this error I am not sure how the Library and Tape Tools might help you detect the cause as such.

Try to look up the event viewer around the same time stamp as that error message. See if there was any events that indicate either a connection to device lost or any errors or warnings.

re: [6044] 10/03/12 00:37:27 Adamm Mover Error: DeviceIo: 05:00:00:00 - Refresh handle on "\\.\Tape0", SCSI cmd 1a, new handle 8c0, error 0

re: [6044] 10/03/12 00:37:27 Adamm Mover Error: DeviceIo: 05:00:00:00 - Refresh handle on "\\.\Tape0", SCSI cmd 1a, new handle 8c0, error 0

 

Julie Barnes's picture

Jaydeep - There were no corresponding errors. The Symantec tech that was working on the issue with me kept insisting it had to be hardware. So, I ran through the gamut of hardware tests yet again with no errors.

Everything worked fine last night. Here's my thinking, but I have no hard proof to back it up. A while back, we did lose a tape drive in the library and had to replace it. While waiting for the replacement drive, we moved the good drive to the primary slot in our library. When we got the replacement drive installed, Backup Exec recognized the 2 drives as Tape2 and Tape3. But Tape2 in BE was actually Tape2 in the library and Tape3 in BE was actually Tape1 in the library.

I'm *thinking* maybe the drives being "out of order" so-to-speak might have been causing an issue.

Yesterday, I removed all references to the changer and drives in both BE and the O/S and reinstalled in the correct order, making sure Tape1 in the library was now Tape1 in BE and Tape2 in the library was now Tape2 and making sure the changer was using Microsoft unknown device drivers and the tape drives were using Symantec drivers. Now I'm not 100% sure if my line of thinking is correct or not, but both jobs ran successfully last night and both drives remained online.

Jaydeep S's picture

Julie this might be correct. I have seen these entries cause an issue. Hence I normally advice to remove the Tape Drive an Changer entry from Backup Exec and restart the services after any hardware change as a best practise.

Julie Barnes's picture

Well so much for that. Drive dropped offline again last night. No errors in system log. Device manager shows everything as good.

Application log errors as follows:

Log Name:      Application
Source:        Backup Exec
Date:          10/5/2012 12:06:04 AM
Event ID:      58053
Task Category: None
Level:         Error
Keywords:      Classic
User:          N/A
Computer:      SvrTape.mcc.local
Description:
Backup Exec Alert: Device Error
(Server: "SVRTAPE") The drive hardware is offline.

Please confirm that the drive hardware is powered on and properly cabled.

 For more information, click the following link:
http://eventlookup.veritas.com/eventlookup/EventLo...
Event Xml:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
  <System>
    <Provider Name="Backup Exec" />
    <EventID Qualifiers="8192">58053</EventID>
    <Level>2</Level>
    <Task>0</Task>
    <Keywords>0x80000000000000</Keywords>
    <TimeCreated SystemTime="2012-10-05T05:06:04.000000000Z" />
    <EventRecordID>39024</EventRecordID>
    <Channel>Application</Channel>
    <Computer>SvrTape.mcc.local</Computer>
    <Security />
  </System>
  <EventData>
    <Data>Backup Exec Alert: Device Error
(Server: "SVRTAPE") The drive hardware is offline.

Please confirm that the drive hardware is powered on and properly cabled.</Data>
  </EventData>
</Event>

Log Name:      Application
Source:        Backup Exec
Date:          10/5/2012 12:05:58 AM
Event ID:      57665
Task Category: None
Level:         Error
Keywords:      Classic
User:          N/A
Computer:      SvrTape.mcc.local
Description:
Storage device "HP 0001" reported an error on a request to rewind the media.

Error reported:
A device attached to the system is not functioning.
.

 For more information, click the following link:
http://eventlookup.veritas.com/eventlookup/EventLo...
Event Xml:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
  <System>
    <Provider Name="Backup Exec" />
    <EventID Qualifiers="8192">57665</EventID>
    <Level>2</Level>
    <Task>0</Task>
    <Keywords>0x80000000000000</Keywords>
    <TimeCreated SystemTime="2012-10-05T05:05:58.000000000Z" />
    <EventRecordID>39023</EventRecordID>
    <Channel>Application</Channel>
    <Computer>SvrTape.mcc.local</Computer>
    <Security />
  </System>
  <EventData>
    <Data>HP 0001</Data>
    <Data>rewind the media</Data>
    <Data>A device attached to the system is not functioning.
</Data>
    <Binary>1F000000F48400E000000000000000009B030000</Binary>
  </EventData>
</Event>

Log Name:      Application
Source:        Backup Exec
Date:          10/5/2012 12:05:58 AM
Event ID:      33152
Task Category: (1)
Level:         Error
Keywords:      Classic
User:          N/A
Computer:      SvrTape.mcc.local
Description:
[4960] 10/05/12 00:05:58 Adamm Mover Error: DeviceIo: 05:00:00:00 - Refresh handle on "\\.\Tape1", SCSI cmd 1a, new handle 914, error 0

%2
%3
%4
%5
%6
%7
%8
Event Xml:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
  <System>
    <Provider Name="Backup Exec" />
    <EventID Qualifiers="8192">33152</EventID>
    <Level>2</Level>
    <Task>1</Task>
    <Keywords>0x80000000000000</Keywords>
    <TimeCreated SystemTime="2012-10-05T05:05:58.000000000Z" />
    <EventRecordID>39022</EventRecordID>
    <Channel>Application</Channel>
    <Computer>SvrTape.mcc.local</Computer>
    <Security />
  </System>
  <EventData>
    <Data>[4960] 10/05/12 00:05:58 Adamm Mover Error: DeviceIo: 05:00:00:00 - Refresh handle on "\\.\Tape1", SCSI cmd 1a, new handle 914, error 0
</Data>
  </EventData>
</Event>

Log Name:      Application
Source:        Backup Exec
Date:          10/5/2012 12:05:58 AM
Event ID:      33152
Task Category: (1)
Level:         Error
Keywords:      Classic
User:          N/A
Computer:      SvrTape.mcc.local
Description:
[4960] 10/05/12 00:05:58 Adamm Mover Error: DeviceIo: 05:00:00:00 - Device error 55 on "\\.\Tape1", SCSI cmd 01, 6 total errors

%2
%3
%4
%5
%6
%7
%8
Event Xml:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
  <System>
    <Provider Name="Backup Exec" />
    <EventID Qualifiers="8192">33152</EventID>
    <Level>2</Level>
    <Task>1</Task>
    <Keywords>0x80000000000000</Keywords>
    <TimeCreated SystemTime="2012-10-05T05:05:58.000000000Z" />
    <EventRecordID>39021</EventRecordID>
    <Channel>Application</Channel>
    <Computer>SvrTape.mcc.local</Computer>
    <Security />
  </System>
  <EventData>
    <Data>[4960] 10/05/12 00:05:58 Adamm Mover Error: DeviceIo: 05:00:00:00 - Device error 55 on "\\.\Tape1", SCSI cmd 01, 6 total errors
</Data>
  </EventData>
</Event>

Log Name:      Application
Source:        Backup Exec
Date:          10/5/2012 12:05:58 AM
Event ID:      33152
Task Category: (1)
Level:         Error
Keywords:      Classic
User:          N/A
Computer:      SvrTape.mcc.local
Description:
[4960] 10/05/12 00:05:58 Adamm Mover Error: DeviceIo: 05:00:00:00 - Refresh handle on "\\.\Tape1", SCSI cmd 01, new handle 914, error 87

%2
%3
%4
%5
%6
%7
%8
Event Xml:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
  <System>
    <Provider Name="Backup Exec" />
    <EventID Qualifiers="8192">33152</EventID>
    <Level>2</Level>
    <Task>1</Task>
    <Keywords>0x80000000000000</Keywords>
    <TimeCreated SystemTime="2012-10-05T05:05:58.000000000Z" />
    <EventRecordID>39020</EventRecordID>
    <Channel>Application</Channel>
    <Computer>SvrTape.mcc.local</Computer>
    <Security />
  </System>
  <EventData>
    <Data>[4960] 10/05/12 00:05:58 Adamm Mover Error: DeviceIo: 05:00:00:00 - Refresh handle on "\\.\Tape1", SCSI cmd 01, new handle 914, error 87
</Data>
  </EventData>
</Event>

Log Name:      Application
Source:        Backup Exec
Date:          10/5/2012 12:05:57 AM
Event ID:      57665
Task Category: None
Level:         Error
Keywords:      Classic
User:          N/A
Computer:      SvrTape.mcc.local
Description:
Storage device "HP 0001" reported an error on a request to write data to media.

Error reported:
This operation returned because the timeout period expired.
.

 For more information, click the following link:
http://eventlookup.veritas.com/eventlookup/EventLo...
Event Xml:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
  <System>
    <Provider Name="Backup Exec" />
    <EventID Qualifiers="8192">57665</EventID>
    <Level>2</Level>
    <Task>0</Task>
    <Keywords>0x80000000000000</Keywords>
    <TimeCreated SystemTime="2012-10-05T05:05:57.000000000Z" />
    <EventRecordID>39019</EventRecordID>
    <Channel>Application</Channel>
    <Computer>SvrTape.mcc.local</Computer>
    <Security />
  </System>
  <EventData>
    <Data>HP 0001</Data>
    <Data>write data to media</Data>
    <Data>This operation returned because the timeout period expired.
</Data>
    <Binary>B4050000F08400E0000001000000000093030000</Binary>
  </EventData>
</Event>

Log Name:      Application
Source:        Backup Exec
Date:          10/5/2012 12:05:57 AM
Event ID:      33152
Task Category: (1)
Level:         Error
Keywords:      Classic
User:          N/A
Computer:      SvrTape.mcc.local
Description:
[4616] 10/05/12 00:05:57 Adamm Mover Error: DeviceIo: 05:00:00:00 - Refresh handle on "\\.\Tape1", SCSI cmd 1a, new handle 944, error 0

%2
%3
%4
%5
%6
%7
%8
Event Xml:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
  <System>
    <Provider Name="Backup Exec" />
    <EventID Qualifiers="8192">33152</EventID>
    <Level>2</Level>
    <Task>1</Task>
    <Keywords>0x80000000000000</Keywords>
    <TimeCreated SystemTime="2012-10-05T05:05:57.000000000Z" />
    <EventRecordID>39018</EventRecordID>
    <Channel>Application</Channel>
    <Computer>SvrTape.mcc.local</Computer>
    <Security />
  </System>
  <EventData>
    <Data>[4616] 10/05/12 00:05:57 Adamm Mover Error: DeviceIo: 05:00:00:00 - Refresh handle on "\\.\Tape1", SCSI cmd 1a, new handle 944, error 0
</Data>
  </EventData>
</Event>

Log Name:      Application
Source:        Backup Exec
Date:          10/5/2012 12:05:57 AM
Event ID:      33152
Task Category: (1)
Level:         Error
Keywords:      Classic
User:          N/A
Computer:      SvrTape.mcc.local
Description:
[4616] 10/05/12 00:05:57 Adamm Mover Error: DeviceIo: 00:00:00:00 - Retry Logic: Failed to enable/disable compression on device: HP       Ultrium 5-SCSI 

%2
%3
%4
%5
%6
%7
%8
Event Xml:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
  <System>
    <Provider Name="Backup Exec" />
    <EventID Qualifiers="8192">33152</EventID>
    <Level>2</Level>
    <Task>1</Task>
    <Keywords>0x80000000000000</Keywords>
    <TimeCreated SystemTime="2012-10-05T05:05:57.000000000Z" />
    <EventRecordID>39017</EventRecordID>
    <Channel>Application</Channel>
    <Computer>SvrTape.mcc.local</Computer>
    <Security />
  </System>
  <EventData>
    <Data>[4616] 10/05/12 00:05:57 Adamm Mover Error: DeviceIo: 00:00:00:00 - Retry Logic: Failed to enable/disable compression on device: HP       Ultrium 5-SCSI 
</Data>
  </EventData>
</Event>

Log Name:      Application
Source:        Backup Exec
Date:          10/5/2012 12:05:57 AM
Event ID:      33152
Task Category: (1)
Level:         Error
Keywords:      Classic
User:          N/A
Computer:      SvrTape.mcc.local
Description:
[4616] 10/05/12 00:05:57 Adamm Mover Error: DeviceIo: 05:00:00:00 - Refresh handle on "\\.\Tape1", SCSI cmd 1a, new handle 944, error 0

%2
%3
%4
%5
%6
%7
%8
Event Xml:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
  <System>
    <Provider Name="Backup Exec" />
    <EventID Qualifiers="8192">33152</EventID>
    <Level>2</Level>
    <Task>1</Task>
    <Keywords>0x80000000000000</Keywords>
    <TimeCreated SystemTime="2012-10-05T05:05:57.000000000Z" />
    <EventRecordID>39016</EventRecordID>
    <Channel>Application</Channel>
    <Computer>SvrTape.mcc.local</Computer>
    <Security />
  </System>
  <EventData>
    <Data>[4616] 10/05/12 00:05:57 Adamm Mover Error: DeviceIo: 05:00:00:00 - Refresh handle on "\\.\Tape1", SCSI cmd 1a, new handle 944, error 0
</Data>
  </EventData>
</Event>

Log Name:      Application
Source:        Backup Exec
Date:          10/5/2012 12:05:57 AM
Event ID:      33152
Task Category: (1)
Level:         Error
Keywords:      Classic
User:          N/A
Computer:      SvrTape.mcc.local
Description:
[4616] 10/05/12 00:05:57 Adamm Mover Error: DeviceIo: 00:00:00:00 - Retry Logic: 2 TURs Failed on device: HP       Ultrium 5-SCSI 

%2
%3
%4
%5
%6
%7
%8
Event Xml:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
  <System>
    <Provider Name="Backup Exec" />
    <EventID Qualifiers="8192">33152</EventID>
    <Level>2</Level>
    <Task>1</Task>
    <Keywords>0x80000000000000</Keywords>
    <TimeCreated SystemTime="2012-10-05T05:05:57.000000000Z" />
    <EventRecordID>39015</EventRecordID>
    <Channel>Application</Channel>
    <Computer>SvrTape.mcc.local</Computer>
    <Security />
  </System>
  <EventData>
    <Data>[4616] 10/05/12 00:05:57 Adamm Mover Error: DeviceIo: 00:00:00:00 - Retry Logic: 2 TURs Failed on device: HP       Ultrium 5-SCSI 
</Data>
  </EventData>
</Event>

Log Name:      Application
Source:        Backup Exec
Date:          10/5/2012 12:05:57 AM
Event ID:      33152
Task Category: (1)
Level:         Error
Keywords:      Classic
User:          N/A
Computer:      SvrTape.mcc.local
Description:
[4616] 10/05/12 00:05:57 Adamm Mover Error: DeviceIo: 05:00:00:00 - Refresh handle on "\\.\Tape1", SCSI cmd 00, new handle 944, error 55

%2
%3
%4
%5
%6
%7
%8
Event Xml:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
  <System>
    <Provider Name="Backup Exec" />
    <EventID Qualifiers="8192">33152</EventID>
    <Level>2</Level>
    <Task>1</Task>
    <Keywords>0x80000000000000</Keywords>
    <TimeCreated SystemTime="2012-10-05T05:05:57.000000000Z" />
    <EventRecordID>39014</EventRecordID>
    <Channel>Application</Channel>
    <Computer>SvrTape.mcc.local</Computer>
    <Security />
  </System>
  <EventData>
    <Data>[4616] 10/05/12 00:05:57 Adamm Mover Error: DeviceIo: 05:00:00:00 - Refresh handle on "\\.\Tape1", SCSI cmd 00, new handle 944, error 55
</Data>
  </EventData>
</Event>

Log Name:      Application
Source:        Backup Exec
Date:          10/5/2012 12:05:57 AM
Event ID:      33152
Task Category: (1)
Level:         Error
Keywords:      Classic
User:          N/A
Computer:      SvrTape.mcc.local
Description:
[4616] 10/05/12 00:05:57 Adamm Mover Error: DeviceIo: 05:00:00:00 - Refresh handle on "\\.\Tape1", SCSI cmd 00, new handle 944, error 0

%2
%3
%4
%5
%6
%7
%8
Event Xml:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
  <System>
    <Provider Name="Backup Exec" />
    <EventID Qualifiers="8192">33152</EventID>
    <Level>2</Level>
    <Task>1</Task>
    <Keywords>0x80000000000000</Keywords>
    <TimeCreated SystemTime="2012-10-05T05:05:57.000000000Z" />
    <EventRecordID>39013</EventRecordID>
    <Channel>Application</Channel>
    <Computer>SvrTape.mcc.local</Computer>
    <Security />
  </System>
  <EventData>
    <Data>[4616] 10/05/12 00:05:57 Adamm Mover Error: DeviceIo: 05:00:00:00 - Refresh handle on "\\.\Tape1", SCSI cmd 00, new handle 944, error 0
</Data>
  </EventData>
</Event>

Log Name:      Application
Source:        Backup Exec
Date:          10/5/2012 12:05:57 AM
Event ID:      33152
Task Category: (1)
Level:         Error
Keywords:      Classic
User:          N/A
Computer:      SvrTape.mcc.local
Description:
[4616] 10/05/12 00:05:57 Adamm Mover Error: DeviceIo: 00:00:00:00 - Retry Logic: Retry logic was engaged on device: HP       Ultrium 5-SCSI 

%2
%3
%4
%5
%6
%7
%8
Event Xml:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
  <System>
    <Provider Name="Backup Exec" />
    <EventID Qualifiers="8192">33152</EventID>
    <Level>2</Level>
    <Task>1</Task>
    <Keywords>0x80000000000000</Keywords>
    <TimeCreated SystemTime="2012-10-05T05:05:57.000000000Z" />
    <EventRecordID>39012</EventRecordID>
    <Channel>Application</Channel>
    <Computer>SvrTape.mcc.local</Computer>
    <Security />
  </System>
  <EventData>
    <Data>[4616] 10/05/12 00:05:57 Adamm Mover Error: DeviceIo: 00:00:00:00 - Retry Logic: Retry logic was engaged on device: HP       Ultrium 5-SCSI 
</Data>
  </EventData>
</Event>

Julie Barnes's picture

They had me switch the tape drives from user-mode drivers to kernel-mode drivers through a registry hack. The backup has run successfully for 3 days now.

Jaydeep S's picture

Nice to know that you have working backups now.

Julie Barnes's picture

Addam attached in case anyone out there can help.

AttachmentSize
adamm2.txt 494.79 KB
Jaydeep S's picture

Look at this time stamp and search the Windows Event viewer. Also are you using a RAID controller to connect a Tape Drive. Also verify the cables for any damage and confirm that they are secure.

[5108] 10/18/12 04:59:21.273 DeviceIo: 05:00:00:00 - Device error 55 on "\\.\Tape2", SCSI cmd 34, 1 total errors
[3560] 10/18/12 04:59:27.756 PvlDrive::DisableAccess() - ReserveDevice failed, offline device
       Drive = 1026 "HP 0001"
       ERROR = 0x0000001F (ERROR_GEN_FAILURE)

[3560] 10/18/12 04:59:27.783 PvlDrive::UpdateOnlineState()
       Drive = 1026 "HP 0001"
       ERROR = The device is offline!

[3560] 10/18/12 04:59:27.783 Begin dump of device's SCSI history

Julie Barnes's picture

The drives are attached via a Q-Logic HBA. The only errors are coming from Backup Exec.

Log Name:      Application
Source:        Backup Exec
Date:          10/18/2012 4:59:33 AM
Event ID:      58053
Task Category: None
Level:         Error
Keywords:      Classic
User:          N/A
Computer:      SvrTape.mcc.local
Description:
Backup Exec Alert: Device Error
(Server: "SVRTAPE") The drive hardware is offline.

Please confirm that the drive hardware is powered on and properly cabled.

 For more information, click the following link:
http://eventlookup.veritas.com/eventlookup/EventLo...
Event Xml:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
  <System>
    <Provider Name="Backup Exec" />
    <EventID Qualifiers="8192">58053</EventID>
    <Level>2</Level>
    <Task>0</Task>
    <Keywords>0x80000000000000</Keywords>
    <TimeCreated SystemTime="2012-10-18T09:59:33.000000000Z" />
    <EventRecordID>40777</EventRecordID>
    <Channel>Application</Channel>
    <Computer>SvrTape.mcc.local</Computer>
    <Security />
  </System>
  <EventData>
    <Data>Backup Exec Alert: Device Error
(Server: "SVRTAPE") The drive hardware is offline.

Please confirm that the drive hardware is powered on and properly cabled.</Data>
  </EventData>
</Event>

Log Name:      Application
Source:        Backup Exec
Date:          10/18/2012 4:59:28 AM
Event ID:      34113
Task Category: None
Level:         Error
Keywords:      Classic
User:          N/A
Computer:      SvrTape.mcc.local
Description:
Backup Exec Alert: Job Failed
(Server: "SVRTAPE") (Job: "Utility Full Backup Policy") Utility Full Backup Policy -- The job failed with the following error: The device timed out.
 

 For more information, click the following link:
http://eventlookup.veritas.com/eventlookup/EventLo...
Event Xml:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
  <System>
    <Provider Name="Backup Exec" />
    <EventID Qualifiers="8192">34113</EventID>
    <Level>2</Level>
    <Task>0</Task>
    <Keywords>0x80000000000000</Keywords>
    <TimeCreated SystemTime="2012-10-18T09:59:28.000000000Z" />
    <EventRecordID>40776</EventRecordID>
    <Channel>Application</Channel>
    <Computer>SvrTape.mcc.local</Computer>
    <Security />
  </System>
  <EventData>
    <Data>Backup Exec Alert: Job Failed
(Server: "SVRTAPE") (Job: "Utility Full Backup Policy") Utility Full Backup Policy -- The job failed with the following error: The device timed out.
</Data>
  </EventData>
</Event>

Log Name:      Application
Source:        Backup Exec
Date:          10/18/2012 4:59:27 AM
Event ID:      58053
Task Category: None
Level:         Error
Keywords:      Classic
User:          N/A
Computer:      SvrTape.mcc.local
Description:
Backup Exec Alert: Device Error
(Server: "SVRTAPE") The drive hardware is offline.

Please confirm that the drive hardware is powered on and properly cabled.

 For more information, click the following link:
http://eventlookup.veritas.com/eventlookup/EventLo...
Event Xml:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
  <System>
    <Provider Name="Backup Exec" />
    <EventID Qualifiers="8192">58053</EventID>
    <Level>2</Level>
    <Task>0</Task>
    <Keywords>0x80000000000000</Keywords>
    <TimeCreated SystemTime="2012-10-18T09:59:27.000000000Z" />
    <EventRecordID>40775</EventRecordID>
    <Channel>Application</Channel>
    <Computer>SvrTape.mcc.local</Computer>
    <Security />
  </System>
  <EventData>
    <Data>Backup Exec Alert: Device Error
(Server: "SVRTAPE") The drive hardware is offline.

Please confirm that the drive hardware is powered on and properly cabled.</Data>
  </EventData>
</Event>

Log Name:      Application
Source:        Backup Exec
Date:          10/18/2012 4:59:21 AM
Event ID:      57665
Task Category: None
Level:         Error
Keywords:      Classic
User:          N/A
Computer:      SvrTape.mcc.local
Description:
Storage device "HP 0001" reported an error on a request to rewind the media.

Error reported:
A device attached to the system is not functioning.
.

 For more information, click the following link:
http://eventlookup.veritas.com/eventlookup/EventLo...
Event Xml:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
  <System>
    <Provider Name="Backup Exec" />
    <EventID Qualifiers="8192">57665</EventID>
    <Level>2</Level>
    <Task>0</Task>
    <Keywords>0x80000000000000</Keywords>
    <TimeCreated SystemTime="2012-10-18T09:59:21.000000000Z" />
    <EventRecordID>40774</EventRecordID>
    <Channel>Application</Channel>
    <Computer>SvrTape.mcc.local</Computer>
    <Security />
  </System>
  <EventData>
    <Data>HP 0001</Data>
    <Data>rewind the media</Data>
    <Data>A device attached to the system is not functioning.
</Data>
    <Binary>1F000000F48400E000000000000000009B030000</Binary>
  </EventData>
</Event>

Log Name:      Application
Source:        Backup Exec
Date:          10/18/2012 4:59:21 AM
Event ID:      33152
Task Category: (1)
Level:         Error
Keywords:      Classic
User:          N/A
Computer:      SvrTape.mcc.local
Description:
[0868] 10/18/12 04:59:21 Adamm Mover Error: DeviceIo: 05:00:00:00 - Refresh handle on "\\.\Tape2", SCSI cmd 1a, new handle 7a0, error 0

%2
%3
%4
%5
%6
%7
%8
Event Xml:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
  <System>
    <Provider Name="Backup Exec" />
    <EventID Qualifiers="8192">33152</EventID>
    <Level>2</Level>
    <Task>1</Task>
    <Keywords>0x80000000000000</Keywords>
    <TimeCreated SystemTime="2012-10-18T09:59:21.000000000Z" />
    <EventRecordID>40773</EventRecordID>
    <Channel>Application</Channel>
    <Computer>SvrTape.mcc.local</Computer>
    <Security />
  </System>
  <EventData>
    <Data>[0868] 10/18/12 04:59:21 Adamm Mover Error: DeviceIo: 05:00:00:00 - Refresh handle on "\\.\Tape2", SCSI cmd 1a, new handle 7a0, error 0
</Data>
  </EventData>
</Event>

Log Name:      Application
Source:        Backup Exec
Date:          10/18/2012 4:59:21 AM
Event ID:      33152
Task Category: (1)
Level:         Error
Keywords:      Classic
User:          N/A
Computer:      SvrTape.mcc.local
Description:
[0868] 10/18/12 04:59:21 Adamm Mover Error: DeviceIo: 05:00:00:00 - Device error 55 on "\\.\Tape2", SCSI cmd 01, 6 total errors

%2
%3
%4
%5
%6
%7
%8
Event Xml:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
  <System>
    <Provider Name="Backup Exec" />
    <EventID Qualifiers="8192">33152</EventID>
    <Level>2</Level>
    <Task>1</Task>
    <Keywords>0x80000000000000</Keywords>
    <TimeCreated SystemTime="2012-10-18T09:59:21.000000000Z" />
    <EventRecordID>40772</EventRecordID>
    <Channel>Application</Channel>
    <Computer>SvrTape.mcc.local</Computer>
    <Security />
  </System>
  <EventData>
    <Data>[0868] 10/18/12 04:59:21 Adamm Mover Error: DeviceIo: 05:00:00:00 - Device error 55 on "\\.\Tape2", SCSI cmd 01, 6 total errors
</Data>
  </EventData>
</Event>

Log Name:      Application
Source:        Backup Exec
Date:          10/18/2012 4:59:21 AM
Event ID:      33152
Task Category: (1)
Level:         Error
Keywords:      Classic
User:          N/A
Computer:      SvrTape.mcc.local
Description:
[0868] 10/18/12 04:59:21 Adamm Mover Error: DeviceIo: 05:00:00:00 - Refresh handle on "\\.\Tape2", SCSI cmd 01, new handle 7a0, error 87

%2
%3
%4
%5
%6
%7
%8
Event Xml:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
  <System>
    <Provider Name="Backup Exec" />
    <EventID Qualifiers="8192">33152</EventID>
    <Level>2</Level>
    <Task>1</Task>
    <Keywords>0x80000000000000</Keywords>
    <TimeCreated SystemTime="2012-10-18T09:59:21.000000000Z" />
    <EventRecordID>40771</EventRecordID>
    <Channel>Application</Channel>
    <Computer>SvrTape.mcc.local</Computer>
    <Security />
  </System>
  <EventData>
    <Data>[0868] 10/18/12 04:59:21 Adamm Mover Error: DeviceIo: 05:00:00:00 - Refresh handle on "\\.\Tape2", SCSI cmd 01, new handle 7a0, error 87
</Data>
  </EventData>
</Event>

Log Name:      Application
Source:        Backup Exec
Date:          10/18/2012 4:59:05 AM
Event ID:      57665
Task Category: None
Level:         Error
Keywords:      Classic
User:          N/A
Computer:      SvrTape.mcc.local
Description:
Storage device "HP 0001" reported an error on a request to write data to media.

Error reported:
This operation returned because the timeout period expired.
.

 For more information, click the following link:
http://eventlookup.veritas.com/eventlookup/EventLo...
Event Xml:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
  <System>
    <Provider Name="Backup Exec" />
    <EventID Qualifiers="8192">57665</EventID>
    <Level>2</Level>
    <Task>0</Task>
    <Keywords>0x80000000000000</Keywords>
    <TimeCreated SystemTime="2012-10-18T09:59:05.000000000Z" />
    <EventRecordID>40770</EventRecordID>
    <Channel>Application</Channel>
    <Computer>SvrTape.mcc.local</Computer>
    <Security />
  </System>
  <EventData>
    <Data>HP 0001</Data>
    <Data>write data to media</Data>
    <Data>This operation returned because the timeout period expired.
</Data>
    <Binary>B4050000F08400E0000001000000000093030000</Binary>
  </EventData>
</Event>

Log Name:      Application
Source:        Backup Exec
Date:          10/18/2012 4:59:05 AM
Event ID:      33152
Task Category: (1)
Level:         Error
Keywords:      Classic
User:          N/A
Computer:      SvrTape.mcc.local
Description:
[3176] 10/18/12 04:59:05 Adamm Mover Error: DeviceIo: 05:00:00:00 - Refresh handle on "\\.\Tape2", SCSI cmd 1a, new handle 950, error 0

%2
%3
%4
%5
%6
%7
%8
Event Xml:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
  <System>
    <Provider Name="Backup Exec" />
    <EventID Qualifiers="8192">33152</EventID>
    <Level>2</Level>
    <Task>1</Task>
    <Keywords>0x80000000000000</Keywords>
    <TimeCreated SystemTime="2012-10-18T09:59:05.000000000Z" />
    <EventRecordID>40769</EventRecordID>
    <Channel>Application</Channel>
    <Computer>SvrTape.mcc.local</Computer>
    <Security />
  </System>
  <EventData>
    <Data>[3176] 10/18/12 04:59:05 Adamm Mover Error: DeviceIo: 05:00:00:00 - Refresh handle on "\\.\Tape2", SCSI cmd 1a, new handle 950, error 0
</Data>
  </EventData>
</Event>

Log Name:      Application
Source:        Backup Exec
Date:          10/18/2012 4:59:05 AM
Event ID:      33152
Task Category: (1)
Level:         Error
Keywords:      Classic
User:          N/A
Computer:      SvrTape.mcc.local
Description:
[3176] 10/18/12 04:59:05 Adamm Mover Error: DeviceIo: 00:00:00:00 - Retry Logic: Failed to enable/disable compression on device: HP       Ultrium 5-SCSI  

%2
%3
%4
%5
%6
%7
%8
Event Xml:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
  <System>
    <Provider Name="Backup Exec" />
    <EventID Qualifiers="8192">33152</EventID>
    <Level>2</Level>
    <Task>1</Task>
    <Keywords>0x80000000000000</Keywords>
    <TimeCreated SystemTime="2012-10-18T09:59:05.000000000Z" />
    <EventRecordID>40768</EventRecordID>
    <Channel>Application</Channel>
    <Computer>SvrTape.mcc.local</Computer>
    <Security />
  </System>
  <EventData>
    <Data>[3176] 10/18/12 04:59:05 Adamm Mover Error: DeviceIo: 00:00:00:00 - Retry Logic: Failed to enable/disable compression on device: HP       Ultrium 5-SCSI  
</Data>
  </EventData>
</Event>

Log Name:      Application
Source:        Backup Exec
Date:          10/18/2012 4:59:05 AM
Event ID:      33152
Task Category: (1)
Level:         Error
Keywords:      Classic
User:          N/A
Computer:      SvrTape.mcc.local
Description:
[3176] 10/18/12 04:59:05 Adamm Mover Error: DeviceIo: 05:00:00:00 - Refresh handle on "\\.\Tape2", SCSI cmd 1a, new handle 950, error 0

%2
%3
%4
%5
%6
%7
%8
Event Xml:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
  <System>
    <Provider Name="Backup Exec" />
    <EventID Qualifiers="8192">33152</EventID>
    <Level>2</Level>
    <Task>1</Task>
    <Keywords>0x80000000000000</Keywords>
    <TimeCreated SystemTime="2012-10-18T09:59:05.000000000Z" />
    <EventRecordID>40767</EventRecordID>
    <Channel>Application</Channel>
    <Computer>SvrTape.mcc.local</Computer>
    <Security />
  </System>
  <EventData>
    <Data>[3176] 10/18/12 04:59:05 Adamm Mover Error: DeviceIo: 05:00:00:00 - Refresh handle on "\\.\Tape2", SCSI cmd 1a, new handle 950, error 0
</Data>
  </EventData>
</Event>

Log Name:      Application
Source:        Backup Exec
Date:          10/18/2012 4:59:05 AM
Event ID:      33152
Task Category: (1)
Level:         Error
Keywords:      Classic
User:          N/A
Computer:      SvrTape.mcc.local
Description:
[3176] 10/18/12 04:59:05 Adamm Mover Error: DeviceIo: 00:00:00:00 - Retry Logic: 2 TURs Failed on device: HP       Ultrium 5-SCSI  

%2
%3
%4
%5
%6
%7
%8
Event Xml:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
  <System>
    <Provider Name="Backup Exec" />
    <EventID Qualifiers="8192">33152</EventID>
    <Level>2</Level>
    <Task>1</Task>
    <Keywords>0x80000000000000</Keywords>
    <TimeCreated SystemTime="2012-10-18T09:59:05.000000000Z" />
    <EventRecordID>40766</EventRecordID>
    <Channel>Application</Channel>
    <Computer>SvrTape.mcc.local</Computer>
    <Security />
  </System>
  <EventData>
    <Data>[3176] 10/18/12 04:59:05 Adamm Mover Error: DeviceIo: 00:00:00:00 - Retry Logic: 2 TURs Failed on device: HP       Ultrium 5-SCSI  
</Data>
  </EventData>
</Event>

Log Name:      Application
Source:        Backup Exec
Date:          10/18/2012 4:59:05 AM
Event ID:      33152
Task Category: (1)
Level:         Error
Keywords:      Classic
User:          N/A
Computer:      SvrTape.mcc.local
Description:
[3176] 10/18/12 04:59:05 Adamm Mover Error: DeviceIo: 05:00:00:00 - Refresh handle on "\\.\Tape2", SCSI cmd 00, new handle 950, error 55

%2
%3
%4
%5
%6
%7
%8
Event Xml:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
  <System>
    <Provider Name="Backup Exec" />
    <EventID Qualifiers="8192">33152</EventID>
    <Level>2</Level>
    <Task>1</Task>
    <Keywords>0x80000000000000</Keywords>
    <TimeCreated SystemTime="2012-10-18T09:59:05.000000000Z" />
    <EventRecordID>40765</EventRecordID>
    <Channel>Application</Channel>
    <Computer>SvrTape.mcc.local</Computer>
    <Security />
  </System>
  <EventData>
    <Data>[3176] 10/18/12 04:59:05 Adamm Mover Error: DeviceIo: 05:00:00:00 - Refresh handle on "\\.\Tape2", SCSI cmd 00, new handle 950, error 55
</Data>
  </EventData>
</Event>

Log Name:      Application
Source:        Backup Exec
Date:          10/18/2012 4:59:05 AM
Event ID:      33152
Task Category: (1)
Level:         Error
Keywords:      Classic
User:          N/A
Computer:      SvrTape.mcc.local
Description:
[3176] 10/18/12 04:59:05 Adamm Mover Error: DeviceIo: 05:00:00:00 - Refresh handle on "\\.\Tape2", SCSI cmd 00, new handle 950, error 0

%2
%3
%4
%5
%6
%7
%8
Event Xml:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
  <System>
    <Provider Name="Backup Exec" />
    <EventID Qualifiers="8192">33152</EventID>
    <Level>2</Level>
    <Task>1</Task>
    <Keywords>0x80000000000000</Keywords>
    <TimeCreated SystemTime="2012-10-18T09:59:05.000000000Z" />
    <EventRecordID>40764</EventRecordID>
    <Channel>Application</Channel>
    <Computer>SvrTape.mcc.local</Computer>
    <Security />
  </System>
  <EventData>
    <Data>[3176] 10/18/12 04:59:05 Adamm Mover Error: DeviceIo: 05:00:00:00 - Refresh handle on "\\.\Tape2", SCSI cmd 00, new handle 950, error 0
</Data>
  </EventData>
</Event>

Log Name:      Application
Source:        Backup Exec
Date:          10/18/2012 4:59:05 AM
Event ID:      33152
Task Category: (1)
Level:         Error
Keywords:      Classic
User:          N/A
Computer:      SvrTape.mcc.local
Description:
[3176] 10/18/12 04:59:05 Adamm Mover Error: DeviceIo: 00:00:00:00 - Retry Logic: Retry logic was engaged on device: HP       Ultrium 5-SCSI  

%2
%3
%4
%5
%6
%7
%8
Event Xml:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
  <System>
    <Provider Name="Backup Exec" />
    <EventID Qualifiers="8192">33152</EventID>
    <Level>2</Level>
    <Task>1</Task>
    <Keywords>0x80000000000000</Keywords>
    <TimeCreated SystemTime="2012-10-18T09:59:05.000000000Z" />
    <EventRecordID>40763</EventRecordID>
    <Channel>Application</Channel>
    <Computer>SvrTape.mcc.local</Computer>
    <Security />
  </System>
  <EventData>
    <Data>[3176] 10/18/12 04:59:05 Adamm Mover Error: DeviceIo: 00:00:00:00 - Retry Logic: Retry logic was engaged on device: HP       Ultrium 5-SCSI  
</Data>
  </EventData>
</Event>

Log Name:      Application
Source:        Backup Exec
Date:          10/18/2012 4:54:05 AM
Event ID:      33152
Task Category: (1)
Level:         Error
Keywords:      Classic
User:          N/A
Computer:      SvrTape.mcc.local
Description:
[3176] 10/18/12 04:54:05 Adamm Mover Error: DeviceIo: 05:00:00:00 - Device error 1167 on "\\.\Tape2", SCSI cmd 0a, 1 total errors

%2
%3
%4
%5
%6
%7
%8
Event Xml:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
  <System>
    <Provider Name="Backup Exec" />
    <EventID Qualifiers="8192">33152</EventID>
    <Level>2</Level>
    <Task>1</Task>
    <Keywords>0x80000000000000</Keywords>
    <TimeCreated SystemTime="2012-10-18T09:54:05.000000000Z" />
    <EventRecordID>40762</EventRecordID>
    <Channel>Application</Channel>
    <Computer>SvrTape.mcc.local</Computer>
    <Security />
  </System>
  <EventData>
    <Data>[3176] 10/18/12 04:54:05 Adamm Mover Error: DeviceIo: 05:00:00:00 - Device error 1167 on "\\.\Tape2", SCSI cmd 0a, 1 total errors
</Data>
  </EventData>
</Event>

 

 

Larry Fine's picture

Still looks like a hardware issue to me.  I would focus on FC errors.  Do you have the QLogic management software installed?  If so, does that show errors?  Might yuou be able to contact QLogic for assistance? 

One thing that is pretty unusual about your environment is the lack of an FC switch.  So possibly your FC HBA is seeing events or glitches from the library end that are normally masked by an FC swtich?

I might also try forcing the HBA to the speed of your tape drives (ie 4GB or 8GB?, rather than the "auto" setting) and try forcing the HBA into "loop" mode.

If you find this is a solution for the thread, please mark it as such.

Julie Barnes's picture

I'd love to have some FC errors to focus on. No related errors in the event log or from the SANSurfer Manager software. I will try to force the speed and mode as you suggest. Thanks!

Julie Barnes's picture

Failed again last night. Errors were a bit different. Sending them to Symantec as well. Attaching here in case anyone sees something and has any thoughts.

AttachmentSize
adamm3.txt 623.23 KB
ddonley_lvs's picture

We have this nearly identical issue, and we have not found any resolution.  only difference is we are using a single drive HP MSL2024 and a 4 GB HP1142SR HBA.

 

Was this ever resolved for you?