Jump to content


Issue Information

  • #000041

  • 0 - None Assigned

  • Unfiled

  • -

  • -

Issue Confirmations

  • Yes (0)No (0)
Photo

File transfers stalling at completion point.

Posted by L_A on 13 December 2009 - 05:54 PM

FTPD type: DrFTPD

When files in the transfer queue are complete they stall(transfer drops to 0k, time to complete stays at 0 secons).
After a while the transfer timeouts and the thread reconnects and trys to transfer again, but this time notices that the file transfer is complete.
It should do that to begin with, not needing a timeout which wastes transfering time.

Post the log and the system system information.

I don't see why it timeouts(even though the transfer is actually completed.
After the timeout it looks like it checks the file checksum compared to the sfv and notices it's the same and then skips it

[2009-12-15T06:40:12] Log opened
[2009-12-15T06:40:12] Initializing. Transfer Queue v4.0.1072.0.
[2009-12-15T06:40:12] Operation Begin
[2009-12-15T06:40:12] Resolving host name "xxx"
[2009-12-15T06:40:12] Connecting to xxx Port: xxx
[2009-12-15T06:40:12] Connected to xxx.
[2009-12-15T06:40:12] 220 ?
[2009-12-15T06:40:12] AUTH TLS
[2009-12-15T06:40:13] 234 AUTH TLS successful
[2009-12-15T06:40:13] Connected. Exchanging encryption keys...
[2009-12-15T06:40:14] Key Exchange: 1024 bit RSA
[2009-12-15T06:40:14] Session Cipher: 128 bit AES
[2009-12-15T06:40:14] TLS encrypted session established.
[2009-12-15T06:40:14] Command channel protection set to Private.
[2009-12-15T06:40:14] PBSZ 0
[2009-12-15T06:40:15] 200 Command okay
[2009-12-15T06:40:15] USER xxx
[2009-12-15T06:40:15] 331 Password required for xxx.
[2009-12-15T06:40:15] PASS (hidden)
[2009-12-15T06:40:16] 230- ÛÛÛÛÛÛÛÛß
[2009-12-15T06:40:16] 230 xxx logged in successfully.
[2009-12-15T06:40:16] SYST
[2009-12-15T06:40:17] 215 UNIX system type.
[2009-12-15T06:40:17] Detected Server Type: UNIX
[2009-12-15T06:40:17] RTT: 1232.921 ms
[2009-12-15T06:40:17] FEAT
[2009-12-15T06:40:18] 211-Extensions supported:
[2009-12-15T06:40:18] PRET
[2009-12-15T06:40:18] AUTH SSL
[2009-12-15T06:40:18] PBSZ
[2009-12-15T06:40:18] CPSV
[2009-12-15T06:40:18] SSCN
[2009-12-15T06:40:18] CLNT
[2009-12-15T06:40:18] NOOP
[2009-12-15T06:40:18] MLST type*,x.crc32*,size*,modify*,unix.owner*,unix.group*,x.slaves*,x.xfertime*
[2009-12-15T06:40:18] 211 End
[2009-12-15T06:40:18] CLNT SmartFTP 4.0.1072.0
[2009-12-15T06:40:18] 200 Command okay
[2009-12-15T06:40:18] Detected Server Software: DrFTPD
[2009-12-15T06:40:18] TYPE I
[2009-12-15T06:40:18] 200 Command okay
[2009-12-15T06:40:18] REST 0
[2009-12-15T06:40:19] 350 Requested file action pending further information.
[2009-12-15T06:40:19] PWD
[2009-12-15T06:40:19] 257 "/" is current directory
[2009-12-15T06:40:19] CWD /xxx/CD1
[2009-12-15T06:40:19] 250 Directory changed to /xxx/CD1
[2009-12-15T06:40:19] PWD
[2009-12-15T06:40:20] 257 "/xxx/CD1" is current directory
[2009-12-15T06:40:20] MLST test.r01
[2009-12-15T06:40:20] 250- Begin
[2009-12-15T06:40:20] type=file;x.crc32=12137727;size=15000000;modify=20091209065703.255;unix.owner=xxx;unix.group=xxx;x.slaves=xx1;x.xfertime=2932; test.r01
[2009-12-15T06:40:20] 250 End.
[2009-12-15T06:40:20] Source File: Size=15000000, Time=2009-12-09T06:57:03
[2009-12-15T06:40:20] Destination File: Size=4882358, Time=2009-12-09T06:57:03
[2009-12-15T06:40:20] RecentTime=2009-12-14T17:38:47
[2009-12-15T06:40:20] Rule "IF Destination Time=Equal AND Size=Smaller AND Transfer=No Matter THEN Resume" matched. Action="Resume".
[2009-12-15T06:40:20] PROT P
[2009-12-15T06:40:21] 200 Command okay
[2009-12-15T06:40:21] PRET RETR test.r01
[2009-12-15T06:40:21] 200 OK, will use xx1 for upcoming transfer
[2009-12-15T06:40:21] PASV
[2009-12-15T06:40:21] 227 Entering Passive Mode (xxx,121,3).
[2009-12-15T06:40:21] Opening data connection to xxx Port: 30979
[2009-12-15T06:40:21] REST 4882358
[2009-12-15T06:40:22] Connected. Exchanging encryption keys...
[2009-12-15T06:40:22] 350 Requested file action pending further information.
[2009-12-15T06:40:22] RETR test.r01
[2009-12-15T06:40:22] 150 File status okay; about to open data connection from xx1.
[2009-12-15T06:40:24] Key Exchange: 1024 bit RSA
[2009-12-15T06:40:24] Session Cipher: 128 bit AES
[2009-12-15T06:40:24] TLS encrypted session established.
[2009-12-15T06:56:21] 10117642 bytes transferred. (10.3 KB/s) (00:15:58)
[2009-12-15T06:57:06] Timeout (45s).
[2009-12-15T06:57:06] Active Help: http://www.smartftp.com/support/kb/74
[2009-12-15T06:57:06] Client closed the connection.
[2009-12-15T06:57:06] Transfer failed.
[2009-12-15T06:57:06] Operation End
[2009-12-15T06:57:06] Log closed
[2009-12-15T06:57:06] Log opened
[2009-12-15T06:57:06] Initializing. Transfer Queue v4.0.1072.0.
[2009-12-15T06:57:06] Operation Begin
[2009-12-15T06:57:06] Resolving host name "xxx"
[2009-12-15T06:57:06] Connecting to xxx Port: 8080
[2009-12-15T06:57:10] Connected to xxx.
[2009-12-15T06:57:10] 220 ?
[2009-12-15T06:57:10] AUTH TLS
[2009-12-15T06:57:11] 234 AUTH TLS successful
[2009-12-15T06:57:11] Connected. Exchanging encryption keys...
[2009-12-15T06:57:13] Key Exchange: 1024 bit RSA
[2009-12-15T06:57:13] Session Cipher: 128 bit AES
[2009-12-15T06:57:13] TLS encrypted session established.
[2009-12-15T06:57:13] Command channel protection set to Private.
[2009-12-15T06:57:13] PBSZ 0
[2009-12-15T06:57:13] 200 Command okay
[2009-12-15T06:57:13] USER xxx
[2009-12-15T06:57:14] 331 Password required for xxx.
[2009-12-15T06:57:14] PASS (hidden)
[2009-12-15T06:57:15] 230 xxx logged in successfully.
[2009-12-15T06:57:15] SYST
[2009-12-15T06:57:15] 215 UNIX system type.
[2009-12-15T06:57:15] Detected Server Type: UNIX
[2009-12-15T06:57:15] RTT: 547.691 ms
[2009-12-15T06:57:15] FEAT
[2009-12-15T06:57:16] 211-Extensions supported:
[2009-12-15T06:57:16] PRET
[2009-12-15T06:57:16] AUTH SSL
[2009-12-15T06:57:16] PBSZ
[2009-12-15T06:57:16] CPSV
[2009-12-15T06:57:16] SSCN
[2009-12-15T06:57:16] CLNT
[2009-12-15T06:57:16] NOOP
[2009-12-15T06:57:16] MLST type*,x.crc32*,size*,modify*,unix.owner*,unix.group*,x.slaves*,x.xfertime*
[2009-12-15T06:57:16] 211 End
[2009-12-15T06:57:16] CLNT SmartFTP 4.0.1072.0
[2009-12-15T06:57:16] 200 Command okay
[2009-12-15T06:57:16] Detected Server Software: DrFTPD
[2009-12-15T06:57:16] TYPE I
[2009-12-15T06:57:17] 200 Command okay
[2009-12-15T06:57:17] REST 0
[2009-12-15T06:57:18] 350 Requested file action pending further information.
[2009-12-15T06:57:18] PWD
[2009-12-15T06:57:18] 257 "/" is current directory
[2009-12-15T06:57:18] CWD /xxx/CD1
[2009-12-15T06:57:19] 250 Directory changed to /xxx/CD1
[2009-12-15T06:57:19] PWD
[2009-12-15T06:57:19] 257 "/xxx/CD1" is current directory
[2009-12-15T06:57:19] MLST test.r01
[2009-12-15T06:57:20] 250- Begin
[2009-12-15T06:57:20] type=file;x.crc32=12137727;size=15000000;modify=20091209065703.255;unix.owner=xxx;unix.group=xxx;x.slaves=xx1;x.xfertime=2932; test.r01
[2009-12-15T06:57:20] 250 End.
[2009-12-15T06:57:20] Using CRC32 hash from test.sfv: 12137727
[2009-12-15T06:57:20] Calculating local file hash "test.r01" Length=15000000.
[2009-12-15T06:57:20] Calculated CRC32 hash of test.r01: 12137727
[2009-12-15T06:57:20] Skipping file "test.r01". Reason: User action.
[2009-12-15T06:57:20] Operation End
[2009-12-15T06:57:20] QUIT
[2009-12-15T06:57:21] 221 Goodbye!
[2009-12-15T06:57:21] 421 Connection closing
[2009-12-15T06:57:21] Log closed

+- System -----------------------------
Microsoft Windows Vista Home Premium Edition
Service Pack 2 (Build 6002)

CPU Speed : 2394 MHz
Total Memory : 2047 MB
Free Memory : 1092 MB

+- SmartFTP ---------------------------
Version : 4.0.1072.0
Time Stamp : 2009-11-20 20:41:14
Platform : x86
Id : 400106663
Maintenance : 2010-05-01
Days in use : 171

+- Language ---------------------------
en-US

+- Internet Explorer ------------------
Version : 8.0.6001.18865

+- Winsock ----------------------------
Winsock : 2.2

Updating status to: Working as Intended

SmartFTP's behavior is correct. The transfer ends and the client waits for the server reply message. Since the server message never comes (e.g. control connection got closed by a buggy router) the client times out and the post transfer check is done right after the timeout.





0 user(s) are reading this issue

0 members, 0 guests, 0 anonymous users