- Users - oVirt List Archives

Re: ovirt-engine-extension-aaa-ldap-setup failed
by Jeremy Tourville 31 Oct '18

31 Oct '18

________________________________ From: Jeremy Tourville <jeremy_tourville(a)hotmail.com> Sent: Thursday, October 25, 2018 7:33 AM To: Ondra Machacek Subject: Re: [ovirt-users] ovirt-engine-extension-aaa-ldap-setup failed I ran the command again after completing the setup wizard as you directed and the results are still the same. Here's the last few lines. I have also attached a complete log file for reference. 2018-10-25 07:09:00,679-05 FINEST VARS-END 2018-10-25 07:09:00,679-05 FINE runSequence Return name='authn' 2018-10-25 07:09:00,680-05 FINE doAuthenticateCredentials Return {Extkey[name=AAA_AUTHN_RESULT;type=class java.lang.Integer;uuid=AAA_AUTHN_RESULT[af9771dc-a0bb-417d-a700-277616aedd85];]=12} 2018-10-25 07:09:00,680-05 FINEST Invoke Output BEGIN 2018-10-25 07:09:00,680-05 FINEST {Extkey[name=EXTENSION_INVOKE_RESULT;type=class java.lang.Integer;uuid=EXTENSION_INVOKE_RESULT[0909d91d-8bde-40fb-b6c0-099c772ddd4e];]=0, Extkey[name=AAA_AUTHN_RESULT;type=class java.lang.Integer;uuid=AAA_AUTHN_RESULT[af9771dc-a0bb-417d-a700-277616aedd85];]=12} 2018-10-25 07:09:00,680-05 FINEST Invoke Output END 2018-10-25 07:09:00,680-05 INFO API: <--Authn.InvokeCommands.AUTHENTICATE_CREDENTIALS profile='ldap.cyber-range.lan' result=CREDENTIALS_INVALID 2018-10-25 07:09:00,684-05 SEVERE Authn.Result code is: CREDENTIALS_INVALID 2018-10-25 07:09:00,684-05 FINE Exception: java.lang.RuntimeException: Authn.Result code is: CREDENTIALS_INVALID at org.ovirt.engine.exttool.aaa.AAAServiceImpl$Action.lambda$static$3(AAAServiceImpl.java:188) at org.ovirt.engine.exttool.aaa.AAAServiceImpl$Action.execute(AAAServiceImpl.java:417) at org.ovirt.engine.exttool.aaa.AAAServiceImpl.run(AAAServiceImpl.java:686) at org.ovirt.engine.exttool.core.ExtensionsToolExecutor.main(ExtensionsToolExecutor.java:120) at org.jboss.modules.Module.run(Module.java:352) at org.jboss.modules.Module.run(Module.java:320) at org.jboss.modules.Main.main(Main.java:593) 2018-10-25 07:09:00,685-05 FINE Exiting with status '1' ________________________________ From: Ondra Machacek <omachace(a)redhat.com> Sent: Thursday, October 25, 2018 2:28 AM To: Jeremy Tourville; users(a)ovirt.org Subject: Re: [ovirt-users] ovirt-engine-extension-aaa-ldap-setup failed On 10/24/18 1:00 AM, Jeremy Tourville wrote: > I am having trouble completing the AAA extension setup. It fails at the end when testing the login flow, if I test the search that part works. > I can confirm that I am able to login to my system using the admin account so there is not a password issue. > > I have listed my results below to include log level=finest. I have also attached a log. Sugeestions on troubleshooting are appreciated. I am not sure where to start. Thanks! > > Please provide credentials to test login flow: > Enter user name: admin > Enter user password: > [ INFO ] Executing login sequence... > Login output: > 2018-10-23 16:43:46,432-05 INFO =================================== ===================================== > 2018-10-23 16:43:46,452-05 INFO ============================ Initia lization ============================ > 2018-10-23 16:43:46,452-05 INFO =================================== ===================================== > 2018-10-23 16:43:46,565-05 INFO Loading extension 'ldap.cyber-range .lan-authn' > 2018-10-23 16:43:46,668-05 INFO Extension 'ldap.cyber-range.lan-aut hn' loaded > 2018-10-23 16:43:46,672-05 INFO Loading extension 'ldap.cyber-range .lan' > 2018-10-23 16:43:46,681-05 INFO Extension 'ldap.cyber-range.lan' lo aded > 2018-10-23 16:43:46,682-05 INFO Initializing extension 'ldap.cyber- range.lan-authn' > 2018-10-23 16:43:46,682-05 INFO [ovirt-engine-extension-aaa-ldap.au thn::ldap.cyber-range.lan-authn] Creating LDAP pool 'authz' > 2018-10-23 16:43:47,236-05 INFO [ovirt-engine-extension-aaa-ldap.au thn::ldap.cyber-range.lan-authn] LDAP pool 'authz' information: vendor='389 Proj ect' version='389-Directory/1.3.7.5 B2018.269.1826' > 2018-10-23 16:43:47,237-05 INFO [ovirt-engine-extension-aaa-ldap.au thn::ldap.cyber-range.lan-authn] Creating LDAP pool 'authn' > 2018-10-23 16:43:47,518-05 INFO [ovirt-engine-extension-aaa-ldap.au thn::ldap.cyber-range.lan-authn] LDAP pool 'authn' information: vendor='389 Proj ect' version='389-Directory/1.3.7.5 B2018.269.1826' > 2018-10-23 16:43:47,518-05 INFO Extension 'ldap.cyber-range.lan-aut hn' initialized > 2018-10-23 16:43:47,519-05 INFO Initializing extension 'ldap.cyber- range.lan' > 2018-10-23 16:43:47,520-05 INFO [ovirt-engine-extension-aaa-ldap.au thz::ldap.cyber-range.lan] Creating LDAP pool 'authz' > 2018-10-23 16:43:47,759-05 INFO [ovirt-engine-extension-aaa-ldap.au thz::ldap.cyber-range.lan] LDAP pool 'authz' information: vendor='389 Project' v ersion='389-Directory/1.3.7.5 B2018.269.1826' > 2018-10-23 16:43:47,760-05 INFO [ovirt-engine-extension-aaa-ldap.au thz::ldap.cyber-range.lan] Available Namespaces: [dc=cyber-range,dc=lan] > 2018-10-23 16:43:47,760-05 INFO Extension 'ldap.cyber-range.lan' in itialized > 2018-10-23 16:43:47,761-05 INFO Start of enabled extensions list > 2018-10-23 16:43:47,761-05 INFO Instance name: 'ldap.cyber-range.la n-authn', Extension name: 'ovirt-engine-extension-aaa-ldap.authn', Version: '1.3 .7', Notes: 'Display name: ovirt-engine-extension-aaa-ldap-1.3.7-1.el7.centos', License: 'ASL 2.0', Home: 'http://www.ovirt.org', Author 'The oVirt Project', Bu ild interface Version: '0', File: '/tmp/tmprnxgRZ/extensions.d/ldap.cyber-range .lan-authn.properties', Initialized: 'true' > 2018-10-23 16:43:47,761-05 INFO Instance name: 'ldap.cyber-range.la n', Extension name: 'ovirt-engine-extension-aaa-ldap.authz', Version: '1.3.7', N otes: 'Display name: ovirt-engine-extension-aaa-ldap-1.3.7-1.el7.centos', Licens e: 'ASL 2.0', Home: 'http://www.ovirt.org', Author 'The oVirt Project', Build in terface Version: '0', File: '/tmp/tmprnxgRZ/extensions.d/ldap.cyber-range.lan.p roperties', Initialized: 'true' > 2018-10-23 16:43:47,761-05 INFO End of enabled extensions list > 2018-10-23 16:43:47,762-05 INFO =================================== ===================================== > 2018-10-23 16:43:47,762-05 INFO ============================== Exec ution =============================== > 2018-10-23 16:43:47,762-05 INFO =================================== ===================================== > 2018-10-23 16:43:47,762-05 INFO Iteration: 0 > 2018-10-23 16:43:47,763-05 INFO Profile='ldap.cyber-range.lan' auth n='ldap.cyber-range.lan-authn' authz='ldap.cyber-range.lan' mapping='null' > 2018-10-23 16:43:47,763-05 INFO API: -->Authn.InvokeCommands.AUTHEN TICATE_CREDENTIALS profile='ldap.cyber-range.lan' user='admin' > 2018-10-23 16:43:47,771-05 INFO API: <--Authn.InvokeCommands.AUTHEN TICATE_CREDENTIALS profile='ldap.cyber-range.lan' result=CREDENTIALS_INVALID > 2018-10-23 16:43:47,775-05 SEVERE Authn.Result code is: CREDENTIALS_I NVALID > [ ERROR ] Login sequence failed > Please investigate details of the failure (search for lines containing SEVERE log level). > Select test sequence to execute (Done, Abort, Login, Search) [Abort]:Login > Please provide credentials to test login flow: > Enter user name: admin > Enter user password: > [ INFO ] Executing login sequence... > Login output: > 2018-10-23 16:44:08,301-05 INFO ======================================================================== > 2018-10-23 16:44:08,320-05 INFO ============================ Initialization ============================ > 2018-10-23 16:44:08,320-05 INFO ======================================================================== > 2018-10-23 16:44:08,346-05 INFO Loading extension 'ldap.cyber-range.lan-authn' > 2018-10-23 16:44:08,397-05 INFO Extension 'ldap.cyber-range.lan-authn' loaded > 2018-10-23 16:44:08,401-05 INFO Loading extension 'ldap.cyber-range.lan' > 2018-10-23 16:44:08,409-05 INFO Extension 'ldap.cyber-range.lan' loaded > 2018-10-23 16:44:08,409-05 INFO Initializing extension 'ldap.cyber-range.lan-authn' > 2018-10-23 16:44:08,410-05 INFO [ovirt-engine-extension-aaa-ldap.authn::ldap.cyber-range.lan-authn] Creating LDAP pool 'authz' > 2018-10-23 16:44:08,914-05 INFO [ovirt-engine-extension-aaa-ldap.authn::ldap.cyber-range.lan-authn] LDAP pool 'authz' information: vendor='389 Project' version='389-Directory/1.3.7.5 B2018.269.1826' > 2018-10-23 16:44:08,915-05 INFO [ovirt-engine-extension-aaa-ldap.authn::ldap.cyber-range.lan-authn] Creating LDAP pool 'authn' > 2018-10-23 16:44:09,192-05 INFO [ovirt-engine-extension-aaa-ldap.authn::ldap.cyber-range.lan-authn] LDAP pool 'authn' information: vendor='389 Project' version='389-Directory/1.3.7.5 B2018.269.1826' > 2018-10-23 16:44:09,193-05 INFO Extension 'ldap.cyber-range.lan-authn' initialized > 2018-10-23 16:44:09,194-05 INFO Initializing extension 'ldap.cyber-range.lan' > 2018-10-23 16:44:09,194-05 INFO [ovirt-engine-extension-aaa-ldap.authz::ldap.cyber-range.lan] Creating LDAP pool 'authz' > 2018-10-23 16:44:09,430-05 INFO [ovirt-engine-extension-aaa-ldap.authz::ldap.cyber-range.lan] LDAP pool 'authz' information: vendor='389 Project' version='389-Directory/1.3.7.5 B2018.269.1826' > 2018-10-23 16:44:09,431-05 INFO [ovirt-engine-extension-aaa-ldap.authz::ldap.cyber-range.lan] Available Namespaces: [dc=cyber-range,dc=lan] > 2018-10-23 16:44:09,432-05 INFO Extension 'ldap.cyber-range.lan' initialized > 2018-10-23 16:44:09,432-05 INFO Start of enabled extensions list > 2018-10-23 16:44:09,432-05 INFO Instance name: 'ldap.cyber-range.lan-authn', Extension name: 'ovirt-engine-extension-aaa-ldap.authn', Version: '1.3.7', Notes: 'Display name: ovirt-engine-extension-aaa-ldap-1.3.7-1.el7.centos', License: 'ASL 2.0', Home: 'http://www.ovirt.org', Author 'The oVirt Project', Build interface Version: '0', File: '/tmp/tmprnxgRZ/extensions.d/ldap.cyber-range.lan-authn.properties', Initialized: 'true' > 2018-10-23 16:44:09,433-05 INFO Instance name: 'ldap.cyber-range.lan', Extension name: 'ovirt-engine-extension-aaa-ldap.authz', Version: '1.3.7', Notes: 'Display name: ovirt-engine-extension-aaa-ldap-1.3.7-1.el7.centos', License: 'ASL 2.0', Home: 'http://www.ovirt.org', Author 'The oVirt Project', Build interface Version: '0', File: '/tmp/tmprnxgRZ/extensions.d/ldap.cyber-range.lan.properties', Initialized: 'true' > 2018-10-23 16:44:09,433-05 INFO End of enabled extensions list > 2018-10-23 16:44:09,433-05 INFO ======================================================================== > 2018-10-23 16:44:09,433-05 INFO ============================== Execution =============================== > 2018-10-23 16:44:09,433-05 INFO ======================================================================== > 2018-10-23 16:44:09,433-05 INFO Iteration: 0 > 2018-10-23 16:44:09,434-05 INFO Profile='ldap.cyber-range.lan' authn='ldap.cyber-range.lan-authn' authz='ldap.cyber-range.lan' mapping='null' > 2018-10-23 16:44:09,434-05 INFO API: -->Authn.InvokeCommands.AUTHENTICATE_CREDENTIALS profile='ldap.cyber-range.lan' user='admin' > 2018-10-23 16:44:09,443-05 INFO API: <--Authn.InvokeCommands.AUTHENTICATE_CREDENTIALS profile='ldap.cyber-range.lan' result=CREDENTIALS_INVALID > 2018-10-23 16:44:09,447-05 SEVERE Authn.Result code is: CREDENTIALS_INVALID > [ ERROR ] Login sequence failed > Please investigate details of the failure (search for lines containing SEVERE log level). > Select test sequence to execute (Done, Abort, Login, Search) [Abort]: Abort > [ ERROR ] Failed to execute stage 'Setup validation': Aborted by user > [ INFO ] Stage: Clean up > Log file is available at /tmp/ovirt-engine-extension-aaa-ldap-setup-20181023164215-uvjiii.log: > [ INFO ] Stage: Pre-termination > [ INFO ] Stage: Termination > > > [root@engine ~]# ovirt-engine-extensions-tool --log-level=FINEST aaa login-user --user-name=admin --profile=ldap.cyber-range.lan In this output you can see it can't find the profile, so this output is unfortunatelly not much helpful. Can you please run the setup and ath the end of setup click on 'Done' instead of 'Abort', and then re-run ^this command? Thank you! > 2018-10-23 17:28:53,690-05 FINE Version: ovirt-engine-4.2.6.4 () > 2018-10-23 17:28:53,720-05 INFO ======================================================================== > 2018-10-23 17:28:53,720-05 INFO ============================ Initialization ============================ > 2018-10-23 17:28:53,721-05 INFO ======================================================================== > 2018-10-23 17:28:53,722-05 FINE Loading extension file 'internal-authz.properties' > 2018-10-23 17:28:53,751-05 INFO Loading extension 'internal-authz' > 2018-10-23 17:28:53,752-05 FINEST Invoke Input BEGIN > 2018-10-23 17:28:53,754-05 FINEST {Extkey[name=EXTENSION_INVOKE_COMMAND;type=class org.ovirt.engine.api.extensions.ExtUUID;uuid=EXTENSION_INVOKE_COMMAND[485778ab-bede-4f1a-b823-77b262a2f28d];]=EXTENSION_LOAD[b0f2460e-7971-4a9c-b4e1-c1db1362a47a], Extkey[name=EXTENSION_INVOKE_CONTEXT;type=class org.ovirt.engine.api.extensions.ExtMap;uuid=EXTENSION_INVOKE_CONTEXT[886d2ebb-312a-49ae-9cc3-e1f849834b7d];]={Extkey[name=EXTENSION_GLOBAL_CONTEXT;type=class org.ovirt.engine.api.extensions.ExtMap;uuid=EXTENSION_GLOBAL_CONTEXT[9799e72f-7af6-4cf1-bf08-297bc8903676];]=*skip*, Extkey[name=EXTENSION_MANAGER_TRACE_LOG;type=interface org.slf4j.Logger;uuid=EXTENSION_MANAGER_TRACE_LOG[863db666-3ea7-4751-9695-918a3197ad83];]=org.slf4j.impl.JDK14LoggerAdapter(org.ovirt.engine.core.extensions.mgr.ExtensionsManager.trace), Extkey[name=EXTENSION_CONFIGURATION_SENSITIVE_KEYS;type=interface java.util.Collection;uuid=EXTENSION_CONFIGURATION_SENSITIVE_KEYS[a456efa1-73ff-4204-9f9b-ebff01e35263];]=[], Extkey[name=EXTENSION_PROVIDES;type=interface java.util.Collection;uuid=EXTENSION_PROVIDES[8cf373a6-65b5-4594-b828-0e275087de91];]=[org.ovirt.engine.api.extensions.aaa.Authz], Extkey[name=EXTENSION_LOCALE;type=class java.lang.String;uuid=EXTENSION_LOCALE[0780b112-0ce0-404a-b85e-8765d778bb29];]=en_US, Extkey[name=EXTENSION_CONFIGURATION_FILE;type=class java.lang.String;uuid=EXTENSION_CONFIGURATION_FILE[4fb0ffd3-983c-4f3f-98ff-9660bd67af6a];]=/etc/ovirt-engine/extensions.d/internal-authz.properties, Extkey[name=EXTENSION_CONFIGURATION;type=class java.util.Properties;uuid=EXTENSION_CONFIGURATION[2d48ab72-f0a1-4312-b4ae-5068a226b0fc];]=***, Extkey[name=EXTENSION_INTERFACE_VERSION_MAX;type=class java.lang.Integer;uuid=EXTENSION_INTERFACE_VERSION_MAX[f4cff49f-2717-4901-8ee9-df362446e3e7];]=0, Extkey[name=EXTENSION_INTERFACE_VERSION_MIN;type=class java.lang.Integer;uuid=EXTENSION_INTERFACE_VERSION_MIN[2b84fc91-305b-497b-a1d7-d961b9d2ce0b];]=0, Extkey[name=EXTENSION_INSTANCE_NAME;type=class java.lang.String;uuid=EXTENSION_INSTANCE_NAME[65c67ff6-aeca-4bd5-a245-8674327f011b];]=internal-authz}} > 2018-10-23 17:28:53,755-05 FINEST Invoke Input END > 2018-10-23 17:28:53,759-05 FINEST Invoke Output BEGIN > 2018-10-23 17:28:53,759-05 FINEST {Extkey[name=AAA_AUTHZ_STATUS;type=class java.lang.Integer;uuid=AAA_AUTHZ_STATUS[566f0ba5-8329-4de1-952a-7a81e4bedd3e];]=0, Extkey[name=EXTENSION_INVOKE_RESULT;type=class java.lang.Integer;uuid=EXTENSION_INVOKE_RESULT[0909d91d-8bde-40fb-b6c0-099c772ddd4e];]=0} > 2018-10-23 17:28:53,759-05 FINEST Invoke Output END > 2018-10-23 17:28:53,760-05 INFO Extension 'internal-authz' loaded > 2018-10-23 17:28:53,760-05 FINE Config BEGIN > 2018-10-23 17:28:53,760-05 FINE ovirt.engine.extension.provides: org.ovirt.engine.api.extensions.aaa.Authz > 2018-10-23 17:28:53,761-05 FINE ovirt.engine.extension.binding.jbossmodule.class: org.ovirt.engine.extension.aaa.jdbc.binding.api.AuthzExtension > 2018-10-23 17:28:53,761-05 FINE ovirt.engine.extension.bindings.method: jbossmodule > 2018-10-23 17:28:53,761-05 FINE config.datasource.file: /etc/ovirt-engine/aaa/internal.properties > 2018-10-23 17:28:53,761-05 FINE ovirt.engine.extension.name: internal-authz > 2018-10-23 17:28:53,762-05 FINE ovirt.engine.extension.binding.jbossmodule.module: org.ovirt.engine.extension.aaa.jdbc > 2018-10-23 17:28:53,762-05 FINE Config END > 2018-10-23 17:28:53,762-05 FINE Loading extension file 'internal-authn.properties' > 2018-10-23 17:28:53,763-05 INFO Loading extension 'internal-authn' > 2018-10-23 17:28:53,763-05 FINEST Invoke Input BEGIN > 2018-10-23 17:28:53,764-05 FINEST {Extkey[name=EXTENSION_INVOKE_COMMAND;type=class org.ovirt.engine.api.extensions.ExtUUID;uuid=EXTENSION_INVOKE_COMMAND[485778ab-bede-4f1a-b823-77b262a2f28d];]=EXTENSION_LOAD[b0f2460e-7971-4a9c-b4e1-c1db1362a47a], Extkey[name=EXTENSION_INVOKE_CONTEXT;type=class org.ovirt.engine.api.extensions.ExtMap;uuid=EXTENSION_INVOKE_CONTEXT[886d2ebb-312a-49ae-9cc3-e1f849834b7d];]={Extkey[name=EXTENSION_GLOBAL_CONTEXT;type=class org.ovirt.engine.api.extensions.ExtMap;uuid=EXTENSION_GLOBAL_CONTEXT[9799e72f-7af6-4cf1-bf08-297bc8903676];]=*skip*, Extkey[name=EXTENSION_MANAGER_TRACE_LOG;type=interface org.slf4j.Logger;uuid=EXTENSION_MANAGER_TRACE_LOG[863db666-3ea7-4751-9695-918a3197ad83];]=org.slf4j.impl.JDK14LoggerAdapter(org.ovirt.engine.core.extensions.mgr.ExtensionsManager.trace), Extkey[name=EXTENSION_CONFIGURATION_SENSITIVE_KEYS;type=interface java.util.Collection;uuid=EXTENSION_CONFIGURATION_SENSITIVE_KEYS[a456efa1-73ff-4204-9f9b-ebff01e35263];]=[], Extkey[name=EXTENSION_PROVIDES;type=interface java.util.Collection;uuid=EXTENSION_PROVIDES[8cf373a6-65b5-4594-b828-0e275087de91];]=[org.ovirt.engine.api.extensions.aaa.Authn], Extkey[name=EXTENSION_LOCALE;type=class java.lang.String;uuid=EXTENSION_LOCALE[0780b112-0ce0-404a-b85e-8765d778bb29];]=en_US, Extkey[name=EXTENSION_CONFIGURATION_FILE;type=class java.lang.String;uuid=EXTENSION_CONFIGURATION_FILE[4fb0ffd3-983c-4f3f-98ff-9660bd67af6a];]=/etc/ovirt-engine/extensions.d/internal-authn.properties, Extkey[name=EXTENSION_CONFIGURATION;type=class java.util.Properties;uuid=EXTENSION_CONFIGURATION[2d48ab72-f0a1-4312-b4ae-5068a226b0fc];]=***, Extkey[name=EXTENSION_INTERFACE_VERSION_MAX;type=class java.lang.Integer;uuid=EXTENSION_INTERFACE_VERSION_MAX[f4cff49f-2717-4901-8ee9-df362446e3e7];]=0, Extkey[name=EXTENSION_INTERFACE_VERSION_MIN;type=class java.lang.Integer;uuid=EXTENSION_INTERFACE_VERSION_MIN[2b84fc91-305b-497b-a1d7-d961b9d2ce0b];]=0, Extkey[name=EXTENSION_INSTANCE_NAME;type=class java.lang.String;uuid=EXTENSION_INSTANCE_NAME[65c67ff6-aeca-4bd5-a245-8674327f011b];]=internal-authn}} > 2018-10-23 17:28:53,765-05 FINEST Invoke Input END > 2018-10-23 17:28:53,766-05 FINEST Invoke Output BEGIN > 2018-10-23 17:28:53,766-05 FINEST {Extkey[name=EXTENSION_INVOKE_RESULT;type=class java.lang.Integer;uuid=EXTENSION_INVOKE_RESULT[0909d91d-8bde-40fb-b6c0-099c772ddd4e];]=0} > 2018-10-23 17:28:53,766-05 FINEST Invoke Output END > 2018-10-23 17:28:53,766-05 INFO Extension 'internal-authn' loaded > 2018-10-23 17:28:53,767-05 FINE Config BEGIN > 2018-10-23 17:28:53,767-05 FINE ovirt.engine.aaa.authn.profile.name: internal > 2018-10-23 17:28:53,767-05 FINE ovirt.engine.extension.provides: org.ovirt.engine.api.extensions.aaa.Authn > 2018-10-23 17:28:53,767-05 FINE ovirt.engine.extension.binding.jbossmodule.class: org.ovirt.engine.extension.aaa.jdbc.binding.api.AuthnExtension > 2018-10-23 17:28:53,767-05 FINE ovirt.engine.aaa.authn.authz.plugin: internal-authz > 2018-10-23 17:28:53,768-05 FINE ovirt.engine.extension.bindings.method: jbossmodule > 2018-10-23 17:28:53,768-05 FINE config.datasource.file: /etc/ovirt-engine/aaa/internal.properties > 2018-10-23 17:28:53,768-05 FINE ovirt.engine.extension.name: internal-authn > 2018-10-23 17:28:53,768-05 FINE ovirt.engine.extension.binding.jbossmodule.module: org.ovirt.engine.extension.aaa.jdbc > 2018-10-23 17:28:53,768-05 FINE Config END > 2018-10-23 17:28:53,769-05 INFO Initializing extension 'internal-authz' > 2018-10-23 17:28:53,769-05 FINEST Invoke Input BEGIN > 2018-10-23 17:28:53,771-05 FINEST {Extkey[name=EXTENSION_INVOKE_COMMAND;type=class org.ovirt.engine.api.extensions.ExtUUID;uuid=EXTENSION_INVOKE_COMMAND[485778ab-bede-4f1a-b823-77b262a2f28d];]=EXTENSION_INITIALIZE[e5ae1b7f-9104-4f23-a444-7b9175ff68d2], Extkey[name=EXTENSION_INVOKE_CONTEXT;type=class org.ovirt.engine.api.extensions.ExtMap;uuid=EXTENSION_INVOKE_CONTEXT[886d2ebb-312a-49ae-9cc3-e1f849834b7d];]={Extkey[name=AAA_AUTHZ_AVAILABLE_NAMESPACES;type=interface java.util.Collection;uuid=AAA_AUTHZ_AVAILABLE_NAMESPACES[6dffa34c-955f-486a-bd35-0a272b45a711];]=[*], Extkey[name=EXTENSION_LICENSE;type=class java.lang.String;uuid=EXTENSION_LICENSE[8a61ad65-054c-4e31-9c6d-1ca4d60a4c18];]=ASL 2.0, Extkey[name=EXTENSION_GLOBAL_CONTEXT;type=class org.ovirt.engine.api.extensions.ExtMap;uuid=EXTENSION_GLOBAL_CONTEXT[9799e72f-7af6-4cf1-bf08-297bc8903676];]=*skip*, Extkey[name=EXTENSION_NAME;type=class java.lang.String;uuid=EXTENSION_NAME[651381d3-f54f-4547-bf28-b0b01a103184];]="ovirt-engine-extension-aaa-jdbc".authz, Extkey[name=EXTENSION_MANAGER_TRACE_LOG;type=interface org.slf4j.Logger;uuid=EXTENSION_MANAGER_TRACE_LOG[863db666-3ea7-4751-9695-918a3197ad83];]=org.slf4j.impl.JDK14LoggerAdapter(org.ovirt.engine.core.extensions.mgr.ExtensionsManager.trace."ovirt-engine-extension-aaa-jdbc".authz.internal-authz), Extkey[name=EXTENSION_CONFIGURATION_SENSITIVE_KEYS;type=interface java.util.Collection;uuid=EXTENSION_CONFIGURATION_SENSITIVE_KEYS[a456efa1-73ff-4204-9f9b-ebff01e35263];]=[], Extkey[name=EXTENSION_VERSION;type=class java.lang.String;uuid=EXTENSION_VERSION[fe35f6a8-8239-4bdb-ab1a-af9f779ce68c];]="1.1.7_master", Extkey[name=EXTENSION_PROVIDES;type=interface java.util.Collection;uuid=EXTENSION_PROVIDES[8cf373a6-65b5-4594-b828-0e275087de91];]=[org.ovirt.engine.api.extensions.aaa.Authz], Extkey[name=EXTENSION_AUTHOR;type=class java.lang.String;uuid=EXTENSION_AUTHOR[ef242f7a-2dad-4bc5-9aad-e07018b7fbcc];]=The oVirt Project, Extkey[name=EXTENSION_LOCALE;type=class java.lang.String;uuid=EXTENSION_LOCALE[0780b112-0ce0-404a-b85e-8765d778bb29];]=en_US, Extkey[name=EXTENSION_CONFIGURATION_FILE;type=class java.lang.String;uuid=EXTENSION_CONFIGURATION_FILE[4fb0ffd3-983c-4f3f-98ff-9660bd67af6a];]=/etc/ovirt-engine/extensions.d/internal-authz.properties, Extkey[name=EXTENSION_HOME_URL;type=class java.lang.String;uuid=EXTENSION_HOME_URL[4ad7a2f4-f969-42d4-b399-72d192e183…, Extkey[name=EXTENSION_CONFIGURATION;type=class java.util.Properties;uuid=EXTENSION_CONFIGURATION[2d48ab72-f0a1-4312-b4ae-5068a226b0fc];]=***, Extkey[name=EXTENSION_INTERFACE_VERSION_MAX;type=class java.lang.Integer;uuid=EXTENSION_INTERFACE_VERSION_MAX[f4cff49f-2717-4901-8ee9-df362446e3e7];]=0, Extkey[name=AAA_AUTHZ_QUERY_MAX_FILTER_SIZE;type=class java.lang.Integer;uuid=AAA_AUTHZ_QUERY_MAX_FILTER_SIZE[2eb1f541-0f65-44a1-a6e3-014e247595f5];]=100, Extkey[name=EXTENSION_INTERFACE_VERSION_MIN;type=class java.lang.Integer;uuid=EXTENSION_INTERFACE_VERSION_MIN[2b84fc91-305b-497b-a1d7-d961b9d2ce0b];]=0, Extkey[name=EXTENSION_INSTANCE_NAME;type=class java.lang.String;uuid=EXTENSION_INSTANCE_NAME[65c67ff6-aeca-4bd5-a245-8674327f011b];]=internal-authz, Extkey[name=AAA_AUTHZ_CAPABILITIES;type=class java.lang.Long;uuid=AAA_AUTHZ_CAPABILITIES[6106d1fb-9291-4351-a947-b897b9540a23];]=1, Extkey[name=EXTENSION_BUILD_INTERFACE_VERSION;type=class java.lang.Integer;uuid=EXTENSION_BUILD_INTERFACE_VERSION[cb479e5a-4b23-46f8-aed3-56a4747a8ab7];]=0, Extkey[name=EXTENSION_NOTES;type=class java.lang.String;uuid=EXTENSION_NOTES[2da5ad7e-185a-4584-aaff-97f66978e4ea];]=Display name: "ovirt-engine-extension-aaa-jdbc"}} > 2018-10-23 17:28:53,771-05 FINEST Invoke Input END > 2018-10-23 17:28:53,832-05 FINEST cursor for: SELECT COUNT(script) AS count FROM schema_version WHERE script = $JcvsJ$upgrade/01_00_0060_missing_indexes.sql$JcvsJ$ > 2018-10-23 17:28:53,846-05 FINEST Invoke Output BEGIN > 2018-10-23 17:28:53,846-05 FINEST {Extkey[name=AAA_AUTHZ_STATUS;type=class java.lang.Integer;uuid=AAA_AUTHZ_STATUS[566f0ba5-8329-4de1-952a-7a81e4bedd3e];]=0, Extkey[name=EXTENSION_INVOKE_RESULT;type=class java.lang.Integer;uuid=EXTENSION_INVOKE_RESULT[0909d91d-8bde-40fb-b6c0-099c772ddd4e];]=0} > 2018-10-23 17:28:53,846-05 FINEST Invoke Output END > 2018-10-23 17:28:53,847-05 INFO Extension 'internal-authz' initialized > 2018-10-23 17:28:53,847-05 FINE Extension 'internal-authz' initialized > 2018-10-23 17:28:53,847-05 INFO Initializing extension 'internal-authn' > 2018-10-23 17:28:53,848-05 FINEST Invoke Input BEGIN > 2018-10-23 17:28:53,849-05 FINEST {Extkey[name=EXTENSION_INVOKE_COMMAND;type=class org.ovirt.engine.api.extensions.ExtUUID;uuid=EXTENSION_INVOKE_COMMAND[485778ab-bede-4f1a-b823-77b262a2f28d];]=EXTENSION_INITIALIZE[e5ae1b7f-9104-4f23-a444-7b9175ff68d2], Extkey[name=EXTENSION_INVOKE_CONTEXT;type=class org.ovirt.engine.api.extensions.ExtMap;uuid=EXTENSION_INVOKE_CONTEXT[886d2ebb-312a-49ae-9cc3-e1f849834b7d];]={Extkey[name=EXTENSION_LICENSE;type=class java.lang.String;uuid=EXTENSION_LICENSE[8a61ad65-054c-4e31-9c6d-1ca4d60a4c18];]=ASL 2.0, Extkey[name=EXTENSION_GLOBAL_CONTEXT;type=class org.ovirt.engine.api.extensions.ExtMap;uuid=EXTENSION_GLOBAL_CONTEXT[9799e72f-7af6-4cf1-bf08-297bc8903676];]=*skip*, Extkey[name=EXTENSION_NAME;type=class java.lang.String;uuid=EXTENSION_NAME[651381d3-f54f-4547-bf28-b0b01a103184];]="ovirt-engine-extension-aaa-jdbc".authn, Extkey[name=EXTENSION_MANAGER_TRACE_LOG;type=interface org.slf4j.Logger;uuid=EXTENSION_MANAGER_TRACE_LOG[863db666-3ea7-4751-9695-918a3197ad83];]=org.slf4j.impl.JDK14LoggerAdapter(org.ovirt.engine.core.extensions.mgr.ExtensionsManager.trace."ovirt-engine-extension-aaa-jdbc".authn.internal-authn), Extkey[name=EXTENSION_CONFIGURATION_SENSITIVE_KEYS;type=interface java.util.Collection;uuid=EXTENSION_CONFIGURATION_SENSITIVE_KEYS[a456efa1-73ff-4204-9f9b-ebff01e35263];]=[], Extkey[name=EXTENSION_VERSION;type=class java.lang.String;uuid=EXTENSION_VERSION[fe35f6a8-8239-4bdb-ab1a-af9f779ce68c];]="1.1.7_master", Extkey[name=EXTENSION_PROVIDES;type=interface java.util.Collection;uuid=EXTENSION_PROVIDES[8cf373a6-65b5-4594-b828-0e275087de91];]=[org.ovirt.engine.api.extensions.aaa.Authn], Extkey[name=EXTENSION_AUTHOR;type=class java.lang.String;uuid=EXTENSION_AUTHOR[ef242f7a-2dad-4bc5-9aad-e07018b7fbcc];]=The oVirt Project, Extkey[name=EXTENSION_LOCALE;type=class java.lang.String;uuid=EXTENSION_LOCALE[0780b112-0ce0-404a-b85e-8765d778bb29];]=en_US, Extkey[name=EXTENSION_CONFIGURATION_FILE;type=class java.lang.String;uuid=EXTENSION_CONFIGURATION_FILE[4fb0ffd3-983c-4f3f-98ff-9660bd67af6a];]=/etc/ovirt-engine/extensions.d/internal-authn.properties, Extkey[name=EXTENSION_HOME_URL;type=class java.lang.String;uuid=EXTENSION_HOME_URL[4ad7a2f4-f969-42d4-b399-72d192e183…, Extkey[name=EXTENSION_CONFIGURATION;type=class java.util.Properties;uuid=EXTENSION_CONFIGURATION[2d48ab72-f0a1-4312-b4ae-5068a226b0fc];]=***, Extkey[name=EXTENSION_INTERFACE_VERSION_MAX;type=class java.lang.Integer;uuid=EXTENSION_INTERFACE_VERSION_MAX[f4cff49f-2717-4901-8ee9-df362446e3e7];]=0, Extkey[name=EXTENSION_INTERFACE_VERSION_MIN;type=class java.lang.Integer;uuid=EXTENSION_INTERFACE_VERSION_MIN[2b84fc91-305b-497b-a1d7-d961b9d2ce0b];]=0, Extkey[name=EXTENSION_INSTANCE_NAME;type=class java.lang.String;uuid=EXTENSION_INSTANCE_NAME[65c67ff6-aeca-4bd5-a245-8674327f011b];]=internal-authn, Extkey[name=EXTENSION_BUILD_INTERFACE_VERSION;type=class java.lang.Integer;uuid=EXTENSION_BUILD_INTERFACE_VERSION[cb479e5a-4b23-46f8-aed3-56a4747a8ab7];]=0, Extkey[name=AAA_AUTHN_CAPABILITIES;type=class java.lang.Long;uuid=AAA_AUTHN_CAPABILITIES[9d16bee3-10fd-46f2-83f9-3d3c54cf258d];]=44, Extkey[name=EXTENSION_NOTES;type=class java.lang.String;uuid=EXTENSION_NOTES[2da5ad7e-185a-4584-aaff-97f66978e4ea];]=Display name: "ovirt-engine-extension-aaa-jdbc"}} > 2018-10-23 17:28:53,849-05 FINEST Invoke Input END > 2018-10-23 17:28:53,856-05 FINEST cursor for: SELECT COUNT(script) AS count FROM schema_version WHERE script = $cnCUr$upgrade/01_00_0060_missing_indexes.sql$cnCUr$ > 2018-10-23 17:28:53,859-05 FINEST Invoke Output BEGIN > 2018-10-23 17:28:53,859-05 FINEST {Extkey[name=EXTENSION_INVOKE_RESULT;type=class java.lang.Integer;uuid=EXTENSION_INVOKE_RESULT[0909d91d-8bde-40fb-b6c0-099c772ddd4e];]=0} > 2018-10-23 17:28:53,859-05 FINEST Invoke Output END > 2018-10-23 17:28:53,859-05 INFO Extension 'internal-authn' initialized > 2018-10-23 17:28:53,860-05 FINE Extension 'internal-authn' initialized > 2018-10-23 17:28:53,860-05 INFO Start of enabled extensions list > 2018-10-23 17:28:53,860-05 INFO Instance name: 'internal-authn', Extension name: '"ovirt-engine-extension-aaa-jdbc".authn', Version: '"1.1.7_master"', Notes: 'Display name: "ovirt-engine-extension-aaa-jdbc"', License: 'ASL 2.0', Home: 'http://www.ovirt.org', Author 'The oVirt Project', Build interface Version: '0', File: '/etc/ovirt-engine/extensions.d/internal-authn.properties', Initialized: 'true' > 2018-10-23 17:28:53,860-05 INFO Instance name: 'internal-authz', Extension name: '"ovirt-engine-extension-aaa-jdbc".authz', Version: '"1.1.7_master"', Notes: 'Display name: "ovirt-engine-extension-aaa-jdbc"', License: 'ASL 2.0', Home: 'http://www.ovirt.org', Author 'The oVirt Project', Build interface Version: '0', File: '/etc/ovirt-engine/extensions.d/internal-authz.properties', Initialized: 'true' > 2018-10-23 17:28:53,861-05 INFO End of enabled extensions list > 2018-10-23 17:28:53,861-05 INFO ======================================================================== > 2018-10-23 17:28:53,861-05 INFO ============================== Execution =============================== > 2018-10-23 17:28:53,861-05 INFO ======================================================================== > 2018-10-23 17:28:53,861-05 INFO Iteration: 0 > 2018-10-23 17:28:53,863-05 SEVERE Profile 'ldap.cyber-range.lan' not found > 2018-10-23 17:28:53,863-05 FINE Exception: > java.lang.IllegalArgumentException: Profile 'ldap.cyber-range.lan' not found > at org.ovirt.engine.exttool.aaa.AAAServiceImpl.getExtensionByProfile(AAAServiceImpl.java:549) > at org.ovirt.engine.exttool.aaa.AAAServiceImpl.access$1200(AAAServiceImpl.java:37) > at org.ovirt.engine.exttool.aaa.AAAServiceImpl$AAAProfile.<init>(AAAServiceImpl.java:838) > at org.ovirt.engine.exttool.aaa.AAAServiceImpl$Action.lambda$static$3(AAAServiceImpl.java:154) > at org.ovirt.engine.exttool.aaa.AAAServiceImpl$Action.execute(AAAServiceImpl.java:417) > at org.ovirt.engine.exttool.aaa.AAAServiceImpl.run(AAAServiceImpl.java:686) > at org.ovirt.engine.exttool.core.ExtensionsToolExecutor.main(ExtensionsToolExecutor.java:120) > at org.jboss.modules.Module.run(Module.java:352) > at org.jboss.modules.Module.run(Module.java:320) > at org.jboss.modules.Main.main(Main.java:593) > > 2018-10-23 17:28:53,864-05 FINE Exiting with status '1' > > > > _______________________________________________ > Users mailing list -- users(a)ovirt.org > To unsubscribe send an email to users-leave(a)ovirt.org > Privacy Statement: https://www.ovirt.org/site/privacy-policy/ > oVirt Code of Conduct: https://www.ovirt.org/community/about/community-guidelines/ > List Archives: https://lists.ovirt.org/archives/list/users@ovirt.org/message/TGT7ASCWSUTU6… >

