Bacula-users

Re: [Bacula-users] Unable to position to end of data on device

2013-09-24 08:06:11
Subject: Re: [Bacula-users] Unable to position to end of data on device
From: Deepak <deepak AT palincorporates DOT com>
To: Radosław Korzeniewski <radoslaw AT korzeniewski DOT net>
Date: Tue, 24 Sep 2013 17:32:36 +0530
Quoting Radosław Korzeniewski <radoslaw AT korzeniewski DOT net>:

> Hello,
>
> 2013/9/24 Deepak <deepak AT palincorporates DOT com>
>
>> Hi,
>>
>> While setting up recycled volumes I was facing some issues which I have
>> resolved with help of you guys.
>>
>> I have three volumes. I have written to 1st and 2nd volueme these are
>> marked with full status. now bacula just took a backup of 200 GB on 3rd
>> volume. After that backup I executed a restore job and restored a few files
>> successfully. Post this restoration I scheduled again a job. Which should
>> write on 3rd volume as per my understanding and it has done the same. but
>> while locating end of data it gave me some error and marked volume status
>> as error and recycled 1st volume.
>>
>> for Recycling part it is doing perfectly. But while locating end of data
>> it is not doing good. I have faced this issue second time in same scenario.
>>
>> Error i received in log files are :
>>
>> 24-Sep 12:26 backup-dir JobId 30: Start Backup JobId 30,
>> Job=CONFIG.2013-09-24_12.26.**00_54
>> 24-Sep 12:26 backup-dir JobId 30: Using Device "Tape-0"
>> 24-Sep 12:27 backup-sd JobId 30: Volume "A00041L4" previously written,
>> moving to end
>> of data.
>> 24-Sep 12:46 backup-sd JobId 30: Error: Unable to position to end of data
>> on device
>> "Tape-0" (/dev/lin_tape/IBMtape0): ERR=dev.c:1208 read error on "Tape-0"
>> (/dev/lin_tape/IBMtape0). ERR=Input/output error.
>>
>> 24-Sep 12:46 backup-sd JobId 30: Marking Volume "A00041L4" in Error in
>> Catalog.
>> 24-Sep 12:46 backup-sd JobId 30: 3307 Issuing autochanger "unload slot 2,
>> drive 0"
>> command.
>> 24-Sep 12:48 backup-dir JobId 30: There are no more Jobs associated with
>> Volume
>> "A00042L4". Marking it purged.
>> 24-Sep 12:48 backup-dir JobId 30: All records pruned from Volume
>> "A00042L4"; marking
>> it "Purged"
>> 24-Sep 12:48 backup-dir JobId 30: Recycled volume "A00042L4"
>> 24-Sep 12:48 backup-sd JobId 30: 3304 Issuing autochanger "load slot 3,
>> drive 0"
>> command.
>> 24-Sep 12:48 backup-sd JobId 30: 3305 Autochanger "load slot 3, drive 0",
>> status is OK.
>> 24-Sep 12:48 backup-sd JobId 30: Recycled volume "A00042L4" on device
>> "Tape-0"
>>
>>
> The cause of this problem is lin_tape driver. I had the same issue on a
> number of implementations. Did you checked your tape drive setup with btape
> utility before first backup? What was the result of the test?
>
>
>>
>> It has recycled volume A00042L4 but marked as error on A00041L4.for more
>> information I am using lin_tape driver for tape drive communication with
>> IBM tape.
>>
>> Please tell me how to resolve this issue.
>>
>>
> Change 'lin_tape' to 'st' driver. It solve all your issues.
>
> best regards
> --
> Radosław Korzeniewski
> radoslaw AT korzeniewski DOT net
>


Okey,

I have removed lin_tape driver permanently loaded st driver and  
rebooted system . now it is showing st devices as my st devices.

[root@bacula ~]# lsscsi | grep tape
[1:0:2:0]    tape    IBM      ULT3580-TD4      B710  /dev/st0
[1:0:3:0]    tape    IBM      ULT3580-TD4      B710  /dev/st1
[1:0:4:0]    tape    IBM      ULT3580-TD4      B710  /dev/st5
[1:0:5:0]    tape    IBM      ULT3580-TD4      B710  /dev/st7
[2:0:4:0]    tape    IBM      ULT3580-TD4      B710  /dev/st2
[2:0:5:0]    tape    IBM      ULT3580-TD4      B710  /dev/st3
[2:0:6:0]    tape    IBM      ULT3580-TD4      B710  /dev/st4
[2:0:7:0]    tape    IBM      ULT3580-TD4      B710  /dev/st6


I have modified my devices configuration in bacula-sd file too.

device configuration is below for autochanger device:

#######################################################
Autochanger {
   Name = Autochanger
   Device = Tape-0, Tape-1, Tape-2, Tape-3
   Changer Command = "/usr/lib64/bacula/mtx-changer %c %o %S %a %d"
   Changer Device = /dev/changer
}

