2018-10-31 16:48:07,873-05 FINE Version: ovirt-engine-4.2.6.4 () 2018-10-31 16:48:07,904-05 INFO ======================================================================== 2018-10-31 16:48:07,904-05 INFO ============================ Initialization ============================ 2018-10-31 16:48:07,905-05 INFO ======================================================================== 2018-10-31 16:48:07,906-05 FINE Loading extension file 'internal-authz.properties' 2018-10-31 16:48:07,935-05 INFO Loading extension 'internal-authz' 2018-10-31 16:48:07,936-05 FINEST Invoke Input BEGIN 2018-10-31 16:48:07,939-05 FINEST {Extkey[name=EXTENSION_INVOKE_COMMAND;type=class org.ovirt.engine.api.extensions.ExtUUID;uuid=EXTENSION_INVOKE_COMMAND[485778ab-bede-4f1a-b823-77b262a2f28d];]=EXTENSION_LOAD[b0f2460e-7971-4a9c-b4e1-c1db1362a47a], 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_GLOBAL_CONTEXT;type=class org.ovirt.engine.api.extensions.ExtMap;uuid=EXTENSION_GLOBAL_CONTEXT[9799e72f-7af6-4cf1-bf08-297bc8903676];]=*skip*, Extkey[name=EXTENSION_MANAGER_TRACE_LOG;type=interface org.slf4j.Logger;uuid=EXTENSION_MANAGER_TRACE_LOG[863db666-3ea7-4751-9695-918a3197ad83];]=org.slf4j.impl.JDK14LoggerAdapter(org.ovirt.engine.core.extensions.mgr.ExtensionsManager.trace), Extkey[name=EXTENSION_CONFIGURATION_SENSITIVE_KEYS;type=interface java.util.Collection;uuid=EXTENSION_CONFIGURATION_SENSITIVE_KEYS[a456efa1-73ff-4204-9f9b-ebff01e35263];]=[], 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_LOCALE;type=class java.lang.String;uuid=EXTENSION_LOCALE[0780b112-0ce0-404a-b85e-8765d778bb29];]=en_US, Extkey[name=EXTENSION_CONFIGURATION_FILE;type=class java.lang.String;uuid=EXTENSION_CONFIGURATION_FILE[4fb0ffd3-983c-4f3f-98ff-9660bd67af6a];]=/etc/ovirt-engine/extensions.d/internal-authz.properties, Extkey[name=EXTENSION_CONFIGURATION;type=class java.util.Properties;uuid=EXTENSION_CONFIGURATION[2d48ab72-f0a1-4312-b4ae-5068a226b0fc];]=***, 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_INTERFACE_VERSION_MIN;type=class java.lang.Integer;uuid=EXTENSION_INTERFACE_VERSION_MIN[2b84fc91-305b-497b-a1d7-d961b9d2ce0b];]=0, Extkey[name=EXTENSION_INSTANCE_NAME;type=class java.lang.String;uuid=EXTENSION_INSTANCE_NAME[65c67ff6-aeca-4bd5-a245-8674327f011b];]=internal-authz}} 2018-10-31 16:48:07,939-05 FINEST Invoke Input END 2018-10-31 16:48:07,943-05 FINEST Invoke Output BEGIN 2018-10-31 16:48:07,943-05 FINEST {Extkey[name=AAA_AUTHZ_STATUS;type=class java.lang.Integer;uuid=AAA_AUTHZ_STATUS[566f0ba5-8329-4de1-952a-7a81e4bedd3e];]=0, Extkey[name=EXTENSION_INVOKE_RESULT;type=class java.lang.Integer;uuid=EXTENSION_INVOKE_RESULT[0909d91d-8bde-40fb-b6c0-099c772ddd4e];]=0} 2018-10-31 16:48:07,943-05 FINEST Invoke Output END 2018-10-31 16:48:07,944-05 INFO Extension 'internal-authz' loaded 2018-10-31 16:48:07,944-05 FINE Config BEGIN 2018-10-31 16:48:07,944-05 FINE ovirt.engine.extension.provides: org.ovirt.engine.api.extensions.aaa.Authz 2018-10-31 16:48:07,945-05 FINE ovirt.engine.extension.binding.jbossmodule.class: org.ovirt.engine.extension.aaa.jdbc.binding.api.AuthzExtension 2018-10-31 16:48:07,945-05 FINE ovirt.engine.extension.bindings.method: jbossmodule 2018-10-31 16:48:07,945-05 FINE config.datasource.file: /etc/ovirt-engine/aaa/internal.properties 2018-10-31 16:48:07,945-05 FINE ovirt.engine.extension.name: internal-authz 2018-10-31 16:48:07,946-05 FINE ovirt.engine.extension.binding.jbossmodule.module: org.ovirt.engine.extension.aaa.jdbc 2018-10-31 16:48:07,946-05 FINE Config END 2018-10-31 16:48:07,946-05 FINE Loading extension file 'internal-authn.properties' 2018-10-31 16:48:07,947-05 INFO Loading extension 'internal-authn' 2018-10-31 16:48:07,947-05 FINEST Invoke Input BEGIN 2018-10-31 16:48:07,949-05 FINEST {Extkey[name=EXTENSION_INVOKE_COMMAND;type=class org.ovirt.engine.api.extensions.ExtUUID;uuid=EXTENSION_INVOKE_COMMAND[485778ab-bede-4f1a-b823-77b262a2f28d];]=EXTENSION_LOAD[b0f2460e-7971-4a9c-b4e1-c1db1362a47a], 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_GLOBAL_CONTEXT;type=class org.ovirt.engine.api.extensions.ExtMap;uuid=EXTENSION_GLOBAL_CONTEXT[9799e72f-7af6-4cf1-bf08-297bc8903676];]=*skip*, Extkey[name=EXTENSION_MANAGER_TRACE_LOG;type=interface org.slf4j.Logger;uuid=EXTENSION_MANAGER_TRACE_LOG[863db666-3ea7-4751-9695-918a3197ad83];]=org.slf4j.impl.JDK14LoggerAdapter(org.ovirt.engine.core.extensions.mgr.ExtensionsManager.trace), Extkey[name=EXTENSION_CONFIGURATION_SENSITIVE_KEYS;type=interface java.util.Collection;uuid=EXTENSION_CONFIGURATION_SENSITIVE_KEYS[a456efa1-73ff-4204-9f9b-ebff01e35263];]=[], 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_LOCALE;type=class java.lang.String;uuid=EXTENSION_LOCALE[0780b112-0ce0-404a-b85e-8765d778bb29];]=en_US, Extkey[name=EXTENSION_CONFIGURATION_FILE;type=class java.lang.String;uuid=EXTENSION_CONFIGURATION_FILE[4fb0ffd3-983c-4f3f-98ff-9660bd67af6a];]=/etc/ovirt-engine/extensions.d/internal-authn.properties, Extkey[name=EXTENSION_CONFIGURATION;type=class java.util.Properties;uuid=EXTENSION_CONFIGURATION[2d48ab72-f0a1-4312-b4ae-5068a226b0fc];]=***, 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_INTERFACE_VERSION_MIN;type=class java.lang.Integer;uuid=EXTENSION_INTERFACE_VERSION_MIN[2b84fc91-305b-497b-a1d7-d961b9d2ce0b];]=0, Extkey[name=EXTENSION_INSTANCE_NAME;type=class java.lang.String;uuid=EXTENSION_INSTANCE_NAME[65c67ff6-aeca-4bd5-a245-8674327f011b];]=internal-authn}} 2018-10-31 16:48:07,949-05 FINEST Invoke Input END 2018-10-31 16:48:07,950-05 FINEST Invoke Output BEGIN 2018-10-31 16:48:07,950-05 FINEST {Extkey[name=EXTENSION_INVOKE_RESULT;type=class java.lang.Integer;uuid=EXTENSION_INVOKE_RESULT[0909d91d-8bde-40fb-b6c0-099c772ddd4e];]=0} 2018-10-31 16:48:07,951-05 FINEST Invoke Output END 2018-10-31 16:48:07,951-05 INFO Extension 'internal-authn' loaded 2018-10-31 16:48:07,951-05 FINE Config BEGIN 2018-10-31 16:48:07,951-05 FINE ovirt.engine.aaa.authn.profile.name: internal 2018-10-31 16:48:07,952-05 FINE ovirt.engine.extension.provides: org.ovirt.engine.api.extensions.aaa.Authn 2018-10-31 16:48:07,952-05 FINE ovirt.engine.extension.binding.jbossmodule.class: org.ovirt.engine.extension.aaa.jdbc.binding.api.AuthnExtension 2018-10-31 16:48:07,952-05 FINE ovirt.engine.aaa.authn.authz.plugin: internal-authz 2018-10-31 16:48:07,952-05 FINE ovirt.engine.extension.bindings.method: jbossmodule 2018-10-31 16:48:07,953-05 FINE config.datasource.file: /etc/ovirt-engine/aaa/internal.properties 2018-10-31 16:48:07,953-05 FINE ovirt.engine.extension.name: internal-authn 2018-10-31 16:48:07,953-05 FINE ovirt.engine.extension.binding.jbossmodule.module: org.ovirt.engine.extension.aaa.jdbc 2018-10-31 16:48:07,953-05 FINE Config END 2018-10-31 16:48:07,953-05 FINE Loading extension file 'ldap.cyber-range.lan.properties' 2018-10-31 16:48:07,963-05 INFO Loading extension 'ldap.cyber-range.lan' 2018-10-31 16:48:07,963-05 FINEST Invoke Input BEGIN 2018-10-31 16:48:07,964-05 FINEST {Extkey[name=EXTENSION_INVOKE_COMMAND;type=class org.ovirt.engine.api.extensions.ExtUUID;uuid=EXTENSION_INVOKE_COMMAND[485778ab-bede-4f1a-b823-77b262a2f28d];]=EXTENSION_LOAD[b0f2460e-7971-4a9c-b4e1-c1db1362a47a], 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_GLOBAL_CONTEXT;type=class org.ovirt.engine.api.extensions.ExtMap;uuid=EXTENSION_GLOBAL_CONTEXT[9799e72f-7af6-4cf1-bf08-297bc8903676];]=*skip*, Extkey[name=EXTENSION_MANAGER_TRACE_LOG;type=interface org.slf4j.Logger;uuid=EXTENSION_MANAGER_TRACE_LOG[863db666-3ea7-4751-9695-918a3197ad83];]=org.slf4j.impl.JDK14LoggerAdapter(org.ovirt.engine.core.extensions.mgr.ExtensionsManager.trace), Extkey[name=EXTENSION_CONFIGURATION_SENSITIVE_KEYS;type=interface java.util.Collection;uuid=EXTENSION_CONFIGURATION_SENSITIVE_KEYS[a456efa1-73ff-4204-9f9b-ebff01e35263];]=[], 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_LOCALE;type=class java.lang.String;uuid=EXTENSION_LOCALE[0780b112-0ce0-404a-b85e-8765d778bb29];]=en_US, Extkey[name=EXTENSION_CONFIGURATION_FILE;type=class java.lang.String;uuid=EXTENSION_CONFIGURATION_FILE[4fb0ffd3-983c-4f3f-98ff-9660bd67af6a];]=/etc/ovirt-engine/extensions.d/ldap.cyber-range.lan.properties, Extkey[name=EXTENSION_CONFIGURATION;type=class java.util.Properties;uuid=EXTENSION_CONFIGURATION[2d48ab72-f0a1-4312-b4ae-5068a226b0fc];]=***, 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_INTERFACE_VERSION_MIN;type=class java.lang.Integer;uuid=EXTENSION_INTERFACE_VERSION_MIN[2b84fc91-305b-497b-a1d7-d961b9d2ce0b];]=0, Extkey[name=EXTENSION_INSTANCE_NAME;type=class java.lang.String;uuid=EXTENSION_INSTANCE_NAME[65c67ff6-aeca-4bd5-a245-8674327f011b];]=ldap.cyber-range.lan}} 2018-10-31 16:48:07,964-05 FINEST Invoke Input END 2018-10-31 16:48:08,022-05 FINE PROPERTIES-BEGIN 2018-10-31 16:48:08,025-05 FINE ._basedir=/etc/ovirt-engine/extensions.d/../aaa .attrmap.map-group-record.attr.GroupRecord_DISPLAY_NAME.map=description .attrmap.map-group-record.attr.GroupRecord_DN.map=_dn .attrmap.map-group-record.attr.GroupRecord_ID.map=nsuniqueid .attrmap.map-group-record.attr.GroupRecord_NAME.map=cn .attrmap.map-principal-record.attr.PrincipalRecord_DEPARTMENT.map=department .attrmap.map-principal-record.attr.PrincipalRecord_DISPLAY_NAME.map=displayName .attrmap.map-principal-record.attr.PrincipalRecord_DN.map=_dn .attrmap.map-principal-record.attr.PrincipalRecord_EMAIL.map=mail .attrmap.map-principal-record.attr.PrincipalRecord_FIRST_NAME.map=givenName .attrmap.map-principal-record.attr.PrincipalRecord_ID.map=nsuniqueid .attrmap.map-principal-record.attr.PrincipalRecord_LAST_NAME.map=sn .attrmap.map-principal-record.attr.PrincipalRecord_NAME.map=uid .attrmap.map-principal-record.attr.PrincipalRecord_PRINCIPAL.map=uid .attrmap.map-principal-record.attr.PrincipalRecord_TITLE.map=title .attrmap.simple-map-memberOf.attr.memberOf.map=${seq\:simple_attrMemberOf} .attrmap.simple-map-namespace.attr.namespace.map=${seq\:simple_attrsBaseDN} .auth-check.default.auth.gssapi.jAASClientName=oVirtKerb .auth-check.default.auth.gssapi.suppressedSystemProperties=java.security.krb5.kdc,\ java.security.krb5.realm,\ java.security.auth.login.config,\ javax.security.auth.useSubjectCredsOnly .auth-check.default.auth.type=simple .auth-check.default.diagnostic.mapping.AUTHORIZATION_DENIED=CREDENTIALS_INCORRECT .auth-check.default.diagnostic.mapping.AUTH_UNKNOWN=CREDENTIALS_INVALID .auth-check.default.diagnostic.mapping.CONNECT_ERROR=REMOTE_UNAVAILABLE .auth-check.default.diagnostic.mapping.CONSTRAINT_VIOLATION=ACCOUNT_RESTRICTION .auth-check.default.diagnostic.mapping.CONSTRAINT_VIOLATION.Account inactivity limit=ACCOUNT_EXPIRED .auth-check.default.diagnostic.mapping.CONSTRAINT_VIOLATION.Exceed password retry limit=ACCOUNT_LOCKED .auth-check.default.diagnostic.mapping.CONSTRAINT_VIOLATION.translation.pattern=^((?Exceed\ password\ retry\ limit).*)|((?Account\ inactivity\ limit).*)$ .auth-check.default.diagnostic.mapping.CONSTRAINT_VIOLATION.translation.replace=${text1}${text2} .auth-check.default.diagnostic.mapping.INAPPROPRIATE_AUTHENTICATION=CREDENTIALS_INVALID .auth-check.default.diagnostic.mapping.INVALID_CREDENTIALS=CREDENTIALS_INCORRECT .auth-check.default.diagnostic.mapping.LOCAL_ERROR.1=ACCOUNT_EXPIRED .auth-check.default.diagnostic.mapping.LOCAL_ERROR.12=ACCOUNT_RESTRICTION .auth-check.default.diagnostic.mapping.LOCAL_ERROR.18=ACCOUNT_LOCKED .auth-check.default.diagnostic.mapping.LOCAL_ERROR.23=CREDENTIALS_EXPIRED .auth-check.default.diagnostic.mapping.LOCAL_ERROR.24=CREDENTIALS_INCORRECT .auth-check.default.diagnostic.mapping.LOCAL_ERROR.6=CREDENTIALS_INVALID .auth-check.default.diagnostic.mapping.LOCAL_ERROR._comment=http\://www.rfc-editor.org/rfc/rfc1510.txt .auth-check.default.diagnostic.mapping.LOCAL_ERROR.translation.pattern=^javax\\.security\\.auth\\.login\\.LoginException\:.*\ \\((?[0-9]+)\\).*$ .auth-check.default.diagnostic.mapping.LOCAL_ERROR.translation.replace=${code} .auth-check.default.diagnostic.mapping.NO_SUCH_OBJECT=CREDENTIALS_INVALID .auth-check.default.diagnostic.mapping.PASSWORD_EXPIRED=CREDENTIALS_EXPIRED .auth-check.default.diagnostic.mapping.SERVER_DOWN=REMOTE_UNAVAILABLE .auth-check.default.diagnostic.mapping.SUCCESS=SUCCESS .auth-check.default.diagnostic.mapping.TIMEOUT=TIMED_OUT .auth-check.default.diagnostic.mapping.UNAVAILABLE=REMOTE_UNAVAILABLE .auth-check.default.diagnostic.mapping.UNWILLING_TO_PERFORM=ACCOUNT_DISABLED .auth-check.default.diagnostic.mapping.default=GENERAL_ERROR .auth-check.default.diagnostic.mapping.translation.pattern=^(.*)$ .auth-check.default.diagnostic.mapping.translation.replace=$1 .auth-check.default.password=*** .auth-check.default.pool=authn .auth-check.default.reuse-connections=true .auth-check.default.user=${seq\:_simple_bind_user} .auth-check.default.whoami.enable=true .include=<389ds.properties> .pool.authz.auth.type=simple .pool.default.auth.digest-md5.jAASClientName=oVirtKerb .pool.default.auth.gssapi.jAASClientName=oVirtKerb .pool.default.auth.gssapi.suppressedSystemProperties=java.security.krb5.kdc,\ java.security.krb5.realm,\ java.security.auth.login.config,\ javax.security.auth.useSubjectCredsOnly .pool.default.auth.simple.bindDN=uid\=admin,ou\=Administrators,ou\=TopologyManagement,o\=NetscapeRoot .pool.default.auth.simple.password=*** .pool.default.auth.type=none .pool.default.connection-options.abandonOnTimeout=true .pool.default.connection-options.allowConcurrentSocketFactoryUse=true .pool.default.connection-options.autoReconnect=true .pool.default.connection-options.connectTimeoutMillis=5000 .pool.default.connection-options.responseTimeoutMillis=60000 .pool.default.connection-pool.initialConnectThreads=1 .pool.default.connection-pool.initialConnections=4 .pool.default.connection-pool.maxConnectionAgeMillis=600000 .pool.default.connection-pool.maxConnections=20 .pool.default.dc-resolve.default.serverset.srvrecord.domain=${seq\:__dc_resolve_domain} .pool.default.dc-resolve.default.serverset.type=srvrecord .pool.default.dc-resolve.enable=false .pool.default.serverset.dns-round-robin.dnsRecordTypes=A .pool.default.serverset.dns-round-robin.selectionMode=RANDOM .pool.default.serverset.dns-round-robin.server=389 .pool.default.serverset.failover.port=389 .pool.default.serverset.fastest-connect.port=389 .pool.default.serverset.fewest-connections.port=389 .pool.default.serverset.single.port=389 .pool.default.serverset.single.server=ldap.cyber-range.lan .pool.default.serverset.srvrecord.domain-conversion.type=none .pool.default.serverset.srvrecord.protocol=tcp .pool.default.serverset.srvrecord.service=ldap .pool.default.serverset.type=single .pool.default.socketfactory.resolver.cacheTTL=10000 .pool.default.socketfactory.resolver.supportIPv6=false .pool.default.socketfactory.type=resolver .pool.default.ssl.enable=false .pool.default.ssl.host-name-verify.enable=true .pool.default.ssl.host-name-verify.wildcards=true .pool.default.ssl.insecure=false .pool.default.ssl.protocol=TLSv1.2 .pool.default.ssl.startTLS=true .pool.default.ssl.startTLSProtocol=TLSv1.2 .pool.default.ssl.truststore.file=/etc/ovirt-engine/extensions.d/../aaa/ldap.cyber-range.lan.jks .pool.default.ssl.truststore.password=*** .search.default.dc-resolve.enable=true .search.default.page-size=100 .search.default.paging=false .search.default.scope=SUB .search.simple-namespace.attrmap=simple-map-namespace .search.simple-namespace.pool=authz .search.simple-namespace.search-request.attributes=${seq\:simple_attrsBaseDN} .search.simple-namespace.search-request.baseDN= .search.simple-namespace.search-request.filter=&(objectClass\=*) .search.simple-namespace.search-request.scope=BASE .search.simple-query-groups.attrmap=map-group-record .search.simple-query-groups.pool=authz .search.simple-query-groups.search-request.attributes=${seq\:simple_attrsGroupRecord} .search.simple-query-groups.search-request.baseDN=${seq\:namespace} .search.simple-query-groups.search-request.filter=&${seq\:simple_filterGroupObject}${seq\:filter} .search.simple-query-principals.attrmap=map-principal-record .search.simple-query-principals.pool=authz .search.simple-query-principals.search-request.attributes=${seq\:simple_attrsPrincipalRecord} .search.simple-query-principals.search-request.baseDN=${seq\:namespace} .search.simple-query-principals.search-request.filter=&${seq\:simple_filterUserObject}${seq\:filter} .search.simple-resolve-groups-member.attrmap=map-group-record .search.simple-resolve-groups-member.pool=authz .search.simple-resolve-groups-member.search-request.attributes=${seq\:simple_attrsGroupRecord} .search.simple-resolve-groups-member.search-request.baseDN=${seq\:simple_baseDN} .search.simple-resolve-groups-member.search-request.filter=&${seq\:simple_filterGroupObject}(${seq\:simple_attrGroupMemberDN}\=${seq\:_simple_dn_encoded}) .search.simple-resolve-groups-memberOf.attrmap=simple-map-memberOf .search.simple-resolve-groups-memberOf.pool=authz .search.simple-resolve-groups-memberOf.search-request.attributes=${seq\:simple_attrMemberOf} .search.simple-resolve-groups-memberOf.search-request.baseDN=${seq\:_simple_dn} .search.simple-resolve-groups-memberOf.search-request.filter=&(objectClass\=*) .search.simple-resolve-groups-memberOf.search-request.scope=BASE .search.simple-resolve-groups-memberOf-item.attrmap=map-group-record .search.simple-resolve-groups-memberOf-item.pool=authz .search.simple-resolve-groups-memberOf-item.search-request.attributes=${seq\:simple_attrsGroupRecord} .search.simple-resolve-groups-memberOf-item.search-request.baseDN=${seq\:_simple_dn} .search.simple-resolve-groups-memberOf-item.search-request.filter=&(objectClass\=*) .search.simple-resolve-groups-memberOf-item.search-request.scope=BASE .search.simple-user-fetch.attrmap=map-principal-record .search.simple-user-fetch.pool=authz .search.simple-user-fetch.search-request.attributes=${seq\:simple_attrsPrincipalRecord} .search.simple-user-fetch.search-request.baseDN=${seq\:simple_baseDN} .search.simple-user-fetch.search-request.filter=&${seq\:simple_filterUserObject}(${seq\:simple_attrsUserName}\=${seq\:user_encoded}) .sensitive-keys.001=password .sequence.__init0.01.description=set\ sensitive\ keys .sequence.__init0.01.type=var-set .sequence.__init0.01.var-set.value=${seq\:sensitiveKeys},\ password,\ passwordNew .sequence.__init0.01.var-set.variable=sensitiveKeys .sequence.__init0.02.condition.not=true .sequence.__init0.02.condition.type=var-set .sequence.__init0.02.condition.var-set.variable=maxFilterSize .sequence.__init0.02.description=set\ max\ filter\ size .sequence.__init0.02.type=var-set .sequence.__init0.02.var-set.value=50 .sequence.__init0.02.var-set.variable=maxFilterSize .sequence.__init0.03.condition.not=true .sequence.__init0.03.condition.type=var-set .sequence.__init0.03.condition.var-set.variable=capability_recursiveGroupResolution .sequence.__init0.03.description=set\ default\ as\ not\ recursive\ group\ resolution .sequence.__init0.03.type=var-set .sequence.__init0.03.var-set.value=false .sequence.__init0.03.var-set.variable=capability_recursiveGroupResolution .sequence.__init0.04.condition.not=true .sequence.__init0.04.condition.type=var-set .sequence.__init0.04.condition.var-set.variable=capability_credentialsChange .sequence.__init0.04.description=set\ default\ as\ no\ credentials\ change .sequence.__init0.04.type=var-set .sequence.__init0.04.var-set.value=false .sequence.__init0.04.var-set.variable=capability_credentialsChange .sequence.a389ds-init-vars.010.description=set\ base\ dn .sequence.a389ds-init-vars.010.type=var-set .sequence.a389ds-init-vars.010.var-set.value=defaultNamingContext .sequence.a389ds-init-vars.010.var-set.variable=simple_attrsBaseDN .sequence.a389ds-init-vars.020.description=set\ user\ attribute .sequence.a389ds-init-vars.020.type=var-set .sequence.a389ds-init-vars.020.var-set.value=uid .sequence.a389ds-init-vars.020.var-set.variable=simple_attrsUserName .sequence.a389ds-init-vars.030.description=set\ principal\ record\ attributes .sequence.a389ds-init-vars.030.type=var-set .sequence.a389ds-init-vars.030.var-set.value=nsuniqueid,\ uid,\ cn,\ displayName,\ department,\ givenName,\ sn,\ title,\ mail .sequence.a389ds-init-vars.030.var-set.variable=simple_attrsPrincipalRecord .sequence.a389ds-init-vars.040.description=set\ user\ object\ filter .sequence.a389ds-init-vars.040.type=var-set .sequence.a389ds-init-vars.040.var-set.value=(objectClass\=organizationalPerson)(${seq\:simple_attrsUserName}\=*) .sequence.a389ds-init-vars.040.var-set.variable=simple_filterUserObject .sequence.a389ds-init-vars.050.description=set\ group\ record\ attributes .sequence.a389ds-init-vars.050.type=var-set .sequence.a389ds-init-vars.050.var-set.value=nsuniqueid,\ cn,\ description .sequence.a389ds-init-vars.050.var-set.variable=simple_attrsGroupRecord .sequence.a389ds-init-vars.060.description=set\ group\ object\ filter .sequence.a389ds-init-vars.060.type=var-set .sequence.a389ds-init-vars.060.var-set.value=(objectClass\=groupOfUniqueNames) .sequence.a389ds-init-vars.060.var-set.variable=simple_filterGroupObject .sequence.authn.stub.call.name=simple-authn .sequence.authn.stub.description=call\ simple .sequence.authn.stub.type=call .sequence.credentials-change.stub.call.name=simple-credentials-change .sequence.credentials-change.stub.description=call\ simple .sequence.credentials-change.stub.type=call .sequence.namespace.stub.call.name=simple-namespace .sequence.namespace.stub.description=call\ simple .sequence.namespace.stub.type=call .sequence.query-groups.stub.call.name=simple-query-groups .sequence.query-groups.stub.description=call\ simple .sequence.query-groups.stub.type=call .sequence.query-principals.stub.call.name=simple-query-principals .sequence.query-principals.stub.description=call\ simple .sequence.query-principals.stub.type=call .sequence.resolve-groups.stub.call.name=simple-resolve-groups .sequence.resolve-groups.stub.description=call\ simple .sequence.resolve-groups.stub.type=call .sequence.resolve-principal.stub.call.name=simple-resolve-principal .sequence.resolve-principal.stub.description=call\ simple .sequence.resolve-principal.stub.type=call .sequence.simple-authn.010.call.name=simple-resolve-user .sequence.simple-authn.010.description=resolve\ user .sequence.simple-authn.010.type=call .sequence.simple-authn.020.auth-check.name=simple-authn .sequence.simple-authn.020.description=auth\ check .sequence.simple-authn.020.type=auth-check .sequence.simple-credentials-change.010.call.name=simple-resolve-user .sequence.simple-credentials-change.010.description=resolve\ user .sequence.simple-credentials-change.010.type=call .sequence.simple-credentials-change.020.credentials-change.password.current=${seq\:password} .sequence.simple-credentials-change.020.credentials-change.password.new=${seq\:passwordNew} .sequence.simple-credentials-change.020.credentials-change.pool=authn .sequence.simple-credentials-change.020.credentials-change.user=${seq\:PrincipalRecord_DN} .sequence.simple-credentials-change.020.description=resolve\ user .sequence.simple-credentials-change.020.type=credentials-change .sequence.simple-init-vars.010.condition.not=true .sequence.simple-init-vars.010.condition.type=var-set .sequence.simple-init-vars.010.condition.var-set.variable=simple_bindFormat .sequence.simple-init-vars.010.description=set\ user\ format\:\ sam\ for\ basic,\ realm\ for\ sasl .sequence.simple-init-vars.010.type=var-set .sequence.simple-init-vars.010.var-set.value=dn .sequence.simple-init-vars.010.var-set.variable=simple_bindFormat .sequence.simple-init-vars.020.condition.not=true .sequence.simple-init-vars.020.condition.type=var-set .sequence.simple-init-vars.020.condition.var-set.variable=simple_groupLogic .sequence.simple-init-vars.020.description=set\ group\ logic .sequence.simple-init-vars.020.type=var-set .sequence.simple-init-vars.020.var-set.value=member .sequence.simple-init-vars.020.var-set.variable=simple_groupLogic .sequence.simple-init-vars.030.condition.not=true .sequence.simple-init-vars.030.condition.type=var-set .sequence.simple-init-vars.030.condition.var-set.variable=simple_attrGroupMemberDN .sequence.simple-init-vars.030.description=set\ group\ member\ attribute .sequence.simple-init-vars.030.type=var-set .sequence.simple-init-vars.030.var-set.value=uniqueMember\:uniqueMemberMatch\: .sequence.simple-init-vars.030.var-set.variable=simple_attrGroupMemberDN .sequence.simple-init-vars.040.condition.not=true .sequence.simple-init-vars.040.condition.type=var-set .sequence.simple-init-vars.040.condition.var-set.variable=simple_attrMemberOf .sequence.simple-init-vars.040.description=set\ object\ group\ membership\ attribute .sequence.simple-init-vars.040.type=var-set .sequence.simple-init-vars.040.var-set.value=memberOf .sequence.simple-init-vars.040.var-set.variable=simple_attrMemberOf .sequence.simple-init-vars.050.condition.not=true .sequence.simple-init-vars.050.condition.type=var-set .sequence.simple-init-vars.050.condition.var-set.variable=simple_attrsBaseDNIndex .sequence.simple-init-vars.050.description=set\ default\ index\ for\ baseDN .sequence.simple-init-vars.050.type=var-set .sequence.simple-init-vars.050.var-set.value=0 .sequence.simple-init-vars.050.var-set.variable=simple_attrsBaseDNIndex .sequence.simple-namespace.010.condition.type=var-set .sequence.simple-namespace.010.condition.var-set.variable=simple_namespaceDefault .sequence.simple-namespace.010.description=set\ default\ namespace .sequence.simple-namespace.010.type=var-set .sequence.simple-namespace.010.var-set.value=${seq\:simple_namespaceDefault} .sequence.simple-namespace.010.var-set.variable=namespaceDefault .sequence.simple-namespace.020.description=namespace\ search .sequence.simple-namespace.020.type=var-list-set .sequence.simple-namespace.020.var-list-set.values.01.value=${seq\:simple_baseDN} .sequence.simple-namespace.020.var-list-set.variable=namespaces .sequence.simple-open-pools.010.description=create\ authz\ pool .sequence.simple-open-pools.010.pool-create.name=authz .sequence.simple-open-pools.010.type=pool-create .sequence.simple-open-pools.020.condition.type=var-set .sequence.simple-open-pools.020.condition.var-set.variable=authn_enable .sequence.simple-open-pools.020.description=create\ authn\ pool .sequence.simple-open-pools.020.pool-create.name=authn .sequence.simple-open-pools.020.type=pool-create .sequence.simple-open-vars.010.condition.not=true .sequence.simple-open-vars.010.condition.type=var-set .sequence.simple-open-vars.010.condition.var-set.variable=simple_baseDN .sequence.simple-open-vars.010.description=set\ base\ DN .sequence.simple-open-vars.010.fetch-record.map.namespace.name=simple_baseDN .sequence.simple-open-vars.010.fetch-record.map.namespace.select=${seq\:simple_attrsBaseDNIndex} .sequence.simple-open-vars.010.fetch-record.search=simple-namespace .sequence.simple-open-vars.010.type=fetch-record .sequence.simple-query-groups.010.description=query\ groups .sequence.simple-query-groups.010.search-open.search=simple-query-groups .sequence.simple-query-groups.010.search-open.variable=query .sequence.simple-query-groups.010.type=search-open .sequence.simple-query-principals.010.description=principals\ search .sequence.simple-query-principals.010.search-open.search=simple-query-principals .sequence.simple-query-principals.010.search-open.variable=query .sequence.simple-query-principals.010.type=search-open .sequence.simple-resolve-groups.001.call.name=simple-resolve-groups-${seq\:simple_groupLogic} .sequence.simple-resolve-groups.001.description=call\ simple\ ${seq\:simple_groupLogic} .sequence.simple-resolve-groups.001.type=call .sequence.simple-resolve-groups-member.010.description=set\ dn .sequence.simple-resolve-groups-member.010.type=var-set .sequence.simple-resolve-groups-member.010.var-set.value=${seq\:dn} .sequence.simple-resolve-groups-member.010.var-set.variable=_simple_dn .sequence.simple-resolve-groups-member.020.description=query\ groups .sequence.simple-resolve-groups-member.020.search-open.search=simple-resolve-groups-member .sequence.simple-resolve-groups-member.020.search-open.variable=querySimpleByDN .sequence.simple-resolve-groups-member.020.type=search-open .sequence.simple-resolve-groups-memberOf.010.description=set\ dn .sequence.simple-resolve-groups-memberOf.010.type=var-set .sequence.simple-resolve-groups-memberOf.010.var-set.value=${seq\:dn} .sequence.simple-resolve-groups-memberOf.010.var-set.variable=_simple_dn .sequence.simple-resolve-groups-memberOf.020.description=enum\ groups .sequence.simple-resolve-groups-memberOf.020.fetch-record.map.memberOf.name=_simple_memberOf .sequence.simple-resolve-groups-memberOf.020.fetch-record.map.memberOf.select=-1 .sequence.simple-resolve-groups-memberOf.020.fetch-record.search=simple-resolve-groups-memberOf .sequence.simple-resolve-groups-memberOf.020.type=fetch-record .sequence.simple-resolve-groups-memberOf.030.description=iterate\ groups\ and\ fetch .sequence.simple-resolve-groups-memberOf.030.for-each.sequence=simple-resolve-groups-memberOf-item .sequence.simple-resolve-groups-memberOf.030.for-each.var-value=_simple_dn .sequence.simple-resolve-groups-memberOf.030.for-each.variable=_simple_memberOf .sequence.simple-resolve-groups-memberOf.030.type=for-each .sequence.simple-resolve-groups-memberOf-item.010.description=resolve\ group .sequence.simple-resolve-groups-memberOf-item.010.search-open.search=simple-resolve-groups-memberOf-item .sequence.simple-resolve-groups-memberOf-item.010.search-open.variable=querySimpleByDN${seq\:forEachIndex} .sequence.simple-resolve-groups-memberOf-item.010.type=search-open .sequence.simple-resolve-principal.010.description=copy\ principal .sequence.simple-resolve-principal.010.type=var-set .sequence.simple-resolve-principal.010.var-set.value=${seq\:PrincipalRecord_PRINCIPAL} .sequence.simple-resolve-principal.010.var-set.variable=user .sequence.simple-resolve-principal.020.search-open.search=simple-user-fetch .sequence.simple-resolve-principal.020.search-open.variable=query .sequence.simple-resolve-principal.020.type=search-open .sequence.simple-resolve-user.010.description=resolve\ user .sequence.simple-resolve-user.010.fetch-record.map.PrincipalRecord_DN.name=PrincipalRecord_DN .sequence.simple-resolve-user.010.fetch-record.map.PrincipalRecord_PRINCIPAL.name=PrincipalRecord_PRINCIPAL .sequence.simple-resolve-user.010.fetch-record.search=simple-user-fetch .sequence.simple-resolve-user.010.type=fetch-record .sequence.simple-resolve-user.020.call.name=simple-resolve-user-error .sequence.simple-resolve-user.020.condition.not=true .sequence.simple-resolve-user.020.condition.type=var-set .sequence.simple-resolve-user.020.condition.var-set.variable=PrincipalRecord_DN .sequence.simple-resolve-user.020.description=no\ user? .sequence.simple-resolve-user.020.type=call .sequence.simple-resolve-user.030.call.name=${seq\:simple_principalPostFetch} .sequence.simple-resolve-user.030.condition.type=var-set .sequence.simple-resolve-user.030.condition.var-set.variable=simple_principalPostFetch .sequence.simple-resolve-user.030.description=post-fetch\ validation .sequence.simple-resolve-user.030.type=call .sequence.simple-resolve-user.040-01.condition.compare.left=${seq\:simple_bindFormat} .sequence.simple-resolve-user.040-01.condition.compare.right=dn .sequence.simple-resolve-user.040-01.condition.type=compare .sequence.simple-resolve-user.040-01.description=set\ bind\ user .sequence.simple-resolve-user.040-01.type=var-set .sequence.simple-resolve-user.040-01.var-set.value=${seq\:PrincipalRecord_DN} .sequence.simple-resolve-user.040-01.var-set.variable=_simple_bind_user .sequence.simple-resolve-user.040-02.condition.compare.left=${seq\:simple_bindFormat} .sequence.simple-resolve-user.040-02.condition.compare.right=realm .sequence.simple-resolve-user.040-02.condition.type=compare .sequence.simple-resolve-user.040-02.description=set\ bind\ user .sequence.simple-resolve-user.040-02.type=var-set .sequence.simple-resolve-user.040-02.var-set.value=${seq\:user} .sequence.simple-resolve-user.040-02.var-set.variable=_simple_bind_user .sequence.simple-resolve-user-error.010.description=error .sequence.simple-resolve-user-error.010.type=var-set .sequence.simple-resolve-user-error.010.var-set.value=INVALID_CREDENTIALS .sequence.simple-resolve-user-error.010.var-set.variable=resultCode .sequence.simple-resolve-user-error.020.description=error .sequence.simple-resolve-user-error.020.type=var-set .sequence.simple-resolve-user-error.020.var-set.value=CREDENTIALS_INVALID .sequence.simple-resolve-user-error.020.var-set.variable=authTranslatedMessage .sequence.simple-resolve-user-error.030.description=stop .sequence.simple-resolve-user-error.030.type=stop .sequence-init.init.0000=__init0 .sequence-init.init.500-simple-init-vars=simple-init-vars .sequence-init.init.600-a389ds-init-vars=a389ds-init-vars .sequence-init.open.500-simple-open-pools-authz=simple-open-pools .sequence-init.open.900-simple-open-vars=simple-open-vars .stats.interval=60000 .vars.password=*** .vars.server=ldap.cyber-range.lan .vars.user=uid\=admin,ou\=Administrators,ou\=TopologyManagement,o\=NetscapeRoot 2018-10-31 16:48:08,026-05 FINE PROPERTIES-END 2018-10-31 16:48:08,026-05 FINE init Entry 2018-10-31 16:48:08,027-05 FINE runSequence Entry name='__init0' 2018-10-31 16:48:08,027-05 FINE Running sequence __init0/01/var-set set sensitive keys 2018-10-31 16:48:08,028-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,028-05 FINEST authz_enable = 1 2018-10-31 16:48:08,028-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,028-05 FINEST stop = false 2018-10-31 16:48:08,028-05 FINEST VARS-END 2018-10-31 16:48:08,028-05 FINE End sequence __init0 set sensitive keys 2018-10-31 16:48:08,029-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,029-05 FINEST authz_enable = 1 2018-10-31 16:48:08,029-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,029-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,029-05 FINEST stop = false 2018-10-31 16:48:08,030-05 FINEST VARS-END 2018-10-31 16:48:08,030-05 FINE Running sequence __init0/02/var-set set max filter size 2018-10-31 16:48:08,030-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,030-05 FINEST authz_enable = 1 2018-10-31 16:48:08,030-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,031-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,031-05 FINEST stop = false 2018-10-31 16:48:08,031-05 FINEST VARS-END 2018-10-31 16:48:08,031-05 FINE End sequence __init0 set max filter size 2018-10-31 16:48:08,031-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,032-05 FINEST authz_enable = 1 2018-10-31 16:48:08,032-05 FINEST maxFilterSize = 50 2018-10-31 16:48:08,032-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,032-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,032-05 FINEST stop = false 2018-10-31 16:48:08,032-05 FINEST VARS-END 2018-10-31 16:48:08,033-05 FINE Running sequence __init0/03/var-set set default as not recursive group resolution 2018-10-31 16:48:08,033-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,033-05 FINEST authz_enable = 1 2018-10-31 16:48:08,033-05 FINEST maxFilterSize = 50 2018-10-31 16:48:08,034-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,034-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,034-05 FINEST stop = false 2018-10-31 16:48:08,034-05 FINEST VARS-END 2018-10-31 16:48:08,038-05 FINE End sequence __init0 set default as not recursive group resolution 2018-10-31 16:48:08,038-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,038-05 FINEST authz_enable = 1 2018-10-31 16:48:08,038-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:08,039-05 FINEST maxFilterSize = 50 2018-10-31 16:48:08,039-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,039-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,039-05 FINEST stop = false 2018-10-31 16:48:08,039-05 FINEST VARS-END 2018-10-31 16:48:08,040-05 FINE Running sequence __init0/04/var-set set default as no credentials change 2018-10-31 16:48:08,040-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,040-05 FINEST authz_enable = 1 2018-10-31 16:48:08,040-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:08,040-05 FINEST maxFilterSize = 50 2018-10-31 16:48:08,040-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,041-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,041-05 FINEST stop = false 2018-10-31 16:48:08,041-05 FINEST VARS-END 2018-10-31 16:48:08,041-05 FINE End sequence __init0 set default as no credentials change 2018-10-31 16:48:08,041-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,042-05 FINEST authz_enable = 1 2018-10-31 16:48:08,042-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:08,042-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:08,042-05 FINEST maxFilterSize = 50 2018-10-31 16:48:08,042-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,042-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,042-05 FINEST stop = false 2018-10-31 16:48:08,043-05 FINEST VARS-END 2018-10-31 16:48:08,043-05 FINE runSequence Return name='__init0' 2018-10-31 16:48:08,043-05 FINE runSequence Entry name='simple-init-vars' 2018-10-31 16:48:08,043-05 FINE Running sequence simple-init-vars/010/var-set set user format: sam for basic, realm for sasl 2018-10-31 16:48:08,044-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,044-05 FINEST authz_enable = 1 2018-10-31 16:48:08,045-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:08,045-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:08,045-05 FINEST maxFilterSize = 50 2018-10-31 16:48:08,045-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,045-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,045-05 FINEST stop = false 2018-10-31 16:48:08,046-05 FINEST VARS-END 2018-10-31 16:48:08,046-05 FINE End sequence simple-init-vars set user format: sam for basic, realm for sasl 2018-10-31 16:48:08,046-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,046-05 FINEST authz_enable = 1 2018-10-31 16:48:08,046-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:08,047-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:08,047-05 FINEST maxFilterSize = 50 2018-10-31 16:48:08,047-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,047-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,047-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:08,047-05 FINEST stop = false 2018-10-31 16:48:08,048-05 FINEST VARS-END 2018-10-31 16:48:08,048-05 FINE Running sequence simple-init-vars/020/var-set set group logic 2018-10-31 16:48:08,048-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,048-05 FINEST authz_enable = 1 2018-10-31 16:48:08,048-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:08,049-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:08,049-05 FINEST maxFilterSize = 50 2018-10-31 16:48:08,049-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,049-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,049-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:08,049-05 FINEST stop = false 2018-10-31 16:48:08,049-05 FINEST VARS-END 2018-10-31 16:48:08,050-05 FINE End sequence simple-init-vars set group logic 2018-10-31 16:48:08,050-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,050-05 FINEST authz_enable = 1 2018-10-31 16:48:08,050-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:08,050-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:08,050-05 FINEST maxFilterSize = 50 2018-10-31 16:48:08,051-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,051-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,051-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:08,051-05 FINEST simple_groupLogic = member 2018-10-31 16:48:08,051-05 FINEST stop = false 2018-10-31 16:48:08,051-05 FINEST VARS-END 2018-10-31 16:48:08,052-05 FINE Running sequence simple-init-vars/030/var-set set group member attribute 2018-10-31 16:48:08,052-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,052-05 FINEST authz_enable = 1 2018-10-31 16:48:08,052-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:08,052-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:08,052-05 FINEST maxFilterSize = 50 2018-10-31 16:48:08,053-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,053-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,053-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:08,053-05 FINEST simple_groupLogic = member 2018-10-31 16:48:08,053-05 FINEST stop = false 2018-10-31 16:48:08,054-05 FINEST VARS-END 2018-10-31 16:48:08,054-05 FINE End sequence simple-init-vars set group member attribute 2018-10-31 16:48:08,054-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,054-05 FINEST authz_enable = 1 2018-10-31 16:48:08,054-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:08,054-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:08,055-05 FINEST maxFilterSize = 50 2018-10-31 16:48:08,055-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,055-05 FINEST simple_attrGroupMemberDN = uniqueMember:uniqueMemberMatch: 2018-10-31 16:48:08,055-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,055-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:08,055-05 FINEST simple_groupLogic = member 2018-10-31 16:48:08,055-05 FINEST stop = false 2018-10-31 16:48:08,056-05 FINEST VARS-END 2018-10-31 16:48:08,056-05 FINE Running sequence simple-init-vars/040/var-set set object group membership attribute 2018-10-31 16:48:08,056-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,056-05 FINEST authz_enable = 1 2018-10-31 16:48:08,056-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:08,057-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:08,057-05 FINEST maxFilterSize = 50 2018-10-31 16:48:08,057-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,057-05 FINEST simple_attrGroupMemberDN = uniqueMember:uniqueMemberMatch: 2018-10-31 16:48:08,057-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,057-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:08,057-05 FINEST simple_groupLogic = member 2018-10-31 16:48:08,058-05 FINEST stop = false 2018-10-31 16:48:08,058-05 FINEST VARS-END 2018-10-31 16:48:08,058-05 FINE End sequence simple-init-vars set object group membership attribute 2018-10-31 16:48:08,058-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,058-05 FINEST authz_enable = 1 2018-10-31 16:48:08,058-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:08,059-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:08,059-05 FINEST maxFilterSize = 50 2018-10-31 16:48:08,059-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,059-05 FINEST simple_attrGroupMemberDN = uniqueMember:uniqueMemberMatch: 2018-10-31 16:48:08,059-05 FINEST simple_attrMemberOf = memberOf 2018-10-31 16:48:08,059-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,059-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:08,060-05 FINEST simple_groupLogic = member 2018-10-31 16:48:08,060-05 FINEST stop = false 2018-10-31 16:48:08,060-05 FINEST VARS-END 2018-10-31 16:48:08,060-05 FINE Running sequence simple-init-vars/050/var-set set default index for baseDN 2018-10-31 16:48:08,060-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,061-05 FINEST authz_enable = 1 2018-10-31 16:48:08,061-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:08,061-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:08,061-05 FINEST maxFilterSize = 50 2018-10-31 16:48:08,061-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,061-05 FINEST simple_attrGroupMemberDN = uniqueMember:uniqueMemberMatch: 2018-10-31 16:48:08,061-05 FINEST simple_attrMemberOf = memberOf 2018-10-31 16:48:08,062-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,062-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:08,062-05 FINEST simple_groupLogic = member 2018-10-31 16:48:08,062-05 FINEST stop = false 2018-10-31 16:48:08,062-05 FINEST VARS-END 2018-10-31 16:48:08,062-05 FINE End sequence simple-init-vars set default index for baseDN 2018-10-31 16:48:08,062-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,063-05 FINEST authz_enable = 1 2018-10-31 16:48:08,063-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:08,063-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:08,063-05 FINEST maxFilterSize = 50 2018-10-31 16:48:08,063-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,063-05 FINEST simple_attrGroupMemberDN = uniqueMember:uniqueMemberMatch: 2018-10-31 16:48:08,063-05 FINEST simple_attrMemberOf = memberOf 2018-10-31 16:48:08,063-05 FINEST simple_attrsBaseDNIndex = 0 2018-10-31 16:48:08,064-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,064-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:08,064-05 FINEST simple_groupLogic = member 2018-10-31 16:48:08,064-05 FINEST stop = false 2018-10-31 16:48:08,064-05 FINEST VARS-END 2018-10-31 16:48:08,064-05 FINE runSequence Return name='simple-init-vars' 2018-10-31 16:48:08,064-05 FINE runSequence Entry name='a389ds-init-vars' 2018-10-31 16:48:08,065-05 FINE Running sequence a389ds-init-vars/010/var-set set base dn 2018-10-31 16:48:08,065-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,065-05 FINEST authz_enable = 1 2018-10-31 16:48:08,065-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:08,065-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:08,065-05 FINEST maxFilterSize = 50 2018-10-31 16:48:08,066-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,066-05 FINEST simple_attrGroupMemberDN = uniqueMember:uniqueMemberMatch: 2018-10-31 16:48:08,066-05 FINEST simple_attrMemberOf = memberOf 2018-10-31 16:48:08,066-05 FINEST simple_attrsBaseDNIndex = 0 2018-10-31 16:48:08,066-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,066-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:08,067-05 FINEST simple_groupLogic = member 2018-10-31 16:48:08,067-05 FINEST stop = false 2018-10-31 16:48:08,067-05 FINEST VARS-END 2018-10-31 16:48:08,067-05 FINE End sequence a389ds-init-vars set base dn 2018-10-31 16:48:08,067-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,068-05 FINEST authz_enable = 1 2018-10-31 16:48:08,068-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:08,068-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:08,068-05 FINEST maxFilterSize = 50 2018-10-31 16:48:08,068-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,068-05 FINEST simple_attrGroupMemberDN = uniqueMember:uniqueMemberMatch: 2018-10-31 16:48:08,068-05 FINEST simple_attrMemberOf = memberOf 2018-10-31 16:48:08,068-05 FINEST simple_attrsBaseDN = defaultNamingContext 2018-10-31 16:48:08,069-05 FINEST simple_attrsBaseDNIndex = 0 2018-10-31 16:48:08,069-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,069-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:08,069-05 FINEST simple_groupLogic = member 2018-10-31 16:48:08,069-05 FINEST stop = false 2018-10-31 16:48:08,069-05 FINEST VARS-END 2018-10-31 16:48:08,069-05 FINE Running sequence a389ds-init-vars/020/var-set set user attribute 2018-10-31 16:48:08,070-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,070-05 FINEST authz_enable = 1 2018-10-31 16:48:08,070-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:08,070-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:08,070-05 FINEST maxFilterSize = 50 2018-10-31 16:48:08,070-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,070-05 FINEST simple_attrGroupMemberDN = uniqueMember:uniqueMemberMatch: 2018-10-31 16:48:08,070-05 FINEST simple_attrMemberOf = memberOf 2018-10-31 16:48:08,071-05 FINEST simple_attrsBaseDN = defaultNamingContext 2018-10-31 16:48:08,071-05 FINEST simple_attrsBaseDNIndex = 0 2018-10-31 16:48:08,071-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,071-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:08,071-05 FINEST simple_groupLogic = member 2018-10-31 16:48:08,071-05 FINEST stop = false 2018-10-31 16:48:08,071-05 FINEST VARS-END 2018-10-31 16:48:08,071-05 FINE End sequence a389ds-init-vars set user attribute 2018-10-31 16:48:08,072-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,072-05 FINEST authz_enable = 1 2018-10-31 16:48:08,072-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:08,072-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:08,072-05 FINEST maxFilterSize = 50 2018-10-31 16:48:08,072-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,072-05 FINEST simple_attrGroupMemberDN = uniqueMember:uniqueMemberMatch: 2018-10-31 16:48:08,072-05 FINEST simple_attrMemberOf = memberOf 2018-10-31 16:48:08,073-05 FINEST simple_attrsBaseDN = defaultNamingContext 2018-10-31 16:48:08,073-05 FINEST simple_attrsBaseDNIndex = 0 2018-10-31 16:48:08,073-05 FINEST simple_attrsUserName = uid 2018-10-31 16:48:08,073-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,073-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:08,073-05 FINEST simple_groupLogic = member 2018-10-31 16:48:08,073-05 FINEST stop = false 2018-10-31 16:48:08,073-05 FINEST VARS-END 2018-10-31 16:48:08,074-05 FINE Running sequence a389ds-init-vars/030/var-set set principal record attributes 2018-10-31 16:48:08,074-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,074-05 FINEST authz_enable = 1 2018-10-31 16:48:08,074-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:08,074-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:08,074-05 FINEST maxFilterSize = 50 2018-10-31 16:48:08,074-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,074-05 FINEST simple_attrGroupMemberDN = uniqueMember:uniqueMemberMatch: 2018-10-31 16:48:08,075-05 FINEST simple_attrMemberOf = memberOf 2018-10-31 16:48:08,075-05 FINEST simple_attrsBaseDN = defaultNamingContext 2018-10-31 16:48:08,075-05 FINEST simple_attrsBaseDNIndex = 0 2018-10-31 16:48:08,075-05 FINEST simple_attrsUserName = uid 2018-10-31 16:48:08,075-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,075-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:08,075-05 FINEST simple_groupLogic = member 2018-10-31 16:48:08,075-05 FINEST stop = false 2018-10-31 16:48:08,075-05 FINEST VARS-END 2018-10-31 16:48:08,076-05 FINE End sequence a389ds-init-vars set principal record attributes 2018-10-31 16:48:08,076-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,076-05 FINEST authz_enable = 1 2018-10-31 16:48:08,076-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:08,076-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:08,076-05 FINEST maxFilterSize = 50 2018-10-31 16:48:08,076-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,076-05 FINEST simple_attrGroupMemberDN = uniqueMember:uniqueMemberMatch: 2018-10-31 16:48:08,077-05 FINEST simple_attrMemberOf = memberOf 2018-10-31 16:48:08,077-05 FINEST simple_attrsBaseDN = defaultNamingContext 2018-10-31 16:48:08,077-05 FINEST simple_attrsBaseDNIndex = 0 2018-10-31 16:48:08,077-05 FINEST simple_attrsPrincipalRecord = nsuniqueid, uid, cn, displayName, department, givenName, sn, title, mail 2018-10-31 16:48:08,077-05 FINEST simple_attrsUserName = uid 2018-10-31 16:48:08,077-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,077-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:08,077-05 FINEST simple_groupLogic = member 2018-10-31 16:48:08,077-05 FINEST stop = false 2018-10-31 16:48:08,078-05 FINEST VARS-END 2018-10-31 16:48:08,078-05 FINE Running sequence a389ds-init-vars/040/var-set set user object filter 2018-10-31 16:48:08,078-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,078-05 FINEST authz_enable = 1 2018-10-31 16:48:08,078-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:08,079-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:08,079-05 FINEST maxFilterSize = 50 2018-10-31 16:48:08,079-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,079-05 FINEST simple_attrGroupMemberDN = uniqueMember:uniqueMemberMatch: 2018-10-31 16:48:08,079-05 FINEST simple_attrMemberOf = memberOf 2018-10-31 16:48:08,079-05 FINEST simple_attrsBaseDN = defaultNamingContext 2018-10-31 16:48:08,079-05 FINEST simple_attrsBaseDNIndex = 0 2018-10-31 16:48:08,079-05 FINEST simple_attrsPrincipalRecord = nsuniqueid, uid, cn, displayName, department, givenName, sn, title, mail 2018-10-31 16:48:08,079-05 FINEST simple_attrsUserName = uid 2018-10-31 16:48:08,079-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,080-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:08,080-05 FINEST simple_groupLogic = member 2018-10-31 16:48:08,080-05 FINEST stop = false 2018-10-31 16:48:08,080-05 FINEST VARS-END 2018-10-31 16:48:08,080-05 FINE End sequence a389ds-init-vars set user object filter 2018-10-31 16:48:08,080-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,080-05 FINEST authz_enable = 1 2018-10-31 16:48:08,080-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:08,081-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:08,081-05 FINEST maxFilterSize = 50 2018-10-31 16:48:08,081-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,081-05 FINEST simple_attrGroupMemberDN = uniqueMember:uniqueMemberMatch: 2018-10-31 16:48:08,081-05 FINEST simple_attrMemberOf = memberOf 2018-10-31 16:48:08,081-05 FINEST simple_attrsBaseDN = defaultNamingContext 2018-10-31 16:48:08,081-05 FINEST simple_attrsBaseDNIndex = 0 2018-10-31 16:48:08,081-05 FINEST simple_attrsPrincipalRecord = nsuniqueid, uid, cn, displayName, department, givenName, sn, title, mail 2018-10-31 16:48:08,081-05 FINEST simple_attrsUserName = uid 2018-10-31 16:48:08,082-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,082-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:08,082-05 FINEST simple_filterUserObject = (objectClass=organizationalPerson)(uid=*) 2018-10-31 16:48:08,082-05 FINEST simple_groupLogic = member 2018-10-31 16:48:08,082-05 FINEST stop = false 2018-10-31 16:48:08,082-05 FINEST VARS-END 2018-10-31 16:48:08,082-05 FINE Running sequence a389ds-init-vars/050/var-set set group record attributes 2018-10-31 16:48:08,083-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,083-05 FINEST authz_enable = 1 2018-10-31 16:48:08,083-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:08,083-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:08,083-05 FINEST maxFilterSize = 50 2018-10-31 16:48:08,083-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,083-05 FINEST simple_attrGroupMemberDN = uniqueMember:uniqueMemberMatch: 2018-10-31 16:48:08,083-05 FINEST simple_attrMemberOf = memberOf 2018-10-31 16:48:08,083-05 FINEST simple_attrsBaseDN = defaultNamingContext 2018-10-31 16:48:08,084-05 FINEST simple_attrsBaseDNIndex = 0 2018-10-31 16:48:08,084-05 FINEST simple_attrsPrincipalRecord = nsuniqueid, uid, cn, displayName, department, givenName, sn, title, mail 2018-10-31 16:48:08,084-05 FINEST simple_attrsUserName = uid 2018-10-31 16:48:08,084-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,084-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:08,084-05 FINEST simple_filterUserObject = (objectClass=organizationalPerson)(uid=*) 2018-10-31 16:48:08,084-05 FINEST simple_groupLogic = member 2018-10-31 16:48:08,084-05 FINEST stop = false 2018-10-31 16:48:08,084-05 FINEST VARS-END 2018-10-31 16:48:08,085-05 FINE End sequence a389ds-init-vars set group record attributes 2018-10-31 16:48:08,085-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,085-05 FINEST authz_enable = 1 2018-10-31 16:48:08,085-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:08,085-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:08,085-05 FINEST maxFilterSize = 50 2018-10-31 16:48:08,085-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,085-05 FINEST simple_attrGroupMemberDN = uniqueMember:uniqueMemberMatch: 2018-10-31 16:48:08,085-05 FINEST simple_attrMemberOf = memberOf 2018-10-31 16:48:08,086-05 FINEST simple_attrsBaseDN = defaultNamingContext 2018-10-31 16:48:08,086-05 FINEST simple_attrsBaseDNIndex = 0 2018-10-31 16:48:08,086-05 FINEST simple_attrsGroupRecord = nsuniqueid, cn, description 2018-10-31 16:48:08,086-05 FINEST simple_attrsPrincipalRecord = nsuniqueid, uid, cn, displayName, department, givenName, sn, title, mail 2018-10-31 16:48:08,086-05 FINEST simple_attrsUserName = uid 2018-10-31 16:48:08,086-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,086-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:08,086-05 FINEST simple_filterUserObject = (objectClass=organizationalPerson)(uid=*) 2018-10-31 16:48:08,086-05 FINEST simple_groupLogic = member 2018-10-31 16:48:08,087-05 FINEST stop = false 2018-10-31 16:48:08,087-05 FINEST VARS-END 2018-10-31 16:48:08,087-05 FINE Running sequence a389ds-init-vars/060/var-set set group object filter 2018-10-31 16:48:08,088-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,088-05 FINEST authz_enable = 1 2018-10-31 16:48:08,088-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:08,088-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:08,088-05 FINEST maxFilterSize = 50 2018-10-31 16:48:08,088-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,088-05 FINEST simple_attrGroupMemberDN = uniqueMember:uniqueMemberMatch: 2018-10-31 16:48:08,089-05 FINEST simple_attrMemberOf = memberOf 2018-10-31 16:48:08,089-05 FINEST simple_attrsBaseDN = defaultNamingContext 2018-10-31 16:48:08,089-05 FINEST simple_attrsBaseDNIndex = 0 2018-10-31 16:48:08,089-05 FINEST simple_attrsGroupRecord = nsuniqueid, cn, description 2018-10-31 16:48:08,089-05 FINEST simple_attrsPrincipalRecord = nsuniqueid, uid, cn, displayName, department, givenName, sn, title, mail 2018-10-31 16:48:08,089-05 FINEST simple_attrsUserName = uid 2018-10-31 16:48:08,089-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,089-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:08,089-05 FINEST simple_filterUserObject = (objectClass=organizationalPerson)(uid=*) 2018-10-31 16:48:08,090-05 FINEST simple_groupLogic = member 2018-10-31 16:48:08,090-05 FINEST stop = false 2018-10-31 16:48:08,090-05 FINEST VARS-END 2018-10-31 16:48:08,090-05 FINE End sequence a389ds-init-vars set group object filter 2018-10-31 16:48:08,090-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,090-05 FINEST authz_enable = 1 2018-10-31 16:48:08,090-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:08,090-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:08,091-05 FINEST maxFilterSize = 50 2018-10-31 16:48:08,091-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,091-05 FINEST simple_attrGroupMemberDN = uniqueMember:uniqueMemberMatch: 2018-10-31 16:48:08,091-05 FINEST simple_attrMemberOf = memberOf 2018-10-31 16:48:08,091-05 FINEST simple_attrsBaseDN = defaultNamingContext 2018-10-31 16:48:08,091-05 FINEST simple_attrsBaseDNIndex = 0 2018-10-31 16:48:08,091-05 FINEST simple_attrsGroupRecord = nsuniqueid, cn, description 2018-10-31 16:48:08,091-05 FINEST simple_attrsPrincipalRecord = nsuniqueid, uid, cn, displayName, department, givenName, sn, title, mail 2018-10-31 16:48:08,091-05 FINEST simple_attrsUserName = uid 2018-10-31 16:48:08,091-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,092-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:08,092-05 FINEST simple_filterGroupObject = (objectClass=groupOfUniqueNames) 2018-10-31 16:48:08,092-05 FINEST simple_filterUserObject = (objectClass=organizationalPerson)(uid=*) 2018-10-31 16:48:08,092-05 FINEST simple_groupLogic = member 2018-10-31 16:48:08,092-05 FINEST stop = false 2018-10-31 16:48:08,092-05 FINEST VARS-END 2018-10-31 16:48:08,092-05 FINE runSequence Return name='a389ds-init-vars' 2018-10-31 16:48:08,092-05 FINE init Return globals={sensitiveKeys=, password, passwordNew, simple_attrMemberOf=memberOf, simple_filterGroupObject=(objectClass=groupOfUniqueNames), simple_attrsGroupRecord=nsuniqueid, cn, description, simple_filterUserObject=(objectClass=organizationalPerson)(uid=*), authz_enable=1, simple_baseDN=dc=cyber-range,dc=lan, maxFilterSize=50, capability_recursiveGroupResolution=false, simple_attrsUserName=uid, stop=false, simple_attrGroupMemberDN=uniqueMember:uniqueMemberMatch:, simple_attrsBaseDNIndex=0, simple_bindFormat=dn, simple_attrsPrincipalRecord=nsuniqueid, uid, cn, displayName, department, givenName, sn, title, mail, capability_credentialsChange=false, simple_groupLogic=member, simple_attrsBaseDN=defaultNamingContext} 2018-10-31 16:48:08,093-05 FINEST Invoke Output BEGIN 2018-10-31 16:48:08,093-05 FINEST {Extkey[name=EXTENSION_INVOKE_RESULT;type=class java.lang.Integer;uuid=EXTENSION_INVOKE_RESULT[0909d91d-8bde-40fb-b6c0-099c772ddd4e];]=0} 2018-10-31 16:48:08,093-05 FINEST Invoke Output END 2018-10-31 16:48:08,093-05 INFO Extension 'ldap.cyber-range.lan' loaded 2018-10-31 16:48:08,093-05 FINE Config BEGIN 2018-10-31 16:48:08,093-05 FINE ovirt.engine.extension.provides: org.ovirt.engine.api.extensions.aaa.Authz 2018-10-31 16:48:08,093-05 FINE ovirt.engine.extension.binding.jbossmodule.class: org.ovirt.engineextensions.aaa.ldap.AuthzExtension 2018-10-31 16:48:08,094-05 FINE ovirt.engine.extension.bindings.method: jbossmodule 2018-10-31 16:48:08,094-05 FINE ovirt.engine.extension.name: ldap.cyber-range.lan 2018-10-31 16:48:08,094-05 FINE config.globals.baseDN.simple_baseDN: dc=cyber-range,dc=lan 2018-10-31 16:48:08,094-05 FINE config.profile.file.1: ../aaa/ldap.cyber-range.lan.properties 2018-10-31 16:48:08,094-05 FINE ovirt.engine.extension.binding.jbossmodule.module: org.ovirt.engine-extensions.aaa.ldap 2018-10-31 16:48:08,094-05 FINE Config END 2018-10-31 16:48:08,094-05 FINE Loading extension file 'ldap.cyber-range.lan-authn.properties' 2018-10-31 16:48:08,095-05 INFO Loading extension 'ldap.cyber-range.lan-authn' 2018-10-31 16:48:08,095-05 FINEST Invoke Input BEGIN 2018-10-31 16:48:08,096-05 FINEST {Extkey[name=EXTENSION_INVOKE_COMMAND;type=class org.ovirt.engine.api.extensions.ExtUUID;uuid=EXTENSION_INVOKE_COMMAND[485778ab-bede-4f1a-b823-77b262a2f28d];]=EXTENSION_LOAD[b0f2460e-7971-4a9c-b4e1-c1db1362a47a], 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_GLOBAL_CONTEXT;type=class org.ovirt.engine.api.extensions.ExtMap;uuid=EXTENSION_GLOBAL_CONTEXT[9799e72f-7af6-4cf1-bf08-297bc8903676];]=*skip*, Extkey[name=EXTENSION_MANAGER_TRACE_LOG;type=interface org.slf4j.Logger;uuid=EXTENSION_MANAGER_TRACE_LOG[863db666-3ea7-4751-9695-918a3197ad83];]=org.slf4j.impl.JDK14LoggerAdapter(org.ovirt.engine.core.extensions.mgr.ExtensionsManager.trace), Extkey[name=EXTENSION_CONFIGURATION_SENSITIVE_KEYS;type=interface java.util.Collection;uuid=EXTENSION_CONFIGURATION_SENSITIVE_KEYS[a456efa1-73ff-4204-9f9b-ebff01e35263];]=[], 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_LOCALE;type=class java.lang.String;uuid=EXTENSION_LOCALE[0780b112-0ce0-404a-b85e-8765d778bb29];]=en_US, Extkey[name=EXTENSION_CONFIGURATION_FILE;type=class java.lang.String;uuid=EXTENSION_CONFIGURATION_FILE[4fb0ffd3-983c-4f3f-98ff-9660bd67af6a];]=/etc/ovirt-engine/extensions.d/ldap.cyber-range.lan-authn.properties, Extkey[name=EXTENSION_CONFIGURATION;type=class java.util.Properties;uuid=EXTENSION_CONFIGURATION[2d48ab72-f0a1-4312-b4ae-5068a226b0fc];]=***, 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_INTERFACE_VERSION_MIN;type=class java.lang.Integer;uuid=EXTENSION_INTERFACE_VERSION_MIN[2b84fc91-305b-497b-a1d7-d961b9d2ce0b];]=0, Extkey[name=EXTENSION_INSTANCE_NAME;type=class java.lang.String;uuid=EXTENSION_INSTANCE_NAME[65c67ff6-aeca-4bd5-a245-8674327f011b];]=ldap.cyber-range.lan-authn}} 2018-10-31 16:48:08,096-05 FINEST Invoke Input END 2018-10-31 16:48:08,102-05 FINE PROPERTIES-BEGIN 2018-10-31 16:48:08,104-05 FINE ._basedir=/etc/ovirt-engine/extensions.d/../aaa .attrmap.map-group-record.attr.GroupRecord_DISPLAY_NAME.map=description .attrmap.map-group-record.attr.GroupRecord_DN.map=_dn .attrmap.map-group-record.attr.GroupRecord_ID.map=nsuniqueid .attrmap.map-group-record.attr.GroupRecord_NAME.map=cn .attrmap.map-principal-record.attr.PrincipalRecord_DEPARTMENT.map=department .attrmap.map-principal-record.attr.PrincipalRecord_DISPLAY_NAME.map=displayName .attrmap.map-principal-record.attr.PrincipalRecord_DN.map=_dn .attrmap.map-principal-record.attr.PrincipalRecord_EMAIL.map=mail .attrmap.map-principal-record.attr.PrincipalRecord_FIRST_NAME.map=givenName .attrmap.map-principal-record.attr.PrincipalRecord_ID.map=nsuniqueid .attrmap.map-principal-record.attr.PrincipalRecord_LAST_NAME.map=sn .attrmap.map-principal-record.attr.PrincipalRecord_NAME.map=uid .attrmap.map-principal-record.attr.PrincipalRecord_PRINCIPAL.map=uid .attrmap.map-principal-record.attr.PrincipalRecord_TITLE.map=title .attrmap.simple-map-memberOf.attr.memberOf.map=${seq\:simple_attrMemberOf} .attrmap.simple-map-namespace.attr.namespace.map=${seq\:simple_attrsBaseDN} .auth-check.default.auth.gssapi.jAASClientName=oVirtKerb .auth-check.default.auth.gssapi.suppressedSystemProperties=java.security.krb5.kdc,\ java.security.krb5.realm,\ java.security.auth.login.config,\ javax.security.auth.useSubjectCredsOnly .auth-check.default.auth.type=simple .auth-check.default.diagnostic.mapping.AUTHORIZATION_DENIED=CREDENTIALS_INCORRECT .auth-check.default.diagnostic.mapping.AUTH_UNKNOWN=CREDENTIALS_INVALID .auth-check.default.diagnostic.mapping.CONNECT_ERROR=REMOTE_UNAVAILABLE .auth-check.default.diagnostic.mapping.CONSTRAINT_VIOLATION=ACCOUNT_RESTRICTION .auth-check.default.diagnostic.mapping.CONSTRAINT_VIOLATION.Account inactivity limit=ACCOUNT_EXPIRED .auth-check.default.diagnostic.mapping.CONSTRAINT_VIOLATION.Exceed password retry limit=ACCOUNT_LOCKED .auth-check.default.diagnostic.mapping.CONSTRAINT_VIOLATION.translation.pattern=^((?Exceed\ password\ retry\ limit).*)|((?Account\ inactivity\ limit).*)$ .auth-check.default.diagnostic.mapping.CONSTRAINT_VIOLATION.translation.replace=${text1}${text2} .auth-check.default.diagnostic.mapping.INAPPROPRIATE_AUTHENTICATION=CREDENTIALS_INVALID .auth-check.default.diagnostic.mapping.INVALID_CREDENTIALS=CREDENTIALS_INCORRECT .auth-check.default.diagnostic.mapping.LOCAL_ERROR.1=ACCOUNT_EXPIRED .auth-check.default.diagnostic.mapping.LOCAL_ERROR.12=ACCOUNT_RESTRICTION .auth-check.default.diagnostic.mapping.LOCAL_ERROR.18=ACCOUNT_LOCKED .auth-check.default.diagnostic.mapping.LOCAL_ERROR.23=CREDENTIALS_EXPIRED .auth-check.default.diagnostic.mapping.LOCAL_ERROR.24=CREDENTIALS_INCORRECT .auth-check.default.diagnostic.mapping.LOCAL_ERROR.6=CREDENTIALS_INVALID .auth-check.default.diagnostic.mapping.LOCAL_ERROR._comment=http\://www.rfc-editor.org/rfc/rfc1510.txt .auth-check.default.diagnostic.mapping.LOCAL_ERROR.translation.pattern=^javax\\.security\\.auth\\.login\\.LoginException\:.*\ \\((?[0-9]+)\\).*$ .auth-check.default.diagnostic.mapping.LOCAL_ERROR.translation.replace=${code} .auth-check.default.diagnostic.mapping.NO_SUCH_OBJECT=CREDENTIALS_INVALID .auth-check.default.diagnostic.mapping.PASSWORD_EXPIRED=CREDENTIALS_EXPIRED .auth-check.default.diagnostic.mapping.SERVER_DOWN=REMOTE_UNAVAILABLE .auth-check.default.diagnostic.mapping.SUCCESS=SUCCESS .auth-check.default.diagnostic.mapping.TIMEOUT=TIMED_OUT .auth-check.default.diagnostic.mapping.UNAVAILABLE=REMOTE_UNAVAILABLE .auth-check.default.diagnostic.mapping.UNWILLING_TO_PERFORM=ACCOUNT_DISABLED .auth-check.default.diagnostic.mapping.default=GENERAL_ERROR .auth-check.default.diagnostic.mapping.translation.pattern=^(.*)$ .auth-check.default.diagnostic.mapping.translation.replace=$1 .auth-check.default.password=*** .auth-check.default.pool=authn .auth-check.default.reuse-connections=true .auth-check.default.user=${seq\:_simple_bind_user} .auth-check.default.whoami.enable=true .include=<389ds.properties> .pool.authz.auth.type=simple .pool.default.auth.digest-md5.jAASClientName=oVirtKerb .pool.default.auth.gssapi.jAASClientName=oVirtKerb .pool.default.auth.gssapi.suppressedSystemProperties=java.security.krb5.kdc,\ java.security.krb5.realm,\ java.security.auth.login.config,\ javax.security.auth.useSubjectCredsOnly .pool.default.auth.simple.bindDN=uid\=admin,ou\=Administrators,ou\=TopologyManagement,o\=NetscapeRoot .pool.default.auth.simple.password=*** .pool.default.auth.type=none .pool.default.connection-options.abandonOnTimeout=true .pool.default.connection-options.allowConcurrentSocketFactoryUse=true .pool.default.connection-options.autoReconnect=true .pool.default.connection-options.connectTimeoutMillis=5000 .pool.default.connection-options.responseTimeoutMillis=60000 .pool.default.connection-pool.initialConnectThreads=1 .pool.default.connection-pool.initialConnections=4 .pool.default.connection-pool.maxConnectionAgeMillis=600000 .pool.default.connection-pool.maxConnections=20 .pool.default.dc-resolve.default.serverset.srvrecord.domain=${seq\:__dc_resolve_domain} .pool.default.dc-resolve.default.serverset.type=srvrecord .pool.default.dc-resolve.enable=false .pool.default.serverset.dns-round-robin.dnsRecordTypes=A .pool.default.serverset.dns-round-robin.selectionMode=RANDOM .pool.default.serverset.dns-round-robin.server=389 .pool.default.serverset.failover.port=389 .pool.default.serverset.fastest-connect.port=389 .pool.default.serverset.fewest-connections.port=389 .pool.default.serverset.single.port=389 .pool.default.serverset.single.server=ldap.cyber-range.lan .pool.default.serverset.srvrecord.domain-conversion.type=none .pool.default.serverset.srvrecord.protocol=tcp .pool.default.serverset.srvrecord.service=ldap .pool.default.serverset.type=single .pool.default.socketfactory.resolver.cacheTTL=10000 .pool.default.socketfactory.resolver.supportIPv6=false .pool.default.socketfactory.type=resolver .pool.default.ssl.enable=false .pool.default.ssl.host-name-verify.enable=true .pool.default.ssl.host-name-verify.wildcards=true .pool.default.ssl.insecure=false .pool.default.ssl.protocol=TLSv1.2 .pool.default.ssl.startTLS=true .pool.default.ssl.startTLSProtocol=TLSv1.2 .pool.default.ssl.truststore.file=/etc/ovirt-engine/extensions.d/../aaa/ldap.cyber-range.lan.jks .pool.default.ssl.truststore.password=*** .search.default.dc-resolve.enable=true .search.default.page-size=100 .search.default.paging=false .search.default.scope=SUB .search.simple-namespace.attrmap=simple-map-namespace .search.simple-namespace.pool=authz .search.simple-namespace.search-request.attributes=${seq\:simple_attrsBaseDN} .search.simple-namespace.search-request.baseDN= .search.simple-namespace.search-request.filter=&(objectClass\=*) .search.simple-namespace.search-request.scope=BASE .search.simple-query-groups.attrmap=map-group-record .search.simple-query-groups.pool=authz .search.simple-query-groups.search-request.attributes=${seq\:simple_attrsGroupRecord} .search.simple-query-groups.search-request.baseDN=${seq\:namespace} .search.simple-query-groups.search-request.filter=&${seq\:simple_filterGroupObject}${seq\:filter} .search.simple-query-principals.attrmap=map-principal-record .search.simple-query-principals.pool=authz .search.simple-query-principals.search-request.attributes=${seq\:simple_attrsPrincipalRecord} .search.simple-query-principals.search-request.baseDN=${seq\:namespace} .search.simple-query-principals.search-request.filter=&${seq\:simple_filterUserObject}${seq\:filter} .search.simple-resolve-groups-member.attrmap=map-group-record .search.simple-resolve-groups-member.pool=authz .search.simple-resolve-groups-member.search-request.attributes=${seq\:simple_attrsGroupRecord} .search.simple-resolve-groups-member.search-request.baseDN=${seq\:simple_baseDN} .search.simple-resolve-groups-member.search-request.filter=&${seq\:simple_filterGroupObject}(${seq\:simple_attrGroupMemberDN}\=${seq\:_simple_dn_encoded}) .search.simple-resolve-groups-memberOf.attrmap=simple-map-memberOf .search.simple-resolve-groups-memberOf.pool=authz .search.simple-resolve-groups-memberOf.search-request.attributes=${seq\:simple_attrMemberOf} .search.simple-resolve-groups-memberOf.search-request.baseDN=${seq\:_simple_dn} .search.simple-resolve-groups-memberOf.search-request.filter=&(objectClass\=*) .search.simple-resolve-groups-memberOf.search-request.scope=BASE .search.simple-resolve-groups-memberOf-item.attrmap=map-group-record .search.simple-resolve-groups-memberOf-item.pool=authz .search.simple-resolve-groups-memberOf-item.search-request.attributes=${seq\:simple_attrsGroupRecord} .search.simple-resolve-groups-memberOf-item.search-request.baseDN=${seq\:_simple_dn} .search.simple-resolve-groups-memberOf-item.search-request.filter=&(objectClass\=*) .search.simple-resolve-groups-memberOf-item.search-request.scope=BASE .search.simple-user-fetch.attrmap=map-principal-record .search.simple-user-fetch.pool=authz .search.simple-user-fetch.search-request.attributes=${seq\:simple_attrsPrincipalRecord} .search.simple-user-fetch.search-request.baseDN=${seq\:simple_baseDN} .search.simple-user-fetch.search-request.filter=&${seq\:simple_filterUserObject}(${seq\:simple_attrsUserName}\=${seq\:user_encoded}) .sensitive-keys.001=password .sequence.__init0.01.description=set\ sensitive\ keys .sequence.__init0.01.type=var-set .sequence.__init0.01.var-set.value=${seq\:sensitiveKeys},\ password,\ passwordNew .sequence.__init0.01.var-set.variable=sensitiveKeys .sequence.__init0.02.condition.not=true .sequence.__init0.02.condition.type=var-set .sequence.__init0.02.condition.var-set.variable=maxFilterSize .sequence.__init0.02.description=set\ max\ filter\ size .sequence.__init0.02.type=var-set .sequence.__init0.02.var-set.value=50 .sequence.__init0.02.var-set.variable=maxFilterSize .sequence.__init0.03.condition.not=true .sequence.__init0.03.condition.type=var-set .sequence.__init0.03.condition.var-set.variable=capability_recursiveGroupResolution .sequence.__init0.03.description=set\ default\ as\ not\ recursive\ group\ resolution .sequence.__init0.03.type=var-set .sequence.__init0.03.var-set.value=false .sequence.__init0.03.var-set.variable=capability_recursiveGroupResolution .sequence.__init0.04.condition.not=true .sequence.__init0.04.condition.type=var-set .sequence.__init0.04.condition.var-set.variable=capability_credentialsChange .sequence.__init0.04.description=set\ default\ as\ no\ credentials\ change .sequence.__init0.04.type=var-set .sequence.__init0.04.var-set.value=false .sequence.__init0.04.var-set.variable=capability_credentialsChange .sequence.a389ds-init-vars.010.description=set\ base\ dn .sequence.a389ds-init-vars.010.type=var-set .sequence.a389ds-init-vars.010.var-set.value=defaultNamingContext .sequence.a389ds-init-vars.010.var-set.variable=simple_attrsBaseDN .sequence.a389ds-init-vars.020.description=set\ user\ attribute .sequence.a389ds-init-vars.020.type=var-set .sequence.a389ds-init-vars.020.var-set.value=uid .sequence.a389ds-init-vars.020.var-set.variable=simple_attrsUserName .sequence.a389ds-init-vars.030.description=set\ principal\ record\ attributes .sequence.a389ds-init-vars.030.type=var-set .sequence.a389ds-init-vars.030.var-set.value=nsuniqueid,\ uid,\ cn,\ displayName,\ department,\ givenName,\ sn,\ title,\ mail .sequence.a389ds-init-vars.030.var-set.variable=simple_attrsPrincipalRecord .sequence.a389ds-init-vars.040.description=set\ user\ object\ filter .sequence.a389ds-init-vars.040.type=var-set .sequence.a389ds-init-vars.040.var-set.value=(objectClass\=organizationalPerson)(${seq\:simple_attrsUserName}\=*) .sequence.a389ds-init-vars.040.var-set.variable=simple_filterUserObject .sequence.a389ds-init-vars.050.description=set\ group\ record\ attributes .sequence.a389ds-init-vars.050.type=var-set .sequence.a389ds-init-vars.050.var-set.value=nsuniqueid,\ cn,\ description .sequence.a389ds-init-vars.050.var-set.variable=simple_attrsGroupRecord .sequence.a389ds-init-vars.060.description=set\ group\ object\ filter .sequence.a389ds-init-vars.060.type=var-set .sequence.a389ds-init-vars.060.var-set.value=(objectClass\=groupOfUniqueNames) .sequence.a389ds-init-vars.060.var-set.variable=simple_filterGroupObject .sequence.authn.stub.call.name=simple-authn .sequence.authn.stub.description=call\ simple .sequence.authn.stub.type=call .sequence.credentials-change.stub.call.name=simple-credentials-change .sequence.credentials-change.stub.description=call\ simple .sequence.credentials-change.stub.type=call .sequence.namespace.stub.call.name=simple-namespace .sequence.namespace.stub.description=call\ simple .sequence.namespace.stub.type=call .sequence.query-groups.stub.call.name=simple-query-groups .sequence.query-groups.stub.description=call\ simple .sequence.query-groups.stub.type=call .sequence.query-principals.stub.call.name=simple-query-principals .sequence.query-principals.stub.description=call\ simple .sequence.query-principals.stub.type=call .sequence.resolve-groups.stub.call.name=simple-resolve-groups .sequence.resolve-groups.stub.description=call\ simple .sequence.resolve-groups.stub.type=call .sequence.resolve-principal.stub.call.name=simple-resolve-principal .sequence.resolve-principal.stub.description=call\ simple .sequence.resolve-principal.stub.type=call .sequence.simple-authn.010.call.name=simple-resolve-user .sequence.simple-authn.010.description=resolve\ user .sequence.simple-authn.010.type=call .sequence.simple-authn.020.auth-check.name=simple-authn .sequence.simple-authn.020.description=auth\ check .sequence.simple-authn.020.type=auth-check .sequence.simple-credentials-change.010.call.name=simple-resolve-user .sequence.simple-credentials-change.010.description=resolve\ user .sequence.simple-credentials-change.010.type=call .sequence.simple-credentials-change.020.credentials-change.password.current=${seq\:password} .sequence.simple-credentials-change.020.credentials-change.password.new=${seq\:passwordNew} .sequence.simple-credentials-change.020.credentials-change.pool=authn .sequence.simple-credentials-change.020.credentials-change.user=${seq\:PrincipalRecord_DN} .sequence.simple-credentials-change.020.description=resolve\ user .sequence.simple-credentials-change.020.type=credentials-change .sequence.simple-init-vars.010.condition.not=true .sequence.simple-init-vars.010.condition.type=var-set .sequence.simple-init-vars.010.condition.var-set.variable=simple_bindFormat .sequence.simple-init-vars.010.description=set\ user\ format\:\ sam\ for\ basic,\ realm\ for\ sasl .sequence.simple-init-vars.010.type=var-set .sequence.simple-init-vars.010.var-set.value=dn .sequence.simple-init-vars.010.var-set.variable=simple_bindFormat .sequence.simple-init-vars.020.condition.not=true .sequence.simple-init-vars.020.condition.type=var-set .sequence.simple-init-vars.020.condition.var-set.variable=simple_groupLogic .sequence.simple-init-vars.020.description=set\ group\ logic .sequence.simple-init-vars.020.type=var-set .sequence.simple-init-vars.020.var-set.value=member .sequence.simple-init-vars.020.var-set.variable=simple_groupLogic .sequence.simple-init-vars.030.condition.not=true .sequence.simple-init-vars.030.condition.type=var-set .sequence.simple-init-vars.030.condition.var-set.variable=simple_attrGroupMemberDN .sequence.simple-init-vars.030.description=set\ group\ member\ attribute .sequence.simple-init-vars.030.type=var-set .sequence.simple-init-vars.030.var-set.value=uniqueMember\:uniqueMemberMatch\: .sequence.simple-init-vars.030.var-set.variable=simple_attrGroupMemberDN .sequence.simple-init-vars.040.condition.not=true .sequence.simple-init-vars.040.condition.type=var-set .sequence.simple-init-vars.040.condition.var-set.variable=simple_attrMemberOf .sequence.simple-init-vars.040.description=set\ object\ group\ membership\ attribute .sequence.simple-init-vars.040.type=var-set .sequence.simple-init-vars.040.var-set.value=memberOf .sequence.simple-init-vars.040.var-set.variable=simple_attrMemberOf .sequence.simple-init-vars.050.condition.not=true .sequence.simple-init-vars.050.condition.type=var-set .sequence.simple-init-vars.050.condition.var-set.variable=simple_attrsBaseDNIndex .sequence.simple-init-vars.050.description=set\ default\ index\ for\ baseDN .sequence.simple-init-vars.050.type=var-set .sequence.simple-init-vars.050.var-set.value=0 .sequence.simple-init-vars.050.var-set.variable=simple_attrsBaseDNIndex .sequence.simple-namespace.010.condition.type=var-set .sequence.simple-namespace.010.condition.var-set.variable=simple_namespaceDefault .sequence.simple-namespace.010.description=set\ default\ namespace .sequence.simple-namespace.010.type=var-set .sequence.simple-namespace.010.var-set.value=${seq\:simple_namespaceDefault} .sequence.simple-namespace.010.var-set.variable=namespaceDefault .sequence.simple-namespace.020.description=namespace\ search .sequence.simple-namespace.020.type=var-list-set .sequence.simple-namespace.020.var-list-set.values.01.value=${seq\:simple_baseDN} .sequence.simple-namespace.020.var-list-set.variable=namespaces .sequence.simple-open-pools.010.description=create\ authz\ pool .sequence.simple-open-pools.010.pool-create.name=authz .sequence.simple-open-pools.010.type=pool-create .sequence.simple-open-pools.020.condition.type=var-set .sequence.simple-open-pools.020.condition.var-set.variable=authn_enable .sequence.simple-open-pools.020.description=create\ authn\ pool .sequence.simple-open-pools.020.pool-create.name=authn .sequence.simple-open-pools.020.type=pool-create .sequence.simple-open-vars.010.condition.not=true .sequence.simple-open-vars.010.condition.type=var-set .sequence.simple-open-vars.010.condition.var-set.variable=simple_baseDN .sequence.simple-open-vars.010.description=set\ base\ DN .sequence.simple-open-vars.010.fetch-record.map.namespace.name=simple_baseDN .sequence.simple-open-vars.010.fetch-record.map.namespace.select=${seq\:simple_attrsBaseDNIndex} .sequence.simple-open-vars.010.fetch-record.search=simple-namespace .sequence.simple-open-vars.010.type=fetch-record .sequence.simple-query-groups.010.description=query\ groups .sequence.simple-query-groups.010.search-open.search=simple-query-groups .sequence.simple-query-groups.010.search-open.variable=query .sequence.simple-query-groups.010.type=search-open .sequence.simple-query-principals.010.description=principals\ search .sequence.simple-query-principals.010.search-open.search=simple-query-principals .sequence.simple-query-principals.010.search-open.variable=query .sequence.simple-query-principals.010.type=search-open .sequence.simple-resolve-groups.001.call.name=simple-resolve-groups-${seq\:simple_groupLogic} .sequence.simple-resolve-groups.001.description=call\ simple\ ${seq\:simple_groupLogic} .sequence.simple-resolve-groups.001.type=call .sequence.simple-resolve-groups-member.010.description=set\ dn .sequence.simple-resolve-groups-member.010.type=var-set .sequence.simple-resolve-groups-member.010.var-set.value=${seq\:dn} .sequence.simple-resolve-groups-member.010.var-set.variable=_simple_dn .sequence.simple-resolve-groups-member.020.description=query\ groups .sequence.simple-resolve-groups-member.020.search-open.search=simple-resolve-groups-member .sequence.simple-resolve-groups-member.020.search-open.variable=querySimpleByDN .sequence.simple-resolve-groups-member.020.type=search-open .sequence.simple-resolve-groups-memberOf.010.description=set\ dn .sequence.simple-resolve-groups-memberOf.010.type=var-set .sequence.simple-resolve-groups-memberOf.010.var-set.value=${seq\:dn} .sequence.simple-resolve-groups-memberOf.010.var-set.variable=_simple_dn .sequence.simple-resolve-groups-memberOf.020.description=enum\ groups .sequence.simple-resolve-groups-memberOf.020.fetch-record.map.memberOf.name=_simple_memberOf .sequence.simple-resolve-groups-memberOf.020.fetch-record.map.memberOf.select=-1 .sequence.simple-resolve-groups-memberOf.020.fetch-record.search=simple-resolve-groups-memberOf .sequence.simple-resolve-groups-memberOf.020.type=fetch-record .sequence.simple-resolve-groups-memberOf.030.description=iterate\ groups\ and\ fetch .sequence.simple-resolve-groups-memberOf.030.for-each.sequence=simple-resolve-groups-memberOf-item .sequence.simple-resolve-groups-memberOf.030.for-each.var-value=_simple_dn .sequence.simple-resolve-groups-memberOf.030.for-each.variable=_simple_memberOf .sequence.simple-resolve-groups-memberOf.030.type=for-each .sequence.simple-resolve-groups-memberOf-item.010.description=resolve\ group .sequence.simple-resolve-groups-memberOf-item.010.search-open.search=simple-resolve-groups-memberOf-item .sequence.simple-resolve-groups-memberOf-item.010.search-open.variable=querySimpleByDN${seq\:forEachIndex} .sequence.simple-resolve-groups-memberOf-item.010.type=search-open .sequence.simple-resolve-principal.010.description=copy\ principal .sequence.simple-resolve-principal.010.type=var-set .sequence.simple-resolve-principal.010.var-set.value=${seq\:PrincipalRecord_PRINCIPAL} .sequence.simple-resolve-principal.010.var-set.variable=user .sequence.simple-resolve-principal.020.search-open.search=simple-user-fetch .sequence.simple-resolve-principal.020.search-open.variable=query .sequence.simple-resolve-principal.020.type=search-open .sequence.simple-resolve-user.010.description=resolve\ user .sequence.simple-resolve-user.010.fetch-record.map.PrincipalRecord_DN.name=PrincipalRecord_DN .sequence.simple-resolve-user.010.fetch-record.map.PrincipalRecord_PRINCIPAL.name=PrincipalRecord_PRINCIPAL .sequence.simple-resolve-user.010.fetch-record.search=simple-user-fetch .sequence.simple-resolve-user.010.type=fetch-record .sequence.simple-resolve-user.020.call.name=simple-resolve-user-error .sequence.simple-resolve-user.020.condition.not=true .sequence.simple-resolve-user.020.condition.type=var-set .sequence.simple-resolve-user.020.condition.var-set.variable=PrincipalRecord_DN .sequence.simple-resolve-user.020.description=no\ user? .sequence.simple-resolve-user.020.type=call .sequence.simple-resolve-user.030.call.name=${seq\:simple_principalPostFetch} .sequence.simple-resolve-user.030.condition.type=var-set .sequence.simple-resolve-user.030.condition.var-set.variable=simple_principalPostFetch .sequence.simple-resolve-user.030.description=post-fetch\ validation .sequence.simple-resolve-user.030.type=call .sequence.simple-resolve-user.040-01.condition.compare.left=${seq\:simple_bindFormat} .sequence.simple-resolve-user.040-01.condition.compare.right=dn .sequence.simple-resolve-user.040-01.condition.type=compare .sequence.simple-resolve-user.040-01.description=set\ bind\ user .sequence.simple-resolve-user.040-01.type=var-set .sequence.simple-resolve-user.040-01.var-set.value=${seq\:PrincipalRecord_DN} .sequence.simple-resolve-user.040-01.var-set.variable=_simple_bind_user .sequence.simple-resolve-user.040-02.condition.compare.left=${seq\:simple_bindFormat} .sequence.simple-resolve-user.040-02.condition.compare.right=realm .sequence.simple-resolve-user.040-02.condition.type=compare .sequence.simple-resolve-user.040-02.description=set\ bind\ user .sequence.simple-resolve-user.040-02.type=var-set .sequence.simple-resolve-user.040-02.var-set.value=${seq\:user} .sequence.simple-resolve-user.040-02.var-set.variable=_simple_bind_user .sequence.simple-resolve-user-error.010.description=error .sequence.simple-resolve-user-error.010.type=var-set .sequence.simple-resolve-user-error.010.var-set.value=INVALID_CREDENTIALS .sequence.simple-resolve-user-error.010.var-set.variable=resultCode .sequence.simple-resolve-user-error.020.description=error .sequence.simple-resolve-user-error.020.type=var-set .sequence.simple-resolve-user-error.020.var-set.value=CREDENTIALS_INVALID .sequence.simple-resolve-user-error.020.var-set.variable=authTranslatedMessage .sequence.simple-resolve-user-error.030.description=stop .sequence.simple-resolve-user-error.030.type=stop .sequence-init.init.0000=__init0 .sequence-init.init.500-simple-init-vars=simple-init-vars .sequence-init.init.600-a389ds-init-vars=a389ds-init-vars .sequence-init.open.500-simple-open-pools-authz=simple-open-pools .sequence-init.open.900-simple-open-vars=simple-open-vars .stats.interval=60000 .vars.password=*** .vars.server=ldap.cyber-range.lan .vars.user=uid\=admin,ou\=Administrators,ou\=TopologyManagement,o\=NetscapeRoot 2018-10-31 16:48:08,104-05 FINE PROPERTIES-END 2018-10-31 16:48:08,104-05 FINE init Entry 2018-10-31 16:48:08,104-05 FINE runSequence Entry name='__init0' 2018-10-31 16:48:08,105-05 FINE Running sequence __init0/01/var-set set sensitive keys 2018-10-31 16:48:08,105-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,105-05 FINEST authn_enable = 1 2018-10-31 16:48:08,105-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,105-05 FINEST stop = false 2018-10-31 16:48:08,105-05 FINEST VARS-END 2018-10-31 16:48:08,105-05 FINE End sequence __init0 set sensitive keys 2018-10-31 16:48:08,106-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,106-05 FINEST authn_enable = 1 2018-10-31 16:48:08,106-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,106-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,106-05 FINEST stop = false 2018-10-31 16:48:08,106-05 FINEST VARS-END 2018-10-31 16:48:08,106-05 FINE Running sequence __init0/02/var-set set max filter size 2018-10-31 16:48:08,107-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,107-05 FINEST authn_enable = 1 2018-10-31 16:48:08,107-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,107-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,107-05 FINEST stop = false 2018-10-31 16:48:08,107-05 FINEST VARS-END 2018-10-31 16:48:08,107-05 FINE End sequence __init0 set max filter size 2018-10-31 16:48:08,107-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,107-05 FINEST authn_enable = 1 2018-10-31 16:48:08,108-05 FINEST maxFilterSize = 50 2018-10-31 16:48:08,108-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,108-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,108-05 FINEST stop = false 2018-10-31 16:48:08,108-05 FINEST VARS-END 2018-10-31 16:48:08,109-05 FINE Running sequence __init0/03/var-set set default as not recursive group resolution 2018-10-31 16:48:08,109-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,109-05 FINEST authn_enable = 1 2018-10-31 16:48:08,109-05 FINEST maxFilterSize = 50 2018-10-31 16:48:08,109-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,109-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,109-05 FINEST stop = false 2018-10-31 16:48:08,110-05 FINEST VARS-END 2018-10-31 16:48:08,110-05 FINE End sequence __init0 set default as not recursive group resolution 2018-10-31 16:48:08,110-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,110-05 FINEST authn_enable = 1 2018-10-31 16:48:08,110-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:08,110-05 FINEST maxFilterSize = 50 2018-10-31 16:48:08,110-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,110-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,110-05 FINEST stop = false 2018-10-31 16:48:08,110-05 FINEST VARS-END 2018-10-31 16:48:08,111-05 FINE Running sequence __init0/04/var-set set default as no credentials change 2018-10-31 16:48:08,111-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,111-05 FINEST authn_enable = 1 2018-10-31 16:48:08,111-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:08,111-05 FINEST maxFilterSize = 50 2018-10-31 16:48:08,111-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,111-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,111-05 FINEST stop = false 2018-10-31 16:48:08,112-05 FINEST VARS-END 2018-10-31 16:48:08,112-05 FINE End sequence __init0 set default as no credentials change 2018-10-31 16:48:08,112-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,112-05 FINEST authn_enable = 1 2018-10-31 16:48:08,112-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:08,112-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:08,112-05 FINEST maxFilterSize = 50 2018-10-31 16:48:08,112-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,112-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,112-05 FINEST stop = false 2018-10-31 16:48:08,113-05 FINEST VARS-END 2018-10-31 16:48:08,113-05 FINE runSequence Return name='__init0' 2018-10-31 16:48:08,113-05 FINE runSequence Entry name='simple-init-vars' 2018-10-31 16:48:08,113-05 FINE Running sequence simple-init-vars/010/var-set set user format: sam for basic, realm for sasl 2018-10-31 16:48:08,113-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,113-05 FINEST authn_enable = 1 2018-10-31 16:48:08,113-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:08,114-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:08,114-05 FINEST maxFilterSize = 50 2018-10-31 16:48:08,114-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,114-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,114-05 FINEST stop = false 2018-10-31 16:48:08,114-05 FINEST VARS-END 2018-10-31 16:48:08,114-05 FINE End sequence simple-init-vars set user format: sam for basic, realm for sasl 2018-10-31 16:48:08,114-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,114-05 FINEST authn_enable = 1 2018-10-31 16:48:08,114-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:08,118-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:08,118-05 FINEST maxFilterSize = 50 2018-10-31 16:48:08,119-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,119-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,119-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:08,119-05 FINEST stop = false 2018-10-31 16:48:08,119-05 FINEST VARS-END 2018-10-31 16:48:08,119-05 FINE Running sequence simple-init-vars/020/var-set set group logic 2018-10-31 16:48:08,119-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,119-05 FINEST authn_enable = 1 2018-10-31 16:48:08,120-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:08,120-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:08,120-05 FINEST maxFilterSize = 50 2018-10-31 16:48:08,120-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,120-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,120-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:08,120-05 FINEST stop = false 2018-10-31 16:48:08,120-05 FINEST VARS-END 2018-10-31 16:48:08,120-05 FINE End sequence simple-init-vars set group logic 2018-10-31 16:48:08,120-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,121-05 FINEST authn_enable = 1 2018-10-31 16:48:08,121-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:08,121-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:08,121-05 FINEST maxFilterSize = 50 2018-10-31 16:48:08,121-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,121-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,121-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:08,121-05 FINEST simple_groupLogic = member 2018-10-31 16:48:08,121-05 FINEST stop = false 2018-10-31 16:48:08,121-05 FINEST VARS-END 2018-10-31 16:48:08,121-05 FINE Running sequence simple-init-vars/030/var-set set group member attribute 2018-10-31 16:48:08,122-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,122-05 FINEST authn_enable = 1 2018-10-31 16:48:08,122-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:08,122-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:08,122-05 FINEST maxFilterSize = 50 2018-10-31 16:48:08,122-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,122-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,122-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:08,122-05 FINEST simple_groupLogic = member 2018-10-31 16:48:08,122-05 FINEST stop = false 2018-10-31 16:48:08,123-05 FINEST VARS-END 2018-10-31 16:48:08,123-05 FINE End sequence simple-init-vars set group member attribute 2018-10-31 16:48:08,123-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,123-05 FINEST authn_enable = 1 2018-10-31 16:48:08,123-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:08,123-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:08,123-05 FINEST maxFilterSize = 50 2018-10-31 16:48:08,123-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,123-05 FINEST simple_attrGroupMemberDN = uniqueMember:uniqueMemberMatch: 2018-10-31 16:48:08,123-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,123-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:08,123-05 FINEST simple_groupLogic = member 2018-10-31 16:48:08,124-05 FINEST stop = false 2018-10-31 16:48:08,124-05 FINEST VARS-END 2018-10-31 16:48:08,124-05 FINE Running sequence simple-init-vars/040/var-set set object group membership attribute 2018-10-31 16:48:08,124-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,124-05 FINEST authn_enable = 1 2018-10-31 16:48:08,124-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:08,124-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:08,124-05 FINEST maxFilterSize = 50 2018-10-31 16:48:08,124-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,125-05 FINEST simple_attrGroupMemberDN = uniqueMember:uniqueMemberMatch: 2018-10-31 16:48:08,125-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,125-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:08,125-05 FINEST simple_groupLogic = member 2018-10-31 16:48:08,125-05 FINEST stop = false 2018-10-31 16:48:08,125-05 FINEST VARS-END 2018-10-31 16:48:08,125-05 FINE End sequence simple-init-vars set object group membership attribute 2018-10-31 16:48:08,125-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,125-05 FINEST authn_enable = 1 2018-10-31 16:48:08,126-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:08,126-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:08,126-05 FINEST maxFilterSize = 50 2018-10-31 16:48:08,126-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,126-05 FINEST simple_attrGroupMemberDN = uniqueMember:uniqueMemberMatch: 2018-10-31 16:48:08,126-05 FINEST simple_attrMemberOf = memberOf 2018-10-31 16:48:08,126-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,126-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:08,126-05 FINEST simple_groupLogic = member 2018-10-31 16:48:08,126-05 FINEST stop = false 2018-10-31 16:48:08,126-05 FINEST VARS-END 2018-10-31 16:48:08,127-05 FINE Running sequence simple-init-vars/050/var-set set default index for baseDN 2018-10-31 16:48:08,127-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,127-05 FINEST authn_enable = 1 2018-10-31 16:48:08,127-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:08,127-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:08,127-05 FINEST maxFilterSize = 50 2018-10-31 16:48:08,127-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,127-05 FINEST simple_attrGroupMemberDN = uniqueMember:uniqueMemberMatch: 2018-10-31 16:48:08,127-05 FINEST simple_attrMemberOf = memberOf 2018-10-31 16:48:08,127-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,127-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:08,128-05 FINEST simple_groupLogic = member 2018-10-31 16:48:08,128-05 FINEST stop = false 2018-10-31 16:48:08,128-05 FINEST VARS-END 2018-10-31 16:48:08,128-05 FINE End sequence simple-init-vars set default index for baseDN 2018-10-31 16:48:08,128-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,128-05 FINEST authn_enable = 1 2018-10-31 16:48:08,128-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:08,128-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:08,128-05 FINEST maxFilterSize = 50 2018-10-31 16:48:08,128-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,128-05 FINEST simple_attrGroupMemberDN = uniqueMember:uniqueMemberMatch: 2018-10-31 16:48:08,129-05 FINEST simple_attrMemberOf = memberOf 2018-10-31 16:48:08,129-05 FINEST simple_attrsBaseDNIndex = 0 2018-10-31 16:48:08,130-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,130-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:08,130-05 FINEST simple_groupLogic = member 2018-10-31 16:48:08,130-05 FINEST stop = false 2018-10-31 16:48:08,130-05 FINEST VARS-END 2018-10-31 16:48:08,130-05 FINE runSequence Return name='simple-init-vars' 2018-10-31 16:48:08,130-05 FINE runSequence Entry name='a389ds-init-vars' 2018-10-31 16:48:08,130-05 FINE Running sequence a389ds-init-vars/010/var-set set base dn 2018-10-31 16:48:08,130-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,130-05 FINEST authn_enable = 1 2018-10-31 16:48:08,131-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:08,131-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:08,131-05 FINEST maxFilterSize = 50 2018-10-31 16:48:08,131-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,131-05 FINEST simple_attrGroupMemberDN = uniqueMember:uniqueMemberMatch: 2018-10-31 16:48:08,131-05 FINEST simple_attrMemberOf = memberOf 2018-10-31 16:48:08,131-05 FINEST simple_attrsBaseDNIndex = 0 2018-10-31 16:48:08,131-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,131-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:08,131-05 FINEST simple_groupLogic = member 2018-10-31 16:48:08,131-05 FINEST stop = false 2018-10-31 16:48:08,131-05 FINEST VARS-END 2018-10-31 16:48:08,131-05 FINE End sequence a389ds-init-vars set base dn 2018-10-31 16:48:08,132-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,132-05 FINEST authn_enable = 1 2018-10-31 16:48:08,132-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:08,132-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:08,132-05 FINEST maxFilterSize = 50 2018-10-31 16:48:08,132-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,132-05 FINEST simple_attrGroupMemberDN = uniqueMember:uniqueMemberMatch: 2018-10-31 16:48:08,132-05 FINEST simple_attrMemberOf = memberOf 2018-10-31 16:48:08,132-05 FINEST simple_attrsBaseDN = defaultNamingContext 2018-10-31 16:48:08,132-05 FINEST simple_attrsBaseDNIndex = 0 2018-10-31 16:48:08,132-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,133-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:08,133-05 FINEST simple_groupLogic = member 2018-10-31 16:48:08,133-05 FINEST stop = false 2018-10-31 16:48:08,133-05 FINEST VARS-END 2018-10-31 16:48:08,133-05 FINE Running sequence a389ds-init-vars/020/var-set set user attribute 2018-10-31 16:48:08,133-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,133-05 FINEST authn_enable = 1 2018-10-31 16:48:08,133-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:08,133-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:08,133-05 FINEST maxFilterSize = 50 2018-10-31 16:48:08,133-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,133-05 FINEST simple_attrGroupMemberDN = uniqueMember:uniqueMemberMatch: 2018-10-31 16:48:08,134-05 FINEST simple_attrMemberOf = memberOf 2018-10-31 16:48:08,134-05 FINEST simple_attrsBaseDN = defaultNamingContext 2018-10-31 16:48:08,134-05 FINEST simple_attrsBaseDNIndex = 0 2018-10-31 16:48:08,134-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,134-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:08,134-05 FINEST simple_groupLogic = member 2018-10-31 16:48:08,134-05 FINEST stop = false 2018-10-31 16:48:08,134-05 FINEST VARS-END 2018-10-31 16:48:08,134-05 FINE End sequence a389ds-init-vars set user attribute 2018-10-31 16:48:08,134-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,134-05 FINEST authn_enable = 1 2018-10-31 16:48:08,134-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:08,135-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:08,135-05 FINEST maxFilterSize = 50 2018-10-31 16:48:08,135-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,135-05 FINEST simple_attrGroupMemberDN = uniqueMember:uniqueMemberMatch: 2018-10-31 16:48:08,135-05 FINEST simple_attrMemberOf = memberOf 2018-10-31 16:48:08,135-05 FINEST simple_attrsBaseDN = defaultNamingContext 2018-10-31 16:48:08,135-05 FINEST simple_attrsBaseDNIndex = 0 2018-10-31 16:48:08,135-05 FINEST simple_attrsUserName = uid 2018-10-31 16:48:08,135-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,135-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:08,135-05 FINEST simple_groupLogic = member 2018-10-31 16:48:08,135-05 FINEST stop = false 2018-10-31 16:48:08,135-05 FINEST VARS-END 2018-10-31 16:48:08,136-05 FINE Running sequence a389ds-init-vars/030/var-set set principal record attributes 2018-10-31 16:48:08,136-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,136-05 FINEST authn_enable = 1 2018-10-31 16:48:08,136-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:08,136-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:08,136-05 FINEST maxFilterSize = 50 2018-10-31 16:48:08,136-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,136-05 FINEST simple_attrGroupMemberDN = uniqueMember:uniqueMemberMatch: 2018-10-31 16:48:08,136-05 FINEST simple_attrMemberOf = memberOf 2018-10-31 16:48:08,136-05 FINEST simple_attrsBaseDN = defaultNamingContext 2018-10-31 16:48:08,136-05 FINEST simple_attrsBaseDNIndex = 0 2018-10-31 16:48:08,137-05 FINEST simple_attrsUserName = uid 2018-10-31 16:48:08,137-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,137-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:08,137-05 FINEST simple_groupLogic = member 2018-10-31 16:48:08,137-05 FINEST stop = false 2018-10-31 16:48:08,137-05 FINEST VARS-END 2018-10-31 16:48:08,137-05 FINE End sequence a389ds-init-vars set principal record attributes 2018-10-31 16:48:08,137-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,137-05 FINEST authn_enable = 1 2018-10-31 16:48:08,137-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:08,137-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:08,137-05 FINEST maxFilterSize = 50 2018-10-31 16:48:08,138-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,138-05 FINEST simple_attrGroupMemberDN = uniqueMember:uniqueMemberMatch: 2018-10-31 16:48:08,138-05 FINEST simple_attrMemberOf = memberOf 2018-10-31 16:48:08,138-05 FINEST simple_attrsBaseDN = defaultNamingContext 2018-10-31 16:48:08,138-05 FINEST simple_attrsBaseDNIndex = 0 2018-10-31 16:48:08,138-05 FINEST simple_attrsPrincipalRecord = nsuniqueid, uid, cn, displayName, department, givenName, sn, title, mail 2018-10-31 16:48:08,138-05 FINEST simple_attrsUserName = uid 2018-10-31 16:48:08,138-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,138-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:08,138-05 FINEST simple_groupLogic = member 2018-10-31 16:48:08,138-05 FINEST stop = false 2018-10-31 16:48:08,138-05 FINEST VARS-END 2018-10-31 16:48:08,139-05 FINE Running sequence a389ds-init-vars/040/var-set set user object filter 2018-10-31 16:48:08,139-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,139-05 FINEST authn_enable = 1 2018-10-31 16:48:08,139-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:08,139-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:08,139-05 FINEST maxFilterSize = 50 2018-10-31 16:48:08,139-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,139-05 FINEST simple_attrGroupMemberDN = uniqueMember:uniqueMemberMatch: 2018-10-31 16:48:08,139-05 FINEST simple_attrMemberOf = memberOf 2018-10-31 16:48:08,139-05 FINEST simple_attrsBaseDN = defaultNamingContext 2018-10-31 16:48:08,139-05 FINEST simple_attrsBaseDNIndex = 0 2018-10-31 16:48:08,139-05 FINEST simple_attrsPrincipalRecord = nsuniqueid, uid, cn, displayName, department, givenName, sn, title, mail 2018-10-31 16:48:08,140-05 FINEST simple_attrsUserName = uid 2018-10-31 16:48:08,140-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,140-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:08,140-05 FINEST simple_groupLogic = member 2018-10-31 16:48:08,140-05 FINEST stop = false 2018-10-31 16:48:08,140-05 FINEST VARS-END 2018-10-31 16:48:08,140-05 FINE End sequence a389ds-init-vars set user object filter 2018-10-31 16:48:08,140-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,140-05 FINEST authn_enable = 1 2018-10-31 16:48:08,140-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:08,140-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:08,140-05 FINEST maxFilterSize = 50 2018-10-31 16:48:08,140-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,141-05 FINEST simple_attrGroupMemberDN = uniqueMember:uniqueMemberMatch: 2018-10-31 16:48:08,141-05 FINEST simple_attrMemberOf = memberOf 2018-10-31 16:48:08,141-05 FINEST simple_attrsBaseDN = defaultNamingContext 2018-10-31 16:48:08,141-05 FINEST simple_attrsBaseDNIndex = 0 2018-10-31 16:48:08,141-05 FINEST simple_attrsPrincipalRecord = nsuniqueid, uid, cn, displayName, department, givenName, sn, title, mail 2018-10-31 16:48:08,141-05 FINEST simple_attrsUserName = uid 2018-10-31 16:48:08,141-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,141-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:08,141-05 FINEST simple_filterUserObject = (objectClass=organizationalPerson)(uid=*) 2018-10-31 16:48:08,141-05 FINEST simple_groupLogic = member 2018-10-31 16:48:08,141-05 FINEST stop = false 2018-10-31 16:48:08,141-05 FINEST VARS-END 2018-10-31 16:48:08,141-05 FINE Running sequence a389ds-init-vars/050/var-set set group record attributes 2018-10-31 16:48:08,142-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,142-05 FINEST authn_enable = 1 2018-10-31 16:48:08,142-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:08,142-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:08,142-05 FINEST maxFilterSize = 50 2018-10-31 16:48:08,142-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,142-05 FINEST simple_attrGroupMemberDN = uniqueMember:uniqueMemberMatch: 2018-10-31 16:48:08,142-05 FINEST simple_attrMemberOf = memberOf 2018-10-31 16:48:08,142-05 FINEST simple_attrsBaseDN = defaultNamingContext 2018-10-31 16:48:08,142-05 FINEST simple_attrsBaseDNIndex = 0 2018-10-31 16:48:08,142-05 FINEST simple_attrsPrincipalRecord = nsuniqueid, uid, cn, displayName, department, givenName, sn, title, mail 2018-10-31 16:48:08,142-05 FINEST simple_attrsUserName = uid 2018-10-31 16:48:08,143-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,143-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:08,143-05 FINEST simple_filterUserObject = (objectClass=organizationalPerson)(uid=*) 2018-10-31 16:48:08,143-05 FINEST simple_groupLogic = member 2018-10-31 16:48:08,143-05 FINEST stop = false 2018-10-31 16:48:08,143-05 FINEST VARS-END 2018-10-31 16:48:08,143-05 FINE End sequence a389ds-init-vars set group record attributes 2018-10-31 16:48:08,143-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,143-05 FINEST authn_enable = 1 2018-10-31 16:48:08,143-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:08,143-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:08,144-05 FINEST maxFilterSize = 50 2018-10-31 16:48:08,144-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,144-05 FINEST simple_attrGroupMemberDN = uniqueMember:uniqueMemberMatch: 2018-10-31 16:48:08,144-05 FINEST simple_attrMemberOf = memberOf 2018-10-31 16:48:08,144-05 FINEST simple_attrsBaseDN = defaultNamingContext 2018-10-31 16:48:08,144-05 FINEST simple_attrsBaseDNIndex = 0 2018-10-31 16:48:08,144-05 FINEST simple_attrsGroupRecord = nsuniqueid, cn, description 2018-10-31 16:48:08,144-05 FINEST simple_attrsPrincipalRecord = nsuniqueid, uid, cn, displayName, department, givenName, sn, title, mail 2018-10-31 16:48:08,144-05 FINEST simple_attrsUserName = uid 2018-10-31 16:48:08,144-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,144-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:08,144-05 FINEST simple_filterUserObject = (objectClass=organizationalPerson)(uid=*) 2018-10-31 16:48:08,144-05 FINEST simple_groupLogic = member 2018-10-31 16:48:08,144-05 FINEST stop = false 2018-10-31 16:48:08,145-05 FINEST VARS-END 2018-10-31 16:48:08,145-05 FINE Running sequence a389ds-init-vars/060/var-set set group object filter 2018-10-31 16:48:08,145-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,145-05 FINEST authn_enable = 1 2018-10-31 16:48:08,145-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:08,145-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:08,145-05 FINEST maxFilterSize = 50 2018-10-31 16:48:08,145-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,145-05 FINEST simple_attrGroupMemberDN = uniqueMember:uniqueMemberMatch: 2018-10-31 16:48:08,145-05 FINEST simple_attrMemberOf = memberOf 2018-10-31 16:48:08,145-05 FINEST simple_attrsBaseDN = defaultNamingContext 2018-10-31 16:48:08,146-05 FINEST simple_attrsBaseDNIndex = 0 2018-10-31 16:48:08,146-05 FINEST simple_attrsGroupRecord = nsuniqueid, cn, description 2018-10-31 16:48:08,146-05 FINEST simple_attrsPrincipalRecord = nsuniqueid, uid, cn, displayName, department, givenName, sn, title, mail 2018-10-31 16:48:08,146-05 FINEST simple_attrsUserName = uid 2018-10-31 16:48:08,146-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,146-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:08,146-05 FINEST simple_filterUserObject = (objectClass=organizationalPerson)(uid=*) 2018-10-31 16:48:08,146-05 FINEST simple_groupLogic = member 2018-10-31 16:48:08,146-05 FINEST stop = false 2018-10-31 16:48:08,146-05 FINEST VARS-END 2018-10-31 16:48:08,146-05 FINE End sequence a389ds-init-vars set group object filter 2018-10-31 16:48:08,146-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,147-05 FINEST authn_enable = 1 2018-10-31 16:48:08,147-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:08,147-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:08,147-05 FINEST maxFilterSize = 50 2018-10-31 16:48:08,147-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,147-05 FINEST simple_attrGroupMemberDN = uniqueMember:uniqueMemberMatch: 2018-10-31 16:48:08,147-05 FINEST simple_attrMemberOf = memberOf 2018-10-31 16:48:08,147-05 FINEST simple_attrsBaseDN = defaultNamingContext 2018-10-31 16:48:08,147-05 FINEST simple_attrsBaseDNIndex = 0 2018-10-31 16:48:08,147-05 FINEST simple_attrsGroupRecord = nsuniqueid, cn, description 2018-10-31 16:48:08,147-05 FINEST simple_attrsPrincipalRecord = nsuniqueid, uid, cn, displayName, department, givenName, sn, title, mail 2018-10-31 16:48:08,147-05 FINEST simple_attrsUserName = uid 2018-10-31 16:48:08,147-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,148-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:08,148-05 FINEST simple_filterGroupObject = (objectClass=groupOfUniqueNames) 2018-10-31 16:48:08,148-05 FINEST simple_filterUserObject = (objectClass=organizationalPerson)(uid=*) 2018-10-31 16:48:08,148-05 FINEST simple_groupLogic = member 2018-10-31 16:48:08,148-05 FINEST stop = false 2018-10-31 16:48:08,148-05 FINEST VARS-END 2018-10-31 16:48:08,148-05 FINE runSequence Return name='a389ds-init-vars' 2018-10-31 16:48:08,148-05 FINE init Return globals={sensitiveKeys=, password, passwordNew, simple_attrMemberOf=memberOf, simple_filterGroupObject=(objectClass=groupOfUniqueNames), simple_attrsGroupRecord=nsuniqueid, cn, description, simple_filterUserObject=(objectClass=organizationalPerson)(uid=*), authn_enable=1, simple_baseDN=dc=cyber-range,dc=lan, maxFilterSize=50, capability_recursiveGroupResolution=false, simple_attrsUserName=uid, stop=false, simple_attrGroupMemberDN=uniqueMember:uniqueMemberMatch:, simple_attrsBaseDNIndex=0, simple_bindFormat=dn, simple_attrsPrincipalRecord=nsuniqueid, uid, cn, displayName, department, givenName, sn, title, mail, capability_credentialsChange=false, simple_groupLogic=member, simple_attrsBaseDN=defaultNamingContext} 2018-10-31 16:48:08,148-05 FINEST Invoke Output BEGIN 2018-10-31 16:48:08,148-05 FINEST {Extkey[name=EXTENSION_INVOKE_RESULT;type=class java.lang.Integer;uuid=EXTENSION_INVOKE_RESULT[0909d91d-8bde-40fb-b6c0-099c772ddd4e];]=0} 2018-10-31 16:48:08,149-05 FINEST Invoke Output END 2018-10-31 16:48:08,149-05 INFO Extension 'ldap.cyber-range.lan-authn' loaded 2018-10-31 16:48:08,149-05 FINE Config BEGIN 2018-10-31 16:48:08,149-05 FINE config.globals.baseDN.simple_baseDN: dc=cyber-range,dc=lan 2018-10-31 16:48:08,149-05 FINE ovirt.engine.extension.provides: org.ovirt.engine.api.extensions.aaa.Authn 2018-10-31 16:48:08,149-05 FINE ovirt.engine.extension.bindings.method: jbossmodule 2018-10-31 16:48:08,149-05 FINE ovirt.engine.extension.binding.jbossmodule.module: org.ovirt.engine-extensions.aaa.ldap 2018-10-31 16:48:08,149-05 FINE ovirt.engine.aaa.authn.authz.plugin: ldap.cyber-range.lan 2018-10-31 16:48:08,149-05 FINE ovirt.engine.extension.name: ldap.cyber-range.lan-authn 2018-10-31 16:48:08,149-05 FINE ovirt.engine.extension.binding.jbossmodule.class: org.ovirt.engineextensions.aaa.ldap.AuthnExtension 2018-10-31 16:48:08,149-05 FINE config.profile.file.1: ../aaa/ldap.cyber-range.lan.properties 2018-10-31 16:48:08,149-05 FINE ovirt.engine.aaa.authn.profile.name: ldap.cyber-range.lan 2018-10-31 16:48:08,149-05 FINE Config END 2018-10-31 16:48:08,150-05 INFO Initializing extension 'internal-authz' 2018-10-31 16:48:08,150-05 FINEST Invoke Input BEGIN 2018-10-31 16:48:08,150-05 FINEST {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], Extkey[name=EXTENSION_INVOKE_CONTEXT;type=class org.ovirt.engine.api.extensions.ExtMap;uuid=EXTENSION_INVOKE_CONTEXT[886d2ebb-312a-49ae-9cc3-e1f849834b7d];]={Extkey[name=AAA_AUTHZ_AVAILABLE_NAMESPACES;type=interface java.util.Collection;uuid=AAA_AUTHZ_AVAILABLE_NAMESPACES[6dffa34c-955f-486a-bd35-0a272b45a711];]=[*], Extkey[name=EXTENSION_LICENSE;type=class java.lang.String;uuid=EXTENSION_LICENSE[8a61ad65-054c-4e31-9c6d-1ca4d60a4c18];]=ASL 2.0, 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_NAME;type=class java.lang.String;uuid=EXTENSION_NAME[651381d3-f54f-4547-bf28-b0b01a103184];]="ovirt-engine-extension-aaa-jdbc".authz, Extkey[name=EXTENSION_MANAGER_TRACE_LOG;type=interface org.slf4j.Logger;uuid=EXTENSION_MANAGER_TRACE_LOG[863db666-3ea7-4751-9695-918a3197ad83];]=org.slf4j.impl.JDK14LoggerAdapter(org.ovirt.engine.core.extensions.mgr.ExtensionsManager.trace."ovirt-engine-extension-aaa-jdbc".authz.internal-authz), Extkey[name=EXTENSION_CONFIGURATION_SENSITIVE_KEYS;type=interface java.util.Collection;uuid=EXTENSION_CONFIGURATION_SENSITIVE_KEYS[a456efa1-73ff-4204-9f9b-ebff01e35263];]=[], Extkey[name=EXTENSION_VERSION;type=class java.lang.String;uuid=EXTENSION_VERSION[fe35f6a8-8239-4bdb-ab1a-af9f779ce68c];]="1.1.7_master", 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_AUTHOR;type=class java.lang.String;uuid=EXTENSION_AUTHOR[ef242f7a-2dad-4bc5-9aad-e07018b7fbcc];]=The oVirt Project, Extkey[name=EXTENSION_LOCALE;type=class java.lang.String;uuid=EXTENSION_LOCALE[0780b112-0ce0-404a-b85e-8765d778bb29];]=en_US, Extkey[name=EXTENSION_CONFIGURATION_FILE;type=class java.lang.String;uuid=EXTENSION_CONFIGURATION_FILE[4fb0ffd3-983c-4f3f-98ff-9660bd67af6a];]=/etc/ovirt-engine/extensions.d/internal-authz.properties, 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_CONFIGURATION;type=class java.util.Properties;uuid=EXTENSION_CONFIGURATION[2d48ab72-f0a1-4312-b4ae-5068a226b0fc];]=***, Extkey[name=EXTENSION_INTERFACE_VERSION_MAX;type=class java.lang.Integer;uuid=EXTENSION_INTERFACE_VERSION_MAX[f4cff49f-2717-4901-8ee9-df362446e3e7];]=0, 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_INTERFACE_VERSION_MIN;type=class java.lang.Integer;uuid=EXTENSION_INTERFACE_VERSION_MIN[2b84fc91-305b-497b-a1d7-d961b9d2ce0b];]=0, Extkey[name=EXTENSION_INSTANCE_NAME;type=class java.lang.String;uuid=EXTENSION_INSTANCE_NAME[65c67ff6-aeca-4bd5-a245-8674327f011b];]=internal-authz, Extkey[name=AAA_AUTHZ_CAPABILITIES;type=class java.lang.Long;uuid=AAA_AUTHZ_CAPABILITIES[6106d1fb-9291-4351-a947-b897b9540a23];]=1, 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_NOTES;type=class java.lang.String;uuid=EXTENSION_NOTES[2da5ad7e-185a-4584-aaff-97f66978e4ea];]=Display name: "ovirt-engine-extension-aaa-jdbc"}} 2018-10-31 16:48:08,150-05 FINEST Invoke Input END 2018-10-31 16:48:08,210-05 FINEST cursor for: SELECT COUNT(script) AS count FROM schema_version WHERE script = $rKmMc$upgrade/01_00_0060_missing_indexes.sql$rKmMc$ 2018-10-31 16:48:08,223-05 FINEST Invoke Output BEGIN 2018-10-31 16:48:08,223-05 FINEST {Extkey[name=AAA_AUTHZ_STATUS;type=class java.lang.Integer;uuid=AAA_AUTHZ_STATUS[566f0ba5-8329-4de1-952a-7a81e4bedd3e];]=0, Extkey[name=EXTENSION_INVOKE_RESULT;type=class java.lang.Integer;uuid=EXTENSION_INVOKE_RESULT[0909d91d-8bde-40fb-b6c0-099c772ddd4e];]=0} 2018-10-31 16:48:08,223-05 FINEST Invoke Output END 2018-10-31 16:48:08,224-05 INFO Extension 'internal-authz' initialized 2018-10-31 16:48:08,224-05 FINE Extension 'internal-authz' initialized 2018-10-31 16:48:08,224-05 INFO Initializing extension 'internal-authn' 2018-10-31 16:48:08,224-05 FINEST Invoke Input BEGIN 2018-10-31 16:48:08,226-05 FINEST {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], 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_LICENSE;type=class java.lang.String;uuid=EXTENSION_LICENSE[8a61ad65-054c-4e31-9c6d-1ca4d60a4c18];]=ASL 2.0, 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_NAME;type=class java.lang.String;uuid=EXTENSION_NAME[651381d3-f54f-4547-bf28-b0b01a103184];]="ovirt-engine-extension-aaa-jdbc".authn, Extkey[name=EXTENSION_MANAGER_TRACE_LOG;type=interface org.slf4j.Logger;uuid=EXTENSION_MANAGER_TRACE_LOG[863db666-3ea7-4751-9695-918a3197ad83];]=org.slf4j.impl.JDK14LoggerAdapter(org.ovirt.engine.core.extensions.mgr.ExtensionsManager.trace."ovirt-engine-extension-aaa-jdbc".authn.internal-authn), Extkey[name=EXTENSION_CONFIGURATION_SENSITIVE_KEYS;type=interface java.util.Collection;uuid=EXTENSION_CONFIGURATION_SENSITIVE_KEYS[a456efa1-73ff-4204-9f9b-ebff01e35263];]=[], Extkey[name=EXTENSION_VERSION;type=class java.lang.String;uuid=EXTENSION_VERSION[fe35f6a8-8239-4bdb-ab1a-af9f779ce68c];]="1.1.7_master", 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_AUTHOR;type=class java.lang.String;uuid=EXTENSION_AUTHOR[ef242f7a-2dad-4bc5-9aad-e07018b7fbcc];]=The oVirt Project, Extkey[name=EXTENSION_LOCALE;type=class java.lang.String;uuid=EXTENSION_LOCALE[0780b112-0ce0-404a-b85e-8765d778bb29];]=en_US, Extkey[name=EXTENSION_CONFIGURATION_FILE;type=class java.lang.String;uuid=EXTENSION_CONFIGURATION_FILE[4fb0ffd3-983c-4f3f-98ff-9660bd67af6a];]=/etc/ovirt-engine/extensions.d/internal-authn.properties, 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_CONFIGURATION;type=class java.util.Properties;uuid=EXTENSION_CONFIGURATION[2d48ab72-f0a1-4312-b4ae-5068a226b0fc];]=***, 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_INTERFACE_VERSION_MIN;type=class java.lang.Integer;uuid=EXTENSION_INTERFACE_VERSION_MIN[2b84fc91-305b-497b-a1d7-d961b9d2ce0b];]=0, Extkey[name=EXTENSION_INSTANCE_NAME;type=class java.lang.String;uuid=EXTENSION_INSTANCE_NAME[65c67ff6-aeca-4bd5-a245-8674327f011b];]=internal-authn, 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_AUTHN_CAPABILITIES;type=class java.lang.Long;uuid=AAA_AUTHN_CAPABILITIES[9d16bee3-10fd-46f2-83f9-3d3c54cf258d];]=44, Extkey[name=EXTENSION_NOTES;type=class java.lang.String;uuid=EXTENSION_NOTES[2da5ad7e-185a-4584-aaff-97f66978e4ea];]=Display name: "ovirt-engine-extension-aaa-jdbc"}} 2018-10-31 16:48:08,226-05 FINEST Invoke Input END 2018-10-31 16:48:08,233-05 FINEST cursor for: SELECT COUNT(script) AS count FROM schema_version WHERE script = $VmVUS$upgrade/01_00_0060_missing_indexes.sql$VmVUS$ 2018-10-31 16:48:08,236-05 FINEST Invoke Output BEGIN 2018-10-31 16:48:08,236-05 FINEST {Extkey[name=EXTENSION_INVOKE_RESULT;type=class java.lang.Integer;uuid=EXTENSION_INVOKE_RESULT[0909d91d-8bde-40fb-b6c0-099c772ddd4e];]=0} 2018-10-31 16:48:08,236-05 FINEST Invoke Output END 2018-10-31 16:48:08,237-05 INFO Extension 'internal-authn' initialized 2018-10-31 16:48:08,237-05 FINE Extension 'internal-authn' initialized 2018-10-31 16:48:08,237-05 INFO Initializing extension 'ldap.cyber-range.lan' 2018-10-31 16:48:08,237-05 FINEST Invoke Input BEGIN 2018-10-31 16:48:08,237-05 FINEST {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], 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_LICENSE;type=class java.lang.String;uuid=EXTENSION_LICENSE[8a61ad65-054c-4e31-9c6d-1ca4d60a4c18];]=ASL 2.0, 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_NAME;type=class java.lang.String;uuid=EXTENSION_NAME[651381d3-f54f-4547-bf28-b0b01a103184];]=ovirt-engine-extension-aaa-ldap.authz, Extkey[name=EXTENSION_MANAGER_TRACE_LOG;type=interface org.slf4j.Logger;uuid=EXTENSION_MANAGER_TRACE_LOG[863db666-3ea7-4751-9695-918a3197ad83];]=org.slf4j.impl.JDK14LoggerAdapter(org.ovirt.engine.core.extensions.mgr.ExtensionsManager.trace.ovirt-engine-extension-aaa-ldap.authz.ldap.cyber-range.lan), Extkey[name=EXTENSION_CONFIGURATION_SENSITIVE_KEYS;type=interface java.util.Collection;uuid=EXTENSION_CONFIGURATION_SENSITIVE_KEYS[a456efa1-73ff-4204-9f9b-ebff01e35263];]=[], Extkey[name=EXTENSION_VERSION;type=class java.lang.String;uuid=EXTENSION_VERSION[fe35f6a8-8239-4bdb-ab1a-af9f779ce68c];]=1.3.7, 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_AUTHOR;type=class java.lang.String;uuid=EXTENSION_AUTHOR[ef242f7a-2dad-4bc5-9aad-e07018b7fbcc];]=The oVirt Project, Extkey[name=EXTENSION_LOCALE;type=class java.lang.String;uuid=EXTENSION_LOCALE[0780b112-0ce0-404a-b85e-8765d778bb29];]=en_US, Extkey[name=EXTENSION_CONFIGURATION_FILE;type=class java.lang.String;uuid=EXTENSION_CONFIGURATION_FILE[4fb0ffd3-983c-4f3f-98ff-9660bd67af6a];]=/etc/ovirt-engine/extensions.d/ldap.cyber-range.lan.properties, 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_CONFIGURATION;type=class java.util.Properties;uuid=EXTENSION_CONFIGURATION[2d48ab72-f0a1-4312-b4ae-5068a226b0fc];]=***, Extkey[name=EXTENSION_INTERFACE_VERSION_MAX;type=class java.lang.Integer;uuid=EXTENSION_INTERFACE_VERSION_MAX[f4cff49f-2717-4901-8ee9-df362446e3e7];]=0, 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];]=50, 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_INSTANCE_NAME;type=class java.lang.String;uuid=EXTENSION_INSTANCE_NAME[65c67ff6-aeca-4bd5-a245-8674327f011b];]=ldap.cyber-range.lan, 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_NOTES;type=class java.lang.String;uuid=EXTENSION_NOTES[2da5ad7e-185a-4584-aaff-97f66978e4ea];]=Display name: ovirt-engine-extension-aaa-ldap-1.3.7-1.el7.centos}} 2018-10-31 16:48:08,237-05 FINEST Invoke Input END 2018-10-31 16:48:08,237-05 FINE open Entry 2018-10-31 16:48:08,238-05 FINE runSequence Entry name='simple-open-pools' 2018-10-31 16:48:08,238-05 FINE Running sequence simple-open-pools/010/pool-create create authz pool 2018-10-31 16:48:08,238-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,238-05 FINEST authz_enable = 1 2018-10-31 16:48:08,238-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:08,238-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:08,238-05 FINEST maxFilterSize = 50 2018-10-31 16:48:08,238-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,238-05 FINEST simple_attrGroupMemberDN = uniqueMember:uniqueMemberMatch: 2018-10-31 16:48:08,238-05 FINEST simple_attrMemberOf = memberOf 2018-10-31 16:48:08,238-05 FINEST simple_attrsBaseDN = defaultNamingContext 2018-10-31 16:48:08,239-05 FINEST simple_attrsBaseDNIndex = 0 2018-10-31 16:48:08,239-05 FINEST simple_attrsGroupRecord = nsuniqueid, cn, description 2018-10-31 16:48:08,239-05 FINEST simple_attrsPrincipalRecord = nsuniqueid, uid, cn, displayName, department, givenName, sn, title, mail 2018-10-31 16:48:08,239-05 FINEST simple_attrsUserName = uid 2018-10-31 16:48:08,239-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,239-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:08,239-05 FINEST simple_filterGroupObject = (objectClass=groupOfUniqueNames) 2018-10-31 16:48:08,239-05 FINEST simple_filterUserObject = (objectClass=organizationalPerson)(uid=*) 2018-10-31 16:48:08,239-05 FINEST simple_groupLogic = member 2018-10-31 16:48:08,239-05 FINEST stop = false 2018-10-31 16:48:08,239-05 FINEST VARS-END 2018-10-31 16:48:08,239-05 FINE getConnectionPoolEntry Entry name='authz', dn='null' 2018-10-31 16:48:08,240-05 FINE getConnectionPoolEntry no pool for 'authz' 2018-10-31 16:48:08,240-05 INFO [ovirt-engine-extension-aaa-ldap.authz::ldap.cyber-range.lan] Creating LDAP pool 'authz' 2018-10-31 16:48:08,240-05 FINE createPool Entry name='authz' 2018-10-31 16:48:08,241-05 FINE createConnectionPool Entry 2018-10-31 16:48:08,241-05 FINE Creating HostNameSSLSocketVerifier 2018-10-31 16:48:08,241-05 FINE HostNameSSLSocketVerifier: com.unboundid.util.ssl.HostNameSSLSocketVerifier@17d0685f 2018-10-31 16:48:08,241-05 FINE Creating trust store 2018-10-31 16:48:08,279-05 FINE Creating key store 2018-10-31 16:48:08,280-05 FINE Creating StartTLSPostConnectProcessor 2018-10-31 16:48:08,368-05 FINE StartTLSPostConnectProcessor: com.unboundid.ldap.sdk.StartTLSPostConnectProcessor@71623278 2018-10-31 16:48:08,369-05 FINE Creating LDAPConnectionOptions 2018-10-31 16:48:08,378-05 FINE 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, responseTimeoutMillis.ABANDON=60000, responseTimeoutMillis.ADD=60000, responseTimeoutMillis.BIND=60000, responseTimeoutMillis.COMPARE=60000, responseTimeoutMillis.DELETE=60000, responseTimeoutMillis.EXTENDED=60000, responseTimeoutMillis.MODIFY=60000, responseTimeoutMillis.MODIFY_DN=60000, responseTimeoutMillis.SEARCH=60000, responseTimeoutMillis.UNBIND=60000, abandonOnTimeout=true, maxMessageSizeBytes=20971520, receiveBufferSizeBytes=0, sendBufferSizeBytes=0, allowConcurrentSocketFactoryUse=true, sslSocketVerifierClass='com.unboundid.util.ssl.HostNameSSLSocketVerifier') 2018-10-31 16:48:08,378-05 FINE Creating SocketFactory 2018-10-31 16:48:08,379-05 FINE Creating resolver 2018-10-31 16:48:08,382-05 FINE Open: Context: {java.naming.provider.url=dns://, java.naming.factory.initial=com.sun.jndi.dns.DnsContextFactory} 2018-10-31 16:48:08,388-05 FINE Resolver: Resolver(env='{java.naming.provider.url=dns://, java.naming.factory.initial=com.sun.jndi.dns.DnsContextFactory}', supportIPv6='false', cacheTTL='10000') 2018-10-31 16:48:08,389-05 FINE SocketFactory: org.ovirt.engineextensions.aaa.ldap.ResolverSocketFactory@27fe3806 2018-10-31 16:48:08,390-05 FINE Creating ServerSet 2018-10-31 16:48:08,390-05 FINE ServerSet: SingleServerSet(server=ldap.cyber-range.lan:389, includesAuthentication=false, includesPostConnectProcessing=false) 2018-10-31 16:48:08,390-05 FINE Creating BindRequest 2018-10-31 16:48:08,390-05 FINE createBindRequest Entry type='simple', user='' 2018-10-31 16:48:08,396-05 FINE createBindRequest Return SimpleBindRequest(dn='uid=admin,ou=Administrators,ou=TopologyManagement,o=NetscapeRoot') 2018-10-31 16:48:08,396-05 FINE BindRequest: SimpleBindRequest(dn='uid=admin,ou=Administrators,ou=TopologyManagement,o=NetscapeRoot') 2018-10-31 16:48:08,396-05 FINE Creating LDAPConnectionPool 2018-10-31 16:48:08,410-05 FINE Entry: name='ldap.cyber-range.lan' 2018-10-31 16:48:08,411-05 FINE Entry: name='ldap.cyber-range.lan' 2018-10-31 16:48:08,411-05 FINE Cache miss 2018-10-31 16:48:08,411-05 FINE Entry: name='ldap.cyber-range.lan' 2018-10-31 16:48:08,411-05 FINE Entry: name='ldap.cyber-range.lan', attrs=[A] 2018-10-31 16:48:08,418-05 FINE Return: {a=A: 172.30.50.6} 2018-10-31 16:48:08,418-05 FINE A 172.30.50.6 2018-10-31 16:48:08,418-05 FINE Return: [172.30.50.6] 2018-10-31 16:48:08,419-05 FINE Return: [ldap.cyber-range.lan/172.30.50.6] 2018-10-31 16:48:08,419-05 FINE Return: ldap.cyber-range.lan/172.30.50.6 2018-10-31 16:48:08,587-05 FINE Entry: name='ldap.cyber-range.lan' 2018-10-31 16:48:08,587-05 FINE Entry: name='ldap.cyber-range.lan' 2018-10-31 16:48:08,588-05 FINE Return: [ldap.cyber-range.lan/172.30.50.6] 2018-10-31 16:48:08,588-05 FINE Return: ldap.cyber-range.lan/172.30.50.6 2018-10-31 16:48:08,649-05 FINE Entry: name='ldap.cyber-range.lan' 2018-10-31 16:48:08,649-05 FINE Entry: name='ldap.cyber-range.lan' 2018-10-31 16:48:08,649-05 FINE Return: [ldap.cyber-range.lan/172.30.50.6] 2018-10-31 16:48:08,649-05 FINE Return: ldap.cyber-range.lan/172.30.50.6 2018-10-31 16:48:08,710-05 FINE Entry: name='ldap.cyber-range.lan' 2018-10-31 16:48:08,710-05 FINE Entry: name='ldap.cyber-range.lan' 2018-10-31 16:48:08,710-05 FINE Return: [ldap.cyber-range.lan/172.30.50.6] 2018-10-31 16:48:08,710-05 FINE Return: ldap.cyber-range.lan/172.30.50.6 2018-10-31 16:48:08,781-05 FINE createConnectionPool Return: LDAPConnectionPool(serverSet=SingleServerSet(server=ldap.cyber-range.lan:389, includesAuthentication=false, includesPostConnectProcessing=false), maxConnections=20) 2018-10-31 16:48:08,795-05 INFO [ovirt-engine-extension-aaa-ldap.authz::ldap.cyber-range.lan] LDAP pool 'authz' information: vendor='389 Project' version='389-Directory/1.3.7.5 B2018.269.1826' 2018-10-31 16:48:08,796-05 FINE RootDSE: [Attribute(name=objectClass, values={'top'}), Attribute(name=creatorsName, values={'cn=server,cn=plugins,cn=config'}), Attribute(name=modifiersName, values={'cn=server,cn=plugins,cn=config'}), Attribute(name=createTimestamp, values={'20181021140445Z'}), Attribute(name=modifyTimestamp, values={'20181021140445Z'}), Attribute(name=defaultnamingcontext, values={'dc=cyber-range,dc=lan'}), Attribute(name=nsBackendSuffix, values={'userRoot:dc=cyber-range,dc=lan', 'NetscapeRoot:o=netscaperoot'}), Attribute(name=dataversion, values={'020181031011613020181031011613'}), Attribute(name=netscapemdsuffix, values={'cn=ldap://dc=ldap,dc=cyber-range,dc=lan:389'}), Attribute(name=namingContexts, values={'dc=cyber-range,dc=lan', 'o=netscaperoot'}), Attribute(name=subschemaSubentry, values={'cn=schema'}), Attribute(name=supportedControl, values={'2.16.840.1.113730.3.4.2', '2.16.840.1.113730.3.4.3', '2.16.840.1.113730.3.4.4', '2.16.840.1.113730.3.4.5', '1.2.840.113556.1.4.473', '2.16.840.1.113730.3.4.9', '2.16.840.1.113730.3.4.16', '2.16.840.1.113730.3.4.15', '2.16.840.1.113730.3.4.17', '2.16.840.1.113730.3.4.19', '1.3.6.1.1.13.1', '1.3.6.1.1.13.2', '1.3.6.1.4.1.42.2.27.8.5.1', '1.3.6.1.4.1.42.2.27.9.5.2', '1.2.840.113556.1.4.319', '1.3.6.1.4.1.42.2.27.9.5.8', '1.3.6.1.4.1.4203.666.5.16', '2.16.840.1.113730.3.4.14', '2.16.840.1.113730.3.4.20', '1.3.6.1.4.1.1466.29539.12', '2.16.840.1.113730.3.4.12', '2.16.840.1.113730.3.4.18', '2.16.840.1.113730.3.4.13'}), Attribute(name=supportedExtension, values={'2.16.840.1.113730.3.5.7', '2.16.840.1.113730.3.5.8', '2.16.840.1.113730.3.5.3', '2.16.840.1.113730.3.5.12', '2.16.840.1.113730.3.5.5', '2.16.840.1.113730.3.5.6', '2.16.840.1.113730.3.5.9', '2.16.840.1.113730.3.5.4', '2.16.840.1.113730.3.6.5', '2.16.840.1.113730.3.6.6', '2.16.840.1.113730.3.6.7', '2.16.840.1.113730.3.6.8', '1.3.6.1.4.1.4203.1.11.3', '1.3.6.1.4.1.1466.20037', '1.3.6.1.4.1.4203.1.11.1'}), Attribute(name=supportedFeatures, values={'1.3.6.1.4.1.4203.1.5.1'}), Attribute(name=supportedLDAPVersion, values={'2', '3'}), Attribute(name=supportedSASLMechanisms, values={'EXTERNAL', 'GSS-SPNEGO', 'GSSAPI', 'DIGEST-MD5', 'CRAM-MD5', 'LOGIN', 'PLAIN', 'ANONYMOUS'}), Attribute(name=vendorName, values={'389 Project'}), Attribute(name=vendorVersion, values={'389-Directory/1.3.7.5 B2018.269.1826'})] 2018-10-31 16:48:08,796-05 FINE createPool Return ConnectionPoolEntry(name='authz', connectionPool=LDAPConnectionPool(serverSet=SingleServerSet(server=ldap.cyber-range.lan:389, includesAuthentication=false, includesPostConnectProcessing=false), maxConnections=20), supportPaging=true, supportPasswordModify=true, supportWhoAmI=true) 2018-10-31 16:48:08,796-05 FINE End sequence simple-open-pools create authz pool 2018-10-31 16:48:08,796-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,796-05 FINEST authz_enable = 1 2018-10-31 16:48:08,796-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:08,797-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:08,797-05 FINEST maxFilterSize = 50 2018-10-31 16:48:08,797-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,797-05 FINEST simple_attrGroupMemberDN = uniqueMember:uniqueMemberMatch: 2018-10-31 16:48:08,797-05 FINEST simple_attrMemberOf = memberOf 2018-10-31 16:48:08,797-05 FINEST simple_attrsBaseDN = defaultNamingContext 2018-10-31 16:48:08,797-05 FINEST simple_attrsBaseDNIndex = 0 2018-10-31 16:48:08,797-05 FINEST simple_attrsGroupRecord = nsuniqueid, cn, description 2018-10-31 16:48:08,797-05 FINEST simple_attrsPrincipalRecord = nsuniqueid, uid, cn, displayName, department, givenName, sn, title, mail 2018-10-31 16:48:08,797-05 FINEST simple_attrsUserName = uid 2018-10-31 16:48:08,797-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,797-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:08,798-05 FINEST simple_filterGroupObject = (objectClass=groupOfUniqueNames) 2018-10-31 16:48:08,798-05 FINEST simple_filterUserObject = (objectClass=organizationalPerson)(uid=*) 2018-10-31 16:48:08,798-05 FINEST simple_groupLogic = member 2018-10-31 16:48:08,798-05 FINEST stop = false 2018-10-31 16:48:08,798-05 FINEST VARS-END 2018-10-31 16:48:08,798-05 FINE Running sequence simple-open-pools/020/pool-create create authn pool 2018-10-31 16:48:08,798-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,798-05 FINEST authz_enable = 1 2018-10-31 16:48:08,798-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:08,798-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:08,798-05 FINEST maxFilterSize = 50 2018-10-31 16:48:08,798-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,798-05 FINEST simple_attrGroupMemberDN = uniqueMember:uniqueMemberMatch: 2018-10-31 16:48:08,799-05 FINEST simple_attrMemberOf = memberOf 2018-10-31 16:48:08,799-05 FINEST simple_attrsBaseDN = defaultNamingContext 2018-10-31 16:48:08,799-05 FINEST simple_attrsBaseDNIndex = 0 2018-10-31 16:48:08,799-05 FINEST simple_attrsGroupRecord = nsuniqueid, cn, description 2018-10-31 16:48:08,799-05 FINEST simple_attrsPrincipalRecord = nsuniqueid, uid, cn, displayName, department, givenName, sn, title, mail 2018-10-31 16:48:08,799-05 FINEST simple_attrsUserName = uid 2018-10-31 16:48:08,799-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,799-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:08,799-05 FINEST simple_filterGroupObject = (objectClass=groupOfUniqueNames) 2018-10-31 16:48:08,799-05 FINEST simple_filterUserObject = (objectClass=organizationalPerson)(uid=*) 2018-10-31 16:48:08,799-05 FINEST simple_groupLogic = member 2018-10-31 16:48:08,799-05 FINEST stop = false 2018-10-31 16:48:08,799-05 FINEST VARS-END 2018-10-31 16:48:08,799-05 FINE Skip 2018-10-31 16:48:08,799-05 FINE runSequence Return name='simple-open-pools' 2018-10-31 16:48:08,799-05 FINE runSequence Entry name='simple-open-vars' 2018-10-31 16:48:08,800-05 FINE Running sequence simple-open-vars/010/fetch-record set base DN 2018-10-31 16:48:08,800-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,800-05 FINEST authz_enable = 1 2018-10-31 16:48:08,800-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:08,800-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:08,800-05 FINEST maxFilterSize = 50 2018-10-31 16:48:08,800-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,800-05 FINEST simple_attrGroupMemberDN = uniqueMember:uniqueMemberMatch: 2018-10-31 16:48:08,800-05 FINEST simple_attrMemberOf = memberOf 2018-10-31 16:48:08,800-05 FINEST simple_attrsBaseDN = defaultNamingContext 2018-10-31 16:48:08,800-05 FINEST simple_attrsBaseDNIndex = 0 2018-10-31 16:48:08,800-05 FINEST simple_attrsGroupRecord = nsuniqueid, cn, description 2018-10-31 16:48:08,800-05 FINEST simple_attrsPrincipalRecord = nsuniqueid, uid, cn, displayName, department, givenName, sn, title, mail 2018-10-31 16:48:08,800-05 FINEST simple_attrsUserName = uid 2018-10-31 16:48:08,800-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,801-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:08,801-05 FINEST simple_filterGroupObject = (objectClass=groupOfUniqueNames) 2018-10-31 16:48:08,801-05 FINEST simple_filterUserObject = (objectClass=organizationalPerson)(uid=*) 2018-10-31 16:48:08,801-05 FINEST simple_groupLogic = member 2018-10-31 16:48:08,801-05 FINEST stop = false 2018-10-31 16:48:08,801-05 FINEST VARS-END 2018-10-31 16:48:08,801-05 FINE Skip 2018-10-31 16:48:08,801-05 FINE runSequence Return name='simple-open-vars' 2018-10-31 16:48:08,801-05 FINE open Return globals={simple_attrMemberOf=memberOf, simple_filterGroupObject=(objectClass=groupOfUniqueNames), simple_filterUserObject=(objectClass=organizationalPerson)(uid=*), maxFilterSize=50, capability_recursiveGroupResolution=false, simple_attrsBaseDNIndex=0, simple_attrsPrincipalRecord=nsuniqueid, uid, cn, displayName, department, givenName, sn, title, mail, capability_credentialsChange=false, simple_attrsBaseDN=defaultNamingContext, sensitiveKeys=, password, passwordNew, simple_attrsGroupRecord=nsuniqueid, cn, description, authz_enable=1, simple_baseDN=dc=cyber-range,dc=lan, simple_attrsUserName=uid, stop=false, simple_attrGroupMemberDN=uniqueMember:uniqueMemberMatch:, simple_bindFormat=dn, simple_groupLogic=member} 2018-10-31 16:48:08,801-05 FINE runSequence Entry name='namespace' 2018-10-31 16:48:08,801-05 FINE Running sequence namespace/stub/call call simple 2018-10-31 16:48:08,802-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,802-05 FINEST authz_enable = 1 2018-10-31 16:48:08,802-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:08,802-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:08,802-05 FINEST maxFilterSize = 50 2018-10-31 16:48:08,802-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,802-05 FINEST simple_attrGroupMemberDN = uniqueMember:uniqueMemberMatch: 2018-10-31 16:48:08,802-05 FINEST simple_attrMemberOf = memberOf 2018-10-31 16:48:08,802-05 FINEST simple_attrsBaseDN = defaultNamingContext 2018-10-31 16:48:08,802-05 FINEST simple_attrsBaseDNIndex = 0 2018-10-31 16:48:08,802-05 FINEST simple_attrsGroupRecord = nsuniqueid, cn, description 2018-10-31 16:48:08,802-05 FINEST simple_attrsPrincipalRecord = nsuniqueid, uid, cn, displayName, department, givenName, sn, title, mail 2018-10-31 16:48:08,802-05 FINEST simple_attrsUserName = uid 2018-10-31 16:48:08,802-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,802-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:08,802-05 FINEST simple_filterGroupObject = (objectClass=groupOfUniqueNames) 2018-10-31 16:48:08,803-05 FINEST simple_filterUserObject = (objectClass=organizationalPerson)(uid=*) 2018-10-31 16:48:08,803-05 FINEST simple_groupLogic = member 2018-10-31 16:48:08,803-05 FINEST stop = false 2018-10-31 16:48:08,803-05 FINEST VARS-END 2018-10-31 16:48:08,803-05 FINE runSequence Entry name='simple-namespace' 2018-10-31 16:48:08,803-05 FINE Running sequence simple-namespace/010/var-set set default namespace 2018-10-31 16:48:08,803-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,803-05 FINEST authz_enable = 1 2018-10-31 16:48:08,803-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:08,803-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:08,803-05 FINEST maxFilterSize = 50 2018-10-31 16:48:08,803-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,803-05 FINEST simple_attrGroupMemberDN = uniqueMember:uniqueMemberMatch: 2018-10-31 16:48:08,803-05 FINEST simple_attrMemberOf = memberOf 2018-10-31 16:48:08,803-05 FINEST simple_attrsBaseDN = defaultNamingContext 2018-10-31 16:48:08,803-05 FINEST simple_attrsBaseDNIndex = 0 2018-10-31 16:48:08,804-05 FINEST simple_attrsGroupRecord = nsuniqueid, cn, description 2018-10-31 16:48:08,804-05 FINEST simple_attrsPrincipalRecord = nsuniqueid, uid, cn, displayName, department, givenName, sn, title, mail 2018-10-31 16:48:08,804-05 FINEST simple_attrsUserName = uid 2018-10-31 16:48:08,804-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,804-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:08,804-05 FINEST simple_filterGroupObject = (objectClass=groupOfUniqueNames) 2018-10-31 16:48:08,804-05 FINEST simple_filterUserObject = (objectClass=organizationalPerson)(uid=*) 2018-10-31 16:48:08,804-05 FINEST simple_groupLogic = member 2018-10-31 16:48:08,804-05 FINEST stop = false 2018-10-31 16:48:08,804-05 FINEST VARS-END 2018-10-31 16:48:08,804-05 FINE Skip 2018-10-31 16:48:08,804-05 FINE Running sequence simple-namespace/020/var-list-set namespace search 2018-10-31 16:48:08,804-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,804-05 FINEST authz_enable = 1 2018-10-31 16:48:08,804-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:08,804-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:08,804-05 FINEST maxFilterSize = 50 2018-10-31 16:48:08,804-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,805-05 FINEST simple_attrGroupMemberDN = uniqueMember:uniqueMemberMatch: 2018-10-31 16:48:08,805-05 FINEST simple_attrMemberOf = memberOf 2018-10-31 16:48:08,805-05 FINEST simple_attrsBaseDN = defaultNamingContext 2018-10-31 16:48:08,805-05 FINEST simple_attrsBaseDNIndex = 0 2018-10-31 16:48:08,805-05 FINEST simple_attrsGroupRecord = nsuniqueid, cn, description 2018-10-31 16:48:08,805-05 FINEST simple_attrsPrincipalRecord = nsuniqueid, uid, cn, displayName, department, givenName, sn, title, mail 2018-10-31 16:48:08,805-05 FINEST simple_attrsUserName = uid 2018-10-31 16:48:08,805-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,805-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:08,805-05 FINEST simple_filterGroupObject = (objectClass=groupOfUniqueNames) 2018-10-31 16:48:08,805-05 FINEST simple_filterUserObject = (objectClass=organizationalPerson)(uid=*) 2018-10-31 16:48:08,805-05 FINEST simple_groupLogic = member 2018-10-31 16:48:08,805-05 FINEST stop = false 2018-10-31 16:48:08,805-05 FINEST VARS-END 2018-10-31 16:48:08,805-05 FINE End sequence simple-namespace namespace search 2018-10-31 16:48:08,805-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,805-05 FINEST authz_enable = 1 2018-10-31 16:48:08,805-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:08,806-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:08,806-05 FINEST maxFilterSize = 50 2018-10-31 16:48:08,806-05 FINEST namespaces = [dc=cyber-range,dc=lan] 2018-10-31 16:48:08,806-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,806-05 FINEST simple_attrGroupMemberDN = uniqueMember:uniqueMemberMatch: 2018-10-31 16:48:08,806-05 FINEST simple_attrMemberOf = memberOf 2018-10-31 16:48:08,806-05 FINEST simple_attrsBaseDN = defaultNamingContext 2018-10-31 16:48:08,806-05 FINEST simple_attrsBaseDNIndex = 0 2018-10-31 16:48:08,806-05 FINEST simple_attrsGroupRecord = nsuniqueid, cn, description 2018-10-31 16:48:08,806-05 FINEST simple_attrsPrincipalRecord = nsuniqueid, uid, cn, displayName, department, givenName, sn, title, mail 2018-10-31 16:48:08,806-05 FINEST simple_attrsUserName = uid 2018-10-31 16:48:08,806-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,806-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:08,806-05 FINEST simple_filterGroupObject = (objectClass=groupOfUniqueNames) 2018-10-31 16:48:08,806-05 FINEST simple_filterUserObject = (objectClass=organizationalPerson)(uid=*) 2018-10-31 16:48:08,806-05 FINEST simple_groupLogic = member 2018-10-31 16:48:08,806-05 FINEST stop = false 2018-10-31 16:48:08,806-05 FINEST VARS-END 2018-10-31 16:48:08,806-05 FINE runSequence Return name='simple-namespace' 2018-10-31 16:48:08,807-05 FINE End sequence namespace call simple 2018-10-31 16:48:08,807-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,807-05 FINEST authz_enable = 1 2018-10-31 16:48:08,807-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:08,807-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:08,807-05 FINEST maxFilterSize = 50 2018-10-31 16:48:08,807-05 FINEST namespaces = [dc=cyber-range,dc=lan] 2018-10-31 16:48:08,807-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,807-05 FINEST simple_attrGroupMemberDN = uniqueMember:uniqueMemberMatch: 2018-10-31 16:48:08,807-05 FINEST simple_attrMemberOf = memberOf 2018-10-31 16:48:08,807-05 FINEST simple_attrsBaseDN = defaultNamingContext 2018-10-31 16:48:08,807-05 FINEST simple_attrsBaseDNIndex = 0 2018-10-31 16:48:08,807-05 FINEST simple_attrsGroupRecord = nsuniqueid, cn, description 2018-10-31 16:48:08,807-05 FINEST simple_attrsPrincipalRecord = nsuniqueid, uid, cn, displayName, department, givenName, sn, title, mail 2018-10-31 16:48:08,807-05 FINEST simple_attrsUserName = uid 2018-10-31 16:48:08,807-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,807-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:08,807-05 FINEST simple_filterGroupObject = (objectClass=groupOfUniqueNames) 2018-10-31 16:48:08,807-05 FINEST simple_filterUserObject = (objectClass=organizationalPerson)(uid=*) 2018-10-31 16:48:08,808-05 FINEST simple_groupLogic = member 2018-10-31 16:48:08,808-05 FINEST stop = false 2018-10-31 16:48:08,808-05 FINEST VARS-END 2018-10-31 16:48:08,808-05 FINE runSequence Return name='namespace' 2018-10-31 16:48:08,808-05 INFO [ovirt-engine-extension-aaa-ldap.authz::ldap.cyber-range.lan] Available Namespaces: [dc=cyber-range,dc=lan] 2018-10-31 16:48:08,808-05 FINEST Invoke Output BEGIN 2018-10-31 16:48:08,808-05 FINEST {Extkey[name=EXTENSION_INVOKE_RESULT;type=class java.lang.Integer;uuid=EXTENSION_INVOKE_RESULT[0909d91d-8bde-40fb-b6c0-099c772ddd4e];]=0} 2018-10-31 16:48:08,808-05 FINEST Invoke Output END 2018-10-31 16:48:08,808-05 INFO Extension 'ldap.cyber-range.lan' initialized 2018-10-31 16:48:08,808-05 FINE Extension 'ldap.cyber-range.lan' initialized 2018-10-31 16:48:08,808-05 INFO Initializing extension 'ldap.cyber-range.lan-authn' 2018-10-31 16:48:08,808-05 FINEST Invoke Input BEGIN 2018-10-31 16:48:08,809-05 FINEST {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], 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_LICENSE;type=class java.lang.String;uuid=EXTENSION_LICENSE[8a61ad65-054c-4e31-9c6d-1ca4d60a4c18];]=ASL 2.0, 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_NAME;type=class java.lang.String;uuid=EXTENSION_NAME[651381d3-f54f-4547-bf28-b0b01a103184];]=ovirt-engine-extension-aaa-ldap.authn, Extkey[name=EXTENSION_MANAGER_TRACE_LOG;type=interface org.slf4j.Logger;uuid=EXTENSION_MANAGER_TRACE_LOG[863db666-3ea7-4751-9695-918a3197ad83];]=org.slf4j.impl.JDK14LoggerAdapter(org.ovirt.engine.core.extensions.mgr.ExtensionsManager.trace.ovirt-engine-extension-aaa-ldap.authn.ldap.cyber-range.lan-authn), Extkey[name=EXTENSION_CONFIGURATION_SENSITIVE_KEYS;type=interface java.util.Collection;uuid=EXTENSION_CONFIGURATION_SENSITIVE_KEYS[a456efa1-73ff-4204-9f9b-ebff01e35263];]=[], Extkey[name=EXTENSION_VERSION;type=class java.lang.String;uuid=EXTENSION_VERSION[fe35f6a8-8239-4bdb-ab1a-af9f779ce68c];]=1.3.7, 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_AUTHOR;type=class java.lang.String;uuid=EXTENSION_AUTHOR[ef242f7a-2dad-4bc5-9aad-e07018b7fbcc];]=The oVirt Project, Extkey[name=EXTENSION_LOCALE;type=class java.lang.String;uuid=EXTENSION_LOCALE[0780b112-0ce0-404a-b85e-8765d778bb29];]=en_US, Extkey[name=EXTENSION_CONFIGURATION_FILE;type=class java.lang.String;uuid=EXTENSION_CONFIGURATION_FILE[4fb0ffd3-983c-4f3f-98ff-9660bd67af6a];]=/etc/ovirt-engine/extensions.d/ldap.cyber-range.lan-authn.properties, 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_CONFIGURATION;type=class java.util.Properties;uuid=EXTENSION_CONFIGURATION[2d48ab72-f0a1-4312-b4ae-5068a226b0fc];]=***, 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_INTERFACE_VERSION_MIN;type=class java.lang.Integer;uuid=EXTENSION_INTERFACE_VERSION_MIN[2b84fc91-305b-497b-a1d7-d961b9d2ce0b];]=0, Extkey[name=EXTENSION_INSTANCE_NAME;type=class java.lang.String;uuid=EXTENSION_INSTANCE_NAME[65c67ff6-aeca-4bd5-a245-8674327f011b];]=ldap.cyber-range.lan-authn, 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_AUTHN_CAPABILITIES;type=class java.lang.Long;uuid=AAA_AUTHN_CAPABILITIES[9d16bee3-10fd-46f2-83f9-3d3c54cf258d];]=12, Extkey[name=EXTENSION_NOTES;type=class java.lang.String;uuid=EXTENSION_NOTES[2da5ad7e-185a-4584-aaff-97f66978e4ea];]=Display name: ovirt-engine-extension-aaa-ldap-1.3.7-1.el7.centos}} 2018-10-31 16:48:08,809-05 FINEST Invoke Input END 2018-10-31 16:48:08,809-05 FINE open Entry 2018-10-31 16:48:08,809-05 FINE runSequence Entry name='simple-open-pools' 2018-10-31 16:48:08,809-05 FINE Running sequence simple-open-pools/010/pool-create create authz pool 2018-10-31 16:48:08,809-05 FINEST VARS-BEGIN 2018-10-31 16:48:08,809-05 FINEST authn_enable = 1 2018-10-31 16:48:08,809-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:08,809-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:08,809-05 FINEST maxFilterSize = 50 2018-10-31 16:48:08,809-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:08,809-05 FINEST simple_attrGroupMemberDN = uniqueMember:uniqueMemberMatch: 2018-10-31 16:48:08,809-05 FINEST simple_attrMemberOf = memberOf 2018-10-31 16:48:08,809-05 FINEST simple_attrsBaseDN = defaultNamingContext 2018-10-31 16:48:08,809-05 FINEST simple_attrsBaseDNIndex = 0 2018-10-31 16:48:08,809-05 FINEST simple_attrsGroupRecord = nsuniqueid, cn, description 2018-10-31 16:48:08,810-05 FINEST simple_attrsPrincipalRecord = nsuniqueid, uid, cn, displayName, department, givenName, sn, title, mail 2018-10-31 16:48:08,810-05 FINEST simple_attrsUserName = uid 2018-10-31 16:48:08,810-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:08,810-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:08,810-05 FINEST simple_filterGroupObject = (objectClass=groupOfUniqueNames) 2018-10-31 16:48:08,810-05 FINEST simple_filterUserObject = (objectClass=organizationalPerson)(uid=*) 2018-10-31 16:48:08,810-05 FINEST simple_groupLogic = member 2018-10-31 16:48:08,810-05 FINEST stop = false 2018-10-31 16:48:08,810-05 FINEST VARS-END 2018-10-31 16:48:08,810-05 FINE getConnectionPoolEntry Entry name='authz', dn='null' 2018-10-31 16:48:08,810-05 FINE getConnectionPoolEntry no pool for 'authz' 2018-10-31 16:48:08,810-05 INFO [ovirt-engine-extension-aaa-ldap.authn::ldap.cyber-range.lan-authn] Creating LDAP pool 'authz' 2018-10-31 16:48:08,810-05 FINE createPool Entry name='authz' 2018-10-31 16:48:08,811-05 FINE createConnectionPool Entry 2018-10-31 16:48:08,811-05 FINE Creating HostNameSSLSocketVerifier 2018-10-31 16:48:08,811-05 FINE HostNameSSLSocketVerifier: com.unboundid.util.ssl.HostNameSSLSocketVerifier@dd3b207 2018-10-31 16:48:08,811-05 FINE Creating trust store 2018-10-31 16:48:08,811-05 FINE Creating key store 2018-10-31 16:48:08,811-05 FINE Creating StartTLSPostConnectProcessor 2018-10-31 16:48:08,811-05 FINE StartTLSPostConnectProcessor: com.unboundid.ldap.sdk.StartTLSPostConnectProcessor@551bdc27 2018-10-31 16:48:08,811-05 FINE Creating LDAPConnectionOptions 2018-10-31 16:48:08,812-05 FINE 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, responseTimeoutMillis.ABANDON=60000, responseTimeoutMillis.ADD=60000, responseTimeoutMillis.BIND=60000, responseTimeoutMillis.COMPARE=60000, responseTimeoutMillis.DELETE=60000, responseTimeoutMillis.EXTENDED=60000, responseTimeoutMillis.MODIFY=60000, responseTimeoutMillis.MODIFY_DN=60000, responseTimeoutMillis.SEARCH=60000, responseTimeoutMillis.UNBIND=60000, abandonOnTimeout=true, maxMessageSizeBytes=20971520, receiveBufferSizeBytes=0, sendBufferSizeBytes=0, allowConcurrentSocketFactoryUse=true, sslSocketVerifierClass='com.unboundid.util.ssl.HostNameSSLSocketVerifier') 2018-10-31 16:48:08,812-05 FINE Creating SocketFactory 2018-10-31 16:48:08,812-05 FINE Creating resolver 2018-10-31 16:48:08,813-05 FINE Open: Context: {java.naming.provider.url=dns://, java.naming.factory.initial=com.sun.jndi.dns.DnsContextFactory} 2018-10-31 16:48:08,813-05 FINE Resolver: Resolver(env='{java.naming.provider.url=dns://, java.naming.factory.initial=com.sun.jndi.dns.DnsContextFactory}', supportIPv6='false', cacheTTL='10000') 2018-10-31 16:48:08,813-05 FINE SocketFactory: org.ovirt.engineextensions.aaa.ldap.ResolverSocketFactory@58fdd99 2018-10-31 16:48:08,813-05 FINE Creating ServerSet 2018-10-31 16:48:08,813-05 FINE ServerSet: SingleServerSet(server=ldap.cyber-range.lan:389, includesAuthentication=false, includesPostConnectProcessing=false) 2018-10-31 16:48:08,813-05 FINE Creating BindRequest 2018-10-31 16:48:08,813-05 FINE createBindRequest Entry type='simple', user='' 2018-10-31 16:48:08,813-05 FINE createBindRequest Return SimpleBindRequest(dn='uid=admin,ou=Administrators,ou=TopologyManagement,o=NetscapeRoot') 2018-10-31 16:48:08,813-05 FINE BindRequest: SimpleBindRequest(dn='uid=admin,ou=Administrators,ou=TopologyManagement,o=NetscapeRoot') 2018-10-31 16:48:08,813-05 FINE Creating LDAPConnectionPool 2018-10-31 16:48:08,814-05 FINE Entry: name='ldap.cyber-range.lan' 2018-10-31 16:48:08,814-05 FINE Entry: name='ldap.cyber-range.lan' 2018-10-31 16:48:08,814-05 FINE Cache miss 2018-10-31 16:48:08,814-05 FINE Entry: name='ldap.cyber-range.lan' 2018-10-31 16:48:08,814-05 FINE Entry: name='ldap.cyber-range.lan', attrs=[A] 2018-10-31 16:48:08,815-05 FINE Return: {a=A: 172.30.50.6} 2018-10-31 16:48:08,815-05 FINE A 172.30.50.6 2018-10-31 16:48:08,815-05 FINE Return: [172.30.50.6] 2018-10-31 16:48:08,815-05 FINE Return: [ldap.cyber-range.lan/172.30.50.6] 2018-10-31 16:48:08,815-05 FINE Return: ldap.cyber-range.lan/172.30.50.6 2018-10-31 16:48:08,875-05 FINE Entry: name='ldap.cyber-range.lan' 2018-10-31 16:48:08,875-05 FINE Entry: name='ldap.cyber-range.lan' 2018-10-31 16:48:08,875-05 FINE Return: [ldap.cyber-range.lan/172.30.50.6] 2018-10-31 16:48:08,876-05 FINE Return: ldap.cyber-range.lan/172.30.50.6 2018-10-31 16:48:08,935-05 FINE Entry: name='ldap.cyber-range.lan' 2018-10-31 16:48:08,935-05 FINE Entry: name='ldap.cyber-range.lan' 2018-10-31 16:48:08,935-05 FINE Return: [ldap.cyber-range.lan/172.30.50.6] 2018-10-31 16:48:08,935-05 FINE Return: ldap.cyber-range.lan/172.30.50.6 2018-10-31 16:48:08,994-05 FINE Entry: name='ldap.cyber-range.lan' 2018-10-31 16:48:08,994-05 FINE Entry: name='ldap.cyber-range.lan' 2018-10-31 16:48:08,994-05 FINE Return: [ldap.cyber-range.lan/172.30.50.6] 2018-10-31 16:48:08,994-05 FINE Return: ldap.cyber-range.lan/172.30.50.6 2018-10-31 16:48:09,061-05 FINE createConnectionPool Return: LDAPConnectionPool(serverSet=SingleServerSet(server=ldap.cyber-range.lan:389, includesAuthentication=false, includesPostConnectProcessing=false), maxConnections=20) 2018-10-31 16:48:09,064-05 INFO [ovirt-engine-extension-aaa-ldap.authn::ldap.cyber-range.lan-authn] LDAP pool 'authz' information: vendor='389 Project' version='389-Directory/1.3.7.5 B2018.269.1826' 2018-10-31 16:48:09,064-05 FINE RootDSE: [Attribute(name=objectClass, values={'top'}), Attribute(name=creatorsName, values={'cn=server,cn=plugins,cn=config'}), Attribute(name=modifiersName, values={'cn=server,cn=plugins,cn=config'}), Attribute(name=createTimestamp, values={'20181021140445Z'}), Attribute(name=modifyTimestamp, values={'20181021140445Z'}), Attribute(name=defaultnamingcontext, values={'dc=cyber-range,dc=lan'}), Attribute(name=nsBackendSuffix, values={'userRoot:dc=cyber-range,dc=lan', 'NetscapeRoot:o=netscaperoot'}), Attribute(name=dataversion, values={'020181031011613020181031011613'}), Attribute(name=netscapemdsuffix, values={'cn=ldap://dc=ldap,dc=cyber-range,dc=lan:389'}), Attribute(name=namingContexts, values={'dc=cyber-range,dc=lan', 'o=netscaperoot'}), Attribute(name=subschemaSubentry, values={'cn=schema'}), Attribute(name=supportedControl, values={'2.16.840.1.113730.3.4.2', '2.16.840.1.113730.3.4.3', '2.16.840.1.113730.3.4.4', '2.16.840.1.113730.3.4.5', '1.2.840.113556.1.4.473', '2.16.840.1.113730.3.4.9', '2.16.840.1.113730.3.4.16', '2.16.840.1.113730.3.4.15', '2.16.840.1.113730.3.4.17', '2.16.840.1.113730.3.4.19', '1.3.6.1.1.13.1', '1.3.6.1.1.13.2', '1.3.6.1.4.1.42.2.27.8.5.1', '1.3.6.1.4.1.42.2.27.9.5.2', '1.2.840.113556.1.4.319', '1.3.6.1.4.1.42.2.27.9.5.8', '1.3.6.1.4.1.4203.666.5.16', '2.16.840.1.113730.3.4.14', '2.16.840.1.113730.3.4.20', '1.3.6.1.4.1.1466.29539.12', '2.16.840.1.113730.3.4.12', '2.16.840.1.113730.3.4.18', '2.16.840.1.113730.3.4.13'}), Attribute(name=supportedExtension, values={'2.16.840.1.113730.3.5.7', '2.16.840.1.113730.3.5.8', '2.16.840.1.113730.3.5.3', '2.16.840.1.113730.3.5.12', '2.16.840.1.113730.3.5.5', '2.16.840.1.113730.3.5.6', '2.16.840.1.113730.3.5.9', '2.16.840.1.113730.3.5.4', '2.16.840.1.113730.3.6.5', '2.16.840.1.113730.3.6.6', '2.16.840.1.113730.3.6.7', '2.16.840.1.113730.3.6.8', '1.3.6.1.4.1.4203.1.11.3', '1.3.6.1.4.1.1466.20037', '1.3.6.1.4.1.4203.1.11.1'}), Attribute(name=supportedFeatures, values={'1.3.6.1.4.1.4203.1.5.1'}), Attribute(name=supportedLDAPVersion, values={'2', '3'}), Attribute(name=supportedSASLMechanisms, values={'EXTERNAL', 'GSS-SPNEGO', 'GSSAPI', 'DIGEST-MD5', 'CRAM-MD5', 'LOGIN', 'PLAIN', 'ANONYMOUS'}), Attribute(name=vendorName, values={'389 Project'}), Attribute(name=vendorVersion, values={'389-Directory/1.3.7.5 B2018.269.1826'})] 2018-10-31 16:48:09,064-05 FINE createPool Return ConnectionPoolEntry(name='authz', connectionPool=LDAPConnectionPool(serverSet=SingleServerSet(server=ldap.cyber-range.lan:389, includesAuthentication=false, includesPostConnectProcessing=false), maxConnections=20), supportPaging=true, supportPasswordModify=true, supportWhoAmI=true) 2018-10-31 16:48:09,064-05 FINE End sequence simple-open-pools create authz pool 2018-10-31 16:48:09,064-05 FINEST VARS-BEGIN 2018-10-31 16:48:09,064-05 FINEST authn_enable = 1 2018-10-31 16:48:09,065-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:09,065-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:09,065-05 FINEST maxFilterSize = 50 2018-10-31 16:48:09,065-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:09,065-05 FINEST simple_attrGroupMemberDN = uniqueMember:uniqueMemberMatch: 2018-10-31 16:48:09,065-05 FINEST simple_attrMemberOf = memberOf 2018-10-31 16:48:09,065-05 FINEST simple_attrsBaseDN = defaultNamingContext 2018-10-31 16:48:09,065-05 FINEST simple_attrsBaseDNIndex = 0 2018-10-31 16:48:09,065-05 FINEST simple_attrsGroupRecord = nsuniqueid, cn, description 2018-10-31 16:48:09,065-05 FINEST simple_attrsPrincipalRecord = nsuniqueid, uid, cn, displayName, department, givenName, sn, title, mail 2018-10-31 16:48:09,065-05 FINEST simple_attrsUserName = uid 2018-10-31 16:48:09,065-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:09,065-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:09,065-05 FINEST simple_filterGroupObject = (objectClass=groupOfUniqueNames) 2018-10-31 16:48:09,065-05 FINEST simple_filterUserObject = (objectClass=organizationalPerson)(uid=*) 2018-10-31 16:48:09,065-05 FINEST simple_groupLogic = member 2018-10-31 16:48:09,065-05 FINEST stop = false 2018-10-31 16:48:09,065-05 FINEST VARS-END 2018-10-31 16:48:09,065-05 FINE Running sequence simple-open-pools/020/pool-create create authn pool 2018-10-31 16:48:09,066-05 FINEST VARS-BEGIN 2018-10-31 16:48:09,066-05 FINEST authn_enable = 1 2018-10-31 16:48:09,066-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:09,066-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:09,066-05 FINEST maxFilterSize = 50 2018-10-31 16:48:09,066-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:09,066-05 FINEST simple_attrGroupMemberDN = uniqueMember:uniqueMemberMatch: 2018-10-31 16:48:09,066-05 FINEST simple_attrMemberOf = memberOf 2018-10-31 16:48:09,066-05 FINEST simple_attrsBaseDN = defaultNamingContext 2018-10-31 16:48:09,066-05 FINEST simple_attrsBaseDNIndex = 0 2018-10-31 16:48:09,066-05 FINEST simple_attrsGroupRecord = nsuniqueid, cn, description 2018-10-31 16:48:09,066-05 FINEST simple_attrsPrincipalRecord = nsuniqueid, uid, cn, displayName, department, givenName, sn, title, mail 2018-10-31 16:48:09,066-05 FINEST simple_attrsUserName = uid 2018-10-31 16:48:09,066-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:09,066-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:09,066-05 FINEST simple_filterGroupObject = (objectClass=groupOfUniqueNames) 2018-10-31 16:48:09,066-05 FINEST simple_filterUserObject = (objectClass=organizationalPerson)(uid=*) 2018-10-31 16:48:09,066-05 FINEST simple_groupLogic = member 2018-10-31 16:48:09,066-05 FINEST stop = false 2018-10-31 16:48:09,066-05 FINEST VARS-END 2018-10-31 16:48:09,067-05 FINE getConnectionPoolEntry Entry name='authn', dn='null' 2018-10-31 16:48:09,067-05 FINE getConnectionPoolEntry no pool for 'authn' 2018-10-31 16:48:09,067-05 INFO [ovirt-engine-extension-aaa-ldap.authn::ldap.cyber-range.lan-authn] Creating LDAP pool 'authn' 2018-10-31 16:48:09,067-05 FINE createPool Entry name='authn' 2018-10-31 16:48:09,067-05 FINE createConnectionPool Entry 2018-10-31 16:48:09,067-05 FINE Creating HostNameSSLSocketVerifier 2018-10-31 16:48:09,067-05 FINE HostNameSSLSocketVerifier: com.unboundid.util.ssl.HostNameSSLSocketVerifier@6b1274d2 2018-10-31 16:48:09,067-05 FINE Creating trust store 2018-10-31 16:48:09,068-05 FINE Creating key store 2018-10-31 16:48:09,068-05 FINE Creating StartTLSPostConnectProcessor 2018-10-31 16:48:09,068-05 FINE StartTLSPostConnectProcessor: com.unboundid.ldap.sdk.StartTLSPostConnectProcessor@7bc1a03d 2018-10-31 16:48:09,068-05 FINE Creating LDAPConnectionOptions 2018-10-31 16:48:09,068-05 FINE 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, responseTimeoutMillis.ABANDON=60000, responseTimeoutMillis.ADD=60000, responseTimeoutMillis.BIND=60000, responseTimeoutMillis.COMPARE=60000, responseTimeoutMillis.DELETE=60000, responseTimeoutMillis.EXTENDED=60000, responseTimeoutMillis.MODIFY=60000, responseTimeoutMillis.MODIFY_DN=60000, responseTimeoutMillis.SEARCH=60000, responseTimeoutMillis.UNBIND=60000, abandonOnTimeout=true, maxMessageSizeBytes=20971520, receiveBufferSizeBytes=0, sendBufferSizeBytes=0, allowConcurrentSocketFactoryUse=true, sslSocketVerifierClass='com.unboundid.util.ssl.HostNameSSLSocketVerifier') 2018-10-31 16:48:09,068-05 FINE Creating SocketFactory 2018-10-31 16:48:09,068-05 FINE Creating resolver 2018-10-31 16:48:09,069-05 FINE Open: Context: {java.naming.provider.url=dns://, java.naming.factory.initial=com.sun.jndi.dns.DnsContextFactory} 2018-10-31 16:48:09,069-05 FINE Resolver: Resolver(env='{java.naming.provider.url=dns://, java.naming.factory.initial=com.sun.jndi.dns.DnsContextFactory}', supportIPv6='false', cacheTTL='10000') 2018-10-31 16:48:09,069-05 FINE SocketFactory: org.ovirt.engineextensions.aaa.ldap.ResolverSocketFactory@ba8d91c 2018-10-31 16:48:09,069-05 FINE Creating ServerSet 2018-10-31 16:48:09,070-05 FINE ServerSet: SingleServerSet(server=ldap.cyber-range.lan:389, includesAuthentication=false, includesPostConnectProcessing=false) 2018-10-31 16:48:09,070-05 FINE Creating BindRequest 2018-10-31 16:48:09,070-05 FINE createBindRequest Entry type='none', user='' 2018-10-31 16:48:09,070-05 FINE createBindRequest Return SimpleBindRequest(dn='') 2018-10-31 16:48:09,070-05 FINE BindRequest: SimpleBindRequest(dn='') 2018-10-31 16:48:09,070-05 FINE Creating LDAPConnectionPool 2018-10-31 16:48:09,075-05 FINE Entry: name='ldap.cyber-range.lan' 2018-10-31 16:48:09,075-05 FINE Entry: name='ldap.cyber-range.lan' 2018-10-31 16:48:09,075-05 FINE Cache miss 2018-10-31 16:48:09,075-05 FINE Entry: name='ldap.cyber-range.lan' 2018-10-31 16:48:09,075-05 FINE Entry: name='ldap.cyber-range.lan', attrs=[A] 2018-10-31 16:48:09,076-05 FINE Return: {a=A: 172.30.50.6} 2018-10-31 16:48:09,076-05 FINE A 172.30.50.6 2018-10-31 16:48:09,076-05 FINE Return: [172.30.50.6] 2018-10-31 16:48:09,076-05 FINE Return: [ldap.cyber-range.lan/172.30.50.6] 2018-10-31 16:48:09,076-05 FINE Return: ldap.cyber-range.lan/172.30.50.6 2018-10-31 16:48:09,135-05 FINE Entry: name='ldap.cyber-range.lan' 2018-10-31 16:48:09,135-05 FINE Entry: name='ldap.cyber-range.lan' 2018-10-31 16:48:09,135-05 FINE Return: [ldap.cyber-range.lan/172.30.50.6] 2018-10-31 16:48:09,135-05 FINE Return: ldap.cyber-range.lan/172.30.50.6 2018-10-31 16:48:09,195-05 FINE Entry: name='ldap.cyber-range.lan' 2018-10-31 16:48:09,196-05 FINE Entry: name='ldap.cyber-range.lan' 2018-10-31 16:48:09,196-05 FINE Return: [ldap.cyber-range.lan/172.30.50.6] 2018-10-31 16:48:09,196-05 FINE Return: ldap.cyber-range.lan/172.30.50.6 2018-10-31 16:48:09,255-05 FINE Entry: name='ldap.cyber-range.lan' 2018-10-31 16:48:09,255-05 FINE Entry: name='ldap.cyber-range.lan' 2018-10-31 16:48:09,255-05 FINE Return: [ldap.cyber-range.lan/172.30.50.6] 2018-10-31 16:48:09,255-05 FINE Return: ldap.cyber-range.lan/172.30.50.6 2018-10-31 16:48:09,313-05 FINE createConnectionPool Return: LDAPConnectionPool(serverSet=SingleServerSet(server=ldap.cyber-range.lan:389, includesAuthentication=false, includesPostConnectProcessing=false), maxConnections=20) 2018-10-31 16:48:09,315-05 INFO [ovirt-engine-extension-aaa-ldap.authn::ldap.cyber-range.lan-authn] LDAP pool 'authn' information: vendor='389 Project' version='389-Directory/1.3.7.5 B2018.269.1826' 2018-10-31 16:48:09,315-05 FINE RootDSE: [Attribute(name=objectClass, values={'top'}), Attribute(name=creatorsName, values={'cn=server,cn=plugins,cn=config'}), Attribute(name=modifiersName, values={'cn=server,cn=plugins,cn=config'}), Attribute(name=createTimestamp, values={'20181021140445Z'}), Attribute(name=modifyTimestamp, values={'20181021140445Z'}), Attribute(name=defaultnamingcontext, values={'dc=cyber-range,dc=lan'}), Attribute(name=nsBackendSuffix, values={'userRoot:dc=cyber-range,dc=lan', 'NetscapeRoot:o=netscaperoot'}), Attribute(name=dataversion, values={'020181031011613020181031011613'}), Attribute(name=netscapemdsuffix, values={'cn=ldap://dc=ldap,dc=cyber-range,dc=lan:389'}), Attribute(name=namingContexts, values={'dc=cyber-range,dc=lan', 'o=netscaperoot'}), Attribute(name=subschemaSubentry, values={'cn=schema'}), Attribute(name=supportedControl, values={'2.16.840.1.113730.3.4.2', '2.16.840.1.113730.3.4.3', '2.16.840.1.113730.3.4.4', '2.16.840.1.113730.3.4.5', '1.2.840.113556.1.4.473', '2.16.840.1.113730.3.4.9', '2.16.840.1.113730.3.4.16', '2.16.840.1.113730.3.4.15', '2.16.840.1.113730.3.4.17', '2.16.840.1.113730.3.4.19', '1.3.6.1.1.13.1', '1.3.6.1.1.13.2', '1.3.6.1.4.1.42.2.27.8.5.1', '1.3.6.1.4.1.42.2.27.9.5.2', '1.2.840.113556.1.4.319', '1.3.6.1.4.1.42.2.27.9.5.8', '1.3.6.1.4.1.4203.666.5.16', '2.16.840.1.113730.3.4.14', '2.16.840.1.113730.3.4.20', '1.3.6.1.4.1.1466.29539.12', '2.16.840.1.113730.3.4.12', '2.16.840.1.113730.3.4.18', '2.16.840.1.113730.3.4.13'}), Attribute(name=supportedExtension, values={'2.16.840.1.113730.3.5.7', '2.16.840.1.113730.3.5.8', '2.16.840.1.113730.3.5.3', '2.16.840.1.113730.3.5.12', '2.16.840.1.113730.3.5.5', '2.16.840.1.113730.3.5.6', '2.16.840.1.113730.3.5.9', '2.16.840.1.113730.3.5.4', '2.16.840.1.113730.3.6.5', '2.16.840.1.113730.3.6.6', '2.16.840.1.113730.3.6.7', '2.16.840.1.113730.3.6.8', '1.3.6.1.4.1.4203.1.11.3', '1.3.6.1.4.1.1466.20037', '1.3.6.1.4.1.4203.1.11.1'}), Attribute(name=supportedFeatures, values={'1.3.6.1.4.1.4203.1.5.1'}), Attribute(name=supportedLDAPVersion, values={'2', '3'}), Attribute(name=supportedSASLMechanisms, values={'EXTERNAL', 'GSS-SPNEGO', 'GSSAPI', 'DIGEST-MD5', 'CRAM-MD5', 'LOGIN', 'PLAIN', 'ANONYMOUS'}), Attribute(name=vendorName, values={'389 Project'}), Attribute(name=vendorVersion, values={'389-Directory/1.3.7.5 B2018.269.1826'})] 2018-10-31 16:48:09,315-05 FINE createPool Return ConnectionPoolEntry(name='authn', connectionPool=LDAPConnectionPool(serverSet=SingleServerSet(server=ldap.cyber-range.lan:389, includesAuthentication=false, includesPostConnectProcessing=false), maxConnections=20), supportPaging=true, supportPasswordModify=true, supportWhoAmI=true) 2018-10-31 16:48:09,315-05 FINE End sequence simple-open-pools create authn pool 2018-10-31 16:48:09,316-05 FINEST VARS-BEGIN 2018-10-31 16:48:09,316-05 FINEST authn_enable = 1 2018-10-31 16:48:09,316-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:09,316-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:09,316-05 FINEST maxFilterSize = 50 2018-10-31 16:48:09,316-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:09,316-05 FINEST simple_attrGroupMemberDN = uniqueMember:uniqueMemberMatch: 2018-10-31 16:48:09,316-05 FINEST simple_attrMemberOf = memberOf 2018-10-31 16:48:09,316-05 FINEST simple_attrsBaseDN = defaultNamingContext 2018-10-31 16:48:09,316-05 FINEST simple_attrsBaseDNIndex = 0 2018-10-31 16:48:09,316-05 FINEST simple_attrsGroupRecord = nsuniqueid, cn, description 2018-10-31 16:48:09,316-05 FINEST simple_attrsPrincipalRecord = nsuniqueid, uid, cn, displayName, department, givenName, sn, title, mail 2018-10-31 16:48:09,316-05 FINEST simple_attrsUserName = uid 2018-10-31 16:48:09,316-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:09,316-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:09,316-05 FINEST simple_filterGroupObject = (objectClass=groupOfUniqueNames) 2018-10-31 16:48:09,316-05 FINEST simple_filterUserObject = (objectClass=organizationalPerson)(uid=*) 2018-10-31 16:48:09,316-05 FINEST simple_groupLogic = member 2018-10-31 16:48:09,316-05 FINEST stop = false 2018-10-31 16:48:09,316-05 FINEST VARS-END 2018-10-31 16:48:09,317-05 FINE runSequence Return name='simple-open-pools' 2018-10-31 16:48:09,317-05 FINE runSequence Entry name='simple-open-vars' 2018-10-31 16:48:09,317-05 FINE Running sequence simple-open-vars/010/fetch-record set base DN 2018-10-31 16:48:09,317-05 FINEST VARS-BEGIN 2018-10-31 16:48:09,317-05 FINEST authn_enable = 1 2018-10-31 16:48:09,317-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:09,317-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:09,317-05 FINEST maxFilterSize = 50 2018-10-31 16:48:09,317-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:09,317-05 FINEST simple_attrGroupMemberDN = uniqueMember:uniqueMemberMatch: 2018-10-31 16:48:09,317-05 FINEST simple_attrMemberOf = memberOf 2018-10-31 16:48:09,317-05 FINEST simple_attrsBaseDN = defaultNamingContext 2018-10-31 16:48:09,317-05 FINEST simple_attrsBaseDNIndex = 0 2018-10-31 16:48:09,317-05 FINEST simple_attrsGroupRecord = nsuniqueid, cn, description 2018-10-31 16:48:09,317-05 FINEST simple_attrsPrincipalRecord = nsuniqueid, uid, cn, displayName, department, givenName, sn, title, mail 2018-10-31 16:48:09,317-05 FINEST simple_attrsUserName = uid 2018-10-31 16:48:09,317-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:09,318-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:09,318-05 FINEST simple_filterGroupObject = (objectClass=groupOfUniqueNames) 2018-10-31 16:48:09,318-05 FINEST simple_filterUserObject = (objectClass=organizationalPerson)(uid=*) 2018-10-31 16:48:09,318-05 FINEST simple_groupLogic = member 2018-10-31 16:48:09,318-05 FINEST stop = false 2018-10-31 16:48:09,318-05 FINEST VARS-END 2018-10-31 16:48:09,318-05 FINE Skip 2018-10-31 16:48:09,318-05 FINE runSequence Return name='simple-open-vars' 2018-10-31 16:48:09,318-05 FINE open Return globals={simple_attrMemberOf=memberOf, simple_filterGroupObject=(objectClass=groupOfUniqueNames), simple_filterUserObject=(objectClass=organizationalPerson)(uid=*), maxFilterSize=50, capability_recursiveGroupResolution=false, simple_attrsBaseDNIndex=0, simple_attrsPrincipalRecord=nsuniqueid, uid, cn, displayName, department, givenName, sn, title, mail, capability_credentialsChange=false, simple_attrsBaseDN=defaultNamingContext, sensitiveKeys=, password, passwordNew, simple_attrsGroupRecord=nsuniqueid, cn, description, authn_enable=1, simple_baseDN=dc=cyber-range,dc=lan, simple_attrsUserName=uid, stop=false, simple_attrGroupMemberDN=uniqueMember:uniqueMemberMatch:, simple_bindFormat=dn, simple_groupLogic=member} 2018-10-31 16:48:09,318-05 FINEST Invoke Output BEGIN 2018-10-31 16:48:09,318-05 FINEST {Extkey[name=EXTENSION_INVOKE_RESULT;type=class java.lang.Integer;uuid=EXTENSION_INVOKE_RESULT[0909d91d-8bde-40fb-b6c0-099c772ddd4e];]=0} 2018-10-31 16:48:09,318-05 FINEST Invoke Output END 2018-10-31 16:48:09,318-05 INFO Extension 'ldap.cyber-range.lan-authn' initialized 2018-10-31 16:48:09,318-05 FINE Extension 'ldap.cyber-range.lan-authn' initialized 2018-10-31 16:48:09,318-05 INFO Start of enabled extensions list 2018-10-31 16:48:09,318-05 INFO Instance name: 'internal-authn', Extension name: '"ovirt-engine-extension-aaa-jdbc".authn', Version: '"1.1.7_master"', Notes: 'Display name: "ovirt-engine-extension-aaa-jdbc"', License: 'ASL 2.0', Home: 'http://www.ovirt.org', Author 'The oVirt Project', Build interface Version: '0', File: '/etc/ovirt-engine/extensions.d/internal-authn.properties', Initialized: 'true' 2018-10-31 16:48:09,318-05 INFO Instance name: 'internal-authz', Extension name: '"ovirt-engine-extension-aaa-jdbc".authz', Version: '"1.1.7_master"', Notes: 'Display name: "ovirt-engine-extension-aaa-jdbc"', License: 'ASL 2.0', Home: 'http://www.ovirt.org', Author 'The oVirt Project', Build interface Version: '0', File: '/etc/ovirt-engine/extensions.d/internal-authz.properties', Initialized: 'true' 2018-10-31 16:48:09,319-05 INFO Instance name: 'ldap.cyber-range.lan-authn', Extension name: 'ovirt-engine-extension-aaa-ldap.authn', Version: '1.3.7', Notes: 'Display name: ovirt-engine-extension-aaa-ldap-1.3.7-1.el7.centos', License: 'ASL 2.0', Home: 'http://www.ovirt.org', Author 'The oVirt Project', Build interface Version: '0', File: '/etc/ovirt-engine/extensions.d/ldap.cyber-range.lan-authn.properties', Initialized: 'true' 2018-10-31 16:48:09,319-05 INFO Instance name: 'ldap.cyber-range.lan', Extension name: 'ovirt-engine-extension-aaa-ldap.authz', Version: '1.3.7', Notes: 'Display name: ovirt-engine-extension-aaa-ldap-1.3.7-1.el7.centos', License: 'ASL 2.0', Home: 'http://www.ovirt.org', Author 'The oVirt Project', Build interface Version: '0', File: '/etc/ovirt-engine/extensions.d/ldap.cyber-range.lan.properties', Initialized: 'true' 2018-10-31 16:48:09,319-05 INFO End of enabled extensions list 2018-10-31 16:48:09,319-05 INFO ======================================================================== 2018-10-31 16:48:09,319-05 INFO ============================== Execution =============================== 2018-10-31 16:48:09,319-05 INFO ======================================================================== 2018-10-31 16:48:09,319-05 INFO Iteration: 0 2018-10-31 16:48:09,320-05 INFO Profile='ldap.cyber-range.lan' authn='ldap.cyber-range.lan-authn' authz='ldap.cyber-range.lan' mapping='null' 2018-10-31 16:48:09,320-05 INFO API: -->Authn.InvokeCommands.AUTHENTICATE_CREDENTIALS profile='ldap.cyber-range.lan' user='admin' 2018-10-31 16:48:09,321-05 FINEST Invoke Input BEGIN 2018-10-31 16:48:09,321-05 FINEST {Extkey[name=AAA_AUTHN_CREDENTIALS;type=class java.lang.String;uuid=AAA_AUTHN_CREDENTIALS[03b96485-4bb5-4592-8167-810a5c909706];]=***, Extkey[name=EXTENSION_INVOKE_COMMAND;type=class org.ovirt.engine.api.extensions.ExtUUID;uuid=EXTENSION_INVOKE_COMMAND[485778ab-bede-4f1a-b823-77b262a2f28d];]=AAA_AUTHN_AUTHENTICATE_CREDENTIALS[d9605c75-6b43-4b00-b32c-06bdfa80244c], Extkey[name=AAA_AUTHN_USER;type=class java.lang.String;uuid=AAA_AUTHN_USER[1ceaba26-1bdc-4663-a3c6-5d926f9dd8f0];]=admin, 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_LICENSE;type=class java.lang.String;uuid=EXTENSION_LICENSE[8a61ad65-054c-4e31-9c6d-1ca4d60a4c18];]=ASL 2.0, 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_NAME;type=class java.lang.String;uuid=EXTENSION_NAME[651381d3-f54f-4547-bf28-b0b01a103184];]=ovirt-engine-extension-aaa-ldap.authn, Extkey[name=EXTENSION_MANAGER_TRACE_LOG;type=interface org.slf4j.Logger;uuid=EXTENSION_MANAGER_TRACE_LOG[863db666-3ea7-4751-9695-918a3197ad83];]=org.slf4j.impl.JDK14LoggerAdapter(org.ovirt.engine.core.extensions.mgr.ExtensionsManager.trace.ovirt-engine-extension-aaa-ldap.authn.ldap.cyber-range.lan-authn), Extkey[name=EXTENSION_CONFIGURATION_SENSITIVE_KEYS;type=interface java.util.Collection;uuid=EXTENSION_CONFIGURATION_SENSITIVE_KEYS[a456efa1-73ff-4204-9f9b-ebff01e35263];]=[], Extkey[name=EXTENSION_VERSION;type=class java.lang.String;uuid=EXTENSION_VERSION[fe35f6a8-8239-4bdb-ab1a-af9f779ce68c];]=1.3.7, 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_AUTHOR;type=class java.lang.String;uuid=EXTENSION_AUTHOR[ef242f7a-2dad-4bc5-9aad-e07018b7fbcc];]=The oVirt Project, Extkey[name=EXTENSION_LOCALE;type=class java.lang.String;uuid=EXTENSION_LOCALE[0780b112-0ce0-404a-b85e-8765d778bb29];]=en_US, Extkey[name=EXTENSION_CONFIGURATION_FILE;type=class java.lang.String;uuid=EXTENSION_CONFIGURATION_FILE[4fb0ffd3-983c-4f3f-98ff-9660bd67af6a];]=/etc/ovirt-engine/extensions.d/ldap.cyber-range.lan-authn.properties, 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_CONFIGURATION;type=class java.util.Properties;uuid=EXTENSION_CONFIGURATION[2d48ab72-f0a1-4312-b4ae-5068a226b0fc];]=***, 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_INTERFACE_VERSION_MIN;type=class java.lang.Integer;uuid=EXTENSION_INTERFACE_VERSION_MIN[2b84fc91-305b-497b-a1d7-d961b9d2ce0b];]=0, Extkey[name=EXTENSION_INSTANCE_NAME;type=class java.lang.String;uuid=EXTENSION_INSTANCE_NAME[65c67ff6-aeca-4bd5-a245-8674327f011b];]=ldap.cyber-range.lan-authn, 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_AUTHN_CAPABILITIES;type=class java.lang.Long;uuid=AAA_AUTHN_CAPABILITIES[9d16bee3-10fd-46f2-83f9-3d3c54cf258d];]=12, Extkey[name=EXTENSION_NOTES;type=class java.lang.String;uuid=EXTENSION_NOTES[2da5ad7e-185a-4584-aaff-97f66978e4ea];]=Display name: ovirt-engine-extension-aaa-ldap-1.3.7-1.el7.centos}} 2018-10-31 16:48:09,321-05 FINEST Invoke Input END 2018-10-31 16:48:09,321-05 FINE doAuthenticateCredentials Entry user='admin' 2018-10-31 16:48:09,321-05 FINE runSequence Entry name='authn' 2018-10-31 16:48:09,321-05 FINE Running sequence authn/stub/call call simple 2018-10-31 16:48:09,321-05 FINEST VARS-BEGIN 2018-10-31 16:48:09,321-05 FINEST authn_enable = 1 2018-10-31 16:48:09,321-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:09,321-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:09,321-05 FINEST maxFilterSize = 50 2018-10-31 16:48:09,322-05 FINEST password = *** 2018-10-31 16:48:09,322-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:09,322-05 FINEST simple_attrGroupMemberDN = uniqueMember:uniqueMemberMatch: 2018-10-31 16:48:09,322-05 FINEST simple_attrMemberOf = memberOf 2018-10-31 16:48:09,322-05 FINEST simple_attrsBaseDN = defaultNamingContext 2018-10-31 16:48:09,322-05 FINEST simple_attrsBaseDNIndex = 0 2018-10-31 16:48:09,322-05 FINEST simple_attrsGroupRecord = nsuniqueid, cn, description 2018-10-31 16:48:09,322-05 FINEST simple_attrsPrincipalRecord = nsuniqueid, uid, cn, displayName, department, givenName, sn, title, mail 2018-10-31 16:48:09,322-05 FINEST simple_attrsUserName = uid 2018-10-31 16:48:09,322-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:09,322-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:09,322-05 FINEST simple_filterGroupObject = (objectClass=groupOfUniqueNames) 2018-10-31 16:48:09,322-05 FINEST simple_filterUserObject = (objectClass=organizationalPerson)(uid=*) 2018-10-31 16:48:09,322-05 FINEST simple_groupLogic = member 2018-10-31 16:48:09,322-05 FINEST stop = false 2018-10-31 16:48:09,322-05 FINEST user = admin 2018-10-31 16:48:09,322-05 FINEST VARS-END 2018-10-31 16:48:09,322-05 FINE runSequence Entry name='simple-authn' 2018-10-31 16:48:09,322-05 FINE Running sequence simple-authn/010/call resolve user 2018-10-31 16:48:09,322-05 FINEST VARS-BEGIN 2018-10-31 16:48:09,322-05 FINEST authn_enable = 1 2018-10-31 16:48:09,322-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:09,323-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:09,323-05 FINEST maxFilterSize = 50 2018-10-31 16:48:09,323-05 FINEST password = *** 2018-10-31 16:48:09,323-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:09,323-05 FINEST simple_attrGroupMemberDN = uniqueMember:uniqueMemberMatch: 2018-10-31 16:48:09,323-05 FINEST simple_attrMemberOf = memberOf 2018-10-31 16:48:09,323-05 FINEST simple_attrsBaseDN = defaultNamingContext 2018-10-31 16:48:09,323-05 FINEST simple_attrsBaseDNIndex = 0 2018-10-31 16:48:09,323-05 FINEST simple_attrsGroupRecord = nsuniqueid, cn, description 2018-10-31 16:48:09,323-05 FINEST simple_attrsPrincipalRecord = nsuniqueid, uid, cn, displayName, department, givenName, sn, title, mail 2018-10-31 16:48:09,323-05 FINEST simple_attrsUserName = uid 2018-10-31 16:48:09,323-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:09,323-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:09,323-05 FINEST simple_filterGroupObject = (objectClass=groupOfUniqueNames) 2018-10-31 16:48:09,323-05 FINEST simple_filterUserObject = (objectClass=organizationalPerson)(uid=*) 2018-10-31 16:48:09,323-05 FINEST simple_groupLogic = member 2018-10-31 16:48:09,323-05 FINEST stop = false 2018-10-31 16:48:09,323-05 FINEST user = admin 2018-10-31 16:48:09,323-05 FINEST VARS-END 2018-10-31 16:48:09,323-05 FINE runSequence Entry name='simple-resolve-user' 2018-10-31 16:48:09,324-05 FINE Running sequence simple-resolve-user/010/fetch-record resolve user 2018-10-31 16:48:09,324-05 FINEST VARS-BEGIN 2018-10-31 16:48:09,324-05 FINEST authn_enable = 1 2018-10-31 16:48:09,324-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:09,324-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:09,324-05 FINEST maxFilterSize = 50 2018-10-31 16:48:09,324-05 FINEST password = *** 2018-10-31 16:48:09,324-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:09,324-05 FINEST simple_attrGroupMemberDN = uniqueMember:uniqueMemberMatch: 2018-10-31 16:48:09,324-05 FINEST simple_attrMemberOf = memberOf 2018-10-31 16:48:09,324-05 FINEST simple_attrsBaseDN = defaultNamingContext 2018-10-31 16:48:09,324-05 FINEST simple_attrsBaseDNIndex = 0 2018-10-31 16:48:09,324-05 FINEST simple_attrsGroupRecord = nsuniqueid, cn, description 2018-10-31 16:48:09,324-05 FINEST simple_attrsPrincipalRecord = nsuniqueid, uid, cn, displayName, department, givenName, sn, title, mail 2018-10-31 16:48:09,324-05 FINEST simple_attrsUserName = uid 2018-10-31 16:48:09,324-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:09,324-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:09,324-05 FINEST simple_filterGroupObject = (objectClass=groupOfUniqueNames) 2018-10-31 16:48:09,325-05 FINEST simple_filterUserObject = (objectClass=organizationalPerson)(uid=*) 2018-10-31 16:48:09,325-05 FINEST simple_groupLogic = member 2018-10-31 16:48:09,325-05 FINEST stop = false 2018-10-31 16:48:09,325-05 FINEST user = admin 2018-10-31 16:48:09,325-05 FINEST VARS-END 2018-10-31 16:48:09,325-05 FINE searchOpen Entry name='simple-user-fetch', pageSize=0, limit=5 2018-10-31 16:48:09,325-05 FINE Creating SearchRequest 2018-10-31 16:48:09,328-05 FINE SearchRequest: SearchRequest(baseDN='dc=cyber-range,dc=lan', scope=SUB, deref=NEVER, sizeLimit=0, timeLimit=0, filter='&(objectClass=organizationalPerson)(uid=*)(uid=admin)', attrs={nsuniqueid, uid, cn, displayName, department, givenName, sn, title, mail}) 2018-10-31 16:48:09,328-05 FINE getConnectionPoolEntry Entry name='authz', dn='dc=cyber-range,dc=lan' 2018-10-31 16:48:09,329-05 FINE getAttrMap Entry name='map-principal-record' 2018-10-31 16:48:09,331-05 FINE getAttrMap Return [AttrMapInfo(PrincipalRecord_DEPARTMENT, STRING), AttrMapInfo(PrincipalRecord_DISPLAY_NAME, STRING), AttrMapInfo(PrincipalRecord_DN, STRING), AttrMapInfo(PrincipalRecord_EMAIL, STRING), AttrMapInfo(PrincipalRecord_FIRST_NAME, STRING), AttrMapInfo(PrincipalRecord_ID, STRING), AttrMapInfo(PrincipalRecord_LAST_NAME, STRING), AttrMapInfo(PrincipalRecord_NAME, STRING), AttrMapInfo(PrincipalRecord_PRINCIPAL, STRING), AttrMapInfo(PrincipalRecord_TITLE, STRING)] 2018-10-31 16:48:09,331-05 FINE SearchOpen Return SearchInstance(searchRequest='SearchRequest(baseDN='dc=cyber-range,dc=lan', scope=SUB, deref=NEVER, sizeLimit=0, timeLimit=0, filter='&(objectClass=organizationalPerson)(uid=*)(uid=admin)', attrs={nsuniqueid, uid, cn, displayName, department, givenName, sn, title, mail})', doPaging=false, resumeCookie='null', pageSize=100, limitLeft=5, done=false) 2018-10-31 16:48:09,331-05 FINEST searchExecute Entry 2018-10-31 16:48:09,331-05 FINE Getting connection out of pool 'authz' 2018-10-31 16:48:09,331-05 FINE Performing SearchRequest 'SearchRequest(baseDN='dc=cyber-range,dc=lan', scope=SUB, deref=NEVER, sizeLimit=0, timeLimit=0, filter='&(objectClass=organizationalPerson)(uid=*)(uid=admin)', attrs={nsuniqueid, uid, cn, displayName, department, givenName, sn, title, mail})' request on server 'ldap.cyber-range.lan' 2018-10-31 16:48:09,333-05 FINE SearchResult: SearchResult(resultCode=0 (success), messageID=3, entriesReturned=0, referencesReturned=0) 2018-10-31 16:48:09,333-05 FINEST SearchReferences: [] 2018-10-31 16:48:09,333-05 FINEST SearchReferences: [] 2018-10-31 16:48:09,333-05 FINEST searchExecute Return: null 2018-10-31 16:48:09,333-05 FINE searchClose Entry 2018-10-31 16:48:09,333-05 FINE We have connection 2018-10-31 16:48:09,333-05 FINE Releasing connection 2018-10-31 16:48:09,333-05 FINE searchClose Return 2018-10-31 16:48:09,333-05 FINE End sequence simple-resolve-user resolve user 2018-10-31 16:48:09,333-05 FINEST VARS-BEGIN 2018-10-31 16:48:09,333-05 FINEST authn_enable = 1 2018-10-31 16:48:09,334-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:09,334-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:09,334-05 FINEST maxFilterSize = 50 2018-10-31 16:48:09,334-05 FINEST password = *** 2018-10-31 16:48:09,334-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:09,334-05 FINEST simple_attrGroupMemberDN = uniqueMember:uniqueMemberMatch: 2018-10-31 16:48:09,334-05 FINEST simple_attrMemberOf = memberOf 2018-10-31 16:48:09,334-05 FINEST simple_attrsBaseDN = defaultNamingContext 2018-10-31 16:48:09,334-05 FINEST simple_attrsBaseDNIndex = 0 2018-10-31 16:48:09,334-05 FINEST simple_attrsGroupRecord = nsuniqueid, cn, description 2018-10-31 16:48:09,334-05 FINEST simple_attrsPrincipalRecord = nsuniqueid, uid, cn, displayName, department, givenName, sn, title, mail 2018-10-31 16:48:09,334-05 FINEST simple_attrsUserName = uid 2018-10-31 16:48:09,334-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:09,334-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:09,334-05 FINEST simple_filterGroupObject = (objectClass=groupOfUniqueNames) 2018-10-31 16:48:09,334-05 FINEST simple_filterUserObject = (objectClass=organizationalPerson)(uid=*) 2018-10-31 16:48:09,334-05 FINEST simple_groupLogic = member 2018-10-31 16:48:09,334-05 FINEST stop = false 2018-10-31 16:48:09,334-05 FINEST user = admin 2018-10-31 16:48:09,334-05 FINEST VARS-END 2018-10-31 16:48:09,334-05 FINE Running sequence simple-resolve-user/020/call no user? 2018-10-31 16:48:09,335-05 FINEST VARS-BEGIN 2018-10-31 16:48:09,335-05 FINEST authn_enable = 1 2018-10-31 16:48:09,335-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:09,335-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:09,335-05 FINEST maxFilterSize = 50 2018-10-31 16:48:09,335-05 FINEST password = *** 2018-10-31 16:48:09,335-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:09,335-05 FINEST simple_attrGroupMemberDN = uniqueMember:uniqueMemberMatch: 2018-10-31 16:48:09,335-05 FINEST simple_attrMemberOf = memberOf 2018-10-31 16:48:09,335-05 FINEST simple_attrsBaseDN = defaultNamingContext 2018-10-31 16:48:09,335-05 FINEST simple_attrsBaseDNIndex = 0 2018-10-31 16:48:09,335-05 FINEST simple_attrsGroupRecord = nsuniqueid, cn, description 2018-10-31 16:48:09,335-05 FINEST simple_attrsPrincipalRecord = nsuniqueid, uid, cn, displayName, department, givenName, sn, title, mail 2018-10-31 16:48:09,335-05 FINEST simple_attrsUserName = uid 2018-10-31 16:48:09,335-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:09,335-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:09,335-05 FINEST simple_filterGroupObject = (objectClass=groupOfUniqueNames) 2018-10-31 16:48:09,335-05 FINEST simple_filterUserObject = (objectClass=organizationalPerson)(uid=*) 2018-10-31 16:48:09,335-05 FINEST simple_groupLogic = member 2018-10-31 16:48:09,335-05 FINEST stop = false 2018-10-31 16:48:09,336-05 FINEST user = admin 2018-10-31 16:48:09,336-05 FINEST VARS-END 2018-10-31 16:48:09,336-05 FINE runSequence Entry name='simple-resolve-user-error' 2018-10-31 16:48:09,336-05 FINE Running sequence simple-resolve-user-error/010/var-set error 2018-10-31 16:48:09,336-05 FINEST VARS-BEGIN 2018-10-31 16:48:09,336-05 FINEST authn_enable = 1 2018-10-31 16:48:09,336-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:09,336-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:09,336-05 FINEST maxFilterSize = 50 2018-10-31 16:48:09,336-05 FINEST password = *** 2018-10-31 16:48:09,336-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:09,336-05 FINEST simple_attrGroupMemberDN = uniqueMember:uniqueMemberMatch: 2018-10-31 16:48:09,336-05 FINEST simple_attrMemberOf = memberOf 2018-10-31 16:48:09,336-05 FINEST simple_attrsBaseDN = defaultNamingContext 2018-10-31 16:48:09,336-05 FINEST simple_attrsBaseDNIndex = 0 2018-10-31 16:48:09,336-05 FINEST simple_attrsGroupRecord = nsuniqueid, cn, description 2018-10-31 16:48:09,336-05 FINEST simple_attrsPrincipalRecord = nsuniqueid, uid, cn, displayName, department, givenName, sn, title, mail 2018-10-31 16:48:09,336-05 FINEST simple_attrsUserName = uid 2018-10-31 16:48:09,337-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:09,337-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:09,337-05 FINEST simple_filterGroupObject = (objectClass=groupOfUniqueNames) 2018-10-31 16:48:09,337-05 FINEST simple_filterUserObject = (objectClass=organizationalPerson)(uid=*) 2018-10-31 16:48:09,337-05 FINEST simple_groupLogic = member 2018-10-31 16:48:09,337-05 FINEST stop = false 2018-10-31 16:48:09,337-05 FINEST user = admin 2018-10-31 16:48:09,337-05 FINEST VARS-END 2018-10-31 16:48:09,337-05 FINE End sequence simple-resolve-user-error error 2018-10-31 16:48:09,337-05 FINEST VARS-BEGIN 2018-10-31 16:48:09,337-05 FINEST authn_enable = 1 2018-10-31 16:48:09,337-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:09,337-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:09,337-05 FINEST maxFilterSize = 50 2018-10-31 16:48:09,337-05 FINEST password = *** 2018-10-31 16:48:09,337-05 FINEST resultCode = INVALID_CREDENTIALS 2018-10-31 16:48:09,337-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:09,337-05 FINEST simple_attrGroupMemberDN = uniqueMember:uniqueMemberMatch: 2018-10-31 16:48:09,337-05 FINEST simple_attrMemberOf = memberOf 2018-10-31 16:48:09,338-05 FINEST simple_attrsBaseDN = defaultNamingContext 2018-10-31 16:48:09,338-05 FINEST simple_attrsBaseDNIndex = 0 2018-10-31 16:48:09,338-05 FINEST simple_attrsGroupRecord = nsuniqueid, cn, description 2018-10-31 16:48:09,338-05 FINEST simple_attrsPrincipalRecord = nsuniqueid, uid, cn, displayName, department, givenName, sn, title, mail 2018-10-31 16:48:09,338-05 FINEST simple_attrsUserName = uid 2018-10-31 16:48:09,338-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:09,338-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:09,338-05 FINEST simple_filterGroupObject = (objectClass=groupOfUniqueNames) 2018-10-31 16:48:09,338-05 FINEST simple_filterUserObject = (objectClass=organizationalPerson)(uid=*) 2018-10-31 16:48:09,338-05 FINEST simple_groupLogic = member 2018-10-31 16:48:09,338-05 FINEST stop = false 2018-10-31 16:48:09,338-05 FINEST user = admin 2018-10-31 16:48:09,338-05 FINEST VARS-END 2018-10-31 16:48:09,338-05 FINE Running sequence simple-resolve-user-error/020/var-set error 2018-10-31 16:48:09,338-05 FINEST VARS-BEGIN 2018-10-31 16:48:09,338-05 FINEST authn_enable = 1 2018-10-31 16:48:09,338-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:09,338-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:09,338-05 FINEST maxFilterSize = 50 2018-10-31 16:48:09,338-05 FINEST password = *** 2018-10-31 16:48:09,339-05 FINEST resultCode = INVALID_CREDENTIALS 2018-10-31 16:48:09,339-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:09,339-05 FINEST simple_attrGroupMemberDN = uniqueMember:uniqueMemberMatch: 2018-10-31 16:48:09,339-05 FINEST simple_attrMemberOf = memberOf 2018-10-31 16:48:09,339-05 FINEST simple_attrsBaseDN = defaultNamingContext 2018-10-31 16:48:09,339-05 FINEST simple_attrsBaseDNIndex = 0 2018-10-31 16:48:09,339-05 FINEST simple_attrsGroupRecord = nsuniqueid, cn, description 2018-10-31 16:48:09,339-05 FINEST simple_attrsPrincipalRecord = nsuniqueid, uid, cn, displayName, department, givenName, sn, title, mail 2018-10-31 16:48:09,339-05 FINEST simple_attrsUserName = uid 2018-10-31 16:48:09,339-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:09,339-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:09,339-05 FINEST simple_filterGroupObject = (objectClass=groupOfUniqueNames) 2018-10-31 16:48:09,339-05 FINEST simple_filterUserObject = (objectClass=organizationalPerson)(uid=*) 2018-10-31 16:48:09,339-05 FINEST simple_groupLogic = member 2018-10-31 16:48:09,339-05 FINEST stop = false 2018-10-31 16:48:09,339-05 FINEST user = admin 2018-10-31 16:48:09,339-05 FINEST VARS-END 2018-10-31 16:48:09,339-05 FINE End sequence simple-resolve-user-error error 2018-10-31 16:48:09,339-05 FINEST VARS-BEGIN 2018-10-31 16:48:09,339-05 FINEST authTranslatedMessage = CREDENTIALS_INVALID 2018-10-31 16:48:09,340-05 FINEST authn_enable = 1 2018-10-31 16:48:09,340-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:09,340-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:09,340-05 FINEST maxFilterSize = 50 2018-10-31 16:48:09,340-05 FINEST password = *** 2018-10-31 16:48:09,340-05 FINEST resultCode = INVALID_CREDENTIALS 2018-10-31 16:48:09,340-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:09,340-05 FINEST simple_attrGroupMemberDN = uniqueMember:uniqueMemberMatch: 2018-10-31 16:48:09,340-05 FINEST simple_attrMemberOf = memberOf 2018-10-31 16:48:09,340-05 FINEST simple_attrsBaseDN = defaultNamingContext 2018-10-31 16:48:09,340-05 FINEST simple_attrsBaseDNIndex = 0 2018-10-31 16:48:09,340-05 FINEST simple_attrsGroupRecord = nsuniqueid, cn, description 2018-10-31 16:48:09,340-05 FINEST simple_attrsPrincipalRecord = nsuniqueid, uid, cn, displayName, department, givenName, sn, title, mail 2018-10-31 16:48:09,340-05 FINEST simple_attrsUserName = uid 2018-10-31 16:48:09,340-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:09,340-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:09,340-05 FINEST simple_filterGroupObject = (objectClass=groupOfUniqueNames) 2018-10-31 16:48:09,340-05 FINEST simple_filterUserObject = (objectClass=organizationalPerson)(uid=*) 2018-10-31 16:48:09,340-05 FINEST simple_groupLogic = member 2018-10-31 16:48:09,340-05 FINEST stop = false 2018-10-31 16:48:09,341-05 FINEST user = admin 2018-10-31 16:48:09,341-05 FINEST VARS-END 2018-10-31 16:48:09,341-05 FINE Running sequence simple-resolve-user-error/030/stop stop 2018-10-31 16:48:09,341-05 FINEST VARS-BEGIN 2018-10-31 16:48:09,341-05 FINEST authTranslatedMessage = CREDENTIALS_INVALID 2018-10-31 16:48:09,341-05 FINEST authn_enable = 1 2018-10-31 16:48:09,341-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:09,341-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:09,341-05 FINEST maxFilterSize = 50 2018-10-31 16:48:09,341-05 FINEST password = *** 2018-10-31 16:48:09,341-05 FINEST resultCode = INVALID_CREDENTIALS 2018-10-31 16:48:09,341-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:09,341-05 FINEST simple_attrGroupMemberDN = uniqueMember:uniqueMemberMatch: 2018-10-31 16:48:09,341-05 FINEST simple_attrMemberOf = memberOf 2018-10-31 16:48:09,341-05 FINEST simple_attrsBaseDN = defaultNamingContext 2018-10-31 16:48:09,341-05 FINEST simple_attrsBaseDNIndex = 0 2018-10-31 16:48:09,341-05 FINEST simple_attrsGroupRecord = nsuniqueid, cn, description 2018-10-31 16:48:09,341-05 FINEST simple_attrsPrincipalRecord = nsuniqueid, uid, cn, displayName, department, givenName, sn, title, mail 2018-10-31 16:48:09,341-05 FINEST simple_attrsUserName = uid 2018-10-31 16:48:09,342-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:09,342-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:09,342-05 FINEST simple_filterGroupObject = (objectClass=groupOfUniqueNames) 2018-10-31 16:48:09,342-05 FINEST simple_filterUserObject = (objectClass=organizationalPerson)(uid=*) 2018-10-31 16:48:09,342-05 FINEST simple_groupLogic = member 2018-10-31 16:48:09,342-05 FINEST stop = false 2018-10-31 16:48:09,342-05 FINEST user = admin 2018-10-31 16:48:09,342-05 FINEST VARS-END 2018-10-31 16:48:09,342-05 FINE End sequence simple-resolve-user-error stop 2018-10-31 16:48:09,342-05 FINEST VARS-BEGIN 2018-10-31 16:48:09,342-05 FINEST authTranslatedMessage = CREDENTIALS_INVALID 2018-10-31 16:48:09,342-05 FINEST authn_enable = 1 2018-10-31 16:48:09,342-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:09,342-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:09,342-05 FINEST maxFilterSize = 50 2018-10-31 16:48:09,342-05 FINEST password = *** 2018-10-31 16:48:09,342-05 FINEST resultCode = INVALID_CREDENTIALS 2018-10-31 16:48:09,342-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:09,342-05 FINEST simple_attrGroupMemberDN = uniqueMember:uniqueMemberMatch: 2018-10-31 16:48:09,342-05 FINEST simple_attrMemberOf = memberOf 2018-10-31 16:48:09,343-05 FINEST simple_attrsBaseDN = defaultNamingContext 2018-10-31 16:48:09,343-05 FINEST simple_attrsBaseDNIndex = 0 2018-10-31 16:48:09,343-05 FINEST simple_attrsGroupRecord = nsuniqueid, cn, description 2018-10-31 16:48:09,343-05 FINEST simple_attrsPrincipalRecord = nsuniqueid, uid, cn, displayName, department, givenName, sn, title, mail 2018-10-31 16:48:09,343-05 FINEST simple_attrsUserName = uid 2018-10-31 16:48:09,343-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:09,343-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:09,343-05 FINEST simple_filterGroupObject = (objectClass=groupOfUniqueNames) 2018-10-31 16:48:09,343-05 FINEST simple_filterUserObject = (objectClass=organizationalPerson)(uid=*) 2018-10-31 16:48:09,343-05 FINEST simple_groupLogic = member 2018-10-31 16:48:09,343-05 FINEST stop = true 2018-10-31 16:48:09,343-05 FINEST user = admin 2018-10-31 16:48:09,343-05 FINEST VARS-END 2018-10-31 16:48:09,343-05 FINE runSequence Return name='simple-resolve-user-error' 2018-10-31 16:48:09,343-05 FINE End sequence simple-resolve-user no user? 2018-10-31 16:48:09,343-05 FINEST VARS-BEGIN 2018-10-31 16:48:09,343-05 FINEST authTranslatedMessage = CREDENTIALS_INVALID 2018-10-31 16:48:09,343-05 FINEST authn_enable = 1 2018-10-31 16:48:09,343-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:09,344-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:09,344-05 FINEST maxFilterSize = 50 2018-10-31 16:48:09,344-05 FINEST password = *** 2018-10-31 16:48:09,344-05 FINEST resultCode = INVALID_CREDENTIALS 2018-10-31 16:48:09,344-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:09,344-05 FINEST simple_attrGroupMemberDN = uniqueMember:uniqueMemberMatch: 2018-10-31 16:48:09,344-05 FINEST simple_attrMemberOf = memberOf 2018-10-31 16:48:09,344-05 FINEST simple_attrsBaseDN = defaultNamingContext 2018-10-31 16:48:09,344-05 FINEST simple_attrsBaseDNIndex = 0 2018-10-31 16:48:09,344-05 FINEST simple_attrsGroupRecord = nsuniqueid, cn, description 2018-10-31 16:48:09,344-05 FINEST simple_attrsPrincipalRecord = nsuniqueid, uid, cn, displayName, department, givenName, sn, title, mail 2018-10-31 16:48:09,344-05 FINEST simple_attrsUserName = uid 2018-10-31 16:48:09,344-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:09,344-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:09,344-05 FINEST simple_filterGroupObject = (objectClass=groupOfUniqueNames) 2018-10-31 16:48:09,344-05 FINEST simple_filterUserObject = (objectClass=organizationalPerson)(uid=*) 2018-10-31 16:48:09,344-05 FINEST simple_groupLogic = member 2018-10-31 16:48:09,344-05 FINEST stop = true 2018-10-31 16:48:09,345-05 FINEST user = admin 2018-10-31 16:48:09,345-05 FINEST VARS-END 2018-10-31 16:48:09,345-05 FINE runSequence Return name='simple-resolve-user' 2018-10-31 16:48:09,345-05 FINE End sequence simple-authn resolve user 2018-10-31 16:48:09,345-05 FINEST VARS-BEGIN 2018-10-31 16:48:09,345-05 FINEST authTranslatedMessage = CREDENTIALS_INVALID 2018-10-31 16:48:09,345-05 FINEST authn_enable = 1 2018-10-31 16:48:09,345-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:09,345-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:09,345-05 FINEST maxFilterSize = 50 2018-10-31 16:48:09,345-05 FINEST password = *** 2018-10-31 16:48:09,345-05 FINEST resultCode = INVALID_CREDENTIALS 2018-10-31 16:48:09,345-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:09,345-05 FINEST simple_attrGroupMemberDN = uniqueMember:uniqueMemberMatch: 2018-10-31 16:48:09,345-05 FINEST simple_attrMemberOf = memberOf 2018-10-31 16:48:09,345-05 FINEST simple_attrsBaseDN = defaultNamingContext 2018-10-31 16:48:09,345-05 FINEST simple_attrsBaseDNIndex = 0 2018-10-31 16:48:09,345-05 FINEST simple_attrsGroupRecord = nsuniqueid, cn, description 2018-10-31 16:48:09,345-05 FINEST simple_attrsPrincipalRecord = nsuniqueid, uid, cn, displayName, department, givenName, sn, title, mail 2018-10-31 16:48:09,346-05 FINEST simple_attrsUserName = uid 2018-10-31 16:48:09,346-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:09,346-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:09,346-05 FINEST simple_filterGroupObject = (objectClass=groupOfUniqueNames) 2018-10-31 16:48:09,346-05 FINEST simple_filterUserObject = (objectClass=organizationalPerson)(uid=*) 2018-10-31 16:48:09,346-05 FINEST simple_groupLogic = member 2018-10-31 16:48:09,346-05 FINEST stop = true 2018-10-31 16:48:09,346-05 FINEST user = admin 2018-10-31 16:48:09,346-05 FINEST VARS-END 2018-10-31 16:48:09,346-05 FINE runSequence Return name='simple-authn' 2018-10-31 16:48:09,346-05 FINE End sequence authn call simple 2018-10-31 16:48:09,346-05 FINEST VARS-BEGIN 2018-10-31 16:48:09,346-05 FINEST authTranslatedMessage = CREDENTIALS_INVALID 2018-10-31 16:48:09,346-05 FINEST authn_enable = 1 2018-10-31 16:48:09,346-05 FINEST capability_credentialsChange = false 2018-10-31 16:48:09,346-05 FINEST capability_recursiveGroupResolution = false 2018-10-31 16:48:09,346-05 FINEST maxFilterSize = 50 2018-10-31 16:48:09,346-05 FINEST password = *** 2018-10-31 16:48:09,346-05 FINEST resultCode = INVALID_CREDENTIALS 2018-10-31 16:48:09,346-05 FINEST sensitiveKeys = , password, passwordNew 2018-10-31 16:48:09,347-05 FINEST simple_attrGroupMemberDN = uniqueMember:uniqueMemberMatch: 2018-10-31 16:48:09,347-05 FINEST simple_attrMemberOf = memberOf 2018-10-31 16:48:09,347-05 FINEST simple_attrsBaseDN = defaultNamingContext 2018-10-31 16:48:09,347-05 FINEST simple_attrsBaseDNIndex = 0 2018-10-31 16:48:09,347-05 FINEST simple_attrsGroupRecord = nsuniqueid, cn, description 2018-10-31 16:48:09,347-05 FINEST simple_attrsPrincipalRecord = nsuniqueid, uid, cn, displayName, department, givenName, sn, title, mail 2018-10-31 16:48:09,347-05 FINEST simple_attrsUserName = uid 2018-10-31 16:48:09,347-05 FINEST simple_baseDN = dc=cyber-range,dc=lan 2018-10-31 16:48:09,347-05 FINEST simple_bindFormat = dn 2018-10-31 16:48:09,347-05 FINEST simple_filterGroupObject = (objectClass=groupOfUniqueNames) 2018-10-31 16:48:09,347-05 FINEST simple_filterUserObject = (objectClass=organizationalPerson)(uid=*) 2018-10-31 16:48:09,347-05 FINEST simple_groupLogic = member 2018-10-31 16:48:09,347-05 FINEST stop = true 2018-10-31 16:48:09,347-05 FINEST user = admin 2018-10-31 16:48:09,347-05 FINEST VARS-END 2018-10-31 16:48:09,347-05 FINE runSequence Return name='authn' 2018-10-31 16:48:09,348-05 FINE doAuthenticateCredentials Return {Extkey[name=AAA_AUTHN_RESULT;type=class java.lang.Integer;uuid=AAA_AUTHN_RESULT[af9771dc-a0bb-417d-a700-277616aedd85];]=12} 2018-10-31 16:48:09,348-05 FINEST Invoke Output BEGIN 2018-10-31 16:48:09,348-05 FINEST {Extkey[name=EXTENSION_INVOKE_RESULT;type=class java.lang.Integer;uuid=EXTENSION_INVOKE_RESULT[0909d91d-8bde-40fb-b6c0-099c772ddd4e];]=0, Extkey[name=AAA_AUTHN_RESULT;type=class java.lang.Integer;uuid=AAA_AUTHN_RESULT[af9771dc-a0bb-417d-a700-277616aedd85];]=12} 2018-10-31 16:48:09,348-05 FINEST Invoke Output END 2018-10-31 16:48:09,348-05 INFO API: <--Authn.InvokeCommands.AUTHENTICATE_CREDENTIALS profile='ldap.cyber-range.lan' result=CREDENTIALS_INVALID 2018-10-31 16:48:09,352-05 SEVERE Authn.Result code is: CREDENTIALS_INVALID 2018-10-31 16:48:09,352-05 FINE Exception: java.lang.RuntimeException: Authn.Result code is: CREDENTIALS_INVALID at org.ovirt.engine.exttool.aaa.AAAServiceImpl$Action.lambda$static$3(AAAServiceImpl.java:188) at org.ovirt.engine.exttool.aaa.AAAServiceImpl$Action.execute(AAAServiceImpl.java:417) at org.ovirt.engine.exttool.aaa.AAAServiceImpl.run(AAAServiceImpl.java:686) at org.ovirt.engine.exttool.core.ExtensionsToolExecutor.main(ExtensionsToolExecutor.java:120) at org.jboss.modules.Module.run(Module.java:352) at org.jboss.modules.Module.run(Module.java:320) at org.jboss.modules.Main.main(Main.java:593) 2018-10-31 16:48:09,353-05 FINE Exiting with status '1'