This backup job takes over seven hours to run. One reason is that we are doing 
a a VSS snapshot of four drives (CDPS) then excluding over 90% of what is in 
the snapshot because it is backed up a different way. I'm not sure now to fix 
that issue.

My real question concerns the information in the job log. at "2015-10-11 
12:16:30 28821126" we see "Generate VSS snapshots". The next line ("2015-10-11 
14:47:39 28821590") is about 2.5 hours later is about the spool max size being 
reached indicating reading of the snapshot had begun. But then at "2015-10-11 
19:37:39" (almost fours later) it looks like the VSS Writer is now done.

It seems there is some overlap in the VSS snaps being taken and spooling to 
disk. Is each drive within the snap handling separately?

I have several servers acting like this, so any tweak advice would be 
appreciated.

Thanks.

Dave

-------------------------------------------------------------------------
2015-10-11 20:01:50     28822260        187903  orlbareos-dir JobId 187903: End 
auto prune.
2015-10-11 20:01:50     28822259        187903  orlbareos-dir JobId 187903: 
Pruned Files from 1 Jobs for client server1-fd from catalog.
2015-10-11 20:00:50     28822258        187903  orlbareos-dir JobId 187903: 
Begin pruning Files.
2015-10-11 20:00:50     28822257        187903  orlbareos-dir JobId 187903: No 
Jobs found to prune.
2015-10-11 20:00:50     28822256        187903  orlbareos-dir JobId 187903: 
Begin pruning Jobs older than 12 months .
2015-10-11 20:00:50     28822255        187903  orlbareos-dir JobId 187903: 
Bareos orlbareos-dir 14.2.2 (12Dec14):
   Build OS: x86_64-unknown-linux-gnu redhat CentOS release 6.2 (Final)
   JobId: 187903
   Job: server1-backup.2015-10-10_21.05.02_59
   Backup Level: Full
   Client: "server1-fd" 13.2.2 (12Nov13) Microsoft Windows Server 2003 R2 
Standard Edition Service Pack 2 (build 3790),Cross-compile,Win32
   FileSet: "windows2" 2011-09-29 21:05:00
   Pool: "Monthly" (From Job FullPool override)
   Catalog: "MyCatalog" (From Client resource)
   Storage: "tape" (From Job resource)
   Scheduled time: 10-Oct-2015 21:05:02
   Start time: 11-Oct-2015 12:16:27
   End time: 11-Oct-2015 20:00:50
   Elapsed time: 7 hours 44 mins 23 secs
   Priority: 10
   FD Files Written: 248,289
   SD Files Written: 248,289
   FD Bytes Written: 53,137,193,632 (53.13 GB)
   SD Bytes Written: 53,256,029,633 (53.25 GB)
   Rate: 1907.1 KB/s
   Software Compression: 46.8 % (lz4)
   VSS: yes
   Encryption: yes
   Accurate: no
   Volume name(s): 000103
   Volume Session Id: 445
   Volume Session Time: 1444161285
   Last Volume Bytes: 641,369,723,904 (641.3 GB)
   Non-fatal FD errors: 0
   SD Errors: 0
   FD termination status: OK
   SD termination status: OK
   Termination: Backup OK

