impossible to upload a big file (it stops by an error after a few hours)

Need help with FileZilla Client? Something does not work as expected? In this forum you may find an answer.

Moderator: Project members

Post Reply
Message
Author
JaviWan
504 Command not implemented
Posts: 7
Joined: 2020-06-18 22:16
First name: Javier
Last name: Rueda

impossible to upload a big file (it stops by an error after a few hours)

#1 Post by JaviWan » 2020-06-18 22:48

Hi!
I hope someone here knows whats's happening with my Filezilla Client.
Anyway, thanks in advance for helping me! :)

The case is that I've been uploading some files to a server (some of them of 6GB) but the problem comes when I try to upload a file of 500GB, as transfer gets an error after uploading 40GB, many times before that (22GB, even 12GB). After that, the file sometimes start uploading from 0%, other times resumes from the previously uploaded %, but keeps getting an error after some time, until finally start uploading again from 0% (it's a frustrating neverending process)

This is the client log, after with various attemps:

Code: Select all

Status:	Resolving address of ftp.XXXXXXXXXXX.com
Status:	Connecting to [IP]:21
Status:	Connection established, waiting for welcome message...
Status:	Initializing TLS...
Status:	Verifying certificate...
Status:	TLS connection established.
Status:	Logged in
Status:	Retrieving directory listing...
Status:	Directory listing of "/" successful
Status:	Retrieving directory listing of  "/DIRECTORY"...
Status:	Directory listing of "/DIRECTORY" successful
Status:	Retrieving directory listing of  "/DIRECTORY/_DIGITAL RENDERS"...
Status:	Directory listing of "/DIRECTORY/_DIGITAL RENDERS" successful
Status:	Resolving address of ftp.XXXXXXXXXXX.com
Status:	Connecting to [IP]:21
Status:	Resolving address of ftp.XXXXXXXXXXX.com
Status:	Connecting to [IP]:21
Status:	Connection established, waiting for welcome message...
Status:	Connection established, waiting for welcome message...
Status:	Initializing TLS...
Status:	Initializing TLS...
Status:	Verifying certificate...
Status:	TLS connection established.
Status:	Verifying certificate...
Status:	TLS connection established.
Status:	Logged in
Status:	Starting upload of E:\_DIGITAL RENDERS\FILE_TO_UPLOAD.mov
Status:	Logged in
Status:	Starting upload of E:\_DIGITAL RENDERS\1T&1P_PRORES4444_3840x1609_audio51-24bit_ENGSUBTS_20200607.mov
Respuesta:	421 Timeout - try typing a little faster next time
Error:	Error GnuTLS -110 en gnutls_record_recv: The TLS connection was non-properly terminated.
Status:	erver did not properly shut down TLS connection
Error:	Could not read from socket: ECONNABORTED - Connection aborted
Error:	Disconnected from server
Comando:	CWD /FILE/_DIGITAL RENDERS
Respuesta:	250 OK. Current directory is /FILE/_DIGITAL RENDERS
Comando:	PWD
Respuesta:	257 "/DIRECTORY/_DIGITAL RENDERS" is your current location
Comando:	TYPE I
Respuesta:	200 TYPE is now 8-bit binary
Comando:	PORT 192,168,0,12,196,163
Respuesta:	500 I won't open a connection to 192.168.0.12 (only to 81.61.154.51)
Comando:	PASV
Respuesta:	227 Entering Passive Mode (107,180,2,111,197,221)
Comando:	REST 11541266432
Respuesta:	350 Restarting at 11541266432
Comando:	STOR 1T&1P_PRORES4444_3840x1609_audio51-24bit_ENGSUBTS_20200607.mov
Respuesta:	150 Accepted data connection
Error:	Disconnected from server: ECONNABORTED - Conexión abortada
Error:	Transfer failed after transfering 36.591.763.456 bytes in 27917 seconds
Status:	Resolving address of ftp.XXXXXXXXXXX.com
Status:	Connecting to [IP]:21
Status:	Connection established, waiting for welcome message...
Status:	Initializing TLS...
Status:	Verifying certificate...
Status:	TLS connection established.
Status:	Logged in
Status:	Starting upload of E:\_DIGITAL RENDERS\1T&1P_PRORES4444_3840x1609_audio51-24bit_ENGSUBTS_20200607.mov
Status:	Retrieving directory listing of  "/DIRECTORY/_DIGITAL RENDERS"...
Comando:	CWD /FILE/_DIGITAL RENDERS
Respuesta:	250 OK. Current directory is /FILE/_DIGITAL RENDERS
Comando:	PWD
Respuesta:	257 "/DIRECTORY/_DIGITAL RENDERS" is your current location
Comando:	TYPE I
Respuesta:	200 TYPE is now 8-bit binary
Comando:	PORT 192,168,0,12,196,161
Respuesta:	500 I won't open a connection to 192.168.0.12 (only to 81.61.154.51)
Comando:	PASV
Respuesta:	227 Entering Passive Mode (107,180,2,111,195,143)
Comando:	REST 11546755072
Respuesta:	350 Restarting at 11546755072
Comando:	STOR FILE_TO_UPLOAD.mov
Respuesta:	150 Accepted data connection
Error:	Disconnected from server: ECONNABORTED - Conexión abortada
Error:	Transfer failed after transfering 42.491.052.032 bytes en 32417 seconds
Status:	Resolving address of ftp.XXXXXXXXXXX.com
Status:	Connecting to [IP]:21
Status:	Connection established, waiting for welcome message...
Status:	Initializing TLS...
Status:	Verifying certificate...
Status:	TLS connection established.
Status:	Logged in
Status:	Starting upload of E:\_DIGITAL RENDERS\FILE_TO_UPLOAD.mov
Status:	Retrieving directory listing of  "/DIRECTORY/_DIGITAL RENDERS"...
Comando:	TYPE I
Respuesta:	200 TYPE is now 8-bit binary
Comando:	PORT 192,168,0,12,198,118
Respuesta:	500 I won't open a connection to 192.168.0.12 (only to 81.61.154.51)
Comando:	PASV
Respuesta:	227 Entering Passive Mode (107,180,2,111,196,20)
Comando:	MLSD
Respuesta:	150 Accepted data connection
Respuesta:	226-Options: -a -l 
Respuesta:	226 4 matches total
Comando:	PORT 192,168,0,12,198,120
Respuesta:	500 I won't open a connection to 192.168.0.12 (only to 81.61.154.51)
Comando:	PASV
Respuesta:	227 Entering Passive Mode (107,180,2,111,197,148)
Comando:	STOR FILE_TO_UPLOAD.mov
Error:	Connection timed out after 20 seconds of inactivity
Error:	Transferencia fallida
Status:	Resolving address of ftp.XXXXXXXXXXX.com
Status:	Connecting to [IP]:21
Status:	Connection established, waiting for welcome message...
Status:	Initializing TLS...
Status:	Verifying certificate...
Status:	TLS connection established.
Status:	Logged in
Status:	Starting upload of E:\_DIGITAL RENDERS\FILE_TO_UPLOAD.mov
Status:	Retrieving directory listing of  "/DIRECTORY/_DIGITAL RENDERS"...
Comando:	TYPE I
Respuesta:	200 TYPE is now 8-bit binary
Comando:	PORT 192,168,0,12,198,123
Respuesta:	500 I won't open a connection to 192.168.0.12 (only to 81.61.154.51)
Comando:	PASV
Respuesta:	227 Entering Passive Mode (107,180,2,111,198,172)
Comando:	MLSD
Respuesta:	150 Accepted data connection
Respuesta:	226-Options: -a -l 
Respuesta:	226 4 matches total
Comando:	PORT 192,168,0,12,198,125
Respuesta:	500 I won't open a connection to 192.168.0.12 (only to 81.61.154.51)
Comando:	PASV
Respuesta:	227 Entering Passive Mode (107,180,2,111,197,238)
Comando:	STOR FILE_TO_UPLOAD.mov
Respuesta:	150 Accepted data connection
Error:	Connection timed out after 20 seconds of inactivity
Error:	Transfer failed after transfering 163.840 bytes en 20 seconds
Status:	Resolving address of ftp.XXXXXXXXXXX.com
Status:	Connecting to [IP]:21
Status:	Connection established, waiting for welcome message...
Status:	Initializing TLS...
Status:	Verifying certificate...
Status:	TLS connection established.
Status:	Logged in
Status:	Starting upload of E:\_DIGITAL RENDERS\FILE_TO_UPLOAD.mov
Status:	Retrieving directory listing of  "/DIRECTORY/_DIGITAL RENDERS"...
Comando:	TYPE I
Respuesta:	200 TYPE is now 8-bit binary
Comando:	PORT 192,168,0,12,198,63
Respuesta:	500 I won't open a connection to 192.168.0.12 (only to 81.61.154.51)
Comando:	PASV
Respuesta:	227 Entering Passive Mode (107,180,2,111,195,108)
Comando:	MLSD
Respuesta:	150 Accepted data connection
Respuesta:	226-Options: -a -l 
Respuesta:	226 4 matches total
Comando:	PORT 192,168,0,12,198,65
Respuesta:	500 I won't open a connection to 192.168.0.12 (only to 81.61.154.51)
Comando:	PASV
Respuesta:	227 Entering Passive Mode (107,180,2,111,195,161)
Comando:	REST 48133013504
Respuesta:	350 Restarting at 48133013504
Comando:	STOR 1T&1P_PRORES4444_3840x1609_audio51-24bit_ENGSUBTS_20200607.mov
Respuesta:	150 Accepted data connection
Error:	Disconnected from server: ECONNABORTED - Conexión abortada
Error:	Transfer failed after transfering 12.994.592.768 bytes en 9911 seconds
Status:	Resolving address of ftp.XXXXXXXXXXX.com
Status:	Connecting to [IP]:21
Status:	Connection established, waiting for welcome message...
Status:	Initializing TLS...
Status:	Verifying certificate...
Status:	TLS connection established.
Status:	Logged in
Status:	Starting upload of E:\_DIGITAL RENDERS\1T&1P_PRORES4444_3840x1609_audio51-24bit_ENGSUBTS_20200607.mov
Status:	Retrieving directory listing of  "/DIRECTORY/_DIGITAL RENDERS"...
Comando:	TYPE I
Respuesta:	200 TYPE is now 8-bit binary
Comando:	PORT 192,168,0,12,198,220
Respuesta:	500 I won't open a connection to 192.168.0.12 (only to 81.61.154.51)
Comando:	PASV
Respuesta:	227 Entering Passive Mode (107,180,2,111,195,136)
Comando:	MLSD
Respuesta:	150 Accepted data connection
Respuesta:	226-Options: -a -l 
Respuesta:	226 4 matches total
Comando:	PORT 192,168,0,12,198,222
Respuesta:	500 I won't open a connection to 192.168.0.12 (only to 81.61.154.51)
Comando:	PASV
Respuesta:	227 Entering Passive Mode (107,180,2,111,195,142)
Comando:	STOR 1T&1P_PRORES4444_3840x1609_audio51-24bit_ENGSUBTS_20200607.mov
Error:	Connection timed out after 20 seconds of inactivity
Error:	Transferencia fallida
Status:	Resolving address of ftp.XXXXXXXXXXX.com
Status:	Connecting to [IP]:21
Status:	Connection established, waiting for welcome message...
Status:	Initializing TLS...
Status:	Verifying certificate...
Status:	TLS connection established.
Status:	Logged in
Status:	Starting upload of E:\_DIGITAL RENDERS\1T&1P_PRORES4444_3840x1609_audio51-24bit_ENGSUBTS_20200607.mov
Status:	Retrieving directory listing of  "/DIRECTORY/_DIGITAL RENDERS"...
Comando:	TYPE I
Respuesta:	200 TYPE is now 8-bit binary
Comando:	PORT 192,168,0,12,198,128
Respuesta:	500 I won't open a connection to 192.168.0.12 (only to 81.61.154.51)
Comando:	PASV
Respuesta:	227 Entering Passive Mode (107,180,2,111,195,171)
Comando:	MLSD
Respuesta:	150 Accepted data connection
Respuesta:	226-Options: -a -l 
Respuesta:	226 4 matches total
Comando:	PORT 192,168,0,12,198,130
Respuesta:	500 I won't open a connection to 192.168.0.12 (only to 81.61.154.51)
Comando:	PASV
Respuesta:	227 Entering Passive Mode (107,180,2,111,197,142)
Comando:	STOR FILE_TO_UPLOAD.mov
Respuesta:	150 Accepted data connection
Comando:	TYPE I
Respuesta:	200 TYPE is now 8-bit binary
Comando:	PORT 192,168,0,12,198,225
Respuesta:	500 I won't open a connection to 192.168.0.12 (only to 81.61.154.51)
Comando:	PASV
Respuesta:	227 Entering Passive Mode (107,180,2,111,196,68)
Comando:	MLSD
Respuesta:	150 Accepted data connection
Respuesta:	226-Options: -a -l 
Respuesta:	226 4 matches total
Comando:	PORT 192,168,0,12,198,227
Respuesta:	500 I won't open a connection to 192.168.0.12 (only to 81.61.154.51)
Comando:	PASV
Respuesta:	227 Entering Passive Mode (107,180,2,111,198,165)
Comando:	STOR 1T&1P_PRORES4444_3840x1609_audio51-24bit_ENGSUBTS_20200607.mov
Respuesta:	150 Accepted data connection

User avatar
botg
Site Admin
Posts: 33128
Joined: 2004-02-23 20:49
First name: Tim
Last name: Kosse
Contact:

Re: impossible to upload a big file (it stops by an error after a few hours)

#2 Post by botg » 2020-06-19 07:29

Firstly, your client is configured to use active mode, but doesn't know its own IP address. Switch to passive mode (preferred) or configure it correctly.

Transfer failures are bound to happen eventually especially if you continuously transfer data for many hours. The reasons are plentiful an diverse. To give just a few examples: Dropped packets, routing changes, IP address changes, flipped bits or a NAT router forgetting its tables.


While FileZilla automatically resumes after a transfer failure, it can only do so if the server tells it the amount of data it has received. If you enable "Show raw directory listings" on the debug page in the settings dialog of FileZilla, what do you see in a message log after a transfer failure when FileZilla tries to resume, but starts over at zero?

JaviWan
504 Command not implemented
Posts: 7
Joined: 2020-06-18 22:16
First name: Javier
Last name: Rueda

Re: impossible to upload a big file (it stops by an error after a few hours)

#3 Post by JaviWan » 2020-06-19 13:35

Thanks for helpingme botg, you're so kind.

Understood, I will use passive mode from now on.

About the message log when FileZilla tries to resume but starts over at zero, I just activated the debug mode (3-verbose level) and check the box "Show raw directory listings". So, as soon as it happen, I will come back here to answer you.

Thanks for everything!

Javi

User avatar
boco
Contributor
Posts: 25259
Joined: 2006-05-01 03:28
Location: Germany

Re: impossible to upload a big file (it stops by an error after a few hours)

#4 Post by boco » 2020-06-19 21:08

You can also activate logging to file in the settings, as the screen log can only hold a certain amount of lines.
### BEGIN SIGNATURE BLOCK ###
No support requests per PM! You will NOT get any reply!!!
FTP connection problems? Do yourself a favor and read Network Configuration.
FileZilla connection test: https://filezilla-project.org/conntest.php
### END SIGNATURE BLOCK ###

JaviWan
504 Command not implemented
Posts: 7
Joined: 2020-06-18 22:16
First name: Javier
Last name: Rueda

Re: impossible to upload a big file (it stops by an error after a few hours)

#5 Post by JaviWan » 2020-06-20 15:13

Thanks for your advice boco.

Here is my log.
At 03:27:57 appears the error that makes the file upload fail and unables it to resume.
I hope it helps you find the problem.
Thanks again in advance!

STARTING THE CONNECTION FROM ZERO

Code: Select all

