View Issue Details
ID | Project | Category | View Status | Date Submitted | Last Update |
---|---|---|---|---|---|
0001532 | bareos-core | file daemon | public | 2023-04-27 10:22 | 2024-01-30 10:42 |
Reporter | hostedpower | Assigned To | bruno-at-bareos | ||
Priority | normal | Severity | major | Reproducibility | always |
Status | closed | Resolution | reopened | ||
Platform | x86 | OS | Windows | OS Version | 2016 |
Product Version | 22.0.3 | ||||
Summary | 0001532: Windows backups fails on a lot of files, seems vss is not used properly | ||||
Description | Using Windows 2022 mdf": ERR=The process cannot access the file because it is being used by another process. Cannot open "E:/SQLLogs/xxxxx/xxxx_Cbro_log.ldf": ERR=The process cannot access the file because it is being used by another proces But we see lot's of these errors (easily 100's at moments which is quite weird). I cannot remember seeing so many errors with Windows backups as we experience now on Windows 2022 and Baroes 22.x (Not sure which one causes it) Could you provide me any troubleshooting steps so I can report back? | ||||
Tags | mssql, VSS, Windows | ||||
Maybe a similar issue here: https://groups.google.com/g/bareos-users/c/F46rRPh7Hf8 | |
Are the "E:/SQLLogs" directory pick into account by the VSS SQL Writer ? | |
Hi, wouldn't it find those by default? We still have these errors atm :( | |
I checked and I saw indeed that the vss writer didn't have systadmin privileges to the database, I changed and fixed this now without any change. It's almost as Bareos doesn't trigger the VSS from the SQL. I would expect errors in the Windows logs otherwise, but I don't seem to find any vss errors. I see this in the logs: 14 2023-11-28 00:48:41 myclient.xxx.com JobId 196538: Generate VSS snapshots. Driver="Win64 VSS", Drive(s)="CDE" Is this sufficient for it to work? backup-fails.txt (27,244 bytes)
139 2023-11-28 00:58:32 yyy-dir JobId 196538: console command: run AfterJob ".bvfs_update JobId=196538" 138 2023-11-28 00:58:32 yyy-dir JobId 196538: Bareos yyy-dir 22.1.1 (23Oct23): Build OS: Debian GNU/Linux 11 (bullseye) JobId: 196538 Job: myclient.xxx.com-files.2023-11-27_23.05.02_37 Backup Level: Incremental, since=2023-11-27 00:38:02 Client: "myclient.xxx.com" 22.1.0 (13Jun23) Microsoft Windows Server 2012 Standard Edition (build 9200), 64-bit,Cross-compile FileSet: "windows-files" 2022-07-06 23:05:00 Pool: "AI-Incremental" (From Job resource) Catalog: "MyCatalog" (From Client resource) Storage: "AI-Incremental" (From Pool resource) Scheduled time: 27-Nov-2023 23:05:02 Start time: 28-Nov-2023 00:48:22 End time: 28-Nov-2023 00:58:32 Elapsed time: 10 mins 10 secs Priority: 10 FD Files Written: 161,364 SD Files Written: 161,364 FD Bytes Written: 7,370,765,464 (7.370 GB) SD Bytes Written: 7,416,406,426 (7.416 GB) Rate: 12083.2 KB/s Software Compression: 55.4 % (gzip3) VSS: yes Encryption: no Accurate: yes Volume name(s): vol-incr-0648 Volume Session Id: 130650 Volume Session Time: 1678051949 Last Volume Bytes: 49,153,528,316 (49.15 GB) Non-fatal FD errors: 106 SD Errors: 0 FD termination status: OK SD termination status: OK Bareos binary info: Bareos subscription release Job triggered by: Scheduler Termination: Backup OK -- with warnings 137 2023-11-28 00:58:32 yyy-dir JobId 196538: Insert of attributes batch table done 136 2023-11-28 00:58:25 yyy-dir JobId 196538: Insert of attributes batch table with 161363 entries start 135 2023-11-28 00:58:24 yyy-sd JobId 196538: Elapsed time=00:09:45, Transfer rate=12.67 M Bytes/second 134 2023-11-28 00:58:24 yyy-sd JobId 196538: Releasing device "AI-Incremental0005" (/var/lib/bareos/storage). 133 2023-11-28 00:58:24 myclient.xxx.com JobId 196538: VSS Writer (BackupComplete): "COM+ REGDB Writer", State: 0x1 (VSS_WS_STABLE) 132 2023-11-28 00:58:24 myclient.xxx.com JobId 196538: VSS Writer (BackupComplete): "Registry Writer", State: 0x1 (VSS_WS_STABLE) 131 2023-11-28 00:58:24 myclient.xxx.com JobId 196538: VSS Writer (BackupComplete): "Shadow Copy Optimization Writer", State: 0x1 (VSS_WS_STABLE) 130 2023-11-28 00:58:24 myclient.xxx.com JobId 196538: VSS Writer (BackupComplete): "ASR Writer", State: 0x1 (VSS_WS_STABLE) 129 2023-11-28 00:58:24 myclient.xxx.com JobId 196538: VSS Writer (BackupComplete): "WMI Writer", State: 0x1 (VSS_WS_STABLE) 128 2023-11-28 00:58:24 myclient.xxx.com JobId 196538: VSS Writer (BackupComplete): "SqlServerWriter", State: 0x1 (VSS_WS_STABLE) 127 2023-11-28 00:58:24 myclient.xxx.com JobId 196538: VSS Writer (BackupComplete): "System Writer", State: 0x1 (VSS_WS_STABLE) 126 2023-11-28 00:58:24 myclient.xxx.com JobId 196538: VSS Writer (BackupComplete): "Performance Counters Writer", State: 0x1 (VSS_WS_STABLE) 125 2023-11-28 00:58:24 myclient.xxx.com JobId 196538: VSS Writer (BackupComplete): "VSS Metadata Store Writer", State: 0x1 (VSS_WS_STABLE) 124 2023-11-28 00:58:24 myclient.xxx.com JobId 196538: VSS Writer (BackupComplete): "Task Scheduler Writer", State: 0x1 (VSS_WS_STABLE) 123 2023-11-28 00:58:21 myclient.xxx.com JobId 196538: Cannot open "E:/SQLLogs/vtldata_log.ldf": ERR=The process cannot access the file because it is being used by another process. . 122 2023-11-28 00:58:21 myclient.xxx.com JobId 196538: Cannot open "E:/SQLLogs/VFData_log.ldf": ERR=The process cannot access the file because it is being used by another process. . 121 2023-11-28 00:58:21 myclient.xxx.com JobId 196538: Cannot open "E:/SQLLogs/SQLTestDB_log.ldf": ERR=The process cannot access the file because it is being used by another process. . 120 2023-11-28 00:58:21 myclient.xxx.com JobId 196538: Cannot open "E:/SQLLogs/rsnetdata_rd_log.ldf": ERR=The process cannot access the file because it is being used by another process. . 119 2023-11-28 00:58:21 myclient.xxx.com JobId 196538: Cannot open "E:/SQLLogs/rsnetdata.LDF": ERR=The process cannot access the file because it is being used by another process. . 118 2023-11-28 00:58:21 myclient.xxx.com JobId 196538: Cannot open "E:/SQLLogs/Ramasoft Externen/rsnetdata_vijverman.ldf": ERR=The process cannot access the file because it is being used by another process. . 117 2023-11-28 00:58:21 myclient.xxx.com JobId 196538: Cannot open "E:/SQLLogs/Ramasoft Externen/rsnetdata_stas.ldf": ERR=The process cannot access the file because it is being used by another process. . 116 2023-11-28 00:58:21 myclient.xxx.com JobId 196538: Cannot open "E:/SQLLogs/Ramasoft Externen/rsnetdata_Solmat_1.LDF": ERR=The process cannot access the file because it is being used by another process. . 115 2023-11-28 00:58:21 myclient.xxx.com JobId 196538: Cannot open "E:/SQLLogs/Ramasoft Externen/rsnetdata_Snels_log.ldf": ERR=The process cannot access the file because it is being used by another process. . 114 2023-11-28 00:58:21 myclient.xxx.com JobId 196538: Cannot open "E:/SQLLogs/Ramasoft Externen/rsnetdata_raamprof_log_new.ldf": ERR=The process cannot access the file because it is being used by another process. . 113 2023-11-28 00:58:21 myclient.xxx.com JobId 196538: Cannot open "E:/SQLLogs/Ramasoft Externen/rsnetdata_PetersLeon_1.LDF": ERR=The process cannot access the file because it is being used by another process. . 112 2023-11-28 00:58:21 myclient.xxx.com JobId 196538: Cannot open "E:/SQLLogs/Ramasoft Externen/rsnetdata_Maes_1.LDF": ERR=The process cannot access the file because it is being used by another process. . 111 2023-11-28 00:58:21 myclient.xxx.com JobId 196538: Cannot open "E:/SQLLogs/Ramasoft Externen/rsnetdata_lindebrings.ldf": ERR=The process cannot access the file because it is being used by another process. . 110 2023-11-28 00:58:21 myclient.xxx.com JobId 196538: Cannot open "E:/SQLLogs/Ramasoft Externen/rsnetdata_Jlk_1.LDF": ERR=The process cannot access the file because it is being used by another process. . 109 2023-11-28 00:58:21 myclient.xxx.com JobId 196538: Cannot open "E:/SQLLogs/Ramasoft Externen/rsnetdata_cope.ldf": ERR=The process cannot access the file because it is being used by another process. . 108 2023-11-28 00:58:21 myclient.xxx.com JobId 196538: Cannot open "E:/SQLLogs/Ramasoft Externen/rsnetdata_Cbro_log.ldf": ERR=The process cannot access the file because it is being used by another process. . 107 2023-11-28 00:58:21 myclient.xxx.com JobId 196538: Cannot open "E:/SQLLogs/Ramasoft Externen/rsnetdata_America_1.LDF": ERR=The process cannot access the file because it is being used by another process. . 106 2023-11-28 00:58:21 myclient.xxx.com JobId 196538: Cannot open "E:/SQLLogs/Ramasoft Externen/rsnetdata_Aludesign_log.ldf": ERR=The process cannot access the file because it is being used by another process. . 105 2023-11-28 00:58:21 myclient.xxx.com JobId 196538: Cannot open "E:/SQLLogs/Ramasoft Externen/rsnetdata_Alucoplast_1.LDF": ERR=The process cannot access the file because it is being used by another process. . 104 2023-11-28 00:58:21 myclient.xxx.com JobId 196538: Cannot open "E:/SQLLogs/Ramasoft Externen/Nuyts/rsnetdata_Nuyts.ldf": ERR=The process cannot access the file because it is being used by another process. . 103 2023-11-28 00:58:21 myclient.xxx.com JobId 196538: Cannot open "E:/SQLLogs/Ramasoft/rsnetdata_dealer_1.LDF": ERR=The process cannot access the file because it is being used by another process. . 102 2023-11-28 00:58:21 myclient.xxx.com JobId 196538: Cannot open "E:/SQLLogs/Planning_log.LDF": ERR=The process cannot access the file because it is being used by another process. . 101 2023-11-28 00:58:21 myclient.xxx.com JobId 196538: Cannot open "E:/SQLLogs/Planning/PlanningTest_Log": ERR=The process cannot access the file because it is being used by another process. . 100 2023-11-28 00:58:21 myclient.xxx.com JobId 196538: Cannot open "D:/SQLData/vtldata.mdf": ERR=The process cannot access the file because it is being used by another process. . 99 2023-11-28 00:58:21 myclient.xxx.com JobId 196538: Cannot open "D:/SQLData/VFData.mdf": ERR=The process cannot access the file because it is being used by another process. . 98 2023-11-28 00:58:21 myclient.xxx.com JobId 196538: Cannot open "D:/SQLData/SQLTestDB.mdf": ERR=The process cannot access the file because it is being used by another process. . 97 2023-11-28 00:58:21 myclient.xxx.com JobId 196538: Cannot open "D:/SQLData/rsnetdata_rd.mdf": ERR=The process cannot access the file because it is being used by another process. . 96 2023-11-28 00:58:21 myclient.xxx.com JobId 196538: Cannot open "D:/SQLData/rsnetdata.mdf": ERR=The process cannot access the file because it is being used by another process. . 95 2023-11-28 00:58:21 myclient.xxx.com JobId 196538: Cannot open "D:/SQLData/Ramasoft Externen/rsnetdata_vijverman.mdf": ERR=The process cannot access the file because it is being used by another process. . 94 2023-11-28 00:58:21 myclient.xxx.com JobId 196538: Cannot open "D:/SQLData/Ramasoft Externen/rsnetdata_stas.mdf": ERR=The process cannot access the file because it is being used by another process. . 93 2023-11-28 00:58:21 myclient.xxx.com JobId 196538: Cannot open "D:/SQLData/Ramasoft Externen/rsnetdata_Solmat.mdf": ERR=The process cannot access the file because it is being used by another process. . 92 2023-11-28 00:58:21 myclient.xxx.com JobId 196538: Cannot open "D:/SQLData/Ramasoft Externen/rsnetdata_Snels.mdf": ERR=The process cannot access the file because it is being used by another process. . 91 2023-11-28 00:58:21 myclient.xxx.com JobId 196538: Cannot open "D:/SQLData/Ramasoft Externen/rsnetdata_raamprof.mdf": ERR=The process cannot access the file because it is being used by another process. . 90 2023-11-28 00:58:21 myclient.xxx.com JobId 196538: Cannot open "D:/SQLData/Ramasoft Externen/rsnetdata_PetersLeon.mdf": ERR=The process cannot access the file because it is being used by another process. . 89 2023-11-28 00:58:21 myclient.xxx.com JobId 196538: Cannot open "D:/SQLData/Ramasoft Externen/rsnetdata_Maes.mdf": ERR=The process cannot access the file because it is being used by another process. . 88 2023-11-28 00:58:21 myclient.xxx.com JobId 196538: Cannot open "D:/SQLData/Ramasoft Externen/rsnetdata_lindebrings.mdf": ERR=The process cannot access the file because it is being used by another process. . 87 2023-11-28 00:58:21 myclient.xxx.com JobId 196538: Cannot open "D:/SQLData/Ramasoft Externen/rsnetdata_Jlk.mdf": ERR=The process cannot access the file because it is being used by another process. . 86 2023-11-28 00:58:21 myclient.xxx.com JobId 196538: Cannot open "D:/SQLData/Ramasoft Externen/rsnetdata_cope.mdf": ERR=The process cannot access the file because it is being used by another process. . 85 2023-11-28 00:58:21 myclient.xxx.com JobId 196538: Cannot open "D:/SQLData/Ramasoft Externen/rsnetdata_Cbro.mdf": ERR=The process cannot access the file because it is being used by another process. . 84 2023-11-28 00:58:21 myclient.xxx.com JobId 196538: Cannot open "D:/SQLData/Ramasoft Externen/rsnetdata_America.mdf": ERR=The process cannot access the file because it is being used by another process. . 83 2023-11-28 00:58:21 myclient.xxx.com JobId 196538: Cannot open "D:/SQLData/Ramasoft Externen/rsnetdata_Aludesign.mdf": ERR=The process cannot access the file because it is being used by another process. . 82 2023-11-28 00:58:21 myclient.xxx.com JobId 196538: Cannot open "D:/SQLData/Ramasoft Externen/rsnetdata_Alucoplast.mdf": ERR=The process cannot access the file because it is being used by another process. . 81 2023-11-28 00:58:21 myclient.xxx.com JobId 196538: Cannot open "D:/SQLData/Ramasoft Externen/Nuyts/rsnetdata_Nuyts.mdf": ERR=The process cannot access the file because it is being used by another process. . 80 2023-11-28 00:58:21 myclient.xxx.com JobId 196538: Cannot open "D:/SQLData/Ramasoft/rsnetdata_dealer.mdf": ERR=The process cannot access the file because it is being used by another process. . 79 2023-11-28 00:58:21 myclient.xxx.com JobId 196538: Cannot open "D:/SQLData/Planning.mdf": ERR=The process cannot access the file because it is being used by another process. . 78 2023-11-28 00:58:21 myclient.xxx.com JobId 196538: Cannot open "D:/SQLData/Planning/PlanningTest": ERR=The process cannot access the file because it is being used by another process. . 77 2023-11-28 00:58:21 myclient.xxx.com JobId 196538: Could not stat "D:/DumpStack.log.tmp": ERR=The process cannot access the file because it is being used by another process. 76 2023-11-28 00:53:07 myclient.xxx.com JobId 196538: Cannot open "C:/Windows/System32/LogFiles/Sum/Svctmp.log": ERR=The process cannot access the file because it is being used by another process. . 75 2023-11-28 00:53:07 myclient.xxx.com JobId 196538: Cannot open "C:/Windows/System32/LogFiles/Sum/Svc.log": ERR=The process cannot access the file because it is being used by another process. . 74 2023-11-28 00:53:07 myclient.xxx.com JobId 196538: Cannot open "C:/Windows/System32/LogFiles/Sum/Current.mdb": ERR=The process cannot access the file because it is being used by another process. . 73 2023-11-28 00:53:07 myclient.xxx.com JobId 196538: Cannot open "C:/Windows/System32/LogFiles/Sum/Current.jfm": ERR=The process cannot access the file because it is being used by another process. . 72 2023-11-28 00:52:43 myclient.xxx.com JobId 196538: Cannot open "C:/Windows/System32/config/systemprofile/AppData/Local/DataSharing/Storage/DSTokenDB2.jfm": ERR=The process cannot access the file because it is being used by another process. . 71 2023-11-28 00:52:43 myclient.xxx.com JobId 196538: Cannot open "C:/Windows/System32/config/systemprofile/AppData/Local/DataSharing/Storage/DSTokenDB2.dat": ERR=The process cannot access the file because it is being used by another process. . 70 2023-11-28 00:52:43 myclient.xxx.com JobId 196538: Cannot open "C:/Windows/System32/config/systemprofile/AppData/Local/DataSharing/Storage/DSS.log": ERR=The process cannot access the file because it is being used by another process. . 69 2023-11-28 00:52:43 myclient.xxx.com JobId 196538: Cannot open "C:/Windows/System32/config/SYSTEM.LOG2": ERR=The process cannot access the file because it is being used by another process. . 68 2023-11-28 00:52:43 myclient.xxx.com JobId 196538: Cannot open "C:/Windows/System32/config/SYSTEM.LOG1": ERR=The process cannot access the file because it is being used by another process. . 67 2023-11-28 00:52:43 myclient.xxx.com JobId 196538: Cannot open "C:/Windows/System32/config/SYSTEM": ERR=The process cannot access the file because it is being used by another process. . 66 2023-11-28 00:52:43 myclient.xxx.com JobId 196538: Cannot open "C:/Windows/System32/config/SOFTWARE.LOG2": ERR=The process cannot access the file because it is being used by another process. . 65 2023-11-28 00:52:43 myclient.xxx.com JobId 196538: Cannot open "C:/Windows/System32/config/SOFTWARE.LOG1": ERR=The process cannot access the file because it is being used by another process. . 64 2023-11-28 00:52:43 myclient.xxx.com JobId 196538: Cannot open "C:/Windows/System32/config/SOFTWARE": ERR=The process cannot access the file because it is being used by another process. . 63 2023-11-28 00:52:43 myclient.xxx.com JobId 196538: Cannot open "C:/Windows/System32/config/SECURITY.LOG2": ERR=The process cannot access the file because it is being used by another process. . 62 2023-11-28 00:52:43 myclient.xxx.com JobId 196538: Cannot open "C:/Windows/System32/config/SECURITY.LOG1": ERR=The process cannot access the file because it is being used by another process. . 61 2023-11-28 00:52:43 myclient.xxx.com JobId 196538: Cannot open "C:/Windows/System32/config/SECURITY": ERR=The process cannot access the file because it is being used by another process. . 60 2023-11-28 00:52:43 myclient.xxx.com JobId 196538: Cannot open "C:/Windows/System32/config/SAM": ERR=The process cannot access the file because it is being used by another process. . 59 2023-11-28 00:52:43 myclient.xxx.com JobId 196538: Cannot open "C:/Windows/System32/config/DEFAULT.LOG2": ERR=The process cannot access the file because it is being used by another process. . 58 2023-11-28 00:52:43 myclient.xxx.com JobId 196538: Cannot open "C:/Windows/System32/config/DEFAULT.LOG1": ERR=The process cannot access the file because it is being used by another process. . 57 2023-11-28 00:52:43 myclient.xxx.com JobId 196538: Cannot open "C:/Windows/System32/config/DEFAULT": ERR=The process cannot access the file because it is being used by another process. . 56 2023-11-28 00:52:42 myclient.xxx.com JobId 196538: Cannot open "C:/Windows/System32/config/BBI.LOG2": ERR=The process cannot access the file because it is being used by another process. . 55 2023-11-28 00:52:42 myclient.xxx.com JobId 196538: Cannot open "C:/Windows/System32/config/BBI.LOG1": ERR=The process cannot access the file because it is being used by another process. . 54 2023-11-28 00:52:42 myclient.xxx.com JobId 196538: Cannot open "C:/Windows/System32/config/BBI": ERR=The process cannot access the file because it is being used by another process. . 53 2023-11-28 00:52:40 myclient.xxx.com JobId 196538: Cannot open "C:/Windows/System32/catroot2/{F750E6C3-38EE-11D1-85E5-00C04FC295EE}/catdb.jfm": ERR=The process cannot access the file because it is being used by another process. . 52 2023-11-28 00:52:40 myclient.xxx.com JobId 196538: Cannot open "C:/Windows/System32/catroot2/{F750E6C3-38EE-11D1-85E5-00C04FC295EE}/catdb": ERR=The process cannot access the file because it is being used by another process. . 51 2023-11-28 00:52:40 myclient.xxx.com JobId 196538: Cannot open "C:/Windows/System32/catroot2/{127D0A1D-4EF2-11D1-8608-00C04FC295EE}/catdb.jfm": ERR=The process cannot access the file because it is being used by another process. . 50 2023-11-28 00:52:40 myclient.xxx.com JobId 196538: Cannot open "C:/Windows/System32/catroot2/{127D0A1D-4EF2-11D1-8608-00C04FC295EE}/catdb": ERR=The process cannot access the file because it is being used by another process. . 49 2023-11-28 00:52:40 myclient.xxx.com JobId 196538: Cannot open "C:/Windows/System32/catroot2/edbtmp.log": ERR=The process cannot access the file because it is being used by another process. . 48 2023-11-28 00:52:40 myclient.xxx.com JobId 196538: Cannot open "C:/Windows/System32/catroot2/edb.log": ERR=The process cannot access the file because it is being used by another process. . 47 2023-11-28 00:50:30 myclient.xxx.com JobId 196538: Cannot open "C:/Windows/ServiceProfiles/SQLTELEMETRY/ntuser.dat.LOG2": ERR=The process cannot access the file because it is being used by another process. . 46 2023-11-28 00:50:30 myclient.xxx.com JobId 196538: Cannot open "C:/Windows/ServiceProfiles/SQLTELEMETRY/ntuser.dat.LOG1": ERR=The process cannot access the file because it is being used by another process. . 45 2023-11-28 00:50:30 myclient.xxx.com JobId 196538: Cannot open "C:/Windows/ServiceProfiles/SQLTELEMETRY/NTUSER.DAT": ERR=The process cannot access the file because it is being used by another process. . 44 2023-11-28 00:50:30 myclient.xxx.com JobId 196538: Cannot open "C:/Windows/ServiceProfiles/SQLTELEMETRY/AppData/Local/Microsoft/Windows/UsrClass.dat": ERR=The process cannot access the file because it is being used by another process. . 43 2023-11-28 00:50:30 myclient.xxx.com JobId 196538: Cannot open "C:/Windows/ServiceProfiles/NetworkService/NTUSER.DAT.LOG2": ERR=The process cannot access the file because it is being used by another process. . 42 2023-11-28 00:50:30 myclient.xxx.com JobId 196538: Cannot open "C:/Windows/ServiceProfiles/NetworkService/NTUSER.DAT": ERR=The process cannot access the file because it is being used by another process. . 41 2023-11-28 00:50:30 myclient.xxx.com JobId 196538: Cannot open "C:/Windows/ServiceProfiles/LocalService/NTUSER.DAT": ERR=The process cannot access the file because it is being used by another process. . 40 2023-11-28 00:49:41 myclient.xxx.com JobId 196538: Cannot open "C:/Windows/appcompat/Programs/Amcache.hve.LOG2": ERR=The process cannot access the file because it is being used by another process. . 39 2023-11-28 00:49:41 myclient.xxx.com JobId 196538: Cannot open "C:/Windows/appcompat/Programs/Amcache.hve.LOG1": ERR=The process cannot access the file because it is being used by another process. . 38 2023-11-28 00:49:41 myclient.xxx.com JobId 196538: Cannot open "C:/Windows/appcompat/Programs/Amcache.hve": ERR=The process cannot access the file because it is being used by another process. . 37 2023-11-28 00:49:39 myclient.xxx.com JobId 196538: Cannot open "C:/Users/gsma_SQLrama$/ntuser.dat.LOG2": ERR=The process cannot access the file because it is being used by another process. . 36 2023-11-28 00:49:39 myclient.xxx.com JobId 196538: Cannot open "C:/Users/gsma_SQLrama$/NTUSER.DAT": ERR=The process cannot access the file because it is being used by another process. . 35 2023-11-28 00:49:12 myclient.xxx.com JobId 196538: Cannot open "C:/ProgramData/Microsoft/Windows Defender/Scans/MpDiag.bin": ERR=The process cannot access the file because it is being used by another process. . 34 2023-11-28 00:49:06 myclient.xxx.com JobId 196538: Cannot open "C:/ProgramData/Microsoft/Windows Defender/IMpService77BDAF73-B396-481F-9042-AD358843EC24.lock": ERR=The process cannot access the file because it is being used by another process. . 33 2023-11-28 00:49:06 myclient.xxx.com JobId 196538: Cannot open "C:/ProgramData/Microsoft/Windows Defender/IMpDlpService77BDAF73-B396-481F-9042-AD358843EC24.lock": ERR=The process cannot access the file because it is being used by another process. . 32 2023-11-28 00:48:50 myclient.xxx.com JobId 196538: Cannot open "C:/Program Files/Microsoft SQL Server/MSSQL15.MSSQLSERVER/MSSQL/DATA/templog.ldf": ERR=The process cannot access the file because it is being used by another process. . 31 2023-11-28 00:48:50 myclient.xxx.com JobId 196538: Cannot open "C:/Program Files/Microsoft SQL Server/MSSQL15.MSSQLSERVER/MSSQL/DATA/tempdb_mssql_8.ndf": ERR=The process cannot access the file because it is being used by another process. . 30 2023-11-28 00:48:50 myclient.xxx.com JobId 196538: Cannot open "C:/Program Files/Microsoft SQL Server/MSSQL15.MSSQLSERVER/MSSQL/DATA/tempdb_mssql_7.ndf": ERR=The process cannot access the file because it is being used by another process. . 29 2023-11-28 00:48:50 myclient.xxx.com JobId 196538: Cannot open "C:/Program Files/Microsoft SQL Server/MSSQL15.MSSQLSERVER/MSSQL/DATA/tempdb_mssql_6.ndf": ERR=The process cannot access the file because it is being used by another process. . 28 2023-11-28 00:48:50 myclient.xxx.com JobId 196538: Cannot open "C:/Program Files/Microsoft SQL Server/MSSQL15.MSSQLSERVER/MSSQL/DATA/tempdb_mssql_5.ndf": ERR=The process cannot access the file because it is being used by another process. . 27 2023-11-28 00:48:50 myclient.xxx.com JobId 196538: Cannot open "C:/Program Files/Microsoft SQL Server/MSSQL15.MSSQLSERVER/MSSQL/DATA/tempdb_mssql_4.ndf": ERR=The process cannot access the file because it is being used by another process. . 26 2023-11-28 00:48:50 myclient.xxx.com JobId 196538: Cannot open "C:/Program Files/Microsoft SQL Server/MSSQL15.MSSQLSERVER/MSSQL/DATA/tempdb_mssql_3.ndf": ERR=The process cannot access the file because it is being used by another process. . 25 2023-11-28 00:48:50 myclient.xxx.com JobId 196538: Cannot open "C:/Program Files/Microsoft SQL Server/MSSQL15.MSSQLSERVER/MSSQL/DATA/tempdb_mssql_2.ndf": ERR=The process cannot access the file because it is being used by another process. . 24 2023-11-28 00:48:50 myclient.xxx.com JobId 196538: Cannot open "C:/Program Files/Microsoft SQL Server/MSSQL15.MSSQLSERVER/MSSQL/DATA/tempdb.mdf": ERR=The process cannot access the file because it is being used by another process. . 23 2023-11-28 00:48:50 myclient.xxx.com JobId 196538: Cannot open "C:/Program Files/Microsoft SQL Server/MSSQL15.MSSQLSERVER/MSSQL/DATA/MSDBLog.ldf": ERR=The process cannot access the file because it is being used by another process. . 22 2023-11-28 00:48:50 myclient.xxx.com JobId 196538: Cannot open "C:/Program Files/Microsoft SQL Server/MSSQL15.MSSQLSERVER/MSSQL/DATA/MSDBData.mdf": ERR=The process cannot access the file because it is being used by another process. . 21 2023-11-28 00:48:50 myclient.xxx.com JobId 196538: Cannot open "C:/Program Files/Microsoft SQL Server/MSSQL15.MSSQLSERVER/MSSQL/DATA/modellog.ldf": ERR=The process cannot access the file because it is being used by another process. . 20 2023-11-28 00:48:50 myclient.xxx.com JobId 196538: Cannot open "C:/Program Files/Microsoft SQL Server/MSSQL15.MSSQLSERVER/MSSQL/DATA/model.mdf": ERR=The process cannot access the file because it is being used by another process. . 19 2023-11-28 00:48:50 myclient.xxx.com JobId 196538: Cannot open "C:/Program Files/Microsoft SQL Server/MSSQL15.MSSQLSERVER/MSSQL/DATA/mastlog.ldf": ERR=The process cannot access the file because it is being used by another process. . 18 2023-11-28 00:48:50 myclient.xxx.com JobId 196538: Cannot open "C:/Program Files/Microsoft SQL Server/MSSQL15.MSSQLSERVER/MSSQL/DATA/master.mdf": ERR=The process cannot access the file because it is being used by another process. . 17 2023-11-28 00:48:42 myclient.xxx.com JobId 196538: VolumeMountpoints are not processed as onefs = yes. 16 2023-11-28 00:48:42 myclient.xxx.com JobId 196538: VolumeMountpoints are not processed as onefs = yes. 15 2023-11-28 00:48:42 myclient.xxx.com JobId 196538: VolumeMountpoints are not processed as onefs = yes. 14 2023-11-28 00:48:41 myclient.xxx.com JobId 196538: Generate VSS snapshots. Driver="Win64 VSS", Drive(s)="CDE" 13 2023-11-28 00:48:39 yyy-sd JobId 196538: Ready to append to end of Volume "vol-incr-0648" size=41724904576 12 2023-11-28 00:48:39 yyy-sd JobId 196538: Volume "vol-incr-0648" previously written, moving to end of data. 11 2023-11-28 00:48:39 myclient.xxx.com JobId 196538: Encryption: TLS_CHACHA20_POLY1305_SHA256 TLSv1.3 10 2023-11-28 00:48:39 myclient.xxx.com JobId 196538: Connected Storage daemon at yyy.hosted-power.com:9103, encryption: TLS_CHACHA20_POLY1305_SHA256 TLSv1.3 9 2023-11-28 00:48:22 myclient.xxx.com JobId 196538: Created 18 wildcard excludes from FilesNotToBackup Registry key 8 2023-11-28 00:48:39 yyy-dir JobId 196538: Using Device "AI-Incremental0005" to write. 7 2023-11-28 00:48:22 yyy-dir JobId 196538: Sending Accurate information. 6 2023-11-28 00:48:21 yyy-dir JobId 196538: Encryption: TLS_CHACHA20_POLY1305_SHA256 TLSv1.3 5 2023-11-28 00:48:21 yyy-dir JobId 196538: Handshake: Immediate TLS 4 2023-11-28 00:48:21 yyy-dir JobId 196538: Using Client Initiated Connection (myclient.xxx.com). 3 2023-11-28 00:48:21 yyy-dir JobId 196538: Encryption: TLS_CHACHA20_POLY1305_SHA256 TLSv1.3 2 2023-11-28 00:48:21 yyy-dir JobId 196538: Connected Storage daemon at yyy.hosted-power.com:9103, encryption: TLS_CHACHA20_POLY1305_SHA256 TLSv1.3 1 2023-11-28 00:48:21 yyy-dir JobId 196538: Start Backup JobId 196538, Job=myclient.xxx.com-files.2023-11-27_23.05.02_37 |
|
found here some link, not sure how relevant: https://www.veritas.com/support/en_US/article.100044497 , which show the files are listed under the SqlServerWriter. Normally the MDF files can be restored correctly, more info here: https://learn.microsoft.com/en-us/sql/relational-databases/backup-restore/sql-server-vss-writer-backup-guide?view=sql-server-ver16 |
|
More info was posted here: https://groups.google.com/g/bareos-users/c/F46rRPh7Hf8 Seems to be a Bareos issue, I think the findings are potentially interesting |
|
The initial root cause describe here should have already been fixed in 23 release. The code change https://github.com/bareos/bareos/pull/1452 Except if you are hit my a different issue. |
|
Hi, I upgraded our whole installation to version 23, only to find out it has exactly (100% percent) the same problems. Also there was a reply on the google thread, specifying the tests and debugging were done already with v23 ;) | |
Maybe it's conincidence, but the first tests even have 3x more non backuped files than before. I checked another one, having also lots more warnings. Host was having between 40 and 50 quite consistently, now it has 127. |
|
Ok final result, it seems at first sight the errors are more or less the same. Probably the recent logging on to the tested servers created even more unbackupable files when testing last night. But in any case, this is not solved. I think the info on the google thread is most likely still relevant and interesting :) |
|
All our testing report success, so we don't have any clue about what happening in your case. Please check the ML there's instruction to have a trace, joblog and the fileset used. I'm attaching here an illustration to the fact that bareos works as documented and expected. We run a backup job while the registry hive is open in regedit and we tried to remove it so you get the expected error, the file is in use. In the background you can see the success of the job backing up that file without any error. See the details about the status of VSS BackupComplete. |
|
Which Windows OS version do you have? We're using Windows 2022. I tried the https://download.bareos.org/current/windows/winbareos-23.0.1~pre57.8e89bfe0a-release-64-bit.exe , but I have 100% the same errors. |
|
Please, may I ask you to either provided the trace and job log as requested. As I can argue that we are using 2019 to 2022 with 0% of your troubles That kind of guerilla can be endless. If we have traces, joblog fileset etc we may have a chance to reproduce. If it is reproducible, then there's a chance to have a fix. If we can elaborate that fix, you may have a chance to solve your trouble. |
|
Hello Bruno, with pleasure, here you can find both logs/trace (valid 7 days): https://we.tl/t-A0WwzxrCBS | |
Thanks for the share. do you mind to share the fileset used during that job ? | |
FileSet { Name = "windows-files" Enable VSS = yes Include { Options { signature = XXH128 compression = GZIP3 Drive Type = fixed IgnoreCase = yes Exclude = yes # Exclude junction points WildDir = "[A-Z]:/Dokumente und Einstellungen" WildDir = "[A-Z]:/Documents and Settings" WildDir = "[A-Z]:/Program Files/Gemeinsame Dateien" WildDir = "[A-Z]:/Program Files/Windows NT/Zubehör" WildDir = "[A-Z]:/ProgramData/Anwendungsdaten" WildDir = "[A-Z]:/ProgramData/Application Data" WildDir = "[A-Z]:/ProgramData/Desktop" WildDir = "[A-Z]:/ProgramData/Documents" WildDir = "[A-Z]:/ProgramData/Dokumente" WildDir = "[A-Z]:/ProgramData/Favoriten" WildDir = "[A-Z]:/ProgramData/Favorites" WildDir = "[A-Z]:/ProgramData/Microsoft/Windows/Start Menu/Programme" WildDir = "[A-Z]:/ProgramData/Start Menu" WildDir = "[A-Z]:/ProgramData/Startmenü" WildDir = "[A-Z]:/ProgramData/Templates" WildDir = "[A-Z]:/ProgramData/Vorlagen" WildDir = "[A-Z]:/Programme" WildDir = "[A-Z]:/Documents and Settings" WildDir = "[A-Z]:/Users/All Users" WildDir = "[A-Z]:/Users/Default User" WildDir = "[A-Z]:/Users/*/AppData/Local/Application Data" WildDir = "[A-Z]:/Users/*/AppData/Local/Anwendungsdaten" WildDir = "[A-Z]:/Users/*/AppData/Local/History" WildDir = "[A-Z]:/Users/*/AppData/Local/Verlauf" WildDir = "[A-Z]:/Users/*/AppData/Local/Temporary Internet Files" WildDir = "[A-Z]:/Users/*/Application Data" WildDir = "[A-Z]:/Users/*/Anwendungsdaten" WildDir = "[A-Z]:/Users/*/Gemeinsame Dateien" WildDir = "[A-Z]:/Users/*/Cookies" WildDir = "[A-Z]:/Users/*/Documents/My Music" WildDir = "[A-Z]:/Users/*/Documents/Eigene Musik" WildDir = "[A-Z]:/Users/*/Documents/My Pictures" WildDir = "[A-Z]:/Users/*/Documents/Eigene Bilder" WildDir = "[A-Z]:/Users/*/Documents/My Videos" WildDir = "[A-Z]:/Users/*/Documents/Eigene Videos" WildDir = "[A-Z]:/Users/*/Local Settings" WildDir = "[A-Z]:/Users/*/Lokale Einstellungen" WildDir = "[A-Z]:/Users/*/My Documents" WildDir = "[A-Z]:/Users/*/Eigene Dateien" WildDir = "[A-Z]:/Users/*/NetHood" WildDir = "[A-Z]:/Users/*/Netzwerkumgebung" WildDir = "[A-Z]:/Users/*/PrintHood" WildDir = "[A-Z]:/Users/*/Druckumgebung" WildDir = "[A-Z]:/Users/*/Recent" WildDir = "[A-Z]:/Users/*/SendTo" WildDir = "[A-Z]:/Users/*/Start Menu" WildDir = "[A-Z]:/Users/*/Startmenü" WildDir = "[A-Z]:/Users/*/Templates" WildDir = "[A-Z]:/Users/*/Vorlagen" # Temporary directories & files WildDir = "[A-Z]:/Windows/Temp" WildDir = "[A-Z]:/Users/*/AppData/Local/Mozilla/Firefox/Profiles/*/Cache" # Recycle bins WildDir = "[A-Z]:/RECYCLER" WildDir = "[A-Z]:/$RECYCLE.BIN" # System restores WildDir = "[A-Z]:/System Volume Information" # Swap files WildFile = "[A-Z]:/pagefile.sys" WildFile = "[A-Z]:/hiberfil.sys" } ExcludeDirContaining = "bkp.exclude" File = "/" #File = "\\<C:/bkpinclude.txt" } IgnoreFileSetChanges = yes # If we change the fileset then don't force a full backup next time. } |
|
It seems to be solved with the test build posted on google groups, does it mean it will be included in Bareos 23.0.1 (or similar)? | |
It will be included in next release so mainly for 23.0.2 (23.0.1 has been released last week), and if it is not in the next it will be in the following. | |
OK thx, we really look forward to release 23.0.2 then, it's a pity now, if we install the built preview, the version number that is saved in registry of the client is 24.x preview, this makes it unlikely an upgrade would be attempted when checking version numbers when 23.0.2 comes out :) | |
our repo next is where the change occurs in master branch of github, so actually "next" is 24.0.0~pre. When the code will be backported mean when PR1666 will be merged https://github.com/bareos/bareos/pull/1666 into bareos-23 a rebuild of the "current" repository will happen, and then bareos current will be tagged 23.0.2~pre Of course subscription packages are maybe more easy to undestand :-) |
|
PR have been merged. | |
Date Modified | Username | Field | Change |
---|---|---|---|
2023-04-27 10:22 | hostedpower | New Issue | |
2023-04-28 16:21 | hostedpower | Note Added: 0004993 | |
2023-05-03 15:39 | bruno-at-bareos | Note Added: 0004997 | |
2023-09-13 11:58 | bruno-at-bareos | Tag Attached: Windows | |
2023-09-13 11:58 | bruno-at-bareos | Tag Attached: VSS | |
2023-09-13 11:58 | bruno-at-bareos | Tag Attached: mssql | |
2023-11-27 22:16 | hostedpower | Note Added: 0005536 | |
2023-11-28 08:10 | hostedpower | Note Added: 0005537 | |
2023-11-28 08:10 | hostedpower | File Added: backup-fails.txt | |
2023-11-28 08:51 | hostedpower | Note Added: 0005538 | |
2024-01-12 08:55 | hostedpower | Note Added: 0005682 | |
2024-01-16 15:24 | bruno-at-bareos | Assigned To | => bruno-at-bareos |
2024-01-16 15:24 | bruno-at-bareos | Status | new => closed |
2024-01-16 15:24 | bruno-at-bareos | Resolution | open => fixed |
2024-01-16 15:24 | bruno-at-bareos | Note Added: 0005688 | |
2024-01-16 22:29 | hostedpower | Status | closed => new |
2024-01-16 22:29 | hostedpower | Resolution | fixed => reopened |
2024-01-16 22:29 | hostedpower | Note Added: 0005690 | |
2024-01-16 22:32 | hostedpower | Note Added: 0005691 | |
2024-01-16 22:38 | hostedpower | Note Edited: 0005691 | |
2024-01-17 09:32 | hostedpower | Note Added: 0005692 | |
2024-01-17 09:39 | bruno-at-bareos | Note Added: 0005693 | |
2024-01-17 09:39 | bruno-at-bareos | File Added: image.png | |
2024-01-17 09:56 | hostedpower | Note Added: 0005694 | |
2024-01-17 10:11 | bruno-at-bareos | Note Added: 0005695 | |
2024-01-17 14:42 | hostedpower | Note Added: 0005696 | |
2024-01-17 15:41 | bruno-at-bareos | Note Added: 0005697 | |
2024-01-17 15:53 | hostedpower | Note Added: 0005698 | |
2024-01-23 09:16 | hostedpower | Note Added: 0005709 | |
2024-01-23 09:26 | bruno-at-bareos | Note Added: 0005710 | |
2024-01-23 16:44 | hostedpower | Note Added: 0005713 | |
2024-01-23 16:59 | bruno-at-bareos | Note Added: 0005714 | |
2024-01-30 10:42 | bruno-at-bareos | Status | new => closed |
2024-01-30 10:42 | bruno-at-bareos | Note Added: 0005724 |