Problems Uploading and Downloading

nilocor

2008-04-14 14:18:12

Hi,

Since installing V3 I have had significant problems uploading/downloading to almost any site. I have downloaded the latest version of the software, deleted the Windows registry entry for the product, made sure I haven't got autodial turned on, and that the Q is started. The file requests just sit in the Q retrying. Interesting if I start and stop the product a few times and wait a few hours it sometimes works. My other computer with V2.5 on it works fine as does the FTP support in Internet Explorer. Here are the logs:

[07:00:08] Resolving host name "192.168.0.7"
[07:00:08] Connecting to 192.168.0.7 Port: 3105
[07:00:08] Connected to 192.168.0.7.
[07:00:08] 220 Mfs_Ftp ver 1.2.9p - {sock16} from "192.168.0.9:3864"
[07:00:08] USER anonymous
[07:00:08] 331 User name okay, need password.
[07:00:08] PASS (hidden)
[07:00:08] 230 Running in TiVo Mode.
[07:00:08] SYST
[07:00:08] 215 UNIX
[07:00:08] Detected Server Type: UNIX
[07:00:08] FEAT
[07:00:08] 502 Command not implemented "FEAT"
[07:00:08] TYPE I
[07:00:09] 200 Type set to I
[07:00:09] REST 0
[07:00:09] 350 Restart okay, awaiting file request.
[07:00:09] PWD
[07:00:09] 257 "/" is current directory.
[07:00:09] CWD /ty
[07:00:09] 250 Directory change successful.
[07:00:09] PWD
[07:00:09] 257 "/ty" is current directory.
[07:00:09] STAT {Masterpiece}{2008-04-13}{A Room With a View}{01.00 AM Mon Apr 14, 2008}{KSYS}.ty
[07:00:09] 502 Command not implemented "STAT {Masterpiece}{2008-04-13}{A Room With a View}{01.00 AM Mon Apr 14, 2008}{KSYS}.ty"
[07:00:09] SIZE {Masterpiece}{2008-04-13}{A Room With a View}{01.00 AM Mon Apr 14, 2008}{KSYS}.ty
[07:00:09] 502 Command not implemented "SIZE {Masterpiece}{2008-04-13}{A Room With a View}{01.00 AM Mon Apr 14, 2008}{KSYS}.ty"
[07:00:09] SIZE not supported.
[07:00:09] MDTM {Masterpiece}{2008-04-13}{A Room With a View}{01.00 AM Mon Apr 14, 2008}{KSYS}.ty
[07:00:09] 502 Command not implemented "MDTM {Masterpiece}{2008-04-13}{A Room With a View}{01.00 AM Mon Apr 14, 2008}{KSYS}.ty"
[07:00:09] Obtaining file information (size/date) from directory listing.
[07:00:09] TYPE A
[07:00:09] 200 Type set to I
[07:00:09] PASV
[07:00:09] File "{Masterpiece}{2008-04-13}{A Room With a View}{01.00 AM Mon Apr 14, 2008}{KSYS}.ty" not found in directory listing.
[07:00:09] Server closed connection

+- System -----------------------------
Microsoft Windows XP Home Edition
Service Pack 2 (Build 2600)

CPU Speed : 2992 MHz
Total Memory : 2047 MB
Free Memory : 2047 MB

+- SmartFTP ---------------------------
Version : 3.0.1012.17
Time Stamp : 2008-04-10 14:29:55
Platform : x86
Unlicensed
Days in use : 365

+- Application DLL --------------------
sfFTPLib.dll : 1.5.12.43
sfFavorites.dll : 1.0.8.17
sfFavoritesShellExtension.dll : 1.0.1.9
sfTransferQueue.dll : 1.0.5.17
sfFTPShellExtension.dll : 1.0.3.17

+- Language ---------------------------
SmartFTP.exe : 3.0.1012.17

+- Internet Explorer ------------------
Version : 7.0.5730.11

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

2008-04-14 18:57:10

The log doesn't look complete to me. Can you post the complete log?
Thank you.
Regards,
Mat

nilocor

2008-04-14 22:43:20

The log doesn't look complete to me. Can you post the complete log?
Thank you.
Regards,
Mat

I did the FTP again and printed both the Transfer Q log and the remote browser logs (see below). Interestingly, when I started up the FTP Client the session that failed this morning started working even though I had not connected to the server. It completed successfully. However, when I added a new job to the Q after connecting to the FTP server the same error occurred (constant retries). *** update *** If I disconnect, terminate the browser, exit SmartFTP and then restart SmartFTP the transfer that constantly retries from the previous session starts working provided I don't try and start a remote browser to connect to the FTP server!

**** Transfer Q Log ****

