Progressbar in taskbar shows wrong status when transfer failed

Hi,

today I uploaded a 10GB file. The progressbar in FlashFXP's taskbar icon was working fine, until a transfer error occured, which caused FXP to restart (resume) the upload:



Log:

FlashFXP 4.1.0 (build 1617) [RC 1]
Support Forums http://forum.flashfxp.com
 
WinSock 2.0 -- OpenSSL 1.0.0d 8 Feb 2011
[R] Connecting to XXX_REMOVED_XXX -> DNS=XXX_REMOVED_XXX IP=10.1.0.20 PORT=21
[R] Connected to XXX_REMOVED_XXX
[R] 220 Welcome to the OpenXXX_REMOVED_XXX FTP service.
[R] USER root
[R] 331 Please specify the password.
[R] PASS (hidden)
[R] 230 Login successful.
[R] SYST
[R] 215 UNIX Type: L8
[R] FEAT
[R] 211-Features:
[R]  EPRT
[R]  EPSV
[R]  MDTM
[R]  PASV
[R]  REST STREAM
[R]  SIZE
[R]  TVFS
[R] 211 End
[R] PWD
[R] 257 "/"
[R] TYPE A
[R] 200 Switching to ASCII mode.
[R] PASV
[R] 227 Entering Passive Mode (10,1,0,20,156,137)
[R] Opening data connection IP: 10.1.0.20 PORT: 40073
[R] LIST -al
[R] 150 Here comes the directory listing.
[R] 226 Directory send OK.
[R] List Complete: 1 KB in 0,03 seconds (1,2 KB/s)
[R] Calculating timezone offset of server...
[R] MDTM .python-history
[R] 213 20110707205451
[R] Timezone offsets: Server: -51 seconds. Local: 7200 seconds. Difference: 7251 seconds.
[R] CWD XXX_REMOVED_XXX
[R] 250 Directory successfully changed.
[R] PWD
[R] 257 "/XXX_REMOVED_XXX/XXX_REMOVED_XXX"
[R] PASV
[R] 227 Entering Passive Mode (10,1,0,20,167,19)
[R] Opening data connection IP: 10.1.0.20 PORT: 42771
[R] LIST -al
[R] 150 Here comes the directory listing.
[R] 226 Directory send OK.
[R] List Complete: 1 KB in 0,10 seconds (1,1 KB/s)
[R] CWD XXX_REMOVED_XXX
[R] 250 Directory successfully changed.
[R] PWD
[R] 257 "/XXX_REMOVED_XXX/XXX_REMOVED_XXX/XXX_REMOVED_XXX"
[R] PASV
[R] 227 Entering Passive Mode (10,1,0,20,123,95)
[R] Opening data connection IP: 10.1.0.20 PORT: 31583
[R] LIST -al
[R] 150 Here comes the directory listing.
[R] 226 Directory send OK.
[R] List Complete: 1 KB in 0,14 seconds (1,9 KB/s)
[R] TYPE I
[R] 200 Switching to Binary mode.
[R] SIZE XXX_REMOVED_XXX.mkv
[R] 550 Could not get file size.
[R] PASV
[R] 227 Entering Passive Mode (10,1,0,20,223,189)
[R] Opening data connection IP: 10.1.0.20 PORT: 57277
[R] STOR XXX_REMOVED_XXX.mkv
[R] 150 Ok to send data.
Transfer Timed Out
[R] 226 File receive OK.
Transferred: XXX_REMOVED_XXX.mkv 4,16 GB in 7 minutes 18 seconds (9,71 MB/s)
[R] Transfer Failed!
[R] SIZE XXX_REMOVED_XXX.mkv
[R] 213 4471128064
[R] MDTM XXX_REMOVED_XXX.mkv
[R] 213 20110803153050
[R] PASV
[R] 227 Entering Passive Mode (10,1,0,20,168,12)
[R] Opening data connection IP: 10.1.0.20 PORT: 43020
[R] REST 4471128064
[R] 350 Restart position accepted (4471128064).
[R] STOR XXX_REMOVED_XXX.mkv
[R] 150 Ok to send data.
[R] 226 File receive OK.
[R] MDTM 20110714094952 XXX_REMOVED_XXX.mkv
[R] 213 File modification time set.
Resumed: XXX_REMOVED_XXX.mkv 6,40 GB in 9 minutes 43 seconds (11,22 MB/s)
[R] TYPE A
[R] 200 Switching to ASCII mode.
[R] PASV
[R] 227 Entering Passive Mode (10,1,0,20,201,147)
[R] Opening data connection IP: 10.1.0.20 PORT: 51603
[R] LIST -al
[R] 150 Here comes the directory listing.
[R] 226 Directory send OK.
[R] List Complete: 1 KB in 0,02 seconds (2,0 KB/s)
Transfer queue completed
Transferred 2 Files (10,56 GB) in 17 minutes 3 seconds (10,57 MB/s)
[R] NOOP
[R] 200 NOOP ok.

System: Windows 7 SP1, x64.

so you're saying the taskbar icon transfer progress didn't "resume"? it looks like it went to complete status. I also see an error in the FlashFXP log that states two files transferred, when it looks like 1 file was actually transferred, but just resumed.

The progressbar in the taskbar icon "jumped" to 99-100% when the transfer error occurred and FXP resumed.

I see from the screenshot the issue you're reporting, though its very difficult to determine exactly how the progress became skewed.

The queue keeps a running total of the amount transferred vs how much is remaining to calculate the progress displayed in the task-bar.

I suspect that somewhere one of the values was either updated or neglected to update. Until I can get a solid test case where I can reproduce this issue its going to be quite difficult to fix.

I spent the last hour trying to duplicate your results but I have been unsuccessful.

I am also unable to reproduce the issue.

I tried to kill the connection on client and server side, I killed the ftpd while the transfer was active, but the progressbar kept the correct status on resume.

Linkster mentioned the log indicates, that two files were involved... are you agree with him? Maybe this can help you to reproduce the issue: I started FXP, connected to an FTP an uploaded just one file. The transfer stopped and FXP automatically resumed...

But anyway: As long as this isn't reproducible, you don't need to spent much time on it.

Only one file was involved. The file he thought was involved is when FlashFXP did a time adjustment check on one of the files in the current folder.

I do over 10gb of test transfers a day over my LAN, I'll keep an eye on the taskbar progress to make sure its where it should be while testing.

Originally Posted by Whissi

I am also unable to reproduce the issue.

I tried to kill the connection on client and server side, I killed the ftpd while the transfer was active, but the progressbar kept the correct status on resume.

Linkster mentioned the log indicates, that two files were involved... are you agree with him? Maybe this can help you to reproduce the issue: I started FXP, connected to an FTP an uploaded just one file. The transfer stopped and FXP automatically resumed...

But anyway: As long as this isn't reproducible, you don't need to spent much time on it.

sorry to join your ticket, but i thought the problem should happen on a transfer timeout, but not killing the ftp daemon which will cause the system sending RST package back to client side, and also cause the control channel disconnected (where the staff could never make a mistake).