Problems downloading - Files complete but don't go to next in queue

I am having trouble with downloading multiple files. It seems at first to be working, but when it reaches (for example) 779kb/779kb, SmartFTP gets stuck there and the average speed just keeps getting lower. It does not move on to the next file in the queue.

If I exit the program and re-enter, it will pick up where it left off, remove the already-completed file from the queue, and move on to the next file. Most of the time, however, it again stops when this file gets to 100%.

When I double-click on the file, the status says that it was downloaded successfully, which it was. However, I still can't get the queue going again without quitting and restarting.

I would appreciate any advice.

Thanks!

Please post the system log from the menu: Help->About "System Information" dialog.

Regards,
Mat

+- System -----------------------------
Microsoft Windows XP Professional
Service Pack 3 (Build 2600)

CPU Speed : 2793 MHz
Total Memory : 2047 MB
Free Memory : 1078 MB

+- SmartFTP ---------------------------
Version : 4.0.1054.0
Time Stamp : 2009-10-06 19:54:07
Platform : x86
Unlicensed
Days in use : 14

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

+- Internet Explorer ------------------
Version : 8.0.6001.18702

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

Please install the latest version from here:
https://www.smartftp.com/download

I think SmartFTP does not receive the ok reply from the server and therefore waits for it. After 30 seconds you should get a timeout and SmartFTP will retry the transfer and the transfer will complete. You can post the log from the transfer queue item and we can confirm this.

Regards,
Mat

I was pretty sure I had the latest version already, but I downloaded it again and reinstalled.

Same problem.

The download log on the first file of of 6 I tried to download is:

[23:02:54] Operation Begin
[23:02:54] Resolving host name "ftp.cpfd-support.com"
[23:02:54] Connecting to 69.89.27.250 Port: 21
[23:02:54] Connected to ftp.cpfd-support.com.
[23:02:54] 220---------- Welcome to Pure-FTPd [TLS] ----------
[23:02:54] 220-You are user number 11 of 1000 allowed.
[23:02:54] 220-Local time is now 21:03. Server port: 21.
[23:02:54] 220-IPv6 connections are also welcome on this server.
[23:02:54] 220 You will be disconnected after 15 minutes of inactivity.
[23:02:54] USER scott@cpfd-support.com
[23:02:55] 331 User scott@cpfd-support.com OK. Password required
[23:02:55] PASS (hidden)
[23:02:55] 230-User scott@cpfd-support.com has group access to: cpfdsupp
[23:02:55] 230 OK. Current restricted directory is /
[23:02:55] SYST
[23:02:55] 215 UNIX Type: L8
[23:02:55] Detected Server Type: UNIX
[23:02:55] RTT: 108.117 ms
[23:02:55] FEAT
[23:02:55] 211-Extensions supported:
[23:02:55] EPRT
[23:02:55] IDLE
[23:02:55] MDTM
[23:02:55] SIZE
[23:02:55] REST STREAM
[23:02:55] MLST type*;size*;sizd*;modify*;UNIX.mode*;UNIX.uid*;UNIX.gid*;unique*;
[23:02:55] MLSD
[23:02:55] ESTP
[23:02:55] PASV
[23:02:55] EPSV
[23:02:55] SPSV
[23:02:55] ESTA
[23:02:55] AUTH TLS
[23:02:55] PBSZ
[23:02:55] PROT
[23:02:55] 211 End.
[23:02:55] Detected Server Software: PureFTPd
[23:02:55] PWD
[23:02:55] 257 "/" is your current location
[23:02:55] CWD /2009-10-26_movies_for_scott/01-regenerator_demo
[23:02:55] 250 OK. Current directory is /2009-10-26_movies_for_scott/01-regenerator_demo
[23:02:55] PWD
[23:02:55] 257 "/2009-10-26_movies_for_scott/01-regenerator_demo" is your current location
[23:02:55] MLST windows_seq_06.avi
[23:02:55] 250-Begin
[23:02:55] type=file;size=806524;modify=20091026231245;UNIX.mode=0766;UNIX.uid=1176;UNIX.gid=1171;unique=821g1159129; windows_seq_06.avi
[23:02:55] 250 End.
[23:02:55] TYPE I
[23:02:55] 200 TYPE is now 8-bit binary
[23:02:55] PASV
[23:02:56] 227 Entering Passive Mode (69,89,27,250,26,122)
[23:02:56] Opening data connection to 69.89.27.250 Port: 6778
[23:02:56] RETR windows_seq_06.avi
[23:02:56] 150-Accepted data connection
[23:02:56] 150 787.6 kbytes to download
[23:03:00] Socket receive buffer set to 262144 bytes.
[23:03:00] 226-File successfully transferred
[23:03:00] 226 3.759 seconds (measured here), 209.52 Kbytes per second
[23:03:00] 806524 bytes transferred. (203 KB/s) (00:00:03)
[23:03:00] MLST windows_seq_06.avi

