Roger Marsh
2023-Nov-08 13:47 UTC
Delay in starting programs on FreeBSD via ssh after upgrade OpenBSD from 7.3 to 7.4
Damien, Sorry about distributed context. Those discussions told me about the new ObscureKeystrokeTiming argument to the ssh command. One reply suggested I try that because it is easy to test. Most of my xterm ssh command combinations in fvwm configuration file are expressed 'Exec exec xterm -title ... -e ssh <user>@<host>' and do not have a noticable delay. One is expressed 'Exec exec ssh -Y opendev xterm -title roger at opendev', which you mention, and does have a noticable delay. It seemed far easier to find out what was going on by asking about this command rather than the formally identical 'ssh -Y <hostname> idle3.n' command: xterm is in OpenBSD base so asking a Python question on ports@ was probably too wide. It is the 'ssh -Y <hostname> idle3.n' command that worries me, and two scripts with 'ssh -vvv -Y ...' traces follow. The first is for starting idle, and the second is for invoking the open file dialogue. Adding '-o "ObscureKeystokeTiming no"' into the command gets rid of the delay: but I count this as a workaround unless told this is how the command is supposed to be done at OpenSSH 9.5 compared with OpenSSH 9.3. Script started on Wed Nov 8 10:58:11 2023 screen$ echo "Do 'ssh ... idle3.9' then keep 'b' key pressed until done" Do 'ssh ... idle3.9' then keep 'b' key pressed until done screen$ echo "Quit by 'Alt-f x' without any key kept pressed" Quit by 'Alt-f x' without any key kept pressed screen$ screen$ screen$ echo "System details first" System details first screen$ uname -a OpenBSD screen.home 7.4 GENERIC.MP#1397 amd64 screen$ ssh chess2 Last login: Wed Nov 8 10:57:20 2023 from screen FreeBSD 13.2-RELEASE releng/13.2-n254617-525ecfdad597 GENERIC Welcome to FreeBSD! Release Notes, Errata: https://www.FreeBSD.org/releases/ Security Advisories: https://www.FreeBSD.org/security/ FreeBSD Handbook: https://www.FreeBSD.org/handbook/ FreeBSD FAQ: https://www.FreeBSD.org/faq/ Questions List: https://www.FreeBSD.org/lists/questions/ FreeBSD Forums: https://forums.FreeBSD.org/ Documents installed with the system are in the /usr/local/share/doc/freebsd/ directory, or can be installed later with: pkg install en-freebsd-doc For other languages, replace "en" with a language code like de or fr. Show the version of FreeBSD installed: freebsd-version ; uname -a Please include that output and any error messages when posting questions. Introduction to manual pages: man man FreeBSD directory layout: man hier To change this login announcement, see motd(5). Nice bash prompt: PS1='(\[$(tput md)\]\t <\w>\[$(tput me)\]) $(echo $?) \$ ' -- Mathieu <mathieu at hal.interactionvirtuelle.com> roger at chess2:~ $ ssh -V OpenSSH_9.3p1, OpenSSL 1.1.1t-freebsd 7 Feb 2023 roger at chess2:~ $ sshd -V OpenSSH_9.3, OpenSSL 1.1.1t-freebsd 7 Feb 2023 roger at chess2:~ $ exit Connection to chess2 closed. screen$ ssh -V OpenSSH_9.5, LibreSSL 3.8.2 screen$ sshd -V OpenSSH_9.5, LibreSSL 3.8.2 screen$ screen$ screen$ screen$ ssh -vvv -Y chess2 idle3.9 OpenSSH_9.5, LibreSSL 3.8.2 debug1: Reading configuration data /etc/ssh/ssh_config debug3: expanded UserKnownHostsFile '~/.ssh/known_hosts' -> '/home/roger/.ssh/known_hosts' debug3: expanded UserKnownHostsFile '~/.ssh/known_hosts2' -> '/home/roger/.ssh/known_hosts2' debug2: resolving "chess2" port 22 debug3: resolve_host: lookup chess2:22 debug3: ssh_connect_direct: entering debug1: Connecting to chess2 [192.168.5.23] port 22. debug3: set_sock_tos: set socket 3 IP_TOS 0x48 debug1: Connection established. debug1: identity file /home/roger/.ssh/id_rsa type 0 debug1: identity file /home/roger/.ssh/id_rsa-cert type -1 debug1: identity file /home/roger/.ssh/id_ecdsa type -1 debug1: identity file /home/roger/.ssh/id_ecdsa-cert type -1 debug1: identity file /home/roger/.ssh/id_ecdsa_sk type -1 debug1: identity file /home/roger/.ssh/id_ecdsa_sk-cert type -1 debug1: identity file /home/roger/.ssh/id_ed25519 type -1 debug1: identity file /home/roger/.ssh/id_ed25519-cert type -1 debug1: identity file /home/roger/.ssh/id_ed25519_sk type -1 debug1: identity file /home/roger/.ssh/id_ed25519_sk-cert type -1 debug1: identity file /home/roger/.ssh/id_xmss type -1 debug1: identity file /home/roger/.ssh/id_xmss-cert type -1 debug1: identity file /home/roger/.ssh/id_dsa type -1 debug1: identity file /home/roger/.ssh/id_dsa-cert type -1 debug1: Local version string SSH-2.0-OpenSSH_9.5 debug1: Remote protocol version 2.0, remote software version OpenSSH_9.3 FreeBSD-20230316 debug1: compat_banner: match: OpenSSH_9.3 FreeBSD-20230316 pat OpenSSH* compat 0x04000000 debug2: fd 3 setting O_NONBLOCK debug1: Authenticating to chess2:22 as 'roger' debug3: record_hostkey: found key type ED25519 in file /home/roger/.ssh/known_hosts:18 debug3: record_hostkey: found key type RSA in file /home/roger/.ssh/known_hosts:19 bdebug3: record_hostkey: found key type ECDSA in file /home/roger/.ssh/known_hosts:20 debug3: load_hostkeys_file: loaded 3 keys from chess2 debug1: load_hostkeys: fopen /home/roger/.ssh/known_hosts2: No such file or directory debug1: load_hostkeys: fopen /etc/ssh/ssh_known_hosts: No such file or directory debug1: load_hostkeys: fopen /etc/ssh/ssh_known_hosts2: No such file or directory debug3: order_hostkeyalgs: have matching best-preference key type ssh-ed25519-cert-v01 at openssh.com, using HostkeyAlgorithms verbatim debug3: send packet: type 20 debug1: SSH2_MSG_KEXINIT sent debug3: receive packet: type 20 debug1: SSH2_MSG_KEXINIT received debug2: local client KEXINIT proposal debug2: KEX algorithms: sntrup761x25519-sha512 at openssh.com,curve25519-sha256,curve25519-sha256 at libssh.org,ecdh-sha2-nistp256,ecdh-sha2-nistp384,ecdh-sha2-nistp521,diffie-hellman-group-exchange-sha256,diffie-hellman-group16-sha512,diffie-hellman-group18-sha512,diffie-hellman-group14-sha256,ext-info-c debug2: host key algorithms: ssh-ed25519-cert-v01 at openssh.com,ecdsa-sha2-nistp256-cert-v01 at openssh.com,ecdsa-sha2-nistp384-cert-v01 at openssh.com,ecdsa-sha2-nistp521-cert-v01 at openssh.com,sk-ssh-ed25519-cert-v01 at openssh.com,sk-ecdsa-sha2-nistp256-cert-v01 at openssh.com,rsa-sha2-512-cert-v01 at openssh.com,rsa-sha2-256-cert-v01 at openssh.com,ssh-ed25519,ecdsa-sha2-nistp256,ecdsa-sha2-nistp384,ecdsa-sha2-nistp521,sk-ssh-ed25519 at openssh.com,sk-ecdsa-sha2-nistp256 at openssh.com,rsa-sha2-512,rsa-sha2-256 debug2: ciphers ctos: chacha20-poly1305 at openssh.com,aes128-ctr,aes192-ctr,aes256-ctr,aes128-gcm at openssh.com,aes256-gcm at openssh.com debug2: ciphers stoc: chacha20-poly1305 at openssh.com,aes128-ctr,aes192-ctr,aes256-ctr,aes128-gcm at openssh.com,aes256-gcm at openssh.com debug2: MACs ctos: umac-64-etm at openssh.com,umac-128-etm at openssh.com,hmac-sha2-256-etm at openssh.com,hmac-sha2-512-etm at openssh.com,hmac-sha1-etm at openssh.com,umac-64 at openssh.com,umac-128 at openssh.com,hmac-sha2-256,hmac-sha2-512,hmac-sha1 debug2: MACs stoc: umac-64-etm at openssh.com,umac-128-etm at openssh.com,hmac-sha2-256-etm at openssh.com,hmac-sha2-512-etm at openssh.com,hmac-sha1-etm at openssh.com,umac-64 at openssh.com,umac-128 at openssh.com,hmac-sha2-256,hmac-sha2-512,hmac-sha1 debug2: compression ctos: none,zlib at openssh.com,zlib debug2: compression stoc: none,zlib at openssh.com,zlib debug2: languages ctos: debug2: languages stoc: debug2: first_kex_follows 0 debug2: reserved 0 debug2: peer server KEXINIT proposal debug2: KEX algorithms: sntrup761x25519-sha512 at openssh.com,curve25519-sha256,curve25519-sha256 at libssh.org,ecdh-sha2-nistp256,ecdh-sha2-nistp384,ecdh-sha2-nistp521,diffie-hellman-group-exchange-sha256,diffie-hellman-group16-sha512,diffie-hellman-group18-sha512,diffie-hellman-group14-sha256 debug2: host key algorithms: rsa-sha2-512,rsa-sha2-256,ssh-rsa,ecdsa-sha2-nistp256,ssh-ed25519 debug2: ciphers ctos: chacha20-poly1305 at openssh.com,aes128-ctr,aes192-ctr,aes256-ctr,aes128-gcm at openssh.com,aes256-gcm at openssh.com debug2: ciphers stoc: chacha20-poly1305 at openssh.com,aes128-ctr,aes192-ctr,aes256-ctr,aes128-gcm at openssh.com,aes256-gcm at openssh.com debug2: MACs ctos: umac-64-etm at openssh.com,umac-128-etm at openssh.com,hmac-sha2-256-etm at openssh.com,hmac-sha2-512-etm at openssh.com,hmac-sha1-etm at openssh.com,umac-64 at openssh.com,umac-128 at openssh.com,hmac-sha2-256,hmac-sha2-512,hmac-sha1 debug2: MACs stoc: umac-64-etm at openssh.com,umac-128-etm at openssh.com,hmac-sha2-256-etm at openssh.com,hmac-sha2-512-etm at openssh.com,hmac-sha1-etm at openssh.com,umac-64 at openssh.com,umac-128 at openssh.com,hmac-sha2-256,hmac-sha2-512,hmac-sha1 debug2: compression ctos: none,zlib at openssh.com debug2: compression stoc: none,zlib at openssh.com debug2: languages ctos: debug2: languages stoc: debug2: first_kex_follows 0 debug2: reserved 0 debug1: kex: algorithm: sntrup761x25519-sha512 at openssh.com debug1: kex: host key algorithm: ssh-ed25519 debug1: kex: server->client cipher: chacha20-poly1305 at openssh.com MAC: <implicit> compression: none debug1: kex: client->server cipher: chacha20-poly1305 at openssh.com MAC: <implicit> compression: none debug3: send packet: type 30 debug1: expecting SSH2_MSG_KEX_ECDH_REPLY debug3: receive packet: type 31 debug1: SSH2_MSG_KEX_ECDH_REPLY received debug1: Server host key: ssh-ed25519 SHA256: redacted debug3: record_hostkey: found key type ED25519 in file /home/roger/.ssh/known_hosts:18 debug3: record_hostkey: found key type RSA in file /home/roger/.ssh/known_hosts:19 debug3: record_hostkey: found key type ECDSA in file /home/roger/.ssh/known_hosts:20 debug3: load_hostkeys_file: loaded 3 keys from chess2 debug1: load_hostkeys: fopen /home/roger/.ssh/known_hosts2: No such file or directory debug1: load_hostkeys: fopen /etc/ssh/ssh_known_hosts: No such file or directory debug1: load_hostkeys: fopen /etc/ssh/ssh_known_hosts2: No such file or directory debug1: Host 'chess2' is known and matches the ED25519 host key. debug1: Found key in /home/roger/.ssh/known_hosts:18 debug3: send packet: type 21 debug2: ssh_set_newkeys: mode 1 debug1: rekey out after 134217728 blocks debug1: SSH2_MSG_NEWKEYS sent debug1: expecting SSH2_MSG_NEWKEYS debug3: receive packet: type 21 debug1: SSH2_MSG_NEWKEYS received debug2: ssh_set_newkeys: mode 0 debug1: rekey in after 134217728 blocks debug3: ssh_get_authentication_socket_path: path '/tmp/ssh-AV3ai4wQ1yej/agent.42920' debug1: get_agent_identities: bound agent to hostkey debug1: get_agent_identities: agent returned 1 keys debug1: Will attempt key: /home/roger/.ssh/id_rsa RSA SHA256:redacted agent debug1: Will attempt key: /home/roger/.ssh/id_ecdsa debug1: Will attempt key: /home/roger/.ssh/id_ecdsa_sk debug1: Will attempt key: /home/roger/.ssh/id_ed25519 debug1: Will attempt key: /home/roger/.ssh/id_ed25519_sk debug1: Will attempt key: /home/roger/.ssh/id_xmss debug1: Will attempt key: /home/roger/.ssh/id_dsa debug2: pubkey_prepare: done debug3: send packet: type 5 debug3: receive packet: type 7 debug1: SSH2_MSG_EXT_INFO received debug1: kex_input_ext_info: server-sig-algs=<ssh-ed25519,sk-ssh-ed25519 at openssh.com,ecdsa-sha2-nistp256,ecdsa-sha2-nistp384,ecdsa-sha2-nistp521,sk-ecdsa-sha2-nistp256 at openssh.com,webauthn-sk-ecdsa-sha2-nistp256 at openssh.com,ssh-dss,ssh-rsa,rsa-sha2-256,rsa-sha2-512> debug1: kex_ext_info_check_ver: publickey-hostbound at openssh.com=<0> debug3: receive packet: type 6 debug2: service_accept: ssh-userauth debug1: SSH2_MSG_SERVICE_ACCEPT received debug3: send packet: type 50 debug3: receive packet: type 51 debug1: Authentications that can continue: publickey debug3: start over, passed a different list publickey debug3: preferred publickey,keyboard-interactive,password debug3: authmethod_lookup publickey debug3: remaining preferred: keyboard-interactive,password debug3: authmethod_is_enabled publickey debug1: Next authentication method: publickey debug1: Offering public key: /home/roger/.ssh/id_rsa RSA SHA256:redacted agent debug3: send packet: type 50 debug2: we sent a publickey packet, wait for reply debug3: receive packet: type 60 debug1: Server accepts key: /home/roger/.ssh/id_rsa RSA SHA256:redacted agent debug3: sign_and_send_pubkey: using publickey-hostbound-v00 at openssh.com with RSA SHA256:redacted debug3: sign_and_send_pubkey: signing using rsa-sha2-512 SHA256:redacted debug3: send packet: type 50 debug3: receive packet: type 52 Authenticated to chess2 ([192.168.5.23]:22) using "publickey". debug1: channel 0: new session [client-session] (inactive timeout: 0) debug3: ssh_session2_open: channel_new: 0 debug2: channel 0: send open debug3: send packet: type 90 debug1: Requesting no-more-sessions at openssh.com debug3: send packet: type 80 debug1: Entering interactive session. debug1: pledge: exec debug3: client_repledge: enter debug3: receive packet: type 80 debug1: client_input_global_request: rtype hostkeys-00 at openssh.com want_reply 0 debug3: client_input_hostkeys: received RSA key SHA256:redacted debug3: client_input_hostkeys: received ECDSA key SHA256:redacted debug3: client_input_hostkeys: received ED25519 key SHA256:redacted debug1: client_input_hostkeys: searching /home/roger/.ssh/known_hosts for chess2 / (none) debug3: hostkeys_foreach: reading file "/home/roger/.ssh/known_hosts" debug3: hostkeys_find: found ssh-ed25519 key at /home/roger/.ssh/known_hosts:18 debug3: hostkeys_find: found ssh-rsa key at /home/roger/.ssh/known_hosts:19 debug3: hostkeys_find: found ecdsa-sha2-nistp256 key at /home/roger/.ssh/known_hosts:20 debug1: client_input_hostkeys: searching /home/roger/.ssh/known_hosts2 for chess2 / (none) debug1: client_input_hostkeys: hostkeys file /home/roger/.ssh/known_hosts2 does not exist debug3: client_input_hostkeys: 3 server keys: 0 new, 3 retained, 0 incomplete match. 0 to remove debug1: client_input_hostkeys: no new or deprecated keys from server debug3: client_repledge: enter debug3: receive packet: type 4 debug1: Remote: /home/roger/.ssh/authorized_keys:2: key options: agent-forwarding port-forwarding pty user-rc x11-forwarding debug3: receive packet: type 4 debug1: Remote: /home/roger/.ssh/authorized_keys:2: key options: agent-forwarding port-forwarding pty user-rc x11-forwarding debug3: receive packet: type 91 debug2: channel_input_open_confirmation: channel 0: callback start debug2: x11_get_proto: /usr/X11R6/bin/xauth list :0 2>/dev/null debug1: Requesting X11 forwarding with authentication spoofing. debug2: channel 0: request x11-req confirm 1 debug3: send packet: type 98 debug2: fd 3 setting TCP_NODELAY debug3: set_sock_tos: set socket 3 IP_TOS 0x48 debug2: client_session2_setup: id 0 debug1: Sending command: idle3.9 debug2: channel 0: request exec confirm 1 debug3: send packet: type 98 debug3: client_repledge: enter debug2: channel_input_open_confirmation: channel 0: callback done debug2: channel 0: open confirm rwindow 0 rmax 32768 debug3: receive packet: type 99 debug2: channel_input_status_confirm: type 99 id 0 debug2: X11 forwarding request accepted on channel 0 debug2: channel 0: rcvd adjust 2097152 debug3: receive packet: type 99 debug2: channel_input_status_confirm: type 99 id 0 debug2: exec request accepted on channel 0 debug3: receive packet: type 90 debug1: client_input_channel_open: ctype x11 rchan 3 win 65536 max 16384 debug1: client_request_x11: request from 127.0.0.1 20921 debug2: fd 7 setting O_NONBLOCK debug3: fd 7 is O_NONBLOCK debug1: channel 1: new x11 [x11] (inactive timeout: 0) debug1: confirm x11 debug3: send packet: type 91 debug3: obfuscate_keystroke_timing: starting: interval ~20ms bbbdebug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms bbbbbbdebug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms bbbbbbbbbbbbbbdebug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms bbbbbbbbbbbbbbbbbbbbbbbdebug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms bbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbdebug2: channel 1: window 2047152 sent adjust 41808 debug2: channel 1: window 2039808 sent adjust 24576 debug2: channel 1: window 2031616 sent adjust 24576 debug2: channel 1: window 2039808 sent adjust 8192 debug2: channel 1: window 2031616 sent adjust 8192 debug2: channel 1: window 2007040 sent adjust 8192 debug2: channel 1: window 2015232 sent adjust 8192 debug2: channel 1: window 1957888 sent adjust 8192 debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug2: channel 1: window 1935220 sent adjust 8192 debug2: channel 1: window 1943412 sent adjust 8192 debug2: channel 1: window 1951604 sent adjust 8192 debug2: channel 1: window 1959796 sent adjust 8192 debug2: channel 1: window 1967988 sent adjust 8192 debug2: channel 1: window 1976180 sent adjust 8192 debug2: channel 1: window 1984372 sent adjust 8192 debug2: channel 1: window 1992564 sent adjust 8192 debug2: channel 1: window 2000756 sent adjust 8192 debug2: channel 1: window 2008948 sent adjust 8192 debug2: channel 1: window 2017140 sent adjust 8192 debug2: channel 1: window 2025332 sent adjust 8192 debug2: channel 1: window 2033524 sent adjust 8192 debug2: channel 1: window 2041716 sent adjust 8192 debug3: obfuscate_keystroke_timing: starting: interval ~20ms bbdebug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms bbbbbbbbdebug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug2: channel 1: window 2047988 sent adjust 49164 debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug2: channel 1: window 2028592 sent adjust 27600 debug2: channel 1: window 2007040 sent adjust 8192 debug2: channel 1: window 1966080 sent adjust 8192 debug2: channel 1: window 1925120 sent adjust 8192 debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug2: channel 1: window 1884160 sent adjust 8192 debug2: channel 1: window 1877028 sent adjust 8192 debug2: channel 1: window 1885220 sent adjust 8192 debug2: channel 1: window 1893412 sent adjust 8192 debug2: channel 1: window 1901604 sent adjust 8192 debug2: channel 1: window 1909796 sent adjust 8192 debug2: channel 1: window 1917988 sent adjust 8192 debug2: channel 1: window 1926180 sent adjust 8192 debug2: channel 1: window 1934372 sent adjust 8192 debug2: channel 1: window 1942564 sent adjust 8192 debug2: channel 1: window 1950756 sent adjust 8192 debug2: channel 1: window 1958948 sent adjust 8192 debug2: channel 1: window 1967140 sent adjust 8192 debug2: channel 1: window 1975332 sent adjust 8192 debug2: channel 1: window 1983524 sent adjust 8192 debug2: channel 1: window 1991716 sent adjust 8192 debug2: channel 1: window 1999908 sent adjust 8192 debug2: channel 1: window 2008100 sent adjust 8192 debug2: channel 1: window 2016292 sent adjust 8192 debug2: channel 1: window 2024484 sent adjust 8192 debug2: channel 1: window 2032676 sent adjust 8192 debug2: channel 1: window 2040868 sent adjust 8192 debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug2: channel 1: window 2047668 sent adjust 49484 debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug2: channel 1: window 2043528 sent adjust 20856 debug2: channel 1: window 2015232 sent adjust 8192 debug2: channel 1: window 1941504 sent adjust 8192 debug2: channel 1: window 1949696 sent adjust 8192 debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug2: channel 1: window 1925120 sent adjust 8192 debug2: channel 1: window 1869404 sent adjust 8192 debug2: channel 1: window 1877596 sent adjust 8192 debug2: channel 1: window 1885788 sent adjust 8192 debug2: channel 1: window 1893980 sent adjust 8192 debug2: channel 1: window 1902172 sent adjust 8192 debug2: channel 1: window 1910364 sent adjust 8192 debug2: channel 1: window 1918556 sent adjust 8192 debug2: channel 1: window 1926748 sent adjust 8192 debug2: channel 1: window 1934940 sent adjust 8192 debug2: channel 1: window 1943132 sent adjust 8192 debug2: channel 1: window 1951324 sent adjust 8192 debug2: channel 1: window 1959516 sent adjust 8192 debug2: channel 1: window 1967708 sent adjust 8192 debug2: channel 1: window 1975900 sent adjust 8192 debug2: channel 1: window 1984092 sent adjust 8192 debug2: channel 1: window 1992284 sent adjust 8192 debug2: channel 1: window 2000476 sent adjust 8192 debug2: channel 1: window 2008668 sent adjust 8192 debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug2: channel 1: window 2016860 sent adjust 8192 debug2: channel 1: window 2025052 sent adjust 8192 debug2: channel 1: window 2033244 sent adjust 8192 debug2: channel 1: window 2041436 sent adjust 8192 debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug2: channel 1: window 2047952 sent adjust 49200 debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: receive packet: type 96 debug2: channel 0: rcvd eof debug2: channel 0: output open -> drain debug2: channel 0: obuf empty debug2: chan_shutdown_write: channel 0: (i0 o1 sock -1 wfd 5 efd 6 [write]) debug2: channel 0: output drain -> closed debug3: receive packet: type 96 debug2: channel 1: rcvd eof debug2: channel 1: output open -> drain debug2: channel 1: obuf empty debug2: chan_shutdown_write: channel 1: (i0 o1 sock 7 wfd 7 efd -1 [closed]) debug2: channel 1: output drain -> closed debug1: channel 1: FORCE input drain debug2: channel 1: ibuf empty debug2: channel 1: send eof debug3: send packet: type 96 debug2: channel 1: input drain -> closed debug2: channel 1: send close debug3: send packet: type 97 debug3: receive packet: type 98 debug1: client_input_channel_req: channel 0 rtype exit-status reply 0 debug3: receive packet: type 98 debug1: client_input_channel_req: channel 0 rtype eow at openssh.com reply 0 debug2: channel 0: rcvd eow debug2: chan_shutdown_read: channel 0: (i0 o3 sock -1 wfd 4 efd 6 [write]) debug2: channel 0: input open -> closed debug3: receive packet: type 97 debug2: channel 0: rcvd close debug3: channel 0: will not send data after close debug3: channel 1: will not send data after close debug2: channel 0: almost dead debug2: channel 0: gc: notify user debug2: channel 0: gc: user detached debug2: channel 0: send close debug3: send packet: type 97 debug2: channel 0: is dead debug2: channel 0: garbage collecting debug1: channel 0: free: client-session, nchannels 2 debug3: channel 0: status: The following connections are open: #0 client-session (t4 [session] r0 i3/0 o3/0 e[write]/0 fd -1/-1/6 sock -1 cc -1 io 0x00/0x00) #1 x11 (t4 [x11] r3 i3/0 o3/0 e[closed]/0 fd 7/7/-1 sock 7 cc -1 io 0x00/0x00) debug3: channel 1: will not send data after close debug3: channel 1: will not send data after close debug3: receive packet: type 97 debug2: channel 1: rcvd close debug3: channel 1: will not send data after close debug2: channel 1: is dead debug2: channel 1: garbage collecting debug1: channel 1: free: x11, nchannels 1 debug3: channel 1: status: The following connections are open: #1 x11 (t4 [x11] r3 i3/0 o3/0 e[closed]/0 fd 7/7/-1 sock 7 cc -1 io 0x00/0x00) debug3: send packet: type 1 Transferred: sent 45440, received 875556 bytes, in 11.0 seconds Bytes per second: sent 4142.1, received 79811.1 debug1: Exit status 0 screen$ bbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbb bbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbb <bbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbb bbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbb <bbbbbbbbbbbbbbb /bin/ksh: bbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbb: not found screen$ exit Script done on Wed Nov 8 11:01:38 2023 Script started on Wed Nov 8 11:37:44 2023 screen$ echo "Do 'ssh ... idle3.9' without any key kept pressed" Do 'ssh ... idle3.9' without any key kept pressed screen$ echo "Type some 'c's before starting open file dialogue" Type some 'c's before starting open file dialogue screen$ echo "Do pointer equivalent of 'Aly-f o' then switch focus to script" Do pointer equivalent of 'Aly-f o' then switch focus to script screen$ echo "and keep 'b' key pressed until dialogue appears" and keep 'b' key pressed until dialogue appears screen$ echo " Dismiss dialogue by 'Esc' without any key kept pressed" Dismiss dialogue by 'Esc' without any key kept pressed screen$ echo "Quit by 'Alt-f x' without any key kept presssed" Quit by 'Alt-f x' without any key kept presssed screen$ screen$ screen$ screen$ echo "System details first" System details first screen$ uname -a OpenBSD screen.home 7.4 GENERIC.MP#1397 amd64 screen$ ssh chess2 Last login: Wed Nov 8 11:32:15 2023 from screen FreeBSD 13.2-RELEASE releng/13.2-n254617-525ecfdad597 GENERIC Welcome to FreeBSD! Release Notes, Errata: https://www.FreeBSD.org/releases/ Security Advisories: https://www.FreeBSD.org/security/ FreeBSD Handbook: https://www.FreeBSD.org/handbook/ FreeBSD FAQ: https://www.FreeBSD.org/faq/ Questions List: https://www.FreeBSD.org/lists/questions/ FreeBSD Forums: https://forums.FreeBSD.org/ Documents installed with the system are in the /usr/local/share/doc/freebsd/ directory, or can be installed later with: pkg install en-freebsd-doc For other languages, replace "en" with a language code like de or fr. Show the version of FreeBSD installed: freebsd-version ; uname -a Please include that output and any error messages when posting questions. Introduction to manual pages: man man FreeBSD directory layout: man hier To change this login announcement, see motd(5). Don't let your zpool fill up completely by creating a dataset with reservation. # zfs create -o refreservation=<5% of total pool space> <poolname>/reserved You can always shrink the reserve if you need the space, but your pool will always have space left this way. -- Benedict Reuschling <bcr at FreeBSD.org> roger at chess2:~ $ ssh -V OpenSSH_9.3p1, OpenSSL 1.1.1t-freebsd 7 Feb 2023 roger at chess2:~ $ sshd -V OpenSSH_9.3, OpenSSL 1.1.1t-freebsd 7 Feb 2023 roger at chess2:~ $ exit Connection to chess2 closed. screen$ ssh -V OpenSSH_9.5, LibreSSL 3.8.2 screen$ sshd -V OpenSSH_9.5, LibreSSL 3.8.2 screen$ screen$ screen$ screen$ ssh -vvv -Y chess2 idle3.9 OpenSSH_9.5, LibreSSL 3.8.2 debug1: Reading configuration data /etc/ssh/ssh_config debug3: expanded UserKnownHostsFile '~/.ssh/known_hosts' -> '/home/roger/.ssh/known_hosts' debug3: expanded UserKnownHostsFile '~/.ssh/known_hosts2' -> '/home/roger/.ssh/known_hosts2' debug2: resolving "chess2" port 22 debug3: resolve_host: lookup chess2:22 debug3: ssh_connect_direct: entering debug1: Connecting to chess2 [192.168.5.23] port 22. debug3: set_sock_tos: set socket 3 IP_TOS 0x48 debug1: Connection established. debug1: identity file /home/roger/.ssh/id_rsa type 0 debug1: identity file /home/roger/.ssh/id_rsa-cert type -1 debug1: identity file /home/roger/.ssh/id_ecdsa type -1 debug1: identity file /home/roger/.ssh/id_ecdsa-cert type -1 debug1: identity file /home/roger/.ssh/id_ecdsa_sk type -1 debug1: identity file /home/roger/.ssh/id_ecdsa_sk-cert type -1 debug1: identity file /home/roger/.ssh/id_ed25519 type -1 debug1: identity file /home/roger/.ssh/id_ed25519-cert type -1 debug1: identity file /home/roger/.ssh/id_ed25519_sk type -1 debug1: identity file /home/roger/.ssh/id_ed25519_sk-cert type -1 debug1: identity file /home/roger/.ssh/id_xmss type -1 debug1: identity file /home/roger/.ssh/id_xmss-cert type -1 debug1: identity file /home/roger/.ssh/id_dsa type -1 debug1: identity file /home/roger/.ssh/id_dsa-cert type -1 debug1: Local version string SSH-2.0-OpenSSH_9.5 debug1: Remote protocol version 2.0, remote software version OpenSSH_9.3 FreeBSD-20230316 debug1: compat_banner: match: OpenSSH_9.3 FreeBSD-20230316 pat OpenSSH* compat 0x04000000 debug2: fd 3 setting O_NONBLOCK debug1: Authenticating to chess2:22 as 'roger' debug3: record_hostkey: found key type ED25519 in file /home/roger/.ssh/known_hosts:18 debug3: record_hostkey: found key type RSA in file /home/roger/.ssh/known_hosts:19 debug3: record_hostkey: found key type ECDSA in file /home/roger/.ssh/known_hosts:20 debug3: load_hostkeys_file: loaded 3 keys from chess2 debug1: load_hostkeys: fopen /home/roger/.ssh/known_hosts2: No such file or directory debug1: load_hostkeys: fopen /etc/ssh/ssh_known_hosts: No such file or directory debug1: load_hostkeys: fopen /etc/ssh/ssh_known_hosts2: No such file or directory debug3: order_hostkeyalgs: have matching best-preference key type ssh-ed25519-cert-v01 at openssh.com, using HostkeyAlgorithms verbatim debug3: send packet: type 20 debug1: SSH2_MSG_KEXINIT sent debug3: receive packet: type 20 debug1: SSH2_MSG_KEXINIT received debug2: local client KEXINIT proposal debug2: KEX algorithms: sntrup761x25519-sha512 at openssh.com,curve25519-sha256,curve25519-sha256 at libssh.org,ecdh-sha2-nistp256,ecdh-sha2-nistp384,ecdh-sha2-nistp521,diffie-hellman-group-exchange-sha256,diffie-hellman-group16-sha512,diffie-hellman-group18-sha512,diffie-hellman-group14-sha256,ext-info-c debug2: host key algorithms: ssh-ed25519-cert-v01 at openssh.com,ecdsa-sha2-nistp256-cert-v01 at openssh.com,ecdsa-sha2-nistp384-cert-v01 at openssh.com,ecdsa-sha2-nistp521-cert-v01 at openssh.com,sk-ssh-ed25519-cert-v01 at openssh.com,sk-ecdsa-sha2-nistp256-cert-v01 at openssh.com,rsa-sha2-512-cert-v01 at openssh.com,rsa-sha2-256-cert-v01 at openssh.com,ssh-ed25519,ecdsa-sha2-nistp256,ecdsa-sha2-nistp384,ecdsa-sha2-nistp521,sk-ssh-ed25519 at openssh.com,sk-ecdsa-sha2-nistp256 at openssh.com,rsa-sha2-512,rsa-sha2-256 debug2: ciphers ctos: chacha20-poly1305 at openssh.com,aes128-ctr,aes192-ctr,aes256-ctr,aes128-gcm at openssh.com,aes256-gcm at openssh.com debug2: ciphers stoc: chacha20-poly1305 at openssh.com,aes128-ctr,aes192-ctr,aes256-ctr,aes128-gcm at openssh.com,aes256-gcm at openssh.com debug2: MACs ctos: umac-64-etm at openssh.com,umac-128-etm at openssh.com,hmac-sha2-256-etm at openssh.com,hmac-sha2-512-etm at openssh.com,hmac-sha1-etm at openssh.com,umac-64 at openssh.com,umac-128 at openssh.com,hmac-sha2-256,hmac-sha2-512,hmac-sha1 debug2: MACs stoc: umac-64-etm at openssh.com,umac-128-etm at openssh.com,hmac-sha2-256-etm at openssh.com,hmac-sha2-512-etm at openssh.com,hmac-sha1-etm at openssh.com,umac-64 at openssh.com,umac-128 at openssh.com,hmac-sha2-256,hmac-sha2-512,hmac-sha1 debug2: compression ctos: none,zlib at openssh.com,zlib debug2: compression stoc: none,zlib at openssh.com,zlib debug2: languages ctos: debug2: languages stoc: debug2: first_kex_follows 0 debug2: reserved 0 debug2: peer server KEXINIT proposal debug2: KEX algorithms: sntrup761x25519-sha512 at openssh.com,curve25519-sha256,curve25519-sha256 at libssh.org,ecdh-sha2-nistp256,ecdh-sha2-nistp384,ecdh-sha2-nistp521,diffie-hellman-group-exchange-sha256,diffie-hellman-group16-sha512,diffie-hellman-group18-sha512,diffie-hellman-group14-sha256 debug2: host key algorithms: rsa-sha2-512,rsa-sha2-256,ssh-rsa,ecdsa-sha2-nistp256,ssh-ed25519 debug2: ciphers ctos: chacha20-poly1305 at openssh.com,aes128-ctr,aes192-ctr,aes256-ctr,aes128-gcm at openssh.com,aes256-gcm at openssh.com debug2: ciphers stoc: chacha20-poly1305 at openssh.com,aes128-ctr,aes192-ctr,aes256-ctr,aes128-gcm at openssh.com,aes256-gcm at openssh.com debug2: MACs ctos: umac-64-etm at openssh.com,umac-128-etm at openssh.com,hmac-sha2-256-etm at openssh.com,hmac-sha2-512-etm at openssh.com,hmac-sha1-etm at openssh.com,umac-64 at openssh.com,umac-128 at openssh.com,hmac-sha2-256,hmac-sha2-512,hmac-sha1 debug2: MACs stoc: umac-64-etm at openssh.com,umac-128-etm at openssh.com,hmac-sha2-256-etm at openssh.com,hmac-sha2-512-etm at openssh.com,hmac-sha1-etm at openssh.com,umac-64 at openssh.com,umac-128 at openssh.com,hmac-sha2-256,hmac-sha2-512,hmac-sha1 debug2: compression ctos: none,zlib at openssh.com debug2: compression stoc: none,zlib at openssh.com debug2: languages ctos: debug2: languages stoc: debug2: first_kex_follows 0 debug2: reserved 0 debug1: kex: algorithm: sntrup761x25519-sha512 at openssh.com debug1: kex: host key algorithm: ssh-ed25519 debug1: kex: server->client cipher: chacha20-poly1305 at openssh.com MAC: <implicit> compression: none debug1: kex: client->server cipher: chacha20-poly1305 at openssh.com MAC: <implicit> compression: none debug3: send packet: type 30 debug1: expecting SSH2_MSG_KEX_ECDH_REPLY debug3: receive packet: type 31 debug1: SSH2_MSG_KEX_ECDH_REPLY received debug1: Server host key: ssh-ed25519 SHA256:redacted debug3: record_hostkey: found key type ED25519 in file /home/roger/.ssh/known_hosts:18 debug3: record_hostkey: found key type RSA in file /home/roger/.ssh/known_hosts:19 debug3: record_hostkey: found key type ECDSA in file /home/roger/.ssh/known_hosts:20 debug3: load_hostkeys_file: loaded 3 keys from chess2 debug1: load_hostkeys: fopen /home/roger/.ssh/known_hosts2: No such file or directory debug1: load_hostkeys: fopen /etc/ssh/ssh_known_hosts: No such file or directory debug1: load_hostkeys: fopen /etc/ssh/ssh_known_hosts2: No such file or directory debug1: Host 'chess2' is known and matches the ED25519 host key. debug1: Found key in /home/roger/.ssh/known_hosts:18 debug3: send packet: type 21 debug2: ssh_set_newkeys: mode 1 debug1: rekey out after 134217728 blocks debug1: SSH2_MSG_NEWKEYS sent debug1: expecting SSH2_MSG_NEWKEYS debug3: receive packet: type 21 debug1: SSH2_MSG_NEWKEYS received debug2: ssh_set_newkeys: mode 0 debug1: rekey in after 134217728 blocks debug3: ssh_get_authentication_socket_path: path '/tmp/ssh-AV3ai4wQ1yej/agent.42920' debug1: get_agent_identities: bound agent to hostkey debug1: get_agent_identities: agent returned 1 keys debug1: Will attempt key: /home/roger/.ssh/id_rsa RSA SHA256:redacted agent debug1: Will attempt key: /home/roger/.ssh/id_ecdsa debug1: Will attempt key: /home/roger/.ssh/id_ecdsa_sk debug1: Will attempt key: /home/roger/.ssh/id_ed25519 debug1: Will attempt key: /home/roger/.ssh/id_ed25519_sk debug1: Will attempt key: /home/roger/.ssh/id_xmss debug1: Will attempt key: /home/roger/.ssh/id_dsa debug2: pubkey_prepare: done debug3: send packet: type 5 debug3: receive packet: type 7 debug1: SSH2_MSG_EXT_INFO received debug1: kex_input_ext_info: server-sig-algs=<ssh-ed25519,sk-ssh-ed25519 at openssh.com,ecdsa-sha2-nistp256,ecdsa-sha2-nistp384,ecdsa-sha2-nistp521,sk-ecdsa-sha2-nistp256 at openssh.com,webauthn-sk-ecdsa-sha2-nistp256 at openssh.com,ssh-dss,ssh-rsa,rsa-sha2-256,rsa-sha2-512> debug1: kex_ext_info_check_ver: publickey-hostbound at openssh.com=<0> debug3: receive packet: type 6 debug2: service_accept: ssh-userauth debug1: SSH2_MSG_SERVICE_ACCEPT received debug3: send packet: type 50 debug3: receive packet: type 51 debug1: Authentications that can continue: publickey debug3: start over, passed a different list publickey debug3: preferred publickey,keyboard-interactive,password debug3: authmethod_lookup publickey debug3: remaining preferred: keyboard-interactive,password debug3: authmethod_is_enabled publickey debug1: Next authentication method: publickey debug1: Offering public key: /home/roger/.ssh/id_rsa RSA SHA256:redacted agent debug3: send packet: type 50 debug2: we sent a publickey packet, wait for reply debug3: receive packet: type 60 debug1: Server accepts key: /home/roger/.ssh/id_rsa RSA SHA256:redacted agent debug3: sign_and_send_pubkey: using publickey-hostbound-v00 at openssh.com with RSA SHA256:redacted debug3: sign_and_send_pubkey: signing using rsa-sha2-512 SHA256:redacted debug3: send packet: type 50 debug3: receive packet: type 52 Authenticated to chess2 ([192.168.5.23]:22) using "publickey". debug1: channel 0: new session [client-session] (inactive timeout: 0) debug3: ssh_session2_open: channel_new: 0 debug2: channel 0: send open debug3: send packet: type 90 debug1: Requesting no-more-sessions at openssh.com debug3: send packet: type 80 debug1: Entering interactive session. debug1: pledge: exec debug3: client_repledge: enter debug3: receive packet: type 80 debug1: client_input_global_request: rtype hostkeys-00 at openssh.com want_reply 0 debug3: client_input_hostkeys: received RSA key SHA256:redacted debug3: client_input_hostkeys: received ECDSA key SHA256:redacted debug3: client_input_hostkeys: received ED25519 key SHA256:redacted debug1: client_input_hostkeys: searching /home/roger/.ssh/known_hosts for chess2 / (none) debug3: hostkeys_foreach: reading file "/home/roger/.ssh/known_hosts" debug3: hostkeys_find: found ssh-ed25519 key at /home/roger/.ssh/known_hosts:18 debug3: hostkeys_find: found ssh-rsa key at /home/roger/.ssh/known_hosts:19 debug3: hostkeys_find: found ecdsa-sha2-nistp256 key at /home/roger/.ssh/known_hosts:20 debug1: client_input_hostkeys: searching /home/roger/.ssh/known_hosts2 for chess2 / (none) debug1: client_input_hostkeys: hostkeys file /home/roger/.ssh/known_hosts2 does not exist debug3: client_input_hostkeys: 3 server keys: 0 new, 3 retained, 0 incomplete match. 0 to remove debug1: client_input_hostkeys: no new or deprecated keys from server debug3: client_repledge: enter debug3: receive packet: type 4 debug1: Remote: /home/roger/.ssh/authorized_keys:2: key options: agent-forwarding port-forwarding pty user-rc x11-forwarding debug3: receive packet: type 4 debug1: Remote: /home/roger/.ssh/authorized_keys:2: key options: agent-forwarding port-forwarding pty user-rc x11-forwarding debug3: receive packet: type 91 debug2: channel_input_open_confirmation: channel 0: callback start debug2: x11_get_proto: /usr/X11R6/bin/xauth list :0 2>/dev/null debug1: Requesting X11 forwarding with authentication spoofing. debug2: channel 0: request x11-req confirm 1 debug3: send packet: type 98 debug2: fd 3 setting TCP_NODELAY debug3: set_sock_tos: set socket 3 IP_TOS 0x48 debug2: client_session2_setup: id 0 debug1: Sending command: idle3.9 debug2: channel 0: request exec confirm 1 debug3: send packet: type 98 debug3: client_repledge: enter debug2: channel_input_open_confirmation: channel 0: callback done debug2: channel 0: open confirm rwindow 0 rmax 32768 debug3: receive packet: type 99 debug2: channel_input_status_confirm: type 99 id 0 debug2: X11 forwarding request accepted on channel 0 debug2: channel 0: rcvd adjust 2097152 debug3: receive packet: type 99 debug2: channel_input_status_confirm: type 99 id 0 debug2: exec request accepted on channel 0 debug3: receive packet: type 90 debug1: client_input_channel_open: ctype x11 rchan 3 win 65536 max 16384 debug1: client_request_x11: request from 127.0.0.1 36792 debug2: fd 7 setting O_NONBLOCK debug3: fd 7 is O_NONBLOCK debug1: channel 1: new x11 [x11] (inactive timeout: 0) debug1: confirm x11 debug3: send packet: type 91 debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug2: channel 1: window 2047152 sent adjust 41808 debug2: channel 1: window 2039808 sent adjust 40960 debug2: channel 1: window 2031616 sent adjust 24576 debug2: channel 1: window 2039808 sent adjust 16384 debug2: channel 1: window 2039808 sent adjust 16384 debug2: channel 1: window 2039808 sent adjust 16384 debug2: channel 1: window 2039808 sent adjust 8192 debug2: channel 1: window 2031616 sent adjust 8192 debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug2: channel 1: window 2007040 sent adjust 8192 debug2: channel 1: window 1984372 sent adjust 8192 debug2: channel 1: window 1992564 sent adjust 8192 debug2: channel 1: window 2000756 sent adjust 8192 debug2: channel 1: window 2008948 sent adjust 8192 debug2: channel 1: window 2017140 sent adjust 8192 debug2: channel 1: window 2025332 sent adjust 8192 debug2: channel 1: window 2033524 sent adjust 8192 debug2: channel 1: window 2041716 sent adjust 8192 debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug2: channel 1: window 2047988 sent adjust 49164 debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug2: channel 1: window 2028592 sent adjust 27600 debug2: channel 1: window 2007040 sent adjust 8192 debug2: channel 1: window 1966080 sent adjust 8192 debug2: channel 1: window 1925120 sent adjust 8192 debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug2: channel 1: window 1884160 sent adjust 8192 debug2: channel 1: window 1877028 sent adjust 8192 debug2: channel 1: window 1885220 sent adjust 8192 debug2: channel 1: window 1893412 sent adjust 8192 debug2: channel 1: window 1901604 sent adjust 8192 debug2: channel 1: window 1909796 sent adjust 8192 debug2: channel 1: window 1917988 sent adjust 8192 debug2: channel 1: window 1926180 sent adjust 8192 debug2: channel 1: window 1934372 sent adjust 8192 debug2: channel 1: window 1942564 sent adjust 8192 debug2: channel 1: window 1950756 sent adjust 8192 debug2: channel 1: window 1958948 sent adjust 8192 debug2: channel 1: window 1967140 sent adjust 8192 debug2: channel 1: window 1975332 sent adjust 8192 debug2: channel 1: window 1983524 sent adjust 8192 debug2: channel 1: window 1991716 sent adjust 8192 debug2: channel 1: window 1999908 sent adjust 8192 debug2: channel 1: window 2008100 sent adjust 8192 debug2: channel 1: window 2016292 sent adjust 8192 debug2: channel 1: window 2024484 sent adjust 8192 debug2: channel 1: window 2032676 sent adjust 8192 debug2: channel 1: window 2040868 sent adjust 8192 debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug2: channel 1: window 2047668 sent adjust 49484 debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: chaff time expired (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: chaff time expired (0 chaff packets sent) ccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccdebug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug2: channel 1: window 2036724 sent adjust 52236 debug2: channel 1: window 2039808 sent adjust 16384 debug2: channel 1: window 1990656 sent adjust 8192 debug2: channel 1: window 1933312 sent adjust 8192 debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug2: channel 1: window 1893980 sent adjust 8192 debug2: channel 1: window 1902172 sent adjust 8192 debug2: channel 1: window 1910364 sent adjust 8192 debug2: channel 1: window 1918556 sent adjust 8192 debug2: channel 1: window 1926748 sent adjust 8192 debug2: channel 1: window 1934940 sent adjust 8192 debug2: channel 1: window 1943132 sent adjust 8192 debug2: channel 1: window 1951324 sent adjust 8192 debug2: channel 1: window 1959516 sent adjust 8192 debug2: channel 1: window 1967708 sent adjust 8192 debug2: channel 1: window 1975900 sent adjust 8192 debug2: channel 1: window 1984092 sent adjust 8192 debug2: channel 1: window 1992284 sent adjust 8192 debug2: channel 1: window 2000476 sent adjust 8192 debug2: channel 1: window 2008668 sent adjust 8192 debug2: channel 1: window 2016860 sent adjust 8192 debug2: channel 1: window 2025052 sent adjust 8192 debug2: channel 1: window 2033244 sent adjust 8192 debug2: channel 1: window 2041436 sent adjust 8192 debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug2: channel 1: rcvd adjust 32772 debug2: channel 1: window 2047800 sent adjust 49352 debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms bbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbdebug2: channel 1: window 2016740 sent adjust 39452 debug2: channel 1: window 1974272 sent adjust 8192 debug2: channel 1: window 1853128 sent adjust 8192 debug2: channel 1: window 1861320 sent adjust 8192 debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug2: channel 1: window 1869512 sent adjust 8192 debug2: channel 1: window 1877704 sent adjust 8192 debug2: channel 1: window 1885896 sent adjust 8192 debug2: channel 1: window 1894088 sent adjust 8192 debug2: channel 1: window 1902280 sent adjust 8192 debug2: channel 1: window 1910472 sent adjust 8192 debug2: channel 1: window 1918664 sent adjust 8192 debug2: channel 1: window 1926856 sent adjust 8192 debug2: channel 1: window 1935048 sent adjust 8192 debug2: channel 1: window 1943240 sent adjust 8192 debug2: channel 1: window 1951432 sent adjust 8192 debug2: channel 1: window 1959624 sent adjust 8192 debug2: channel 1: window 1967816 sent adjust 8192 debug2: channel 1: window 1976008 sent adjust 8192 debug2: channel 1: window 1984200 sent adjust 8192 debug2: channel 1: window 1992392 sent adjust 8192 debug2: channel 1: window 2000584 sent adjust 8192 debug2: channel 1: window 2008776 sent adjust 8192 debug2: channel 1: window 2016968 sent adjust 8192 bdebug2: channel 1: window 2025160 sent adjust 8192 debug2: channel 1: window 2033352 sent adjust 8192 debug2: channel 1: window 2041544 sent adjust 8192 debug3: obfuscate_keystroke_timing: starting: interval ~20ms bdebug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms bbbbbbbbdebug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms bbbbbbbdebug2: channel 1: window 2047992 sent adjust 49160 bbbbbbbbbbbdebug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms bdebug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms bdebug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms bbbbbdebug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms bbbbbbbbdebug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms bdebug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) bbdebug3: obfuscate_keystroke_timing: starting: interval ~20ms bbbbbbbbbbbbbbbbdebug2: channel 1: rcvd adjust 32772 bbbbbbbbbbbbbbbbbbbbbdebug2: channel 1: window 2047816 sent adjust 49336 debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: chaff time expired (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: obfuscate_keystroke_timing: stopping: output buffer filling (0 chaff packets sent) debug3: obfuscate_keystroke_timing: starting: interval ~20ms debug3: receive packet: type 96 debug2: channel 0: rcvd eof debug2: channel 0: output open -> drain debug2: channel 0: obuf empty debug2: chan_shutdown_write: channel 0: (i0 o1 sock -1 wfd 5 efd 6 [write]) debug2: channel 0: output drain -> closed debug3: receive packet: type 96 debug2: channel 1: rcvd eof debug2: channel 1: output open -> drain debug2: channel 1: obuf empty debug2: chan_shutdown_write: channel 1: (i0 o1 sock 7 wfd 7 efd -1 [closed]) debug2: channel 1: output drain -> closed debug1: channel 1: FORCE input drain debug2: channel 1: ibuf empty debug2: channel 1: send eof debug3: send packet: type 96 debug2: channel 1: input drain -> closed debug2: channel 1: send close debug3: send packet: type 97 debug3: receive packet: type 98 debug1: client_input_channel_req: channel 0 rtype exit-status reply 0 debug3: receive packet: type 98 debug1: client_input_channel_req: channel 0 rtype eow at openssh.com reply 0 debug2: channel 0: rcvd eow debug2: chan_shutdown_read: channel 0: (i0 o3 sock -1 wfd 4 efd 6 [write]) debug2: channel 0: input open -> closed debug3: receive packet: type 97 debug2: channel 0: rcvd close debug3: channel 0: will not send data after close debug3: channel 1: will not send data after close debug2: channel 0: almost dead debug2: channel 0: gc: notify user debug2: channel 0: gc: user detached debug2: channel 0: send close debug3: send packet: type 97 debug2: channel 0: is dead debug2: channel 0: garbage collecting debug1: channel 0: free: client-session, nchannels 2 debug3: channel 0: status: The following connections are open: #0 client-session (t4 [session] r0 i3/0 o3/0 e[write]/0 fd -1/-1/6 sock -1 cc -1 io 0x00/0x00) #1 x11 (t4 [x11] r3 i3/0 o3/0 e[closed]/0 fd 7/7/-1 sock 7 cc -1 io 0x00/0x00) debug3: channel 1: will not send data after close debug3: channel 1: will not send data after close debug3: receive packet: type 97 debug2: channel 1: rcvd close debug3: channel 1: will not send data after close debug2: channel 1: is dead debug2: channel 1: garbage collecting debug1: channel 1: free: x11, nchannels 1 debug3: channel 1: status: The following connections are open: #1 x11 (t4 [x11] r3 i3/0 o3/0 e[closed]/0 fd 7/7/-1 sock 7 cc -1 io 0x00/0x00) debug3: send packet: type 1 Transferred: sent 104712, received 1253692 bytes, in 124.7 seconds Bytes per second: sent 839.6, received 10052.7 debug1: Exit status 0 screen$ cccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccc ccccccccccccccccccccccccccccccccccc <ccccccccccccccccccccccccccccccccccccccccccc ccccccccccccccccccccccccccccccccccccccc <ccccccccccccccccccccccccccccccccccccccc ccccccccccccccccccccccccccccccccccccccc <ccccccccccccccccccccccccccccccccccccccc ccccccccccccccccccccccccccccccccccccccc <ccccccccccccccccccccccccccccccccccccccc ccccccccccccccccccccccccccccccccccccccc <ccccccccccccccccbbbbbbbbbbbbbbbbbbbbbbb ccccccccccccccccbbbbbbbbbbbbbbbbbbbbbbb <bbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbb bbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbb <bbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbb bbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbb <bbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbb bbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbb <bbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbb bbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbb <bbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbb bbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbb <bbbbbbbbbbb /bin/ksh: ccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccccbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbb: not found screen$ exit Script done on Wed Nov 8 11:46:06 2023 Roger On Fri, 3 Nov 2023 11:48:38 +1100 (AEDT) Damien Miller <djm at mindrot.org> wrote:> On Fri, 27 Oct 2023, Roger Marsh wrote: > > This is missing a lot of context, and I had to go to the misc@ archives to > find out the actual command that is running slow for you. > > AFAIK, it's > ssh -Y opendev xterm -title roger at opendev > > Please get a debug trace from the ssh connection, e.g. "ssh -vvv -Y ..." > and annotate where the delay happens. Without this, we're not in a position > to help. > > -d > > > Context is provided by the forwarded message: the most recent (final?) post on > > > > Subject: Re: Delay in starting xterm via ssh after upgrade from 7.3 to 7.4 > > > > on misc at openbsd.org > > > > Begin forwarded message: > > > > On 2023-10-25, Roger Marsh <r.m at rmswch.plus.com> wrote: > > > On 23 Oct 2023 09:32:19 -0600 > > > "Andy Bradford" <amb-sendok-1700667140.jgjncfcjadjlnbiepadd at bradfordsorg> wrote: > > > > > >> Thus said Roger Marsh on Thu, 19 Oct 2023 17:23:47 -0000: > > >> > > >> > fixes the delay problem, but was the delay a predictable consequence > > >> > of some change? Or perhaps the entry should never have been expressed > > >> > in the way that led to the delay? > > >> > > >> Most likely the cause is an unexpected side effect of some other change. > > >> There have been some interesting changes to SSH with this release, > > >> perhaps try disabling: > > >> > > >> http://man.openbsd.org/OpenBSD-7.4/ssh_config#ObscureKeystrokeTiming > > >> > > >> I would be surprised if this is actually the cause, but it is a change > > >> that was introduced and something that is easily tested. > > >> > > >> You could also look through: > > >> > > >> http://www.openbsd.org/plus74.html > > >> > > >> See if any of the changes stand out as relevant and try to test them. > > >> > > >> Andy > > >> > > > > > > Thanks. > > > > > > ObscureKeystokeTiming turned out to be the cause, or so it seemed after reading the ssh_config reference. Setting this option to 'no' whereever ssh is used in the .fvwmrc configuration file got rid of the response time problem. > > > > > > I remember looking at plus74.html some time before formal release. If ObscureKeystrokeTiming had caught my eye I think I would not have associated it with the possibility of a large increase in response time. > > > > > > The line in plus74.html immediately before the one about ObscureKeystokeTiming states 'Limit artificial login delay ...'. That change looks a more likely cause than 'ObscureKeystokeTiming' measures. > > > > > > Roger > > > > > > > > > > Almost the first thing done after adjusting the .fvwmrc file was open up a Python Idle session on a FreeBSD 13.2 installation by ssh from the OpenBSD 7.4 box in the Xserver role. The delay was instantly obvious, both starting the session and opening *.py files (or anything else I suppose) from the Idle menu. > > > > This was unexpected too. > > > > After reverting the OpenBSD Xserver box to 7.3 response times are back to what they were before any OpenBSD boxes were upgraded from 7.3 to 7.4. The plan is to keep the OpenBSD Xserver box at 7.3 until the next upgrade of the FreeBSD 13.2 box when 14.0 is released (it's well into release candidates right now) and hope it has OpenSSH 9.4 and fixes the problem. > > > > On the FreeBSD 13.2 box 'ssh -V' says OpenSSH_9.3p1 and 'sshd -V' says OpenSSH_9.3, while both say OpenSSH_9.3 on the OpenBSD 7.3 box. > > > > I would rather not be relying on hope. > > > > Roger > > _______________________________________________ > > openssh-unix-dev mailing list > > openssh-unix-dev at mindrot.org > > https://lists.mindrot.org/mailman/listinfo/openssh-unix-dev > > > _______________________________________________ > openssh-unix-dev mailing list > openssh-unix-dev at mindrot.org > https://lists.mindrot.org/mailman/listinfo/openssh-unix-dev
Thorsten Glaser
2023-Nov-08 18:39 UTC
Delay in starting programs on FreeBSD via ssh after upgrade OpenBSD from 7.3 to 7.4
On Wed, 8 Nov 2023, Roger Marsh wrote:>Adding '-o "ObscureKeystokeTiming no"' into the command gets rid of theHmm, shouldn?t ObscureKeystrokeTiming (with r) only apply to sessions with a tty? Otherwise it would also slow down rsync, anoncvs, etc. bye, //mirabilos -- 15:41?<Lo-lan-do:#fusionforge> Somebody write a testsuite for helloworld :-)
Damien Miller
2023-Nov-08 21:21 UTC
Delay in starting programs on FreeBSD via ssh after upgrade OpenBSD from 7.3 to 7.4
On Wed, 8 Nov 2023, Roger Marsh wrote:> Damien, > > Sorry about distributed context. > > Those discussions told me about the new ObscureKeystrokeTiming > argument to the ssh command. One reply suggested I try that because it > is easy to test. > > Most of my xterm ssh command combinations in fvwm configuration file > are expressed 'Exec exec xterm -title ... -e ssh <user>@<host>' and do > not have a noticable delay. > > One is expressed 'Exec exec ssh -Y opendev xterm -title > roger at opendev', which you mention, and does have a noticable delay. It > seemed far easier to find out what was going on by asking about this > command rather than the formally identical 'ssh -Y <hostname> idle3.n' > command: xterm is in OpenBSD base so asking a Python question on > ports@ was probably too wide. > > It is the 'ssh -Y <hostname> idle3.n' command that worries me, and > two scripts with 'ssh -vvv -Y ...' traces follow. The first is for > starting idle, and the second is for invoking the open file dialogue. > > Adding '-o "ObscureKeystokeTiming no"' into the command gets rid > of the delay: but I count this as a workaround unless told this is > how the command is supposed to be done at OpenSSH 9.5 compared with > OpenSSH 9.3.It's possible the timing attack mitigation interacts badly with X11 forwarding. Could you try this patch? If you still have problems, then please post another -vvv debug trace. diff --git a/channels.c b/channels.c index 1b310e3..111c808 100644 --- a/channels.c +++ b/channels.c @@ -886,6 +886,23 @@ channel_still_open(struct ssh *ssh) return 0; } +/* Returns true if a channel with a TTY is open. */ +int +channel_tty_open(struct ssh *ssh) +{ + u_int i; + Channel *c; + + for (i = 0; i < ssh->chanctxt->channels_alloc; i++) { + c = ssh->chanctxt->channels[i]; + if (c == NULL || c->type != SSH_CHANNEL_OPEN) + continue; + if (c->client_tty) + return 1; + } + return 0; +} + /* Returns the id of an open channel suitable for keepaliving */ int channel_find_open(struct ssh *ssh) diff --git a/channels.h b/channels.h index 7cfba92..c31733f 100644 --- a/channels.h +++ b/channels.h @@ -337,6 +337,7 @@ int channel_output_poll(struct ssh *); int channel_not_very_much_buffered_data(struct ssh *); void channel_close_all(struct ssh *); int channel_still_open(struct ssh *); +int channel_tty_open(struct ssh *); const char *channel_format_extended_usage(const Channel *); char *channel_open_message(struct ssh *); int channel_find_open(struct ssh *); diff --git a/clientloop.c b/clientloop.c index deebbba..cb69c72 100644 --- a/clientloop.c +++ b/clientloop.c @@ -580,7 +580,7 @@ obfuscate_keystroke_timing(struct ssh *ssh, struct timespec *timeout, if (options.obscure_keystroke_timing_interval <= 0) return 1; /* disabled in config */ - if (!channel_still_open(ssh) || quit_pending) { + if (!channel_tty_open(ssh) || quit_pending) { /* Stop if no channels left of we're waiting for one to close */ stop_reason = "no active channels"; } else if (ssh_packet_is_rekeying(ssh)) {
Seemingly Similar Threads
- Privacy improving suggestions for ObscureKeystrokeTiming
- [PATCH] fix for Linux hang on exit bug in 2.9.9p2
- [PATCH]: Patch to fix hang on exit bug under Linux and add optional exit delay
- sshd hangs on logout -- is this a bug?
- SSH connection hanging on logout