Incremental backup error (not always, but most of times)

Questions about Cobian Reflector
Post Reply
xikilin
Posts: 3
Joined: 18 Mar 2022, 01:35

Incremental backup error (not always, but most of times)

Post by xikilin »

Hello!

I've been using Cobian Backup for years, mostly since the encryption kind of malware got common. Since some kind of attacks could infect network shares, I've been using FTP backups. As I remember, everything was working fine till maybe 2-3 years ago.

The issue I'm facing now is on a Windows Server 2012 R2 using Cobian Reflector to backup to a NAS with FTP Server enabled. I was using Cobian Backup 11 before, but facing same kind of problems, so I tried Reflector... but with no luck. Nothing was changed on the NAS, as far as I know, and on Windows Server only the regular Windows updates.

The software is configured to make incremental backups and use a specific day to a full backup. The full backup always works fine, but the incremental fails most of times (not always) with the following errors:

Parts of start and end parts of a backup log:
======================================================================

2022-03-17 21:00:01 Calculating the number of files. This could take a while...

2022-03-17 21:02:44 Backing up the task "PARTILHA"
2022-03-17 21:02:44 Applying the parameters to the task "PARTILHA" (if any).
2022-03-17 21:02:44 Requesting the creation of a Volume Shadow Copy image for the source...
2022-03-17 21:02:56 The Volume Shadow Copy image has been successfully created.
2022-03-17 21:02:56 Starting the copy. The user running the task is: SYSTEM
2022-03-17 21:02:56 Connecting to 192.168.100.250:21...
2022-03-17 21:02:56 Connected to 192.168.100.250:21.
2022-03-17 21:02:56 Logged in into the remote server.
2022-03-17 21:02:56 Uploading the directory "D:\PARTILHA" to "/COPIAS/PARTILHA"...
ERR 2022-03-17 21:13:36 Couldn't create the remote directory "/COPIAS/PARTILHA/OBRAS/2020-376 Revivis, Lda - CNT - São Brás de Alportel/07.PROJETO JFMETAL/A.MODELAÇÃO/Arquivado/4 Fabrico/Listas": No transfer timeout (600 seconds): closing control connection (code=421) ERR 2022-03-17 21:13:36 Couldn't create the remote directory "/COPIAS/PARTILHA/OBRAS/2020-376 Revivis, Lda - CNT - São Brás de Alportel/07.PROJETO JFMETAL/A.MODELAÇÃO/Arquivado/4 Fabrico/logs": Control channel unexpectedly closed ('' read so far) ERR 2022-03-17 21:13:36 Couldn't create the remote directory "/COPIAS/PARTILHA/OBRAS/2020-376 Revivis, Lda - CNT - São Brás de Alportel/07.PROJETO JFMETAL/A.MODELAÇÃO/Arquivado/4 Fabrico/NC files plates": The FTP client has not yet connected to the server. The requested action cannot be performed until after a connection has been established.
ERR 2022-03-17 21:13:36 Couldn't create the remote directory "/COPIAS/PARTILHA/OBRAS/2020-376 Revivis, Lda - CNT - São Brás de Alportel/07.PROJETO JFMETAL/A.MODELAÇÃO/Arquivado/4 Fabrico/ProjectOrganizer": The FTP client has not yet connected to the server. The requested action cannot be performed until after a connection has been established.
ERR 2022-03-17 21:13:36 Couldn't create the remote directory "/COPIAS/PARTILHA/OBRAS/2020-376 Revivis, Lda - CNT - São Brás de Alportel/07.PROJETO JFMETAL/A.MODELAÇÃO/Arquivado/4 Fabrico/Reports": The FTP client has not yet connected to the server. The requested action cannot be performed until after a connection has been established.

...

ERR 2022-03-17 21:13:36 Couldn't create the remote directory "/COPIAS/PARTILHA/OBRAS/Video Promocional": The FTP client has not yet connected to the server. The requested action cannot be performed until after a connection has been established.
ERR 2022-03-17 21:13:36 Couldn't create the remote directory "/COPIAS/PARTILHA/OBRAS/Vincennes": The FTP client has not yet connected to the server. The requested action cannot be performed until after a connection has been established.
2022-03-17 21:13:36 The server has disconnected. Reconnecting...
2022-03-17 21:13:36 Connecting to 192.168.100.250:21...
2022-03-17 21:13:36 Connected to 192.168.100.250:21.
2022-03-17 21:13:36 Logged in into the remote server.
2022-03-17 21:22:08 The directory "D:\PARTILHA" has been uploaded to "/COPIAS/PARTILHA".
2022-03-17 21:22:08 Disconnected from 192.168.100.250.

2022-03-17 21:22:08 * Backup part for the task "PARTILHA" has ended *
2022-03-17 21:22:08 * Number of backed up files: 100. Backup part size: 573,01 MB. *

2022-03-17 21:22:08 ** Backup for the task "PARTILHA" has ended **
2022-03-17 21:22:08 ** Processed files: 1755692. Backed up files: 100. Created folders: 29. Backup size: 573,01 MB. **
2022-03-17 21:22:08 ** Number of errors: 146. Time elapsed: 0 hours, 19 minutes, 24 seconds. **