2015-10-11 19:57:41     28822254        187903  orlbareos-sd JobId 187903: 
Sending spooled attrs to the Director. Despooling 92,083,191 bytes ...
2015-10-11 19:57:40     28822253        187903  orlbareos-sd JobId 187903: 
Alert: Error Counter logging not supported
2015-10-11 19:57:40     28822252        187903  orlbareos-sd JobId 187903: 
Alert: 
2015-10-11 19:57:40     28822251        187903  orlbareos-sd JobId 187903: 
Alert: TapeAlert: OK
2015-10-11 19:57:36     28822250        187903  orlbareos-sd JobId 187903: 
Alert: 
2015-10-11 19:57:36     28822249        187903  orlbareos-sd JobId 187903: 
Alert: Copyright (C) 2002-12 by Bruce Allen, 
http://smartmontools.sourceforge.net
2015-10-11 19:57:36     28822248        187903  orlbareos-sd JobId 187903: 
Alert: smartctl 5.43 2012-06-30 r3573 [x86_64-linux-2.6.32-573.7.1.el6.x86_64] 
(local build)
2015-10-11 19:57:36     28822247        187903  orlbareos-sd JobId 187903: 
Elapsed time=07:41:09, Transfer rate=1.924 M Bytes/second
2015-10-11 19:57:30     28822246        187903  orlbareos-sd JobId 187903: 
Despooling elapsed time = 00:12:54, Transfer rate = 45.79 M Bytes/second
2015-10-11 19:37:42     28822224        187903  orlbareos-sd JobId 187903: 
Committing spooled data to Volume "000103". Despooling 35,446,339,427 bytes ...
2015-10-11 19:37:39     28822223        187903  server1-fd JobId 187903: VSS 
Writer (BackupComplete): "WMI Writer", State: 0x1 (VSS_WS_STABLE)
2015-10-11 19:37:39     28822222        187903  server1-fd JobId 187903: VSS 
Writer (BackupComplete): "IIS Metabase Writer", State: 0x1 (VSS_WS_STABLE)
2015-10-11 19:37:39     28822221        187903  server1-fd JobId 187903: VSS 
Writer (BackupComplete): "COM+ REGDB Writer", State: 0x1 (VSS_WS_STABLE)
2015-10-11 19:37:39     28822220        187903  server1-fd JobId 187903: VSS 
Writer (BackupComplete): "Registry Writer", State: 0x1 (VSS_WS_STABLE)
2015-10-11 19:37:39     28822219        187903  server1-fd JobId 187903: VSS 
Writer (BackupComplete): "Event Log Writer", State: 0x1 (VSS_WS_STABLE)
2015-10-11 19:37:39     28822218        187903  server1-fd JobId 187903: VSS 
Writer (BackupComplete): "MSDEWriter", State: 0x1 (VSS_WS_STABLE)
2015-10-11 19:37:39     28822217        187903  server1-fd JobId 187903: VSS 
Writer (BackupComplete): "System Writer", State: 0x1 (VSS_WS_STABLE)
2015-10-11 19:37:21     28822216        187903  server1-fd JobId 187903: 
S:/UserDB is a different filesystem. Will not descend from S:/ into it.
2015-10-11 19:37:21     28822215        187903  server1-fd JobId 187903: 
S:/TempDB is a different filesystem. Will not descend from S:/ into it.
2015-10-11 19:37:21     28822214        187903  server1-fd JobId 187903: 
S:/SystemDB is a different filesystem. Will not descend from S:/ into it.
2015-10-11 19:37:21     28822213        187903  server1-fd JobId 187903: 
S:/SnapInfo is a different filesystem. Will not descend from S:/ into it.
2015-10-11 19:37:21     28822212        187903  server1-fd JobId 187903: S:/Log 
is a different filesystem. Will not descend from S:/ into it.
2015-10-11 15:59:29     28821689        187903  orlbareos-sd JobId 187903: 
Spooling data again ...
2015-10-11 15:59:21     28821688        187903  orlbareos-sd JobId 187903: 
Despooling elapsed time = 00:04:58, Transfer rate = 60.01 M Bytes/second
2015-10-11 14:47:39     28821591        187903  orlbareos-sd JobId 187903: 
Writing spooled data to Volume. Despooling 17,883,019,378 bytes ...
2015-10-11 14:47:39     28821590        187903  orlbareos-sd JobId 187903: User 
specified Job spool size reached: JobSpoolSize=17,883,019,378 
MaxJobSpoolSize=100,000,000,000
2015-10-11 12:16:30     28821126        187903  server1-fd JobId 187903: 
Generate VSS snapshots. Driver="Win32 VSS", Drive(s)="CDPS"
2015-10-11 12:16:27     28821123        187903  orlbareos-sd JobId 187903: 
Spooling data ...
2015-10-11 12:16:27     28821122        187903  orlbareos-dir JobId 187903: 
Using Device "Drive-0" to write.
2015-10-11 12:16:27     28821121        187903  orlbareos-dir JobId 187903: 
Start Backup JobId 187903, Job=server1-backup.2015-10-10_21.05.02_59

-- 
You received this message because you are subscribed to the Google Groups 
"bareos-users" group.
To unsubscribe from this group and stop receiving emails from it, send an email 
to [email protected].
To post to this group, send email to [email protected].
For more options, visit https://groups.google.com/d/optout.

Reply via email to