Skip to main content

"The backup cannot be started" error at night, by scheduler, but works OK when started manualy

Thread solved

Hello!

Almost every day I see this error in Activity (see attached screenshot): "The backup cannot be started. Ensure that destination 'F:\' is accessible and try again.", I give it a manual backup and it works. It's strange that sometimes it went well for a week at night too. I have not identified the reason. F: is a USB connected 1GB external SSD.
I can send the logs, can someone help me identify the problem and correct it?

I looked in the log with ReadLogFileNewGen.exe and I see intense activity in C:\ProgramData\Acronis\TrueImageHome\Logs\afcdpsrv.log even when I thought the computer was closed. It's normal?

Thank you,
Bogdan

Attachment Size
Activity.png 234.2 KB
0 Users found this helpful

Bogdan, welcome to these public User Forums.

The error message suggests that you might have 'USB Selective suspend' enabled in the Power Options for your PC and this is allowing the USB external SSD to go into sleep or a hibernation state.

Try changing that Power option.

An alternative option is to create a small CheckDrive.BAT file as below and use this as a Pre Command for your backup task (Options > Advanced > Pre Post Commands - Edit then select the .bat file from where you have stored it.).

REM CheckDrive.BAT

echo off
set desktop=%USERPROFILE%\Desktop\USB_Drive.txt
set usbhdd="F:"
set loopcount=5
if not exist %usbhdd% goto loop

:exitloop
if not exist %usbhdd% goto nodrive
dir %usbhdd% >> %desktop%
echo "Drive found!" >> %desktop%
exit /b 0

:nodrive
echo "Drive not found!" >> %desktop%
exit /b 1

:loop
set /a loopcount=loopcount-1
if %loopcount%==0 goto exitloop
dir %usbhdd% >> %desktop%
timeout /t 5
goto loop

Hi Steve,

I don't have any USB option in Power Options. I searched and see that others have this problem also.

But CheckDrive.BAT worked, which I modified to display more spaced and with the date and time, for later troubleshooting:

:exitloop
if not exist %usbhdd% goto nodrive
dir %usbhdd% >> %log%
echo.  >> %log%
echo %DATE% %TIME% Drive found! >> %log%
echo.  >> %log%
echo ------------------- >> %log%
echo.  >> %log%
exit /b 0

:nodrive
echo.  >> %log%
echo %DATE% %TIME% Drive not found! >> %log%
echo.  >> %log%
echo ------------------- >> %log%
echo.  >> %log%
exit /b 1

 

Thank you very much!
Bogdan

frestogaslorastaswastavewroviwroclolacorashibushurutraciwrubrishabenichikucrijorejenufrilomuwrigaslowrikejawrachosleratiswurelaseriprouobrunoviswosuthitribrepakotritopislivadrauibretisetewrapenuwrapi
Posts: 2
Comments: 1727

Bogdan Dragomir wrote:

Hi Steve,

I don't have any USB option in Power Options. I searched and see that others have this problem also.

But CheckDrive.BAT worked, which I modified to display more spaced and with the date and time, for later troubleshooting:

:exitloop
if not exist %usbhdd% goto nodrive
dir %usbhdd% >> %log%
echo.  >> %log%
echo %DATE% %TIME% Drive found! >> %log%
echo.  >> %log%
echo ------------------- >> %log%
echo.  >> %log%
exit /b 0

:nodrive
echo.  >> %log%
echo %DATE% %TIME% Drive not found! >> %log%
echo.  >> %log%
echo ------------------- >> %log%
echo.  >> %log%
exit /b 1

 

Thank you very much!
Bogdan

Hello!

Thanks for updating the thread.

I am glad the issue is solved. And thank you, Steve, for being so proactive and accurate in helping the users!

Best regards.

Hello,

Things worked correctly until September 28, since then the backup no longer starts at the scheduled time.

This is the BAT log, it can be seen that it does not even try at night at 5:30, when it is scheduled:

-------------------
 
 Volume in drive F is bkpssd1t
 Volume Serial Number is 22C4-572C

 Directory of F:\

