View Issue Details

IDProjectCategoryView StatusLast Update
0000869bareos-core[All Projects] Generalpublic2017-10-26 13:00
ReporterdiashkAssigned To 
PriorityhighSeverityminorReproducibilityalways
Status newResolutionopen 
Platformwindows server 2016OSOS Version
Product Version16.2.4 
Fixed in Version 
Summary0000869: Non-fatal FD errors on backup job
DescriptionHello,

When I run a incremental backup job I always get a warning.
I can not understand why does it happen and how to fix it.
Additional InformationTimestamp Log Job Message
2017-10-26 12:00:10 1920063 18799 bareos-dir JobId 18799: Bareos bareos-dir 14.2.2 (12Dec14):
Build OS: x86_64-unknown-linux-gnu redhat CentOS release 6.2 (Final)
JobId: 18799
Job: Backup_my_client.2017-10-26_11.59.48_04
Backup Level: Incremental, since=2017-10-26 11:32:40
Client: "my_client_fd" 16.2.4 (01Jul16) Microsoft Windows Server 2012 Standard Edition (build 9200), 64-bit,Cross-compile,Win64
FileSet: "windows_websitepanel" 2015-05-28 06:00:00
Pool: "Incremental" (From command line)
Catalog: "MyCatalog" (From Client resource)
Storage: "File" (From Job resource)
Scheduled time: 26-Oct-2017 11:59:48
Start time: 26-Oct-2017 11:59:50
End time: 26-Oct-2017 12:00:10
Elapsed time: 20 secs
Priority: 10
FD Files Written: 3
SD Files Written: 3
FD Bytes Written: 358 (358 B)
SD Bytes Written: 1,128 (1.128 KB)
Rate: 0.0 KB/s
Software Compression: 72.3 % (gzip)
VSS: yes
Encryption: no
Accurate: no
Volume name(s): Incremental-0030
Volume Session Id: 623
Volume Session Time: 1507455273
Last Volume Bytes: 2,730,543,382 (2.730 GB)
Non-fatal FD errors: 1
SD Errors: 0
FD termination status: OK
SD termination status: OK
Termination: Backup OK -- with warnings