[15:31:10] Resolving host name "192.168.0.7"
[15:31:10] Connecting to 192.168.0.7 Port: 3105
[15:31:10] Connected to 192.168.0.7.
[15:31:10] 220 Mfs_Ftp ver 1.2.9p - {sock16} from "192.168.0.9:1992"
[15:31:10] USER anonymous
[15:31:10] 331 User name okay, need password.
[15:31:10] PASS (hidden)
[15:31:10] 230 Running in TiVo Mode.
[15:31:10] SYST
[15:31:10] 215 UNIX
[15:31:10] Detected Server Type: UNIX
[15:31:10] FEAT
[15:31:10] 502 Command not implemented "FEAT"
[15:31:10] TYPE I
[15:31:10] 200 Type set to I
[15:31:10] REST 0
[15:31:10] 350 Restart okay, awaiting file request.
[15:31:10] PWD
[15:31:10] 257 "/" is current directory.
[15:31:10] CWD /ty
[15:31:10] 250 Directory change successful.
[15:31:10] PWD
[15:31:10] 257 "/ty" is current directory.
[15:31:10] STAT {Masterpiece}{2008-03-30}{Sense and Sensibility}{09.00 PM Sun Mar 30, 2008}{KSYS}.ty
[15:31:10] 502 Command not implemented "STAT {Masterpiece}{2008-03-30}{Sense and Sensibility}{09.00 PM Sun Mar 30, 2008}{KSYS}.ty"
[15:31:10] SIZE {Masterpiece}{2008-03-30}{Sense and Sensibility}{09.00 PM Sun Mar 30, 2008}{KSYS}.ty
[15:31:10] 502 Command not implemented "SIZE {Masterpiece}{2008-03-30}{Sense and Sensibility}{09.00 PM Sun Mar 30, 2008}{KSYS}.ty"
[15:31:10] SIZE not supported.
[15:31:10] MDTM {Masterpiece}{2008-03-30}{Sense and Sensibility}{09.00 PM Sun Mar 30, 2008}{KSYS}.ty
[15:31:10] 502 Command not implemented "MDTM {Masterpiece}{2008-03-30}{Sense and Sensibility}{09.00 PM Sun Mar 30, 2008}{KSYS}.ty"
[15:31:10] Obtaining file information (size/date) from directory listing.
[15:31:10] TYPE A
[15:31:10] 200 Type set to I
[15:31:10] PASV
[15:31:10] File "{Masterpiece}{2008-03-30}{Sense and Sensibility}{09.00 PM Sun Mar 30, 2008}{KSYS}.ty" not found in directory listing.
[15:31:10] Server closed connection
[15:31:40] Resolving host name "192.168.0.7"
[15:31:40] Connecting to 192.168.0.7 Port: 3105
[15:31:40] Connected to 192.168.0.7.
[15:31:40] 220 Mfs_Ftp ver 1.2.9p - {sock17} from "192.168.0.9:1993"
[15:31:40] USER anonymous
[15:31:40] 331 User name okay, need password.
[15:31:40] PASS (hidden)
[15:31:40] 230 Running in TiVo Mode.
[15:31:40] SYST
[15:31:40] 215 UNIX
[15:31:40] Detected Server Type: UNIX
[15:31:40] FEAT
[15:31:40] 502 Command not implemented "FEAT"
[15:31:40] TYPE I
[15:31:40] 200 Type set to I
[15:31:40] REST 0
[15:31:40] 350 Restart okay, awaiting file request.
[15:31:40] PWD
[15:31:40] 257 "/" is current directory.
[15:31:40] CWD /ty
[15:31:40] 250 Directory change successful.
[15:31:40] PWD
[15:31:40] 257 "/ty" is current directory.
[15:31:40] STAT {Masterpiece}{2008-03-30}{Sense and Sensibility}{09.00 PM Sun Mar 30, 2008}{KSYS}.ty
[15:31:40] 502 Command not implemented "STAT {Masterpiece}{2008-03-30}{Sense and Sensibility}{09.00 PM Sun Mar 30, 2008}{KSYS}.ty"
[15:31:40] SIZE {Masterpiece}{2008-03-30}{Sense and Sensibility}{09.00 PM Sun Mar 30, 2008}{KSYS}.ty
[15:31:40] 502 Command not implemented "SIZE {Masterpiece}{2008-03-30}{Sense and Sensibility}{09.00 PM Sun Mar 30, 2008}{KSYS}.ty"
[15:31:40] SIZE not supported.
[15:31:40] MDTM {Masterpiece}{2008-03-30}{Sense and Sensibility}{09.00 PM Sun Mar 30, 2008}{KSYS}.ty
[15:31:40] 502 Command not implemented "MDTM {Masterpiece}{2008-03-30}{Sense and Sensibility}{09.00 PM Sun Mar 30, 2008}{KSYS}.ty"
[15:31:40] Obtaining file information (size/date) from directory listing.
[15:31:40] TYPE A
[15:31:40] 200 Type set to I
[15:31:40] PASV
[15:31:40] File "{Masterpiece}{2008-03-30}{Sense and Sensibility}{09.00 PM Sun Mar 30, 2008}{KSYS}.ty" not found in directory listing.
[15:31:40] Server closed connection

