Data connection problem w SecureFx Beta 2

[willj]

I am trying to do an FTP with Z/os 1.4 FTP w TLS. I've tried a number of different variiations, but they all end with a data connection problem before the directory list.

This is an explicit FTP/SSL with port 621. The following configuration parameter on z/os PASSIVEDATAPORTS (50000,50100) restricts the passive data ports used.

Below is a copy of the SecureFx log.

What's amiss?

willj

i Session 00004 established for session acm.adpc.purdue.edu
i Control connection successfully established.
< 220-FTPTLSD1 IBM FTP CS V1R4 at acm.adpc.purdue.edu, 14:27:59 on 2006-09-07.
< 220 Connection will close if idle for more than 5 minutes.
> AUTH TLS
< 234 Security environment established - ready for negotiation
i Time zone of server could not be determined.
> USER w06ewdj
i Control connection is protected (SSL Explicit).
< 331 Send password please.
> PASS <password>
< 230 W06EWDJ is logged on. Working directory is "W06EWDJ.".
i Remote operating system type is MVS.
> PWD
< 257 "'W06EWDJ.'" is working directory.
> TYPE A
< 200 Representation type is Ascii NonPrint
> PBSZ 0
< 200 Protection buffer size accepted
> PROT P
< 200 Data connection protection set to private
> PASV
< 227 Entering Passive Mode (128,210,29,17,195,126)
> LIST
< 125 List started OK
i Data connection 71AB3B91 could not be established (-2146893032).
< 534 TLS negotiation failed -- data connection closed
< 550 No data sets found.

[tnygren]

Hi Willj,

Thanks for the log from SecureFX.

I just had a couple of questions.

What did the server report in its log file?

Does using 'Port' instead of PASV change the behavior?

This can be changed by clearing any check mark from the 'Use outgoing data connections (PASV)' option in the 'FTP' sub-category under 'Connection' in the 'Session Options'.

[willj]

I tried changing to Port rather than PASV and encountered the same problem.

I turned on detail debugging for the server and here is what I got when attempting this. I will put this into multiple replies since the forum won't accept over 10000 characters

Thanks,

willj