2022-03-17 21:22:08 Deleting the Volume Shadow Copy image...
2022-03-17 21:22:10 The Volume Shadow Copy image has been successfully deleted.

2022-03-17 21:22:10 Mailing the log files...
2022-03-17 21:22:10 Flushing the log to file. This can take some seconds. Wait...

2022-03-17 21:22:10 The backup has ended. There are errors. Consult the log file.

======================================================================

From over a million filesm wich should result on at least between 1.000 to 3.000 new or modifyed files per day, it only backed up 100 (today). Some times it backs up only 3 or for files, some times none... and some times it complets the task (incremental) with no errors.

On saturday, the day I specified for a full backup, it completes the task with no errors.

I tried to reset the NAS device. The first backup (complete) was OK; The second gave that kind of error, but the next days (maybe 2 weeks) were all ok. But then it started to give this error again.

I was using Reflector before I done the reset to NAS. But already before that, I was receiving errors with Cobian Backup 11. Not the same specific error, but the result was the same: only few files or none were backed up on incremental; everything ok on full backup.

I only know the basics about FTP. I've been using the same settings for years:
- The software is installed as a service with local system account;
- The FTP destination is configured with default settings, just checked the passive mode (whatever that means);
- The backup folder is set (COPIAS);
- The test works fine with no errors (the test folder or file is created on destination);
- The NAS device is a Netgear ReadyNAS with firmware 6.10.7 (latest) and there are not much FTP settings I can change on it.

This is not the only problem I have with FTP backup. Other errors regarding time outs, permitions, login issues, I solved it by changing the backup task destination to a network share (the same NAS device and the same FTP folder). I think this can be a solution, but I don't know if it is secure enough compared to FTP. The inttention of the backup is to have all files secured in case of a ransomware attack or accidental delete.

Can someone help me with this problem?

Thanks in advance!
Helder
tetsu
Posts: 123
Joined: 07 Oct 2021, 17:16

Re: Incremental backup error (not always, but most of times)

Post by tetsu »

cobian wrote: 11 Mar 2022, 13:00 FTP sources can only be full.
As far I have read, the incremental backup does not work on FTP, only full. I quote the post where Luis says this.
Regards.
User avatar
cobian
Site Admin
Posts: 4492
Joined: 31 Oct 2020, 01:25
Location: Sweden
Contact:

Re: Incremental backup error (not always, but most of times)

Post by cobian »

Exactly.
--
Luis Cobian
Cobian Backup's creator
xikilin
Posts: 3
Joined: 18 Mar 2022, 01:35

Re: Incremental backup error (not always, but most of times)

Post by xikilin »

I think you misunderstood my post...

FTP is the destination, not the source.
Source: D:\PARTILHA
Destination: FTP 192.168.100.250/COPIAS

I have this working fine on another machine (source: Local Folder; destination: FTP) with Cobian Backup v11. And had this kind of backup working fine on few other machines. Some of them started to give some errors and failing the tasks. I just can't understand why. The solution I found was to change from FTP to NETWORK destination.

I think FTP worked if the start was set to application instead of service, but in this case I need it to be as service.

There is an update about this, I haven't realized before:
The total files to backup is over 2,5 Million. Every time it fails, it never reaches all the files. Sometimes 1,3 million; sometimes 1,5 million, sometimes 1,7 million.

I can't do it now (late night), but I will try to upload some other logs tomorrow.

Thank you for answering!
Helder
User avatar
cobian
Site Admin
Posts: 4492
Joined: 31 Oct 2020, 01:25
Location: Sweden
Contact:

Re: Incremental backup error (not always, but most of times)

Post by cobian »

Sorry, I misunderstood the original post. Then things to check:

* Are you using the archive attribute?
* Are you cleaning the archive attribute?
--
Luis Cobian
Cobian Backup's creator
xikilin
Posts: 3
Joined: 18 Mar 2022, 01:35

Re: Incremental backup error (not always, but most of times)

Post by xikilin »

Hello, Luis!

Answering your questions:
* Are you using the archive attribute?
>> YES

* Are you cleaning the archive attribute?
>> YES

I attach print-screens from the task. And also the test log file bellow:
============================================================
Testing your ftp settings...

