NonStop Backup Won't Start- TIB file 0 Bytes
I have been using NonStop backup quite a while now ATIH 2017 on a Windows 7 machine using an external drive. Today the backup won't start. Further investigation shows the .TIB file for the backup as 0 bytes. Reboot and eject and reconnect have no effect. Suggestions?
Thanks.


- Log in to post comments

Shut down and started again. Going through the new files. "Interesting" sections are:
TI_Monitor :
5/1/2018 2:55:00 PM: Monitor started.
5/1/2018 2:55:00 PM: Load qt resources
5/1/2018 2:55:01 PM: GetDisplayInfo succeeded: DPI: 96, scaling: 100%. Monitors: Resolution: 1360x768, work area: 1360x768, physical DPI: 50. Resolution: 1680x1050, work area: 1605x1050, physical DPI: 50.
5/1/2018 2:55:01 PM: EnableHiDpiScaling - use system scaling.
5/1/2018 2:55:01 PM: Init GA events
5/1/2018 2:55:02 PM: Error 0x19203f5: Sync Agent service error. Try to restart your computer.
5/1/2018 2:55:07 PM: Error 0x19203f5: Sync Agent service error. Try to restart your computer.
5/1/2018 2:55:48 PM: Error 0xac0190: No Internet connection.
5/1/2018 2:55:48 PM: Unable to get account info: login error: Error 0xac0190: No Internet connection.
5/1/2018 2:55:48 PM: Unable to get used cloud size: Error 0x1900002: No Internet connection.
5/1/2018 2:55:48 PM: Unable to update license notifications for LicenseCheckOperation
5/1/2018 2:55:48 PM: Error 0x19203f5: Sync Agent service error. Try to restart your computer.
The Sync Agent service error repeats several times.
Try and Decide:
5/1/2018 2:55:02 PM: [tnd][T] build: 2410, process: 6188, 'C:\Program Files (x86)\Acronis\TrueImageHome\TrueImageMonitor.exe'
5/1/2018 2:55:02 PM: [tnd][T] 2018.05.01 21:55:02 UTC
5/1/2018 2:55:02 PM: [tnd][T] 2018.05.01 14:55:02 LOCAL
5/1/2018 2:55:02 PM: [tnd][T] Initialize Tnd API
5/1/2018 2:55:02 PM: [tnd][E] Failed to open control device '\\.\Global\TndControl2410': status 2
5/1/2018 2:55:02 PM: [tnd][E] Failed to initialize: error 0x2
5/1/2018 2:55:02 PM: [tnd][E] SetLastError: Error 0x97003a
5/1/2018 2:55:02 PM: [tnd][E] Failed to open control device '\\.\Global\TndControl2410': status 2
5/1/2018 2:55:02 PM: [tnd][E] Failed to initialize: error 0x2
5/1/2018 2:55:02 PM: [tnd][T] Free buffer 04A785E8
5/1/2018 2:55:02 PM: [tnd][T] TndSetSessionStateChangedCallback
5/1/2018 2:55:02 PM: [tnd][T] TndSetSessionLowFreeSpaceCallback
Sync Agent:
id=7; level=4; module=423; code=25; human_time=05/01/18 21:53:38
date/time=5/1/2018 2:53:38 PM; message=Blocking operation was cancelled.; line_tag=0x795E7E15DFD807CB
Error_Code=ARkApwHLB9jfFX5eeUJsb2NraW5nIG9wZXJhdGlvbiB3YXMgY2FuY2VsbGVkLgAkbW9kdWxlAEFzeW5jX2FnZW50X3Nydl92c18zMDQ1ACRmaWxlAEFrOlwzMDQ1XGNvbm5lY3Rpdml0eVxzdHJlYW1caW1wbFxjb21tb24uaAAkZnVuYwBBQ29ubmVjdGl2aXR5OjpXYWl0V2l0aEJyZWFrACRsaW5lAE4uAAAAAAAAAAA=
id=8; level=4; module=423; code=25; human_time=05/01/18 21:53:38
date/time=5/1/2018 2:53:38 PM; message=Blocking operation was cancelled.; line_tag=0x795E7E15DFD807CB
Error_Code=ARkApwHLB9jfFX5eeUJsb2NraW5nIG9wZXJhdGlvbiB3YXMgY2FuY2VsbGVkLgAkbW9kdWxlAEFzeW5jX2FnZW50X3Nydl92c18zMDQ1ACRmaWxlAEFrOlwzMDQ1XGNvbm5lY3Rpdml0eVxzdHJlYW1caW1wbFxjb21tb24uaAAkZnVuYwBBQ29ubmVjdGl2aXR5OjpXYWl0V2l0aEJyZWFrACRsaW5lAE4uAAAAAAAAAAA=
id=9; level=4; module=423; code=25; human_time=05/01/18 21:53:38
date/time=5/1/2018 2:53:38 PM; message=Blocking operation was cancelled.; line_tag=0x795E7E15DFD807CB
Error_Code=ARkApwHLB9jfFX5eeUJsb2NraW5nIG9wZXJhdGlvbiB3YXMgY2FuY2VsbGVkLgAkbW9kdWxlAEFzeW5jX2FnZW50X3Nydl92c18zMDQ1ACRmaWxlAEFrOlwzMDQ1XGNvbm5lY3Rpdml0eVxzdHJlYW1caW1wbFxjb21tb24uaAAkZnVuYwBBQ29ubmVjdGl2aXR5OjpXYWl0V2l0aEJyZWFrACRsaW5lAE4uAAAAAAAAAAA=
id=10; level=4; module=423; code=25; human_time=05/01/18 21:53:38
date/time=5/1/2018 2:53:38 PM; message=Blocking operation was cancelled.; line_tag=0x795E7E15DFD807CB
Error_Code=ARkApwHLB9jfFX5eeUJsb2NraW5nIG9wZXJhdGlvbiB3YXMgY2FuY2VsbGVkLgAkbW9kdWxlAEFzeW5jX2FnZW50X3Nydl92c18zMDQ1ACRmaWxlAEFrOlwzMDQ1XGNvbm5lY3Rpdml0eVxzdHJlYW1caW1wbFxjb21tb24uaAAkZnVuYwBBQ29ubmVjdGl2aXR5OjpXYWl0V2l0aEJyZWFrACRsaW5lAE4uAAAAAAAAAAA=
id=11; level=4; module=423; code=25; human_time=05/01/18 21:53:38
date/time=5/1/2018 2:53:38 PM; message=Blocking operation was cancelled.; line_tag=0x795E7E15DFD807CB
Error_Code=ARkApwHLB9jfFX5eeUJsb2NraW5nIG9wZXJhdGlvbiB3YXMgY2FuY2VsbGVkLgAkbW9kdWxlAEFzeW5jX2FnZW50X3Nydl92c18zMDQ1ACRmaWxlAEFrOlwzMDQ1XGNvbm5lY3Rpdml0eVxzdHJlYW1caW1wbFxjb21tb24uaAAkZnVuYwBBQ29ubmVjdGl2aXR5OjpXYWl0V2l0aEJyZWFrACRsaW5lAE4uAAAAAAAAAAA
afcdpsrv:
01-05-2018 14:59:43: [FileTracker] CreateSession, create and finalize checkpoint: {0F8B711C-1894-49B7-92D3-38DD4A63F7D5}
01-05-2018 14:59:43: tid=0x00002128>KCdpDriverSession::KCdpDriverSession() status 0x0, session name {D989D0B6-00F4-4263-A646-86FD4CC2EB76}, polling timeout 300000 ms
01-05-2018 14:59:43: [FileTracker] CreateCheckpoint, session name: {D989D0B6-00F4-4263-A646-86FD4CC2EB76}, checkpoint name: {B714F01E-12FA-491C-BEFE-E202906DCBE6}
01-05-2018 14:59:43: tid=0x00002128>KCdpDriverSession::Start(), status 0x0, session name {D989D0B6-00F4-4263-A646-86FD4CC2EB76}
01-05-2018 14:59:43: tid=0x00002128>Session Started
01-05-2018 14:59:43: tid=0x00002128>Driver started
01-05-2018 14:59:43: tid=0x00002128>Archive existence checked before consolidation with result: false
01-05-2018 14:59:43: Consolidation has failed.
01-05-2018 14:59:43: Code: 0x1720001 tag: 0x6F3D13FF1B3C09AC $module: afcdpsrv_vs_4310 Code: 0x1720001 tag: 0x6F3D13FF1B3C06CF $module: afcdpsrv_vs_4310 Code: 0x52 tag: 0xC59423F28FF7EC2A $module: afcdpsrv_vs_4310 Code: 0x170404 tag: 0x393E6815106ABC7 $module: afcdpsrv_vs_4310
01-05-2018 14:59:43: tid=0x00002128>Archive existence checked after consolidation with result: false
01-05-2018 14:59:43: tid=0x00002128>globalOperationMutex Unlock()
01-05-2018 14:59:43: tid=0x00002128>ERROR: KCdpArchiveCreator::OnRun():
01-05-2018 14:59:43: Code: 0x1720001 tag: 0x6F3D13FF1B3C05EE $module: afcdpsrv_vs_4310 Code: 0x1720001 tag: 0x6F3D13FF1B3C09AC $module: afcdpsrv_vs_4310 Code: 0x1720001 tag: 0x6F3D13FF1B3C06CF $module: afcdpsrv_vs_4310 Code: 0x52 tag: 0xC59423F28FF7EC2A $module: afcdpsrv_vs_4310 Code: 0x170404 tag: 0x393E6815106ABC7 $module: afcdpsrv_vs_4310
01-05-2018 14:59:43: Code: 0x1720001 tag: 0x6F3D13FF1B3C05EE $module: afcdpsrv_vs_4310 Code: 0x17200
NonStop Backup:
id=3; level=2; module=107; code=0; date/time=5/1/2018 2:55:26 PM
message=Destination paths: I:\PandaBackup\Time Explorer Storage\A2347F2F-3DB4-4819-BF67-F32DDF70AF88\image.data
id=4; level=2; module=107; code=0; date/time=5/1/2018 2:55:26 PM
message=
id=5; level=2; module=107; code=0; date/time=5/1/2018 2:55:26 PM
message=Initial full backup version has been created successfully.
id=6; level=4; module=107; code=0; date/time=5/1/2018 2:59:43 PM
message=Consolidation has failed.
id=7; level=4; module=370; code=1; date/time=5/1/2018 2:59:43 PM
message= ; line_tag=0x6F3D13FF1B3C09AC
id=8; level=4; module=370; code=1; date/time=5/1/2018 2:59:44 PM
message= ; line_tag=0x6F3D13FF1B3C06CF
id=9; level=4; module=0; code=82; date/time=5/1/2018 2:59:44 PM
message= ; line_tag=0xC59423F28FF7EC2A
id=10; level=4; module=23; code=1028; date/time=5/1/2018 2:59:44 PM
message= ; line_tag=0x393E6815106ABC7
id=11; level=4294967295; module=370; code=1; date/time=5/1/2018 2:59:44 PM
message= ; line_tag=0x6F3D13FF1B3C05EE
id=12; level=4294967295; module=370; code=1; date/time=5/1/2018 2:59:44 PM
message= ; line_tag=0x6F3D13FF1B3C09AC
id=13; level=4294967295; module=370; code=1; date/time=5/1/2018 2:59:44 PM
message= ; line_tag=0x6F3D13FF1B3C06CF
id=14; level=4294967295; module=0; code=82; date/time=5/1/2018 2:59:44 PM
message= ; line_tag=0xC59423F28FF7EC2A
id=15; level=4294967295; module=23; code=1028; date/time=5/1/2018 2:59:44 PM
message= ; line_tag=0x393E6815106ABC7
id=16; level=4; module=370; code=1; date/time=5/1/2018 2:59:44 PM
message= ; line_tag=0x6F3D13FF1B3C05EE
id=17; level=4; module=370; code=1; date/time=5/1/2018 2:59:44 PM
message= ; line_tag=0x6F3D13FF1B3C09AC
id=18; level=4; module=370; code=1; date/time=5/1/2018 2:59:44 PM
message= ; line_tag=0x6F3D13FF1B3C06CF
id=19; level=4; module=0; code=82; date/time=5/1/2018 2:59:44 PM
message= ; line_tag=0xC59423F28FF7EC2A
id=20; level=4; module=23; code=1028; date/time=5/1/2018 2:59:44 PM
message= ; line_tag=0x393E6815106ABC7
id=21; level=4294967295; module=370; code=1; date/time=5/1/2018 2:59:44 PM
message= ; line_tag=0x6F3D13FF1B3C05EE
id=22; level=2; module=107; code=0; date/time=5/1/2018 2:59:44 PM
message=Nonstop backup has been created successfully.
MMS:
5/1/2018 2:53:38 PM: 2176 I00000000: -- Server is stopping --
5/1/2018 2:53:38 PM: 2176 I00000000: -- Server stopped --
5/1/2018 2:53:38 PM: 2932 E01920010: Error 0x1920010: The account cannot be found.
| trace level: error
| line: 0xcaf802b9a1d59e8b
| file: k:\3045\products\imager\home\sync_agent\impl\api\sync_agent_api_man.cpp:747
| function: SyncAgentApi::SyncAgentApiMan::UnSubscribe
| $module: sync_agent_api_vs_3045
5/1/2018 2:54:54 PM: 3008 W012C0000: Service engine: Addons source directory is not set.
5/1/2018 2:54:54 PM: 3008 W012C0000: Service engine: Addon additional semantics are not set. Using only essential addons.
5/1/2018 2:54:54 PM: 3056 I00000000: -- Server is starting --
5/1/2018 2:54:54 PM: 3056 I00000000: Server protocol: '35', identity: ''.
5/1/2018 2:54:55 PM: 3056 I02470003: Curl CA certificates location: 'C:\ProgramData\Acronis\CaCertificates\'
5/1/2018 2:55:25 PM: 3056 E019203F3: Error 0x19203f3: Sync Agent service error. Try to restart your computer.
| trace level: error
| line: 0xcaf802b9a1d59e52
| file: k:\3045\products\imager\home\sync_agent\impl\api\sync_agent_api_man.cpp:690
| function: SyncAgentApi::SyncAgentApiMan::SubscribeRemote
| $module: sync_agent_api_vs_3045
|
| error 0x1920023: Sync Agent service error. Try to restart your computer.
| line: 0xcaf802b9a1d59cc6
| file: k:\3045\products\imager\home\sync_agent\impl\api\sync_agent_api_man.cpp:294
| function: `anonymous-namespace'::ConvertRpcErrorCode
| $module: sync_agent_api_vs_3045
|
| error 0xfff0: The RPC server is unavailable
| line: 0xbd28fdbd64edb8d9
| file: k:\3045\common\error.cpp:283
| function: Common::Error::AddWindowsError
| code: 0x800706ba
| $module: sync_agent_api_vs_3045
5/1/2018 2:55:25 PM: 3056 I00000000: -- Server started --
Anything else to look at?
- Log in to post comments

David, what are you seeing in the ti_demon log files for your NSB task? You can ignore most of the other logs such as Sync Agent, MMS etc unless dealing with an issue in those specific areas.
You need to be looking for logs that cover the time period when your NSB task should run or is giving errors.
- Log in to post comments
In reply to David, what are you seeing… by truwrikodrorow…

This is the latest ti_demon log in it's entirety. Almost a month old:
2018-04-04 09:00:08:030 12720 I00640000: Backup reserve copy attributes: format tib; need_reserve_backup_copy false;
2018-04-04 09:00:08:031 12720 I00640002: Operation PANDA started manually.
2018-04-04 09:00:33:179 12720 I013C0006: Operation has succeeded.
I've attached the logs from the C:\ProgramData\Acronis\TrueImageHome\Logs dated today. 3 files from the ti_monitor subdirectory.
Attachment | Size |
---|---|
449071-146819.log | 3.26 KB |
449071-146822.log | 832 bytes |
449071-146825.log | 74 bytes |
449071-146828.log | 45 KB |
449071-146831.gz | 20 bytes |
449071-146834.gz | 4.14 KB |
- Log in to post comments

David, the only entries of real interest are from the NSB log which shows:
id=5; level=2; module=107; code=0; date/time=02/05/2018 15:28:35
message=Initial full backup version has been created successfully.
id=6; level=4; module=107; code=0; date/time=02/05/2018 15:32:53
message=Consolidation has failed.
The latter message refers to automatic cleanup activity but doesn't tell us why this has failed?
Please take a look at KB 32417: Acronis True Image: Troubleshooting Nonstop Backup and try the actions suggested in this document.
If you still have a problem, then I would normally suggest opening a Support Case with Acronis but your ATI 2017 is no longer supported (since October 2017) so this would be a Pay Per Incident service. You alternative would be to try deleting and recreating the NSB task (only one NSB task is permitted).
- Log in to post comments

Thanks so much Steve. I can see why you are a "Legend". I will take a look at that article and see what I can find out. Again, I really appreciate your help.
- Log in to post comments

Steve, I wanted to let you know I got it working again. After reading a couple of the article I noticed a line about the cdp.metadata file being required for the backup to run or be recovered. I noticed a backup version of mine from 4-30 in the backup target folder so I made a copy of it, renamed the cdp.metadata file to cdp.metadata_hold and renamed my backup copy to cdp.metadata. Went back into the UI and I could now open up the NonStop Backup and get it running again. Thanks again for your help.
- Log in to post comments

David, glad you have been able to get NSB going again, thank you for the feedback.
- Log in to post comments