Hm. Where is the error?

That's the log. I never said it listed an error, I only said that it stops when the file is 100% complete and does not move on to the next one in the queue as it should. I just sits at 988K/988K 100% while the average speed slowly decreases. I'll find the file really was transferred. If I quit the program and restart it, it will crash about 50% of the time and the other 50% of the time it will update the queue, removing the completed file(s), and move on to the next in the queue. When THAT file gets to 100%, it does the same thing all over again, not moving to the next file as it is supposed to do.

It is refusing to crash now, wouldn't you know, though when I exit it after it gets stuck, then re-start it, the transfer queue is empty. It was not empty before - SmartFTP tried to restart the queue then crashed about 50% of the time. Now the queue is empty but no crash.

Keep in mind that, though crashes are annoying, it is the program getting stuck on a completed file and not going to the next that is the real issue. The crashes are more likely to have been caused by me having to repeatedly close and re-open the program and kill all the extra SmartFTP processes piling up in Task Manager.

Post the log from the transfer. To get the log double click the item in the transfer queue.

[19:42:56] Operation Begin
[19:42:56] Resolving host name "ftp.cpfd-support.com"
[19:42:56] Connecting to 69.89.27.250 Port: 21
[19:42:56] Connected to ftp.cpfd-support.com.
[19:42:56] 220---------- Welcome to Pure-FTPd [TLS] ----------
[19:42:56] 220-You are user number 4 of 1000 allowed.
[19:42:56] 220-Local time is now 17:43. Server port: 21.
[19:42:56] 220-IPv6 connections are also welcome on this server.
[19:42:56] 220 You will be disconnected after 15 minutes of inactivity.
[19:42:56] USER scott@cpfd-support.com
[19:42:56] 331 User scott@cpfd-support.com OK. Password required
[19:42:56] PASS (hidden)
[19:42:57] 230-User scott@cpfd-support.com has group access to: cpfdsupp
[19:42:57] 230 OK. Current restricted directory is /
[19:42:57] SYST
[19:42:57] 215 UNIX Type: L8
[19:42:57] Detected Server Type: UNIX
[19:42:57] RTT: 110.392 ms
[19:42:57] FEAT
[19:42:57] 211-Extensions supported:
[19:42:57] EPRT
[19:42:57] IDLE
[19:42:57] MDTM
[19:42:57] SIZE
[19:42:57] REST STREAM
[19:42:57] MLST type*;size*;sizd*;modify*;UNIX.mode*;UNIX.uid*;UNIX.gid*;unique*;
[19:42:57] MLSD
[19:42:57] ESTP
[19:42:57] PASV
[19:42:57] EPSV
[19:42:57] SPSV
[19:42:57] ESTA
[19:42:57] AUTH TLS
[19:42:57] PBSZ
[19:42:57] PROT
[19:42:57] 211 End.
[19:42:57] Detected Server Software: PureFTPd
[19:42:57] PWD
[19:42:57] 257 "/" is your current location
[19:42:57] CWD /2009-10-26_movies_for_scott/01-regenerator_demo
[19:42:57] 250 OK. Current directory is /2009-10-26_movies_for_scott/01-regenerator_demo
[19:42:57] PWD
[19:42:57] 257 "/2009-10-26_movies_for_scott/01-regenerator_demo" is your current location
[19:42:57] MLST windows_seq_08.avi
[19:42:57] 250-Begin
[19:42:57] type=file;size=795268;modify=20091026231209;UNIX.mode=0766;UNIX.uid=1176;UNIX.gid=1171;unique=821g116a7d3; windows_seq_08.avi
[19:42:57] 250 End.
[19:42:57] Source File: Size=795268, SizeUnit=Byte, Time=2009-10-26T23:12:09, TimeFormat=Exact
[19:42:57] Destination File: Size=0, SizeUnit=Byte, Time=2009-10-26T23:12:09, TimeFormat=Exact
[19:42:57] RecentTime=2009-11-06T00:42:57
[19:42:57] Rule "IF Destination Time=Equal AND Size=Smaller AND Transfer=No Matter THEN Resume" matched. Action="Resume".
[19:42:57] TYPE I
[19:42:57] 200 TYPE is now 8-bit binary
[19:42:57] PASV
[19:42:57] 227 Entering Passive Mode (69,89,27,250,139,157)
[19:42:57] Opening data connection to 69.89.27.250 Port: 35741
[19:42:57] RETR windows_seq_08.avi
[19:42:58] 150-Accepted data connection
[19:42:58] 150 776.6 kbytes to download
[19:43:00] 226-File successfully transferred
[19:43:00] 226 1.964 seconds (measured here), 395.34 Kbytes per second
[19:43:00] 795268 bytes transferred. (365 KB/s) (00:00:02)
[19:43:00] MLST windows_seq_08.avi

