Bug 14645

Summary: qemux86-64 oops on bootup
Product: [Build System, Metadata & Runtime] OE-Core Reporter: akuster <akuster808>
Component: virtual machinesAssignee: Unassigned <unassigned>
Status: ACCEPTED --- QA Contact: akuster <akuster808>
Severity: normal    
Priority: Medium CC: randy.macleod, tim.orling, tvgamblin
Version: unspecified   
Target Milestone: 5.99   
Hardware: x86   
OS: Multiple   
Whiteboard:
OS type for building Yocto: --- Type of Regression: ---
Verified: Documentation change: Don't know

Description akuster 2021-12-06 15:57:20 UTC
Using a clean poky clone master:467c21abe432aab8cf985fcad917d9728052f5e9

qemux86-64 oops. I first noticed this about last week.

I am logging this just to capture the message.

This could be related to the AMD cpu on my laptop.
Comment 1 akuster 2021-12-06 15:57:27 UTC
12058.929275] RIP: 0010:finish_task_switch.isra.0+0xc0/0x260
[12058.929473] Code: 48 8b 43 08 48 89 da 4c 89 ff 48 8b 1b 48 c7 02 00 00 00 00 e8 41 ec f5 00 48 1
[12058.930144] RSP: 0018:ffffa4a34007bda0 EFLAGS: 00000246
[12058.930360] RAX: 0000000000000000 RBX: 0000000000000000 RCX: 0000000000000002
[12058.930591] RDX: 0000000000000000 RSI: 0000000000000000 RDI: ffff8b7d0f9aaa00
[12058.930841] RBP: ffffa4a34007bdc8 R08: 0000000000000c00 R09: 0000000000000000
[12058.931152] R10: 0000000000000001 R11: 0000000000000773 R12: ffff8b7d0124e800
[12058.931379] R13: 0000000000000000 R14: 0000000000000000 R15: ffff8b7d0f9aaa00
[12058.931619]  __schedule+0x20d/0x610
[12058.931743]  schedule+0x67/0xe0
[12058.931880]  schedule_timeout+0x8f/0x160
[12058.932178]  ? __bpf_trace_tick_stop+0x10/0x10
[12058.932337]  rcu_gp_kthread+0x5b2/0x9a0
[12058.932464]  ? rcu_gp_cleanup+0x3d0/0x3d0
[12058.932595]  kthread+0x13b/0x160
[12058.932685]  ? set_kthread_struct+0x50/0x50
[12058.932804]  ret_from_fork+0x22/0x30
[12058.933058] rcu: INFO: rcu_preempt detected stalls on CPUs/tasks:
[12058.933493] rcu: 	0-...!: (1 GPs behind) idle=cb8/0/0x0 softirq=939/940 fqs=0 
[12058.933963] rcu: 	1-...!: (1 GPs behind) idle=dda/0/0x1 softirq=824/825 fqs=0 
[12058.934402] rcu: 	2-...!: (1 GPs behind) idle=c52/1/0x4000000000000000 softirq=810/810 fqs=0 
[12058.934874] rcu: 	3-...!: (0 ticks this GP) idle=d28/0/0x0 softirq=619/619 fqs=0 
[12058.935496] 	(detected by 2, t=100012 jiffies, g=789, q=13)
[12058.935825] Sending NMI from CPU 2 to CPUs 0:
[12058.936464] NMI backtrace for cpu 0 skipped: idling at default_idle+0x1c/0x20
[12058.938584] Sending NMI from CPU 2 to CPUs 1:
[12058.939462] NMI backtrace for cpu 1
[12058.939518] CPU: 1 PID: 303 Comm: syslogd Not tainted 5.14.18-yocto-standard #1
[12058.939531] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.14.0-0-g155821a1990b-4
[12058.939541] RIP: 0010:page_mapping+0x0/0x90
[12058.939549] Code: f8 c9 c3 e8 32 a5 00 00 4c 8b 65 f8 c9 c3 4c 89 e7 e8 14 19 e7 ff 84 c0 0f 85 5
[12058.939559] RSP: 0018:ffffa4a340983cd0 EFLAGS: 00000202
[12058.939580] RAX: 000000000000cee0 RBX: 0000000000000068 RCX: 0000000000000000
[12058.939589] RDX: 0000000000000004 RSI: ffff8b7d0442f238 RDI: ffffc8adc021efc0
[12058.939596] RBP: ffffa4a340983ce0 R08: 0000000000000068 R09: ffffc8adc021efc0
[12058.939603] R10: ffffc8adc021eff4 R11: 0000000000000000 R12: ffffc8adc021efc0
[12058.939614] R13: 0000000000000068 R14: ffff8b7d0442f238 R15: 000000000000cee0
[12058.939621] FS:  00007f1287477740(0000) GS:ffff8b7d0f880000(0000) knlGS:0000000000000000
[12058.939630] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[12058.939639] CR2: 00005649b8ff82e0 CR3: 0000000003caa000 CR4: 00000000001506e0
[12058.939676] Call Trace:
[12058.939787]  ? set_page_dirty+0x13/0xb0
[12058.939814]  shmem_write_end+0x4c/0x1b0
[12058.939822]  generic_perform_write+0x10a/0x1f0
[12058.939830]  __generic_file_write_iter+0x150/0x1d0
[12058.939838]  generic_file_write_iter+0x64/0xb0
[12058.939844]  new_sync_write+0x114/0x1a0
[12058.939852]  vfs_write+0x1c2/0x260
[12058.939860]  ksys_write+0x67/0xe0
[12058.939866]  __x64_sys_write+0x19/0x20
[12058.939873]  do_syscall_64+0x43/0x90
[12058.939879]  entry_SYSCALL_64_after_hwframe+0x44/0xae
[12058.939885] RIP: 0033:0x7f1287574ee7
[12058.939895] Code: 0f 00 f7 d8 64 89 02 48 c7 c0 ff ff ff ff eb b7 0f 1f 00 f3 0f 1e fa 64 8b 04 4
[12058.939905] RSP: 002b:00007ffc03c17248 EFLAGS: 00000246 ORIG_RAX: 0000000000000001
[12058.939926] RAX: ffffffffffffffda RBX: 0000000000000068 RCX: 00007f1287574ee7
[12058.939934] RDX: 0000000000000068 RSI: 0000560077d3d718 RDI: 0000000000000003
[12058.939943] RBP: 0000560077d3d718 R08: 0000000000000000 R09: 613461666666663a
[12058.939951] R10: 3265663762303433 R11: 0000000000000246 R12: 0000000000000003
[12058.939959] R13: 00007f12874776c0 R14: 00007ffc03c173d0 R15: 0000560077d3d528
[12058.941202] NMI backtrace for cpu 2
[12058.953162] CPU: 2 PID: 306 Comm: klogd Not tainted 5.14.18-yocto-standard #1
[12058.953709] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.14.0-0-g155821a1990b-4
[12058.954094] Call Trace:
[12060.954092]  dump_stack_lvl+0x38/0x49
[12062.953789]  dump_stack+0x10/0x12
[12062.954003]  nmi_cpu_backtrace.cold+0x32/0x6a
[12064.954018]  ? lapic_can_unplug_cpu+0x80/0x80
[12064.954352]  nmi_trigger_cpumask_backtrace+0x95/0xb0
[12064.954679]  arch_trigger_cpumask_backtrace+0x19/0x20
[12066.954588]  rcu_dump_cpu_stacks+0xc2/0xf4
[12068.954534]  rcu_sched_clock_irq.cold+0x4a/0x45e
[12070.954542]  ? syslog_print+0x1bf/0x200
[12072.954265]  ? cpuacct_account_field+0x57/0x60
[12072.954623]  ? account_user_time+0xa1/0xb0
[12074.954745]  update_process_times+0x94/0xd0
[12076.954495]  tick_sched_timer+0xb6/0xe0
[12078.954203]  ? can_stop_idle_tick+0xa0/0xa0
[12078.954575]  __hrtimer_run_queues+0x13d/0x2e0
[12080.954316] rcu: INFO: rcu_preempt self-detected stall on CPU
[12080.954316]  hrtimer_interrupt+0xfe/0x290
[12080.955105]  __sysvec_apic_timer_interrupt+0x69/0x120
[12080.955551]  sysvec_apic_timer_interrupt+0x36/0x80
[12080.955978]  ? asm_sysvec_apic_timer_interrupt+0xa/0x20
[12080.956436]  asm_sysvec_apic_timer_interrupt+0x12/0x20
[12080.956894] RIP: 0033:0x7fcc23bf7da0
[12080.957238] Code: 19 48 c7 45 38 00 00 00 00 48 89 ef 5d 31 f6 e9 c6 ee ff ff 66 0f 1f 44 00 00 c
[12080.958470] RSP: 002b:00007ffc63537498 EFLAGS: 00000206
[12080.958705] RAX: 00007fcc23d61fe0 RBX: 00007ffc635374f8 RCX: 000055def9250c20
[12080.958987] RDX: 0000000000002000 RSI: 000055def9250c20 RDI: 000055def924c2a0
[12080.959236] RBP: 000055def9250c20 R08: 0000000000000000 R09: 0000000000000000
[12080.959511] R10: 000000000001c370 R11: 00007fcc23d60aa0 R12: 000055def924c2a0
[12080.959751] R13: 00007ffc635374f0 R14: 00007ffc635375c8 R15: 0000000000000002
[12080.960037] Sending NMI from CPU 2 to CPUs 3:
[12084.960115] NMI backtrace for cpu 3
[12084.960125] CPU: 3 PID: 0 Comm: swapper/3 Not tainted 5.14.18-yocto-standard #1
[12084.960129] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.14.0-0-g155821a1990b-4
[12084.960132] RIP: 0010:queued_spin_lock_slowpath+0x2d/0x1e0
[12084.960135] Code: 00 00 8b 05 85 26 57 01 55 48 89 fa 48 89 e5 85 c0 7e 19 b9 01 00 00 00 8b 02 8
[12084.960139] RSP: 0018:ffffa4a340128ee8 EFLAGS: 00000002
[12084.960153] RAX: 0000000000000001 RBX: 0000000000000009 RCX: 0000000000000001
[12084.960156] RDX: ffffffffb0cbf380 RSI: 0000000000000001 RDI: ffffffffb0cbf380
[12084.960158] RBP: ffffa4a340128ee8 R08: 0000000000000000 R09: 0000000000000101
[12084.960161] R10: ffff8b7d0124b4d0 R11: ffffffffb0c060d0 R12: 0000000000000246
[12084.960163] R13: ffffffffb0cbf380 R14: ffffffffb0cbf380 R15: ffff8b7d0f9ab730
[12084.960166] FS:  0000000000000000(0000) GS:ffff8b7d0f980000(0000) knlGS:0000000000000000
[12084.960169] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[12084.960171] CR2: 0000560a470e2658 CR3: 0000000003e92000 CR4: 00000000001506e0
[12084.960174] Call Trace:
[12084.960176]  <IRQ>
[12084.960178]  _raw_spin_lock_irqsave+0x33/0x40
[12084.960180]  rcu_core+0xcd/0x6e0
[12084.960182]  ? lapic_timer_shutdown+0x14/0x20
[12084.960184]  ? clockevents_switch_state+0x62/0xf0
[12084.960186]  rcu_core_si+0xe/0x10
[12084.960188]  __do_softirq+0xd2/0x2af
[12084.960191]  irq_exit_rcu+0x9a/0xb0
[12084.960193]  sysvec_apic_timer_interrupt+0x74/0x80
[12084.960195]  </IRQ>
[12084.960197]  asm_sysvec_apic_timer_interrupt+0x12/0x20
[12084.960199] RIP: 0010:default_idle+0x1c/0x20
[12084.960203] Code: cc cc cc cc cc cc cc cc cc cc cc cc cc cc 0f 1f 44 00 00 8b 05 5d a1 d7 00 55 0
[12084.960206] RSP: 0018:ffffa4a34009beb0 EFLAGS: 00000246
[12084.960211] RAX: 0000000000000000 RBX: 0000000000000003 RCX: 0000000000000000
[12084.960213] RDX: 0000000000006d26 RSI: 0000000000000087 RDI: ffff8b7d0124e800
[12084.960216] RBP: ffffa4a34009beb0 R08: ffff8b7d0f99df20 R09: 0000000000000001
[12084.960218] R10: 0000000000000000 R11: 0000000000000384 R12: ffff8b7d0124e800
[12084.960221] R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000000
[12084.960223]  arch_cpu_idle+0x15/0x20
[12084.960225]  default_idle_call+0x3b/0xf0
[12084.960228]  do_idle+0x1ee/0x260
[12084.960230]  cpu_startup_entry+0x20/0x30
[12084.960232]  start_secondary+0xf3/0x100
[12084.960234]  secondary_startup_64_no_verify+0xc2/0xcb
[12084.960253] rcu: rcu_preempt kthread starved for 22021 jiffies! g789 f0x0 RCU_GP_WAIT_FQS(5) ->s3
[12086.959947] rcu: 	1-...!: (1 GPs behind) idle=e72/0/0x1 softirq=824/850 fqs=0 
[12086.960064] rcu: 	Unless rcu_preempt kthread gets sufficient CPU time, OOM is now expected be.
[12086.960071] rcu: RCU grace-period kthread stack dump:
[12086.960076] task:rcu_preempt     state:R  running task    
[12088.960358] 	(t=28027 jiffies g=789 q=13)
[12088.960789]  stack:    0 pid:   14 ppid:     2 flags:0x00004000
[12090.960994] NMI backtrace for cpu 1
[12090.961731] Call Trace:
[12090.961753]  __schedule+0x205/0x610
[12092.961760] CPU: 1 PID: 0 Comm: swapper/1 Not tainted 5.14.18-yocto-standard #1
[12092.961760]  schedule+0x67/0xe0
[12094.961885] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.14.0-0-g155821a1990b-4
[12094.961885]  schedule_timeout+0x8f/0x160
[12096.962001] Call Trace:
[12096.962001]  ? __bpf_trace_tick_stop+0x10/0x10
[12098.962295]  <IRQ>
[12098.962567]  rcu_gp_kthread+0x74d/0x9a0
[12100.962786]  dump_stack_lvl+0x38/0x49
[12100.962786]  ? rcu_gp_cleanup+0x3d0/0x3d0
[12102.962617]  dump_stack+0x10/0x12
[12102.962617]  kthread+0x13b/0x160
[12104.962540]  nmi_cpu_backtrace.cold+0x32/0x6a
[12104.962540]  ? set_kthread_struct+0x50/0x50
[12106.962571]  ? lapic_can_unplug_cpu+0x80/0x80
[12106.962571]  ret_from_fork+0x22/0x30
[12108.962530]  nmi_trigger_cpumask_backtrace+0x95/0xb0
[12108.962530] rcu: Stack dump where RCU GP kthread last ran:
[12110.962662]  arch_trigger_cpumask_backtrace+0x19/0x20
[12110.974863]  rcu_dump_cpu_stacks+0xc2/0xf4
[12110.975075]  rcu_sched_clock_irq.cold+0x1e6/0x45e
[12110.975263]  ? timekeeping_advance+0x36f/0x580
[12110.975463]  update_process_times+0x94/0xd0
[12110.975611]  tick_sched_timer+0xb6/0xe0
[12110.975757]  ? can_stop_idle_tick+0xa0/0xa0
[12110.975909]  __hrtimer_run_queues+0x13d/0x2e0
[12110.976103]  hrtimer_interrupt+0xfe/0x290
[12110.976257]  __sysvec_apic_timer_interrupt+0x69/0x120
[12110.976460]  sysvec_apic_timer_interrupt+0x6f/0x80
[12110.976676]  </IRQ>
[12110.976763]  asm_sysvec_apic_timer_interrupt+0x12/0x20
[12110.976972] RIP: 0010:default_idle+0x1c/0x20
[12110.977181] Code: cc cc cc cc cc cc cc cc cc cc cc cc cc cc 0f 1f 44 00 00 8b 05 5d a1 d7 00 55 0
[12110.977885] RSP: 0018:ffffa4a34008beb0 EFLAGS: 00000246
[12110.978087] RAX: 0000000000000000 RBX: 0000000000000001 RCX: 0000000000000000
[12110.978315] RDX: 0000000000006e6e RSI: ffffa4a34008be50 RDI: ffff8b7d0124ce00
[12110.978658] RBP: ffffa4a34008beb0 R08: 0000000000000001 R09: 0000000000000002
[12110.978942] R10: 0000000000000001 R11: 0000000000000040 R12: ffff8b7d0124ce00
[12110.979183] R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000000
[12110.979526]  arch_cpu_idle+0x15/0x20
[12110.979733]  default_idle_call+0x3b/0xf0
[12110.979951]  do_idle+0x1ee/0x260
[12110.980090]  ? complete+0x3f/0x50
[12110.980255]  cpu_startup_entry+0x20/0x30
[12110.980411]  start_secondary+0xf3/0x100
[12110.980552]  secondary_startup_64_no_verify+0xc2/0xcb
[12110.980766] Sending NMI from CPU 1 to CPUs 2:
[12110.989414] NMI backtrace for cpu 2
[12110.989441] CPU: 2 PID: 306 Comm: klogd Not tainted 5.14.18-yocto-standard #1
[12110.989445] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.14.0-0-g155821a1990b-4
[12110.989448] RIP: 0010:queued_spin_lock_slowpath+0x2d/0x1e0
[12110.989451] Code: 00 00 8b 05 85 26 57 01 55 48 89 fa 48 89 e5 85 c0 7e 19 b9 01 00 00 00 8b 02 8
[12110.989456] RSP: 0000:ffffa4a340993ce0 EFLAGS: 00000002
[12110.989467] RAX: 0000000000000001 RBX: ffffffffb0cbf380 RCX: 0000000000000001
[12110.989470] RDX: ffffffffb0cbf380 RSI: 0000000000000001 RDI: ffffffffb0cbf380
[12110.989474] RBP: ffffa4a340993ce0 R08: ffffffffafddd280 R09: 0000000000000005
[12110.989476] R10: 000000003e515249 R11: 00000000492f3c20 R12: 0000000000000046
[12110.989479] R13: ffffffffb0cbf480 R14: ffffffffb0cbf380 R15: 0000000000000311
[12110.989482] FS:  00007fcc23b6f740(0000) GS:ffff8b7d0f900000(0000) knlGS:0000000000000000
[12110.989485] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[12110.989488] CR2: 00007f6c4e62f550 CR3: 0000000003e92000 CR4: 00000000001506e0
[12110.989490] Call Trace:
[12110.989493]  _raw_spin_lock_irqsave+0x33/0x40
[12110.989496]  rcu_force_quiescent_state+0x80/0xf0
[12110.989499]  rcu_sched_clock_irq.cold+0x2e3/0x45e
[12110.989502]  ? syslog_print+0x1bf/0x200
[12110.989505]  ? cpuacct_account_field+0x57/0x60
[12110.989507]  ? account_user_time+0xa1/0xb0
[12110.989510]  update_process_times+0x94/0xd0
[12110.989513]  tick_sched_timer+0xb6/0xe0
[12110.989516]  ? can_stop_idle_tick+0xa0/0xa0
[12110.989518]  __hrtimer_run_queues+0x13d/0x2e0
[12110.989521]  hrtimer_interrupt+0xfe/0x290
[12110.989523]  __sysvec_apic_timer_interrupt+0x69/0x120
[12110.989526]  sysvec_apic_timer_interrupt+0x36/0x80
[12110.989529]  ? asm_sysvec_apic_timer_interrupt+0xa/0x20
[12110.989532]  asm_sysvec_apic_timer_interrupt+0x12/0x20
[12110.989535] RIP: 0033:0x7fcc23bf7da0
[12110.989539] Code: 19 48 c7 45 38 00 00 00 00 48 89 ef 5d 31 f6 e9 c6 ee ff ff 66 0f 1f 44 00 00 c
[12110.989542] RSP: 002b:00007ffc63537498 EFLAGS: 00000206
[12110.989546] RAX: 00007fcc23d61fe0 RBX: 00007ffc635374f8 RCX: 000055def9250c20
[12110.989549] RDX: 0000000000002000 RSI: 000055def9250c20 RDI: 000055def924c2a0
[12110.989552] RBP: 000055def9250c20 R08: 0000000000000000 R09: 0000000000000000
[12110.989555] R10: 000000000001c370 R11: 00007fcc23d60aa0 R12: 000055def924c2a0
[12110.989558] R13: 00007ffc635374f0 R14: 00007ffc635375c8 R15: 0000000000000002
[12237.220806] rcu: INFO: rcu_preempt self-detected stall on CPU
[12243.220268] rcu: 	3-...0: (8 ticks this GP) idle=dda/0/0x3 softirq=627/627 fqs=2 
[12245.220178] 	(t=30000 jiffies g=813 q=2)
[12247.220079] NMI backtrace for cpu 3
[12249.219909] CPU: 3 PID: 0 Comm: swapper/3 Not tainted 5.14.18-yocto-standard #1
[12255.219319] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.14.0-0-g155821a1990b-4
[12257.219455] Call Trace:
[12257.219602]  <IRQ>
[12257.219690]  dump_stack_lvl+0x38/0x49
[12257.219842]  dump_stack+0x10/0x12
[12257.219963]  nmi_cpu_backtrace.cold+0x32/0x6a
[12257.220138]  ? lapic_can_unplug_cpu+0x80/0x80
[12257.220299]  nmi_trigger_cpumask_backtrace+0x95/0xb0
[12257.220525]  arch_trigger_cpumask_backtrace+0x19/0x20
[12257.220727]  rcu_dump_cpu_stacks+0xc2/0xf4
[12257.220882]  rcu_sched_clock_irq.cold+0x1e6/0x45e
[12257.221056]  ? cpuacct_account_field+0x57/0x60
[12257.221227]  ? account_system_index_time+0x91/0xa0
[12257.221424]  update_process_times+0x94/0xd0
[12257.221621]  tick_sched_timer+0xb6/0xe0
[12257.221936]  ? can_stop_idle_tick+0xa0/0xa0
[12257.222264]  __hrtimer_run_queues+0x13d/0x2e0
[12257.222609]  hrtimer_interrupt+0xfe/0x290
[12257.222937]  __sysvec_apic_timer_interrupt+0x69/0x120
[12257.223327]  sysvec_apic_timer_interrupt+0x36/0x80
[12257.223703]  asm_sysvec_apic_timer_interrupt+0x12/0x20
[12257.224102] RIP: 0010:load_balance+0x67e/0xa10
[12257.224473] Code: 39 c8 75 ca 48 8b 7d 80 e8 af 1b ff ff 8b 9d 58 ff ff ff 01 9d 40 ff ff ff 48 f
[12257.225784] RSP: 0018:ffffa4a340128de0 EFLAGS: 00000206
[12257.226195] RAX: 0000000000000000 RBX: ffff8b7d0127ae60 RCX: ffff8b7d0f900000
[12257.226718] RDX: 00000000ee62184d RSI: 000000004b81ff7d RDI: ffff8b7d0f92aa00
[12257.227242] RBP: ffffa4a340128ee8 R08: 00000b2044d0fbad R09: 0000000000000001
[12257.227773] R10: 0000000000001dcd R11: 0000000000000002 R12: 000000000002aa00
[12257.228254] R13: ffff8b7d0f92aa00 R14: ffff8b7d0f92aa00 R15: ffff8b7d0f997478
[12257.228828]  rebalance_domains+0x272/0x390
[12257.229173]  run_rebalance_domains+0x63/0x80
[12257.229479]  __do_softirq+0xd2/0x2af
[12257.229763]  irq_exit_rcu+0x9a/0xb0
[12257.230029]  sysvec_apic_timer_interrupt+0x74/0x80
[12257.230419]  </IRQ>
[12257.230585]  asm_sysvec_apic_timer_interrupt+0x12/0x20
[12257.230995] RIP: 0010:default_idle+0x1c/0x20
[12257.231355] Code: cc cc cc cc cc cc cc cc cc cc cc cc cc cc 0f 1f 44 00 00 8b 05 5d a1 d7 00 55 0
[12257.232134] RSP: 0018:ffffa4a34009beb0 EFLAGS: 00000246
[12257.232329] RAX: 0000000000000000 RBX: 0000000000000003 RCX: 0000000000000000
[12257.232583] RDX: 0000000000006dd6 RSI: ffffa4a34009be50 RDI: ffff8b7d0124e800
[12257.232824] RBP: ffffa4a34009beb0 R08: 0000000000000001 R09: 0000000000000000
[12257.233054] R10: 0000000000000000 R11: 0000000000000000 R12: ffff8b7d0124e800
[12257.233300] R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000000
[12257.233547]  arch_cpu_idle+0x15/0x20
[12257.233686]  default_idle_call+0x3b/0xf0
[12257.233832]  do_idle+0x1ee/0x260
[12257.233942]  cpu_startup_entry+0x20/0x30
[12257.234072]  start_secondary+0xf3/0x100
[12257.234196]  secondary_startup_64_no_verify+0xc2/0xcb
runqemu - ERROR - Failed to run qemu: qemu-system-x86_64: warning: TCG doesn't support requested fe]
qemu-system-x86_64: warning: TCG doesn't support requested feature: CPUID.01H:ECX.tsc-deadline [bit]
qemu-system-x86_64: warning: TCG doesn't support requested feature: CPUID.01H:ECX.avx [bit 28]
qemu-system-x86_64: warning: TCG doesn't support requested feature: CPUID.01H:ECX.f16c [bit 29]
qemu-system-x86_64: warning: TCG doesn't support requested feature: CPUID.01H:ECX.x2apic [bit 21]
qemu-system-x86_64: warning: TCG doesn't support requested feature: CPUID.01H:ECX.tsc-deadline [bit]
qemu-system-x86_64: warning: TCG doesn't support requested feature: CPUID.01H:ECX.avx [bit 28]
qemu-system-x86_64: warning: TCG doesn't support requested feature: CPUID.01H:ECX.f16c [bit 29]
qemu-system-x86_64: warning: TCG doesn't support requested feature: CPUID.01H:ECX.x2apic [bit 21]
qemu-system-x86_64: warning: TCG doesn't support requested feature: CPUID.01H:ECX.tsc-deadline [bit]
qemu-system-x86_64: warning: TCG doesn't support requested feature: CPUID.01H:ECX.avx [bit 28]
qemu-system-x86_64: warning: TCG doesn't support requested feature: CPUID.01H:ECX.f16c [bit 29]
qemu-system-x86_64: warning: TCG doesn't support requested feature: CPUID.01H:ECX.x2apic [bit 21]
qemu-system-x86_64: warning: TCG doesn't support requested feature: CPUID.01H:ECX.tsc-deadline [bit]
qemu-system-x86_64: warning: TCG doesn't support requested feature: CPUID.01H:ECX.avx [bit 28]
qemu-system-x86_64: warning: TCG doesn't support requested feature: CPUID.01H:ECX.f16c [bit 29]
Comment 2 akuster 2021-12-06 15:58:01 UTC
I wont have time to dig into as I am on holiday this week.