1 0

Force VM delete API
by Hari Prasanth Loganathan 31 Oct '18

31 Oct '18

Hi Team, I have a VM which is moved to Image locked state in ovirt, I am not able to remove this VM from ovirt, So Is there a way to perform force delete of VM using ovirt API? Thanks, Hari -- DISCLAIMER - *MSysTechnologies LLC* This email message, contents and its attachments may contain confidential, proprietary or legally privileged information and is intended solely for the use of the individual or entity to whom it is actually intended. If you have erroneously received this message, please permanently delete it immediately and notify the sender. If you are not the intended recipient of the email message,you are notified strictly not to disseminate,distribute or copy this e-mail.E-mail transmission cannot be guaranteed to be secure or error-free as Information could be intercepted, corrupted, lost, destroyed, incomplete or contain viruses and MSysTechnologies LLC accepts no liability for the contents and integrity of this mail or for any damage caused by the limitations of the e-mail transmission.

4 7

Automated VM rollout
by Markus Schaufler 31 Oct '18

31 Oct '18

Hi! We are developing a tool for automated vm rollouts and struggle with the disk ordering which seems to be completly random? ie. when creating a vm with 3 disks the order on the virtio scsi bus is different each time - which of course causes troubles when executing the next steps. Thanks for any hints on this!

