Hello Alon,

I have deleted Legacy domain with engine-manage-domain, and I have changed
configuration to absolute file name as you can see:

/etc/ovirt-engine/extensions.d/siee-local-authn.properties:

ovirt.engine.extension.name = siee-local-authn
ovirt.engine.extension.bindings.method = jbossmodule
ovirt.engine.extension.binding.jbossmodule.module =
org.ovirt.engine-extensions.aaa.ldap
ovirt.engine.extension.binding.jbossmodule.class =
org.ovirt.engineextensions.aaa.ldap.AuthnExtension
ovirt.engine.extension.provides = org.ovirt.engine.api.extensions.aaa.Authn
ovirt.engine.aaa.authn.profile.name = siee
ovirt.engine.aaa.authn.authz.plugin = siee-local-authz
config.profile.file.1 = /etc/ovirt-engine/extensions.d/aaa/siee.properties

/etc/ovirt-engine/extensions.d/siee-local-authz.properties:

ovirt.engine.extension.name = siee-local-authz
ovirt.engine.extension.bindings.method = jbossmodule
ovirt.engine.extension.binding.jbossmodule.module =
org.ovirt.engine-extensions.aaa.ldap
ovirt.engine.extension.binding.jbossmodule.class =
org.ovirt.engineextensions.aaa.ldap.AuthzExtension
ovirt.engine.extension.provides = org.ovirt.engine.api.extensions.aaa.Authz
config.profile.file.1 = /etc/ovirt-engine/extensions.d/aaa/siee.properties

I had configured relative file name because the example
/usr/share/ovirt-engine-extension-aaa-ldap/examples/ad/extensions.d/domain1-authz.properties
has a relative file name.

I have done the same: delete engine.log, restart ovirt-engine and try log
in and the same error is showed, "General command validation failure."

Attach engine.log file.

Thanks,

Juanjo.


On Fri, Dec 5, 2014 at 9:52 AM, Alon Bar-Lev <alo...@redhat.com> wrote:

>
> Hi!
>
> You have the following errors:
>
> 2014-12-05 09:32:31,778 INFO
> [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (MSC service
> thread 1-5) Loading extension 'siee-local-authn'
> 2014-12-05 09:32:31,819 ERROR
> [org.ovirt.engine.core.utils.extensionsmgr.EngineExtensionsManager] (MSC
> service thread 1-5) Could not load extension based on configuration file
> '/etc/ovirt-engine/extensions.d/siee-local-authn.properties'. Please check
> the configuration file is valid. Exception message is: Error loading
> extension 'siee-local-authn': /aaa/siee.properties (No such file or
> directory)
> 2014-12-05 09:32:31,823 INFO
> [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (MSC service
> thread 1-5) Loading extension 'siee-local-authz'
> 2014-12-05 09:32:31,824 ERROR
> [org.ovirt.engine.core.utils.extensionsmgr.EngineExtensionsManager] (MSC
> service thread 1-5) Could not load extension based on configuration file
> '/etc/ovirt-engine/extensions.d/siee-local-authz.properties'. Please check
> the configuration file is valid. Exception message is: Error loading
> extension 'siee-local-authz': /aaa/siee.properties (No such file or
> directory)
>
> Per my last message, you should provide absolute file names if you use
> 3.5.0.
> Please see inline comments bellow.
>
> Also, you are trying to authenticate with the legacy provider:
>
> 2014-12-05 09:33:04,871 ERROR
> [org.ovirt.engine.extensions.aaa.builtin.kerberosldap.DirectorySearcher]
> (ajp--127.0.0.1-8702-5) Failed ldap search server
> ldap://adserver.siee.local:389 using user juanjo@SIEE.LOCAL due to
> Authentication Failed. Please verify the username and password.. We should
> not try the next server
>
> Can you please use engine-manage-domains to remove the legacy (old)
> domain, so we reduce confusion?
>
> Thanks!
>
> ----- Original Message -----
> > From: "Juan Jose" <jj197...@gmail.com>
> > To: "Alon Bar-Lev" <alo...@redhat.com>
> > Cc: "Ondra Machacek" <omach...@redhat.com>, "Yair Zaslavsky" <
> yzasl...@redhat.com>, users@ovirt.org
> > Sent: Friday, December 5, 2014 10:43:01 AM
> > Subject: Re: [ovirt-users] Adding domain to oVirt to 3.5 issue
> >
> > Hello Alon,
> >
> > I have done what you have said. My new configuration files are:
> >
> > /etc/ovirt-engine/extensions.d/siee-local-authn.properties:
> >
> > ovirt.engine.extension.name = siee-local-authn
> > ovirt.engine.extension.bindings.method = jbossmodule
> > ovirt.engine.extension.binding.jbossmodule.module =
> > org.ovirt.engine-extensions.aaa.ldap
> > ovirt.engine.extension.binding.jbossmodule.class =
> > org.ovirt.engineextensions.aaa.ldap.AuthnExtension
> > ovirt.engine.extension.provides =
> org.ovirt.engine.api.extensions.aaa.Authn
> > ovirt.engine.aaa.authn.profile.name = siee
> > ovirt.engine.aaa.authn.authz.plugin = siee-local-authz
> > config.profile.file.1 = aaa/siee.properties
>
> should be: /etc/ovirt-engine/extensions.d/aaa/siee.properties in 3.5.0 or
> can be ../aaa/siee.properties in 3.5.1.
>
> >
> > /etc/ovirt-engine/extensions.d/siee-local-authz.properties:
> >
> > ovirt.engine.extension.name = siee-local-authz
> > ovirt.engine.extension.bindings.method = jbossmodule
> > ovirt.engine.extension.binding.jbossmodule.module =
> > org.ovirt.engine-extensions.aaa.ldap
> > ovirt.engine.extension.binding.jbossmodule.class =
> > org.ovirt.engineextensions.aaa.ldap.AuthzExtension
> > ovirt.engine.extension.provides =
> org.ovirt.engine.api.extensions.aaa.Authz
> > config.profile.file.1 = aaa/siee.properties
>
> should be: /etc/ovirt-engine/extensions.d/aaa/siee.properties in 3.5.0 or
> can be ../aaa/siee.properties in 3.5.1.
>
>
> >
> > /etc/ovirt-engine/extensions.d/aaa/siee.properties:
> >
> > include = <ad.properties>
> >
> > #
> > # Active directory domain name.
> > #
> > vars.domain = siee.local
> >
> > #
> > # Search user and its password.
> > #
> > vars.user = searcher@${global:vars.domain}
> > vars.password = xxxxxxx
> >
> > #
> > # Optional DNS servers, if enterprise
> > # DNS server cannot resolve the domain srvrecord.
> > #
> > #vars.dns = dns://dc1.${global:vars.domain}
> dns://dc2.${global:vars.domain}
> >
> > pool.default.serverset.type = srvrecord
> > pool.default.serverset.srvrecord.domain = ${global:vars.domain}
> > pool.default.auth.simple.bindDN = ${global:vars.user}
> > pool.default.auth.simple.password = ${global:vars.password}
> >
> > # Uncomment if using custom DNS
> >
> #pool.default.serverset.srvrecord.jndi-properties.java.naming.provider.url
> > = ${global:vars.dns}
> > #pool.default.socketfactory.resolver.uRL = ${global:vars.dns}
> >
> > # Create keystore, import certificate chain and uncomment
> > # if using ssl/tls.
> > #pool.default.ssl.startTLS = true
> > #pool.default.ssl.truststore.file =
> > ${local:_basedir}/${global:vars.domain}.jks
> > #pool.default.ssl.truststore.password = changeit
> >
> > After reconfigure my files with ovirt-engine stopped I have started
> > ovirt-engine and I have tried to log in. The error persist,
> > "General command validation failure." and after that I have stopped
> > ovirt-engine again. I attach my engine.log file.
> >
> > Many thanks again,
> >
> > Juanjo.
> >
> >
> > On Tue, Dec 2, 2014 at 3:46 PM, Alon Bar-Lev <alo...@redhat.com> wrote:
> >
> > >
> > >
> > > ----- Original Message -----
> > > > From: "Juan Jose" <jj197...@gmail.com>
> > > > To: "Alon Bar-Lev" <alo...@redhat.com>
> > > > Cc: "Ondra Machacek" <omach...@redhat.com>, "Yair Zaslavsky" <
> > > yzasl...@redhat.com>, users@ovirt.org
> > > > Sent: Tuesday, December 2, 2014 3:48:54 PM
> > > > Subject: Re: [ovirt-users] Adding domain to oVirt to 3.5 issue
> > > >
> > > > Hello Alon and everybody,
> > > >
> > > > I have installed package ovirt-engine-extension-aaa-ldap and
> configure my
> > > > files as the documentation says. The files are:
> > > >
> > > > /etc/ovirt-engine/extensions.d/siee.local-authn.properties:
> > > >
> > > > ovirt.engine.extension.name = siee.local-authn
> > > > ovirt.engine.extension.bindings.method = jbossmodule
> > > > ovirt.engine.extension.binding.jbossmodule.module =
> > > > org.ovirt.engine-extensions.aaa.ldap
> > > > ovirt.engine.extension.binding.jbossmodule.class =
> > > > org.ovirt.engineextensions.aaa.ldap.AuthnExtension
> > > > ovirt.engine.extension.provides =
> > > org.ovirt.engine.api.extensions.aaa.Authn
> > > > ovirt.engine.aaa.authn.profile.name = siee.local
> > > > ovirt.engine.aaa.authn.authz.plugin = siee.local-authz
> > > > config.profile.file.1 = aaa/siee.local.properties
> > >
> > > please use absolute file name for 3.5.0 relative will be available in
> 3.5.1
> > >
> > > >
> > > > /etc/ovirt-engine/extensions.d/siee.local-authz.properties:
> > > >
> > > > ovirt.engine.extension.name = siee.local-authz
> > > > ovirt.engine.extension.bindings.method = jbossmodule
> > > > ovirt.engine.extension.binding.jbossmodule.module =
> > > > org.ovirt.engine-extensions.aaa.ldap
> > > > ovirt.engine.extension.binding.jbossmodule.class =
> > > > org.ovirt.engineextensions.aaa.ldap.AuthzExtension
> > > > ovirt.engine.extension.provides =
> > > org.ovirt.engine.api.extensions.aaa.Authz
> > > > config.profile.file.1 = aaa/siee.local.properties
> > >
> > > please use absolute file name for 3.5.0 relative will be available in
> 3.5.1
> > >
> > >
> > > >
> > > > /etc/ovirt-engine/extensions.d/aaa/siee.local.properties:
> > > >
> > > > include = <ad.properties>
> > > >
> > > > #
> > > > # Active directory domain name.
> > > > #
> > > > vars.domain = siee.local
> > > >
> > > > #
> > > > # Search user and its password.
> > > > #
> > > > vars.user = juanjo@${global:vars.domain}
> > > > vars.password = xxxxxxxx
> > >
> > > this should be dedicate user for search not your private user.
> > >
> > > >
> > > > #
> > > > # Optional DNS servers, if enterprise
> > > > # DNS server cannot resolve the domain srvrecord.
> > > > #
> > > > #vars.dns = dns://dc1.${global:vars.domain}
> > > dns://dc2.${global:vars.domain}
> > > >
> > > > pool.default.serverset.type = srvrecord
> > > > pool.default.serverset.srvrecord.domain = ${global:vars.domain}
> > > > pool.default.auth.simple.bindDN = ${global:vars.user}
> > > > pool.default.auth.simple.password = ${global:vars.password}
> > > >
> > > > # Uncomment if using custom DNS
> > > >
> > >
> #pool.default.serverset.srvrecord.jndi-properties.java.naming.provider.url
> > > > = ${global:vars.dns}
> > > > #pool.default.socketfactory.resolver.uRL = ${global:vars.dns}
> > > >
> > > > # Create keystore, import certificate chain and uncomment
> > > > # if using ssl/tls.
> > > > #pool.default.ssl.startTLS = true
> > > > #pool.default.ssl.truststore.file =
> > > > ${local:_basedir}/${global:vars.domain}.jks
> > > > #pool.default.ssl.truststore.password = changeit
> > > >
> > > > And after this configuration I restart ovirt-engine service. When I
> try
> > > to
> > > > login in administrator portal I can see the error "The user name or
> > > > password is incorrect.". In /var/log/ovirt-engine/engine.log I have
> the
> > > > errors:
> > > >
> > > > 2014-12-02 14:02:21,983 ERROR
> > > >
> [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
> > > > (ajp--127.0.0.1-8702-8) Correlation ID: null, Call Stack: null,
> Custom
> > > > Event ID: -1, Message: User juanjo cannot login, please verify the
> > > username
> > > > and password.
> > > > 2014-12-02 14:02:21,991 ERROR
> > > >
> [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
> > > > (ajp--127.0.0.1-8702-8) Correlation ID: null, Call Stack: null,
> Custom
> > > > Event ID: -1, Message: User juanjo failed to log in.
> > > >
> > > > I'm using correct user and password becuase I can login in a Windows
> > > client
> > > > machine which is inside siee.local domain with this user and its
> correct
> > > > password.
> > > >
> > > > What do you think it could be the problem?
> > > >
> > > > If you need more information or I have to configure any other
> parameters,
> > > > please tell me.
> > >
> > > please attach full engine.log, more correctly, stop engine, remove
> > > engine.log start engine, try to login and send log.
> > > please make sure you select the "siee.local" domain in dropdown of
> login
> > > screen.
> > >
> > > when I get the engine.log I will be able to understand who to progress.
> > >
> > > thanks!
> > >
> > >
> > > >
> > > > Many thanks in advanced,
> > > >
> > > > Juanjo.
> > > >
> > > >
> > > >
> > > > On Wed, Nov 26, 2014 at 3:19 PM, Alon Bar-Lev <alo...@redhat.com>
> wrote:
> > > >
> > > > >
> > > > >
> > > > > ----- Original Message -----
> > > > > > From: "Juan Jose" <jj197...@gmail.com>
> > > > > > To: "Alon Bar-Lev" <alo...@redhat.com>
> > > > > > Cc: "Ondra Machacek" <omach...@redhat.com>, "Yair Zaslavsky" <
> > > > > yzasl...@redhat.com>, users@ovirt.org
> > > > > > Sent: Wednesday, November 26, 2014 3:04:14 PM
> > > > > > Subject: Re: [ovirt-users] Adding domain to oVirt to 3.5 issue
> > > > > >
> > > > > > Hello Alon and everybody,
> > > > > >
> > > > > > Check in my ovirt-engine machine for ovirt-engine-aaa-ldap
> package
> > > and it
> > > > > > is not available:
> > > > > >
> > > > > > yum list "ovirt-engine*"
> > > > > > Loaded plugins: fastestmirror, refresh-packagekit, security,
> > > versionlock
> > > > > > Loading mirror speeds from cached hostfile
> > > > > >  * base: ftp.udl.es
> > > > > >  * epel: mirror.uv.es
> > > > > >  * extras: ftp.udl.es
> > > > > >  * ovirt-3.5: ftp.nluug.nl
> > > > > >  * ovirt-3.5-epel: mirror.uv.es
> > > > > >  * ovirt-3.5-jpackage-6.0-generic: mirror.ibcp.fr
> > > > > >  * ovirt-epel: mirror.uv.es
> > > > > >  * ovirt-jpackage-6.0-generic: mirror.ibcp.fr
> > > > > >  * updates: ftp.udl.es
> > > > > > Installed Packages
> > > > > > ovirt-engine.noarch
> > > > > > 3.5.0.1-1.el6                         @ovirt-3.5
> > > > > > ovirt-engine-backend.noarch
> > > > > > 3.5.0.1-1.el6                         @ovirt-3.5
> > > > > > ovirt-engine-cli.noarch
> > > > > > 3.3.0.6-1.el6                         @ovirt-3.3.3
> > > > > > ovirt-engine-dbscripts.noarch
> > > > > > 3.5.0.1-1.el6                         @ovirt-3.5
> > > > > > ovirt-engine-extensions-api-impl.noarch
> > > > > > 3.5.0.1-1.el6                         @ovirt-3.5
> > > > > > ovirt-engine-jboss-as.x86_64
> > > > > > 7.1.1-1.el6                           @ovirt-3.5
> > > > > > ovirt-engine-lib.noarch
> > > > > > 3.5.0.1-1.el6                         @ovirt-3.5
> > > > > > ovirt-engine-restapi.noarch
> > > > > > 3.5.0.1-1.el6                         @ovirt-3.5
> > > > > > ovirt-engine-sdk-python.noarch
> > > > > > 3.5.0.8-1.el6                         @ovirt-3.5
> > > > > > ovirt-engine-setup.noarch
> > > > > > 3.5.0.1-1.el6                         @ovirt-3.5
> > > > > > ovirt-engine-setup-base.noarch
> > > > > > 3.5.0.1-1.el6                         @ovirt-3.5
> > > > > > ovirt-engine-setup-plugin-ovirt-engine.noarch
> > > > > > 3.5.0.1-1.el6                         @ovirt-3.5
> > > > > > ovirt-engine-setup-plugin-ovirt-engine-common.noarch
> > > > > > 3.5.0.1-1.el6                         @ovirt-3.5
> > > > > > ovirt-engine-setup-plugin-websocket-proxy.noarch
> > > > > > 3.5.0.1-1.el6                         @ovirt-3.5
> > > > > > ovirt-engine-tools.noarch
> > > > > > 3.5.0.1-1.el6                         @ovirt-3.5
> > > > > > ovirt-engine-userportal.noarch
> > > > > > 3.5.0.1-1.el6                         @ovirt-3.5
> > > > > > ovirt-engine-webadmin-portal.noarch
> > > > > > 3.5.0.1-1.el6                         @ovirt-3.5
> > > > > > ovirt-engine-websocket-proxy.noarch
> > > > > > 3.5.0.1-1.el6                         @ovirt-3.5
> > > > > > Available Packages
> > > > > > ovirt-engine-cli.noarch
> > > > > > 3.5.0.5-1.el6                         ovirt-3.5
> > > > > > ovirt-engine-dwh.noarch
> > > > > > 3.5.0-1.el6                           ovirt-3.5
> > > > > > ovirt-engine-dwh-setup.noarch
> > > > > > 3.5.0-1.el6                           ovirt-3.5
> > > > > > ovirt-engine-extensions-api-impl-javadoc.noarch
> > > > > > 3.5.0.1-1.el6                         ovirt-3.5
> > > > > > ovirt-engine-reports.noarch
> > > > > > 3.5.1-0.1.el6                         ovirt-3.5
> > > > > > ovirt-engine-reports-setup.noarch
> > > > > > 3.5.1-0.1.el6                         ovirt-3.5
> > > > > > ovirt-engine-sdk-java.noarch
> > > > > > 3.5.0.5-1.el6                         ovirt-3.5
> > > > > > ovirt-engine-sdk-java-javadoc.noarch
> > > > > > 3.5.0.5-1.el6                         ovirt-3.5
> > > > > > ovirt-engine-setup-plugin-allinone.noarch
> > > > > >
> > > > > > How can I get this package?
> > > > >
> > > > >
> > > > > Thanks for trying!
> > > > >
> > > > > Package is available at ovirt-3.5-snapshot[1].
> > > > >
> > > > > [1] http://resources.ovirt.org/pub/ovirt-3.5-snapshot/
> > > > >
> > > >
> > >
> >
>
2014-12-05 11:25:03,956 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Loaded file "/usr/share/ovirt-engine/services/ovirt-engine/ovirt-engine.conf".
2014-12-05 11:25:03,957 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) The file "/etc/ovirt-engine/engine.conf" doesn't exist or isn't readable. Will return an empty set of properties.
2014-12-05 11:25:03,958 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Loaded file "/etc/ovirt-engine/engine.conf.d/10-setup-database.conf".
2014-12-05 11:25:03,959 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Loaded file "/etc/ovirt-engine/engine.conf.d/10-setup-jboss.conf".
2014-12-05 11:25:03,959 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Loaded file "/etc/ovirt-engine/engine.conf.d/10-setup-pki.conf".
2014-12-05 11:25:03,960 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Loaded file "/etc/ovirt-engine/engine.conf.d/10-setup-protocols.conf".
2014-12-05 11:25:03,961 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Loaded file "/etc/ovirt-engine/engine.conf.d/50-ovirt-engine-extension-aaa-ldap.conf".
2014-12-05 11:25:03,962 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "ENGINE_AJP_ENABLED" is "true".
2014-12-05 11:25:03,962 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "ENGINE_AJP_PORT" is "8702".
2014-12-05 11:25:03,963 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "ENGINE_APPS" is "engine.ear restapi.war legacy_restapi.war".
2014-12-05 11:25:03,963 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "ENGINE_CACHE" is "/var/cache/ovirt-engine".
2014-12-05 11:25:03,964 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "ENGINE_DB_CHECK_INTERVAL" is "1000".
2014-12-05 11:25:03,964 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "ENGINE_DB_CONNECTION_TIMEOUT" is "300000".
2014-12-05 11:25:03,965 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "ENGINE_DB_DATABASE" is "engine".
2014-12-05 11:25:03,965 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "ENGINE_DB_DRIVER" is "org.postgresql.Driver".
2014-12-05 11:25:03,966 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "ENGINE_DB_HOST" is "localhost".
2014-12-05 11:25:03,966 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "ENGINE_DB_MAX_CONNECTIONS" is "100".
2014-12-05 11:25:03,967 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "ENGINE_DB_MIN_CONNECTIONS" is "1".
2014-12-05 11:25:03,967 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "ENGINE_DB_PASSWORD" is "***".
2014-12-05 11:25:03,968 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "ENGINE_DB_PORT" is "5432".
2014-12-05 11:25:03,968 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "ENGINE_DB_SECURED" is "False".
2014-12-05 11:25:03,968 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "ENGINE_DB_SECURED_VALIDATION" is "False".
2014-12-05 11:25:03,970 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "ENGINE_DB_URL" is "jdbc:postgresql://localhost:5432/engine?sslfactory=org.postgresql.ssl.NonValidatingFactory".
2014-12-05 11:25:03,970 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "ENGINE_DB_USER" is "engine".
2014-12-05 11:25:03,971 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "ENGINE_DEBUG_ADDRESS" is "".
2014-12-05 11:25:03,971 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "ENGINE_DOC" is "/usr/share/doc/ovirt-engine".
2014-12-05 11:25:03,972 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "ENGINE_ETC" is "/etc/ovirt-engine".
2014-12-05 11:25:03,972 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "ENGINE_EXTENSION_PATH" is "/usr/share/ovirt-engine/extensions.d:/etc/ovirt-engine/extensions.d".
2014-12-05 11:25:03,973 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "ENGINE_FQDN" is "ovirt-engine.siee.local".
2014-12-05 11:25:03,973 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "ENGINE_GROUP" is "ovirt".
2014-12-05 11:25:03,974 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "ENGINE_HEAP_MAX" is "1g".
2014-12-05 11:25:03,974 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "ENGINE_HEAP_MIN" is "1g".
2014-12-05 11:25:03,974 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "ENGINE_HTTPS_ENABLED" is "false".
2014-12-05 11:25:03,975 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "ENGINE_HTTPS_PORT" is "None".
2014-12-05 11:25:03,975 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "ENGINE_HTTPS_PROTOCOLS" is "SSLv3,TLSv1,TLSv1.1,TLSv1.2".
2014-12-05 11:25:03,976 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "ENGINE_HTTP_ENABLED" is "false".
2014-12-05 11:25:03,976 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "ENGINE_HTTP_PORT" is "None".
2014-12-05 11:25:03,976 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "ENGINE_JAVA_MODULEPATH" is "/usr/share/ovirt-engine/modules:/usr/share/ovirt-engine-extension-aaa-ldap/modules".
2014-12-05 11:25:03,977 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "ENGINE_JVM_ARGS" is " -XX:+HeapDumpOnOutOfMemoryError -XX:HeapDumpPath="/var/log/ovirt-engine/dump"".
2014-12-05 11:25:03,978 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "ENGINE_LOG" is "/var/log/ovirt-engine".
2014-12-05 11:25:03,978 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "ENGINE_LOG_TO_CONSOLE" is "false".
2014-12-05 11:25:03,978 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "ENGINE_MANUAL" is "/usr/share/ovirt-engine/manual".
2014-12-05 11:25:03,979 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "ENGINE_PERM_MAX" is "256m".
2014-12-05 11:25:03,979 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "ENGINE_PERM_MIN" is "256m".
2014-12-05 11:25:03,980 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "ENGINE_PKI" is "/etc/pki/ovirt-engine".
2014-12-05 11:25:03,980 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "ENGINE_PKI_CA" is "/etc/pki/ovirt-engine/ca.pem".
2014-12-05 11:25:03,980 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "ENGINE_PKI_ENGINE_CERT" is "/etc/pki/ovirt-engine/certs/engine.cer".
2014-12-05 11:25:03,981 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "ENGINE_PKI_ENGINE_STORE" is "/etc/pki/ovirt-engine/keys/engine.p12".
2014-12-05 11:25:03,981 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "ENGINE_PKI_ENGINE_STORE_ALIAS" is "1".
2014-12-05 11:25:03,982 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "ENGINE_PKI_ENGINE_STORE_PASSWORD" is "***".
2014-12-05 11:25:03,985 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "ENGINE_PKI_TRUST_STORE" is "/etc/pki/ovirt-engine/.truststore".
2014-12-05 11:25:03,985 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "ENGINE_PKI_TRUST_STORE_PASSWORD" is "***".
2014-12-05 11:25:03,986 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "ENGINE_PROPERTIES" is " jsse.enableSNIExtension=false "java.security.krb5.conf=/etc/ovirt-engine/krb5.conf"".
2014-12-05 11:25:03,986 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "ENGINE_PROXY_ENABLED" is "true".
2014-12-05 11:25:03,986 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "ENGINE_PROXY_HTTPS_PORT" is "443".
2014-12-05 11:25:03,987 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "ENGINE_PROXY_HTTP_PORT" is "80".
2014-12-05 11:25:03,987 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "ENGINE_REPORTS_BASE_URL" is "".
2014-12-05 11:25:03,988 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "ENGINE_REPORTS_DASHBOARD_URL" is "/flow.html?viewAsDashboardFrame=true".
2014-12-05 11:25:03,988 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "ENGINE_REPORTS_PROXY_URL" is "/ovirt/reports-interface".
2014-12-05 11:25:03,989 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "ENGINE_REPORTS_READ_TIMEOUT" is "".
2014-12-05 11:25:03,989 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "ENGINE_REPORTS_RIGHTCLICK_URL" is "".
2014-12-05 11:25:03,989 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "ENGINE_REPORTS_VERIFY_CHAIN" is "true".
2014-12-05 11:25:03,990 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "ENGINE_REPORTS_VERIFY_HOST" is "true".
2014-12-05 11:25:03,990 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "ENGINE_STOP_INTERVAL" is "1".
2014-12-05 11:25:03,990 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "ENGINE_STOP_TIME" is "10".
2014-12-05 11:25:03,991 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "ENGINE_TMP" is "/var/tmp/ovirt-engine".
2014-12-05 11:25:03,991 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "ENGINE_UP_MARK" is "/var/lib/ovirt-engine/engine.up".
2014-12-05 11:25:03,992 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "ENGINE_URI" is "/ovirt-engine".
2014-12-05 11:25:04,000 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "ENGINE_USER" is "ovirt".
2014-12-05 11:25:04,000 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "ENGINE_USR" is "/usr/share/ovirt-engine".
2014-12-05 11:25:04,001 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "ENGINE_VAR" is "/var/lib/ovirt-engine".
2014-12-05 11:25:04,001 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "ENGINE_VERBOSE_GC" is "false".
2014-12-05 11:25:04,002 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "JBOSS_HOME" is "/usr/share/ovirt-engine-jboss-as".
2014-12-05 11:25:04,002 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "JBOSS_RUNTIME" is "/var/lib/ovirt-engine/jboss_runtime".
2014-12-05 11:25:04,002 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-4) Value of property "SENSITIVE_KEYS" is ",ENGINE_DB_PASSWORD,ENGINE_PKI_TRUST_STORE_PASSWORD,ENGINE_PKI_ENGINE_STORE_PASSWORD".
2014-12-05 11:25:04,404 INFO  [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) Start initializing Backend
2014-12-05 11:25:04,451 WARN  [org.ovirt.engine.core.utils.ConfigUtilsBase] (MSC service thread 1-2) Could not find enum value for option: DesktopAudioDeviceType
2014-12-05 11:25:04,452 WARN  [org.ovirt.engine.core.utils.ConfigUtilsBase] (MSC service thread 1-2) Could not find enum value for option: DesktopAudioDeviceType
2014-12-05 11:25:04,453 WARN  [org.ovirt.engine.core.utils.ConfigUtilsBase] (MSC service thread 1-2) Could not find enum value for option: DesktopAudioDeviceType
2014-12-05 11:25:04,453 WARN  [org.ovirt.engine.core.utils.ConfigUtilsBase] (MSC service thread 1-2) Could not find enum value for option: DesktopAudioDeviceType
2014-12-05 11:25:04,514 WARN  [org.ovirt.engine.core.utils.ConfigUtilsBase] (MSC service thread 1-2) Could not find enum value for option: UserSessionTimeOutInvalidationInterval
2014-12-05 11:25:04,708 INFO  [org.ovirt.engine.core.utils.osinfo.OsInfoPreferencesLoader] (MSC service thread 1-2) Loaded file /etc/ovirt-engine/osinfo.conf.d/00-defaults.properties
2014-12-05 11:25:04,709 INFO  [org.ovirt.engine.core.utils.osinfo.OsInfoPreferencesLoader] (MSC service thread 1-2) Loaded file /etc/ovirt-engine/osinfo.conf.d/10-sysprep.properties
2014-12-05 11:25:04,835 INFO  [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) Running ovirt-engine 3.5.0.1-1.el6
2014-12-05 11:25:04,836 INFO  [org.ovirt.engine.core.bll.CpuFlagsManagerHandler] (MSC service thread 1-2) Start initializing dictionaries
2014-12-05 11:25:04,839 INFO  [org.ovirt.engine.core.bll.CpuFlagsManagerHandler] (MSC service thread 1-2) Finished initializing dictionaries
2014-12-05 11:25:04,839 INFO  [org.ovirt.engine.core.bll.AuditLogCleanupManager] (MSC service thread 1-2) Start initializing AuditLogCleanupManager
2014-12-05 11:25:04,840 INFO  [org.ovirt.engine.core.bll.AuditLogCleanupManager] (MSC service thread 1-2) Setting audit cleanup manager to run at: 35 35 3 * * ?
2014-12-05 11:25:04,864 INFO  [org.ovirt.engine.core.bll.AuditLogCleanupManager] (MSC service thread 1-2) Finished initializing AuditLogCleanupManager
2014-12-05 11:25:04,865 INFO  [org.ovirt.engine.core.bll.AuditLogCleanupManager] (MSC service thread 1-2) Start initializing CommandEntityCleanupManager
2014-12-05 11:25:04,866 INFO  [org.ovirt.engine.core.bll.AuditLogCleanupManager] (MSC service thread 1-2) Setting command entity cleanup manager to run at: 35 35 3 * * ?
2014-12-05 11:25:04,867 INFO  [org.ovirt.engine.core.bll.AuditLogCleanupManager] (MSC service thread 1-2) Finished initializing CommandEntityCleanupManager
2014-12-05 11:25:04,871 INFO  [org.ovirt.engine.core.bll.TagsDirector] (MSC service thread 1-2) Start initializing TagsDirector
2014-12-05 11:25:04,883 INFO  [org.ovirt.engine.core.bll.TagsDirector] (MSC service thread 1-2) Tag root added to tree
2014-12-05 11:25:04,894 INFO  [org.ovirt.engine.core.bll.TagsDirector] (MSC service thread 1-2) Finished initializing TagsDirector
2014-12-05 11:25:04,895 INFO  [org.ovirt.engine.core.bll.IsoDomainListSyncronizer] (MSC service thread 1-2) Start initializing IsoDomainListSyncronizer
2014-12-05 11:25:04,902 INFO  [org.ovirt.engine.core.bll.IsoDomainListSyncronizer] (MSC service thread 1-2) Finished initializing IsoDomainListSyncronizer
2014-12-05 11:25:04,984 INFO  [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) Completed initializing handlers
2014-12-05 11:25:05,014 INFO  [org.ovirt.engine.core.utils.ErrorTranslatorImpl] (MSC service thread 1-2) Start initializing ErrorTranslatorImpl
2014-12-05 11:25:05,023 WARN  [org.ovirt.engine.core.utils.ErrorTranslatorImpl] (MSC service thread 1-2) Code MAC_ADDRESS_IS_IN_USE appears more than once in string table.
2014-12-05 11:25:05,024 INFO  [org.ovirt.engine.core.utils.ErrorTranslatorImpl] (MSC service thread 1-2) Finished initializing ErrorTranslatorImpl
2014-12-05 11:25:05,024 INFO  [org.ovirt.engine.core.utils.ErrorTranslatorImpl] (MSC service thread 1-2) Start initializing ErrorTranslatorImpl
2014-12-05 11:25:05,025 INFO  [org.ovirt.engine.core.utils.ErrorTranslatorImpl] (MSC service thread 1-2) Finished initializing ErrorTranslatorImpl
2014-12-05 11:25:05,026 INFO  [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) Mark incomplete jobs as UNKNOWN
2014-12-05 11:25:05,078 INFO  [org.ovirt.engine.core.bll.job.JobRepositoryCleanupManager] (MSC service thread 1-2) Start initializing JobRepositoryCleanupManager
2014-12-05 11:25:05,079 INFO  [org.ovirt.engine.core.bll.job.JobRepositoryCleanupManager] (MSC service thread 1-2) Finished initializing JobRepositoryCleanupManager
2014-12-05 11:25:05,080 INFO  [org.ovirt.engine.core.bll.AutoRecoveryManager] (MSC service thread 1-2) Start initializing AutoRecoveryManager
2014-12-05 11:25:05,082 INFO  [org.ovirt.engine.core.bll.AutoRecoveryManager] (MSC service thread 1-2) Finished initializing AutoRecoveryManager
2014-12-05 11:25:05,084 INFO  [org.ovirt.engine.core.dal.job.ExecutionMessageDirector] (MSC service thread 1-2) Start initializing ExecutionMessageDirector
2014-12-05 11:25:05,086 INFO  [org.ovirt.engine.core.dal.job.ExecutionMessageDirector] (MSC service thread 1-2) Finished initializing ExecutionMessageDirector
2014-12-05 11:25:05,137 INFO  [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (MSC service thread 1-2) Loading extension 'builtin-authn-internal'
2014-12-05 11:25:05,139 INFO  [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (MSC service thread 1-2) Extension 'builtin-authn-internal' loaded
2014-12-05 11:25:05,141 INFO  [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (MSC service thread 1-2) Loading extension 'internal'
2014-12-05 11:25:05,144 INFO  [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (MSC service thread 1-2) Extension 'internal' loaded
2014-12-05 11:25:05,150 INFO  [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (MSC service thread 1-2) Loading extension 'siee-local-authn'
2014-12-05 11:25:05,201 INFO  [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (MSC service thread 1-2) Extension 'siee-local-authn' loaded
2014-12-05 11:25:05,206 INFO  [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (MSC service thread 1-2) Loading extension 'siee-local-authz'
2014-12-05 11:25:05,215 INFO  [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (MSC service thread 1-2) Extension 'siee-local-authz' loaded
2014-12-05 11:25:05,216 INFO  [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (MSC service thread 1-2) Initializing extension 'siee-local-authn'
2014-12-05 11:25:05,216 INFO  [org.ovirt.engineextensions.aaa.ldap.Framework] (MSC service thread 1-2) [ovirt-engine-extension-aaa-ldap.authn::siee-local-authn] Creating LDAP pool 'authz'
2014-12-05 11:25:05,449 ERROR [org.ovirt.engineextensions.aaa.ldap.AuthnExtension] (MSC service thread 1-2) [ovirt-engine-extension-aaa-ldap.authn::siee-local-authn] Cannot initialize LDAP framework, deferring initialization. Error: null
2014-12-05 11:25:05,450 INFO  [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (MSC service thread 1-2) Extension 'siee-local-authn' initialized
2014-12-05 11:25:05,451 INFO  [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (MSC service thread 1-2) Initializing extension 'builtin-authn-internal'
2014-12-05 11:25:05,452 INFO  [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (MSC service thread 1-2) Extension 'builtin-authn-internal' initialized
2014-12-05 11:25:05,453 INFO  [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (MSC service thread 1-2) Initializing extension 'siee-local-authz'
2014-12-05 11:25:05,453 INFO  [org.ovirt.engineextensions.aaa.ldap.Framework] (MSC service thread 1-2) [ovirt-engine-extension-aaa-ldap.authz::siee-local-authz] Creating LDAP pool 'authz'
2014-12-05 11:25:05,575 ERROR [org.ovirt.engineextensions.aaa.ldap.AuthzExtension] (MSC service thread 1-2) [ovirt-engine-extension-aaa-ldap.authz::siee-local-authz] Cannot initialize LDAP framework, deferring initialization. Error: null
2014-12-05 11:25:05,577 INFO  [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (MSC service thread 1-2) Extension 'siee-local-authz' initialized
2014-12-05 11:25:05,578 INFO  [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (MSC service thread 1-2) Initializing extension 'internal'
2014-12-05 11:25:05,579 INFO  [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (MSC service thread 1-2) Extension 'internal' initialized
2014-12-05 11:25:05,579 INFO  [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (MSC service thread 1-2) Start of enabled extensions list
2014-12-05 11:25:05,580 INFO  [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (MSC service thread 1-2) Instance name: 'siee-local-authn', Extension name: 'ovirt-engine-extension-aaa-ldap.authn', Version: '0.0.0_master', Notes: 'Display name: ovirt-engine-extension-aaa-ldap-0.0.0-0.0.master.20141107092318.git5a0b572.el6', License: 'ASL 2.0', Home: 'http://www.ovirt.org', Author 'The oVirt Project', Build interface Version: '0',  File: '/etc/ovirt-engine/extensions.d/siee-local-authn.properties', Initialized: 'true'
2014-12-05 11:25:05,581 INFO  [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (MSC service thread 1-2) Instance name: 'builtin-authn-internal', Extension name: 'Internal Authn (Built-in)', Version: 'N/A', Notes: '', License: 'ASL 2.0', Home: 'http://www.ovirt.org', Author 'The oVirt Project', Build interface Version: '0',  File: 'N/A', Initialized: 'true'
2014-12-05 11:25:05,582 INFO  [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (MSC service thread 1-2) Instance name: 'siee-local-authz', Extension name: 'ovirt-engine-extension-aaa-ldap.authz', Version: '0.0.0_master', Notes: 'Display name: ovirt-engine-extension-aaa-ldap-0.0.0-0.0.master.20141107092318.git5a0b572.el6', License: 'ASL 2.0', Home: 'http://www.ovirt.org', Author 'The oVirt Project', Build interface Version: '0',  File: '/etc/ovirt-engine/extensions.d/siee-local-authz.properties', Initialized: 'true'
2014-12-05 11:25:05,584 INFO  [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (MSC service thread 1-2) Instance name: 'internal', Extension name: 'Internal Authz (Built-in)', Version: 'N/A', Notes: '', License: 'ASL 2.0', Home: 'http://www.ovirt.org', Author 'The oVirt Project', Build interface Version: '0',  File: 'N/A', Initialized: 'true'
2014-12-05 11:25:05,585 INFO  [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (MSC service thread 1-2) End of enabled extensions list
2014-12-05 11:25:05,678 INFO  [org.ovirt.engine.core.bll.aaa.DbUserCacheManager] (MSC service thread 1-4) Start initializing DbUserCacheManager
2014-12-05 11:25:05,680 INFO  [org.ovirt.engine.core.bll.aaa.DbUserCacheManager] (MSC service thread 1-4) Finished initializing DbUserCacheManager
2014-12-05 11:25:05,700 INFO  [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (MSC service thread 1-4) Initialization of AsyncTaskManager completed successfully.
2014-12-05 11:25:05,702 INFO  [org.ovirt.engine.core.vdsbroker.ResourceManager] (MSC service thread 1-4) Start initializing ResourceManager
2014-12-05 11:25:05,751 INFO  [org.ovirt.engine.core.bll.aaa.SyncUsers] (DefaultQuartzScheduler_Worker-1) Principal admin::internal synchronized
2014-12-05 11:25:05,796 INFO  [org.ovirt.engine.core.vdsbroker.VdsManager] (MSC service thread 1-4) Entered VdsManager constructor
2014-12-05 11:25:05,838 INFO  [org.ovirt.engine.core.vdsbroker.VdsManager] (MSC service thread 1-4) Initialize vdsBroker (ovirt-host3.siee.local,54,321)
2014-12-05 11:25:05,950 INFO  [org.ovirt.engine.core.vdsbroker.ResourceManager] (MSC service thread 1-4) VDS f776a450-a783-4228-afe5-ca2c04549f0b was added to the Resource Manager
2014-12-05 11:25:05,950 INFO  [org.ovirt.engine.core.vdsbroker.VdsManager] (MSC service thread 1-4) Entered VdsManager constructor
2014-12-05 11:25:05,961 INFO  [org.ovirt.engine.core.vdsbroker.VdsManager] (MSC service thread 1-4) Initialize vdsBroker (ovirt-host2.siee.local,54,321)
2014-12-05 11:25:05,967 INFO  [org.ovirt.engine.core.vdsbroker.ResourceManager] (MSC service thread 1-4) VDS e193487b-3155-41f8-9b5e-7b87945cc2e1 was added to the Resource Manager
2014-12-05 11:25:05,967 INFO  [org.ovirt.engine.core.vdsbroker.VdsManager] (MSC service thread 1-4) Entered VdsManager constructor
2014-12-05 11:25:05,980 INFO  [org.ovirt.engine.core.vdsbroker.VdsManager] (MSC service thread 1-4) Initialize vdsBroker (ovirt-host1.siee.local,54,321)
2014-12-05 11:25:05,984 INFO  [org.ovirt.engine.core.vdsbroker.ResourceManager] (MSC service thread 1-4) VDS e9fb2823-1ce5-409a-8ea3-cde601a45978 was added to the Resource Manager
2014-12-05 11:25:05,985 INFO  [org.ovirt.engine.core.vdsbroker.VdsManager] (MSC service thread 1-4) Entered VdsManager constructor
2014-12-05 11:25:05,995 INFO  [org.ovirt.engine.core.vdsbroker.VdsManager] (MSC service thread 1-4) Initialize vdsBroker (ovirt-host4.siee.local,54,321)
2014-12-05 11:25:05,999 INFO  [org.ovirt.engine.core.vdsbroker.ResourceManager] (MSC service thread 1-4) VDS e14b8e32-c4b5-41f5-afb2-35b3ac0432fc was added to the Resource Manager
2014-12-05 11:25:06,025 INFO  [org.ovirt.engine.core.vdsbroker.ResourceManager] (MSC service thread 1-4) Finished initializing ResourceManager
2014-12-05 11:25:06,031 INFO  [org.ovirt.engine.core.bll.OvfDataUpdater] (MSC service thread 1-4) Initialization of OvfDataUpdater completed successfully.
2014-12-05 11:25:06,032 INFO  [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (MSC service thread 1-4) Start scheduling to enable vds load balancer
2014-12-05 11:25:06,032 INFO  [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (MSC service thread 1-4) Finished scheduling to enable vds load balancer
2014-12-05 11:25:06,033 INFO  [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (MSC service thread 1-4) Start HA Reservation check
2014-12-05 11:25:06,033 INFO  [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (MSC service thread 1-4) Finished HA Reservation check
2014-12-05 11:25:06,036 INFO  [org.ovirt.engine.core.bll.network.MacPoolManagerRanges] (org.ovirt.thread.pool-8-thread-1) Start initializing MacPoolManagerRanges
2014-12-05 11:25:06,040 INFO  [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (MSC service thread 1-4) Init VM custom properties utilities
2014-12-05 11:25:06,040 INFO  [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (MSC service thread 1-4) Init device custom properties utilities
2014-12-05 11:25:06,044 INFO  [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (MSC service thread 1-4) Initializing Scheduling manager
2014-12-05 11:25:06,050 INFO  [org.ovirt.engine.core.bll.network.MacPoolManagerRanges] (org.ovirt.thread.pool-8-thread-1) Finished initializing. Available MACs in pool: 243
2014-12-05 11:25:06,071 INFO  [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (MSC service thread 1-4) External scheduler disabled, discovery skipped
2014-12-05 11:25:06,072 INFO  [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (MSC service thread 1-4) Initialized Scheduling manager
2014-12-05 11:25:06,072 INFO  [org.ovirt.engine.core.bll.dwh.DwhHeartBeat] (MSC service thread 1-4) Initializing DWH Heart Beat
2014-12-05 11:25:06,073 INFO  [org.ovirt.engine.core.bll.dwh.DwhHeartBeat] (MSC service thread 1-4) DWH Heart Beat initialized
2014-12-05 11:25:08,527 INFO  [org.ovirt.engine.core.utils.servlet.UnsupportedLocaleHelper] (ajp--127.0.0.1-8702-2) Invalid locale found in configuration: 
2014-12-05 11:25:08,528 INFO  [org.ovirt.engine.core.utils.servlet.UnsupportedLocaleHelper] (ajp--127.0.0.1-8702-2) Invalid locale found in configuration: 
2014-12-05 11:25:09,996 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetHardwareInfoVDSCommand] (DefaultQuartzScheduler_Worker-9) START, GetHardwareInfoVDSCommand(HostName = host4, HostId = e14b8e32-c4b5-41f5-afb2-35b3ac0432fc, vds=Host[host4,e14b8e32-c4b5-41f5-afb2-35b3ac0432fc]), log id: 428c860b
2014-12-05 11:25:10,037 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetHardwareInfoVDSCommand] (DefaultQuartzScheduler_Worker-9) FINISH, GetHardwareInfoVDSCommand, log id: 428c860b
2014-12-05 11:25:10,047 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-9) AuditLogType: UNASSIGNED not exist in string table
2014-12-05 11:25:10,051 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-9) AuditLogType: VDS_HIGH_NETWORK_USE not exist in string table
2014-12-05 11:25:10,053 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-9) AuditLogType: USER_FAILED_REMOVE_VM not exist in string table
2014-12-05 11:25:10,053 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-9) AuditLogType: USER_RUN_UNLOCK_ENTITY_SCRIPT not exist in string table
2014-12-05 11:25:10,054 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetHardwareInfoVDSCommand] (DefaultQuartzScheduler_Worker-7) START, GetHardwareInfoVDSCommand(HostName = host2, HostId = e193487b-3155-41f8-9b5e-7b87945cc2e1, vds=Host[host2,e193487b-3155-41f8-9b5e-7b87945cc2e1]), log id: 3ac2fe82
2014-12-05 11:25:10,067 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetHardwareInfoVDSCommand] (DefaultQuartzScheduler_Worker-6) START, GetHardwareInfoVDSCommand(HostName = host3, HostId = f776a450-a783-4228-afe5-ca2c04549f0b, vds=Host[host3,f776a450-a783-4228-afe5-ca2c04549f0b]), log id: 3b28d29f
2014-12-05 11:25:10,069 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-9) AuditLogType: VDS_NETWORK_MTU_DIFFER_FROM_LOGICAL_NETWORK not exist in string table
2014-12-05 11:25:10,070 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-9) AuditLogType: STORAGE_ACTIVATE_ASYNC not exist in string table
2014-12-05 11:25:10,071 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-9) AuditLogType: USER_ADDED_DISK_PROFILE not exist in string table
2014-12-05 11:25:10,071 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-9) AuditLogType: USER_FAILED_TO_ADD_DISK_PROFILE not exist in string table
2014-12-05 11:25:10,072 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-9) AuditLogType: USER_REMOVED_DISK_PROFILE not exist in string table
2014-12-05 11:25:10,073 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-9) AuditLogType: USER_FAILED_TO_REMOVE_DISK_PROFILE not exist in string table
2014-12-05 11:25:10,074 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-9) AuditLogType: USER_UPDATED_DISK_PROFILE not exist in string table
2014-12-05 11:25:10,074 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-9) AuditLogType: USER_FAILED_TO_UPDATE_DISK_PROFILE not exist in string table
2014-12-05 11:25:10,074 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetHardwareInfoVDSCommand] (DefaultQuartzScheduler_Worker-8) START, GetHardwareInfoVDSCommand(HostName = host1, HostId = e9fb2823-1ce5-409a-8ea3-cde601a45978, vds=Host[host1,e9fb2823-1ce5-409a-8ea3-cde601a45978]), log id: 7c21a2d0
2014-12-05 11:25:10,075 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-9) AuditLogType: USER_ADDED_CPU_PROFILE not exist in string table
2014-12-05 11:25:10,077 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-9) AuditLogType: USER_FAILED_TO_ADD_CPU_PROFILE not exist in string table
2014-12-05 11:25:10,077 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-9) AuditLogType: USER_REMOVED_CPU_PROFILE not exist in string table
2014-12-05 11:25:10,078 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-9) AuditLogType: USER_FAILED_TO_REMOVE_CPU_PROFILE not exist in string table
2014-12-05 11:25:10,078 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-9) AuditLogType: USER_UPDATED_CPU_PROFILE not exist in string table
2014-12-05 11:25:10,079 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-9) AuditLogType: USER_FAILED_TO_UPDATE_CPU_PROFILE not exist in string table
2014-12-05 11:25:10,103 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetHardwareInfoVDSCommand] (DefaultQuartzScheduler_Worker-7) FINISH, GetHardwareInfoVDSCommand, log id: 3ac2fe82
2014-12-05 11:25:10,122 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetHardwareInfoVDSCommand] (DefaultQuartzScheduler_Worker-6) FINISH, GetHardwareInfoVDSCommand, log id: 3b28d29f
2014-12-05 11:25:10,144 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetHardwareInfoVDSCommand] (DefaultQuartzScheduler_Worker-8) FINISH, GetHardwareInfoVDSCommand, log id: 7c21a2d0
2014-12-05 11:25:10,298 WARN  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-8) Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: Host host1 does not enforce SELinux.
2014-12-05 11:25:10,305 WARN  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-9) Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: Host host4 does not enforce SELinux.
2014-12-05 11:25:10,306 WARN  [org.ovirt.engine.core.vdsbroker.VdsManager] (DefaultQuartzScheduler_Worker-9) Host host4 does not report SELinux enforcement information.
2014-12-05 11:25:10,306 WARN  [org.ovirt.engine.core.vdsbroker.VdsManager] (DefaultQuartzScheduler_Worker-8) Host host1 does not report SELinux enforcement information.
2014-12-05 11:25:10,320 WARN  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-7) Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: Host host2 does not enforce SELinux.
2014-12-05 11:25:10,321 WARN  [org.ovirt.engine.core.vdsbroker.VdsManager] (DefaultQuartzScheduler_Worker-7) Host host2 does not report SELinux enforcement information.
2014-12-05 11:25:10,329 WARN  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-6) Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: Host host3 does not enforce SELinux.
2014-12-05 11:25:10,330 WARN  [org.ovirt.engine.core.vdsbroker.VdsManager] (DefaultQuartzScheduler_Worker-6) Host host3 does not report SELinux enforcement information.
2014-12-05 11:25:10,616 INFO  [org.ovirt.engine.core.vdsbroker.VdsManager] (DefaultQuartzScheduler_Worker-9) Initializing Host: host4
2014-12-05 11:25:10,633 INFO  [org.ovirt.engine.core.vdsbroker.VdsManager] (DefaultQuartzScheduler_Worker-6) Initializing Host: host3
2014-12-05 11:25:10,675 INFO  [org.ovirt.engine.core.vdsbroker.VdsManager] (DefaultQuartzScheduler_Worker-8) Initializing Host: host1
2014-12-05 11:25:10,718 INFO  [org.ovirt.engine.core.bll.HandleVdsVersionCommand] (DefaultQuartzScheduler_Worker-9) [550077ee] Running command: HandleVdsVersionCommand internal: true. Entities affected :  ID: e14b8e32-c4b5-41f5-afb2-35b3ac0432fc Type: VDS
2014-12-05 11:25:10,719 INFO  [org.ovirt.engine.core.bll.HandleVdsVersionCommand] (DefaultQuartzScheduler_Worker-8) [5416db7f] Running command: HandleVdsVersionCommand internal: true. Entities affected :  ID: e9fb2823-1ce5-409a-8ea3-cde601a45978 Type: VDS
2014-12-05 11:25:10,722 INFO  [org.ovirt.engine.core.bll.HandleVdsVersionCommand] (DefaultQuartzScheduler_Worker-6) [11c1d5db] Running command: HandleVdsVersionCommand internal: true. Entities affected :  ID: f776a450-a783-4228-afe5-ca2c04549f0b Type: VDS
2014-12-05 11:25:10,733 INFO  [org.ovirt.engine.core.vdsbroker.VdsManager] (DefaultQuartzScheduler_Worker-7) Initializing Host: host2
2014-12-05 11:25:10,756 INFO  [org.ovirt.engine.core.bll.HandleVdsVersionCommand] (DefaultQuartzScheduler_Worker-7) [225dff24] Running command: HandleVdsVersionCommand internal: true. Entities affected :  ID: e193487b-3155-41f8-9b5e-7b87945cc2e1 Type: VDS
2014-12-05 11:25:13,453 INFO  [org.ovirt.engine.core.utils.servlet.UnsupportedLocaleHelper] (ajp--127.0.0.1-8702-4) Invalid locale found in configuration: 
2014-12-05 11:25:13,454 INFO  [org.ovirt.engine.core.utils.servlet.UnsupportedLocaleHelper] (ajp--127.0.0.1-8702-4) Invalid locale found in configuration: 
2014-12-05 11:25:16,050 INFO  [org.ovirt.engine.core.bll.storage.SetStoragePoolStatusCommand] (DefaultQuartzScheduler_Worker-25) [1a254a25] Running command: SetStoragePoolStatusCommand internal: true. Entities affected :  ID: 5849b030-626e-47cb-ad90-3ce782d831b3 Type: StoragePool
2014-12-05 11:25:16,075 INFO  [org.ovirt.engine.core.vdsbroker.storage.StoragePoolDomainHelper] (DefaultQuartzScheduler_Worker-25) [1a254a25] Storage Pool 5849b030-626e-47cb-ad90-3ce782d831b3 - Updating Storage Domain 2a299756-37df-49b4-85a6-220800d747bb status from Active to Unknown, reason : null
2014-12-05 11:25:16,089 INFO  [org.ovirt.engine.core.vdsbroker.storage.StoragePoolDomainHelper] (DefaultQuartzScheduler_Worker-25) [1a254a25] Storage Pool 5849b030-626e-47cb-ad90-3ce782d831b3 - Updating Storage Domain 1cff1b75-c4bd-42c2-8589-5d20fb30ee4c status from Active to Unknown, reason : null
2014-12-05 11:25:16,143 WARN  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-25) [1a254a25] Correlation ID: 1a254a25, Call Stack: null, Custom Event ID: -1, Message: Invalid status on Data Center Default. Setting status to Non Responsive.
2014-12-05 11:25:16,295 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IrsProxyData] (DefaultQuartzScheduler_Worker-25) [1a254a25] hostFromVds::selectedVds - host1, spmStatus SPM, storage pool Default
2014-12-05 11:25:16,310 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IrsProxyData] (DefaultQuartzScheduler_Worker-25) [1a254a25] Initialize Irs proxy from vds: ovirt-host1.siee.local
2014-12-05 11:25:16,327 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-25) [1a254a25] Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: Storage Pool Manager runs on Host host1 (Address: ovirt-host1.siee.local).
2014-12-05 11:25:16,354 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMGetAllTasksInfoVDSCommand] (org.ovirt.thread.pool-8-thread-39) [1a254a25] START, SPMGetAllTasksInfoVDSCommand( storagePoolId = 5849b030-626e-47cb-ad90-3ce782d831b3, ignoreFailoverLimit = false), log id: 6d271738
2014-12-05 11:25:16,394 INFO  [org.ovirt.engine.core.bll.storage.SyncLunsInfoForBlockStorageDomainCommand] (org.ovirt.thread.pool-8-thread-41) [2e94a248] Running command: SyncLunsInfoForBlockStorageDomainCommand internal: true. Entities affected :  ID: 2a299756-37df-49b4-85a6-220800d747bb Type: Storage
2014-12-05 11:25:16,413 INFO  [org.ovirt.engine.core.bll.storage.SyncLunsInfoForBlockStorageDomainCommand] (org.ovirt.thread.pool-8-thread-42) [6b17db3b] Running command: SyncLunsInfoForBlockStorageDomainCommand internal: true. Entities affected :  ID: 1cff1b75-c4bd-42c2-8589-5d20fb30ee4c Type: Storage
2014-12-05 11:25:16,418 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMGetAllTasksInfoVDSCommand] (org.ovirt.thread.pool-8-thread-39) [1a254a25] -- executeIrsBrokerCommand: Attempting on storage pool 5849b030-626e-47cb-ad90-3ce782d831b3
2014-12-05 11:25:16,437 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMGetAllTasksInfoVDSCommand] (org.ovirt.thread.pool-8-thread-39) [1a254a25] START, HSMGetAllTasksInfoVDSCommand(HostName = host1, HostId = e9fb2823-1ce5-409a-8ea3-cde601a45978), log id: fb1a87f
2014-12-05 11:25:16,451 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetVGInfoVDSCommand] (org.ovirt.thread.pool-8-thread-41) [2e94a248] START, GetVGInfoVDSCommand(HostName = host1, HostId = e9fb2823-1ce5-409a-8ea3-cde601a45978, VGID=BQdHug-TNC8-N6RF-JeGr-omZl-Fn2m-a00lLN), log id: 280ee85e
2014-12-05 11:25:16,453 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetVGInfoVDSCommand] (org.ovirt.thread.pool-8-thread-42) [6b17db3b] START, GetVGInfoVDSCommand(HostName = host1, HostId = e9fb2823-1ce5-409a-8ea3-cde601a45978, VGID=Mnj1Ww-DTOX-N13g-45X3-VR16-iMW9-XHBI08), log id: 503d8d2f
2014-12-05 11:25:16,459 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMGetAllTasksInfoVDSCommand] (org.ovirt.thread.pool-8-thread-39) [1a254a25] FINISH, HSMGetAllTasksInfoVDSCommand, return: [], log id: fb1a87f
2014-12-05 11:25:16,460 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMGetAllTasksInfoVDSCommand] (org.ovirt.thread.pool-8-thread-39) [1a254a25] FINISH, SPMGetAllTasksInfoVDSCommand, return: [], log id: 6d271738
2014-12-05 11:25:16,461 INFO  [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (org.ovirt.thread.pool-8-thread-39) [1a254a25] Discovered no tasks on Storage Pool Default
2014-12-05 11:25:16,570 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetVGInfoVDSCommand] (org.ovirt.thread.pool-8-thread-41) [2e94a248] FINISH, GetVGInfoVDSCommand, return: [LUNs [id=330000000f3254dde, physicalVolumeId=KvmLW3-2k06-kHgt-QOju-HACX-rbSA-1N2TA2, volumeGroupId=BQdHug-TNC8-N6RF-JeGr-omZl-Fn2m-a00lLN, serial=SFreeBSD_iSCSI_Disk_001018a07b9e160, lunMapping=0, vendorId=FreeBSD, productId=iSCSI Disk, _lunConnections=[{ id: null, connection: 192.168.0.130, iqn: iqn.es.uab.nas02:ovirt, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };], deviceSize=390, vendorName=FreeBSD, pathsDictionary={sdb=true}, lunType=ISCSI, status=null, diskId=null, diskAlias=null, storageDomainId=2a299756-37df-49b4-85a6-220800d747bb, storageDomainName=null]], log id: 280ee85e
2014-12-05 11:25:16,570 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetVGInfoVDSCommand] (org.ovirt.thread.pool-8-thread-42) [6b17db3b] FINISH, GetVGInfoVDSCommand, return: [LUNs [id=330000000ea8a41f7, physicalVolumeId=jYdl7M-atDu-vScd-5vQa-IedI-9nFx-FhaTdM, volumeGroupId=Mnj1Ww-DTOX-N13g-45X3-VR16-iMW9-XHBI08, serial=SFreeBSD_iSCSI_Disk_0010189d39e8160, lunMapping=0, vendorId=FreeBSD, productId=iSCSI Disk, _lunConnections=[{ id: null, connection: 192.168.0.129, iqn: iqn.es.uab.nas01:ovirt, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };], deviceSize=390, vendorName=FreeBSD, pathsDictionary={sdc=true}, lunType=ISCSI, status=null, diskId=null, diskAlias=null, storageDomainId=1cff1b75-c4bd-42c2-8589-5d20fb30ee4c, storageDomainName=null]], log id: 503d8d2f
2014-12-05 11:25:32,804 INFO  [org.ovirt.engineextensions.aaa.ldap.Framework] (ajp--127.0.0.1-8702-3) [ovirt-engine-extension-aaa-ldap.authn::siee-local-authn] Creating LDAP pool 'authz'
2014-12-05 11:25:32,925 ERROR [org.ovirt.engine.core.bll.aaa.LoginAdminUserCommand] (ajp--127.0.0.1-8702-3) Error during CanDoActionFailure.: Class: class org.ovirt.engine.core.extensions.mgr.ExtensionInvokeCommandFailedException
Input:
{Extkey[name=AAA_AUTHN_CREDENTIALS;type=class java.lang.String;uuid=AAA_AUTHN_CREDENTIALS[03b96485-4bb5-4592-8167-810a5c909706];]=***, Extkey[name=EXTENSION_INVOKE_CONTEXT;type=class org.ovirt.engine.api.extensions.ExtMap;uuid=EXTENSION_INVOKE_CONTEXT[886d2ebb-312a-49ae-9cc3-e1f849834b7d];]={Extkey[name=EXTENSION_INTERFACE_VERSION_MAX;type=class java.lang.Integer;uuid=EXTENSION_INTERFACE_VERSION_MAX[f4cff49f-2717-4901-8ee9-df362446e3e7];]=0, Extkey[name=EXTENSION_LICENSE;type=class java.lang.String;uuid=EXTENSION_LICENSE[8a61ad65-054c-4e31-9c6d-1ca4d60a4c18];]=ASL 2.0, Extkey[name=EXTENSION_NOTES;type=class java.lang.String;uuid=EXTENSION_NOTES[2da5ad7e-185a-4584-aaff-97f66978e4ea];]=Display name: ovirt-engine-extension-aaa-ldap-0.0.0-0.0.master.20141107092318.git5a0b572.el6, Extkey[name=EXTENSION_HOME_URL;type=class java.lang.String;uuid=EXTENSION_HOME_URL[4ad7a2f4-f969-42d4-b399-72d192e18304];]=http://www.ovirt.org, Extkey[name=EXTENSION_LOCALE;type=class java.lang.String;uuid=EXTENSION_LOCALE[0780b112-0ce0-404a-b85e-8765d778bb29];]=en_US, Extkey[name=EXTENSION_NAME;type=class java.lang.String;uuid=EXTENSION_NAME[651381d3-f54f-4547-bf28-b0b01a103184];]=ovirt-engine-extension-aaa-ldap.authn, Extkey[name=EXTENSION_INTERFACE_VERSION_MIN;type=class java.lang.Integer;uuid=EXTENSION_INTERFACE_VERSION_MIN[2b84fc91-305b-497b-a1d7-d961b9d2ce0b];]=0, Extkey[name=EXTENSION_CONFIGURATION;type=class java.util.Properties;uuid=EXTENSION_CONFIGURATION[2d48ab72-f0a1-4312-b4ae-5068a226b0fc];]=***, Extkey[name=EXTENSION_AUTHOR;type=class java.lang.String;uuid=EXTENSION_AUTHOR[ef242f7a-2dad-4bc5-9aad-e07018b7fbcc];]=The oVirt Project, Extkey[name=EXTENSION_INSTANCE_NAME;type=class java.lang.String;uuid=EXTENSION_INSTANCE_NAME[65c67ff6-aeca-4bd5-a245-8674327f011b];]=siee-local-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=EXTENSION_CONFIGURATION_SENSITIVE_KEYS;type=interface java.util.Collection;uuid=EXTENSION_CONFIGURATION_SENSITIVE_KEYS[a456efa1-73ff-4204-9f9b-ebff01e35263];]=[], Extkey[name=AAA_AUTHN_CAPABILITIES;type=class java.lang.Long;uuid=AAA_AUTHN_CAPABILITIES[9d16bee3-10fd-46f2-83f9-3d3c54cf258d];]=12, Extkey[name=EXTENSION_GLOBAL_CONTEXT;type=class org.ovirt.engine.api.extensions.ExtMap;uuid=EXTENSION_GLOBAL_CONTEXT[9799e72f-7af6-4cf1-bf08-297bc8903676];]=*skip*, Extkey[name=EXTENSION_VERSION;type=class java.lang.String;uuid=EXTENSION_VERSION[fe35f6a8-8239-4bdb-ab1a-af9f779ce68c];]=0.0.0_master, Extkey[name=EXTENSION_MANAGER_TRACE_LOG;type=interface org.slf4j.Logger;uuid=EXTENSION_MANAGER_TRACE_LOG[863db666-3ea7-4751-9695-918a3197ad83];]=org.slf4j.impl.Slf4jLogger(org.ovirt.engine.core.extensions.mgr.ExtensionsManager.trace.ovirt-engine-extension-aaa-ldap.authn.siee-local-authn), 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=AAA_AUTHN_USER;type=class java.lang.String;uuid=AAA_AUTHN_USER[1ceaba26-1bdc-4663-a3c6-5d926f9dd8f0];]=juanjo, 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]}
Output:
{Extkey[name=EXTENSION_INVOKE_RESULT;type=class java.lang.Integer;uuid=EXTENSION_INVOKE_RESULT[0909d91d-8bde-40fb-b6c0-099c772ddd4e];]=2}

	at org.ovirt.engine.core.extensions.mgr.ExtensionProxy.invoke(ExtensionProxy.java:91) [extensions-manager.jar:]
	at org.ovirt.engine.core.extensions.mgr.ExtensionProxy.invoke(ExtensionProxy.java:109) [extensions-manager.jar:]
	at org.ovirt.engine.core.bll.aaa.LoginBaseCommand.authenticate(LoginBaseCommand.java:369) [bll.jar:]
	at org.ovirt.engine.core.bll.aaa.LoginBaseCommand.isUserCanBeAuthenticated(LoginBaseCommand.java:210) [bll.jar:]
	at org.ovirt.engine.core.bll.aaa.LoginAdminUserCommand.canDoAction(LoginAdminUserCommand.java:15) [bll.jar:]
	at org.ovirt.engine.core.bll.CommandBase.internalCanDoAction(CommandBase.java:744) [bll.jar:]
	at org.ovirt.engine.core.bll.CommandBase.executeAction(CommandBase.java:338) [bll.jar:]
	at org.ovirt.engine.core.bll.Backend.login(Backend.java:575) [bll.jar:]
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [rt.jar:1.7.0_51]
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) [rt.jar:1.7.0_51]
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.7.0_51]
	at java.lang.reflect.Method.invoke(Method.java:606) [rt.jar:1.7.0_51]
	at org.jboss.as.ee.component.ManagedReferenceMethodInterceptorFactory$ManagedReferenceMethodInterceptor.processInvocation(ManagedReferenceMethodInterceptorFactory.java:72) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final]
	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
	at org.jboss.invocation.InterceptorContext$Invocation.proceed(InterceptorContext.java:374) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
	at org.jboss.as.weld.ejb.Jsr299BindingsInterceptor.delegateInterception(Jsr299BindingsInterceptor.java:114) [jboss-as-weld-7.1.1.Final.jar:7.1.1.Final]
	at org.jboss.as.weld.ejb.Jsr299BindingsInterceptor.doMethodInterception(Jsr299BindingsInterceptor.java:125) [jboss-as-weld-7.1.1.Final.jar:7.1.1.Final]
	at org.jboss.as.weld.ejb.Jsr299BindingsInterceptor.processInvocation(Jsr299BindingsInterceptor.java:135) [jboss-as-weld-7.1.1.Final.jar:7.1.1.Final]
	at org.jboss.as.ee.component.interceptors.UserInterceptorFactory$1.processInvocation(UserInterceptorFactory.java:36) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final]
	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
	at org.jboss.invocation.InterceptorContext$Invocation.proceed(InterceptorContext.java:374) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
	at org.ovirt.engine.core.bll.interceptors.ThreadLocalSessionCleanerInterceptor.injectWebContextToThreadLocal(ThreadLocalSessionCleanerInterceptor.java:13) [bll.jar:]
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [rt.jar:1.7.0_51]
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) [rt.jar:1.7.0_51]
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.7.0_51]
	at java.lang.reflect.Method.invoke(Method.java:606) [rt.jar:1.7.0_51]
	at org.jboss.as.ee.component.ManagedReferenceLifecycleMethodInterceptorFactory$ManagedReferenceLifecycleMethodInterceptor.processInvocation(ManagedReferenceLifecycleMethodInterceptorFactory.java:123) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final]
	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
	at org.jboss.invocation.WeavedInterceptor.processInvocation(WeavedInterceptor.java:53) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
	at org.jboss.as.ee.component.interceptors.UserInterceptorFactory$1.processInvocation(UserInterceptorFactory.java:36) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final]
	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
	at org.jboss.as.weld.ejb.EjbRequestScopeActivationInterceptor.processInvocation(EjbRequestScopeActivationInterceptor.java:82) [jboss-as-weld-7.1.1.Final.jar:7.1.1.Final]
	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
	at org.jboss.invocation.InitialInterceptor.processInvocation(InitialInterceptor.java:21) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
	at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
	at org.jboss.as.ee.component.interceptors.ComponentDispatcherInterceptor.processInvocation(ComponentDispatcherInterceptor.java:53) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final]
	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
	at org.jboss.as.ejb3.component.singleton.SingletonComponentInstanceAssociationInterceptor.processInvocation(SingletonComponentInstanceAssociationInterceptor.java:53) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final]
	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
	at org.jboss.as.ejb3.tx.CMTTxInterceptor.invokeInNoTx(CMTTxInterceptor.java:211) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final]
	at org.jboss.as.ejb3.tx.CMTTxInterceptor.supports(CMTTxInterceptor.java:363) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final]
	at org.jboss.as.ejb3.tx.CMTTxInterceptor.processInvocation(CMTTxInterceptor.java:194) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final]
	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
	at org.jboss.as.ejb3.component.interceptors.CurrentInvocationContextInterceptor.processInvocation(CurrentInvocationContextInterceptor.java:41) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final]
	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
	at org.jboss.as.ejb3.component.interceptors.LoggingInterceptor.processInvocation(LoggingInterceptor.java:59) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final]
	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
	at org.jboss.as.ee.component.NamespaceContextInterceptor.processInvocation(NamespaceContextInterceptor.java:50) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final]
	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
	at org.jboss.as.ee.component.TCCLInterceptor.processInvocation(TCCLInterceptor.java:45) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final]
	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
	at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
	at org.jboss.as.ee.component.ViewService$View.invoke(ViewService.java:165) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final]
	at org.jboss.as.ee.component.ViewDescription$1.processInvocation(ViewDescription.java:173) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final]
	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
	at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
	at org.jboss.as.ee.component.ProxyInvocationHandler.invoke(ProxyInvocationHandler.java:72) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final]
	at org.ovirt.engine.core.common.interfaces.BackendLocal$$$view8.login(Unknown Source) [common.jar:]
	at org.ovirt.engine.ui.frontend.server.gwt.GenericApiGWTServiceImpl.login(GenericApiGWTServiceImpl.java:183)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [rt.jar:1.7.0_51]
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) [rt.jar:1.7.0_51]
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.7.0_51]
	at java.lang.reflect.Method.invoke(Method.java:606) [rt.jar:1.7.0_51]
	at com.google.gwt.rpc.server.RPC.invokeAndStreamResponse(RPC.java:196)
	at com.google.gwt.rpc.server.RpcServlet.processCall(RpcServlet.java:172)
	at com.google.gwt.rpc.server.RpcServlet.processPost(RpcServlet.java:233)
	at com.google.gwt.user.server.rpc.AbstractRemoteServiceServlet.doPost(AbstractRemoteServiceServlet.java:62)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:754) [jboss-servlet-api_3.0_spec-1.0.0.Final.jar:1.0.0.Final]
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:847) [jboss-servlet-api_3.0_spec-1.0.0.Final.jar:1.0.0.Final]
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:329)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:248)
	at org.ovirt.engine.core.utils.servlet.HeaderFilter.doFilter(HeaderFilter.java:94) [utils.jar:]
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:280)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:248)
	at org.ovirt.engine.ui.frontend.server.gwt.GwtCachingFilter.doFilter(GwtCachingFilter.java:132)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:280)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:248)
	at org.ovirt.engine.core.branding.BrandingFilter.doFilter(BrandingFilter.java:72)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:280)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:248)
	at org.ovirt.engine.core.utils.servlet.LocaleFilter.doFilter(LocaleFilter.java:64) [utils.jar:]
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:280)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:248)
	at org.ovirt.engine.core.aaa.filters.SessionMgmtFilter.doFilter(SessionMgmtFilter.java:31) [aaa.jar:]
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:280)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:248)
	at org.ovirt.engine.core.aaa.filters.LoginFilter.doFilter(LoginFilter.java:73) [aaa.jar:]
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:280)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:248)
	at org.ovirt.engine.core.aaa.filters.NegotiationFilter.doFilter(NegotiationFilter.java:131) [aaa.jar:]
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:280)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:248)
	at org.ovirt.engine.core.aaa.filters.BasicAuthenticationFilter.doFilter(BasicAuthenticationFilter.java:75) [aaa.jar:]
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:280)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:248)
	at org.ovirt.engine.core.aaa.filters.SessionValidationFilter.doFilter(SessionValidationFilter.java:63) [aaa.jar:]
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:280)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:248)
	at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:275)
	at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:161)
	at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:489)
	at org.jboss.as.web.security.SecurityContextAssociationValve.invoke(SecurityContextAssociationValve.java:153)
	at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:155)
	at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102)
	at org.jboss.web.rewrite.RewriteValve.invoke(RewriteValve.java:466)
	at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109)
	at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:368)
	at org.apache.coyote.ajp.AjpProcessor.process(AjpProcessor.java:505)
	at org.apache.coyote.ajp.AjpProtocol$AjpConnectionHandler.process(AjpProtocol.java:445)
	at org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:930)
	at java.lang.Thread.run(Thread.java:744) [rt.jar:1.7.0_51]