qemuarm64 works fine.
Comment 3 akuster 2021-12-06 15:59:59 UTC
runqemu qemux86-64 slirp

/home/akuster/oss/clean/poky_new/build/tmp/work/x86_64-linux/qemu-helper-native/1.0-r1/recipe-sysroot-native/usr/bin/qemu-system-x86_64 -device virtio-net-pci,netdev=net0,mac=52:54:00:12:35:02 -netdev user,id=net0,hostfwd=tcp::2222-:22,hostfwd=tcp::2323-:23,tftp=/home/akuster/oss/clean/poky_new/build/tmp/deploy/images/qemux86-64 -object rng-random,filename=/dev/urandom,id=rng0 -device virtio-rng-pci,rng=rng0 -drive file=/home/akuster/oss/clean/poky_new/build/tmp/deploy/images/qemux86-64/core-image-minimal-qemux86-64-20211206061235.rootfs.ext4,if=virtio,format=raw -usb -device usb-tablet   -cpu IvyBridge -machine q35 -smp 4 -m 256 -serial mon:vc -serial null  -kernel /home/akuster/oss/clean/poky_new/build/tmp/deploy/images/qemux86-64/bzImage--5.14.18+git0+4e79d0b092_8411a30747-r0-qemux86-64-20211206061235.bin -append 'root=/dev/vda rw  mem=256M ip=dhcp oprofile.timer=1 tsc=reliable no_timer_check rcupdate.rcu_expedited=1 '
Comment 4 Randy MacLeod 2021-12-09 15:46:04 UTC
Looks like an RCU stall.
What was the load on the system at the time?

