Re: [PATCH] init/main: Clear boot task idle flag

[Date Prev][Date Next][Thread Prev][Thread Next][Date Index][Thread Index]

 




Hello,

kernel test robot noticed "BUG:sleeping_function_called_from_invalid_context_at_kernel/sched/completion.c" on:

commit: afa19b70494c22ba27224f90c0805e88e5db41e1 ("[PATCH] init/main: Clear boot task idle flag")
url: https://github.com/intel-lab-lkp/linux/commits/Liam-R-Howlett/init-main-Clear-boot-task-idle-flag/20230913-085921
base: https://git.kernel.org/cgit/linux/kernel/git/torvalds/linux.git 3669558bdf354cd352be955ef2764cde6a9bf5ec
patch link: https://lore.kernel.org/all/20230913005647.1534747-1-Liam.Howlett@xxxxxxxxxx/
patch subject: [PATCH] init/main: Clear boot task idle flag

in testcase: boot

compiler: gcc-12
test machine: qemu-system-x86_64 -enable-kvm -cpu SandyBridge -smp 2 -m 16G

(please refer to attached dmesg/kmsg for entire log/backtrace)


besides BUG:sleeping_function_called_from_invalid_context_at_kernel/sched/completion.c,
we also observed other issues on this commit but clean on parent:


3669558bdf354cd3 afa19b70494c22ba27224f90c08
---------------- ---------------------------
       fail:runs  %reproduction    fail:runs
           |             |             |
           :6           17%           1:6     dmesg.BUG:sleeping_function_called_from_invalid_context_at_drivers/dma-buf/selftest.c
           :6           17%           1:6     dmesg.BUG:sleeping_function_called_from_invalid_context_at_drivers/net/ethernet/intel/e1000_hw.c
           :6          100%           6:6     dmesg.BUG:sleeping_function_called_from_invalid_context_at_include/linux/sched/mm.h
           :6           83%           5:6     dmesg.BUG:sleeping_function_called_from_invalid_context_at_kernel/locking/mutex.c
           :6           50%           3:6     dmesg.BUG:sleeping_function_called_from_invalid_context_at_kernel/locking/rwsem.c
           :6           17%           1:6     dmesg.BUG:sleeping_function_called_from_invalid_context_at_kernel/rcu/tree.c
           :6          100%           6:6     dmesg.BUG:sleeping_function_called_from_invalid_context_at_kernel/sched/completion.c
           :6          100%           6:6     dmesg.RIP:__do_softirq
           :6          100%           6:6     dmesg.RIP:__flush_smp_call_function_queue
           :6           33%           2:6     dmesg.RIP:__handle_irq_event_percpu
           :6          100%           6:6     dmesg.RIP:__hrtimer_run_queues
           :6          100%           6:6     dmesg.RIP:__note_gp_changes
           :6          100%           6:6     dmesg.RIP:__queue_work
           :6          100%           6:6     dmesg.RIP:__raise_softirq_irqoff
           :6          100%           6:6     dmesg.RIP:__run_timers
           :6          100%           6:6     dmesg.RIP:__smp_call_single_queue
           :6          100%           6:6     dmesg.RIP:__sysvec_apic_timer_interrupt
           :6          100%           6:6     dmesg.RIP:__sysvec_call_function_single
           :6           50%           3:6     dmesg.RIP:__update_load_avg_blocked_se
           :6          100%           6:6     dmesg.RIP:__update_load_avg_cfs_rq
           :6          100%           6:6     dmesg.RIP:__update_load_avg_se
           :6          100%           6:6     dmesg.RIP:__wake_nocb_gp
           :6           67%           4:6     dmesg.RIP:_raw_spin_unlock_irqrestore
           :6          100%           6:6     dmesg.RIP:arch_ftrace_ops_list_func
           :6          100%           6:6     dmesg.RIP:attach_entity_load_avg
           :6          100%           6:6     dmesg.RIP:call_timer_fn
           :6          100%           6:6     dmesg.RIP:default_idle
           :6          100%           6:6     dmesg.RIP:dequeue_task_fair
           :6          100%           6:6     dmesg.RIP:detach_entity_load_avg
           :6           33%           2:6     dmesg.RIP:detach_if_pending
           :6           67%           4:6     dmesg.RIP:dma_fence_signal_timestamp_locked
           :6           33%           2:6     dmesg.RIP:do_nocb_deferred_wakeup
           :6          100%           6:6     dmesg.RIP:do_nocb_deferred_wakeup_timer
           :6          100%           6:6     dmesg.RIP:enqueue_hrtimer
           :6          100%           6:6     dmesg.RIP:enqueue_task_fair
           :6          100%           6:6     dmesg.RIP:enqueue_timer
           :6           17%           1:6     dmesg.RIP:hrtimer_init
           :6           67%           4:6     dmesg.RIP:load_balance
           :6          100%           6:6     dmesg.RIP:lock_acquire
           :6          100%           6:6     dmesg.RIP:lock_acquired
           :6          100%           6:6     dmesg.RIP:lock_contended
           :6          100%           6:6     dmesg.RIP:lock_release
           :6           33%           2:6     dmesg.RIP:printk_sprint
           :6           17%           1:6     dmesg.RIP:rcu_barrier_trace
           :6          100%           6:6     dmesg.RIP:rcu_core
           :6          100%           6:6     dmesg.RIP:rcu_is_cpu_rrupt_from_idle
           :6           83%           5:6     dmesg.RIP:rcu_qs
           :6          100%           6:6     dmesg.RIP:rcu_report_qs_rnp
           :6          100%           6:6     dmesg.RIP:rcu_sched_clock_irq
           :6           50%           3:6     dmesg.RIP:resched_curr
           :6          100%           6:6     dmesg.RIP:set_task_cpu
           :6          100%           6:6     dmesg.RIP:trace_hardirqs_off
           :6          100%           6:6     dmesg.RIP:trace_hardirqs_off_finish
           :6          100%           6:6     dmesg.RIP:trace_hardirqs_on
           :6          100%           6:6     dmesg.RIP:trace_reschedule_entry
           :6          100%           6:6     dmesg.RIP:trace_reschedule_exit
           :6          100%           6:6     dmesg.RIP:try_to_wake_up
           :6          100%           6:6     dmesg.RIP:ttwu_do_activate
           :6          100%           6:6     dmesg.RIP:update_cpu_capacity
           :6          100%           6:6     dmesg.RIP:update_curr
           :6          100%           6:6     dmesg.RIP:update_dl_rq_load_avg
           :6          100%           6:6     dmesg.RIP:update_irq_load_avg
           :6          100%           6:6     dmesg.RIP:update_rt_rq_load_avg
           :6          100%           6:6     dmesg.RIP:update_sd_lb_stats
           :6           17%           1:6     dmesg.RIP:wake_up_nohz_cpu
           :6          100%           6:6     dmesg.WARNING:at_arch/x86/include/asm/trace/irq_vectors.h:#__sysvec_apic_timer_interrupt
           :6          100%           6:6     dmesg.WARNING:at_arch/x86/include/asm/trace/irq_vectors.h:#__sysvec_call_function_single
           :6          100%           6:6     dmesg.WARNING:at_arch/x86/include/asm/trace/irq_vectors.h:#trace_reschedule_entry
           :6          100%           6:6     dmesg.WARNING:at_arch/x86/include/asm/trace/irq_vectors.h:#trace_reschedule_exit
           :6          100%           6:6     dmesg.WARNING:at_include/linux/trace_recursion.h:#arch_ftrace_ops_list_func
           :6          100%           6:6     dmesg.WARNING:at_include/trace/events/csd.h:#__flush_smp_call_function_queue
           :6           67%           4:6     dmesg.WARNING:at_include/trace/events/dma_fence.h:#dma_fence_signal_timestamp_locked
           :6          100%           6:6     dmesg.WARNING:at_include/trace/events/ipi.h:#__smp_call_single_queue
           :6           50%           3:6     dmesg.WARNING:at_include/trace/events/ipi.h:#resched_curr
           :6           17%           1:6     dmesg.WARNING:at_include/trace/events/ipi.h:#wake_up_nohz_cpu
           :6          100%           6:6     dmesg.WARNING:at_include/trace/events/irq.h:#__do_softirq
           :6           33%           2:6     dmesg.WARNING:at_include/trace/events/irq.h:#__handle_irq_event_percpu
           :6          100%           6:6     dmesg.WARNING:at_include/trace/events/irq.h:#__raise_softirq_irqoff
           :6          100%           6:6     dmesg.WARNING:at_include/trace/events/lock.h:#lock_acquire
           :6          100%           6:6     dmesg.WARNING:at_include/trace/events/lock.h:#lock_acquired
           :6          100%           6:6     dmesg.WARNING:at_include/trace/events/lock.h:#lock_contended
           :6          100%           6:6     dmesg.WARNING:at_include/trace/events/lock.h:#lock_release
           :6          100%           6:6     dmesg.WARNING:at_include/trace/events/preemptirq.h:#trace_hardirqs_off
           :6          100%           6:6     dmesg.WARNING:at_include/trace/events/preemptirq.h:#trace_hardirqs_off_finish
           :6          100%           6:6     dmesg.WARNING:at_include/trace/events/preemptirq.h:#trace_hardirqs_on
           :6           33%           2:6     dmesg.WARNING:at_include/trace/events/printk.h:#printk_sprint
           :6          100%           6:6     dmesg.WARNING:at_include/trace/events/rcu.h:#__note_gp_changes
           :6          100%           6:6     dmesg.WARNING:at_include/trace/events/rcu.h:#__wake_nocb_gp
           :6           33%           2:6     dmesg.WARNING:at_include/trace/events/rcu.h:#do_nocb_deferred_wakeup
           :6          100%           6:6     dmesg.WARNING:at_include/trace/events/rcu.h:#do_nocb_deferred_wakeup_timer
           :6           17%           1:6     dmesg.WARNING:at_include/trace/events/rcu.h:#rcu_barrier_trace
           :6          100%           6:6     dmesg.WARNING:at_include/trace/events/rcu.h:#rcu_core
           :6           83%           5:6     dmesg.WARNING:at_include/trace/events/rcu.h:#rcu_qs
           :6          100%           6:6     dmesg.WARNING:at_include/trace/events/rcu.h:#rcu_report_qs_rnp
           :6          100%           6:6     dmesg.WARNING:at_include/trace/events/rcu.h:#rcu_sched_clock_irq
           :6           50%           3:6     dmesg.WARNING:at_include/trace/events/sched.h:#__update_load_avg_blocked_se
           :6          100%           6:6     dmesg.WARNING:at_include/trace/events/sched.h:#__update_load_avg_cfs_rq
           :6          100%           6:6     dmesg.WARNING:at_include/trace/events/sched.h:#__update_load_avg_se
           :6          100%           6:6     dmesg.WARNING:at_include/trace/events/sched.h:#attach_entity_load_avg
           :6          100%           6:6     dmesg.WARNING:at_include/trace/events/sched.h:#dequeue_task_fair
           :6          100%           6:6     dmesg.WARNING:at_include/trace/events/sched.h:#detach_entity_load_avg
           :6          100%           6:6     dmesg.WARNING:at_include/trace/events/sched.h:#enqueue_task_fair
           :6          100%           6:6     dmesg.WARNING:at_include/trace/events/sched.h:#set_task_cpu
           :6          100%           6:6     dmesg.WARNING:at_include/trace/events/sched.h:#try_to_wake_up
           :6          100%           6:6     dmesg.WARNING:at_include/trace/events/sched.h:#ttwu_do_activate
           :6          100%           6:6     dmesg.WARNING:at_include/trace/events/sched.h:#update_cpu_capacity
           :6          100%           6:6     dmesg.WARNING:at_include/trace/events/sched.h:#update_curr
           :6          100%           6:6     dmesg.WARNING:at_include/trace/events/sched.h:#update_dl_rq_load_avg
           :6          100%           6:6     dmesg.WARNING:at_include/trace/events/sched.h:#update_irq_load_avg
           :6          100%           6:6     dmesg.WARNING:at_include/trace/events/sched.h:#update_rt_rq_load_avg
           :6          100%           6:6     dmesg.WARNING:at_include/trace/events/sched.h:#update_sd_lb_stats
           :6          100%           6:6     dmesg.WARNING:at_include/trace/events/timer.h:#__hrtimer_run_queues
           :6          100%           6:6     dmesg.WARNING:at_include/trace/events/timer.h:#__run_timers
           :6          100%           6:6     dmesg.WARNING:at_include/trace/events/timer.h:#call_timer_fn
           :6           33%           2:6     dmesg.WARNING:at_include/trace/events/timer.h:#detach_if_pending
           :6          100%           6:6     dmesg.WARNING:at_include/trace/events/timer.h:#enqueue_hrtimer
           :6          100%           6:6     dmesg.WARNING:at_include/trace/events/timer.h:#enqueue_timer
           :6           17%           1:6     dmesg.WARNING:at_include/trace/events/timer.h:#hrtimer_init
           :6          100%           6:6     dmesg.WARNING:at_include/trace/events/workqueue.h:#__queue_work
           :6          100%           6:6     dmesg.WARNING:at_kernel/rcu/tree.c:#rcu_is_cpu_rrupt_from_idle
           :6           17%           1:6     dmesg.WARNING:inconsistent_lock_state
           :6          100%           6:6     dmesg.WARNING:suspicious_RCU_usage
           :6           33%           2:6     dmesg.arch/x86/kernel/irq.c:#IRQ_failed_to_wake_up_RCU
           :6          100%           6:6     dmesg.include/linux/cgroup.h:#suspicious_rcu_dereference_check()usage
           :6          100%           6:6     dmesg.include/linux/rcupdate.h:#rcu_read_lock()used_illegally_while_idle
           :6          100%           6:6     dmesg.include/linux/rcupdate.h:#rcu_read_unlock()used_illegally_while_idle
           :6           17%           1:6     dmesg.inconsistent{IN-HARDIRQ-W}->{HARDIRQ-ON-W}usage
           :6          100%           6:6     dmesg.kernel/events/core.c:#suspicious_rcu_dereference_check()usage
           :6           83%           5:6     dmesg.kernel/exit.c:#suspicious_rcu_dereference_check()usage
           :6          100%           6:6     dmesg.kernel/rcu/tree.c:#RCU_dynticks_nmi_nesting_counter_underflow/zero
           :6           67%           4:6     dmesg.kernel/sched/core.c:#suspicious_rcu_dereference_check()usage
           :6          100%           6:6     dmesg.kernel/sched/fair.c:#suspicious_rcu_dereference_check()usage
           :6           83%           5:6     dmesg.kernel/workqueue.c:#RCU_or_wq_pool_mutex_should_be_held
           :6           83%           5:6     dmesg.kernel/workqueue.c:#suspicious_rcu_dereference_check()usage