Sep 8 09:43:06 acm ftpd[83886363]: SD1094 spawn_ftps: my pid is 83886363 and my parent's is 284
Sep 8 09:43:06 acm ftpd[83886363]: SD1145 spawn_ftps: tmpmsg is 220-FTPTLSD1 IBM FTP CS V1R4 at acm.adpc.purdue.edu, 09:43:06 on 2006-
Sep 8 09:43:06 acm ftpd[83886363]: SD0489 reply: entered
Sep 8 09:43:06 acm ftpd[83886363]: SD1232 spawn_ftps: tmpmsg is 220 Connection will close if idle for more than 5 minutes.
Sep 8 09:43:06 acm ftpd[83886363]: SD0489 reply: entered
Sep 8 09:43:06 acm ftpd[83886363]: BU0300 logCONN: entered
Sep 8 09:43:06 acm ftpd[83886363]: SD0558 getCONNinfo: entered
Sep 8 09:43:06 acm ftpd[83886363]: BU0863 getRemoteHost: entered
Sep 8 09:43:06 acm ftpd[83886363]: BU0881 getRemoteHost: ftip->remoteHostname dyn-167-088.admin.purdue.edu
Sep 8 09:43:06 acm ftpd[83886363]: EZYFS50I ID=FTPTLSD100000 CONN starts Client IPaddr=128.210.167.88 hostname=dyn-167-088.admin.pur
Sep 8 09:43:06 acm ftpd[83886363]: SD0725 setup_new_pgm: entered
Sep 8 09:43:06 acm ftpd[83886363]: SD0609 applyTraceFilters: entered
Sep 8 09:43:06 acm ftpd[83886363]: SD0609 applyTraceFilters: entered
Sep 8 09:43:06 acm ftpd[83886363]: SD0863 setup_new_pgm: msgcat->_name = /usr/lib/nls/msg/C/ftpdmsg.cat
Sep 8 09:43:06 acm ftpd[83886363]: SD1019 setup_new_pgm: issuing execv
Sep 8 09:43:06 acm ftps[83886363]: SR3457 stat_session: entered
Sep 8 09:43:06 acm ftps[83886363]: GU1000 chkVerRel: entered
Sep 8 09:43:06 acm ftps[83886363]: GU1069 chkVerRel: system information for CPAC: OS/390 version 03 release 14.00 (2086)
Sep 8 09:43:06 acm ftps[83886363]: PR0249 parse_cmd: entered
Sep 8 09:43:06 acm ftps[83886363]: SR0293 clear_reply_sent: entered
Sep 8 09:43:06 acm ftps[83886363]: SR1008 get_command: entered
Sep 8 09:43:06 acm ftps[83886363]: SR1061 get_command: select rc is 1
Sep 8 09:43:06 acm ftps[83886363]: SR1120 get_command: received 10 bytes
Sep 8 09:43:06 acm ftps[83886363]: PR0273 parse_cmd: get_command rc 10 utf8 bytes 0
Sep 8 09:43:06 acm ftps[83886363]: FR0493 authCmdCheck: entered with cmd 210
Sep 8 09:43:06 acm ftps[83886363]: PR1377 parse_ebcdic: entered
Sep 8 09:43:06 acm ftps[83886363]: PR0414 parse_cmd: >>> AUTH TLS
Sep 8 09:43:06 acm ftps[83886363]: PR0750 onearg_cmd: entered
Sep 8 09:43:06 acm ftps[83886363]: FR0126 auth: entered with mechname TLS
Sep 8 09:43:06 acm ftps[83886363]: FR1730 resetSecurity: entered
Sep 8 09:43:06 acm ftps[83886363]: FR1799 ftpAuth: entered
Sep 8 09:43:06 acm ftps[83886363]: FR1833 ftpAuth: keyring = /etc/tls/acmadpc.kdb
Sep 8 09:43:06 acm ftps[83886363]: FR1835 ftpAuth: stash = /etc/tls/acmadpc.sth
Sep 8 09:43:06 acm ftps[83886363]: FR1842 ftpAuth: environment_open()
Sep 8 09:43:06 acm ftps[83886363]: FR1925 ftpAuth: connect as a server
Sep 8 09:43:06 acm ftps[83886363]: FR1975 ftpAuth: environment_init()
Sep 8 09:43:07 acm ftps[83886363]: FR1984 ftpAuth: environment initialization complete
Sep 8 09:43:07 acm ftps[83886363]: SR2821 reply: entered
Sep 8 09:43:07 acm ftps[83886363]: SR2850 reply: --> 234 Security environment established - ready for negotiation
Sep 8 09:43:07 acm ftps[83886363]: SR3544 usingUTF8: entered
Sep 8 09:43:07 acm ftps[83886363]: FR0366 authClient: entered
Sep 8 09:43:07 acm ftps[83886363]: FR0372 authClient: secure_socket_open()
Sep 8 09:43:07 acm ftps[83886363]: FR0420 authClient: cipherspecs = 0A
Sep 8 09:43:07 acm ftps[83886363]: SR3600 getFNDELAY: entered
Sep 8 09:43:07 acm ftps[83886363]: FR0443 authClient: secure_socket_init()
Sep 8 09:43:07 acm ftps[83886363]: FU0374 secureRead: entered
Sep 8 09:43:07 acm ftps[83886363]: FU0374 secureRead: entered
Sep 8 09:43:07 acm ftps[83886363]: FU0437 secureWrite: entered
Sep 8 09:43:07 acm ftps[83886363]: FU0374 secureRead: entered
Sep 8 09:43:08 acm FSUM1221 syslogd: last message repeated 5 times
Sep 8 09:43:08 acm ftps[83886363]: FU0437 secureWrite: entered
Sep 8 09:43:08 acm ftps[83886363]: FU0437 secureWrite: entered
Sep 8 09:43:08 acm ftps[83886363]: SR3635 setFNDELAY: entered
Sep 8 09:43:08 acm ftps[83886363]: FU0599 tlsLevel: entered
Sep 8 09:43:08 acm ftps[83886363]: GU3777 checkSpec: entered with 0A (0,3)
Sep 8 09:43:08 acm ftps[83886363]: FU0653 tlsLevel: using TLSV1 with SSL_3DES_SHA (0A)
Sep 8 09:43:08 acm ftps[83886363]: FR0949 getUserid: entered
Sep 8 09:43:08 acm ftps[83886363]: FR0974 getUserid: no client certificate available
Sep 8 09:43:08 acm ftps[83886363]: BU0514 logSECURE: entered
Sep 8 09:43:08 acm ftps[83886363]: EZYFS54I ID=FTPTLSD100000 SECURE OK Mechanism=TLS
Sep 8 09:43:08 acm ftps[83886363]: PR1032 onearg_cmd: returning
Sep 8 09:43:08 acm ftps[83886363]: SR0293 clear_reply_sent: entered
Sep 8 09:43:08 acm ftps[83886363]: SR1008 get_command: entered
Sep 8 09:43:08 acm ftps[83886363]: SR1061 get_command: select rc is 1
Sep 8 09:43:08 acm ftps[83886363]: FU0374 secureRead: entered
Sep 8 09:43:08 acm ftps[83886363]: FU0374 secureRead: entered
Sep 8 09:43:08 acm ftps[83886363]: SR1120 get_command: received 14 bytes
Sep 8 09:43:08 acm ftps[83886363]: PR0273 parse_cmd: get_command rc 14 utf8 bytes 0
Sep 8 09:43:08 acm ftps[83886363]: FR0493 authCmdCheck: entered with cmd 19
Sep 8 09:43:08 acm ftps[83886363]: PR1377 parse_ebcdic: entered
Sep 8 09:43:08 acm ftps[83886363]: PR0414 parse_cmd: >>> USER w06ewdj
Sep 8 09:43:08 acm ftps[83886363]: PR0750 onearg_cmd: entered
Sep 8 09:43:08 acm ftps[83886363]: RA1300 user: entered with username 'w06ewdj'.
Sep 8 09:43:08 acm ftps[83886363]: RA1698 user: username = W06EWDJ, authusername =
Sep 8 09:43:08 acm ftps[83886363]: SR2821 reply: entered
Sep 8 09:43:08 acm ftps[83886363]: SR2850 reply: --> 331 Send password please.
Sep 8 09:43:08 acm ftps[83886363]: SR3544 usingUTF8: entered
Sep 8 09:43:08 acm ftps[83886363]: FU0437 secureWrite: entered
Sep 8 09:43:08 acm ftps[83886363]: PR1032 onearg_cmd: returning
Sep 8 09:43:08 acm ftps[83886363]: SR0293 clear_reply_sent: entered
Sep 8 09:43:08 acm ftps[83886363]: SR1008 get_command: entered
Sep 8 09:43:13 acm ftps[83886363]: SR1061 get_command: select rc is 1
Sep 8 09:43:13 acm ftps[83886363]: FU0374 secureRead: entered
Sep 8 09:43:13 acm ftps[83886363]: FU0374 secureRead: entered
Sep 8 09:43:13 acm ftps[83886363]: SR1120 get_command: received 14 bytes
Sep 8 09:43:13 acm ftps[83886363]: PR0273 parse_cmd: get_command rc 14 utf8 bytes 0
Sep 8 09:43:13 acm ftps[83886363]: FR0493 authCmdCheck: entered with cmd 26
Sep 8 09:43:13 acm ftps[83886363]: PR1377 parse_ebcdic: entered
Sep 8 09:43:13 acm ftps[83886363]: PR0419 parse_cmd: >>> PASS ******
Sep 8 09:43:13 acm ftps[83886363]: PR0750 onearg_cmd: entered
Sep 8 09:43:13 acm ftps[83886363]: RA0448 pass: entered
Sep 8 09:43:13 acm ftps[83886363]: SR3457 stat_session: entered
Sep 8 09:43:13 acm ftps[83886363]: RA0251 ftpinitgps: entered
Sep 8 09:43:13 acm ftps[83886363]: RA1212 pass: Using MVS for initial working directory
Sep 8 09:43:13 acm ftps[83886363]: RA1217 pass: gettsop failed - safrc: 4, racfrc: 8, racfre: 8.
Sep 8 09:43:13 acm ftps[83886363]: PR1032 onearg_cmd: returning
Sep 8 09:43:13 acm ftps[83886363]: BU0126 logACCESS: entered
Sep 8 09:43:13 acm ftps[83886363]: EZYFS56I ID=FTPTLSD100000 ACCESS OK USERID=W06EWDJ
Sep 8 09:43:13 acm ftps[83886363]: RA1775 loginReply: entered
Sep 8 09:43:13 acm ftps[83886363]: RA2288 user_options: entered
Sep 8 09:43:13 acm ftps[83886363]: RA2295 user_options: gettsop() failed - safrc: 4, racfrc: 8, racfre: 8.
Sep 8 09:43:13 acm ftps[83886363]: SR2821 reply: entered
Sep 8 09:43:13 acm ftps[83886363]: SR2850 reply: --> 230 W06EWDJ is logged on. Working directory is "W06EWDJ.".

