From mboxrd@z Thu Jan 1 00:00:00 1970 From: zerodaysfordays@sdf.lonestar.org (Jakob L. Kreuze) Subject: Re: Progress on 'guix deploy' Date: Wed, 12 Jun 2019 12:34:13 -0400 Message-ID: <87ftoebw56.fsf@sdf.lonestar.org> References: <875zpgrjqf.fsf@sdf.lonestar.org> <87h88x7pni.fsf@gnu.org> <871s01l4c2.fsf@sdf.lonestar.org> <87k1dscfxc.fsf@sdf.lonestar.org> <87h88wynhh.fsf@gnu.org> <87r27zc06r.fsf@sdf.lonestar.org> Mime-Version: 1.0 Content-Type: multipart/signed; boundary="=-=-="; micalg=pgp-sha256; protocol="application/pgp-signature" Return-path: Received: from eggs.gnu.org ([2001:470:142:3::10]:53751) by lists.gnu.org with esmtp (Exim 4.86_2) (envelope-from ) id 1hb6Dm-0002rX-Kw for guix-devel@gnu.org; Wed, 12 Jun 2019 12:35:36 -0400 Received: from Debian-exim by eggs.gnu.org with spam-scanned (Exim 4.71) (envelope-from ) id 1hb6Dk-0000J7-Ej for guix-devel@gnu.org; Wed, 12 Jun 2019 12:35:34 -0400 In-Reply-To: <87r27zc06r.fsf@sdf.lonestar.org> (Jakob L. Kreuze's message of "Tue, 11 Jun 2019 16:54:36 -0400") List-Id: "Development of GNU Guix and the GNU System distribution." List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , Errors-To: guix-devel-bounces+gcggd-guix-devel=m.gmane.org@gnu.org Sender: "Guix-devel" To: Ludovic =?utf-8?Q?Court=C3=A8s?= Cc: guix-devel@gnu.org --=-=-= Content-Type: text/plain Content-Transfer-Encoding: quoted-printable zerodaysfordays@sdf.lonestar.org (Jakob L. Kreuze) writes: > Very unusal. I'm wondering if it's caused by my use of > 'call-with-connected-session/auth' from (gnu tests ssh) rather than > 'open-ssh-session' in (guix ssh). That's the main difference between > the test suite and 'guix deploy' in terms of how they communicate with > the guest. The "[w]e need lightweight compression when exchanging full archives" comment in (guix ssh) gave me a little bit of hope that the issue could be solved by addressing the difference in how SSH sessions were being opened, but I've ensured that they're opened with the same parameters and still run into this seemingly nondeterministic behavior. Well, I suppose it's deterministic in the sense that it always fails when run from the test suite, and always works when run from 'guix deploy' ;) Running both with `#:log-verbosity 'protocol` yields essentially the same output, aside from the authentication method. #+BEGIN_SRC diff -u /home/jakob/ssh-clean-2 /home/jakob/ssh-clean-1 =2D-- /home/jakob/ssh-clean-2 2019-06-12 12:25:33.856336431 -0400 +++ /home/jakob/ssh-clean-1 2019-06-12 12:25:03.056337396 -0400 @@ -1,5 +1,9 @@ +1] ssh_config_parse_line: Unsupported option: IdentitiesOnly, line: 4 +1] ssh_config_parse_line: Unsupported option: IdentitiesOnly, line: 9 +1] ssh_config_parse_line: Unsupported option: IdentitiesOnly, line: 14 +1] ssh_config_parse_line: Unsupported option: IdentitiesOnly, line: 19 2] ssh_connect: libssh 0.8.7 (c) 2003-2018 Aris Adamantiadis, Andreas Schn= eider and libssh contributors. Distributed under the LGPL, please refer to = COPYING file for information about your rights, using threading threads_pth= read =2D2] ssh_socket_connect: Nonblocking connection socket: 11 +2] ssh_socket_connect: Nonblocking connection socket: 16 2] ssh_connect: Socket connecting, now waiting for the callbacks to work 1] socket_callback_connected: Socket connection callback: 1 (0) 1] ssh_client_connection_callback: SSH server banner: SSH-2.0-OpenSSH_8.0 @@ -11,16 +15,34 @@ 2] ssh_client_curve25519_reply: SSH_MSG_NEWKEYS sent 2] ssh_packet_newkeys: Received SSH_MSG_NEWKEYS 2] ssh_packet_newkeys: Signature verified and valid +2] ssh_pki_import_privkey_base64: Trying to decode privkey passphrase=3Dfa= lse +2] ssh_pki_openssh_import: Opening OpenSSH private key: ciphername: none, = kdf: none, nkeys: 1 +2] ssh_userauth_publickey_auto: Successfully authenticated using /home/jak= ob/.ssh/guixsd_slave 2] channel_open: Creating a channel 43 with 64000 window and 32768 max pac= ket 2] ssh_packet_global_request: Received SSH_MSG_GLOBAL_REQUEST packet 2] ssh_packet_global_request: UNKNOWN SSH_MSG_GLOBAL_REQUEST hostkeys-00@o= penssh.com 0 1] ssh_packet_process: Couldn't do anything with packet type 80 +2] ssh_packet_ignore_callback: Received SSH_MSG_DEBUG packet +2] ssh_packet_ignore_callback: Received SSH_MSG_DEBUG packet 2] ssh_packet_channel_open_conf: Received a CHANNEL_OPEN_CONFIRMATION for = channel 43:0 2] ssh_packet_channel_open_conf: Remote window : 0, maxpacket : 32768 2] channel_rcv_change_window: Adding 2097152 bytes to channel (43:0) (from= 0 bytes) 2] channel_request: Channel request exec success 2] grow_window: growing window (channel 43:0) to 1280000 bytes 2] channel_open: Creating a channel 44 with 64000 window and 32768 max pac= ket =2D1] ssh_socket_exception_callback: Socket exception callback: 2 (-1) =2D1] ssh_socket_exception_callback: Socket error: Unknown error -1 =2D0] channel-open-session: [GSSH ERROR] Socket error: Unknown error -1: #<= input-output: channel (closed) 6c024c0> +2] ssh_packet_channel_open_conf: Received a CHANNEL_OPEN_CONFIRMATION for = channel 44:1 +2] ssh_packet_channel_open_conf: Remote window : 0, maxpacket : 32768 +2] channel_rcv_change_window: Adding 2097152 bytes to channel (44:1) (from= 0 bytes) +2] channel_request: Channel request exec success +2] grow_window: growing window (channel 44:1) to 1280000 bytes +2] channel_rcv_change_window: Adding 112751 bytes to channel (44:1) (from = 1939345 bytes) +2] channel_rcv_change_window: Adding 96256 bytes to channel (44:1) (from 1= 961984 bytes) +2] channel_rcv_change_window: Adding 4096 bytes to channel (44:1) (from 19= 13495 bytes) +2] channel_rcv_change_window: Adding 65536 bytes to channel (44:1) (from 1= 917591 bytes) +2] channel_rcv_change_window: Adding 65536 bytes to channel (44:1) (from 1= 983127 bytes) +2] channel_open: Creating a channel 45 with 64000 window and 32768 max pac= ket +2] ssh_packet_channel_open_conf: Received a CHANNEL_OPEN_CONFIRMATION for = channel 45:1 +2] ssh_packet_channel_open_conf: Remote window : 0, maxpacket : 32768 +2] channel_rcv_change_window: Adding 2097152 bytes to channel (45:1) (from= 0 bytes) +2] channel_request: Channel request exec success +2] channel_rcv_change_window: Adding 129520 bytes to channel (45:1) (from = 1902096 bytes) ... Diff finished. Wed Jun 12 12:25:42 2019 #+END_SRC --=-=-= Content-Type: application/pgp-signature; name="signature.asc" -----BEGIN PGP SIGNATURE----- iQIzBAEBCAAdFiEEa1VJLOiXAjQ2BGSm9Qb9Fp2P2VoFAl0BKYUACgkQ9Qb9Fp2P 2VptWBAAlHFfxifK4I5F6Yf4R32PHrdPPp5IwD7LKjun5nUGOGqKhRLm6qZlIMHT 7NRJnCtRTmU0sbQsv9qHdU056+MoA7S1mSI4fgPPgXxvbJvZbzBAc1xlfZWYxH3O ifl8U6sPMuGaPEhzjiySzPg7bMuHpMt2WY6JXb+a3O+bqPA58qDFBWmn78jba33e 64lnzIGzazJgW52w2RApvNyDoTp7GuuYl0mbhSUWWmkB1qOrHRQ4VivqcFH5BWkU NJbplkWUb5NpLV1Fc/d1bi3GAod7CoS++PCN9x3vEZfNkwVNf6qGZr9u3b7xc2s3 AT3n3rZYSYtMJqhP5089mvXuMSoS2Ti9hyMoHFwwl0KMOuIYK8Ra3VMwLRYwV8Am BK5YZCKOnxiNYwCW2v1mY2dCF46UBr8ukvNgptSLLW9PKiseXTVBaFpzJVX10WNX end94mvf5abAsSNH92GPfo+vj4xVv8d0aFCXEzPmUG+0W549qMGgzZSldqDLEMME EzBWc6E2XHN6dk+XbmorWwI3yV9kcGEETnVnswRroXyywZiHuyFT6gSngOKvohdt GeJJ7FU9rTshhRIbNJJ1yf4JpUxO899GGlvPNtGK/2h68m/0YiIQajAlsV5aXPF6 xKHR6n3mwmAYQjZypbp0B8z9pq4g8OlmZNUv+h83SBsOvSbLSg8= =5+ei -----END PGP SIGNATURE----- --=-=-=--