If you fix the issue in a separate patch/commit (i.e. not just a new version of
the same patch/commit), kindly add following tags
| Reported-by: kernel test robot <oliver.sang@xxxxxxxxx>
| Closes: https://lore.kernel.org/oe-lkp/202309142334.a345320b-oliver.sang@xxxxxxxxx


[    2.318233][    T1] BUG: sleeping function called from invalid context at kernel/sched/completion.c:111
[    2.318647][    T1] in_atomic(): 0, irqs_disabled(): 0, non_block: 0, pid: 1, name: swapper/0
[    2.319725][    T1] preempt_count: 0, expected: 0
[    2.320293][    T1] no locks held by swapper/0/1.
[    2.320882][    T1] CPU: 0 PID: 1 Comm: swapper/0 Not tainted 6.6.0-rc1-00034-gafa19b70494c #1
[    2.321957][    T1] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.16.2-debian-1.16.2-1 04/01/2014
[    2.321957][    T1] Call Trace:
[    2.321957][    T1]  <TASK>
[ 2.321957][ T1] dump_stack_lvl (lib/dump_stack.c:107) 
[ 2.321957][ T1] __might_resched (kernel/sched/core.c:10188) 
[ 2.321957][ T1] ? sched_core_find (kernel/sched/core.c:10142) 
[ 2.321957][ T1] __wait_for_common (kernel/sched/completion.c:115) 
[ 2.321957][ T1] ? usleep_range_state (kernel/time/timer.c:2129) 
[ 2.321957][ T1] ? reacquire_held_locks (kernel/locking/lockdep.c:5404) 
[ 2.321957][ T1] ? wait_for_completion_io_timeout (kernel/sched/completion.c:110) 
[ 2.321957][ T1] ? do_raw_spin_lock (kernel/locking/spinlock_debug.c:121) 
[ 2.321957][ T1] ? rest_init (init/main.c:1431) 
[ 2.321957][ T1] kernel_init (init/main.c:1439) 
[ 2.321957][ T1] ? _raw_spin_unlock_irq (arch/x86/include/asm/irqflags.h:42 arch/x86/include/asm/irqflags.h:77 include/linux/spinlock_api_smp.h:159 kernel/locking/spinlock.c:202) 
[ 2.321957][ T1] ret_from_fork (arch/x86/kernel/process.c:153) 
[ 2.321957][ T1] ? rest_init (init/main.c:1431) 
[ 2.321957][ T1] ret_from_fork_asm (arch/x86/entry/entry_64.S:312) 
[    2.321957][    T1]  </TASK>
[    2.322276][    T1] smpboot: CPU0: Intel Xeon E312xx (Sandy Bridge) (family: 0x6, model: 0x2a, stepping: 0x1)
[    2.327212][    T1] RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1.
[    2.328461][    T1] RCU Tasks Rude: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1.
[    2.330045][    T1] RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1.
[    2.331385][    T1] Running RCU-tasks wait API self tests
[    2.333117][    T1] Performance Events: unsupported p6 CPU model 42 no PMU driver, software events only.
[    2.334654][    T1] signal: max sigframe size: 1776
[    2.335915][    T1] rcu: Hierarchical SRCU implementation.
[    2.336653][    T1] rcu: 	Max phase no-delay instances is 1000.
[    2.342550][   T14] Callback from call_rcu_tasks_trace() invoked.
[    2.344299][    T1] smp: Bringing up secondary CPUs ...
[    2.347251][    T1] smpboot: x86: Booting SMP configuration:
[    2.347999][    T1] .... node  #0, CPUs:      #1
[    0.487313][    T0] masked ExtINT on CPU#1
[    2.348624][    T0] ------------[ cut here ]------------
[ 2.348624][ T0] WARNING: CPU: 0 PID: 0 at include/trace/events/preemptirq.h:36 trace_hardirqs_off_finish (include/trace/events/preemptirq.h:36 include/trace/events/preemptirq.h:36 kernel/trace/trace_preemptirq.c:78 kernel/trace/trace_preemptirq.c:73) 
[    2.348624][    T0] Modules linked in:
[    2.348624][    T0] CPU: 0 PID: 0 Comm: swapper/0 Tainted: G        W          6.6.0-rc1-00034-gafa19b70494c #1
[    2.348624][    T0] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.16.2-debian-1.16.2-1 04/01/2014
[ 2.348624][ T0] RIP: 0010:trace_hardirqs_off_finish (include/trace/events/preemptirq.h:36 include/trace/events/preemptirq.h:36 kernel/trace/trace_preemptirq.c:78 kernel/trace/trace_preemptirq.c:73) 
[ 2.348624][ T0] Code: 03 80 3c 02 00 75 2a 48 8b 7b 08 31 d2 48 89 ee e8 99 f4 ff ff 65 ff 0d 0a 69 ce 7e e9 5d ff ff ff e8 38 18 e9 ff 84 c0 75 80 <0f> 0b e9 79 ff ff ff e8 c8 c0 3a 00 eb cf 66 0f 1f 44 00 00 65 8b
All code
========
   0:	03 80 3c 02 00 75    	add    0x7500023c(%rax),%eax
   6:	2a 48 8b             	sub    -0x75(%rax),%cl
   9:	7b 08                	jnp    0x13
   b:	31 d2                	xor    %edx,%edx
   d:	48 89 ee             	mov    %rbp,%rsi
  10:	e8 99 f4 ff ff       	call   0xfffffffffffff4ae
  15:	65 ff 0d 0a 69 ce 7e 	decl   %gs:0x7ece690a(%rip)        # 0x7ece6926
  1c:	e9 5d ff ff ff       	jmp    0xffffffffffffff7e
  21:	e8 38 18 e9 ff       	call   0xffffffffffe9185e
  26:	84 c0                	test   %al,%al
  28:	75 80                	jne    0xffffffffffffffaa
  2a:*	0f 0b                	ud2		<-- trapping instruction
  2c:	e9 79 ff ff ff       	jmp    0xffffffffffffffaa
  31:	e8 c8 c0 3a 00       	call   0x3ac0fe
  36:	eb cf                	jmp    0x7
  38:	66 0f 1f 44 00 00    	nopw   0x0(%rax,%rax,1)
  3e:	65                   	gs
  3f:	8b                   	.byte 0x8b

