https://progress.opensuse.org/https://progress.opensuse.org/themes/openSUSE/favicon/favicon.ico?15829177842017-03-10T14:26:23ZopenSUSE Project Management ToolopenQA Project - action #17664: Whitespace in auth module config causes webui to fail to starthttps://progress.opensuse.org/issues/17664?journal_id=434882017-03-10T14:26:23Zktsamisktsamis@suse.com
<ul></ul><p>Since the first failure, I can't start the service again, I rebooted and nothing. It's dead. Not just the webui but the scheduler and all. I can't use it at all now. Not even with <strong>auth = OpenID</strong>. The service doesn't start at all. I can't even edit the Category of this issue, since it looks like a concrete bug.</p>
openQA Project - action #17664: Whitespace in auth module config causes webui to fail to starthttps://progress.opensuse.org/issues/17664?journal_id=434902017-03-10T14:45:59Zoholecekoholecek@suse.com
<ul></ul><p>I will need more details - journal log for scheduler and webui. Scheduler doesn't even need auth plugin.<br>
Can you verify your installation is correct? So far I'm unable to reproduce the problem. Btw. this part of code wasn't touched last few months, maybe some conflicting update? Also there seems to be an apparmor issue (seems unrelated, but for a test try to temporarily disable it).</p>
openQA Project - action #17664: Whitespace in auth module config causes webui to fail to starthttps://progress.opensuse.org/issues/17664?journal_id=434922017-03-10T14:54:41Zktsamisktsamis@suse.com
<ul></ul><p>I am using SLE12-SP2, fully updated and openqa fully updated. </p>
<pre><code>e251:/var/log # journalctl -u openqa-scheduler.service
-- Logs begin at Fri 2017-03-10 15:14:07 CET, end at Fri 2017-03-10 15:48:25 CET. --
Mar 10 15:14:08 linux-x5uj systemd[1]: Starting The openQA Scheduler...
Mar 10 15:14:09 linux-x5uj openqa-scheduler[998]: Archive::Extract will be removed from the Perl core distribution in the next major release. Please install it from CPAN. It is being used at /usr/share/openqa/sc
Mar 10 15:14:09 linux-x5uj systemd[1]: Started The openQA Scheduler.
Mar 10 15:14:09 linux-x5uj openqa-scheduler[998]: [Fri Mar 10 15:14:09 2017] [scheduler:debug] Scheduler started
Mar 10 15:20:11 e251 systemd[1]: Stopping The openQA Scheduler...
Mar 10 15:20:11 e251 systemd[1]: Stopped The openQA Scheduler.
Mar 10 15:20:11 e251 systemd[1]: Starting The openQA Scheduler...
Mar 10 15:20:12 e251 systemd[1]: Started The openQA Scheduler.
Mar 10 15:20:12 e251 openqa-scheduler[2822]: [Fri Mar 10 15:20:12 2017] [scheduler:debug] Scheduler started
Mar 10 15:21:28 e251 systemd[1]: Stopping The openQA Scheduler...
Mar 10 15:21:28 e251 systemd[1]: Stopped The openQA Scheduler.
Mar 10 15:21:28 e251 systemd[1]: Starting The openQA Scheduler...
Mar 10 15:21:29 e251 openqa-scheduler[3008]: Archive::Extract will be removed from the Perl core distribution in the next major release. Please install it from CPAN. It is being used at /usr/share/openqa/script/
Mar 10 15:21:29 e251 systemd[1]: Started The openQA Scheduler.
Mar 10 15:21:29 e251 openqa-scheduler[3008]: [Fri Mar 10 15:21:29 2017] [scheduler:debug] Scheduler started
</code></pre>
<p>The last lines (since the last service restart) of webui log:</p>
<pre><code>journalctl -u openqa-webui.service
Mar 10 15:21:28 e251 systemd[1]: Stopped The openQA web UI.
Mar 10 15:21:30 e251 systemd[1]: Started The openQA web UI.
Mar 10 15:21:31 e251 openqa[3016]: Archive::Extract will be removed from the Perl core distribution in the next major release. Please install it from CPAN. It is being used at /usr/share/openqa/script/../lib/Ope
Mar 10 15:21:31 e251 openqa[3016]: Use of inherited AUTOLOAD for non-method OpenQA::WebAPI::auth_config() is
Mar 10 15:21:31 e251 openqa[3016]: deprecated at /usr/share/openqa/script/../lib/OpenQA/WebAPI.pm line 156 (#1)
Mar 10 15:21:31 e251 openqa[3016]: (D deprecated) As an (ahem) accidental feature, AUTOLOAD
Mar 10 15:21:31 e251 openqa[3016]: subroutines are looked up as methods (using the @ISA hierarchy)
Mar 10 15:21:31 e251 openqa[3016]: even when the subroutines to be autoloaded were called as plain
Mar 10 15:21:31 e251 openqa[3016]: functions (e.g. Foo::bar()), not as methods (e.g. Foo->bar() or
Mar 10 15:21:31 e251 openqa[3016]: $obj->bar()).
Mar 10 15:21:31 e251 openqa[3016]: This bug will be rectified in future by using method lookup only for
Mar 10 15:21:31 e251 openqa[3016]: methods' AUTOLOADs. However, there is a significant base of existing
Mar 10 15:21:31 e251 openqa[3016]: code that may be using the old behavior. So, as an interim step, Perl
Mar 10 15:21:31 e251 openqa[3016]: currently issues an optional warning when non-methods use inherited
Mar 10 15:21:31 e251 openqa[3016]: AUTOLOADs.
Mar 10 15:21:31 e251 openqa[3016]: The simple rule is: Inheritance will not work when autoloading
Mar 10 15:21:31 e251 openqa[3016]: non-methods. The simple fix for old code is: In any module that used
Mar 10 15:21:31 e251 openqa[3016]: to depend on inheriting AUTOLOAD for non-methods from a base class
Mar 10 15:21:31 e251 openqa[3016]: named BaseClass, execute *AUTOLOAD = \&BaseClass::AUTOLOAD during
Mar 10 15:21:31 e251 openqa[3016]: startup.
Mar 10 15:21:31 e251 openqa[3016]: In code that currently says use AutoLoader; @ISA = qw(AutoLoader);
Mar 10 15:21:31 e251 openqa[3016]: you should remove AutoLoader from @ISA and change use AutoLoader; to
Mar 10 15:21:31 e251 openqa[3016]: use AutoLoader 'AUTOLOAD';.
Mar 10 15:21:31 e251 openqa[3016]: Undefined subroutine &OpenQA::WebAPI::auth_config called at
Mar 10 15:21:31 e251 openqa[3016]: /usr/lib/perl5/vendor_perl/5.18.2/Mojo/Server.pm line 17 (#2)
Mar 10 15:21:31 e251 openqa[3016]: (F) The subroutine indicated hasn't been defined, or if it was, it has
Mar 10 15:21:31 e251 openqa[3016]: since been undefined.
Mar 10 15:21:31 e251 openqa[3016]: Uncaught exception from user code:
Mar 10 15:21:31 e251 openqa[3016]: Undefined subroutine &OpenQA::WebAPI::auth_config called at /usr/lib/perl5/vendor_perl/5.18.2/Mojo/Server.pm line 17.
Mar 10 15:21:31 e251 openqa[3016]: Mojolicious::AUTOLOAD('HASH(0x7971eb8)') called at /usr/share/openqa/script/../lib/OpenQA/WebAPI.pm line 156
Mar 10 15:21:31 e251 openqa[3016]: OpenQA::WebAPI::startup('OpenQA::WebAPI=HASH(0x1376988)') called at /usr/lib/perl5/vendor_perl/5.18.2/Mojolicious.pm line 179
Mar 10 15:21:31 e251 openqa[3016]: Mojolicious::new('OpenQA::WebAPI') called at /usr/lib/perl5/vendor_perl/5.18.2/Mojo/Server.pm line 17
Mar 10 15:21:31 e251 openqa[3016]: Mojo::Server::build_app('Mojo::Server=HASH(0x126bf38)', 'OpenQA::WebAPI') called at /usr/lib/perl5/vendor_perl/5.18.2/Mojolicious/Commands.pm line 71
Mar 10 15:21:31 e251 openqa[3016]: Mojolicious::Commands::start_app('Mojolicious::Commands', 'OpenQA::WebAPI') called at /usr/share/openqa/script/../lib/OpenQA/WebAPI.pm line 501
Mar 10 15:21:31 e251 openqa[3016]: OpenQA::WebAPI::run() called at /usr/share/openqa/script/openqa line 35
Mar 10 15:21:31 e251 systemd[1]: openqa-webui.service: Main process exited, code=exited, status=255/n/a
Mar 10 15:21:31 e251 systemd[1]: openqa-webui.service: Unit entered failed state.
Mar 10 15:21:31 e251 systemd[1]: openqa-webui.service: Failed with result 'exit-code'.
Mar 10 15:34:01 e251 systemd[1]: Stopped The openQA web UI.
</code></pre>
<p>I disabled apparmor and tried to restart, same result.</p>
<p>Available packages to be installed:</p>
<pre><code>e251:/var/log # zypper dup
Warning: You are about to do a distribution upgrade with all enabled repositories. Make sure these repositories are compatible before you continue. See 'man zypper' for more information about this command.
Loading repository data...
Reading installed packages...
Computing distribution upgrade...
The following 6 NEW packages are going to be installed:
bdftopcf gstreamer-plugins-cogl libtidyp-1_04-0 perl-HTML-Tidy sles-release-POOL twm
The following 5 packages are going to be upgraded:
libsgutils2-2 sg3_utils xf86-video-qxl xorg-x11-server xorg-x11-server-extra
The following 5 packages are not supported by their vendor:
bdftopcf gstreamer-plugins-cogl libtidyp-1_04-0 perl-HTML-Tidy twm
5 packages to upgrade, 6 new.
Overall download size: 3.9 MiB. Already cached: 0 B. After the operation, additional 696.8 KiB will be used.
Continue? [y/n/? shows all options] (y): n
</code></pre>
<p>Should I update them and try again? <em>zypper patch</em> does not show the perl package. This is a new installation, of both the server and openQA.</p>
openQA Project - action #17664: Whitespace in auth module config causes webui to fail to starthttps://progress.opensuse.org/issues/17664?journal_id=435102017-03-10T15:33:44Zoholecekoholecek@suse.com
<ul><li><strong>Category</strong> changed from <i>130</i> to <i>Regressions/Crashes</i></li></ul><p>To verify if installation is correct you can use <code>rpm -qV openQA</code>. Any suspicious log entries in journal itself? Is the error message same in case of openID authentication? This seems like damaged <code>/usr/share/openqa/lib/OpenQA/WebAPI/Auth/Fake.pm</code>.</p>
openQA Project - action #17664: Whitespace in auth module config causes webui to fail to starthttps://progress.opensuse.org/issues/17664?journal_id=435122017-03-10T15:39:27Zktsamisktsamis@suse.com
<ul></ul><pre><code>e251:/var/log # rpm -qV openQA
.......T. c /etc/apache2/vhosts.d/openqa-common.inc
S.5....T. c /etc/openqa/openqa.ini
</code></pre>
<p>The first file was a try on an option to see if it fixed it and reverting the change. The file is the original.<br>
I don't see anything in journal that would be suspicious or even relevant.<br>
Should I reinstall and see what happens?</p>
openQA Project - action #17664: Whitespace in auth module config causes webui to fail to starthttps://progress.opensuse.org/issues/17664?journal_id=435142017-03-10T15:47:51Zoholecekoholecek@suse.com
<ul></ul><p>Hmm.. just patched out auth_config from my OpenID.pm and got very similar error and interestingly <code>rpm -qV</code> didn't notice a change. Please try to reinstall and/or provide the copy of your <code>/usr/share/openqa/lib/OpenQA/WebAPI/Auth/Fake.pm</code>. You can use paste.opensuse.org for it, for example.</p>
openQA Project - action #17664: Whitespace in auth module config causes webui to fail to starthttps://progress.opensuse.org/issues/17664?journal_id=435442017-03-13T08:14:03Zktsamisktsamis@suse.com
<ul></ul><p><strong>/usr/share/openqa/lib/OpenQA/WebAPI/Auth/Fake.pm:</strong><br>
<a href="http://paste.opensuse.org/49969109" class="external">http://paste.opensuse.org/49969109</a></p>
<p>I will reinstall openQA and let you know the results.</p>
openQA Project - action #17664: Whitespace in auth module config causes webui to fail to starthttps://progress.opensuse.org/issues/17664?journal_id=436342017-03-13T14:16:26Zktsamisktsamis@suse.com
<ul></ul><p>So I reinstalled the openQA and openQA-worker packages and interestingly enough the configuration files were not overwritten. So I still have the old ones (modified from before) and I still get the same errors. What should I try next?<br>
<strong>zypper log and service status</strong>:<br>
<a href="http://paste.opensuse.org/56577025" class="external">http://paste.opensuse.org/56577025</a></p>
openQA Project - action #17664: Whitespace in auth module config causes webui to fail to starthttps://progress.opensuse.org/issues/17664?journal_id=436802017-03-13T19:01:27Zokurzokurz@suse.com
<ul><li><strong>Category</strong> changed from <i>Regressions/Crashes</i> to <i>Support</i></li><li><strong>Status</strong> changed from <i>New</i> to <i>Feedback</i></li><li><strong>Assignee</strong> set to <i>okurz</i></li></ul><p>ktsamis wrote:</p>
<blockquote>
<p>What should I try next?</p>
</blockquote>
<p>I suggest you install openQA on a clean SLES12SP2 and see if it works there. openSUSE Leap 42.2 is a more common setup so there might be something we miss here which is special to your environment.</p>
openQA Project - action #17664: Whitespace in auth module config causes webui to fail to starthttps://progress.opensuse.org/issues/17664?journal_id=437122017-03-14T08:34:54Zktsamisktsamis@suse.com
<ul></ul><p>I tried to install again on SLE12-SP2 and it failed again, with the same error, same setup, everything. I deliberately did not update SLE packages, only the latest openqa. So it doesn't look like a package conflict unless it's something fundamentally wrong. You can reproduce the bug, if you follow these instructions: <a href="https://pes.suse.de/QA_Maintenance/Deployment_of_openQA/" class="external">https://pes.suse.de/QA_Maintenance/Deployment_of_openQA/</a> </p>
<p>The step that it failed, is the login with OpenID is not working (anymore?) so I change <strong>/etc/openqa/openqa.ini</strong> into:</p>
<pre><code>[auth]
method = Fake
</code></pre>
<p>restart openqa and apache2 and I get the above errors. So this seems like a bug.</p>
openQA Project - action #17664: Whitespace in auth module config causes webui to fail to starthttps://progress.opensuse.org/issues/17664?journal_id=437142017-03-14T09:01:29Zktsamisktsamis@suse.com
<ul></ul><p>I will install a new VM with Leap and set up openQA there. If you need access to the old SLE ones, I can give you access to debug what's going on there. If not, let me know to destroy them. btw any idea what's going on with OpenID? I saw action <a class="issue tracker-4 status-6 priority-4 priority-default closed" title="action: error message on production instance from time to time "Not an OpenID message at /usr/share/openq... (Rejected)" href="https://progress.opensuse.org/issues/17652">#17652</a> has been filed but it still does not work for me. I will try with Leap and comment in that action ticket if it still does not work.</p>
openQA Project - action #17664: Whitespace in auth module config causes webui to fail to starthttps://progress.opensuse.org/issues/17664?journal_id=437162017-03-14T09:14:02Zoholecekoholecek@suse.com
<ul></ul><p>I would appreciate access to the machine. I'm curios what's going on there.</p>
openQA Project - action #17664: Whitespace in auth module config causes webui to fail to starthttps://progress.opensuse.org/issues/17664?journal_id=437222017-03-14T09:41:02Zoholecekoholecek@suse.com
<ul><li><strong>Subject</strong> changed from <i>503 with auth = Fake</i> to <i>Whitespace in auth module config causes webui to fail to start</i></li><li><strong>Category</strong> changed from <i>Support</i> to <i>Regressions/Crashes</i></li><li><strong>Status</strong> changed from <i>Feedback</i> to <i>In Progress</i></li><li><strong>Assignee</strong> changed from <i>okurz</i> to <i>oholecek</i></li></ul><p>There is a bug or special behaviour in Config::IniFiles. There was a whitespace character after 'Fake' which was causing this. I'll fix the plugin loading.</p>
openQA Project - action #17664: Whitespace in auth module config causes webui to fail to starthttps://progress.opensuse.org/issues/17664?journal_id=438282017-03-14T13:14:29Zoholecekoholecek@suse.com
<ul><li><strong>% Done</strong> changed from <i>0</i> to <i>90</i></li></ul><p><a href="https://github.com/os-autoinst/openQA/pull/1253" class="external">https://github.com/os-autoinst/openQA/pull/1253</a></p>
openQA Project - action #17664: Whitespace in auth module config causes webui to fail to starthttps://progress.opensuse.org/issues/17664?journal_id=439582017-03-15T13:01:42Zoholecekoholecek@suse.com
<ul><li><strong>Status</strong> changed from <i>In Progress</i> to <i>Resolved</i></li><li><strong>% Done</strong> changed from <i>90</i> to <i>100</i></li></ul> openQA Project - action #17664: Whitespace in auth module config causes webui to fail to starthttps://progress.opensuse.org/issues/17664?journal_id=439602017-03-15T13:02:27Zoholecekoholecek@suse.com
<ul><li><strong>Target version</strong> set to <i>Milestone 6</i></li></ul>