Subject: dispatch_protocol_error: type 90 seq 4

dispatch_protocol_error: type 90 seq 4

From: Dave McCaldon <davem_at_intersystems.com>
Date: Thu, 11 Mar 2010 16:54:04 -0500

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