23/09/2023  05:55   199,102,001,152 BOGDAN-HP2022-0012.tibx
01/10/2023  21:12   206,012,485,632 BOGDAN-HP2022-0013.tibx
05/10/2023  13:26   184,855,281,664 BOGDAN-HP2022-0014.tibx
07/07/2023  06:28            12,288 BOGDAN-HP2022.tibx
               4 File(s) 589,969,780,736 bytes
               0 Dir(s)  407,995,650,048 bytes free
 
06/10/2023  9:38:51.70 Drive found!
 
-------------------
 
 Volume in drive F is bkpssd1t
 Volume Serial Number is 22C4-572C

 Directory of F:\

23/09/2023  05:55   199,102,001,152 BOGDAN-HP2022-0012.tibx
01/10/2023  21:12   206,012,485,632 BOGDAN-HP2022-0013.tibx
06/10/2023  09:44   186,968,379,392 BOGDAN-HP2022-0014.tibx
07/07/2023  06:28            12,288 BOGDAN-HP2022.tibx
               4 File(s) 592,082,878,464 bytes
               0 Dir(s)  405,848,231,936 bytes free
 
07/10/2023 11:01:15.98 Drive found!
 
-------------------
 
 Volume in drive F is bkpssd1t
 Volume Serial Number is 22C4-572C

 Directory of F:\

23/09/2023  05:55   199,102,001,152 BOGDAN-HP2022-0012.tibx
01/10/2023  21:12   206,012,485,632 BOGDAN-HP2022-0013.tibx
07/10/2023  11:07   188,723,572,736 BOGDAN-HP2022-0014.tibx
07/07/2023  06:28            12,288 BOGDAN-HP2022.tibx
               4 File(s) 593,838,071,808 bytes
               0 Dir(s)  404,105,760,768 bytes free
 
08/10/2023 12:39:05.02 Drive found!
 
-------------------

 

I'm attaching screenshots from the options window (where I ticked resume unexecuted operations when the external disk is attached, assuming that's the problem) and with the execution history from the last few days and from the time it started to stop working.

 

I tried to attach PNG captures (using Firefox), then a ZIP archive with them, but this message appears "The file could not be uploaded". What's wrong?

Then I uploaded them to Google Drive but this forum does not permit links. Maybe will accept this broken link (please remove spaces):

drive. google. com/drive/folders/1GhnHIOHYxVW3thvK9FBt5ofFXs3rIq8C?usp=sharing

 

What do you advise me to do?

 

Thank you!
Bogdan

Bogdan, unfortunately the images only tell a part of the story here and not why the backup didn't run on schedule.

I would suggest creating an Acronis System Report and putting this on your Google Drive then post another 'broken' link to the zip file so that I can take a look at the various logs that should offer more clues about this issue.

Steve, I have just uploaded the zip archive with Acronis System Report to the same 'broken' link. Please check.

Thank you very much!

Bogdan, a couple years ago I had a very similar issue. It started with ATI 2019 and affected two machines. I see you are running ATI 2021 so maybe the scheduler hasn't changed much.

In my case only disk/partition backups failed when starting from schedule but never failed when started manually. Once the failure occurred, it would continue to fail. I spent three months trying to do everything I could think of. I even went so far as starting completely from scratch by getting rid of the data base and reinstalling ATI. Every time I thought I had it whipped, the failure would return. I was trying to backup to my NAS and I would find that Acronis would always be able to access the NAS OK.

After three months I gave up, convinced that there is a bug in the Acronis software which introduces something into their database which then makes the scheduler invoke the task in such a way as it will fail.

My solution was to create a task in the Windows Task Scheduler which runs the backup. It has been working perfectly since then.

Here you can read my summary posting...

https://forum.acronis.com/forum/acronis-true-image-2019-forum/mysterious-write-error-0x1e50023-full-backupmy-solution

 

Bruno, thank you for the idea and the constructive message!
I'll apply it, unless Steve has a better idea. I'm curious what he will find in the huge logs I sent him.

It's interesting that today I see in Activity that at 6:08 a.m. ATI made a Full backup, as if he was ashamed of the mistakes made so far :)

