Missing 150 connecting to port xxxxx

Advertisement

vholmstedt
Joined:
Posts:
1
Location:
Vestfold University College

Missing 150 connecting to port xxxxx

Test targets:
I have used log files at debug level 2, WinSCP 4.2.7 build 758.

I have tested two Win7 machines, one Vista and one XP.

Problem:
When using FTP from one Win7 machine the log reveals that reply "150 connecting to port xxxxx" is missing. Then WinSCP sends LIST -a and the log lists part or whole directory, but ends with "Disconnected from server" and "Could not retrieve directory listing".

Test conditions:
The same machine let WinSCP perform correctly a while ago.

The same WinSCP version also performs correctly on the other 3 machines. The connection address and user credentials are the same. All machines use the same router, 2 by air and to by cable.

Virus and firewall were disabled during the testing.

Internet Explorer's ftp client works fine with the same connection address and user credentials from the same machine where WinSCP fails to recieve "150 connecting to port xxxxx".

my guess
The WinSCP FTP client tries to establish a port for the server to send data, but for some reason this is not allowed on one of my machines.

my hope
Unfornately the error is on the machine I would like to use most, so I hope there is a solution.

Reply with quote

Advertisement

martin
Site Admin
martin avatar
Joined:
Posts:
40,476
Location:
Prague, Czechia

Re: Missing 150 connecting to port xxxxx

Please post a full log file showing the problem (from both machines).

To generate log file, enable logging, log in to your server and do the operation and only the operation that causes the error. For posting extensive logs you may use pastebin or similar application. Note that passwords and passphrases not stored in the log. You may want to remove other data you consider sensitive though, such as host names, IP addresses, account names or file names (unless they are relevant to the problem). If you do not want to post the log publicly, you may email it to me. You will find my address (if you log in) in my forum profile. Please include link back to this topic in your email. Also note in this topic that you have emailed the log.

Reply with quote

vholms
Guest

Missing 150 connecting to port xxxxx

The log from the successful Win7 machine:


. 2010-07-10 22:06:29.315 --------------------------------------------------------------------------
. 2010-07-10 22:06:29.315 WinSCP Version 4.2.7 (Build 758) (OS 6.1.7600)
. 2010-07-10 22:06:29.315 Login time: 10. juli 2010 22:06:29
. 2010-07-10 22:06:29.315 --------------------------------------------------------------------------
. 2010-07-10 22:06:29.315 Session name: test_evalanche_connection
. 2010-07-10 22:06:29.315 Host name: ftp.evalanche.me (Port: 21)
. 2010-07-10 22:06:29.315 User name: evalanche.me (Password: Yes, Key file: No)
. 2010-07-10 22:06:29.315 Tunnel: No
. 2010-07-10 22:06:29.315 Transfer Protocol: FTP
. 2010-07-10 22:06:29.315 Ping type: C, Ping interval: 30 sec; Timeout: 15 sec
. 2010-07-10 22:06:29.315 Proxy: none
. 2010-07-10 22:06:29.315 FTP: FTPS: None; Passive: No [Force IP: No]
. 2010-07-10 22:06:29.315 Local directory: default, Remote directory: home, Update: No, Cache: Yes
. 2010-07-10 22:06:29.315 Cache directory changes: Yes, Permanent: Yes
. 2010-07-10 22:06:29.315 DST mode: 1
. 2010-07-10 22:06:29.315 --------------------------------------------------------------------------
. 2010-07-10 22:06:29.909 Connecting to ftp.evalanche.me ...
. 2010-07-10 22:06:30.143 Connected with ftp.evalanche.me. Waiting for welcome message...
< 2010-07-10 22:06:30.143 220---------- Welcome to Pure-FTPd [privsep] [TLS] ----------
< 2010-07-10 22:06:30.143 220-You are user number 3 of 100 allowed.
< 2010-07-10 22:06:30.143 220-Local time is now 22:06. Server port: 21.
< 2010-07-10 22:06:30.143 220-This is a private system - No anonymous login
< 2010-07-10 22:06:30.143 220 You will be disconnected after 15 minutes of inactivity.
> 2010-07-10 22:06:30.143 USER evalanche.me
< 2010-07-10 22:06:30.190 331 User evalanche.me OK. Password required
> 2010-07-10 22:06:30.190 PASS ***************
< 2010-07-10 22:06:30.346 230-User evalanche.me has group access to: evalanch
< 2010-07-10 22:06:30.346 230 OK. Current restricted directory is /
> 2010-07-10 22:06:30.346 SYST
< 2010-07-10 22:06:30.393 215 UNIX Type: L8
> 2010-07-10 22:06:30.393 FEAT
< 2010-07-10 22:06:30.440 211-Extensions supported:
< 2010-07-10 22:06:30.440 EPRT
< 2010-07-10 22:06:30.440 IDLE
< 2010-07-10 22:06:30.440 MDTM
< 2010-07-10 22:06:30.440 SIZE
< 2010-07-10 22:06:30.440 REST STREAM
< 2010-07-10 22:06:30.440 MLST type*;size*;sizd*;modify*;UNIX.mode*;UNIX.uid*;UNIX.gid*;unique*;
< 2010-07-10 22:06:30.440 MLSD
< 2010-07-10 22:06:30.440 TVFS
< 2010-07-10 22:06:30.440 ESTP
< 2010-07-10 22:06:30.440 PASV
< 2010-07-10 22:06:30.440 EPSV
< 2010-07-10 22:06:30.440 SPSV
< 2010-07-10 22:06:30.440 ESTA
< 2010-07-10 22:06:30.440 AUTH TLS
< 2010-07-10 22:06:30.440 PBSZ
< 2010-07-10 22:06:30.440 PROT
< 2010-07-10 22:06:30.440 211 End.
. 2010-07-10 22:06:30.456 Connected
. 2010-07-10 22:06:30.456 Got reply 1 to the command 1
. 2010-07-10 22:06:30.456 --------------------------------------------------------------------------
. 2010-07-10 22:06:30.456 Using FTP protocol.
. 2010-07-10 22:06:30.456 Doing startup conversation with host.
> 2010-07-10 22:06:30.502 PWD
< 2010-07-10 22:06:30.596 257 "/" is your current location
. 2010-07-10 22:06:30.596 Got reply 1 to the command 16
. 2010-07-10 22:06:30.612 Getting current directory name.
. 2010-07-10 22:06:30.659 Retrieving directory listing...
> 2010-07-10 22:06:30.659 TYPE A
< 2010-07-10 22:06:30.752 200 TYPE is now ASCII
> 2010-07-10 22:06:30.831 PORT 192,168,1,34,192,62
< 2010-07-10 22:06:30.940 200 PORT command successful
> 2010-07-10 22:06:30.940 LIST -a
< 2010-07-10 22:06:31.096 150 Connecting to port 60453
< 2010-07-10 22:06:31.143 226-Options: -a -l
< 2010-07-10 22:06:31.143 226 28 matches total
. 2010-07-10 22:06:31.159 drwx--x--x 18 786082 evalanch 4096 Jul 6 14:07 .
. 2010-07-10 22:06:31.159 drwx--x--x 18 786082 evalanch 4096 Jul 6 14:07 ..
. 2010-07-10 22:06:31.159 -rw-r--r-- 1 786082 evalanch 210 Mar 14 2009 .htaccess
. 2010-07-10 22:06:31.159 drwxr-xr-x 7 786082 evalanch 2048 Mar 28 2009 .ilosoft
. 2010-07-10 22:06:31.159 -rw-r--r-- 1 786082 evalanch 3325 Mar 14 2009 b-one-default.html
. 2010-07-10 22:06:31.159 drwxr-xr-x 3 786082 evalanch 2048 May 6 2009 corcovado
. 2010-07-10 22:06:31.159 drwxr-xr-x 5 786082 evalanch 2048 Apr 19 2009 family
. 2010-07-10 22:06:31.159 drwxr-xr-x 2 786082 evalanch 2048 Nov 9 2009 gamledager
. 2010-07-10 22:06:31.159 drwxr-xr-x 2 786082 evalanch 2048 Mar 21 13:34 gjestehus
. 2010-07-10 22:06:31.159 drwxr-xr-x 8 786082 evalanch 2048 Jun 4 11:06 guesthouse
. 2010-07-10 22:06:31.159 -rwxr-xr-x 1 786082 evalanch 88 Dec 20 2009 guesthouse.html
. 2010-07-10 22:06:31.159 drwxr-xr-x 2 786082 evalanch 2048 Jul 6 14:07 help
. 2010-07-10 22:06:31.159 drwxr-xr-x 3 786082 evalanch 2048 Mar 20 2009 images
. 2010-07-10 22:06:31.159 -rw-r--r-- 1 786082 evalanch 5411 Mar 20 2009 index.html
. 2010-07-10 22:06:31.159 drwxr-xr-x 2 786082 evalanch 2048 Jun 2 2009 kjvbrosjyre
. 2010-07-10 22:06:31.159 drwxr-xr-x 3 786082 evalanch 4096 Mar 28 2009 lingua
. 2010-07-10 22:06:31.159 -rw-r--r-- 1 786082 evalanch 5282 Mar 20 2009 merkedager.html
. 2010-07-10 22:06:31.159 drwxr-xr-x 4 786082 evalanch 2048 Feb 5 21:33 nki2corco
. 2010-07-10 22:06:31.159 drwxr-xr-x 3 786082 evalanch 2048 Jul 7 09:52 nkilos
. 2010-07-10 22:06:31.159 drwxr-xr-x 2 786082 evalanch 2048 Sep 28 2009 systems
. 2010-07-10 22:06:31.159 -rwxr-xr-x 1 786082 evalanch 1384 Mar 28 2009 testbasura.php
. 2010-07-10 22:06:31.159 drwxr-xr-x 2 786082 evalanch 2048 Apr 14 2009 testcore
. 2010-07-10 22:06:31.159 -rwxr-xr-x 1 786082 evalanch 267 Apr 28 2009 testexec.php
. 2010-07-10 22:06:31.159 -rwxr-xr-x 1 786082 evalanch 38 Jul 20 2009 testupload.html
. 2010-07-10 22:06:31.159 drwxr-xr-x 2 786082 evalanch 2048 Feb 26 13:40 whatis
. 2010-07-10 22:06:31.159 drwxr-xr-x 3 786082 evalanch 2048 Dec 29 2009 x
. 2010-07-10 22:06:31.159 -rwxr-xr-x 1 786082 evalanch 2084 Apr 7 2009 yr.php
. 2010-07-10 22:06:31.159 -rwxr-xr-x 1 786082 evalanch 2072 Apr 7 2009 yrlondon.php
. 2010-07-10 22:06:31.190 Directory listing successful
. 2010-07-10 22:06:31.190 Got reply 1 to the command 2
. 2010-07-10 22:06:31.190 Startup conversation with host finished.
. 2010-07-10 22:06:35.096 Session upkeep
. 2010-07-10 22:06:35.737 Session upkeep
. 2010-07-10 22:06:35.831 Session upkeep
. 2010-07-10 22:06:37.956 Session upkeep
. 2010-07-10 22:06:38.315 Session upkeep
. 2010-07-10 22:06:38.831 Session upkeep
. 2010-07-10 22:06:39.315 Session upkeep
. 2010-07-10 22:06:39.831 Session upkeep
. 2010-07-10 22:06:40.549 Session upkeep
. 2010-07-10 22:06:40.815 Session upkeep
. 2010-07-10 22:06:41.315 Session upkeep
. 2010-07-10 22:06:41.815 Session upkeep
. 2010-07-10 22:06:42.924 Got reply 1004 to the command 2
. 2010-07-10 22:06:42.924 Disconnected from server


