Please help me! I have paid a lot of money for two units with disks and licenses to run ReadyNas Replicate and I can't get it to work properly! I get the same error message every time I try to use the ReadyNAS Replicate. I have tried running both Backup Data and Backup System and all the time I get "Snapshot initialization failed" error. Both devices are ReadyNAS NV+ v2 with RAIDiator 5.3.5 and Flex-RAID (Raid5) disk configuration (running 4 x Seagate ST3000DM001-9YN166 in each unit). I am running the latest version (1.1.3) of ReadyNAS Replicate (two purchased licenses). I have disabled encryption and not using compression to speed up the process. I have tried creating new backup jobs, removing the old failed folders and restarting both units several times. I can't see any solution, it feels like I have tried all combinations and it fails every time. Below is the log from the latest attempt to run a Backup System:
[2012-07-22 20:54:35] [Info] Starting backup job "nas BU" [2012-07-22 20:54:35] [Info] Replicate addon version: 1.1.3 build R1 [2012-07-22 20:54:35] [Info] Create target folder: /c/backup/nas BU [2012-07-22 20:54:35] [Info] Locking target folder: /c/backup/nas BU [2012-07-22 20:54:36] [Info] Making full backup [2012-07-22 20:54:36] [Info] p2p connect to nas-A0-A1-AF in 757198 network [2012-07-22 20:54:42] [Info] Connection method: 2 [2012-07-22 20:54:42] [Info] LAN connection available [2012-07-22 20:54:42] [Info] Get LAN credentials [2012-07-22 20:54:42] [Info] Sending task to source device [2012-07-22 20:54:43] [Info] Awaiting task report [2012-07-22 20:54:44] [Info] Received success report [2012-07-22 20:54:44] [Info] Using LAN connection [2012-07-22 20:54:44] [Info] Initializing snapshot on source device [2012-07-22 20:54:44] [Info] Sending task to source device [2012-07-22 20:54:44] [Info] Awaiting task report [2012-07-22 20:54:47] [Error] Received failed report [2012-07-22 20:54:47] [Error] Snapshot initialization failed [2012-07-22 20:54:47] [Info] Deinitializing snapshot on source device [2012-07-22 20:54:47] [Info] Sending task to source device [2012-07-22 20:54:47] [Info] Awaiting task report [2012-07-22 20:54:49] [Info] Received success report [2012-07-22 20:54:49] [Info] Snapshot deinitialization finished [2012-07-22 20:54:49] [Info] p2p disconnect with nas-A0-A1-AF in 757198 network [2012-07-22 20:54:50] [Info] Unlock target folder: /c/backup/nas BU [2012-07-22 20:54:50] [Info] Job finished
System backup does work in the opposite direction?
Today I tested to create a backup job making a backup in the other direction (from my backup nas to my main nas). It seemed to work just fine. I cancelled the backup since I don't want that backup but it gave me hope that there might be a solution to this problem.
[2012-07-24 05:17:52] [Info] Starting backup job "bu_nas BU" [2012-07-24 05:17:52] [Info] Replicate addon version: 1.1.3 build R1 [2012-07-24 05:17:52] [Info] Create target folder: /c/backup/bu_nas BU [2012-07-24 05:17:53] [Info] Locking target folder: /c/backup/bu_nas BU [2012-07-24 05:17:54] [Info] Making full backup [2012-07-24 05:17:54] [Info] p2p connect to nas-A0-63-3F in 761509 network [2012-07-24 05:17:59] [Info] Connection method: 2 [2012-07-24 05:17:59] [Info] LAN connection available [2012-07-24 05:17:59] [Info] Get LAN credentials [2012-07-24 05:17:59] [Info] Sending task to source device [2012-07-24 05:17:59] [Info] Awaiting task report [2012-07-24 05:18:00] [Info] Received success report [2012-07-24 05:18:00] [Info] Using LAN connection [2012-07-24 05:18:00] [Info] Initializing snapshot on source device [2012-07-24 05:18:00] [Info] Sending task to source device [2012-07-24 05:18:01] [Info] Awaiting task report [2012-07-24 05:18:04] [Info] Received success report [2012-07-24 05:18:04] [Info] Snapshot initialization finished [2012-07-24 05:18:04] [Info] Creating system config backup [2012-07-24 05:18:04] [Info] Sending task to source device [2012-07-24 05:18:04] [Info] Awaiting task report [2012-07-24 05:18:14] [Info] Received success report [2012-07-24 05:18:14] [Info] Copy in progress [2012-07-24 05:21:12] [Error] Job cancelled [2012-07-24 05:21:12] [Info] Deinitializing snapshot on source device [2012-07-24 05:21:13] [Info] Sending task to source device [2012-07-24 05:21:13] [Info] Awaiting task report [2012-07-24 05:21:15] [Info] Received success report [2012-07-24 05:21:15] [Info] Snapshot deinitialization finished [2012-07-24 05:21:15] [Info] p2p disconnect with nas-A0-63-3F in 761509 network [2012-07-24 05:21:16] [Info] Unlock target folder: /c/backup/bu_nas BU [2012-07-24 05:21:16] [Info] Job finished
I also tested the real System backup with the same result as earlier, takes about 13 seconds to fail.
[2012-07-24 05:16:18] [Info] Starting backup job "nas BU" [2012-07-24 05:16:18] [Info] Replicate addon version: 1.1.3 build R1 [2012-07-24 05:16:18] [Info] Locking target folder: /c/backup/nas BU [2012-07-24 05:16:20] [Info] Resume source is "/c/backup/nas BU/2012-07-22 20-54 (failed)/data" [2012-07-24 05:16:20] [Warning] Backup item "/c/backup/nas BU/2012-07-22 20-54 (failed)/data/c" not found [2012-07-24 05:16:20] [Info] p2p connect to nas-A0-A1-AF in 757198 network [2012-07-24 05:16:26] [Info] Connection method: 2 [2012-07-24 05:16:26] [Info] LAN connection available [2012-07-24 05:16:26] [Info] Get LAN credentials [2012-07-24 05:16:26] [Info] Sending task to source device [2012-07-24 05:16:26] [Info] Awaiting task report [2012-07-24 05:16:27] [Info] Received success report [2012-07-24 05:16:27] [Info] Using LAN connection [2012-07-24 05:16:27] [Info] Initializing snapshot on source device [2012-07-24 05:16:27] [Info] Sending task to source device [2012-07-24 05:16:27] [Info] Awaiting task report [2012-07-24 05:16:29] [Error] Received failed report [2012-07-24 05:16:29] [Error] Snapshot initialization failed [2012-07-24 05:16:29] [Info] Deinitializing snapshot on source device [2012-07-24 05:16:29] [Info] Sending task to source device [2012-07-24 05:16:29] [Info] Awaiting task report [2012-07-24 05:16:30] [Info] Received success report [2012-07-24 05:16:31] [Info] Snapshot deinitialization finished [2012-07-24 05:16:31] [Info] p2p disconnect with nas-A0-A1-AF in 757198 network [2012-07-24 05:16:31] [Info] Unlock target folder: /c/backup/nas BU [2012-07-24 05:16:31] [Info] Job finished
I removed all backup jobs and all the folders they created on both my nas-units. I also restarted both nas-units and created one new System backup job with a new name. But it didn't work...
[2012-07-24 06:04:37] [Info] Starting backup job "FULL NAS BU" [2012-07-24 06:04:37] [Info] Replicate addon version: 1.1.3 build R1 [2012-07-24 06:04:37] [Info] Create target folder: /c/backup/FULL NAS BU [2012-07-24 06:04:37] [Info] Locking target folder: /c/backup/FULL NAS BU [2012-07-24 06:04:38] [Info] Making full backup [2012-07-24 06:04:38] [Info] p2p connect to nas-A0-A1-AF in 761563 network [2012-07-24 06:04:43] [Info] Connection method: 2 [2012-07-24 06:04:43] [Info] LAN connection available [2012-07-24 06:04:43] [Info] Get LAN credentials [2012-07-24 06:04:43] [Info] Sending task to source device [2012-07-24 06:04:44] [Info] Awaiting task report [2012-07-24 06:04:45] [Info] Received success report [2012-07-24 06:04:45] [Info] Using LAN connection [2012-07-24 06:04:45] [Info] Initializing snapshot on source device [2012-07-24 06:04:45] [Info] Sending task to source device [2012-07-24 06:04:45] [Info] Awaiting task report [2012-07-24 06:04:49] [Error] Received failed report [2012-07-24 06:04:49] [Error] Snapshot initialization failed [2012-07-24 06:04:49] [Info] Deinitializing snapshot on source device [2012-07-24 06:04:49] [Info] Sending task to source device [2012-07-24 06:04:50] [Info] Awaiting task report [2012-07-24 06:04:53] [Info] Received success report [2012-07-24 06:04:53] [Info] Snapshot deinitialization finished [2012-07-24 06:04:53] [Info] p2p disconnect with nas-A0-A1-AF in 761563 network [2012-07-24 06:04:54] [Info] Unlock target folder: /c/backup/FULL NAS BU [2012-07-24 06:04:54] [Info] Job finished
Re: "Snapshot initialization failed" error every time
I made another attempt, this time also downloading the complete system logs from both units and comparing them. I can't see any problem in the logs that explains why I get "Snapshot initialization failed" all the time.
[2012-07-26 20:48:25] [Info] Starting backup job "System Backup" [2012-07-26 20:48:25] [Info] Replicate addon version: 1.1.3 build R1 [2012-07-26 20:48:25] [Info] Locking target folder: /c/backup/System Backup [2012-07-26 20:48:26] [Info] Resume source is "/c/backup/System Backup/2012-07-24 07-20 (failed)/data" [2012-07-26 20:48:26] [Warning] Backup item "/c/backup/System Backup/2012-07-24 07-20 (failed)/data/c" not found [2012-07-26 20:48:26] [Info] p2p connect to nas-A0-A1-AF in 761690 network [2012-07-26 20:48:31] [Info] Connection method: 2 [2012-07-26 20:48:31] [Info] LAN connection available [2012-07-26 20:48:31] [Info] Get LAN credentials [2012-07-26 20:48:31] [Info] Sending task to source device [2012-07-26 20:48:32] [Info] Awaiting task report [2012-07-26 20:48:33] [Info] Received success report [2012-07-26 20:48:33] [Info] Using LAN connection [2012-07-26 20:48:33] [Info] Initializing snapshot on source device [2012-07-26 20:48:33] [Info] Sending task to source device [2012-07-26 20:48:33] [Info] Awaiting task report [2012-07-26 20:48:35] [Error] Received failed report [2012-07-26 20:48:35] [Error] Snapshot initialization failed [2012-07-26 20:48:35] [Info] Deinitializing snapshot on source device [2012-07-26 20:48:35] [Info] Sending task to source device [2012-07-26 20:48:35] [Info] Awaiting task report [2012-07-26 20:48:36] [Info] Received success report [2012-07-26 20:48:36] [Info] Snapshot deinitialization finished [2012-07-26 20:48:36] [Info] p2p disconnect with nas-A0-A1-AF in 761690 network [2012-07-26 20:48:37] [Info] Unlock target folder: /c/backup/System Backup [2012-07-26 20:48:37] [Info] Job finished
BU NAS - daemon.log Line 3015: Jul 26 20:48:32 nas-A0-63-3F rsyncd[3862]: rsync to reports/7f199c40-d752-11e1-84b6-1f5e32884f9a.report from UNKNOWN (5.173.154.162) Line 3016: Jul 26 20:48:32 nas-A0-63-3F rsyncd[3862]: receiving file list Line 3017: Jul 26 20:48:32 nas-A0-63-3F rsyncd[3862]: sent 54 bytes received 339 bytes total size 210 Line 3018: Jul 26 20:48:34 nas-A0-63-3F rsyncd[3907]: rsync to reports/7fea94bc-d752-11e1-b234-6fb4c1235f98.report from UNKNOWN (5.173.154.162) Line 3019: Jul 26 20:48:34 nas-A0-63-3F rsyncd[3907]: receiving file list Line 3020: Jul 26 20:48:35 nas-A0-63-3F rsyncd[3907]: sent 54 bytes received 248 bytes total size 119 Line 3021: Jul 26 20:48:36 nas-A0-63-3F rsyncd[3925]: rsync to reports/8148ec28-d752-11e1-88b7-8b862c4e0a53.report from UNKNOWN (5.173.154.162) Line 3022: Jul 26 20:48:36 nas-A0-63-3F rsyncd[3925]: receiving file list Line 3023: Jul 26 20:48:36 nas-A0-63-3F rsyncd[3925]: sent 54 bytes received 238 bytes total size 109
BU NAS - system.log Line 6570: Jul 26 20:48:32 nas-A0-63-3F rsyncd[3862]: rsync to reports/7f199c40-d752-11e1-84b6-1f5e32884f9a.report from UNKNOWN (5.173.154.162) Line 6571: Jul 26 20:48:32 nas-A0-63-3F rsyncd[3862]: receiving file list Line 6572: Jul 26 20:48:32 nas-A0-63-3F rsyncd[3862]: sent 54 bytes received 339 bytes total size 210 Line 6573: Jul 26 20:48:34 nas-A0-63-3F rsyncd[3907]: rsync to reports/7fea94bc-d752-11e1-b234-6fb4c1235f98.report from UNKNOWN (5.173.154.162) Line 6574: Jul 26 20:48:34 nas-A0-63-3F rsyncd[3907]: receiving file list Line 6575: Jul 26 20:48:35 nas-A0-63-3F rsyncd[3907]: sent 54 bytes received 248 bytes total size 119 Line 6576: Jul 26 20:48:36 nas-A0-63-3F rsyncd[3925]: rsync to reports/8148ec28-d752-11e1-88b7-8b862c4e0a53.report from UNKNOWN (5.173.154.162) Line 6577: Jul 26 20:48:36 nas-A0-63-3F rsyncd[3925]: receiving file list Line 6578: Jul 26 20:48:36 nas-A0-63-3F rsyncd[3925]: sent 54 bytes received 238 bytes total size 109
MAIN NAS - daemon.log Line 10001: Jul 26 20:48:31 nas-A0-A1-AF rsyncd[4915]: rsync to tasks/ from UNKNOWN (5.174.76.192) Line 10002: Jul 26 20:48:31 nas-A0-A1-AF rsyncd[4915]: receiving file list Line 10003: Jul 26 20:48:32 nas-A0-A1-AF rsyncd[4915]: sent 54 bytes received 321 bytes total size 196 Line 10004: Jul 26 20:48:33 nas-A0-A1-AF rsyncd[4958]: rsync to tasks/ from UNKNOWN (5.174.76.192) Line 10005: Jul 26 20:48:33 nas-A0-A1-AF rsyncd[4958]: receiving file list Line 10006: Jul 26 20:48:33 nas-A0-A1-AF rsyncd[4958]: sent 54 bytes received 407 bytes total size 282 Line 10007: Jul 26 20:48:35 nas-A0-A1-AF rsyncd[5049]: rsync to tasks/ from UNKNOWN (5.174.76.192) Line 10008: Jul 26 20:48:35 nas-A0-A1-AF rsyncd[5049]: receiving file list Line 10009: Jul 26 20:48:35 nas-A0-A1-AF rsyncd[5049]: sent 54 bytes received 374 bytes total size 249
MAIN NAS - system.log Line 10004: Jul 26 20:48:31 nas-A0-A1-AF rsyncd[4915]: rsync to tasks/ from UNKNOWN (5.174.76.192) Line 10005: Jul 26 20:48:31 nas-A0-A1-AF rsyncd[4915]: receiving file list Line 10006: Jul 26 20:48:32 nas-A0-A1-AF rsyncd[4915]: sent 54 bytes received 321 bytes total size 196 Line 10007: Jul 26 20:48:33 nas-A0-A1-AF rsyncd[4958]: rsync to tasks/ from UNKNOWN (5.174.76.192) Line 10008: Jul 26 20:48:33 nas-A0-A1-AF rsyncd[4958]: receiving file list Line 10009: Jul 26 20:48:33 nas-A0-A1-AF rsyncd[4958]: sent 54 bytes received 407 bytes total size 282 Line 10010: Jul 26 20:48:35 nas-A0-A1-AF rsyncd[5049]: rsync to tasks/ from UNKNOWN (5.174.76.192) Line 10011: Jul 26 20:48:35 nas-A0-A1-AF rsyncd[5049]: receiving file list Line 10012: Jul 26 20:48:35 nas-A0-A1-AF rsyncd[5049]: sent 54 bytes received 374 bytes total size 249
Re: "Snapshot initialization failed" error every time
Thanks for that link! I have posted a question using that form and included a link to this forum post 🙂 Hopefully I will get some more feedback from there!
Re: "Snapshot initialization failed" error every time
Problem solved! It was the default setting (10 GB) for snapshot size that had to be changed to "no snapshot". I got the information from Jeff Capone (Netgear). I created a new "System backup"-job with correct settings and after performing a restart of both NAS units the backup is now running and is expected to finish within about 30 hours according to ReadyNAS Replicate. It will be interesting to see how long time the incremental backup will take, that will determine how it will be possible to schedule the System backup using ReadyNAS Replicate.
Re: "Snapshot initialization failed" error every time
FWIW, I would get that "Snapshot initialization failed" if a substantial amount of data - several GBs or more - was being written to the NAS while it was trying to do a backup.
Re: "Snapshot initialization failed" error every time
This was the email that I got from Netgear support
Hi, The NV+ does not have snapshotting capabilities and I am not sure why the UI when you create a replicate job is offering that option; I will report this bug. In any case, when you create a job please set the snapshot to "no snapshot" and try to run the job and let me know. Thanks, Jeff
... In addition to the various security options, the NV+ comes with snapshot support. Consider a snapshot as an instant point-in-time image of your data, sort of like a photo taken with a camera. Regardless of the number of files or the volume of data on the ReadyNAS, a snapshot only takes a couple of seconds, and you can continue using the ReadyNAS as usual during the snapshot process. If you inadvertantly change or delete files or folders after you’ve taken a snapshot, you can always revert to the version saved in that snapshot. This can be useful if your files were infected with a virus and you needed to revert back to a good copy saved at midnight. A snapshot can also be scheduled to coincide with your backups. Typical backups can take hours, so a backup of a snapshot insures that you’re making a backup of something that won’t be changing during the backup process. ...
On this page about NV+ v2 Netgear says clearly that there is no snapshot backup support: http://www.netgear.com/images/ReadyNAS_ ... -57079.pdf Instead of snapshot support there seems to be some kind of lock while making backup.
