action #53045
open[opensuse][kde][sporadic] krunner suggestions check is racy
0%
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
Updated by StefanBruens over 5 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
Updated by okurz over 5 years ago
- Related to action #51944: [opensuse][functional][u] test fails in dolphin -- "kdialog --getopenfilename" fails to start added
Updated by okurz over 5 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
Updated by SLindoMansilla over 5 years ago
- Subject changed from krunner suggestions check is racy to [opensuse][kde] krunner suggestions check is racy
Updated by okurz over 5 years ago
- Related to action #54290: [opensuse][kde] Sometimes, on KDE, alt-f2 does not show krunner added
Updated by okurz over 5 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.
Updated by StefanBruens over 5 years ago
"baloorunner crashed" - https://openqa.opensuse.org/tests/1006453/file/video.ogv#t=320.42,320.46
Updated by okurz over 5 years ago
cool :) maybe this could be made more obvious? E.g. in the post_fail_hook?
Updated by StefanBruens over 5 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
Updated by zluo almost 5 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.
Updated by okurz almost 5 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.
Updated by okurz about 3 years 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
Updated by slo-gin about 2 years ago
This ticket was set to Normal priority but was not updated within the SLO period. Please consider picking up this ticket or just set the ticket to the next lower priority.
Updated by slo-gin 8 months ago
This ticket was set to Normal priority but was not updated within the SLO period. Please consider picking up this ticket or just set the ticket to the next lower priority.