Licence expiry date: 31/12/9999
Set LocalDirectory to C:\Windows\system32
Licence expiry date: 31/12/9999
waitOnShutdownSSL=True
StrictReturnCodes=False
Setting socket timeout=120000
SecureSocket timeout=120000
SecureSocket timeout=120000
Command encoding=System.Text.SBCSCodePageEncoding
Setting socket buffer sizes=-1
220 ProFTPD Server (NETGEAR ReadyNAS) [BACKUPS]
---> USER admin
331 Password required for admin
---> PASS ********
230 User admin logged in
ChangeWorkingDirectory('/COPIAS')
---> CWD /COPIAS
250 CWD command successful
---> PWD
257 "/COPIAS" is the current directory
---> TYPE I
200 Type set to I
---> PASV
227 Entering Passive Mode (192,168,100,250,135,147).
Server supplied address=192.168.100.250
Server supplied port=34707
autoPassiveIPSubstitution=False
remoteAddr=192.168.100.250
SecureSocket timeout=120000
SecureSocket timeout=120000
SecureSocket timeout=120000
SecureSocket timeout=120000
---> STOR Cobian Reflector upload test.txt
150 Opening BINARY mode data connection for Cobian Reflector upload test.txt
Closing source stream
Shutdown(Both)
Attempt to close unconnected passive data-channel.
Transferred 66 bytes to remote host
226 Transfer complete
Purging task queue
============================================================
Attachments
Advanced tab
Advanced tab
8_Advanced.jpg (181.31 KiB) Viewed 5399 times
Dynamics tab
Dynamics tab
4_Dynamics.jpg (150.11 KiB) Viewed 5399 times
Schedule tab
Schedule tab
3_Schedule.jpg (184.06 KiB) Viewed 5399 times
Files tab
Files tab
2_Files.jpg (154.52 KiB) Viewed 5399 times
General tab
General tab
1_General.jpg (197.81 KiB) Viewed 5399 times
Last edited by xikilin on 22 Mar 2022, 11:54, edited 1 time in total.
xikilin
Posts: 3
Joined: 18 Mar 2022, 01:35

Re: Incremental backup error (not always, but most of times)

Post by xikilin »

Hello again!

And as I said, here are some logs. I spepareted them by "=======" for better reading:

1st Reflector backup - ERRORS: NO
- Incremental, changed to full due being the 1st backup (archive attribut checked to all files before task)
===============================================================
This is an automatic mail message from Cobian Reflector. You will find the log files below this text.

Cobian Reflector 2022-01-03.txt

2022-01-03 22:20:32 Welcome to Cobian Reflector!
2022-01-03 22:20:32 Creating an empty list: "C:\Program Files\Cobian Reflector\Lists\MainList.lst".
2022-01-03 22:20:33 Creating the history database.
2022-01-03 22:20:33 The history database has been created.
2022-01-03 22:20:34 The history table has been successfully created.
2022-01-03 22:26:30 The list "MainList.lst" has been successfully saved.
2022-01-03 22:26:37 Backing up some selected tasks.

2022-01-03 22:26:38 A backup has started...
2022-01-03 22:26:38 Calculating the number of files. This could take a while...

2022-01-03 22:30:14 Backing up the task "New task"
2022-01-03 22:30:15 Changing the backup type to full. Reason: first backup of the task.
2022-01-03 22:30:15 Requesting the creation of a Volume Shadow Copy image for the source...
2022-01-03 22:30:29 The Volume Shadow Copy image has been successfully created.
2022-01-03 22:30:29 Starting the copy. The user running the task is: SYSTEM
2022-01-03 22:30:30 Connecting to 192.168.100.250:21...
2022-01-03 22:30:30 Connected to 192.168.100.250:21.
2022-01-03 22:30:30 Logged in into the remote server.
2022-01-03 22:30:30 Uploading the directory "D:\PARTILHA" to "/COPIAS/PARTILHA"...
2022-01-03 22:32:07 The settings have been changed and saved.
2022-01-03 22:32:07 A new language is being used: english.
2022-01-03 22:35:42 The list "MainList.lst" has been successfully saved.

2022-01-03 22:40:00 Checking for updates...
2022-01-03 22:40:01 Your program is up to date.

2022-01-03 22:43:58 The settings have been changed and saved.
2022-01-03 22:45:11 The settings have been changed and saved.
2022-01-03 22:46:18 The settings have been changed and saved.
2022-01-04 00:00:00 Deleting the log files that are older than 90 days...

Cobian Reflector 2022-01-04.txt

2022-01-04 12:09:01 The directory "D:\PARTILHA" has been uploaded to "/COPIAS/PARTILHA".
2022-01-04 12:09:01 Disconnected from 192.168.100.250.
2022-01-04 12:09:01 * Backup part for the task "New task" has ended. Number of backed up files: 2178104. Backup part size: 807,82 GB. *
2022-01-04 12:09:02 ** Backup for the task "New task" has ended. Number of backed up files: 2178104. Created folders: 59267. Backup size: 807,82 GB. **
2022-01-04 12:09:02 *** Number of errors: 0. Time elapsed for the task "New task": 13 hours, 38 minutes, 47 seconds. ***

2022-01-04 12:09:02 Deleting the Volume Shadow Copy image...
2022-01-04 12:09:03 The Volume Shadow Copy image has been successfully deleted.
2022-01-04 12:09:04 The backup has ended without errors.


2022-01-04 12:09:04 Mailing the log files...
===============================================================





2nd Reflector backup - ERRORS: NO
- Incremental
===============================================================
This is an automatic mail message from Cobian Reflector. You will find the log files below this text.

Cobian Reflector 2022-01-04.txt

2022-01-04 12:09:07 The log files have been successfully deleted.


2022-01-04 21:00:01 A backup has started...
2022-01-04 21:00:01 Calculating the number of files. This could take a while...

