Backup failed, then went through
Hello all,
I have a new license of Acronis and I am also using Acronis for the first time.
My "home Server" with Windows 11 is online 24/7 and makes a backup at 3am.
However it always seems to fail in the first time, then tries again a few minutes later and it goest through without errors. Any idea what the reason could be?
2022-08-09T03:00:23:506+02:00 6908 I00000000: -----
2022-08-09T03:00:23:506+02:00 6908 I00000000: Acronis Cyber Protect Home Office Demon started. Version: 26.3.1.39900.
2022-08-09T03:00:23:599+02:00 6908 I00640000: Attribute für Backup-Reservekopie: format tib; need_reserve_backup_copy false;
2022-08-09T03:00:23:599+02:00 6908 I00640002: Aktion User1 wurde per Planung gestartet.
2022-08-09T03:00:23:631+02:00 6908 I00640000: Attribute für Backup-Reservekopie: format tib; need_reserve_backup_copy false;
2022-08-09T03:00:23:631+02:00 6908 I013C0000: Aktion: Backup
2022-08-09T03:00:23:631+02:00 6908 I0064000B: Priorität geändert in 'Niedrig'
2022-08-09T03:00:27:604+02:00 6908 I000B03F0: Backup-Archiv erstellen Kennwortgeschützt Von: In Datei: "ftp://xxxxxx.xxxxxx.de:21/user1.tib" Komprimierung: Normal Exclude: Durch Ausschlussmaske erfasste Dateien Passendes Kriterium: hiberfil.sys, pagefile.sys, $Recycle.Bin, swapfile.sys, System Volume Information, *.tib, *.tibx, *.tib.metadata, *.~, *.tmp, C:\Users\user1\AppData\Local\Temp\*, C:\Users\user1\AppData\Local\Microsoft\Windows\INetCache\*, C:\Users\user1\AppData\Local\Mozilla\Firefox\Profiles\*\cache2, C:\Users\user1\AppData\Local\Mozilla\Firefox\Profiles\*\OfflineCache, C:\Users\user1\AppData\Local\Opera Software\Opera Stable\Cache, C:\Users\user1\AppData\Local\Opera Software\Opera Stable\Media Cache, C:\Users\user1\AppData\Local\Google\Chrome\User Data\Default\Cache, C:\Windows\CSC
2022-08-09T03:00:31:952+02:00 6908 I00040001: Error 0x40001: Fehler beim Lesen der Datei.
| Ablaufverfolgungsebene: Information
| line: 0x1d8eab676a3f6923
| Datei: c:\jenkins_agent\workspace\ati-main-win-ati\680\products\imager\archive\impl\operations\archive_message_callback.cpp:762
| Funktion: TrueImage::Archive::ArchiveMsgReadCallbackProcessor::ProcessReadCallback
| line: 0x1d8eab676a3f6923, c:\jenkins_agent\workspace\ati-main-win-ati\680\products\imager\archive\impl\operations\archive_message_callback.cpp:762, TrueImage::Archive::ArchiveMsgReadCallbackProcessor::ProcessReadCallback
| Path:
| $module: ti_demon_vs_39900
|
| error 0x40001: Fehler beim Lesen der Datei.
| line: 0xdda2c8557334c401
| Datei: c:\jenkins_agent\workspace\ati-main-win-ati\680\core\network\ftp\ftp_ifile.cpp:120
| Funktion: FTPIFile::Read
| line: 0xdda2c8557334c401, c:\jenkins_agent\workspace\ati-main-win-ati\680\core\network\ftp\ftp_ifile.cpp:120, FTPIFile::Read
| $module: ti_demon_vs_39900
2022-08-09T03:00:31:952+02:00 6908 E000103F1: Die Aktion kann im stillen Modus nicht ausgeführt werden. (0x103F1)
$module = "ti_demon_vs_39900"
TrueImage::Archive::ArchiveMsgReadCallbackProcessor::ProcessReadCallback:
c:\jenkins_agent\workspace\ati-main-win-ati\680\products\imager\archive\impl\operations\archive_message_callback.cpp(935)
Fehler beim Lesen der Datei. (0x40001)
$module = "ti_demon_vs_39900"
TrueImage::Archive::ArchiveMsgReadCallbackProcessor::ProcessReadCallback:
c:\jenkins_agent\workspace\ati-main-win-ati\680\products\imager\archive\impl\operations\archive_message_callback.cpp(936)
2022-08-09T03:00:31:953+02:00 6908 E000101F6: Error 0x101f6: Aktion wurde abgebrochen.
| Ablaufverfolgungsebene: Fehler
| line: 0x65b5eb7011094703
| Datei: c:\jenkins_agent\workspace\ati-main-win-ati\680\processor\diskadm\da_commit.cpp:348
| Funktion: DaProcessor::CommitImpl::OnDaError
| line: 0x65b5eb7011094703, c:\jenkins_agent\workspace\ati-main-win-ati\680\processor\diskadm\da_commit.cpp:348, DaProcessor::CommitImpl::OnDaError
| $module: ti_demon_vs_39900
|
| error 0x70022: Aktion wurde abgebrochen.
| line: 0x29c4f0bddb125114
| Datei: c:\jenkins_agent\workspace\ati-main-win-ati\680\core\resizer\backup\chunk_maps.cpp:39
| Funktion: resizer::SequentialChunkMap::SequentialChunkMap
| line: 0x29c4f0bddb125114, c:\jenkins_agent\workspace\ati-main-win-ati\680\core\resizer\backup\chunk_maps.cpp:39, resizer::SequentialChunkMap::SequentialChunkMap
| $module: ti_demon_vs_39900
|
| error 0x4000f: Abgebrochen.
| line: 0x1d8eab676a3f69d4
| Datei: c:\jenkins_agent\workspace\ati-main-win-ati\680\products\imager\archive\impl\operations\archive_message_callback.cpp:939
| Funktion: TrueImage::Archive::ArchiveMsgReadCallbackProcessor::ProcessReadCallback
| line: 0x1d8eab676a3f69d4, c:\jenkins_agent\workspace\ati-main-win-ati\680\products\imager\archive\impl\operations\archive_message_callback.cpp:939, TrueImage::Archive::ArchiveMsgReadCallbackProcessor::ProcessReadCallback
| $module: ti_demon_vs_39900
2022-08-09T03:00:31:999+02:00 6908 E013C0005: Error 0x13c0005: Aktion mit Fehlern beendet.
| Ablaufverfolgungsebene: Fehler
| line: 0x9f2c53c72e8bcedb
| Datei: c:\jenkins_agent\workspace\ati-main-win-ati\680\products\imager\demon\main.cpp:741
| Funktion: main
| line: 0x9f2c53c72e8bcedb, c:\jenkins_agent\workspace\ati-main-win-ati\680\products\imager\demon\main.cpp:741, main
| $module: ti_demon_vs_39900
And this is the log of the successful run:
2022-08-09T03:01:09:689+02:00 7236 I00000000: -----
2022-08-09T03:01:09:689+02:00 7236 I00000000: Acronis Cyber Protect Home Office Demon started. Version: 26.3.1.39900.
2022-08-09T03:01:09:723+02:00 7236 I00640000: Attribute für Backup-Reservekopie: format tib; need_reserve_backup_copy false;
2022-08-09T03:01:09:723+02:00 7236 I00640002: Aktion user1 wurde per Planung gestartet.
2022-08-09T03:01:09:758+02:00 7236 I00640000: Attribute für Backup-Reservekopie: format tib; need_reserve_backup_copy false;
2022-08-09T03:01:09:758+02:00 7236 I013C0000: Aktion: Backup
2022-08-09T03:01:09:758+02:00 7236 I0064000B: Priorität geändert in 'Niedrig'
2022-08-09T03:01:13:206+02:00 7236 I000B03F0: Backup-Archiv erstellen Kennwortgeschützt Von: In Datei: "ftp://xxxxxxxxx.de:21/user1.tib" Komprimierung: Normal Exclude: Durch Ausschlussmaske erfasste Dateien Passendes Kriterium: hiberfil.sys, pagefile.sys, $Recycle.Bin, swapfile.sys, System Volume Information, *.tib, *.tibx, *.tib.metadata, *.~, *.tmp, C:\Users\user1\AppData\Local\Temp\*, C:\Users\user1\AppData\Local\Microsoft\Windows\INetCache\*, C:\Users\user1\AppData\Local\Mozilla\Firefox\Profiles\*\cache2, C:\Users\user1\AppData\Local\Mozilla\Firefox\Profiles\*\OfflineCache, C:\Users\user1\AppData\Local\Opera Software\Opera Stable\Cache, C:\Users\user1\AppData\Local\Opera Software\Opera Stable\Media Cache, C:\Users\user1\AppData\Local\Google\Chrome\User Data\Default\Cache, C:\Windows\CSC
2022-08-09T03:01:18:034+02:00 7236 I000101F8: Ausstehende Aktion 175 hat begonnen: 'Volume-Struktur sichern'.
2022-08-09T03:01:18:037+02:00 7236 I000101F8: Ausstehende Aktion 178 hat begonnen: 'Volume-Image erstellen'.
2022-08-09T03:01:18:114+02:00 7236 I00100000: Volume Tracker konnte keine Änderungen auf dem Volume '\\?\Volume{a7367229-4f89-414c-ae0e-46fd37720560}\' finden.
2022-08-09T03:01:18:584+02:00 1508 I00640000: Schreibe inkrementelle Version zu Datei: user1_inc_b4_s2_v1.tib
2022-08-09T03:01:19:592+02:00 7236 I000101F8: Ausstehende Aktion 178 hat begonnen: 'Volume-Image erstellen'.
2022-08-09T03:02:42:533+02:00 7236 I00100000: Volume Tracker konnte keine Änderungen auf dem Volume 'C:' finden.
2022-08-09T03:08:34:357+02:00 4672 I00640000: Schreibe inkrementelle Version zu Datei: user1_inc_b4_s2_v2.tib
2022-08-09T03:10:01:063+02:00 7236 I000101F8: Ausstehende Aktion 178 hat begonnen: 'Volume-Image erstellen'.
2022-08-09T03:10:01:135+02:00 7236 I00100000: Volume Tracker konnte keine Änderungen auf dem Volume '\\?\Volume{ae97da85-ac47-46fc-b449-08ee4a2a1c32}\' finden.
2022-08-09T03:10:01:725+02:00 7236 I00640000: Die folgenden Backups wurden erfolgreich erstellt: "xxxxxxxxxxxxxxx.de:21/user1_inc_b4_s2_v1.tib"
"fxxxxxxxxxxxxxx.de:21/user1_inc_b4_s2_v2.tib"
2022-08-09T03:10:07:038+02:00 7236 I013C0006: Aktion erfolgreich.
Would be thankful for any hints.
Regards