Joshua pointed out that RCU stalls are common and there is a qemu option to extend the RCU stall maximum.

This log is odd, can you explain it:
    qemu-system-x86_64: warning: TCG doesn't support requested feature: \
    CPUID.01H:ECX.tsc-deadline [bit]
This looks like it's a mismatch between your AMD host and the intel vm.

Runqemu was recently changed to enable KVM by default. 
Try turning off KVM:
   qemu_use_kvm is the variable.
Comment 5 akuster 2021-12-11 14:06:59 UTC
(In reply to comment #4)
> Looks like an RCU stall.
> What was the load on the system at the time?

I was on my laptop and it was the only thing running. qemuarm64 works fine.

> 
> Joshua pointed out that RCU stalls are common and there is a qemu option to
> extend the RCU stall maximum.
> 
> This log is odd, can you explain it:
>     qemu-system-x86_64: warning: TCG doesn't support requested feature: \
>     CPUID.01H:ECX.tsc-deadline [bit]
> This looks like it's a mismatch between your AMD host and the intel vm.

yeah, that is the bit that concerns me.

> 
> Runqemu was recently changed to enable KVM by default. 
> Try turning off KVM:
>    qemu_use_kvm is the variable.

will give it a try.
Comment 6 akuster 2021-12-11 18:51:52 UTC
This report looks similar to what I am seeing.

https://gitlab.com/qemu-project/qemu/-/issues/164
Comment 7 Trevor Gamblin 2021-12-16 18:06:19 UTC
Can you clarify which AMD CPU you are using? I just tested a fresh build on a Ryzen 5700G-based system and it didn't have any issues booting.
Comment 8 akuster 2021-12-16 18:49:01 UTC
processor	: 15
vendor_id	: AuthenticAMD
cpu family	: 23
model		: 96
model name	: AMD Ryzen 7 PRO 4750U with Radeon Graphics
stepping	: 1
microcode	: 0x8600106
cpu MHz		: 1400.000
cache size	: 512 KB
physical id	: 0
siblings	: 16
core id		: 7
cpu cores	: 8
apicid		: 15
initial apicid	: 15
fpu		: yes
fpu_exception	: yes
cpuid level	: 16
wp		: yes
flags		: fpu vme de pse tsc msr pae mce cx8 apic sep mtrr pge mca cmov pat pse36 clflush mmx fxsr sse sse2 ht syscall nx mmxext fxsr_opt pdpe1gb rdtscp lm constant_tsc rep_good nopl nonstop_tsc cpuid extd_apicid aperfmperf pni pclmulqdq monitor ssse3 fma cx16 sse4_1 sse4_2 movbe popcnt aes xsave avx f16c rdrand lahf_lm cmp_legacy svm extapic cr8_legacy abm sse4a misalignsse 3dnowprefetch osvw ibs skinit wdt tce topoext perfctr_core perfctr_nb bpext perfctr_llc mwaitx cpb cat_l3 cdp_l3 hw_pstate ssbd mba ibrs ibpb stibp vmmcall fsgsbase bmi1 avx2 smep bmi2 cqm rdt_a rdseed adx smap clflushopt clwb sha_ni xsaveopt xsavec xgetbv1 xsaves cqm_llc cqm_occup_llc cqm_mbm_total cqm_mbm_local clzero irperf xsaveerptr rdpru wbnoinvd arat npt lbrv svm_lock nrip_save tsc_scale vmcb_clean flushbyasid decodeassists pausefilter pfthreshold avic v_vmsave_vmload vgif umip rdpid overflow_recov succor smca
bugs		: sysret_ss_attrs spectre_v1 spectre_v2 spec_store_bypass
bogomips	: 3393.52
TLB size	: 3072 4K pages
clflush size	: 64
cache_alignment	: 64
address sizes	: 48 bits physical, 48 bits virtual
power management: ts ttp tm hwpstate cpb eff_freq_ro [13] [14]
Comment 9 akuster 2021-12-16 18:50:17 UTC
this could be BIOS related. I did have to tweak it to get vmare working
Comment 10 akuster 2021-12-16 18:51:19 UTC
I even tried the ubnuntu qemu package and it failed too.
Comment 11 Randy MacLeod 2023-10-24 14:24:28 UTC
Bulk move from 4.99 or 0.00 to 5.99