Attachment 672496 Details for Bug 891976 – Excerpt from server.log showing what happens after click on "Sign in"

Excerpt from server.log showing what happens after click on "Sign in"

spnego-server.log (text/x-log), 27.58 KB, created by Tomas Kyjovsky on 2013-01-04 17:33:54 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Tomas Kyjovsky

Created: 2013-01-04 17:33:54 UTC

Size: 27.58 KB

patch

obsolete

>13:04:44,245 DEBUG [org.gatein.sso.integration.SSOFilterIntegratorPlugin] (http-server.local.network/10.34.26.120:8080-1) SSOFilterIntegratorPlugin initialization with parameters filterClass: org.gatein.sso.agent.filter.LoginRedirectFilter, filterMapping: /sso
>13:04:44,247 DEBUG [org.gatein.sso.agent.filter.api.AbstractSSOInterceptor] (http-server.local.network/10.34.26.120:8080-1) Interceptor initialized with context SSOInterceptorInitializationContext filterConfig=null, initParams: {enabled=true, filterMapping=/sso, LOGIN_URL=/@@portal.container.name@@/dologin, filterClass=org.gatein.sso.agent.filter.LoginRedirectFilter}, initializedFromServletAPI: false, containerName: portal
>13:04:44,247 INFO  [org.gatein.sso.agent.filter.LoginRedirectFilter] (http-server.local.network/10.34.26.120:8080-1) Filter configuration: loginUrl=/portal/dologin
>13:04:44,247 DEBUG [org.gatein.sso.integration.SSOFilterIntegratorImpl] (http-server.local.network/10.34.26.120:8080-1) Added new SSOInterceptor org.gatein.sso.agent.filter.LoginRedirectFilter@7e333bfc
>13:04:44,248 INFO  [org.gatein.sso.integration.SSODelegateFilter] (http-server.local.network/10.34.26.120:8080-1) Initialized SSO integrator with interceptors: {org.gatein.sso.agent.filter.LoginRedirectFilter@7e333bfc=/sso}
>13:04:44,248 TRACE [org.gatein.sso.integration.SSODelegateFilter] (http-server.local.network/10.34.26.120:8080-1) Trying mapping /sso of SSO interceptor org.gatein.sso.agent.filter.LoginRedirectFilter@7e333bfc. Request URI is /portal/sso
>13:04:44,248 TRACE [org.gatein.sso.integration.SSODelegateFilter] (http-server.local.network/10.34.26.120:8080-1) Going to invoke SSO interceptor org.gatein.sso.agent.filter.LoginRedirectFilter@7e333bfc
>13:04:44,287 TRACE [org.jboss.security.negotiation.NegotiationAuthenticator] (http-server.local.network/10.34.26.120:8080-1) Authenticating user
>13:04:44,287 DEBUG [org.jboss.security.negotiation.NegotiationAuthenticator] (http-server.local.network/10.34.26.120:8080-1) Header - null
>13:04:44,287 DEBUG [org.jboss.security.negotiation.NegotiationAuthenticator] (http-server.local.network/10.34.26.120:8080-1) No Authorization Header, initiating negotiation
>13:04:45,341 TRACE [org.jboss.security.negotiation.NegotiationAuthenticator] (http-server.local.network/10.34.26.120:8080-1) Authenticating user
>13:04:45,341 DEBUG [org.jboss.security.negotiation.NegotiationAuthenticator] (http-server.local.network/10.34.26.120:8080-1) Header - Negotiate YIIFRwYGKwYBBQUCoIIFOzCCBTegJzAlBgkqhkiG9xIBAgIGBSsFAQUCBgkqhkiC9xIBAgIGBisGAQUCBaKCBQoEggUGYIIFAgYJKoZIhvcSAQICAQBuggTxMIIE7aADAgEFoQMCAQ6iBwMFAAAAAACjggFkYYIBYDCCAVygAwIBBaEPGw1MT0NBTC5ORVRXT1JLoicwJaADAgEDoR4wHBsESFRUUBsUc2VydmVyLmxvY2FsLm5ldHdvcmujggEZMIIBFaADAgEXoQMCAQKiggEHBIIBA95+6vk9jP8lrZ5JPH0N9JUxrdrQXRwsbH/FkrvK6p3Y1vSIjxYFfI+Vb2dQo+FDN6qBw1HcyJqag6xF+J+jPBoG0Lj3L6iknibBXJT9g4DppH2OR6pgKIDpB5xeKM7haBT/R+ettVu3+RJV7lGsiSP3fehVLVJjDrQUb3qCL5SiOZxyUNrkisosmhQJ50Iq8BThzf3NIMu0VZkAlgm0Arpl+yhURRBC2rvdz3CMn44sfSdyF1hrYPL9+a2TGxe25fuikDj43di9ZBBMxLCeqSUbIl/OB4j2odfyhBX/UF2XojHSbFYPHgt7SsbDk+STbE4n/IEC7TnH8O4fBmbsg0chzz+kggNuMIIDaqADAgEXooIDYQSCA13W5rHJUiinJ0NBD6EbkBJNvxqqtyZwOZM+Atd4De0FcpD6xwSiRxulPR4lW+9dcR4YvgvbCWwzlzC9k0u4DUXevOtXFeByIEmIS/iQEgUwxIWzcN3n945tBHbBHetNYJKbE7aB2OiNvHbtVU7FM3P5ahha+60Lca4aLBBfO9fjIjzjNH7a5HH0hjdz3g7s8aXADnokllnV+E8P2imHvbMDXjbBbF7P6BqOZHNN2LPW7hliUaUAgT+jvXWo4MAstP7G3VKtt6HPu1vqmn2mPccqFryy/0kD6uyKYowRz+1+Q29TDa1Uwk/R+w1cB6c8QoMRNoVUibvCc402eqtnqSJ4LCHH6VuhuuxjHqMWyOm+FhsfKMkBZh+d1jPpWUREHPGdcqXDw90dSxmG16485o1uLSXJoGP30CFgBIys3/KdEdFZVkrCBGia9dFbOQqqbDb3Su3kNuU+LeUBou8xanbC+oHG11SmIWE3Xe8ldQWhfHalpCOKXA8oDp0HxAnbuusoy86qxRf5yrNtqGpuYO6vS0xgHd7hMszXQRdBUgIi8OMRq7WQnWnpDMXndF1KVP799cnwzCQfAVT+fMaVfZLWtBSe8ECnqlpFgcejdkuzXGusV9rwz8R3IyM/bl3CGYhmZCm67spR+3Z1ra4qrnNNOKab810XwwQukpo9tliVAac3+0SqsX1gVZyyLelmtGWTmwERncvOHa7etXHJqhhsU6jBDNKIRSfo6BaA6Kz3gjGU2YjmKKkBV+rxCpsWM8KMA5+xQtpgaEbYxZaUBByEomrNe5yBbVCDWuYUJZxTPSd/VoPDfkEIFTdoyTc+qndw6s7kjldbFhF+K2o3SuSDtopOlVT6jRmwpcD4CcQfRzXeLNFjbgM3vJv2w99FIMV2YsICH2LzJcmHuOShIIshgWiqsvZg9L9H3GxMxBiWWrEEeah3QXyiqnpQp143CgIa3tVd+ofRx3qLD7ilnD4By46Cef322lRBvBfMC3kYEEik99UIKEMyZuqCDwBbjoeDrIOsyfae9/eKSEqqkHmiDcQJwZWDcRYhTEOJMEj91OWot5mu6+V48XvgTQX89OUGHwinEdtQwe1bT0dKKGAfgR+s11upkiHbsj+gFJol1vf1j6xDqrx+s+ysXXw=
>13:04:45,346 TRACE [org.jboss.security.negotiation.common.MessageTrace.Request.Base64] (http-server.local.network/10.34.26.120:8080-1) YIIFRwYGKwYBBQUCoIIFOzCCBTegJzAlBgkqhkiG9xIBAgIGBSsFAQUCBgkqhkiC9xIBAgIGBisGAQUCBaKCBQoEggUGYIIFAgYJKoZIhvcSAQICAQBuggTxMIIE7aADAgEFoQMCAQ6iBwMFAAAAAACjggFkYYIBYDCCAVygAwIBBaEPGw1MT0NBTC5ORVRXT1JLoicwJaADAgEDoR4wHBsESFRUUBsUc2VydmVyLmxvY2FsLm5ldHdvcmujggEZMIIBFaADAgEXoQMCAQKiggEHBIIBA95+6vk9jP8lrZ5JPH0N9JUxrdrQXRwsbH/FkrvK6p3Y1vSIjxYFfI+Vb2dQo+FDN6qBw1HcyJqag6xF+J+jPBoG0Lj3L6iknibBXJT9g4DppH2OR6pgKIDpB5xeKM7haBT/R+ettVu3+RJV7lGsiSP3fehVLVJjDrQUb3qCL5SiOZxyUNrkisosmhQJ50Iq8BThzf3NIMu0VZkAlgm0Arpl+yhURRBC2rvdz3CMn44sfSdyF1hrYPL9+a2TGxe25fuikDj43di9ZBBMxLCeqSUbIl/OB4j2odfyhBX/UF2XojHSbFYPHgt7SsbDk+STbE4n/IEC7TnH8O4fBmbsg0chzz+kggNuMIIDaqADAgEXooIDYQSCA13W5rHJUiinJ0NBD6EbkBJNvxqqtyZwOZM+Atd4De0FcpD6xwSiRxulPR4lW+9dcR4YvgvbCWwzlzC9k0u4DUXevOtXFeByIEmIS/iQEgUwxIWzcN3n945tBHbBHetNYJKbE7aB2OiNvHbtVU7FM3P5ahha+60Lca4aLBBfO9fjIjzjNH7a5HH0hjdz3g7s8aXADnokllnV+E8P2imHvbMDXjbBbF7P6BqOZHNN2LPW7hliUaUAgT+jvXWo4MAstP7G3VKtt6HPu1vqmn2mPccqFryy/0kD6uyKYowRz+1+Q29TDa1Uwk/R+w1cB6c8QoMRNoVUibvCc402eqtnqSJ4LCHH6VuhuuxjHqMWyOm+FhsfKMkBZh+d1jPpWUREHPGdcqXDw90dSxmG16485o1uLSXJoGP30CFgBIys3/KdEdFZVkrCBGia9dFbOQqqbDb3Su3kNuU+LeUBou8xanbC+oHG11SmIWE3Xe8ldQWhfHalpCOKXA8oDp0HxAnbuusoy86qxRf5yrNtqGpuYO6vS0xgHd7hMszXQRdBUgIi8OMRq7WQnWnpDMXndF1KVP799cnwzCQfAVT+fMaVfZLWtBSe8ECnqlpFgcejdkuzXGusV9rwz8R3IyM/bl3CGYhmZCm67spR+3Z1ra4qrnNNOKab810XwwQukpo9tliVAac3+0SqsX1gVZyyLelmtGWTmwERncvOHa7etXHJqhhsU6jBDNKIRSfo6BaA6Kz3gjGU2YjmKKkBV+rxCpsWM8KMA5+xQtpgaEbYxZaUBByEomrNe5yBbVCDWuYUJZxTPSd/VoPDfkEIFTdoyTc+qndw6s7kjldbFhF+K2o3SuSDtopOlVT6jRmwpcD4CcQfRzXeLNFjbgM3vJv2w99FIMV2YsICH2LzJcmHuOShIIshgWiqsvZg9L9H3GxMxBiWWrEEeah3QXyiqnpQp143CgIa3tVd+ofRx3qLD7ilnD4By46Cef322lRBvBfMC3kYEEik99UIKEMyZuqCDwBbjoeDrIOsyfae9/eKSEqqkHmiDcQJwZWDcRYhTEOJMEj91OWot5mu6+V48XvgTQX89OUGHwinEdtQwe1bT0dKKGAfgR+s11upkiHbsj+gFJol1vf1j6xDqrx+s+ysXXw=
>13:04:45,348 TRACE [org.jboss.security.negotiation.common.MessageTrace.Request.Hex] (http-server.local.network/10.34.26.120:8080-1)  0x60 0x82 0x05 0x47 0x06 0x06 0x2b 0x06 0x01 0x05 0x05 0x02 0xa0 0x82 0x05 0x3b 0x30 0x82 0x05 0x37 0xa0 0x27 0x30 0x25 0x06 0x09 0x2a 0x86 0x48 0x86 0xf7 0x12 0x01 0x02 0x02 0x06 0x05 0x2b 0x05 0x01 0x05 0x02 0x06 0x09 0x2a 0x86 0x48 0x82 0xf7 0x12 0x01 0x02 0x02 0x06 0x06 0x2b 0x06 0x01 0x05 0x02 0x05 0xa2 0x82 0x05 0x0a 0x04 0x82 0x05 0x06 0x60 0x82 0x05 0x02 0x06 0x09 0x2a 0x86 0x48 0x86 0xf7 0x12 0x01 0x02 0x02 0x01 0x00 0x6e 0x82 0x04 0xf1 0x30 0x82 0x04 0xed 0xa0 0x03 0x02 0x01 0x05 0xa1 0x03 0x02 0x01 0x0e 0xa2 0x07 0x03 0x05 0x00 0x00 0x00 0x00 0x00 0xa3 0x82 0x01 0x64 0x61 0x82 0x01 0x60 0x30 0x82 0x01 0x5c 0xa0 0x03 0x02 0x01 0x05 0xa1 0x0f 0x1b 0x0d 0x4c 0x4f 0x43 0x41 0x4c 0x2e 0x4e 0x45 0x54 0x57 0x4f 0x52 0x4b 0xa2 0x27 0x30 0x25 0xa0 0x03 0x02 0x01 0x03 0xa1 0x1e 0x30 0x1c 0x1b 0x04 0x48 0x54 0x54 0x50 0x1b 0x14 0x73 0x65 0x72 0x76 0x65 0x72 0x2e 0x6c 0x6f 0x63 0x61 0x6c 0x2e 0x6e 0x65 0x74 0x77 0x6f 0x72 0x6b 0xa3 0x82 0x01 0x19 0x30 0x82 0x01 0x15 0xa0 0x03 0x02 0x01 0x17 0xa1 0x03 0x02 0x01 0x02 0xa2 0x82 0x01 0x07 0x04 0x82 0x01 0x03 0xde 0x7e 0xea 0xf9 0x3d 0x8c 0xff 0x25 0xad 0x9e 0x49 0x3c 0x7d 0x0d 0xf4 0x95 0x31 0xad 0xda 0xd0 0x5d 0x1c 0x2c 0x6c 0x7f 0xc5 0x92 0xbb 0xca 0xea 0x9d 0xd8 0xd6 0xf4 0x88 0x8f 0x16 0x05 0x7c 0x8f 0x95 0x6f 0x67 0x50 0xa3 0xe1 0x43 0x37 0xaa 0x81 0xc3 0x51 0xdc 0xc8 0x9a 0x9a 0x83 0xac 0x45 0xf8 0x9f 0xa3 0x3c 0x1a 0x06 0xd0 0xb8 0xf7 0x2f 0xa8 0xa4 0x9e 0x26 0xc1 0x5c 0x94 0xfd 0x83 0x80 0xe9 0xa4 0x7d 0x8e 0x47 0xaa 0x60 0x28 0x80 0xe9 0x07 0x9c 0x5e 0x28 0xce 0xe1 0x68 0x14 0xff 0x47 0xe7 0xad 0xb5 0x5b 0xb7 0xf9 0x12 0x55 0xee 0x51 0xac 0x89 0x23 0xf7 0x7d 0xe8 0x55 0x2d 0x52 0x63 0x0e 0xb4 0x14 0x6f 0x7a 0x82 0x2f 0x94 0xa2 0x39 0x9c 0x72 0x50 0xda 0xe4 0x8a 0xca 0x2c 0x9a 0x14 0x09 0xe7 0x42 0x2a 0xf0 0x14 0xe1 0xcd 0xfd 0xcd 0x20 0xcb 0xb4 0x55 0x99 0x00 0x96 0x09 0xb4 0x02 0xba 0x65 0xfb 0x28 0x54 0x45 0x10 0x42 0xda 0xbb 0xdd 0xcf 0x70 0x8c 0x9f 0x8e 0x2c 0x7d 0x27 0x72 0x17 0x58 0x6b 0x60 0xf2 0xfd 0xf9 0xad 0x93 0x1b 0x17 0xb6 0xe5 0xfb 0xa2 0x90 0x38 0xf8 0xdd 0xd8 0xbd 0x64 0x10 0x4c 0xc4 0xb0 0x9e 0xa9 0x25 0x1b 0x22 0x5f 0xce 0x07 0x88 0xf6 0xa1 0xd7 0xf2 0x84 0x15 0xff 0x50 0x5d 0x97 0xa2 0x31 0xd2 0x6c 0x56 0x0f 0x1e 0x0b 0x7b 0x4a 0xc6 0xc3 0x93 0xe4 0x93 0x6c 0x4e 0x27 0xfc 0x81 0x02 0xed 0x39 0xc7 0xf0 0xee 0x1f 0x06 0x66 0xec 0x83 0x47 0x21 0xcf 0x3f 0xa4 0x82 0x03 0x6e 0x30 0x82 0x03 0x6a 0xa0 0x03 0x02 0x01 0x17 0xa2 0x82 0x03 0x61 0x04 0x82 0x03 0x5d 0xd6 0xe6 0xb1 0xc9 0x52 0x28 0xa7 0x27 0x43 0x41 0x0f 0xa1 0x1b 0x90 0x12 0x4d 0xbf 0x1a 0xaa 0xb7 0x26 0x70 0x39 0x93 0x3e 0x02 0xd7 0x78 0x0d 0xed 0x05 0x72 0x90 0xfa 0xc7 0x04 0xa2 0x47 0x1b 0xa5 0x3d 0x1e 0x25 0x5b 0xef 0x5d 0x71 0x1e 0x18 0xbe 0x0b 0xdb 0x09 0x6c 0x33 0x97 0x30 0xbd 0x93 0x4b 0xb8 0x0d 0x45 0xde 0xbc 0xeb 0x57 0x15 0xe0 0x72 0x20 0x49 0x88 0x4b 0xf8 0x90 0x12 0x05 0x30 0xc4 0x85 0xb3 0x70 0xdd 0xe7 0xf7 0x8e 0x6d 0x04 0x76 0xc1 0x1d 0xeb 0x4d 0x60 0x92 0x9b 0x13 0xb6 0x81 0xd8 0xe8 0x8d 0xbc 0x76 0xed 0x55 0x4e 0xc5 0x33 0x73 0xf9 0x6a 0x18 0x5a 0xfb 0xad 0x0b 0x71 0xae 0x1a 0x2c 0x10 0x5f 0x3b 0xd7 0xe3 0x22 0x3c 0xe3 0x34 0x7e 0xda 0xe4 0x71 0xf4 0x86 0x37 0x73 0xde 0x0e 0xec 0xf1 0xa5 0xc0 0x0e 0x7a 0x24 0x96 0x59 0xd5 0xf8 0x4f 0x0f 0xda 0x29 0x87 0xbd 0xb3 0x03 0x5e 0x36 0xc1 0x6c 0x5e 0xcf 0xe8 0x1a 0x8e 0x64 0x73 0x4d 0xd8 0xb3 0xd6 0xee 0x19 0x62 0x51 0xa5 0x00 0x81 0x3f 0xa3 0xbd 0x75 0xa8 0xe0 0xc0 0x2c 0xb4 0xfe 0xc6 0xdd 0x52 0xad 0xb7 0xa1 0xcf 0xbb 0x5b 0xea 0x9a 0x7d 0xa6 0x3d 0xc7 0x2a 0x16 0xbc 0xb2 0xff 0x49 0x03 0xea 0xec 0x8a 0x62 0x8c 0x11 0xcf 0xed 0x7e 0x43 0x6f 0x53 0x0d 0xad 0x54 0xc2 0x4f 0xd1 0xfb 0x0d 0x5c 0x07 0xa7 0x3c 0x42 0x83 0x11 0x36 0x85 0x54 0x89 0xbb 0xc2 0x73 0x8d 0x36 0x7a 0xab 0x67 0xa9 0x22 0x78 0x2c 0x21 0xc7 0xe9 0x5b 0xa1 0xba 0xec 0x63 0x1e 0xa3 0x16 0xc8 0xe9 0xbe 0x16 0x1b 0x1f 0x28 0xc9 0x01 0x66 0x1f 0x9d 0xd6 0x33 0xe9 0x59 0x44 0x44 0x1c 0xf1 0x9d 0x72 0xa5 0xc3 0xc3 0xdd 0x1d 0x4b 0x19 0x86 0xd7 0xae 0x3c 0xe6 0x8d 0x6e 0x2d 0x25 0xc9 0xa0 0x63 0xf7 0xd0 0x21 0x60 0x04 0x8c 0xac 0xdf 0xf2 0x9d 0x11 0xd1 0x59 0x56 0x4a 0xc2 0x04 0x68 0x9a 0xf5 0xd1 0x5b 0x39 0x0a 0xaa 0x6c 0x36 0xf7 0x4a 0xed 0xe4 0x36 0xe5 0x3e 0x2d 0xe5 0x01 0xa2 0xef 0x31 0x6a 0x76 0xc2 0xfa 0x81 0xc6 0xd7 0x54 0xa6 0x21 0x61 0x37 0x5d 0xef 0x25 0x75 0x05 0xa1 0x7c 0x76 0xa5 0xa4 0x23 0x8a 0x5c 0x0f 0x28 0x0e 0x9d 0x07 0xc4 0x09 0xdb 0xba 0xeb 0x28 0xcb 0xce 0xaa 0xc5 0x17 0xf9 0xca 0xb3 0x6d 0xa8 0x6a 0x6e 0x60 0xee 0xaf 0x4b 0x4c 0x60 0x1d 0xde 0xe1 0x32 0xcc 0xd7 0x41 0x17 0x41 0x52 0x02 0x22 0xf0 0xe3 0x11 0xab 0xb5 0x90 0x9d 0x69 0xe9 0x0c 0xc5 0xe7 0x74 0x5d 0x4a 0x54 0xfe 0xfd 0xf5 0xc9 0xf0 0xcc 0x24 0x1f 0x01 0x54 0xfe 0x7c 0xc6 0x95 0x7d 0x92 0xd6 0xb4 0x14 0x9e 0xf0 0x40 0xa7 0xaa 0x5a 0x45 0x81 0xc7 0xa3 0x76 0x4b 0xb3 0x5c 0x6b 0xac 0x57 0xda 0xf0 0xcf 0xc4 0x77 0x23 0x23 0x3f 0x6e 0x5d 0xc2 0x19 0x88 0x66 0x64 0x29 0xba 0xee 0xca 0x51 0xfb 0x76 0x75 0xad 0xae 0x2a 0xae 0x73 0x4d 0x38 0xa6 0x9b 0xf3 0x5d 0x17 0xc3 0x04 0x2e 0x92 0x9a 0x3d 0xb6 0x58 0x95 0x01 0xa7 0x37 0xfb 0x44 0xaa 0xb1 0x7d 0x60 0x55 0x9c 0xb2 0x2d 0xe9 0x66 0xb4 0x65 0x93 0x9b 0x01 0x11 0x9d 0xcb 0xce 0x1d 0xae 0xde 0xb5 0x71 0xc9 0xaa 0x18 0x6c 0x53 0xa8 0xc1 0x0c 0xd2 0x88 0x45 0x27 0xe8 0xe8 0x16 0x80 0xe8 0xac 0xf7 0x82 0x31 0x94 0xd9 0x88 0xe6 0x28 0xa9 0x01 0x57 0xea 0xf1 0x0a 0x9b 0x16 0x33 0xc2 0x8c 0x03 0x9f 0xb1 0x42 0xda 0x60 0x68 0x46 0xd8 0xc5 0x96 0x94 0x04 0x1c 0x84 0xa2 0x6a 0xcd 0x7b 0x9c 0x81 0x6d 0x50 0x83 0x5a 0xe6 0x14 0x25 0x9c 0x53 0x3d 0x27 0x7f 0x56 0x83 0xc3 0x7e 0x41 0x08 0x15 0x37 0x68 0xc9 0x37 0x3e 0xaa 0x77 0x70 0xea 0xce 0xe4 0x8e 0x57 0x5b 0x16 0x11 0x7e 0x2b 0x6a 0x37 0x4a 0xe4 0x83 0xb6 0x8a 0x4e 0x95 0x54 0xfa 0x8d 0x19 0xb0 0xa5 0xc0 0xf8 0x09 0xc4 0x1f 0x47 0x35 0xde 0x2c 0xd1 0x63 0x6e 0x03 0x37 0xbc 0x9b 0xf6 0xc3 0xdf 0x45 0x20 0xc5 0x76 0x62 0xc2 0x02 0x1f 0x62 0xf3 0x25 0xc9 0x87 0xb8 0xe4 0xa1 0x20 0x8b 0x21 0x81 0x68 0xaa 0xb2 0xf6 0x60 0xf4 0xbf 0x47 0xdc 0x6c 0x4c 0xc4 0x18 0x96 0x5a 0xb1 0x04 0x79 0xa8 0x77 0x41 0x7c 0xa2 0xaa 0x7a 0x50 0xa7 0x5e 0x37 0x0a 0x02 0x1a 0xde 0xd5 0x5d 0xfa 0x87 0xd1 0xc7 0x7a 0x8b 0x0f 0xb8 0xa5 0x9c 0x3e 0x01 0xcb 0x8e 0x82 0x79 0xfd 0xf6 0xda 0x54 0x41 0xbc 0x17 0xcc 0x0b 0x79 0x18 0x10 0x48 0xa4 0xf7 0xd5 0x08 0x28 0x43 0x32 0x66 0xea 0x82 0x0f 0x00 0x5b 0x8e 0x87 0x83 0xac 0x83 0xac 0xc9 0xf6 0x9e 0xf7 0xf7 0x8a 0x48 0x4a 0xaa 0x90 0x79 0xa2 0x0d 0xc4 0x09 0xc1 0x95 0x83 0x71 0x16 0x21 0x4c 0x43 0x89 0x30 0x48 0xfd 0xd4 0xe5 0xa8 0xb7 0x99 0xae 0xeb 0xe5 0x78 0xf1 0x7b 0xe0 0x4d 0x05 0xfc 0xf4 0xe5 0x06 0x1f 0x08 0xa7 0x11 0xdb 0x50 0xc1 0xed 0x5b 0x4f 0x47 0x4a 0x28 0x60 0x1f 0x81 0x1f 0xac 0xd7 0x5b 0xa9 0x92 0x21 0xdb 0xb2 0x3f 0xa0 0x14 0x9a 0x25 0xd6 0xf7 0xf5 0x8f 0xac 0x43 0xaa 0xbc 0x7e 0xb3 0xec 0xac 0x5d 0x7c
>13:04:45,348 DEBUG [org.jboss.security.negotiation.NegotiationAuthenticator] (http-server.local.network/10.34.26.120:8080-1) Creating new NegotiationContext
>13:04:45,349 TRACE [org.jboss.security.negotiation.common.NegotiationContext] (http-server.local.network/10.34.26.120:8080-1) associate 1416419064
>13:04:45,389 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) Debug is  true storeKey true useTicketCache false useKeyTab true doNotPrompt true ticketCache is null isInitiator true KeyTab is /etc/krb5.keytab refreshKrb5Config is false principal is HTTP/server.local.network@LOCAL.NETWORK tryFirstPass is false useFirstPass is false storePass is false clearPass is false
>13:04:45,434 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) >>> KeyTabInputStream, readName(): LOCAL.NETWORK
>13:04:45,435 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) >>> KeyTabInputStream, readName(): HTTP
>13:04:45,435 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) >>> KeyTabInputStream, readName(): server.local.network
>13:04:45,436 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) >>> KeyTab: load() entry length: 74; type: 23
>13:04:45,582 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) Added key: 23version: 2
>13:04:45,583 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) Ordering keys wrt default_tkt_enctypes list
>13:04:45,585 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) Config name: /etc/krb5.conf
>13:04:45,585 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) default etypes for default_tkt_enctypes: 23.
>13:04:45,586 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) 0: EncryptionKey: keyType=23 kvno=2 keyValue (hex dump)=
>13:04:45,586 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) 0000: 5C AB 2E B6 42 54 5D AE   50 62 18 4D 1C DA 94 FA  \...BT].Pb.M....
>13:04:45,586 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) 
>13:04:45,586 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) 
>13:04:45,586 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) principal's key obtained from the keytab
>13:04:45,587 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) Acquire TGT using AS Exchange
>13:04:45,589 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) >>> KdcAccessibility: reset
>13:04:45,591 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) default etypes for default_tkt_enctypes: 23.
>13:04:45,591 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) >>> KrbAsReq calling createMessage
>13:04:45,591 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) >>> KrbAsReq in createMessage
>13:04:45,593 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) >>> KrbKdcReq send: kdc=server.local.network UDP:88, timeout=30000, number of retries =3, #bytes=159
>13:04:45,595 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) >>> KDCCommunication: kdc=server.local.network UDP:88, timeout=30000,Attempt =1, #bytes=159
>13:04:45,596 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) >>> KrbKdcReq send: #bytes read=281
>13:04:45,596 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) >>> KrbKdcReq send: #bytes read=281
>13:04:45,596 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) >>> KdcAccessibility: remove server.local.network
>13:04:45,597 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) >>> KDCRep: init() encoding tag is 126 req type is 11
>13:04:45,598 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) >>>KRBError:
>13:04:45,598 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) 	 cTime is Fri Jan 04 13:04:45 CET 2013 1357301085000
>13:04:45,598 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) 	 sTime is Fri Jan 04 13:04:45 CET 2013 1357301085000
>13:04:45,599 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) 	 suSec is 596013
>13:04:45,599 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) 	 error code is 25
>13:04:45,599 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) 	 error Message is Additional pre-authentication required
>13:04:45,599 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) 	 crealm is LOCAL.NETWORK
>13:04:45,599 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) 	 cname is HTTP/server.local.network
>13:04:45,599 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) 	 realm is LOCAL.NETWORK
>13:04:45,600 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) 	 sname is krbtgt/LOCAL.NETWORK
>13:04:45,600 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) 	 eData provided.
>13:04:45,600 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) 	 msgType is 30
>13:04:45,600 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) >>>Pre-Authentication Data:
>13:04:45,600 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) 	 PA-DATA type = 136
>13:04:45,600 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) >>>Pre-Authentication Data:
>13:04:45,600 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) 	 PA-DATA type = 11
>13:04:45,601 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) 	 PA-ETYPE-INFO etype = 23
>13:04:45,601 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) 	 PA-ETYPE-INFO salt = null
>13:04:45,601 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) >>>Pre-Authentication Data:
>13:04:45,601 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) 	 PA-DATA type = 19
>13:04:45,601 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) 	 PA-ETYPE-INFO2 etype = 23
>13:04:45,601 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) 	 PA-ETYPE-INFO2 salt = null
>13:04:45,602 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) >>>Pre-Authentication Data:
>13:04:45,602 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) 	 PA-DATA type = 2
>13:04:45,602 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) 	 PA-ENC-TIMESTAMP
>13:04:45,602 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) >>>Pre-Authentication Data:
>13:04:45,602 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) 	 PA-DATA type = 133
>13:04:45,602 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) KRBError received: NEEDED_PREAUTH
>13:04:45,602 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) AcquireTGT: PREAUTH FAILED/REQUIRED, re-send AS-REQ
>13:04:45,602 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) >>>KrbAsReq salt is LOCAL.NETWORKHTTPserver.local.network
>13:04:45,603 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) default etypes for default_tkt_enctypes: 23.
>13:04:45,603 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) Pre-Authenticaton: find key for etype = 23
>13:04:45,603 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) AS-REQ: Add PA_ENC_TIMESTAMP now
>13:04:45,604 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) >>> EType: sun.security.krb5.internal.crypto.ArcFourHmacEType
>13:04:45,678 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) >>> KrbAsReq calling createMessage
>13:04:45,678 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) >>> KrbAsReq in createMessage
>13:04:45,678 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) >>> KrbKdcReq send: kdc=server.local.network UDP:88, timeout=30000, number of retries =3, #bytes=242
>13:04:45,678 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) >>> KDCCommunication: kdc=server.local.network UDP:88, timeout=30000,Attempt =1, #bytes=242
>13:04:45,724 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) >>> KrbKdcReq send: #bytes read=671
>13:04:45,725 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) >>> KrbKdcReq send: #bytes read=671
>13:04:45,725 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) >>> KdcAccessibility: remove server.local.network
>13:04:45,726 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) >>> EType: sun.security.krb5.internal.crypto.ArcFourHmacEType
>13:04:45,728 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) >>> KrbAsRep cons in KrbAsReq.getReply HTTP/server.local.network
>13:04:45,728 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) principal is HTTP/server.local.network@LOCAL.NETWORK
>13:04:45,728 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) EncryptionKey: keyType=23 keyBytes (hex dump)=0000: 5C AB 2E B6 42 54 5D AE   50 62 18 4D 1C DA 94 FA  \...BT].Pb.M....
>13:04:45,728 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) 
>13:04:45,732 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) Added server's keyKerberos Principal HTTP/server.local.network@LOCAL.NETWORKKey Version 2key EncryptionKey: keyType=23 keyBytes (hex dump)=
>13:04:45,733 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) 0000: 5C AB 2E B6 42 54 5D AE   50 62 18 4D 1C DA 94 FA  \...BT].Pb.M....
>13:04:45,733 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) 
>13:04:45,733 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) 
>13:04:45,733 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) 		[Krb5LoginModule] added Krb5Principal  HTTP/server.local.network@LOCAL.NETWORK to Subject
>13:04:45,733 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) Commit Succeeded 
>13:04:45,733 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) 
>13:04:45,764 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) Found key for HTTP/server.local.network@LOCAL.NETWORK(23)
>13:04:45,766 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) Entered Krb5Context.acceptSecContext with state=STATE_NEW
>13:04:45,768 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) >>> EType: sun.security.krb5.internal.crypto.ArcFourHmacEType
>13:04:45,770 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) default etypes for permitted_enctypes: 23.
>13:04:45,771 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) >>> EType: sun.security.krb5.internal.crypto.ArcFourHmacEType
>13:04:45,772 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) replay cache for root@LOCAL.NETWORK is null.
>13:04:45,773 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) object 0: 1357301085339/339702
>13:04:45,773 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) object 0: 1357301085339/339702
>13:04:45,773 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) >>> KrbApReq: authenticate succeed.
>13:04:45,774 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) >>> EType: sun.security.krb5.internal.crypto.ArcFourHmacEType
>13:04:45,776 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) >>>Delegated Creds have pname=root@LOCAL.NETWORK sname=krbtgt/LOCAL.NETWORK@LOCAL.NETWORK authtime=20130104101406Z starttime=20130104120445Z endtime=20130104201406ZrenewTill=20130105101403Z
>13:04:45,776 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) Krb5Context setting peerSeqNumber to: 234096111
>13:04:45,777 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) Krb5Context setting mySeqNumber to: 234096111
>13:04:45,777 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) 		[Krb5LoginModule]: Entering logout
>13:04:45,778 INFO  [stdout] (http-server.local.network/10.34.26.120:8080-1) 		[Krb5LoginModule]: logged out Subject
>13:04:45,867 DEBUG [org.jboss.security.negotiation.NegotiationAuthenticator] (http-server.local.network/10.34.26.120:8080-1) authenticated principal = GenericPrincipal[UwnC9QJulvwSALp0IV+YX2Ur_1357301085349(administrators,organization,users,)]
>13:04:45,868 TRACE [org.jboss.security.negotiation.common.MessageTrace.Response.Base64] (http-server.local.network/10.34.26.120:8080-1) oQIwAA==
>13:04:45,868 TRACE [org.jboss.security.negotiation.common.NegotiationContext] (http-server.local.network/10.34.26.120:8080-1) clear 1416419064
>13:04:45,869 TRACE [org.gatein.sso.integration.SSODelegateFilter] (http-server.local.network/10.34.26.120:8080-1) Trying mapping /sso of SSO interceptor org.gatein.sso.agent.filter.LoginRedirectFilter@7e333bfc. Request URI is /portal/dologin
>13:04:45,869 TRACE [org.gatein.sso.integration.SSODelegateFilter] (http-server.local.network/10.34.26.120:8080-1) No more SSO interceptors. Going to invoke container filter chain org.apache.catalina.core.ApplicationFilterChain@6fa95e80
>13:04:45,919 TRACE [org.gatein.sso.integration.SSODelegateFilter] (http-server.local.network/10.34.26.120:8080-1) Trying mapping /sso of SSO interceptor org.gatein.sso.agent.filter.LoginRedirectFilter@7e333bfc. Request URI is /portal/
>13:04:45,919 TRACE [org.gatein.sso.integration.SSODelegateFilter] (http-server.local.network/10.34.26.120:8080-1) No more SSO interceptors. Going to invoke container filter chain org.apache.catalina.core.ApplicationFilterChain@599556aa
>13:04:45,934 TRACE [org.gatein.sso.integration.SSODelegateFilter] (http-server.local.network/10.34.26.120:8080-1) Trying mapping /sso of SSO interceptor org.gatein.sso.agent.filter.LoginRedirectFilter@7e333bfc. Request URI is /portal/classic/
>13:04:45,934 TRACE [org.gatein.sso.integration.SSODelegateFilter] (http-server.local.network/10.34.26.120:8080-1) No more SSO interceptors. Going to invoke container filter chain org.apache.catalina.core.ApplicationFilterChain@2f58b3d8
>13:04:46,864 WARN  [portal:UIPortalApplication] (http-server.local.network/10.34.26.120:8080-1) Could not load user profile for UwnC9QJulvwSALp0IV+YX2Ur_1357301085349. Using default portal locale.
>13:04:46,870 TRACE [org.gatein.sso.saml.plugin.listener.IDPHttpSessionListener] (http-server.local.network/10.34.26.120:8080-1) Portal is not acting as SAML2 IDP. Ignore this listener
>13:04:46,917 TRACE [org.gatein.sso.integration.SSODelegateFilter] (http-server.local.network/10.34.26.120:8080-1) Trying mapping /sso of SSO interceptor org.gatein.sso.agent.filter.LoginRedirectFilter@7e333bfc. Request URI is /portal/classic/
>13:04:46,918 TRACE [org.gatein.sso.integration.SSODelegateFilter] (http-server.local.network/10.34.26.120:8080-1) No more SSO interceptors. Going to invoke container filter chain org.apache.catalina.core.ApplicationFilterChain@335c592
>13:04:50,884 TRACE [org.gatein.sso.integration.SSODelegateFilter] (http-server.local.network/10.34.26.120:8080-1) Trying mapping /sso of SSO interceptor org.gatein.sso.agent.filter.LoginRedirectFilter@7e333bfc. Request URI is /portal/skins/3.5.0.Final-redhat-3/portal/resource/JppSkin/web_BannerPortlet/JppSkin/web_BreadcumbsPortlet/JppSkin/web_FooterPortlet/JppSkin/web_NavigationPortlet/1357300828807-min-lt.css
>13:04:50,884 TRACE [org.gatein.sso.integration.SSODelegateFilter] (http-server.local.network/10.34.26.120:8080-1) No more SSO interceptors. Going to invoke container filter chain org.apache.catalina.core.ApplicationFilterChain@62bb3825
>
>

Actions: View

Attachments on bug 891976: 672496 | 672497