Project

General

Profile

action #53045

[opensuse][kde][sporadic] krunner suggestions check is racy

Added by StefanBruens over 2 years ago. Updated 3 months ago.

Status:
New
Priority:
Normal
Assignee:
-
Category:
Bugs in existing tests
Target version:
-
Start date:
2019-06-13
Due date:
% Done:

0%

Estimated time:
Difficulty:

Description

Observation

openQA test in scenario opensuse-Staging:F-Staging-DVD-x86_64-kde@64bit succeeds in
ooffice

but
same needle/check essentially fails in
https://openqa.opensuse.org/tests/957874#step/oomath/30

https://openqa.opensuse.org/tests/957874/file/autoinst-log.txt

[2019-06-13T18:23:05.918 CEST] [debug] /var/lib/openqa/cache/openqa1-opensuse/tests/opensuse/tests/x11/ooffice.pm:21 called testapi::x11_start_program
[2019-06-13T18:23:05.918 CEST] [debug] <<< testapi::check_screen(mustmatch='desktop-runner', timeout=30)
[2019-06-13T18:23:06.704 CEST] [debug] >>> testapi::_handle_found_needle: found desktop-runner-kde-20190424, similarity 1.00 @ 254/2
[2019-06-13T18:23:06.704 CEST] [debug] /var/lib/openqa/cache/openqa1-opensuse/tests/opensuse/tests/x11/ooffice.pm:21 called testapi::x11_start_program
[2019-06-13T18:23:06.704 CEST] [debug] <<< testapi::type_string(string='oowriter', max_interval=13, wait_screen_changes=0, wait_still_screen=0, timeout=30, similarity_level=47)
[2019-06-13T18:23:06.704 CEST] [debug] /var/lib/openqa/cache/openqa1-opensuse/tests/opensuse/tests/x11/ooffice.pm:21 called testapi::x11_start_program
[2019-06-13T18:23:06.704 CEST] [debug] <<< testapi::wait_screen_change(timeout=10, similarity_level=50)
[2019-06-13T18:23:09.126 CEST] [debug] waiting for screen change: 0 35.209458787774
[2019-06-13T18:23:09.126 CEST] [debug] >>> testapi::wait_screen_change: screen change seen at 0
[2019-06-13T18:23:09.126 CEST] [debug] /var/lib/openqa/cache/openqa1-opensuse/tests/opensuse/tests/x11/ooffice.pm:21 called testapi::x11_start_program
[2019-06-13T18:23:09.126 CEST] [debug] <<< testapi::check_screen(mustmatch='desktop-runner-plasma-suggestions', timeout=0)
[2019-06-13T18:23:09.390 CEST] [debug] no match: -0.1s, best candidate: desktop-runner-plasma-suggestions-20180423 (0.00)
[2019-06-13T18:23:09.522 CEST] [debug] >>> testapi::_check_backend_response: match=desktop-runner-plasma-suggestions timed out after 0 (check_screen)
[2019-06-13T18:23:09.562 CEST] [debug] /var/lib/openqa/cache/openqa1-opensuse/tests/opensuse/tests/x11/ooffice.pm:21 called testapi::x11_start_program
[2019-06-13T18:23:09.562 CEST] [debug] <<< testapi::send_key(key='esc', do_wait=0)
[2019-06-13T18:23:39.768 CEST] [debug] /var/lib/openqa/cache/openqa1-opensuse/tests/opensuse/tests/x11/ooffice.pm:21 called testapi::x11_start_program
...
[2019-06-13T18:24:10.560 CEST] [debug] /var/lib/openqa/cache/openqa1-opensuse/tests/opensuse/tests/x11/ooffice.pm:21 called testapi::x11_start_program
[2019-06-13T18:24:10.560 CEST] [debug] <<< testapi::send_key(key='alt-f2', do_wait=0)
[2019-06-13T18:24:10.767 CEST] [debug] waiting for screen change: 0 1000000
[2019-06-13T18:24:11.271 CEST] [debug] waiting for screen change: 1 17.1063380248686
[2019-06-13T18:24:11.271 CEST] [debug] >>> testapi::wait_screen_change: screen change seen at 1
[2019-06-13T18:24:11.271 CEST] [debug] /var/lib/openqa/cache/openqa1-opensuse/tests/opensuse/tests/x11/ooffice.pm:21 called testapi::x11_start_program
[2019-06-13T18:24:11.271 CEST] [debug] <<< testapi::check_screen(mustmatch='desktop-runner', timeout=10)
[2019-06-13T18:24:11.484 CEST] [debug] >>> testapi::_handle_found_needle: found desktop-runner-kde-20190424, similarity 1.00 @ 254/2
[2019-06-13T18:24:11.484 CEST] [debug] /var/lib/openqa/cache/openqa1-opensuse/tests/opensuse/tests/x11/ooffice.pm:21 called testapi::x11_start_program
[2019-06-13T18:24:11.485 CEST] [debug] <<< testapi::type_string(string='oowriter', max_interval=13, wait_screen_changes=0, wait_still_screen=0, timeout=30, similarity_level=47)
[2019-06-13T18:24:11.485 CEST] [debug] /var/lib/openqa/cache/openqa1-opensuse/tests/opensuse/tests/x11/ooffice.pm:21 called testapi::x11_start_program
[2019-06-13T18:24:11.485 CEST] [debug] <<< testapi::wait_screen_change(timeout=10, similarity_level=50)
[2019-06-13T18:24:13.906 CEST] [debug] waiting for screen change: 0 35.209458787774
[2019-06-13T18:24:13.906 CEST] [debug] >>> testapi::wait_screen_change: screen change seen at 0
[2019-06-13T18:24:13.906 CEST] [debug] /var/lib/openqa/cache/openqa1-opensuse/tests/opensuse/tests/x11/ooffice.pm:21 called testapi::x11_start_program
[2019-06-13T18:24:13.906 CEST] [debug] <<< testapi::check_screen(mustmatch='desktop-runner-plasma-suggestions', timeout=0)
[2019-06-13T18:24:14.267 CEST] [debug] >>> testapi::_handle_found_needle: found desktop-runner-plasma-suggestions-20190524, similarity 1.00 @ 243/1