[willj]

Sep 8 09:43:13 acm ftps[83886363]: SR3544 usingUTF8: entered
Sep 8 09:43:13 acm ftps[83886363]: FU0437 secureWrite: entered
Sep 8 09:43:13 acm ftps[83886363]: SR0293 clear_reply_sent: entered
Sep 8 09:43:13 acm ftps[83886363]: SR1008 get_command: entered
Sep 8 09:43:13 acm ftps[83886363]: SR1061 get_command: select rc is 1
Sep 8 09:43:13 acm ftps[83886363]: FU0374 secureRead: entered
Sep 8 09:43:13 acm ftps[83886363]: FU0374 secureRead: entered
Sep 8 09:43:13 acm ftps[83886363]: SR1120 get_command: received 6 bytes
Sep 8 09:43:13 acm ftps[83886363]: PR0273 parse_cmd: get_command rc 6 utf8 bytes 0
Sep 8 09:43:13 acm ftps[83886363]: FR0493 authCmdCheck: entered with cmd 38
Sep 8 09:43:13 acm ftps[83886363]: PR1377 parse_ebcdic: entered
Sep 8 09:43:13 acm ftps[83886363]: PR0414 parse_cmd: >>> SYST
Sep 8 09:43:13 acm ftps[83886363]: PR0590 noarg_cmd: entered
Sep 8 09:43:13 acm ftps[83886363]: SR2821 reply: entered
Sep 8 09:43:13 acm ftps[83886363]: SR2850 reply: --> 215 MVS is the operating system of this server. FTP Server is running on z/OS.
Sep 8 09:43:13 acm ftps[83886363]: SR3544 usingUTF8: entered
Sep 8 09:43:13 acm ftps[83886363]: FU0437 secureWrite: entered
Sep 8 09:43:13 acm ftps[83886363]: SR0293 clear_reply_sent: entered
Sep 8 09:43:13 acm ftps[83886363]: SR1008 get_command: entered
Sep 8 09:43:13 acm ftps[83886363]: SR1061 get_command: select rc is 1
Sep 8 09:43:13 acm ftps[83886363]: FU0374 secureRead: entered
Sep 8 09:43:13 acm ftps[83886363]: FU0374 secureRead: entered
Sep 8 09:43:13 acm ftps[83886363]: SR1120 get_command: received 5 bytes
Sep 8 09:43:13 acm ftps[83886363]: PR0273 parse_cmd: get_command rc 5 utf8 bytes 0
Sep 8 09:43:13 acm ftps[83886363]: FR0493 authCmdCheck: entered with cmd 28
Sep 8 09:43:13 acm ftps[83886363]: PR1377 parse_ebcdic: entered
Sep 8 09:43:13 acm ftps[83886363]: PR0414 parse_cmd: >>> PWD
Sep 8 09:43:13 acm ftps[83886363]: PR0590 noarg_cmd: entered
Sep 8 09:43:13 acm ftps[83886363]: RD2071 pwd: entered
Sep 8 09:43:13 acm ftps[83886363]: SR2821 reply: entered
Sep 8 09:43:13 acm ftps[83886363]: SR2850 reply: --> 257 "'W06EWDJ.'" is working directory.
Sep 8 09:43:13 acm ftps[83886363]: SR3544 usingUTF8: entered
Sep 8 09:43:13 acm ftps[83886363]: FU0437 secureWrite: entered
Sep 8 09:43:13 acm ftps[83886363]: SR0293 clear_reply_sent: entered
Sep 8 09:43:13 acm ftps[83886363]: SR1008 get_command: entered
Sep 8 09:43:13 acm ftps[83886363]: SR1061 get_command: select rc is 1
Sep 8 09:43:13 acm ftps[83886363]: FU0374 secureRead: entered
Sep 8 09:43:13 acm ftps[83886363]: FU0374 secureRead: entered
Sep 8 09:43:13 acm ftps[83886363]: SR1120 get_command: received 8 bytes
Sep 8 09:43:13 acm ftps[83886363]: PR0273 parse_cmd: get_command rc 8 utf8 bytes 0
Sep 8 09:43:13 acm ftps[83886363]: FR0493 authCmdCheck: entered with cmd 40
Sep 8 09:43:13 acm ftps[83886363]: PR1377 parse_ebcdic: entered
Sep 8 09:43:13 acm ftps[83886363]: PR0414 parse_cmd: >>> TYPE A
Sep 8 09:43:13 acm ftps[83886363]: PR1260 type_cmd: entered
Sep 8 09:43:13 acm ftps[83886363]: RT1806 xtype: entered with type 'A' dataformat ' ' opt1 ' ' opt2 ' ' opt3 ' '
Sep 8 09:43:13 acm ftps[83886363]: SR2821 reply: entered
Sep 8 09:43:13 acm ftps[83886363]: SR2850 reply: --> 200 Representation type is Ascii NonPrint
Sep 8 09:43:13 acm ftps[83886363]: SR3544 usingUTF8: entered
Sep 8 09:43:13 acm ftps[83886363]: FU0437 secureWrite: entered
Sep 8 09:43:13 acm ftps[83886363]: SR0293 clear_reply_sent: entered
Sep 8 09:43:13 acm ftps[83886363]: SR1008 get_command: entered
Sep 8 09:43:16 acm ftps[83886363]: SR1061 get_command: select rc is 1
Sep 8 09:43:16 acm ftps[83886363]: FU0374 secureRead: entered
Sep 8 09:43:16 acm ftps[83886363]: FU0374 secureRead: entered
Sep 8 09:43:16 acm ftps[83886363]: SR1120 get_command: received 8 bytes
Sep 8 09:43:16 acm ftps[83886363]: PR0273 parse_cmd: get_command rc 8 utf8 bytes 0
Sep 8 09:43:16 acm ftps[83886363]: FR0493 authCmdCheck: entered with cmd 212
Sep 8 09:43:16 acm ftps[83886363]: PR1377 parse_ebcdic: entered
Sep 8 09:43:16 acm ftps[83886363]: PR0414 parse_cmd: >>> PBSZ 0
Sep 8 09:43:16 acm ftps[83886363]: PR0750 onearg_cmd: entered
Sep 8 09:43:16 acm ftps[83886363]: FR1041 pbsz: entered with parm 0
Sep 8 09:43:16 acm ftps[83886363]: SR2821 reply: entered
Sep 8 09:43:16 acm ftps[83886363]: SR2850 reply: --> 200 Protection buffer size accepted
Sep 8 09:43:16 acm ftps[83886363]: SR3544 usingUTF8: entered
Sep 8 09:43:16 acm ftps[83886363]: FU0437 secureWrite: entered
Sep 8 09:43:16 acm ftps[83886363]: PR1032 onearg_cmd: returning
Sep 8 09:43:16 acm ftps[83886363]: SR0293 clear_reply_sent: entered
Sep 8 09:43:16 acm ftps[83886363]: SR1008 get_command: entered
Sep 8 09:43:16 acm ftps[83886363]: SR1061 get_command: select rc is 1
Sep 8 09:43:16 acm ftps[83886363]: FU0374 secureRead: entered
Sep 8 09:43:16 acm ftps[83886363]: FU0374 secureRead: entered
Sep 8 09:43:16 acm ftps[83886363]: SR1120 get_command: received 8 bytes
Sep 8 09:43:16 acm ftps[83886363]: PR0273 parse_cmd: get_command rc 8 utf8 bytes 0
Sep 8 09:43:16 acm ftps[83886363]: FR0493 authCmdCheck: entered with cmd 213
Sep 8 09:43:16 acm ftps[83886363]: PR1377 parse_ebcdic: entered
Sep 8 09:43:16 acm ftps[83886363]: PR0414 parse_cmd: >>> PROT P
Sep 8 09:43:16 acm ftps[83886363]: PR0750 onearg_cmd: entered
Sep 8 09:43:16 acm ftps[83886363]: FR1130 prot: entered with parm P
Sep 8 09:43:16 acm ftps[83886363]: SR2821 reply: entered
Sep 8 09:43:16 acm ftps[83886363]: SR2850 reply: --> 200 Data connection protection set to private
Sep 8 09:43:16 acm ftps[83886363]: SR3544 usingUTF8: entered
Sep 8 09:43:16 acm ftps[83886363]: FU0437 secureWrite: entered
Sep 8 09:43:16 acm ftps[83886363]: PR1032 onearg_cmd: returning
Sep 8 09:43:16 acm ftps[83886363]: SR0293 clear_reply_sent: entered
Sep 8 09:43:16 acm ftps[83886363]: SR1008 get_command: entered
Sep 8 09:43:16 acm ftps[83886363]: SR1061 get_command: select rc is 1
Sep 8 09:43:16 acm ftps[83886363]: FU0374 secureRead: entered
Sep 8 09:43:16 acm ftps[83886363]: FU0374 secureRead: entered
Sep 8 09:43:16 acm ftps[83886363]: SR1120 get_command: received 27 bytes
Sep 8 09:43:16 acm ftps[83886363]: PR0273 parse_cmd: get_command rc 27 utf8 bytes 0
Sep 8 09:43:16 acm ftps[83886363]: FR0493 authCmdCheck: entered with cmd 108
Sep 8 09:43:16 acm ftps[83886363]: PR1377 parse_ebcdic: entered
Sep 8 09:43:16 acm ftps[83886363]: PR0414 parse_cmd: >>> PORT 128,210,167,88,6,208
Sep 8 09:43:16 acm ftps[83886363]: PR0750 onearg_cmd: entered
Sep 8 09:43:16 acm ftps[83886363]: SR2129 port: entered with hostaddr 80d2a758 port 1744
Sep 8 09:43:16 acm ftps[83886363]: SR2821 reply: entered
Sep 8 09:43:16 acm ftps[83886363]: SR2850 reply: --> 200 Port request OK.
Sep 8 09:43:16 acm ftps[83886363]: SR3544 usingUTF8: entered
Sep 8 09:43:16 acm ftps[83886363]: FU0437 secureWrite: entered
Sep 8 09:43:16 acm ftps[83886363]: PR1032 onearg_cmd: returning
Sep 8 09:43:16 acm ftps[83886363]: SR0293 clear_reply_sent: entered
Sep 8 09:43:16 acm ftps[83886363]: SR1008 get_command: entered
Sep 8 09:43:16 acm ftps[83886363]: SR1061 get_command: select rc is 1
Sep 8 09:43:16 acm ftps[83886363]: FU0374 secureRead: entered
Sep 8 09:43:16 acm ftps[83886363]: FU0374 secureRead: entered
Sep 8 09:43:16 acm ftps[83886363]: SR1120 get_command: received 6 bytes
Sep 8 09:43:16 acm ftps[83886363]: PR0273 parse_cmd: get_command rc 6 utf8 bytes 0
Sep 8 09:43:16 acm ftps[83886363]: FR0493 authCmdCheck: entered with cmd 105
Sep 8 09:43:16 acm ftps[83886363]: PR1377 parse_ebcdic: entered
Sep 8 09:43:16 acm ftps[83886363]: PR0414 parse_cmd: >>> LIST
Sep 8 09:43:16 acm ftps[83886363]: PR0750 onearg_cmd: entered
Sep 8 09:43:16 acm ftps[83886363]: RD1301 list: entered with command LIST, pathname "".
Sep 8 09:43:16 acm ftps[83886363]: MR0679 genList: entered
Sep 8 09:43:16 acm ftps[83886363]: MR0728 genList: routine entered with command LIST, pathname "".
Sep 8 09:43:16 acm ftps[83886363]: MR1077 genList: built dsn 'W06EWDJ.*', state 3.
Sep 8 09:43:16 acm ftps[83886363]: MR4184 psList: entered with command LIST, fulldsname "W06EWDJ.*".
Sep 8 09:43:16 acm ftps[83886363]: MR4428 psList: Filter Key >> W06EWDJ.** <<
Sep 8 09:43:16 acm ftps[83886363]: MR4481 psList: RCode 0 ReCode 0 from CSI mod
Sep 8 09:43:16 acm ftps[83886363]: MR4516 psList: csiusdln = 1256
Sep 8 09:43:16 acm ftps[83886363]: MR4518 psList: csiresum =
Sep 8 09:43:16 acm ftps[83886363]: MR5083 psListD: routine entered with command LIST
Sep 8 09:43:16 acm ftps[83886363]: GU1321 get_recfm_values: entered
Sep 8 09:43:16 acm ftps[83886363]: SR0481 data_connect: entered