And the log from the erroneous Win7 machine:

. 2010-07-10 21:59:07.879 --------------------------------------------------------------------------
. 2010-07-10 21:59:07.879 WinSCP Version 4.2.7 (Build 758) (OS 6.1.7600)
. 2010-07-10 21:59:07.879 Login time: 10. juli 2010 21:59:07
. 2010-07-10 21:59:07.879 --------------------------------------------------------------------------
. 2010-07-10 21:59:07.879 Session name: test_evalanche_connection
. 2010-07-10 21:59:07.879 Host name: ftp.evalanche.me (Port: 21)
. 2010-07-10 21:59:07.879 User name: evalanche.me (Password: Yes, Key file: No)
. 2010-07-10 21:59:07.879 Tunnel: No
. 2010-07-10 21:59:07.879 Transfer Protocol: FTP
. 2010-07-10 21:59:07.879 Ping type: C, Ping interval: 30 sec; Timeout: 15 sec
. 2010-07-10 21:59:07.879 Proxy: none
. 2010-07-10 21:59:07.879 FTP: FTPS: None; Passive: No [Force IP: No]
. 2010-07-10 21:59:07.879 Local directory: default, Remote directory: home, Update: No, Cache: Yes
. 2010-07-10 21:59:07.879 Cache directory changes: Yes, Permanent: Yes
. 2010-07-10 21:59:07.879 DST mode: 1
. 2010-07-10 21:59:07.879 --------------------------------------------------------------------------
. 2010-07-10 21:59:07.941 Connecting to ftp.evalanche.me ...
. 2010-07-10 21:59:07.941 Connected with ftp.evalanche.me. Waiting for welcome message...
< 2010-07-10 21:59:07.941 220---------- Welcome to Pure-FTPd [privsep] [TLS] ----------
< 2010-07-10 21:59:07.941 220-You are user number 6 of 100 allowed.
< 2010-07-10 21:59:07.941 220-Local time is now 21:59. Server port: 21.
< 2010-07-10 21:59:07.941 220-This is a private system - No anonymous login
< 2010-07-10 21:59:07.941 220 You will be disconnected after 15 minutes of inactivity.
> 2010-07-10 21:59:07.941 USER evalanche.me
< 2010-07-10 21:59:07.972 331 User evalanche.me OK. Password required
> 2010-07-10 21:59:07.972 PASS ***************
< 2010-07-10 21:59:08.097 230-User evalanche.me has group access to: evalanch
< 2010-07-10 21:59:08.097 230 OK. Current restricted directory is /
> 2010-07-10 21:59:08.097 SYST
< 2010-07-10 21:59:08.128 215 UNIX Type: L8
> 2010-07-10 21:59:08.128 FEAT
< 2010-07-10 21:59:08.144 211-Extensions supported:
< 2010-07-10 21:59:08.144 EPRT
< 2010-07-10 21:59:08.144 IDLE
< 2010-07-10 21:59:08.144 MDTM
< 2010-07-10 21:59:08.144 SIZE
< 2010-07-10 21:59:08.144 REST STREAM
< 2010-07-10 21:59:08.144 MLST type*;size*;sizd*;modify*;UNIX.mode*;UNIX.uid*;UNIX.gid*;unique*;
< 2010-07-10 21:59:08.144 MLSD
< 2010-07-10 21:59:08.144 TVFS
< 2010-07-10 21:59:08.144 ESTP
< 2010-07-10 21:59:08.144 PASV
< 2010-07-10 21:59:08.144 EPSV
< 2010-07-10 21:59:08.144 SPSV
< 2010-07-10 21:59:08.144 ESTA
< 2010-07-10 21:59:08.144 AUTH TLS
< 2010-07-10 21:59:08.144 PBSZ
< 2010-07-10 21:59:08.144 PROT
< 2010-07-10 21:59:08.144 211 End.
. 2010-07-10 21:59:08.144 Connected
. 2010-07-10 21:59:08.144 Got reply 1 to the command 1
. 2010-07-10 21:59:08.144 --------------------------------------------------------------------------
. 2010-07-10 21:59:08.144 Using FTP protocol.
. 2010-07-10 21:59:08.144 Doing startup conversation with host.
> 2010-07-10 21:59:08.159 PWD
< 2010-07-10 21:59:08.175 257 "/" is your current location
. 2010-07-10 21:59:08.175 Got reply 1 to the command 16
. 2010-07-10 21:59:08.175 Getting current directory name.
. 2010-07-10 21:59:08.191 Retrieving directory listing...
> 2010-07-10 21:59:08.191 TYPE A
< 2010-07-10 21:59:08.206 200 TYPE is now ASCII
> 2010-07-10 21:59:08.206 PORT 192,168,1,9,196,59
< 2010-07-10 21:59:08.237 200 PORT command successful
> 2010-07-10 21:59:08.237 LIST -a
. 2010-07-10 21:59:08.315 drwx--x--x 18 786082 evalanch 4096 Jul 6 14:07 .
. 2010-07-10 21:59:08.315 drwx--x--x 18 786082 evalanch 4096 Jul 6 14:07 ..
. 2010-07-10 21:59:08.315 -rw-r--r-- 1 786082 evalanch 210 Mar 14 2009 .htaccess
. 2010-07-10 21:59:08.315 drwxr-xr-x 7 786082 evalanch 2048 Mar 28 2009 .ilosoft
. 2010-07-10 21:59:08.315 -rw-r--r-- 1 786082 evalanch 3325 Mar 14 2009 b-one-default.html
. 2010-07-10 21:59:08.315 drwxr-xr-x 3 786082 evalanch 2048 May 6 2009 corcovado
. 2010-07-10 21:59:08.315 drwxr-xr-x 5 786082 evalanch 2048 Apr 19 2009 family
. 2010-07-10 21:59:08.315 drwxr-xr-x 2 786082 evalanch 2048 Nov 9 2009 gamledager
. 2010-07-10 21:59:08.315 drwxr-xr-x 2 786082 evalanch 2048 Mar 21 13:34 gjestehus
. 2010-07-10 21:59:08.315 drwxr-xr-x 8 786082 evalanch 2048 Jun 4 11:06 guesthouse
. 2010-07-10 21:59:08.315 -rwxr-xr-x 1 786082 evalanch 88 Dec 20 2009 guesthouse.html
. 2010-07-10 21:59:08.315 drwxr-xr-x 2 786082 evalanch 2048 Jul 6 14:07 help
. 2010-07-10 21:59:08.315 drwxr-xr-x 3 786082 evalanch 2048 Mar 20 2009 images
. 2010-07-10 21:59:08.315 -rw-r--r-- 1 786082 evalanch 5411 Mar 20 2009 index.html
. 2010-07-10 21:59:08.315 drwxr-xr-x 2 786082 evalanch 2048 Jun 2 2009 kjvbrosjyre
. 2010-07-10 21:59:08.315 drwxr-xr-x 3 786082 evalanch 4096 Mar 28 2009 lingua
. 2010-07-10 21:59:08.315 -rw-r--r-- 1 786082 evalanch 5282 Mar 20 2009 merkedager.html
. 2010-07-10 21:59:08.315 drwxr-xr-x 4 786082 evalanch 2048 Feb 5 21:33 nki2corco
. 2010-07-10 21:59:08.315 drwxr-xr-x 3 786082 evalanch 2048 Jul 7 09:52 nkilos
. 2010-07-10 21:59:08.315 drwxr-xr-x 2 786082 evalanch 2048 Sep 28 2009 systems
. 2010-07-10 21:59:08.315 -rwxr-xr-x 1 786082 evalanch 1384 Mar 28 2009 testbasura.php
. 2010-07-10 21:59:08.315 drwxr-xr-x 2 786082 evalanch 2048 Apr 14 2009 testcore
. 2010-07-10 21:59:08.315 -rwxr-xr-x 1 786082 evalanch 267 Apr 28 2009 testexec.php
. 2010-07-10 21:59:08.315 -rwxr-xr-x 1 786082 evalanch 38 Jul 20 2009 testupload.html
. 2010-07-10 21:59:08.315 drwxr-xr-x 2 786082 evalanch 2048 Feb 26 13:40 whatis
. 2010-07-10 21:59:08.315 drwxr-xr-x 3 786082 evalanch 2048 Dec 29 2009 x
. 2010-07-10 21:59:08.315 -rwxr-xr-x 1 786082 evalanch 2084 Apr 7 2009 yr.php
. 2010-07-10 21:59:08.315 -rwxr-xr-x 1 786082 evalanch 2072 Apr 7 2009 yrlondon.php
. 2010-07-10 21:59:29.578 Disconnected from server
. 2010-07-10 21:59:29.578 Could not retrieve directory listing
. 2010-07-10 21:59:29.578 Got reply 1004 to the command 2
* 2010-07-10 21:59:29.578 (ESshFatal) Lost connection.
* 2010-07-10 21:59:29.578 drwx--x--x 18 786082 evalanch 4096 Jul 6 14:07 .
* 2010-07-10 21:59:29.578 drwx--x--x 18 786082 evalanch 4096 Jul 6 14:07 ..
* 2010-07-10 21:59:29.578 -rw-r--r-- 1 786082 evalanch 210 Mar 14 2009 .htaccess
* 2010-07-10 21:59:29.578 drwxr-xr-x 7 786082 evalanch 2048 Mar 28 2009 .ilosoft
* 2010-07-10 21:59:29.578 -rw-r--r-- 1 786082 evalanch 3325 Mar 14 2009 b-one-default.html
* 2010-07-10 21:59:29.578 drwxr-xr-x 3 786082 evalanch 2048 May 6 2009 corcovado
* 2010-07-10 21:59:29.578 drwxr-xr-x 5 786082 evalanch 2048 Apr 19 2009 family
* 2010-07-10 21:59:29.578 drwxr-xr-x 2 786082 evalanch 2048 Nov 9 2009 gamledager
* 2010-07-10 21:59:29.578 drwxr-xr-x 2 786082 evalanch 2048 Mar 21 13:34 gjestehus
* 2010-07-10 21:59:29.578 drwxr-xr-x 8 786082 evalanch 2048 Jun 4 11:06 guesthouse
* 2010-07-10 21:59:29.578 -rwxr-xr-x 1 786082 evalanch 88 Dec 20 2009 guesthouse.html
* 2010-07-10 21:59:29.578 drwxr-xr-x 2 786082 evalanch 2048 Jul 6 14:07 help
* 2010-07-10 21:59:29.578 drwxr-xr-x 3 786082 evalanch 2048 Mar 20 2009 images
* 2010-07-10 21:59:29.578 -rw-r--r-- 1 786082 evalanch 5411 Mar 20 2009 index.html
* 2010-07-10 21:59:29.578 drwxr-xr-x 2 786082 evalanch 2048 Jun 2 2009 kjvbrosjyre
* 2010-07-10 21:59:29.578 drwxr-xr-x 3 786082 evalanch 4096 Mar 28 2009 lingua
* 2010-07-10 21:59:29.578 -rw-r--r-- 1 786082 evalanch 5282 Mar 20 2009 merkedager.html
* 2010-07-10 21:59:29.578 drwxr-xr-x 4 786082 evalanch 2048 Feb 5 21:33 nki2corco
* 2010-07-10 21:59:29.578 drwxr-xr-x 3 786082 evalanch 2048 Jul 7 09:52 nkilos
* 2010-07-10 21:59:29.578 drwxr-xr-x 2 786082 evalanch 2048 Sep 28 2009 systems
* 2010-07-10 21:59:29.578 -rwxr-xr-x 1 786082 evalanch 1384 Mar 28 2009 testbasura.php
* 2010-07-10 21:59:29.578 drwxr-xr-x 2 786082 evalanch 2048 Apr 14 2009 testcore
* 2010-07-10 21:59:29.578 -rwxr-xr-x 1 786082 evalanch 267 Apr 28 2009 testexec.php
* 2010-07-10 21:59:29.578 -rwxr-xr-x 1 786082 evalanch 38 Jul 20 2009 testupload.html
* 2010-07-10 21:59:29.578 drwxr-xr-x 2 786082 evalanch 2048 Feb 26 13:40 whatis
* 2010-07-10 21:59:29.578 drwxr-xr-x 3 786082 evalanch 2048 Dec 29 2009 x
* 2010-07-10 21:59:29.578 -rwxr-xr-x 1 786082 evalanch 2084 Apr 7 2009 yr.php
* 2010-07-10 21:59:29.578 -rwxr-xr-x 1 786082 evalanch 2072 Apr 7 2009 yrlondon.php
* 2010-07-10 21:59:29.578 Disconnected from server
* 2010-07-10 21:59:29.578 Could not retrieve directory listing
* 2010-07-10 21:59:29.578 PORT command successful
* 2010-07-10 21:59:29.578 Error listing directory '/'.
. 2010-07-10 21:59:30.670 Timeout detected.

The line corresponding to the timestamp "22:06:31.096" is present when successful and missing when failing.
When failing, the dirlist is repeated in this test, although I only tried to connect and stopped WinSCP immediately after
having regained the UI control.


Thank you for your interest.

Reply with quote

Advertisement

You can post new topics in this forum