Test suite description

TIMEOUT_SCALE property is added to avoid sporadic shutdown fails in kde@USBboot_64 scenario.

Added by : oorlov;

Related ticket: poo#37000

Expected result

When a matching needle exists, and the runner shows the suggestions (as it obviously does, see https://openqa.opensuse.org/tests/957874/file/video.ogv#t=251.62,251.66), the check should not fail.

Further details

Always latest result in this scenario: latest


Related issues

Related to openQA Tests - action #51944: [opensuse][functional][u] test fails in dolphin -- "kdialog --getopenfilename" fails to startRejected2019-05-23

Related to openQA Tests - action #35589: [functional][u][opensuse][sporadic][medium] test fails in kontact - needs workaround for boo#1105207, then akregator not closedRejected2018-04-26

Related to openQA Tests - action #54290: [opensuse][kde] Sometimes, on KDE, alt-f2 does not show krunnerNew2019-07-15

History

#1 Updated by StefanBruens over 2 years ago

As can be seen, check_screen('desktop-runner') is run with a timeout of 30 seconds, but check_screen('desktop-runner-plasma-suggestions') uses a timeout of 0.

Pull request: https://github.com/os-autoinst/os-autoinst-distri-opensuse/pull/7679

#2 Updated by okurz over 2 years ago

  • Related to action #51944: [opensuse][functional][u] test fails in dolphin -- "kdialog --getopenfilename" fails to start added

#3 Updated by okurz over 2 years ago

  • Related to action #35589: [functional][u][opensuse][sporadic][medium] test fails in kontact - needs workaround for boo#1105207, then akregator not closed added

#4 Updated by SLindoMansilla over 2 years ago

  • Subject changed from krunner suggestions check is racy to [opensuse][kde] krunner suggestions check is racy

#5 Updated by okurz over 2 years ago

  • Related to action #54290: [opensuse][kde] Sometimes, on KDE, alt-f2 does not show krunner added

#6 Updated by okurz over 2 years ago

https://github.com/os-autoinst/os-autoinst-distri-opensuse/pull/7679 was merged since about 2 months already but we still have problems . https://openqa.opensuse.org/tests/1006453#step/hexchat/21 seems to be another, more recent example: In https://openqa.opensuse.org/tests/1006453#step/hexchat/20 we detect the "suggestions window" but we continue typing. After the complete word "hexchat" was typed the suggestion window disappeared but is checked for again in https://openqa.opensuse.org/tests/1006453#step/hexchat/23 when the test fails.

#8 Updated by okurz over 2 years ago

cool :) maybe this could be made more obvious? E.g. in the post_fail_hook?

