I have a Windows client app using libssh2-1.2.3 that seems to be unable to connect to a remote server (OpenSSH 5.2) to do SFTP, it fails on the server side with:
dispatch_protocol_error: type 90 seq 4
The client side reports:
[libssh2] 44.093943 Transport: Disconnect(7): Service not requested or authenticated(en)
[libssh2] 44.093943 Failure Event: -13 - socket disconnect
Does anyone have any more details on what this means? (configuration perhaps?)
--- Here's the sshd log: debug3: fd 5 is not O_NONBLOCK debug1: Server will not fork when running in debugging mode. debug3: send_rexec_state: entering fd = 8 config len 414 debug3: ssh_msg_send: type 0 debug3: send_rexec_state: done debug1: rexec start in 5 out 5 newsock 5 pipe -1 sock 8 debug1: inetd sockets after dupping: 3, 3 Connection from 10.1.72.50 port 53813 debug1: Client protocol version 2.0; client software version libssh2_1.2.3 debug1: no match: libssh2_1.2.3 debug1: Enabling compatibility mode for protocol 2.0 debug1: Local version string SSH-2.0-OpenSSH_5.2 debug2: fd 3 setting O_NONBLOCK debug3: privsep user:group 71:65 debug1: permanently_set_uid: 71/65 debug1: list_hostkey_types: ssh-rsa,ssh-dss debug1: SSH2_MSG_KEXINIT sent debug2: Network child is on pid 29175 debug3: preauth child monitor started debug3: mm_request_receive entering debug1: SSH2_MSG_KEXINIT received debug2: kex_parse_kexinit: diffie-hellman-group-exchange-sha256,diffie-hellman-group-exchange-sha1,diffie-hellman-group14-sha1, diffie-hellman-group1-sha1 debug2: kex_parse_kexinit: ssh-rsa,ssh-dss debug2: kex_parse_kexinit: aes128-ctr,aes192-ctr,aes256-ctr,arcfour256,arcfour128,aes128-cbc,3des-cbc,blowfish-cbc,cast128-cbc, aes192-cbc,aes256-cbc,arcfour,rijndael-cbc_at_lysator.liu.se debug2: kex_parse_kexinit: aes128-ctr,aes192-ctr,aes256-ctr,arcfour256,arcfour128,aes128-cbc,3des-cbc,blowfish-cbc,cast128-cbc, aes192-cbc,aes256-cbc,arcfour,rijndael-cbc_at_lysator.liu.se debug2: kex_parse_kexinit: hmac-md5,hmac-sha1,umac-64_at_openssh.com,hmac-ripemd160,hmac-ripemd160_at_openssh.com,hmac-sha1-96,hmac-m d5-96 debug2: kex_parse_kexinit: hmac-md5,hmac-sha1,umac-64_at_openssh.com,hmac-ripemd160,hmac-ripemd160_at_openssh.com,hmac-sha1-96,hmac-m d5-96 debug2: kex_parse_kexinit: none,zlib_at_openssh.com debug2: kex_parse_kexinit: none,zlib_at_openssh.com 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-group14-sha1,diffie-hellman-group-exchange-sha1,diffie-hellman-group1-sha1 debug2: kex_parse_kexinit: ssh-rsa,ssh-dss debug2: kex_parse_kexinit: aes128-ctr,aes192-ctr,aes256-ctr,aes256-cbc,rijndael-cbc_at_lysator.liu.se,aes192-cbc,aes128-cbc,blowfi sh-cbc,arcfour128,arcfour,cast128-cbc,3des-cbc debug2: kex_parse_kexinit: aes128-ctr,aes192-ctr,aes256-ctr,aes256-cbc,rijndael-cbc_at_lysator.liu.se,aes192-cbc,aes128-cbc,blowfi sh-cbc,arcfour128,arcfour,cast128-cbc,3des-cbc debug2: kex_parse_kexinit: hmac-sha1,hmac-sha1-96,hmac-md5,hmac-md5-96,hmac-ripemd160,hmac-ripemd160_at_openssh.com debug2: kex_parse_kexinit: hmac-sha1,hmac-sha1-96,hmac-md5,hmac-md5-96,hmac-ripemd160,hmac-ripemd160_at_openssh.com debug2: kex_parse_kexinit: none debug2: kex_parse_kexinit: none debug2: kex_parse_kexinit: debug2: kex_parse_kexinit: debug2: kex_parse_kexinit: first_kex_follows 0 debug2: kex_parse_kexinit: reserved 0 debug2: mac_setup: found hmac-sha1 debug1: kex: client->server aes128-ctr hmac-sha1 none debug2: mac_setup: found hmac-sha1 debug1: kex: server->client aes128-ctr hmac-sha1 none debug2: dh_gen_key: priv key bits set: 157/320 debug2: bits set: 1040/2048 debug1: expecting SSH2_MSG_KEXDH_INIT debug2: bits set: 1016/2048 debug3: mm_key_sign entering debug3: mm_request_send entering: type 4 debug3: mm_key_sign: waiting for MONITOR_ANS_SIGN debug3: mm_request_receive_expect entering: type 5 debug3: mm_request_receive entering debug3: monitor_read: checking request 4 debug3: mm_answer_sign debug3: mm_answer_sign: signature 0x7f5746305010(271) debug3: mm_request_send entering: type 5 debug2: monitor_read: 4 used once, disabling now debug3: mm_request_receive entering 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: KEX done dispatch_protocol_error: type 90 seq 4 The client trace looks like this: [libssh2] 44.057943 SFTP: Initializing SFTP subsystem [libssh2] 44.057943 Conn: Allocated new channel ID#0 [libssh2] 44.057943 Conn: Opening Channel - win 65536 pack 32768 => libssh2_transport_write plain (24 bytes) 0000: 5A 00 00 00 07 73 65 73 73 69 6F 6E 00 00 00 00 : Z....session.... 0010: 00 01 00 00 00 00 80 00 : ........ => libssh2_transport_write send() (68 bytes) 0000: C7 83 17 24 26 30 30 D2 B6 95 56 2E 4F 82 46 BC : ...$&00...V.O.F. 0010: 96 46 66 5E 9A 5A 17 38 98 6C A1 B3 7A 05 AF 91 : .Ff^.Z.8.l..z... 0020: 33 57 50 EC 0F 73 51 CE B5 EA 31 51 C2 7F C6 2D : 3WP..sQ...1Q...- 0030: DB 59 68 2F D4 6C 21 E8 AF CC 58 1A 5A E3 39 71 : .Yh/.l!...X.Z.9q 0040: FD EB AC 50 : ...P [libssh2] 44.058943 Transport: Looking for packet of type: 91 [libssh2] 44.058943 Transport: Looking for packet of type: 92 [libssh2] 44.058943 Failure Event: -37 - Would block [libssh2] 44.058943 Failure Event: -37 - Would block starting up channel [libssh2] 44.093943 Transport: Looking for packet of type: 91 [libssh2] 44.093943 Transport: Looking for packet of type: 92 => libssh2_transport_read() raw (116 bytes) 0000: 08 0A B0 5C E3 B6 7C DD 97 2E E3 6F C2 8C A9 DC : ...\..|....o.... 0010: 1F A7 3C 24 FA 52 03 5A 69 CC 74 F5 2E 4C AA 32 : ..<$.R.Zi.t..L.2 0020: 13 4D 70 9E AF C8 D8 5C 33 8E 74 E4 FC CD 6D E5 : .Mp....\3.t...m. 0030: 5D A2 C5 8B 51 EF 26 E1 CE BE BB EE 0C 7C 14 D4 : ]...Q.&......|.. 0040: 80 85 BE 5E 1C DB 69 ED D6 6A 8A A1 97 8D DA EC : ...^..i..j...... 0050: 8E 11 F0 A0 E2 A3 68 FF F1 60 8C 5B 7C 30 6E A7 : ......h..`.[|0n. 0060: DE DE 68 55 31 91 47 CF 8A 38 44 30 4B 8A FB 76 : ..hU1.G..8D0K..v 0070: 09 92 2B 01 : ..+. => libssh2_transport_read() plain (53 bytes) 0000: 01 00 00 00 07 00 00 00 26 53 65 72 76 69 63 65 : ........&Service 0010: 20 6E 6F 74 20 72 65 71 75 65 73 74 65 64 20 6F : not requested o 0020: 72 20 61 75 74 68 65 6E 74 69 63 61 74 65 64 00 : r authenticated. 0030: 00 00 02 65 6E : ...en [libssh2] 44.093943 Transport: Packet type 1 received, length=53 [libssh2] 44.093943 Transport: Disconnect(7): Service not requested or authenticated(en) [libssh2] 44.093943 Failure Event: -13 - socket disconnect [libssh2] 44.093943 Transport: Looking for packet of type: 94 [libssh2] 44.093943 Transport: Looking for packet of type: 95 [libssh2] 44.093943 Failure Event: -21 - Unable to startup channel [libssh2] 44.572943 Transport: Disconnecting: reason=11, desc=Normal Shutdown, Thank you for playing, lang= => libssh2_transport_write plain (51 bytes) 0000: 01 00 00 00 0B 00 00 00 26 4E 6F 72 6D 61 6C 20 : ........&Normal 0010: 53 68 75 74 64 6F 77 6E 2C 20 54 68 61 6E 6B 20 : Shutdown, Thank 0020: 79 6F 75 20 66 6F 72 20 70 6C 61 79 69 6E 67 00 : you for playing. 0030: 00 00 00 : ... => libssh2_transport_write send() (100 bytes) 0000: C1 FD 85 D0 FC 6C 6C B0 2A 1C 0C C7 0C 81 4A 19 : .....ll.*.....J. 0010: 88 EA 7F 41 02 A6 EF FA B8 38 34 07 B9 11 D9 83 : ...A.....84..... 0020: 73 C7 82 5F B7 03 BD 9C F9 CF 8E C4 5E 78 1C FB : s.._........^x.. 0030: 9C 72 DF 23 E9 F2 0F 32 47 C9 55 F2 A6 66 87 E1 : .r.#...2G.U..f.. 0040: FF 94 07 04 3A CE FF F9 40 3D F5 98 96 C1 EB 58 : ....:...@=.....X 0050: 34 65 4B 12 38 34 F8 A8 CC 39 22 BA 9D 30 57 BC : 4eK.84...9"..0W. 0060: 8B 38 77 02 : .8w. [libssh2] 44.572943 Transport: Freeing session resource
_______________________________________________
libssh2-devel http://cool.haxx.se/cgi-bin/mailman/listinfo/libssh2-devel
Received on 2010-03-11