[willj]

Sep 8 09:43:16 acm ftps[83886363]: SR0900 data_connect: connection established for socket 9
Sep 8 09:43:16 acm ftps[83886363]: SR2821 reply: entered
Sep 8 09:43:16 acm ftps[83886363]: SR2850 reply: --> 125 List started OK
Sep 8 09:43:16 acm ftps[83886363]: SR3544 usingUTF8: entered
Sep 8 09:43:16 acm ftps[83886363]: FU0437 secureWrite: entered
Sep 8 09:43:16 acm ftps[83886363]: FU0244 protDataConn: entered
Sep 8 09:43:16 acm ftps[83886363]: FU0250 protDataConn: secure_socket_open()
Sep 8 09:43:16 acm ftps[83886363]: SR3600 getFNDELAY: entered
Sep 8 09:43:16 acm ftps[83886363]: FU0334 protDataConn: secure_socket_init()
Sep 8 09:43:16 acm ftps[83886363]: FU0374 secureRead: entered
Sep 8 09:43:16 acm ftps[83886363]: FU0374 secureRead: entered
Sep 8 09:43:16 acm ftps[83886363]: FU0437 secureWrite: entered
Sep 8 09:43:16 acm ftps[83886363]: FU0374 secureRead: entered
Sep 8 09:43:16 acm ftps[83886363]: FU0339 protDataConn: secure_socket_init() failed with rc = 420 (Socket closed by remote partner)
Sep 8 09:43:16 acm ftps[83886363]: FR0856 endSecureConn: entered
Sep 8 09:43:16 acm ftps[83886363]: SR2821 reply: entered
Sep 8 09:43:16 acm ftps[83886363]: SR2850 reply: --> 534 TLS negotiation failed -- data connection closed
Sep 8 09:43:16 acm ftps[83886363]: SR3544 usingUTF8: entered
Sep 8 09:43:16 acm ftps[83886363]: FU0437 secureWrite: entered
Sep 8 09:43:16 acm ftps[83886363]: SR0317 data_close: entered (ps=-1, ds=9)
Sep 8 09:43:16 acm ftps[83886363]: SR0419 data_close: close(ds 9)
Sep 8 09:43:16 acm ftps[83886363]: MR5083 psListD: routine entered with command LIST
Sep 8 09:43:16 acm ftps[83886363]: MR5624 psListD: end of list and no matches found.
Sep 8 09:43:16 acm ftps[83886363]: SR2821 reply: entered
Sep 8 09:43:16 acm ftps[83886363]: SR2850 reply: --> 550 No data sets found.
Sep 8 09:43:16 acm ftps[83886363]: SR3544 usingUTF8: entered
Sep 8 09:43:16 acm ftps[83886363]: FU0437 secureWrite: entered
Sep 8 09:43:16 acm ftps[83886363]: PR1032 onearg_cmd: returning
Sep 8 09:43:16 acm ftps[83886363]: SR0293 clear_reply_sent: entered
Sep 8 09:43:16 acm ftps[83886363]: SR1008 get_command

