[Freeipa-users] Problem with webui: kerberos ticket no longer valid

David Sastre d.sastre.medina at gmail.com
Fri Aug 24 09:56:15 UTC 2012


Hello,

I'm having an issue with the web ui, it is returning "Kerberos ticket
is no longer valid" message regardless I have a valid ticket:

$ ssh sysadm at panoramix 'klist'

Ticket cache: FILE:/tmp/krb5cc_500
Default principal: admin at DOMAIN.COM

Valid starting     Expires            Service principal
08/24/12 10:42:57  08/25/12 10:42:53  krbtgt/DOMAIN.COM at DOMAIN.COM
08/24/12 10:43:19  08/25/12 10:42:53  HTTP/panoramix.domain.com at DOMAIN.COM

Following the advice in:

https://docs.fedoraproject.org/en-US/Fedora/15/html/FreeIPA_Guide/Troubleshooting-UI.html

I have obtained this log:

$ ssh -X sysadm at panoramix 'export NSPR_LOG_MODULES=negotiateauth:5;
export NSPR_LOG_FILE=/tmp/moz.log; firefox'

973989664[7f8b38e5b040]:   using REQ_DELEGATE
973989664[7f8b38e5b040]:   service = panoramix.domain.com
973989664[7f8b38e5b040]:   using negotiate-gss
973989664[7f8b38e5b040]: entering nsAuthGSSAPI::nsAuthGSSAPI()
973989664[7f8b38e5b040]: entering nsAuthGSSAPI::Init()
973989664[7f8b38e5b040]: nsHttpNegotiateAuth::GenerateCredentials()
[challenge=Negotiate]
973989664[7f8b38e5b040]: entering nsAuthGSSAPI::GetNextToken()
973989664[7f8b38e5b040]:   leaving nsAuthGSSAPI::GetNextToken [rv=0]
973989664[7f8b38e5b040]:   Sending a token of length 1375
973989664[7f8b38e5b040]: nsHttpNegotiateAuth::GenerateCredentials()
[challenge=Negotiate oRQwEqADCgEAoQsGCSqGSIb3EgECAg==]
973989664[7f8b38e5b040]: entering nsAuthGSSAPI::GetNextToken()
973989664[7f8b38e5b040]:   leaving nsAuthGSSAPI::GetNextToken [rv=4b0028]
973989664[7f8b38e5b040]:   No output token to send, exiting
973989664[7f8b38e5b040]:   using REQ_DELEGATE
973989664[7f8b38e5b040]:   service = panoramix.domain.com
973989664[7f8b38e5b040]:   using negotiate-gss
973989664[7f8b38e5b040]: entering nsAuthGSSAPI::nsAuthGSSAPI()
973989664[7f8b38e5b040]: entering nsAuthGSSAPI::Init()
973989664[7f8b38e5b040]: nsHttpNegotiateAuth::GenerateCredentials()
[challenge=Negotiate]
973989664[7f8b38e5b040]: entering nsAuthGSSAPI::GetNextToken()
973989664[7f8b38e5b040]:   leaving nsAuthGSSAPI::GetNextToken [rv=0]
973989664[7f8b38e5b040]:   Sending a token of length 1375
973989664[7f8b38e5b040]:   using REQ_DELEGATE
973989664[7f8b38e5b040]:   service = panoramix.domain.com
973989664[7f8b38e5b040]:   using negotiate-gss
973989664[7f8b38e5b040]: entering nsAuthGSSAPI::nsAuthGSSAPI()
973989664[7f8b38e5b040]: entering nsAuthGSSAPI::Init()
973989664[7f8b38e5b040]: nsHttpNegotiateAuth::GenerateCredentials()
[challenge=Negotiate]
973989664[7f8b38e5b040]: entering nsAuthGSSAPI::GetNextToken()
973989664[7f8b38e5b040]:   leaving nsAuthGSSAPI::GetNextToken [rv=0]
973989664[7f8b38e5b040]:   Sending a token of length 1375
973989664[7f8b38e5b040]: nsHttpNegotiateAuth::GenerateCredentials()
[challenge=Negotiate oRQwEqADCgEAoQsGCSqGSIb3EgECAg==]
973989664[7f8b38e5b040]: entering nsAuthGSSAPI::GetNextToken()
973989664[7f8b38e5b040]:   leaving nsAuthGSSAPI::GetNextToken [rv=4b0028]
973989664[7f8b38e5b040]:   No output token to send, exiting

Relevant portions of apache's access and error logs with LogLevel Debug are:

172.22.249.66 - - [24/Aug/2012:11:43:52 +0200] "POST /ipa/session/json
HTTP/1.1" 401 1856 "https://panoramix.domain.com/ipa/ui/" "Mozilla/5.0
(X11; Linux x86_64; rv:10.0.6) Gecko/20120717 Firefox/10.0.6"
172.22.249.66 - admin at DOMAIN.COM [24/Aug/2012:11:43:52 +0200] "POST
/ipa/session/json HTTP/1.1" 401 -
"https://panoramix.domain.com/ipa/ui/" "Mozilla/5.0 (X11; Linux
x86_64; rv:10.0.6) Gecko/20120717 Firefox/10.0.6"
172.22.249.66 - - [24/Aug/2012:11:43:52 +0200] "GET
/ipa/session/login_kerberos HTTP/1.1" 401 1856
"https://panoramix.domain.com/ipa/ui/" "Mozilla/5.0 (X11; Linux
x86_64; rv:10.0.6) Gecko/20120717 Firefox/10.0.6"
172.22.249.66 - admin at DOMAIN.COM [24/Aug/2012:11:43:52 +0200] "GET
/ipa/session/login_kerberos HTTP/1.1" 200 -
"https://panoramix.domain.com/ipa/ui/" "Mozilla/5.0 (X11; Linux
x86_64; rv:10.0.6) Gecko/20120717 Firefox/10.0.6"
172.22.249.66 - - [24/Aug/2012:11:43:52 +0200] "POST /ipa/session/json
HTTP/1.1" 401 1856 "https://panoramix.domain.com/ipa/ui/" "Mozilla/5.0
(X11; Linux x86_64; rv:10.0.6) Gecko/20120717 Firefox/10.0.6"
172.22.249.66 - admin at DOMAIN.COM [24/Aug/2012:11:43:52 +0200] "POST
/ipa/session/json HTTP/1.1" 401 -
"https://panoramix.domain.com/ipa/ui/" "Mozilla/5.0 (X11; Linux
x86_64; rv:10.0.6) Gecko/20120717 Firefox/10.0.6"

