Project

General

Profile

Actions
Copy link

action #101391

open

[qem][qe-core][leap-for-sle-updates][sporadic] test fails in first_boot on a login screen when auto-login was expected - product bug, needs investigation

Added by okurz over 2 years ago. Updated about 1 month ago.

Status:
Workable
Priority:
Normal
Assignee:
szarate
Category:
Bugs in existing tests
Target version:
-
Start date:
Due date:
% Done:

0%

Estimated time:
Difficulty:

Description

Observation

openQA test in scenario opensuse-15.3-Leap-DVD-Incidents-x86_64-cryptlvm@64bit fails in
first_boot
on a login screen when auto-login was expected.
Just wanted to react on thehejik who asked in #team-lsg-qe-openqa-review and after a short time I found https://openqa.suse.de/tests/7507549#next_previous which shows many jobs failing in "first_boot" for multiple days sporadically. The first occurence was from 12 days ago (https://openqa.suse.de/tests/7367074). Now it blocks https://build.suse.de/request/show/256268 and potentially other

Reproducible

Fails since (at least) https://openqa.suse.de/tests/7367074 sporadically . I counted 20/180 failures now -> roughly 11% fail-ratio.

Expected result

Reference: :21235:less (or more recent)

Further details

Always latest result in this scenario: latest

Actions
Copy link
 #2

Updated by tjyrinki_suse over 2 years ago

  • Status changed from New to Workable
  • Priority changed from Urgent to High
  • Target version set to QE-Core: Ready
  • Start date deleted (2021-10-22)
Actions
Copy link
 #3

Updated by szarate over 2 years ago

  • Subject changed from [qem][qe-core][leap-for-sle-updates][sporadic] test fails in first_boot on a login screen when auto-login was expected to [qem][qe-core][leap-for-sle-updates][sporadic] test fails in first_boot on a login screen when auto-login was expected - product bug, needs investigation

This looks like a product bug when looking at the journal:


Nov 08 04:03:04.182232 localhost.localdomain systemd-logind[1304]: New session 3 of user root.
Nov 08 04:03:04.208620 localhost.localdomain login[3147]: pam_unix(login:session): session opened for user root by LOGIN(uid=0)
Nov 08 04:03:04.209905 localhost.localdomain login[3147]: ROOT LOGIN ON tty5
Nov 08 04:03:30.336923 localhost.localdomain dbus-daemon[1213]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.103' (uid=0 pid=3201 comm="systemd-analyze plot ")
Nov 08 04:03:30.344582 localhost.localdomain systemd[1]: Starting Hostname Service...
Nov 08 04:03:30.782256 localhost.localdomain dbus-daemon[1213]: [system] Successfully activated service 'org.freedesktop.hostname1'
Nov 08 04:03:30.782674 localhost.localdomain systemd[1]: Started Hostname Service.
Nov 08 04:03:43.714852 localhost.localdomain systemd[1]: proc-sys-fs-binfmt_misc.automount: Got automount request for /proc/sys/fs/binfmt_misc, triggered by 3208 (find)
Nov 08 04:03:43.716746 localhost.localdomain systemd[1]: Mounting Arbitrary Executable File Formats File System...
Nov 08 04:03:43.742415 localhost.localdomain systemd[1]: Mounted Arbitrary Executable File Formats File System.
Nov 08 04:04:00.821413 localhost.localdomain systemd[1]: systemd-hostnamed.service: Succeeded.
Nov 08 04:10:19.296173 localhost.localdomain systemd-journald[758]: Missed 2249 kernel messages
Nov 08 04:10:19.296173 localhost.localdomain kernel:  do_sys_poll+0x3b2/0x570
Nov 08 04:10:19.297974 localhost.localdomain kernel:  ? kmem_cache_alloc+0x18a/0x270
Nov 08 04:10:19.298231 localhost.localdomain kernel:  ? __update_load_avg_cfs_rq+0x255/0x2d0
Nov 08 04:10:19.298280 localhost.localdomain kernel:  ? __update_load_avg_cfs_rq+0x255/0x2d0
Nov 08 04:10:19.298323 localhost.localdomain kernel:  ? prep_new_page+0x8d/0x160
Nov 08 04:10:19.298352 localhost.localdomain kernel:  ? get_page_from_freelist+0xbea/0x1750
Nov 08 04:10:19.298378 localhost.localdomain kernel:  ? __update_load_avg_cfs_rq+0x255/0x2d0
Nov 08 04:10:19.298426 localhost.localdomain kernel:  ? update_load_avg+0x1ac/0x5f0
Nov 08 04:10:19.298453 localhost.localdomain kernel:  ? __update_load_avg_cfs_rq+0x255/0x2d0
Nov 08 04:10:19.298479 localhost.localdomain kernel:  ? poll_select_finish+0x220/0x220
Nov 08 04:10:19.298506 localhost.localdomain kernel:  ? poll_select_finish+0x220/0x220
Nov 08 04:10:19.299489 localhost.localdomain kernel:  ? check_preempt_wakeup+0x102/0x230
Nov 08 04:10:19.299523 localhost.localdomain kernel:  ? check_preempt_curr+0x29/0x80
Nov 08 04:10:19.299548 localhost.localdomain kernel:  ? ttwu_do_wakeup+0x19/0x150
Nov 08 04:10:19.299573 localhost.localdomain kernel:  ? try_to_wake_up+0x439/0x500
Nov 08 04:10:19.299604 localhost.localdomain kernel:  ? __seccomp_filter+0x7a/0x690
Nov 08 04:10:19.299629 localhost.localdomain kernel:  ? __x64_sys_poll+0x37/0x150
Nov 08 04:10:19.299891 localhost.localdomain kernel:  __x64_sys_poll+0x37/0x150
Nov 08 04:10:19.299927 localhost.localdomain kernel:  do_syscall_64+0x5b/0x1e0
Nov 08 04:10:19.299956 localhost.localdomain kernel:  entry_SYSCALL_64_after_hwframe+0x44/0xa9
Nov 08 04:10:19.299993 localhost.localdomain kernel: RIP: 0033:0x7fb53844a099
Nov 08 04:10:19.300021 localhost.localdomain kernel: Code: Bad RIP value.
Nov 08 04:10:19.300048 localhost.localdomain kernel: RSP: 002b:00007fffdeb89f00 EFLAGS: 00000293 ORIG_RAX: 0000000000000007
Nov 08 04:10:19.301742 localhost.localdomain kernel: RAX: ffffffffffffffda RBX: 000056537a066bb0 RCX: 00007fb53844a099
Nov 08 04:10:19.301775 localhost.localdomain kernel: RDX: 00000000ffffffff RSI: 0000000000000002 RDI: 000056537a066bb0
Nov 08 04:10:19.301807 localhost.localdomain kernel: RBP: 0000000000000002 R08: 0000000000000000 R09: 000056537a066e50
Nov 08 04:10:19.301839 localhost.localdomain kernel: R10: 000056537a060f20 R11: 0000000000000293 R12: 00000000ffffffff
Nov 08 04:10:19.302214 localhost.localdomain kernel: R13: 00000000ffffffff R14: 00007fb538ddf900 R15: 0000000000000002
Nov 08 04:10:19.302258 localhost.localdomain kernel: task:gmain           state:S stack:    0 pid: 2344 ppid:     1 flags:0x00000100
Nov 08 04:10:19.302295 localhost.localdomain kernel: Call Trace:
Nov 08 04:10:19.302330 localhost.localdomain kernel:  __schedule+0x2ff/0x760
Nov 08 04:10:19.302358 localhost.localdomain kernel:  schedule+0x2f/0xa0
Nov 08 04:10:19.302402 localhost.localdomain kernel:  schedule_hrtimeout_range_clock+0xee/0x100
Nov 08 04:10:19.302450 localhost.localdomain kernel:  ? remove_wait_queue+0x12/0x50
Nov 08 04:10:19.302722 localhost.localdomain kernel:  poll_schedule_timeout.constprop.13+0x42/0x70
Nov 08 04:10:19.302764 localhost.localdomain kernel:  do_sys_poll+0x3b2/0x570
Nov 08 04:10:19.302943 localhost.localdomain kernel:  ? prep_new_page+0x8d/0x160
Nov 08 04:10:19.302977 localhost.localdomain kernel:  ? get_page_from_freelist+0xbea/0x1750
Nov 08 04:10:19.303006 localhost.localdomain kernel:  ? poll_select_finish+0x220/0x220
Nov 08 04:10:19.303032 localhost.localdomain kernel:  ? mem_cgroup_commit_charge+0x5f/0x490
Nov 08 04:10:19.303059 localhost.localdomain kernel:  ? mem_cgroup_try_charge+0x70/0x190
Nov 08 04:10:19.303086 localhost.localdomain kernel:  ? mem_cgroup_throttle_swaprate+0x19/0x140
Nov 08 04:10:19.303128 localhost.localdomain kernel:  ? __seccomp_filter+0x7a/0x690
Nov 08 04:10:19.303154 localhost.localdomain kernel:  ? __x64_sys_poll+0x37/0x150
Nov 08 04:10:19.303180 localhost.localdomain kernel:  __x64_sys_poll+0x37/0x150
Nov 08 04:10:19.303221 localhost.localdomain kernel:  do_syscall_64+0x5b/0x1e0
Nov 08 04:10:19.303826 localhost.localdomain kernel:  entry_SYSCALL_64_after_hwframe+0x44/0xa9
Nov 08 04:10:19.303863 localhost.localdomain kernel: RIP: 0033:0x7fb53844a099
Nov 08 04:10:19.303891 localhost.localdomain kernel: Code: Bad RIP value.
Nov 08 04:10:19.303935 localhost.localdomain kernel: RSP: 002b:00007fb535f83d30 EFLAGS: 00000293 ORIG_RAX: 0000000000000007
Nov 08 04:10:19.303962 localhost.localdomain kernel: RAX: ffffffffffffffda RBX: 000056537a04a0a0 RCX: 00007fb53844a099
Nov 08 04:10:19.303995 localhost.localdomain kernel: RDX: 00000000ffffffff RSI: 0000000000000001 RDI: 000056537a04a0a0
Nov 08 04:10:19.304038 localhost.localdomain kernel: RBP: 0000000000000001 R08: 0000000000000000 R09: 000056537a04b7a8
Nov 08 04:10:19.304070 localhost.localdomain kernel: R10: 000056537a03aaa0 R11: 0000000000000293 R12: 00000000ffffffff
Nov 08 04:10:19.304098 localhost.localdomain kernel: R13: 00000000ffffffff R14: 00007fb538ddf900 R15: 0000000000000001
Nov 08 04:10:19.304124 localhost.localdomain kernel: task:gdbus           state:S stack:    0 pid: 2345 ppid:     1 flags:0x00000100
Nov 08 04:10:19.304401 localhost.localdomain kernel: Call Trace:
Actions
Copy link
 #4

Updated by okurz about 2 years ago

This ticket was set to "High" priority but was not updated within the SLO period for "High" tickets (30 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 30 days or just set the ticket to the next lower priority of "Normal" (SLO: updated within 365 days).

Actions
Copy link
 #5

Updated by tjyrinki_suse about 2 years ago

  • Assignee set to szarate
  • Priority changed from High to Normal
  • Target version deleted (QE-Core: Ready)
Actions
Copy link
 #6

Updated by slo-gin about 1 year 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.

Actions
Copy link
 #7

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.

Actions
Copy link

Also available in: Atom PDF