20:26:49	Status:	Resolving address of ftp.example.com
20:26:49	Status:	Connecting to XXX.XXX.X.XXX:21...
20:26:49	Status:	Connection established, waiting for welcome message...
20:26:50	Status:	Initializing TLS...
20:26:50	Status:	Verifying certificate...
20:26:50	Status:	TLS connection established.
20:26:51	Status:	Logged in
20:26:51	Status:	Retrieving directory listing...
20:26:52	Status:	Directory listing of "/" successful
20:27:08	Status:	Retrieving directory listing of "/SERVER_FOLDER"...
20:27:08	Command:	CWD SERVER_FOLDER
20:27:08	Response:	250 OK. Current directory is /SERVER_FOLDER
20:27:08	Command:	PWD
20:27:08	Response:	257 "/SERVER_FOLDER" is your current location
20:27:08	Command:	PASV
20:27:08	Response:	227 Entering Passive Mode (107,180,2,111,196,183)
20:27:08	Command:	MLSD
20:27:08	Response:	150 Accepted data connection
20:27:09	Response:	226-Options: -a -l 
20:27:09	Response:	226 6 matches total
20:27:09	Status:	Directory listing of "/SERVER_FOLDER" successful
20:27:12	Status:	Retrieving directory listing of "/SERVER_MAIN_FOLDER/FOLDER DESTINATION"...
20:27:12	Command:	CWD FOLDER DESTINATION
20:27:12	Response:	250 OK. Current directory is /SERVER_MAIN_FOLDER/FOLDER DESTINATION
20:27:12	Command:	PWD
20:27:12	Response:	257 "/SERVER_MAIN_FOLDER/FOLDER DESTINATION" is your current location
20:27:12	Command:	PASV
20:27:12	Response:	227 Entering Passive Mode (107,180,2,111,196,167)
20:27:12	Command:	MLSD
20:27:12	Response:	150 Accepted data connection
20:27:13	Response:	226-Options: -a -l 
20:27:13	Response:	226 4 matches total
20:27:13	Status:	Directory listing of "/SERVER_MAIN_FOLDER/FOLDER DESTINATION" successful
20:27:19	Status:	Resolving address of ftp.example.com
20:27:19	Status:	Connecting to XXX.XXX.X.XXX:21...
20:27:19	Status:	Resolving address of ftp.example.com
20:27:19	Status:	Connecting to XXX.XXX.X.XXX:21...
20:27:19	Status:	Connection established, waiting for welcome message...
20:27:19	Status:	Connection established, waiting for welcome message...
20:27:19	Response:	220---------- Welcome to Pure-FTPd [privsep] [TLS] ----------
20:27:19	Response:	220-You are user number 2 of 500 allowed.
20:27:19	Response:	220-Local time is now 11:27. Server port: 21.
20:27:19	Response:	220-This is a private system - No anonymous login
20:27:19	Response:	220 You will be disconnected after 15 minutes of inactivity.
20:27:19	Command:	AUTH TLS
20:27:19	Response:	220---------- Welcome to Pure-FTPd [privsep] [TLS] ----------
20:27:19	Response:	220-You are user number 3 of 500 allowed.
20:27:19	Response:	220-Local time is now 11:27. Server port: 21.
20:27:19	Response:	220-This is a private system - No anonymous login
20:27:19	Response:	220 You will be disconnected after 15 minutes of inactivity.
20:27:19	Command:	AUTH TLS
20:27:19	Response:	234 AUTH TLS OK.
20:27:19	Status:	Initializing TLS...
20:27:19	Response:	234 AUTH TLS OK.
20:27:19	Status:	Initializing TLS...
20:27:20	Status:	Verifying certificate...
20:27:20	Status:	Verifying certificate...
20:27:20	Status:	TLS connection established.
20:27:20	Command:	USER user@example.com
20:27:20	Status:	TLS connection established.
20:27:20	Command:	USER user@example.com
20:27:20	Response:	331 User user@example.com OK. Password required
20:27:20	Command:	PASS ************
20:27:20	Response:	331 User user@example.com OK. Password required
20:27:20	Command:	PASS ************
20:27:20	Response:	230-Your bandwidth usage is restricted
20:27:20	Response:	230 OK. Current restricted directory is /
20:27:20	Command:	OPTS UTF8 ON
20:27:20	Response:	230-Your bandwidth usage is restricted
20:27:20	Response:	230 OK. Current restricted directory is /
20:27:20	Command:	OPTS UTF8 ON
20:27:20	Response:	200 OK, UTF-8 enabled
20:27:20	Command:	PBSZ 0
20:27:20	Response:	200 OK, UTF-8 enabled
20:27:20	Command:	PBSZ 0
20:27:20	Response:	200 PBSZ=0
20:27:20	Command:	PROT P
20:27:20	Response:	200 PBSZ=0
20:27:20	Command:	PROT P
20:27:20	Response:	200 Data protection level set to "private"
20:27:20	Status:	Logged in
20:27:20	Status:	Starting upload of E:\FOLDER DESTINATION\MY_FILE
20:27:20	Command:	CWD /SERVER_MAIN_FOLDER/FOLDER DESTINATION
20:27:20	Response:	200 Data protection level set to "private"
20:27:20	Status:	Logged in
20:27:20	Status:	Starting upload of E:\MY_FOLDER\MY_FILE
20:27:20	Response:	250 OK. Current directory is /SERVER_MAIN_FOLDER/FOLDER DESTINATION
20:27:20	Command:	PWD
20:27:20	Response:	257 "/SERVER_MAIN_FOLDER/FOLDER DESTINATION" is your current location
20:27:20	Command:	CWD /SERVER_MAIN_FOLDER/FOLDER DESTINATION
20:27:21	Response:	250 OK. Current directory is /SERVER_MAIN_FOLDER/FOLDER DESTINATION
20:27:21	Command:	PWD
20:27:21	Response:	257 "/SERVER_MAIN_FOLDER/FOLDER DESTINATION" is your current location
20:27:30	Command:	TYPE I
20:27:30	Response:	200 TYPE is now 8-bit binary
20:27:30	Command:	PASV
20:27:30	Response:	227 Entering Passive Mode (107,180,2,111,195,243)
20:27:30	Command:	REST 32768
20:27:30	Response:	350 Restarting at 32768
20:27:30	Command:	STOR MY_FILE
20:27:31	Response:	150 Accepted data connection
20:27:32	Command:	TYPE I
20:27:32	Response:	200 TYPE is now 8-bit binary
20:27:32	Command:	PASV
20:27:32	Response:	227 Entering Passive Mode (107,180,2,111,195,152)
20:27:32	Command:	REST 32768
20:27:33	Response:	350 Restarting at 32768
20:27:33	Command:	STOR 1T&1P_PRORES4444_3840x1609_audio51-24bit_ENGSUBTS_20200607.mov
20:27:33	Response:	150 Accepted data connection
20:27:43	Status:	Sending keep-alive command
20:27:43	Command:	NOOP
20:27:43	Response:	200 Zzz...
20:28:13	Status:	Sending keep-alive command
20:28:13	Command:	NOOP
20:28:13	Response:	200 Zzz...
20:28:43	Status:	Sending keep-alive command
20:28:43	Command:	NOOP
20:28:43	Response:	200 Zzz...
20:29:13	Status:	Sending keep-alive command
20:29:13	Command:	NOOP
20:29:13	Response:	200 Zzz...
20:29:43	Status:	Sending keep-alive command
20:29:43	Command:	PWD
20:29:43	Response:	257 "/SERVER_MAIN_FOLDER/FOLDER DESTINATION" is your current location
20:30:13	Status:	Sending keep-alive command
20:30:13	Command:	PWD
20:30:13	Response:	257 "/SERVER_MAIN_FOLDER/FOLDER DESTINATION" is your current location
20:30:43	Status:	Sending keep-alive command
20:30:43	Command:	PWD
20:30:44	Response:	257 "/SERVER_MAIN_FOLDER/FOLDER DESTINATION" is your current location
20:31:14	Status:	Sending keep-alive command
20:31:14	Command:	PWD
20:31:14	Response:	257 "/SERVER_MAIN_FOLDER/FOLDER DESTINATION" is your current location
20:31:44	Status:	Sending keep-alive command
20:31:44	Command:	NOOP
20:31:44	Response:	200 Zzz...
20:32:14	Status:	Sending keep-alive command
20:32:14	Command:	TYPE I
20:32:14	Response:	200 TYPE is now 8-bit binary
20:32:44	Status:	Sending keep-alive command
20:32:44	Command:	PWD
20:32:44	Response:	257 "/SERVER_MAIN_FOLDER/FOLDER DESTINATION" is your current location
20:33:14	Status:	Sending keep-alive command
20:33:14	Command:	PWD
20:33:14	Response:	257 "/SERVER_MAIN_FOLDER/FOLDER DESTINATION" is your current location
20:33:44	Status:	Sending keep-alive command
20:33:44	Command:	NOOP
20:33:44	Response:	200 Zzz...
20:34:14	Status:	Sending keep-alive command
20:34:14	Command:	TYPE I
20:34:14	Response:	200 TYPE is now 8-bit binary
20:34:44	Status:	Sending keep-alive command
20:34:44	Command:	TYPE I
20:34:45	Response:	200 TYPE is now 8-bit binary
20:35:15	Status:	Sending keep-alive command
20:35:15	Command:	TYPE I
20:35:15	Response:	200 TYPE is now 8-bit binary
20:35:45	Status:	Sending keep-alive command
20:35:45	Command:	NOOP
20:35:45	Response:	200 Zzz...
20:36:15	Status:	Sending keep-alive command
20:36:15	Command:	NOOP
20:36:15	Response:	200 Zzz...
20:36:45	Status:	Sending keep-alive command
20:36:45	Command:	PWD
20:36:45	Response:	257 "/SERVER_MAIN_FOLDER/FOLDER DESTINATION" is your current location
20:37:15	Status:	Sending keep-alive command
20:37:15	Command:	PWD
20:37:15	Response:	257 "/SERVER_MAIN_FOLDER/FOLDER DESTINATION" is your current location
20:37:45	Status:	Sending keep-alive command
20:37:45	Command:	TYPE I
20:37:45	Response:	200 TYPE is now 8-bit binary
20:38:15	Status:	Sending keep-alive command
20:38:15	Command:	TYPE I
20:38:15	Response:	200 TYPE is now 8-bit binary
20:38:45	Status:	Sending keep-alive command
20:38:45	Command:	TYPE I
20:38:45	Response:	200 TYPE is now 8-bit binary
20:39:15	Status:	Sending keep-alive command
20:39:15	Command:	TYPE I
20:39:16	Response:	200 TYPE is now 8-bit binary
20:39:46	Status:	Sending keep-alive command
20:39:46	Command:	TYPE I
20:39:46	Response:	200 TYPE is now 8-bit binary
20:40:16	Status:	Sending keep-alive command
20:40:16	Command:	TYPE I
20:40:16	Response:	200 TYPE is now 8-bit binary
20:40:46	Status:	Sending keep-alive command
20:40:46	Command:	NOOP
20:40:46	Response:	200 Zzz...
20:41:16	Status:	Sending keep-alive command
20:41:16	Command:	NOOP
20:41:16	Response:	200 Zzz...
20:41:46	Status:	Sending keep-alive command
20:41:46	Command:	NOOP
20:41:46	Response:	200 Zzz...
20:42:16	Status:	Sending keep-alive command
20:42:16	Command:	TYPE I
20:42:16	Response:	200 TYPE is now 8-bit binary
20:42:46	Status:	Sending keep-alive command
20:42:46	Command:	PWD
20:42:46	Response:	257 "/SERVER_MAIN_FOLDER/FOLDER DESTINATION" is your current location
20:43:16	Status:	Sending keep-alive command
20:43:16	Command:	TYPE I
20:43:17	Response:	200 TYPE is now 8-bit binary
20:43:47	Status:	Sending keep-alive command
20:43:47	Command:	NOOP
20:43:47	Response:	200 Zzz...
20:44:17	Status:	Sending keep-alive command
20:44:17	Command:	NOOP
20:44:17	Response:	200 Zzz...
20:44:47	Status:	Sending keep-alive command
20:44:47	Command:	NOOP
20:44:47	Response:	200 Zzz...
20:45:17	Status:	Sending keep-alive command
20:45:17	Command:	NOOP
20:45:17	Response:	200 Zzz...
20:45:47	Status:	Sending keep-alive command
20:45:47	Command:	PWD
20:45:47	Response:	257 "/SERVER_MAIN_FOLDER/FOLDER DESTINATION" is your current location
20:46:17	Status:	Sending keep-alive command
20:46:17	Command:	NOOP
20:46:17	Response:	200 Zzz...
20:46:47	Status:	Sending keep-alive command
20:46:47	Command:	PWD
20:46:47	Response:	257 "/SERVER_MAIN_FOLDER/FOLDER DESTINATION" is your current location
20:47:17	Status:	Sending keep-alive command
20:47:17	Command:	PWD
20:47:18	Response:	257 "/SERVER_MAIN_FOLDER/FOLDER DESTINATION" is your current location
20:47:48	Status:	Sending keep-alive command
20:47:48	Command:	NOOP
20:47:48	Response:	200 Zzz...
20:48:18	Status:	Sending keep-alive command
20:48:18	Command:	PWD
20:48:18	Response:	257 "/SERVER_MAIN_FOLDER/FOLDER DESTINATION" is your current location
20:48:48	Status:	Sending keep-alive command
20:48:48	Command:	PWD
20:48:48	Response:	257 "/SERVER_MAIN_FOLDER/FOLDER DESTINATION" is your current location
20:49:18	Status:	Sending keep-alive command
20:49:18	Command:	TYPE I
20:49:18	Response:	200 TYPE is now 8-bit binary
20:49:48	Status:	Sending keep-alive command
20:49:48	Command:	NOOP
20:49:48	Response:	200 Zzz...
20:50:18	Status:	Sending keep-alive command
20:50:18	Command:	TYPE I
20:50:18	Response:	200 TYPE is now 8-bit binary
20:50:48	Status:	Sending keep-alive command
20:50:48	Command:	PWD
20:50:48	Response:	257 "/SERVER_MAIN_FOLDER/FOLDER DESTINATION" is your current location
20:51:18	Status:	Sending keep-alive command
20:51:18	Command:	TYPE I
20:51:18	Response:	200 TYPE is now 8-bit binary
20:51:48	Status:	Sending keep-alive command
20:51:48	Command:	TYPE I
20:51:49	Response:	200 TYPE is now 8-bit binary
20:52:19	Status:	Sending keep-alive command
20:52:19	Command:	PWD
20:52:19	Response:	257 "/SERVER_MAIN_FOLDER/FOLDER DESTINATION" is your current location
20:52:49	Status:	Sending keep-alive command
20:52:49	Command:	PWD
20:52:49	Response:	257 "/SERVER_MAIN_FOLDER/FOLDER DESTINATION" is your current location
20:53:19	Status:	Sending keep-alive command
20:53:19	Command:	NOOP
20:53:19	Response:	200 Zzz...
20:53:49	Status:	Sending keep-alive command
20:53:49	Command:	NOOP
20:53:49	Response:	200 Zzz...
20:54:19	Status:	Sending keep-alive command
20:54:19	Command:	PWD
20:54:19	Response:	257 "/SERVER_MAIN_FOLDER/FOLDER DESTINATION" is your current location
20:54:49	Status:	Sending keep-alive command
20:54:49	Command:	NOOP
20:54:49	Response:	200 Zzz...
20:55:19	Status:	Sending keep-alive command
20:55:19	Command:	PWD
20:55:19	Response:	257 "/SERVER_MAIN_FOLDER/FOLDER DESTINATION" is your current location
20:55:49	Status:	Sending keep-alive command
20:55:49	Command:	NOOP
20:55:50	Response:	200 Zzz...
20:56:20	Status:	Sending keep-alive command
20:56:20	Command:	NOOP
20:56:20	Response:	200 Zzz...
20:56:50	Status:	Sending keep-alive command
20:56:50	Command:	PWD
20:56:50	Response:	257 "/SERVER_MAIN_FOLDER/FOLDER DESTINATION" is your current location
20:57:20	Status:	Sending keep-alive command
20:57:20	Command:	NOOP
20:57:20	Response:	200 Zzz...
21:12:20	Response:	421 Timeout - try typing a little faster next time
21:12:20	Error:	GnuTLS error -110 in gnutls_record_recv: The TLS connection was non-properly terminated.
21:12:20	Status:	Server did not properly shut down TLS connection
21:12:20	Error:	Could not read from socket: ECONNABORTED - Connection aborted
21:12:20	Error:	Disconnected from server
21:27:41	Error:	Disconnected from server: ECONNABORTED - Connection aborted
21:27:41	Error:	File transfer failed after transferring 536.444.928 bytes in 3610 seconds
21:27:41	Status:	Resolving address of ftp.example.com
21:27:41	Status:	Connecting to XXX.XXX.X.XXX:21...
21:27:41	Status:	Connection established, waiting for welcome message...
21:27:41	Response:	220---------- Welcome to Pure-FTPd [privsep] [TLS] ----------
21:27:41	Response:	220-You are user number 7 of 500 allowed.
21:27:41	Response:	220-Local time is now 12:27. Server port: 21.
21:27:41	Response:	220-This is a private system - No anonymous login
21:27:41	Response:	220 You will be disconnected after 15 minutes of inactivity.
21:27:41	Command:	AUTH TLS
21:27:41	Response:	234 AUTH TLS OK.
21:27:41	Status:	Initializing TLS...
21:27:42	Status:	Verifying certificate...
21:27:42	Status:	TLS connection established.
21:27:42	Command:	USER user@example.com
21:27:42	Response:	331 User user@example.com OK. Password required
21:27:42	Command:	PASS ************
21:27:42	Response:	230-Your bandwidth usage is restricted
21:27:42	Response:	230 OK. Current restricted directory is /
21:27:42	Command:	OPTS UTF8 ON
21:27:42	Response:	200 OK, UTF-8 enabled
21:27:42	Command:	PBSZ 0
21:27:42	Response:	200 PBSZ=0
21:27:42	Command:	PROT P
21:27:42	Response:	200 Data protection level set to "private"
21:27:42	Status:	Logged in
21:27:42	Status:	Starting upload of E:\FOLDER DESTINATION\MY_FILE
21:27:42	Command:	CWD /SERVER_MAIN_FOLDER/FOLDER DESTINATION
21:27:42	Response:	250 OK. Current directory is /SERVER_MAIN_FOLDER/FOLDER DESTINATION
21:27:42	Status:	Retrieving directory listing of "/SERVER_MAIN_FOLDER/FOLDER DESTINATION"...
21:27:42	Command:	TYPE I
21:27:43	Response:	200 TYPE is now 8-bit binary
21:27:43	Command:	PASV
21:27:43	Response:	227 Entering Passive Mode (107,180,2,111,195,149)
21:27:43	Command:	MLSD
21:27:43	Response:	150 Accepted data connection
21:27:43	Response:	226-Options: -a -l 
21:27:43	Response:	226 4 matches total
21:27:43	Command:	PASV
21:27:43	Response:	227 Entering Passive Mode (107,180,2,111,197,41)
21:27:43	Command:	REST 536461312
21:27:44	Response:	350 Restarting at 536461312
21:27:44	Command:	STOR MY_FILE
21:27:44	Response:	150 Accepted data connection
23:42:44	Error:	Disconnected from server: ECONNABORTED - Connection aborted
23:42:44	Error:	File transfer failed after transferring 15.352.004.608 bytes in 11711 seconds
23:42:44	Status:	Resolving address of ftp.example.com
23:42:44	Status:	Connecting to XXX.XXX.X.XXX:21...
23:42:44	Status:	Connection established, waiting for welcome message...
23:42:44	Response:	220---------- Welcome to Pure-FTPd [privsep] [TLS] ----------
23:42:44	Response:	220-You are user number 3 of 500 allowed.
23:42:44	Response:	220-Local time is now 14:42. Server port: 21.
23:42:44	Response:	220-This is a private system - No anonymous login
23:42:44	Response:	220 You will be disconnected after 15 minutes of inactivity.
23:42:44	Command:	AUTH TLS
23:42:45	Response:	234 AUTH TLS OK.
23:42:45	Status:	Initializing TLS...
23:42:45	Status:	Verifying certificate...
23:42:45	Status:	TLS connection established.
23:42:45	Command:	USER user@example.com
23:42:45	Response:	331 User user@example.com OK. Password required
23:42:45	Command:	PASS ************
23:42:45	Response:	230-Your bandwidth usage is restricted
23:42:45	Response:	230 OK. Current restricted directory is /
23:42:45	Command:	OPTS UTF8 ON
23:42:45	Response:	200 OK, UTF-8 enabled
23:42:45	Command:	PBSZ 0
23:42:45	Response:	200 PBSZ=0
23:42:45	Command:	PROT P
23:42:46	Response:	200 Data protection level set to "private"
23:42:46	Status:	Logged in
23:42:46	Status:	Starting upload of E:\MY_FOLDER\MY_FILE
23:42:46	Command:	CWD /SERVER_MAIN_FOLDER/FOLDER DESTINATION
23:42:46	Response:	250 OK. Current directory is /SERVER_MAIN_FOLDER/FOLDER DESTINATION
23:42:46	Status:	Retrieving directory listing of "/SERVER_MAIN_FOLDER/FOLDER DESTINATION"...
23:42:46	Command:	TYPE I
23:42:46	Response:	200 TYPE is now 8-bit binary
23:42:46	Command:	PASV
23:42:46	Response:	227 Entering Passive Mode (107,180,2,111,196,127)
23:42:46	Command:	MLSD
23:42:46	Response:	150 Accepted data connection
23:42:47	Response:	226-Options: -a -l 
23:42:47	Response:	226 4 matches total
23:42:47	Command:	PASV
23:42:47	Response:	227 Entering Passive Mode (107,180,2,111,199,35)
23:42:47	Command:	REST 15352020992
23:42:47	Response:	350 Restarting at 15352020992
23:42:47	Command:	STOR 1T&1P_PRORES4444_3840x1609_audio51-24bit_ENGSUBTS_20200607.mov
23:42:47	Response:	150 Accepted data connection
03:27:57	Error:	Disconnected from server: ECONNABORTED - Connection aborted
03:27:57	Error:	File transfer failed after transferring 28.332.457.984 bytes in 21613 seconds
03:27:57	Status:	Resolving address of ftp.example.com
03:27:57	Status:	Connecting to XXX.XXX.X.XXX:21...
03:27:57	Status:	Connection established, waiting for welcome message...
03:27:57	Response:	220---------- Welcome to Pure-FTPd [privsep] [TLS] ----------
03:27:57	Response:	220-You are user number 5 of 500 allowed.
03:27:57	Response:	220-Local time is now 18:27. Server port: 21.
03:27:57	Response:	220-This is a private system - No anonymous login
03:27:57	Response:	220 You will be disconnected after 15 minutes of inactivity.
03:27:57	Command:	AUTH TLS
03:27:57	Response:	234 AUTH TLS OK.
03:27:57	Status:	Initializing TLS...
03:27:58	Status:	Verifying certificate...
03:27:58	Status:	TLS connection established.
03:27:58	Command:	USER user@example.com
03:27:58	Response:	331 User user@example.com OK. Password required
03:27:58	Command:	PASS ************
03:27:58	Response:	230-Your bandwidth usage is restricted
03:27:58	Response:	230 OK. Current restricted directory is /
03:27:58	Command:	OPTS UTF8 ON
03:27:58	Response:	200 OK, UTF-8 enabled
03:27:58	Command:	PBSZ 0
03:27:58	Response:	200 PBSZ=0
03:27:58	Command:	PROT P
03:27:58	Response:	200 Data protection level set to "private"
03:27:58	Status:	Logged in
03:27:58	Status:	Starting upload of E:\FOLDER DESTINATION\MY_FILE
03:27:58	Command:	CWD /SERVER_MAIN_FOLDER/FOLDER DESTINATION
03:27:58	Response:	250 OK. Current directory is /SERVER_MAIN_FOLDER/FOLDER DESTINATION
03:27:58	Status:	Retrieving directory listing of "/SERVER_MAIN_FOLDER/FOLDER DESTINATION"...
03:27:58	Command:	TYPE I
03:27:59	Response:	200 TYPE is now 8-bit binary
03:27:59	Command:	PASV
03:27:59	Response:	227 Entering Passive Mode (107,180,2,111,198,189)
03:27:59	Command:	MLSD
03:27:59	Response:	150 Accepted data connection
03:27:59	Response:	226-Options: -a -l 
03:27:59	Response:	226 4 matches total
03:27:59	Command:	PASV
03:27:59	Response:	227 Entering Passive Mode (107,180,2,111,196,20)
03:27:59	Command:	STOR MY_FILE
03:28:00	Response:	150 Accepted data connection