- Log in to post comments

Peter, welcome to these public User Forums.
The initial log shows a failure to reach and read from the destination FTP backup archive file which suggests that perhaps the process needs more time before that remote system is ready?
I would suggest trying a Pre Command for the task to ping the remote server and ensure it is 'woken up' before continuing with the backup task. I use a Pre Command to check my NAS is available before trying to backup to it (to avoid the backup running when I am not at home!).
:: File Name = CheckIP.Bat
@echo off
:: ----------------------------------------------------------
:: ----- Set baseIP to the IP address to be checked -----
:: ----------------------------------------------------------set baseIP=192.168.0.29
set loopcount=20:: Checking for active baseIP address on network
:: errorlevel 0 = IP found / 1 = IP not found.:loop
echo pinging %baseIP% for %loopcount% times
ping -n 1 -l 1 %baseIP% > nul
if %errorlevel%==1 goto next
@echo %baseIP% found active on network!
timeout /t 10
exit /b 0
:next
@echo %baseIP% not found active on network!
set /a loopcount=loopcount-1
if %loopcount%==0 goto exitloop
timeout /t 10
goto loop
:exitloop
if %errorlevel%==1 exit /b 1
exit /b 0
- Log in to post comments

Steve,
thank you so much for that idea, i was able to recreate the issue and adding a simple ping did the job.
Acronis Support wasn't able to help me, but you were. Amazing!
Kind regards,
Peter
- Log in to post comments