[tnygren]

Hi Willj,

Is this log from the server for the PASV or PORT attempt?

I'm thinking that this is something that our developers may have to look into so I would like to be able to give them a full set of logs.

Would it be possible to get a PORT attempt log from SecureFX and the PORT or PASV log (whichever the log you posted file is not)?

It may be easier to attach the log file as a text document to your post or you can send the logs to support@vandyke.com with a subject for the email of ATTN: Teresa Forum Thread 1686.

[willj]

Hi,

The previous log I sent was for PORT (Set use outgoing data connections was not checked).

I have created another log this morning with set Use Outgoing data connection checked). I've attached the log file.

Let me know if you need something else.

willj

[tnygren]

Hi Willj,

This is exactly what I needed!

I have forwarded this information on to our developers to find out what is happening so it can be resolved.

As soon as a resolution is found, a post will be made here.

[tnygren]

Hi Willj,

Our developers had a couple of questions.

Do any other FTP/SSL clients work to this server?

If so, could you tell me which ones?

Would it be possible to get access to this machine?

The account would only need minimal permissions and can be rooted somewhere with no files available. All we need is the ability to issue the AUTH TLS & LIST commands to the server.

If this would be possible, could you email this information to me using the information in my previous post?

[willj]

Hi,

I've successfully done FTP with TLS to our z/os 1.4 server with the following clients: z/os 1.4 client, Host Explorer 11 Ftp, WS_FTP PRO, Core FTP Lite, and Lftp.

I'll contact you by email regarding your other request.

Thanks,

willj

[tnygren]

Hi Willj,

I just received your email and responded.

Please let me know if you do not get my message.

Thank you for the list of clients that are working!