Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Public key authentication fails on Windows 7 #822

Closed
spointu opened this issue Jul 24, 2017 · 4 comments
Closed

Public key authentication fails on Windows 7 #822

spointu opened this issue Jul 24, 2017 · 4 comments
Assignees

Comments

@spointu
Copy link

spointu commented Jul 24, 2017

My environment
Version 0.0.17.0 (same behaviour with 0.0.18.0)
Running on Windows 7
Connecting from ssh client on Debian Jessie

My config file:
LogLevel DEBUG3
Subsystem sftp sftp-server.exe
hostkeyagent \.\pipe\openssh-ssh-agent
PubkeyAuthentication yes
PasswordAuthentication no
PidFile C:\Windows\Temp\sshd.pid
AuthorizedKeysFile "C:\Program Files (x86)\Pulse.ssh\authorized_keys"

The permissions on authorised_keys file:
C:\Program Files (x86)\Pulse.ssh>icacls authorized_keys
authorized_keys NT SERVICE\sshd:(R)
W7-1-UDK\pulse:(F)
BUILTIN\Administrators:(F)

What is failing
I cannot authenticate using public keys. Password authentication works fine

ssh-agent logs:
2868 13:57:11:892 agent_start pid:2868, dbg:0
2868 13:57:11:986 client pid 2932 connected
2868 13:57:11:986 debug1: spawned worker 2952 for agent client pid 2932
2952 13:57:12:001 agent_process_connection pipe:00000110
2952 13:57:12:001 debug1: client type: sshd service
2952 13:57:12:001 debug3: connection io 0081AF58 #bytes:0 state:0
2952 13:57:12:001 debug1: connection 0081AF58 clean up
2952 13:57:12:001 debug1: iocp error: 6 on 00000000
2868 14:00:01:408 client pid 556 connected
2868 14:00:01:408 debug1: spawned worker 1584 for agent client pid 556
1584 14:00:01:423 agent_process_connection pipe:00000110
1584 14:00:01:423 debug1: client type: system
1584 14:00:01:423 debug3: connection io 004BAF58 #bytes:0 state:0
1584 14:00:01:423 debug3: connection io 004BAF58 #bytes:4 state:1
1584 14:00:01:423 debug3: connection io 004BAF58 #bytes:1 state:2
1584 14:00:01:423 debug1: process agent request type 11
1584 14:00:01:423 debug3: connection io 004BAF58 #bytes:9 state:3
1584 14:00:01:423 debug1: iocp error: 109 on 004BAF58
1584 14:00:01:423 debug1: connection 004BAF58 clean up
1584 14:00:01:423 debug1: iocp error: 6 on 00000000
2868 14:00:06:236 client pid 1440 connected
2868 14:00:06:236 debug1: spawned worker 2992 for agent client pid 1440
2992 14:00:06:251 agent_process_connection pipe:00000110
2992 14:00:06:251 debug1: client type: sshd service
2992 14:00:06:251 debug3: connection io 0066AF58 #bytes:0 state:0
2992 14:00:06:251 debug1: connection 0066AF58 clean up
2992 14:00:06:251 debug1: iocp error: 6 on 00000000
2868 14:00:06:267 client pid 1440 connected
2868 14:00:06:267 debug1: spawned worker 2908 for agent client pid 1440
2908 14:00:06:267 agent_process_connection pipe:00000110
2908 14:00:06:267 debug1: client type: sshd service
2908 14:00:06:283 debug3: connection io 0055AF58 #bytes:0 state:0
2868 14:00:06:345 client pid 1440 connected
2868 14:00:06:345 debug1: spawned worker 2376 for agent client pid 1440
2376 14:00:06:361 agent_process_connection pipe:00000110
2376 14:00:06:361 debug1: client type: sshd service
2376 14:00:06:361 debug3: connection io 003DAF58 #bytes:0 state:0
2376 14:00:06:361 debug3: connection io 003DAF58 #bytes:4 state:1
2376 14:00:06:361 debug3: connection io 003DAF58 #bytes:954 state:2
2376 14:00:06:361 debug1: process agent request type 0
2376 14:00:06:361 debug1: LsaLogonUser failed NTSTATUS: 1
2376 14:00:06:361 error: unable to generate token for user pulse
2376 14:00:06:361 debug3: connection io 003DAF58 #bytes:5 state:3
2908 14:00:06:376 debug1: iocp error: 109 on 0055AF58
2908 14:00:06:376 debug1: connection 0055AF58 clean up
2908 14:00:06:376 debug1: iocp error: 6 on 00000000
2376 14:00:06:376 debug1: iocp error: 109 on 003DAF58
2376 14:00:06:376 debug1: connection 003DAF58 clean up
2376 14:00:06:376 debug1: iocp error: 6 on 00000000
2868 14:06:04:861 client pid 1408 connected
2868 14:06:04:861 debug1: spawned worker 2324 for agent client pid 1408
2324 14:06:04:876 agent_process_connection pipe:00000110
2324 14:06:04:876 debug1: client type: sshd service
2324 14:06:04:876 debug3: connection io 0045AF58 #bytes:0 state:0
2324 14:06:04:876 debug1: connection 0045AF58 clean up
2324 14:06:04:876 debug1: iocp error: 6 on 00000000
2868 14:06:04:892 client pid 1408 connected
2868 14:06:04:892 debug1: spawned worker 1172 for agent client pid 1408
1172 14:06:04:908 agent_process_connection pipe:00000110
1172 14:06:04:908 debug1: client type: sshd service
1172 14:06:04:908 debug3: connection io 006BAF58 #bytes:0 state:0
1172 14:06:05:126 debug1: iocp error: 109 on 006BAF58
1172 14:06:05:126 debug1: connection 006BAF58 clean up
1172 14:06:05:126 debug1: iocp error: 6 on 00000000
2868 14:06:07:595 client pid 3020 connected
2868 14:06:07:595 debug1: spawned worker 2004 for agent client pid 3020
2004 14:06:07:611 agent_process_connection pipe:00000110
2004 14:06:07:611 debug1: client type: sshd service
2004 14:06:07:611 debug3: connection io 0026AF58 #bytes:0 state:0
2004 14:06:07:611 debug1: connection 0026AF58 clean up
2004 14:06:07:611 debug1: iocp error: 6 on 00000000
2868 14:06:07:626 client pid 3020 connected
2868 14:06:07:626 debug1: spawned worker 2556 for agent client pid 3020
2556 14:06:07:642 agent_process_connection pipe:00000110
2556 14:06:07:642 debug1: client type: sshd service
2556 14:06:07:642 debug3: connection io 003FAF58 #bytes:0 state:0
2868 14:06:07:861 client pid 3020 connected
2868 14:06:07:861 debug1: spawned worker 2456 for agent client pid 3020
2456 14:06:07:876 agent_process_connection pipe:00000110
2456 14:06:07:876 debug1: client type: sshd service
2456 14:06:07:876 debug3: connection io 0072AF58 #bytes:0 state:0
2456 14:06:07:876 debug3: connection io 0072AF58 #bytes:4 state:1
2456 14:06:07:876 debug3: connection io 0072AF58 #bytes:954 state:2
2456 14:06:07:876 debug1: process agent request type 0
2456 14:06:07:876 debug1: LsaLogonUser failed NTSTATUS: 1
2456 14:06:07:876 error: unable to generate token for user pulse
2456 14:06:07:876 debug3: connection io 0072AF58 #bytes:5 state:3
2556 14:06:07:876 debug1: iocp error: 109 on 003FAF58
2556 14:06:07:876 debug1: connection 003FAF58 clean up
2556 14:06:07:876 debug1: iocp error: 6 on 00000000
2456 14:06:07:876 debug1: iocp error: 109 on 0072AF58
2456 14:06:07:876 debug1: connection 0072AF58 clean up
2456 14:06:07:876 debug1: iocp error: 6 on 00000000