4 5

VM paused then killed with "device vda reported I/O error"
by fsoyer 31 Oct '18

31 Oct '18

Hi, related (or maybe not) with my problem "VMs unexpectidly restarted", I have one VM (only one) which was paused then killed this morning (1:01AM). This is the second time (first time about 15 days ago), only this one (it is on a domain with 5 others VMs, and it is not the most used of them. And it was at night, without any particular treatment at this time). The others VMs on the same storage were not impacted at all. And it is not on the same storage domain as the other VM of "VMs unexpectidly restarted"... At the same time, gluster seems to have seen absolutly nothing. Is there really a storage issue ?? Here are some revelant logs /var/log/messages of the node vdsm.log engine.log glusterd.log data01-brick.log For recall, this is a 3 nodes 4.2.3 cluster, on Gluster 3.12.13 (2+arbiter). Any idea where or what I must search for ? Thanks -- Cordialement, Frank

2 3

SR-IOV / No free VF (pci=assign-busses issue ?)
by jeanbaptiste＠nfrance.com 31 Oct '18

31 Oct '18

Hello, I'm facing an issue since days regarding SR-IOV. On a specific configuration, I'm unable to start VM with a VF. Tyan S5510 + Intel E3 1220 (vt-x and vt-d enabled). I've enable 63 VF (intel X520-DA2). Engine interface show me : Enabled virtual function: 63 Free Virtual function: 63 I've create a Network + vNIC profile (Passthrough enabled) I have the same issue if I force mapping "Allowed Networks / All networks / Specific networks" or not (on host setup network) When I try to start VM Engine error message is: Failed to run VM test due to a failed validation: [Cannot run VM. There is no host that satisfies current scheduling constraints. See below for details:, The host sriov-hp1.admin-lan.nfrance.com did not satisfy internal filter Network because there are no free virtual functions which are suitable for virtual nic(s) nic1. A virtual function is considered as suitable if the VF's configuration of its physical function contains the virtual nic's network/network label.] (User: admin@internal-authz). Problem seem not be related to host. VF are visible into ip l on my host. Unfortunately, I have no representative error log on side host or engine log (expect error above on engine log) For information, kernel parameters on host are: intel_iommu=on pci=assign-busses. pci=assign-busses is mandatory on this motherboard otherwise VF cannot be created. Do you have idea ?