Remote Browser Log

[15:30:34] SmartFTP v3.0.1012.17
[15:30:34] Resolving host name "192.168.0.7"
[15:30:34] Connecting to 192.168.0.7 Port: 3105
[15:30:34] Connected to 192.168.0.7.
[15:30:34] 220 Mfs_Ftp ver 1.2.9p - {sock14} from "192.168.0.9:1989"
[15:30:34] USER anonymous
[15:30:34] 331 User name okay, need password.
[15:30:34] PASS (hidden)
[15:30:34] 230 Running in TiVo Mode.
[15:30:34] SYST
[15:30:35] 215 UNIX
[15:30:35] Detected Server Type: UNIX
[15:30:35] FEAT
[15:30:35] 502 Command not implemented "FEAT"
[15:30:35] TYPE I
[15:30:35] 200 Type set to I
[15:30:35] REST 0
[15:30:35] 350 Restart okay, awaiting file request.
[15:30:35] PWD
[15:30:35] 257 "/" is current directory.
[15:30:35] TYPE A
[15:30:35] 200 Type set to I

2008-04-14 23:01:58

It looks like the server software just doesn't work that well. For example it returns:
[15:31:40] TYPE A
[15:31:40] 200 Type set to I
which should be Type set to A or an error.

And it closes the connection after a PASV command:
[15:31:40] PASV
[15:31:40] File "{Masterpiece}{2008-03-30}{Sense and Sensibility}{09.00 PM Sun Mar 30, 2008}{KSYS}.ty" not found in directory listing.
[15:31:40] Server closed connection

Try Active Mode (PORT) instead of Passive Mode (PASV)

Regards,
Mat

nilocor

2008-04-15 01:54:11

It looks like the server software just doesn't work that well. For example it returns:
[15:31:40] TYPE A
[15:31:40] 200 Type set to I
which should be Type set to A or an error.

And it closes the connection after a PASV command:
[15:31:40] PASV
[15:31:40] File "{Masterpiece}{2008-03-30}{Sense and Sensibility}{09.00 PM Sun Mar 30, 2008}{KSYS}.ty" not found in directory listing.
[15:31:40] Server closed connection

Try Active Mode (PORT) instead of Passive Mode (PASV)

Regards,
Mat

No improvement in active mode. I get the same problem. As I said earlier it works fine with 2.5 and IE Browser, which means it is a change in V3. Here are the new logs in active mode:

Client Log

[18:47:09] SmartFTP v3.0.1012.17
[18:47:10] Resolving host name "192.168.0.7"
[18:47:10] Connecting to 192.168.0.7 Port: 3105
[18:47:10] Connected to 192.168.0.7.
[18:47:10] 220 Mfs_Ftp ver 1.2.9p - {sock17} from "192.168.0.9:2737"
[18:47:10] USER anonymous
[18:47:10] 331 User name okay, need password.
[18:47:10] PASS (hidden)
[18:47:10] 230 Running in TiVo Mode.
[18:47:10] SYST
[18:47:10] 215 UNIX
[18:47:10] Detected Server Type: UNIX
[18:47:10] FEAT
[18:47:10] 502 Command not implemented "FEAT"
[18:47:10] TYPE I
[18:47:10] 200 Type set to I
[18:47:10] REST 0
[18:47:10] 350 Restart okay, awaiting file request.
[18:47:10] PWD
[18:47:10] 257 "/" is current directory.
[18:47:10] TYPE A
[18:47:10] 200 Type set to I
[18:47:10] PORT 192,168,0,9,10,178
[18:47:10] 200 PORT command successful.
[18:47:10] LIST -aL
[18:47:10] 150 Opening ASCII mode data connection for file list.
[18:47:10] 542 bytes transferred. (4.85 KB/s) (109 ms)
[18:47:11] 226 Transfer complete.
[18:47:29] CWD /ty
[18:47:29] 250 Directory change successful.
[18:47:29] PWD
[18:47:29] 257 "/ty" is current directory.
[18:47:29] PORT 192,168,0,9,10,179
[18:47:29] 200 PORT command successful.
[18:47:29] LIST -aL
[18:47:29] 150 Opening ASCII mode data connection for file list.
[18:47:32] 226 Transfer complete.
[18:47:32] 1549 bytes transferred. (478 bytes/s) (00:00:03)
[18:48:02] NOOP
[18:48:02] 200 Command okay.
[18:48:33] NOOP
[18:48:33] 200 Command okay.
[18:48:50] The operation has been added to the Transfer Queue. Check the Transfer Queue for the status.
[18:49:03] NOOP
[18:49:03] 200 Command okay.
[18:49:33] NOOP
[18:49:33] 200 Command okay.
[18:50:03] NOOP
[18:50:03] 200 Command okay.
[18:50:33] NOOP
[18:50:33] 200 Command okay.
[18:51:03] NOOP
[18:51:03] 200 Command okay.