Device {
   Name = Tape-0
   Drive Index = 0
   Media Type = LTO-4
#  Archive Device = /dev/lin_tape/IBMtape0
   Archive Device = /dev/st0
   AutomaticMount = yes;               # when device opened, read it
   AlwaysOpen = yes;
   LabelMedia = yes;
   RemovableMedia = yes;
   RandomAccess = no;
#  Maximum File Size = 5GB
     Hardware End of Medium = No
     Fast Forward Space File = No
## Changer Command = "/usr/lib64/bacula/mtx-changer %c %o %S %a %d"
## Changer Device = /dev/sg0
   AutoChanger = yes
#  # Enable the Alert command only if you have the mtx package loaded
  Alert Command = "sh -c 'tapeinfo -f %c |grep TapeAlert|cat'"
## If you have smartctl, enable this, it has more info than tapeinfo
## Alert Command = "sh -c 'smartctl -H -l error %c'"
   TWO EOF = yes
   MaximumOpenWait = 600
}


Device {
   Name = Tape-1
   Drive Index = 1
   Media Type = LTO-4
#  Archive Device = /dev/lin_tape/IBMtape1
   Archive Device = /dev/st1
   AutomaticMount = yes;               # when device opened, read it
   AlwaysOpen = yes;
   LabelMedia = yes;
   RemovableMedia = yes;
   RandomAccess = no;
#  Maximum File Size = 5GB
## Changer Command = "/usr/lib64/bacula/mtx-changer %c %o %S %a %d"
     Hardware End of Medium = No
     Fast Forward Space File = No
## Changer Device = /dev/sg0
## AutoChanger = yes
   AutoChanger = yes
#  # Enable the Alert command only if you have the mtx package loaded
  Alert Command = "sh -c 'tapeinfo -f %c |grep TapeAlert|cat'"
## If you have smartctl, enable this, it has more info than tapeinfo
## Alert Command = "sh -c 'smartctl -H -l error %c'"
   TWO EOF = yes
   MaximumOpenWait = 600
}

Device {
   Name = Tape-2
   Drive Index = 2
   Media Type = LTO-4
#  Archive Device = /dev/lin_tape/IBMtape2
   Archive Device = /dev/st2
   AutomaticMount = yes;               # when device opened, read it
   AlwaysOpen = yes;
   LabelMedia = yes;
   RemovableMedia = yes;
   RandomAccess = no;
#  Maximum File Size = 5GB
     Hardware End of Medium = No
     Fast Forward Space File = No
## Changer Command = "/usr/lib64/bacula/mtx-changer %c %o %S %a %d"
## Changer Device = /dev/sg0
## AutoChanger = yes
   AutoChanger = yes
#  # Enable the Alert command only if you have the mtx package loaded
  Alert Command = "sh -c 'tapeinfo -f %c |grep TapeAlert|cat'"
## If you have smartctl, enable this, it has more info than tapeinfo
## Alert Command = "sh -c 'smartctl -H -l error %c'"
   TWO EOF = yes
   MaximumOpenWait = 600
}

Device {
   Name = Tape-3
   Drive Index = 3
   Media Type = LTO-4
#  Archive Device = /dev/lin_tape/IBMtape3
   Archive Device = /dev/st3
   AutomaticMount = yes;               # when device opened, read it
   AlwaysOpen = yes;
   LabelMedia = yes;
   RemovableMedia = yes;
   RandomAccess = no;
#  Maximum File Size = 5GB
     Hardware End of Medium = No
     Fast Forward Space File = No
## Changer Command = "/usr/lib64/bacula/mtx-changer %c %o %S %a %d"
## Changer Device = /dev/sg0
## AutoChanger = yes
   AutoChanger = yes
#  # Enable the Alert command only if you have the mtx package loaded
  Alert Command = "sh -c 'tapeinfo -f %c |grep TapeAlert|cat'"
## If you have smartctl, enable this, it has more info than tapeinfo
## Alert Command = "sh -c 'smartctl -H -l error %c'"
   TWO EOF = yes
   MaximumOpenWait = 600
}


################################################


I have restarted bacula and started some jobs but I am getting below error:

################################################

2013-09-24 16:33:02backup-dir JobId 37: Start Backup JobId 37,  
Job=CONFIGBackup.2013-09-24_16.33.00_05
2013-09-24 16:33:02backup-dir JobId 37: Using Device "Tape-0"
2013-09-24 16:33:03backup-sd JobId 37: 3304 Issuing autochanger "load  
slot 3, drive 0" command.
2013-09-24 16:38:04backup-sd JobId 37: Fatal error: 3992 Bad  
autochanger "load slot 3, drive 0": ERR=Child died from signal 15:  
Termination.
Results=Program killed by Bacula (timeout)

2013-09-24 16:38:04backup-fd JobId 37: Fatal error: job.c:2395 Bad  
response to Append Data command. Wanted 3000 OK data
, got 3903 Error append data

