Re: Problems with login to debian7 server

[Daniel Kroker <dk@xxxxxxxxx>]

Hi,

in the attachment is the debug log file from openssh server.

Am 13.05.2014 10:57, schrieb Daniel Kroker:
> Hi,
>
> now i have call ssh_userauth_none before i call ssh_userauth_list but 
> ssh_userauth_none returned "SSH_AUTH_ERROR "
>
> rc = ssh_userauth_none(session, NULL);
>
>   if (rc == SSH_AUTH_ERROR) {
>
>     qDebug() << "SSH Error 121: ", ssh_get_error(session);
>
>     return rc;
>
>   }
>
>   method = ssh_userauth_list(session, NULL);
> Am 28.04.2014 16:12, schrieb Daniel Kroker:
> > Hi,
> >
> > no.
> >
> > Am 28.04.2014 16:02, schrieb Andreas Schneider:
> > > On Monday 28 April 2014 15:34:56 Daniel Kroker wrote:
> > > > Hello,
> > > >
> > > > i have problems to login into debian 7 server. Debian 5 works fine for
> > > > me with this code.
> > > >
> > > >     int rc = method = ssh_userauth_list(session, NULL);
> > > >     qDebug() << "method: " << QString::number(method);
> > > >
> > > > ssh_userauth_list returned 0 it looks like 0 is 
> > > > ssh_auth_method_unknown.
> > >
> > > Did you call ssh_userauth_none() before?
> > >
> > >
> > >
> > >     -- andreas

