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