2022-01-04 21:09:55 Backing up the task "PARTILHA"
2022-01-04 21:09:55 Requesting the creation of a Volume Shadow Copy image for the source...
2022-01-04 21:10:09 The Volume Shadow Copy image has been successfully created.
2022-01-04 21:10:09 Starting the copy. The user running the task is: SYSTEM
2022-01-04 21:10:09 Connecting to 192.168.100.250:21...
2022-01-04 21:10:09 Connected to 192.168.100.250:21.
2022-01-04 21:10:09 Logged in into the remote server.
2022-01-04 21:10:09 Uploading the directory "D:\PARTILHA" to "/COPIAS/PARTILHA"...
2022-01-04 21:33:21 The directory "D:\PARTILHA" has been uploaded to "/COPIAS/PARTILHA".
2022-01-04 21:33:21 Disconnected from 192.168.100.250.
2022-01-04 21:33:21 * Backup part for the task "PARTILHA" has ended. Number of backed up files: 1187. Backup part size: 190,81 MB. *
2022-01-04 21:33:21 ** Backup for the task "PARTILHA" has ended. Number of backed up files: 1187. Created folders: 50. Backup size: 190,81 MB. **
2022-01-04 21:33:21 *** Number of errors: 0. Time elapsed for the task "PARTILHA": 0 hours, 23 minutes, 25 seconds. ***

2022-01-04 21:33:21 Deleting the Volume Shadow Copy image...
===============================================================





3rd Reflector backup - ERRORS: YES
- Incremental (I did it manually for testing)
===============================================================
This is an automatic mail message from Cobian Reflector. You will find the log files below this text.

Cobian Reflector 2022-01-04.txt

2022-01-04 21:33:24 The backup has ended without errors.

2022-01-04 21:33:24 The log files have been successfully deleted.


2022-01-04 22:40:00 Checking for updates...
2022-01-04 22:40:00 Your program is up to date.

2022-01-04 23:01:43 The settings have been changed and saved.
2022-01-04 23:02:04 Backing up some selected tasks.

2022-01-04 23:02:05 A backup has started...
2022-01-04 23:02:05 Calculating the number of files. This could take a while...

2022-01-04 23:04:35 Backing up the task "PARTILHA"
2022-01-04 23:04:35 Requesting the creation of a Volume Shadow Copy image for the source...
2022-01-04 23:04:45 The Volume Shadow Copy image has been successfully created.
2022-01-04 23:04:45 Starting the copy. The user running the task is: SYSTEM
2022-01-04 23:04:45 Connecting to 192.168.100.250:21...
2022-01-04 23:04:45 Connected to 192.168.100.250:21.
2022-01-04 23:04:45 Logged in into the remote server.
2022-01-04 23:04:45 Uploading the directory "D:\PARTILHA" to "/COPIAS/PARTILHA"...
ERR 2022-01-04 23:14:45 Couldn't create the remote directory "/COPIAS/PARTILHA/OBRAS/2020-218 L.C.P Construction et Réalisation - Sansonnens, Payerne Suiça/07.PROJETO JFMETAL/A.MODELAÇÃO/Arquivado/Modelos antigos/OF.2020.0218 SANSONNENS.R4/logs": Control channel unexpectedly closed ('' read so far) ERR 2022-01-04 23:14:45 Couldn't create the remote directory "/COPIAS/PARTILHA/OBRAS/2020-218 L.C.P Construction et Réalisation - Sansonnens, Payerne Suiça/07.PROJETO JFMETAL/A.MODELAÇÃO/Arquivado/Modelos antigos/OF.2020.0218 SANSONNENS.R4/Reports": The FTP client has not yet connected to the server. The requested action cannot be performed until after a connection has been established.
ERR 2022-01-04 23:14:45 Couldn't create the remote directory "/COPIAS/PARTILHA/OBRAS/2020-218 L.C.P Construction et Réalisation - Sansonnens, Payerne Suiça/07.PROJETO JFMETAL/A.MODELAÇÃO/Arquivado/Modelos antigos/OF.2020.0218 SANSONNENS.R4/SessionFileRepository": The FTP client has not yet connected to the server. The requested action cannot be performed until after a connection has been established.

...
... a lot of same kind errors ...
...

ERR 2022-01-04 23:14:45 Couldn't create the remote directory "/COPIAS/PARTILHA/UTILIZADORES": The FTP client has not yet connected to the server. The requested action cannot be performed until after a connection has been established.
ERR 2022-01-04 23:14:45 Couldn't create the remote directory "/COPIAS/PARTILHA/_NUNO": The FTP client has not yet connected to the server. The requested action cannot be performed until after a connection has been established.
2022-01-04 23:14:45 The server has disconnected. Reconnecting...
2022-01-04 23:14:45 Connecting to 192.168.100.250:21...
2022-01-04 23:14:45 Connected to 192.168.100.250:21.
2022-01-04 23:14:45 Logged in into the remote server.
2022-01-04 23:14:45 The directory "D:\PARTILHA" has been uploaded to "/COPIAS/PARTILHA".
2022-01-04 23:14:45 Disconnected from 192.168.100.250.
2022-01-04 23:14:45 * Backup part for the task "PARTILHA" has ended. Number of backed up files: 1. Backup part size: 0 bytes. *
2022-01-04 23:14:45 ** Backup for the task "PARTILHA" has ended. Number of backed up files: 1. Created folders: 0. Backup size: 0 bytes. **
2022-01-04 23:14:45 *** Number of errors: 147. Time elapsed for the task "PARTILHA": 0 hours, 10 minutes, 10 seconds. ***