Code starting with the faulting instruction
===========================================
   0:	0f 0b                	ud2
   2:	e9 79 ff ff ff       	jmp    0xffffffffffffff80
   7:	e8 c8 c0 3a 00       	call   0x3ac0d4
   c:	eb cf                	jmp    0xffffffffffffffdd
   e:	66 0f 1f 44 00 00    	nopw   0x0(%rax,%rax,1)
  14:	65                   	gs
  15:	8b                   	.byte 0x8b
[    2.348624][    T0] RSP: 0000:ffffffff84a07d60 EFLAGS: 00010046
[    2.348624][    T0] RAX: 0000000000000000 RBX: 0000000000000000 RCX: ffffffff81513ac2
[    2.348624][    T0] RDX: 0000000000000000 RSI: 0000000000000008 RDI: ffffffff85a79540
[    2.348624][    T0] RBP: ffffffff8352c418 R08: 0000000000000000 R09: fffffbfff0b4f2a8
[    2.348624][    T0] R10: ffffffff85a79547 R11: 0000000000000000 R12: 0000000000000000
[    2.348624][    T0] R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000000
[    2.348624][    T0] FS:  0000000000000000(0000) GS:ffff8883aea00000(0000) knlGS:0000000000000000
[    2.348624][    T0] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[    2.348624][    T0] CR2: ffff88843ffff000 CR3: 0000000004a89000 CR4: 00000000000406f0
[    2.348624][    T0] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
[    2.348624][    T0] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
[    2.348624][    T0] Call Trace:
[    2.348624][    T0]  <TASK>
[ 2.348624][ T0] ? __warn (kernel/panic.c:673) 
[ 2.348624][ T0] ? trace_hardirqs_off_finish (include/trace/events/preemptirq.h:36 include/trace/events/preemptirq.h:36 kernel/trace/trace_preemptirq.c:78 kernel/trace/trace_preemptirq.c:73) 
[ 2.348624][ T0] ? report_bug (lib/bug.c:180 lib/bug.c:219) 
[ 2.348624][ T0] ? handle_bug (arch/x86/kernel/traps.c:237) 
[ 2.348624][ T0] ? exc_invalid_op (arch/x86/kernel/traps.c:258 (discriminator 1)) 
[ 2.348624][ T0] ? asm_exc_invalid_op (arch/x86/include/asm/idtentry.h:568) 
[ 2.348624][ T0] ? irqentry_enter (kernel/entry/common.c:315) 
[ 2.348624][ T0] ? trace_hardirqs_off_finish (arch/x86/include/asm/bitops.h:228 arch/x86/include/asm/bitops.h:240 include/asm-generic/bitops/instrumented-non-atomic.h:142 include/linux/cpumask.h:504 include/linux/cpumask.h:1082 include/trace/events/preemptirq.h:36 kernel/trace/trace_preemptirq.c:78 kernel/trace/trace_preemptirq.c:73) 
[ 2.348624][ T0] ? trace_hardirqs_off_finish (include/trace/events/preemptirq.h:36 include/trace/events/preemptirq.h:36 kernel/trace/trace_preemptirq.c:78 kernel/trace/trace_preemptirq.c:73) 
[ 2.348624][ T0] irqentry_enter (kernel/entry/common.c:315) 
[ 2.348624][ T0] sysvec_call_function_single (arch/x86/kernel/smp.c:287) 
[ 2.348624][ T0] asm_sysvec_call_function_single (arch/x86/include/asm/idtentry.h:652) 
[ 2.348624][ T0] RIP: 0010:default_idle (arch/x86/include/asm/irqflags.h:37 arch/x86/include/asm/irqflags.h:72 arch/x86/kernel/process.c:750) 
[ 2.348624][ T0] Code: 07 76 e7 48 89 07 49 c7 c0 08 00 00 00 4d 29 c8 4c 01 c7 4c 29 c2 e9 72 ff ff ff cc cc cc cc eb 07 0f 00 2d 37 79 30 00 fb f4 <fa> c3 0f 1f 00 65 48 8b 04 25 40 a4 1f 00 f0 80 48 02 20 48 8b 10
All code
========
   0:	07                   	(bad)
   1:	76 e7                	jbe    0xffffffffffffffea
   3:	48 89 07             	mov    %rax,(%rdi)
   6:	49 c7 c0 08 00 00 00 	mov    $0x8,%r8
   d:	4d 29 c8             	sub    %r9,%r8
  10:	4c 01 c7             	add    %r8,%rdi
  13:	4c 29 c2             	sub    %r8,%rdx
  16:	e9 72 ff ff ff       	jmp    0xffffffffffffff8d
  1b:	cc                   	int3
  1c:	cc                   	int3
  1d:	cc                   	int3
  1e:	cc                   	int3
  1f:	eb 07                	jmp    0x28
  21:	0f 00 2d 37 79 30 00 	verw   0x307937(%rip)        # 0x30795f
  28:	fb                   	sti
  29:	f4                   	hlt
  2a:*	fa                   	cli		<-- trapping instruction
  2b:	c3                   	ret
  2c:	0f 1f 00             	nopl   (%rax)
  2f:	65 48 8b 04 25 40 a4 	mov    %gs:0x1fa440,%rax
  36:	1f 00 
  38:	f0 80 48 02 20       	lock orb $0x20,0x2(%rax)
  3d:	48 8b 10             	mov    (%rax),%rdx

