Attachment 936286 Details for Bug 1134004 – engine debug log

engine debug log

engine_debug.log (text/plain), 88.76 KB, created by Ondra Machacek on 2014-09-10 19:06:02 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Ondra Machacek

Created: 2014-09-10 19:06:02 UTC

Size: 88.76 KB

patch

obsolete

>2014-09-10 18:49:37,514 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) PROPERTIES-END
>2014-09-10 18:49:37,514 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) Entry
>2014-09-10 18:49:37,514 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) runSequence entry name='__init0'
>2014-09-10 18:49:37,514 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) Running sequence __init0/01/var-set set setsitive keys
>2014-09-10 18:49:37,514 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) VARS-BEGIN
>2014-09-10 18:49:37,514 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) authz_enable = 1
>2014-09-10 18:49:37,514 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) stop = false
>2014-09-10 18:49:37,514 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) VARS-END
>2014-09-10 18:49:37,514 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) End sequence __init0 set setsitive keys
>2014-09-10 18:49:37,514 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) VARS-BEGIN
>2014-09-10 18:49:37,514 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) authz_enable = 1
>2014-09-10 18:49:37,514 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) sensitiveKeys = , password, passwordNew
>2014-09-10 18:49:37,514 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) stop = false
>2014-09-10 18:49:37,514 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) VARS-END
>2014-09-10 18:49:37,515 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) Running sequence __init0/02/var-set set max filter size
>2014-09-10 18:49:37,515 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) VARS-BEGIN
>2014-09-10 18:49:37,515 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) authz_enable = 1
>2014-09-10 18:49:37,515 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) sensitiveKeys = , password, passwordNew
>2014-09-10 18:49:37,515 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) stop = false
>2014-09-10 18:49:37,515 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) VARS-END
>2014-09-10 18:49:37,522 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) End sequence __init0 set max filter size
>2014-09-10 18:49:37,522 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) VARS-BEGIN
>2014-09-10 18:49:37,522 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) authz_enable = 1
>2014-09-10 18:49:37,522 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) maxFilterSize = 50
>2014-09-10 18:49:37,522 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) sensitiveKeys = , password, passwordNew
>2014-09-10 18:49:37,522 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) stop = false
>2014-09-10 18:49:37,522 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) VARS-END
>2014-09-10 18:49:37,522 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) Running sequence __init0/03/var-set set default as not recusive group resolution
>2014-09-10 18:49:37,522 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) VARS-BEGIN
>2014-09-10 18:49:37,523 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) authz_enable = 1
>2014-09-10 18:49:37,523 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) maxFilterSize = 50
>2014-09-10 18:49:37,523 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) sensitiveKeys = , password, passwordNew
>2014-09-10 18:49:37,523 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) stop = false
>2014-09-10 18:49:37,523 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) VARS-END
>2014-09-10 18:49:37,523 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) End sequence __init0 set default as not recusive group resolution
>2014-09-10 18:49:37,523 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) VARS-BEGIN
>2014-09-10 18:49:37,523 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) authz_enable = 1
>2014-09-10 18:49:37,523 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) capability_resucrsiveGroupResolution = false
>2014-09-10 18:49:37,523 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) maxFilterSize = 50
>2014-09-10 18:49:37,523 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) sensitiveKeys = , password, passwordNew
>2014-09-10 18:49:37,523 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) stop = false
>2014-09-10 18:49:37,523 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) VARS-END
>2014-09-10 18:49:37,523 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) Running sequence __init0/04/var-set set default as no credentials change
>2014-09-10 18:49:37,523 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) VARS-BEGIN
>2014-09-10 18:49:37,523 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) authz_enable = 1
>2014-09-10 18:49:37,523 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) capability_resucrsiveGroupResolution = false
>2014-09-10 18:49:37,523 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) maxFilterSize = 50
>2014-09-10 18:49:37,523 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) sensitiveKeys = , password, passwordNew
>2014-09-10 18:49:37,523 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) stop = false
>2014-09-10 18:49:37,524 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) VARS-END
>2014-09-10 18:49:37,524 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) End sequence __init0 set default as no credentials change
>2014-09-10 18:49:37,524 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) VARS-BEGIN
>2014-09-10 18:49:37,524 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) authz_enable = 1
>2014-09-10 18:49:37,524 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) capability_credentialsChange = false
>2014-09-10 18:49:37,524 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) capability_resucrsiveGroupResolution = false
>2014-09-10 18:49:37,524 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) maxFilterSize = 50
>2014-09-10 18:49:37,524 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) sensitiveKeys = , password, passwordNew
>2014-09-10 18:49:37,524 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) stop = false
>2014-09-10 18:49:37,524 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) VARS-END
>2014-09-10 18:49:37,524 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) runSequence Return name='__init0'
>2014-09-10 18:49:37,524 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) runSequence entry name='simple-init-vars'
>2014-09-10 18:49:37,524 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) Running sequence simple-init-vars/010/var-set set user format: sam for basic, realm for sasl
>2014-09-10 18:49:37,524 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) VARS-BEGIN
>2014-09-10 18:49:37,524 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) authz_enable = 1
>2014-09-10 18:49:37,524 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) capability_credentialsChange = false
>2014-09-10 18:49:37,524 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) capability_resucrsiveGroupResolution = false
>2014-09-10 18:49:37,524 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) maxFilterSize = 50
>2014-09-10 18:49:37,524 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) sensitiveKeys = , password, passwordNew
>2014-09-10 18:49:37,525 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) stop = false
>2014-09-10 18:49:37,525 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) VARS-END
>2014-09-10 18:49:37,525 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) End sequence simple-init-vars set user format: sam for basic, realm for sasl
>2014-09-10 18:49:37,525 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) VARS-BEGIN
>2014-09-10 18:49:37,525 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) authz_enable = 1
>2014-09-10 18:49:37,525 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) capability_credentialsChange = false
>2014-09-10 18:49:37,525 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) capability_resucrsiveGroupResolution = false
>2014-09-10 18:49:37,525 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) maxFilterSize = 50
>2014-09-10 18:49:37,525 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) sensitiveKeys = , password, passwordNew
>2014-09-10 18:49:37,525 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_bindFormat = dn
>2014-09-10 18:49:37,525 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) stop = false
>2014-09-10 18:49:37,525 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) VARS-END
>2014-09-10 18:49:37,525 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) runSequence Return name='simple-init-vars'
>2014-09-10 18:49:37,525 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) runSequence entry name='openldap-init-vars'
>2014-09-10 18:49:37,525 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) Running sequence openldap-init-vars/010/var-set set base dn
>2014-09-10 18:49:37,525 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) VARS-BEGIN
>2014-09-10 18:49:37,525 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) authz_enable = 1
>2014-09-10 18:49:37,525 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) capability_credentialsChange = false
>2014-09-10 18:49:37,525 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) capability_resucrsiveGroupResolution = false
>2014-09-10 18:49:37,526 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) maxFilterSize = 50
>2014-09-10 18:49:37,526 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) sensitiveKeys = , password, passwordNew
>2014-09-10 18:49:37,526 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_bindFormat = dn
>2014-09-10 18:49:37,526 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) stop = false
>2014-09-10 18:49:37,526 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) VARS-END
>2014-09-10 18:49:37,526 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) End sequence openldap-init-vars set base dn
>2014-09-10 18:49:37,526 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) VARS-BEGIN
>2014-09-10 18:49:37,526 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) authz_enable = 1
>2014-09-10 18:49:37,526 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) capability_credentialsChange = false
>2014-09-10 18:49:37,526 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) capability_resucrsiveGroupResolution = false
>2014-09-10 18:49:37,526 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) maxFilterSize = 50
>2014-09-10 18:49:37,526 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) sensitiveKeys = , password, passwordNew
>2014-09-10 18:49:37,526 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_attrsBaseDN = namingContexts
>2014-09-10 18:49:37,526 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_bindFormat = dn
>2014-09-10 18:49:37,526 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) stop = false
>2014-09-10 18:49:37,526 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) VARS-END
>2014-09-10 18:49:37,526 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) Running sequence openldap-init-vars/020/var-set set user attribute
>2014-09-10 18:49:37,526 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) VARS-BEGIN
>2014-09-10 18:49:37,526 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) authz_enable = 1
>2014-09-10 18:49:37,526 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) capability_credentialsChange = false
>2014-09-10 18:49:37,527 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) capability_resucrsiveGroupResolution = false
>2014-09-10 18:49:37,527 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) maxFilterSize = 50
>2014-09-10 18:49:37,527 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) sensitiveKeys = , password, passwordNew
>2014-09-10 18:49:37,527 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_attrsBaseDN = namingContexts
>2014-09-10 18:49:37,527 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_bindFormat = dn
>2014-09-10 18:49:37,527 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) stop = false
>2014-09-10 18:49:37,527 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) VARS-END
>2014-09-10 18:49:37,527 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) End sequence openldap-init-vars set user attribute
>2014-09-10 18:49:37,527 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) VARS-BEGIN
>2014-09-10 18:49:37,527 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) authz_enable = 1
>2014-09-10 18:49:37,527 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) capability_credentialsChange = false
>2014-09-10 18:49:37,527 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) capability_resucrsiveGroupResolution = false
>2014-09-10 18:49:37,527 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) maxFilterSize = 50
>2014-09-10 18:49:37,527 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) sensitiveKeys = , password, passwordNew
>2014-09-10 18:49:37,527 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_attrsBaseDN = namingContexts
>2014-09-10 18:49:37,527 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_attrsUserName = uid
>2014-09-10 18:49:37,527 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_bindFormat = dn
>2014-09-10 18:49:37,527 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) stop = false
>2014-09-10 18:49:37,527 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) VARS-END
>2014-09-10 18:49:37,528 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) Running sequence openldap-init-vars/030/var-set set principal record attributes
>2014-09-10 18:49:37,528 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) VARS-BEGIN
>2014-09-10 18:49:37,528 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) authz_enable = 1
>2014-09-10 18:49:37,528 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) capability_credentialsChange = false
>2014-09-10 18:49:37,528 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) capability_resucrsiveGroupResolution = false
>2014-09-10 18:49:37,528 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) maxFilterSize = 50
>2014-09-10 18:49:37,528 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) sensitiveKeys = , password, passwordNew
>2014-09-10 18:49:37,528 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_attrsBaseDN = namingContexts
>2014-09-10 18:49:37,528 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_attrsUserName = uid
>2014-09-10 18:49:37,528 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_bindFormat = dn
>2014-09-10 18:49:37,528 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) stop = false
>2014-09-10 18:49:37,528 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) VARS-END
>2014-09-10 18:49:37,528 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) End sequence openldap-init-vars set principal record attributes
>2014-09-10 18:49:37,528 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) VARS-BEGIN
>2014-09-10 18:49:37,528 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) authz_enable = 1
>2014-09-10 18:49:37,528 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) capability_credentialsChange = false
>2014-09-10 18:49:37,528 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) capability_resucrsiveGroupResolution = false
>2014-09-10 18:49:37,528 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) maxFilterSize = 50
>2014-09-10 18:49:37,528 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) sensitiveKeys = , password, passwordNew
>2014-09-10 18:49:37,528 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_attrsBaseDN = namingContexts
>2014-09-10 18:49:37,528 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_attrsPrincipalRecord = entryUUID, uid, displayName, memberOf, department, givenName, sn, title, mail
>2014-09-10 18:49:37,529 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_attrsUserName = uid
>2014-09-10 18:49:37,529 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_bindFormat = dn
>2014-09-10 18:49:37,529 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) stop = false
>2014-09-10 18:49:37,529 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) VARS-END
>2014-09-10 18:49:37,529 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) Running sequence openldap-init-vars/040/var-set 
>2014-09-10 18:49:37,529 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) VARS-BEGIN
>2014-09-10 18:49:37,529 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) authz_enable = 1
>2014-09-10 18:49:37,529 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) capability_credentialsChange = false
>2014-09-10 18:49:37,529 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) capability_resucrsiveGroupResolution = false
>2014-09-10 18:49:37,529 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) maxFilterSize = 50
>2014-09-10 18:49:37,529 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) sensitiveKeys = , password, passwordNew
>2014-09-10 18:49:37,529 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_attrsBaseDN = namingContexts
>2014-09-10 18:49:37,529 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_attrsPrincipalRecord = entryUUID, uid, displayName, memberOf, department, givenName, sn, title, mail
>2014-09-10 18:49:37,529 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_attrsUserName = uid
>2014-09-10 18:49:37,529 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_bindFormat = dn
>2014-09-10 18:49:37,529 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) stop = false
>2014-09-10 18:49:37,529 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) VARS-END
>2014-09-10 18:49:37,529 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) End sequence openldap-init-vars 
>2014-09-10 18:49:37,529 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) VARS-BEGIN
>2014-09-10 18:49:37,529 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) authz_enable = 1
>2014-09-10 18:49:37,530 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) capability_credentialsChange = false
>2014-09-10 18:49:37,530 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) capability_resucrsiveGroupResolution = false
>2014-09-10 18:49:37,530 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) maxFilterSize = 50
>2014-09-10 18:49:37,530 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) sensitiveKeys = , password, passwordNew
>2014-09-10 18:49:37,530 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_attrsBaseDN = namingContexts
>2014-09-10 18:49:37,530 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_attrsPrincipalRecord = entryUUID, uid, displayName, memberOf, department, givenName, sn, title, mail
>2014-09-10 18:49:37,530 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_attrsUserName = uid
>2014-09-10 18:49:37,530 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_bindFormat = dn
>2014-09-10 18:49:37,530 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_filterUserObject = (objectClass=uidObject)(uid=*)
>2014-09-10 18:49:37,530 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) stop = false
>2014-09-10 18:49:37,530 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) VARS-END
>2014-09-10 18:49:37,530 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) Running sequence openldap-init-vars/050/var-set set group record attributes
>2014-09-10 18:49:37,530 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) VARS-BEGIN
>2014-09-10 18:49:37,530 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) authz_enable = 1
>2014-09-10 18:49:37,530 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) capability_credentialsChange = false
>2014-09-10 18:49:37,530 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) capability_resucrsiveGroupResolution = false
>2014-09-10 18:49:37,530 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) maxFilterSize = 50
>2014-09-10 18:49:37,530 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) sensitiveKeys = , password, passwordNew
>2014-09-10 18:49:37,530 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_attrsBaseDN = namingContexts
>2014-09-10 18:49:37,530 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_attrsPrincipalRecord = entryUUID, uid, displayName, memberOf, department, givenName, sn, title, mail
>2014-09-10 18:49:37,530 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_attrsUserName = uid
>2014-09-10 18:49:37,531 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_bindFormat = dn
>2014-09-10 18:49:37,531 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_filterUserObject = (objectClass=uidObject)(uid=*)
>2014-09-10 18:49:37,531 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) stop = false
>2014-09-10 18:49:37,531 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) VARS-END
>2014-09-10 18:49:37,531 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) End sequence openldap-init-vars set group record attributes
>2014-09-10 18:49:37,531 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) VARS-BEGIN
>2014-09-10 18:49:37,531 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) authz_enable = 1
>2014-09-10 18:49:37,531 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) capability_credentialsChange = false
>2014-09-10 18:49:37,531 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) capability_resucrsiveGroupResolution = false
>2014-09-10 18:49:37,531 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) maxFilterSize = 50
>2014-09-10 18:49:37,531 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) sensitiveKeys = , password, passwordNew
>2014-09-10 18:49:37,531 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_attrsBaseDN = namingContexts
>2014-09-10 18:49:37,531 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_attrsGroupRecord = entryUUID, cn, description, memberOf
>2014-09-10 18:49:37,531 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_attrsPrincipalRecord = entryUUID, uid, displayName, memberOf, department, givenName, sn, title, mail
>2014-09-10 18:49:37,531 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_attrsUserName = uid
>2014-09-10 18:49:37,531 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_bindFormat = dn
>2014-09-10 18:49:37,531 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_filterUserObject = (objectClass=uidObject)(uid=*)
>2014-09-10 18:49:37,531 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) stop = false
>2014-09-10 18:49:37,531 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) VARS-END
>2014-09-10 18:49:37,531 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) Running sequence openldap-init-vars/060/var-set 
>2014-09-10 18:49:37,532 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) VARS-BEGIN
>2014-09-10 18:49:37,532 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) authz_enable = 1
>2014-09-10 18:49:37,532 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) capability_credentialsChange = false
>2014-09-10 18:49:37,532 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) capability_resucrsiveGroupResolution = false
>2014-09-10 18:49:37,532 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) maxFilterSize = 50
>2014-09-10 18:49:37,532 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) sensitiveKeys = , password, passwordNew
>2014-09-10 18:49:37,537 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_attrsBaseDN = namingContexts
>2014-09-10 18:49:37,537 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_attrsGroupRecord = entryUUID, cn, description, memberOf
>2014-09-10 18:49:37,537 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_attrsPrincipalRecord = entryUUID, uid, displayName, memberOf, department, givenName, sn, title, mail
>2014-09-10 18:49:37,537 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_attrsUserName = uid
>2014-09-10 18:49:37,537 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_bindFormat = dn
>2014-09-10 18:49:37,537 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_filterUserObject = (objectClass=uidObject)(uid=*)
>2014-09-10 18:49:37,537 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) stop = false
>2014-09-10 18:49:37,537 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) VARS-END
>2014-09-10 18:49:37,537 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) End sequence openldap-init-vars 
>2014-09-10 18:49:37,537 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) VARS-BEGIN
>2014-09-10 18:49:37,537 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) authz_enable = 1
>2014-09-10 18:49:37,537 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) capability_credentialsChange = false
>2014-09-10 18:49:37,537 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) capability_resucrsiveGroupResolution = false
>2014-09-10 18:49:37,537 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) maxFilterSize = 50
>2014-09-10 18:49:37,538 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) sensitiveKeys = , password, passwordNew
>2014-09-10 18:49:37,538 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_attrsBaseDN = namingContexts
>2014-09-10 18:49:37,538 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_attrsGroupRecord = entryUUID, cn, description, memberOf
>2014-09-10 18:49:37,538 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_attrsPrincipalRecord = entryUUID, uid, displayName, memberOf, department, givenName, sn, title, mail
>2014-09-10 18:49:37,538 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_attrsUserName = uid
>2014-09-10 18:49:37,538 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_bindFormat = dn
>2014-09-10 18:49:37,538 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_filterGroupObject = (objectClass=groupOfNames)
>2014-09-10 18:49:37,538 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_filterUserObject = (objectClass=uidObject)(uid=*)
>2014-09-10 18:49:37,538 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) stop = false
>2014-09-10 18:49:37,538 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) VARS-END
>2014-09-10 18:49:37,538 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) runSequence Return name='openldap-init-vars'
>2014-09-10 18:49:37,538 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) Return globals={simple_bindFormat=dn, authz_enable=1, simple_attrsGroupRecord=entryUUID, cn, description, memberOf, simple_attrsPrincipalRecord=entryUUID, uid, displayName, memberOf, department, givenName, sn, title, mail, capability_credentialsChange=false, capability_resucrsiveGroupResolution=false, simple_attrsBaseDN=namingContexts, simple_attrsUserName=uid, maxFilterSize=50, sensitiveKeys=, password, passwordNew, simple_filterUserObject=(objectClass=uidObject)(uid=*), stop=false, simple_filterGroupObject=(objectClass=groupOfNames)}
>2014-09-10 18:49:37,538 TRACE [org.ovirt.engine.core.extensions.mgr.ExtensionsManager.trace] (ServerService Thread Pool -- 45) Invoke Output BEGIN
>2014-09-10 18:49:37,538 TRACE [org.ovirt.engine.core.extensions.mgr.ExtensionsManager.trace] (ServerService Thread Pool -- 45) {Extkey[name=EXTENSION_INVOKE_RESULT;type=class java.lang.Integer;uuid=EXTENSION_INVOKE_RESULT[0909d91d-8bde-40fb-b6c0-099c772ddd4e];]=0}
>2014-09-10 18:49:37,538 TRACE [org.ovirt.engine.core.extensions.mgr.ExtensionsManager.trace] (ServerService Thread Pool -- 45) Invoke Output END
>2014-09-10 18:49:37,539 INFO  [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (ServerService Thread Pool -- 45) Extension 'ldap-authz-openldap_brq-openldap' loaded
>2014-09-10 18:49:37,539 DEBUG [org.ovirt.engine.core.extensions.mgr.ExtensionsManager.trace.aaa.ldap.authz.ldap-authz-openldap_brq-openldap] (ServerService Thread Pool -- 45) Config BEGIN
>2014-09-10 18:49:37,539 DEBUG [org.ovirt.engine.core.extensions.mgr.ExtensionsManager.trace.aaa.ldap.authz.ldap-authz-openldap_brq-openldap] (ServerService Thread Pool -- 45) ovirt.engine.extension.provides: org.ovirt.engine.api.extensions.aaa.Authz
>2014-09-10 18:49:37,539 DEBUG [org.ovirt.engine.core.extensions.mgr.ExtensionsManager.trace.aaa.ldap.authz.ldap-authz-openldap_brq-openldap] (ServerService Thread Pool -- 45) ovirt.engine.extension.binding.jbossmodule.class: org.ovirt.engineextensions.aaa.ldap.AuthzExtension
>2014-09-10 18:49:37,539 DEBUG [org.ovirt.engine.core.extensions.mgr.ExtensionsManager.trace.aaa.ldap.authz.ldap-authz-openldap_brq-openldap] (ServerService Thread Pool -- 45) ovirt.engine.extension.bindings.method: jbossmodule
>2014-09-10 18:49:37,539 DEBUG [org.ovirt.engine.core.extensions.mgr.ExtensionsManager.trace.aaa.ldap.authz.ldap-authz-openldap_brq-openldap] (ServerService Thread Pool -- 45) ovirt.engine.extension.enabled: true
>2014-09-10 18:49:37,539 DEBUG [org.ovirt.engine.core.extensions.mgr.ExtensionsManager.trace.aaa.ldap.authz.ldap-authz-openldap_brq-openldap] (ServerService Thread Pool -- 45) ovirt.engine.extension.name: ldap-authz-openldap_brq-openldap
>2014-09-10 18:49:37,539 DEBUG [org.ovirt.engine.core.extensions.mgr.ExtensionsManager.trace.aaa.ldap.authz.ldap-authz-openldap_brq-openldap] (ServerService Thread Pool -- 45) config.profile.file.1: /tmp/brq-openldap.rhev.lab.eng.brq.redhat.com.properties
>2014-09-10 18:49:37,539 DEBUG [org.ovirt.engine.core.extensions.mgr.ExtensionsManager.trace.aaa.ldap.authz.ldap-authz-openldap_brq-openldap] (ServerService Thread Pool -- 45) ovirt.engine.extension.binding.jbossmodule.module: org.ovirt.engine-extensions.aaa.ldap
>2014-09-10 18:49:37,539 DEBUG [org.ovirt.engine.core.extensions.mgr.ExtensionsManager.trace.aaa.ldap.authz.ldap-authz-openldap_brq-openldap] (ServerService Thread Pool -- 45) ovirt.engine.aaa.authn.mapping.plugin: http-mapping
>2014-09-10 18:49:37,539 DEBUG [org.ovirt.engine.core.extensions.mgr.ExtensionsManager.trace.aaa.ldap.authz.ldap-authz-openldap_brq-openldap] (ServerService Thread Pool -- 45) Config END
>2014-09-10 18:49:37,540 INFO  [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (ServerService Thread Pool -- 45) Initializing extension 'ldap-authn-openldap_brq-openldap'
>2014-09-10 18:49:37,540 TRACE [org.ovirt.engine.core.extensions.mgr.ExtensionsManager.trace.aaa.ldap.authn.ldap-authn-openldap_brq-openldap] (ServerService Thread Pool -- 45) Invoke Input BEGIN
>2014-09-10 18:49:37,540 TRACE [org.ovirt.engine.core.extensions.mgr.ExtensionsManager.trace.aaa.ldap.authn.ldap-authn-openldap_brq-openldap] (ServerService Thread Pool -- 45) {Extkey[name=EXTENSION_INVOKE_CONTEXT;type=class org.ovirt.engine.api.extensions.ExtMap;uuid=EXTENSION_INVOKE_CONTEXT[886d2ebb-312a-49ae-9cc3-e1f849834b7d];]={Extkey[name=EXTENSION_INTERFACE_VERSION_MAX;type=class java.lang.Integer;uuid=EXTENSION_INTERFACE_VERSION_MAX[f4cff49f-2717-4901-8ee9-df362446e3e7];]=0, Extkey[name=EXTENSION_LICENSE;type=class java.lang.String;uuid=EXTENSION_LICENSE[8a61ad65-054c-4e31-9c6d-1ca4d60a4c18];]=ASL 2.0, Extkey[name=EXTENSION_NOTES;type=class java.lang.String;uuid=EXTENSION_NOTES[2da5ad7e-185a-4584-aaff-97f66978e4ea];]=Display name: ovirt-engine-extension-aaa-ldap-0.0.0-0.0.2.master.el6_5, Extkey[name=EXTENSION_HOME_URL;type=class java.lang.String;uuid=EXTENSION_HOME_URL[4ad7a2f4-f969-42d4-b399-72d192e18304];]=http://www.ovirt.org, Extkey[name=EXTENSION_LOCALE;type=class java.lang.String;uuid=EXTENSION_LOCALE[0780b112-0ce0-404a-b85e-8765d778bb29];]=en_US, Extkey[name=EXTENSION_NAME;type=class java.lang.String;uuid=EXTENSION_NAME[651381d3-f54f-4547-bf28-b0b01a103184];]=aaa.ldap.authn, Extkey[name=EXTENSION_INTERFACE_VERSION_MIN;type=class java.lang.Integer;uuid=EXTENSION_INTERFACE_VERSION_MIN[2b84fc91-305b-497b-a1d7-d961b9d2ce0b];]=0, Extkey[name=EXTENSION_CONFIGURATION;type=class java.util.Properties;uuid=EXTENSION_CONFIGURATION[2d48ab72-f0a1-4312-b4ae-5068a226b0fc];]=***, Extkey[name=EXTENSION_AUTHOR;type=class java.lang.String;uuid=EXTENSION_AUTHOR[ef242f7a-2dad-4bc5-9aad-e07018b7fbcc];]=The oVirt Project, Extkey[name=EXTENSION_INSTANCE_NAME;type=class java.lang.String;uuid=EXTENSION_INSTANCE_NAME[65c67ff6-aeca-4bd5-a245-8674327f011b];]=ldap-authn-openldap_brq-openldap, Extkey[name=EXTENSION_BUILD_INTERFACE_VERSION;type=class java.lang.Integer;uuid=EXTENSION_BUILD_INTERFACE_VERSION[cb479e5a-4b23-46f8-aed3-56a4747a8ab7];]=0, Extkey[name=EXTENSION_CONFIGURATION_SENSITIVE_KEYS;type=interface java.util.Collection;uuid=EXTENSION_CONFIGURATION_SENSITIVE_KEYS[a456efa1-73ff-4204-9f9b-ebff01e35263];]=[], Extkey[name=AAA_AUTHN_CAPABILITIES;type=class java.lang.Long;uuid=AAA_AUTHN_CAPABILITIES[9d16bee3-10fd-46f2-83f9-3d3c54cf258d];]=12, Extkey[name=EXTENSION_GLOBAL_CONTEXT;type=class org.ovirt.engine.api.extensions.ExtMap;uuid=EXTENSION_GLOBAL_CONTEXT[9799e72f-7af6-4cf1-bf08-297bc8903676];]=*skip*, Extkey[name=EXTENSION_VERSION;type=class java.lang.String;uuid=EXTENSION_VERSION[fe35f6a8-8239-4bdb-ab1a-af9f779ce68c];]=0.0.0_master, Extkey[name=EXTENSION_MANAGER_TRACE_LOG;type=interface org.slf4j.Logger;uuid=EXTENSION_MANAGER_TRACE_LOG[863db666-3ea7-4751-9695-918a3197ad83];]=org.slf4j.impl.Slf4jLogger(org.ovirt.engine.core.extensions.mgr.ExtensionsManager.trace.aaa.ldap.authn.ldap-authn-openldap_brq-openldap), Extkey[name=EXTENSION_PROVIDES;type=interface java.util.Collection;uuid=EXTENSION_PROVIDES[8cf373a6-65b5-4594-b828-0e275087de91];]=[org.ovirt.engine.api.extensions.aaa.Authn]}, Extkey[name=EXTENSION_INVOKE_COMMAND;type=class org.ovirt.engine.api.extensions.ExtUUID;uuid=EXTENSION_INVOKE_COMMAND[485778ab-bede-4f1a-b823-77b262a2f28d];]=EXTENSION_INITIALIZE[e5ae1b7f-9104-4f23-a444-7b9175ff68d2]}
>2014-09-10 18:49:37,541 TRACE [org.ovirt.engine.core.extensions.mgr.ExtensionsManager.trace.aaa.ldap.authn.ldap-authn-openldap_brq-openldap] (ServerService Thread Pool -- 45) Invoke Input END
>2014-09-10 18:49:37,541 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) Entry
>2014-09-10 18:49:37,541 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) runSequence entry name='simple-open-pools'
>2014-09-10 18:49:37,541 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) Running sequence simple-open-pools/010/pool-create create authz pool
>2014-09-10 18:49:37,541 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) VARS-BEGIN
>2014-09-10 18:49:37,541 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) authn_enable = 1
>2014-09-10 18:49:37,541 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) capability_credentialsChange = false
>2014-09-10 18:49:37,541 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) capability_resucrsiveGroupResolution = false
>2014-09-10 18:49:37,541 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) maxFilterSize = 50
>2014-09-10 18:49:37,541 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) sensitiveKeys = , password, passwordNew
>2014-09-10 18:49:37,541 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_attrsBaseDN = namingContexts
>2014-09-10 18:49:37,541 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_attrsGroupRecord = entryUUID, cn, description, memberOf
>2014-09-10 18:49:37,541 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_attrsPrincipalRecord = entryUUID, uid, displayName, memberOf, department, givenName, sn, title, mail
>2014-09-10 18:49:37,541 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_attrsUserName = uid
>2014-09-10 18:49:37,541 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_bindFormat = dn
>2014-09-10 18:49:37,541 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_filterGroupObject = (objectClass=groupOfNames)
>2014-09-10 18:49:37,542 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_filterUserObject = (objectClass=uidObject)(uid=*)
>2014-09-10 18:49:37,542 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) stop = false
>2014-09-10 18:49:37,542 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) VARS-END
>2014-09-10 18:49:37,542 INFO  [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) Creating LDAP pool 'authz' for 'ldap-authn-openldap_brq-openldap'
>2014-09-10 18:49:37,542 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) Entry name='authz'
>2014-09-10 18:49:37,542 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) Entry
>2014-09-10 18:49:37,543 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) Creating LDAPConnectionOptions
>2014-09-10 18:49:37,550 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) LDAPConnectionOptions: LDAPConnectionOptions(autoReconnect=true, bindWithDNRequiresPassword=true, followReferrals=false, useKeepAlive=true, useLinger=true, lingerTimeoutSeconds=5, useReuseAddress=true, useSchema=false, usePooledSchema=false, pooledSchemaTimeoutMillis=3600000, useSynchronousMode=false, useTCPNoDelay=true, captureConnectStackTrace=false, connectTimeoutMillis=5000, responseTimeoutMillis=60000, abandonOnTimeout=true, maxMessageSize=20971520, receiveBufferSize=0, sendBufferSize=0, allowConcurrentSocketFactoryUse=true, sslSocketVerifierClass='com.unboundid.util.ssl.TrustAllSSLSocketVerifier')
>2014-09-10 18:49:37,550 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) Creating SocketFactory
>2014-09-10 18:49:37,550 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) Creating resolver
>2014-09-10 18:49:37,553 DEBUG [org.ovirt.engineextensions.aaa.ldap.Resolver] (ServerService Thread Pool -- 45) Open: Context: {java.naming.provider.url=dns://, java.naming.factory.initial=com.sun.jndi.dns.DnsContextFactory}
>2014-09-10 18:49:37,554 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) Resolver: Resolver(env='{java.naming.provider.url=dns://, java.naming.factory.initial=com.sun.jndi.dns.DnsContextFactory}', supportIPv6='false', cacheTTL='10000')
>2014-09-10 18:49:37,555 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) SocketFactory: org.ovirt.engineextensions.aaa.ldap.ResolverSocketFactory@c40dd0e
>2014-09-10 18:49:37,555 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) Creating ServerSet
>2014-09-10 18:49:37,556 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) ServerSet: SingleServerSet(server=brq-openldap.rhev.lab.eng.brq.redhat.com:389)
>2014-09-10 18:49:37,556 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) Creating BindRequest
>2014-09-10 18:49:37,556 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) Entry type='simple', user=''
>2014-09-10 18:49:37,565 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) BindRequest: SimpleBindRequest(dn='uid=user2,ou=Users,dc=brq-openldap,dc=rhev,dc=lab,dc=eng,dc=brq,dc=redhat,dc=com')
>2014-09-10 18:49:37,565 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) Creating LDAPConnectionPool
>2014-09-10 18:49:37,589 DEBUG [org.ovirt.engineextensions.aaa.ldap.Resolver] (Background connect thread for brq-openldap.rhev.lab.eng.brq.redhat.com/10.34.63.224:389) Entry: name='brq-openldap.rhev.lab.eng.brq.redhat.com'
>2014-09-10 18:49:37,590 DEBUG [org.ovirt.engineextensions.aaa.ldap.Resolver] (Background connect thread for brq-openldap.rhev.lab.eng.brq.redhat.com/10.34.63.224:389) Entry: name='brq-openldap.rhev.lab.eng.brq.redhat.com'
>2014-09-10 18:49:37,590 DEBUG [org.ovirt.engineextensions.aaa.ldap.Resolver] (Background connect thread for brq-openldap.rhev.lab.eng.brq.redhat.com/10.34.63.224:389) Cache miss
>2014-09-10 18:49:37,590 DEBUG [org.ovirt.engineextensions.aaa.ldap.Resolver] (Background connect thread for brq-openldap.rhev.lab.eng.brq.redhat.com/10.34.63.224:389) Entry: name='brq-openldap.rhev.lab.eng.brq.redhat.com'
>2014-09-10 18:49:37,590 DEBUG [org.ovirt.engineextensions.aaa.ldap.Resolver] (Background connect thread for brq-openldap.rhev.lab.eng.brq.redhat.com/10.34.63.224:389) Entry: name='brq-openldap.rhev.lab.eng.brq.redhat.com', attrs=%s
>2014-09-10 18:49:37,592 DEBUG [org.ovirt.engineextensions.aaa.ldap.Resolver] (Background connect thread for brq-openldap.rhev.lab.eng.brq.redhat.com/10.34.63.224:389) Return: {a=A: 10.34.63.224}
>2014-09-10 18:49:37,593 DEBUG [org.ovirt.engineextensions.aaa.ldap.Resolver] (Background connect thread for brq-openldap.rhev.lab.eng.brq.redhat.com/10.34.63.224:389) A 10.34.63.224
>2014-09-10 18:49:37,593 DEBUG [org.ovirt.engineextensions.aaa.ldap.Resolver] (Background connect thread for brq-openldap.rhev.lab.eng.brq.redhat.com/10.34.63.224:389) Return: [10.34.63.224]
>2014-09-10 18:49:37,598 DEBUG [org.ovirt.engineextensions.aaa.ldap.Resolver] (Background connect thread for brq-openldap.rhev.lab.eng.brq.redhat.com/10.34.63.224:389) Return: [brq-openldap.rhev.lab.eng.brq.redhat.com/10.34.63.224]
>2014-09-10 18:49:37,598 DEBUG [org.ovirt.engineextensions.aaa.ldap.Resolver] (Background connect thread for brq-openldap.rhev.lab.eng.brq.redhat.com/10.34.63.224:389) Return: brq-openldap.rhev.lab.eng.brq.redhat.com/10.34.63.224
>2014-09-10 18:49:37,625 DEBUG [org.ovirt.engineextensions.aaa.ldap.Resolver] (Background connect thread for brq-openldap.rhev.lab.eng.brq.redhat.com/10.34.63.224:389) Entry: name='brq-openldap.rhev.lab.eng.brq.redhat.com'
>2014-09-10 18:49:37,625 DEBUG [org.ovirt.engineextensions.aaa.ldap.Resolver] (Background connect thread for brq-openldap.rhev.lab.eng.brq.redhat.com/10.34.63.224:389) Entry: name='brq-openldap.rhev.lab.eng.brq.redhat.com'
>2014-09-10 18:49:37,625 DEBUG [org.ovirt.engineextensions.aaa.ldap.Resolver] (Background connect thread for brq-openldap.rhev.lab.eng.brq.redhat.com/10.34.63.224:389) Return: [brq-openldap.rhev.lab.eng.brq.redhat.com/10.34.63.224]
>2014-09-10 18:49:37,625 DEBUG [org.ovirt.engineextensions.aaa.ldap.Resolver] (Background connect thread for brq-openldap.rhev.lab.eng.brq.redhat.com/10.34.63.224:389) Return: brq-openldap.rhev.lab.eng.brq.redhat.com/10.34.63.224
>2014-09-10 18:49:37,722 DEBUG [org.ovirt.engineextensions.aaa.ldap.Resolver] (Background connect thread for brq-openldap.rhev.lab.eng.brq.redhat.com/10.34.63.224:389) Entry: name='brq-openldap.rhev.lab.eng.brq.redhat.com'
>2014-09-10 18:49:37,723 DEBUG [org.ovirt.engineextensions.aaa.ldap.Resolver] (Background connect thread for brq-openldap.rhev.lab.eng.brq.redhat.com/10.34.63.224:389) Entry: name='brq-openldap.rhev.lab.eng.brq.redhat.com'
>2014-09-10 18:49:37,723 DEBUG [org.ovirt.engineextensions.aaa.ldap.Resolver] (Background connect thread for brq-openldap.rhev.lab.eng.brq.redhat.com/10.34.63.224:389) Return: [brq-openldap.rhev.lab.eng.brq.redhat.com/10.34.63.224]
>2014-09-10 18:49:37,723 DEBUG [org.ovirt.engineextensions.aaa.ldap.Resolver] (Background connect thread for brq-openldap.rhev.lab.eng.brq.redhat.com/10.34.63.224:389) Return: brq-openldap.rhev.lab.eng.brq.redhat.com/10.34.63.224
>2014-09-10 18:49:37,753 DEBUG [org.ovirt.engineextensions.aaa.ldap.Resolver] (Background connect thread for brq-openldap.rhev.lab.eng.brq.redhat.com/10.34.63.224:389) Entry: name='brq-openldap.rhev.lab.eng.brq.redhat.com'
>2014-09-10 18:49:37,753 DEBUG [org.ovirt.engineextensions.aaa.ldap.Resolver] (Background connect thread for brq-openldap.rhev.lab.eng.brq.redhat.com/10.34.63.224:389) Entry: name='brq-openldap.rhev.lab.eng.brq.redhat.com'
>2014-09-10 18:49:37,754 DEBUG [org.ovirt.engineextensions.aaa.ldap.Resolver] (Background connect thread for brq-openldap.rhev.lab.eng.brq.redhat.com/10.34.63.224:389) Return: [brq-openldap.rhev.lab.eng.brq.redhat.com/10.34.63.224]
>2014-09-10 18:49:37,754 DEBUG [org.ovirt.engineextensions.aaa.ldap.Resolver] (Background connect thread for brq-openldap.rhev.lab.eng.brq.redhat.com/10.34.63.224:389) Return: brq-openldap.rhev.lab.eng.brq.redhat.com/10.34.63.224
>2014-09-10 18:49:37,789 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) LDAPConnectionPool: LDAPConnectionPool(serverSet=SingleServerSet(server=brq-openldap.rhev.lab.eng.brq.redhat.com:389), maxConnections=20)
>2014-09-10 18:49:37,814 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) Return RootDSE: [Attribute(name=objectClass, values={'top', 'OpenLDAProotDSE'}), Attribute(name=structuralObjectClass, values={'OpenLDAProotDSE'}), Attribute(name=configContext, values={'cn=config'}), Attribute(name=monitorContext, values={'cn=Monitor'}), Attribute(name=namingContexts, values={'dc=brq-openldap,dc=rhev,dc=lab,dc=eng,dc=brq,dc=redhat,dc=com'}), Attribute(name=supportedControl, values={'2.16.840.1.113730.3.4.18', '2.16.840.1.113730.3.4.2', '1.3.6.1.4.1.4203.1.10.1', '1.2.840.113556.1.4.319', '1.2.826.0.1.3344810.2.3', '1.3.6.1.1.13.2', '1.3.6.1.1.13.1', '1.3.6.1.1.12'}), Attribute(name=supportedExtension, values={'1.3.6.1.4.1.1466.20037', '1.3.6.1.4.1.4203.1.11.1', '1.3.6.1.4.1.4203.1.11.3', '1.3.6.1.1.8'}), Attribute(name=supportedFeatures, values={'1.3.6.1.1.14', '1.3.6.1.4.1.4203.1.5.1', '1.3.6.1.4.1.4203.1.5.2', '1.3.6.1.4.1.4203.1.5.3', '1.3.6.1.4.1.4203.1.5.4', '1.3.6.1.4.1.4203.1.5.5'}), Attribute(name=supportedLDAPVersion, values={'3'}), Attribute(name=supportedSASLMechanisms, values={'CRAM-MD5', 'NTLM', 'DIGEST-MD5', 'GSSAPI'}), Attribute(name=entryDN, values={''}), Attribute(name=subschemaSubentry, values={'cn=Subschema'})]
>2014-09-10 18:49:37,816 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) Return
>2014-09-10 18:49:37,817 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) End sequence simple-open-pools create authz pool
>2014-09-10 18:49:37,817 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) VARS-BEGIN
>2014-09-10 18:49:37,817 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) authn_enable = 1
>2014-09-10 18:49:37,817 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) capability_credentialsChange = false
>2014-09-10 18:49:37,817 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) capability_resucrsiveGroupResolution = false
>2014-09-10 18:49:37,817 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) maxFilterSize = 50
>2014-09-10 18:49:37,817 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) sensitiveKeys = , password, passwordNew
>2014-09-10 18:49:37,817 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_attrsBaseDN = namingContexts
>2014-09-10 18:49:37,817 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_attrsGroupRecord = entryUUID, cn, description, memberOf
>2014-09-10 18:49:37,817 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_attrsPrincipalRecord = entryUUID, uid, displayName, memberOf, department, givenName, sn, title, mail
>2014-09-10 18:49:37,817 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_attrsUserName = uid
>2014-09-10 18:49:37,817 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_bindFormat = dn
>2014-09-10 18:49:37,817 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_filterGroupObject = (objectClass=groupOfNames)
>2014-09-10 18:49:37,817 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_filterUserObject = (objectClass=uidObject)(uid=*)
>2014-09-10 18:49:37,817 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) stop = false
>2014-09-10 18:49:37,817 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) VARS-END
>2014-09-10 18:49:37,817 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) Running sequence simple-open-pools/020/pool-create create authn pool
>2014-09-10 18:49:37,817 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) VARS-BEGIN
>2014-09-10 18:49:37,817 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) authn_enable = 1
>2014-09-10 18:49:37,817 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) capability_credentialsChange = false
>2014-09-10 18:49:37,817 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) capability_resucrsiveGroupResolution = false
>2014-09-10 18:49:37,818 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) maxFilterSize = 50
>2014-09-10 18:49:37,818 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) sensitiveKeys = , password, passwordNew
>2014-09-10 18:49:37,818 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_attrsBaseDN = namingContexts
>2014-09-10 18:49:37,818 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_attrsGroupRecord = entryUUID, cn, description, memberOf
>2014-09-10 18:49:37,818 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_attrsPrincipalRecord = entryUUID, uid, displayName, memberOf, department, givenName, sn, title, mail
>2014-09-10 18:49:37,818 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_attrsUserName = uid
>2014-09-10 18:49:37,818 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_bindFormat = dn
>2014-09-10 18:49:37,818 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_filterGroupObject = (objectClass=groupOfNames)
>2014-09-10 18:49:37,818 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_filterUserObject = (objectClass=uidObject)(uid=*)
>2014-09-10 18:49:37,818 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) stop = false
>2014-09-10 18:49:37,818 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) VARS-END
>2014-09-10 18:49:37,818 INFO  [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) Creating LDAP pool 'authn' for 'ldap-authn-openldap_brq-openldap'
>2014-09-10 18:49:37,818 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) Entry name='authn'
>2014-09-10 18:49:37,818 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) Entry
>2014-09-10 18:49:37,818 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) Creating LDAPConnectionOptions
>2014-09-10 18:49:37,819 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) LDAPConnectionOptions: LDAPConnectionOptions(autoReconnect=true, bindWithDNRequiresPassword=true, followReferrals=false, useKeepAlive=true, useLinger=true, lingerTimeoutSeconds=5, useReuseAddress=true, useSchema=false, usePooledSchema=false, pooledSchemaTimeoutMillis=3600000, useSynchronousMode=false, useTCPNoDelay=true, captureConnectStackTrace=false, connectTimeoutMillis=5000, responseTimeoutMillis=60000, abandonOnTimeout=true, maxMessageSize=20971520, receiveBufferSize=0, sendBufferSize=0, allowConcurrentSocketFactoryUse=true, sslSocketVerifierClass='com.unboundid.util.ssl.TrustAllSSLSocketVerifier')
>2014-09-10 18:49:37,819 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) Creating SocketFactory
>2014-09-10 18:49:37,819 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) Creating resolver
>2014-09-10 18:49:37,819 DEBUG [org.ovirt.engineextensions.aaa.ldap.Resolver] (ServerService Thread Pool -- 45) Open: Context: {java.naming.provider.url=dns://, java.naming.factory.initial=com.sun.jndi.dns.DnsContextFactory}
>2014-09-10 18:49:37,819 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) Resolver: Resolver(env='{java.naming.provider.url=dns://, java.naming.factory.initial=com.sun.jndi.dns.DnsContextFactory}', supportIPv6='false', cacheTTL='10000')
>2014-09-10 18:49:37,819 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) SocketFactory: org.ovirt.engineextensions.aaa.ldap.ResolverSocketFactory@2dbb4dbb
>2014-09-10 18:49:37,819 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) Creating ServerSet
>2014-09-10 18:49:37,820 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) ServerSet: SingleServerSet(server=brq-openldap.rhev.lab.eng.brq.redhat.com:389)
>2014-09-10 18:49:37,820 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) Creating BindRequest
>2014-09-10 18:49:37,820 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) Entry type='none', user=''
>2014-09-10 18:49:37,820 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) BindRequest: SimpleBindRequest(dn='')
>2014-09-10 18:49:37,820 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) Creating LDAPConnectionPool
>2014-09-10 18:49:37,821 DEBUG [org.ovirt.engineextensions.aaa.ldap.Resolver] (Background connect thread for brq-openldap.rhev.lab.eng.brq.redhat.com/10.34.63.224:389) Entry: name='brq-openldap.rhev.lab.eng.brq.redhat.com'
>2014-09-10 18:49:37,821 DEBUG [org.ovirt.engineextensions.aaa.ldap.Resolver] (Background connect thread for brq-openldap.rhev.lab.eng.brq.redhat.com/10.34.63.224:389) Entry: name='brq-openldap.rhev.lab.eng.brq.redhat.com'
>2014-09-10 18:49:37,821 DEBUG [org.ovirt.engineextensions.aaa.ldap.Resolver] (Background connect thread for brq-openldap.rhev.lab.eng.brq.redhat.com/10.34.63.224:389) Cache miss
>2014-09-10 18:49:37,821 DEBUG [org.ovirt.engineextensions.aaa.ldap.Resolver] (Background connect thread for brq-openldap.rhev.lab.eng.brq.redhat.com/10.34.63.224:389) Entry: name='brq-openldap.rhev.lab.eng.brq.redhat.com'
>2014-09-10 18:49:37,821 DEBUG [org.ovirt.engineextensions.aaa.ldap.Resolver] (Background connect thread for brq-openldap.rhev.lab.eng.brq.redhat.com/10.34.63.224:389) Entry: name='brq-openldap.rhev.lab.eng.brq.redhat.com', attrs=%s
>2014-09-10 18:49:37,822 DEBUG [org.ovirt.engineextensions.aaa.ldap.Resolver] (Background connect thread for brq-openldap.rhev.lab.eng.brq.redhat.com/10.34.63.224:389) Return: {a=A: 10.34.63.224}
>2014-09-10 18:49:37,822 DEBUG [org.ovirt.engineextensions.aaa.ldap.Resolver] (Background connect thread for brq-openldap.rhev.lab.eng.brq.redhat.com/10.34.63.224:389) A 10.34.63.224
>2014-09-10 18:49:37,822 DEBUG [org.ovirt.engineextensions.aaa.ldap.Resolver] (Background connect thread for brq-openldap.rhev.lab.eng.brq.redhat.com/10.34.63.224:389) Return: [10.34.63.224]
>2014-09-10 18:49:37,822 DEBUG [org.ovirt.engineextensions.aaa.ldap.Resolver] (Background connect thread for brq-openldap.rhev.lab.eng.brq.redhat.com/10.34.63.224:389) Return: [brq-openldap.rhev.lab.eng.brq.redhat.com/10.34.63.224]
>2014-09-10 18:49:37,822 DEBUG [org.ovirt.engineextensions.aaa.ldap.Resolver] (Background connect thread for brq-openldap.rhev.lab.eng.brq.redhat.com/10.34.63.224:389) Return: brq-openldap.rhev.lab.eng.brq.redhat.com/10.34.63.224
>2014-09-10 18:49:37,826 DEBUG [org.ovirt.engineextensions.aaa.ldap.Resolver] (Background connect thread for brq-openldap.rhev.lab.eng.brq.redhat.com/10.34.63.224:389) Entry: name='brq-openldap.rhev.lab.eng.brq.redhat.com'
>2014-09-10 18:49:37,827 DEBUG [org.ovirt.engineextensions.aaa.ldap.Resolver] (Background connect thread for brq-openldap.rhev.lab.eng.brq.redhat.com/10.34.63.224:389) Entry: name='brq-openldap.rhev.lab.eng.brq.redhat.com'
>2014-09-10 18:49:37,827 DEBUG [org.ovirt.engineextensions.aaa.ldap.Resolver] (Background connect thread for brq-openldap.rhev.lab.eng.brq.redhat.com/10.34.63.224:389) Return: [brq-openldap.rhev.lab.eng.brq.redhat.com/10.34.63.224]
>2014-09-10 18:49:37,827 DEBUG [org.ovirt.engineextensions.aaa.ldap.Resolver] (Background connect thread for brq-openldap.rhev.lab.eng.brq.redhat.com/10.34.63.224:389) Return: brq-openldap.rhev.lab.eng.brq.redhat.com/10.34.63.224
>2014-09-10 18:49:37,831 DEBUG [org.ovirt.engineextensions.aaa.ldap.Resolver] (Background connect thread for brq-openldap.rhev.lab.eng.brq.redhat.com/10.34.63.224:389) Entry: name='brq-openldap.rhev.lab.eng.brq.redhat.com'
>2014-09-10 18:49:37,831 DEBUG [org.ovirt.engineextensions.aaa.ldap.Resolver] (Background connect thread for brq-openldap.rhev.lab.eng.brq.redhat.com/10.34.63.224:389) Entry: name='brq-openldap.rhev.lab.eng.brq.redhat.com'
>2014-09-10 18:49:37,831 DEBUG [org.ovirt.engineextensions.aaa.ldap.Resolver] (Background connect thread for brq-openldap.rhev.lab.eng.brq.redhat.com/10.34.63.224:389) Return: [brq-openldap.rhev.lab.eng.brq.redhat.com/10.34.63.224]
>2014-09-10 18:49:37,831 DEBUG [org.ovirt.engineextensions.aaa.ldap.Resolver] (Background connect thread for brq-openldap.rhev.lab.eng.brq.redhat.com/10.34.63.224:389) Return: brq-openldap.rhev.lab.eng.brq.redhat.com/10.34.63.224
>2014-09-10 18:49:37,833 DEBUG [org.ovirt.engineextensions.aaa.ldap.Resolver] (Background connect thread for brq-openldap.rhev.lab.eng.brq.redhat.com/10.34.63.224:389) Entry: name='brq-openldap.rhev.lab.eng.brq.redhat.com'
>2014-09-10 18:49:37,833 DEBUG [org.ovirt.engineextensions.aaa.ldap.Resolver] (Background connect thread for brq-openldap.rhev.lab.eng.brq.redhat.com/10.34.63.224:389) Entry: name='brq-openldap.rhev.lab.eng.brq.redhat.com'
>2014-09-10 18:49:37,833 DEBUG [org.ovirt.engineextensions.aaa.ldap.Resolver] (Background connect thread for brq-openldap.rhev.lab.eng.brq.redhat.com/10.34.63.224:389) Return: [brq-openldap.rhev.lab.eng.brq.redhat.com/10.34.63.224]
>2014-09-10 18:49:37,834 DEBUG [org.ovirt.engineextensions.aaa.ldap.Resolver] (Background connect thread for brq-openldap.rhev.lab.eng.brq.redhat.com/10.34.63.224:389) Return: brq-openldap.rhev.lab.eng.brq.redhat.com/10.34.63.224
>2014-09-10 18:49:37,837 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) LDAPConnectionPool: LDAPConnectionPool(serverSet=SingleServerSet(server=brq-openldap.rhev.lab.eng.brq.redhat.com:389), maxConnections=20)
>2014-09-10 18:49:37,840 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) Return RootDSE: [Attribute(name=objectClass, values={'top', 'OpenLDAProotDSE'}), Attribute(name=structuralObjectClass, values={'OpenLDAProotDSE'}), Attribute(name=configContext, values={'cn=config'}), Attribute(name=monitorContext, values={'cn=Monitor'}), Attribute(name=namingContexts, values={'dc=brq-openldap,dc=rhev,dc=lab,dc=eng,dc=brq,dc=redhat,dc=com'}), Attribute(name=supportedControl, values={'2.16.840.1.113730.3.4.18', '2.16.840.1.113730.3.4.2', '1.3.6.1.4.1.4203.1.10.1', '1.2.840.113556.1.4.319', '1.2.826.0.1.3344810.2.3', '1.3.6.1.1.13.2', '1.3.6.1.1.13.1', '1.3.6.1.1.12'}), Attribute(name=supportedExtension, values={'1.3.6.1.4.1.1466.20037', '1.3.6.1.4.1.4203.1.11.1', '1.3.6.1.4.1.4203.1.11.3', '1.3.6.1.1.8'}), Attribute(name=supportedFeatures, values={'1.3.6.1.1.14', '1.3.6.1.4.1.4203.1.5.1', '1.3.6.1.4.1.4203.1.5.2', '1.3.6.1.4.1.4203.1.5.3', '1.3.6.1.4.1.4203.1.5.4', '1.3.6.1.4.1.4203.1.5.5'}), Attribute(name=supportedLDAPVersion, values={'3'}), Attribute(name=supportedSASLMechanisms, values={'CRAM-MD5', 'NTLM', 'DIGEST-MD5', 'GSSAPI'}), Attribute(name=entryDN, values={''}), Attribute(name=subschemaSubentry, values={'cn=Subschema'})]
>2014-09-10 18:49:37,842 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) Return
>2014-09-10 18:49:37,842 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) End sequence simple-open-pools create authn pool
>2014-09-10 18:49:37,842 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) VARS-BEGIN
>2014-09-10 18:49:37,842 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) authn_enable = 1
>2014-09-10 18:49:37,842 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) capability_credentialsChange = false
>2014-09-10 18:49:37,842 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) capability_resucrsiveGroupResolution = false
>2014-09-10 18:49:37,843 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) maxFilterSize = 50
>2014-09-10 18:49:37,843 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) sensitiveKeys = , password, passwordNew
>2014-09-10 18:49:37,843 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_attrsBaseDN = namingContexts
>2014-09-10 18:49:37,843 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_attrsGroupRecord = entryUUID, cn, description, memberOf
>2014-09-10 18:49:37,843 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_attrsPrincipalRecord = entryUUID, uid, displayName, memberOf, department, givenName, sn, title, mail
>2014-09-10 18:49:37,843 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_attrsUserName = uid
>2014-09-10 18:49:37,843 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_bindFormat = dn
>2014-09-10 18:49:37,843 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_filterGroupObject = (objectClass=groupOfNames)
>2014-09-10 18:49:37,843 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_filterUserObject = (objectClass=uidObject)(uid=*)
>2014-09-10 18:49:37,843 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) stop = false
>2014-09-10 18:49:37,843 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) VARS-END
>2014-09-10 18:49:37,843 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) runSequence Return name='simple-open-pools'
>2014-09-10 18:49:37,843 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) runSequence entry name='simple-open-vars'
>2014-09-10 18:49:37,843 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) Running sequence simple-open-vars/010/fetch-record set base DN
>2014-09-10 18:49:37,843 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) VARS-BEGIN
>2014-09-10 18:49:37,843 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) authn_enable = 1
>2014-09-10 18:49:37,843 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) capability_credentialsChange = false
>2014-09-10 18:49:37,843 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) capability_resucrsiveGroupResolution = false
>2014-09-10 18:49:37,843 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) maxFilterSize = 50
>2014-09-10 18:49:37,843 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) sensitiveKeys = , password, passwordNew
>2014-09-10 18:49:37,843 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_attrsBaseDN = namingContexts
>2014-09-10 18:49:37,843 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_attrsGroupRecord = entryUUID, cn, description, memberOf
>2014-09-10 18:49:37,844 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_attrsPrincipalRecord = entryUUID, uid, displayName, memberOf, department, givenName, sn, title, mail
>2014-09-10 18:49:37,844 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_attrsUserName = uid
>2014-09-10 18:49:37,844 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_bindFormat = dn
>2014-09-10 18:49:37,844 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_filterGroupObject = (objectClass=groupOfNames)
>2014-09-10 18:49:37,844 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_filterUserObject = (objectClass=uidObject)(uid=*)
>2014-09-10 18:49:37,844 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) stop = false
>2014-09-10 18:49:37,844 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) VARS-END
>2014-09-10 18:49:37,844 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) searchOpen Entry name='simple-namespace', pageSize=0, limit=5
>2014-09-10 18:49:37,844 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) Creating SearchRequest
>2014-09-10 18:49:37,848 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) SearchRequest: SearchRequest(baseDN='', scope=BASE, deref=NEVER, sizeLimit=0, timeLimit=0, filter='&(objectClass=*)', attrs={namingContexts})
>2014-09-10 18:49:37,853 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) Entry name='authz'
>2014-09-10 18:49:37,853 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) Entry name='simple-map-namespace'
>2014-09-10 18:49:37,855 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) AttrMapInfo Return [AttrMapInfo(namespace, STRING, '%s', namingContexts)]
>2014-09-10 18:49:37,855 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) SearchOpen Return SearchInstance(searchRequest='SearchRequest(baseDN='', scope=BASE, deref=NEVER, sizeLimit=0, timeLimit=0, filter='&(objectClass=*)', attrs={namingContexts})', doPaging=true, resumeCookie='null', pageSize=100, limitLeft=5, done=false)
>2014-09-10 18:49:37,855 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) Enter
>2014-09-10 18:49:37,874 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) SearchRequest: SearchRequest(baseDN='', scope=BASE, deref=NEVER, sizeLimit=0, timeLimit=0, filter='&(objectClass=*)', attrs={namingContexts}, controls={SimplePagedResultsControl(pageSize=100, isCritical=false)})
>2014-09-10 18:49:37,875 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) SearchResult: SearchResult(resultCode=0 (success), messageID=2, entriesReturned=1, referencesReturned=0)
>2014-09-10 18:49:37,875 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) SearchReferences: []
>2014-09-10 18:49:37,875 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) SearchReferences: []
>2014-09-10 18:49:37,875 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) Return: [{namespace=[dc=brq-openldap,dc=rhev,dc=lab,dc=eng,dc=brq,dc=redhat,dc=com], _dn=[]}]
>2014-09-10 18:49:37,876 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) Enter
>2014-09-10 18:49:37,876 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) Return: null
>2014-09-10 18:49:37,876 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) End sequence simple-open-vars set base DN
>2014-09-10 18:49:37,876 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) VARS-BEGIN
>2014-09-10 18:49:37,876 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) _simple_baseDN = dc=brq-openldap,dc=rhev,dc=lab,dc=eng,dc=brq,dc=redhat,dc=com
>2014-09-10 18:49:37,876 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) authn_enable = 1
>2014-09-10 18:49:37,876 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) capability_credentialsChange = false
>2014-09-10 18:49:37,876 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) capability_resucrsiveGroupResolution = false
>2014-09-10 18:49:37,876 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) maxFilterSize = 50
>2014-09-10 18:49:37,876 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) search_attr__dn = 
>2014-09-10 18:49:37,876 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) sensitiveKeys = , password, passwordNew
>2014-09-10 18:49:37,876 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_attrsBaseDN = namingContexts
>2014-09-10 18:49:37,876 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_attrsGroupRecord = entryUUID, cn, description, memberOf
>2014-09-10 18:49:37,876 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_attrsPrincipalRecord = entryUUID, uid, displayName, memberOf, department, givenName, sn, title, mail
>2014-09-10 18:49:37,876 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_attrsUserName = uid
>2014-09-10 18:49:37,876 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_bindFormat = dn
>2014-09-10 18:49:37,876 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_filterGroupObject = (objectClass=groupOfNames)
>2014-09-10 18:49:37,876 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) simple_filterUserObject = (objectClass=uidObject)(uid=*)
>2014-09-10 18:49:37,876 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) stop = false
>2014-09-10 18:49:37,876 TRACE [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) VARS-END
>2014-09-10 18:49:37,876 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) runSequence Return name='simple-open-vars'
>2014-09-10 18:49:37,877 DEBUG [org.ovirt.engineextensions.aaa.ldap.Framework] (ServerService Thread Pool -- 45) Return globals={simple_bindFormat=dn, simple_attrsGroupRecord=entryUUID, cn, description, memberOf, _simple_baseDN=dc=brq-openldap,dc=rhev,dc=lab,dc=eng,dc=brq,dc=redhat,dc=com, simple_attrsPrincipalRecord=entryUUID, uid, displayName, memberOf, department, givenName, sn, title, mail, capability_credentialsChange=false, capability_resucrsiveGroupResolution=false, simple_attrsBaseDN=namingContexts, simple_attrsUserName=uid, maxFilterSize=50, sensitiveKeys=, password, passwordNew, simple_filterUserObject=(objectClass=uidObject)(uid=*), stop=false, search_attr__dn=, simple_filterGroupObject=(objectClass=groupOfNames), authn_enable=1}
>2014-09-10 18:49:37,877 TRACE [org.ovirt.engine.core.extensions.mgr.ExtensionsManager.trace.aaa.ldap.authn.ldap-authn-openldap_brq-openldap] (ServerService Thread Pool -- 45) Invoke Output BEGIN
>2014-09-10 18:49:37,877 TRACE [org.ovirt.engine.core.extensions.mgr.ExtensionsManager.trace.aaa.ldap.authn.ldap-authn-openldap_brq-openldap] (ServerService Thread Pool -- 45) {Extkey[name=EXTENSION_INVOKE_RESULT;type=class java.lang.Integer;uuid=EXTENSION_INVOKE_RESULT[0909d91d-8bde-40fb-b6c0-099c772ddd4e];]=0}
>2014-09-10 18:49:37,877 TRACE [org.ovirt.engine.core.extensions.mgr.ExtensionsManager.trace.aaa.ldap.authn.ldap-authn-openldap_brq-openldap] (ServerService Thread Pool -- 45) Invoke Output END
>2014-09-10 18:49:37,877 INFO  [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (ServerService Thread Pool -- 45) Extension 'ldap-authn-openldap_brq-openldap' initialized
>2014-09-10 18:49:37,877 INFO  [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (ServerService Thread Pool -- 45) Initializing extension 'builtin-authn-brq-openldap.rhev.lab.eng.brq.redhat.com'
>2014-09-10 18:49:37,878 TRACE [org.ovirt.engine.core.extensions.mgr.ExtensionsManager.trace.Kerberos/Ldap Authn (Built-in).builtin-authn-brq-openldap.rhev.lab.eng.brq.redhat.com] (ServerService Thread Pool -- 45) Invoke Input BEGIN
>2014-09-10 18:49:37,878 TRACE [org.ovirt.engine.core.extensions.mgr.ExtensionsManager.trace.Kerberos/Ldap Authn (Built-in).builtin-authn-brq-openldap.rhev.lab.eng.brq.redhat.com] (ServerService Thread Pool -- 45) {Extkey[name=EXTENSION_INVOKE_CONTEXT;type=class org.ovirt.engine.api.extensions.ExtMap;uuid=EXTENSION_INVOKE_CONTEXT[886d2ebb-312a-49ae-9cc3-e1f849834b7d];]={Extkey[name=EXTENSION_INTERFACE_VERSION_MAX;type=class java.lang.Integer;uuid=EXTENSION_INTERFACE_VERSION_MAX[f4cff49f-2717-4901-8ee9-df362446e3e7];]=0, Extkey[name=EXTENSION_LICENSE;type=class java.lang.String;uuid=EXTENSION_LICENSE[8a61ad65-054c-4e31-9c6d-1ca4d60a4c18];]=ASL 2.0, Extkey[name=EXTENSION_HOME_URL;type=class java.lang.String;uuid=EXTENSION_HOME_URL[4ad7a2f4-f969-42d4-b399-72d192e18304];]=http://www.ovirt.org, Extkey[name=EXTENSION_LOCALE;type=class java.lang.String;uuid=EXTENSION_LOCALE[0780b112-0ce0-404a-b85e-8765d778bb29];]=en_US, Extkey[name=EXTENSION_NAME;type=class java.lang.String;uuid=EXTENSION_NAME[651381d3-f54f-4547-bf28-b0b01a103184];]=Kerberos/Ldap Authn (Built-in), Extkey[name=EXTENSION_INTERFACE_VERSION_MIN;type=class java.lang.Integer;uuid=EXTENSION_INTERFACE_VERSION_MIN[2b84fc91-305b-497b-a1d7-d961b9d2ce0b];]=0, Extkey[name=EXTENSION_CONFIGURATION;type=class java.util.Properties;uuid=EXTENSION_CONFIGURATION[2d48ab72-f0a1-4312-b4ae-5068a226b0fc];]=***, Extkey[name=EXTENSION_AUTHOR;type=class java.lang.String;uuid=EXTENSION_AUTHOR[ef242f7a-2dad-4bc5-9aad-e07018b7fbcc];]=The oVirt Project, Extkey[name=EXTENSION_INSTANCE_NAME;type=class java.lang.String;uuid=EXTENSION_INSTANCE_NAME[65c67ff6-aeca-4bd5-a245-8674327f011b];]=builtin-authn-brq-openldap.rhev.lab.eng.brq.redhat.com, Extkey[name=EXTENSION_BUILD_INTERFACE_VERSION;type=class java.lang.Integer;uuid=EXTENSION_BUILD_INTERFACE_VERSION[cb479e5a-4b23-46f8-aed3-56a4747a8ab7];]=0, Extkey[name=EXTENSION_CONFIGURATION_SENSITIVE_KEYS;type=interface java.util.Collection;uuid=EXTENSION_CONFIGURATION_SENSITIVE_KEYS[a456efa1-73ff-4204-9f9b-ebff01e35263];]=[, config.authn.user.password], Extkey[name=AAA_AUTHN_CAPABILITIES;type=class java.lang.Long;uuid=AAA_AUTHN_CAPABILITIES[9d16bee3-10fd-46f2-83f9-3d3c54cf258d];]=8, Extkey[name=EXTENSION_GLOBAL_CONTEXT;type=class org.ovirt.engine.api.extensions.ExtMap;uuid=EXTENSION_GLOBAL_CONTEXT[9799e72f-7af6-4cf1-bf08-297bc8903676];]=*skip*, Extkey[name=EXTENSION_VERSION;type=class java.lang.String;uuid=EXTENSION_VERSION[fe35f6a8-8239-4bdb-ab1a-af9f779ce68c];]=N/A, Extkey[name=EXTENSION_MANAGER_TRACE_LOG;type=interface org.slf4j.Logger;uuid=EXTENSION_MANAGER_TRACE_LOG[863db666-3ea7-4751-9695-918a3197ad83];]=org.slf4j.impl.Slf4jLogger(org.ovirt.engine.core.extensions.mgr.ExtensionsManager.trace.Kerberos/Ldap Authn (Built-in).builtin-authn-brq-openldap.rhev.lab.eng.brq.redhat.com), Extkey[name=EXTENSION_PROVIDES;type=interface java.util.Collection;uuid=EXTENSION_PROVIDES[8cf373a6-65b5-4594-b828-0e275087de91];]=[org.ovirt.engine.api.extensions.aaa.Authn]}, Extkey[name=EXTENSION_INVOKE_COMMAND;type=class org.ovirt.engine.api.extensions.ExtUUID;uuid=EXTENSION_INVOKE_COMMAND[485778ab-bede-4f1a-b823-77b262a2f28d];]=EXTENSION_INITIALIZE[e5ae1b7f-9104-4f23-a444-7b9175ff68d2]}
>2014-09-10 18:49:37,878 TRACE [org.ovirt.engine.core.extensions.mgr.ExtensionsManager.trace.Kerberos/Ldap Authn (Built-in).builtin-authn-brq-openldap.rhev.lab.eng.brq.redhat.com] (ServerService Thread Pool -- 45) Invoke Input END
>2014-09-10 18:49:37,878 TRACE [org.ovirt.engine.core.extensions.mgr.ExtensionsManager.trace.Kerberos/Ldap Authn (Built-in).builtin-authn-brq-openldap.rhev.lab.eng.brq.redhat.com] (ServerService Thread Pool -- 45) Invoke Output BEGIN
>2014-09-10 18:49:37,879 TRACE [org.ovirt.engine.core.extensions.mgr.ExtensionsManager.trace.Kerberos/Ldap Authn (Built-in).builtin-authn-brq-openldap.rhev.lab.eng.brq.redhat.com] (ServerService Thread Pool -- 45) {Extkey[name=EXTENSION_INVOKE_RESULT;type=class java.lang.Integer;uuid=EXTENSION_INVOKE_RESULT[0909d91d-8bde-40fb-b6c0-099c772ddd4e];]=0}
>2014-09-10 18:49:37,879 TRACE [org.ovirt.engine.core.extensions.mgr.ExtensionsManager.trace.Kerberos/Ldap Authn (Built-in).builtin-authn-brq-openldap.rhev.lab.eng.brq.redhat.com] (ServerService Thread Pool -- 45) Invoke Output END
>2014-09-10 18:49:37,879 INFO  [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (ServerService Thread Pool -- 45) Extension 'builtin-authn-brq-openldap.rhev.lab.eng.brq.redhat.com' initialized
>2014-09-10 18:49:37,879 INFO  [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (ServerService Thread Pool -- 45) Initializing extension 'brq-openldap.rhev.lab.eng.brq.redhat.com'
>2014-09-10 18:49:37,879 TRACE [org.ovirt.engine.core.extensions.mgr.ExtensionsManager.trace.Kerberos/Ldap Authz (Built-in).brq-openldap.rhev.lab.eng.brq.redhat.com] (ServerService Thread Pool -- 45) Invoke Input BEGIN
>2014-09-10 18:49:37,879 TRACE [org.ovirt.engine.core.extensions.mgr.ExtensionsManager.trace.Kerberos/Ldap Authz (Built-in).brq-openldap.rhev.lab.eng.brq.redhat.com] (ServerService Thread Pool -- 45) {Extkey[name=EXTENSION_INVOKE_CONTEXT;type=class org.ovirt.engine.api.extensions.ExtMap;uuid=EXTENSION_INVOKE_CONTEXT[886d2ebb-312a-49ae-9cc3-e1f849834b7d];]={Extkey[name=EXTENSION_INTERFACE_VERSION_MAX;type=class java.lang.Integer;uuid=EXTENSION_INTERFACE_VERSION_MAX[f4cff49f-2717-4901-8ee9-df362446e3e7];]=0, Extkey[name=EXTENSION_LICENSE;type=class java.lang.String;uuid=EXTENSION_LICENSE[8a61ad65-054c-4e31-9c6d-1ca4d60a4c18];]=ASL 2.0, Extkey[name=EXTENSION_HOME_URL;type=class java.lang.String;uuid=EXTENSION_HOME_URL[4ad7a2f4-f969-42d4-b399-72d192e18304];]=http://www.ovirt.org, Extkey[name=EXTENSION_LOCALE;type=class java.lang.String;uuid=EXTENSION_LOCALE[0780b112-0ce0-404a-b85e-8765d778bb29];]=en_US, Extkey[name=EXTENSION_NAME;type=class java.lang.String;uuid=EXTENSION_NAME[651381d3-f54f-4547-bf28-b0b01a103184];]=Kerberos/Ldap Authz (Built-in), Extkey[name=EXTENSION_INTERFACE_VERSION_MIN;type=class java.lang.Integer;uuid=EXTENSION_INTERFACE_VERSION_MIN[2b84fc91-305b-497b-a1d7-d961b9d2ce0b];]=0, Extkey[name=EXTENSION_CONFIGURATION;type=class java.util.Properties;uuid=EXTENSION_CONFIGURATION[2d48ab72-f0a1-4312-b4ae-5068a226b0fc];]=***, Extkey[name=EXTENSION_AUTHOR;type=class java.lang.String;uuid=EXTENSION_AUTHOR[ef242f7a-2dad-4bc5-9aad-e07018b7fbcc];]=The oVirt Project, Extkey[name=AAA_AUTHZ_QUERY_MAX_FILTER_SIZE;type=class java.lang.Integer;uuid=AAA_AUTHZ_QUERY_MAX_FILTER_SIZE[2eb1f541-0f65-44a1-a6e3-014e247595f5];]=100, Extkey[name=EXTENSION_INSTANCE_NAME;type=class java.lang.String;uuid=EXTENSION_INSTANCE_NAME[65c67ff6-aeca-4bd5-a245-8674327f011b];]=brq-openldap.rhev.lab.eng.brq.redhat.com, Extkey[name=EXTENSION_BUILD_INTERFACE_VERSION;type=class java.lang.Integer;uuid=EXTENSION_BUILD_INTERFACE_VERSION[cb479e5a-4b23-46f8-aed3-56a4747a8ab7];]=0, Extkey[name=AAA_AUTHZ_CAPABILITIES;type=class java.lang.Long;uuid=AAA_AUTHZ_CAPABILITIES[6106d1fb-9291-4351-a947-b897b9540a23];]=1, Extkey[name=EXTENSION_CONFIGURATION_SENSITIVE_KEYS;type=interface java.util.Collection;uuid=EXTENSION_CONFIGURATION_SENSITIVE_KEYS[a456efa1-73ff-4204-9f9b-ebff01e35263];]=[], Extkey[name=EXTENSION_GLOBAL_CONTEXT;type=class org.ovirt.engine.api.extensions.ExtMap;uuid=EXTENSION_GLOBAL_CONTEXT[9799e72f-7af6-4cf1-bf08-297bc8903676];]=*skip*, Extkey[name=EXTENSION_VERSION;type=class java.lang.String;uuid=EXTENSION_VERSION[fe35f6a8-8239-4bdb-ab1a-af9f779ce68c];]=N/A, Extkey[name=AAA_AUTHZ_AVAILABLE_NAMESPACES;type=interface java.util.Collection;uuid=AAA_AUTHZ_AVAILABLE_NAMESPACES[6dffa34c-955f-486a-bd35-0a272b45a711];]=[*], Extkey[name=EXTENSION_MANAGER_TRACE_LOG;type=interface org.slf4j.Logger;uuid=EXTENSION_MANAGER_TRACE_LOG[863db666-3ea7-4751-9695-918a3197ad83];]=org.slf4j.impl.Slf4jLogger(org.ovirt.engine.core.extensions.mgr.ExtensionsManager.trace.Kerberos/Ldap Authz (Built-in).brq-openldap.rhev.lab.eng.brq.redhat.com), Extkey[name=EXTENSION_PROVIDES;type=interface java.util.Collection;uuid=EXTENSION_PROVIDES[8cf373a6-65b5-4594-b828-0e275087de91];]=[org.ovirt.engine.api.extensions.aaa.Authz]}, Extkey[name=EXTENSION_INVOKE_COMMAND;type=class org.ovirt.engine.api.extensions.ExtUUID;uuid=EXTENSION_INVOKE_COMMAND[485778ab-bede-4f1a-b823-77b262a2f28d];]=EXTENSION_INITIALIZE[e5ae1b7f-9104-4f23-a444-7b9175ff68d2]}
>2014-09-10 18:49:37,880 TRACE [org.ovirt.engine.core.extensions.mgr.ExtensionsManager.trace.Kerberos/Ldap Authz (Built-in).brq-openldap.rhev.lab.eng.brq.redhat.com] (ServerService Thread Pool -- 45) Invoke Input END
>2014-09-10 18:49:37,880 TRACE [org.ovirt.engine.core.extensions.mgr.ExtensionsManager.trace.Kerberos/Ldap Authz (Built-in).brq-openldap.rhev.lab.eng.brq.redhat.com] (ServerService Thread Pool -- 45) Invoke Output BEGIN
>2014-09-10 18:49:37,880 TRACE [org.ovirt.engine.core.extensions.mgr.ExtensionsManager.trace.Kerberos/Ldap Authz (Built-in).brq-openldap.rhev.lab.eng.brq.redhat.com] (ServerService Thread Pool -- 45) {Extkey[name=EXTENSION_INVOKE_RESULT;type=class java.lang.Integer;uuid=EXTENSION_INVOKE_RESULT[0909d91d-8bde-40fb-b6c0-099c772ddd4e];]=0, Extkey[name=AAA_AUTHZ_STATUS;type=class java.lang.Integer;uuid=AAA_AUTHZ_STATUS[566f0ba5-8329-4de1-952a-7a81e4bedd3e];]=0}
>2014-09-10 18:49:37,880 TRACE [org.ovirt.engine.core.extensions.mgr.ExtensionsManager.trace.Kerberos/Ldap Authz (Built-in).brq-openldap.rhev.lab.eng.brq.redhat.com] (ServerService Thread Pool -- 45) Invoke Output END
>2014-09-10 18:49:37,880 INFO  [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (ServerService Thread Pool -- 45) Extension 'brq-openldap.rhev.lab.eng.brq.redhat.com' initialized
>

Actions: View

Attachments on bug 1134004: 936283 | 936286