2014-12-05 11:25:58,242 INFO  [org.ovirt.engineextensions.aaa.ldap.Framework] (ajp--127.0.0.1-8702-4) [ovirt-engine-extension-aaa-ldap.authn::siee-local-authn] Creating LDAP pool 'authz'
2014-12-05 11:25:58,361 ERROR [org.ovirt.engine.core.bll.aaa.LoginAdminUserCommand] (ajp--127.0.0.1-8702-4) Error during CanDoActionFailure.: Class: class org.ovirt.engine.core.extensions.mgr.ExtensionInvokeCommandFailedException
Input:
{Extkey[name=AAA_AUTHN_CREDENTIALS;type=class java.lang.String;uuid=AAA_AUTHN_CREDENTIALS[03b96485-4bb5-4592-8167-810a5c909706];]=***, Extkey[name=EXTENSION_INVOKE_CONTEXT;type=class org.ovirt.engine.api.extensions.ExtMap;uuid=EXTENSION_INVOKE_CONTEXT[886d2ebb-312a-49ae-9cc3-e1f849834b7d];]={Extkey[name=EXTENSION_INTERFACE_VERSION_MAX;type=class java.lang.Integer;uuid=EXTENSION_INTERFACE_VERSION_MAX[f4cff49f-2717-4901-8ee9-df362446e3e7];]=0, Extkey[name=EXTENSION_LICENSE;type=class java.lang.String;uuid=EXTENSION_LICENSE[8a61ad65-054c-4e31-9c6d-1ca4d60a4c18];]=ASL 2.0, Extkey[name=EXTENSION_NOTES;type=class java.lang.String;uuid=EXTENSION_NOTES[2da5ad7e-185a-4584-aaff-97f66978e4ea];]=Display name: ovirt-engine-extension-aaa-ldap-0.0.0-0.0.master.20141107092318.git5a0b572.el6, Extkey[name=EXTENSION_HOME_URL;type=class java.lang.String;uuid=EXTENSION_HOME_URL[4ad7a2f4-f969-42d4-b399-72d192e18304];]=http://www.ovirt.org, Extkey[name=EXTENSION_LOCALE;type=class java.lang.String;uuid=EXTENSION_LOCALE[0780b112-0ce0-404a-b85e-8765d778bb29];]=en_US, Extkey[name=EXTENSION_NAME;type=class java.lang.String;uuid=EXTENSION_NAME[651381d3-f54f-4547-bf28-b0b01a103184];]=ovirt-engine-extension-aaa-ldap.authn, Extkey[name=EXTENSION_INTERFACE_VERSION_MIN;type=class java.lang.Integer;uuid=EXTENSION_INTERFACE_VERSION_MIN[2b84fc91-305b-497b-a1d7-d961b9d2ce0b];]=0, Extkey[name=EXTENSION_CONFIGURATION;type=class java.util.Properties;uuid=EXTENSION_CONFIGURATION[2d48ab72-f0a1-4312-b4ae-5068a226b0fc];]=***, Extkey[name=EXTENSION_AUTHOR;type=class java.lang.String;uuid=EXTENSION_AUTHOR[ef242f7a-2dad-4bc5-9aad-e07018b7fbcc];]=The oVirt Project, Extkey[name=EXTENSION_INSTANCE_NAME;type=class java.lang.String;uuid=EXTENSION_INSTANCE_NAME[65c67ff6-aeca-4bd5-a245-8674327f011b];]=siee-local-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=EXTENSION_CONFIGURATION_SENSITIVE_KEYS;type=interface java.util.Collection;uuid=EXTENSION_CONFIGURATION_SENSITIVE_KEYS[a456efa1-73ff-4204-9f9b-ebff01e35263];]=[], Extkey[name=AAA_AUTHN_CAPABILITIES;type=class java.lang.Long;uuid=AAA_AUTHN_CAPABILITIES[9d16bee3-10fd-46f2-83f9-3d3c54cf258d];]=12, Extkey[name=EXTENSION_GLOBAL_CONTEXT;type=class org.ovirt.engine.api.extensions.ExtMap;uuid=EXTENSION_GLOBAL_CONTEXT[9799e72f-7af6-4cf1-bf08-297bc8903676];]=*skip*, Extkey[name=EXTENSION_VERSION;type=class java.lang.String;uuid=EXTENSION_VERSION[fe35f6a8-8239-4bdb-ab1a-af9f779ce68c];]=0.0.0_master, Extkey[name=EXTENSION_MANAGER_TRACE_LOG;type=interface org.slf4j.Logger;uuid=EXTENSION_MANAGER_TRACE_LOG[863db666-3ea7-4751-9695-918a3197ad83];]=org.slf4j.impl.Slf4jLogger(org.ovirt.engine.core.extensions.mgr.ExtensionsManager.trace.ovirt-engine-extension-aaa-ldap.authn.siee-local-authn), 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=AAA_AUTHN_USER;type=class java.lang.String;uuid=AAA_AUTHN_USER[1ceaba26-1bdc-4663-a3c6-5d926f9dd8f0];]=siee0, 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]}
Output:
{Extkey[name=EXTENSION_INVOKE_RESULT;type=class java.lang.Integer;uuid=EXTENSION_INVOKE_RESULT[0909d91d-8bde-40fb-b6c0-099c772ddd4e];]=2}

	at org.ovirt.engine.core.extensions.mgr.ExtensionProxy.invoke(ExtensionProxy.java:91) [extensions-manager.jar:]
	at org.ovirt.engine.core.extensions.mgr.ExtensionProxy.invoke(ExtensionProxy.java:109) [extensions-manager.jar:]
	at org.ovirt.engine.core.bll.aaa.LoginBaseCommand.authenticate(LoginBaseCommand.java:369) [bll.jar:]
	at org.ovirt.engine.core.bll.aaa.LoginBaseCommand.isUserCanBeAuthenticated(LoginBaseCommand.java:210) [bll.jar:]
	at org.ovirt.engine.core.bll.aaa.LoginAdminUserCommand.canDoAction(LoginAdminUserCommand.java:15) [bll.jar:]
	at org.ovirt.engine.core.bll.CommandBase.internalCanDoAction(CommandBase.java:744) [bll.jar:]
	at org.ovirt.engine.core.bll.CommandBase.executeAction(CommandBase.java:338) [bll.jar:]
	at org.ovirt.engine.core.bll.Backend.login(Backend.java:575) [bll.jar:]
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [rt.jar:1.7.0_51]
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) [rt.jar:1.7.0_51]
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.7.0_51]
	at java.lang.reflect.Method.invoke(Method.java:606) [rt.jar:1.7.0_51]
	at org.jboss.as.ee.component.ManagedReferenceMethodInterceptorFactory$ManagedReferenceMethodInterceptor.processInvocation(ManagedReferenceMethodInterceptorFactory.java:72) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final]
	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
	at org.jboss.invocation.InterceptorContext$Invocation.proceed(InterceptorContext.java:374) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
	at org.jboss.as.weld.ejb.Jsr299BindingsInterceptor.delegateInterception(Jsr299BindingsInterceptor.java:114) [jboss-as-weld-7.1.1.Final.jar:7.1.1.Final]
	at org.jboss.as.weld.ejb.Jsr299BindingsInterceptor.doMethodInterception(Jsr299BindingsInterceptor.java:125) [jboss-as-weld-7.1.1.Final.jar:7.1.1.Final]
	at org.jboss.as.weld.ejb.Jsr299BindingsInterceptor.processInvocation(Jsr299BindingsInterceptor.java:135) [jboss-as-weld-7.1.1.Final.jar:7.1.1.Final]
	at org.jboss.as.ee.component.interceptors.UserInterceptorFactory$1.processInvocation(UserInterceptorFactory.java:36) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final]
	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
	at org.jboss.invocation.InterceptorContext$Invocation.proceed(InterceptorContext.java:374) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
	at org.ovirt.engine.core.bll.interceptors.ThreadLocalSessionCleanerInterceptor.injectWebContextToThreadLocal(ThreadLocalSessionCleanerInterceptor.java:13) [bll.jar:]
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [rt.jar:1.7.0_51]
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) [rt.jar:1.7.0_51]
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.7.0_51]
	at java.lang.reflect.Method.invoke(Method.java:606) [rt.jar:1.7.0_51]
	at org.jboss.as.ee.component.ManagedReferenceLifecycleMethodInterceptorFactory$ManagedReferenceLifecycleMethodInterceptor.processInvocation(ManagedReferenceLifecycleMethodInterceptorFactory.java:123) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final]
	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
	at org.jboss.invocation.WeavedInterceptor.processInvocation(WeavedInterceptor.java:53) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
	at org.jboss.as.ee.component.interceptors.UserInterceptorFactory$1.processInvocation(UserInterceptorFactory.java:36) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final]
	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
	at org.jboss.as.weld.ejb.EjbRequestScopeActivationInterceptor.processInvocation(EjbRequestScopeActivationInterceptor.java:82) [jboss-as-weld-7.1.1.Final.jar:7.1.1.Final]
	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
	at org.jboss.invocation.InitialInterceptor.processInvocation(InitialInterceptor.java:21) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
	at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
	at org.jboss.as.ee.component.interceptors.ComponentDispatcherInterceptor.processInvocation(ComponentDispatcherInterceptor.java:53) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final]
	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
	at org.jboss.as.ejb3.component.singleton.SingletonComponentInstanceAssociationInterceptor.processInvocation(SingletonComponentInstanceAssociationInterceptor.java:53) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final]
	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
	at org.jboss.as.ejb3.tx.CMTTxInterceptor.invokeInNoTx(CMTTxInterceptor.java:211) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final]
	at org.jboss.as.ejb3.tx.CMTTxInterceptor.supports(CMTTxInterceptor.java:363) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final]
	at org.jboss.as.ejb3.tx.CMTTxInterceptor.processInvocation(CMTTxInterceptor.java:194) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final]
	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
	at org.jboss.as.ejb3.component.interceptors.CurrentInvocationContextInterceptor.processInvocation(CurrentInvocationContextInterceptor.java:41) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final]
	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
	at org.jboss.as.ejb3.component.interceptors.LoggingInterceptor.processInvocation(LoggingInterceptor.java:59) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final]
	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
	at org.jboss.as.ee.component.NamespaceContextInterceptor.processInvocation(NamespaceContextInterceptor.java:50) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final]
	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
	at org.jboss.as.ee.component.TCCLInterceptor.processInvocation(TCCLInterceptor.java:45) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final]
	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
	at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
	at org.jboss.as.ee.component.ViewService$View.invoke(ViewService.java:165) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final]
	at org.jboss.as.ee.component.ViewDescription$1.processInvocation(ViewDescription.java:173) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final]
	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
	at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
	at org.jboss.as.ee.component.ProxyInvocationHandler.invoke(ProxyInvocationHandler.java:72) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final]
	at org.ovirt.engine.core.common.interfaces.BackendLocal$$$view8.login(Unknown Source) [common.jar:]
	at org.ovirt.engine.ui.frontend.server.gwt.GenericApiGWTServiceImpl.login(GenericApiGWTServiceImpl.java:183)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [rt.jar:1.7.0_51]
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) [rt.jar:1.7.0_51]
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.7.0_51]
	at java.lang.reflect.Method.invoke(Method.java:606) [rt.jar:1.7.0_51]
	at com.google.gwt.rpc.server.RPC.invokeAndStreamResponse(RPC.java:196)
	at com.google.gwt.rpc.server.RpcServlet.processCall(RpcServlet.java:172)
	at com.google.gwt.rpc.server.RpcServlet.processPost(RpcServlet.java:233)
	at com.google.gwt.user.server.rpc.AbstractRemoteServiceServlet.doPost(AbstractRemoteServiceServlet.java:62)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:754) [jboss-servlet-api_3.0_spec-1.0.0.Final.jar:1.0.0.Final]
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:847) [jboss-servlet-api_3.0_spec-1.0.0.Final.jar:1.0.0.Final]
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:329)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:248)
	at org.ovirt.engine.core.utils.servlet.HeaderFilter.doFilter(HeaderFilter.java:94) [utils.jar:]
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:280)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:248)
	at org.ovirt.engine.ui.frontend.server.gwt.GwtCachingFilter.doFilter(GwtCachingFilter.java:132)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:280)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:248)
	at org.ovirt.engine.core.branding.BrandingFilter.doFilter(BrandingFilter.java:72)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:280)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:248)
	at org.ovirt.engine.core.utils.servlet.LocaleFilter.doFilter(LocaleFilter.java:64) [utils.jar:]
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:280)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:248)
	at org.ovirt.engine.core.aaa.filters.SessionMgmtFilter.doFilter(SessionMgmtFilter.java:31) [aaa.jar:]
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:280)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:248)
	at org.ovirt.engine.core.aaa.filters.LoginFilter.doFilter(LoginFilter.java:73) [aaa.jar:]
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:280)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:248)
	at org.ovirt.engine.core.aaa.filters.NegotiationFilter.doFilter(NegotiationFilter.java:131) [aaa.jar:]
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:280)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:248)
	at org.ovirt.engine.core.aaa.filters.BasicAuthenticationFilter.doFilter(BasicAuthenticationFilter.java:75) [aaa.jar:]
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:280)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:248)
	at org.ovirt.engine.core.aaa.filters.SessionValidationFilter.doFilter(SessionValidationFilter.java:63) [aaa.jar:]
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:280)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:248)
	at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:275)
	at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:161)
	at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:489)
	at org.jboss.as.web.security.SecurityContextAssociationValve.invoke(SecurityContextAssociationValve.java:153)
	at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:155)
	at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102)
	at org.jboss.web.rewrite.RewriteValve.invoke(RewriteValve.java:466)
	at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109)
	at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:368)
	at org.apache.coyote.ajp.AjpProcessor.process(AjpProcessor.java:505)
	at org.apache.coyote.ajp.AjpProtocol$AjpConnectionHandler.process(AjpProtocol.java:445)
	at org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:930)
	at java.lang.Thread.run(Thread.java:744) [rt.jar:1.7.0_51]

_______________________________________________
Users mailing list
Users@ovirt.org
http://lists.ovirt.org/mailman/listinfo/users

Reply via email to