Was fine...now some files must retry multiple times.

thomas_freeman

2009-12-18 23:00:22

I have been using SmartFTP for quite a while. It worked fine for a long time but then recently (after not needing to transfer files for a while) I found that files were needing to retry many times before "poof" they magically were transferred. I can't figure out any rhyme or reason to this. No clear pattern.

I have bitdefender and I recently create a rule giivng full access to SmartFTP (and I moved the rule to the top of the list). Didn't seem to help. I have disabled the firewall, etc. Still no change.

I also have netnanny installed. However, i can't find any setting that might relate to FTP.

Here is a copy of one of the retry logs:
[16:32:28] Initializing. Transfer Queue v4.0.1072.0.
[16:32:28] Operation Begin
[16:32:28] Resolving host name "www.****.info"
[16:32:28] Connecting to ***.***.***.*** Port: 21
[16:32:28] Connected to www.****.info.
[16:32:28] 220---------- Welcome to Pure-FTPd [TLS] ----------
[16:32:28] 220-You are user number 11 of 50 allowed.
[16:32:28] 220-Local time is now 22:32. Server port: 21.
[16:32:28] 220 You will be disconnected after 15 minutes of inactivity.
[16:32:28] USER ****
[16:32:28] 331 User **** OK. Password required
[16:32:28] PASS (hidden)
[16:32:28] 230-User **** has group access to: ****
[16:32:28] 230 OK. Current restricted directory is /
[16:32:28] SYST
[16:32:28] 215 UNIX Type: L8
[16:32:28] Detected Server Type: UNIX
[16:32:28] RTT: 66.405 ms
[16:32:28] FEAT
[16:32:28] 211-Extensions supported:
[16:32:28] EPRT
[16:32:28] IDLE
[16:32:28] MDTM
[16:32:28] SIZE
[16:32:28] REST STREAM
[16:32:28] MLST type*;size*;sizd*;modify*;UNIX.mode*;UNIX.uid*;UNIX.gid*;unique*;
[16:32:28] MLSD
[16:32:28] ESTP
[16:32:28] PASV
[16:32:28] EPSV
[16:32:28] SPSV
[16:32:28] ESTA
[16:32:28] AUTH TLS
[16:32:28] PBSZ
[16:32:28] PROT
[16:32:28] 211 End.
[16:32:28] Detected Server Software: PureFTPd
[16:32:28] PWD
[16:32:28] 257 "/" is your current location
[16:32:28] CWD /public_html/articles
[16:32:28] 250 OK. Current directory is /public_html/articles
[16:32:28] PWD
[16:32:28] 257 "/public_html/articles" is your current location
[16:32:28] Remote file exist check: "Budget2009.xls".
[16:32:28] MLST Budget2009.xls
[16:32:29] 550 Can't check for file existence
[16:32:29] TYPE I
[16:32:29] 200 TYPE is now 8-bit binary
[16:32:29] PORT 192,168,1,239,5,44
[16:32:29] 200 PORT command successful
[16:32:29] STOR Budget2009.xls
[16:32:29] 150 Connecting to port 1324
[16:32:29] Socket send buffer set to 16384 bytes.
[16:32:29] 34816 bytes transferred. (83.7 KB/s) (406 ms)
[16:32:29] Transfer failed.
[16:32:29] Operation End
[16:32:29] Initializing. Transfer Queue v4.0.1072.0.
[16:32:29] Operation Begin
[16:32:29] Resolving host name "www.****.info"
[16:32:29] Connecting to ***.***.***.*** Port: 21
[16:32:30] Connected to www.****.info.
[16:32:30] 220---------- Welcome to Pure-FTPd [TLS] ----------
[16:32:30] 220-You are user number 11 of 50 allowed.
[16:32:30] 220-Local time is now 22:32. Server port: 21.
[16:32:30] 220 You will be disconnected after 15 minutes of inactivity.
[16:32:30] USER ****
[16:32:30] 331 User **** OK. Password required
[16:32:30] PASS (hidden)
[16:32:30] 230-User **** has group access to: ****
[16:32:30] 230 OK. Current restricted directory is /
[16:32:30] SYST
[16:32:30] 215 UNIX Type: L8
[16:32:30] Detected Server Type: UNIX
[16:32:30] RTT: 64.551 ms
[16:32:30] FEAT
[16:32:30] 211-Extensions supported:
[16:32:30] EPRT
[16:32:30] IDLE
[16:32:30] MDTM
[16:32:30] SIZE
[16:32:30] REST STREAM
[16:32:30] MLST type*;size*;sizd*;modify*;UNIX.mode*;UNIX.uid*;UNIX.gid*;unique*;
[16:32:30] MLSD
[16:32:30] ESTP
[16:32:30] PASV
[16:32:30] EPSV
[16:32:30] SPSV
[16:32:30] ESTA
[16:32:30] AUTH TLS
[16:32:30] PBSZ
[16:32:30] PROT
[16:32:30] 211 End.
[16:32:30] Detected Server Software: PureFTPd
[16:32:30] PWD
[16:32:30] 257 "/" is your current location
[16:32:30] CWD /public_html/articles
[16:32:30] 250 OK. Current directory is /public_html/articles
[16:32:30] PWD
[16:32:30] 257 "/public_html/articles" is your current location
[16:32:30] Remote file exist check: "Budget2009.xls".
[16:32:30] MLST Budget2009.xls
[16:32:30] 250-Begin
[16:32:30] type=file;size=28672;modify=20091218223229;UNIX.mode=0644;UNIX.uid=1400;UNIX.gid=1400;unique=904g1fe84a0; Budget2009.xls
[16:32:30] 250 End.
[16:32:30] Source File: Size=34816, Time=2009-11-04T19:43:30
[16:32:30] Destination File: Size=28672, Time=2009-12-18T22:32:29
[16:32:30] RecentTime=2009-12-18T22:32:29
[16:32:30] Rule "IF Destination Time=Recent AND Size=Smaller AND Transfer=No Matter THEN Resume" matched. Action="Resume".
[16:32:30] TYPE I
[16:32:30] 200 TYPE is now 8-bit binary
[16:32:30] PORT 192,168,1,239,5,50
[16:32:30] 200 PORT command successful
[16:32:30] REST 28672
[16:32:31] 350 Restarting at 28672
[16:32:31] STOR Budget2009.xls
[16:32:31] 150 Connecting to port 1330
[16:32:31] 6144 bytes transferred. (400 KB/s) (15 ms)
[16:32:31] Transfer failed.
[16:32:31] Operation End
[16:33:02] Initializing. Transfer Queue v4.0.1072.0.
[16:33:02] Operation Begin
[16:33:02] Resolving host name "www.****.info"
[16:33:02] Connecting to ***.***.***.*** Port: 21
[16:33:02] Connected to www.****.info.
[16:33:02] 220---------- Welcome to Pure-FTPd [TLS] ----------
[16:33:02] 220-You are user number 11 of 50 allowed.
[16:33:02] 220-Local time is now 22:33. Server port: 21.
[16:33:02] 220 You will be disconnected after 15 minutes of inactivity.
[16:33:02] USER ****
[16:33:02] 331 User **** OK. Password required
[16:33:02] PASS (hidden)
[16:33:02] 230-User **** has group access to: ****
[16:33:02] 230 OK. Current restricted directory is /
[16:33:02] SYST
[16:33:02] 215 UNIX Type: L8
[16:33:02] Detected Server Type: UNIX
[16:33:02] RTT: 66.910 ms
[16:33:02] FEAT
[16:33:02] 211-Extensions supported:
[16:33:02] EPRT
[16:33:02] IDLE
[16:33:02] MDTM
[16:33:02] SIZE
[16:33:02] REST STREAM
[16:33:02] MLST type*;size*;sizd*;modify*;UNIX.mode*;UNIX.uid*;UNIX.gid*;unique*;
[16:33:02] MLSD
[16:33:02] ESTP
[16:33:02] PASV
[16:33:02] EPSV
[16:33:02] SPSV
[16:33:02] ESTA
[16:33:02] AUTH TLS
[16:33:02] PBSZ
[16:33:02] PROT
[16:33:02] 211 End.
[16:33:02] Detected Server Software: PureFTPd
[16:33:02] PWD
[16:33:02] 257 "/" is your current location
[16:33:02] CWD /public_html/articles
[16:33:02] 250 OK. Current directory is /public_html/articles
[16:33:02] PWD
[16:33:02] 257 "/public_html/articles" is your current location
[16:33:02] Remote file exist check: "Budget2009.xls".
[16:33:02] MLST Budget2009.xls
[16:33:02] 250-Begin
[16:33:02] type=file;size=30120;modify=20091218223230;UNIX.mode=0644;UNIX.uid=1400;UNIX.gid=1400;unique=904g1fe84a0; Budget2009.xls
[16:33:02] 250 End.
[16:33:02] Source File: Size=34816, Time=2009-11-04T19:43:30
[16:33:02] Destination File: Size=30120, Time=2009-12-18T22:32:30
[16:33:02] RecentTime=2009-12-18T22:32:31
[16:33:02] Rule "IF Destination Time=Recent AND Size=Smaller AND Transfer=No Matter THEN Resume" matched. Action="Resume".
[16:33:02] TYPE I
[16:33:03] 200 TYPE is now 8-bit binary
[16:33:03] PORT 192,168,1,239,5,59
[16:33:03] 200 PORT command successful
[16:33:03] REST 30120
[16:33:03] 350 Restarting at 30120
[16:33:03] STOR Budget2009.xls
[16:33:03] 150 Connecting to port 1339
[16:33:03] 4696 bytes transferred. (99.6 KB/s) (46 ms)
[16:33:03] Transfer failed.
[16:33:03] Operation End
[16:33:26] Initializing. Transfer Queue v4.0.1072.0.
[16:33:26] Operation Begin
[16:33:26] Resolving host name "www.****.info"
[16:33:26] Connecting to ***.***.***.*** Port: 21
[16:33:26] Connected to www.****.info.
[16:33:26] 220---------- Welcome to Pure-FTPd [TLS] ----------
[16:33:26] 220-You are user number 12 of 50 allowed.
[16:33:26] 220-Local time is now 22:33. Server port: 21.
[16:33:26] 220 You will be disconnected after 15 minutes of inactivity.
[16:33:26] USER ****
[16:33:26] 331 User **** OK. Password required
[16:33:26] PASS (hidden)
[16:33:26] 230-User **** has group access to: ****
[16:33:26] 230 OK. Current restricted directory is /
[16:33:26] SYST
[16:33:26] 215 UNIX Type: L8
[16:33:26] Detected Server Type: UNIX
[16:33:26] RTT: 75.327 ms
[16:33:26] FEAT
[16:33:26] 211-Extensions supported:
[16:33:26] EPRT
[16:33:26] IDLE
[16:33:26] MDTM
[16:33:26] SIZE
[16:33:26] REST STREAM
[16:33:26] MLST type*;size*;sizd*;modify*;UNIX.mode*;UNIX.uid*;UNIX.gid*;unique*;
[16:33:26] MLSD
[16:33:26] ESTP
[16:33:26] PASV
[16:33:26] EPSV
[16:33:26] SPSV
[16:33:26] ESTA
[16:33:26] AUTH TLS
[16:33:26] PBSZ
[16:33:26] PROT
[16:33:26] 211 End.
[16:33:26] Detected Server Software: PureFTPd
[16:33:26] PWD
[16:33:26] 257 "/" is your current location
[16:33:26] CWD /public_html/articles
[16:33:26] 250 OK. Current directory is /public_html/articles
[16:33:26] PWD
[16:33:26] 257 "/public_html/articles" is your current location
[16:33:26] Remote file exist check: "Budget2009.xls".
[16:33:26] MLST Budget2009.xls
[16:33:26] 250-Begin
[16:33:26] type=file;size=31568;modify=20091218223302;UNIX.mode=0644;UNIX.uid=1400;UNIX.gid=1400;unique=904g1fe84a0; Budget2009.xls
[16:33:26] 250 End.
[16:33:26] Source File: Size=34816, Time=2009-11-04T19:43:30
[16:33:26] Destination File: Size=31568, Time=2009-12-18T22:33:02
[16:33:26] RecentTime=2009-12-18T22:33:03
[16:33:26] Rule "IF Destination Time=Recent AND Size=Smaller AND Transfer=No Matter THEN Resume" matched. Action="Resume".
[16:33:26] TYPE I
[16:33:27] 200 TYPE is now 8-bit binary
[16:33:27] PASV
[16:33:27] 227 Entering Passive Mode (209,25,133,93,128,9)
[16:33:27] Opening data connection to ***.***.***.*** Port: 32777
[16:33:27] REST 31568
[16:33:27] 350 Restarting at 31568
[16:33:27] STOR Budget2009.xls
[16:33:27] 150 Accepted data connection
[16:33:27] 3248 bytes transferred. (40.6 KB/s) (78 ms)
[16:33:27] Transfer failed.
[16:33:27] Operation End
[16:33:57] Initializing. Transfer Queue v4.0.1072.0.
[16:33:57] Operation Begin
[16:33:57] Resolving host name "www.****.info"
[16:33:57] Connecting to ***** Port: 21
[16:33:57] Connected to www.*****.***** .
[16:33:57] 220---------- Welcome to Pure-FTPd [TLS] ----------
[16:33:57] 220-You are user number 13 of 50 allowed.
[16:33:57] 220-Local time is now 22:33. Server port: 21.
[16:33:57] 220 You will be disconnected after 15 minutes of inactivity.
[16:33:57] USER ****
[16:33:57] 331 User ***** OK. Password required
[16:33:57] PASS (hidden)
[16:33:57] 230-User **** has group access to: ****
[16:33:57] 230 OK. Current restricted directory is /
[16:33:57] SYST
[16:33:57] 215 UNIX Type: L8
[16:33:57] Detected Server Type: UNIX
[16:33:57] RTT: 78.210 ms
[16:33:57] FEAT
[16:33:58] 211-Extensions supported:
[16:33:58] EPRT
[16:33:58] IDLE
[16:33:58] MDTM
[16:33:58] SIZE
[16:33:58] REST STREAM
[16:33:58] MLST type*;size*;sizd*;modify*;UNIX.mode*;UNIX.uid*;UNIX.gid*;unique*;
[16:33:58] MLSD
[16:33:58] ESTP
[16:33:58] PASV
[16:33:58] EPSV
[16:33:58] SPSV
[16:33:58] ESTA
[16:33:58] AUTH TLS
[16:33:58] PBSZ
[16:33:58] PROT
[16:33:58] 211 End.
[16:33:58] Detected Server Software: PureFTPd
[16:33:58] PWD
[16:33:58] 257 "/" is your current location
[16:33:58] CWD /public_html/articles
[16:33:58] 250 OK. Current directory is /public_html/articles
[16:33:58] PWD
[16:33:58] 257 "/public_html/articles" is your current location
[16:33:58] Remote file exist check: "Budget2009.xls".
[16:33:58] MLST Budget2009.xls
[16:33:58] 250-Begin
[16:33:58] type=file;size=33028;modify=20091218223326;UNIX.mode=0644;UNIX.uid=1400;UNIX.gid=1400;unique=904g1fe84a0; Budget2009.xls
[16:33:58] 250 End.
[16:33:58] Source File: Size=34816, Time=2009-11-04T19:43:30
[16:33:58] Destination File: Size=33028, Time=2009-12-18T22:33:26
[16:33:58] RecentTime=2009-12-18T22:33:27
[16:33:58] Rule "IF Destination Time=Recent AND Size=Smaller AND Transfer=No Matter THEN Resume" matched. Action="Resume".
[16:33:58] TYPE I
[16:33:58] 200 TYPE is now 8-bit binary
[16:33:58] PASV
[16:33:58] 227 Entering Passive Mode (209,25,133,93,154,32)
[16:33:58] Opening data connection to ***.***.***.*** Port: 39456
[16:33:59] REST 33028
[16:33:59] 350 Restarting at 33028
[16:33:59] STOR Budget2009.xls
[16:33:59] 150 Accepted data connection
[16:33:59] 1788 bytes transferred. (7.46 KB/s) (234 ms)
[16:33:59] Transfer failed.
[16:33:59] Operation End
[16:34:29] Initializing. Transfer Queue v4.0.1072.0.
[16:34:29] Operation Begin
[16:34:29] Remote file exist check: "Budget2009.xls".
[16:34:29] MLST Budget2009.xls
[16:34:29] 250-Begin
[16:34:29] type=file;size=34488;modify=20091218223358;UNIX.mode=0644;UNIX.uid=1400;UNIX.gid=1400;unique=904g1fe84a0; Budget2009.xls
[16:34:29] 250 End.
[16:34:29] Source File: Size=34816, Time=2009-11-04T19:43:30
[16:34:29] Destination File: Size=34488, Time=2009-12-18T22:33:58
[16:34:29] RecentTime=2009-12-18T22:33:59
[16:34:29] Rule "IF Destination Time=Recent AND Size=Smaller AND Transfer=No Matter THEN Resume" matched. Action="Resume".
[16:34:29] TYPE I
[16:34:29] 200 TYPE is now 8-bit binary
[16:34:29] PASV
[16:34:29] 227 Entering Passive Mode (209,25,133,93,156,111)
[16:34:29] Opening data connection to ***.***.***.*** Port: 40047
[16:34:29] REST 34488
[16:34:29] 350 Restarting at 34488
[16:34:29] STOR Budget2009.xls
[16:34:29] 150 Accepted data connection
[16:34:30] 328 bytes transferred. (750 bytes/s) (437 ms)
[16:34:30] Server closed connection
[16:34:30] Transfer failed.
[16:34:30] Operation End

mb

2009-12-18 23:02:00

thomas_freeman

2009-12-19 17:55:18

Thanks. I did try it.

thomas_freeman

2009-12-24 22:26:28

Turns out this is a known problem with Netnanny. They are working on a fix for to be released in a few weeks. In the meantime we are uninstalling it.