Transfer Log

[18:48:51] Resolving host name "192.168.0.7"
[18:48:51] Connecting to 192.168.0.7 Port: 3105
[18:48:51] Connected to 192.168.0.7.
[18:48:51] 220 Mfs_Ftp ver 1.2.9p - {sock16} from "192.168.0.9:2740"
[18:48:51] USER anonymous
[18:48:51] 331 User name okay, need password.
[18:48:51] PASS (hidden)
[18:48:51] 230 Running in TiVo Mode.
[18:48:51] SYST
[18:48:51] 215 UNIX
[18:48:51] Detected Server Type: UNIX
[18:48:51] FEAT
[18:48:51] 502 Command not implemented "FEAT"
[18:48:51] TYPE I
[18:48:51] 200 Type set to I
[18:48:51] REST 0
[18:48:51] 350 Restart okay, awaiting file request.
[18:48:51] PWD
[18:48:51] 257 "/" is current directory.
[18:48:51] CWD /ty
[18:48:51] 250 Directory change successful.
[18:48:51] PWD
[18:48:51] 257 "/ty" is current directory.
[18:48:51] STAT {Masterpiece}{2008-04-06}{Sense and Sensibility}{09.00 PM Sun Apr 06, 2008}{KSYS}.ty
[18:48:51] 502 Command not implemented "STAT {Masterpiece}{2008-04-06}{Sense and Sensibility}{09.00 PM Sun Apr 06, 2008}{KSYS}.ty"
[18:48:51] SIZE {Masterpiece}{2008-04-06}{Sense and Sensibility}{09.00 PM Sun Apr 06, 2008}{KSYS}.ty
[18:48:51] 502 Command not implemented "SIZE {Masterpiece}{2008-04-06}{Sense and Sensibility}{09.00 PM Sun Apr 06, 2008}{KSYS}.ty"
[18:48:51] SIZE not supported.
[18:48:51] MDTM {Masterpiece}{2008-04-06}{Sense and Sensibility}{09.00 PM Sun Apr 06, 2008}{KSYS}.ty
[18:48:51] 502 Command not implemented "MDTM {Masterpiece}{2008-04-06}{Sense and Sensibility}{09.00 PM Sun Apr 06, 2008}{KSYS}.ty"
[18:48:51] Obtaining file information (size/date) from directory listing.
[18:48:51] TYPE A
[18:48:51] 200 Type set to I
[18:48:51] PORT 192,168,0,9,10,181
[18:48:51] File "{Masterpiece}{2008-04-06}{Sense and Sensibility}{09.00 PM Sun Apr 06, 2008}{KSYS}.ty" not found in directory listing.
[18:49:21] Resolving host name "192.168.0.7"
[18:49:21] Connecting to 192.168.0.7 Port: 3105
[18:49:21] Connected to 192.168.0.7.
[18:49:21] 220 Mfs_Ftp ver 1.2.9p - {sock16} from "192.168.0.9:2748"
[18:49:21] USER anonymous
[18:49:21] 331 User name okay, need password.
[18:49:21] PASS (hidden)
[18:49:21] 230 Running in TiVo Mode.
[18:49:21] SYST
[18:49:21] 215 UNIX
[18:49:21] Detected Server Type: UNIX
[18:49:21] FEAT
[18:49:21] 502 Command not implemented "FEAT"
[18:49:21] TYPE I
[18:49:21] 200 Type set to I
[18:49:21] REST 0
[18:49:21] 350 Restart okay, awaiting file request.
[18:49:21] PWD
[18:49:21] 257 "/" is current directory.
[18:49:21] CWD /ty
[18:49:21] 250 Directory change successful.
[18:49:21] PWD
[18:49:21] 257 "/ty" is current directory.
[18:49:21] STAT {Masterpiece}{2008-04-06}{Sense and Sensibility}{09.00 PM Sun Apr 06, 2008}{KSYS}.ty
[18:49:21] 502 Command not implemented "STAT {Masterpiece}{2008-04-06}{Sense and Sensibility}{09.00 PM Sun Apr 06, 2008}{KSYS}.ty"
[18:49:21] SIZE {Masterpiece}{2008-04-06}{Sense and Sensibility}{09.00 PM Sun Apr 06, 2008}{KSYS}.ty
[18:49:21] 502 Command not implemented "SIZE {Masterpiece}{2008-04-06}{Sense and Sensibility}{09.00 PM Sun Apr 06, 2008}{KSYS}.ty"
[18:49:21] SIZE not supported.
[18:49:21] MDTM {Masterpiece}{2008-04-06}{Sense and Sensibility}{09.00 PM Sun Apr 06, 2008}{KSYS}.ty
[18:49:21] 502 Command not implemented "MDTM {Masterpiece}{2008-04-06}{Sense and Sensibility}{09.00 PM Sun Apr 06, 2008}{KSYS}.ty"
[18:49:21] Obtaining file information (size/date) from directory listing.
[18:49:21] TYPE A
[18:49:21] 200 Type set to I
[18:49:21] PORT 192,168,0,9,10,189
[18:49:21] File "{Masterpiece}{2008-04-06}{Sense and Sensibility}{09.00 PM Sun Apr 06, 2008}{KSYS}.ty" not found in directory listing.
[18:49:51] Resolving host name "192.168.0.7"
[18:49:51] Connecting to 192.168.0.7 Port: 3105
[18:49:51] Connected to 192.168.0.7.
[18:49:51] 220 Mfs_Ftp ver 1.2.9p - {sock16} from "192.168.0.9:2752"
[18:49:51] USER anonymous
[18:49:51] 331 User name okay, need password.
[18:49:51] PASS (hidden)
[18:49:51] 230 Running in TiVo Mode.
[18:49:51] SYST
[18:49:51] 215 UNIX
[18:49:51] Detected Server Type: UNIX
[18:49:51] FEAT
[18:49:51] 502 Command not implemented "FEAT"
[18:49:51] TYPE I
[18:49:51] 200 Type set to I
[18:49:51] REST 0
[18:49:51] 350 Restart okay, awaiting file request.
[18:49:51] PWD
[18:49:51] 257 "/" is current directory.
[18:49:51] CWD /ty
[18:49:51] 250 Directory change successful.
[18:49:51] PWD
[18:49:51] 257 "/ty" is current directory.
[18:49:51] STAT {Masterpiece}{2008-04-06}{Sense and Sensibility}{09.00 PM Sun Apr 06, 2008}{KSYS}.ty
[18:49:51] 502 Command not implemented "STAT {Masterpiece}{2008-04-06}{Sense and Sensibility}{09.00 PM Sun Apr 06, 2008}{KSYS}.ty"
[18:49:51] SIZE {Masterpiece}{2008-04-06}{Sense and Sensibility}{09.00 PM Sun Apr 06, 2008}{KSYS}.ty
[18:49:51] 502 Command not implemented "SIZE {Masterpiece}{2008-04-06}{Sense and Sensibility}{09.00 PM Sun Apr 06, 2008}{KSYS}.ty"
[18:49:51] SIZE not supported.
[18:49:51] MDTM {Masterpiece}{2008-04-06}{Sense and Sensibility}{09.00 PM Sun Apr 06, 2008}{KSYS}.ty
[18:49:51] 502 Command not implemented "MDTM {Masterpiece}{2008-04-06}{Sense and Sensibility}{09.00 PM Sun Apr 06, 2008}{KSYS}.ty"
[18:49:51] Obtaining file information (size/date) from directory listing.
[18:49:51] TYPE A
[18:49:51] 200 Type set to I
[18:49:51] PORT 192,168,0,9,10,193
[18:49:51] File "{Masterpiece}{2008-04-06}{Sense and Sensibility}{09.00 PM Sun Apr 06, 2008}{KSYS}.ty" not found in directory listing.
[18:50:21] Resolving host name "192.168.0.7"
[18:50:21] Connecting to 192.168.0.7 Port: 3105
[18:50:21] Connected to 192.168.0.7.
[18:50:21] 220 Mfs_Ftp ver 1.2.9p - {sock16} from "192.168.0.9:2754"
[18:50:21] USER anonymous
[18:50:21] 331 User name okay, need password.
[18:50:21] PASS (hidden)
[18:50:21] 230 Running in TiVo Mode.
[18:50:21] SYST
[18:50:21] 215 UNIX
[18:50:21] Detected Server Type: UNIX
[18:50:21] FEAT
[18:50:21] 502 Command not implemented "FEAT"
[18:50:21] TYPE I
[18:50:21] 200 Type set to I
[18:50:21] REST 0
[18:50:21] 350 Restart okay, awaiting file request.
[18:50:21] PWD
[18:50:21] 257 "/" is current directory.
[18:50:21] CWD /ty
[18:50:21] 250 Directory change successful.
[18:50:21] PWD
[18:50:21] 257 "/ty" is current directory.
[18:50:21] STAT {Masterpiece}{2008-04-06}{Sense and Sensibility}{09.00 PM Sun Apr 06, 2008}{KSYS}.ty
[18:50:21] 502 Command not implemented "STAT {Masterpiece}{2008-04-06}{Sense and Sensibility}{09.00 PM Sun Apr 06, 2008}{KSYS}.ty"
[18:50:21] SIZE {Masterpiece}{2008-04-06}{Sense and Sensibility}{09.00 PM Sun Apr 06, 2008}{KSYS}.ty
[18:50:21] 502 Command not implemented "SIZE {Masterpiece}{2008-04-06}{Sense and Sensibility}{09.00 PM Sun Apr 06, 2008}{KSYS}.ty"
[18:50:21] SIZE not supported.
[18:50:21] MDTM {Masterpiece}{2008-04-06}{Sense and Sensibility}{09.00 PM Sun Apr 06, 2008}{KSYS}.ty
[18:50:21] 502 Command not implemented "MDTM {Masterpiece}{2008-04-06}{Sense and Sensibility}{09.00 PM Sun Apr 06, 2008}{KSYS}.ty"
[18:50:21] Obtaining file information (size/date) from directory listing.
[18:50:21] TYPE A
[18:50:21] 200 Type set to I
[18:50:21] PORT 192,168,0,9,10,195
[18:50:21] File "{Masterpiece}{2008-04-06}{Sense and Sensibility}{09.00 PM Sun Apr 06, 2008}{KSYS}.ty" not found in directory listing.
[18:50:21] Server closed connection
[18:50:21] TYPE I
[18:50:51] Resolving host name "192.168.0.7"
[18:50:51] Connecting to 192.168.0.7 Port: 3105
[18:50:51] Connected to 192.168.0.7.
[18:50:51] 220 Mfs_Ftp ver 1.2.9p - {sock16} from "192.168.0.9:2756"
[18:50:51] USER anonymous
[18:50:51] 331 User name okay, need password.
[18:50:51] PASS (hidden)
[18:50:51] 230 Running in TiVo Mode.
[18:50:51] SYST
[18:50:51] 215 UNIX
[18:50:51] Detected Server Type: UNIX
[18:50:51] FEAT
[18:50:51] 502 Command not implemented "FEAT"
[18:50:51] TYPE I
[18:50:51] 200 Type set to I
[18:50:51] REST 0
[18:50:51] 350 Restart okay, awaiting file request.
[18:50:51] PWD
[18:50:51] 257 "/" is current directory.
[18:50:51] CWD /ty
[18:50:51] 250 Directory change successful.
[18:50:51] PWD
[18:50:51] 257 "/ty" is current directory.
[18:50:51] STAT {Masterpiece}{2008-04-06}{Sense and Sensibility}{09.00 PM Sun Apr 06, 2008}{KSYS}.ty
[18:50:51] 502 Command not implemented "STAT {Masterpiece}{2008-04-06}{Sense and Sensibility}{09.00 PM Sun Apr 06, 2008}{KSYS}.ty"
[18:50:51] SIZE {Masterpiece}{2008-04-06}{Sense and Sensibility}{09.00 PM Sun Apr 06, 2008}{KSYS}.ty
[18:50:51] 502 Command not implemented "SIZE {Masterpiece}{2008-04-06}{Sense and Sensibility}{09.00 PM Sun Apr 06, 2008}{KSYS}.ty"
[18:50:51] SIZE not supported.
[18:50:51] MDTM {Masterpiece}{2008-04-06}{Sense and Sensibility}{09.00 PM Sun Apr 06, 2008}{KSYS}.ty
[18:50:51] 502 Command not implemented "MDTM {Masterpiece}{2008-04-06}{Sense and Sensibility}{09.00 PM Sun Apr 06, 2008}{KSYS}.ty"
[18:50:51] Obtaining file information (size/date) from directory listing.
[18:50:51] TYPE A
[18:50:51] 200 Type set to I
[18:50:51] PORT 192,168,0,9,10,197
[18:50:51] File "{Masterpiece}{2008-04-06}{Sense and Sensibility}{09.00 PM Sun Apr 06, 2008}{KSYS}.ty" not found in directory listing.
[18:51:21] Resolving host name "192.168.0.7"
[18:51:21] Connecting to 192.168.0.7 Port: 3105
[18:51:21] Connected to 192.168.0.7.
[18:51:21] 220 Mfs_Ftp ver 1.2.9p - {sock16} from "192.168.0.9:2760"
[18:51:21] USER anonymous
[18:51:21] 331 User name okay, need password.
[18:51:21] PASS (hidden)
[18:51:21] 230 Running in TiVo Mode.
[18:51:21] SYST
[18:51:21] 215 UNIX
[18:51:21] Detected Server Type: UNIX
[18:51:21] FEAT
[18:51:21] 502 Command not implemented "FEAT"
[18:51:21] TYPE I
[18:51:21] 200 Type set to I
[18:51:21] REST 0
[18:51:21] 350 Restart okay, awaiting file request.
[18:51:21] PWD
[18:51:21] 257 "/" is current directory.
[18:51:21] CWD /ty
[18:51:21] 250 Directory change successful.
[18:51:21] PWD
[18:51:21] 257 "/ty" is current directory.
[18:51:21] STAT {Masterpiece}{2008-04-06}{Sense and Sensibility}{09.00 PM Sun Apr 06, 2008}{KSYS}.ty
[18:51:21] 502 Command not implemented "STAT {Masterpiece}{2008-04-06}{Sense and Sensibility}{09.00 PM Sun Apr 06, 2008}{KSYS}.ty"
[18:51:21] SIZE {Masterpiece}{2008-04-06}{Sense and Sensibility}{09.00 PM Sun Apr 06, 2008}{KSYS}.ty
[18:51:21] 502 Command not implemented "SIZE {Masterpiece}{2008-04-06}{Sense and Sensibility}{09.00 PM Sun Apr 06, 2008}{KSYS}.ty"
[18:51:21] SIZE not supported.
[18:51:21] MDTM {Masterpiece}{2008-04-06}{Sense and Sensibility}{09.00 PM Sun Apr 06, 2008}{KSYS}.ty
[18:51:21] 502 Command not implemented "MDTM {Masterpiece}{2008-04-06}{Sense and Sensibility}{09.00 PM Sun Apr 06, 2008}{KSYS}.ty"
[18:51:21] Obtaining file information (size/date) from directory listing.
[18:51:21] TYPE A
[18:51:21] 200 Type set to I
[18:51:21] PORT 192,168,0,9,10,201
[18:51:21] File "{Masterpiece}{2008-04-06}{Sense and Sensibility}{09.00 PM Sun Apr 06, 2008}{KSYS}.ty" not found in directory listing.
[18:51:21] Server closed connection
[18:51:51] Resolving host name "192.168.0.7"
[18:51:51] Connecting to 192.168.0.7 Port: 3105
[18:51:51] Connected to 192.168.0.7.
[18:51:51] 220 Mfs_Ftp ver 1.2.9p - {sock16} from "192.168.0.9:2765"
[18:51:51] USER anonymous
[18:51:51] 331 User name okay, need password.
[18:51:51] PASS (hidden)
[18:51:51] 230 Running in TiVo Mode.
[18:51:51] SYST
[18:51:51] 215 UNIX
[18:51:51] Detected Server Type: UNIX
[18:51:51] FEAT
[18:51:51] 502 Command not implemented "FEAT"
[18:51:51] TYPE I
[18:51:51] 200 Type set to I
[18:51:51] REST 0
[18:51:51] 350 Restart okay, awaiting file request.
[18:51:51] PWD
[18:51:51] 257 "/" is current directory.
[18:51:51] CWD /ty
[18:51:51] 250 Directory change successful.
[18:51:51] PWD
[18:51:51] 257 "/ty" is current directory.
[18:51:51] STAT {Masterpiece}{2008-04-06}{Sense and Sensibility}{09.00 PM Sun Apr 06, 2008}{KSYS}.ty
[18:51:51] 502 Command not implemented "STAT {Masterpiece}{2008-04-06}{Sense and Sensibility}{09.00 PM Sun Apr 06, 2008}{KSYS}.ty"
[18:51:51] SIZE {Masterpiece}{2008-04-06}{Sense and Sensibility}{09.00 PM Sun Apr 06, 2008}{KSYS}.ty
[18:51:51] 502 Command not implemented "SIZE {Masterpiece}{2008-04-06}{Sense and Sensibility}{09.00 PM Sun Apr 06, 2008}{KSYS}.ty"
[18:51:51] SIZE not supported.
[18:51:51] MDTM {Masterpiece}{2008-04-06}{Sense and Sensibility}{09.00 PM Sun Apr 06, 2008}{KSYS}.ty
[18:51:51] 502 Command not implemented "MDTM {Masterpiece}{2008-04-06}{Sense and Sensibility}{09.00 PM Sun Apr 06, 2008}{KSYS}.ty"
[18:51:51] Obtaining file information (size/date) from directory listing.
[18:51:51] TYPE A
[18:51:51] 200 Type set to I
[18:51:51] PORT 192,168,0,9,10,206
[18:51:51] File "{Masterpiece}{2008-04-06}{Sense and Sensibility}{09.00 PM Sun Apr 06, 2008}{KSYS}.ty" not found in directory listing.
[18:51:51] Server closed connection
[18:52:21] Resolving host name "192.168.0.7"
[18:52:21] Connecting to 192.168.0.7 Port: 3105
[18:52:21] Connected to 192.168.0.7.
[18:52:21] 220 Mfs_Ftp ver 1.2.9p - {sock16} from "192.168.0.9:2767"
[18:52:21] USER anonymous
[18:52:21] 331 User name okay, need password.
[18:52:21] PASS (hidden)
[18:52:21] 230 Running in TiVo Mode.
[18:52:21] SYST
[18:52:21] 215 UNIX
[18:52:21] Detected Server Type: UNIX
[18:52:21] FEAT
[18:52:21] 502 Command not implemented "FEAT"
[18:52:21] TYPE I
[18:52:21] 200 Type set to I
[18:52:21] REST 0
[18:52:21] 350 Restart okay, awaiting file request.
[18:52:21] PWD
[18:52:21] 257 "/" is current directory.
[18:52:21] CWD /ty
[18:52:21] 250 Directory change successful.
[18:52:21] PWD
[18:52:21] 257 "/ty" is current directory.
[18:52:21] STAT {Masterpiece}{2008-04-06}{Sense and Sensibility}{09.00 PM Sun Apr 06, 2008}{KSYS}.ty
[18:52:21] 502 Command not implemented "STAT {Masterpiece}{2008-04-06}{Sense and Sensibility}{09.00 PM Sun Apr 06, 2008}{KSYS}.ty"
[18:52:21] SIZE {Masterpiece}{2008-04-06}{Sense and Sensibility}{09.00 PM Sun Apr 06, 2008}{KSYS}.ty
[18:52:21] 502 Command not implemented "SIZE {Masterpiece}{2008-04-06}{Sense and Sensibility}{09.00 PM Sun Apr 06, 2008}{KSYS}.ty"
[18:52:21] SIZE not supported.
[18:52:21] MDTM {Masterpiece}{2008-04-06}{Sense and Sensibility}{09.00 PM Sun Apr 06, 2008}{KSYS}.ty
[18:52:21] 502 Command not implemented "MDTM {Masterpiece}{2008-04-06}{Sense and Sensibility}{09.00 PM Sun Apr 06, 2008}{KSYS}.ty"
[18:52:21] Obtaining file information (size/date) from directory listing.
[18:52:21] TYPE A
[18:52:21] 200 Type set to I
[18:52:21] PORT 192,168,0,9,10,208
[18:52:21] File "{Masterpiece}{2008-04-06}{Sense and Sensibility}{09.00 PM Sun Apr 06, 2008}{KSYS}.ty" not found in directory listing.
[18:52:21] Server closed connection

