Timeout in data transfer.

Discussion of Co:Z sftp, a port of OpenSSH sftp for z/OS
Post Reply
magnus
Posts: 26
Joined: Tue Jun 09, 2009 7:11 am
Location: Stockholm, Sweden

Timeout in data transfer.

Post by magnus »

Hi.

I'm having problems with a SFTP transfer.

The problem is that it seems that the transfer is set up OK but the data is not sent, then when we cancel the job and restart it the job is executed OK.

I'm not sure where to look for the problem, I cant find anything in the SYSLOG and nothing in the batchjob when running in -vvv mode.

I'm just hoping that someone on the forum maybe can help me with where to search for where the problem is, I feel pretty lost.

I include the output from the job:

CoZBatchÝN¨: Copyright (C) 2005-2009 Dovetailed Technologies LLC. All rights reserved.
CoZBatchÝN¨: version 1.4.1 2009-10-09
CoZBatchÝD¨: stdin file opened: //DD:STDIN
CoZBatchÝD¨: stdout file opened: //DD:STDOUT
CoZBatchÝD¨: stderr file opened: //DD:STDERR
CoZBatchÝD¨: region size requested = 0K, Actual below/above limit = 11240K / 1442816K
CoZBatchÝD¨: geteuid=2001
CoZBatchÝI¨: executing progname=login-shell="-/bin/sh"
CoZBatchÝD¨: spawning progname=/bin/sh
CoZBatchÝD¨: argvÝ0¨ = -/bin/sh
CoZBatchÝD¨: current environment:
CoZBatchÝD¨: >LIBPATH=/lib:/usr/lib:.<
CoZBatchÝD¨: >_EDC_ADD_ERRNO2=1<
CoZBatchÝD¨: >HOME=/home/af52sftp<
CoZBatchÝD¨: >_BPX_SHAREAS=MUST<
CoZBatchÝD¨: >_BPX_SPAWN_SCRIPT=YES<
CoZBatchÝD¨: >_BPXK_JOBLOG=STDERR<
CoZBatchÝD¨: >PATH=/bin:/usr/bin<
CoZBatchÝD¨: spawned /bin/sh (16777778)
Co:Z cozsftp version: 1.4.1 (5.0p1) 2009-10-09
Copyright (C) Dovetailed Technologies, LLC. 2008. All rights reserved.
Ý00717¨ debug3: connect_to_server arg=/bin/ssh
Ý00717¨ debug3: connect_to_server arg=-oForwardX11 no
Ý00717¨ debug3: connect_to_server arg=-oForwardAgent no
Ý00717¨ debug3: connect_to_server arg=-oClearAllForwardings yes
Ý00717¨ debug3: connect_to_server arg=-oBatchMode=no
Ý00717¨ debug3: connect_to_server arg=-oConnectTimeout=60
Ý00717¨ debug3: connect_to_server arg=-oServerAliveInterval=60
Ý00717¨ debug3: connect_to_server arg=-oStrictHostKeyChecking=no
Ý00717¨ debug3: connect_to_server arg=-v
Ý00717¨ debug3: connect_to_server arg=-v
Ý00717¨ debug3: connect_to_server arg=-v
Ý00717¨ debug3: connect_to_server arg=-obatchmode yes
Ý00717¨ debug3: connect_to_server arg=-xxxxxx
Ý00717¨ debug3: connect_to_server arg=-oProtocol 2
Ý00717¨ debug3: connect_to_server arg=-s
Ý00717¨ debug3: connect_to_server arg=xxx.xxx.xxx.x
Ý00717¨ debug3: connect_to_server arg=sftp
OpenSSH_3.8.1p1, OpenSSL 0.9.7d 17 Mar 2004
debug1: Reading configuration data /etc/ssh/ssh_config
debug3: Seeding PRNG from /usr/lib/ssh/ssh-rand-helper
debug1: Rhosts Authentication disabled, originating port will not be trusted.
debug2: ssh_connect: needpriv 0
debug1: Connecting to xxx.xxx.xxx.x Ýxxx.xxx.xxx.x¨ port 22.
debug2: fd 3 setting O_NONBLOCK
debug1: fd 3 clearing O_NONBLOCK
debug1: Connection established.
debug3: Not a RSA1 key file /home/af52sftp/.ssh/id_rsa.
debug2: key_type_from_name: unknown key type '-----BEGIN'
debug3: key_read: missing keytype
debug3: key_read: missing whitespace
debug3: key_read: missing whitespace
debug3: key_read: missing whitespace
debug3: key_read: missing whitespace
debug3: key_read: missing whitespace
debug3: key_read: missing whitespace
debug3: key_read: missing whitespace
debug3: key_read: missing whitespace
debug3: key_read: missing whitespace
debug3: key_read: missing whitespace
debug3: key_read: missing whitespace
debug3: key_read: missing whitespace
debug3: key_read: missing whitespace
debug2: key_type_from_name: unknown key type '-----END'
debug3: key_read: missing keytype
debug1: identity file /home/af52sftp/.ssh/id_rsa type 1
debug3: Not a RSA1 key file /home/af52sftp/.ssh/id_dsa.
debug2: key_type_from_name: unknown key type '-----BEGIN'
debug3: key_read: missing keytype
debug3: key_read: missing whitespace
debug3: key_read: missing whitespace
debug3: key_read: missing whitespace
debug3: key_read: missing whitespace
debug3: key_read: missing whitespace
debug3: key_read: missing whitespace
debug3: key_read: missing whitespace
debug3: key_read: missing whitespace
debug3: key_read: missing whitespace
debug3: key_read: missing whitespace
debug2: key_type_from_name: unknown key type '-----END'
debug3: key_read: missing keytype
debug1: identity file /home/af52sftp/.ssh/id_dsa type 2
debug1: Remote protocol version 2.0, remote software version 5.0.3.13 SSH Tectia Server
debug1: no match: 5.0.3.13 SSH Tectia Server
debug1: Enabling compatibility mode for protocol 2.0
debug1: Local version string SSH-2.0-OpenSSH_3.8.1p1
debug3: RNG is ready, skipping seeding
debug1: SSH2_MSG_KEXINIT sent
debug1: SSH2_MSG_KEXINIT received
debug2: kex_parse_kexinit: diffie-hellman-group-exchange-sha1,diffie-hellman-group1-sha1
debug2: kex_parse_kexinit: ssh-rsa,ssh-dss
debug2: kex_parse_kexinit: aes128-cbc,3des-cbc,blowfish-cbc,cast128-cbc,arcfour,aes192-cbc,aes256-cbc,rijndael-cbcÖlysator.liu.se,ae
s128-ctr,aes192-ctr,aes256-ctr
debug2: kex_parse_kexinit: aes128-cbc,3des-cbc,blowfish-cbc,cast128-cbc,arcfour,aes192-cbc,aes256-cbc,rijndael-cbcÖlysator.liu.se,ae
s128-ctr,aes192-ctr,aes256-ctr
debug2: kex_parse_kexinit: hmac-md5,hmac-sha1,hmac-ripemd160,hmac-ripemd160Öopenssh.com,hmac-sha1-96,hmac-md5-96
debug2: kex_parse_kexinit: hmac-md5,hmac-sha1,hmac-ripemd160,hmac-ripemd160Öopenssh.com,hmac-sha1-96,hmac-md5-96
debug2: kex_parse_kexinit: none,zlib
debug2: kex_parse_kexinit: none,zlib
debug2: kex_parse_kexinit:
debug2: kex_parse_kexinit:
debug2: kex_parse_kexinit: first_kex_follows 0
debug2: kex_parse_kexinit: reserved 0
debug2: kex_parse_kexinit: diffie-hellman-group1-sha1,extension1-sha1Össh.com
debug2: kex_parse_kexinit: ssh-rsa
debug2: kex_parse_kexinit: 3des-cbc,aes128-cbc
debug2: kex_parse_kexinit: 3des-cbc,aes128-cbc
debug2: kex_parse_kexinit: hmac-md5,hmac-sha1
debug2: kex_parse_kexinit: hmac-md5,hmac-sha1
debug2: kex_parse_kexinit: none,zlib
debug2: kex_parse_kexinit: none,zlib
debug2: kex_parse_kexinit:
debug2: kex_parse_kexinit:
debug2: kex_parse_kexinit: first_kex_follows 0
debug2: kex_parse_kexinit: reserved 0
debug2: mac_init: found hmac-md5
debug1: kex: server->client aes128-cbc hmac-md5 none
debug2: mac_init: found hmac-md5
debug1: kex: client->server aes128-cbc hmac-md5 none
debug2: dh_gen_key: priv key bits set: 135/256
debug2: bits set: 485/1024
debug1: sending SSH2_MSG_KEXDH_INIT
debug1: expecting SSH2_MSG_KEXDH_REPLY
debug3: check_host_in_hostfile: filename /home/af52sftp/.ssh/known_hosts
debug3: check_host_in_hostfile: match line 2
debug1: Host 'xxx.xxx.xxx.x' is known and matches the RSA host key.
debug1: Found key in /home/af52sftp/.ssh/known_hosts:2
debug2: bits set: 503/1024
debug1: ssh_rsa_verify: signature correct
debug2: kex_derive_keys
debug2: set_newkeys: mode 1
debug1: SSH2_MSG_NEWKEYS sent
debug1: expecting SSH2_MSG_NEWKEYS
debug2: set_newkeys: mode 0
debug1: SSH2_MSG_NEWKEYS received
debug1: SSH2_MSG_SERVICE_REQUEST sent
dovetail
Site Admin
Posts: 2025
Joined: Thu Jul 29, 2004 12:12 pm

Post by dovetail »

Co:Z sftp invokes the IBM Ported Tools OpenSSH "ssh" command to do encrypted network communication - it looks like your problem is a hung ssh session based on the trace.

Does it seem to hang in the same place every time?

You might set up a debug version of sshd running on the target server and turn debug3 tracing on to see that the server side is seeing.

If I had to guess, I would probably say that what you have is a network firewall or router issue. Perhaps you can use Wireshark on the network to see what is hanging up the connection.
magnus
Posts: 26
Joined: Tue Jun 09, 2009 7:11 am
Location: Stockholm, Sweden

Post by magnus »

Hi.

Thanks for the reply, I did know that the problem wasn't in Co:Z, but I needed som help in pointing me in the right direction :D

Yes it hangs in the same place every time, but our big problem is that it works when we restart it so it's really hard to search for the error.

I have know discussed this with the receiving part and we will trace this...

Once again, thanks for the reply, I needed support from someone outside my organization!
Post Reply