2022-01-04 23:14:45 Deleting the Volume Shadow Copy image...
===============================================================





4th Reflector backup - ERRORS: YES
- Incremental (I did it manually for testing)
===============================================================
This is an automatic mail message from Cobian Reflector. You will find the log files below this text.

Cobian Reflector 2022-01-04.txt

2022-01-04 23:14:47 The log files have been successfully deleted.

2022-01-05 00:00:00 Deleting the log files that are older than 90 days...

Cobian Reflector 2022-01-05.txt


2022-01-05 21:00:01 A backup has started...
2022-01-05 21:00:01 Calculating the number of files. This could take a while...

2022-01-05 21:02:56 Backing up the task "PARTILHA"
2022-01-05 21:02:56 Requesting the creation of a Volume Shadow Copy image for the source...
2022-01-05 21:03:07 The Volume Shadow Copy image has been successfully created.
2022-01-05 21:03:07 Starting the copy. The user running the task is: SYSTEM
2022-01-05 21:03:07 Connecting to 192.168.100.250:21...
2022-01-05 21:03:07 Connected to 192.168.100.250:21.
2022-01-05 21:03:07 Logged in into the remote server.
2022-01-05 21:03:07 Uploading the directory "D:\PARTILHA" to "/COPIAS/PARTILHA"...
ERR 2022-01-05 21:13:55 Couldn't create the remote directory "/COPIAS/PARTILHA/OBRAS/2020-340 Atlantinível, Lda - Centro Comunitário Caxinas/07.PROJETO JFMETAL/A.MODELAÇÃO/Arquivado/Fabrico das treliças/CustomComponentDialogFiles": No transfer timeout (600 seconds): closing control connection (code=421) ERR 2022-01-05 21:13:55 Couldn't create the remote directory "/COPIAS/PARTILHA/OBRAS/2020-340 Atlantinível, Lda - Centro Comunitário Caxinas/07.PROJETO JFMETAL/A.MODELAÇÃO/Arquivado/Fabrico das treliças/drawings": Control channel unexpectedly closed ('' read so far) ERR 2022-01-05 21:13:55 Couldn't create the remote directory "/COPIAS/PARTILHA/OBRAS/2020-340 Atlantinível, Lda - Centro Comunitário Caxinas/07.PROJETO JFMETAL/A.MODELAÇÃO/Arquivado/Fabrico das treliças/IFC": The FTP client has not yet connected to the server. The requested action cannot be performed until after a connection has been established.

...
... a lot of same kind errors ...
...

ERR 2022-01-05 21:13:55 Couldn't create the remote directory "/COPIAS/PARTILHA/_NUNO": The FTP client has not yet connected to the server. The requested action cannot be performed until after a connection has been established.
2022-01-05 21:13:55 The directory "D:\PARTILHA" has been uploaded to "/COPIAS/PARTILHA".
2022-01-05 21:13:55 * Backup part for the task "PARTILHA" has ended. Number of backed up files: 6. Backup part size: 3,91 MB. *
2022-01-05 21:13:55 ** Backup for the task "PARTILHA" has ended. Number of backed up files: 6. Created folders: 1. Backup size: 3,91 MB. **
2022-01-05 21:13:55 *** Number of errors: 148. Time elapsed for the task "PARTILHA": 0 hours, 10 minutes, 59 seconds. ***

2022-01-05 21:13:55 Deleting the Volume Shadow Copy image...
===============================================================





5th Reflector backup - ERRORS: NO
- Incremental
===============================================================
This is an automatic mail message from Cobian Reflector. You will find the log files below this text.

Cobian Reflector 2022-01-05.txt

2022-01-05 21:13:56 The log files have been successfully deleted.


2022-01-05 22:40:00 Checking for updates...
2022-01-05 22:40:00 Your program is up to date.


Cobian Reflector 2022-01-06.txt

2022-01-06 00:00:00 Deleting the log files that are older than 90 days...

2022-01-06 21:00:01 A backup has started...
2022-01-06 21:00:01 Calculating the number of files. This could take a while...

