Attachment 1486132 Details for Bug 1537866 – trace messages from two runs of kinit

trace messages from two runs of kinit

kinittrace.txt (text/plain), 5.76 KB, created by Björn Persson on 2018-09-23 05:04:59 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Björn Persson

Created: 2018-09-23 05:04:59 UTC

Size: 5.76 KB

patch

obsolete

>[beorn@tag ~]$ KRB5_TRACE=/dev/stderr kinit rombobeorn@FEDORAPROJECT.ORG
>[7694] 1537653790.52454: Getting initial credentials for rombobeorn@FEDORAPROJECT.ORG
>[7694] 1537653790.52456: Sending request (207 bytes) to FEDORAPROJECT.ORG
>[7694] 1537653790.52457: Resolving hostname id.fedoraproject.org
>[7694] 1537653790.52458: TLS certificate name matched "id.fedoraproject.org"
>[7694] 1537653790.52459: Sending HTTPS request to https 185.141.165.254:443
>[7694] 1537653790.52460: Received answer (322 bytes) from https 185.141.165.254:443
>[7694] 1537653790.52461: Terminating TCP connection to https 185.141.165.254:443
>[7694] 1537653796.790427: Response was not from master KDC
>[7694] 1537653796.790428: Received error from KDC: -1765328359/Additional pre-authentication required
>[7694] 1537653796.790431: Processing preauth types: 16, 15, 14, 136, 19, 147, 2, 133
>[7694] 1537653796.790432: Selected etype info: etype aes256-cts, salt "3'Ds5@-0:LIC'DI@", params ""
>[7694] 1537653796.790433: Received cookie: MIT
>Password for rombobeorn@FEDORAPROJECT.ORG:
>[7694] 1537653808.197872: AS key obtained for encrypted timestamp: aes256-cts/3ECA
>[7694] 1537653808.197874: Encrypted timestamp (for 1537653802.197912): plain 301AA011180F32303138303932323232303332325AA1050203030518, encrypted 59C4E9F1B88F75C8C7C4267801A442C49F9974B6185CC3DA1EA00BDB039FB7A3AEB9DECFBC80ED24F7BF342975E1B97A6B5435AD198F49E5
>[7694] 1537653808.197875: Preauth module encrypted_timestamp (2) (real) returned: 0/Success
>[7694] 1537653808.197876: Produced preauth for next request: 133, 2
>[7694] 1537653808.197877: Sending request (302 bytes) to FEDORAPROJECT.ORG
>[7694] 1537653808.197878: Resolving hostname id.fedoraproject.org
>[7694] 1537653808.197879: TLS certificate name matched "id.fedoraproject.org"
>[7694] 1537653808.197880: Sending HTTPS request to https 8.43.85.67:443
>[7694] 1537653809.55297: Received answer (795 bytes) from https 8.43.85.67:443
>[7694] 1537653809.55298: Terminating TCP connection to https 8.43.85.67:443
>[7694] 1537653813.904737: Response was not from master KDC
>[7694] 1537653813.904738: Processing preauth types: 19
>[7694] 1537653813.904739: Selected etype info: etype aes256-cts, salt "3'Ds5@-0:LIC'DI@", params ""
>[7694] 1537653813.904740: Produced preauth for next request: (empty)
>[7694] 1537653813.904741: AS key determined by preauth: aes256-cts/3ECA
>[7694] 1537653813.904742: Decrypted AS reply; session key is: aes256-cts/2BE7
>[7694] 1537653813.904743: FAST negotiation: available
>[7694] 1537653813.904744: Initializing KCM:1000 with default princ rombobeorn@FEDORAPROJECT.ORG
>[beorn@tag ~]$ echo $?
>141
>[beorn@tag ~]$ KRB5_TRACE=/dev/stderr kinit rombobeorn@FEDORAPROJECT.ORG
>[7838] 1537653855.622643: Getting initial credentials for rombobeorn@FEDORAPROJECT.ORG
>[7838] 1537653855.622645: Sending request (207 bytes) to FEDORAPROJECT.ORG
>[7838] 1537653855.622646: Resolving hostname id.fedoraproject.org
>[7838] 1537653856.36028: TLS certificate name matched "id.fedoraproject.org"
>[7838] 1537653856.36029: Sending HTTPS request to https 67.219.144.68:443
>[7838] 1537653856.36030: Received answer (322 bytes) from https 67.219.144.68:443
>[7838] 1537653856.36031: Terminating TCP connection to https 67.219.144.68:443
>[7838] 1537653861.381066: Response was not from master KDC
>[7838] 1537653861.381067: Received error from KDC: -1765328359/Additional pre-authentication required
>[7838] 1537653861.381070: Processing preauth types: 16, 15, 14, 136, 19, 147, 2, 133
>[7838] 1537653861.381071: Selected etype info: etype aes256-cts, salt "3'Ds5@-0:LIC'DI@", params ""
>[7838] 1537653861.381072: Received cookie: MIT
>Password for rombobeorn@FEDORAPROJECT.ORG:
>[7838] 1537653864.38048: AS key obtained for encrypted timestamp: aes256-cts/3ECA
>[7838] 1537653864.38050: Encrypted timestamp (for 1537653859.83707): plain 301AA011180F32303138303932323232303431395AA10502030146FB, encrypted AF53619DBEBA53AE408EF59C1313002F3437C891B8D69C381B1A46C2847221E3BA6943B176F6D76041730385D8E0864E2B7C5916A644A511
>[7838] 1537653864.38051: Preauth module encrypted_timestamp (2) (real) returned: 0/Success
>[7838] 1537653864.38052: Produced preauth for next request: 133, 2
>[7838] 1537653864.38053: Sending request (302 bytes) to FEDORAPROJECT.ORG
>[7838] 1537653864.38054: Resolving hostname id.fedoraproject.org
>[7838] 1537653864.38055: TLS certificate name matched "id.fedoraproject.org"
>[7838] 1537653864.38056: Sending HTTPS request to https 152.19.134.142:443
>[7838] 1537653864.38057: Received answer (795 bytes) from https 152.19.134.142:443
>[7838] 1537653864.38058: Terminating TCP connection to https 152.19.134.142:443
>[7838] 1537653869.807944: Response was not from master KDC
>[7838] 1537653869.807945: Processing preauth types: 19
>[7838] 1537653869.807946: Selected etype info: etype aes256-cts, salt "3'Ds5@-0:LIC'DI@", params ""
>[7838] 1537653869.807947: Produced preauth for next request: (empty)
>[7838] 1537653869.807948: AS key determined by preauth: aes256-cts/3ECA
>[7838] 1537653869.807949: Decrypted AS reply; session key is: aes256-cts/AD42
>[7838] 1537653869.807950: FAST negotiation: available
>[7838] 1537653869.807951: Initializing KCM:1000 with default princ rombobeorn@FEDORAPROJECT.ORG
>[7838] 1537653869.807952: Storing rombobeorn@FEDORAPROJECT.ORG -> krbtgt/FEDORAPROJECT.ORG@FEDORAPROJECT.ORG in KCM:1000
>[7838] 1537653869.807953: Storing config in KCM:1000 for krbtgt/FEDORAPROJECT.ORG@FEDORAPROJECT.ORG: fast_avail: yes
>[7838] 1537653869.807954: Storing rombobeorn@FEDORAPROJECT.ORG -> krb5_ccache_conf_data/fast_avail/krbtgt\/FEDORAPROJECT.ORG\@FEDORAPROJECT.ORG@X-CACHECONF: in KCM:1000
>[7838] 1537653869.807955: Storing config in KCM:1000 for krbtgt/FEDORAPROJECT.ORG@FEDORAPROJECT.ORG: pa_type: 2
>[7838] 1537653869.807956: Storing rombobeorn@FEDORAPROJECT.ORG -> krb5_ccache_conf_data/pa_type/krbtgt\/FEDORAPROJECT.ORG\@FEDORAPROJECT.ORG@X-CACHECONF: in KCM:1000
>[beorn@tag ~]$ echo $?
>0

Actions: View

Attachments on bug 1537866: 1395749 | 1395750 | 1486132 | 1486133 | 1486134 | 1486380 | 1486381 | 1486382