Thread Info
  • State Not Answered
  • Locked Locked
  • Replies 4 replies
  • Subscribers 2 subscribers
  • Views 4392 views
  • Users 0 members are here
Options
Suggested
This discussion has been locked.
You can no longer post new replies to this discussion. If you have a question you can start a new discussion

PASV Ftp long transfer timeout

derrickoltmann
I'm having some issues where I can connect fine, using either the Non-Transparent or no FTP Proxy, and transfer small files to a FileZilla server behind the UTM.

The problem, however, is it appears that the connection gets dropped after ~5min.

All timeouts on Filezilla Server are set to 0.
Firewall (windows) is off on this server.
WebFilter & IPS have exceptions for the server host.

I've tested long transfers to other FTP servers (godaddy) which go through fine.
Tested with another server (pure-ftpd) on a NAS behind this UTM, same problem.

This is what I get from frox debug log when using Non-Transparent:

2014:08:26-08:49:58 redrocker frox[19097]: S: 230 Logged on
2014:08:26-08:49:58 redrocker frox[19097]: C: CWD /
2014:08:26-08:49:58 redrocker frox[19097]: S: 250 CWD successful. "/" is current directory.
2014:08:26-08:49:58 redrocker frox[19097]: C: PWD
2014:08:26-08:49:58 redrocker frox[19097]: S: 257 "/" is current directory.
2014:08:26-08:49:58 redrocker frox[19097]: C: TYPE I
2014:08:26-08:49:58 redrocker frox[19097]: S: 200 Type set to I
2014:08:26-08:49:58 redrocker frox[19097]: Intercepted a PASV command
2014:08:26-08:49:58 redrocker frox[19097]: C: PASV
2014:08:26-08:49:59 redrocker frox[19097]: PASV: 67.198.x.x:5625
2014:08:26-08:49:59 redrocker frox[19097]: S: 227 Entering Passive Mode (67,198,x,x,219,219)
2014:08:26-08:49:59 redrocker frox[19097]: C: PWD
2014:08:26-08:49:59 redrocker frox[19097]: C: STOR 200MB.zip
2014:08:26-08:49:59 redrocker frox[19097]: Client has connected to proxy data line
2014:08:26-08:49:59 redrocker frox[19097]: S: 150 Opening data channel for file upload to server of "/200MB.zip"
2014:08:26-08:54:56 redrocker frox[19080]: Connection timed out.
2014:08:26-08:54:56 redrocker frox[19080]: Closing session


