From ulrich.sibiller@gmail.com Mon May 25 21:10:45 2020 Received: (at 1460) by bugs.x2go.org; 25 May 2020 19:10:48 +0000 X-Spam-Checker-Version: SpamAssassin 3.4.2 (2018-09-13) on ymir.das-netzwerkteam.de X-Spam-Level: X-Spam-Status: No, score=-0.7 required=3.0 tests=BAYES_00,DKIM_SIGNED, DKIM_VALID,DKIM_VALID_AU,FREEMAIL_FROM,FREEMAIL_REPLYTO, FREEMAIL_REPLYTO_END_DIGIT,SPF_HELO_NONE autolearn=no autolearn_force=no version=3.4.2 Received: from mail-yb1-xb32.google.com (mail-yb1-xb32.google.com [IPv6:2607:f8b0:4864:20::b32]) by ymir.das-netzwerkteam.de (Postfix) with ESMTPS id DE6175DAF8 for <1460@bugs.x2go.org>; Mon, 25 May 2020 21:10:42 +0200 (CEST) Received: by mail-yb1-xb32.google.com with SMTP id y18so1361269ybb.3 for <1460@bugs.x2go.org>; Mon, 25 May 2020 12:10:42 -0700 (PDT) DKIM-Signature: v=1; a=rsa-sha256; c=relaxed/relaxed; d=gmail.com; s=20161025; h=mime-version:references:in-reply-to:reply-to:from:date:message-id :subject:to:content-transfer-encoding; bh=gNQvVMJ7wIwDf21ng5788K8ze8UO8sCjEpjeTBy1cB0=; b=V73/eaGFC7udMFMvd10+HAJtdA13c453jcafhMFUGleKfhEO5vB1AIN6GIEfd+Wd/K /gmlzskj+3CqgNbmC6fMm3NI5/X2OjM+55w0lIOR5+vL2d7Mr/7YrjSugK85N/+yXwNM dwVjJJUAypENoFrCnJrZoDQ3xrqREp4Mkuzpps5esnH6dq8gL7BShoVEmLaGOaAG4ukx 4DLWjgSTg0mNtiyfHqEp3aeBo2yFyRJEH/FTHRPUZdfuJJr4ktyZljX0nmwmCCbur9Sj PRJibRNYIWxi6E+Ok9OxqKK86VS990KFen+7YiQzcYs1q1J6IsC698ersCe6FC4aabN3 Mpeg== X-Google-DKIM-Signature: v=1; a=rsa-sha256; c=relaxed/relaxed; d=1e100.net; s=20161025; h=x-gm-message-state:mime-version:references:in-reply-to:reply-to :from:date:message-id:subject:to:content-transfer-encoding; bh=gNQvVMJ7wIwDf21ng5788K8ze8UO8sCjEpjeTBy1cB0=; b=gx/PrlhQVbIfi7m2TkV8dWhKJ3xHumRJwsRWpEXpWSkMjAJJk14rDb4A9TP2lVm9/M nEwoCBnzRL6/y4kA0e+Ya+vCrw5+3u7pI5oaGhPNn3fzgemirl3XtA5ft9r9Xeo1L+Kw 54hYy7AC/UU9qphTdHp255GFRoN3KNF2rdh6Q9ne/0uBRGh8j22Rxvy2DtyfwWwFpsNT f8pgoJHwMPBZy4XOOotjgOOaJ4BaVbUzQQ9c/ugs8Efw7k/S7oIBU0yCn/o/zCy8TRSN kVZNyZB3evRcnHV5cdzknohXWcrKj23woOh9FVlTRhDJSxBCpRrTS6dAgBsrMurbuUdv oYJw== X-Gm-Message-State: AOAM531t/jLxMBboG7Dvc8QPg76tL8/1ardUCjws7hlPJpsAlhFDnm4Z AZ330FyKKCj0fZaPxFNXIssbeGFfkO67AK2DpIo= X-Google-Smtp-Source: ABdhPJz2+T8iJVEuZd4JjRQCjLA/ZEpJB4jSPUoC6nh27SBVLn2vrM02v2p+FC8wkAW0yK9BHaqEvyW6/GyIMVNFGlE= X-Received: by 2002:a25:6d88:: with SMTP id i130mr3343454ybc.306.1590433841276; Mon, 25 May 2020 12:10:41 -0700 (PDT) MIME-Version: 1.0 References: <20200419182314.GT12762@trav.math.uni-bonn.de> <20200504154819.GC12762@trav.math.uni-bonn.de> <20200525170757.GV12762@trav.math.uni-bonn.de> In-Reply-To: <20200525170757.GV12762@trav.math.uni-bonn.de> Reply-To: uli42@gmx.de From: Ulrich Sibiller Date: Mon, 25 May 2020 21:10:15 +0200 Message-ID: Subject: Re: [X2Go-Dev] Bug#1460: libssh-debug/libssh-packetlog output To: =?UTF-8?Q?Edgar_Fu=C3=9F?= , 1460@bugs.x2go.org Content-Type: text/plain; charset="UTF-8" Content-Transfer-Encoding: quoted-printable I have seen a very similar problem with the latest windows client. I believe the libssh 0.9.3 being the culprit. However, as I do not have a development environment on that windows system I cannot verify by swapping libssh for 0.9.4 or some older version. Interestingly no downloadable binary versions of libssh.dll seem to exist. I also cannot test with an older version because only the newest version contains the ! patch (prepend ! to a hostname to skip the internal test for interactivity on the remote host) and I need that for that special setup... So from my POV it would help if you could try using an older version of x2goclient (with an older libssh) to see if it works with that. Uli PS: I suspect this happens when the other side has a quite old openssh running, in my case it's OpenSSH 5.8, for you it's 5.9... So If you can upgrade the ssh server this might also solve the problem (and is also not an option for me, unfortunately. Corporate environments tend to suck...) On Mon, May 25, 2020 at 7:10 PM Edgar Fu=C3=9F wrote: > > By chance, I learned of the --libssh-debug and --libssh-packetlog options= . > So here's the output of a session that crashes. Any hints as how to pursu= e this further? > > C:\Program Files (x86)\x2goclient>x2goclient.debug.exe --debug --libssh-d= ebug --libssh-packetlog > x2go-INFO-1> "Starting X2Go Client 4.1.2.2..." > x2go-INFO-4> "Translator: :/i18n/x2goclient_de_de found." > x2go-INFO-4> "=C3=9Cbersetzung: :/i18n/qt_de_de gefunden." > x2go-DEBUG-../src/onmainwindow.cpp:10779> Getting X.Org Server settings. > x2go-INFO-3> "X2GoClient wurde gestartet." > x2go-DEBUG-../src/onmainwindow.cpp:575> "$HOME=3DC:/Users/ef" > x2go-DEBUG-../src/onmainwindow.cpp:2266> Reading 1 sessions from config f= ile. > x2go-DEBUG-../src/sessionbutton.cpp:361> Creating QPixmap with session ic= on: ":/img/icons/128x128/x2gosession.png". > x2go-DEBUG-../src/onmainwindow.cpp:13290> libssh not initialized yet. Ini= tializing. > x2go-DEBUG-../src/pulsemanager.cpp:369> pulseaudio --version returned:"pu= lseaudio 13.0 > " > > x2go-DEBUG-../src/onmainwindow.cpp:10682> Starting helper servers for Win= dows ... > x2go-DEBUG-../src/onmainwindow.cpp:10849> "/cygdrive/C/Users/ef/.x2go/var= " cygwin var path > x2go-DEBUG-../src/onmainwindow.cpp:10937> "C:/Users/ef/.x2go/etc/sshd_con= fig created." > x2go-DEBUG-../src/onmainwindow.cpp:10344> Resolved localhost. > x2go-DEBUG-../src/onmainwindow.cpp:10366> Port is free: 7022 > x2go-DEBUG-../src/onmainwindow.cpp:11288> Logging cygwin sshd to: "C:/Use= rs/ef/.x2go/sshLogs/Hp2320.log" > x2go-DEBUG-../src/onmainwindow.cpp:11302> Creating desktop: x2go_ef > x2go-DEBUG-../src/pulsemanager.cpp:227> pulse started with arguments ("--= exit-idle-time=3D-1", "-n", "-F", "C:\Users\ef\.x2go\pulse\config.pa", "-p"= , "C:\Program Files (x86)\x2goclient\pulse\lib\pulse-13.0\modules", "--log-= level=3Ddebug", "--verbose", "--log-target=3Dfile:C:\Users\ef\.x2go\pulse\p= ulse.log") - waiting for it to finx2go-DEBUG-../src/onmainwindow.cpp:10344>= Resolved localhost. > ish... > x2go-DEBUG-../src/onmainwindow.cpp:10372> Port already in use: 7022 > x2go-DEBUG-../src/onmainwindow.cpp:10344> Resolved localhost. > x2go-DEBUG-../src/onmainwindow.cpp:10372> Port already in use: 7022 > x2go-DEBUG-../src/onmainwindow.cpp:11399> User mode OpenSSH server starte= d successfully. > x2go-DEBUG-../src/onmainwindow.cpp:2752> Creating QPixmap with session ic= on: '":/img/icons/128x128/x2gosession.png"'. > x2go-INFO-8> "Verbindung mit Server wird gestartet: aegidienberg:22" > x2go-DEBUG-../src/onmainwindow.cpp:2853> Starting new ssh connection to s= erver:"aegidienberg":"22" krbLogin: false > x2go-DEBUG-../src/sshmasterconnection.cpp:168> SshMasterConnection, host = "aegidienberg"; port 22; user "mtuser"; useproxy true; proxyserver "login.m= ath.uni-bonn.de"; proxyport 22 > x2go-DEBUG-../src/sshmasterconnection.cpp:248> Starting SSH connection wi= thout Kerberos authentication. > x2go-DEBUG-../src/sshmasterconnection.cpp:250> SshMasterConnection, insta= nce SshMasterConnection(0x3a10da8) created. > x2go-DEBUG-../src/sshmasterconnection.cpp:495> SshMasterConnection, insta= nce SshMasterConnection(0x3a10da8) entering thread. > x2go-DEBUG-../src/sshmasterconnection.cpp:498> proxyserver: "login.math.u= ni-bonn.de"; proxyport: 22; proxylogin: "" > x2go-DEBUG-../src/sshmasterconnection.cpp:168> SshMasterConnection, host = "login.math.uni-bonn.de"; port 22; user ""; useproxy false; proxyserver "";= proxyport 0 > x2go-DEBUG-../src/sshmasterconnection.cpp:224> Temporary session user nam= e after config file parse: > x2go-DEBUG-../src/sshmasterconnection.cpp:248> Starting SSH connection wi= thout Kerberos authentication. > x2go-DEBUG-../src/sshmasterconnection.cpp:250> SshMasterConnection, insta= nce SshMasterConnection(0x3a112e8) created. > x2go-DEBUG-../src/sshmasterconnection.cpp:495> SshMasterConnection, insta= nce SshMasterConnection(0x3a112e8) entering thread. > x2go-DEBUG-../src/sshmasterconnection.cpp:575> Setting SSH directory to C= :/Users/ef/ssh > x2go-DEBUG-../src/sshmasterconnection.cpp:797> Session port before config= file parse: 22 > x2go-DEBUG-../src/sshmasterconnection.cpp:807> Session port after config = file parse: 22 > [2020/05/25 18:47:02.347704, 2] ssh_connect: libssh 0.9.3 (c) 2003-2019 = Aris Adamantiadis, Andreas Schneider and libssh contributors. Distributed u= nder the LGPL, please refer to COPYING file for information about your righ= ts, using threading threads_pthread > [2020/05/25 18:47:02.401163, 2] ssh_socket_connect: Nonblocking connecti= on socket: 2228 > [2020/05/25 18:47:02.401163, 2] ssh_connect: Socket connecting, now wait= ing for the callbacks to work > [2020/05/25 18:47:02.401163, 3] ssh_connect: Actual timeout : 60000 > [2020/05/25 18:47:02.438119, 3] ssh_socket_pollcallback: Received POLLOU= T in connecting state > [2020/05/25 18:47:02.438119, 1] socket_callback_connected: Socket connec= tion callback: 1 (0) > [2020/05/25 18:47:02.438119, 3] ssh_socket_unbuffered_write: Enabling PO= LLOUT for socket > [2020/05/25 18:47:02.491386, 3] callback_receive_banner: Received banner= : SSH-2.0-OpenSSH_5.9 NetBSD_Secure_Shell-20110907-hpn13v11-lpk > [2020/05/25 18:47:02.491386, 2] ssh_client_connection_callback: SSH serv= er banner: SSH-2.0-OpenSSH_5.9 NetBSD_Secure_Shell-20110907-hpn13v11-lpk > [2020/05/25 18:47:02.491386, 2] ssh_analyze_banner: Analyzing banner: SS= H-2.0-OpenSSH_5.9 NetBSD_Secure_Shell-20110907-hpn13v11-lpk > [2020/05/25 18:47:02.491386, 2] ssh_analyze_banner: We are talking to an= OpenSSH client version: 5.9 (50900) > [2020/05/25 18:47:03.714230, 3] ssh_client_select_hostkeys: Order of wan= ted host keys: "ssh-ed25519,ecdsa-sha2-nistp521,ecdsa-sha2-nistp384,ecdsa-s= ha2-nistp256,rsa-sha2-512,rsa-sha2-256,ssh-rsa,ssh-dss" > [2020/05/25 18:47:03.803831, 1] ssh_known_hosts_read_entries: Failed to = open the known_hosts file '/etc/ssh/ssh_known_hosts': No such file or direc= tory > [2020/05/25 18:47:03.803831, 3] ssh_client_select_hostkeys: Algorithms f= ound in known_hosts files: "ecdsa-sha2-nistp521" > [2020/05/25 18:47:03.803831, 3] ssh_client_select_hostkeys: Changing hos= t key method to "ecdsa-sha2-nistp521,ssh-ed25519,ecdsa-sha2-nistp384,ecdsa-= sha2-nistp256,rsa-sha2-512,rsa-sha2-256,ssh-rsa,ssh-dss" > [2020/05/25 18:47:03.803831, 3] ssh_socket_unbuffered_write: Enabling PO= LLOUT for socket > [2020/05/25 18:47:03.803831, 3] packet_send2: packet: wrote [type=3D20, = len=3D940, padding_size=3D4, comp=3D935, payload=3D935] > [2020/05/25 18:47:03.803831, 3] ssh_send_kex: SSH_MSG_KEXINIT sent > [2020/05/25 18:47:03.803831, 3] ssh_packet_socket_callback: packet: read= type 20 [len=3D812,padding=3D5,comp=3D806,payload=3D806] > [2020/05/25 18:47:03.803831, 3] ssh_packet_process: Dispatching handler = for packet type 20 > [2020/05/25 18:47:03.822580, 2] ssh_kex_select_methods: Negotiated ecdh-= sha2-nistp256,ecdsa-sha2-nistp521,aes256-ctr,aes256-ctr,hmac-sha1,hmac-sha1= ,none,none,, > [2020/05/25 18:47:03.822580, 3] packet_send2: packet: wrote [type=3D30, = len=3D76, padding_size=3D5, comp=3D70, payload=3D70] > [2020/05/25 18:47:03.822580, 3] ssh_socket_unbuffered_write: Enabling PO= LLOUT for socket > [2020/05/25 18:47:03.875547, 3] ssh_packet_socket_callback: packet: read= type 31 [len=3D428,padding=3D11,comp=3D416,payload=3D416] > [2020/05/25 18:47:03.875547, 3] ssh_packet_process: Dispatching handler = for packet type 31 > [2020/05/25 18:47:03.875547, 3] ssh_socket_unbuffered_write: Enabling PO= LLOUT for socket > [2020/05/25 18:47:03.875547, 3] packet_send2: packet: wrote [type=3D21, = len=3D12, padding_size=3D10, comp=3D1, payload=3D1] > [2020/05/25 18:47:03.875547, 3] crypt_set_algorithms2: Set output algori= thm to aes256-ctr > [2020/05/25 18:47:03.875547, 3] crypt_set_algorithms2: Set HMAC output a= lgorithm to hmac-sha1 > [2020/05/25 18:47:03.875547, 3] crypt_set_algorithms2: Set input algorit= hm to aes256-ctr > [2020/05/25 18:47:03.875547, 3] crypt_set_algorithms2: Set HMAC input al= gorithm to hmac-sha1 > [2020/05/25 18:47:03.875547, 2] ssh_init_rekey_state: Set rekey after 42= 94967296 blocks > [2020/05/25 18:47:03.875547, 2] ssh_init_rekey_state: Set rekey after 42= 94967296 blocks > [2020/05/25 18:47:03.875547, 2] ssh_packet_client_ecdh_reply: SSH_MSG_NE= WKEYS sent > [2020/05/25 18:47:03.875547, 3] ssh_packet_socket_callback: Processing 1= 6 bytes left in socket buffer > [2020/05/25 18:47:03.875547, 3] ssh_packet_socket_callback: packet: read= type 21 [len=3D12,padding=3D10,comp=3D1,payload=3D1] > [2020/05/25 18:47:03.875547, 3] ssh_packet_process: Dispatching handler = for packet type 21 > [2020/05/25 18:47:03.875547, 2] ssh_packet_newkeys: Received SSH_MSG_NEW= KEY > _______________________________________________ > x2go-dev mailing list > x2go-dev@lists.x2go.org > https://lists.x2go.org/listinfo/x2go-dev