Am 26.03.20 um 16:48 schrieb Martin Simmons:
> Looks like a bug to me, but a possible workaround is to limit the size of your
Me2. If the short block is correctly identified at writing it should be repeated
or should be finished on the new disk. In both cases the data on the disks 
should
be ok. Only the reading has maybe than a problem.
But if the tail data of the short block is lost, the data on the disks should be
incomplete

> disk volumes (see Maximum Volume Bytes) to avoid filling the disks during the
> backup.  This will avoid the "short block" when you migrate.
Yes, but I have different size of disks (320 GB to 2 TB) and cannot change each
time the max size.

> BTW, can you post the log from jobid 47704 as well?

Migration job:

21-Mar 20:23 backup-dir JobId 47703: The following 1 JobId was chosen to be 
migrated: 46802
21-Mar 20:23 backup-dir JobId 47703: Migration using JobId=46802 
Job=nihilnihil_home.2020-01-05_23.50.01_29
21-Mar 20:23 backup-dir JobId 47703: Start Migration JobId 47703, 
Job=Migrate2FileTmpVol.2020-03-21_20.23.31_48
21-Mar 20:23 backup-dir JobId 47703: Using Device "HPUltrium4-2" to read.
21-Mar 20:23 backup-sd JobId 47703: 3307 Issuing autochanger "unload Volume 
LTO40027, Slot 5, Drive 1" command.
21-Mar 20:27 backup-sd JobId 47703: 3304 Issuing autochanger "load Volume 
LTO40026, Slot 9, Drive 1" command.
21-Mar 20:28 backup-sd JobId 47703: 3305 Autochanger "load Volume LTO40026, 
Slot 9, Drive 1", status is OK.
21-Mar 20:28 backup-sd JobId 47703: Ready to read from volume "LTO40026" on 
Tape device "HP Ultrium 4-2" (/dev/tape/by-id/scsi-HU19145705-nst).
21-Mar 20:28 backup-sd JobId 47703: Forward spacing Volume "LTO40026" to 
addr=19:1457
22-Mar 00:36 backup-sd JobId 47703: End of Volume "LTO40026" at addr=628:5763 
on device "HP Ultrium 4-2" (/dev/tape/by-id/scsi-HU19145705-nst).
22-Mar 00:37 backup-sd JobId 47703: 3307 Issuing autochanger "unload Volume 
LTO40026, Slot 9, Drive 1" command.
22-Mar 00:38 backup-sd JobId 47703: 3304 Issuing autochanger "load Volume 
LTO40025, Slot 17, Drive 1" command.
22-Mar 00:40 backup-sd JobId 47703: 3305 Autochanger "load Volume LTO40025, 
Slot 17, Drive 1", status is OK.
22-Mar 00:40 backup-sd JobId 47703: Ready to read from volume "LTO40025" on 
Tape device "HP Ultrium 4-2" (/dev/tape/by-id/scsi-HU19145705-nst).
22-Mar 00:40 backup-sd JobId 47703: Forward spacing Volume "LTO40025" to 
addr=1:11779
22-Mar 05:33 backup-sd JobId 47703: End of Volume "LTO40025" at addr=0:0 on 
device "HP Ultrium 4-2" (/dev/tape/by-id/scsi-HU19145705-nst).
22-Mar 05:33 backup-sd JobId 47703: 3307 Issuing autochanger "unload Volume 
LTO40025, Slot 17, Drive 1" command.
22-Mar 05:34 backup-sd JobId 47703: 3304 Issuing autochanger "load Volume 
LTO40027, Slot 5, Drive 1" command.
22-Mar 05:36 backup-sd JobId 47703: 3305 Autochanger "load Volume LTO40027, 
Slot 5, Drive 1", status is OK.
22-Mar 05:36 backup-sd JobId 47703: Ready to read from volume "LTO40027" on 
Tape device "HP Ultrium 4-2" (/dev/tape/by-id/scsi-HU19145705-nst).
22-Mar 05:36 backup-sd JobId 47703: Forward spacing Volume "LTO40027" to 
addr=0:1
22-Mar 10:32 backup-sd JobId 47703: End of Volume "LTO40027" at addr=298:0 on 
device "HP Ultrium 4-2" (/dev/tape/by-id/scsi-HU19145705-nst).
22-Mar 10:32 backup-sd JobId 47703: Elapsed time=14:03:10, Transfer rate=21.39 
M Bytes/second
22-Mar 12:21 backup-dir JobId 47703: Bacula backup-dir 9.4.2 (04Feb19):
  Build OS:               x86_64-pc-linux-gnu debian buster/sid
  Prev Backup JobId:      46802
  Prev Backup Job:        nihilnihil_home.2020-01-05_23.50.01_29
  New Backup JobId:       47704
  Current JobId:          47703
  Current Job:            Migrate2FileTmpVol.2020-03-21_20.23.31_48
  Backup Level:           Full
  Client:                 backup-fd
  FileSet:                "Full Set" 2017-10-09 08:53:50
  Read Pool:              "Monthly" (From Job resource)
  Read Storage:           "FibreCAT TX48 S2" (From Pool resource)
  Write Pool:             "Migrate" (From Job Pool's NextPool resource)
  Write Storage:          "Disk2" (From Job Pool's NextPool resource)
  Catalog:                "MyCatalog" (From Client resource)
  Start time:             21-Mar-2020 20:23:34
  End time:               22-Mar-2020 12:16:45
  Elapsed time:           15 hours 53 mins 11 secs
  Priority:               21
  SD Files Written:       1,030,385
  SD Bytes Written:       1,082,331,572,757 (1.082 TB)
  Rate:                   18924.9 KB/s
  Volume name(s):         DISK016|DISK017
  Volume Session Id:      41
  Volume Session Time:    1584646035
  Last Volume Bytes:      110,838,413,473 (110.8 GB)
  SD Errors:              0
  SD termination status:  OK
  Termination:            Migration OK

Related Backup-Migration Job:


21-Mar 20:23 backup-dir JobId 47704: Using Volume "DISK016" from 'Scratch' pool.
21-Mar 20:23 backup-dir JobId 47704: Using Device "DiskStorage2" to write.
21-Mar 20:23 backup-sd JobId 47704: Wrote label to prelabeled Volume "DISK016" 
on File device "DiskStorage2" (/media/baculadisk2)
22-Mar 07:47 backup-sd JobId 47704: [SI0201] Out of freespace caused End of 
Volume "DISK016" at 972406513998 on device "DiskStorage2" (/media/baculadisk2). 
Write of 64512 bytes got 57010.
22-Mar 07:47 backup-sd JobId 47704: End of medium on Volume "DISK016" 
Bytes=972,406,513,998 Blocks=15,073,266 at 22-Mar-2020 07:47.
22-Mar 07:47 backup-sd JobId 47704:

WARNING: device is full! Please add more disk space then ...

Please mount append Volume "DISK016" or label a new one for:
    Job:          nihilnihil_home.2020-03-21_20.23.31_49
    Storage:      "DiskStorage2" (/media/baculadisk2)
    Pool:         Migrate
    Media type:   Disk
22-Mar 07:52 backup-dir JobId 47704: Recycled volume "DISK017"
22-Mar 07:52 backup-dir JobId 47704: Using Volume "DISK017" from 'Scratch' pool.
22-Mar 07:52 backup-sd JobId 47704: Warning: mount.c:215 Open of File device 
"DiskStorage2" (/media/baculadisk2) Volume "DISK017" failed: ERR=file_dev.c:190 
Could not open(/media/baculadisk2/DISK017,OPEN_READ_WRITE,0640): ERR=No such 
file or directory

22-Mar 07:52 backup-sd JobId 47704: Warning: mount.c:215 Open of File device 
"DiskStorage2" (/media/baculadisk2) Volume "DISK017" failed: ERR=file_dev.c:190 
Could not open(/media/baculadisk2/DISK017,OPEN_READ_WRITE,0640): ERR=No such 
file or directory

22-Mar 07:57 backup-sd JobId 47704: Warning: mount.c:215 Open of File device 
"DiskStorage2" (/media/baculadisk2) Volume "DISK017" failed: ERR=file_dev.c:190 
Could not open(/media/baculadisk2/DISK017,OPEN_READ_WRITE,0640): ERR=No such 
file or directory

22-Mar 08:02 backup-sd JobId 47704: Warning: mount.c:215 Open of File device 
"DiskStorage2" (/media/baculadisk2) Volume "DISK017" failed: ERR=file_dev.c:190 
Could not open(/media/baculadisk2/DISK017,OPEN_READ_WRITE,0640): ERR=No such 
file or directory

22-Mar 08:07 backup-sd JobId 47704: Warning: mount.c:215 Open of File device 
"DiskStorage2" (/media/baculadisk2) Volume "DISK017" failed: ERR=file_dev.c:190 
Could not open(/media/baculadisk2/DISK017,OPEN_READ_WRITE,0640): ERR=No such 
file or directory

22-Mar 08:12 backup-sd JobId 47704: Warning: mount.c:215 Open of File device 
"DiskStorage2" (/media/baculadisk2) Volume "DISK017" failed: ERR=file_dev.c:190 
Could not open(/media/baculadisk2/DISK017,OPEN_READ_WRITE,0640): ERR=No such 
file or directory

22-Mar 08:22 backup-sd JobId 47704: Warning: mount.c:215 Open of File device 
"DiskStorage2" (/media/baculadisk2) Volume "DISK017" failed: ERR=file_dev.c:190 
Could not open(/media/baculadisk2/DISK017,OPEN_READ_WRITE,0640): ERR=No such 
file or directory

22-Mar 08:32 backup-sd JobId 47704: Warning: mount.c:215 Open of File device 
"DiskStorage2" (/media/baculadisk2) Volume "DISK017" failed: ERR=file_dev.c:190 
Could not open(/media/baculadisk2/DISK017,OPEN_READ_WRITE,0640): ERR=No such 
file or directory

22-Mar 08:42 backup-sd JobId 47704: Warning: mount.c:215 Open of File device 
"DiskStorage2" (/media/baculadisk2) Volume "DISK017" failed: ERR=file_dev.c:190 
Could not open(/media/baculadisk2/DISK017,OPEN_READ_WRITE,0640): ERR=No such 
file or directory

22-Mar 08:47 backup-sd JobId 47704: Please mount append Volume "DISK017" or 
label a new one for:
    Job:          nihilnihil_home.2020-03-21_20.23.31_49
    Storage:      "DiskStorage2" (/media/baculadisk2)
    Pool:         Migrate
    Media type:   Disk
22-Mar 08:57 backup-sd JobId 47704: Warning: mount.c:215 Open of File device 
"DiskStorage2" (/media/baculadisk2) Volume "DISK017" failed: ERR=file_dev.c:190 
Could not open(/media/baculadisk2/DISK017,OPEN_READ_WRITE,0640): ERR=No such 
file or directory

22-Mar 09:07 backup-sd JobId 47704: Warning: mount.c:215 Open of File device 
"DiskStorage2" (/media/baculadisk2) Volume "DISK017" failed: ERR=file_dev.c:190 
Could not open(/media/baculadisk2/DISK017,OPEN_READ_WRITE,0640): ERR=No such 
file or directory

22-Mar 09:07 backup-sd JobId 47704: Please mount append Volume "DISK017" or 
label a new one for:
    Job:          nihilnihil_home.2020-03-21_20.23.31_49
    Storage:      "DiskStorage2" (/media/baculadisk2)
    Pool:         Migrate
    Media type:   Disk
22-Mar 09:08 backup-sd JobId 47704: Warning: label.c:398 Open File device 
"DiskStorage2" (/media/baculadisk2) Volume "DISK017" failed: ERR=file_dev.c:190 
Could not open(/media/baculadisk2/DISK017,CREATE_READ_WRITE,0640): 
ERR=Permission denied
22-Mar 09:08 backup-sd JobId 47704: Warning: label.c:398 Open File device 
"DiskStorage2" (/media/baculadisk2) Volume "DISK017" failed: ERR=file_dev.c:190 
Could not open(/media/baculadisk2/DISK017,CREATE_READ_WRITE,0640): 
ERR=Permission denied
22-Mar 09:08 backup-sd JobId 47704: Warning: mount.c:215 Open of File device 
"DiskStorage2" (/media/baculadisk2) Volume "DISK017" failed: ERR=file_dev.c:190 
Could not open(/media/baculadisk2/DISK017,CREATE_READ_WRITE,0640): 
ERR=Permission denied

22-Mar 09:08 backup-sd JobId 47704: Marking Volume "DISK017" Read-Only in 
Catalog.
22-Mar 09:08 backup-sd JobId 47704: Please mount append Volume "DISK017" or 
label a new one for:
    Job:          nihilnihil_home.2020-03-21_20.23.31_49
    Storage:      "DiskStorage2" (/media/baculadisk2)
    Pool:         Migrate
    Media type:   Disk
22-Mar 09:09 backup-dir JobId 47704: Pruning oldest volume "DISK016"
22-Mar 09:09 backup-dir JobId 47704: Found no Job associated with the Volume 
"DISK016" to prune
22-Mar 09:09 backup-dir JobId 47704: Pruning oldest volume "DISK016"
22-Mar 09:09 backup-dir JobId 47704: Found no Job associated with the Volume 
"DISK016" to prune
22-Mar 09:09 backup-sd JobId 47704: Job nihilnihil_home.2020-03-21_20.23.31_49 
is waiting. Cannot find any appendable volumes.
Please use the "label" command to create a new Volume for:
    Storage:      "DiskStorage2" (/media/baculadisk2)
    Pool:         Migrate
    Media type:   Disk
22-Mar 09:11 backup-dir JobId 47704: Recycled volume "DISK017"
22-Mar 09:11 backup-dir JobId 47704: Using Volume "DISK017" from 'Scratch' pool.
22-Mar 09:11 backup-sd JobId 47704: Please mount append Volume "DISK017" or 
label a new one for:
    Job:          nihilnihil_home.2020-03-21_20.23.31_49
    Storage:      "DiskStorage2" (/media/baculadisk2)
    Pool:         Migrate
    Media type:   Disk
22-Mar 09:18 backup-sd JobId 47704: Recycled volume "DISK017" on File device 
"DiskStorage2" (/media/baculadisk2), all previous data lost.
22-Mar 09:18 backup-sd JobId 47704: New volume "DISK017" mounted on device 
"DiskStorage2" (/media/baculadisk2) at 22-Mar-2020 09:18.
22-Mar 10:34 backup-sd JobId 47704: Elapsed time=12:39:08, Transfer rate=23.76 
M Bytes/second
22-Mar 10:34 backup-sd JobId 47704: Sending spooled attrs to the Director. 
Despooling 300,838,146 bytes ...


By the way I created a bscan. Here the lines about the disk change:


bscan: bscan.c:442-0 Record: SessId=42 SessTim=1584646035 FileIndex=932145 
Stream=23 len=62656
bscan: bscan.c:442-0 Record: SessId=42 SessTim=1584646035 FileIndex=932145 
Stream=23 len=62336
bscan: bscan.c:442-0 Record: SessId=42 SessTim=1584646035 FileIndex=932145 
Stream=23 len=61632
bscan: bscan.c:442-0 Record: SessId=42 SessTim=1584646035 FileIndex=932145 
Stream=23 len=62528
bscan: bscan.c:442-0 Record: SessId=42 SessTim=1584646035 FileIndex=932145 
Stream=23 len=62672
25-Mar 23:27 bscan JobId 0: Error: block.c:682 [SE0208] Volume data error at 
0:0! Short block of 57010 bytes on device "FileTmpVol" (/media/baculadisk2) 
discarded.
bscan: read_records.c:160-0 block.c:682 [SE0208] Volume data error at 0:0! 
Short block of 57010 bytes on device "FileTmpVol" (/media/baculadisk2) 
discarded.
25-Mar 23:27 bscan JobId 0: Error: read_records.c:160 block.c:682 [SE0208] 
Volume data error at 0:0! Short block of 57010 bytes on device "FileTmpVol" 
(/media/baculadisk2) discarded.
25-Mar 23:27 bscan JobId 0: End of Volume "DISK016|DISK017" at 
addr=972406571008 on device "FileTmpVol" (/media/baculadisk2).
bscan: bscan.c:357-0 Create JobMedia for Job 
nihilnihil_home.2020-03-21_20.23.31_49
25-Mar 23:27 bscan JobId 0: Ready to read from volume "DISK017" on File device 
"FileTmpVol" (/media/baculadisk2).
bscan: bscan.c:385-0 First Volume Size = 972406571008
bscan: bscan.c:442-0 Record: SessId=42 SessTim=1584646035 FileIndex=-2 Stream=2 
len=177
bscan: bscan.c:468-0 Pool record for Scratch found in DB.
bscan: bscan.c:482-0 Pool type "Backup" is OK.
bscan: bscan.c:492-0 Media record for DISK017 found in DB.
bscan: bscan.c:510-0 Media type "Disk" is OK.
bscan: bscan.c:519-0 VOL_LABEL: OK for Volume: DISK017
done: 0%
bscan: bscan.c:442-0 Record: SessId=42 SessTim=1584646035 FileIndex=932145 
Stream=23 len=61648
bscan: bscan.c:442-0 Record: SessId=42 SessTim=1584646035 FileIndex=932145 
Stream=23 len=61712
bscan: bscan.c:442-0 Record: SessId=42 SessTim=1584646035 FileIndex=932145 
Stream=23 len=61920
bscan: bscan.c:442-0 Record: SessId=42 SessTim=1584646035 FileIndex=932145 
Stream=23 len=62560

The good is, the File is older so i is already in each full backup since month.

Any idea how I can extract the single data für fileindex 932145 from the disks 
for comparing?
If the short block is only repeated as whole block on the next disk the problem 
could be fixed
by modify the database so the short block will not be read or by truncating at 
short block on
the DISK016 (?)

Cheers,
Pierre



_______________________________________________
Bacula-users mailing list
Bacula-users@lists.sourceforge.net
https://lists.sourceforge.net/lists/listinfo/bacula-users

Reply via email to