This thread was automatically locked due to age.
Parents
  • 0
    What version of UTM - 9.205-12?

    There's only one timer with a five-minute default that I can think of.  Although I can't imagine that it might be the issue, what if you:

    cc set packetfilter timeouts ip_conntrack_tcp_timeout_max_retrans 360


    Does that change your connection timeout from five to six minutes?  Don't forget to set it back to 300 after your experiment.

    Cheers - Bob
  • 0 in reply to BAlfson
    Version: 9.205-12.

    After changing to 360, I still got the timeout at ~5min.

    Interestingly, the transfer still went though fine. (I think this was FileZilla client's doing, the automated software we use to transfer the backups does not recover from a dropped connection so gracefully)

    Unfortunately,  the backup transfer software does not give me very detailed logs. But the jist of what happens is:
    It Connects, Transfers, Read Timeout, Retries complete new transfer, Read Timeout, "completes", logs and sends error.
    The file had actually (mostly) been sent, size even seems correct, but corrupt with an unexpected EOF.

    Logs for Frox, Filezilla Server, and Filezilla Client below:


    Frox Log: 
    2014:08:27-12:09:54 redrocker frox[20990]: Frox started

    2014:08:27-12:09:54 redrocker frox[20990]: Listening on 0.0.0.0:2121

    2014:08:27-12:09:54 redrocker frox[20990]: Dropped privileges

    2014:08:27-12:10:10 redrocker frox[21074]: VS: Virus scanner temp file is /tmp/VS_21074

    2014:08:27-12:10:10 redrocker frox[21074]: Connect from 67.198.x.x(67-198-x-x.dyn.grandenetworks.net)

    2014:08:27-12:10:10 redrocker frox[21074]: S: 220 Frox transparent ftp proxy. Login with username[@host[[:P]ort]]

    2014:08:27-12:10:10 redrocker frox[21074]: NTP: Host=192.168.x.x

    2014:08:27-12:10:10 redrocker frox[21074]: NTP: Port=21

    2014:08:27-12:10:10 redrocker frox[21074]: ... to 192.168.2.58(192.168.2.58)

    2014:08:27-12:10:10 redrocker frox[21074]: Connecting to server...

    2014:08:27-12:10:10 redrocker frox[21074]: OK

    2014:08:27-12:10:10 redrocker frox[21074]: Apparent address = 67.198.xx.***(redrocker)

    2014:08:27-12:10:10 redrocker frox[21074]: Real address = 192.168.x.x

    2014:08:27-12:10:10 redrocker frox[21074]: Proxy address = 192.168.x.x

    2014:08:27-12:10:10 redrocker frox[21074]: C: USER ******x

    2014:08:27-12:10:10 redrocker frox[21074]: S: 331 Password required for mclenco

    2014:08:27-12:10:10 redrocker frox[21074]: C: PASS ******x

    2014:08:27-12:10:10 redrocker frox[21074]: S: 230 Logged on

    2014:08:27-12:10:10 redrocker frox[21074]: C: PWD

    2014:08:27-12:10:10 redrocker frox[21074]: S: 257 "/" is current directory.

    2014:08:27-12:10:10 redrocker frox[21074]: C: TYPE I

    2014:08:27-12:10:10 redrocker frox[21074]: S: 200 Type set to I

    2014:08:27-12:10:10 redrocker frox[21074]: Intercepted a PASV command

    2014:08:27-12:10:10 redrocker frox[21074]: C: PASV

    2014:08:27-12:10:10 redrocker frox[21074]: PASV: 67.198.xx.***:5649

    2014:08:27-12:10:10 redrocker frox[21074]: S: 227 Entering Passive Mode (67,198,xx,***,248,238)

    2014:08:27-12:10:10 redrocker frox[21074]: C: LIST

    2014:08:27-12:10:10 redrocker frox[21074]: Client has connected to proxy data line

    2014:08:27-12:10:11 redrocker frox[21074]: S: 150 Opening data channel for directory listing of "/"

    2014:08:27-12:10:11 redrocker frox[21074]: Closing server data connection

    2014:08:27-12:10:11 redrocker frox[21074]: Closing client data connection

    2014:08:27-12:10:11 redrocker frox[21074]: S: 226 Successfully transferred "/"

    2014:08:27-12:10:18 redrocker frox[21085]: VS: Virus scanner temp file is /tmp/VS_21085

    2014:08:27-12:10:18 redrocker frox[21085]: Connect from 67.198.xx.***(67-198-xx-***.dyn.grandenetworks.net)

    2014:08:27-12:10:18 redrocker frox[21085]: S: 220 Frox transparent ftp proxy. Login with username[@host[[:P]ort]]

    2014:08:27-12:10:18 redrocker frox[21085]: NTP: Host=192.168.x.xx

    2014:08:27-12:10:18 redrocker frox[21085]: NTP: Port=21

    2014:08:27-12:10:18 redrocker frox[21085]: ... to 192.168.2.58(192.168.2.58)

    2014:08:27-12:10:18 redrocker frox[21085]: Connecting to server...

    2014:08:27-12:10:18 redrocker frox[21085]: OK

    2014:08:27-12:10:18 redrocker frox[21085]: Apparent address = 67.198.69.174(redrocker)

    2014:08:27-12:10:18 redrocker frox[21085]: Real address = 192.168.x.xx

    2014:08:27-12:10:18 redrocker frox[21085]: Proxy address = 192.168.x.xx

    2014:08:27-12:10:18 redrocker frox[21085]: C: USER ******x

    2014:08:27-12:10:18 redrocker frox[21085]: S: 331 Password required for mclenco

    2014:08:27-12:10:18 redrocker frox[21085]: C: PASS ******x

    2014:08:27-12:10:18 redrocker frox[21085]: S: 230 Logged on

    2014:08:27-12:10:18 redrocker frox[21085]: C: CWD /

    2014:08:27-12:10:18 redrocker frox[21085]: S: 250 CWD successful. "/" is current directory.

    2014:08:27-12:10:18 redrocker frox[21085]: C: TYPE I

    2014:08:27-12:10:18 redrocker frox[21085]: S: 200 Type set to I

    2014:08:27-12:10:18 redrocker frox[21085]: Intercepted a PASV command

    2014:08:27-12:10:18 redrocker frox[21085]: C: PASV

    2014:08:27-12:10:19 redrocker frox[21085]: PASV: 67.198.xx.***:5650

    2014:08:27-12:10:19 redrocker frox[21085]: S: 227 Entering Passive Mode (67,198,xx,***,28,181)

    2014:08:27-12:10:19 redrocker frox[21085]: C: PWD

    2014:08:27-12:10:19 redrocker frox[21085]: C: STOR 200MB.zip

    2014:08:27-12:10:19 redrocker frox[21085]: Client has connected to proxy data line

    2014:08:27-12:10:19 redrocker frox[21085]: S: 150 Opening data channel for file upload to server of "/200MB.zip"

    2014:08:27-12:15:11 redrocker frox[21074]: Connection timed out.

    2014:08:27-12:15:11 redrocker frox[21074]: Closing session    

    2014:08:27-12:19:43 redrocker frox[21085]: Closing server data connection

    2014:08:27-12:19:43 redrocker frox[21085]: Closing client data connection

    2014:08:27-12:19:43 redrocker frox[21085]: id="0101" severity="info" sys="SecureWeb" sub="ftp" name="send file, not scanned" srcip="67.198.xx.***" dstip="192.168.x.xx" url="192.168.x.xx/200MB.zip" user="mclenco" size="209715200"

    2014:08:27-12:19:43 redrocker frox[21085]: S: 226 Successfully transferred "/200MB.zip"

    2014:08:27-12:19:43 redrocker frox[21085]: Intercepted a PASV command

    2014:08:27-12:19:43 redrocker frox[21085]: C: PASV

    2014:08:27-12:19:43 redrocker frox[21085]: PASV: 67.198.xx.***:5600

    2014:08:27-12:19:43 redrocker frox[21085]: S: 227 Entering Passive Mode (67,198,69,174,92,89)

    2014:08:27-12:19:43 redrocker frox[21085]: C: LIST

    2014:08:27-12:19:43 redrocker frox[21085]: Client has connected to proxy data line

    2014:08:27-12:19:43 redrocker frox[21085]: S: 150 Opening data channel for directory listing of "/"

    2014:08:27-12:19:43 redrocker frox[21085]: Closing server data connection

    2014:08:27-12:19:43 redrocker frox[21085]: Closing client data connection

    2014:08:27-12:19:43 redrocker frox[21085]: S: 226 Successfully transferred "/"

    2014:08:27-12:20:12 redrocker frox[22082]: VS: Virus scanner temp file is /tmp/VS_22082

    2014:08:27-12:20:12 redrocker frox[22082]: Connect from 67.198.xx.***(67-198-xx-***.dyn.grandenetworks.net)

    2014:08:27-12:20:12 redrocker frox[22082]: S: 220 Frox transparent ftp proxy. Login with username[@host[[:P]ort]]

    2014:08:27-12:20:12 redrocker frox[22082]: NTP: Host=192.168.x.xx

    2014:08:27-12:20:12 redrocker frox[22082]: NTP: Port=21

    2014:08:27-12:20:12 redrocker frox[22082]: ... to 192.168.2.58(192.168.2.58)

    2014:08:27-12:20:12 redrocker frox[22082]: Connecting to server...

    2014:08:27-12:20:12 redrocker frox[22082]: OK

    2014:08:27-12:20:12 redrocker frox[22082]: Apparent address = 67.198.69.174(redrocker)

    2014:08:27-12:20:12 redrocker frox[22082]: Real address = 192.168.x.xx

    2014:08:27-12:20:12 redrocker frox[22082]: Proxy address = 192.168.x.xx

    2014:08:27-12:20:12 redrocker frox[22082]: C: USER ******x

    2014:08:27-12:20:12 redrocker frox[22082]: S: 331 Password required for mclenco

    2014:08:27-12:20:12 redrocker frox[22082]: C: PASS *********

    2014:08:27-12:20:12 redrocker frox[22082]: S: 230 Logged on

    2014:08:27-12:20:12 redrocker frox[22082]: C: CWD /

    2014:08:27-12:20:12 redrocker frox[22082]: S: 250 CWD successful. "/" is current directory.

    2014:08:27-12:20:12 redrocker frox[22082]: C: TYPE I

    2014:08:27-12:20:12 redrocker frox[22082]: S: 200 Type set to I

    2014:08:27-12:20:12 redrocker frox[22082]: Intercepted a PASV command

    2014:08:27-12:20:12 redrocker frox[22082]: C: PASV

    2014:08:27-12:20:12 redrocker frox[22082]: PASV: 67.198.xx.***:5601

    2014:08:27-12:20:12 redrocker frox[22082]: S: 227 Entering Passive Mode (67,198,xx,***,171,58)

    2014:08:27-12:20:12 redrocker frox[22082]: C: LIST

    2014:08:27-12:20:12 redrocker frox[22082]: Client has connected to proxy data line

    2014:08:27-12:20:13 redrocker frox[22082]: S: 150 Opening data channel for directory listing of "/"

    2014:08:27-12:20:13 redrocker frox[22082]: Closing server data connection

    2014:08:27-12:20:13 redrocker frox[22082]: Closing client data connection

    2014:08:27-12:20:13 redrocker frox[22082]: S: 226 Successfully transferred "/"

    2014:08:27-12:20:19 redrocker frox[22082]: Client closed connection

    2014:08:27-12:20:19 redrocker frox[22082]: Closing session



    FileZilla Server Log: 
    (000058)8/27/2014 12:10:14 PM - (not logged in) (192.168.x.1)> Connected on port 21, sending welcome message...

    (000058)8/27/2014 12:10:14 PM - (not logged in) (192.168.x.1)> 220-FileZilla Server version 0.9.46 beta

    (000058)8/27/2014 12:10:14 PM - (not logged in) (192.168.x.1)> 220-written by Tim Kosse (tim.kosse@filezilla-project.org)

    (000058)8/27/2014 12:10:14 PM - (not logged in) (192.168.x.1)> 220 Please visit sourceforge.net/.../

    (000058)8/27/2014 12:10:14 PM - (not logged in) (192.168.x.1)> USER ******

    (000058)8/27/2014 12:10:14 PM - (not logged in) (192.168.x.1)> 331 Password required for ******

    (000058)8/27/2014 12:10:14 PM - (not logged in) (192.168.x.1)> PASS *********

    (000058)8/27/2014 12:10:14 PM - ****** (192.168.x.1)> 230 Logged on

    (000058)8/27/2014 12:10:14 PM - ****** (192.168.x.1)> PWD

    (000058)8/27/2014 12:10:14 PM - ****** (192.168.x.1)> 257 "/" is current directory.

    (000058)8/27/2014 12:10:14 PM - ****** (192.168.x.1)> TYPE I

    (000058)8/27/2014 12:10:14 PM - ****** (192.168.x.1)> 200 Type set to I

    (000058)8/27/2014 12:10:14 PM - ****** (192.168.x.1)> PASV

    (000058)8/27/2014 12:10:14 PM - ****** (192.168.x.1)> 227 Entering Passive Mode (67,198,xx,***,22,17)

    (000058)8/27/2014 12:10:14 PM - ****** (192.168.x.1)> LIST

    (000058)8/27/2014 12:10:15 PM - ****** (192.168.x.1)> 150 Opening data channel for directory listing of "/"

    (000058)8/27/2014 12:10:15 PM - ****** (192.168.x.1)> 226 Successfully transferred "/"

    (000059)8/27/2014 12:10:23 PM - (not logged in) (192.168.x.1)> Connected on port 21, sending welcome message...

    (000059)8/27/2014 12:10:23 PM - (not logged in) (192.168.x.1)> 220-FileZilla Server version 0.9.46 beta

    (000059)8/27/2014 12:10:23 PM - (not logged in) (192.168.x.1)> 220-written by Tim Kosse (tim.kosse@filezilla-project.org)

    (000059)8/27/2014 12:10:23 PM - (not logged in) (192.168.x.1)> 220 Please visit sourceforge.net/.../

    (000059)8/27/2014 12:10:23 PM - (not logged in) (192.168.x.1)> USER ******

    (000059)8/27/2014 12:10:23 PM - (not logged in) (192.168.x.1)> 331 Password required for ******

    (000059)8/27/2014 12:10:23 PM - (not logged in) (192.168.x.1)> PASS *********

    (000059)8/27/2014 12:10:23 PM - ****** (192.168.x.1)> 230 Logged on

    (000059)8/27/2014 12:10:23 PM - ****** (192.168.x.1)> CWD /

    (000059)8/27/2014 12:10:23 PM - ****** (192.168.x.1)> 250 CWD successful. "/" is current directory.

    (000059)8/27/2014 12:10:23 PM - ****** (192.168.x.1)> TYPE I

    (000059)8/27/2014 12:10:23 PM - ****** (192.168.x.1)> 200 Type set to I

    (000059)8/27/2014 12:10:23 PM - ****** (192.168.x.1)> PASV

    (000059)8/27/2014 12:10:23 PM - ****** (192.168.x.1)> 227 Entering Passive Mode (67,198,xx,***,22,18)

    (000059)8/27/2014 12:10:23 PM - ****** (192.168.x.1)> PWD

    (000059)8/27/2014 12:10:23 PM - ****** (192.168.x.1)> 257 "/" is current directory.

    (000059)8/27/2014 12:10:23 PM - ****** (192.168.x.1)> STOR 200MB.zip

    (000059)8/27/2014 12:10:23 PM - ****** (192.168.x.1)> 150 Opening data channel for file upload to server of "/200MB.zip"

    (000058)8/27/2014 12:15:15 PM - ****** (192.168.x.1)> disconnected.

    (000059)8/27/2014 12:19:47 PM - ****** (192.168.x.1)> 226 Successfully transferred "/200MB.zip"

    (000059)8/27/2014 12:19:47 PM - ****** (192.168.x.1)> PASV

    (000059)8/27/2014 12:19:47 PM - ****** (192.168.x.1)> 227 Entering Passive Mode (67,198,xx,***,21,224)

    (000059)8/27/2014 12:19:48 PM - ****** (192.168.x.1)> LIST

    (000059)8/27/2014 12:19:48 PM - ****** (192.168.x.1)> 150 Opening data channel for directory listing of "/"

    (000059)8/27/2014 12:19:48 PM - ****** (192.168.x.1)> 226 Successfully transferred "/"

    (000060)8/27/2014 12:20:16 PM - (not logged in) (192.168.x.1)> Connected on port 21, sending welcome message...

    (000060)8/27/2014 12:20:16 PM - (not logged in) (192.168.x.1)> 220-FileZilla Server version 0.9.46 beta

    (000060)8/27/2014 12:20:16 PM - (not logged in) (192.168.x.1)> 220-written by Tim Kosse (tim.kosse@filezilla-project.org)

    (000060)8/27/2014 12:20:16 PM - (not logged in) (192.168.x.1)> 220 Please visit sourceforge.net/.../

    (000060)8/27/2014 12:20:16 PM - (not logged in) (192.168.x.1)> USER ******

    (000060)8/27/2014 12:20:16 PM - (not logged in) (192.168.x.1)> 331 Password required for ******

    (000060)8/27/2014 12:20:16 PM - (not logged in) (192.168.x.1)> PASS *********

    (000060)8/27/2014 12:20:16 PM - ****** (192.168.x.1)> 230 Logged on

    (000060)8/27/2014 12:20:16 PM - ****** (192.168.x.1)> CWD /

    (000060)8/27/2014 12:20:16 PM - ****** (192.168.x.1)> 250 CWD successful. "/" is current directory.

    (000060)8/27/2014 12:20:16 PM - ****** (192.168.x.1)> TYPE I

    (000060)8/27/2014 12:20:16 PM - ****** (192.168.x.1)> 200 Type set to I

    (000060)8/27/2014 12:20:16 PM - ****** (192.168.x.1)> PASV

    (000060)8/27/2014 12:20:16 PM - ****** (192.168.x.1)> 227 Entering Passive Mode (67,198,xx,***,21,225)

    (000060)8/27/2014 12:20:16 PM - ****** (192.168.x.1)> LIST

    (000060)8/27/2014 12:20:17 PM - ****** (192.168.x.1)> 150 Opening data channel for directory listing of "/"

    (000060)8/27/2014 12:20:17 PM - ****** (192.168.x.1)> 226 Successfully transferred "/"

    (000060)8/27/2014 12:20:23 PM - ****** (192.168.x.1)> disconnected.



    FileZilla Client Log: 

    Status:	Connecting to 67.198.xx.***:2121...

    Status:	Connection established, waiting for welcome message...

    Response:	220 Frox transparent ftp proxy. Login with username[@host[[:P]ort]]

    Command:	USER ***xx@192.168.x.xx

    Response:	331 Password required for ***xx

    Command:	PASS *********

    Response:	230 Logged on

    Status:	Server does not support non-ASCII characters.

    Status:	Connected

    Status:	Starting upload of S:\Test Files\200MB.zip

    Command:	CWD /

    Response:	250 CWD successful. "/" is current directory.

    Command:	TYPE I

    Response:	200 Type set to I

    Command:	PASV

    Response:	227 Entering Passive Mode (67,198,xx,***,28,181)

    Command:	STOR 200MB.zip

    Response:	150 Opening data channel for file upload to server of "/200MB.zip"

    Error:	Connection closed by server

    Response:	226 Successfully transferred "/200MB.zip"

    Status:	File transfer successful, transferred 209,715,200 bytes in 564 seconds

    Status:	Retrieving directory listing...

    Command:	PASV

    Response:	227 Entering Passive Mode (67,198,xx,***,92,89)

    Command:	LIST

    Response:	150 Opening data channel for directory listing of "/"

    Response:	226 Successfully transferred "/"

    Status:	Directory listing successful
Reply
  • 0 in reply to BAlfson
    Version: 9.205-12.

    After changing to 360, I still got the timeout at ~5min.

    Interestingly, the transfer still went though fine. (I think this was FileZilla client's doing, the automated software we use to transfer the backups does not recover from a dropped connection so gracefully)

    Unfortunately,  the backup transfer software does not give me very detailed logs. But the jist of what happens is:
    It Connects, Transfers, Read Timeout, Retries complete new transfer, Read Timeout, "completes", logs and sends error.
    The file had actually (mostly) been sent, size even seems correct, but corrupt with an unexpected EOF.

    Logs for Frox, Filezilla Server, and Filezilla Client below:


    Frox Log: 
    2014:08:27-12:09:54 redrocker frox[20990]: Frox started

    2014:08:27-12:09:54 redrocker frox[20990]: Listening on 0.0.0.0:2121

    2014:08:27-12:09:54 redrocker frox[20990]: Dropped privileges

    2014:08:27-12:10:10 redrocker frox[21074]: VS: Virus scanner temp file is /tmp/VS_21074

    2014:08:27-12:10:10 redrocker frox[21074]: Connect from 67.198.x.x(67-198-x-x.dyn.grandenetworks.net)

    2014:08:27-12:10:10 redrocker frox[21074]: S: 220 Frox transparent ftp proxy. Login with username[@host[[:P]ort]]

    2014:08:27-12:10:10 redrocker frox[21074]: NTP: Host=192.168.x.x

    2014:08:27-12:10:10 redrocker frox[21074]: NTP: Port=21

    2014:08:27-12:10:10 redrocker frox[21074]: ... to 192.168.2.58(192.168.2.58)

    2014:08:27-12:10:10 redrocker frox[21074]: Connecting to server...

    2014:08:27-12:10:10 redrocker frox[21074]: OK

    2014:08:27-12:10:10 redrocker frox[21074]: Apparent address = 67.198.xx.***(redrocker)

    2014:08:27-12:10:10 redrocker frox[21074]: Real address = 192.168.x.x

    2014:08:27-12:10:10 redrocker frox[21074]: Proxy address = 192.168.x.x

    2014:08:27-12:10:10 redrocker frox[21074]: C: USER ******x

    2014:08:27-12:10:10 redrocker frox[21074]: S: 331 Password required for mclenco

    2014:08:27-12:10:10 redrocker frox[21074]: C: PASS ******x

    2014:08:27-12:10:10 redrocker frox[21074]: S: 230 Logged on

    2014:08:27-12:10:10 redrocker frox[21074]: C: PWD

    2014:08:27-12:10:10 redrocker frox[21074]: S: 257 "/" is current directory.

    2014:08:27-12:10:10 redrocker frox[21074]: C: TYPE I

    2014:08:27-12:10:10 redrocker frox[21074]: S: 200 Type set to I

    2014:08:27-12:10:10 redrocker frox[21074]: Intercepted a PASV command

    2014:08:27-12:10:10 redrocker frox[21074]: C: PASV

    2014:08:27-12:10:10 redrocker frox[21074]: PASV: 67.198.xx.***:5649

    2014:08:27-12:10:10 redrocker frox[21074]: S: 227 Entering Passive Mode (67,198,xx,***,248,238)

    2014:08:27-12:10:10 redrocker frox[21074]: C: LIST

    2014:08:27-12:10:10 redrocker frox[21074]: Client has connected to proxy data line

    2014:08:27-12:10:11 redrocker frox[21074]: S: 150 Opening data channel for directory listing of "/"

    2014:08:27-12:10:11 redrocker frox[21074]: Closing server data connection

    2014:08:27-12:10:11 redrocker frox[21074]: Closing client data connection

    2014:08:27-12:10:11 redrocker frox[21074]: S: 226 Successfully transferred "/"

    2014:08:27-12:10:18 redrocker frox[21085]: VS: Virus scanner temp file is /tmp/VS_21085

    2014:08:27-12:10:18 redrocker frox[21085]: Connect from 67.198.xx.***(67-198-xx-***.dyn.grandenetworks.net)

    2014:08:27-12:10:18 redrocker frox[21085]: S: 220 Frox transparent ftp proxy. Login with username[@host[[:P]ort]]

    2014:08:27-12:10:18 redrocker frox[21085]: NTP: Host=192.168.x.xx

    2014:08:27-12:10:18 redrocker frox[21085]: NTP: Port=21

    2014:08:27-12:10:18 redrocker frox[21085]: ... to 192.168.2.58(192.168.2.58)

    2014:08:27-12:10:18 redrocker frox[21085]: Connecting to server...

    2014:08:27-12:10:18 redrocker frox[21085]: OK

    2014:08:27-12:10:18 redrocker frox[21085]: Apparent address = 67.198.69.174(redrocker)

    2014:08:27-12:10:18 redrocker frox[21085]: Real address = 192.168.x.xx

    2014:08:27-12:10:18 redrocker frox[21085]: Proxy address = 192.168.x.xx

    2014:08:27-12:10:18 redrocker frox[21085]: C: USER ******x

    2014:08:27-12:10:18 redrocker frox[21085]: S: 331 Password required for mclenco

    2014:08:27-12:10:18 redrocker frox[21085]: C: PASS ******x

    2014:08:27-12:10:18 redrocker frox[21085]: S: 230 Logged on

    2014:08:27-12:10:18 redrocker frox[21085]: C: CWD /

    2014:08:27-12:10:18 redrocker frox[21085]: S: 250 CWD successful. "/" is current directory.

    2014:08:27-12:10:18 redrocker frox[21085]: C: TYPE I

    2014:08:27-12:10:18 redrocker frox[21085]: S: 200 Type set to I

    2014:08:27-12:10:18 redrocker frox[21085]: Intercepted a PASV command

    2014:08:27-12:10:18 redrocker frox[21085]: C: PASV

    2014:08:27-12:10:19 redrocker frox[21085]: PASV: 67.198.xx.***:5650

    2014:08:27-12:10:19 redrocker frox[21085]: S: 227 Entering Passive Mode (67,198,xx,***,28,181)

    2014:08:27-12:10:19 redrocker frox[21085]: C: PWD

    2014:08:27-12:10:19 redrocker frox[21085]: C: STOR 200MB.zip

    2014:08:27-12:10:19 redrocker frox[21085]: Client has connected to proxy data line

    2014:08:27-12:10:19 redrocker frox[21085]: S: 150 Opening data channel for file upload to server of "/200MB.zip"

    2014:08:27-12:15:11 redrocker frox[21074]: Connection timed out.

    2014:08:27-12:15:11 redrocker frox[21074]: Closing session    

    2014:08:27-12:19:43 redrocker frox[21085]: Closing server data connection

    2014:08:27-12:19:43 redrocker frox[21085]: Closing client data connection

    2014:08:27-12:19:43 redrocker frox[21085]: id="0101" severity="info" sys="SecureWeb" sub="ftp" name="send file, not scanned" srcip="67.198.xx.***" dstip="192.168.x.xx" url="192.168.x.xx/200MB.zip" user="mclenco" size="209715200"

    2014:08:27-12:19:43 redrocker frox[21085]: S: 226 Successfully transferred "/200MB.zip"

    2014:08:27-12:19:43 redrocker frox[21085]: Intercepted a PASV command

    2014:08:27-12:19:43 redrocker frox[21085]: C: PASV

    2014:08:27-12:19:43 redrocker frox[21085]: PASV: 67.198.xx.***:5600

    2014:08:27-12:19:43 redrocker frox[21085]: S: 227 Entering Passive Mode (67,198,69,174,92,89)

    2014:08:27-12:19:43 redrocker frox[21085]: C: LIST

    2014:08:27-12:19:43 redrocker frox[21085]: Client has connected to proxy data line

    2014:08:27-12:19:43 redrocker frox[21085]: S: 150 Opening data channel for directory listing of "/"

    2014:08:27-12:19:43 redrocker frox[21085]: Closing server data connection

    2014:08:27-12:19:43 redrocker frox[21085]: Closing client data connection

    2014:08:27-12:19:43 redrocker frox[21085]: S: 226 Successfully transferred "/"

    2014:08:27-12:20:12 redrocker frox[22082]: VS: Virus scanner temp file is /tmp/VS_22082

    2014:08:27-12:20:12 redrocker frox[22082]: Connect from 67.198.xx.***(67-198-xx-***.dyn.grandenetworks.net)

    2014:08:27-12:20:12 redrocker frox[22082]: S: 220 Frox transparent ftp proxy. Login with username[@host[[:P]ort]]

    2014:08:27-12:20:12 redrocker frox[22082]: NTP: Host=192.168.x.xx

    2014:08:27-12:20:12 redrocker frox[22082]: NTP: Port=21

    2014:08:27-12:20:12 redrocker frox[22082]: ... to 192.168.2.58(192.168.2.58)

    2014:08:27-12:20:12 redrocker frox[22082]: Connecting to server...

    2014:08:27-12:20:12 redrocker frox[22082]: OK

    2014:08:27-12:20:12 redrocker frox[22082]: Apparent address = 67.198.69.174(redrocker)

    2014:08:27-12:20:12 redrocker frox[22082]: Real address = 192.168.x.xx

    2014:08:27-12:20:12 redrocker frox[22082]: Proxy address = 192.168.x.xx

    2014:08:27-12:20:12 redrocker frox[22082]: C: USER ******x

    2014:08:27-12:20:12 redrocker frox[22082]: S: 331 Password required for mclenco

    2014:08:27-12:20:12 redrocker frox[22082]: C: PASS *********

    2014:08:27-12:20:12 redrocker frox[22082]: S: 230 Logged on

    2014:08:27-12:20:12 redrocker frox[22082]: C: CWD /

    2014:08:27-12:20:12 redrocker frox[22082]: S: 250 CWD successful. "/" is current directory.

    2014:08:27-12:20:12 redrocker frox[22082]: C: TYPE I

    2014:08:27-12:20:12 redrocker frox[22082]: S: 200 Type set to I

    2014:08:27-12:20:12 redrocker frox[22082]: Intercepted a PASV command

    2014:08:27-12:20:12 redrocker frox[22082]: C: PASV

    2014:08:27-12:20:12 redrocker frox[22082]: PASV: 67.198.xx.***:5601

    2014:08:27-12:20:12 redrocker frox[22082]: S: 227 Entering Passive Mode (67,198,xx,***,171,58)

    2014:08:27-12:20:12 redrocker frox[22082]: C: LIST

    2014:08:27-12:20:12 redrocker frox[22082]: Client has connected to proxy data line

    2014:08:27-12:20:13 redrocker frox[22082]: S: 150 Opening data channel for directory listing of "/"

    2014:08:27-12:20:13 redrocker frox[22082]: Closing server data connection

    2014:08:27-12:20:13 redrocker frox[22082]: Closing client data connection

    2014:08:27-12:20:13 redrocker frox[22082]: S: 226 Successfully transferred "/"

    2014:08:27-12:20:19 redrocker frox[22082]: Client closed connection

    2014:08:27-12:20:19 redrocker frox[22082]: Closing session



    FileZilla Server Log: 
    (000058)8/27/2014 12:10:14 PM - (not logged in) (192.168.x.1)> Connected on port 21, sending welcome message...

    (000058)8/27/2014 12:10:14 PM - (not logged in) (192.168.x.1)> 220-FileZilla Server version 0.9.46 beta

    (000058)8/27/2014 12:10:14 PM - (not logged in) (192.168.x.1)> 220-written by Tim Kosse (tim.kosse@filezilla-project.org)

    (000058)8/27/2014 12:10:14 PM - (not logged in) (192.168.x.1)> 220 Please visit sourceforge.net/.../

    (000058)8/27/2014 12:10:14 PM - (not logged in) (192.168.x.1)> USER ******

    (000058)8/27/2014 12:10:14 PM - (not logged in) (192.168.x.1)> 331 Password required for ******

    (000058)8/27/2014 12:10:14 PM - (not logged in) (192.168.x.1)> PASS *********

    (000058)8/27/2014 12:10:14 PM - ****** (192.168.x.1)> 230 Logged on

    (000058)8/27/2014 12:10:14 PM - ****** (192.168.x.1)> PWD

    (000058)8/27/2014 12:10:14 PM - ****** (192.168.x.1)> 257 "/" is current directory.

    (000058)8/27/2014 12:10:14 PM - ****** (192.168.x.1)> TYPE I

    (000058)8/27/2014 12:10:14 PM - ****** (192.168.x.1)> 200 Type set to I

    (000058)8/27/2014 12:10:14 PM - ****** (192.168.x.1)> PASV

    (000058)8/27/2014 12:10:14 PM - ****** (192.168.x.1)> 227 Entering Passive Mode (67,198,xx,***,22,17)

    (000058)8/27/2014 12:10:14 PM - ****** (192.168.x.1)> LIST

    (000058)8/27/2014 12:10:15 PM - ****** (192.168.x.1)> 150 Opening data channel for directory listing of "/"

    (000058)8/27/2014 12:10:15 PM - ****** (192.168.x.1)> 226 Successfully transferred "/"

    (000059)8/27/2014 12:10:23 PM - (not logged in) (192.168.x.1)> Connected on port 21, sending welcome message...

    (000059)8/27/2014 12:10:23 PM - (not logged in) (192.168.x.1)> 220-FileZilla Server version 0.9.46 beta

    (000059)8/27/2014 12:10:23 PM - (not logged in) (192.168.x.1)> 220-written by Tim Kosse (tim.kosse@filezilla-project.org)

    (000059)8/27/2014 12:10:23 PM - (not logged in) (192.168.x.1)> 220 Please visit sourceforge.net/.../

    (000059)8/27/2014 12:10:23 PM - (not logged in) (192.168.x.1)> USER ******

    (000059)8/27/2014 12:10:23 PM - (not logged in) (192.168.x.1)> 331 Password required for ******

    (000059)8/27/2014 12:10:23 PM - (not logged in) (192.168.x.1)> PASS *********

    (000059)8/27/2014 12:10:23 PM - ****** (192.168.x.1)> 230 Logged on

    (000059)8/27/2014 12:10:23 PM - ****** (192.168.x.1)> CWD /

    (000059)8/27/2014 12:10:23 PM - ****** (192.168.x.1)> 250 CWD successful. "/" is current directory.

    (000059)8/27/2014 12:10:23 PM - ****** (192.168.x.1)> TYPE I

    (000059)8/27/2014 12:10:23 PM - ****** (192.168.x.1)> 200 Type set to I

    (000059)8/27/2014 12:10:23 PM - ****** (192.168.x.1)> PASV

    (000059)8/27/2014 12:10:23 PM - ****** (192.168.x.1)> 227 Entering Passive Mode (67,198,xx,***,22,18)

    (000059)8/27/2014 12:10:23 PM - ****** (192.168.x.1)> PWD

    (000059)8/27/2014 12:10:23 PM - ****** (192.168.x.1)> 257 "/" is current directory.

    (000059)8/27/2014 12:10:23 PM - ****** (192.168.x.1)> STOR 200MB.zip

    (000059)8/27/2014 12:10:23 PM - ****** (192.168.x.1)> 150 Opening data channel for file upload to server of "/200MB.zip"

    (000058)8/27/2014 12:15:15 PM - ****** (192.168.x.1)> disconnected.

    (000059)8/27/2014 12:19:47 PM - ****** (192.168.x.1)> 226 Successfully transferred "/200MB.zip"

    (000059)8/27/2014 12:19:47 PM - ****** (192.168.x.1)> PASV

    (000059)8/27/2014 12:19:47 PM - ****** (192.168.x.1)> 227 Entering Passive Mode (67,198,xx,***,21,224)

    (000059)8/27/2014 12:19:48 PM - ****** (192.168.x.1)> LIST

    (000059)8/27/2014 12:19:48 PM - ****** (192.168.x.1)> 150 Opening data channel for directory listing of "/"

    (000059)8/27/2014 12:19:48 PM - ****** (192.168.x.1)> 226 Successfully transferred "/"

    (000060)8/27/2014 12:20:16 PM - (not logged in) (192.168.x.1)> Connected on port 21, sending welcome message...

    (000060)8/27/2014 12:20:16 PM - (not logged in) (192.168.x.1)> 220-FileZilla Server version 0.9.46 beta

    (000060)8/27/2014 12:20:16 PM - (not logged in) (192.168.x.1)> 220-written by Tim Kosse (tim.kosse@filezilla-project.org)

    (000060)8/27/2014 12:20:16 PM - (not logged in) (192.168.x.1)> 220 Please visit sourceforge.net/.../

    (000060)8/27/2014 12:20:16 PM - (not logged in) (192.168.x.1)> USER ******

    (000060)8/27/2014 12:20:16 PM - (not logged in) (192.168.x.1)> 331 Password required for ******

    (000060)8/27/2014 12:20:16 PM - (not logged in) (192.168.x.1)> PASS *********

    (000060)8/27/2014 12:20:16 PM - ****** (192.168.x.1)> 230 Logged on

    (000060)8/27/2014 12:20:16 PM - ****** (192.168.x.1)> CWD /

    (000060)8/27/2014 12:20:16 PM - ****** (192.168.x.1)> 250 CWD successful. "/" is current directory.

    (000060)8/27/2014 12:20:16 PM - ****** (192.168.x.1)> TYPE I

    (000060)8/27/2014 12:20:16 PM - ****** (192.168.x.1)> 200 Type set to I

    (000060)8/27/2014 12:20:16 PM - ****** (192.168.x.1)> PASV

    (000060)8/27/2014 12:20:16 PM - ****** (192.168.x.1)> 227 Entering Passive Mode (67,198,xx,***,21,225)

    (000060)8/27/2014 12:20:16 PM - ****** (192.168.x.1)> LIST

    (000060)8/27/2014 12:20:17 PM - ****** (192.168.x.1)> 150 Opening data channel for directory listing of "/"

    (000060)8/27/2014 12:20:17 PM - ****** (192.168.x.1)> 226 Successfully transferred "/"

    (000060)8/27/2014 12:20:23 PM - ****** (192.168.x.1)> disconnected.



    FileZilla Client Log: 

    Status:	Connecting to 67.198.xx.***:2121...

    Status:	Connection established, waiting for welcome message...

    Response:	220 Frox transparent ftp proxy. Login with username[@host[[:P]ort]]

    Command:	USER ***xx@192.168.x.xx

    Response:	331 Password required for ***xx

    Command:	PASS *********

    Response:	230 Logged on

    Status:	Server does not support non-ASCII characters.

    Status:	Connected

    Status:	Starting upload of S:\Test Files\200MB.zip

    Command:	CWD /

    Response:	250 CWD successful. "/" is current directory.

    Command:	TYPE I

    Response:	200 Type set to I

    Command:	PASV

    Response:	227 Entering Passive Mode (67,198,xx,***,28,181)

    Command:	STOR 200MB.zip

    Response:	150 Opening data channel for file upload to server of "/200MB.zip"

    Error:	Connection closed by server

    Response:	226 Successfully transferred "/200MB.zip"

    Status:	File transfer successful, transferred 209,715,200 bytes in 564 seconds

    Status:	Retrieving directory listing...

    Command:	PASV

    Response:	227 Entering Passive Mode (67,198,xx,***,92,89)

    Command:	LIST

    Response:	150 Opening data channel for directory listing of "/"

    Response:	226 Successfully transferred "/"

    Status:	Directory listing successful
Children
No Data