[Fri Aug 24 11:43:52 2012] [error] [client 172.22.249.66] File does
not exist: /var/www/htdocs/panoramix.domain.com/ca
[Fri Aug 24 11:43:52 2012] [info] Initial (No.1) HTTPS request
received for child 194 (server panoramix.domain.com:443)
[Fri Aug 24 11:43:52 2012] [debug] src/mod_auth_kerb.c(1939): [client
172.22.249.66] kerb_authenticate_user entered with user (NULL) and
auth_type Kerberos, referer: https://panoramix.domain.com/ipa/ui/
[Fri Aug 24 11:43:52 2012] [info] Connection to child 194 closed
(server panoramix.domain.com:443, client 172.22.249.66)
[Fri Aug 24 11:43:52 2012] [info] Connection to child 196 established
(server panoramix.domain.com:443, client 172.22.249.66)
[Fri Aug 24 11:43:52 2012] [info] Initial (No.1) HTTPS request
received for child 196 (server panoramix.domain.com:443)
[Fri Aug 24 11:43:52 2012] [debug] src/mod_auth_kerb.c(1939): [client
172.22.249.66] kerb_authenticate_user entered with user (NULL) and
auth_type Kerberos, referer: https://panoramix.domain.com/ipa/ui/
[Fri Aug 24 11:43:52 2012] [debug] src/mod_auth_kerb.c(1278): [client
172.22.249.66] Acquiring creds for HTTP at panoramix.domain.com, referer:
https://panoramix.domain.com/ipa/ui/
[Fri Aug 24 11:43:52 2012] [debug] src/mod_auth_kerb.c(1691): [client
172.22.249.66] Verifying client data using KRB5 GSS-API , referer:
https://panoramix.domain.com/ipa/ui/
[Fri Aug 24 11:43:52 2012] [debug] src/mod_auth_kerb.c(1707): [client
172.22.249.66] Client delegated us their credential, referer:
https://panoramix.domain.com/ipa/ui/
[Fri Aug 24 11:43:52 2012] [debug] src/mod_auth_kerb.c(1726): [client
172.22.249.66] GSS-API token of length 22 bytes will be sent back,
referer: https://panoramix.domain.com/ipa/ui/
[Fri Aug 24 11:43:52 2012] [info] Connection to child 196 closed
(server panoramix.domain.com:443, client 172.22.249.66)
[Fri Aug 24 11:43:52 2012] [info] Connection to child 197 established
(server panoramix.domain.com:443, client 172.22.249.66)
[Fri Aug 24 11:43:52 2012] [info] Initial (No.1) HTTPS request
received for child 197 (server panoramix.domain.com:443)
[Fri Aug 24 11:43:52 2012] [debug] src/mod_auth_kerb.c(1939): [client
172.22.249.66] kerb_authenticate_user entered with user (NULL) and
auth_type Kerberos, referer: https://panoramix.domain.com/ipa/ui/
[Fri Aug 24 11:43:52 2012] [info] Connection to child 197 closed
(server panoramix.domain.com:443, client 172.22.249.66)
[Fri Aug 24 11:43:52 2012] [info] Connection to child 198 established
(server panoramix.domain.com:443, client 172.22.249.66)
[Fri Aug 24 11:43:52 2012] [info] Initial (No.1) HTTPS request
received for child 198 (server panoramix.domain.com:443)
[Fri Aug 24 11:43:52 2012] [debug] src/mod_auth_kerb.c(1939): [client
172.22.249.66] kerb_authenticate_user entered with user (NULL) and
auth_type Kerberos, referer: https://panoramix.domain.com/ipa/ui/
[Fri Aug 24 11:43:52 2012] [debug] src/mod_auth_kerb.c(1278): [client
172.22.249.66] Acquiring creds for HTTP at panoramix.domain.com, referer:
https://panoramix.domain.com/ipa/ui/
[Fri Aug 24 11:43:52 2012] [debug] src/mod_auth_kerb.c(1691): [client
172.22.249.66] Verifying client data using KRB5 GSS-API , referer:
https://panoramix.domain.com/ipa/ui/
[Fri Aug 24 11:43:52 2012] [debug] src/mod_auth_kerb.c(1707): [client
172.22.249.66] Client delegated us their credential, referer:
https://panoramix.domain.com/ipa/ui/
[Fri Aug 24 11:43:52 2012] [debug] src/mod_auth_kerb.c(1726): [client
172.22.249.66] GSS-API token of length 22 bytes will be sent back,
referer: https://panoramix.domain.com/ipa/ui/
[Fri Aug 24 11:43:52 2012] [info] Connection to child 198 closed
(server panoramix.domain.com:443, client 172.22.249.66)
[Fri Aug 24 11:43:52 2012] [info] Connection to child 199 established
(server panoramix.domain.com:443, client 172.22.249.66)
[Fri Aug 24 11:43:52 2012] [info] Initial (No.1) HTTPS request
received for child 199 (server panoramix.domain.com:443)
[Fri Aug 24 11:43:52 2012] [debug] src/mod_auth_kerb.c(1939): [client
172.22.249.66] kerb_authenticate_user entered with user (NULL) and
auth_type Kerberos, referer: https://panoramix.domain.com/ipa/ui/
[Fri Aug 24 11:43:52 2012] [info] Connection to child 199 closed
(server panoramix.domain.com:443, client 172.22.249.66)
[Fri Aug 24 11:43:52 2012] [info] Connection to child 200 established
(server panoramix.domain.com:443, client 172.22.249.66)
[Fri Aug 24 11:43:52 2012] [info] Initial (No.1) HTTPS request
received for child 200 (server panoramix.domain.com:443)
[Fri Aug 24 11:43:52 2012] [debug] src/mod_auth_kerb.c(1939): [client
172.22.249.66] kerb_authenticate_user entered with user (NULL) and
auth_type Kerberos, referer: https://panoramix.domain.com/ipa/ui/
[Fri Aug 24 11:43:52 2012] [debug] src/mod_auth_kerb.c(1278): [client
172.22.249.66] Acquiring creds for HTTP at panoramix.domain.com, referer:
https://panoramix.domain.com/ipa/ui/
[Fri Aug 24 11:43:52 2012] [debug] src/mod_auth_kerb.c(1691): [client
172.22.249.66] Verifying client data using KRB5 GSS-API , referer:
https://panoramix.domain.com/ipa/ui/
[Fri Aug 24 11:43:52 2012] [debug] src/mod_auth_kerb.c(1707): [client
172.22.249.66] Client delegated us their credential, referer:
https://panoramix.domain.com/ipa/ui/
[Fri Aug 24 11:43:52 2012] [debug] src/mod_auth_kerb.c(1726): [client
172.22.249.66] GSS-API token of length 22 bytes will be sent back,
referer: https://panoramix.domain.com/ipa/ui/
[Fri Aug 24 11:43:52 2012] [info] Connection to child 200 closed
(server panoramix.domain.com:443, client 172.22.249.66)

# lsb_release -a
LSB Version:
:core-4.0-amd64:core-4.0-noarch:graphics-4.0-amd64:graphics-4.0-noarch:printing-4.0-amd64:printing-4.0-noarch
Distributor ID: CentOS
Description:    CentOS release 6.3 (Final)
Release:        6.3
Codename:       Final

# rpm -qa | egrep '(ipa-|sssd)'
ipa-pki-common-theme-9.0.3-7.el6.noarch
sssd-client-1.8.0-32.el6.x86_64
ipa-client-2.2.0-16.el6.x86_64
ipa-pki-ca-theme-9.0.3-7.el6.noarch
ipa-admintools-2.2.0-16.el6.x86_64
ipa-server-2.2.0-16.el6.x86_64
ipa-python-2.2.0-16.el6.x86_64
sssd-1.8.0-32.el6.x86_64
ipa-server-selinux-2.2.0-16.el6.x86_64

Thanks in advance.




More information about the Freeipa-users mailing list