2022-01-06 21:02:24 Backing up the task "PARTILHA"
2022-01-06 21:02:24 Requesting the creation of a Volume Shadow Copy image for the source...
2022-01-06 21:02:36 The Volume Shadow Copy image has been successfully created.
2022-01-06 21:02:36 Starting the copy. The user running the task is: SYSTEM
2022-01-06 21:02:36 Connecting to 192.168.100.250:21...
2022-01-06 21:02:36 Connected to 192.168.100.250:21.
2022-01-06 21:02:36 Logged in into the remote server.
2022-01-06 21:02:36 Uploading the directory "D:\PARTILHA" to "/COPIAS/PARTILHA"...
2022-01-06 21:26:22 The directory "D:\PARTILHA" has been uploaded to "/COPIAS/PARTILHA".
2022-01-06 21:26:22 Disconnected from 192.168.100.250.
2022-01-06 21:26:22 * Backup part for the task "PARTILHA" has ended. Number of backed up files: 2928. Backup part size: 807,21 MB. *
2022-01-06 21:26:22 ** Backup for the task "PARTILHA" has ended. Number of backed up files: 2928. Created folders: 166. Backup size: 807,21 MB. **
2022-01-06 21:26:22 *** Number of errors: 0. Time elapsed for the task "PARTILHA": 0 hours, 23 minutes, 57 seconds. ***

2022-01-06 21:26:22 Deleting the Volume Shadow Copy image...
===============================================================





6th Reflector backup - ERRORS: NO
- Incremental
===============================================================
This is an automatic mail message from Cobian Reflector. You will find the log files below this text.

Cobian Reflector 2022-01-06.txt

2022-01-06 21:26:25 The log files have been successfully deleted.


2022-01-06 22:40:00 Checking for updates...
2022-01-06 22:40:00 Your program is up to date.


Cobian Reflector 2022-01-07.txt

2022-01-07 00:00:00 Deleting the log files that are older than 90 days...

2022-01-07 21:00:01 A backup has started...
2022-01-07 21:00:01 Calculating the number of files. This could take a while...

2022-01-07 21:02:10 Backing up the task "PARTILHA"
2022-01-07 21:02:10 Requesting the creation of a Volume Shadow Copy image for the source...
2022-01-07 21:02:22 The Volume Shadow Copy image has been successfully created.
2022-01-07 21:02:22 Starting the copy. The user running the task is: SYSTEM
2022-01-07 21:02:22 Connecting to 192.168.100.250:21...
2022-01-07 21:02:22 Connected to 192.168.100.250:21.
2022-01-07 21:02:22 Logged in into the remote server.
2022-01-07 21:02:22 Uploading the directory "D:\PARTILHA" to "/COPIAS/PARTILHA"...
2022-01-07 21:25:49 The directory "D:\PARTILHA" has been uploaded to "/COPIAS/PARTILHA".
2022-01-07 21:25:49 Disconnected from 192.168.100.250.
2022-01-07 21:25:49 * Backup part for the task "PARTILHA" has ended. Number of backed up files: 2615. Backup part size: 407,74 MB. *
2022-01-07 21:25:49 ** Backup for the task "PARTILHA" has ended. Number of backed up files: 2615. Created folders: 126. Backup size: 407,74 MB. **
2022-01-07 21:25:49 *** Number of errors: 0. Time elapsed for the task "PARTILHA": 0 hours, 23 minutes, 39 seconds. ***

2022-01-07 21:25:49 Deleting the Volume Shadow Copy image...
===============================================================





7th Reflector backup - ERRORS: NO
- Incremental, changed to full due to fixed day for full backup
===============================================================
This is an automatic mail message from Cobian Reflector. You will find the log files below this text.

Cobian Reflector 2022-01-07.txt

2022-01-07 21:25:51 The backup has ended without errors.

2022-01-07 21:25:51 The log files have been successfully deleted.


2022-01-07 22:40:00 Checking for updates...
2022-01-07 22:40:00 Your program is up to date.

2022-01-08 00:00:00 Deleting the log files that are older than 90 days...

Cobian Reflector 2022-01-08.txt


2022-01-08 21:00:01 A backup has started...
2022-01-08 21:00:01 Calculating the number of files. This could take a while...

2022-01-08 21:02:14 Backing up the task "PARTILHA"
2022-01-08 21:02:14 Changing the backup type to full. Reason: using a fixed day for full backups.
2022-01-08 21:02:14 Requesting the creation of a Volume Shadow Copy image for the source...
2022-01-08 21:02:26 The Volume Shadow Copy image has been successfully created.
2022-01-08 21:02:26 Starting the copy. The user running the task is: SYSTEM
2022-01-08 21:02:26 Connecting to 192.168.100.250:21...
2022-01-08 21:02:26 Connected to 192.168.100.250:21.
2022-01-08 21:02:26 Logged in into the remote server.
2022-01-08 21:02:26 Uploading the directory "D:\PARTILHA" to "/COPIAS/PARTILHA"...

2022-01-08 22:40:00 Checking for updates...
ERR 2022-01-08 22:40:00 A new version is available!
2022-01-08 22:40:00
2022-01-08 22:40:00 Current version: 0.9.75 BETA
2022-01-08 22:40:00
2022-01-08 22:40:00 * The first backup of a task is now UNPARKED as default.
2022-01-08 22:40:00 * "Log individual operations" label has been changed to "Debug mode"
2022-01-08 22:40:00 * A menu option has been added to restore all default Options
2022-01-08 22:40:00 * A menu option has been added to restore the last saved task
2022-01-08 22:40:00 * Crc check is now possible for uncompressed operations
2022-01-08 22:40:00 * Dutch language restored
2022-01-08 22:40:00 * Galician language updated
2022-01-08 22:40:00 * Italian language updated