2 2

Engine restore failing
by Maton, Brett 31 Oct '18

31 Oct '18

oVirt: 4.2.6.2-1 I'm Moving hosted engine from one storage domain to another by backing up and restoring the engine. New VM provisioned in new storage domain, I get as far as trying to restore the backup: but am getting this DbJustRestored Error: engine-backup --mode=restore --file=engine.backup --log=engine-restore.log --provision-db --provision-dwh-db --restore-permissions Preparing to restore: - Unpacking file 'engine.backup' Restoring: - Files Provisioning PostgreSQL users/databases: - user 'engine', database 'engine' - user 'ovirt_engine_history', database 'ovirt_engine_history' Restoring: - Engine database 'engine' - Cleaning up temporary tables in engine database 'engine' FATAL: Failed checking DbJustRestored restore log attached, any suggestions ?

2 9

Re: Storage domain mount error: Lustre file system(Posix compliant FS)
by 김형근 31 Oct '18

31 Oct '18

Um... Does the gluster included in version 4.2.7 improve small file I/O performance? If that 's the case, I will test you as you say. Finally, I want to ask one more time. How can I use lustre? 보내는사람 : Yaniv Kaul <ykaul(a)redhat.com> 받는사람 : '김형근'<okok102928(a)fusiondata.co.kr> 참조 : Ovirt Users <users(a)ovirt.org> 보낸 날짜 : 2018-10-29 16:57:57 제목 : Re: [ovirt-users] Storage domain mount error: Lustre file system(Posix compliant FS) On Thu, Oct 25, 2018 at 7:25 AM <okok102928(a)fusiondata.co.kr> wrote: Hi. I am an ovirt user in Korea. I am working on VDI. It's a pleasure to meet you, the ovirt specialists. (I do not speak English well... Thank you for your understanding!) I am testing Lustre File System in Ovirt or RH(E)V environment. (The reason is simple: glusterfs and nfs have limit of performance, SAN Storage and excellent Software Defined Storage are quite expensive.) Please re-test Gluster with latest 4.2.7, where we have improved performance nicely specifically for Gluster based storage. Testing for file system performance was successful. As expected, luster showed amazing performance. However, there was an error adding luster storage to the storage domain as Posix compliant FS. Domain Function : Data Storage Type : POSIX compliant FS Host to Use : [SPM_HOSTNAME] Name : [STORAGE_DOMAIN_NAME] Path : 10.10.10.15@tcp:/lustre/vmstore VFS Type : lustre Mount Options : The vdsm debug logs are shown below. 2018-10-25 12:46:58,963+0900 INFO  (jsonrpc/2) [storage.xlease] Formatting index for lockspace u'c0ef7ee6-1da9-4eef-9e03-387cd3a24445' (version=1) (xlease:653) 2018-10-25 12:46:58,971+0900 DEBUG (jsonrpc/2) [root] /usr/bin/dd iflag=fullblock of=/rhev/data-center/mnt/10.10.10.15@tcp:_lustre_vmstore/c0ef7ee6-1da9-4eef-9e03-387cd3a24445/dom_md/xleases oflag=direct,seek_bytes seek=1048576 bs=256512 count=1 conv=notrunc,nocreat,fsync (cwd None) (commands:65) 2018-10-25 12:46:58,985+0900 DEBUG (jsonrpc/2) [root] FAILED: <err> = "/usr/bin/dd: error writing '/rhev/data-center/mnt/10.10.10.15@tcp:_lustre_vmstore/c0ef7ee6-1da9-4eef-9e03-387cd3a24445/dom_md/xleases': Invalid argument\n1+0 records in\n0+0 records out\n0 bytes (0 B) copied, 0.000943896 s, 0.0 kB/s\n"; <rc> = 1 (commands:86) 2018-10-25 12:46:58,985+0900 INFO  (jsonrpc/2) [vdsm.api] FINISH createStorageDomain error=Command ['/usr/bin/dd', 'iflag=fullblock', u'of=/rhev/data-center/mnt/10.10.10.15@tcp:_lustre_vmstore/c0ef7ee6-1da9-4eef-9e03-387cd3a24445/dom_md/xleases', 'oflag=direct,seek_bytes', 'seek=1048576', 'bs=256512', 'count=1', 'conv=notrunc,nocreat,fsync'] failed with rc=1 out='[suppressed]' err="/usr/bin/dd: error writing '/rhev/data-center/mnt/10.10.10.15@tcp:_lustre_vmstore/c0ef7ee6-1da9-4eef-9e03-387cd3a24445/dom_md/xleases': Invalid argument\n1+0 records in\n0+0 records out\n0 bytes (0 B) copied, 0.000943896 s, 0.0 kB/s\n" from=::ffff:192.168.161.104,52188, flow_id=794bd395, task_id=c9847bf3-2267-483b-9099-f05a46981f7f (api:50) 2018-10-25 12:46:58,985+0900 ERROR (jsonrpc/2) [storage.TaskManager.Task] (Task='c9847bf3-2267-483b-9099-f05a46981f7f') Unexpected error (task:875) Traceback (most recent call last):  File "/usr/lib/python2.7/site-packages/vdsm/storage/task.py", line 882, in _run    return fn(*args, **kargs)  File "<string>", line 2, in createStorageDomain   File "/usr/lib/python2.7/site-packages/vdsm/common/api.py", line 48, in method     ret = func(*args, **kwargs)   File "/usr/lib/python2.7/site-packages/vdsm/storage/hsm.py", line 2591, in createStorageDomain     storageType, domVersion)   File "/usr/lib/python2.7/site-packages/vdsm/storage/nfsSD.py", line 87, in create     remotePath, storageType, version)   File "/usr/lib/python2.7/site-packages/vdsm/storage/fileSD.py", line 465, in _prepareMetadata     cls.format_external_leases(sdUUID, xleases_path)   File "/usr/lib/python2.7/site-packages/vdsm/storage/sd.py", line 1200, in format_external_leases     xlease.format_index(lockspace, backend)   File "/usr/lib/python2.7/site-packages/vdsm/storage/xlease.py", line 661, in format_index     index.dump(file)   File "/usr/lib/python2.7/site-packages/vdsm/storage/xlease.py", line 761, in dump     file.pwrite(INDEX_BASE, self._buf)   File "/usr/lib/python2.7/site-packages/vdsm/storage/xlease.py", line 994, in pwrite     self._run(args, data=buf[:])   File "/usr/lib/python2.7/site-packages/vdsm/storage/xlease.py", line 1011, in _run     raise cmdutils.Error(args, rc, "[suppressed]", err) Error: Command ['/usr/bin/dd', 'iflag=fullblock', u'of=/rhev/data-center/mnt/10.10.10.15@tcp:_lustre_vmstore/c0ef7ee6-1da9-4eef-9e03-387cd3a24445/dom_md/xleases', 'oflag=direct,seek_bytes', 'seek=1048576', 'bs=256512', 'count=1', 'conv=notrunc,nocreat,fsync'] failed with rc=1 out='[suppressed]' err="/usr/bin/dd: error writing '/rhev/data-center/mnt/10.10.10.15@tcp:_lustre_vmstore/c0ef7ee6-1da9-4eef-9e03-387cd3a24445/dom_md/xleases': Invalid argument\n1+0 records in\n0+0 records out\n0 bytes (0 B) copied, 0.000943896 s, 0.0 kB/s\n" 2018-10-25 12:46:58,986+0900 DEBUG (jsonrpc/2) [storage.TaskManager.Task] (Task='c9847bf3-2267-483b-9099-f05a46981f7f') Task._run: c9847bf3-2267-483b-9099-f05a46981f7f (6, u'c0ef7ee6-1da9-4eef-9e03-387cd3a24445', u'vmstore', u'10.10.10.15@tcp:/lustre/vmstore', 1, u'4') {} failed - stopping task (task:894) 2018-10-25 12:46:58,986+0900 DEBUG (jsonrpc/2) [storage.TaskManager.Task] (Task='c9847bf3-2267-483b-9099-f05a46981f7f') stopping in state failed (force False) (task:1256) 2018-10-25 12:46:58,986+0900 DEBUG (jsonrpc/2) [storage.TaskManager.Task] (Task='c9847bf3-2267-483b-9099-f05a46981f7f') ref 1 aborting True (task:1002) 2018-10-25 12:46:58,986+0900 INFO  (jsonrpc/2) [storage.TaskManager.Task] (Task='c9847bf3-2267-483b-9099-f05a46981f7f') aborting: Task is aborted: u'Command [\'/usr/bin/dd\', \'iflag=fullblock\', u\'of=/rhev/data-center/mnt/10.10.10.15@tcp:_lustre_vmstore/c0ef7ee6-1da9-4eef-9e03-387cd3a24445/dom_md/xleases\', \'oflag=direct,seek_bytes\', \'seek=1048576\', \'bs=256512\', \'count=1\', \'conv=notrunc,nocreat,fsync\'] failed with rc=1 out=\'[suppressed]\' err="/usr/bin/dd: error writing \'/rhev/data-center/mnt/10.10.10.15@tcp:_lustre_vmstore/c0ef7ee6-1da9-4eef-9e03-387cd3a24445/dom_md/xleases\': Invalid argument\\n1+0 records in\\n0+0 records out\\n0 bytes (0 B) copied, 0.000943896 s, 0.0 kB/s\\n"' - code 100 (task:1181) 2018-10-25 12:46:58,986+0900 DEBUG (jsonrpc/2) [storage.TaskManager.Task] (Task='c9847bf3-2267-483b-9099-f05a46981f7f') Prepare: aborted: Command ['/usr/bin/dd', 'iflag=fullblock', u'of=/rhev/data-center/mnt/10.10.10.15@tcp:_lustre_vmstore/c0ef7ee6-1da9-4eef-9e03-387cd3a24445/dom_md/xleases', 'oflag=direct,seek_bytes', 'seek=1048576', 'bs=256512', 'count=1', 'conv=notrunc,nocreat,fsync'] failed with rc=1 out='[suppressed]' err="/usr/bin/dd: error writing '/rhev/data-center/mnt/10.10..10.15@tcp:_lustre_vmstore/c0ef7ee6-1da9-4eef-9e03-387cd3a24445/dom_md/xleases': Invalid argument\n1+0 records in\n0+0 records out\n0 bytes (0 B) copied, 0.000943896 s, 0.0 kB/s\n" (task:1186) 2018-10-25 12:46:58,986+0900 DEBUG (jsonrpc/2) [storage.TaskManager.Task] (Task='c9847bf3-2267-483b-9099-f05a46981f7f') ref 0 aborting True (task:1002) 2018-10-25 12:46:58,986+0900 DEBUG (jsonrpc/2) [storage.TaskManager.Task] (Task='c9847bf3-2267-483b-9099-f05a46981f7f') Task._doAbort: force False (task:937) 2018-10-25 12:46:58,986+0900 DEBUG (jsonrpc/2) [storage.ResourceManager.Owner] Owner.cancelAll requests {} (resourceManager:947) 2018-10-25 12:46:58,986+0900 DEBUG (jsonrpc/2) [storage.TaskManager.Task] (Task='c9847bf3-2267-483b-9099-f05a46981f7f') moving from state failed -> state aborting (task:602) 2018-10-25 12:46:58,986+0900 DEBUG (jsonrpc/2) [storage.TaskManager.Task] (Task='c9847bf3-2267-483b-9099-f05a46981f7f') _aborting: recover policy none (task:557) 2018-10-25 12:46:58,987+0900 DEBUG (jsonrpc/2) [storage.TaskManager.Task] (Task='c9847bf3-2267-483b-9099-f05a46981f7f') moving from state failed -> state failed (task:602) 2018-10-25 12:46:58,987+0900 DEBUG (jsonrpc/2) [storage.ResourceManager.Owner] Owner.releaseAll requests {} resources {} (resourceManager:910) 2018-10-25 12:46:58,987+0900 DEBUG (jsonrpc/2) [storage.ResourceManager.Owner] Owner.cancelAll requests {} (resourceManager:947) 2018-10-25 12:46:58,987+0900 ERROR (jsonrpc/2) [storage.Dispatcher] FINISH createStorageDomain error=Command ['/usr/bin/dd', 'iflag=fullblock', u'of=/rhev/data-center/mnt/10.10.10.15@tcp:_lustre_vmstore/c0ef7ee6-1da9-4eef-9e03-387cd3a24445/dom_md/xleases', 'oflag=direct,seek_bytes', 'seek=1048576', 'bs=256512', 'count=1', 'conv=notrunc,nocreat,fsync'] failed with rc=1 out='[suppressed]' err="/usr/bin/dd: error writing '/rhev/data-center/mnt/10.10.10.15@tcp:_lustre_vmstore/c0ef7ee6-1da9-4eef-9e03-387cd3a24445/dom_md/xleases': Invalid argument\n1+0 records in\n0+0 records out\n0 bytes (0 B) copied, 0.000943896 s, 0.0 kB/s\n" (dispatcher:86) Traceback (most recent call last):   File "/usr/lib/python2.7/site-packages/vdsm/storage/dispatcher.py", line 73, in wrapper     result = ctask.prepare(func, *args, **kwargs)   File "/usr/lib/python2.7/site-packages/vdsm/storage/task.py", line 108, in wrapper     return m(self, *a, **kw)   File "/usr/lib/python2.7/site-packages/vdsm/storage/task.py", line 1189, in prepare     raise self.error Error: Command ['/usr/bin/dd', 'iflag=fullblock', u'of=/rhev/data-center/mnt/10.10.10.15@tcp:_lustre_vmstore/c0ef7ee6-1da9-4eef-9e03-387cd3a24445/dom_md/xleases', 'oflag=direct,seek_bytes', 'seek=1048576', 'bs=256512', 'count=1', 'conv=notrunc,nocreat,fsync'] failed with rc=1 out='[suppressed]' err="/usr/bin/dd: error writing '/rhev/data-center/mnt/10.10.10.15@tcp:_lustre_vmstore/c0ef7ee6-1da9-4eef-9e03-387cd3a24445/dom_md/xleases': Invalid argument\n1+0 records in\n0+0 records out\n0 bytes (0 B) copied, 0.000943896 s, 0.0 kB/s\n" 2018-10-25 12:46:58,987+0900 INFO  (jsonrpc/2) [jsonrpc.JsonRpcServer] RPC call StorageDomain.create failed (error 351) in 0.41 seconds (__init__:573) 2018-10-25 12:46:59,058+0900 DEBUG (jsonrpc/3) [jsonrpc.JsonRpcServer] Calling 'StoragePool.disconnectStorageServer' in bridge with {u'connectionParams': [{u'id': u'316c5f1f-753e-42a1-8e30-4ee6f976906a', u'connection': u'10.10.10.15@tcp:/lustre/vmstore', u'iqn': u'', u'user': u'', u'tpgt': u'1', u'vfs_type': u'lustre', u'password': '********', u'port': u''}], u'storagepoolID': u'00000000-0000-0000-0000-000000000000', u'domainType': 6} (__init__:590) 2018-10-25 12:46:59,058+0900 WARN  (jsonrpc/3) [devel] Provided value "6" not defined in StorageDomainType enum for StoragePool.disconnectStorageServer (vdsmapi:275) 2018-10-25 12:46:59,058+0900 WARN  (jsonrpc/3) [devel] Provided parameters {u'id': u'316c5f1f-753e-42a1-8e30-4ee6f976906a', u'connection': u'10.10.10.15@tcp:/lustre/vmstore', u'iqn': u'', u'user': u'', u'tpgt': u'1', u'vfs_type': u'lustre', u'password': '********', u'port': u''} do not match any of union ConnectionRefParameters values (vdsmapi:275) 2018-10-25 12:46:59,059+0900 DEBUG (jsonrpc/3) [storage.TaskManager.Task] (Task='3c6b249f-a47f-47f1-a647-5893b6f60b7c') moving from state preparing -> state preparing (task:602) 2018-10-25 12:46:59,059+0900 INFO  (jsonrpc/3) [vdsm.api] START disconnectStorageServer(domType=6, spUUID=u'00000000-0000-0000-0000-000000000000', conList=[{u'id': u'316c5f1f-753e-42a1-8e30-4ee6f976906a', u'connection': u'10.10.10.15@tcp:/lustre/vmstore', u'iqn': u'', u'user': u'', u'tpgt': u'1', u'vfs_type': u'lustre', u'password': '********', u'port': u''}], options=None) from=::ffff:192.168.161.104,52188, flow_id=f1bf4bf8-9033-42af-9329-69960638ba0e, task_id=3c6b249f-a47f-47f1-a647-5893b6f60b7c (api:46) 2018-10-25 12:46:59,059+0900 INFO  (jsonrpc/3) [storage.Mount] unmounting /rhev/data-center/mnt/10.10.10.15@tcp:_lustre_vmstore (mount:212) 2018-10-25 12:46:59,094+0900 DEBUG (jsonrpc/3) [storage.Mount] /rhev/data-center/mnt/10.10.10.15@tcp:_lustre_vmstore unmounted: 0.03 seconds (utils:452) 1. If you use the direct flag when using the dd command, luster only works in multiples of 4k (4096). This is it, VDSM does not support native 4K. Y.  Therefore, bs = 256512, which is not a multiple of 4096, causes an error. 2. Ovirt / RH(E)V An error occurs regardless of version. I have tested both in 3.6, 4.1, and 4.2 environments. I searched hard, but I did not have a similar case. I want to ask three questions. 1. Is there a way to fix the problem in Ovirt? (Secure bypass or some configurations) 2. (Extension of Question # 1) Where does block size 256512 come from? Why is it 256512? 3. Is this a problem you need to solve in the Lustre file system? (For example, a setting capable of direct IO in units of 512 bytes) I need help. Thank you for your reply. _______________________________________________ Users mailing list -- users(a)ovirt.org To unsubscribe send an email to users-leave(a)ovirt.org Privacy Statement: https://www.ovirt.org/site/privacy-policy/ oVirt Code of Conduct: https://www.ovirt.org/community/about/community-guidelines/ List Archives: https://lists.ovirt.org/archives/list/users@ovirt.org/message/2XVAJGYIJEZKR…

1 0

I can't connect the USB to a Virtual Machine in the VM Portal
by siovelrm＠gmail.com 30 Oct '18

30 Oct '18

Sorry for my questions, but I discovered Ovirt a short time ago and I liked it a lot, so I would like to use it to the maximum How I can connect the USB to a Virtual Machine in the VM Portal?

2 1

Unable to export vm as ova
by sangeetha.rathinavel＠oracle.com 30 Oct '18

30 Oct '18

When I try to export a vm to ova, get this below error in engine log 2018-10-26 12:11:36,657-04 ERROR [org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback] (EE-ManagedThreadFactory-engineScheduled-Thread-37) [484d3716] EngineException: ENGINE (Failed with error ENGINE and code 5001): org.ovirt.engine.core.common.errors.EngineException: EngineException: ENGINE (Failed with error ENGINE and code 5001) at org.ovirt.engine.core.bll.exportimport.ExportOvaCommand.createOva(ExportOvaCommand.java:301) [bll.jar:] at org.ovirt.engine.core.bll.exportimport.ExportOvaCommand.executeNextOperation(ExportOvaCommand.java:285) [bll.jar:] at org.ovirt.engine.core.bll.exportimport.ExportOvaCommand.performNextOperation(ExportOvaCommand.java:277) [bll.jar:] at org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback.childCommandsExecutionEnded(SerialChildCommandsExecutionCallback.java:32) [bll.jar:] at org.ovirt.engine.core.bll.ChildCommandsCallbackBase.doPolling(ChildCommandsCallbackBase.java:68) [bll.jar:] at org.ovirt.engine.core.bll.tasks.CommandCallbacksPoller.invokeCallbackMethodsImpl(CommandCallbacksPoller.java:146) [bll.jar:] at org.ovirt.engine.core.bll.tasks.CommandCallbacksPoller.invokeCallbackMethods(CommandCallbacksPoller.java:107) [bll.jar:] at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [rt.jar:1.8.0_181] In Ansible log writing disk: path=/rhev/data-center/mnt/ca-ovmstor101:_export_sanrathi_brml11g04-oVirt-nfs-01/aec73d1f-ea3d-4228-a151-a41b20b6b67b/images/e1186fdb-3fc3-46ae-945c-dd19bb6d434c/d95c52e3-35d1-493e-ab2d-42577bdb0391 size=196848 Traceback (most recent call last): File "/root/.ansible/tmp/ansible-tmp-1540570288.02-200413742597669/pack_ova.py", line 96, in <module> write_disks(ova_path, disks_info.split('+')) File "/root/.ansible/tmp/ansible-tmp-1540570288.02-200413742597669/pack_ova.py", line 79, in write_disks write_disk(ova_path, disk_path, disk_size) File "/root/.ansible/tmp/ansible-tmp-1540570288.02-200413742597669/pack_ova.py", line 59, in write_disk fd = os.open(disk_path, os.O_RDONLY | os.O_DIRECT) OSError: [Errno 13] Permission denied: '/rhev/data-center/mnt/ca-ovmstor101:_export_sanrathi_brml11g04-oVirt-nfs-01/aec73d1f-ea3d-4228-a151-a41b20b6b67b/images/e1186fdb-3fc3-46ae-945c-dd19bb6d434c/d95c52e3-35d1-493e-ab2d-42577bdb0391' I have my storage in NFS . Note: Export to Domain works fine. Thanks in advance .

2 4

GetGlusterLocalLogicalVolumeListVDSCommand execution failed: null
by Marco Lorenzo Crociani 30 Oct '18

30 Oct '18

Hi, I'm updating ovirt to 4.2.6.4-1.el7 Engine is updated Compute nodes are updated Storage nodes are not yet updated because ovirt 4.2.6.4-1.el7 depends on gluster 3.12 while now I have gluster 3.10. Compatibility Versions: Compute datacenter 4.2 Storage 4.1 (because I don't have yet updated the storage cluster) so Data Centers is still 4.1 I was about to upgrade the storage nodes when I noticed that /var/log/ovirt-engine/engine.log is flooded with errors like: 2018-10-25 19:36:17,164+02 ERROR [org.ovirt.engine.core.vdsbroker.gluster.GetGlusterLocalLogicalVolumeListVDSCommand] (DefaultQuartzScheduler2) [55e89794] Command 'GetGlusterLocalLogicalVolumeListVDSCommand(HostName = s23, VdsIdVDSCommandParametersBase:{hostId='84a33357-1d04-44db-b0e3-4638ebc39d6c'})' execution failed: null 6 errors (one for each storage server) every 6 seconds. How could I fix them? Can I continue to upgrade the storage nodes? Best regards, -- Marco Crociani

3 2