by cbkowitz » Tue Aug 17, 2010 10:06 pm
New wrinkle, this works on another PC.
Both PC's are using the same config file, program and dll.
Here's the debug log from the one that works:
DEBUG [ExFTPConnection] 17 Aug 2010 06:57:26.516 : Loading properties from C:\Program Files\FTPMonitorAdmin\ftptempload.ftp
DEBUG [ExFTPConnection] 17 Aug 2010 06:57:26.531 : Loading properties from input-stream
DEBUG [ExFTPConnection] 17 Aug 2010 06:57:26.531 : Parsing properties
DEBUG [FTPConnection] 17 Aug 2010 06:57:26.531 : Set LocalDirectory='\\fs01.trustmark.mhc\apps\cbsbilling\workdirs\Electronic Cases\City Of Boston (2023)'
DEBUG [SecureFTPConnection] 17 Aug 2010 06:57:26.734 : Setting transfer limit of -1 bytes/sec
DEBUG [ExFTPClient] 17 Aug 2010 06:57:26.734 : No limit now set on transfers
DEBUG [SSHFTPClient] 17 Aug 2010 06:57:26.734 : No limit now set on transfers
DEBUG [HTTPClient] 17 Aug 2010 06:57:26.734 : No limit now set on transfers
DEBUG [SFTPMessageStore] 17 Aug 2010 06:57:26.734 : Set timeout=120000
DEBUG [SecureFTPConnection] 17 Aug 2010 06:57:26.734 : Setting useUnencryptedCommands=False
DEBUG [SSLFTPCertificate] 17 Aug 2010 06:57:26.734 : Attempt to load file in PEM format was unsuccessful: C:\PGPKeys\sftptsmk.pfx
DEBUG [SSLFTPCertificate] 17 Aug 2010 06:57:26.734 : Attempt to load file in CER format was unsuccessful: C:\PGPKeys\sftptsmk.pfx
INFO [LicenseProperties] 17 Aug 2010 06:57:26.797 : Licence expiry date: 12/31/9999
INFO [LicenseProperties] 17 Aug 2010 06:57:26.797 : Production license
INFO [LicenseProperties] 17 Aug 2010 06:57:26.813 : Licence expiry date: 12/31/9999
INFO [LicenseProperties] 17 Aug 2010 06:57:26.813 : Production license
DEBUG [SSLFTPClient] 17 Aug 2010 06:57:26.813 : Connecting to 140.241.102.9:20121
DEBUG [SSLFTPControlSocket] 17 Aug 2010 06:57:26.813 : waitOnShutdownSSL=True
DEBUG [HostNameResolver] 17 Aug 2010 06:57:26.828 : Resolving 140.241.102.9
DEBUG [HostNameResolver] 17 Aug 2010 06:57:26.828 : 140.241.102.9 resolved to 140.241.102.9
DEBUG [ExFTPControlSocket] 17 Aug 2010 06:57:26.828 : Connecting directly to ftp-server 140.241.102.9:20121
DEBUG [FTPControlSocket] 17 Aug 2010 06:57:26.891 : Setting socket timeout=120000
DEBUG [FTPControlSocket] 17 Aug 2010 06:57:26.891 : Command encoding=System.Text.ASCIIEncoding
DEBUG [ExFTPControlSocket] 17 Aug 2010 06:57:26.891 : Created control-socket: SocksContext=, ProxySettings=NoProxy, RemoteHost=140.241.102.9, controlPort=20121, timeout=120000
DEBUG [FTPControlSocket] 17 Aug 2010 06:57:26.891 : StrictReturnCodes=False
DEBUG [FTPControlSocket] 17 Aug 2010 06:57:27.703 : 220-SFTPD1 IBM FTP CS V1R10 at BSTNJES.cityhall.boston.cob, 11:55:15 on 2010-08-17.
DEBUG [FTPControlSocket] 17 Aug 2010 06:57:27.719 : 220- ____ ___ _______ __ ___ _____ ____ ___ ____ _____ ___ _ _
DEBUG [FTPControlSocket] 17 Aug 2010 06:57:27.719 : 220- / ___|_ _|_ _\ \ / / / _ \| ___| | __ ) / _ \/ ___|_ _/ _ \| \ | |
DEBUG [FTPControlSocket] 17 Aug 2010 06:57:27.719 : 220- | | | | | | \ V / | | | | |_ | _ \| | | \___ \ | || | | | \| |
DEBUG [FTPControlSocket] 17 Aug 2010 06:57:27.719 : 220- | |___ | | | | | | | |_| | _| | |_) | |_| |___) || || |_| | |\ |
DEBUG [FTPControlSocket] 17 Aug 2010 06:57:27.719 : 220- \____|___| |_| |_| \___/|_| |____/ \___/|____/ |_| \___/|_| \_|
DEBUG [FTPControlSocket] 17 Aug 2010 06:57:27.719 : 220-
DEBUG [FTPControlSocket] 17 Aug 2010 06:57:27.719 : 220- ____ _____ ____ _ _ ____ _____
DEBUG [FTPControlSocket] 17 Aug 2010 06:57:27.734 : 220- / ___|| ____/ ___| | | | _ \| ____|
DEBUG [FTPControlSocket] 17 Aug 2010 06:57:27.734 : 220- \___ \| _|| | | | | | |_) | _|
DEBUG [FTPControlSocket] 17 Aug 2010 06:57:27.734 : 220- ___) | |__| |___| |_| | _ <| |___
DEBUG [FTPControlSocket] 17 Aug 2010 06:57:27.734 : 220- |____/|_____\____|\___/|_| \_\_____|
DEBUG [FTPControlSocket] 17 Aug 2010 06:57:27.734 : 220-
DEBUG [FTPControlSocket] 17 Aug 2010 06:57:27.734 : 220- _____ _____ ____ ____ _____ ______ _____ ____ _____ ____
DEBUG [FTPControlSocket] 17 Aug 2010 06:57:27.734 : 220- | ___|_ _| _ \ / ___|| ____| _ \ \ / /_ _/ ___| ____/ ___|
DEBUG [FTPControlSocket] 17 Aug 2010 06:57:27.734 : 220- | |_ | | | |_) | \___ \| _| | |_) \ \ / / | | | | _| \___ \
DEBUG [FTPControlSocket] 17 Aug 2010 06:57:27.734 : 220- | _| | | | __/ ___) | |___| _ < \ V / | | |___| |___ ___) |
DEBUG [FTPControlSocket] 17 Aug 2010 06:57:27.734 : 220- |_| |_| |_| |____/|_____|_| \_\ \_/ |___\____|_____|____/
DEBUG [FTPControlSocket] 17 Aug 2010 06:57:27.734 : 220-
DEBUG [FTPControlSocket] 17 Aug 2010 06:57:27.734 : 220-
DEBUG [FTPControlSocket] 17 Aug 2010 06:57:27.734 : 220-; ......... Welcome to the CITY of BOSTON SECURE SFTP/SSL site .........
DEBUG [FTPControlSocket] 17 Aug 2010 06:57:27.734 : 220-;
DEBUG [FTPControlSocket] 17 Aug 2010 06:57:27.734 : 220-; This site uses the Explicit SSL/TLS authentication. We are expecting
DEBUG [FTPControlSocket] 17 Aug 2010 06:57:27.734 : 220-; both password and keyring authentification using z/OS system SSL and
DEBUG [FTPControlSocket] 17 Aug 2010 06:57:27.734 : 220-; RACF services. Our server must be a trusted authority to the client.
DEBUG [FTPControlSocket] 17 Aug 2010 06:57:27.797 : 220-; We are using TLS Protocol version 3.
DEBUG [FTPControlSocket] 17 Aug 2010 06:57:27.797 : 220-;
DEBUG [FTPControlSocket] 17 Aug 2010 06:57:27.797 : 220 Connection will close if idle for more than 2 minutes.
DEBUG [FTPControlSocket] 17 Aug 2010 06:57:27.813 : ---> AUTH TLS
DEBUG [FTPControlSocket] 17 Aug 2010 06:57:32.859 : 234 Security environment established - ready for negotiation
DEBUG [SSLFTPControlSocket] 17 Aug 2010 06:57:32.859 : Beginning Ssl3, Tls1 handshake.
DEBUG [SocketController] 17 Aug 2010 06:57:41.313 : Processing hello
DEBUG [SecureSocket] 17 Aug 2010 06:57:41.641 : OnHandshakeComplete(True,null)
DEBUG [SSLFTPControlSocket] 17 Aug 2010 06:57:41.641 : Ssl3, Tls1 handshake complete.
DEBUG [FTPControlSocket] 17 Aug 2010 06:57:41.641 : ---> PBSZ 0
DEBUG [FTPControlSocket] 17 Aug 2010 06:57:41.688 : 200 Protection buffer size accepted
DEBUG [FTPControlSocket] 17 Aug 2010 06:57:41.688 : ---> PROT P
DEBUG [FTPControlSocket] 17 Aug 2010 06:57:41.734 : 200 Data connection protection set to private
DEBUG [FTPControlSocket] 17 Aug 2010 06:57:41.734 : ---> USER SFTPTSMK
DEBUG [FTPControlSocket] 17 Aug 2010 06:57:41.781 : 331 Send password please.
DEBUG [FTPControlSocket] 17 Aug 2010 06:57:41.781 : ---> PASS ********
DEBUG [FTPControlSocket] 17 Aug 2010 06:57:42.172 : 230-;
DEBUG [FTPControlSocket] 17 Aug 2010 06:57:42.359 : 230-; You have successfully logged in to our Secure FTP site and mapped
DEBUG [FTPControlSocket] 17 Aug 2010 06:57:42.359 : 230-; into your home directory. Access restrictions apply.
DEBUG [FTPControlSocket] 17 Aug 2010 06:57:42.359 : 230-;
DEBUG [FTPControlSocket] 17 Aug 2010 06:57:42.359 : 230-; To stop the connection, enter the QUIT (or quit) command.....
DEBUG [FTPControlSocket] 17 Aug 2010 06:57:42.359 : 230-;
DEBUG [FTPControlSocket] 17 Aug 2010 06:57:42.359 : 230-; Please continue..........
DEBUG [FTPControlSocket] 17 Aug 2010 06:57:42.359 : 230-;
DEBUG [FTPControlSocket] 17 Aug 2010 06:57:42.359 : 230 SFTPTSMK is logged on. Working directory is "/u/sftp/trustmark_insurance".
DEBUG [FTPConnection] 17 Aug 2010 06:57:42.359 : Successfully logged in
DEBUG [ExFTPConnection] 17 Aug 2010 06:57:42.375 : Starting KeepAlive thread with period 30s
DEBUG [FTPControlSocket] 17 Aug 2010 06:57:42.375 : ---> TYPE A
DEBUG [FTPControlSocket] 17 Aug 2010 06:57:42.422 : 200 Representation type is Ascii NonPrint
DEBUG [FTPControlSocket] 17 Aug 2010 06:57:42.422 : ---> PWD
DEBUG [FTPControlSocket] 17 Aug 2010 06:57:42.453 : 257 "/u/sftp/trustmark_insurance" is the HFS working directory.
DEBUG [FTPTaskProcessor] 17 Aug 2010 06:57:42.484 : Invoking task on connection 0: [ID=1] GetFileInfos()
DEBUG [FTPTaskProcessor] 17 Aug 2010 06:57:42.484 : Running task on connection 0: [ID=1] GetFileInfos()
DEBUG [FTPControlSocket] 17 Aug 2010 06:57:42.484 : ---> SYST
DEBUG [FTPControlSocket] 17 Aug 2010 06:57:42.531 : 215 UNIX is the operating system of this server. FTP Server is running on z/OS.
DEBUG [FTPFileFactory] 17 Aug 2010 06:57:42.531 : Selected UNIX parser
DEBUG [FTPControlSocket] 17 Aug 2010 06:57:42.531 : ---> PWD
DEBUG [FTPControlSocket] 17 Aug 2010 06:57:42.578 : 257 "/u/sftp/trustmark_insurance" is the HFS working directory.
DEBUG [FTPControlSocket] 17 Aug 2010 06:57:42.703 : ---> PASV
DEBUG [FTPControlSocket] 17 Aug 2010 06:57:42.750 : 227 Entering Passive Mode (140,241,102,9,5,182)
DEBUG [FTPControlSocket] 17 Aug 2010 06:57:42.750 : Server supplied address=140.241.102.9
DEBUG [FTPControlSocket] 17 Aug 2010 06:57:42.750 : Server supplied port=1462
DEBUG [FTPControlSocket] 17 Aug 2010 06:57:42.750 : Substituting server supplied IP (140.241.102.9) with remote host IP (140.241.102.9)
DEBUG [HostNameResolver] 17 Aug 2010 06:57:42.750 : Resolving 140.241.102.9
DEBUG [HostNameResolver] 17 Aug 2010 06:57:42.750 : 140.241.102.9 resolved to 140.241.102.9
DEBUG [ExFTPControlSocket] 17 Aug 2010 06:57:42.750 : Connecting directly to ftp-server 140.241.102.9:1462
DEBUG [ExFTPPassiveDataSocket] 17 Aug 2010 06:57:42.797 : Created passive data-socket: ProxyConnector=
DEBUG [SSLFTPPassiveDataSocket] 17 Aug 2010 06:57:42.797 : Created passive data-socket: Protocol=Ssl3, Tls1, CompatibilityFlags=Standard, UseSSLClosure=True, WaitOnClose=True
DEBUG [FTPControlSocket] 17 Aug 2010 06:57:42.797 : ---> LIST
DEBUG [FTPControlSocket] 17 Aug 2010 06:57:43.156 : 125 List started OK
DEBUG [FTPClient] 17 Aug 2010 06:57:43.156 : Reading ASCII listing data
DEBUG [SSLFTPPassiveDataSocket] 17 Aug 2010 06:57:43.156 : Beginning Ssl3, Tls1 handshake on passive data-channel
DEBUG [SocketController] 17 Aug 2010 06:57:43.312 : Processing hello
DEBUG [SecureSocket] 17 Aug 2010 06:57:43.422 : OnHandshakeComplete(True,null)
DEBUG [SSLFTPPassiveDataSocket] 17 Aug 2010 06:57:43.422 : Handshake complete
DEBUG [SocketController] 17 Aug 2010 06:57:43.422 : OnReceive closing (size == 0)
DEBUG [SocketController] 17 Aug 2010 06:57:43.422 : CloseConnection(e=null)
DEBUG [SocketController] 17 Aug 2010 06:57:43.422 : Shut down socket
DEBUG [SocketController] 17 Aug 2010 06:57:43.422 : Closed socket
DEBUG [TransferBuffer] 17 Aug 2010 06:57:43.422 : Close() called when open
DEBUG [SSLFTPPassiveDataSocket] 17 Aug 2010 06:57:43.422 : Attempt to close unconnected passive data-channel.
DEBUG [FTPClient] 17 Aug 2010 06:57:43.422 : -->total 800
DEBUG [FTPClient] 17 Aug 2010 06:57:43.422 : -->-rw-r----- 1 CTMXMITS SFTPGRP 399629 Aug 12 11:30 BSTN_addendum.txt
DEBUG [FTPControlSocket] 17 Aug 2010 06:57:43.453 : 250 List completed successfully.
DEBUG [FTPClient] 17 Aug 2010 06:57:43.453 : Found 2 listing lines
DEBUG [FTPFileFactory] 17 Aug 2010 06:57:43.469 : Parse() called using culture: Invariant Language (Invariant Country)
DEBUG [FTPFileFactory] 17 Aug 2010 06:57:43.469 : Confirmed format UNIX
DEBUG [ExFTPConnection] 17 Aug 2010 06:58:13.875 : KeepAlive - testing connection
DEBUG [ExFTPConnection] 17 Aug 2010 06:58:13.875 : Testing connection
DEBUG [FTPControlSocket] 17 Aug 2010 06:58:13.875 : ---> PWD
DEBUG [FTPControlSocket] 17 Aug 2010 06:58:13.906 : 257 "/u/sftp/trustmark_insurance" is the HFS working directory.
DEBUG [SocketController] 17 Aug 2010 06:58:19.047 : Dispose()
DEBUG [SocketController] 17 Aug 2010 06:58:19.047 : CloseConnection(e=null)