2022-01-08 22:40:00 You can visit the program's website to download the new version.
2022-01-08 22:40:00 Download it at: https://www.cobiansoft.com/cobianbackup.html.


Cobian Reflector 2022-01-09.txt

2022-01-09 00:00:00 Deleting the log files that are older than 90 days...
2022-01-09 10:34:07 The directory "D:\PARTILHA" has been uploaded to "/COPIAS/PARTILHA".
2022-01-09 10:34:07 Disconnected from 192.168.100.250.
2022-01-09 10:34:07 * Backup part for the task "PARTILHA" has ended. Number of backed up files: 2181840. Backup part size: 808,77 GB. *
2022-01-09 10:34:07 ** Backup for the task "PARTILHA" has ended. Number of backed up files: 2181840. Created folders: 0. Backup size: 808,77 GB. **
2022-01-09 10:34:07 *** Number of errors: 0. Time elapsed for the task "PARTILHA": 13 hours, 31 minutes, 52 seconds. ***

2022-01-09 10:34:07 Deleting the Volume Shadow Copy image...
===============================================================





8th Reflector backup - ERRORS: NO
This is an automatic mail message from Cobian Reflector. You will find the log files below this text.

Cobian Reflector 2022-01-09.txt

2022-01-09 10:34:11 The log files have been successfully deleted.


2022-01-09 22:40:00 Checking for updates...
ERR 2022-01-09 22:40:00 A new version is available!
2022-01-09 22:40:00
2022-01-09 22:40:00 Current version: 0.9.75 BETA
2022-01-09 22:40:00
2022-01-09 22:40:00 * The first backup of a task is now UNPARKED as default.
2022-01-09 22:40:00 * "Log individual operations" label has been changed to "Debug mode"
2022-01-09 22:40:00 * A menu option has been added to restore all default Options
2022-01-09 22:40:00 * A menu option has been added to restore the last saved task
2022-01-09 22:40:00 * Crc check is now possible for uncompressed operations
2022-01-09 22:40:00 * Dutch language restored
2022-01-09 22:40:00 * Galician language updated
2022-01-09 22:40:00 * Italian language updated

2022-01-09 22:40:00 You can visit the program's website to download the new version.
2022-01-09 22:40:00 Download it at: https://www.cobiansoft.com/cobianbackup.html.

2022-01-10 00:00:00 Deleting the log files that are older than 90 days...

Cobian Reflector 2022-01-10.txt


2022-01-10 21:00:01 A backup has started...
2022-01-10 21:00:01 Calculating the number of files. This could take a while...

2022-01-10 21:05:10 Backing up the task "PARTILHA"
2022-01-10 21:05:10 Requesting the creation of a Volume Shadow Copy image for the source...
2022-01-10 21:05:23 The Volume Shadow Copy image has been successfully created.
2022-01-10 21:05:23 Starting the copy. The user running the task is: SYSTEM
2022-01-10 21:05:23 Connecting to 192.168.100.250:21...
2022-01-10 21:05:23 Connected to 192.168.100.250:21.
2022-01-10 21:05:23 Logged in into the remote server.
2022-01-10 21:05:23 Uploading the directory "D:\PARTILHA" to "/COPIAS/PARTILHA"...
2022-01-10 21:28:24 The directory "D:\PARTILHA" has been uploaded to "/COPIAS/PARTILHA".
2022-01-10 21:28:24 Disconnected from 192.168.100.250.
2022-01-10 21:28:24 * Backup part for the task "PARTILHA" has ended. Number of backed up files: 809. Backup part size: 242,2 MB. *
2022-01-10 21:28:24 ** Backup for the task "PARTILHA" has ended. Number of backed up files: 809. Created folders: 88. Backup size: 242,2 MB. **
2022-01-10 21:28:24 *** Number of errors: 0. Time elapsed for the task "PARTILHA": 0 hours, 23 minutes, 14 seconds. ***

2022-01-10 21:28:24 Deleting the Volume Shadow Copy image...
===============================================================



Please note that when errors, sometimes a few files are copied.



After these backups, there were 2 weeks with no errors. 11 incremental backups (2 of them complete) with no errors. The 12th one, incremental, got those "known" errors gain (a lot of them); but the 13th one, also incremental, finished with no errors.

Meanwhie, few incremental were finishing ok with no errors, but most of them always with errors. The complete ones (on saturdays) where always ok with no errors.

But now, the last complete backup (this weekend), also gave 4 errors:
===============================================================
This is an automatic mail message from Cobian Reflector. You will find the log files below this text.

Cobian Reflector 2022-03-18.txt

2022-03-18 21:22:19 The log files have been successfully deleted.


Cobian Reflector 2022-03-19.txt

2022-03-19 00:00:00 Deleting the log files that are older than 90 days...