User avatar
botg
Site Admin
Posts: 33128
Joined: 2004-02-23 20:49
First name: Tim
Last name: Kosse
Contact:

Re: impossible to upload a big file (it stops by an error after a few hours)

#6 Post by botg » 2020-06-22 07:08

Unfortunately the option to display raw directory listings wasn't enabled in this log. Please provide a log with this option enabled.

JaviWan
504 Command not implemented
Posts: 7
Joined: 2020-06-18 22:16
First name: Javier
Last name: Rueda

Re: impossible to upload a big file (it stops by an error after a few hours)

#7 Post by JaviWan » 2020-06-23 23:42

Please excuse me botg. I didn't noticed that filezilla resets that "raw directory listing" anytime I restart the software.

Here it is the log (you'll find the error at: 22:00:24).
Thanks a lot in advance!

Code: Select all

18:50:26	Trace:	CFtpControlSocket::SetAsyncRequestReply
18:50:26	Status:	TLS connection established.
18:50:26	Trace:	CControlSocket::SendNextCommand()
18:50:26	Trace:	CFtpLogonOpData::Send() in state 6
18:50:26	Command:	USER FTP@example.com
18:50:26	Trace:	CFtpControlSocket::OnReceive()
18:50:26	Trace:	CFtpControlSocket::OnReceive()
18:50:26	Response:	331 User FTP@example.com OK. Password required
18:50:26	Trace:	CFtpLogonOpData::ParseResponse() in state 6
18:50:26	Trace:	CControlSocket::SendNextCommand()
18:50:26	Trace:	CFtpLogonOpData::Send() in state 6
18:50:26	Command:	PASS ************
18:50:26	Trace:	CFtpControlSocket::OnReceive()
18:50:26	Response:	230-Your bandwidth usage is restricted
18:50:26	Response:	230 OK. Current restricted directory is /
18:50:26	Trace:	CFtpLogonOpData::ParseResponse() in state 6
18:50:26	Trace:	CControlSocket::SendNextCommand()
18:50:26	Trace:	CFtpLogonOpData::Send() in state 7
18:50:26	Command:	SYST
18:50:27	Trace:	CFtpControlSocket::OnReceive()
18:50:27	Response:	215 UNIX Type: L8
18:50:27	Trace:	CFtpLogonOpData::ParseResponse() in state 7
18:50:27	Trace:	CControlSocket::SendNextCommand()
18:50:27	Trace:	CFtpLogonOpData::Send() in state 8
18:50:27	Command:	FEAT
18:50:27	Trace:	CFtpControlSocket::OnReceive()
18:50:27	Response:	211-Extensions supported:
18:50:27	Response:	 EPRT
18:50:27	Response:	 IDLE
18:50:27	Response:	 MDTM
18:50:27	Response:	 SIZE
18:50:27	Response:	 MFMT
18:50:27	Response:	 REST STREAM
18:50:27	Response:	 MLST type*;size*;sizd*;modify*;UNIX.mode*;UNIX.uid*;UNIX.gid*;unique*;
18:50:27	Response:	 MLSD
18:50:27	Response:	 AUTH TLS
18:50:27	Response:	 PBSZ
18:50:27	Response:	 PROT
18:50:27	Response:	 UTF8
18:50:27	Response:	 TVFS
18:50:27	Response:	 ESTA
18:50:27	Response:	 PASV
18:50:27	Response:	 EPSV
18:50:27	Response:	 SPSV
18:50:27	Response:	 ESTP
18:50:27	Response:	211 End.
18:50:27	Trace:	CFtpLogonOpData::ParseResponse() in state 8
18:50:27	Trace:	CControlSocket::SendNextCommand()
18:50:27	Trace:	CFtpLogonOpData::Send() in state 10
18:50:27	Command:	OPTS UTF8 ON
18:50:27	Trace:	CFtpControlSocket::OnReceive()
18:50:27	Response:	200 OK, UTF-8 enabled
18:50:27	Trace:	CFtpLogonOpData::ParseResponse() in state 10
18:50:27	Trace:	CControlSocket::SendNextCommand()
18:50:27	Trace:	CFtpLogonOpData::Send() in state 11
18:50:27	Command:	PBSZ 0
18:50:27	Trace:	CFtpControlSocket::OnReceive()
18:50:27	Response:	200 PBSZ=0
18:50:27	Trace:	CFtpLogonOpData::ParseResponse() in state 11
18:50:27	Trace:	CControlSocket::SendNextCommand()
18:50:27	Trace:	CFtpLogonOpData::Send() in state 12
18:50:27	Command:	PROT P
18:50:27	Trace:	CFtpControlSocket::OnReceive()
18:50:27	Response:	200 Data protection level set to "private"
18:50:27	Trace:	CFtpLogonOpData::ParseResponse() in state 12
18:50:27	Status:	Logged in
18:50:27	Trace:	Measured latency of 126 ms
18:50:27	Trace:	CFtpControlSocket::ResetOperation(0)
18:50:27	Trace:	CControlSocket::ResetOperation(0)
18:50:27	Trace:	CFtpLogonOpData::Reset(0) in state 15
18:50:27	Trace:	CControlSocket::SendNextCommand()
18:50:27	Trace:	CFtpListOpData::Send() in state 0
18:50:27	Status:	Retrieving directory listing...
18:50:27	Trace:	CFtpChangeDirOpData::Send() in state 0
18:50:27	Trace:	CFtpChangeDirOpData::Send() in state 1
18:50:27	Command:	PWD
18:50:27	Trace:	CFtpControlSocket::OnReceive()
18:50:27	Response:	257 "/" is your current location
18:50:27	Trace:	CFtpChangeDirOpData::ParseResponse() in state 1
18:50:27	Trace:	CFtpControlSocket::ResetOperation(0)
18:50:27	Trace:	CControlSocket::ResetOperation(0)
18:50:27	Trace:	CFtpChangeDirOpData::Reset(0) in state 1
18:50:27	Trace:	CFtpListOpData::SubcommandResult(0) in state 1
18:50:27	Trace:	CControlSocket::SendNextCommand()
18:50:27	Trace:	CFtpListOpData::Send() in state 2
18:50:27	Trace:	CFtpRawTransferOpData::Send() in state 0
18:50:27	Trace:	CFtpRawTransferOpData::Send() in state 1
18:50:27	Command:	TYPE I
18:50:27	Trace:	CFtpControlSocket::OnReceive()
18:50:27	Response:	200 TYPE is now 8-bit binary
18:50:27	Trace:	CFtpRawTransferOpData::ParseResponse() in state 1
18:50:27	Trace:	CControlSocket::SendNextCommand()
18:50:27	Trace:	CFtpRawTransferOpData::Send() in state 2
18:50:27	Command:	PASV
18:50:27	Trace:	CFtpControlSocket::OnReceive()
18:50:27	Response:	227 Entering Passive Mode (107,180,2,111,198,236)
18:50:27	Trace:	CFtpRawTransferOpData::ParseResponse() in state 2
18:50:27	Trace:	CControlSocket::SendNextCommand()
18:50:27	Trace:	CFtpRawTransferOpData::Send() in state 4
18:50:27	Trace:	Binding data connection source IP to control connection source IP 192.168.0.162
18:50:27	Trace:	tls_layer_impl::client_handshake()
18:50:27	Trace:	Trying to resume existing TLS session.
18:50:27	Command:	MLSD
18:50:28	Trace:	tls_layer_impl::continue_handshake()
18:50:28	Trace:	CFtpControlSocket::OnReceive()
18:50:28	Response:	150 Accepted data connection
18:50:28	Trace:	CFtpRawTransferOpData::ParseResponse() in state 4
18:50:28	Trace:	CControlSocket::SendNextCommand()
18:50:28	Trace:	CFtpRawTransferOpData::Send() in state 5
18:50:28	Trace:	tls_layer_impl::continue_handshake()
18:50:28	Trace:	TLS Handshake successful
18:50:28	Trace:	TLS Session resumed
18:50:28	Trace:	Protocol: TLS1.2, Key exchange: ECDHE-SECP256R1, Cipher: AES-256-GCM, MAC: AEAD
18:50:28	Trace:	tls_layer_impl::verify_certificate()
18:50:28	Trace:	CTransferSocket::OnConnect
18:50:28	Trace:	CTransferSocket::TransferEnd(1)
18:50:28	Trace:	tls_layer_impl::shutdown()
18:50:28	Trace:	tls_layer_impl::continue_shutdown()
18:50:28	Trace:	CFtpControlSocket::TransferEnd()
18:50:28	Trace:	CFtpControlSocket::OnReceive()
18:50:28	Response:	226-Options: -a -l 
18:50:28	Response:	226 4 matches total
18:50:28	Trace:	CFtpRawTransferOpData::ParseResponse() in state 7
18:50:28	Trace:	CFtpControlSocket::ResetOperation(0)
18:50:28	Trace:	CControlSocket::ResetOperation(0)
18:50:28	Trace:	CFtpRawTransferOpData::Reset(0) in state 7
18:50:28	Trace:	CFtpListOpData::SubcommandResult(0) in state 3
18:50:28	Listing:	type=cdir;sizd=4096;modify=20200616164712;UNIX.mode=0755;UNIX.uid=1340207;UNIX.gid=1340207;unique=fd04g8ca3af9; .
18:50:28	Listing:	type=pdir;sizd=4096;modify=20200616164712;UNIX.mode=0755;UNIX.uid=1340207;UNIX.gid=1340207;unique=fd04g8ca3af9; ..
18:50:28	Listing:	type=dir;sizd=4096;modify=20200608235757;UNIX.mode=0755;UNIX.uid=1340207;UNIX.gid=1340207;unique=fd04g8ca4a09; SERVER_FOLDER
18:50:28	Listing:	type=dir;sizd=4096;modify=20200617040724;UNIX.mode=0755;UNIX.uid=1340207;UNIX.gid=1340207;unique=fd04g8cc407d; The Bomb movie
18:50:28	Trace:	CFtpControlSocket::ResetOperation(0)
18:50:28	Trace:	CControlSocket::ResetOperation(0)
18:50:28	Trace:	CFtpListOpData::Reset(0) in state 3
18:50:28	Status:	Directory listing of "/" successful
18:50:45	Trace:	CControlSocket::SendNextCommand()
18:50:45	Trace:	CFtpLogonOpData::Send() in state 0
18:50:45	Status:	Resolving address of ftp.example.com
18:50:45	Status:	Connecting to XXX.XXX.X.XXX:21...
18:50:45	Status:	Connection established, waiting for welcome message...
18:50:45	Trace:	CFtpControlSocket::OnReceive()
18:50:45	Response:	220---------- Welcome to Pure-FTPd [privsep] [TLS] ----------
18:50:45	Response:	220-You are user number 5 of 500 allowed.
18:50:45	Response:	220-Local time is now 09:50. Server port: 21.
18:50:45	Response:	220-This is a private system - No anonymous login
18:50:45	Response:	220 You will be disconnected after 15 minutes of inactivity.
18:50:45	Trace:	CFtpLogonOpData::ParseResponse() in state 1
18:50:45	Trace:	CControlSocket::SendNextCommand()
18:50:45	Trace:	CFtpLogonOpData::Send() in state 2
18:50:45	Command:	AUTH TLS
18:50:45	Trace:	CFtpControlSocket::OnReceive()
18:50:45	Response:	234 AUTH TLS OK.
18:50:45	Trace:	CFtpLogonOpData::ParseResponse() in state 2
18:50:45	Status:	Initializing TLS...
18:50:45	Trace:	tls_layer_impl::client_handshake()
18:50:45	Trace:	tls_layer_impl::continue_handshake()
18:50:45	Trace:	tls_layer_impl::continue_handshake()
18:50:45	Trace:	tls_layer_impl::continue_handshake()
18:50:45	Trace:	tls_layer_impl::continue_handshake()
18:50:46	Trace:	tls_layer_impl::continue_handshake()
18:50:46	Trace:	TLS Handshake successful
18:50:46	Trace:	Protocol: TLS1.2, Key exchange: ECDHE-SECP256R1-RSA-SHA384, Cipher: AES-256-GCM, MAC: AEAD
18:50:46	Trace:	tls_layer_impl::verify_certificate()
18:50:46	Trace:	Hostname does not match certificate SANs
18:50:46	Status:	Verifying certificate...
18:50:46	Trace:	CFtpControlSocket::SetAsyncRequestReply
18:50:46	Status:	TLS connection established.
18:50:46	Trace:	CControlSocket::SendNextCommand()
18:50:46	Trace:	CFtpLogonOpData::Send() in state 6
18:50:46	Command:	USER FTP@example.com
18:50:46	Trace:	CFtpControlSocket::OnReceive()
18:50:46	Trace:	CFtpControlSocket::OnReceive()
18:50:46	Response:	331 User FTP@example.com OK. Password required
18:50:46	Trace:	CFtpLogonOpData::ParseResponse() in state 6
18:50:46	Trace:	CControlSocket::SendNextCommand()
18:50:46	Trace:	CFtpLogonOpData::Send() in state 6
18:50:46	Command:	PASS ************
18:50:46	Trace:	CFtpControlSocket::OnReceive()
18:50:46	Response:	230-Your bandwidth usage is restricted
18:50:46	Response:	230 OK. Current restricted directory is /
18:50:46	Trace:	CFtpLogonOpData::ParseResponse() in state 6
18:50:46	Trace:	CControlSocket::SendNextCommand()
18:50:46	Trace:	CFtpLogonOpData::Send() in state 10
18:50:46	Command:	OPTS UTF8 ON
18:50:46	Trace:	CFtpControlSocket::OnReceive()
18:50:46	Response:	200 OK, UTF-8 enabled
18:50:46	Trace:	CFtpLogonOpData::ParseResponse() in state 10
18:50:46	Trace:	CControlSocket::SendNextCommand()
18:50:46	Trace:	CFtpLogonOpData::Send() in state 11
18:50:46	Command:	PBSZ 0
18:50:46	Trace:	CFtpControlSocket::OnReceive()
18:50:46	Response:	200 PBSZ=0
18:50:46	Trace:	CFtpLogonOpData::ParseResponse() in state 11
18:50:46	Trace:	CControlSocket::SendNextCommand()
18:50:46	Trace:	CFtpLogonOpData::Send() in state 12
18:50:46	Command:	PROT P
18:50:46	Trace:	CFtpControlSocket::OnReceive()
18:50:46	Response:	200 Data protection level set to "private"
18:50:46	Trace:	CFtpLogonOpData::ParseResponse() in state 12
18:50:46	Status:	Logged in
18:50:46	Trace:	Measured latency of 125 ms
18:50:46	Trace:	CFtpControlSocket::ResetOperation(0)
18:50:46	Trace:	CControlSocket::ResetOperation(0)
18:50:46	Trace:	CFtpLogonOpData::Reset(0) in state 15
18:50:46	Trace:	CFtpControlSocket::FileTransfer()
18:50:46	Trace:	CControlSocket::SendNextCommand()
18:50:46	Trace:	CFtpFileTransferOpData::Send() in state 0
18:50:46	Status:	Starting upload of E:\_DIGITAL RENDERS\FILE_TO_UPLOAD.MOV
18:50:46	Trace:	CFtpChangeDirOpData::Send() in state 0
18:50:46	Trace:	CFtpChangeDirOpData::Send() in state 2
18:50:46	Command:	CWD /SERVER_FOLDER
18:50:46	Trace:	CFtpControlSocket::OnReceive()
18:50:46	Response:	250 OK. Current directory is /SERVER_FOLDER
18:50:46	Trace:	CFtpChangeDirOpData::ParseResponse() in state 2
18:50:46	Trace:	CControlSocket::SendNextCommand()
18:50:46	Trace:	CFtpChangeDirOpData::Send() in state 3
18:50:46	Command:	PWD
18:50:46	Trace:	CFtpControlSocket::OnReceive()
18:50:46	Response:	257 "/SERVER_FOLDER" is your current location
18:50:46	Trace:	CFtpChangeDirOpData::ParseResponse() in state 3
18:50:46	Trace:	CFtpControlSocket::ResetOperation(0)
18:50:46	Trace:	CControlSocket::ResetOperation(0)
18:50:46	Trace:	CFtpChangeDirOpData::Reset(0) in state 3
18:50:46	Trace:	CFtpFileTransferOpData::SubcommandResult(0) in state 1
18:50:46	Trace:	CControlSocket::SendNextCommand()
18:50:46	Trace:	CFtpListOpData::Send() in state 0
18:50:46	Status:	Retrieving directory listing of "/SERVER_FOLDER"...
18:50:46	Trace:	CFtpChangeDirOpData::Send() in state 0
18:50:46	Trace:	CFtpControlSocket::ResetOperation(0)
18:50:46	Trace:	CControlSocket::ResetOperation(0)
18:50:46	Trace:	CFtpChangeDirOpData::Reset(0) in state 0
18:50:46	Trace:	CFtpListOpData::SubcommandResult(0) in state 1
18:50:46	Trace:	CControlSocket::SendNextCommand()
18:50:46	Trace:	CFtpListOpData::Send() in state 2
18:50:46	Trace:	CFtpRawTransferOpData::Send() in state 0
18:50:46	Trace:	CFtpRawTransferOpData::Send() in state 1
18:50:46	Command:	TYPE I
18:50:47	Trace:	CFtpControlSocket::OnReceive()
18:50:47	Response:	200 TYPE is now 8-bit binary
18:50:47	Trace:	CFtpRawTransferOpData::ParseResponse() in state 1
18:50:47	Trace:	CControlSocket::SendNextCommand()
18:50:47	Trace:	CFtpRawTransferOpData::Send() in state 2
18:50:47	Command:	PASV
18:50:47	Trace:	CFtpControlSocket::OnReceive()
18:50:47	Response:	227 Entering Passive Mode (107,180,2,111,196,228)
18:50:47	Trace:	CFtpRawTransferOpData::ParseResponse() in state 2
18:50:47	Trace:	CControlSocket::SendNextCommand()
18:50:47	Trace:	CFtpRawTransferOpData::Send() in state 4
18:50:47	Trace:	Binding data connection source IP to control connection source IP 192.168.0.162
18:50:47	Trace:	tls_layer_impl::client_handshake()
18:50:47	Trace:	Trying to resume existing TLS session.
18:50:47	Command:	MLSD
18:50:47	Trace:	tls_layer_impl::continue_handshake()
18:50:47	Trace:	tls_layer_impl::continue_handshake()
18:50:47	Trace:	TLS Handshake successful
18:50:47	Trace:	TLS Session resumed
18:50:47	Trace:	Protocol: TLS1.2, Key exchange: ECDHE-SECP256R1, Cipher: AES-256-GCM, MAC: AEAD
18:50:47	Trace:	tls_layer_impl::verify_certificate()
18:50:47	Trace:	CTransferSocket::OnConnect
18:50:47	Trace:	CFtpControlSocket::OnReceive()
18:50:47	Response:	150 Accepted data connection
18:50:47	Trace:	CFtpRawTransferOpData::ParseResponse() in state 4
18:50:47	Trace:	CControlSocket::SendNextCommand()
18:50:47	Trace:	CFtpRawTransferOpData::Send() in state 5
18:50:47	Listing:	type=cdir;sizd=4096;modify=20200608235757;UNIX.mode=0755;UNIX.uid=1340207;UNIX.gid=1340207;unique=fd04g8ca4a09; .
18:50:47	Listing:	type=pdir;sizd=4096;modify=20200616164712;UNIX.mode=0755;UNIX.uid=1340207;UNIX.gid=1340207;unique=fd04g8ca3af9; ..
18:50:47	Listing:	type=dir;sizd=4096;modify=20200608213030;UNIX.mode=0755;UNIX.uid=1340207;UNIX.gid=1340207;unique=fd04g8ca4a0e; AUDIO_MIXES
18:50:47	Listing:	type=file;size=30886;modify=20200608201432;UNIX.mode=0644;UNIX.uid=1340207;UNIX.gid=1340207;unique=fd04g8ca4a0b; ENGLISH SUBTITLES.srt
18:50:47	Listing:	type=dir;sizd=4096;modify=20200608201433;UNIX.mode=0755;UNIX.uid=1340207;UNIX.gid=1340207;unique=fd04g8ca4a0a; OTHER LANGUAGES SUBTITLES
18:50:47	Listing:	type=dir;sizd=4096;modify=20200623164943;UNIX.mode=0755;UNIX.uid=1340207;UNIX.gid=1340207;unique=fd04g8ca4a2a; _DIGITAL RENDERS
18:50:47	Trace:	CTransferSocket::TransferEnd(1)
18:50:47	Trace:	tls_layer_impl::shutdown()
18:50:47	Trace:	tls_layer_impl::continue_shutdown()
18:50:47	Trace:	CFtpControlSocket::TransferEnd()
18:50:47	Trace:	CFtpControlSocket::OnReceive()
18:50:47	Response:	226-Options: -a -l 
18:50:47	Response:	226 6 matches total
18:50:47	Trace:	CFtpRawTransferOpData::ParseResponse() in state 7
18:50:47	Trace:	CFtpControlSocket::ResetOperation(0)
18:50:47	Trace:	CControlSocket::ResetOperation(0)
18:50:47	Trace:	CFtpRawTransferOpData::Reset(0) in state 7
18:50:47	Trace:	CFtpListOpData::SubcommandResult(0) in state 3
18:50:47	Trace:	CFtpControlSocket::ResetOperation(0)
18:50:47	Trace:	CControlSocket::ResetOperation(0)
18:50:47	Trace:	CFtpListOpData::Reset(0) in state 3
18:50:47	Trace:	CFtpFileTransferOpData::SubcommandResult(0) in state 2
18:50:47	Trace:	CControlSocket::SendNextCommand()
18:50:47	Trace:	CFtpFileTransferOpData::Send() in state 5
18:50:47	Trace:	CFtpRawTransferOpData::Send() in state 0
18:50:47	Trace:	CFtpRawTransferOpData::Send() in state 2
18:50:47	Command:	PASV
18:50:47	Trace:	CFtpControlSocket::OnReceive()
18:50:47	Response:	227 Entering Passive Mode (107,180,2,111,196,106)
18:50:47	Trace:	CFtpRawTransferOpData::ParseResponse() in state 2
18:50:47	Trace:	CControlSocket::SendNextCommand()
18:50:47	Trace:	CFtpRawTransferOpData::Send() in state 4
18:50:47	Trace:	Binding data connection source IP to control connection source IP 192.168.0.162
18:50:47	Trace:	tls_layer_impl::client_handshake()
18:50:47	Trace:	Trying to resume existing TLS session.
18:50:47	Command:	STOR FILE_TO_UPLOAD.MOV
18:50:48	Trace:	tls_layer_impl::continue_handshake()
18:50:48	Trace:	CFtpControlSocket::OnReceive()
18:50:48	Response:	150 Accepted data connection
18:50:48	Trace:	CFtpRawTransferOpData::ParseResponse() in state 4
18:50:48	Trace:	CControlSocket::SendNextCommand()
18:50:48	Trace:	CFtpRawTransferOpData::Send() in state 5
18:50:48	Trace:	tls_layer_impl::continue_handshake()
18:50:48	Trace:	TLS Handshake successful
18:50:48	Trace:	TLS Session resumed
18:50:48	Trace:	Protocol: TLS1.2, Key exchange: ECDHE-SECP256R1, Cipher: AES-256-GCM, MAC: AEAD
18:50:48	Trace:	tls_layer_impl::verify_certificate()
18:50:48	Trace:	CTransferSocket::OnConnect
18:50:58	Status:	Sending keep-alive command
18:50:58	Command:	NOOP
18:50:58	Trace:	CFtpControlSocket::OnReceive()
18:50:58	Response:	200 Zzz...
18:50:58	Trace:	Skipping reply after cancelled operation or keepalive command.
18:51:28	Status:	Sending keep-alive command
18:51:28	Command:	NOOP
18:51:28	Trace:	CFtpControlSocket::OnReceive()
18:51:28	Response:	200 Zzz...
18:51:28	Trace:	Skipping reply after cancelled operation or keepalive command.
18:51:58	Status:	Sending keep-alive command
18:51:58	Command:	PWD
18:51:58	Trace:	CFtpControlSocket::OnReceive()
18:51:58	Response:	257 "/" is your current location
18:51:58	Trace:	Skipping reply after cancelled operation or keepalive command.
18:52:28	Status:	Sending keep-alive command
18:52:28	Command:	PWD
18:52:29	Trace:	CFtpControlSocket::OnReceive()
18:52:29	Response:	257 "/" is your current location
18:52:29	Trace:	Skipping reply after cancelled operation or keepalive command.
18:52:59	Status:	Sending keep-alive command
18:52:59	Command:	NOOP
18:52:59	Trace:	CFtpControlSocket::OnReceive()
18:52:59	Response:	200 Zzz...
18:52:59	Trace:	Skipping reply after cancelled operation or keepalive command.
18:53:29	Status:	Sending keep-alive command
18:53:29	Command:	PWD
18:53:29	Trace:	CFtpControlSocket::OnReceive()
18:53:29	Response:	257 "/" is your current location
18:53:29	Trace:	Skipping reply after cancelled operation or keepalive command.
18:53:59	Status:	Sending keep-alive command
18:53:59	Command:	TYPE I
18:53:59	Trace:	CFtpControlSocket::OnReceive()
18:53:59	Response:	200 TYPE is now 8-bit binary
18:53:59	Trace:	Skipping reply after cancelled operation or keepalive command.
18:54:29	Status:	Sending keep-alive command
18:54:29	Command:	PWD
18:54:29	Trace:	CFtpControlSocket::OnReceive()
18:54:29	Response:	257 "/" is your current location
18:54:29	Trace:	Skipping reply after cancelled operation or keepalive command.
18:54:59	Status:	Sending keep-alive command
18:54:59	Command:	NOOP
18:54:59	Trace:	CFtpControlSocket::OnReceive()
18:54:59	Response:	200 Zzz...
18:54:59	Trace:	Skipping reply after cancelled operation or keepalive command.
18:55:29	Status:	Sending keep-alive command
18:55:29	Command:	NOOP
18:55:29	Trace:	CFtpControlSocket::OnReceive()
18:55:29	Response:	200 Zzz...
18:55:29	Trace:	Skipping reply after cancelled operation or keepalive command.
18:55:59	Status:	Sending keep-alive command
18:55:59	Command:	TYPE I
18:55:59	Trace:	CFtpControlSocket::OnReceive()
18:55:59	Response:	200 TYPE is now 8-bit binary
18:55:59	Trace:	Skipping reply after cancelled operation or keepalive command.
18:56:29	Status:	Sending keep-alive command
18:56:29	Command:	TYPE I
18:56:30	Trace:	CFtpControlSocket::OnReceive()
18:56:30	Response:	200 TYPE is now 8-bit binary
18:56:30	Trace:	Skipping reply after cancelled operation or keepalive command.
18:57:00	Status:	Sending keep-alive command
18:57:00	Command:	TYPE I
18:57:00	Trace:	CFtpControlSocket::OnReceive()
18:57:00	Response:	200 TYPE is now 8-bit binary
18:57:00	Trace:	Skipping reply after cancelled operation or keepalive command.
18:57:30	Status:	Sending keep-alive command
18:57:30	Command:	PWD
18:57:30	Trace:	CFtpControlSocket::OnReceive()
18:57:30	Response:	257 "/" is your current location
18:57:30	Trace:	Skipping reply after cancelled operation or keepalive command.
18:58:00	Status:	Sending keep-alive command
18:58:00	Command:	TYPE I
18:58:00	Trace:	CFtpControlSocket::OnReceive()
18:58:00	Response:	200 TYPE is now 8-bit binary
18:58:00	Trace:	Skipping reply after cancelled operation or keepalive command.
18:58:30	Status:	Sending keep-alive command
18:58:30	Command:	TYPE I
18:58:30	Trace:	CFtpControlSocket::OnReceive()
18:58:30	Response:	200 TYPE is now 8-bit binary
18:58:30	Trace:	Skipping reply after cancelled operation or keepalive command.
18:59:00	Status:	Sending keep-alive command
18:59:00	Command:	PWD
18:59:00	Trace:	CFtpControlSocket::OnReceive()
18:59:00	Response:	257 "/" is your current location
18:59:00	Trace:	Skipping reply after cancelled operation or keepalive command.
18:59:30	Status:	Sending keep-alive command
18:59:30	Command:	NOOP
18:59:30	Trace:	CFtpControlSocket::OnReceive()
18:59:30	Response:	200 Zzz...
18:59:30	Trace:	Skipping reply after cancelled operation or keepalive command.
19:00:00	Status:	Sending keep-alive command
19:00:00	Command:	NOOP
19:00:00	Trace:	CFtpControlSocket::OnReceive()
19:00:00	Response:	200 Zzz...
19:00:00	Trace:	Skipping reply after cancelled operation or keepalive command.
19:00:30	Status:	Sending keep-alive command
19:00:30	Command:	TYPE I
19:00:31	Trace:	CFtpControlSocket::OnReceive()
19:00:31	Response:	200 TYPE is now 8-bit binary
19:00:31	Trace:	Skipping reply after cancelled operation or keepalive command.
19:01:01	Status:	Sending keep-alive command
19:01:01	Command:	PWD
19:01:01	Trace:	CFtpControlSocket::OnReceive()
19:01:01	Response:	257 "/" is your current location
19:01:01	Trace:	Skipping reply after cancelled operation or keepalive command.
19:01:31	Status:	Sending keep-alive command
19:01:31	Command:	PWD
19:01:31	Trace:	CFtpControlSocket::OnReceive()
19:01:31	Response:	257 "/" is your current location
19:01:31	Trace:	Skipping reply after cancelled operation or keepalive command.
19:02:01	Status:	Sending keep-alive command
19:02:01	Command:	TYPE I
19:02:01	Trace:	CFtpControlSocket::OnReceive()
19:02:01	Response:	200 TYPE is now 8-bit binary
19:02:01	Trace:	Skipping reply after cancelled operation or keepalive command.
19:02:31	Status:	Sending keep-alive command
19:02:31	Command:	PWD
19:02:31	Trace:	CFtpControlSocket::OnReceive()
19:02:31	Response:	257 "/" is your current location
19:02:31	Trace:	Skipping reply after cancelled operation or keepalive command.
19:03:01	Status:	Sending keep-alive command
19:03:01	Command:	TYPE I
19:03:01	Trace:	CFtpControlSocket::OnReceive()
19:03:01	Response:	200 TYPE is now 8-bit binary
19:03:01	Trace:	Skipping reply after cancelled operation or keepalive command.
19:03:31	Status:	Sending keep-alive command
19:03:31	Command:	TYPE I
19:03:31	Trace:	CFtpControlSocket::OnReceive()
19:03:31	Response:	200 TYPE is now 8-bit binary
19:03:31	Trace:	Skipping reply after cancelled operation or keepalive command.
19:04:01	Status:	Sending keep-alive command
19:04:01	Command:	PWD
19:04:01	Trace:	CFtpControlSocket::OnReceive()
19:04:01	Response:	257 "/" is your current location
19:04:01	Trace:	Skipping reply after cancelled operation or keepalive command.
19:04:31	Status:	Sending keep-alive command
19:04:31	Command:	TYPE I
19:04:32	Trace:	CFtpControlSocket::OnReceive()
19:04:32	Response:	200 TYPE is now 8-bit binary
19:04:32	Trace:	Skipping reply after cancelled operation or keepalive command.
19:05:02	Status:	Sending keep-alive command
19:05:02	Command:	PWD
19:05:02	Trace:	CFtpControlSocket::OnReceive()
19:05:02	Response:	257 "/" is your current location
19:05:02	Trace:	Skipping reply after cancelled operation or keepalive command.
19:05:32	Status:	Sending keep-alive command
19:05:32	Command:	TYPE I
19:05:32	Trace:	CFtpControlSocket::OnReceive()
19:05:32	Response:	200 TYPE is now 8-bit binary
19:05:32	Trace:	Skipping reply after cancelled operation or keepalive command.
19:06:02	Status:	Sending keep-alive command
19:06:02	Command:	PWD
19:06:02	Trace:	CFtpControlSocket::OnReceive()
19:06:02	Response:	257 "/" is your current location
19:06:02	Trace:	Skipping reply after cancelled operation or keepalive command.
19:06:32	Status:	Sending keep-alive command
19:06:32	Command:	TYPE I
19:06:32	Trace:	CFtpControlSocket::OnReceive()
19:06:32	Response:	200 TYPE is now 8-bit binary
19:06:32	Trace:	Skipping reply after cancelled operation or keepalive command.
19:07:02	Status:	Sending keep-alive command
19:07:02	Command:	PWD
19:07:02	Trace:	CFtpControlSocket::OnReceive()
19:07:02	Response:	257 "/" is your current location
19:07:02	Trace:	Skipping reply after cancelled operation or keepalive command.
19:07:32	Status:	Sending keep-alive command
19:07:32	Command:	PWD
19:07:32	Trace:	CFtpControlSocket::OnReceive()
19:07:32	Response:	257 "/" is your current location
19:07:32	Trace:	Skipping reply after cancelled operation or keepalive command.
19:08:02	Status:	Sending keep-alive command
19:08:02	Command:	NOOP
19:08:02	Trace:	CFtpControlSocket::OnReceive()
19:08:02	Response:	200 Zzz...
19:08:02	Trace:	Skipping reply after cancelled operation or keepalive command.
19:08:32	Status:	Sending keep-alive command
19:08:32	Command:	PWD
19:08:33	Trace:	CFtpControlSocket::OnReceive()
19:08:33	Response:	257 "/" is your current location
19:08:33	Trace:	Skipping reply after cancelled operation or keepalive command.
19:09:03	Status:	Sending keep-alive command
19:09:03	Command:	PWD
19:09:03	Trace:	CFtpControlSocket::OnReceive()
19:09:03	Response:	257 "/" is your current location
19:09:03	Trace:	Skipping reply after cancelled operation or keepalive command.
19:09:33	Status:	Sending keep-alive command
19:09:33	Command:	PWD
19:09:33	Trace:	CFtpControlSocket::OnReceive()
19:09:33	Response:	257 "/" is your current location
19:09:33	Trace:	Skipping reply after cancelled operation or keepalive command.
19:10:03	Status:	Sending keep-alive command
19:10:03	Command:	TYPE I
19:10:03	Trace:	CFtpControlSocket::OnReceive()
19:10:03	Response:	200 TYPE is now 8-bit binary
19:10:03	Trace:	Skipping reply after cancelled operation or keepalive command.
19:10:33	Status:	Sending keep-alive command
19:10:33	Command:	TYPE I
19:10:33	Trace:	CFtpControlSocket::OnReceive()
19:10:33	Response:	200 TYPE is now 8-bit binary
19:10:33	Trace:	Skipping reply after cancelled operation or keepalive command.
19:11:03	Status:	Sending keep-alive command
19:11:03	Command:	PWD
19:11:03	Trace:	CFtpControlSocket::OnReceive()
19:11:03	Response:	257 "/" is your current location
19:11:03	Trace:	Skipping reply after cancelled operation or keepalive command.
19:11:33	Status:	Sending keep-alive command
19:11:33	Command:	PWD
19:11:33	Trace:	CFtpControlSocket::OnReceive()
19:11:33	Response:	257 "/" is your current location
19:11:33	Trace:	Skipping reply after cancelled operation or keepalive command.
19:12:03	Status:	Sending keep-alive command
19:12:03	Command:	NOOP
19:12:04	Trace:	CFtpControlSocket::OnReceive()
19:12:04	Response:	200 Zzz...
19:12:04	Trace:	Skipping reply after cancelled operation or keepalive command.
19:12:34	Status:	Sending keep-alive command
19:12:34	Command:	TYPE I
19:12:34	Trace:	CFtpControlSocket::OnReceive()
19:12:34	Response:	200 TYPE is now 8-bit binary
19:12:34	Trace:	Skipping reply after cancelled operation or keepalive command.
19:13:04	Status:	Sending keep-alive command
19:13:04	Command:	NOOP
19:13:04	Trace:	CFtpControlSocket::OnReceive()
19:13:04	Response:	200 Zzz...
19:13:04	Trace:	Skipping reply after cancelled operation or keepalive command.
19:13:34	Status:	Sending keep-alive command
19:13:34	Command:	TYPE I
19:13:34	Trace:	CFtpControlSocket::OnReceive()
19:13:34	Response:	200 TYPE is now 8-bit binary
19:13:34	Trace:	Skipping reply after cancelled operation or keepalive command.
19:14:04	Status:	Sending keep-alive command
19:14:04	Command:	NOOP
19:14:04	Trace:	CFtpControlSocket::OnReceive()
19:14:04	Response:	200 Zzz...
19:14:04	Trace:	Skipping reply after cancelled operation or keepalive command.
19:14:34	Status:	Sending keep-alive command
19:14:34	Command:	PWD
19:14:34	Trace:	CFtpControlSocket::OnReceive()
19:14:34	Response:	257 "/" is your current location
19:14:34	Trace:	Skipping reply after cancelled operation or keepalive command.
19:15:04	Status:	Sending keep-alive command
19:15:04	Command:	PWD
19:15:04	Trace:	CFtpControlSocket::OnReceive()
19:15:04	Response:	257 "/" is your current location
19:15:04	Trace:	Skipping reply after cancelled operation or keepalive command.
19:15:34	Status:	Sending keep-alive command
19:15:34	Command:	PWD
19:15:34	Trace:	CFtpControlSocket::OnReceive()
19:15:34	Response:	257 "/" is your current location
19:15:34	Trace:	Skipping reply after cancelled operation or keepalive command.
19:16:04	Status:	Sending keep-alive command
19:16:04	Command:	TYPE I
19:16:05	Trace:	CFtpControlSocket::OnReceive()
19:16:05	Response:	200 TYPE is now 8-bit binary
19:16:05	Trace:	Skipping reply after cancelled operation or keepalive command.
19:16:35	Status:	Sending keep-alive command
19:16:35	Command:	PWD
19:16:35	Trace:	CFtpControlSocket::OnReceive()
19:16:35	Response:	257 "/" is your current location
19:16:35	Trace:	Skipping reply after cancelled operation or keepalive command.
19:17:05	Status:	Sending keep-alive command
19:17:05	Command:	PWD
19:17:05	Trace:	CFtpControlSocket::OnReceive()
19:17:05	Response:	257 "/" is your current location
19:17:05	Trace:	Skipping reply after cancelled operation or keepalive command.
19:17:35	Status:	Sending keep-alive command
19:17:35	Command:	NOOP
19:17:35	Trace:	CFtpControlSocket::OnReceive()
19:17:35	Response:	200 Zzz...
19:17:35	Trace:	Skipping reply after cancelled operation or keepalive command.
19:18:05	Status:	Sending keep-alive command
19:18:05	Command:	NOOP
19:18:05	Trace:	CFtpControlSocket::OnReceive()
19:18:05	Response:	200 Zzz...
19:18:05	Trace:	Skipping reply after cancelled operation or keepalive command.
19:18:35	Status:	Sending keep-alive command
19:18:35	Command:	NOOP
19:18:35	Trace:	CFtpControlSocket::OnReceive()
19:18:35	Response:	200 Zzz...
19:18:35	Trace:	Skipping reply after cancelled operation or keepalive command.
19:19:05	Status:	Sending keep-alive command
19:19:05	Command:	TYPE I
19:19:05	Trace:	CFtpControlSocket::OnReceive()
19:19:05	Response:	200 TYPE is now 8-bit binary
19:19:05	Trace:	Skipping reply after cancelled operation or keepalive command.
19:19:35	Status:	Sending keep-alive command
19:19:35	Command:	NOOP
19:19:35	Trace:	CFtpControlSocket::OnReceive()
19:19:35	Response:	200 Zzz...
19:19:35	Trace:	Skipping reply after cancelled operation or keepalive command.
19:20:05	Status:	Sending keep-alive command
19:20:05	Command:	PWD
19:20:06	Trace:	CFtpControlSocket::OnReceive()
19:20:06	Response:	257 "/" is your current location
19:20:06	Trace:	Skipping reply after cancelled operation or keepalive command.
19:20:36	Status:	Sending keep-alive command
19:20:36	Command:	PWD
19:20:36	Trace:	CFtpControlSocket::OnReceive()
19:20:36	Response:	257 "/" is your current location
19:20:36	Trace:	Skipping reply after cancelled operation or keepalive command.
19:35:36	Trace:	CFtpControlSocket::OnReceive()
19:35:36	Response:	421 Timeout - try typing a little faster next time
19:35:36	Trace:	Unexpected reply, no reply was pending.
19:35:36	Trace:	CFtpControlSocket::OnReceive()
19:35:36	Error:	GnuTLS error -110 in gnutls_record_recv: The TLS connection was non-properly terminated.
19:35:36	Status:	Server did not properly shut down TLS connection
19:35:36	Error:	Could not read from socket: ECONNABORTED - Connection aborted
19:35:36	Error:	Disconnected from server
19:35:36	Trace:	CFtpControlSocket::ResetOperation(66)
19:35:36	Trace:	CControlSocket::ResetOperation(66)
21:21:01	Trace:	CRealControlSocket::OnSocketError(106)
21:21:01	Error:	Disconnected from server: ECONNABORTED - Connection aborted
21:21:01	Trace:	CFtpControlSocket::ResetOperation(66)
21:21:01	Trace:	CControlSocket::ResetOperation(66)
21:21:01	Trace:	CFtpRawTransferOpData::Reset(66) in state 5
21:21:01	Trace:	CFtpControlSocket::ResetOperation(66)
21:21:01	Trace:	CControlSocket::ResetOperation(66)
21:21:01	Trace:	CFtpFileTransferOpData::Reset(66) in state 7
21:21:01	Error:	File transfer failed after transferring 11.814.535.168 bytes in 9013 seconds
21:21:01	Trace:	CFtpControlSocket::FileTransfer()
21:21:01	Trace:	CControlSocket::SendNextCommand()
21:21:01	Trace:	CFtpLogonOpData::Send() in state 0
21:21:01	Status:	Resolving address of ftp.example.com
21:21:01	Status:	Connecting to XXX.XXX.X.XXX:21...
21:21:01	Status:	Connection established, waiting for welcome message...
21:21:01	Trace:	CFtpControlSocket::OnReceive()
21:21:01	Response:	220---------- Welcome to Pure-FTPd [privsep] [TLS] ----------
21:21:01	Response:	220-You are user number 6 of 500 allowed.
21:21:01	Response:	220-Local time is now 12:21. Server port: 21.
21:21:01	Response:	220-This is a private system - No anonymous login
21:21:01	Response:	220 You will be disconnected after 15 minutes of inactivity.
21:21:01	Trace:	CFtpLogonOpData::ParseResponse() in state 1
21:21:01	Trace:	CControlSocket::SendNextCommand()
21:21:01	Trace:	CFtpLogonOpData::Send() in state 2
21:21:01	Command:	AUTH TLS
21:21:01	Trace:	CFtpControlSocket::OnReceive()
21:21:01	Response:	234 AUTH TLS OK.
21:21:01	Trace:	CFtpLogonOpData::ParseResponse() in state 2
21:21:01	Status:	Initializing TLS...
21:21:01	Trace:	tls_layer_impl::client_handshake()
21:21:01	Trace:	tls_layer_impl::continue_handshake()
21:21:01	Trace:	tls_layer_impl::continue_handshake()
21:21:01	Trace:	tls_layer_impl::continue_handshake()
21:21:01	Trace:	tls_layer_impl::continue_handshake()
21:21:01	Trace:	tls_layer_impl::continue_handshake()
21:21:01	Trace:	TLS Handshake successful
21:21:01	Trace:	Protocol: TLS1.2, Key exchange: ECDHE-SECP256R1-RSA-SHA384, Cipher: AES-256-GCM, MAC: AEAD
21:21:01	Trace:	tls_layer_impl::verify_certificate()
21:21:01	Trace:	Hostname does not match certificate SANs
21:21:01	Status:	Verifying certificate...
21:21:01	Trace:	CFtpControlSocket::SetAsyncRequestReply
21:21:01	Status:	TLS connection established.
21:21:01	Trace:	CControlSocket::SendNextCommand()
21:21:01	Trace:	CFtpLogonOpData::Send() in state 6
21:21:01	Command:	USER FTP@example.com
21:21:01	Trace:	CFtpControlSocket::OnReceive()
21:21:02	Trace:	CFtpControlSocket::OnReceive()
21:21:02	Response:	331 User FTP@example.com OK. Password required
21:21:02	Trace:	CFtpLogonOpData::ParseResponse() in state 6
21:21:02	Trace:	CControlSocket::SendNextCommand()
21:21:02	Trace:	CFtpLogonOpData::Send() in state 6
21:21:02	Command:	PASS ************
21:21:02	Trace:	CFtpControlSocket::OnReceive()
21:21:02	Response:	230-Your bandwidth usage is restricted
21:21:02	Response:	230 OK. Current restricted directory is /
21:21:02	Trace:	CFtpLogonOpData::ParseResponse() in state 6
21:21:02	Trace:	CControlSocket::SendNextCommand()
21:21:02	Trace:	CFtpLogonOpData::Send() in state 10
21:21:02	Command:	OPTS UTF8 ON
21:21:02	Trace:	CFtpControlSocket::OnReceive()
21:21:02	Response:	200 OK, UTF-8 enabled
21:21:02	Trace:	CFtpLogonOpData::ParseResponse() in state 10
21:21:02	Trace:	CControlSocket::SendNextCommand()
21:21:02	Trace:	CFtpLogonOpData::Send() in state 11
21:21:02	Command:	PBSZ 0
21:21:02	Trace:	CFtpControlSocket::OnReceive()
21:21:02	Response:	200 PBSZ=0
21:21:02	Trace:	CFtpLogonOpData::ParseResponse() in state 11
21:21:02	Trace:	CControlSocket::SendNextCommand()
21:21:02	Trace:	CFtpLogonOpData::Send() in state 12
21:21:02	Command:	PROT P
21:21:02	Trace:	CFtpControlSocket::OnReceive()
21:21:02	Response:	200 Data protection level set to "private"
21:21:02	Trace:	CFtpLogonOpData::ParseResponse() in state 12
21:21:02	Status:	Logged in
21:21:02	Trace:	Measured latency of 127 ms
21:21:02	Trace:	CFtpControlSocket::ResetOperation(0)
21:21:02	Trace:	CControlSocket::ResetOperation(0)
21:21:02	Trace:	CFtpLogonOpData::Reset(0) in state 15
21:21:02	Trace:	CControlSocket::SendNextCommand()
21:21:02	Trace:	CFtpFileTransferOpData::Send() in state 0
21:21:02	Status:	Starting upload of E:\_DIGITAL RENDERS\FILE_TO_UPLOAD.MOV
21:21:02	Trace:	CFtpChangeDirOpData::Send() in state 0
21:21:02	Trace:	CFtpChangeDirOpData::Send() in state 2
21:21:02	Command:	CWD /SERVER_FOLDER
21:21:02	Trace:	CFtpControlSocket::OnReceive()
21:21:02	Response:	250 OK. Current directory is /SERVER_FOLDER
21:21:02	Trace:	CFtpChangeDirOpData::ParseResponse() in state 2
21:21:02	Trace:	CFtpControlSocket::ResetOperation(0)
21:21:02	Trace:	CControlSocket::ResetOperation(0)
21:21:02	Trace:	CFtpChangeDirOpData::Reset(0) in state 2
21:21:02	Trace:	CFtpFileTransferOpData::SubcommandResult(0) in state 1
21:21:02	Trace:	CControlSocket::SendNextCommand()
21:21:02	Trace:	CFtpListOpData::Send() in state 0
21:21:02	Status:	Retrieving directory listing of "/SERVER_FOLDER"...
21:21:02	Trace:	CFtpChangeDirOpData::Send() in state 0
21:21:02	Trace:	CFtpControlSocket::ResetOperation(0)
21:21:02	Trace:	CControlSocket::ResetOperation(0)
21:21:02	Trace:	CFtpChangeDirOpData::Reset(0) in state 0
21:21:02	Trace:	CFtpListOpData::SubcommandResult(0) in state 1
21:21:02	Trace:	CControlSocket::SendNextCommand()
21:21:02	Trace:	CFtpListOpData::Send() in state 2
21:21:02	Trace:	CFtpRawTransferOpData::Send() in state 0
21:21:02	Trace:	CFtpRawTransferOpData::Send() in state 1
21:21:02	Command:	TYPE I
21:21:02	Trace:	CFtpControlSocket::OnReceive()
21:21:02	Response:	200 TYPE is now 8-bit binary
21:21:02	Trace:	CFtpRawTransferOpData::ParseResponse() in state 1
21:21:02	Trace:	CControlSocket::SendNextCommand()
21:21:02	Trace:	CFtpRawTransferOpData::Send() in state 2
21:21:02	Command:	PASV
21:21:02	Trace:	CFtpControlSocket::OnReceive()
21:21:02	Response:	227 Entering Passive Mode (107,180,2,111,196,146)
21:21:02	Trace:	CFtpRawTransferOpData::ParseResponse() in state 2
21:21:02	Trace:	CControlSocket::SendNextCommand()
21:21:02	Trace:	CFtpRawTransferOpData::Send() in state 4
21:21:02	Trace:	Binding data connection source IP to control connection source IP 192.168.0.162
21:21:02	Trace:	tls_layer_impl::client_handshake()
21:21:02	Trace:	Trying to resume existing TLS session.
21:21:02	Command:	MLSD
21:21:03	Trace:	tls_layer_impl::continue_handshake()
21:21:03	Trace:	tls_layer_impl::continue_handshake()
21:21:03	Trace:	TLS Handshake successful
21:21:03	Trace:	TLS Session resumed
21:21:03	Trace:	Protocol: TLS1.2, Key exchange: ECDHE-SECP256R1, Cipher: AES-256-GCM, MAC: AEAD
21:21:03	Trace:	tls_layer_impl::verify_certificate()
21:21:03	Trace:	CTransferSocket::OnConnect
21:21:03	Trace:	CFtpControlSocket::OnReceive()
21:21:03	Response:	150 Accepted data connection
21:21:03	Trace:	CFtpRawTransferOpData::ParseResponse() in state 4
21:21:03	Trace:	CControlSocket::SendNextCommand()
21:21:03	Trace:	CFtpRawTransferOpData::Send() in state 5
21:21:03	Listing:	type=cdir;sizd=4096;modify=20200623165049;UNIX.mode=0755;UNIX.uid=1340207;UNIX.gid=1340207;unique=fd04g8ca4a09; .
21:21:03	Listing:	type=pdir;sizd=4096;modify=20200616164712;UNIX.mode=0755;UNIX.uid=1340207;UNIX.gid=1340207;unique=fd04g8ca3af9; ..
21:21:03	Listing:	type=file;size=11813765120;modify=20200623192103;UNIX.mode=0644;UNIX.uid=1340207;UNIX.gid=1340207;unique=fd04g8ca49b8; FILE_TO_UPLOAD.MOV
21:21:03	Listing:	type=dir;sizd=4096;modify=20200608213030;UNIX.mode=0755;UNIX.uid=1340207;UNIX.gid=1340207;unique=fd04g8ca4a0e; AUDIO_MIXES
21:21:03	Listing:	type=file;size=30886;modify=20200608201432;UNIX.mode=0644;UNIX.uid=1340207;UNIX.gid=1340207;unique=fd04g8ca4a0b; ENGLISH SUBTITLES.srt
21:21:03	Listing:	type=dir;sizd=4096;modify=20200608201433;UNIX.mode=0755;UNIX.uid=1340207;UNIX.gid=1340207;unique=fd04g8ca4a0a; OTHER LANGUAGES SUBTITLES
21:21:03	Listing:	type=dir;sizd=4096;modify=20200623164943;UNIX.mode=0755;UNIX.uid=1340207;UNIX.gid=1340207;unique=fd04g8ca4a2a; _DIGITAL RENDERS
21:21:03	Trace:	CTransferSocket::TransferEnd(1)
21:21:03	Trace:	tls_layer_impl::shutdown()
21:21:03	Trace:	tls_layer_impl::continue_shutdown()
21:21:03	Trace:	CFtpControlSocket::TransferEnd()
21:21:03	Trace:	CFtpControlSocket::OnReceive()
21:21:03	Response:	226-Options: -a -l 
21:21:03	Response:	226 7 matches total
21:21:03	Trace:	CFtpRawTransferOpData::ParseResponse() in state 7
21:21:03	Trace:	CFtpControlSocket::ResetOperation(0)
21:21:03	Trace:	CControlSocket::ResetOperation(0)
21:21:03	Trace:	CFtpRawTransferOpData::Reset(0) in state 7
21:21:03	Trace:	CFtpListOpData::SubcommandResult(0) in state 3
21:21:03	Trace:	CFtpControlSocket::ResetOperation(0)
21:21:03	Trace:	CControlSocket::ResetOperation(0)
21:21:03	Trace:	CFtpListOpData::Reset(0) in state 3
21:21:03	Trace:	CFtpFileTransferOpData::SubcommandResult(0) in state 2
21:21:03	Trace:	CFtpControlSocket::SetAsyncRequestReply
21:21:03	Trace:	CControlSocket::SendNextCommand()
21:21:03	Trace:	CFtpFileTransferOpData::Send() in state 5
21:21:03	Trace:	CFtpRawTransferOpData::Send() in state 0
21:21:03	Trace:	CFtpRawTransferOpData::Send() in state 2
21:21:03	Command:	PASV
21:21:03	Trace:	CFtpControlSocket::OnReceive()
21:21:03	Response:	227 Entering Passive Mode (107,180,2,111,197,41)
21:21:03	Trace:	CFtpRawTransferOpData::ParseResponse() in state 2
21:21:03	Trace:	CControlSocket::SendNextCommand()
21:21:03	Trace:	CFtpRawTransferOpData::Send() in state 3
21:21:03	Command:	REST 11813765120
21:21:03	Trace:	CFtpControlSocket::OnReceive()
21:21:03	Response:	350 Restarting at 11813765120
21:21:03	Trace:	CFtpRawTransferOpData::ParseResponse() in state 3
21:21:03	Trace:	CControlSocket::SendNextCommand()
21:21:03	Trace:	CFtpRawTransferOpData::Send() in state 4
21:21:03	Trace:	Binding data connection source IP to control connection source IP 192.168.0.162
21:21:03	Trace:	tls_layer_impl::client_handshake()
21:21:03	Trace:	Trying to resume existing TLS session.
21:21:03	Command:	STOR FILE_TO_UPLOAD.MOV
21:21:04	Trace:	tls_layer_impl::continue_handshake()
21:21:04	Trace:	CFtpControlSocket::OnReceive()
21:21:04	Response:	150 Accepted data connection
21:21:04	Trace:	CFtpRawTransferOpData::ParseResponse() in state 4
21:21:04	Trace:	CControlSocket::SendNextCommand()
21:21:04	Trace:	CFtpRawTransferOpData::Send() in state 5
21:21:04	Trace:	tls_layer_impl::continue_handshake()
21:21:04	Trace:	TLS Handshake successful
21:21:04	Trace:	TLS Session resumed
21:21:04	Trace:	Protocol: TLS1.2, Key exchange: ECDHE-SECP256R1, Cipher: AES-256-GCM, MAC: AEAD
21:21:04	Trace:	tls_layer_impl::verify_certificate()
21:21:04	Trace:	CTransferSocket::OnConnect
22:00:24	Error:	Connection timed out after 20 seconds of inactivity
22:00:24	Trace:	CFtpControlSocket::ResetOperation(2114)
22:00:24	Trace:	CControlSocket::ResetOperation(2114)
22:00:24	Trace:	CFtpRawTransferOpData::Reset(2114) in state 5
22:00:24	Trace:	CFtpControlSocket::ResetOperation(2114)
22:00:24	Trace:	CControlSocket::ResetOperation(2114)
22:00:24	Trace:	CFtpFileTransferOpData::Reset(2114) in state 7
22:00:24	Error:	File transfer failed after transferring 3.068.395.520 bytes in 2360 seconds
22:00:24	Trace:	CFtpControlSocket::FileTransfer()
22:00:24	Trace:	CControlSocket::SendNextCommand()
22:00:24	Trace:	CFtpLogonOpData::Send() in state 0
22:00:24	Status:	Resolving address of ftp.example.com
22:00:24	Status:	Connecting to XXX.XXX.X.XXX:21...
22:00:24	Status:	Connection established, waiting for welcome message...
22:00:24	Trace:	CFtpControlSocket::OnReceive()
22:00:24	Response:	220---------- Welcome to Pure-FTPd [privsep] [TLS] ----------
22:00:24	Response:	220-You are user number 3 of 500 allowed.
22:00:24	Response:	220-Local time is now 13:00. Server port: 21.
22:00:24	Response:	220-This is a private system - No anonymous login
22:00:24	Response:	220 You will be disconnected after 15 minutes of inactivity.
22:00:24	Trace:	CFtpLogonOpData::ParseResponse() in state 1
22:00:24	Trace:	CControlSocket::SendNextCommand()
22:00:24	Trace:	CFtpLogonOpData::Send() in state 2
22:00:24	Command:	AUTH TLS
22:00:24	Trace:	CFtpControlSocket::OnReceive()
22:00:24	Response:	234 AUTH TLS OK.
22:00:24	Trace:	CFtpLogonOpData::ParseResponse() in state 2
22:00:24	Status:	Initializing TLS...
22:00:24	Trace:	tls_layer_impl::client_handshake()
22:00:24	Trace:	tls_layer_impl::continue_handshake()
22:00:24	Trace:	tls_layer_impl::continue_handshake()
22:00:24	Trace:	tls_layer_impl::continue_handshake()
22:00:24	Trace:	tls_layer_impl::continue_handshake()
22:00:25	Trace:	tls_layer_impl::continue_handshake()
22:00:25	Trace:	TLS Handshake successful
22:00:25	Trace:	Protocol: TLS1.2, Key exchange: ECDHE-SECP256R1-RSA-SHA384, Cipher: AES-256-GCM, MAC: AEAD
22:00:25	Trace:	tls_layer_impl::verify_certificate()
22:00:25	Trace:	Hostname does not match certificate SANs
22:00:25	Status:	Verifying certificate...
22:00:25	Trace:	CFtpControlSocket::SetAsyncRequestReply
22:00:25	Status:	TLS connection established.
22:00:25	Trace:	CControlSocket::SendNextCommand()
22:00:25	Trace:	CFtpLogonOpData::Send() in state 6
22:00:25	Command:	USER FTP@example.com
22:00:25	Trace:	CFtpControlSocket::OnReceive()
22:00:25	Trace:	CFtpControlSocket::OnReceive()
22:00:25	Response:	331 User FTP@example.com OK. Password required
22:00:25	Trace:	CFtpLogonOpData::ParseResponse() in state 6
22:00:25	Trace:	CControlSocket::SendNextCommand()
22:00:25	Trace:	CFtpLogonOpData::Send() in state 6
22:00:25	Command:	PASS ************
22:00:26	Trace:	CFtpControlSocket::OnReceive()
22:00:26	Response:	230-Your bandwidth usage is restricted
22:00:26	Response:	230 OK. Current restricted directory is /
22:00:26	Trace:	CFtpLogonOpData::ParseResponse() in state 6
22:00:26	Trace:	CControlSocket::SendNextCommand()
22:00:26	Trace:	CFtpLogonOpData::Send() in state 10
22:00:26	Command:	OPTS UTF8 ON
22:00:26	Trace:	CFtpControlSocket::OnReceive()
22:00:26	Response:	200 OK, UTF-8 enabled
22:00:26	Trace:	CFtpLogonOpData::ParseResponse() in state 10
22:00:26	Trace:	CControlSocket::SendNextCommand()
22:00:26	Trace:	CFtpLogonOpData::Send() in state 11
22:00:26	Command:	PBSZ 0
22:00:26	Trace:	CFtpControlSocket::OnReceive()
22:00:26	Response:	200 PBSZ=0
22:00:26	Trace:	CFtpLogonOpData::ParseResponse() in state 11
22:00:26	Trace:	CControlSocket::SendNextCommand()
22:00:26	Trace:	CFtpLogonOpData::Send() in state 12
22:00:26	Command:	PROT P
22:00:26	Trace:	CFtpControlSocket::OnReceive()
22:00:26	Response:	200 Data protection level set to "private"
22:00:26	Trace:	CFtpLogonOpData::ParseResponse() in state 12
22:00:26	Status:	Logged in
22:00:26	Trace:	Measured latency of 162 ms
22:00:26	Trace:	CFtpControlSocket::ResetOperation(0)
22:00:26	Trace:	CControlSocket::ResetOperation(0)
22:00:26	Trace:	CFtpLogonOpData::Reset(0) in state 15
22:00:26	Trace:	CControlSocket::SendNextCommand()
22:00:26	Trace:	CFtpFileTransferOpData::Send() in state 0
22:00:26	Status:	Starting upload of E:\_DIGITAL RENDERS\FILE_TO_UPLOAD.MOV
22:00:26	Trace:	CFtpChangeDirOpData::Send() in state 0
22:00:26	Trace:	CFtpChangeDirOpData::Send() in state 2
22:00:26	Command:	CWD /SERVER_FOLDER
22:00:27	Trace:	CFtpControlSocket::OnReceive()
22:00:27	Response:	250 OK. Current directory is /SERVER_FOLDER
22:00:27	Trace:	CFtpChangeDirOpData::ParseResponse() in state 2
22:00:27	Trace:	CFtpControlSocket::ResetOperation(0)
22:00:27	Trace:	CControlSocket::ResetOperation(0)
22:00:27	Trace:	CFtpChangeDirOpData::Reset(0) in state 2
22:00:27	Trace:	CFtpFileTransferOpData::SubcommandResult(0) in state 1
22:00:27	Trace:	CControlSocket::SendNextCommand()
22:00:27	Trace:	CFtpListOpData::Send() in state 0
22:00:27	Status:	Retrieving directory listing of "/SERVER_FOLDER"...
22:00:27	Trace:	CFtpChangeDirOpData::Send() in state 0
22:00:27	Trace:	CFtpControlSocket::ResetOperation(0)
22:00:27	Trace:	CControlSocket::ResetOperation(0)
22:00:27	Trace:	CFtpChangeDirOpData::Reset(0) in state 0
22:00:27	Trace:	CFtpListOpData::SubcommandResult(0) in state 1
22:00:27	Trace:	CControlSocket::SendNextCommand()
22:00:27	Trace:	CFtpListOpData::Send() in state 2
22:00:27	Trace:	CFtpRawTransferOpData::Send() in state 0
22:00:27	Trace:	CFtpRawTransferOpData::Send() in state 1
22:00:27	Command:	TYPE I
22:00:27	Trace:	CFtpControlSocket::OnReceive()
22:00:27	Response:	200 TYPE is now 8-bit binary
22:00:27	Trace:	CFtpRawTransferOpData::ParseResponse() in state 1
22:00:27	Trace:	CControlSocket::SendNextCommand()
22:00:27	Trace:	CFtpRawTransferOpData::Send() in state 2
22:00:27	Command:	PASV
22:00:27	Trace:	CFtpControlSocket::OnReceive()
22:00:27	Response:	227 Entering Passive Mode (107,180,2,111,198,89)
22:00:27	Trace:	CFtpRawTransferOpData::ParseResponse() in state 2
22:00:27	Trace:	CControlSocket::SendNextCommand()
22:00:27	Trace:	CFtpRawTransferOpData::Send() in state 4
22:00:27	Trace:	Binding data connection source IP to control connection source IP 192.168.0.162
22:00:27	Trace:	tls_layer_impl::client_handshake()
22:00:27	Trace:	Trying to resume existing TLS session.
22:00:27	Command:	MLSD
22:00:27	Trace:	tls_layer_impl::continue_handshake()
22:00:27	Trace:	tls_layer_impl::continue_handshake()
22:00:27	Trace:	TLS Handshake successful
22:00:27	Trace:	TLS Session resumed
22:00:27	Trace:	Protocol: TLS1.2, Key exchange: ECDHE-SECP256R1, Cipher: AES-256-GCM, MAC: AEAD
22:00:27	Trace:	tls_layer_impl::verify_certificate()
22:00:27	Trace:	CTransferSocket::OnConnect
22:00:27	Trace:	CFtpControlSocket::OnReceive()
22:00:27	Response:	150 Accepted data connection
22:00:27	Trace:	CFtpRawTransferOpData::ParseResponse() in state 4
22:00:27	Trace:	CControlSocket::SendNextCommand()
22:00:27	Trace:	CFtpRawTransferOpData::Send() in state 5
22:00:27	Listing:	type=cdir;sizd=4096;modify=20200623200027;UNIX.mode=0755;UNIX.uid=1340207;UNIX.gid=1340207;unique=fd04g8ca4a09; .
22:00:27	Listing:	type=pdir;sizd=4096;modify=20200616164712;UNIX.mode=0755;UNIX.uid=1340207;UNIX.gid=1340207;unique=fd04g8ca3af9; ..
22:00:27	Listing:	type=file;size=14879981568;modify=20200623200026;UNIX.mode=0644;UNIX.uid=1340207;UNIX.gid=1340207;unique=fd04g8ca49b8; FILE_TO_UPLOAD.MOV
22:00:27	Listing:	type=dir;sizd=4096;modify=20200608213030;UNIX.mode=0755;UNIX.uid=1340207;UNIX.gid=1340207;unique=fd04g8ca4a0e; AUDIO_MIXES
22:00:27	Listing:	type=file;size=30886;modify=20200608201432;UNIX.mode=0644;UNIX.uid=1340207;UNIX.gid=1340207;unique=fd04g8ca4a0b; ENGLISH SUBTITLES.srt
22:00:27	Listing:	type=dir;sizd=4096;modify=20200608201433;UNIX.mode=0755;UNIX.uid=1340207;UNIX.gid=1340207;unique=fd04g8ca4a0a; OTHER LANGUAGES SUBTITLES
22:00:27	Listing:	type=dir;sizd=4096;modify=20200623164943;UNIX.mode=0755;UNIX.uid=1340207;UNIX.gid=1340207;unique=fd04g8ca4a2a; _DIGITAL RENDERS
22:00:27	Trace:	CTransferSocket::TransferEnd(1)
22:00:27	Trace:	tls_layer_impl::shutdown()
22:00:27	Trace:	tls_layer_impl::continue_shutdown()
22:00:27	Trace:	CFtpControlSocket::TransferEnd()
22:00:28	Trace:	CFtpControlSocket::OnReceive()
22:00:28	Response:	226-Options: -a -l 
22:00:28	Response:	226 7 matches total
22:00:28	Trace:	CFtpRawTransferOpData::ParseResponse() in state 7
22:00:28	Trace:	CFtpControlSocket::ResetOperation(0)
22:00:28	Trace:	CControlSocket::ResetOperation(0)
22:00:28	Trace:	CFtpRawTransferOpData::Reset(0) in state 7
22:00:28	Trace:	CFtpListOpData::SubcommandResult(0) in state 3
22:00:28	Trace:	CFtpControlSocket::ResetOperation(0)
22:00:28	Trace:	CControlSocket::ResetOperation(0)
22:00:28	Trace:	CFtpListOpData::Reset(0) in state 3
22:00:28	Trace:	CFtpFileTransferOpData::SubcommandResult(0) in state 2
22:00:28	Trace:	CFtpControlSocket::SetAsyncRequestReply
22:00:28	Trace:	CControlSocket::SendNextCommand()
22:00:28	Trace:	CFtpFileTransferOpData::Send() in state 5
22:00:28	Trace:	CFtpRawTransferOpData::Send() in state 0
22:00:28	Trace:	CFtpRawTransferOpData::Send() in state 2
22:00:28	Command:	PASV
22:00:28	Trace:	CFtpControlSocket::OnReceive()
22:00:28	Response:	227 Entering Passive Mode (107,180,2,111,196,213)
22:00:28	Trace:	CFtpRawTransferOpData::ParseResponse() in state 2
22:00:28	Trace:	CControlSocket::SendNextCommand()
22:00:28	Trace:	CFtpRawTransferOpData::Send() in state 3
22:00:28	Command:	REST 14879981568
22:00:28	Trace:	CFtpControlSocket::OnReceive()
22:00:28	Response:	350 Restarting at 14879981568
22:00:28	Trace:	CFtpRawTransferOpData::ParseResponse() in state 3
22:00:28	Trace:	CControlSocket::SendNextCommand()
22:00:28	Trace:	CFtpRawTransferOpData::Send() in state 4
22:00:28	Trace:	Binding data connection source IP to control connection source IP 192.168.0.162
22:00:28	Trace:	tls_layer_impl::client_handshake()
22:00:28	Trace:	Trying to resume existing TLS session.
22:00:28	Command:	STOR FILE_TO_UPLOAD.MOV
22:00:28	Trace:	tls_layer_impl::continue_handshake()
22:00:28	Trace:	CFtpControlSocket::OnReceive()
22:00:28	Response:	150 Accepted data connection
22:00:28	Trace:	CFtpRawTransferOpData::ParseResponse() in state 4
22:00:28	Trace:	CControlSocket::SendNextCommand()
22:00:28	Trace:	CFtpRawTransferOpData::Send() in state 5
22:00:28	Trace:	tls_layer_impl::continue_handshake()
22:00:28	Trace:	TLS Handshake successful
22:00:28	Trace:	TLS Session resumed
22:00:28	Trace:	Protocol: TLS1.2, Key exchange: ECDHE-SECP256R1, Cipher: AES-256-GCM, MAC: AEAD
22:00:28	Trace:	tls_layer_impl::verify_certificate()
22:00:28	Trace:	CTransferSocket::OnConnect

User avatar
botg
Site Admin
Posts: 33128
Joined: 2004-02-23 20:49
First name: Tim
Last name: Kosse
Contact:

Re: impossible to upload a big file (it stops by an error after a few hours)

#8 Post by botg » 2020-06-24 08:09

So far so good, nothing unusual in the log. It shows a couple of successful resumes. There has not been a case where it starts over from nothing.

JaviWan
504 Command not implemented
Posts: 7
Joined: 2020-06-18 22:16
First name: Javier
Last name: Rueda

Re: impossible to upload a big file (it stops by an error after a few hours)

#9 Post by JaviWan » 2020-06-24 09:22

Thanks botg for checking the log. I can't understand why the log says it resumed the upload, when it did not. Anyway, I will perform another test and will share here the new log. And if in this last test you continue seeing nothing estrange, I will considerar myself defeated :P

Thanks for your help and patience.

JaviWan
504 Command not implemented
Posts: 7
Joined: 2020-06-18 22:16
First name: Javier
Last name: Rueda

Re: impossible to upload a big file (it stops by an error after a few hours)

#10 Post by JaviWan » 2020-06-26 22:28

Hi botg.

Here I'm with a last log. Thanks for all your help and patience. I hope this log is "the one" (or as I said, I will thow my hopes to fix it)

Here is the log (as the forum does not lets me to copy such a big log):
https://docs.google.com/document/d/1_Ma ... AChFNi1lro

-> Error is at: 2020-06-26 19:12:36 5444 2 Error: File transfer failed

------------------------------------------------

I copy below the lines surrounding the error, since a previous uploading resume:

Code: Select all

2020-06-26 18:27:02 5444 2 Trace: TLS Handshake successful
2020-06-26 18:27:02 5444 2 Trace: TLS Session resumed
2020-06-26 18:27:02 5444 2 Trace: Protocol: TLS1.2, Key exchange: ECDHE-SECP256R1, Cipher: AES-256-GCM, MAC: AEAD
2020-06-26 18:27:02 5444 2 Trace: tls_layer_impl::verify_certificate()
2020-06-26 18:27:02 5444 2 Trace: CTransferSocket::OnConnect
2020-06-26 18:27:02 5444 2 Trace: CFtpControlSocket::OnReceive()
2020-06-26 18:27:02 5444 2 Response: 150 Accepted data connection
2020-06-26 18:27:02 5444 2 Trace: CFtpRawTransferOpData::ParseResponse() in state 4
2020-06-26 18:27:02 5444 2 Trace: CControlSocket::SendNextCommand()
2020-06-26 18:27:02 5444 2 Trace: CFtpRawTransferOpData::Send() in state 5
2020-06-26 19:12:13 5444 2 Trace: CRealControlSocket::OnSocketError(106)
2020-06-26 19:12:13 5444 2 Error: Disconnected from server: ECONNABORTED - Connection aborted
2020-06-26 19:12:13 5444 2 Trace: CFtpControlSocket::ResetOperation(66)
2020-06-26 19:12:13 5444 2 Trace: CControlSocket::ResetOperation(66)
2020-06-26 19:12:13 5444 2 Trace: CFtpRawTransferOpData::Reset(66) in state 5
2020-06-26 19:12:13 5444 2 Trace: CFtpControlSocket::ResetOperation(66)
2020-06-26 19:12:13 5444 2 Trace: CControlSocket::ResetOperation(66)
2020-06-26 19:12:13 5444 2 Trace: CFtpFileTransferOpData::Reset(66) in state 7
2020-06-26 19:12:13 5444 2 Error: File transfer failed after transferring 3.555.753.984 bytes in 2711 seconds
2020-06-26 19:12:13 5444 2 Trace: CFtpControlSocket::FileTransfer()
2020-06-26 19:12:13 5444 2 Trace: CControlSocket::SendNextCommand()
2020-06-26 19:12:13 5444 2 Trace: CFtpLogonOpData::Send() in state 0
2020-06-26 19:12:13 5444 2 Status: Resolving address of ftp.address.com
2020-06-26 19:12:13 5444 2 Status: Connecting to 000.000.0.000:21...
2020-06-26 19:12:13 5444 2 Status: Connection established, waiting for welcome message...
2020-06-26 19:12:13 5444 2 Trace: CFtpControlSocket::OnReceive()
2020-06-26 19:12:13 5444 2 Response: 220---------- Welcome to Pure-FTPd [privsep] [TLS] ----------
2020-06-26 19:12:13 5444 2 Response: 220-You are user number 5 of 500 allowed.
2020-06-26 19:12:13 5444 2 Response: 220-Local time is now 10:12. Server port: 21.
2020-06-26 19:12:13 5444 2 Response: 220-This is a private system - No anonymous login
2020-06-26 19:12:13 5444 2 Response: 220 You will be disconnected after 15 minutes of inactivity.
2020-06-26 19:12:13 5444 2 Trace: CFtpLogonOpData::ParseResponse() in state 1
2020-06-26 19:12:13 5444 2 Trace: CControlSocket::SendNextCommand()
2020-06-26 19:12:13 5444 2 Trace: CFtpLogonOpData::Send() in state 2
2020-06-26 19:12:13 5444 2 Command: AUTH TLS
2020-06-26 19:12:13 5444 2 Trace: CFtpControlSocket::OnReceive()
2020-06-26 19:12:13 5444 2 Response: 234 AUTH TLS OK.
2020-06-26 19:12:13 5444 2 Trace: CFtpLogonOpData::ParseResponse() in state 2
2020-06-26 19:12:13 5444 2 Status: Initializing TLS...
2020-06-26 19:12:13 5444 2 Trace: tls_layer_impl::client_handshake()
2020-06-26 19:12:13 5444 2 Trace: tls_layer_impl::continue_handshake()
2020-06-26 19:12:13 5444 2 Trace: tls_layer_impl::continue_handshake()
2020-06-26 19:12:13 5444 2 Trace: tls_layer_impl::continue_handshake()
2020-06-26 19:12:13 5444 2 Trace: tls_layer_impl::continue_handshake()
2020-06-26 19:12:14 5444 2 Trace: tls_layer_impl::continue_handshake()
2020-06-26 19:12:14 5444 2 Trace: TLS Handshake successful
2020-06-26 19:12:14 5444 2 Trace: Protocol: TLS1.2, Key exchange: ECDHE-SECP256R1-RSA-SHA384, Cipher: AES-256-GCM, MAC: AEAD
2020-06-26 19:12:14 5444 2 Trace: tls_layer_impl::verify_certificate()
2020-06-26 19:12:14 5444 2 Trace: Hostname does not match certificate SANs
2020-06-26 19:12:14 5444 2 Status: Verifying certificate...
2020-06-26 19:12:14 5444 2 Trace: CFtpControlSocket::SetAsyncRequestReply
2020-06-26 19:12:14 5444 2 Status: TLS connection established.
2020-06-26 19:12:14 5444 2 Trace: CControlSocket::SendNextCommand()
2020-06-26 19:12:14 5444 2 Trace: CFtpLogonOpData::Send() in state 6
2020-06-26 19:12:14 5444 2 Command: USER distribution_ftp@address.com
2020-06-26 19:12:14 5444 2 Trace: CFtpControlSocket::OnReceive()
2020-06-26 19:12:14 5444 2 Trace: CFtpControlSocket::OnReceive()
2020-06-26 19:12:14 5444 2 Response: 331 User distribution_ftp@address.com OK. Password required
2020-06-26 19:12:14 5444 2 Trace: CFtpLogonOpData::ParseResponse() in state 6
2020-06-26 19:12:14 5444 2 Trace: CControlSocket::SendNextCommand()
2020-06-26 19:12:14 5444 2 Trace: CFtpLogonOpData::Send() in state 6
2020-06-26 19:12:14 5444 2 Command: PASS ************
2020-06-26 19:12:14 5444 2 Trace: CFtpControlSocket::OnReceive()
2020-06-26 19:12:14 5444 2 Response: 230-Your bandwidth usage is restricted
2020-06-26 19:12:14 5444 2 Response: 230 OK. Current restricted directory is /
2020-06-26 19:12:14 5444 2 Trace: CFtpLogonOpData::ParseResponse() in state 6
2020-06-26 19:12:14 5444 2 Trace: CControlSocket::SendNextCommand()
2020-06-26 19:12:14 5444 2 Trace: CFtpLogonOpData::Send() in state 10
2020-06-26 19:12:14 5444 2 Command: OPTS UTF8 ON
2020-06-26 19:12:14 5444 2 Trace: CFtpControlSocket::OnReceive()
2020-06-26 19:12:14 5444 2 Response: 200 OK, UTF-8 enabled
2020-06-26 19:12:14 5444 2 Trace: CFtpLogonOpData::ParseResponse() in state 10
2020-06-26 19:12:14 5444 2 Trace: CControlSocket::SendNextCommand()
2020-06-26 19:12:14 5444 2 Trace: CFtpLogonOpData::Send() in state 11
2020-06-26 19:12:14 5444 2 Command: PBSZ 0
2020-06-26 19:12:14 5444 2 Trace: CFtpControlSocket::OnReceive()
2020-06-26 19:12:14 5444 2 Response: 200 PBSZ=0
2020-06-26 19:12:14 5444 2 Trace: CFtpLogonOpData::ParseResponse() in state 11
2020-06-26 19:12:14 5444 2 Trace: CControlSocket::SendNextCommand()
2020-06-26 19:12:14 5444 2 Trace: CFtpLogonOpData::Send() in state 12
2020-06-26 19:12:14 5444 2 Command: PROT P
2020-06-26 19:12:15 5444 2 Trace: CFtpControlSocket::OnReceive()
2020-06-26 19:12:15 5444 2 Response: 200 Data protection level set to "private"
2020-06-26 19:12:15 5444 2 Trace: CFtpLogonOpData::ParseResponse() in state 12
2020-06-26 19:12:15 5444 2 Status: Logged in
2020-06-26 19:12:15 5444 2 Trace: Measured latency of 728 ms
2020-06-26 19:12:15 5444 2 Trace: CFtpControlSocket::ResetOperation(0)
2020-06-26 19:12:15 5444 2 Trace: CControlSocket::ResetOperation(0)
2020-06-26 19:12:15 5444 2 Trace: CFtpLogonOpData::Reset(0) in state 15
2020-06-26 19:12:15 5444 2 Trace: CControlSocket::SendNextCommand()
2020-06-26 19:12:15 5444 2 Trace: CFtpFileTransferOpData::Send() in state 0
2020-06-26 19:12:15 5444 2 Status: Starting upload of E:\MY_FOLDER\MY_FILE_TO_UPLOAD.mov
2020-06-26 19:12:15 5444 2 Trace: CFtpChangeDirOpData::Send() in state 0
2020-06-26 19:12:15 5444 2 Trace: CFtpChangeDirOpData::Send() in state 2
2020-06-26 19:12:15 5444 2 Command: CWD /FTP_MAIN_FOLDER
2020-06-26 19:12:15 5444 2 Trace: CFtpControlSocket::OnReceive()
2020-06-26 19:12:15 5444 2 Response: 250 OK. Current directory is /FTP_MAIN_FOLDER
2020-06-26 19:12:15 5444 2 Trace: CFtpChangeDirOpData::ParseResponse() in state 2
2020-06-26 19:12:15 5444 2 Trace: CFtpControlSocket::ResetOperation(0)
2020-06-26 19:12:15 5444 2 Trace: CControlSocket::ResetOperation(0)
2020-06-26 19:12:15 5444 2 Trace: CFtpChangeDirOpData::Reset(0) in state 2
2020-06-26 19:12:15 5444 2 Trace: CFtpFileTransferOpData::SubcommandResult(0) in state 1
2020-06-26 19:12:15 5444 2 Trace: CControlSocket::SendNextCommand()
2020-06-26 19:12:15 5444 2 Trace: CFtpListOpData::Send() in state 0
2020-06-26 19:12:15 5444 2 Status: Retrieving directory listing of "/FTP_MAIN_FOLDER"...
2020-06-26 19:12:15 5444 2 Trace: CFtpChangeDirOpData::Send() in state 0
2020-06-26 19:12:15 5444 2 Trace: CFtpControlSocket::ResetOperation(0)
2020-06-26 19:12:15 5444 2 Trace: CControlSocket::ResetOperation(0)
2020-06-26 19:12:15 5444 2 Trace: CFtpChangeDirOpData::Reset(0) in state 0
2020-06-26 19:12:15 5444 2 Trace: CFtpListOpData::SubcommandResult(0) in state 1
2020-06-26 19:12:15 5444 2 Trace: CControlSocket::SendNextCommand()
2020-06-26 19:12:15 5444 2 Trace: CFtpListOpData::Send() in state 2
2020-06-26 19:12:15 5444 2 Trace: CFtpRawTransferOpData::Send() in state 0
2020-06-26 19:12:15 5444 2 Trace: CFtpRawTransferOpData::Send() in state 1
2020-06-26 19:12:15 5444 2 Command: TYPE I
2020-06-26 19:12:15 5444 2 Trace: CFtpControlSocket::OnReceive()
2020-06-26 19:12:15 5444 2 Response: 200 TYPE is now 8-bit binary
2020-06-26 19:12:15 5444 2 Trace: CFtpRawTransferOpData::ParseResponse() in state 1
2020-06-26 19:12:15 5444 2 Trace: CControlSocket::SendNextCommand()
2020-06-26 19:12:15 5444 2 Trace: CFtpRawTransferOpData::Send() in state 2
2020-06-26 19:12:15 5444 2 Command: PASV
2020-06-26 19:12:15 5444 2 Trace: CFtpControlSocket::OnReceive()
2020-06-26 19:12:15 5444 2 Response: 227 Entering Passive Mode (107,180,2,111,196,142)
2020-06-26 19:12:15 5444 2 Trace: CFtpRawTransferOpData::ParseResponse() in state 2
2020-06-26 19:12:15 5444 2 Trace: CControlSocket::SendNextCommand()
2020-06-26 19:12:15 5444 2 Trace: CFtpRawTransferOpData::Send() in state 4
2020-06-26 19:12:15 5444 2 Trace: Binding data connection source IP to control connection source IP MY_IP_XXX.XXX.X.XXX
2020-06-26 19:12:15 5444 2 Trace: tls_layer_impl::client_handshake()
2020-06-26 19:12:15 5444 2 Trace: Trying to resume existing TLS session.
2020-06-26 19:12:15 5444 2 Command: MLSD
2020-06-26 19:12:15 5444 2 Trace: tls_layer_impl::continue_handshake()
2020-06-26 19:12:15 5444 2 Trace: tls_layer_impl::continue_handshake()
2020-06-26 19:12:15 5444 2 Trace: TLS Handshake successful
2020-06-26 19:12:15 5444 2 Trace: TLS Session resumed
2020-06-26 19:12:15 5444 2 Trace: Protocol: TLS1.2, Key exchange: ECDHE-SECP256R1, Cipher: AES-256-GCM, MAC: AEAD
2020-06-26 19:12:15 5444 2 Trace: tls_layer_impl::verify_certificate()
2020-06-26 19:12:15 5444 2 Trace: CTransferSocket::OnConnect
2020-06-26 19:12:15 5444 2 Trace: CFtpControlSocket::OnReceive()
2020-06-26 19:12:15 5444 2 Response: 150 Accepted data connection
2020-06-26 19:12:15 5444 2 Trace: CFtpRawTransferOpData::ParseResponse() in state 4
2020-06-26 19:12:15 5444 2 Trace: CControlSocket::SendNextCommand()
2020-06-26 19:12:15 5444 2 Trace: CFtpRawTransferOpData::Send() in state 5
2020-06-26 19:12:15 5444 2 Listing: type=cdir;sizd=4096;modify=20200626162703;UNIX.mode=0755;UNIX.uid=1340207;UNIX.gid=1340207;unique=fd04g8ca4a09; .
2020-06-26 19:12:15 5444 2 Listing: type=pdir;sizd=4096;modify=20200616164712;UNIX.mode=0755;UNIX.uid=1340207;UNIX.gid=1340207;unique=fd04g8ca3af9; ..
2020-06-26 19:12:15 5444 2 Listing: type=file;size=74585079808;modify=20200626171217;UNIX.mode=0644;UNIX.uid=1340207;UNIX.gid=1340207;unique=fd04g8ca4ef2; .pureftpd-upload.5ef621cd.15.3cf92e.e689e26d
2020-06-26 19:12:15 5444 2 Listing: type=dir;sizd=4096;modify=20200608213030;UNIX.mode=0755;UNIX.uid=1340207;UNIX.gid=1340207;unique=fd04g8ca4a0e; AUDIO_FOLDER
2020-06-26 19:12:15 5444 2 Listing: type=file;size=30886;modify=20200608201432;UNIX.mode=0644;UNIX.uid=1340207;UNIX.gid=1340207;unique=fd04g8ca4a0b; A_TEXT_FILE.txt
2020-06-26 19:12:15 5444 2 Listing: type=dir;sizd=4096;modify=20200608201433;UNIX.mode=0755;UNIX.uid=1340207;UNIX.gid=1340207;unique=fd04g8ca4a0a; TEXTS_FOLDER
2020-06-26 19:12:15 5444 2 Listing: type=dir;sizd=4096;modify=20200625154438;UNIX.mode=0755;UNIX.uid=1340207;UNIX.gid=1340207;unique=fd04g8ca4a2a; _OTHER_FILES_FOLDER
2020-06-26 19:12:15 5444 2 Trace: CTransferSocket::TransferEnd(1)
2020-06-26 19:12:15 5444 2 Trace: tls_layer_impl::shutdown()
2020-06-26 19:12:15 5444 2 Trace: tls_layer_impl::continue_shutdown()
2020-06-26 19:12:15 5444 2 Trace: CFtpControlSocket::TransferEnd()
2020-06-26 19:12:16 5444 2 Trace: CFtpControlSocket::OnReceive()
2020-06-26 19:12:16 5444 2 Response: 226-Options: -a -l 
2020-06-26 19:12:16 5444 2 Response: 226 7 matches total
2020-06-26 19:12:16 5444 2 Trace: CFtpRawTransferOpData::ParseResponse() in state 7
2020-06-26 19:12:16 5444 2 Trace: CFtpControlSocket::ResetOperation(0)
2020-06-26 19:12:16 5444 2 Trace: CControlSocket::ResetOperation(0)
2020-06-26 19:12:16 5444 2 Trace: CFtpRawTransferOpData::Reset(0) in state 7
2020-06-26 19:12:16 5444 2 Trace: CFtpListOpData::SubcommandResult(0) in state 3
2020-06-26 19:12:16 5444 2 Trace: CFtpControlSocket::ResetOperation(0)
2020-06-26 19:12:16 5444 2 Trace: CControlSocket::ResetOperation(0)
2020-06-26 19:12:16 5444 2 Trace: CFtpListOpData::Reset(0) in state 3
2020-06-26 19:12:16 5444 2 Trace: CFtpFileTransferOpData::SubcommandResult(0) in state 2
2020-06-26 19:12:16 5444 2 Trace: CControlSocket::SendNextCommand()
2020-06-26 19:12:16 5444 2 Trace: CFtpFileTransferOpData::Send() in state 5
2020-06-26 19:12:16 5444 2 Trace: CFtpRawTransferOpData::Send() in state 0
2020-06-26 19:12:16 5444 2 Trace: CFtpRawTransferOpData::Send() in state 2
2020-06-26 19:12:16 5444 2 Command: PASV
2020-06-26 19:12:16 5444 2 Trace: CFtpControlSocket::OnReceive()
2020-06-26 19:12:16 5444 2 Response: 227 Entering Passive Mode (107,180,2,111,195,136)
2020-06-26 19:12:16 5444 2 Trace: CFtpRawTransferOpData::ParseResponse() in state 2
2020-06-26 19:12:16 5444 2 Trace: CControlSocket::SendNextCommand()
2020-06-26 19:12:16 5444 2 Trace: CFtpRawTransferOpData::Send() in state 4
2020-06-26 19:12:16 5444 2 Trace: Binding data connection source IP to control connection source IP MY_IP_XXX.XXX.X.XXX
2020-06-26 19:12:16 5444 2 Trace: tls_layer_impl::client_handshake()
2020-06-26 19:12:16 5444 2 Trace: Trying to resume existing TLS session.
2020-06-26 19:12:16 5444 2 Command: STOR MY_FILE_TO_UPLOAD.mov
2020-06-26 19:12:16 5444 2 Trace: tls_layer_impl::continue_handshake()
2020-06-26 19:12:36 5444 2 Error: Connection timed out after 20 seconds of inactivity
2020-06-26 19:12:36 5444 2 Trace: CFtpControlSocket::ResetOperation(2114)
2020-06-26 19:12:36 5444 2 Trace: CControlSocket::ResetOperation(2114)
2020-06-26 19:12:36 5444 2 Trace: CFtpRawTransferOpData::Reset(2114) in state 4
2020-06-26 19:12:36 5444 2 Trace: CFtpControlSocket::ResetOperation(2114)
2020-06-26 19:12:36 5444 2 Trace: CControlSocket::ResetOperation(2114)
2020-06-26 19:12:36 5444 2 Trace: CFtpFileTransferOpData::Reset(2114) in state 7
2020-06-26 19:12:36 5444 2 Error: File transfer failed
2020-06-26 19:12:36 5444 2 Trace: CFtpControlSocket::FileTransfer()
2020-06-26 19:12:36 5444 2 Trace: CControlSocket::SendNextCommand()
2020-06-26 19:12:36 5444 2 Trace: CFtpLogonOpData::Send() in state 0
2020-06-26 19:12:36 5444 2 Status: Resolving address of ftp.address.com
2020-06-26 19:12:36 5444 2 Status: Connecting to 000.000.0.000:21...
2020-06-26 19:12:36 5444 2 Status: Connection established, waiting for welcome message...
2020-06-26 19:12:36 5444 2 Trace: CFtpControlSocket::OnReceive()
2020-06-26 19:12:36 5444 2 Response: 220---------- Welcome to Pure-FTPd [privsep] [TLS] ----------
2020-06-26 19:12:36 5444 2 Response: 220-You are user number 6 of 500 allowed.
2020-06-26 19:12:36 5444 2 Response: 220-Local time is now 10:12. Server port: 21.
2020-06-26 19:12:36 5444 2 Response: 220-This is a private system - No anonymous login
2020-06-26 19:12:36 5444 2 Response: 220 You will be disconnected after 15 minutes of inactivity.
2020-06-26 19:12:36 5444 2 Trace: CFtpLogonOpData::ParseResponse() in state 1
2020-06-26 19:12:36 5444 2 Trace: CControlSocket::SendNextCommand()
2020-06-26 19:12:36 5444 2 Trace: CFtpLogonOpData::Send() in state 2
2020-06-26 19:12:36 5444 2 Command: AUTH TLS
2020-06-26 19:12:36 5444 2 Trace: CFtpControlSocket::OnReceive()
2020-06-26 19:12:36 5444 2 Response: 234 AUTH TLS OK.
2020-06-26 19:12:36 5444 2 Trace: CFtpLogonOpData::ParseResponse() in state 2
2020-06-26 19:12:36 5444 2 Status: Initializing TLS...
2020-06-26 19:12:36 5444 2 Trace: tls_layer_impl::client_handshake()
2020-06-26 19:12:36 5444 2 Trace: tls_layer_impl::continue_handshake()
2020-06-26 19:12:36 5444 2 Trace: tls_layer_impl::continue_handshake()
2020-06-26 19:12:36 5444 2 Trace: tls_layer_impl::continue_handshake()
2020-06-26 19:12:36 5444 2 Trace: tls_layer_impl::continue_handshake()
2020-06-26 19:12:36 5444 2 Trace: tls_layer_impl::continue_handshake()
2020-06-26 19:12:36 5444 2 Trace: TLS Handshake successful
2020-06-26 19:12:36 5444 2 Trace: Protocol: TLS1.2, Key exchange: ECDHE-SECP256R1-RSA-SHA384, Cipher: AES-256-GCM, MAC: AEAD
2020-06-26 19:12:36 5444 2 Trace: tls_layer_impl::verify_certificate()
2020-06-26 19:12:36 5444 2 Trace: Hostname does not match certificate SANs
2020-06-26 19:12:36 5444 2 Status: Verifying certificate...
2020-06-26 19:12:36 5444 2 Trace: CFtpControlSocket::SetAsyncRequestReply
2020-06-26 19:12:36 5444 2 Status: TLS connection established.
2020-06-26 19:12:36 5444 2 Trace: CControlSocket::SendNextCommand()
2020-06-26 19:12:36 5444 2 Trace: CFtpLogonOpData::Send() in state 6
2020-06-26 19:12:36 5444 2 Command: USER distribution_ftp@address.com
2020-06-26 19:12:36 5444 2 Trace: CFtpControlSocket::OnReceive()
2020-06-26 19:12:36 5444 2 Trace: CFtpControlSocket::OnReceive()
2020-06-26 19:12:36 5444 2 Response: 331 User distribution_ftp@address.com OK. Password required
2020-06-26 19:12:36 5444 2 Trace: CFtpLogonOpData::ParseResponse() in state 6
2020-06-26 19:12:36 5444 2 Trace: CControlSocket::SendNextCommand()
2020-06-26 19:12:36 5444 2 Trace: CFtpLogonOpData::Send() in state 6
2020-06-26 19:12:36 5444 2 Command: PASS ************
2020-06-26 19:12:37 5444 2 Trace: CFtpControlSocket::OnReceive()
2020-06-26 19:12:37 5444 2 Response: 230-Your bandwidth usage is restricted
2020-06-26 19:12:37 5444 2 Response: 230 OK. Current restricted directory is /
2020-06-26 19:12:37 5444 2 Trace: CFtpLogonOpData::ParseResponse() in state 6
2020-06-26 19:12:37 5444 2 Trace: CControlSocket::SendNextCommand()
2020-06-26 19:12:37 5444 2 Trace: CFtpLogonOpData::Send() in state 10
2020-06-26 19:12:37 5444 2 Command: OPTS UTF8 ON
2020-06-26 19:12:37 5444 2 Trace: CFtpControlSocket::OnReceive()
2020-06-26 19:12:37 5444 2 Response: 200 OK, UTF-8 enabled
2020-06-26 19:12:37 5444 2 Trace: CFtpLogonOpData::ParseResponse() in state 10
2020-06-26 19:12:37 5444 2 Trace: CControlSocket::SendNextCommand()
2020-06-26 19:12:37 5444 2 Trace: CFtpLogonOpData::Send() in state 11
2020-06-26 19:12:37 5444 2 Command: PBSZ 0
2020-06-26 19:12:37 5444 2 Trace: CFtpControlSocket::OnReceive()
2020-06-26 19:12:37 5444 2 Response: 200 PBSZ=0
2020-06-26 19:12:37 5444 2 Trace: CFtpLogonOpData::ParseResponse() in state 11
2020-06-26 19:12:37 5444 2 Trace: CControlSocket::SendNextCommand()
2020-06-26 19:12:37 5444 2 Trace: CFtpLogonOpData::Send() in state 12
2020-06-26 19:12:37 5444 2 Command: PROT P
2020-06-26 19:12:37 5444 2 Trace: CFtpControlSocket::OnReceive()
2020-06-26 19:12:37 5444 2 Response: 200 Data protection level set to "private"
2020-06-26 19:12:37 5444 2 Trace: CFtpLogonOpData::ParseResponse() in state 12
2020-06-26 19:12:37 5444 2 Status: Logged in
2020-06-26 19:12:37 5444 2 Trace: Measured latency of 720 ms
2020-06-26 19:12:37 5444 2 Trace: CFtpControlSocket::ResetOperation(0)
2020-06-26 19:12:37 5444 2 Trace: CControlSocket::ResetOperation(0)
2020-06-26 19:12:37 5444 2 Trace: CFtpLogonOpData::Reset(0) in state 15
2020-06-26 19:12:37 5444 2 Trace: CControlSocket::SendNextCommand()
2020-06-26 19:12:37 5444 2 Trace: CFtpFileTransferOpData::Send() in state 0
2020-06-26 19:12:37 5444 2 Status: Starting upload of E:\MY_FOLDER\MY_FILE_TO_UPLOAD.mov

User avatar
botg
Site Admin
Posts: 33128
Joined: 2004-02-23 20:49
First name: Tim
Last name: Kosse
Contact:

Re: impossible to upload a big file (it stops by an error after a few hours)

#11 Post by botg » 2020-06-29 09:09

Thank you. I've boiled down the log to the relevant bits:

Code: Select all

2020-06-26 18:26:56 5444 2 Status: Starting upload of E:\MY_FOLDER\MY_FILE_TO_UPLOAD.mov
2020-06-26 18:26:57 5444 2 Command: MLSD
2020-06-26 18:26:59 5444 2 Listing: type=file;size=71029719040;modify=20200626162632;UNIX.mode=0644;UNIX.uid=1340207;UNIX.gid=1340207;unique=fd04g8ca4ef2; MY_FILE_TO_UPLOAD.mov
2020-06-26 18:27:00 5444 2 Command: PASV
2020-06-26 18:27:00 5444 2 Command: REST 71029719040
2020-06-26 18:27:01 5444 2 Command: STOR MY_FILE_TO_UPLOAD.mov
So far a pretty normal resumed transfer.

Code: Select all

2020-06-26 19:12:13 5444 2 Error: File transfer failed after transferring 3.555.753.984 bytes in 2711 seconds
2020-06-26 19:12:15 5444 2 Status: Starting upload of E:\MY_FOLDER\MY_FILE_TO_UPLOAD.mov
2020-06-26 19:12:15 5444 2 Command: MLSD
2020-06-26 19:12:15 5444 2 Listing: type=file;size=74585079808;modify=20200626171217;UNIX.mode=0644;UNIX.uid=1340207;UNIX.gid=1340207;unique=fd04g8ca4ef2; .pureftpd-upload.5ef621cd.15.3cf92e.e689e26d
Where did the file go? What is that weird other file?

Code: Select all

2020-06-26 19:12:16 5444 2 Command: PASV
2020-06-26 19:12:16 5444 2 Command: STOR MY_FILE_TO_UPLOAD.mov
Since the target file no longer exists, FileZilla starts over from scratch.

Your server uses temporary files for uploads. The solution is to configure the server to not use temporary files. I believe the configuration option for Pure-FTPd is to set NoTruncate to No.


You might ask yourself why this only happens sometimes, not always. This is timing related. By the time the client tries to resume, the server may not yet have noticed that the transfer has failed, or might still be busy finalizing the transfer under the temporary name. Unfortunately this isn't something that can be addressed client side e.g. by waiting a set amount of time after a failed transfer. No amount of waiting time would be sufficient, there are always conceivable scenarios where the server takes longer than any client would wait.

JaviWan
504 Command not implemented
Posts: 7
Joined: 2020-06-18 22:16
First name: Javier
Last name: Rueda

Re: impossible to upload a big file (it stops by an error after a few hours)

#12 Post by JaviWan » 2020-06-29 11:46

Thank you very much botg for all your help.

As the problem is on the side of the server where I upload the file, I will send your analysis to them. Let's hope they manage to fix it the way you describe.

Thanks, thanks, thanks.

javier

Post Reply