2017-10-26 12:00:10 1920062 18799 bareos-sd JobId 18799: Elapsed time=00:00:20, Transfer rate=56 Bytes/second
2017-10-26 12:00:01 1920061 18799 my_client_fd JobId 18799: VSS Writer (BackupComplete): "IIS Config Writer", State: 0x1 (VSS_WS_STABLE)
2017-10-26 12:00:01 1920060 18799 my_client_fd JobId 18799: VSS Writer (BackupComplete): "WMI Writer", State: 0x1 (VSS_WS_STABLE)
2017-10-26 12:00:01 1920059 18799 my_client_fd JobId 18799: VSS Writer (BackupComplete): "Registry Writer", State: 0x1 (VSS_WS_STABLE)
2017-10-26 12:00:01 1920058 18799 my_client_fd JobId 18799: VSS Writer (BackupComplete): "COM+ REGDB Writer", State: 0x1 (VSS_WS_STABLE)
2017-10-26 12:00:01 1920057 18799 my_client_fd JobId 18799: VSS Writer (BackupComplete): "Shadow Copy Optimization Writer", State: 0x1 (VSS_WS_STABLE)
2017-10-26 12:00:01 1920056 18799 my_client_fd JobId 18799: VSS Writer (BackupComplete): "ASR Writer", State: 0x1 (VSS_WS_STABLE)
2017-10-26 12:00:01 1920055 18799 my_client_fd JobId 18799: Warning: VSS Writer (BackupComplete): "SqlServerWriter", State: 0x7 (VSS_WS_FAILED_AT_PREPARE_BACKUP)
2017-10-26 12:00:01 1920054 18799 my_client_fd JobId 18799: VSS Writer (BackupComplete): "System Writer", State: 0x1 (VSS_WS_STABLE)
2017-10-26 12:00:01 1920053 18799 my_client_fd JobId 18799: VSS Writer (BackupComplete): "Performance Counters Writer", State: 0x1 (VSS_WS_STABLE)
2017-10-26 12:00:01 1920052 18799 my_client_fd JobId 18799: VSS Writer (BackupComplete): "VSS Metadata Store Writer", State: 0x1 (VSS_WS_STABLE)
2017-10-26 12:00:01 1920051 18799 my_client_fd JobId 18799: VSS Writer (BackupComplete): "Task Scheduler Writer", State: 0x1 (VSS_WS_STABLE)
2017-10-26 12:00:01 1920050 18799 my_client_fd JobId 18799: Warning: VSS Writer "SqlServerWriter" has invalid state. ERR=The writer vetoed the shadow copy creation process during the backup preparation state.
2017-10-26 11:59:58 1920049 18799 my_client_fd JobId 18799: VSS Writer (PrepareForBackup): "SqlServerWriter", State: 0x7 (VSS_WS_FAILED_AT_PREPARE_BACKUP)
2017-10-26 11:59:58 1920048 18799 my_client_fd JobId 18799: Warning: VSS Writer "SqlServerWriter" has invalid state. ERR=The writer vetoed the shadow copy creation process during the backup preparation state.
2017-10-26 11:59:49 1920017 18799 my_client_fd JobId 18799: Warning: VSS Writer "SqlServerWriter" has invalid state. ERR=The writer vetoed the shadow copy creation process during the backup preparation state.
2017-10-26 11:59:47 1920016 18799 my_client_fd JobId 18799: VolumeMountpoints are not processed as onefs = yes.
2017-10-26 11:59:46 1920015 18799 my_client_fd JobId 18799: Generate VSS snapshots. Driver="Win64 VSS", Drive(s)="C"
2017-10-26 11:59:50 1920014 18799 bareos-sd JobId 18799: Ready to append to end of Volume "Incremental-0030" size=2730541696
2017-10-26 11:59:50 1920013 18799 bareos-sd JobId 18799: Volume "Incremental-0030" previously written, moving to end of data.
2017-10-26 11:59:43 1920012 18799 my_client_fd JobId 18799: ClientBeforeJob: C:\Windows\system32>set backup_to=c:\backups\MYSQL
2017-10-26 11:59:43 1920011 18799 my_client_fd JobId 18799: ClientBeforeJob:
2017-10-26 11:59:43 1920010 18799 my_client_fd JobId 18799: shell command: run ClientBeforeJob "c:\backups\mysql.bat"
2017-10-26 11:59:43 1920009 18799 my_client_fd JobId 18799: ClientBeforeJob: c:\backups\MSSQL>SET rar_format=201710
2017-10-26 11:59:43 1920008 18799 my_client_fd JobId 18799: ClientBeforeJob:
2017-10-26 11:59:43 1920007 18799 my_client_fd JobId 18799: ClientBeforeJob: c:\backups\MSSQL>for /F "tokens=2,3,4 delims=/ " %i in ('date /t') do SET rar_format=%k%i
2017-10-26 11:59:43 1920006 18799 my_client_fd JobId 18799: ClientBeforeJob:
2017-10-26 11:59:43 1920005 18799 my_client_fd JobId 18799: ClientBeforeJob: BACKUP DATABASE is terminating abnormally.
2017-10-26 11:59:43 1920004 18799 my_client_fd JobId 18799: ClientBeforeJob: Msg 3013, Level 16, State 1, Server WIN-GGHKINK6P98\SQLEXPRESS, Line 1
2017-10-26 11:59:43 1920003 18799 my_client_fd JobId 18799: ClientBeforeJob: BACKUP DATABASE permission denied in database 'msdb'.
2017-10-26 11:59:43 1920002 18799 my_client_fd JobId 18799: ClientBeforeJob: Msg 262, Level 14, State 1, Server WIN-GGHKINK6P98\SQLEXPRESS, Line 1
2017-10-26 11:59:43 1920001 18799 my_client_fd JobId 18799: ClientBeforeJob: c:\backups\MSSQL>sqlcmd -E -S (local)\SQLEXPRESS -Q "Backup Database msdb To Disk = 'c:\backups\MSSQL\26.10.2017-msdb.bak'"
2017-10-26 11:59:43 1920000 18799 my_client_fd JobId 18799: ClientBeforeJob:
2017-10-26 11:59:43 1919999 18799 my_client_fd JobId 18799: ClientBeforeJob: BACKUP DATABASE is terminating abnormally.
2017-10-26 11:59:43 1919998 18799 my_client_fd JobId 18799: ClientBeforeJob: Msg 3013, Level 16, State 1, Server WIN-GGHKINK6P98\SQLEXPRESS, Line 1
2017-10-26 11:59:43 1919997 18799 my_client_fd JobId 18799: ClientBeforeJob: The server principal "WORKGROUP\WIN-GGHKINK6P98$" is not able to access the database "model" under the current security context.
2017-10-26 11:59:43 1919996 18799 my_client_fd JobId 18799: ClientBeforeJob: Msg 916, Level 14, State 1, Server WIN-GGHKINK6P98\SQLEXPRESS, Line 1
2017-10-26 11:59:43 1919995 18799 my_client_fd JobId 18799: ClientBeforeJob: c:\backups\MSSQL>sqlcmd -E -S (local)\SQLEXPRESS -Q "Backup Database model To Disk = 'c:\backups\MSSQL\26.10.2017-model.bak'"
2017-10-26 11:59:43 1919994 18799 my_client_fd JobId 18799: ClientBeforeJob:
2017-10-26 11:59:43 1919993 18799 my_client_fd JobId 18799: ClientBeforeJob: BACKUP DATABASE is terminating abnormally.
2017-10-26 11:59:43 1919992 18799 my_client_fd JobId 18799: ClientBeforeJob: Msg 3013, Level 16, State 1, Server WIN-GGHKINK6P98\SQLEXPRESS, Line 1
2017-10-26 11:59:43 1919991 18799 my_client_fd JobId 18799: ClientBeforeJob: Backup and restore operations are not allowed on database tempdb.
2017-10-26 11:59:43 1919990 18799 my_client_fd JobId 18799: ClientBeforeJob: Msg 3147, Level 16, State 3, Server WIN-GGHKINK6P98\SQLEXPRESS, Line 1
2017-10-26 11:59:43 1919989 18799 my_client_fd JobId 18799: ClientBeforeJob: c:\backups\MSSQL>sqlcmd -E -S (local)\SQLEXPRESS -Q "Backup Database tempdb To Disk = 'c:\backups\MSSQL\26.10.2017-tempdb.bak'"
2017-10-26 11:59:43 1919988 18799 my_client_fd JobId 18799: ClientBeforeJob:
2017-10-26 11:59:43 1919987 18799 my_client_fd JobId 18799: ClientBeforeJob: BACKUP DATABASE is terminating abnormally.
2017-10-26 11:59:43 1919986 18799 my_client_fd JobId 18799: ClientBeforeJob: Msg 3013, Level 16, State 1, Server WIN-GGHKINK6P98\SQLEXPRESS, Line 1
2017-10-26 11:59:43 1919985 18799 my_client_fd JobId 18799: ClientBeforeJob: BACKUP DATABASE permission denied in database 'master'.
2017-10-26 11:59:43 1919984 18799 my_client_fd JobId 18799: ClientBeforeJob: Msg 262, Level 14, State 1, Server WIN-GGHKINK6P98\SQLEXPRESS, Line 1
2017-10-26 11:59:43 1919983 18799 my_client_fd JobId 18799: ClientBeforeJob: c:\backups\MSSQL>sqlcmd -E -S (local)\SQLEXPRESS -Q "Backup Database master To Disk = 'c:\backups\MSSQL\26.10.2017-master.bak'"
2017-10-26 11:59:43 1919982 18799 my_client_fd JobId 18799: ClientBeforeJob:
2017-10-26 11:59:43 1919981 18799 my_client_fd JobId 18799: ClientBeforeJob: Disk = 'c:\backups\MSSQL\26.10.2017-%i.bak'"
2017-10-26 11:59:43 1919980 18799 my_client_fd JobId 18799: ClientBeforeJob: c:\backups\MSSQL>FOR /F "skip=2 eol=( delims= " %i IN (dbs_list.tmp) DO sqlcmd -E -S (local)\SQLEXPRESS -Q "Backup Database %i To
2017-10-26 11:59:43 1919979 18799 my_client_fd JobId 18799: ClientBeforeJob:
2017-10-26 11:59:43 1919978 18799 my_client_fd JobId 18799: ClientBeforeJob: c:\backups\MSSQL>sqlcmd -E -S (local)\SQLEXPRESS -Q "SELECT name FROM master..sysdatabases" 1>dbs_list.tmp
2017-10-26 11:59:43 1919977 18799 my_client_fd JobId 18799: ClientBeforeJob:
2017-10-26 11:59:43 1919976 18799 my_client_fd JobId 18799: ClientBeforeJob: c:\backups\MSSQL>del /Q *.bak
2017-10-26 11:59:43 1919975 18799 my_client_fd JobId 18799: ClientBeforeJob:
2017-10-26 11:59:43 1919974 18799 my_client_fd JobId 18799: ClientBeforeJob: C:\Windows\system32>cd c:\backups\MSSQL
2017-10-26 11:59:43 1919973 18799 my_client_fd JobId 18799: ClientBeforeJob:
2017-10-26 11:59:43 1919972 18799 my_client_fd JobId 18799: ClientBeforeJob: C:\Windows\system32>SET day_in_month=26
2017-10-26 11:59:43 1919971 18799 my_client_fd JobId 18799: ClientBeforeJob:
2017-10-26 11:59:43 1919970 18799 my_client_fd JobId 18799: ClientBeforeJob: C:\Windows\system32>for /F "tokens=2,3,4 delims=/ " %i in ('date /t') do SET day_in_month=%j
2017-10-26 11:59:43 1919969 18799 my_client_fd JobId 18799: ClientBeforeJob:
2017-10-26 11:59:43 1919968 18799 my_client_fd JobId 18799: ClientBeforeJob: C:\Windows\system32>SET date_today=26.10.2017
2017-10-26 11:59:43 1919967 18799 my_client_fd JobId 18799: ClientBeforeJob:
2017-10-26 11:59:43 1919966 18799 my_client_fd JobId 18799: ClientBeforeJob: C:\Windows\system32>for /F "tokens=2,3,4 delims=/ " %i in ('date /t') do SET date_today=%j.%i.%k
2017-10-26 11:59:43 1919965 18799 my_client_fd JobId 18799: ClientBeforeJob:
2017-10-26 11:59:43 1919964 18799 my_client_fd JobId 18799: ClientBeforeJob: C:\Windows\system32>SET tmp_directory=full_bu-26.10.2017
2017-10-26 11:59:43 1919963 18799 my_client_fd JobId 18799: ClientBeforeJob:
2017-10-26 11:59:43 1919962 18799 my_client_fd JobId 18799: ClientBeforeJob: C:\Windows\system32>for /F "tokens=2,3,4 delims=/ " %i in ('date /t') do SET tmp_directory=full_bu-%j.%i.%k
2017-10-26 11:59:43 1919961 18799 my_client_fd JobId 18799: ClientBeforeJob:
2017-10-26 11:59:43 1919960 18799 my_client_fd JobId 18799: ClientBeforeJob: C:\Windows\system32>set mssql_path=(local)\SQLEXPRESS
2017-10-26 11:59:43 1919959 18799 my_client_fd JobId 18799: ClientBeforeJob:
2017-10-26 11:59:43 1919958 18799 my_client_fd JobId 18799: ClientBeforeJob: C:\Windows\system32>set backup_to=c:\backups\MSSQL
2017-10-26 11:59:43 1919957 18799 my_client_fd JobId 18799: ClientBeforeJob:
2017-10-26 11:59:43 1919956 18799 my_client_fd JobId 18799: shell command: run ClientBeforeJob "c:\backups\mssql.bat"
2017-10-26 11:59:43 1919955 18799 my_client_fd JobId 18799: Created 20 wildcard excludes from FilesNotToBackup Registry key
2017-10-26 11:59:43 1919954 18799 my_client_fd JobId 18799: DIR and FD clocks differ by -6 seconds, FD automatically compensating.
2017-10-26 11:59:50 1919953 18799 bareos-dir JobId 18799: Using Device "FileStorage" to write.
2017-10-26 11:59:50
TagsNo tags attached.
bareos-master: impact
bareos-master: action
bareos-19.2: impact
bareos-19.2: action
bareos-18.2: impact
bareos-18.2: action
bareos-17.2: impact
bareos-17.2: action
bareos-16.2: impact
bareos-16.2: action
bareos-15.2: impact
bareos-15.2: action
bareos-14.2: impact
bareos-14.2: action
bareos-13.2: impact
bareos-13.2: action
bareos-12.4: impact
bareos-12.4: action

Activities

There are no notes attached to this issue.

Issue History

Date Modified Username Field Change
2017-10-26 13:00 diashk New Issue