Code starting with the faulting instruction
===========================================
   0:	fa                   	cli
   1:	c3                   	ret
   2:	0f 1f 00             	nopl   (%rax)
   5:	65 48 8b 04 25 40 a4 	mov    %gs:0x1fa440,%rax
   c:	1f 00 
   e:	f0 80 48 02 20       	lock orb $0x20,0x2(%rax)
  13:	48 8b 10             	mov    (%rax),%rdx
[    2.348624][    T0] RSP: 0000:ffffffff84a07e40 EFLAGS: 00000206
[    2.348624][    T0] RAX: 0000000000003056 RBX: 0000000000000000 RCX: ffffffff8352c7ba
[    2.348624][    T0] RDX: 0000000000000000 RSI: 0000000000000000 RDI: ffffffff812e3bd3
[    2.348624][    T0] RBP: 0000000000000000 R08: 0000000000000001 R09: ffffed1075d7ed9d
[    2.348624][    T0] R10: ffff8883aebf6ceb R11: 0000000000000000 R12: 1ffffffff0940fca
[    2.348624][    T0] R13: ffffffff84a1e940 R14: dffffc0000000000 R15: 0000000000013f10
[ 2.348624][ T0] ? ct_kernel_exit+0xca/0x100 
[ 2.348624][ T0] ? cpuidle_idle_call (kernel/sched/idle.c:171) 
[ 2.348624][ T0] default_idle_call (include/linux/cpuidle.h:143 kernel/sched/idle.c:98) 
[ 2.348624][ T0] cpuidle_idle_call (kernel/sched/idle.c:171) 
[ 2.348624][ T0] ? arch_cpu_idle_exit+0x30/0x30 
[ 2.348624][ T0] ? lockdep_hardirqs_on_prepare (kernel/locking/lockdep.c:4565) 
[ 2.348624][ T0] do_idle (kernel/sched/idle.c:282) 
[ 2.348624][ T0] cpu_startup_entry (kernel/sched/idle.c:378 (discriminator 1)) 
[ 2.348624][ T0] rest_init (include/linux/rcupdate.h:750 init/main.c:697) 
[ 2.348624][ T0] arch_call_rest_init+0xf/0x20 
[ 2.348624][ T0] start_kernel (init/main.c:1021 (discriminator 1)) 
[ 2.348624][ T0] x86_64_start_reservations (arch/x86/kernel/head64.c:544) 
[ 2.348624][ T0] x86_64_start_kernel (??:?) 
[ 2.348624][ T0] secondary_startup_64_no_verify (arch/x86/kernel/head_64.S:433) 
[    2.348624][    T0]  </TASK>
[    2.348624][    T0] irq event stamp: 12375
[ 2.348624][ T0] hardirqs last enabled at (12374): cpuidle_idle_call (kernel/sched/idle.c:171) 
[ 2.348624][ T0] hardirqs last disabled at (12375): sysvec_call_function_single (arch/x86/kernel/smp.c:287) 
[ 2.348624][ T0] softirqs last enabled at (8563): __do_softirq (arch/x86/include/asm/preempt.h:27 kernel/softirq.c:400 kernel/softirq.c:582) 
[ 2.348624][ T0] softirqs last disabled at (8558): __irq_exit_rcu (kernel/softirq.c:427 kernel/softirq.c:632) 
[    2.348624][    T0] ---[ end trace 0000000000000000 ]---


The kernel config and materials to reproduce are available at:
https://download.01.org/0day-ci/archive/20230914/202309142334.a345320b-oliver.sang@xxxxxxxxx



-- 
0-DAY CI Kernel Test Service
https://github.com/intel/lkp-tests/wiki




[Index of Archives]     [Linux Kernel]     [Kernel Development Newbies]     [Linux USB Devel]     [Video for Linux]     [Linux Audio Users]     [Yosemite Hiking]     [Linux Kernel]     [Linux SCSI]

  Powered by Linux