Bacula-users

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

2014-07-10 14:01:42
Subject: [Bacula-users] Bacula 7.0.4 + bacula-fd 6.0.6 still produce failed verify jobs on windows
From: "J. Echter" <j.echter AT echter-kuechen-elektro DOT de>
To: bacula-users AT lists.sourceforge DOT net
Date: Thu, 10 Jul 2014 19:43:14 +0200
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

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