#9 Updated by StefanBruens over 2 years ago

The hook could check for drkonqui entries in the journal ...

hexchat-journal.log:

Aug 14 05:19:49 susetest kwin_x11[13804]: qt.qpa.xcb: QXcbConnection: XCB error: 3 (BadWindow), sequence: 12814, resource id: 35651752, major code: 18 (ChangeProperty), minor code: 0
Aug 14 05:19:54 susetest org.kde.runners.baloo[13710]: KCrash: Application 'baloorunner' crashing...
Aug 14 05:19:55 susetest org.kde.runners.baloo[13710]: KCrash: Attempting to start /usr/lib64/libexec/drkonqi from kdeinit
Aug 14 05:19:56 susetest krunner[13810]: Xapian Database does not exist at  "/home/bernhard/.local/share/akonadi/search_db/contacts/"
Aug 14 05:19:56 susetest krunner[13810]: Xapian Database does not exist at  "/home/bernhard/.local/share/akonadi/search_db/emailContacts/"
Aug 14 05:19:57 susetest drkonqi[25600]: found lsb_release
Aug 14 05:19:57 susetest drkonqi[25600]: Using /proc to determine executable path
Aug 14 05:19:57 susetest drkonqi[25600]: Executable is: "/usr/lib64/libexec/baloorunner"
Aug 14 05:19:57 susetest drkonqi[25600]: Executable exists: true
Aug 14 05:19:57 susetest drkonqi[25600]: Enabling drkonqi crash catching
Aug 14 05:19:57 susetest kdeinit5[13771]: Registering ":1.75/StatusNotifierItem" to system tray
Aug 14 05:19:57 susetest plasmashell[13813]: Registering ":1.75/StatusNotifierItem"
Aug 14 05:19:59 susetest drkonqi[25600]: Sending SIGSTOP to process

#10 Updated by zluo almost 2 years ago

  • Status changed from New to Rejected
  • Assignee set to zluo

https://openqa.opensuse.org/tests/1109316#step/oomath/3 looks good, no issue with krunner suggestion for now.

#11 Updated by okurz almost 2 years ago

  • Subject changed from [opensuse][kde] krunner suggestions check is racy to [opensuse][kde][sporadic] krunner suggestions check is racy
  • Status changed from Rejected to New

you seem to have missed the last unhandled comments and also missed "racy" in the subject pointing to the issue being linked to sporadic failures. I doubt there had been effective changes to krunner behaviour since the last comment about related failures.

#12 Updated by zluo almost 2 years ago

  • Assignee deleted (zluo)

okay, then please take over.

#13 Updated by okurz 3 months ago

This ticket was set to "Normal" priority but was not updated within the SLO period for "Normal" tickets (365 days) as described on https://progress.opensuse.org/projects/openqatests/wiki/Wiki#SLOs-service-level-objectives . Please consider picking up this ticket within the next 365 days or just set the ticket to the next lower priority of "Low" (no SLO related time period). This update was done as agreed within the SUSE QE Sync call 2021-09-01

Also available in: Atom PDF