It's the first time it's backed up at the correct time, for almost 2 weeks and I didn't change anything yesterday.

Bogdan, thanks for the system report zip file - I will get back to you later once I have had opportunity to take a look at it.

The system report shows (from the Schedule2 log) up to 4th October, that the backup task 1-6 is being tried at 05:30 each morning, is delayed and tried again at 05:50 and then has been shown as failed with an error code = 1 on most days but no further mention on this day!

04/10/2023 05:30:03:239 AM 00002058 Trying task 1-6 as TIME_NORMAL
04/10/2023 05:30:03:239 AM 00002058 Init delay

04/10/2023 05:50:15:346 AM 00002604 Running task 1-6
04/10/2023 05:50:15:346 AM 000071D8 API Reply: OP=WAIT_CHANGES_EX2 Ver=4 Status=0 Task=1-6 Result=3(RUN_WAITED) Extra=9(TIME_DELAYED)
04/10/2023 05:50:15:390 AM 00002604 LogonUser*** domain="BOGDAN-HP2022" user*** > error=0
04/10/2023 05:50:15:390 AM 00002604 NewSecurityDescriptor: > error=0
04/10/2023 05:50:15:390 AM 00002604 SetTokenInformation: > error=0
04/10/2023 05:50:15:390 AM 00002604 Scheduler user *** account found
04/10/2023 05:50:15:390 AM 00002604 Session found
04/10/2023 05:50:15:392 AM 00002604 DuplicateTokenEx: < token=00000748 > error=0 token=00000784
04/10/2023 05:50:15:396 AM 00002604 CreateEnvironmentBlock: > error=0
04/10/2023 05:50:15:396 AM 00002604 GetVistaLinkedToken: < token=00000784 > error=0 token=000006EC
04/10/2023 05:50:15:425 AM 00002604 CreateProcessAsUser*** token=000006EC > error=0
04/10/2023 05:50:15:425 AM 00002604 Prevented from sleep: on
04/10/2023 05:50:15:425 AM 00002604 Stored token 00000000 replaced with 00000000

04/10/2023 05:50:15:438 AM 00007DB4 API Reply: OP=WAIT_CHANGES_EX3 Ver=4 Status=0 Task=1-6 Result=3(RUN_WAITED) Extra=9(TIME_DELAYED)
04/10/2023 05:50:15:438 AM 00000734 API Reply: OP=WAIT_CHANGES_EX2 Ver=4 Status=0 Task=1-6 Result=3(RUN_WAITED) Extra=9(TIME_DELAYED)
04/10/2023 05:50:15:466 AM 00003F04 API Reply: OP=WAIT_CHANGES_EX2 Ver=4 Status=0 Task=1-6 Result=3(RUN_WAITED) Extra=9(TIME_DELAYED)

04/10/2023 05:50:15:475 AM 00003F04 API Reply: OP=WAIT_CHANGES_EX2 Ver=4 Status=0 Task=1-6 Result=8(RUN_STARTED) Extra=0

My suggestion here would be to download the Acronis Scheduler Manager tool and run this to produce a black command window, then issue a 'task zap' command (without quotes) which will clean out all current scheduler entries.  Then go back to your HP2022 task and set the schedule again and see if that clears up this issue?

You can also use the MVP Assistant to download the Scheduler Manager (from Settings), look at all the Acronis tasks (Acronis Configuration) and run Scheduler Manager operations to disable/enable scheduling for the specific task. The Task Zap operation will remove scheduling from all tasks.

Today it ran correctly again at 5:36. There is something energetic here, sometimes things (teeth, software, hardware, life) straighten themselves out as soon as you decide to take a step towards fixing them :)

I will do as you both said, if things get crazy again.

Thank you very much!

Hello,

It worked very well, at night, for a week, until I restarted the computer yesterday for a Windows update. Then Acronis forgot the password and asks me to add it when I give him a manual backup or when I save the configuration options.

 

I don't know if it has to do with any restart or only with Windows updates (11).
What do you think?

Thank you,
Bogdan