I'm trying to set up a remote project via FTP and not having much luck. I'm on a Windows XP SP3 box using VS 2008 and VS.PHP version 2.7.6.6075. My server is a Windows 2003 Server running IIS and Apache (on different ports). When I try to connect via FTP, I get a dialog box that says 'The FTP client has not yet connected to the server....". I followd the steps from another post and have a DebugView log of the connection (see below). It looks like the FTP connection works fine (See line 34), but it tries to connect run 'DoPhpInfo' and failes to create a socket (See line 123). Please let me know if I need to make a configuration change or if you need more info.
Thanks,
Jeff
DebugView log:
00000000 0.00000000 [191600] Executing ProjectFromRemoteServer wizard
00000001 0.11223205 [191600] Ready to show NewProjectFromRemoteServerWizard dialog
00000002 28.92374229 [191600] NewProjectFromRemoteServerWizard::serverInfoPage_BeforeMoveNext
00000003 28.92380333 [191600] Passing server, using default port
00000004 28.92401314 [191600] RemoteServer::Init
00000005 28.93448830 [191600] BasicServer::Initialize
00000006 28.93460083 [191600] Initializing Ftp://jdales@75.126.30.30:0/
00000007 28.97016525 [191600] WARN [ExFTPConnection] 3 Feb 2010 14:07:33.979 : Values of KeepAlivePeriodSecs less than 10 seconds are not advised.
00000008 28.97031403 [191600] DEBUG [ExFTPConnection] 3 Feb 2010 14:07:33.979 : Starting KeepAlive thread with period 30s
00000009 28.97037697 [191600] DEBUG [ExFTPConnection] 3 Feb 2010 14:07:33.995 : KeepAlive thread finished.
00000010 28.97094536 [191600] RemoteServices using FTP
00000011 28.97212601 [191600] BasicServer::Connect
00000012 29.02492142 [191600] BasicServer::ProgressUpdate
00000013 29.03053284 [191600] INFO [LicenseProperties] 3 Feb 2010 14:07:34.058 : Licence expiry date: 12/31/9999
00000014 29.03058815 [191600] INFO [LicenseProperties] 3 Feb 2010 14:07:34.058 : Production license
00000015 29.03139496 [191600] DEBUG [FTPConnection] 3 Feb 2010 14:07:34.058 : Set LocalDirectory='C:\Program Files\Attrice Corporation\Team Foundation Sidekicks'
00000016 29.03162003 [191600] DEBUG [SecureFTPConnection] 3 Feb 2010 14:07:34.058 : Set LocalDirectory to C:\Program Files\Attrice Corporation\Team Foundation Sidekicks
00000017 29.03268814 [191600] INFO [LicenseProperties] 3 Feb 2010 14:07:34.058 : Licence expiry date: 12/31/9999
00000018 29.03274536 [191600] INFO [LicenseProperties] 3 Feb 2010 14:07:34.058 : Production license
00000019 29.03284454 [191600] DEBUG [SSLFTPClient] 3 Feb 2010 14:07:34.058 : Connecting to 75.126.30.30:21
00000020 29.03335190 [191600] DEBUG [HostNameResolver] 3 Feb 2010 14:07:34.058 : 75.126.30.30 resolved to 75.126.30.30
00000021 29.03394127 [191600] DEBUG [SSLFTPControlSocket] 3 Feb 2010 14:07:34.058 : waitOnShutdownSSL=True
00000022 29.03718948 [191600] DEBUG [ExFTPControlSocket] 3 Feb 2010 14:07:34.073 : Connecting directly to ftp-server 75.126.30.30:21
00000023 29.03826714 [191600] DEBUG [FTPControlSocket] 3 Feb 2010 14:07:34.073 : Setting socket timeout=120000
00000024 29.03876686 [191600] DEBUG [SecureSocket] 3 Feb 2010 14:07:34.073 : Set timeout=120000
00000025 29.03883743 [191600] DEBUG [SecureSocket] 3 Feb 2010 14:07:34.073 : Set timeout=120000
00000026 29.03970909 [191600] DEBUG [FTPControlSocket] 3 Feb 2010 14:07:34.073 : Command encoding=System.Text.ASCIIEncoding
00000027 29.04013252 [191600] DEBUG [ExFTPControlSocket] 3 Feb 2010 14:07:34.073 : Created control-socket: SocksContext=, ProxySettings=NoProxy, RemoteHost=75.126.30.30, controlPort=21, timeout=120000
00000028 29.04132271 [191600] DEBUG [FTPControlSocket] 3 Feb 2010 14:07:34.073 : StrictReturnCodes=False
00000029 29.14568138 [191600] DEBUG [FTPControlSocket] 3 Feb 2010 14:07:34.183 : 220 Microsoft FTP Service
00000030 29.14818001 [191600] DEBUG [FTPControlSocket] 3 Feb 2010 14:07:34.183 : ---> USER jdales
00000031 29.20284843 [191600] DEBUG [FTPControlSocket] 3 Feb 2010 14:07:34.229 : 331 Password required for jdales.
00000032 29.20308113 [191600] DEBUG [FTPControlSocket] 3 Feb 2010 14:07:34.229 : ---> PASS ********
00000033 29.25498199 [191600] DEBUG [FTPControlSocket] 3 Feb 2010 14:07:34.292 : 230 User jdales logged in.
00000034 29.25504112 [191600] DEBUG [FTPConnection] 3 Feb 2010 14:07:34.292 : Successfully logged in
00000035 29.25606537 [191600] DEBUG [ExFTPConnection] 3 Feb 2010 14:07:34.292 : Starting KeepAlive thread with period 1s
00000036 29.25662422 [191600] DEBUG [FTPControlSocket] 3 Feb 2010 14:07:34.292 : ---> TYPE I
00000037 29.31004524 [191600] DEBUG [FTPControlSocket] 3 Feb 2010 14:07:34.339 : 200 Type set to I.
00000038 29.31045341 [191600] DEBUG [FTPControlSocket] 3 Feb 2010 14:07:34.339 : ---> PWD
00000039 29.36308479 [191600] DEBUG [FTPControlSocket] 3 Feb 2010 14:07:34.386 : 257 "/" is current directory.
00000040 29.36386299 [191600] BasicServer::ProgressUpdate
00000041 29.36416817 [191600] NewProjectFromRemoteServerWizard::projectLocationPage_BeforeDisplay
00000042 30.75565910 [191600] DEBUG [ExFTPConnection] 3 Feb 2010 14:07:35.792 : KeepAlive - testing connection
00000043 30.75592041 [191600] DEBUG [ExFTPConnection] 3 Feb 2010 14:07:35.792 : Testing connection
00000044 30.75597763 [191600] DEBUG [FTPControlSocket] 3 Feb 2010 14:07:35.792 : ---> PWD
00000045 30.81019020 [191600] DEBUG [FTPControlSocket] 3 Feb 2010 14:07:35.839 : 257 "/" is current directory.
00000046 32.30936813 [191600] DEBUG [ExFTPConnection] 3 Feb 2010 14:07:37.339 : KeepAlive - testing connection
00000047 32.30942535 [191600] DEBUG [ExFTPConnection] 3 Feb 2010 14:07:37.339 : Testing connection
00000048 32.30948257 [191600] DEBUG [FTPControlSocket] 3 Feb 2010 14:07:37.339 : ---> PWD
00000049 32.36283493 [191600] DEBUG [FTPControlSocket] 3 Feb 2010 14:07:37.386 : 257 "/" is current directory.
00000050 33.86303711 [191600] DEBUG [ExFTPConnection] 3 Feb 2010 14:07:38.886 : KeepAlive - testing connection
00000051 33.86309052 [191600] DEBUG [ExFTPConnection] 3 Feb 2010 14:07:38.886 : Testing connection
00000052 33.86314774 [191600] DEBUG [FTPControlSocket] 3 Feb 2010 14:07:38.886 : ---> PWD
00000053 33.91466522 [191600] DEBUG [FTPControlSocket] 3 Feb 2010 14:07:38.948 : 257 "/" is current directory.
00000054 35.41473389 [191600] DEBUG [ExFTPConnection] 3 Feb 2010 14:07:40.448 : KeepAlive - testing connection
00000055 35.41478729 [191600] DEBUG [ExFTPConnection] 3 Feb 2010 14:07:40.448 : Testing connection
00000056 35.41484451 [191600] DEBUG [FTPControlSocket] 3 Feb 2010 14:07:40.448 : ---> PWD
00000057 35.46778870 [191600] DEBUG [FTPControlSocket] 3 Feb 2010 14:07:40.495 : 257 "/" is current directory.
00000058 36.96743774 [191600] DEBUG [ExFTPConnection] 3 Feb 2010 14:07:41.995 : KeepAlive - testing connection
00000059 36.96748734 [191600] DEBUG [ExFTPConnection] 3 Feb 2010 14:07:41.995 : Testing connection
00000060 36.96754456 [191600] DEBUG [FTPControlSocket] 3 Feb 2010 14:07:41.995 : ---> PWD
00000061 37.01956558 [191600] DEBUG [FTPControlSocket] 3 Feb 2010 14:07:42.042 : 257 "/" is current directory.
00000062 38.51917648 [191600] DEBUG [ExFTPConnection] 3 Feb 2010 14:07:43.542 : KeepAlive - testing connection
00000063 38.51923370 [191600] DEBUG [ExFTPConnection] 3 Feb 2010 14:07:43.542 : Testing connection
00000064 38.51929092 [191600] DEBUG [FTPControlSocket] 3 Feb 2010 14:07:43.542 : ---> PWD
00000065 38.57334900 [191600] DEBUG [FTPControlSocket] 3 Feb 2010 14:07:43.604 : 257 "/" is current directory.
00000066 40.07284546 [191600] DEBUG [ExFTPConnection] 3 Feb 2010 14:07:45.104 : KeepAlive - testing connection
00000067 40.07290649 [191600] DEBUG [ExFTPConnection] 3 Feb 2010 14:07:45.104 : Testing connection
00000068 40.07296371 [191600] DEBUG [FTPControlSocket] 3 Feb 2010 14:07:45.104 : ---> PWD
00000069 40.12500381 [191600] DEBUG [FTPControlSocket] 3 Feb 2010 14:07:45.151 : 257 "/" is current directory.
00000070 41.62454987 [191600] DEBUG [ExFTPConnection] 3 Feb 2010 14:07:46.651 : KeepAlive - testing connection
00000071 41.62459946 [191600] DEBUG [ExFTPConnection] 3 Feb 2010 14:07:46.651 : Testing connection
00000072 41.62466049 [191600] DEBUG [FTPControlSocket] 3 Feb 2010 14:07:46.651 : ---> PWD
00000073 41.67743301 [191600] DEBUG [FTPControlSocket] 3 Feb 2010 14:07:46.714 : 257 "/" is current directory.
00000074 43.17726517 [191600] DEBUG [ExFTPConnection] 3 Feb 2010 14:07:48.214 : KeepAlive - testing connection
00000075 43.17733002 [191600] DEBUG [ExFTPConnection] 3 Feb 2010 14:07:48.214 : Testing connection
00000076 43.17738342 [191600] DEBUG [FTPControlSocket] 3 Feb 2010 14:07:48.214 : ---> PWD
00000077 43.22993469 [191600] DEBUG [FTPControlSocket] 3 Feb 2010 14:07:48.261 : 257 "/" is current directory.
00000078 44.72998047 [191600] DEBUG [ExFTPConnection] 3 Feb 2010 14:07:49.761 : KeepAlive - testing connection
00000079 44.73003006 [191600] DEBUG [ExFTPConnection] 3 Feb 2010 14:07:49.761 : Testing connection
00000080 44.73008728 [191600] DEBUG [FTPControlSocket] 3 Feb 2010 14:07:49.761 : ---> PWD
00000081 44.78248978 [191600] DEBUG [FTPControlSocket] 3 Feb 2010 14:07:49.808 : 257 "/" is current directory.
00000082 46.28266525 [191600] DEBUG [ExFTPConnection] 3 Feb 2010 14:07:51.308 : KeepAlive - testing connection
00000083 46.28271484 [191600] DEBUG [ExFTPConnection] 3 Feb 2010 14:07:51.308 : Testing connection
00000084 46.28277588 [191600] DEBUG [FTPControlSocket] 3 Feb 2010 14:07:51.308 : ---> PWD
00000085 46.33511734 [191600] DEBUG [FTPControlSocket] 3 Feb 2010 14:07:51.370 : 257 "/" is current directory.
00000086 47.83437729 [191600] DEBUG [ExFTPConnection] 3 Feb 2010 14:07:52.870 : KeepAlive - testing connection
00000087 47.83443069 [191600] DEBUG [ExFTPConnection] 3 Feb 2010 14:07:52.870 : Testing connection
00000088 47.83448792 [191600] DEBUG [FTPControlSocket] 3 Feb 2010 14:07:52.870 : ---> PWD
00000089 47.88750839 [191600] DEBUG [FTPControlSocket] 3 Feb 2010 14:07:52.917 : 257 "/" is current directory.
00000090 48.73767090 [191600] NewProjectFromRemoteServerWizard::browseButton_Click
00000091 48.87806702 [191600] RemoteServer::GetLibraryReference
00000092 48.87820435 [191600] BasicServer::CheckConnection
00000093 48.88079834 [191600] DEBUG [FTPControlSocket] 3 Feb 2010 14:07:53.917 : ---> CWD /
00000094 48.93525314 [191600] DEBUG [FTPControlSocket] 3 Feb 2010 14:07:53.964 : 250 CWD command successful.
00000095 48.93533325 [191600] DEBUG [FTPControlSocket] 3 Feb 2010 14:07:53.964 : ---> PWD
00000096 48.98759460 [191600] DEBUG [FTPControlSocket] 3 Feb 2010 14:07:54.011 : 257 "/" is current directory.
00000097 49.12945938 [191600] DEBUG [AsyncProcessor] 3 Feb 2010 14:07:54.151 : Queuing FTP task GetFileInfos()
00000098 49.12951660 [191600] DEBUG [AsyncProcessor] 3 Feb 2010 14:07:54.151 : Starting FTP task processor
00000099 49.13108063 [191600] DEBUG [AsyncProcessor] 3 Feb 2010 14:07:54.167 : Running FTP task GetFileInfos()
00000100 49.13154221 [191600] DEBUG [AsyncTask] 3 Feb 2010 14:07:54.167 : Running task: GetFileInfos()
00000101 49.13443375 [191600] ALL [FTPConnection] 3 Feb 2010 14:07:54.167 : Invoking delegate EnterpriseDT.Net.Ftp.FTPDirectoryListEventHandler -> EnterpriseDT.Net.Ftp.Forms.FTPRemoteFileList.2Ily1AbBM
00000102 49.13449097 [191600] ALL [FTPConnection] 3 Feb 2010 14:07:54.167 : Have GUI control
00000103 49.13458252 [191600] ALL [FTPConnection] 3 Feb 2010 14:07:54.167 : GUI control invocation required
00000104 49.13464355 [191600] ALL [FTPConnection] 3 Feb 2010 14:07:54.167 : Invoking delegate asynchronously using GUI control
00000105 49.13553619 [191600] DEBUG [FTPControlSocket] 3 Feb 2010 14:07:54.167 : ---> SYST
00000106 49.18745041 [191600] DEBUG [FTPControlSocket] 3 Feb 2010 14:07:54.214 : 215 Windows_NT
00000107 49.18805695 [191600] DEBUG [FTPFileFactory] 3 Feb 2010 14:07:54.214 : Selected Windows parser
00000108 49.18827057 [191600] DEBUG [FTPControlSocket] 3 Feb 2010 14:07:54.214 : ---> PWD
00000109 49.24267197 [191600] DEBUG [FTPControlSocket] 3 Feb 2010 14:07:54.276 : 257 "/" is current directory.
00000110 49.24475479 [191600] DEBUG [FTPControlSocket] 3 Feb 2010 14:07:54.276 : ---> PASV
00000111 49.30103302 [191600] DEBUG [FTPControlSocket] 3 Feb 2010 14:07:54.323 : 227 Entering Passive Mode (75,126,30,30,5,133).
00000112 49.30114746 [191600] DEBUG [FTPControlSocket] 3 Feb 2010 14:07:54.323 : Server supplied address=75.126.30.30
00000113 49.30120850 [191600] DEBUG [FTPControlSocket] 3 Feb 2010 14:07:54.323 : Server supplied port=1413
00000114 49.30126190 [191600] DEBUG [FTPControlSocket] 3 Feb 2010 14:07:54.323 : Substituting server supplied IP (75.126.30.30) with remote host IP (75.126.30.30)
00000115 49.30189896 [191600] DEBUG [SecureSocket] 3 Feb 2010 14:07:54.323 : Set timeout=120000
00000116 49.30195618 [191600] DEBUG [SecureSocket] 3 Feb 2010 14:07:54.323 : Set timeout=120000
00000117 49.30201721 [191600] DEBUG [ExFTPControlSocket] 3 Feb 2010 14:07:54.323 : Connecting directly to ftp-server 75.126.30.30:1413
00000118 57.86488724 [191600] NewProjectFromRemoteServerWizard::projectLocationPage_BeforeMoveNext
00000119 61.54081726 [191600] NewProjectFromRemoteServerWizard::projectLocationPage_BeforeMoveNext
00000120 61.54191971 [191600] BasicServer::DirectoryExists /
00000121 61.54211807 [191600] BasicServer::CleanPath
00000122 61.54231262 [191600] BasicServer::GetSlash
00000123 61.54710007 [191600] NewProjectFromRemoteServerWizard::DoPhpInfo
00000124 70.39274597 [191600] ERROR [ExFTPControlSocket] 3 Feb 2010 14:08:15.417 : Failed to create connecting socket : System.Net.Sockets.SocketException: A connection attempt failed because the connected party did not properly respond after a period of time, or established connection failed because connected host has failed to respond 75.126.30.30:1413
00000125 70.39291382 [191600] System.Net.Sockets.SocketException: A connection attempt failed because the connected party did not properly respond after a period of time, or established connection failed because connected host has failed to respond 75.126.30.30:1413
00000126 70.42626953 [191600] at System.Net.Sockets.Socket.DoConnect(EndPoint endPointSnapshot, SocketAddress socketAddress)
00000127 70.42626953 [191600] at System.Net.Sockets.Socket.Connect(EndPoint remoteEP)
00000128 70.42626953 [191600] at YuwuhxlrxUH1tMxTjs7.1duPRLlv5NN9rBbC9na.Connect(EndPoint )
00000129 70.42626953 [191600] at rjDe9lFTaDBMguVappG.Vk6pMhForwq7D6x8Uww.Connect(EndPoint )
00000130 70.42626953 [191600] at jSacldPurte6hG72Lcv.UCsxuYPtR3UCwm2aSSq.Connect(EndPoint )
00000131 70.42626953 [191600] at 2sO4Z5xGnyr2rOdlZYe.1GW0U8xCaZV6K3UM3gt.xlgUNGIJRO(BaseSocket , IPAddress , Int32 )
00000132 70.42626953 [191600] at sDgCPTP6TSR88M4GMdQ.6riRXsPc7Tbb7p6ytVd.xlgUNGIJRO(BaseSocket , IPAddress , Int32 )
00000133 70.42626953 [191600] at 2sO4Z5xGnyr2rOdlZYe.1GW0U8xCaZV6K3UM3gt.UoGHiXdQ2(String , Int32 , BaseSocket )
00000134 70.42699432 [191600] ALL [FTPConnection] 3 Feb 2010 14:08:15.464 : Invoking delegate EnterpriseDT.Net.Ftp.FTPDirectoryListEventHandler -> EnterpriseDT.Net.Ftp.Forms.FTPRemoteFileList.qt5FT75wZ
00000135 70.42704773 [191600] ALL [FTPConnection] 3 Feb 2010 14:08:15.464 : Have GUI control
00000136 70.42710114 [191600] ALL [FTPConnection] 3 Feb 2010 14:08:15.464 : GUI control invocation not required
00000137 70.42715454 [191600] ALL [FTPConnection] 3 Feb 2010 14:08:15.464 : Invoking delegate dynamically
00000138 70.42803955 [191600] ERROR [FTPRemoteFileList] 3 Feb 2010 14:08:15.464 : Error while filling FTPRemoteFileList : System.NullReferenceException: Object reference not set to an instance of an object.
00000139 70.42809296 [191600] System.NullReferenceException: Object reference not set to an instance of an object.
00000140 70.42820740 [191600] at EnterpriseDT.Net.Ftp.Forms.FTPRemoteFileList.qt5FT75wZ(Object , FTPDirectoryListEventArgs )
00000141 70.42828369 [191600] ALL [FTPConnection] 3 Feb 2010 14:08:15.464 : Dynamic delegate invocation complete
00000142 70.42842102 [191600] DEBUG [FTPControlSocket] 3 Feb 2010 14:08:15.464 : ---> CWD /
00000143 70.42891693 [191600] DEBUG [AsyncProcessor] 3 Feb 2010 14:08:15.464 : FTP task completed GetFileInfos()
00000144 70.48225403 [191600] DEBUG [FTPControlSocket] 3 Feb 2010 14:08:15.511 : 250 CWD command successful.
00000145 70.48231506 [191600] DEBUG [FTPControlSocket] 3 Feb 2010 14:08:15.511 : ---> PWD
00000146 70.53451538 [191600] DEBUG [FTPControlSocket] 3 Feb 2010 14:08:15.558 : 257 "/" is current directory.
00000147 70.53467560 [191600] ALL [FTPConnection] 3 Feb 2010 14:08:15.558 : Invoking delegate EnterpriseDT.Net.Ftp.FTPDirectoryEventHandler -> EnterpriseDT.Net.Ftp.Forms.FTPRemoteFileList.bjOPpjEBb
00000148 70.53473663 [191600] ALL [FTPConnection] 3 Feb 2010 14:08:15.558 : Have GUI control
00000149 70.53479004 [191600] ALL [FTPConnection] 3 Feb 2010 14:08:15.558 : GUI control invocation not required
00000150 70.53484344 [191600] ALL [FTPConnection] 3 Feb 2010 14:08:15.558 : Invoking delegate dynamically
00000151 70.53515625 [191600] DEBUG [AsyncProcessor] 3 Feb 2010 14:08:15.558 : Queuing FTP task GetFileInfos()
00000152 70.53520966 [191600] DEBUG [AsyncProcessor] 3 Feb 2010 14:08:15.558 : Starting FTP task processor
00000153 70.53530121 [191600] ALL [FTPConnection] 3 Feb 2010 14:08:15.558 : Dynamic delegate invocation complete
00000154 70.53539276 [191600] DEBUG [AsyncProcessor] 3 Feb 2010 14:08:15.558 : Running FTP task GetFileInfos()
00000155 70.53545380 [191600] DEBUG [AsyncTask] 3 Feb 2010 14:08:15.558 : Running task: GetFileInfos()
00000156 70.53553772 [191600] ALL [FTPConnection] 3 Feb 2010 14:08:15.558 : Invoking delegate EnterpriseDT.Net.Ftp.FTPDirectoryListEventHandler -> EnterpriseDT.Net.Ftp.Forms.FTPRemoteFileList.2Ily1AbBM
00000157 70.53559113 [191600] ALL [FTPConnection] 3 Feb 2010 14:08:15.558 : Have GUI control
00000158 70.53565216 [191600] ALL [FTPConnection] 3 Feb 2010 14:08:15.558 : GUI control invocation not required
00000159 70.53570557 [191600] ALL [FTPConnection] 3 Feb 2010 14:08:15.558 : Invoking delegate dynamically
00000160 70.53578186 [191600] ALL [FTPConnection] 3 Feb 2010 14:08:15.558 : Dynamic delegate invocation complete
00000161 70.53585815 [191600] DEBUG [FTPControlSocket] 3 Feb 2010 14:08:15.558 : ---> PWD
00000162 70.58936310 [191600] DEBUG [FTPControlSocket] 3 Feb 2010 14:08:15.620 : 257 "/" is current directory.
00000163 70.58942413 [191600] DEBUG [FTPControlSocket] 3 Feb 2010 14:08:15.620 : ---> PASV
00000164 70.64200592 [191600] DEBUG [FTPControlSocket] 3 Feb 2010 14:08:15.667 : 227 Entering Passive Mode (75,126,30,30,5,144).
00000165 70.64212036 [191600] DEBUG [FTPControlSocket] 3 Feb 2010 14:08:15.667 : Server supplied address=75.126.30.30
00000166 70.64218140 [191600] DEBUG [FTPControlSocket] 3 Feb 2010 14:08:15.667 : Server supplied port=1424
00000167 70.64223480 [191600] DEBUG [FTPControlSocket] 3 Feb 2010 14:08:15.667 : Substituting server supplied IP (75.126.30.30) with remote host IP (75.126.30.30)
00000168 70.64232635 [191600] DEBUG [SecureSocket] 3 Feb 2010 14:08:15.667 : Set timeout=120000
00000169 70.64238739 [191600] DEBUG [SecureSocket] 3 Feb 2010 14:08:15.667 : Set timeout=120000
00000170 70.64244080 [191600] DEBUG [ExFTPControlSocket] 3 Feb 2010 14:08:15.667 : Connecting directly to ftp-server 75.126.30.30:1424
00000171 91.61577606 [191600] ERROR [ExFTPControlSocket] 3 Feb 2010 14:08:36.651 : Failed to create connecting socket : System.Net.Sockets.SocketException: A connection attempt failed because the connected party did not properly respond after a period of time, or established connection failed because connected host has failed to respond 75.126.30.30:1424
00000172 91.61582184 [191600] System.Net.Sockets.SocketException: A connection attempt failed because the connected party did not properly respond after a period of time, or established connection failed because connected host has failed to respond 75.126.30.30:1424
00000173 91.61595154 [191600] at System.Net.Sockets.Socket.DoConnect(EndPoint endPointSnapshot, SocketAddress socketAddress)
00000174 91.61595154 [191600] at System.Net.Sockets.Socket.Connect(EndPoint remoteEP)
00000175 91.61595154 [191600] at YuwuhxlrxUH1tMxTjs7.1duPRLlv5NN9rBbC9na.Connect(EndPoint )
00000176 91.61595154 [191600] at rjDe9lFTaDBMguVappG.Vk6pMhForwq7D6x8Uww.Connect(EndPoint )
00000177 91.61595154 [191600] at jSacldPurte6hG72Lcv.UCsxuYPtR3UCwm2aSSq.Connect(EndPoint )
00000178 91.61595154 [191600] at 2sO4Z5xGnyr2rOdlZYe.1GW0U8xCaZV6K3UM3gt.xlgUNGIJRO(BaseSocket , IPAddress , Int32 )
00000179 91.61595154 [191600] at sDgCPTP6TSR88M4GMdQ.6riRXsPc7Tbb7p6ytVd.xlgUNGIJRO(BaseSocket , IPAddress , Int32 )
00000180 91.61595154 [191600] at 2sO4Z5xGnyr2rOdlZYe.1GW0U8xCaZV6K3UM3gt.UoGHiXdQ2(String , Int32 , BaseSocket )
00000181 91.61605835 [191600] ALL [FTPConnection] 3 Feb 2010 14:08:36.651 : Invoking delegate EnterpriseDT.Net.Ftp.FTPDirectoryListEventHandler -> EnterpriseDT.Net.Ftp.Forms.FTPRemoteFileList.qt5FT75wZ
00000182 91.61611176 [191600] ALL [FTPConnection] 3 Feb 2010 14:08:36.651 : Have GUI control
00000183 91.61617279 [191600] ALL [FTPConnection] 3 Feb 2010 14:08:36.651 : GUI control invocation not required
00000184 91.61622620 [191600] ALL [FTPConnection] 3 Feb 2010 14:08:36.651 : Invoking delegate dynamically
00000185 91.61633301 [191600] ERROR [FTPRemoteFileList] 3 Feb 2010 14:08:36.651 : Error while filling FTPRemoteFileList : System.NullReferenceException: Object reference not set to an instance of an object.
00000186 91.61638641 [191600] System.NullReferenceException: Object reference not set to an instance of an object.
00000187 91.61647034 [191600] at EnterpriseDT.Net.Ftp.Forms.FTPRemoteFileList.qt5FT75wZ(Object , FTPDirectoryListEventArgs )
00000188 91.61654663 [191600] ALL [FTPConnection] 3 Feb 2010 14:08:36.651 : Dynamic delegate invocation complete
00000189 91.61667633 [191600] DEBUG [AsyncProcessor] 3 Feb 2010 14:08:36.651 : FTP task completed GetFileInfos()
00000190 91.61918640 [191600] DEBUG [FTPControlSocket] 3 Feb 2010 14:08:36.651 : ---> PASV
00000191 91.67214966 [191600] DEBUG [FTPControlSocket] 3 Feb 2010 14:08:36.698 : 227 Entering Passive Mode (75,126,30,30,5,150).
00000192 91.67226410 [191600] DEBUG [FTPControlSocket] 3 Feb 2010 14:08:36.698 : Server supplied address=75.126.30.30
00000193 91.67233276 [191600] DEBUG [FTPControlSocket] 3 Feb 2010 14:08:36.698 : Server supplied port=1430
00000194 91.67239380 [191600] DEBUG [FTPControlSocket] 3 Feb 2010 14:08:36.698 : Substituting server supplied IP (75.126.30.30) with remote host IP (75.126.30.30)
00000195 91.67249298 [191600] DEBUG [SecureSocket] 3 Feb 2010 14:08:36.698 : Set timeout=120000
00000196 91.67255402 [191600] DEBUG [SecureSocket] 3 Feb 2010 14:08:36.698 : Set timeout=120000
00000197 91.67262268 [191600] DEBUG [ExFTPControlSocket] 3 Feb 2010 14:08:36.698 : Connecting directly to ftp-server 75.126.30.30:1430
00000198 112.63869476 [191600] ERROR [ExFTPControlSocket] 3 Feb 2010 14:08:57.667 : Failed to create connecting socket : System.Net.Sockets.SocketException: A connection attempt failed because the connected party did not properly respond after a period of time, or established connection failed because connected host has failed to respond 75.126.30.30:1430
00000199 112.63874817 [191600] System.Net.Sockets.SocketException: A connection attempt failed because the connected party did not properly respond after a period of time, or established connection failed because connected host has failed to respond 75.126.30.30:1430
00000200 112.63888550 [191600] at System.Net.Sockets.Socket.DoConnect(EndPoint endPointSnapshot, SocketAddress socketAddress)
00000201 112.63888550 [191600] at System.Net.Sockets.Socket.Connect(EndPoint remoteEP)
00000202 112.63888550 [191600] at YuwuhxlrxUH1tMxTjs7.1duPRLlv5NN9rBbC9na.Connect(EndPoint )
00000203 112.63888550 [191600] at rjDe9lFTaDBMguVappG.Vk6pMhForwq7D6x8Uww.Connect(EndPoint )
00000204 112.63888550 [191600] at jSacldPurte6hG72Lcv.UCsxuYPtR3UCwm2aSSq.Connect(EndPoint )
00000205 112.63888550 [191600] at 2sO4Z5xGnyr2rOdlZYe.1GW0U8xCaZV6K3UM3gt.xlgUNGIJRO(BaseSocket , IPAddress , Int32 )
00000206 112.63888550 [191600] at sDgCPTP6TSR88M4GMdQ.6riRXsPc7Tbb7p6ytVd.xlgUNGIJRO(BaseSocket , IPAddress , Int32 )
00000207 112.63888550 [191600] at 2sO4Z5xGnyr2rOdlZYe.1GW0U8xCaZV6K3UM3gt.UoGHiXdQ2(String , Int32 , BaseSocket )
00000208 112.63900757 [191600] DEBUG [FTPClient] 3 Feb 2010 14:08:57.667 : Closing source stream
00000209 112.63907623 [191600] ERROR [FTPClient] 3 Feb 2010 14:08:57.667 : Caught exception : System.Net.Sockets.SocketException: A connection attempt failed because the connected party did not properly respond after a period of time, or established connection failed because connected host has failed to respond 75.126.30.30:1430
00000210 112.63912964 [191600] System.Net.Sockets.SocketException: A connection attempt failed because the connected party did not properly respond after a period of time, or established connection failed because connected host has failed to respond 75.126.30.30:1430
00000211 112.63935852 [191600] at System.Net.Sockets.Socket.DoConnect(EndPoint endPointSnapshot, SocketAddress socketAddress)
00000212 112.63935852 [191600] at System.Net.Sockets.Socket.Connect(EndPoint remoteEP)
00000213 112.63935852 [191600] at YuwuhxlrxUH1tMxTjs7.1duPRLlv5NN9rBbC9na.Connect(EndPoint )
00000214 112.63935852 [191600] at rjDe9lFTaDBMguVappG.Vk6pMhForwq7D6x8Uww.Connect(EndPoint )
00000215 112.63935852 [191600] at jSacldPurte6hG72Lcv.UCsxuYPtR3UCwm2aSSq.Connect(EndPoint )
00000216 112.63935852 [191600] at 2sO4Z5xGnyr2rOdlZYe.1GW0U8xCaZV6K3UM3gt.xlgUNGIJRO(BaseSocket , IPAddress , Int32 )
00000217 112.63935852 [191600] at sDgCPTP6TSR88M4GMdQ.6riRXsPc7Tbb7p6ytVd.xlgUNGIJRO(BaseSocket , IPAddress , Int32 )
00000218 112.63935852 [191600] at 2sO4Z5xGnyr2rOdlZYe.1GW0U8xCaZV6K3UM3gt.UoGHiXdQ2(String , Int32 , BaseSocket )
00000219 112.63935852 [191600] at sDgCPTP6TSR88M4GMdQ.6riRXsPc7Tbb7p6ytVd.j2lULdEJyl(String , Int32 )
00000220 112.63935852 [191600] at EnterpriseDT.Net.Ftp.FTPControlSocket.ooC3mZXOZJ()
00000221 112.63935852 [191600] at EnterpriseDT.Net.Ftp.FTPControlSocket.uSd3aSZS9y(FTPConnectMode connectMode)
00000222 112.63935852 [191600] at EnterpriseDT.Net.Ftp.FTPClient.Jct5RFKph(String , Boolean )
00000223 112.63935852 [191600] at EnterpriseDT.Net.Ftp.FTPClient.SrRcBNIYs(Stream , String , Boolean , Boolean )
00000224 112.63945007 [191600] ERROR [FTPClient] 3 Feb 2010 14:08:57.667 : SystemException in Put(Stream,string,bool) : System.Net.Sockets.SocketException: A connection attempt failed because the connected party did not properly respond after a period of time, or established connection failed because connected host has failed to respond 75.126.30.30:1430
00000225 112.63949585 [191600] System.Net.Sockets.SocketException: A connection attempt failed because the connected party did not properly respond after a period of time, or established connection failed because connected host has failed to respond 75.126.30.30:1430
00000226 112.63960266 [191600] at EnterpriseDT.Net.Ftp.FTPClient.SrRcBNIYs(Stream , String , Boolean , Boolean )
00000227 112.63960266 [191600] at EnterpriseDT.Net.Ftp.FTPClient.Put(Stream srcStream, String remoteFile, Boolean append)
00000228 112.63984680 [191600] DEBUG [FTPControlSocket] 3 Feb 2010 14:08:57.667 : Setting socket timeout=500
00000229 112.63991547 [191600] DEBUG [SecureSocket] 3 Feb 2010 14:08:57.667 : Set timeout=500
00000230 112.63996887 [191600] DEBUG [SecureSocket] 3 Feb 2010 14:08:57.667 : Set timeout=500
00000231 113.65721130 [191600] ERROR [SecureNetworkStream] 3 Feb 2010 14:08:58.683 : Socket.Receive() failed : System.Net.Sockets.SocketException: A connection attempt failed because the connected party did not properly respond after a period of time, or established connection failed because connected host has failed to respond
00000232 113.65726471 [191600] System.Net.Sockets.SocketException: A connection attempt failed because the connected party did not properly respond after a period of time, or established connection failed because connected host has failed to respond
00000233 113.66262817 [191600] at System.Net.Sockets.Socket.Receive(Byte[] buffer, Int32 offset, Int32 size, SocketFlags socketFlags)
00000234 113.66262817 [191600] at YuwuhxlrxUH1tMxTjs7.1duPRLlv5NN9rBbC9na.Receive(Byte[] , Int32 , Int32 , SocketFlags )
00000235 113.66262817 [191600] at rjDe9lFTaDBMguVappG.Vk6pMhForwq7D6x8Uww.Receive(Byte[] , Int32 , Int32 , SocketFlags )
00000236 113.66262817 [191600] at roQ7TgFSjQgynBoZyAW.egSqkAFi1HHTx2SSLOG.Read(Byte[] , Int32 , Int32 )
00000237 113.66280365 [191600] ERROR [FTPControlSocket] 3 Feb 2010 14:08:58.698 : Read failed ('' read so far)
00000238 113.66322327 [191600] ERROR [FTPClient] 3 Feb 2010 14:08:58.698 : Exception in ValidateTransferOnError()) : EnterpriseDT.Net.Ftp.ControlChannelIOException: An I/O exception occurred.
00000239 113.66328430 [191600] EnterpriseDT.Net.Ftp.ControlChannelIOException: An I/O exception occurred.
00000240 113.66343689 [191600] at EnterpriseDT.Net.Ftp.FTPControlSocket.KCH2gIWv4()
00000241 113.66343689 [191600] at EnterpriseDT.Net.Ftp.FTPControlSocket.cI13pY9Naj()
00000242 113.66343689 [191600] at EnterpriseDT.Net.Ftp.FTPClient.ValidateTransfer()
00000243 113.66343689 [191600] at EnterpriseDT.Net.Ftp.FTPClient.ValidateTransferOnError()
00000244 113.66350555 [191600] DEBUG [FTPControlSocket] 3 Feb 2010 14:08:58.698 : Setting socket timeout=120000
00000245 113.66355896 [191600] DEBUG [SecureSocket] 3 Feb 2010 14:08:58.698 : Set timeout=120000
00000246 113.66361237 [191600] DEBUG [SecureSocket] 3 Feb 2010 14:08:58.698 : Set timeout=120000
00000247 113.66436005 [191600] ALL [FTPConnection] 3 Feb 2010 14:08:58.698 : Invoking delegate EnterpriseDT.Net.Ftp.FTPFileTransferEventHandler -> EnterpriseDT.Net.Ftp.Forms.FTPRemoteFileList.sHxXshylp
00000248 113.66442108 [191600] ALL [FTPConnection] 3 Feb 2010 14:08:58.698 : Have GUI control
00000249 113.66448212 [191600] ALL [FTPConnection] 3 Feb 2010 14:08:58.698 : GUI control invocation not required
00000250 113.66452789 [191600] ALL [FTPConnection] 3 Feb 2010 14:08:58.698 : Invoking delegate dynamically
00000251 113.66467285 [191600] ALL [FTPConnection] 3 Feb 2010 14:08:58.698 : Dynamic delegate invocation complete
00000252 113.88756561 [191600] DEBUG [ExFTPConnection] 3 Feb 2010 14:08:58.917 : KeepAlive thread finished.
juanc says:
Can you confirm you can connect to this FTP server using another FTP client?
jdales says:
Yes, I can connect via FTP on the command line or Filezilla just fine.
juanc says:
From the log file it is saying it can't connect ot the server. Please note it is connecting to port 1424.
00000170 70.64244080 [191600] DEBUG [ExFTPControlSocket] 3 Feb 2010 14:08:15.667 : Connecting
directly to ftp-server 75.126.30.30:1424
00000171 91.61577606 [191600] ERROR [ExFTPControlSocket] 3 Feb 2010 14:08:36.651 : Failed to create connecting socket : System.Net.Sockets.SocketException: A connection attempt failed because the connected party did not properly respond after a period of time, or established connection failed because connected host has failed to respond 75.126.30.30:1424
00000172 91.61582184 [191600] System.Net.Sockets.SocketException: A connection attempt failed because the connected party did not properly respond after a period of time, or established connection failed because connected host has failed to respond 75.126.30.30:1424
00000173 91.61595154 [191600] at System.Net.Sockets.Socket.DoConnect(EndPoint endPointSnapshot, SocketAddress socketAddress)
jdales says:
I thought I'd update this issue in case any one else runs into this. JCX debugging uses Passive mode when run in FTP mode. Passive mode uses 2 channels control (port 21) and data (server assigned). By default the Windows Firewall doesn't have ports opened for the data channel. I modified the IIS Metabase value for PassivePortRange to specify ports for the data channel and then I opened those ports in the firewall and now things work fine.
juanc says:
I figured that passive more was more firewall friendly than active mode. I could add an option to do active mode as well if that's going to help in certain cases...