Excessive delay at ssh connection to remote host
James Moe
moe.james at sohnen-moe.com
Tue Oct 6 11:36:57 AEDT 2026
On 2026-10-04 12:59, James Moe via openssh-unix-dev wrote:
> I have already provided a log file using the above (only with -d -d). The issue
> for me was that there are no timestamps in the log and I could not parse where
> the delay(s) occurred.
>
/usr/sbin/sshd -p 2222 -e -d -d -d 2>&1 | ts | tee /tmp/sshd-2222.txt
Nothing happens for over 2 minutes. This does not appear to be a sshd problem,
although it may still be a configuration issue. The delay is only associated
with this one host.
Any ideas about what may be causing the delay?
----[ sshd log of remote host ]----
...bunch o' initialization...
Oct 05 13:00:30 debug2: fd 7 setting O_NONBLOCK
Oct 05 13:00:30 debug3: sock_set_v6only: set socket 7 IPV6_V6ONLY
Oct 05 13:00:30 debug1: Bind to port 2222 on ::.
Oct 05 13:00:30 Server listening on :: port 2222.
It is here that I started a connection at the local host at 13:00:41.
Oct 05 13:02:57 debug3: fd 8 is not O_NONBLOCK
Oct 05 13:02:57 debug1: Server will not fork when running in debugging mode.
Oct 05 13:02:57 debug1: rexec start in 8 out 8 newsock 8 config_s 9/10
Oct 05 13:02:57 debug3: send_rexec_state: entering fd = 9 config len 7143
Oct 05 13:02:57 debug3: send_rexec_state: done
Oct 05 13:02:57 debug3: recv_rexec_state: entering fd = 4
Oct 05 13:02:57 debug3: ssh_msg_recv entering
Connection establishes normally.
----[ end ]---
--
James Moe
moe dot james at sohnen-moe dot com
520.743.3936
Think.
-------------- next part --------------
Oct 05 13:00:30 debug1: sshd version OpenSSH_10.5, OpenSSL 3.5.3 16 Sep 2025
Oct 05 13:00:30 debug3: Running on Linux 7.2.5-1-default #1 SMP PREEMPT_DYNAMIC Sat Sep 12 06:50:42 UTC 2026 (6b7e8a9) x86_64
Oct 05 13:00:30 debug3: Started with: /usr/sbin/sshd -p 2222 -e -d -d -d
Oct 05 13:00:30 debug2: load_server_config: filename /usr/etc/ssh/sshd_config
Oct 05 13:00:30 debug2: load_server_config: done config len = 4123
Oct 05 13:00:30 debug2: parse_server_config_depth: config /usr/etc/ssh/sshd_config len 4123
Oct 05 13:00:30 debug2: /usr/etc/ssh/sshd_config line 12: new include /etc/ssh/sshd_config.d/*.conf
Oct 05 13:00:30 debug2: /usr/etc/ssh/sshd_config line 12: no match for /etc/ssh/sshd_config.d/*.conf
Oct 05 13:00:30 debug2: /usr/etc/ssh/sshd_config line 18: new include /usr/etc/ssh/sshd_config.d/*.conf
Oct 05 13:00:30 debug2: /usr/etc/ssh/sshd_config line 18: including /usr/etc/ssh/sshd_config.d/40-suse-crypto-policies.conf
Oct 05 13:00:30 debug2: load_server_config: filename /usr/etc/ssh/sshd_config.d/40-suse-crypto-policies.conf
Oct 05 13:00:30 debug2: load_server_config: done config len = 413
Oct 05 13:00:30 debug2: parse_server_config_depth: config /usr/etc/ssh/sshd_config.d/40-suse-crypto-policies.conf len 413
Oct 05 13:00:30 debug2: /usr/etc/ssh/sshd_config.d/40-suse-crypto-policies.conf line 6: new include /etc/crypto-policies/back-ends/opensshserver.config
Oct 05 13:00:30 debug2: /usr/etc/ssh/sshd_config.d/40-suse-crypto-policies.conf line 6: including /etc/crypto-policies/back-ends/opensshserver.config
Oct 05 13:00:30 debug2: load_server_config: filename /etc/crypto-policies/back-ends/opensshserver.config
Oct 05 13:00:30 debug2: load_server_config: done config len = 2331
Oct 05 13:00:30 debug2: parse_server_config_depth: config /etc/crypto-policies/back-ends/opensshserver.config len 2331
Oct 05 13:00:30 debug3: /etc/crypto-policies/back-ends/opensshserver.config:1 setting Ciphers aes256-gcm at openssh.com,chacha20-poly1305 at openssh.com,aes256-ctr,aes128-gcm at openssh.com,aes128-ctr
Oct 05 13:00:30 debug3: /etc/crypto-policies/back-ends/opensshserver.config:2 setting MACs hmac-sha2-256-etm at openssh.com,hmac-sha1-etm at openssh.com,hmac-sha2-512-etm at openssh.com,hmac-sha2-256,hmac-sha1,hmac-sha2-512
Oct 05 13:00:30 debug3: /etc/crypto-policies/back-ends/opensshserver.config:3 setting GSSAPIKexAlgorithms gss-curve25519-sha256-,gss-nistp256-sha256-,gss-group14-sha256-,gss-group16-sha512-
Oct 05 13:00:30 debug3: gss kex names ok: [gss-curve25519-sha256-,gss-nistp256-sha256-,gss-group14-sha256-,gss-group16-sha512-]
Oct 05 13:00:30 debug3: /etc/crypto-policies/back-ends/opensshserver.config:4 setting KexAlgorithms sntrup761x25519-sha512,sntrup761x25519-sha512 at openssh.com,mlkem768x25519-sha256,curve25519-sha256,curve25519-sha256 at libssh.org,ecdh-sha2-nistp256,ecdh-sha2-nistp384,ecdh-sha2-nistp521,diffie-hellman-group-exchange-sha256,diffie-hellman-group14-sha256,diffie-hellman-group16-sha512,diffie-hellman-group18-sha512
Oct 05 13:00:30 debug3: /etc/crypto-policies/back-ends/opensshserver.config:5 setting HostKeyAlgorithms ecdsa-sha2-nistp256,ecdsa-sha2-nistp256-cert-v01 at openssh.com,sk-ecdsa-sha2-nistp256 at openssh.com,sk-ecdsa-sha2-nistp256-cert-v01 at openssh.com,ecdsa-sha2-nistp384,ecdsa-sha2-nistp384-cert-v01 at openssh.com,ecdsa-sha2-nistp521,ecdsa-sha2-nistp521-cert-v01 at openssh.com,ssh-ed25519,ssh-ed25519-cert-v01 at openssh.com,sk-ssh-ed25519 at openssh.com,sk-ssh-ed25519-cert-v01 at openssh.com,rsa-sha2-256,rsa-sha2-256-cert-v01 at openssh.com,rsa-sha2-512,rsa-sha2-512-cert-v01 at openssh.com
Oct 05 13:00:30 debug3: /etc/crypto-policies/back-ends/opensshserver.config:6 setting PubkeyAcceptedAlgorithms ecdsa-sha2-nistp256,ecdsa-sha2-nistp256-cert-v01 at openssh.com,sk-ecdsa-sha2-nistp256 at openssh.com,sk-ecdsa-sha2-nistp256-cert-v01 at openssh.com,ecdsa-sha2-nistp384,ecdsa-sha2-nistp384-cert-v01 at openssh.com,ecdsa-sha2-nistp521,ecdsa-sha2-nistp521-cert-v01 at openssh.com,ssh-ed25519,ssh-ed25519-cert-v01 at openssh.com,sk-ssh-ed25519 at openssh.com,sk-ssh-ed25519-cert-v01 at openssh.com,rsa-sha2-256,rsa-sha2-256-cert-v01 at openssh.com,rsa-sha2-512,rsa-sha2-512-cert-v01 at openssh.com
Oct 05 13:00:30 debug3: /etc/crypto-policies/back-ends/opensshserver.config:7 setting HostbasedAcceptedAlgorithms ecdsa-sha2-nistp256,ecdsa-sha2-nistp256-cert-v01 at openssh.com,sk-ecdsa-sha2-nistp256 at openssh.com,sk-ecdsa-sha2-nistp256-cert-v01 at openssh.com,ecdsa-sha2-nistp384,ecdsa-sha2-nistp384-cert-v01 at openssh.com,ecdsa-sha2-nistp521,ecdsa-sha2-nistp521-cert-v01 at openssh.com,ssh-ed25519,ssh-ed25519-cert-v01 at openssh.com,sk-ssh-ed25519 at openssh.com,sk-ssh-ed25519-cert-v01 at openssh.com,rsa-sha2-256,rsa-sha2-256-cert-v01 at openssh.com,rsa-sha2-512,rsa-sha2-512-cert-v01 at openssh.com
Oct 05 13:00:30 debug3: /etc/crypto-policies/back-ends/opensshserver.config:8 setting CASignatureAlgorithms ecdsa-sha2-nistp256,sk-ecdsa-sha2-nistp256 at openssh.com,ecdsa-sha2-nistp384,ecdsa-sha2-nistp521,ssh-ed25519,sk-ssh-ed25519 at openssh.com,rsa-sha2-256,rsa-sha2-512
Oct 05 13:00:30 debug3: /etc/crypto-policies/back-ends/opensshserver.config:9 setting RequiredRSASize 2048
Oct 05 13:00:30 debug3: /usr/etc/ssh/sshd_config:49 setting AuthorizedKeysFile .ssh/authorized_keys
Oct 05 13:00:30 debug3: /usr/etc/ssh/sshd_config:94 setting UsePAM yes
Oct 05 13:00:30 debug3: /usr/etc/ssh/sshd_config:99 setting X11Forwarding yes
Oct 05 13:00:30 debug3: /usr/etc/ssh/sshd_config:103 setting PrintMotd no
Oct 05 13:00:30 debug3: /usr/etc/ssh/sshd_config:120 setting Subsystem sftp /usr/libexec/ssh/sftp-server
Oct 05 13:00:30 debug3: /usr/etc/ssh/sshd_config:123 setting AcceptEnv LANG LC_CTYPE LC_NUMERIC LC_TIME LC_COLLATE LC_MONETARY LC_MESSAGES
Oct 05 13:00:30 debug3: /usr/etc/ssh/sshd_config:124 setting AcceptEnv LC_PAPER LC_NAME LC_ADDRESS LC_TELEPHONE LC_MEASUREMENT
Oct 05 13:00:30 debug3: /usr/etc/ssh/sshd_config:125 setting AcceptEnv LC_IDENTIFICATION LC_ALL
Oct 05 13:00:30 debug3: /usr/etc/ssh/sshd_config:128 setting AcceptEnv LC_TERMINAL LC_TERMINAL_VERSION
Oct 05 13:00:30 debug3: /usr/etc/ssh/sshd_config:129 setting AcceptEnv COLORTERM TERM_PROGRAM TERM_PROGRAM_VERSION
Oct 05 13:00:30 debug1: private host key #0: ssh-rsa SHA256:8Hej8HhXYFgbbfCkxdP8BBJ9fa2mB0BHukafK2p+SDM
Oct 05 13:00:30 debug1: private host key #1: ecdsa-sha2-nistp256 SHA256:fCfJ8NV2n/ynC0TXxsHWYSnqEv0lmXrp0fFj/F8tJ34
Oct 05 13:00:30 debug1: private host key #2: ssh-ed25519 SHA256:afPTJUksIP6UOXoEyloLZD0T30pUOdLVecTrHjsYI00
Oct 05 13:00:30 debug1: private host key #3: ssh-mldsa44-ed25519 at openssh.com SHA256:BBHy+cX+3ZzAjLldyj3H3Wq8KK5ZnPMc20T5iSx0los
Oct 05 13:00:30 debug3: pack_config: d config len 4123
Oct 05 13:00:30 debug3: pack_config: done
Oct 05 13:00:30 debug1: rexec_argv[1]='-p'
Oct 05 13:00:30 debug1: rexec_argv[2]='2222'
Oct 05 13:00:30 debug1: rexec_argv[3]='-e'
Oct 05 13:00:30 debug1: rexec_argv[4]='-d'
Oct 05 13:00:30 debug1: rexec_argv[5]='-d'
Oct 05 13:00:30 debug1: rexec_argv[6]='-d'
Oct 05 13:00:30 debug3: using /usr/libexec/ssh/sshd-session for re-exec
Oct 05 13:00:30 debug3: oom_adjust_setup
Oct 05 13:00:30 debug1: Set /proc/self/oom_score_adj from 0 to -1000
Oct 05 13:00:30 debug2: fd 6 setting O_NONBLOCK
Oct 05 13:00:30 debug1: Bind to port 2222 on 0.0.0.0.
Oct 05 13:00:30 Server listening on 0.0.0.0 port 2222.
Oct 05 13:00:30 debug2: fd 7 setting O_NONBLOCK
Oct 05 13:00:30 debug3: sock_set_v6only: set socket 7 IPV6_V6ONLY
Oct 05 13:00:30 debug1: Bind to port 2222 on ::.
Oct 05 13:00:30 Server listening on :: port 2222.
Oct 05 13:02:57 debug3: fd 8 is not O_NONBLOCK
Oct 05 13:02:57 debug1: Server will not fork when running in debugging mode.
Oct 05 13:02:57 debug1: rexec start in 8 out 8 newsock 8 config_s 9/10
Oct 05 13:02:57 debug3: send_rexec_state: entering fd = 9 config len 7143
Oct 05 13:02:57 debug3: send_rexec_state: done
Oct 05 13:02:57 debug3: recv_rexec_state: entering fd = 4
Oct 05 13:02:57 debug3: ssh_msg_recv entering
Oct 05 13:02:57 debug3: ssh_msg_recv entering
Oct 05 13:02:57 debug2: parse_hostkeys: privkey 0: ssh-rsa
Oct 05 13:02:57 debug2: parse_hostkeys: pubkey 0: ssh-rsa
Oct 05 13:02:57 debug2: parse_hostkeys: privkey 1: ecdsa-sha2-nistp256
Oct 05 13:02:57 debug2: parse_hostkeys: pubkey 1: ecdsa-sha2-nistp256
Oct 05 13:02:57 debug2: parse_hostkeys: privkey 2: ssh-ed25519
Oct 05 13:02:57 debug2: parse_hostkeys: pubkey 2: ssh-ed25519
Oct 05 13:02:57 debug2: parse_hostkeys: privkey 3: ssh-mldsa44-ed25519 at openssh.com
Oct 05 13:02:57 debug2: parse_hostkeys: pubkey 3: ssh-mldsa44-ed25519 at openssh.com
Oct 05 13:02:57 debug3: recv_rexec_state: done
Oct 05 13:02:57 debug2: parse_server_config_depth: config rexec len 4123
Oct 05 13:02:57 debug2: parse_server_config_depth: config len 0
Oct 05 13:02:57 debug2: parse_server_config_depth: config /usr/etc/ssh/sshd_config.d/40-suse-crypto-policies.conf len 413
Oct 05 13:02:57 debug2: parse_server_config_depth: config /etc/crypto-policies/back-ends/opensshserver.config len 2331
Oct 05 13:02:57 debug3: /etc/crypto-policies/back-ends/opensshserver.config:1 setting Ciphers aes256-gcm at openssh.com,chacha20-poly1305 at openssh.com,aes256-ctr,aes128-gcm at openssh.com,aes128-ctr
Oct 05 13:02:57 debug3: /etc/crypto-policies/back-ends/opensshserver.config:2 setting MACs hmac-sha2-256-etm at openssh.com,hmac-sha1-etm at openssh.com,hmac-sha2-512-etm at openssh.com,hmac-sha2-256,hmac-sha1,hmac-sha2-512
Oct 05 13:02:57 debug3: /etc/crypto-policies/back-ends/opensshserver.config:3 setting GSSAPIKexAlgorithms gss-curve25519-sha256-,gss-nistp256-sha256-,gss-group14-sha256-,gss-group16-sha512-
Oct 05 13:02:57 debug3: gss kex names ok: [gss-curve25519-sha256-,gss-nistp256-sha256-,gss-group14-sha256-,gss-group16-sha512-]
Oct 05 13:02:57 debug3: /etc/crypto-policies/back-ends/opensshserver.config:4 setting KexAlgorithms sntrup761x25519-sha512,sntrup761x25519-sha512 at openssh.com,mlkem768x25519-sha256,curve25519-sha256,curve25519-sha256 at libssh.org,ecdh-sha2-nistp256,ecdh-sha2-nistp384,ecdh-sha2-nistp521,diffie-hellman-group-exchange-sha256,diffie-hellman-group14-sha256,diffie-hellman-group16-sha512,diffie-hellman-group18-sha512
Oct 05 13:02:57 debug3: /etc/crypto-policies/back-ends/opensshserver.config:5 setting HostKeyAlgorithms ecdsa-sha2-nistp256,ecdsa-sha2-nistp256-cert-v01 at openssh.com,sk-ecdsa-sha2-nistp256 at openssh.com,sk-ecdsa-sha2-nistp256-cert-v01 at openssh.com,ecdsa-sha2-nistp384,ecdsa-sha2-nistp384-cert-v01 at openssh.com,ecdsa-sha2-nistp521,ecdsa-sha2-nistp521-cert-v01 at openssh.com,ssh-ed25519,ssh-ed25519-cert-v01 at openssh.com,sk-ssh-ed25519 at openssh.com,sk-ssh-ed25519-cert-v01 at openssh.com,rsa-sha2-256,rsa-sha2-256-cert-v01 at openssh.com,rsa-sha2-512,rsa-sha2-512-cert-v01 at openssh.com
Oct 05 13:02:57 debug3: /etc/crypto-policies/back-ends/opensshserver.config:6 setting PubkeyAcceptedAlgorithms ecdsa-sha2-nistp256,ecdsa-sha2-nistp256-cert-v01 at openssh.com,sk-ecdsa-sha2-nistp256 at openssh.com,sk-ecdsa-sha2-nistp256-cert-v01 at openssh.com,ecdsa-sha2-nistp384,ecdsa-sha2-nistp384-cert-v01 at openssh.com,ecdsa-sha2-nistp521,ecdsa-sha2-nistp521-cert-v01 at openssh.com,ssh-ed25519,ssh-ed25519-cert-v01 at openssh.com,sk-ssh-ed25519 at openssh.com,sk-ssh-ed25519-cert-v01 at openssh.com,rsa-sha2-256,rsa-sha2-256-cert-v01 at openssh.com,rsa-sha2-512,rsa-sha2-512-cert-v01 at openssh.com
Oct 05 13:02:57 debug3: /etc/crypto-policies/back-ends/opensshserver.config:7 setting HostbasedAcceptedAlgorithms ecdsa-sha2-nistp256,ecdsa-sha2-nistp256-cert-v01 at openssh.com,sk-ecdsa-sha2-nistp256 at openssh.com,sk-ecdsa-sha2-nistp256-cert-v01 at openssh.com,ecdsa-sha2-nistp384,ecdsa-sha2-nistp384-cert-v01 at openssh.com,ecdsa-sha2-nistp521,ecdsa-sha2-nistp521-cert-v01 at openssh.com,ssh-ed25519,ssh-ed25519-cert-v01 at openssh.com,sk-ssh-ed25519 at openssh.com,sk-ssh-ed25519-cert-v01 at openssh.com,rsa-sha2-256,rsa-sha2-256-cert-v01 at openssh.com,rsa-sha2-512,rsa-sha2-512-cert-v01 at openssh.com
Oct 05 13:02:57 debug3: /etc/crypto-policies/back-ends/opensshserver.config:8 setting CASignatureAlgorithms ecdsa-sha2-nistp256,sk-ecdsa-sha2-nistp256 at openssh.com,ecdsa-sha2-nistp384,ecdsa-sha2-nistp521,ssh-ed25519,sk-ssh-ed25519 at openssh.com,rsa-sha2-256,rsa-sha2-512
Oct 05 13:02:57 debug3: /etc/crypto-policies/back-ends/opensshserver.config:9 setting RequiredRSASize 2048
Oct 05 13:02:57 debug3: rexec:49 setting AuthorizedKeysFile .ssh/authorized_keys
Oct 05 13:02:57 debug3: rexec:94 setting UsePAM yes
Oct 05 13:02:57 debug3: rexec:99 setting X11Forwarding yes
Oct 05 13:02:57 debug3: rexec:103 setting PrintMotd no
Oct 05 13:02:57 debug3: rexec:120 setting Subsystem sftp /usr/libexec/ssh/sftp-server
Oct 05 13:02:57 debug3: rexec:123 setting AcceptEnv LANG LC_CTYPE LC_NUMERIC LC_TIME LC_COLLATE LC_MONETARY LC_MESSAGES
Oct 05 13:02:57 debug3: rexec:124 setting AcceptEnv LC_PAPER LC_NAME LC_ADDRESS LC_TELEPHONE LC_MEASUREMENT
Oct 05 13:02:57 debug3: rexec:125 setting AcceptEnv LC_IDENTIFICATION LC_ALL
Oct 05 13:02:57 debug3: rexec:128 setting AcceptEnv LC_TERMINAL LC_TERMINAL_VERSION
Oct 05 13:02:57 debug3: rexec:129 setting AcceptEnv COLORTERM TERM_PROGRAM TERM_PROGRAM_VERSION
Oct 05 13:02:57 debug1: sshd-session version OpenSSH_10.5, OpenSSL 3.5.3 16 Sep 2025
Oct 05 13:02:57 debug1: network sockets: 6, 6
Oct 05 13:02:57 debug2: fd 6 setting TCP_NODELAY
Oct 05 13:02:57 debug3: set_sock_tos: set socket 6 IP_TOS 0x10
Oct 05 13:02:57 debug3: server_process_channel_timeouts: setting 0 timeouts
Oct 05 13:02:57 debug3: channel_clear_timeouts: clearing
Oct 05 13:02:57 Connection from 192.168.69.115 port 35078 on 192.168.69.109 port 2222 rdomain ""
Oct 05 13:02:57 debug2: fd 6 setting O_NONBLOCK
Oct 05 13:02:57 debug2: Network child is on pid 686973
Oct 05 13:02:57 debug3: preauth child monitor started
Oct 05 13:02:57 debug1: network sockets: 5, 5 [preauth]
Oct 05 13:02:57 debug3: recv_privsep_state: begin [preauth]
Oct 05 13:02:57 debug3: mm_get_state: entering [preauth]
Oct 05 13:02:57 debug3: mm_request_send: entering, type 51 [preauth]
Oct 05 13:02:57 debug3: mm_get_state: waiting for MONITOR_ANS_STATE [preauth]
Oct 05 13:02:57 debug3: mm_request_receive_expect: entering, type 52 [preauth]
Oct 05 13:02:57 debug3: mm_request_receive: entering [preauth]
Oct 05 13:02:57 debug3: mm_request_receive: entering
Oct 05 13:02:57 debug3: monitor_read: checking request 51
Oct 05 13:02:57 debug1: mm_answer_state: config len 4123
Oct 05 13:02:57 debug3: mm_request_send: entering, type 52
Oct 05 13:02:57 debug3: mm_answer_state: done
Oct 05 13:02:57 debug2: monitor_read: 51 used once, disabling now
Oct 05 13:02:57 debug3: mm_get_state: done [preauth]
Oct 05 13:02:57 debug2: parse_hostkeys: key 0: ssh-rsa [preauth]
Oct 05 13:02:57 debug2: parse_hostkeys: key 1: ecdsa-sha2-nistp256 [preauth]
Oct 05 13:02:57 debug2: parse_hostkeys: key 2: ssh-ed25519 [preauth]
Oct 05 13:02:57 debug2: parse_hostkeys: key 3: ssh-mldsa44-ed25519 at openssh.com [preauth]
Oct 05 13:02:57 debug3: recv_privsep_state: done [preauth]
Oct 05 13:02:57 debug2: fd 5 is TCP_NODELAY [preauth]
Oct 05 13:02:57 debug3: set_sock_tos: set socket 5 IP_TOS 0x10 [preauth]
Oct 05 13:02:57 debug1: sshd-auth version OpenSSH_10.5, OpenSSL 3.5.3 16 Sep 2025 [preauth]
Oct 05 13:02:57 debug3: server_process_channel_timeouts: setting 0 timeouts [preauth]
Oct 05 13:02:57 debug3: channel_clear_timeouts: clearing [preauth]
Oct 05 13:02:57 debug3: fd 5 is O_NONBLOCK [preauth]
Oct 05 13:02:57 debug3: ssh_sandbox_init: preparing seccomp filter sandbox [preauth]
Oct 05 13:02:57 debug1: SELinux support disabled [preauth]
Oct 05 13:02:57 debug3: privsep user:group 495:494 [preauth]
Oct 05 13:02:57 debug1: permanently_set_uid: 495/494 [preauth]
Oct 05 13:02:57 debug3: ssh_sandbox_child: setting PR_SET_NO_NEW_PRIVS [preauth]
Oct 05 13:02:57 debug3: ssh_sandbox_child: attaching seccomp filter program [preauth]
Oct 05 13:02:57 debug3: append_hostkey_type: ssh-rsa key not permitted by HostkeyAlgorithms [preauth]
Oct 05 13:02:57 debug3: append_hostkey_type: ssh-mldsa44-ed25519 at openssh.com key not permitted by HostkeyAlgorithms [preauth]
Oct 05 13:02:57 debug1: list_hostkey_types: rsa-sha2-512,rsa-sha2-256,ecdsa-sha2-nistp256,ssh-ed25519 [preauth]
Oct 05 13:02:57 debug1: Local version string SSH-2.0-OpenSSH_10.5 [preauth]
Oct 05 13:02:57 debug1: Remote protocol version 2.0, remote software version OpenSSH_10.5 [preauth]
Oct 05 13:02:57 debug1: compat_banner: match: OpenSSH_10.5 pat OpenSSH* compat 0x04000000 [preauth]
Oct 05 13:02:57 debug3: mm_sshkey_setcompat: entering [preauth]
Oct 05 13:02:57 debug3: mm_request_send: entering, type 18 [preauth]
Oct 05 13:02:57 debug3: send packet: type 20 [preauth]
Oct 05 13:02:57 debug1: SSH2_MSG_KEXINIT sent [preauth]
Oct 05 13:02:57 debug3: mm_request_receive: entering
Oct 05 13:02:57 debug3: monitor_read: checking request 18
Oct 05 13:02:57 debug3: mm_answer_setcompat: entering
Oct 05 13:02:57 debug2: monitor_read: 18 used once, disabling now
Oct 05 13:02:57 debug3: receive packet: type 20 [preauth]
Oct 05 13:02:57 debug1: SSH2_MSG_KEXINIT received [preauth]
Oct 05 13:02:57 debug2: local server KEXINIT proposal [preauth]
Oct 05 13:02:57 debug2: KEX algorithms: sntrup761x25519-sha512,sntrup761x25519-sha512 at openssh.com,mlkem768x25519-sha256,curve25519-sha256,curve25519-sha256 at libssh.org,ecdh-sha2-nistp256,ecdh-sha2-nistp384,ecdh-sha2-nistp521,diffie-hellman-group-exchange-sha256,diffie-hellman-group14-sha256,diffie-hellman-group16-sha512,diffie-hellman-group18-sha512,ext-info-s,kex-strict-s-v00 at openssh.com [preauth]
Oct 05 13:02:57 debug2: host key algorithms: rsa-sha2-512,rsa-sha2-256,ecdsa-sha2-nistp256,ssh-ed25519 [preauth]
Oct 05 13:02:57 debug2: ciphers ctos: aes256-gcm at openssh.com,chacha20-poly1305 at openssh.com,aes256-ctr,aes128-gcm at openssh.com,aes128-ctr [preauth]
Oct 05 13:02:57 debug2: ciphers stoc: aes256-gcm at openssh.com,chacha20-poly1305 at openssh.com,aes256-ctr,aes128-gcm at openssh.com,aes128-ctr [preauth]
Oct 05 13:02:57 debug2: MACs ctos: hmac-sha2-256-etm at openssh.com,hmac-sha1-etm at openssh.com,hmac-sha2-512-etm at openssh.com,hmac-sha2-256,hmac-sha1,hmac-sha2-512 [preauth]
Oct 05 13:02:57 debug2: MACs stoc: hmac-sha2-256-etm at openssh.com,hmac-sha1-etm at openssh.com,hmac-sha2-512-etm at openssh.com,hmac-sha2-256,hmac-sha1,hmac-sha2-512 [preauth]
Oct 05 13:02:57 debug2: compression ctos: none,zlib at openssh.com [preauth]
Oct 05 13:02:57 debug2: compression stoc: none,zlib at openssh.com [preauth]
Oct 05 13:02:57 debug2: languages ctos: [preauth]
Oct 05 13:02:57 debug2: languages stoc: [preauth]
Oct 05 13:02:57 debug2: first_kex_follows 0 [preauth]
Oct 05 13:02:57 debug2: reserved 0 [preauth]
Oct 05 13:02:57 debug2: peer client KEXINIT proposal [preauth]
Oct 05 13:02:57 debug2: KEX algorithms: sntrup761x25519-sha512,sntrup761x25519-sha512 at openssh.com,mlkem768x25519-sha256,curve25519-sha256,curve25519-sha256 at libssh.org,ecdh-sha2-nistp256,ecdh-sha2-nistp384,ecdh-sha2-nistp521,diffie-hellman-group-exchange-sha256,diffie-hellman-group14-sha256,diffie-hellman-group16-sha512,diffie-hellman-group18-sha512,ext-info-c,kex-strict-c-v00 at openssh.com [preauth]
Oct 05 13:02:57 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,webauthn-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,webauthn-sk-ecdsa-sha2-nistp256 at openssh.com,rsa-sha2-512,rsa-sha2-256 [preauth]
Oct 05 13:02:57 debug2: ciphers ctos: aes256-gcm at openssh.com,chacha20-poly1305 at openssh.com,aes256-ctr,aes128-gcm at openssh.com,aes128-ctr [preauth]
Oct 05 13:02:57 debug2: ciphers stoc: aes256-gcm at openssh.com,chacha20-poly1305 at openssh.com,aes256-ctr,aes128-gcm at openssh.com,aes128-ctr [preauth]
Oct 05 13:02:57 debug2: MACs ctos: hmac-sha2-256-etm at openssh.com,hmac-sha1-etm at openssh.com,hmac-sha2-512-etm at openssh.com,hmac-sha2-256,hmac-sha1,hmac-sha2-512 [preauth]
Oct 05 13:02:57 debug2: MACs stoc: hmac-sha2-256-etm at openssh.com,hmac-sha1-etm at openssh.com,hmac-sha2-512-etm at openssh.com,hmac-sha2-256,hmac-sha1,hmac-sha2-512 [preauth]
Oct 05 13:02:57 debug2: compression ctos: none,zlib at openssh.com [preauth]
Oct 05 13:02:57 debug2: compression stoc: none,zlib at openssh.com [preauth]
Oct 05 13:02:57 debug2: languages ctos: [preauth]
Oct 05 13:02:57 debug2: languages stoc: [preauth]
Oct 05 13:02:57 debug2: first_kex_follows 0 [preauth]
Oct 05 13:02:57 debug2: reserved 0 [preauth]
Oct 05 13:02:57 debug3: kex_choose_conf: will use strict KEX ordering [preauth]
Oct 05 13:02:57 debug1: kex: algorithm: sntrup761x25519-sha512 [preauth]
Oct 05 13:02:57 debug1: kex: host key algorithm: ssh-ed25519 [preauth]
Oct 05 13:02:57 debug1: kex: client->server cipher: aes256-gcm at openssh.com MAC: <implicit> compression: none [preauth]
Oct 05 13:02:57 debug1: kex: server->client cipher: aes256-gcm at openssh.com MAC: <implicit> compression: none [preauth]
Oct 05 13:02:57 debug1: kex: sntrup761x25519-sha512 need=32 dh_need=32 [preauth]
Oct 05 13:02:57 debug3: mm_request_send: entering, type 120 [preauth]
Oct 05 13:02:57 debug3: mm_request_receive_expect: entering, type 121 [preauth]
Oct 05 13:02:57 debug3: mm_request_receive: entering [preauth]
Oct 05 13:02:57 debug3: mm_request_receive: entering
Oct 05 13:02:57 debug3: monitor_read: checking request 120
Oct 05 13:02:57 debug3: mm_request_send: entering, type 121
Oct 05 13:02:57 debug1: kex: sntrup761x25519-sha512 need=32 dh_need=32 [preauth]
Oct 05 13:02:57 debug3: mm_request_send: entering, type 120 [preauth]
Oct 05 13:02:57 debug3: mm_request_receive_expect: entering, type 121 [preauth]
Oct 05 13:02:57 debug3: mm_request_receive: entering [preauth]
Oct 05 13:02:57 debug3: mm_request_receive: entering
Oct 05 13:02:57 debug3: monitor_read: checking request 120
Oct 05 13:02:57 debug3: mm_request_send: entering, type 121
Oct 05 13:02:57 debug1: expecting SSH2_MSG_KEX_ECDH_INIT [preauth]
Oct 05 13:02:57 debug3: receive packet: type 30 [preauth]
Oct 05 13:02:57 debug1: SSH2_MSG_KEX_ECDH_INIT received [preauth]
Oct 05 13:02:57 debug3: mm_sshkey_sign: entering [preauth]
Oct 05 13:02:57 debug3: mm_request_send: entering, type 6 [preauth]
Oct 05 13:02:57 debug3: mm_sshkey_sign: waiting for MONITOR_ANS_SIGN [preauth]
Oct 05 13:02:57 debug3: mm_request_receive_expect: entering, type 7 [preauth]
Oct 05 13:02:57 debug3: mm_request_receive: entering [preauth]
Oct 05 13:02:57 debug3: mm_request_receive: entering
Oct 05 13:02:57 debug3: monitor_read: checking request 6
Oct 05 13:02:57 debug3: mm_answer_sign: entering
Oct 05 13:02:57 debug1: mm_answer_sign: hostkey ssh-ed25519 index 2
Oct 05 13:02:57 debug3: mm_answer_sign: ssh-ed25519 KEX signature len=83
Oct 05 13:02:57 debug3: mm_request_send: entering, type 7
Oct 05 13:02:57 debug2: monitor_read: 6 used once, disabling now
Oct 05 13:02:57 debug3: mm_sshkey_sign: ssh-ed25519 signature len=83 [preauth]
Oct 05 13:02:57 debug3: send packet: type 31 [preauth]
Oct 05 13:02:57 debug3: send packet: type 21 [preauth]
Oct 05 13:02:57 debug1: ssh_packet_send2_wrapped: resetting send seqnr 3 [preauth]
Oct 05 13:02:57 debug2: ssh_set_newkeys: mode 1 [preauth]
Oct 05 13:02:57 debug1: rekey out after 4294967296 blocks [preauth]
Oct 05 13:02:57 debug1: SSH2_MSG_NEWKEYS sent [preauth]
Oct 05 13:02:57 debug1: Sending SSH2_MSG_EXT_INFO [preauth]
Oct 05 13:02:57 debug3: send packet: type 7 [preauth]
Oct 05 13:02:57 debug1: expecting SSH2_MSG_NEWKEYS [preauth]
Oct 05 13:02:57 debug3: receive packet: type 21 [preauth]
Oct 05 13:02:57 debug1: ssh_packet_read_poll2: resetting read seqnr 3 [preauth]
Oct 05 13:02:57 debug1: SSH2_MSG_NEWKEYS received [preauth]
Oct 05 13:02:57 debug2: ssh_set_newkeys: mode 0 [preauth]
Oct 05 13:02:57 debug1: rekey in after 4294967296 blocks [preauth]
Oct 05 13:02:57 debug2: KEX algorithms: sntrup761x25519-sha512,sntrup761x25519-sha512 at openssh.com,mlkem768x25519-sha256,curve25519-sha256,curve25519-sha256 at libssh.org,ecdh-sha2-nistp256,ecdh-sha2-nistp384,ecdh-sha2-nistp521,diffie-hellman-group-exchange-sha256,diffie-hellman-group14-sha256,diffie-hellman-group16-sha512,diffie-hellman-group18-sha512,ext-info-s,kex-strict-s-v00 at openssh.com [preauth]
Oct 05 13:02:57 debug2: host key algorithms: rsa-sha2-512,rsa-sha2-256,ecdsa-sha2-nistp256,ssh-ed25519 [preauth]
Oct 05 13:02:57 debug2: ciphers ctos: aes256-gcm at openssh.com,chacha20-poly1305 at openssh.com,aes256-ctr,aes128-gcm at openssh.com,aes128-ctr [preauth]
Oct 05 13:02:57 debug2: ciphers stoc: aes256-gcm at openssh.com,chacha20-poly1305 at openssh.com,aes256-ctr,aes128-gcm at openssh.com,aes128-ctr [preauth]
Oct 05 13:02:57 debug2: MACs ctos: hmac-sha2-256-etm at openssh.com,hmac-sha1-etm at openssh.com,hmac-sha2-512-etm at openssh.com,hmac-sha2-256,hmac-sha1,hmac-sha2-512 [preauth]
Oct 05 13:02:57 debug2: MACs stoc: hmac-sha2-256-etm at openssh.com,hmac-sha1-etm at openssh.com,hmac-sha2-512-etm at openssh.com,hmac-sha2-256,hmac-sha1,hmac-sha2-512 [preauth]
Oct 05 13:02:57 debug2: compression ctos: none,zlib at openssh.com [preauth]
Oct 05 13:02:57 debug2: compression stoc: none,zlib at openssh.com [preauth]
Oct 05 13:02:57 debug2: languages ctos: [preauth]
Oct 05 13:02:57 debug2: languages stoc: [preauth]
Oct 05 13:02:57 debug2: first_kex_follows 0 [preauth]
Oct 05 13:02:57 debug2: reserved 0 [preauth]
Oct 05 13:02:57 debug1: KEX done [preauth]
Oct 05 13:02:57 debug3: receive packet: type 7 [preauth]
Oct 05 13:02:57 debug1: SSH2_MSG_EXT_INFO received [preauth]
Oct 05 13:02:57 debug3: kex_input_ext_info: extension ext-info-in-auth at openssh.com [preauth]
Oct 05 13:02:57 debug1: kex_ext_info_check_ver: ext-info-in-auth at openssh.com=<0> [preauth]
Oct 05 13:02:57 debug3: receive packet: type 5 [preauth]
Oct 05 13:02:57 debug3: send packet: type 6 [preauth]
Oct 05 13:02:57 debug3: receive packet: type 50 [preauth]
Oct 05 13:02:57 debug1: userauth-request for user sma-user1 service ssh-connection method none [preauth]
Oct 05 13:02:57 debug1: attempt 0 failures 0 [preauth]
Oct 05 13:02:57 debug3: mm_getpwnamallow: entering [preauth]
Oct 05 13:02:57 debug3: mm_request_send: entering, type 8 [preauth]
Oct 05 13:02:57 debug3: mm_getpwnamallow: waiting for MONITOR_ANS_PWNAM [preauth]
Oct 05 13:02:57 debug3: mm_request_receive_expect: entering, type 9 [preauth]
Oct 05 13:02:57 debug3: mm_request_receive: entering [preauth]
Oct 05 13:02:57 debug3: mm_request_receive: entering
Oct 05 13:02:57 debug3: monitor_read: checking request 8
Oct 05 13:02:57 debug3: mm_answer_pwnamallow: entering
Oct 05 13:02:57 debug2: parse_server_config_depth: config reprocess config len 4123
Oct 05 13:02:57 debug2: parse_server_config_depth: config len 0
Oct 05 13:02:57 debug2: parse_server_config_depth: config /usr/etc/ssh/sshd_config.d/40-suse-crypto-policies.conf len 413
Oct 05 13:02:57 debug2: parse_server_config_depth: config /etc/crypto-policies/back-ends/opensshserver.config len 2331
Oct 05 13:02:57 debug3: server_process_channel_timeouts: setting 0 timeouts
Oct 05 13:02:57 debug3: channel_clear_timeouts: clearing
Oct 05 13:02:57 debug3: mm_answer_pwnamallow: sending MONITOR_ANS_PWNAM: 1
Oct 05 13:02:57 debug3: mm_request_send: entering, type 9
Oct 05 13:02:57 debug2: monitor_read: 8 used once, disabling now
Oct 05 13:02:57 debug3: server_process_channel_timeouts: setting 0 timeouts [preauth]
Oct 05 13:02:57 debug3: channel_clear_timeouts: clearing [preauth]
Oct 05 13:02:57 debug2: input_userauth_request: setting up authctxt for sma-user1 [preauth]
Oct 05 13:02:57 debug3: mm_start_pam: entering [preauth]
Oct 05 13:02:57 debug3: mm_request_send: entering, type 100 [preauth]
Oct 05 13:02:57 debug3: mm_inform_authserv: entering [preauth]
Oct 05 13:02:57 debug3: mm_request_send: entering, type 4 [preauth]
Oct 05 13:02:57 debug3: mm_inform_authrole: entering [preauth]
Oct 05 13:02:57 debug3: mm_request_send: entering, type 80 [preauth]
Oct 05 13:02:57 debug1: kex_server_update_ext_info: Sending SSH2_MSG_EXT_INFO [preauth]
Oct 05 13:02:57 debug3: send packet: type 7 [preauth]
Oct 05 13:02:57 debug2: input_userauth_request: try method none [preauth]
Oct 05 13:02:57 debug3: userauth_finish: failure partial=0 next methods="publickey,password,keyboard-interactive" [preauth]
Oct 05 13:02:57 debug3: send packet: type 51 [preauth]
Oct 05 13:02:57 debug3: mm_request_receive: entering
Oct 05 13:02:57 debug3: monitor_read: checking request 100
Oct 05 13:02:57 debug1: PAM: initializing for "sma-user1" with service "sshd"
Oct 05 13:02:57 debug1: PAM: setting PAM_RHOST to "192.168.69.115"
Oct 05 13:02:57 debug1: PAM: setting PAM_TTY to "ssh"
Oct 05 13:02:57 debug2: monitor_read: 100 used once, disabling now
Oct 05 13:02:57 debug3: receive packet: type 50 [preauth]
Oct 05 13:02:57 debug1: userauth-request for user sma-user1 service ssh-connection method publickey [preauth]
Oct 05 13:02:57 debug1: attempt 1 failures 0 [preauth]
Oct 05 13:02:57 debug2: input_userauth_request: try method publickey [preauth]
Oct 05 13:02:57 debug2: userauth_pubkey: valid user sma-user1 querying public key ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHbvlLCXAXcBRsuF52OsOmnE4dLhT0ua+HsqW6+dDxPG [preauth]
Oct 05 13:02:57 debug1: userauth_pubkey: publickey test pkalg ssh-ed25519 pkblob ED25519 SHA256:gaxKqY1KfNtIUVs2IAMReWmhsg7ZITBKh7HDS96mQPI [preauth]
Oct 05 13:02:57 debug3: mm_key_allowed: entering [preauth]
Oct 05 13:02:57 debug3: mm_request_send: entering, type 22 [preauth]
Oct 05 13:02:57 debug3: mm_key_allowed: waiting for MONITOR_ANS_KEYALLOWED [preauth]
Oct 05 13:02:57 debug3: mm_request_receive_expect: entering, type 23 [preauth]
Oct 05 13:02:57 debug3: mm_request_receive: entering [preauth]
Oct 05 13:02:57 debug3: mm_request_receive: entering
Oct 05 13:02:57 debug3: monitor_read: checking request 4
Oct 05 13:02:57 debug3: mm_answer_authserv: service=ssh-connection, style=
Oct 05 13:02:57 debug2: monitor_read: 4 used once, disabling now
Oct 05 13:02:57 debug3: mm_request_receive: entering
Oct 05 13:02:57 debug3: monitor_read: checking request 80
Oct 05 13:02:57 debug3: mm_answer_authrole: role=
Oct 05 13:02:57 debug2: monitor_read: 80 used once, disabling now
Oct 05 13:02:57 debug3: mm_request_receive: entering
Oct 05 13:02:57 debug3: monitor_read: checking request 22
Oct 05 13:02:57 debug3: mm_answer_keyallowed: entering
Oct 05 13:02:57 debug1: temporarily_use_uid: 1000/100 (e=0/0)
Oct 05 13:02:57 debug1: restore_uid: 0/0
Oct 05 13:02:57 debug1: temporarily_use_uid: 1000/100 (e=0/0)
Oct 05 13:02:57 debug1: trying public key file /home/sma-user1/.ssh/authorized_keys
Oct 05 13:02:57 debug1: fd 7 clearing O_NONBLOCK
Oct 05 13:02:57 debug2: auth_check_authkeys_file: /home/sma-user1/.ssh/authorized_keys: processed 1/1 lines
Oct 05 13:02:57 debug1: restore_uid: 0/0
Oct 05 13:02:57 debug3: mm_answer_keyallowed: publickey authentication test: ED25519 key is not allowed
Oct 05 13:02:57 Failed publickey for sma-user1 from 192.168.69.115 port 35078 ssh2: ED25519 SHA256:gaxKqY1KfNtIUVs2IAMReWmhsg7ZITBKh7HDS96mQPI
Oct 05 13:02:57 debug3: mm_request_send: entering, type 23
Oct 05 13:02:57 debug2: userauth_pubkey: authenticated 0 pkalg ssh-ed25519 [preauth]
Oct 05 13:02:57 debug3: user_specific_delay: user specific delay 3.050ms [preauth]
Oct 05 13:02:57 debug3: ensure_minimum_time_since: elapsed 11.700ms, delaying 4.400ms (requested 8.050ms) [preauth]
Oct 05 13:02:57 debug3: userauth_finish: failure partial=0 next methods="publickey,password,keyboard-interactive" [preauth]
Oct 05 13:02:57 debug3: send packet: type 51 [preauth]
Oct 05 13:03:08 debug3: receive packet: type 50 [preauth]
Oct 05 13:03:08 debug1: userauth-request for user sma-user1 service ssh-connection method password [preauth]
Oct 05 13:03:08 debug1: attempt 2 failures 1 [preauth]
Oct 05 13:03:08 debug2: input_userauth_request: try method password [preauth]
Oct 05 13:03:08 debug3: mm_auth_password: entering [preauth]
Oct 05 13:03:08 debug3: mm_request_send: entering, type 12 [preauth]
Oct 05 13:03:08 debug3: mm_auth_password: waiting for MONITOR_ANS_AUTHPASSWORD [preauth]
Oct 05 13:03:08 debug3: mm_request_receive_expect: entering, type 13 [preauth]
Oct 05 13:03:08 debug3: mm_request_receive: entering [preauth]
Oct 05 13:03:08 debug3: mm_request_receive: entering
Oct 05 13:03:08 debug3: monitor_read: checking request 12
Oct 05 13:03:08 debug2: sshpam_auth_passwd: auth information in SSH_AUTH_INFO_0
Oct 05 13:03:08 debug3: sshpam_passwd_conv: PAM: called with 1 messages
Oct 05 13:03:08 debug1: PAM: password authentication accepted for sma-user1
Oct 05 13:03:08 debug3: mm_answer_authpassword: sending result 1
Oct 05 13:03:08 debug3: mm_request_send: entering, type 13
Oct 05 13:03:08 debug3: mm_request_receive_expect: entering, type 102
Oct 05 13:03:08 debug3: mm_request_receive: entering
Oct 05 13:03:08 debug1: do_pam_account: called
Oct 05 13:03:08 debug2: do_pam_account: auth information in SSH_AUTH_INFO_0
Oct 05 13:03:08 debug3: PAM: do_pam_account pam_acct_mgmt = 0 (Success)
Oct 05 13:03:08 debug3: mm_request_send: entering, type 103
Oct 05 13:03:08 Accepted password for sma-user1 from 192.168.69.115 port 35078 ssh2
Oct 05 13:03:08 debug1: monitor_child_preauth: user sma-user1 authenticated by privileged process
Oct 05 13:03:08 debug3: mm_get_keystate: Waiting for new keys
Oct 05 13:03:08 debug3: mm_request_receive_expect: entering, type 26
Oct 05 13:03:08 debug3: mm_request_receive: entering
Oct 05 13:03:08 debug3: mm_get_keystate: GOT new keys
Oct 05 13:03:08 debug3: mm_request_receive_expect: entering, type 122
Oct 05 13:03:08 debug3: mm_request_receive: entering
Oct 05 13:03:08 debug3: mm_request_send: entering, type 123
Oct 05 13:03:08 debug3: mm_auth_password: user authenticated [preauth]
Oct 05 13:03:08 debug3: user_specific_delay: user specific delay 3.050ms [preauth]
Oct 05 13:03:08 debug3: ensure_minimum_time_since: elapsed 25.434ms, delaying 6.766ms (requested 8.050ms) [preauth]
Oct 05 13:03:08 debug3: mm_do_pam_account: entering [preauth]
Oct 05 13:03:08 debug3: mm_request_send: entering, type 102 [preauth]
Oct 05 13:03:08 debug3: mm_request_receive_expect: entering, type 103 [preauth]
Oct 05 13:03:08 debug3: mm_request_receive: entering [preauth]
Oct 05 13:03:08 debug3: mm_do_pam_account: returning 1 [preauth]
Oct 05 13:03:08 debug3: send packet: type 52 [preauth]
Oct 05 13:03:08 debug3: mm_request_send: entering, type 26 [preauth]
Oct 05 13:03:08 debug3: mm_send_keystate: Finished sending state [preauth]
Oct 05 13:03:08 debug3: mm_request_send: entering, type 122 [preauth]
Oct 05 13:03:08 debug3: mm_request_receive_expect: entering, type 123 [preauth]
Oct 05 13:03:08 debug3: mm_request_receive: entering [preauth]
Oct 05 13:03:08 debug1: monitor_read_log: child log fd closed
Oct 05 13:03:08 debug3: monitor_child_preauth: preauth child 686973 terminated successfully
Oct 05 13:03:08 debug1: audit_event: unhandled event 2
Oct 05 13:03:08 debug1: SELinux support disabled
Oct 05 13:03:08 debug1: PAM: establishing credentials
Oct 05 13:03:08 debug3: PAM: opening session
Oct 05 13:03:08 debug2: do_pam_session: auth information in SSH_AUTH_INFO_0
Oct 05 13:03:08 debug3: sshpam_store_conv: PAM: called with 1 messages
Oct 05 13:03:08 debug3: sshpam_store_conv: PAM: called with 1 messages
Oct 05 13:03:08 debug3: sshpam_store_conv: PAM: called with 1 messages
Oct 05 13:03:08 User child is on pid 686978
Oct 05 13:03:09 debug1: PAM: establishing credentials
Oct 05 13:03:09 debug1: permanently_set_uid: 1000/100
Oct 05 13:03:09 debug3: monitor_apply_keystate: packet_set_state
Oct 05 13:03:09 debug2: ssh_set_newkeys: mode 0
Oct 05 13:03:09 debug1: rekey in after 4294967296 blocks
Oct 05 13:03:09 debug2: ssh_set_newkeys: mode 1
Oct 05 13:03:09 debug1: rekey out after 4294967296 blocks
Oct 05 13:03:09 debug1: ssh_packet_set_postauth: called
Oct 05 13:03:09 debug3: ssh_packet_set_state: done
Oct 05 13:03:09 debug3: notify_hostkeys: key 0: ssh-rsa SHA256:8Hej8HhXYFgbbfCkxdP8BBJ9fa2mB0BHukafK2p+SDM
Oct 05 13:03:09 debug3: notify_hostkeys: key 1: ecdsa-sha2-nistp256 SHA256:fCfJ8NV2n/ynC0TXxsHWYSnqEv0lmXrp0fFj/F8tJ34
Oct 05 13:03:09 debug3: notify_hostkeys: key 2: ssh-ed25519 SHA256:afPTJUksIP6UOXoEyloLZD0T30pUOdLVecTrHjsYI00
Oct 05 13:03:09 debug3: notify_hostkeys: key 3: ssh-mldsa44-ed25519 at openssh.com SHA256:BBHy+cX+3ZzAjLldyj3H3Wq8KK5ZnPMc20T5iSx0los
Oct 05 13:03:09 debug3: notify_hostkeys: sent 4 hostkeys
Oct 05 13:03:09 debug3: send packet: type 80
Oct 05 13:03:09 debug1: active: key options: agent-forwarding port-forwarding pty user-rc x11-forwarding
Oct 05 13:03:09 debug1: Entering interactive session for SSH2.
Oct 05 13:03:09 debug1: server_init_dispatch
Oct 05 13:03:09 debug2: process_output: session QoS is now interactive
Oct 05 13:03:09 debug3: set_sock_tos: set socket 6 IP_TOS 0x10
Oct 05 13:03:09 debug3: receive packet: type 90
Oct 05 13:03:09 debug1: server_input_channel_open: ctype session rchan 0 win 1048576 max 16384
Oct 05 13:03:09 debug1: input_session_request
Oct 05 13:03:09 debug3: channel 0: classify type "(null)" (no TTY) as interactive
Oct 05 13:03:09 debug3: channel 0: classify type "session" (no TTY) as bulk
Oct 05 13:03:09 debug1: channel 0: new session [server-session] (inactive timeout: 0)
Oct 05 13:03:09 debug2: session_new: allocate (allocated 0 max 10)
Oct 05 13:03:09 debug3: session_unused: session id 0 unused
Oct 05 13:03:09 debug1: session_new: session 0
Oct 05 13:03:09 debug1: session_open: channel 0
Oct 05 13:03:09 debug1: session_open: session 0: link with channel 0
Oct 05 13:03:09 debug1: server_input_channel_open: confirm session
Oct 05 13:03:09 debug3: send packet: type 91
Oct 05 13:03:09 debug3: receive packet: type 80
Oct 05 13:03:09 debug1: server_input_global_request: rtype no-more-sessions at openssh.com want_reply 0
Oct 05 13:03:09 debug3: receive packet: type 98
Oct 05 13:03:09 debug1: server_input_channel_req: channel 0 request pty-req reply 1
Oct 05 13:03:09 debug1: session_by_channel: session 0 channel 0
Oct 05 13:03:09 debug1: session_input_channel_req: session 0 req pty-req
Oct 05 13:03:09 debug1: Allocating pty.
Oct 05 13:03:09 debug3: mm_request_send: entering, type 28
Oct 05 13:03:09 debug3: mm_pty_allocate: waiting for MONITOR_ANS_PTY
Oct 05 13:03:09 debug3: mm_request_receive_expect: entering, type 29
Oct 05 13:03:09 debug3: mm_request_receive: entering
Oct 05 13:03:09 debug3: mm_request_receive: entering
Oct 05 13:03:09 debug3: monitor_read: checking request 28
Oct 05 13:03:09 debug3: mm_answer_pty: entering
Oct 05 13:03:09 debug2: session_new: allocate (allocated 0 max 10)
Oct 05 13:03:09 debug3: session_unused: session id 0 unused
Oct 05 13:03:09 debug1: session_new: session 0
Oct 05 13:03:09 debug1: SELinux support disabled
Oct 05 13:03:09 debug3: mm_request_send: entering, type 29
Oct 05 13:03:09 debug3: mm_answer_pty: tty /dev/pts/6 ptyfd 9
Oct 05 13:03:09 debug1: session_pty_req: session 0 alloc /dev/pts/6
Oct 05 13:03:09 debug3: send packet: type 99
Oct 05 13:03:09 debug3: receive packet: type 98
Oct 05 13:03:09 debug1: server_input_channel_req: channel 0 request env reply 0
Oct 05 13:03:09 debug1: session_by_channel: session 0 channel 0
Oct 05 13:03:09 debug1: session_input_channel_req: session 0 req env
Oct 05 13:03:09 debug2: Setting env 0: COLORTERM=truecolor
Oct 05 13:03:09 debug3: receive packet: type 98
Oct 05 13:03:09 debug1: server_input_channel_req: channel 0 request env reply 0
Oct 05 13:03:09 debug1: session_by_channel: session 0 channel 0
Oct 05 13:03:09 debug1: session_input_channel_req: session 0 req env
Oct 05 13:03:09 debug2: Setting env 1: LANG=en_US.UTF-8
Oct 05 13:03:09 debug3: receive packet: type 98
Oct 05 13:03:09 debug1: server_input_channel_req: channel 0 request shell reply 1
Oct 05 13:03:09 debug1: session_by_channel: session 0 channel 0
Oct 05 13:03:09 debug1: session_input_channel_req: session 0 req shell
Oct 05 13:03:09 debug3: channel 0: classify type "session:shell" (no TTY) as bulk
Oct 05 13:03:09 debug2: channel_set_xtype: labeled channel 0 as session:shell (inactive timeout 0)
Oct 05 13:03:09 Starting session: shell on pts/6 for sma-user1 from 192.168.69.115 port 35078 id 0
Oct 05 13:03:09 debug2: channel 0: rfd 12 isatty
Oct 05 13:03:09 debug2: fd 12 setting O_NONBLOCK
Oct 05 13:03:09 debug3: fd 10 is O_NONBLOCK
Oct 05 13:03:09 debug3: channel 0: classify type "session:shell" (with TTY) as interactive
Oct 05 13:03:09 debug3: send packet: type 99
Oct 05 13:03:09 debug1: Setting controlling tty using TIOCSCTTY.
Oct 05 13:03:21 debug1: Received SIGCHLD.
Oct 05 13:03:21 debug1: session_by_pid: pid 686981
Oct 05 13:03:21 debug2: channel 0: request exit-status confirm 0
Oct 05 13:03:21 debug3: send packet: type 98
Oct 05 13:03:21 debug1: session_exit_message: session 0 channel 0 pid 686981 exit 0
Oct 05 13:03:21 debug1: session_exit_message: release channel 0
Oct 05 13:03:21 debug2: channel 0: write failed
Oct 05 13:03:21 debug2: chan_shutdown_write: channel 0: (i0 o0 sock -1 wfd 10 efd -1 [closed])
Oct 05 13:03:21 debug2: channel 0: send eow
Oct 05 13:03:21 debug3: send packet: type 98
Oct 05 13:03:21 debug2: channel 0: output open -> closed
Oct 05 13:03:21 debug3: mm_request_send: entering, type 30
Oct 05 13:03:21 debug2: channel 0: read failed rfd 12 maxlen 32768: Resource temporarily unavailable
Oct 05 13:03:21 debug2: channel 0: read failed
Oct 05 13:03:21 debug2: chan_shutdown_read: channel 0: (i0 o3 sock -1 wfd 12 efd -1 [closed])
Oct 05 13:03:21 debug2: channel 0: input open -> drain
Oct 05 13:03:21 debug3: mm_request_receive: entering
Oct 05 13:03:21 debug2: channel 0: ibuf empty
Oct 05 13:03:21 debug3: monitor_read: checking request 30
Oct 05 13:03:21 debug2: channel 0: send eof
Oct 05 13:03:21 debug3: send packet: type 96
Oct 05 13:03:21 debug3: mm_answer_pty_cleanup: entering
Oct 05 13:03:21 debug1: session_by_tty: session 0 tty /dev/pts/6
Oct 05 13:03:21 debug2: channel 0: input drain -> closed
Oct 05 13:03:21 debug3: mm_session_close: session 0 pid 686978
Oct 05 13:03:21 debug2: channel 0: send_close2
Oct 05 13:03:21 debug3: mm_session_close: tty /dev/pts/6 ptyfd 9
Oct 05 13:03:21 debug2: channel 0: send close for remote id 0
Oct 05 13:03:21 debug1: session_pty_cleanup2: session 0 release /dev/pts/6
Oct 05 13:03:21 debug3: send packet: type 97
Oct 05 13:03:21 debug3: channel 0: will not send data after close
Oct 05 13:03:21 syslogin_perform_logout: logout() returned an error
Oct 05 13:03:21 debug3: receive packet: type 97
Oct 05 13:03:21 debug2: channel 0: rcvd close
Oct 05 13:03:21 debug3: channel 0: will not send data after close
Oct 05 13:03:21 debug2: channel 0: is dead
Oct 05 13:03:21 debug2: channel 0: gc: notify user
Oct 05 13:03:21 debug1: session_by_channel: session 0 channel 0
Oct 05 13:03:21 debug1: session_close_by_channel: channel 0 child 0
Oct 05 13:03:21 Close session: user sma-user1 from 192.168.69.115 port 35078 id 0
Oct 05 13:03:21 debug3: session_unused: session id 0 unused
Oct 05 13:03:21 debug2: channel 0: gc: user detached
Oct 05 13:03:21 debug2: channel 0: is dead
Oct 05 13:03:21 debug2: channel 0: garbage collecting
Oct 05 13:03:21 debug1: channel 0: free: server-session, nchannels 1
Oct 05 13:03:21 debug3: channel 0: status: The following connections are open:
Oct 05 13:03:21 #0 server-session (t4 [session:shell] r0 nm0 i3/0 o3/0 e[closed]/0 fd -1/-1/-1 sock -1 cc -1 nc0 io 0x00/0x00 TI)
Oct 05 13:03:21
Oct 05 13:03:21 debug3: receive packet: type 1
Oct 05 13:03:21 Received disconnect from 192.168.69.115 port 35078:11: disconnected by user
Oct 05 13:03:21 debug3: mm_request_send: entering, type 122
Oct 05 13:03:21 debug3: mm_request_receive_expect: entering, type 123
Oct 05 13:03:21 debug3: mm_request_receive: entering
Oct 05 13:03:21 debug3: session_unused: session id 0 unused
Oct 05 13:03:21 debug3: mm_request_receive: entering
Oct 05 13:03:21 debug3: monitor_read: checking request 122
Oct 05 13:03:21 debug3: mm_request_send: entering, type 123
Oct 05 13:03:21 Disconnected from user sma-user1 192.168.69.115 port 35078
Oct 05 13:03:21 debug1: do_cleanup
Oct 05 13:03:21 debug3: sshpam_thread_cleanup: entering
Oct 05 13:03:21 debug1: temporarily_use_uid: 1000/100 (e=1000/100)
Oct 05 13:03:21 debug1: restore_uid: (unprivileged)
Oct 05 13:03:21 debug3: mm_request_receive: entering
Oct 05 13:03:21 debug3: mm_request_receive: monitor fd closed
Oct 05 13:03:21 debug1: mm_reap: child exited with status 255
Oct 05 13:03:21 debug1: do_cleanup
Oct 05 13:03:21 debug1: PAM: cleanup
Oct 05 13:03:21 debug1: PAM: closing session
Oct 05 13:03:21 debug1: PAM: deleting credentials
Oct 05 13:03:21 debug3: sshpam_thread_cleanup: entering
Oct 05 13:03:21 debug1: temporarily_use_uid: 1000/100 (e=0/0)
Oct 05 13:03:21 debug1: restore_uid: 0/0
More information about the openssh-unix-dev
mailing list