Bacula-users

Re: [Bacula-users] Bacula 7.0.4 + bacula-fd 6.0.6 still produce failed verify jobs on windows

2014-07-10 15:49:04
Subject: Re: [Bacula-users] Bacula 7.0.4 + bacula-fd 6.0.6 still produce failed verify jobs on windows
From: Kern Sibbald <kern AT sibbald DOT com>
To: "J. Echter" <j.echter AT echter-kuechen-elektro DOT de>, bacula-users AT lists.sourceforge DOT net
Date: Thu, 10 Jul 2014 21:44:14 +0200
The next version of the Windows FD will have that problem fixed.  It is
something that I will work on in the next few weeks.

Best regards,
Kern

On 07/10/2014 07:43 PM, J. Echter wrote:
> Hi,
>
> i upgraded to bacula 7.x and bought the bacula 6.x enterprise binarys
> for my windows boxes, hoping the verify error will go away.
>
> Sadly, it didn't...
>
> Here' the log from the backup:
>
> 08-Jul 20:00 bacula-dir JobId 3271: No prior Full backup Job record found.
> 08-Jul 20:00 bacula-dir JobId 3271: No prior or suitable Full backup found in 
> catalog. Doing FULL backup.
> 09-Jul 00:32 bacula-dir JobId 3271: shell command: run BeforeJob 
> "/etc/bacula/holiday.sh"
> 09-Jul 00:32 bacula-dir JobId 3271: BeforeJob: Date is not a known vacation 
> day.
> 09-Jul 00:32 bacula-dir JobId 3271: Start Backup JobId 3271, 
> Job=Datev.2014-07-08_20.00.01_37
> 09-Jul 00:32 bacula-dir JobId 3271: Using Device "File" to write.
> 09-Jul 00:32 datev-fd JobId 3271: Generate VSS snapshots. Driver="Win64 VSS", 
> Drive(s)="C"
> 09-Jul 00:34 bacula-sd JobId 3271: End of medium on Volume "file-0074" 
> Bytes=1,073,719,618 Blocks=16,651 at 09-Jul-2014 00:34.
> 09-Jul 00:34 bacula-dir JobId 3271: There are no more Jobs associated with 
> Volume "file-0075". Marking it purged.
> 09-Jul 00:34 bacula-dir JobId 3271: All records pruned from Volume 
> "file-0075"; marking it "Purged"
> 09-Jul 00:34 bacula-dir JobId 3271: Recycled volume "file-0075"
> 09-Jul 00:34 bacula-sd JobId 3271: Recycled volume "file-0075" on file device 
> "File" (/BACKUP/archivefiles), all previous data lost.
> 09-Jul 00:34 bacula-sd JobId 3271: New volume "file-0075" mounted on device 
> "File" (/BACKUP/archivefiles) at 09-Jul-2014 00:34.
> 09-Jul 00:40 bacula-sd JobId 3271: End of medium on Volume "file-0075" 
> Bytes=1,073,737,773 Blocks=16,644 at 09-Jul-2014 00:40.
> 09-Jul 00:40 bacula-dir JobId 3271: There are no more Jobs associated with 
> Volume "file-0076". Marking it purged.
> 09-Jul 00:40 bacula-dir JobId 3271: All records pruned from Volume 
> "file-0076"; marking it "Purged"
> 09-Jul 00:40 bacula-dir JobId 3271: Recycled volume "file-0076"
> 09-Jul 00:40 bacula-sd JobId 3271: Recycled volume "file-0076" on file device 
> "File" (/BACKUP/archivefiles), all previous data lost.
> 09-Jul 00:40 bacula-sd JobId 3271: New volume "file-0076" mounted on device 
> "File" (/BACKUP/archivefiles) at 09-Jul-2014 00:40.
> 09-Jul 00:46 bacula-sd JobId 3271: End of medium on Volume "file-0076" 
> Bytes=1,073,737,794 Blocks=16,644 at 09-Jul-2014 00:46.
> 09-Jul 00:46 bacula-dir JobId 3271: There are no more Jobs associated with 
> Volume "file-0077". Marking it purged.
> 09-Jul 00:46 bacula-dir JobId 3271: All records pruned from Volume 
> "file-0077"; marking it "Purged"
> 09-Jul 00:46 bacula-dir JobId 3271: Recycled volume "file-0077"
> 09-Jul 00:46 bacula-sd JobId 3271: Recycled volume "file-0077" on file device 
> "File" (/BACKUP/archivefiles), all previous data lost.
> 09-Jul 00:46 bacula-sd JobId 3271: New volume "file-0077" mounted on device 
> "File" (/BACKUP/archivefiles) at 09-Jul-2014 00:46.
> 09-Jul 00:50 datev-fd JobId 3271: VSS Writer (BackupComplete): "Task 
> Scheduler Writer", State: 0x1 (VSS_WS_STABLE)
> 09-Jul 00:50 datev-fd JobId 3271: VSS Writer (BackupComplete): "VSS Metadata 
> Store Writer", State: 0x1 (VSS_WS_STABLE)
> 09-Jul 00:50 datev-fd JobId 3271: VSS Writer (BackupComplete): "Performance 
> Counters Writer", State: 0x1 (VSS_WS_STABLE)
> 09-Jul 00:50 datev-fd JobId 3271: VSS Writer (BackupComplete): "System 
> Writer", State: 0x1 (VSS_WS_STABLE)
> 09-Jul 00:50 datev-fd JobId 3271: VSS Writer (BackupComplete): "ASR Writer", 
> State: 0x1 (VSS_WS_STABLE)
> 09-Jul 00:50 datev-fd JobId 3271: VSS Writer (BackupComplete): "MSSearch 
> Service Writer", State: 0x1 (VSS_WS_STABLE)
> 09-Jul 00:50 datev-fd JobId 3271: VSS Writer (BackupComplete): "COM+ REGDB 
> Writer", State: 0x1 (VSS_WS_STABLE)
> 09-Jul 00:50 datev-fd JobId 3271: VSS Writer (BackupComplete): 
> "SqlServerWriter", State: 0x1 (VSS_WS_STABLE)
> 09-Jul 00:50 datev-fd JobId 3271: VSS Writer (BackupComplete): "Shadow Copy 
> Optimization Writer", State: 0x1 (VSS_WS_STABLE)
> 09-Jul 00:50 datev-fd JobId 3271: VSS Writer (BackupComplete): "Registry 
> Writer", State: 0x1 (VSS_WS_STABLE)
> 09-Jul 00:50 datev-fd JobId 3271: VSS Writer (BackupComplete): "WMI Writer", 
> State: 0x1 (VSS_WS_STABLE)
> 09-Jul 00:50 datev-fd JobId 3271: VSS Writer (BackupComplete): "BITS Writer", 
> State: 0x1 (VSS_WS_STABLE)
> 09-Jul 00:50 datev-fd JobId 3271: shell command: run ClientAfterJob 
> ""c:/netstart.bat""
> 09-Jul 00:50 bacula-sd JobId 3271: Elapsed time=00:17:30, Transfer rate=2.640 
> M Bytes/second
> 09-Jul 00:50 datev-fd JobId 3271: ClientAfterJob: 
> 09-Jul 00:50 datev-fd JobId 3271: ClientAfterJob: C:\Windows\system32>net 
> start MSSQL$DATEV_DBEngine 
> 09-Jul 00:50 datev-fd JobId 3271: ClientAfterJob: SQL Server (DATEV_DBENGINE) 
> wird gestartet..
> 09-Jul 00:50 datev-fd JobId 3271: ClientAfterJob: SQL Server (DATEV_DBENGINE) 
> wurde erfolgreich gestartet.
> 09-Jul 00:50 datev-fd JobId 3271: ClientAfterJob: 
> 09-Jul 00:50 bacula-dir JobId 3271: Bacula bacula-dir 7.0.4 (04Jun14):
>   Build OS:               x86_64-redhat-linux-gnu redhat Enterprise release
>   JobId:                  3271
>   Job:                    Datev.2014-07-08_20.00.01_37
>   Backup Level:           Full (upgraded from Incremental)
>   Client:                 "datev-fd" 6.0.6 (30Sep12) Microsoft Windows 7 
> Ultimate Edition Service Pack 1 (build 7601), 64-bit,Cross-compile,Win64
>   FileSet:                "Datev" 2014-04-22 20:00:00
>   Pool:                   "File" (From Job resource)
>   Catalog:                "MyCatalog" (From Client resource)
>   Storage:                "File" (From Pool resource)
>   Scheduled time:         08-Jul-2014 20:00:01
>   Start time:             09-Jul-2014 00:32:49
>   End time:               09-Jul-2014 00:50:36
>   Elapsed time:           17 mins 47 secs
>   Priority:               3
>   FD Files Written:       10,783
>   SD Files Written:       10,783
>   FD Bytes Written:       2,770,199,479 (2.770 GB)
>   SD Bytes Written:       2,772,222,965 (2.772 GB)
>   Rate:                   2596.3 KB/s
>   Software Compression:   86.2% 7.3:1
>   VSS:                    yes
>   Encryption:             no
>   Accurate:               yes
>   Volume name(s):         file-0074|file-0075|file-0076|file-0077
>   Volume Session Id:      16
>   Volume Session Time:    1404762693
>   Last Volume Bytes:      611,230,550 (611.2 MB)
>   Non-fatal FD errors:    0
>   SD Errors:              0
>   FD termination status:  OK
>   SD termination status:  OK
>   Termination:            Backup OK
>
> 09-Jul 00:50 bacula-dir JobId 3271: Begin pruning Jobs older than 1 year 6 
> months 2 days 12 hours .
> 09-Jul 00:50 bacula-dir JobId 3271: No Jobs found to prune.
> 09-Jul 00:50 bacula-dir JobId 3271: Begin pruning Files.
> 09-Jul 00:50 bacula-dir JobId 3271: No Files found to prune.
> 09-Jul 00:50 bacula-dir JobId 3271: End auto prune.
>
>
> and here's the log from the failed verify:
>
> 09-Jul 00:50 bacula-dir JobId 3282: shell command: run BeforeJob 
> "/etc/bacula/holiday.sh"
> 09-Jul 00:50 bacula-dir JobId 3282: BeforeJob: Date is not a known vacation 
> day.
> 09-Jul 00:50 bacula-dir JobId 3282: Verifying against JobId=3271 
> Job=Datev.2014-07-08_20.00.01_37
> 09-Jul 00:50 bacula-dir JobId 3282: Bootstrap records written to 
> /var/lib/bacula/bacula-dir.restore.8.bsr
> 09-Jul 00:50 bacula-dir JobId 3282: Start Verify JobId=3282 
> Level=VolumeToCatalog Job=Datev-Verify.2014-07-08_20.00.01_48
> 09-Jul 00:50 bacula-dir JobId 3282: Using Device "File" to read.
> 09-Jul 00:50 bacula-sd JobId 3282: Ready to read from volume "file-0074" on 
> file device "File" (/BACKUP/archivefiles).
> 09-Jul 00:50 bacula-sd JobId 3282: Forward spacing Volume "file-0074" to 
> file:block 0:1054623206.
> 09-Jul 00:50 bacula-sd JobId 3282: End of Volume at file 0 on device "File" 
> (/BACKUP/archivefiles), Volume "file-0074"
> 09-Jul 00:50 bacula-sd JobId 3282: Ready to read from volume "file-0075" on 
> file device "File" (/BACKUP/archivefiles).
> 09-Jul 00:50 bacula-sd JobId 3282: Forward spacing Volume "file-0075" to 
> file:block 0:192.
> 09-Jul 00:51 bacula-sd JobId 3282: End of Volume at file 0 on device "File" 
> (/BACKUP/archivefiles), Volume "file-0075"
> 09-Jul 00:51 bacula-sd JobId 3282: Ready to read from volume "file-0076" on 
> file device "File" (/BACKUP/archivefiles).
> 09-Jul 00:51 bacula-sd JobId 3282: Forward spacing Volume "file-0076" to 
> file:block 0:192.
> 09-Jul 00:51 bacula-sd JobId 3282: End of Volume at file 0 on device "File" 
> (/BACKUP/archivefiles), Volume "file-0076"
> 09-Jul 00:51 bacula-sd JobId 3282: Ready to read from volume "file-0077" on 
> file device "File" (/BACKUP/archivefiles).
> 09-Jul 00:51 bacula-sd JobId 3282: Forward spacing Volume "file-0077" to 
> file:block 0:192.
> 09-Jul 00:52 bacula-sd JobId 3282: End of Volume at file 0 on device "File" 
> (/BACKUP/archivefiles), Volume "file-0077"
> 09-Jul 00:52 bacula-sd JobId 3282: End of all volumes.
> 09-Jul 00:52 bacula-sd JobId 3282: Elapsed time=00:01:35, Transfer rate=29.18 
> M Bytes/second
> 09-Jul 00:52 bacula-dir JobId 3282: Error: Bacula bacula-dir 7.0.4 (04Jun14):
>   Build OS:               x86_64-redhat-linux-gnu redhat Enterprise release
>   JobId:                  3282
>   Job:                    Datev-Verify.2014-07-08_20.00.01_48
>   FileSet:                Datev
>   Verify Level:           VolumeToCatalog
>   Client:                 datev-fd
>   Verify JobId:           3271
>   Verify Job:             Datev
>   Start time:             09-Jul-2014 00:50:37
>   End time:               09-Jul-2014 00:52:14
>   Files Expected:         10,783
>   Files Examined:         10,782
>   Non-fatal FD errors:    0
>   FD termination status:  OK
>   SD termination status:  OK
>   Termination:            *** Verify Error ***
>
> 09-Jul 00:52 bacula-dir JobId 3282: Begin pruning Jobs older than 6 months 5 
> days .
> 09-Jul 00:52 bacula-dir JobId 3282: No Jobs found to prune.
> 09-Jul 00:52 bacula-dir JobId 3282: Begin pruning Files.
> 09-Jul 00:52 bacula-dir JobId 3282: No Files found to prune.
> 09-Jul 00:52 bacula-dir JobId 3282: End auto prune.
>
> as you see, it gets one file less to verify than it thinks it backed up.
>
> Linux boxes work very well.
>
> Im on centos 6.5 x64.
>
> Any hints on that?
>
> Thanks
>
> Juergen
>
>
>
> ------------------------------------------------------------------------------
> Open source business process management suite built on Java and Eclipse
> Turn processes into business applications with Bonita BPM Community Edition
> Quickly connect people, data, and systems into organized workflows
> Winner of BOSSIE, CODIE, OW2 and Gartner awards
> http://p.sf.net/sfu/Bonitasoft
> _______________________________________________
> Bacula-users mailing list
> Bacula-users AT lists.sourceforge DOT net
> https://lists.sourceforge.net/lists/listinfo/bacula-users
>


------------------------------------------------------------------------------
Open source business process management suite built on Java and Eclipse
Turn processes into business applications with Bonita BPM Community Edition
Quickly connect people, data, and systems into organized workflows
Winner of BOSSIE, CODIE, OW2 and Gartner awards
http://p.sf.net/sfu/Bonitasoft
_______________________________________________
Bacula-users mailing list
Bacula-users AT lists.sourceforge DOT net
https://lists.sourceforge.net/lists/listinfo/bacula-users

<Prev in Thread] Current Thread [Next in Thread>