virtme: waiting for virtiofsd to start virtme: use 'microvm' QEMU architecture [ 0.996912][ T1] Segment Routing with IPv6 [ 0.996954][ T1] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 0.996956][ T1] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 1, name: swapper/0 [ 0.996957][ T1] preempt_count: 1, expected: 0 [ 0.996958][ T1] RCU nest depth: 0, expected: 0 [ 0.996959][ T1] locks held by swapper/0/1: 4, last CPU#3: [ 0.996961][ T1] #0: ffffffffbbf99cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 0.996971][ T1] #1: ffffffffbbf99d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 0.996976][ T1] #2: ffffffffbbe89660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 0.996979][ T1] #3: ffffffffbbe89560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x202/0x4f0 [ 0.996983][ T1] irq event stamp: 317192 [ 0.996983][ T1] hardirqs last enabled at (317191): [] __down_trylock_console_sem+0x86/0xa0 [ 0.996986][ T1] hardirqs last disabled at (317192): [] console_emit_next_record+0x3f8/0x4f0 [ 0.996987][ T1] softirqs last enabled at (317098): [] __alloc_skb+0x4c5/0x5f0 [ 0.996991][ T1] softirqs last disabled at (317094): [] __alloc_skb+0x4c5/0x5f0 [ 0.996993][ T1] Preemption disabled at: [ 0.996993][ T1] [] vprintk_emit+0x31b/0x3e0 [ 0.996998][ T1] CPU: 3 UID: 0 PID: 1 Comm: swapper/0 Not tainted 7.2.0-virtme #1 PREEMPT(full) [ 0.997000][ T1] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 0.997002][ T1] Call Trace: [ 0.997004][ T1] [ 0.997007][ T1] dump_stack_lvl+0x6f/0xa0 [ 0.997012][ T1] ? vprintk_emit+0x31b/0x3e0 [ 0.997014][ T1] __might_resched.cold+0x1fe/0x2c1 [ 0.997019][ T1] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 0.997022][ T1] ? __kmalloc_noprof+0xdb/0x760 [ 0.997027][ T1] __kmalloc_noprof+0x443/0x760 [ 0.997028][ T1] ? alloc_buf.isra.0+0x4b/0x260 [ 0.997034][ T1] ? do_raw_spin_unlock+0x59/0x250 [ 0.997036][ T1] alloc_buf.isra.0+0x4b/0x260 [ 0.997038][ T1] put_chars+0x1e1/0x2f0 [ 0.997041][ T1] ? __send_to_port+0x420/0x420 [ 0.997042][ T1] ? printk_get_next_message+0x2fe/0x7d0 [ 0.997045][ T1] ? rcu_read_lock_any_held+0x3c/0x90 [ 0.997048][ T1] ? validate_chain+0x38b/0xc20 [ 0.997052][ T1] hvc_console_print+0x292/0x780 [ 0.997055][ T1] ? __lock_acquire+0x518/0xc20 [ 0.997056][ T1] ? __lock_acquire+0x518/0xc20 [ 0.997060][ T1] ? hvc_write+0x3a0/0x3a0 [ 0.997063][ T1] ? rcu_is_watching+0x16/0xd0 [ 0.997066][ T1] ? lock_acquire+0x13c/0x160 [ 0.997069][ T1] console_emit_next_record+0x252/0x4f0 [ 0.997072][ T1] ? devkmsg_read+0x4e0/0x4e0 [ 0.997076][ T1] ? rcu_is_watching+0x16/0xd0 [ 0.997078][ T1] ? lock_acquire+0x13c/0x160 [ 0.997081][ T1] console_flush_one_record+0x46f/0x710 [ 0.997085][ T1] ? console_emit_next_record+0x4f0/0x4f0 [ 0.997086][ T1] ? __lock_acquire+0x518/0xc20 [ 0.997091][ T1] console_unlock+0xee/0x1f0 [ 0.997093][ T1] ? console_flush_one_record+0x710/0x710 [ 0.997095][ T1] ? rcu_is_watching+0x16/0xd0 [ 0.997097][ T1] ? lock_acquire+0x60/0x160 [ 0.997100][ T1] ? __down_trylock_console_sem+0x5e/0xa0 [ 0.997101][ T1] ? vprintk_emit+0x320/0x3e0 [ 0.997104][ T1] vprintk_emit+0x37c/0x3e0 [ 0.997107][ T1] ? wake_up_klogd_work_func+0x90/0x90 [ 0.997112][ T1] ? unix_bpf_build_proto+0x140/0x140 [ 0.997116][ T1] _printk+0xc7/0x100 [ 0.997119][ T1] ? snapshot_read.cold+0x21/0x21 [ 0.997122][ T1] ? genl_unregister_family+0x300/0x300 [ 0.997126][ T1] ? unix_bpf_build_proto+0x140/0x140 [ 0.997127][ T1] ? __up_write+0x23d/0x5f0 [ 0.997132][ T1] seg6_init+0x48/0x50 [ 0.997134][ T1] inet6_init+0x2c9/0x3c0 [ 0.997136][ T1] do_one_initcall+0x124/0x4f0 [ 0.997140][ T1] ? trace_event_raw_event_initcall_level+0x280/0x280 [ 0.997142][ T1] ? parameq+0x110/0x110 [ 0.997146][ T1] ? kernel_init_freeable+0x3f1/0x630 [ 0.997150][ T1] ? rcu_is_watching+0x16/0xd0 [ 0.997153][ T1] kernel_init_freeable+0x596/0x630 [ 0.997155][ T1] ? rest_init+0x280/0x280 [ 0.997160][ T1] kernel_init+0x21/0x150 [ 0.997162][ T1] ? rest_init+0x280/0x280 [ 0.997164][ T1] ret_from_fork+0x474/0x6b0 [ 0.997169][ T1] ? arch_exit_to_user_mode_prepare.isra.0+0x120/0x120 [ 0.997172][ T1] ? __switch_to+0x5a3/0xe00 [ 0.997175][ T1] ? rest_init+0x280/0x280 [ 0.997177][ T1] ret_from_fork_asm+0x11/0x20 [ 0.997184][ T1] [ 1.013270][ T1] In-situ OAM (IOAM) with IPv6 [ 1.013678][ T1] NET: Registered PF_PACKET protocol family [ 1.013925][ T1] 9pnet: Installing 9P2000 support [ 1.014372][ T1] Key type dns_resolver registered [ 1.015335][ T1] NET: Registered PF_VSOCK protocol family [ 1.020795][ T1] IPI shorthand broadcast: enabled [ 1.118300][ T1] sched_clock: Marking stable (1087001679, 31187454)->(1190098465, -71909332) [ 1.121275][ T1] registered taskstats version 1 [ 1.123000][ T1] Loading compiled-in X.509 certificates [ 1.220651][ T1] Demotion targets for Node 0: null [ 1.221003][ T1] kmemleak: Kernel memory leak detector initialized (mem pool available: 11718) [ 1.221280][ T1] page_owner is disabled [ 1.222287][ T1] PM: Magic number: 6:288:6 [ 1.223706][ T1] ALSA device list: [ 1.223850][ T1] No soundcards found. [ 1.225694][ T1] md: Skipping autodetection of RAID arrays. (raid=autodetect will force) [ 1.228401][ T1] VFS: Mounted root (virtiofs filesystem) readonly on device 0:24. [ 1.229223][ T1] devtmpfs: mounted [ 1.229490][ T1] VFS: Pivoted into new rootfs [ 1.256506][ T1] Freeing unused kernel image (initmem) memory: 2540K [ 1.256737][ T1] Write protecting the kernel read-only data: 55296k [ 1.257172][ T1] Freeing unused kernel image (text/rodata gap) memory: 296K [ 1.259287][ T1] Freeing unused kernel image (rodata/data gap) memory: 324K [ 1.259540][ T1] Run /usr/lib/python3.14/site-packages/virtme/guest/bin/virtme-ng-init as init process [ 1.259785][ T1] with arguments: [ 1.259899][ T1] /usr/lib/python3.14/site-packages/virtme/guest/bin/virtme-ng-init [ 1.260116][ T1] with environment: [ 1.260232][ T1] HOME=/ [ 1.260344][ T1] TERM=dumb [ 1.260447][ T1] virtme_hostname=vmksft-netdevsim-dbg,debug-threads=on [ 1.260660][ T1] nr_open=2147483584 [ 1.260767][ T1] virtme_link_mods=/srv/vmksft/testing/wt-14/.virtme_mods/lib/modules/0.0.0 [ 1.261015][ T1] virtme_rw_overlay0=/etc [ 1.261155][ T1] virtme_rw_overlay1=/lib [ 1.261299][ T1] virtme_rw_overlay2=/home [ 1.261442][ T1] virtme_rw_overlay3=/opt [ 1.261579][ T1] virtme_rw_overlay4=/srv [ 1.261720][ T1] virtme_rw_overlay5=/usr [ 1.261862][ T1] virtme_rw_overlay6=/var [ 1.262008][ T1] virtme_rw_overlay7=/tmp [ 1.262145][ T1] virtme_console=ttyS0 [ 1.262290][ T1] virtme_chdir=srv/vmksft/testing/wt-14 [ 1.277866][ T1] virtme-ng-init: mount devtmpfs -> /dev: EBUSY: Device or resource busy [ 1.279472][ T1] virtme-ng-init: Setting hostname to vmksft-netdevsim-dbg,debug-threads=on... [ 1.289812][ T1] overlayfs: failed to set xattr on upper [ 1.290117][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.290452][ T1] overlayfs: ...falling back to uuid=null. [ 1.292703][ T1] overlayfs: failed to set xattr on upper [ 1.292925][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.293204][ T1] overlayfs: ...falling back to uuid=null. [ 1.295063][ T1] overlayfs: failed to set xattr on upper [ 1.295372][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.295644][ T1] overlayfs: ...falling back to uuid=null. [ 1.297564][ T1] overlayfs: failed to set xattr on upper [ 1.297789][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.298059][ T1] overlayfs: ...falling back to uuid=null. [ 1.299993][ T1] overlayfs: failed to set xattr on upper [ 1.300756][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.301068][ T1] overlayfs: ...falling back to uuid=null. [ 1.302729][ T1] overlayfs: failed to set xattr on upper [ 1.302951][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.303240][ T1] overlayfs: ...falling back to uuid=null. [ 1.305086][ T1] overlayfs: failed to set xattr on upper [ 1.305531][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.305803][ T1] overlayfs: ...falling back to uuid=null. [ 1.307685][ T1] overlayfs: failed to set xattr on upper [ 1.307916][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.308211][ T1] overlayfs: ...falling back to uuid=null. [ 1.318777][ T1] virtme-ng-init: running systemd-tmpfiles [ 1.386913][ T75] kwatchdog (75) used greatest stack depth: 29688 bytes left [ 3.269749][ T74] systemd-tmpfile (74) used greatest stack depth: 24992 bytes left [ 3.269766][ T74] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 3.269769][ T74] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 74, name: systemd-tmpfile [ 3.269770][ T74] preempt_count: 2, expected: 0 [ 3.269771][ T74] RCU nest depth: 0, expected: 0 [ 3.269772][ T74] locks held by systemd-tmpfile/74: 5, last CPU#3: [ 3.269774][ T74] #0: ffffffffbbe027b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 3.269787][ T74] #1: ffffffffbbf99cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 3.269792][ T74] #2: ffffffffbbf99d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 3.269796][ T74] #3: ffffffffbbe89660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 3.269799][ T74] #4: ffffffffbbe89560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x202/0x4f0 [ 3.269802][ T74] irq event stamp: 2264800 [ 3.269803][ T74] hardirqs last enabled at (2264799): [] __down_trylock_console_sem+0x86/0xa0 [ 3.269805][ T74] hardirqs last disabled at (2264800): [] console_emit_next_record+0x3f8/0x4f0 [ 3.269807][ T74] softirqs last enabled at (2264410): [] handle_softirqs+0x67c/0x900 [ 3.269809][ T74] softirqs last disabled at (2264405): [] __irq_exit_rcu+0x145/0x1c0 [ 3.269812][ T74] Preemption disabled at: [ 3.269813][ T74] [<0000000000000000>] 0x0 [ 3.269820][ T74] CPU: 3 UID: 0 PID: 74 Comm: systemd-tmpfile Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 3.269823][ T74] Tainted: [W]=WARN [ 3.269824][ T74] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 3.269826][ T74] Call Trace: [ 3.269828][ T74] [ 3.269829][ T74] dump_stack_lvl+0x6f/0xa0 [ 3.269835][ T74] __might_resched.cold+0x1fe/0x2c1 [ 3.269840][ T74] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 3.269843][ T74] ? __kmalloc_noprof+0xdb/0x760 [ 3.269848][ T74] __kmalloc_noprof+0x443/0x760 [ 3.269850][ T74] ? alloc_buf.isra.0+0x4b/0x260 [ 3.269856][ T74] ? do_raw_spin_unlock+0x59/0x250 [ 3.269859][ T74] alloc_buf.isra.0+0x4b/0x260 [ 3.269862][ T74] put_chars+0x1e1/0x2f0 [ 3.269864][ T74] ? __send_to_port+0x420/0x420 [ 3.269866][ T74] ? printk_get_next_message+0x2fe/0x7d0 [ 3.269869][ T74] ? rcu_read_lock_any_held+0x3c/0x90 [ 3.269872][ T74] ? validate_chain+0x38b/0xc20 [ 3.269876][ T74] hvc_console_print+0x292/0x780 [ 3.269879][ T74] ? __lock_acquire+0x518/0xc20 [ 3.269880][ T74] ? __lock_acquire+0x518/0xc20 [ 3.269884][ T74] ? hvc_write+0x3a0/0x3a0 [ 3.269887][ T74] ? rcu_is_watching+0x16/0xd0 [ 3.269889][ T74] ? lock_acquire+0x13c/0x160 [ 3.269893][ T74] console_emit_next_record+0x252/0x4f0 [ 3.269896][ T74] ? devkmsg_read+0x4e0/0x4e0 [ 3.269900][ T74] ? rcu_is_watching+0x16/0xd0 [ 3.269907][ T74] ? lock_acquire+0x13c/0x160 [ 3.269910][ T74] console_flush_one_record+0x46f/0x710 [ 3.269914][ T74] ? console_emit_next_record+0x4f0/0x4f0 [ 3.269915][ T74] ? __lock_acquire+0x518/0xc20 [ 3.269920][ T74] console_unlock+0xee/0x1f0 [ 3.269923][ T74] ? console_flush_one_record+0x710/0x710 [ 3.269924][ T74] ? rcu_is_watching+0x16/0xd0 [ 3.269926][ T74] ? lock_acquire+0x60/0x160 [ 3.269929][ T74] ? __down_trylock_console_sem+0x5e/0xa0 [ 3.269931][ T74] ? vprintk_emit+0x320/0x3e0 [ 3.269934][ T74] vprintk_emit+0x37c/0x3e0 [ 3.269937][ T74] ? wake_up_klogd_work_func+0x90/0x90 [ 3.269940][ T74] ? __lock_acquire+0x518/0xc20 [ 3.269944][ T74] _printk+0xc7/0x100 [ 3.269947][ T74] ? snapshot_read.cold+0x21/0x21 [ 3.269950][ T74] ? do_raw_spin_lock+0x131/0x280 [ 3.269951][ T1] virtme-ng-init: Failed to read '/usr/lib/tmpfiles.d/audit.conf': Permission denied [ 3.269951][ T1] Failed to create directory or subvolume "/var/spool/at/spool": Permission denied [ 3.269951][ T1] Failed to create file /var/spool/at/.SEQ: Permission denied [ 3.269951][ T1] Failed to opendir() '/proc/self/fd/6': Permission denied [ 3.269952][ T74] ? __rwlock_init+0x150/0x150 [ 3.269956][ T74] ? do_raw_spin_lock+0x131/0x280 [ 3.269958][ T74] do_exit.cold+0x82/0x9c [ 3.269962][ T74] ? exit_notify+0x890/0x890 [ 3.269963][ T74] ? __lock_release.isra.0+0x69/0x1a0 [ 3.269966][ T74] ? rcu_is_watching+0x16/0xd0 [ 3.269970][ T74] do_group_exit+0xb8/0x370 [ 3.269973][ T74] __x64_sys_exit_group+0x3c/0x50 [ 3.269975][ T74] x64_sys_call+0x1567/0x1570 [ 3.269977][ T74] do_syscall_64+0xff/0x530 [ 3.269980][ T74] ? exc_page_fault+0xee/0x100 [ 3.269984][ T74] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 3.269986][ T74] RIP: 0033:0x7f8ec14911b8 [ 3.269988][ T74] Code: Unable to access opcode bytes at 0x7f8ec149118e. [ 3.269989][ T74] RSP: 002b:00007fff56bb2398 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 3.269991][ T74] RAX: ffffffffffffffda RBX: 00007f8ec15c1f88 RCX: 00007f8ec14911b8 [ 3.269993][ T74] RDX: 00007f8ec0cef4c8 RSI: fffffffffffffe90 RDI: 0000000000000049 [ 3.269994][ T74] RBP: 00007fff56bb23f0 R08: 0000000000000000 R09: 0000000000001000 [ 3.269994][ T74] R10: 00007fff56bb21b0 R11: 0000000000000246 R12: 0000000000000001 [ 3.269995][ T74] R13: 0000000000000049 R14: 00007f8ec15c0680 R15: 00007f8ec15c1fa0 [ 3.270002][ T74] [ 3.291878][ T1] virtme-ng-init: basic initialization done [ 3.305449][ T77] virtme-ng-init (77) used greatest stack depth: 24976 bytes left [ 3.332528][ T80] ip (80) used greatest stack depth: 23944 bytes left [ 3.348796][ T76] virtme-ng-init: Starting systemd-udevd version 259.8-1.fc44 [ 3.349166][ T76] virtme-ng-init: triggering udev coldplug [ 5.312925][ T76] virtme-ng-init: waiting for udev to settle [ 5.312939][ T76] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 5.312942][ T76] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 76, name: virtme-ng-init [ 5.312943][ T76] preempt_count: 1, expected: 0 [ 5.312944][ T76] RCU nest depth: 0, expected: 0 [ 5.312945][ T76] locks held by virtme-ng-init/76: 4, last CPU#1: [ 5.312948][ T76] #0: ffffffffbbf99cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 5.312960][ T76] #1: ffffffffbbf99d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 5.312964][ T76] #2: ffffffffbbe89660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 5.312968][ T76] #3: ffffffffbbe89560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x202/0x4f0 [ 5.312971][ T76] irq event stamp: 6746 [ 5.312972][ T76] hardirqs last enabled at (6745): [] __down_trylock_console_sem+0x86/0xa0 [ 5.312974][ T76] hardirqs last disabled at (6746): [] console_emit_next_record+0x3f8/0x4f0 [ 5.312976][ T76] softirqs last enabled at (2878): [] handle_softirqs+0x67c/0x900 [ 5.312980][ T76] softirqs last disabled at (2871): [] __irq_exit_rcu+0x145/0x1c0 [ 5.312983][ T76] Preemption disabled at: [ 5.312984][ T76] [] vprintk_emit+0x31b/0x3e0 [ 5.312989][ T76] CPU: 1 UID: 0 PID: 76 Comm: virtme-ng-init Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 5.312993][ T76] Tainted: [W]=WARN [ 5.312994][ T76] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 5.312996][ T76] Call Trace: [ 5.312997][ T76] [ 5.312999][ T76] dump_stack_lvl+0x6f/0xa0 [ 5.313004][ T76] ? vprintk_emit+0x31b/0x3e0 [ 5.313006][ T76] __might_resched.cold+0x1fe/0x2c1 [ 5.313011][ T76] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 5.313014][ T76] ? __kmalloc_noprof+0xdb/0x760 [ 5.313019][ T76] __kmalloc_noprof+0x443/0x760 [ 5.313021][ T76] ? alloc_buf.isra.0+0x4b/0x260 [ 5.313027][ T76] ? do_raw_spin_unlock+0x59/0x250 [ 5.313029][ T76] alloc_buf.isra.0+0x4b/0x260 [ 5.313032][ T76] put_chars+0x1e1/0x2f0 [ 5.313035][ T76] ? __send_to_port+0x420/0x420 [ 5.313036][ T76] ? printk_get_next_message+0x2fe/0x7d0 [ 5.313040][ T76] ? rcu_read_lock_any_held+0x3c/0x90 [ 5.313043][ T76] ? validate_chain+0x38b/0xc20 [ 5.313047][ T76] hvc_console_print+0x292/0x780 [ 5.313049][ T76] ? __lock_acquire+0x518/0xc20 [ 5.313051][ T76] ? __lock_acquire+0x518/0xc20 [ 5.313055][ T76] ? hvc_write+0x3a0/0x3a0 [ 5.313057][ T76] ? rcu_is_watching+0x16/0xd0 [ 5.313060][ T76] ? lock_acquire+0x13c/0x160 [ 5.313064][ T76] console_emit_next_record+0x252/0x4f0 [ 5.313067][ T76] ? devkmsg_read+0x4e0/0x4e0 [ 5.313071][ T76] ? rcu_is_watching+0x16/0xd0 [ 5.313073][ T76] ? lock_acquire+0x13c/0x160 [ 5.313077][ T76] console_flush_one_record+0x46f/0x710 [ 5.313081][ T76] ? console_emit_next_record+0x4f0/0x4f0 [ 5.313082][ T76] ? __lock_acquire+0x518/0xc20 [ 5.313087][ T76] console_unlock+0xee/0x1f0 [ 5.313089][ T76] ? console_flush_one_record+0x710/0x710 [ 5.313091][ T76] ? rcu_is_watching+0x16/0xd0 [ 5.313093][ T76] ? lock_acquire+0x60/0x160 [ 5.313096][ T76] ? __down_trylock_console_sem+0x5e/0xa0 [ 5.313098][ T76] ? vprintk_emit+0x320/0x3e0 [ 5.313101][ T76] vprintk_emit+0x37c/0x3e0 [ 5.313104][ T76] ? wake_up_klogd_work_func+0x90/0x90 [ 5.313107][ T76] ? _copy_from_iter+0x1bb/0x1810 [ 5.313112][ T76] devkmsg_emit.constprop.0+0xbc/0xf1 [ 5.313116][ T76] ? vprintk_emit.cold+0x107/0x107 [ 5.313118][ T76] ? simple_strntoull+0x10f/0x140 [ 5.313122][ T76] ? date_str+0x1e0/0x1e0 [ 5.313124][ T76] ? devkmsg_write+0xd1/0x2c0 [ 5.313128][ T76] devkmsg_write.cold+0x5a/0x8b [ 5.313131][ T76] ? vprintk_default+0x20/0x20 [ 5.313135][ T76] ? vprintk_default+0x20/0x20 [ 5.313137][ T76] new_sync_write+0x33e/0x760 [ 5.313141][ T76] ? kasan_quarantine_put+0x102/0x2b0 [ 5.313145][ T76] ? new_sync_read+0x750/0x750 [ 5.313148][ T76] ? __lock_release.isra.0+0x69/0x1a0 [ 5.313153][ T76] ? __fget_files+0x1e3/0x460 [ 5.313157][ T76] vfs_write+0x6a2/0xbd0 [ 5.313161][ T76] ksys_write+0x116/0x250 [ 5.313164][ T76] ? __ia32_sys_read+0xc0/0xc0 [ 5.313166][ T76] ? rcu_is_watching+0x16/0xd0 [ 5.313170][ T76] do_syscall_64+0xff/0x530 [ 5.313172][ T76] ? exc_page_fault+0xee/0x100 [ 5.313175][ T76] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 5.313177][ T76] RIP: 0033:0x7fccfececed2 [ 5.313181][ T76] Code: 08 0f 85 51 ec ff ff 49 89 fb 48 89 f0 48 89 d7 48 89 ce 4c 89 c2 4d 89 ca 4c 8b 44 24 08 4c 8b 4c 24 10 4c 89 5c 24 08 0f 05 66 2e 0f 1f 84 00 00 00 00 00 0f 1f 00 f3 0f 1e fa 55 48 89 e5 [ 5.313183][ T76] RSP: 002b:00007fccfec33c68 EFLAGS: 00000246 ORIG_RAX: 0000000000000001 [ 5.313188][ T76] RAX: ffffffffffffffda RBX: 000000000000002e RCX: 00007fccfececed2 [ 5.313189][ T76] RDX: 000000000000002e RSI: 00007fccf80012c0 RDI: 0000000000000003 [ 5.313190][ T76] RBP: 00007fccfec33c90 R08: 0000000000000000 R09: 0000000000000000 [ 5.313191][ T76] R10: 0000000000000000 R11: 0000000000000246 R12: 00007fccfed770a0 [ 5.313192][ T76] R13: 00007fccfec753e0 R14: 00007fccf80012c0 R15: 00007fccfec33da8 [ 5.313198][ T76] [ 5.880124][ T76] virtme-ng-init: udev is done [ 5.883502][ T1] virtme-ng-init: initialization done [ 7.883557][ C0] clocksource: Watchdog remote CPU 2 read timed out [ 7.883595][ C0] [ 7.883596][ C0] ======================================================== [ 7.883597][ C0] WARNING: possible irq lock inversion dependency detected [ 7.883600][ C0] 7.2.0-virtme #1 Tainted: G W [ 7.883601][ C0] -------------------------------------------------------- [ 7.883602][ C0] swapper/0/0 just changed the state of lock: [ 7.883603][ C0] ffffffffbbe89660 (console_owner){..-.}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 7.883614][ C0] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 7.883615][ C0] (fs_reclaim){+.+.}-{0:0} [ 7.883617][ C0] [ 7.883617][ C0] [ 7.883617][ C0] and interrupts could create inverse lock ordering between them. [ 7.883617][ C0] [ 7.883617][ C0] [ 7.883617][ C0] other info that might help us debug this: [ 7.883618][ C0] Possible interrupt unsafe locking scenario: [ 7.883618][ C0] [ 7.883619][ C0] CPU0 CPU1 [ 7.883619][ C0] ---- ---- [ 7.883619][ C0] lock(fs_reclaim); [ 7.883620][ C0] local_irq_disable(); [ 7.883621][ C0] lock(console_owner); [ 7.883622][ C0] lock(fs_reclaim); [ 7.883623][ C0] [ 7.883623][ C0] lock(console_owner); [ 7.883624][ C0] [ 7.883624][ C0] *** DEADLOCK *** [ 7.883624][ C0] [ 7.883624][ C0] locks held by swapper/0/0: 4, last CPU#0: [ 7.883625][ C0] #0: ffa0000000007c90 ((&watchdog_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 7.883631][ C0] #1: ffffffffbbffe8b8 (watchdog_lock){+.-.}-{3:3}, at: clocksource_watchdog+0x15/0x30 [ 7.883634][ C0] #2: ffffffffbbf99cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 7.883637][ C0] #3: ffffffffbbf99d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 7.883640][ C0] [ 7.883640][ C0] the shortest dependencies between 2nd lock and 1st lock: [ 7.883645][ C0] -> (fs_reclaim){+.+.}-{0:0} { [ 7.883647][ C0] HARDIRQ-ON-W at: [ 7.883648][ C0] __lock_acquire+0x388/0xc20 [ 7.883651][ C0] lock_acquire.part.0+0xd4/0x280 [ 7.883652][ C0] fs_reclaim_acquire+0xd5/0x120 [ 7.883655][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 7.883657][ C0] kthread_create_worker_on_node+0xea/0x210 [ 7.883660][ C0] workqueue_init+0x2a/0x680 [ 7.883663][ C0] kernel_init_freeable+0x2fe/0x630 [ 7.883665][ C0] kernel_init+0x21/0x150 [ 7.883669][ C0] ret_from_fork+0x474/0x6b0 [ 7.883673][ C0] ret_from_fork_asm+0x11/0x20 [ 7.883675][ C0] SOFTIRQ-ON-W at: [ 7.883676][ C0] __lock_acquire+0x388/0xc20 [ 7.883677][ C0] lock_acquire.part.0+0xd4/0x280 [ 7.883678][ C0] fs_reclaim_acquire+0xd5/0x120 [ 7.883680][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 7.883681][ C0] kthread_create_worker_on_node+0xea/0x210 [ 7.883682][ C0] workqueue_init+0x2a/0x680 [ 7.883683][ C0] kernel_init_freeable+0x2fe/0x630 [ 7.883684][ C0] kernel_init+0x21/0x150 [ 7.883685][ C0] ret_from_fork+0x474/0x6b0 [ 7.883687][ C0] ret_from_fork_asm+0x11/0x20 [ 7.883688][ C0] INITIAL USE at: [ 7.883688][ C0] __lock_acquire+0x388/0xc20 [ 7.883690][ C0] lock_acquire.part.0+0xd4/0x280 [ 7.883691][ C0] fs_reclaim_acquire+0xd5/0x120 [ 7.883692][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 7.883693][ C0] kthread_create_worker_on_node+0xea/0x210 [ 7.883694][ C0] workqueue_init+0x2a/0x680 [ 7.883695][ C0] kernel_init_freeable+0x2fe/0x630 [ 7.883696][ C0] kernel_init+0x21/0x150 [ 7.883698][ C0] ret_from_fork+0x474/0x6b0 [ 7.883699][ C0] ret_from_fork_asm+0x11/0x20 [ 7.883700][ C0] } [ 7.883700][ C0] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 7.883704][ C0] ... acquired at: [ 7.883705][ C0] __lock_acquire+0x518/0xc20 [ 7.883706][ C0] lock_acquire.part.0+0xd4/0x280 [ 7.883707][ C0] fs_reclaim_acquire+0xd5/0x120 [ 7.883709][ C0] __kmalloc_noprof+0xd3/0x760 [ 7.883710][ C0] alloc_buf.isra.0+0x4b/0x260 [ 7.883712][ C0] put_chars+0x1e1/0x2f0 [ 7.883714][ C0] hvc_console_print+0x292/0x780 [ 7.883715][ C0] console_emit_next_record+0x252/0x4f0 [ 7.883716][ C0] console_flush_one_record+0x46f/0x710 [ 7.883718][ C0] console_unlock+0xee/0x1f0 [ 7.883719][ C0] vprintk_emit+0x37c/0x3e0 [ 7.883720][ C0] _printk+0xc7/0x100 [ 7.883723][ C0] seg6_init+0x48/0x50 [ 7.883726][ C0] inet6_init+0x2c9/0x3c0 [ 7.883727][ C0] do_one_initcall+0x124/0x4f0 [ 7.883729][ C0] kernel_init_freeable+0x596/0x630 [ 7.883730][ C0] kernel_init+0x21/0x150 [ 7.883731][ C0] ret_from_fork+0x474/0x6b0 [ 7.883733][ C0] ret_from_fork_asm+0x11/0x20 [ 7.883734][ C0] [ 7.883734][ C0] -> (console_owner){..-.}-{0:0} { [ 7.883736][ C0] IN-SOFTIRQ-W at: [ 7.883736][ C0] __lock_acquire+0x388/0xc20 [ 7.883737][ C0] lock_acquire.part.0+0xd4/0x280 [ 7.883738][ C0] console_lock_spinning_enable+0x5c/0x60 [ 7.883740][ C0] console_emit_next_record+0x1f4/0x4f0 [ 7.883741][ C0] console_flush_one_record+0x46f/0x710 [ 7.883742][ C0] console_unlock+0xee/0x1f0 [ 7.883743][ C0] vprintk_emit+0x37c/0x3e0 [ 7.883745][ C0] _printk+0xc7/0x100 [ 7.883746][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 7.883748][ C0] call_timer_fn+0x160/0x4d0 [ 7.883749][ C0] __run_timers+0x68f/0xaa0 [ 7.883751][ C0] run_timer_softirq+0xf0/0x160 [ 7.883752][ C0] handle_softirqs+0x1d3/0x900 [ 7.883755][ C0] __irq_exit_rcu+0x145/0x1c0 [ 7.883756][ C0] irq_exit_rcu+0xe/0x30 [ 7.883758][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 7.883760][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 7.883762][ C0] pv_native_safe_halt+0xf/0x10 [ 7.883763][ C0] default_idle+0x9/0x10 [ 7.883764][ C0] default_idle_call+0x6e/0xb0 [ 7.883766][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 7.883769][ C0] do_idle+0xd8/0x190 [ 7.883770][ C0] cpu_startup_entry+0x53/0x70 [ 7.883772][ C0] rest_init+0x279/0x280 [ 7.883773][ C0] start_kernel+0x3af/0x3b0 [ 7.883774][ C0] x86_64_start_reservations+0x24/0x30 [ 7.883776][ C0] x86_64_start_kernel+0x12b/0x130 [ 7.883778][ C0] common_startup_64+0x13e/0x148 [ 7.883779][ C0] INITIAL USE at: [ 7.883780][ C0] } [ 7.883781][ C0] ... key at: [] console_owner_dep_map+0x0/0x60 [ 7.883783][ C0] ... acquired at: [ 7.883784][ C0] mark_lock+0x1d7/0xa00 [ 7.883785][ C0] mark_usage+0x42/0x170 [ 7.883786][ C0] __lock_acquire+0x388/0xc20 [ 7.883787][ C0] lock_acquire.part.0+0xd4/0x280 [ 7.883788][ C0] console_lock_spinning_enable+0x5c/0x60 [ 7.883789][ C0] console_emit_next_record+0x1f4/0x4f0 [ 7.883791][ C0] console_flush_one_record+0x46f/0x710 [ 7.883792][ C0] console_unlock+0xee/0x1f0 [ 7.883793][ C0] vprintk_emit+0x37c/0x3e0 [ 7.883794][ C0] _printk+0xc7/0x100 [ 7.883796][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 7.883797][ C0] call_timer_fn+0x160/0x4d0 [ 7.883798][ C0] __run_timers+0x68f/0xaa0 [ 7.883799][ C0] run_timer_softirq+0xf0/0x160 [ 7.883800][ C0] handle_softirqs+0x1d3/0x900 [ 7.883802][ C0] __irq_exit_rcu+0x145/0x1c0 [ 7.883803][ C0] irq_exit_rcu+0xe/0x30 [ 7.883805][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 7.883806][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 7.883807][ C0] pv_native_safe_halt+0xf/0x10 [ 7.883809][ C0] default_idle+0x9/0x10 [ 7.883810][ C0] default_idle_call+0x6e/0xb0 [ 7.883812][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 7.883813][ C0] do_idle+0xd8/0x190 [ 7.883815][ C0] cpu_startup_entry+0x53/0x70 [ 7.883816][ C0] rest_init+0x279/0x280 [ 7.883818][ C0] start_kernel+0x3af/0x3b0 [ 7.883819][ C0] x86_64_start_reservations+0x24/0x30 [ 7.883820][ C0] x86_64_start_kernel+0x12b/0x130 [ 7.883821][ C0] common_startup_64+0x13e/0x148 [ 7.883822][ C0] [ 7.883823][ C0] [ 7.883823][ C0] stack backtrace: [ 7.883825][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 7.883828][ C0] Tainted: [W]=WARN [ 7.883829][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 7.883831][ C0] Call Trace: [ 7.883832][ C0] [ 7.883833][ C0] dump_stack_lvl+0x6f/0xa0 [ 7.883837][ C0] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 7.883839][ C0] mark_lock_irq+0x989/0x9c0 [ 7.883840][ C0] ? add_lock_to_list+0x2c/0x1a0 [ 7.883844][ C0] mark_lock+0x1d7/0xa00 [ 7.883845][ C0] mark_usage+0x42/0x170 [ 7.883846][ C0] __lock_acquire+0x388/0xc20 [ 7.883848][ C0] lock_acquire.part.0+0xd4/0x280 [ 7.883850][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 7.883851][ C0] ? rcu_is_watching+0x16/0xd0 [ 7.883854][ C0] ? lock_acquire+0x13c/0x160 [ 7.883856][ C0] console_lock_spinning_enable+0x5c/0x60 [ 7.883857][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 7.883858][ C0] console_emit_next_record+0x1f4/0x4f0 [ 7.883860][ C0] ? devkmsg_read+0x4e0/0x4e0 [ 7.883862][ C0] ? rcu_is_watching+0x16/0xd0 [ 7.883864][ C0] ? lock_acquire+0x13c/0x160 [ 7.883865][ C0] console_flush_one_record+0x46f/0x710 [ 7.883867][ C0] ? console_emit_next_record+0x4f0/0x4f0 [ 7.883869][ C0] ? __lock_acquire+0x518/0xc20 [ 7.883871][ C0] console_unlock+0xee/0x1f0 [ 7.883872][ C0] ? console_flush_one_record+0x710/0x710 [ 7.883874][ C0] ? rcu_is_watching+0x16/0xd0 [ 7.883875][ C0] ? lock_acquire+0x60/0x160 [ 7.883877][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 7.883878][ C0] ? vprintk_emit+0x320/0x3e0 [ 7.883880][ C0] vprintk_emit+0x37c/0x3e0 [ 7.883881][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 7.883884][ C0] ? clocksource_watchdog.part.0+0x4d0/0x540 [ 7.883885][ C0] _printk+0xc7/0x100 [ 7.883887][ C0] ? snapshot_read.cold+0x21/0x21 [ 7.883888][ C0] ? clocksource_watchdog.part.0+0x4d0/0x540 [ 7.883890][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 7.883892][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 7.883893][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 7.883895][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 7.883896][ C0] call_timer_fn+0x160/0x4d0 [ 7.883897][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 7.883899][ C0] ? debug_object_active_state+0x430/0x430 [ 7.883902][ C0] ? find_held_lock+0x2b/0x80 [ 7.883904][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 7.883905][ C0] ? rcu_is_watching+0x16/0xd0 [ 7.883907][ C0] __run_timers+0x68f/0xaa0 [ 7.883909][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 7.883911][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 7.883912][ C0] ? __lock_acquire+0x518/0xc20 [ 7.883913][ C0] ? trace_rcu_watching+0xc0/0x1f0 [ 7.883917][ C0] ? __rwlock_init+0x150/0x150 [ 7.883928][ C0] run_timer_softirq+0xf0/0x160 [ 7.883930][ C0] ? __run_timers+0xaa0/0xaa0 [ 7.883931][ C0] ? handle_softirqs+0x19c/0x900 [ 7.883933][ C0] ? rcu_is_watching+0x16/0xd0 [ 7.883935][ C0] handle_softirqs+0x1d3/0x900 [ 7.883937][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 7.883938][ C0] ? _local_bh_enable+0xc0/0xc0 [ 7.883941][ C0] __irq_exit_rcu+0x145/0x1c0 [ 7.883943][ C0] irq_exit_rcu+0xe/0x30 [ 7.883944][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 7.883946][ C0] [ 7.883947][ C0] [ 7.883947][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 7.883949][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 7.883951][ C0] Code: 48 8b 3d 94 f2 f7 01 e8 1f 00 00 00 48 2b 05 58 b3 98 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 16 11 00 fb f4 0f 1f 40 d6 48 83 ec 20 8b 17 49 89 f8 83 e2 fe 41 89 d2 0f 01 [ 7.883953][ C0] RSP: 0018:ffffffffbbc07cf8 EFLAGS: 00000296 [ 7.883955][ C0] RAX: 0000000000034439 RBX: ffffffffbbc1c600 RCX: ffffffffb8ced307 [ 7.883956][ C0] RDX: ffffffffbbc1c600 RSI: ffffffffbba7099a RDI: ffffffffbb48f560 [ 7.883957][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 7.883958][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: 1ffffffff7780fa2 [ 7.883959][ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 [ 7.883960][ C0] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 7.883963][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 7.883964][ C0] default_idle+0x9/0x10 [ 7.883966][ C0] default_idle_call+0x6e/0xb0 [ 7.883968][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 7.883970][ C0] ? arch_cpu_idle_exit+0x40/0x40 [ 7.883972][ C0] ? mark_tsc_async_resets+0x30/0x30 [ 7.883975][ C0] ? rcu_is_watching+0x16/0xd0 [ 7.883976][ C0] do_idle+0xd8/0x190 [ 7.883978][ C0] cpu_startup_entry+0x53/0x70 [ 7.883980][ C0] rest_init+0x279/0x280 [ 7.883982][ C0] ? __cpuidle_text_end+0x3b/0x3b [ 7.883984][ C0] ? rest_init+0x280/0x280 [ 7.883986][ C0] ? acpi_hw_set_mode+0x4a0/0x4a0 [ 7.883990][ C0] ? cpus_read_unlock+0x4f/0xc0 [ 7.883991][ C0] ? acpi_enable+0x1e4/0x330 [ 7.883994][ C0] start_kernel+0x3af/0x3b0 [ 7.883995][ C0] x86_64_start_reservations+0x24/0x30 [ 7.883997][ C0] x86_64_start_kernel+0x12b/0x130 [ 7.883999][ C0] common_startup_64+0x13e/0x148 [ 7.884002][ C0] [ 7.884005][ C0] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 7.884006][ C0] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 0, name: swapper/0 [ 7.884007][ C0] preempt_count: 103, expected: 0 [ 7.884008][ C0] RCU nest depth: 0, expected: 0 [ 7.884009][ C0] INFO: lockdep is turned off. [ 7.884009][ C0] irq event stamp: 214101 [ 7.884010][ C0] hardirqs last enabled at (214100): [] __down_trylock_console_sem+0x86/0xa0 [ 7.884012][ C0] hardirqs last disabled at (214101): [] console_emit_next_record+0x3f8/0x4f0 [ 7.884014][ C0] softirqs last enabled at (214048): [] handle_softirqs+0x67c/0x900 [ 7.884015][ C0] softirqs last disabled at (214077): [] __irq_exit_rcu+0x145/0x1c0 [ 7.884017][ C0] Preemption disabled at: [ 7.884018][ C0] [] schedule_preempt_disabled+0x22/0x30 [ 7.884020][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 7.884022][ C0] Tainted: [W]=WARN [ 7.884023][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 7.884023][ C0] Call Trace: [ 7.884024][ C0] [ 7.884025][ C0] dump_stack_lvl+0x6f/0xa0 [ 7.884026][ C0] ? schedule_preempt_disabled+0x22/0x30 [ 7.884028][ C0] __might_resched.cold+0x1fe/0x2c1 [ 7.884030][ C0] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 7.884033][ C0] ? rcu_is_watching+0x16/0xd0 [ 7.884035][ C0] __kmalloc_noprof+0x443/0x760 [ 7.884036][ C0] ? __rwlock_init+0x150/0x150 [ 7.884038][ C0] ? alloc_buf.isra.0+0x4b/0x260 [ 7.884039][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 7.884041][ C0] alloc_buf.isra.0+0x4b/0x260 [ 7.884042][ C0] put_chars+0x1e1/0x2f0 [ 7.884044][ C0] ? __send_to_port+0x420/0x420 [ 7.884047][ C0] hvc_console_print+0x292/0x780 [ 7.884048][ C0] ? mark_usage+0x42/0x170 [ 7.884049][ C0] ? __lock_acquire+0x388/0xc20 [ 7.884051][ C0] ? hvc_write+0x3a0/0x3a0 [ 7.884052][ C0] ? lock_acquire.part.0+0xd4/0x280 [ 7.884053][ C0] ? rcu_is_watching+0x16/0xd0 [ 7.884055][ C0] ? lock_acquire+0x13c/0x160 [ 7.884056][ C0] console_emit_next_record+0x252/0x4f0 [ 7.884058][ C0] ? devkmsg_read+0x4e0/0x4e0 [ 7.884060][ C0] ? rcu_is_watching+0x16/0xd0 [ 7.884062][ C0] ? lock_acquire+0x13c/0x160 [ 7.884063][ C0] console_flush_one_record+0x46f/0x710 [ 7.884065][ C0] ? console_emit_next_record+0x4f0/0x4f0 [ 7.884067][ C0] ? __lock_acquire+0x518/0xc20 [ 7.884069][ C0] console_unlock+0xee/0x1f0 [ 7.884070][ C0] ? console_flush_one_record+0x710/0x710 [ 7.884071][ C0] ? rcu_is_watching+0x16/0xd0 [ 7.884073][ C0] ? lock_acquire+0x60/0x160 [ 7.884074][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 7.884076][ C0] ? vprintk_emit+0x320/0x3e0 [ 7.884077][ C0] vprintk_emit+0x37c/0x3e0 [ 7.884079][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 7.884081][ C0] ? clocksource_watchdog.part.0+0x4d0/0x540 [ 7.884082][ C0] _printk+0xc7/0x100 [ 7.884084][ C0] ? snapshot_read.cold+0x21/0x21 [ 7.884086][ C0] ? clocksource_watchdog.part.0+0x4d0/0x540 [ 7.884087][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 7.884089][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 7.884090][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 7.884092][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 7.884093][ C0] call_timer_fn+0x160/0x4d0 [ 7.884095][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 7.884096][ C0] ? debug_object_active_state+0x430/0x430 [ 7.884098][ C0] ? find_held_lock+0x2b/0x80 [ 7.884100][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 7.884101][ C0] ? rcu_is_watching+0x16/0xd0 [ 7.884103][ C0] __run_timers+0x68f/0xaa0 [ 7.884104][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 7.884106][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 7.884107][ C0] ? __lock_acquire+0x518/0xc20 [ 7.884109][ C0] ? trace_rcu_watching+0xc0/0x1f0 [ 7.884111][ C0] ? __rwlock_init+0x150/0x150 [ 7.884113][ C0] run_timer_softirq+0xf0/0x160 [ 7.884115][ C0] ? __run_timers+0xaa0/0xaa0 [ 7.884116][ C0] ? handle_softirqs+0x19c/0x900 [ 7.884118][ C0] ? rcu_is_watching+0x16/0xd0 [ 7.884119][ C0] handle_softirqs+0x1d3/0x900 [ 7.884122][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 7.884123][ C0] ? _local_bh_enable+0xc0/0xc0 [ 7.884125][ C0] __irq_exit_rcu+0x145/0x1c0 [ 7.884127][ C0] irq_exit_rcu+0xe/0x30 [ 7.884129][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 7.884131][ C0] [ 7.884131][ C0] [ 7.884132][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 7.884133][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 7.884135][ C0] Code: 48 8b 3d 94 f2 f7 01 e8 1f 00 00 00 48 2b 05 58 b3 98 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 16 11 00 fb f4 0f 1f 40 d6 48 83 ec 20 8b 17 49 89 f8 83 e2 fe 41 89 d2 0f 01 [ 7.884136][ C0] RSP: 0018:ffffffffbbc07cf8 EFLAGS: 00000296 [ 7.884137][ C0] RAX: 0000000000034439 RBX: ffffffffbbc1c600 RCX: ffffffffb8ced307 [ 7.884138][ C0] RDX: ffffffffbbc1c600 RSI: ffffffffbba7099a RDI: ffffffffbb48f560 [ 7.884138][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 7.884139][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: 1ffffffff7780fa2 [ 7.884140][ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 [ 7.884141][ C0] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 7.884144][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 7.884145][ C0] default_idle+0x9/0x10 [ 7.884147][ C0] default_idle_call+0x6e/0xb0 [ 7.884149][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 7.884150][ C0] ? arch_cpu_idle_exit+0x40/0x40 [ 7.884152][ C0] ? mark_tsc_async_resets+0x30/0x30 [ 7.884154][ C0] ? rcu_is_watching+0x16/0xd0 [ 7.884156][ C0] do_idle+0xd8/0x190 [ 7.884158][ C0] cpu_startup_entry+0x53/0x70 [ 7.884160][ C0] rest_init+0x279/0x280 [ 7.884161][ C0] ? __cpuidle_text_end+0x3b/0x3b [ 7.884164][ C0] ? rest_init+0x280/0x280 [ 7.884166][ C0] ? acpi_hw_set_mode+0x4a0/0x4a0 [ 7.884168][ C0] ? cpus_read_unlock+0x4f/0xc0 [ 7.884169][ C0] ? acpi_enable+0x1e4/0x330 [ 7.884171][ C0] start_kernel+0x3af/0x3b0 [ 7.884172][ C0] x86_64_start_reservations+0x24/0x30 [ 7.884174][ C0] x86_64_start_kernel+0x12b/0x130 [ 7.884176][ C0] common_startup_64+0x13e/0x148 [ 7.884179][ C0]