action #58948
open[sporadic][aarch64][ppc64le] missing first character in passed cmd string: e.g. 'ypper' rather than 'zypper' typed string
0%
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
Updated by michel_mno over 4 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.
Updated by okurz over 4 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.
Updated by szarate over 4 years ago
- Related to coordination #43889: [qe-core][epic][functional][virtio][wayland] openQA makes spelling mistakes added
Updated by szarate over 4 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
Updated by okurz over 2 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 okurz almost 2 years ago
- Related to action #91314: [opensuse][typing][sporadic][aarch64][ppc64le] repeated keys on aarc64 or ppc64le: Leap 15.3: test fails in repos - zzzypper command not found added
Updated by okurz almost 2 years ago
- Subject changed from [sporadic] missing first character in passed cmd string: 'ypper' rather than 'zypper' typed string to [sporadic][aarch64][ppc64le] missing first character in passed cmd string: e.g. 'ypper' rather than 'zypper' typed string
https://openqa.suse.de/tests/9099337#step/hostname/10 is another occurence in sle-15-SP4-JeOS-for-kvm-and-xen-Updates-aarch64-Build20220709-1-jeos-main@aarch64 within the rather simple test code:
assert_script_run "hostnamectl status|grep $hostname";
assert_script_run "uname -n|grep $hostname";
where we are missing the "u" of "uname" so here on aarch64 again missing the first character of a string even though the key buffer should accept some characters even if the previous command has not completely terminated yet.
Updated by slo-gin about 1 month 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.