sshd logs:
2932 13:57:11:986 debug1: sshd version OpenSSH_7.5, LibreSSL 2.5.3
2932 13:57:11:986 debug3: socket:0, socktype:1, io:0063F930, fd:3
2932 13:57:11:986 debug3: close - io:0063F930, type:2, fd:3, table_index:3
2932 13:57:12:001 debug3: open - handle:000000F8, io:0063F930, fd:3
2932 13:57:12:001 debug3: close - io:0063F930, type:2, fd:3, table_index:3
2932 13:57:12:001 debug1: private host key #0: ssh-rsa SHA256:hg/xirHFu+b8YMKiso6NHSsszSBecp7CNPu1WwScDkw
2932 13:57:12:001 debug3: open - handle:000000F8, io:0063F930, fd:3
2932 13:57:12:017 debug3: close - io:0063F930, type:2, fd:3, table_index:3
2932 13:57:12:017 debug1: private host key #1: ssh-dss SHA256:slNA6L+oL3gDiqu1/F2HhPbAK/pJlhKYjmIeEIPiVSU
2932 13:57:12:017 debug3: open - handle:000000F8, io:0063F930, fd:3
2932 13:57:12:017 debug3: close - io:0063F930, type:2, fd:3, table_index:3
2932 13:57:12:017 debug1: private host key #2: ecdsa-sha2-nistp256 SHA256:REWuaAiwHMpH7awojD6oViWjPEO1sIGgeR+xWM6Vvfk
2932 13:57:12:017 debug3: open - handle:000000F8, io:0063F930, fd:3
2932 13:57:12:017 debug3: close - io:0063F930, type:2, fd:3, table_index:3
2932 13:57:12:017 debug1: private host key #3: ssh-ed25519 SHA256://F1hesZoZr/HJEq4/vz0gKZi1DmtaAei4tsAIh5d+g
2932 13:57:12:017 debug3: socket:212, socktype:1, io:0063F930, fd:3
2932 13:57:12:017 debug2: fd 3 setting O_NONBLOCK
2932 13:57:12:017 debug3: sock_set_v6only: set socket 3 IPV6_V6ONLY
2932 13:57:12:017 debug1: Bind to port 22 on ::.
2932 13:57:12:017 Server listening on :: port 22.
2932 13:57:12:017 debug3: socket:300, socktype:1, io:0063FBB0, fd:4
2932 13:57:12:017 debug2: fd 4 setting O_NONBLOCK
2932 13:57:12:017 debug1: Bind to port 22 on 0.0.0.0.
2932 13:57:12:017 Server listening on 0.0.0.0 port 22.
2932 14:00:06:220 debug3: socket:312, io:0063FC30, fd:5
2932 14:00:06:220 debug3: fd 5 is not O_NONBLOCK
2932 14:00:06:220 debug3: pipe - r-h:164,io:0063FCB0,fd:6 w-h:160,io:0063FD30,fd:7
2932 14:00:06:220 debug3: spawning "C:\Program Files (x86)\OpenSSH-Win32\sshd.exe"
2932 14:00:06:220 debug3: Register child 00000148 pid 1440, 0 zombies of 0
2932 14:00:06:220 debug3: close - io:0063FC30, type:1, fd:5, table_index:5
2932 14:00:06:220 debug1: Forked child 1440.
2932 14:00:06:220 debug3: close - io:0063FD30, type:2, fd:7, table_index:7
1440 14:00:06:236 debug1: sshd version OpenSSH_7.5, LibreSSL 2.5.3
1440 14:00:06:236 debug3: socket:0, socktype:1, io:0068FA48, fd:3
1440 14:00:06:236 debug3: close - io:0068FA48, type:2, fd:3, table_index:3
1440 14:00:06:236 debug3: open - handle:000000F4, io:0068FA48, fd:3
1440 14:00:06:251 debug3: close - io:0068FA48, type:2, fd:3, table_index:3
1440 14:00:06:251 debug1: private host key #0: ssh-rsa SHA256:hg/xirHFu+b8YMKiso6NHSsszSBecp7CNPu1WwScDkw
1440 14:00:06:251 debug3: open - handle:000000F4, io:0068FA48, fd:3
1440 14:00:06:251 debug3: close - io:0068FA48, type:2, fd:3, table_index:3
1440 14:00:06:267 debug1: private host key #1: ssh-dss SHA256:slNA6L+oL3gDiqu1/F2HhPbAK/pJlhKYjmIeEIPiVSU
1440 14:00:06:267 debug3: open - handle:000000F4, io:0068FA48, fd:3
1440 14:00:06:267 debug3: close - io:0068FA48, type:2, fd:3, table_index:3
1440 14:00:06:267 debug1: private host key #2: ecdsa-sha2-nistp256 SHA256:REWuaAiwHMpH7awojD6oViWjPEO1sIGgeR+xWM6Vvfk
1440 14:00:06:267 debug3: open - handle:000000F4, io:0068FA48, fd:3
1440 14:00:06:267 debug3: close - io:0068FA48, type:2, fd:3, table_index:3
1440 14:00:06:267 debug1: private host key #3: ssh-ed25519 SHA256://F1hesZoZr/HJEq4/vz0gKZi1DmtaAei4tsAIh5d+g
1440 14:00:06:267 debug1: child socket: 312
1440 14:00:06:267 debug1: child startup_pipe: 160
1440 14:00:06:267 Connection from ::1 port 49161 on ::1 port 22
1440 14:00:06:267 debug1: Client protocol version 2.0; client software version OpenSSH_6.7p1 Debian-5+deb8u3
1440 14:00:06:267 debug1: match: OpenSSH_6.7p1 Debian-5+deb8u3 pat OpenSSH* compat 0x04000000
1440 14:00:06:267 debug1: Local version string SSH-2.0-OpenSSH_7.5
1440 14:00:06:267 debug2: fd 3 setting O_NONBLOCK
1440 14:00:06:267 debug3: socket:0, socktype:1, io:0068FD48, fd:5
1440 14:00:06:267 debug3: list_hostkey_types: ssh-dss key not permitted by HostkeyAlgorithms
1440 14:00:06:267 debug1: list_hostkey_types: ssh-rsa,rsa-sha2-512,rsa-sha2-256,ecdsa-sha2-nistp256,ssh-ed25519
1440 14:00:06:267 debug3: send packet: type 20
1440 14:00:06:267 debug1: SSH2_MSG_KEXINIT sent
1440 14:00:06:283 debug3: receive packet: type 20
1440 14:00:06:283 debug1: SSH2_MSG_KEXINIT received
1440 14:00:06:283 debug2: local server KEXINIT proposal
1440 14:00:06:283 debug2: KEX algorithms: curve25519-sha256,[email protected],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,diffie-hellman-group14-sha1
1440 14:00:06:283 debug2: host key algorithms: ssh-rsa,rsa-sha2-512,rsa-sha2-256,ecdsa-sha2-nistp256,ssh-ed25519
1440 14:00:06:283 debug2: ciphers ctos: [email protected],aes128-ctr,aes192-ctr,aes256-ctr
1440 14:00:06:283 debug2: ciphers stoc: [email protected],aes128-ctr,aes192-ctr,aes256-ctr
1440 14:00:06:283 debug2: MACs ctos: [email protected],[email protected],[email protected],[email protected],[email protected],[email protected],[email protected],hmac-sha2-256,hmac-sha2-512,hmac-sha1
1440 14:00:06:283 debug2: MACs stoc: [email protected],[email protected],[email protected],[email protected],[email protected],[email protected],[email protected],hmac-sha2-256,hmac-sha2-512,hmac-sha1
1440 14:00:06:283 debug2: compression ctos: none
1440 14:00:06:283 debug2: compression stoc: none
1440 14:00:06:283 debug2: languages ctos:
1440 14:00:06:283 debug2: languages stoc:
1440 14:00:06:283 debug2: first_kex_follows 0
1440 14:00:06:283 debug2: reserved 0
1440 14:00:06:283 debug2: peer client KEXINIT proposal
1440 14:00:06:283 debug2: KEX algorithms: [email protected],ecdh-sha2-nistp256,ecdh-sha2-nistp384,ecdh-sha2-nistp521,diffie-hellman-group-exchange-sha256,diffie-hellman-group14-sha1,diffie-hellman-group-exchange-sha1,diffie-hellman-group1-sha1
1440 14:00:06:283 debug2: host key algorithms: [email protected],[email protected],[email protected],ecdsa-sha2-nistp256,ecdsa-sha2-nistp384,ecdsa-sha2-nistp521,[email protected],[email protected],[email protected],[email protected],[email protected],ssh-ed25519,ssh-rsa,ssh-dss
1440 14:00:06:283 debug2: ciphers ctos: aes128-ctr,aes192-ctr,aes256-ctr,[email protected],[email protected],[email protected],arcfour256,arcfour128,aes128-cbc,3des-cbc,blowfish-cbc,cast128-cbc,aes192-cbc,aes256-cbc,arcfour,[email protected]
1440 14:00:06:283 debug2: ciphers stoc: aes128-ctr,aes192-ctr,aes256-ctr,[email protected],[email protected],[email protected],arcfour256,arcfour128,aes128-cbc,3des-cbc,blowfish-cbc,cast128-cbc,aes192-cbc,aes256-cbc,arcfour,[email protected]
1440 14:00:06:283 debug2: MACs ctos: [email protected],[email protected],[email protected],[email protected],[email protected],[email protected],[email protected],hmac-sha2-256,hmac-sha2-512,hmac-sha1,[email protected],[email protected],[email protected],[email protected],hmac-md5,hmac-ripemd160,[email protected],hmac-sha1-96,hmac-md5-96
1440 14:00:06:283 debug2: MACs stoc: [email protected],[email protected],[email protected],[email protected],[email protected],[email protected],[email protected],hmac-sha2-256,hmac-sha2-512,hmac-sha1,[email protected],[email protected],[email protected],[email protected],hmac-md5,hmac-ripemd160,[email protected],hmac-sha1-96,hmac-md5-96
1440 14:00:06:283 debug2: compression ctos: none,[email protected],zlib
1440 14:00:06:283 debug2: compression stoc: none,[email protected],zlib
1440 14:00:06:283 debug2: languages ctos:
1440 14:00:06:283 debug2: languages stoc:
1440 14:00:06:283 debug2: first_kex_follows 0
1440 14:00:06:283 debug2: reserved 0
1440 14:00:06:283 debug1: kex: algorithm: [email protected]
1440 14:00:06:283 debug1: kex: host key algorithm: ecdsa-sha2-nistp256
1440 14:00:06:283 debug1: kex: client->server cipher: aes128-ctr MAC: [email protected] compression: none
1440 14:00:06:283 debug1: kex: server->client cipher: aes128-ctr MAC: [email protected] compression: none
1440 14:00:06:283 debug1: expecting SSH2_MSG_KEX_ECDH_INIT
1440 14:00:06:283 debug3: receive packet: type 30
1440 14:00:06:298 debug3: send packet: type 31
1440 14:00:06:298 debug3: send packet: type 21
1440 14:00:06:298 debug2: set_newkeys: mode 1
1440 14:00:06:298 debug1: rekey after 4294967296 blocks
1440 14:00:06:298 debug1: SSH2_MSG_NEWKEYS sent
1440 14:00:06:298 debug1: expecting SSH2_MSG_NEWKEYS
1440 14:00:06:298 debug3: receive packet: type 21
1440 14:00:06:298 debug1: SSH2_MSG_NEWKEYS received
1440 14:00:06:298 debug2: set_newkeys: mode 0
1440 14:00:06:298 debug1: rekey after 4294967296 blocks
1440 14:00:06:298 debug1: KEX done
1440 14:00:06:345 debug3: receive packet: type 5
1440 14:00:06:345 debug3: send packet: type 6
1440 14:00:06:345 debug3: receive packet: type 50
1440 14:00:06:345 debug1: userauth-request for user pulse service ssh-connection method none
1440 14:00:06:345 debug1: attempt 0 failures 0
1440 14:00:06:345 debug2: parse_server_config: config reprocess config len 243
1440 14:00:06:345 debug2: input_userauth_request: setting up authctxt for pulse
1440 14:00:06:345 debug2: input_userauth_request: try method none
1440 14:00:06:345 Failed none for pulse from ::1 port 49161 ssh2
1440 14:00:06:345 debug3: userauth_finish: failure partial=0 next methods="publickey,keyboard-interactive"
1440 14:00:06:345 debug3: send packet: type 51
1440 14:00:06:345 debug3: receive packet: type 50
1440 14:00:06:345 debug1: userauth-request for user pulse service ssh-connection method publickey
1440 14:00:06:345 debug1: attempt 1 failures 0
1440 14:00:06:345 debug2: input_userauth_request: try method publickey
1440 14:00:06:345 debug1: userauth_pubkey: test whether pkalg/pkblob are acceptable for RSA SHA256:LEUOco6ipZ0flqGgXci40qsaCZgYElijRqjMcdFlGjQ
1440 14:00:06:345 debug1: trying public key file C:\Program Files (x86)\Pulse\.ssh\authorized_keys
1440 14:00:06:345 debug1: matching key found: file C:\Program Files (x86)\Pulse\.ssh\authorized_keys, line 1 RSA SHA256:LEUOco6ipZ0flqGgXci40qsaCZgYElijRqjMcdFlGjQ
1440 14:00:06:345 debug3: send packet: type 60
1440 14:00:06:345 debug2: userauth_pubkey: authenticated 0 pkalg ssh-rsa
1440 14:00:06:345 Postponed publickey for pulse from ::1 port 49161 ssh2
1440 14:00:06:345 debug3: receive packet: type 50
1440 14:00:06:345 debug1: userauth-request for user pulse service ssh-connection method publickey
1440 14:00:06:345 debug1: attempt 2 failures 0
1440 14:00:06:345 debug2: input_userauth_request: try method publickey
1440 14:00:06:345 debug3: userauth_pubkey: have signature for RSA SHA256:LEUOco6ipZ0flqGgXci40qsaCZgYElijRqjMcdFlGjQ
1440 14:00:06:345 debug1: trying public key file C:\Program Files (x86)\Pulse\.ssh\authorized_keys
1440 14:00:06:345 debug1: matching key found: file C:\Program Files (x86)\Pulse\.ssh\authorized_keys, line 1 RSA SHA256:LEUOco6ipZ0flqGgXci40qsaCZgYElijRqjMcdFlGjQ
1440 14:00:06:345 debug3: socket:0, socktype:1, io:0068FEC8, fd:6
1440 14:00:06:361 debug2: userauth_pubkey: authenticated 0 pkalg ssh-rsa
1440 14:00:06:361 Failed publickey for pulse from ::1 port 49161 ssh2: RSA SHA256:LEUOco6ipZ0flqGgXci40qsaCZgYElijRqjMcdFlGjQ
1440 14:00:06:361 debug3: userauth_finish: failure partial=0 next methods="publickey,keyboard-interactive"
1440 14:00:06:361 debug3: send packet: type 51
1440 14:00:06:376 debug3: receive packet: type 50
1440 14:00:06:376 debug1: userauth-request for user pulse service ssh-connection method keyboard-interactive
1440 14:00:06:376 debug1: attempt 3 failures 1
1440 14:00:06:376 debug2: input_userauth_request: try method keyboard-interactive
1440 14:00:06:376 debug1: keyboard-interactive devs
1440 14:00:06:376 debug1: auth2_challenge: user=pulse devs=
1440 14:00:06:376 debug1: kbdint_alloc: devices ''
1440 14:00:06:376 debug2: auth2_challenge_start: devices
1440 14:00:06:376 Failed keyboard-interactive for pulse from ::1 port 49161 ssh2
1440 14:00:06:376 debug3: userauth_finish: failure partial=0 next methods="publickey,keyboard-interactive"
1440 14:00:06:376 debug3: send packet: type 51
1440 14:00:06:376 Connection closed by authenticating user pulse ::1 port 49161
1440 14:00:06:376 debug1: do_cleanup
2932 14:00:06:376 debug3: close - io:0063FCB0, type:2, fd:6, table_index:6
2932 14:00:06:376 debug3: zombie'ing child at index 0, 0 zombies of 1
2932 14:00:06:376 debug3: Unregister child at index 0, 1 zombies of 1
2932 14:06:04:845 debug3: socket:316, io:0063FCB0, fd:5
2932 14:06:04:845 debug3: fd 5 is not O_NONBLOCK
2932 14:06:04:845 debug3: pipe - r-h:164,io:0063FD30,fd:6 w-h:328,io:0063FC30,fd:7
2932 14:06:04:845 debug3: spawning "C:\Program Files (x86)\OpenSSH-Win32\sshd.exe"
2932 14:06:04:845 debug3: Register child 000000DC pid 1408, 0 zombies of 0
2932 14:06:04:845 debug3: close - io:0063FCB0, type:1, fd:5, table_index:5
2932 14:06:04:845 debug1: Forked child 1408.
2932 14:06:04:845 debug3: close - io:0063FC30, type:2, fd:7, table_index:7
1408 14:06:04:861 debug1: sshd version OpenSSH_7.5, LibreSSL 2.5.3
1408 14:06:04:861 debug3: socket:0, socktype:1, io:0060FA48, fd:3
1408 14:06:04:861 debug3: close - io:0060FA48, type:2, fd:3, table_index:3
1408 14:06:04:861 debug3: open - handle:000000F4, io:0060FA48, fd:3
1408 14:06:04:876 debug3: close - io:0060FA48, type:2, fd:3, table_index:3
1408 14:06:04:876 debug1: private host key #0: ssh-rsa SHA256:hg/xirHFu+b8YMKiso6NHSsszSBecp7CNPu1WwScDkw
1408 14:06:04:876 debug3: open - handle:000000F4, io:0060FA48, fd:3
1408 14:06:04:876 debug3: close - io:0060FA48, type:2, fd:3, table_index:3
1408 14:06:04:892 debug1: private host key #1: ssh-dss SHA256:slNA6L+oL3gDiqu1/F2HhPbAK/pJlhKYjmIeEIPiVSU
1408 14:06:04:892 debug3: open - handle:000000F4, io:0060FA48, fd:3
1408 14:06:04:892 debug3: close - io:0060FA48, type:2, fd:3, table_index:3
1408 14:06:04:892 debug1: private host key #2: ecdsa-sha2-nistp256 SHA256:REWuaAiwHMpH7awojD6oViWjPEO1sIGgeR+xWM6Vvfk
1408 14:06:04:892 debug3: open - handle:000000F4, io:0060FA48, fd:3
1408 14:06:04:892 debug3: close - io:0060FA48, type:2, fd:3, table_index:3
1408 14:06:04:892 debug1: private host key #3: ssh-ed25519 SHA256://F1hesZoZr/HJEq4/vz0gKZi1DmtaAei4tsAIh5d+g
1408 14:06:04:892 debug1: child socket: 316
1408 14:06:04:892 debug1: child startup_pipe: 328
1408 14:06:04:892 Connection from 192.168.56.2 port 36885 on 192.168.56.100 port 22
1408 14:06:04:892 debug1: Client protocol version 2.0; client software version OpenSSH_6.7p1 Debian-5+deb8u3
1408 14:06:04:892 debug1: match: OpenSSH_6.7p1 Debian-5+deb8u3 pat OpenSSH* compat 0x04000000
1408 14:06:04:892 debug1: Local version string SSH-2.0-OpenSSH_7.5
1408 14:06:04:892 debug2: fd 3 setting O_NONBLOCK
1408 14:06:04:892 debug3: socket:0, socktype:1, io:0060FD48, fd:5
1408 14:06:04:892 debug3: list_hostkey_types: ssh-dss key not permitted by HostkeyAlgorithms
1408 14:06:04:892 debug1: list_hostkey_types: ssh-rsa,rsa-sha2-512,rsa-sha2-256,ecdsa-sha2-nistp256,ssh-ed25519
1408 14:06:04:892 debug3: send packet: type 20
1408 14:06:04:892 debug1: SSH2_MSG_KEXINIT sent
1408 14:06:04:892 debug3: receive packet: type 20
1408 14:06:04:892 debug1: SSH2_MSG_KEXINIT received
1408 14:06:04:892 debug2: local server KEXINIT proposal
1408 14:06:04:892 debug2: KEX algorithms: curve25519-sha256,[email protected],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,diffie-hellman-group14-sha1
1408 14:06:04:892 debug2: host key algorithms: ssh-rsa,rsa-sha2-512,rsa-sha2-256,ecdsa-sha2-nistp256,ssh-ed25519
1408 14:06:04:892 debug2: ciphers ctos: [email protected],aes128-ctr,aes192-ctr,aes256-ctr
1408 14:06:04:892 debug2: ciphers stoc: [email protected],aes128-ctr,aes192-ctr,aes256-ctr
1408 14:06:04:892 debug2: MACs ctos: [email protected],[email protected],[email protected],[email protected],[email protected],[email protected],[email protected],hmac-sha2-256,hmac-sha2-512,hmac-sha1
1408 14:06:04:892 debug2: MACs stoc: [email protected],[email protected],[email protected],[email protected],[email protected],[email protected],[email protected],hmac-sha2-256,hmac-sha2-512,hmac-sha1
1408 14:06:04:892 debug2: compression ctos: none
1408 14:06:04:892 debug2: compression stoc: none
1408 14:06:04:892 debug2: languages ctos:
1408 14:06:04:892 debug2: languages stoc:
1408 14:06:04:892 debug2: first_kex_follows 0
1408 14:06:04:892 debug2: reserved 0
1408 14:06:04:892 debug2: peer client KEXINIT proposal
1408 14:06:04:892 debug2: KEX algorithms: [email protected],ecdh-sha2-nistp256,ecdh-sha2-nistp384,ecdh-sha2-nistp521,diffie-hellman-group-exchange-sha256,diffie-hellman-group14-sha1,diffie-hellman-group-exchange-sha1,diffie-hellman-group1-sha1
1408 14:06:04:892 debug2: host key algorithms: [email protected],[email protected],[email protected],[email protected],[email protected],[email protected],[email protected],[email protected],ecdsa-sha2-nistp256,ecdsa-sha2-nistp384,ecdsa-sha2-nistp521,ssh-ed25519,ssh-rsa,ssh-dss
1408 14:06:04:892 debug2: ciphers ctos: aes128-ctr,aes192-ctr,aes256-ctr,[email protected],[email protected],[email protected],arcfour256,arcfour128,aes128-cbc,3des-cbc,blowfish-cbc,cast128-cbc,aes192-cbc,aes256-cbc,arcfour,[email protected]
1408 14:06:04:892 debug2: ciphers stoc: aes128-ctr,aes192-ctr,aes256-ctr,[email protected],[email protected],[email protected],arcfour256,arcfour128,aes128-cbc,3des-cbc,blowfish-cbc,cast128-cbc,aes192-cbc,aes256-cbc,arcfour,[email protected]
1408 14:06:04:892 debug2: MACs ctos: [email protected],[email protected],[email protected],[email protected],[email protected],[email protected],[email protected],hmac-sha2-256,hmac-sha2-512,hmac-sha1,[email protected],[email protected],[email protected],[email protected],hmac-md5,hmac-ripemd160,[email protected],hmac-sha1-96,hmac-md5-96
1408 14:06:04:892 debug2: MACs stoc: [email protected],[email protected],[email protected],[email protected],[email protected],[email protected],[email protected],hmac-sha2-256,hmac-sha2-512,hmac-sha1,[email protected],[email protected],[email protected],[email protected],hmac-md5,hmac-ripemd160,[email protected],hmac-sha1-96,hmac-md5-96
1408 14:06:04:892 debug2: compression ctos: none,[email protected],zlib
1408 14:06:04:892 debug2: compression stoc: none,[email protected],zlib
1408 14:06:04:892 debug2: languages ctos:
1408 14:06:04:892 debug2: languages stoc:
1408 14:06:04:892 debug2: first_kex_follows 0
1408 14:06:04:892 debug2: reserved 0
1408 14:06:04:892 debug1: kex: algorithm: [email protected]
1408 14:06:04:892 debug1: kex: host key algorithm: ecdsa-sha2-nistp256
1408 14:06:04:892 debug1: kex: client->server cipher: aes128-ctr MAC: [email protected] compression: none
1408 14:06:04:892 debug1: kex: server->client cipher: aes128-ctr MAC: [email protected] compression: none
1408 14:06:04:892 debug1: expecting SSH2_MSG_KEX_ECDH_INIT
1408 14:06:04:908 debug3: receive packet: type 30
1408 14:06:04:908 debug3: send packet: type 31
1408 14:06:04:908 debug3: send packet: type 21
1408 14:06:04:908 debug2: set_newkeys: mode 1
1408 14:06:04:908 debug1: rekey after 4294967296 blocks
1408 14:06:04:908 debug1: SSH2_MSG_NEWKEYS sent
1408 14:06:04:908 debug1: expecting SSH2_MSG_NEWKEYS
1408 14:06:04:908 debug3: receive packet: type 21
1408 14:06:04:908 debug1: SSH2_MSG_NEWKEYS received
1408 14:06:04:908 debug2: set_newkeys: mode 0
1408 14:06:04:908 debug1: rekey after 4294967296 blocks
1408 14:06:04:908 debug1: KEX done
1408 14:06:05:111 debug3: receive packet: type 5
1408 14:06:05:111 debug3: send packet: type 6
1408 14:06:05:126 debug3: receive packet: type 50
1408 14:06:05:126 debug1: userauth-request for user root service ssh-connection method none
1408 14:06:05:126 debug1: attempt 0 failures 0
1408 14:06:05:126 debug2: parse_server_config: config reprocess config len 243
1408 14:06:05:126 debug3: NetUserGetInfo() failed with error: 2221 for user: root and domain: (null) \n
1408 14:06:05:126 error: DsGetDcNameW() failed with error: 1355 \n
1408 14:06:05:126 Invalid user root from 192.168.56.2 port 36885
1408 14:06:05:126 debug2: input_userauth_request: try method none
1408 14:06:05:126 Failed none for invalid user root from 192.168.56.2 port 36885 ssh2
1408 14:06:05:126 debug3: userauth_finish: failure partial=0 next methods="publickey,keyboard-interactive"
1408 14:06:05:126 debug3: send packet: type 51
1408 14:06:05:126 debug3: receive packet: type 50
1408 14:06:05:126 debug1: userauth-request for user root service ssh-connection method publickey
1408 14:06:05:126 debug1: attempt 1 failures 0
1408 14:06:05:126 debug2: input_userauth_request: try method publickey
1408 14:06:05:126 debug2: userauth_pubkey: disabled because of invalid user
1408 14:06:05:126 Failed publickey for invalid user root from 192.168.56.2 port 36885 ssh2
1408 14:06:05:126 debug3: userauth_finish: failure partial=0 next methods="publickey,keyboard-interactive"
1408 14:06:05:126 debug3: send packet: type 51
1408 14:06:05:126 Connection closed by invalid user root 192.168.56.2 port 36885
1408 14:06:05:126 debug1: do_cleanup
2932 14:06:05:126 debug3: close - io:0063FD30, type:2, fd:6, table_index:6
2932 14:06:05:126 debug3: zombie'ing child at index 0, 0 zombies of 1
2932 14:06:05:126 debug3: Unregister child at index 0, 1 zombies of 1

Works fine with same setup but on Windows 10

@bagajjal
Copy link
Collaborator

This is the reason for the failure:
2376 14:00:06:361 debug1: LsaLogonUser failed NTSTATUS: 1
2376 14:00:06:361 error: unable to generate token for user pulse

Can you share details on your Win7 box- x86 vs x64? SP? all latest updates installed?

@spointu
Copy link
Author

spointu commented Jul 24, 2017

Win7 Enterprise x64 no SP applied. I'll try with a fully updated Win7 and let you know if the problem is resolved.

@spointu
Copy link
Author

spointu commented Jul 25, 2017

I have tested on an up-to-date Win7 and it's the same error.

@spointu
Copy link
Author

spointu commented Jul 26, 2017

It's ok now. I did not realise that the paths have changed to C:\Program Files\OpenSSH. I was still using OpenSSH-Win32.

@spointu spointu closed this as completed Jul 26, 2017
@bagajjal bagajjal self-assigned this Jul 26, 2017
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
None yet
Projects
None yet
Development

No branches or pull requests

2 participants