2013-09-24 16:38:04backup-dir JobId 37: Error: Bacula backup-dir  
5.2.12 (12Sep12):
   Build OS:               x86_64-unknown-linux-gnu redhat Enterprise release
   JobId:                  37
   Job:                    CONFIGBackup.2013-09-24_16.33.00_05
   Backup Level:           Full
   Client:                 "backup-fd" 5.2.12 (12Sep12)  
x86_64-unknown-linux-gnu,redhat,Enterprise release
   FileSet:                "CONFIG" 2013-09-23 15:30:25
   Pool:                   "BACKUP" (From Job resource)
   Catalog:                "DefaultCatalog" (From Client resource)
   Storage:                "Autochanger" (From Pool resource)
   Scheduled time:         24-Sep-2013 16:33:00
   Start time:             24-Sep-2013 16:33:02
   End time:               24-Sep-2013 16:38:04
   Elapsed time:           5 mins 2 secs
   Priority:               10
   FD Files Written:       0
   SD Files Written:       0
   FD Bytes Written:       0 (0 B)
   SD Bytes Written:       0 (0 B)
   Rate:                   0.0 KB/s
   Software Compression:   None
   VSS:                    no
   Encryption:             no
   Accurate:               yes
   Volume name(s):
   Volume Session Id:      1
   Volume Session Time:    1380020355
   Last Volume Bytes:      547,871,450,112 (547.8 GB)
   Non-fatal FD errors:    1
   SD Errors:              1
   FD termination status:  Error
   SD termination status:  Error
   Termination:            *** Backup Error ***
24-Sep 17:00 backup-dir JobId 38: Start Backup JobId 38,  
Job=CONFIGBackup.2013-09-24_17.00.00_11
24-Sep 17:00 backup-dir JobId 38: Using Device "Tape-0"
24-Sep 17:03 backup-sd JobId 38: Warning: Volume "A00042L4" wanted on  
"Tape-0" (/dev/st0) is in use by device "Tape-0" (/dev/st0)
24-Sep 17:03 backup-sd JobId 38: Warning: mount.c:217 Open device  
"Tape-0" (/dev/st0) Volume "A00042L4" failed: ERR=dev.c:513 Unable to  
open device "Tape-0" (/dev/st0): ERR=No medium found

24-Sep 17:18 backup-sd JobId 38: Warning: mount.c:217 Open device  
"Tape-0" (/dev/st0) Volume "A00042L4" failed: ERR=dev.c:513 Unable to  
open device "Tape-0" (/dev/st0): ERR=No medium found

24-Sep 17:20 backup-dir JobId 39: Fatal error: JobId 38 already  
running. Duplicate job not allowed.
24-Sep 17:20 backup-dir JobId 39: Bacula backup-dir 5.2.12 (12Sep12):
   Build OS:               x86_64-unknown-linux-gnu redhat Enterprise release
   JobId:                  39
   Job:                    CONFIGBackup.2013-09-24_17.20.00_13
   Backup Level:           Full
   Client:                 "backup-fd" 5.2.12 (12Sep12)  
x86_64-unknown-linux-gnu,redhat,Enterprise release
   FileSet:                "CONFIG" 2013-09-23 15:30:25
   Pool:                   "BACKUP" (From Job resource)
   Catalog:                "DefaultCatalog" (From Client resource)
   Storage:                "Autochanger" (From Pool resource)
   Scheduled time:         24-Sep-2013 17:20:00
   Start time:             24-Sep-2013 17:20:00
   End time:               24-Sep-2013 17:20:00
   Elapsed time:           0 secs
   Priority:               10
   FD Files Written:       0
   SD Files Written:       0
   FD Bytes Written:       0 (0 B)
   SD Bytes Written:       0 (0 B)
   Rate:                   0.0 KB/s
   Software Compression:   None
   VSS:                    no
   Encryption:             no
   Accurate:               yes
   Volume name(s):
   Volume Session Id:      0
   Volume Session Time:    0
   Last Volume Bytes:      0 (0 B)
   Non-fatal FD errors:    1
   SD Errors:              0
   FD termination status:
   SD termination status:
   Termination:            Backup Canceled


with mtx and mt tape drive is working fine manually I have manually  
tested mtx-changer command too.

Please tell me why this is happening...???


Regards,


------------------------------------------------------------------------------
October Webinars: Code for Performance
Free Intel webinars can help you accelerate application performance.
Explore tips for MPI, OpenMP, advanced profiling, and more. Get the most from 
the latest Intel processors and coprocessors. See abstracts and register >
http://pubads.g.doubleclick.net/gampad/clk?id=60133471&iu=/4140/ostg.clktrk
_______________________________________________
Bacula-users mailing list
Bacula-users AT lists.sourceforge DOT net
https://lists.sourceforge.net/lists/listinfo/bacula-users