Just for good measure, here is another one for a different file, same symptom:

[19:44:39] Operation Begin
[19:44:39] Resolving host name "ftp.cpfd-support.com"
[19:44:39] Connecting to 69.89.27.250 Port: 21
[19:44:39] Connected to ftp.cpfd-support.com.
[19:44:39] 220---------- Welcome to Pure-FTPd [TLS] ----------
[19:44:39] 220-You are user number 6 of 1000 allowed.
[19:44:39] 220-Local time is now 17:45. Server port: 21.
[19:44:39] 220-IPv6 connections are also welcome on this server.
[19:44:39] 220 You will be disconnected after 15 minutes of inactivity.
[19:44:39] USER scott@cpfd-support.com
[19:44:39] 331 User scott@cpfd-support.com OK. Password required
[19:44:39] PASS (hidden)
[19:44:39] 230-User scott@cpfd-support.com has group access to: cpfdsupp
[19:44:39] 230 OK. Current restricted directory is /
[19:44:39] SYST
[19:44:39] 215 UNIX Type: L8
[19:44:39] Detected Server Type: UNIX
[19:44:39] RTT: 121.367 ms
[19:44:39] FEAT
[19:44:39] 211-Extensions supported:
[19:44:39] EPRT
[19:44:39] IDLE
[19:44:39] MDTM
[19:44:39] SIZE
[19:44:39] REST STREAM
[19:44:39] MLST type*;size*;sizd*;modify*;UNIX.mode*;UNIX.uid*;UNIX.gid*;unique*;
[19:44:39] MLSD
[19:44:39] ESTP
[19:44:39] PASV
[19:44:39] EPSV
[19:44:39] SPSV
[19:44:39] ESTA
[19:44:39] AUTH TLS
[19:44:39] PBSZ
[19:44:39] PROT
[19:44:39] 211 End.
[19:44:39] Detected Server Software: PureFTPd
[19:44:39] PWD
[19:44:40] 257 "/" is your current location
[19:44:40] CWD /2009-10-26_movies_for_scott/01-regenerator_demo
[19:44:40] 250 OK. Current directory is /2009-10-26_movies_for_scott/01-regenerator_demo
[19:44:40] PWD
[19:44:40] 257 "/2009-10-26_movies_for_scott/01-regenerator_demo" is your current location
[19:44:40] MLST windows_seq_15.avi
[19:44:40] 250-Begin
[19:44:40] type=file;size=3536336;modify=20091026231719;UNIX.mode=0766;UNIX.uid=1176;UNIX.gid=1171;unique=821g1134481; windows_seq_15.avi
[19:44:40] 250 End.
[19:44:40] TYPE I
[19:44:40] 200 TYPE is now 8-bit binary
[19:44:40] PASV
[19:44:40] 227 Entering Passive Mode (69,89,27,250,127,67)
[19:44:40] Opening data connection to 69.89.27.250 Port: 32579
[19:44:40] RETR windows_seq_15.avi
[19:44:40] 150-Accepted data connection
[19:44:40] 150 3453.5 kbytes to download
[19:44:43] Socket receive buffer set to 262144 bytes.
[19:44:47] 3536336 bytes transferred. (479 KB/s) (00:00:07)

Please try to post the complete log (with the timeout).

From the log you posted it seems like the server does not send the transfer complete reply?

This is the only log I see. It never DOES timeout, which I think is part of the problem. It will sit there for HOURS on the same file showing 100% finished and not move to the next file. The log file is exactly what I already posted. Sometimes it does work and it will send two or three files before once again stopping.

What is your timeout value?

Retry = 30 sec, timeout = 60sec.

Unless you've changed the version in the last few days, I've already installed the latest one. Twice.

"What is the status of the transfer queue item?" - How do I determine this? I have shown the logs above. How do I determine the status of the transfer queue item?

Thanks!

The latest version is build 1065.

The status is displayed in the transfer queue (e.g. waiting for retry, post transfer, etc).