May 13 12:33:03 irecou1 sshd[22787]: debug3: oom_adjust_restore
May 13 12:33:03 irecou1 sshd[22787]: Set /proc/self/oom_score_adj to 0
May 13 12:33:03 irecou1 sshd[22787]: debug1: rexec start in 5 out 5 newsock 5 pipe 7 sock 8
May 13 12:33:03 irecou1 sshd[22787]: debug1: inetd sockets after dupping: 3, 3
May 13 12:33:03 irecou1 sshd[22787]: Connection from 10.0.0.101 port 3447
May 13 12:33:03 irecou1 sshd[22787]: debug1: Client protocol version 2.0; client software version libssh-0.5.2
May 13 12:33:03 irecou1 sshd[22787]: debug1: no match: libssh-0.5.2
May 13 12:33:03 irecou1 sshd[22787]: debug1: Enabling compatibility mode for protocol 2.0
May 13 12:33:03 irecou1 sshd[22787]: debug1: Local version string SSH-2.0-OpenSSH_6.0p1 Debian-4+deb7u1
May 13 12:33:03 irecou1 sshd[22787]: debug2: fd 3 setting O_NONBLOCK
May 13 12:33:03 irecou1 sshd[22787]: debug2: Network child is on pid 22788
May 13 12:33:03 irecou1 sshd[22787]: debug3: preauth child monitor started
May 13 12:33:03 irecou1 sshd[22787]: debug3: privsep user:group 101:65534 [preauth]
May 13 12:33:03 irecou1 sshd[22787]: debug1: permanently_set_uid: 101/65534 [preauth]
May 13 12:33:03 irecou1 sshd[22787]: debug1: list_hostkey_types: ssh-rsa,ssh-dss [preauth]
May 13 12:33:03 irecou1 sshd[22787]: debug1: SSH2_MSG_KEXINIT sent [preauth]
May 13 12:33:03 irecou1 sshd[22787]: debug1: SSH2_MSG_KEXINIT received [preauth]
May 13 12:33:03 irecou1 sshd[22787]: debug2: kex_parse_kexinit: ecdh-sha2-nistp256,ecdh-sha2-nistp384,ecdh-sha2-nistp521,diffie-hellman-group-exchange-sha256,diffie-hellman-group-exchange-sha1,diffie-hellman-group14-sha1,diffie-hellman-group1-sha1 [preauth]
May 13 12:33:03 irecou1 sshd[22787]: debug2: kex_parse_kexinit: ssh-rsa,ssh-dss [preauth]
May 13 12:33:03 irecou1 sshd[22787]: debug2: kex_parse_kexinit: aes128-ctr,aes192-ctr,aes256-ctr,arcfour256,arcfour128,aes128-cbc,3des-cbc,blowfish-cbc,cast128-cbc,aes192-cbc,aes256-cbc,arcfour,rijndael-cbc@xxxxxxxxxxxxxx [preauth]
May 13 12:33:03 irecou1 sshd[22787]: debug2: kex_parse_kexinit: aes128-ctr,aes192-ctr,aes256-ctr,arcfour256,arcfour128,aes128-cbc,3des-cbc,blowfish-cbc,cast128-cbc,aes192-cbc,aes256-cbc,arcfour,rijndael-cbc@xxxxxxxxxxxxxx [preauth]
May 13 12:33:03 irecou1 sshd[22787]: debug2: kex_parse_kexinit: hmac-md5,hmac-sha1,umac-64@xxxxxxxxxxx,hmac-sha2-256,hmac-sha2-256-96,hmac-sha2-512,hmac-sha2-512-96,hmac-ripemd160,hmac-ripemd160@xxxxxxxxxxx,hmac-sha1-96,hmac-md5-96 [preauth]
May 13 12:33:03 irecou1 sshd[22787]: debug2: kex_parse_kexinit: hmac-md5,hmac-sha1,umac-64@xxxxxxxxxxx,hmac-sha2-256,hmac-sha2-256-96,hmac-sha2-512,hmac-sha2-512-96,hmac-ripemd160,hmac-ripemd160@xxxxxxxxxxx,hmac-sha1-96,hmac-md5-96 [preauth]
May 13 12:33:03 irecou1 sshd[22787]: debug2: kex_parse_kexinit: none,zlib@xxxxxxxxxxx [preauth]
May 13 12:33:03 irecou1 sshd[22787]: debug2: kex_parse_kexinit: none,zlib@xxxxxxxxxxx [preauth]
May 13 12:33:03 irecou1 sshd[22787]: debug2: kex_parse_kexinit:  [preauth]
May 13 12:33:03 irecou1 sshd[22787]: debug2: kex_parse_kexinit:  [preauth]
May 13 12:33:03 irecou1 sshd[22787]: debug2: kex_parse_kexinit: first_kex_follows 0  [preauth]
May 13 12:33:03 irecou1 sshd[22787]: debug2: kex_parse_kexinit: reserved 0  [preauth]
May 13 12:33:03 irecou1 sshd[22787]: debug2: kex_parse_kexinit: diffie-hellman-group1-sha1 [preauth]
May 13 12:33:03 irecou1 sshd[22787]: debug2: kex_parse_kexinit: ssh-rsa [preauth]
May 13 12:33:03 irecou1 sshd[22787]: debug2: kex_parse_kexinit: aes256-ctr [preauth]
May 13 12:33:03 irecou1 sshd[22787]: debug2: kex_parse_kexinit: aes256-ctr [preauth]
May 13 12:33:03 irecou1 sshd[22787]: debug2: kex_parse_kexinit: hmac-sha1 [preauth]
May 13 12:33:03 irecou1 sshd[22787]: debug2: kex_parse_kexinit: hmac-sha1 [preauth]
May 13 12:33:03 irecou1 sshd[22787]: debug2: kex_parse_kexinit: none [preauth]
May 13 12:33:03 irecou1 sshd[22787]: debug2: kex_parse_kexinit: none [preauth]
May 13 12:33:03 irecou1 sshd[22787]: debug2: kex_parse_kexinit:  [preauth]
May 13 12:33:03 irecou1 sshd[22787]: debug2: kex_parse_kexinit:  [preauth]
May 13 12:33:03 irecou1 sshd[22787]: debug2: kex_parse_kexinit: first_kex_follows 0  [preauth]
May 13 12:33:03 irecou1 sshd[22787]: debug2: kex_parse_kexinit: reserved 0  [preauth]
May 13 12:33:03 irecou1 sshd[22787]: debug2: mac_setup: found hmac-sha1 [preauth]
May 13 12:33:03 irecou1 sshd[22787]: debug1: kex: client->server aes256-ctr hmac-sha1 none [preauth]
May 13 12:33:03 irecou1 sshd[22787]: debug2: mac_setup: found hmac-sha1 [preauth]
May 13 12:33:03 irecou1 sshd[22787]: debug1: kex: server->client aes256-ctr hmac-sha1 none [preauth]
May 13 12:33:03 irecou1 sshd[22787]: debug2: dh_gen_key: priv key bits set: 266/512 [preauth]
May 13 12:33:03 irecou1 sshd[22787]: debug2: bits set: 529/1024 [preauth]
May 13 12:33:03 irecou1 sshd[22787]: debug1: expecting SSH2_MSG_KEXDH_INIT [preauth]
May 13 12:33:03 irecou1 sshd[22787]: debug2: bits set: 491/1024 [preauth]
May 13 12:33:03 irecou1 sshd[22787]: debug3: mm_key_sign entering [preauth]
May 13 12:33:03 irecou1 sshd[22787]: debug3: mm_request_send entering: type 5 [preauth]
May 13 12:33:03 irecou1 sshd[22787]: debug3: mm_key_sign: waiting for MONITOR_ANS_SIGN [preauth]
May 13 12:33:03 irecou1 sshd[22787]: debug3: mm_request_receive_expect entering: type 6 [preauth]
May 13 12:33:03 irecou1 sshd[22787]: debug3: mm_request_receive entering [preauth]
May 13 12:33:03 irecou1 sshd[22787]: debug3: mm_request_receive entering
May 13 12:33:03 irecou1 sshd[22787]: debug3: monitor_read: checking request 5
May 13 12:33:03 irecou1 sshd[22787]: debug3: mm_answer_sign
May 13 12:33:03 irecou1 sshd[22787]: debug3: mm_answer_sign: signature 0x7f0f68152c50(271)
May 13 12:33:03 irecou1 sshd[22787]: debug3: mm_request_send entering: type 6
May 13 12:33:03 irecou1 sshd[22787]: debug2: monitor_read: 5 used once, disabling now
May 13 12:33:03 irecou1 sshd[22787]: debug2: kex_derive_keys [preauth]
May 13 12:33:03 irecou1 sshd[22787]: debug2: set_newkeys: mode 1 [preauth]
May 13 12:33:03 irecou1 sshd[22787]: debug2: cipher_init: set keylen (16 -> 32) [preauth]
May 13 12:33:03 irecou1 sshd[22787]: debug1: SSH2_MSG_NEWKEYS sent [preauth]
May 13 12:33:03 irecou1 sshd[22787]: debug1: expecting SSH2_MSG_NEWKEYS [preauth]
May 13 12:33:03 irecou1 sshd[22787]: debug2: set_newkeys: mode 0 [preauth]
May 13 12:33:03 irecou1 sshd[22787]: debug2: cipher_init: set keylen (16 -> 32) [preauth]
May 13 12:33:03 irecou1 sshd[22787]: debug1: SSH2_MSG_NEWKEYS received [preauth]
May 13 12:33:03 irecou1 sshd[22787]: debug1: KEX done [preauth]
May 13 12:33:03 irecou1 sshd[22787]: debug1: userauth-request for user sdm service ssh-connection method none [preauth]
May 13 12:33:03 irecou1 sshd[22787]: debug1: attempt 0 failures 0 [preauth]
May 13 12:33:03 irecou1 sshd[22787]: debug3: mm_getpwnamallow entering [preauth]
May 13 12:33:03 irecou1 sshd[22787]: debug3: mm_request_send entering: type 7 [preauth]
May 13 12:33:03 irecou1 sshd[22787]: debug3: mm_getpwnamallow: waiting for MONITOR_ANS_PWNAM [preauth]
May 13 12:33:03 irecou1 sshd[22787]: debug3: mm_request_receive_expect entering: type 8 [preauth]
May 13 12:33:03 irecou1 sshd[22787]: debug3: mm_request_receive entering [preauth]
May 13 12:33:03 irecou1 sshd[22787]: debug3: mm_request_receive entering
May 13 12:33:03 irecou1 sshd[22787]: debug3: monitor_read: checking request 7
May 13 12:33:03 irecou1 sshd[22787]: debug3: mm_answer_pwnamallow
May 13 12:33:03 irecou1 sshd[22787]: debug3: Trying to reverse map address 10.0.0.101.
May 13 12:33:13 irecou1 sshd[22787]: debug2: parse_server_config: config reprocess config len 700
May 13 12:33:13 irecou1 sshd[22787]: debug3: mm_answer_pwnamallow: sending MONITOR_ANS_PWNAM: 1
May 13 12:33:13 irecou1 sshd[22787]: debug3: mm_request_send entering: type 8
May 13 12:33:13 irecou1 sshd[22787]: debug2: monitor_read: 7 used once, disabling now
May 13 12:33:13 irecou1 sshd[22787]: debug2: input_userauth_request: setting up authctxt for sdm [preauth]
May 13 12:33:13 irecou1 sshd[22787]: debug3: mm_start_pam entering [preauth]
May 13 12:33:13 irecou1 sshd[22787]: debug3: mm_request_send entering: type 50 [preauth]
May 13 12:33:13 irecou1 sshd[22787]: debug3: mm_inform_authserv entering [preauth]
May 13 12:33:13 irecou1 sshd[22787]: debug3: mm_request_send entering: type 3 [preauth]
May 13 12:33:13 irecou1 sshd[22787]: debug2: input_userauth_request: try method none [preauth]
May 13 12:33:13 irecou1 sshd[22787]: Connection closed by 10.0.0.101 [preauth]
May 13 12:33:13 irecou1 sshd[22787]: debug1: do_cleanup [preauth]
May 13 12:33:13 irecou1 sshd[22787]: debug3: PAM: sshpam_thread_cleanup entering [preauth]
May 13 12:33:13 irecou1 sshd[22787]: debug3: mm_request_receive entering
May 13 12:33:13 irecou1 sshd[22787]: debug3: monitor_read: checking request 50
May 13 12:33:13 irecou1 sshd[22787]: debug1: PAM: initializing for "sdm"
May 13 12:33:13 irecou1 sshd[22787]: debug1: PAM: setting PAM_RHOST to "10.0.0.101"
May 13 12:33:13 irecou1 sshd[22787]: debug1: PAM: setting PAM_TTY to "ssh"
May 13 12:33:13 irecou1 sshd[22787]: debug2: monitor_read: 50 used once, disabling now
May 13 12:33:13 irecou1 sshd[22787]: debug1: monitor_read_log: child log fd closed
May 13 12:33:13 irecou1 sshd[22787]: debug3: mm_request_receive entering
May 13 12:33:13 irecou1 sshd[22787]: debug3: monitor_read: checking request 3
May 13 12:33:13 irecou1 sshd[22787]: debug3: mm_answer_authserv: service=ssh-connection, style=, role=
May 13 12:33:13 irecou1 sshd[22787]: debug2: monitor_read: 3 used once, disabling now
May 13 12:33:13 irecou1 sshd[22787]: debug3: mm_request_receive entering
May 13 12:33:13 irecou1 sshd[22787]: debug1: do_cleanup
May 13 12:33:13 irecou1 sshd[22787]: debug1: PAM: cleanup
May 13 12:33:13 irecou1 sshd[22787]: debug3: PAM: sshpam_thread_cleanup entering
May 13 12:33:13 irecou1 sshd[22787]: debug1: Killing privsep child 22788