Skip to main content

Backup failed, then went through

Thread solved

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

 

 

 

0 Users found this helpful
frestogaslorastaswastavewroviwroclolacorashibushurutraciwrubrishabenichikucrijorejenufrilomuwrigaslowrikejawrachosleratiswurelaseriprouobrunoviswosuthitribrepakotritopislivadrauibretisetewrapenuwrapi
Posts: 0
Comments: 488

Dear Peter,

Thank you for reaching out. We would recommend to contact our support with this problem Customer Service and Support (acronis.com)
Make sure ftps and sftp are turned off as we officially do no support them in Acronis Cyber Protect Home Office.

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

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