2022-03-19 01:33:00 Checking for updates...
2022-03-19 01:33:00 Your program is up to date.


2022-03-19 21:00:01 A backup has started...
2022-03-19 21:00:01 Calculating the number of files. This could take a while...

2022-03-19 21:02:15 Backing up the task "PARTILHA"
2022-03-19 21:02:15 Applying the parameters to the task "PARTILHA" (if any).
2022-03-19 21:02:15 Changing the backup type to full. Reason: using a fixed day for full backups.
2022-03-19 21:02:15 Requesting the creation of a Volume Shadow Copy image for the source...
2022-03-19 21:02:26 The Volume Shadow Copy image has been successfully created.
2022-03-19 21:02:26 Starting the copy. The user running the task is: SYSTEM
2022-03-19 21:02:26 Connecting to 192.168.100.250:21...
2022-03-19 21:02:26 Connected to 192.168.100.250:21.
2022-03-19 21:02:26 Logged in into the remote server.
2022-03-19 21:02:26 Uploading the directory "D:\PARTILHA" to "/COPIAS/PARTILHA"...
ERR 2022-03-19 21:05:25 An error occurred while uploading the file "D:\PARTILHA\ARQUITETURA\JFMETAL_MZ\SOPCO\SOPCO v1.skb" to "/COPIAS/PARTILHA/ARQUITETURA/JFMETAL_MZ/SOPCO": PASV: data transfer in progress (code=450) ERR 2022-03-19 21:05:25 An error occurred while uploading the file "D:\PARTILHA\ARQUITETURA\JFMETAL_MZ\SOPCO\SOPCO v1.skp" to "/COPIAS/PARTILHA/ARQUITETURA/JFMETAL_MZ/SOPCO": Input string was not in a correct format.

Cobian Reflector 2022-03-20.txt

2022-03-20 00:00:00 Deleting the log files that are older than 90 days...

2022-03-20 01:33:00 Checking for updates...
2022-03-20 01:33:00 Your program is up to date.

ERR 2022-03-20 08:00:44 An error occurred while uploading the file "D:\PARTILHA\OBRAS\Temp\LMarinho\Documents\progeCAD 2020 - English\Samples\Electrical\Plc_2010.dwg" to "/COPIAS/PARTILHA/OBRAS/Temp/LMarinho/Documents/progeCAD 2020 - English/Samples/Electrical": Input string was not in a correct format.
ERR 2022-03-20 08:00:44 An error occurred while uploading the file "D:\PARTILHA\OBRAS\Temp\LMarinho\Documents\progeCAD 2020 - English\Samples\Electrical\Sei_multisheets.dwg" to "/COPIAS/PARTILHA/OBRAS/Temp/LMarinho/Documents/progeCAD 2020 - English/Samples/Electrical": Input string was not in a correct format.
2022-03-20 10:48:08 The directory "D:\PARTILHA" has been uploaded to "/COPIAS/PARTILHA".
2022-03-20 10:48:08 Disconnected from 192.168.100.250.

2022-03-20 10:48:08 * Backup part for the task "PARTILHA" has ended *
2022-03-20 10:48:08 * Number of backed up files: 2268509. Backup part size: 857,3 GB. *

2022-03-20 10:48:08 ** Backup for the task "PARTILHA" has ended **
2022-03-20 10:48:08 ** Processed files: 2268513. Backed up files: 2268509. Created folders: 592. Backup size: 857,3 GB. **
2022-03-20 10:48:08 ** Number of errors: 4. Time elapsed: 13 hours, 45 minutes, 52 seconds. **

2022-03-20 10:48:08 Deleting the Volume Shadow Copy image...
2022-03-20 10:48:11 The Volume Shadow Copy image has been successfully deleted.

2022-03-20 10:48:11 Mailing the log files...
2022-03-20 10:48:11 Flushing the log to file. This can take some seconds. Wait...

2022-03-20 10:48:12 The backup has ended. There are errors. Consult the log file.
===============================================================

I did a hdd check to server and NAS and they report no problems. Both Server and NAS have a 2TB HDD. Server has about 1TB free space and NAS about 500GB free space.

Is anyone familiar with these kind of errors (I believe they are know FTP errors)? Those error codes may help...?

Anyway, I'll appreciate any help.

Thank you!
Helder
Last edited by xikilin on 22 Mar 2022, 00:16, edited 1 time in total.
Tecland
Posts: 4
Joined: 11 May 2022, 16:19

Re: Incremental backup error (not always, but most of times)

Post by Tecland »

I am having the same issue, maybe because the backup is incremental? I mean, having the same configuration as you, my full backups are ok with no errors, but the incremental ones have always a lot.

Thank you
User avatar
cobian
Site Admin
Posts: 4492
Joined: 31 Oct 2020, 01:25
Location: Sweden
Contact:

Re: Incremental backup error (not always, but most of times)

Post by cobian »

The errors you are getting "Control channel unexpectedly closed" means that the server has disconnected the client. Look at the server log to find out the cause. Perhaps timeout?
--
Luis Cobian
Cobian Backup's creator
Post Reply