2008-04-15 04:49:42

The log tells me that the server closes the connection right after the PORT/PASV command. So the problem is very likely there. I can't tell you why the server closes the connection but I'm assuming it's due to a bug in the server software.

Regards,
Mat

nilocor

2008-04-15 05:24:48

The log tells me that the server closes the connection right after the PORT/PASV command. So the problem is very likely there. I can't tell you why the server closes the connection but I'm assuming it's due to a bug in the server software.

Regards,
Mat

Why doesn't this happen in V2.5. I have tried V2.5 on the same server with no problems. V2.5 still works fine. Also the Windows FTP server on the same PC against the same server works fine. What is different in V3 here?

nilocor

2008-04-15 05:35:03

The log tells me that the server closes the connection right after the PORT/PASV command. So the problem is very likely there. I can't tell you why the server closes the connection but I'm assuming it's due to a bug in the server software.

Regards,
Mat

I played around with this a little more and discovered that if I simply close the remote browser the transfer starts. This suggests that the server doesn't like multiple connections. I ran into another problem at another FTP site. It wouldn't let me connect because it said I was trying to start too many connections. Has something changed in 3.0 here? Both sites work fine with 2.5.

2008-04-15 05:50:19

Hello ..

Yes. SmartFTP opens at least 2 connections now. One for the Remote Browser and one for the Transfer Queue. There is kb article regarding this topic:
https://www.smartftp.com/support/kb/421- ... f2602.html
The article metnions workarounds for such problems. But I think your server should allow you to open multiple connections.

Regards,
Mat

nilocor

2008-04-15 05:54:58

The log tells me that the server closes the connection right after the PORT/PASV command. So the problem is very likely there. I can't tell you why the server closes the connection but I'm assuming it's due to a bug in the server software.

Regards,
Mat

Even more thoughts! The FTP Server uses Port 3105. It seems that both the remote browser and the transfer task are trying to connect to port 3105 and only one can connect at a time. I found that if the browser is connected then the transfer doesn't work until the browser is closed. While the transfer is working I cannot start a remote browser the server refuses the connection. Once the transfer finishes then I can start the browser. I know I asked this before, but is there something different about the way this works in V3. Could a firewall problem be causing this issue? Thanks for your help. Colin.

nilocor

2008-04-15 18:45:49

nilocor

2008-04-15 18:55:25

Thanks for the update. This doesn't explain what changed in V3 to create this problem.

Rereading your reply, I guess the answer is that V3 now opens two connections. It also explains why IE works. I guess that it doesn't use background connections. Thanks for the great support. Colin.