Project

General

Profile

action #58948

[sporadic] missing first character in passed cmd string: 'ypper' rather than 'zypper' typed string

Added by michel_mno about 2 years ago. Updated 5 months ago.

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

0%

Estimated time:
Difficulty:

Description

missing first character in passed cmd string: 'ypper' rather than 'zypper' typed string

test fails in php7_postgresql, missing 'z' character from typed 'zypper' string as per last example https://openqa.opensuse.org/tests/1071315#step/php7_postgresql/8

extract autoinst-log that do not report failure when string command is typed, but failure reported:

[2019-10-31T11:12:36.029 UTC] [debug] /var/lib/openqa/cache/openqa1-opensuse/tests/opensuse/tests/console/php7_postgresql.pm:46 called apachetest::setup_apache2
[2019-10-31T11:12:36.029 UTC] [debug] <<< testapi::script_run(cmd='zypper -n --no-gpg-checks in apache2 apache2-mod_php7 php7 ; ( exit ${PIPESTATUS[0]} )', timeout=700, output='', quiet=undef)
[2019-10-31T11:12:36.029 UTC] [debug] /var/lib/openqa/cache/openqa1-opensuse/tests/opensuse/tests/console/php7_postgresql.pm:46 called apachetest::setup_apache2
[2019-10-31T11:12:36.029 UTC] [debug] <<< testapi::type_string(string='zypper -n --no-gpg-checks in apache2 apache2-mod_php7 php7 ; ( exit ${PIPESTATUS[0]} )', max_interval=250, wait_screen_changes=0, wait_still_screen=0, timeout=30, similarity_level=47)
[2019-10-31T11:12:45.004 UTC] [debug] /var/lib/openqa/cache/openqa1-opensuse/tests/opensuse/tests/console/php7_postgresql.pm:46 called apachetest::setup_apache2
[2019-10-31T11:12:45.004 UTC] [debug] <<< testapi::type_string(string='; echo S30s9-$?- > /dev/hvc0
', max_interval=250, wait_screen_changes=0, wait_still_screen=0, timeout=30, similarity_level=47)
[2019-10-31T11:12:48.037 UTC] [debug] /var/lib/openqa/cache/openqa1-opensuse/tests/opensuse/tests/console/php7_postgresql.pm:46 called apachetest::setup_apache2
[2019-10-31T11:12:48.037 UTC] [debug] <<< testapi::wait_serial(quiet=undef, regexp=qr/S30s9-\d+-/, buffer_size=undef, record_output=undef, no_regex=0, expect_not_found=0, timeout=700)
[2019-10-31T11:12:49.269 UTC] [debug] >>> testapi::wait_serial: (?^:S30s9-\d+-): ok
[2019-10-31T11:12:49.269 UTC] [debug] /var/lib/openqa/cache/openqa1-opensuse/tests/opensuse/tests/console/php7_postgresql.pm:46 called apachetest::setup_apache2
[2019-10-31T11:12:49.270 UTC] [debug] <<< testapi::upload_logs(file='/var/log/zypper.log', failok=0, timeout=90)
[2019-10-31T11:12:49.270 UTC] [debug] /var/lib/openqa/cache/openqa1-opensuse/tests/opensuse/tests/console/php7_postgresql.pm:46 called apachetest::setup_apache2
[2019-10-31T11:12:49.270 UTC] [debug] <<< testapi::assert_script_run(cmd='curl --form upload=@/var/log/zypper.log --form upname=php7_postgresql-zypper.log http://10.0.2.2:20023/0jsBYXetumAWWg9A/uploadlog/zypper.log', fail_message='', timeout=90, quiet=undef)
[2019-10-31T11:12:49.270 UTC] [debug] /var/lib/openqa/cache/openqa1-opensuse/tests/opensuse/tests/console/php7_postgresql.pm:46 called apachetest::setup_apache2
[2019-10-31T11:12:49.270 UTC] [debug] <<< testapi::type_string(string='curl --form upload=@/var/log/zypper.log --form upname=php7_postgresql-zypper.log http://10.0.2.2:20023/0jsBYXetumAWWg9A/uploadlog/zypper.log', max_interval=250, wait_screen_changes=0, wait_still_screen=0, timeout=30, similarity_level=47)
[2019-10-31T11:13:03.889 UTC] [debug] /var/lib/openqa/cache/openqa1-opensuse/tests/opensuse/tests/console/php7_postgresql.pm:46 called apachetest::setup_apache2
[2019-10-31T11:13:03.889 UTC] [debug] <<< testapi::type_string(string='; echo sLNz3-$?- > /dev/hvc0
', max_interval=250, wait_screen_changes=0, wait_still_screen=0, timeout=30, similarity_level=47)
[2019-10-31T11:13:06.916 UTC] [debug] /var/lib/openqa/cache/openqa1-opensuse/tests/opensuse/tests/console/php7_postgresql.pm:46 called apachetest::setup_apache2
[2019-10-31T11:13:06.917 UTC] [debug] <<< testapi::wait_serial(regexp=qr/sLNz3-\d+-/, quiet=undef, buffer_size=undef, timeout=90, record_output=undef, no_regex=0, expect_not_found=0)
[2019-10-31T11:13:09.150 UTC] [debug] >>> testapi::wait_serial: (?^:sLNz3-\d+-): ok
[2019-10-31T11:13:09.384 UTC] [debug] 'zypper -n --no-gpg-checks in apache2 apache2-mod_php7 php7' failed with code 127 at /var/lib/openqa/cache/openqa1-opensuse/tests/opensuse/lib/utils.pm line 525.

This issue is tracking transient failure on different tests for different snapshots,
each time the missing character is the first one of a new submitted command.

o3 url not accessible anymore:
20190627 minimalx https://openqa.opensuse.org/tests/970339#step/force_scheduled_tasks/8
'ind' rather than 'find'
20190627 toolchain https://openqa.opensuse.org/tests/970357#step/gcc_fortran_compilation/3
'get' rather than 'wget'
still accessible, but no more logs:
20190703 extra_tests_filesystems https://openqa.opensuse.org/tests/975065#step/snapper_undochange/32
'napper' rather than 'snapper'
still have autoinst log:
2191028 extra_tests_in_textmode https://openqa.opensuse.org/tests/1070395#step/zypper_extend/26
'ypper' rather 'zypper'
20191030 textmode https://openqa.opensuse.org/tests/1071315#step/php7_postgresql/8
'ypper' rather 'zypper'

I do not think this is same issue as old xterm missing chars tracked by https://progress.opensuse.org/issues/44051 because in this new issue the missing character is the first one of passed buffer.

Observation

openQA test in scenario opensuse-Tumbleweed-DVD-ppc64le-textmode@ppc64le fails in
php7_postgresql

Test suite description

Maintainer: okurz

Installation in textmode and selecting the textmode "desktop" during installation.

Reproducible

transient failure

Expected result

Last good: 20191028 (or more recent)

Further details

Always latest result in this scenario: latest


Related issues

Related to openQA Tests - coordination #43889: [qe-core][epic][functional][virtio][wayland] openQA makes spelling mistakesBlocked

History

#1 Updated by michel_mno about 2 years ago

another occurance snapshot 20191120 ppc64 minimalx test x_vt module; with "s -ef ..." typed in place of "ps -ef ..."
https://openqa.opensuse.org/tests/1091288#step/x_vt/3
as before failure is first buffer command after Migrating/Snapshot actions.

[2019-11-21T06:40:52.248 UTC] [debug] Migrating remaining bytes:    0
[2019-11-21T06:40:52.249 UTC] [debug] Snapshot complete
[2019-11-21T06:40:52.351 UTC] [debug] EVENT {"event":"RESUME","timestamp":{"microseconds":351044,"seconds":1574318452}}
[2019-11-21T06:40:52.358 UTC] [debug] ||| starting x_vt tests/console/x_vt.pm
[2019-11-21T06:40:52.363 UTC] [debug] QEMU: Formatting '/var/lib/openqa/pool/6/raid/hd0-overlay9', fmt=qcow2 size=21474836480 backing_file=/var/lib/openqa/pool/6/raid/hd0-overlay8 backing_fmt=qcow2 cluster_size=65536 lazy_refcounts=off refcount_bits=16
[2019-11-21T06:40:52.363 UTC] [debug] QEMU: Formatting '/var/lib/openqa/pool/6/raid/cd0-overlay9', fmt=qcow2 size=3742142464 backing_file=/var/lib/openqa/pool/6/raid/cd0-overlay8 backing_fmt=qcow2 cluster_size=65536 lazy_refcounts=off refcount_bits=16
[2019-11-21T06:40:52.365 UTC] [debug] /var/lib/openqa/cache/openqa1-opensuse/tests/opensuse/tests/console/x_vt.pm:20 called testapi::assert_script_run
[2019-11-21T06:40:52.365 UTC] [debug] <<< testapi::assert_script_run(cmd='ps -ef | grep bin/X', timeout=90, fail_message='', quiet=undef)
...

[2019-11-21T06:40:58.697 UTC] [debug] >>> testapi::wait_serial: (?^:OeUCu-\d+-): ok
[2019-11-21T06:40:58.807 UTC] [debug] command 'ps -ef | grep bin/X' failed at /var/lib/openqa/cache/openqa1-opensuse/tests/opensuse/tests/console/x_vt.pm line 20.

#2 Updated by okurz about 2 years ago

  • Category set to Bugs in existing tests

the last one is probably special as the code in the test module starts to type as soon as the module started when the previous one just recorded a qemu snapshot. Seems the VM missed the first character.

#3 Updated by szarate about 2 years ago

  • Related to coordination #43889: [qe-core][epic][functional][virtio][wayland] openQA makes spelling mistakes added

#4 Updated by szarate about 2 years ago

  • Subject changed from missing first character in passed cmd string: 'ypper' rather than 'zypper' typed string to [sporadic] missing first character in passed cmd string: 'ypper' rather than 'zypper' typed string

#5 Updated by okurz 5 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