lists.openwall.net   lists  /  announce  owl-users  owl-dev  john-users  john-dev  passwdqc-users  yescrypt  popa3d-users  /  oss-security  kernel-hardening  musl  sabotage  tlsify  passwords  /  crypt-dev  xvendor  /  Bugtraq  Full-Disclosure  linux-kernel  linux-netdev  linux-ext4  linux-hardening  linux-cve-announce  PHC 
Open Source and information security mailing list archives
 
Hash Suite: Windows password security audit tool. GUI, reports in PDF.
[<prev] [next>] [day] [month] [year] [list]
Message-ID: <20220228133156.GA1145@xsang-OptiPlex-9020>
Date:   Mon, 28 Feb 2022 21:31:56 +0800
From:   kernel test robot <oliver.sang@...el.com>
To:     Chengming Zhou <zhouchengming@...edance.com>
Cc:     lkp@...ts.01.org, lkp@...el.com,
        LKML <linux-kernel@...r.kernel.org>, aubrey.li@...ux.intel.com,
        yu.c.chen@...el.com
Subject: [sched/cpuacct]  d67e27e3d2: WARNING:suspicious_RCU_usage



Greeting,

FYI, we noticed the following commit (built with gcc-9):

commit: d67e27e3d2e9c8412287d0ac8b8e4eaab404de6d ("sched/cpuacct: optimize away RCU read lock")
https://git.kernel.org/cgit/linux/kernel/git/peterz/queue.git sched/core

in testcase: boot

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

caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):



If you fix the issue, kindly add following tag
Reported-by: kernel test robot <oliver.sang@...el.com>


[    2.042854][    C0] WARNING: suspicious RCU usage
[    2.042857][    C0] 5.17.0-rc5-00050-gd67e27e3d2e9 #1 Not tainted
[    2.042861][    C0] -----------------------------
[    2.042863][    C0] include/linux/cgroup.h:494 suspicious rcu_dereference_check() usage!
[    2.042867][    C0]
[    2.042867][    C0] other info that might help us debug this:
[    2.042867][    C0]
[    2.042869][    C0]
[    2.042869][    C0] rcu_scheduler_active = 1, debug_locks = 1
[    2.042873][    C0] 2 locks held by swapper/0/1:
[ 2.042876][ C0] #0: ffffffffa4178740 (console_lock){+.+.}-{0:0}, at: vprintk_emit (kernel/printk/printk.c:1843 kernel/printk/printk.c:2244) 
[ 2.043761][ C0] #1: ffff88839d439b58 (&rq->__lock){-...}-{2:2}, at: scheduler_tick (kernel/sched/sched.h:1539 kernel/sched/sched.h:1617 kernel/sched/core.c:5263) 
[    2.043779][    C0]
[    2.043779][    C0] stack backtrace:
[    2.043782][    C0] CPU: 0 PID: 1 Comm: swapper/0 Not tainted 5.17.0-rc5-00050-gd67e27e3d2e9 #1
[    2.043787][    C0] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.12.0-1 04/01/2014
[    2.043791][    C0] Call Trace:
[    2.043795][    C0]  <IRQ>
[ 2.043800][ C0] dump_stack_lvl (lib/dump_stack.c:107) 
[ 2.043809][ C0] cpuacct_charge (include/linux/cgroup.h:494 kernel/sched/cpuacct.c:40 kernel/sched/cpuacct.c:342) 
[ 2.043820][ C0] update_curr (include/linux/cgroup.h:481 include/linux/cgroup.h:550 include/linux/cgroup.h:794 kernel/sched/fair.c:877) 
[ 2.043820][ C0] task_tick_fair (kernel/sched/fair.c:4612 (discriminator 2) kernel/sched/fair.c:11180 (discriminator 2)) 
[ 2.043820][ C0] ? lock_is_held_type (kernel/locking/lockdep.c:5380 kernel/locking/lockdep.c:5682) 
[ 2.043820][ C0] scheduler_tick (arch/x86/include/asm/jump_label.h:27 include/linux/jump_label.h:212 kernel/sched/features.h:99 kernel/sched/core.c:5269) 
[ 2.043820][ C0] update_process_times (kernel/time/timer.c:1793) 
[ 2.043820][ C0] tick_periodic (kernel/time/tick-common.c:101) 
[ 2.043820][ C0] tick_handle_periodic (kernel/time/tick-common.c:120) 
[ 2.043820][ C0] __sysvec_apic_timer_interrupt (arch/x86/include/asm/jump_label.h:27 include/linux/jump_label.h:212 arch/x86/include/asm/trace/irq_vectors.h:41 arch/x86/kernel/apic/apic.c:1104) 
[ 2.043820][ C0] sysvec_apic_timer_interrupt (arch/x86/kernel/apic/apic.c:1097 (discriminator 14)) 
[    2.043820][    C0]  </IRQ>
[    2.043820][    C0]  <TASK>
[ 2.043820][ C0] asm_sysvec_apic_timer_interrupt (arch/x86/include/asm/idtentry.h:638) 
[ 2.043820][ C0] RIP: 0010:console_unlock (kernel/printk/printk.c:2719) 
[ 2.043820][ C0] Code: 08 e9 2d fc ff ff 48 c7 c6 83 c9 11 a0 48 c7 c7 a0 80 df a3 e8 ea f6 fd ff e8 65 2c 00 00 48 83 7c 24 18 00 0f 85 d4 00 00 00 <8b> 44 24 30 85 c0 0f 84 68 fa ff ff 31 d2 be a0 0a 00 00 48 c7 c7
All code
========
   0:	08 e9                	or     %ch,%cl
   2:	2d fc ff ff 48       	sub    $0x48fffffc,%eax
   7:	c7 c6 83 c9 11 a0    	mov    $0xa011c983,%esi
   d:	48 c7 c7 a0 80 df a3 	mov    $0xffffffffa3df80a0,%rdi
  14:	e8 ea f6 fd ff       	callq  0xfffffffffffdf703
  19:	e8 65 2c 00 00       	callq  0x2c83
  1e:	48 83 7c 24 18 00    	cmpq   $0x0,0x18(%rsp)
  24:	0f 85 d4 00 00 00    	jne    0xfe
  2a:*	8b 44 24 30          	mov    0x30(%rsp),%eax		<-- trapping instruction
  2e:	85 c0                	test   %eax,%eax
  30:	0f 84 68 fa ff ff    	je     0xfffffffffffffa9e
  36:	31 d2                	xor    %edx,%edx
  38:	be a0 0a 00 00       	mov    $0xaa0,%esi
  3d:	48                   	rex.W
  3e:	c7                   	.byte 0xc7
  3f:	c7                   	.byte 0xc7

Code starting with the faulting instruction
===========================================
   0:	8b 44 24 30          	mov    0x30(%rsp),%eax
   4:	85 c0                	test   %eax,%eax
   6:	0f 84 68 fa ff ff    	je     0xfffffffffffffa74
   c:	31 d2                	xor    %edx,%edx
   e:	be a0 0a 00 00       	mov    $0xaa0,%esi
  13:	48                   	rex.W
  14:	c7                   	.byte 0xc7
  15:	c7                   	.byte 0xc7
[    2.043820][    C0] RSP: 0000:ffffc9000001fb70 EFLAGS: 00000206
[    2.043820][    C0] RAX: 000000000000002b RBX: dffffc0000000000 RCX: 1ffffffff4bd2afe
[    2.043820][    C0] RDX: 0000000000000000 RSI: 0000000000000000 RDI: ffffffffa011ca80
[    2.043820][    C0] RBP: ffffc9000001fbc8 R08: 0000000000000001 R09: fffffbfff4bd2a41
[    2.043820][    C0] R10: ffffffffa5e95207 R11: fffffbfff4bd2a40 R12: 0000000000000000
[    2.043820][    C0] R13: 0000000000000000 R14: ffffffffa47076e8 R15: 0000000000000000
[ 2.043820][ C0] ? console_unlock (arch/x86/include/asm/irqflags.h:45 arch/x86/include/asm/irqflags.h:80 arch/x86/include/asm/irqflags.h:138 kernel/printk/printk.c:2715) 
[ 2.043820][ C0] ? do_syslog (kernel/printk/printk.c:2088) 
[ 2.043820][ C0] ? rcu_read_unlock (include/linux/rcupdate.h:723 (discriminator 5)) 
[ 2.043820][ C0] ? vprintk_emit (kernel/printk/printk.c:1843 kernel/printk/printk.c:2244) 
[ 2.043820][ C0] vprintk_emit (arch/x86/include/asm/preempt.h:85 kernel/printk/printk.c:2246) 
[ 2.043820][ C0] _printk (kernel/printk/printk.c:2261) 
[ 2.043820][ C0] ? record_print_text.cold (kernel/printk/printk.c:2261) 
[ 2.043820][ C0] native_smp_prepare_cpus (arch/x86/kernel/smpboot.c:1432) 
[ 2.043820][ C0] kernel_init_freeable (include/linux/compiler.h:241 include/linux/init.h:124 init/main.c:1417 init/main.c:1602) 
[ 2.043820][ C0] ? lockdep_hardirqs_on_prepare (kernel/locking/lockdep.c:4232 kernel/locking/lockdep.c:4292 kernel/locking/lockdep.c:4244) 
[ 2.043820][ C0] ? console_on_rootfs (init/main.c:1584) 
[ 2.043820][ C0] ? rwlock_bug+0xc0/0xc0 
[ 2.043820][ C0] ? rest_init (init/main.c:1494) 
[ 2.043820][ C0] kernel_init (init/main.c:1504) 
[ 2.043820][ C0] ret_from_fork (arch/x86/entry/entry_64.S:301) 
[    2.043820][    C0]  </TASK>
[    2.043820][    C0]
[    2.043820][    C0] =============================
[    2.043820][    C0] WARNING: suspicious RCU usage
[    2.043820][    C0] 5.17.0-rc5-00050-gd67e27e3d2e9 #1 Not tainted
[    2.043820][    C0] -----------------------------
[    2.043820][    C0] include/linux/cgroup.h:481 suspicious rcu_dereference_check() usage!
[    2.043820][    C0]
[    2.043820][    C0] other info that might help us debug this:
[    2.043820][    C0]
[    2.043820][    C0] rcu_scheduler_active = 1, debug_locks = 1
[    2.043820][    C0] 2 locks held by swapper/0/1:
[ 2.043820][ C0] #0: ffffffffa4178740 (console_lock){+.+.}-{0:0}, at: vprintk_emit (kernel/printk/printk.c:1843 kernel/printk/printk.c:2244) 
[ 2.043820][ C0] #1: ffff88839d439b58 (&rq->__lock){-...}-{2:2}, at: scheduler_tick (kernel/sched/sched.h:1539 kernel/sched/sched.h:1617 kernel/sched/core.c:5263) 
[    2.043820][    C0]
[    2.043820][    C0] stack backtrace:
[    2.043820][    C0] CPU: 0 PID: 1 Comm: swapper/0 Not tainted 5.17.0-rc5-00050-gd67e27e3d2e9 #1
[    2.043820][    C0] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.12.0-1 04/01/2014
[    2.043820][    C0] Call Trace:
[    2.043820][    C0]  <IRQ>
[ 2.043820][ C0] dump_stack_lvl (lib/dump_stack.c:107) 
[ 2.043820][ C0] update_curr (include/linux/cgroup.h:481 include/linux/cgroup.h:550 include/linux/cgroup.h:794 kernel/sched/fair.c:877) 
[ 2.043820][ C0] task_tick_fair (kernel/sched/fair.c:4612 (discriminator 2) kernel/sched/fair.c:11180 (discriminator 2)) 
[ 2.043820][ C0] ? lock_is_held_type (kernel/locking/lockdep.c:5380 kernel/locking/lockdep.c:5682) 
[ 2.043820][ C0] scheduler_tick (arch/x86/include/asm/jump_label.h:27 include/linux/jump_label.h:212 kernel/sched/features.h:99 kernel/sched/core.c:5269) 
[ 2.043820][ C0] update_process_times (kernel/time/timer.c:1793) 
[ 2.043820][ C0] tick_periodic (kernel/time/tick-common.c:101) 
[ 2.043820][ C0] tick_handle_periodic (kernel/time/tick-common.c:120) 
[ 2.043820][ C0] __sysvec_apic_timer_interrupt (arch/x86/include/asm/jump_label.h:27 include/linux/jump_label.h:212 arch/x86/include/asm/trace/irq_vectors.h:41 arch/x86/kernel/apic/apic.c:1104) 
[ 2.043820][ C0] sysvec_apic_timer_interrupt (arch/x86/kernel/apic/apic.c:1097 (discriminator 14)) 
[    2.043820][    C0]  </IRQ>
[    2.043820][    C0]  <TASK>
[ 2.043820][ C0] asm_sysvec_apic_timer_interrupt (arch/x86/include/asm/idtentry.h:638) 
[ 2.043820][ C0] RIP: 0010:console_unlock (kernel/printk/printk.c:2719) 
[ 2.043820][ C0] Code: 08 e9 2d fc ff ff 48 c7 c6 83 c9 11 a0 48 c7 c7 a0 80 df a3 e8 ea f6 fd ff e8 65 2c 00 00 48 83 7c 24 18 00 0f 85 d4 00 00 00 <8b> 44 24 30 85 c0 0f 84 68 fa ff ff 31 d2 be a0 0a 00 00 48 c7 c7
All code
========
   0:	08 e9                	or     %ch,%cl
   2:	2d fc ff ff 48       	sub    $0x48fffffc,%eax
   7:	c7 c6 83 c9 11 a0    	mov    $0xa011c983,%esi
   d:	48 c7 c7 a0 80 df a3 	mov    $0xffffffffa3df80a0,%rdi
  14:	e8 ea f6 fd ff       	callq  0xfffffffffffdf703
  19:	e8 65 2c 00 00       	callq  0x2c83
  1e:	48 83 7c 24 18 00    	cmpq   $0x0,0x18(%rsp)
  24:	0f 85 d4 00 00 00    	jne    0xfe
  2a:*	8b 44 24 30          	mov    0x30(%rsp),%eax		<-- trapping instruction
  2e:	85 c0                	test   %eax,%eax
  30:	0f 84 68 fa ff ff    	je     0xfffffffffffffa9e
  36:	31 d2                	xor    %edx,%edx
  38:	be a0 0a 00 00       	mov    $0xaa0,%esi
  3d:	48                   	rex.W
  3e:	c7                   	.byte 0xc7
  3f:	c7                   	.byte 0xc7

Code starting with the faulting instruction
===========================================
   0:	8b 44 24 30          	mov    0x30(%rsp),%eax
   4:	85 c0                	test   %eax,%eax
   6:	0f 84 68 fa ff ff    	je     0xfffffffffffffa74
   c:	31 d2                	xor    %edx,%edx
   e:	be a0 0a 00 00       	mov    $0xaa0,%esi
  13:	48                   	rex.W
  14:	c7                   	.byte 0xc7
  15:	c7                   	.byte 0xc7
[    2.043820][    C0] RSP: 0000:ffffc9000001fb70 EFLAGS: 00000206
[    2.043820][    C0] RAX: 000000000000002b RBX: dffffc0000000000 RCX: 1ffffffff4bd2afe
[    2.043820][    C0] RDX: 0000000000000000 RSI: 0000000000000000 RDI: ffffffffa011ca80
[    2.043820][    C0] RBP: ffffc9000001fbc8 R08: 0000000000000001 R09: fffffbfff4bd2a41
[    2.043820][    C0] R10: ffffffffa5e95207 R11: fffffbfff4bd2a40 R12: 0000000000000000
[    2.043820][    C0] R13: 0000000000000000 R14: ffffffffa47076e8 R15: 0000000000000000
[ 2.043820][ C0] ? console_unlock (arch/x86/include/asm/irqflags.h:45 arch/x86/include/asm/irqflags.h:80 arch/x86/include/asm/irqflags.h:138 kernel/printk/printk.c:2715) 
[ 2.043820][ C0] ? do_syslog (kernel/printk/printk.c:2088) 
[ 2.043820][ C0] ? rcu_read_unlock (include/linux/rcupdate.h:723 (discriminator 5)) 
[ 2.043820][ C0] ? vprintk_emit (kernel/printk/printk.c:1843 kernel/printk/printk.c:2244) 
[ 2.043820][ C0] vprintk_emit (arch/x86/include/asm/preempt.h:85 kernel/printk/printk.c:2246) 
[ 2.043820][ C0] _printk (kernel/printk/printk.c:2261) 
[ 2.043820][ C0] ? record_print_text.cold (kernel/printk/printk.c:2261) 
[ 2.043820][ C0] native_smp_prepare_cpus (arch/x86/kernel/smpboot.c:1432) 
[ 2.043820][ C0] kernel_init_freeable (include/linux/compiler.h:241 include/linux/init.h:124 init/main.c:1417 init/main.c:1602) 
[ 2.043820][ C0] ? lockdep_hardirqs_on_prepare (kernel/locking/lockdep.c:4232 kernel/locking/lockdep.c:4292 kernel/locking/lockdep.c:4244) 
[ 2.043820][ C0] ? console_on_rootfs (init/main.c:1584) 
[ 2.043820][ C0] ? rwlock_bug+0xc0/0xc0 
[ 2.043820][ C0] ? rest_init (init/main.c:1494) 
[ 2.043820][ C0] kernel_init (init/main.c:1504) 
[ 2.043820][ C0] ret_from_fork (arch/x86/entry/entry_64.S:301) 
[    2.043820][    C0]  </TASK>
[    2.107153][    T1] cblist_init_generic: Setting adjustable number of callback queues.
[    2.107829][    T1] cblist_init_generic: Setting shift to 1 and lim to 1.
[    2.109039][    T1] cblist_init_generic: Setting shift to 1 and lim to 1.
[    2.110002][    T1] Running RCU-tasks wait API self tests
[    2.111302][    T1] Performance Events: unsupported p6 CPU model 42 no PMU driver, software events only.
[    2.113022][    T1] rcu: Hierarchical SRCU implementation.
[    2.114307][   T14]
[    2.114774][   T14] =============================
[    2.114820][   T14] WARNING: suspicious RCU usage
[    2.114820][   T14] 5.17.0-rc5-00050-gd67e27e3d2e9 #1 Not tainted
[    2.114820][   T14] -----------------------------
[    2.114820][   T14] include/linux/cgroup.h:481 suspicious rcu_dereference_check() usage!
[    2.114820][   T14]
[    2.114820][   T14] other info that might help us debug this:
[    2.114820][   T14]
[    2.114820][   T14] rcu_scheduler_active = 1, debug_locks = 1
[    2.114820][   T14] 1 lock held by migration/0/14:
[ 2.114820][ T14] #0: ffff88839d439b58 (&rq->__lock){-...}-{2:2}, at: __schedule (kernel/sched/sched.h:1539 (discriminator 3) kernel/sched/sched.h:1617 (discriminator 3) kernel/sched/core.c:6220 (discriminator 3)) 
[    2.114820][   T14]
[    2.114820][   T14] stack backtrace:
[    2.114820][   T14] CPU: 0 PID: 14 Comm: migration/0 Not tainted 5.17.0-rc5-00050-gd67e27e3d2e9 #1
[    2.114820][   T14] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.12.0-1 04/01/2014
[    2.114820][   T14] Stopper: 0x0 <- 0x0
[    2.114820][   T14] Call Trace:
[    2.114820][   T14]  <TASK>
[ 2.114820][ T14] dump_stack_lvl (lib/dump_stack.c:107) 
[ 2.114820][ T14] put_prev_task_stop (include/linux/cgroup.h:481 include/linux/cgroup.h:550 include/linux/cgroup.h:794 kernel/sched/stop_task.c:88) 
[ 2.114820][ T14] __schedule (kernel/sched/core.c:5635 kernel/sched/core.c:6122 kernel/sched/core.c:6270) 
[ 2.114820][ T14] ? io_schedule_timeout (kernel/sched/core.c:6185) 
[ 2.114820][ T14] ? lockdep_hardirqs_on_prepare (kernel/locking/lockdep.c:4232 kernel/locking/lockdep.c:4292 kernel/locking/lockdep.c:4244) 
[ 2.114820][ T14] ? smpboot_thread_fn (kernel/smpboot.c:112) 
[ 2.114820][ T14] schedule (include/linux/instrumented.h:71 (discriminator 1) include/asm-generic/bitops/instrumented-non-atomic.h:134 (discriminator 1) include/linux/thread_info.h:118 (discriminator 1) include/linux/sched.h:2135 (discriminator 1) kernel/sched/core.c:6381 (discriminator 1)) 
[ 2.114820][ T14] smpboot_thread_fn (kernel/smpboot.c:160) 
[ 2.114820][ T14] ? smpboot_register_percpu_thread (kernel/smpboot.c:107) 
[ 2.114820][ T14] kthread (kernel/kthread.c:377) 
[ 2.114820][ T14] ? kthread_complete_and_exit (kernel/kthread.c:332) 
[ 2.114820][ T14] ret_from_fork (arch/x86/entry/entry_64.S:301) 
[    2.114820][   T14]  </TASK>
[    2.118750][    T1] NMI watchdog: Perf NMI watchdog permanently disabled
[    2.118919][   T10] Callback from call_rcu_tasks_trace() invoked.
[    2.120449][    T1] smp: Bringing up secondary CPUs ...
[    2.122366][    T1] x86: Booting SMP configuration:
[    2.122854][    T1] .... node  #0, CPUs:      #1
[    0.120445][    T0] masked ExtINT on CPU#1
[    0.120445][    T0] smpboot: CPU 1 Converting physical 0 to logical die 1
[    2.125083][    T1] smp: Brought up 1 node, 2 CPUs
[    2.125850][    T1] smpboot: Max logical packages: 2
[    2.126665][    T1] smpboot: Total of 2 processors activated (10774.03 BogoMIPS)
[    2.219942][    T9] Callback from call_rcu_tasks_rude() invoked.
[    2.282486][   T21] node 0 deferred pages initialised in 154ms
[    2.384701][    T1] allocated 201326592 bytes of page_ext
[    2.385983][    T1] Node 0, zone      DMA: page owner found early allocated 0 pages
[    2.389880][    T1] Node 0, zone    DMA32: page owner found early allocated 0 pages
[    2.412052][    T1] Node 0, zone   Normal: page owner found early allocated 50469 pages
[    2.415224][    T1] devtmpfs: initialized
[    2.416685][    T1] x86/mm: Memory block size: 128MB
[    2.451512][    T1] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns
[    2.451870][    T1] futex hash table entries: 512 (order: 4, 65536 bytes, linear)
[    2.453960][    T1] pinctrl core: initialized pinctrl subsystem
[    2.459513][    T1] NET: Registered PF_NETLINK/PF_ROUTE protocol family
[    2.461798][    T1] audit: initializing netlink subsys (disabled)
[    2.463438][   T27] audit: type=2000 audit(1645945121.029:1): state=initialized audit_enabled=0 res=1
[    2.464201][    T1] thermal_sys: Registered thermal governor 'fair_share'
[    2.464832][    T1] thermal_sys: Registered thermal governor 'bang_bang'
[    2.465832][    T1] thermal_sys: Registered thermal governor 'step_wise'
[    2.466832][    T1] thermal_sys: Registered thermal governor 'user_space'
[    2.467955][    T1] cpuidle: using governor menu
[    2.471077][    T1] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
[    2.473505][    T1] PCI: Using configuration type 1 for base access
[    2.518308][    T1] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.
[    2.520331][    T1] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
[    2.522793][    T1] cryptd: max_cpu_qlen set to 1000
[    2.526542][    T1] ACPI: Added _OSI(Module Device)
[    2.526833][    T1] ACPI: Added _OSI(Processor Device)
[    2.527652][    T1] ACPI: Added _OSI(3.0 _SCP Extensions)
[    2.527831][    T1] ACPI: Added _OSI(Processor Aggregator Device)
[    2.528793][    T1] ACPI: Added _OSI(Linux-Dell-Video)
[    2.528841][    T1] ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio)
[    2.529770][    T1] ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics)
[    2.561600][    T1] ACPI: 1 ACPI AML tables successfully acquired and loaded
[    2.571121][    T1] ACPI: Interpreter enabled
[    2.572018][    T1] ACPI: PM: (supports S0 S3 S4 S5)
[    2.572829][    T1] ACPI: Using IOAPIC for interrupt routing
[    2.573901][    T1] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[    2.577064][    T1] ACPI: Enabled 2 GPEs in block 00 to 0F
[    2.647225][    T1] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
[    2.647879][    T1] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3]
[    2.648833][    T1] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI]
[    2.650156][    T1] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge.
[    2.658220][    T1] acpiphp: Slot [3] registered
[    2.659111][    T1] acpiphp: Slot [4] registered
[    2.660104][    T1] acpiphp: Slot [5] registered
[    2.661093][    T1] acpiphp: Slot [6] registered
[    2.662118][    T1] acpiphp: Slot [7] registered
[    2.663081][    T1] acpiphp: Slot [8] registered
[    2.664113][    T1] acpiphp: Slot [9] registered
[    2.665095][    T1] acpiphp: Slot [10] registered
[    2.666121][    T1] acpiphp: Slot [11] registered
[    2.667081][    T1] acpiphp: Slot [12] registered
[    2.668105][    T1] acpiphp: Slot [13] registered
[    2.669093][    T1] acpiphp: Slot [14] registered
[    2.670095][    T1] acpiphp: Slot [15] registered
[    2.671081][    T1] acpiphp: Slot [16] registered
[    2.672112][    T1] acpiphp: Slot [17] registered
[    2.673081][    T1] acpiphp: Slot [18] registered
[    2.674081][    T1] acpiphp: Slot [19] registered
[    2.675092][    T1] acpiphp: Slot [20] registered
[    2.676132][    T1] acpiphp: Slot [21] registered
[    2.677090][    T1] acpiphp: Slot [22] registered
[    2.678081][    T1] acpiphp: Slot [23] registered
[    2.679092][    T1] acpiphp: Slot [24] registered
[    2.680101][    T1] acpiphp: Slot [25] registered
[    2.681081][    T1] acpiphp: Slot [26] registered
[    2.682093][    T1] acpiphp: Slot [27] registered
[    2.683081][    T1] acpiphp: Slot [28] registered
[    2.684117][    T1] acpiphp: Slot [29] registered
[    2.685081][    T1] acpiphp: Slot [30] registered
[    2.686103][    T1] acpiphp: Slot [31] registered
[    2.686951][    T1] PCI host bridge to bus 0000:00
[    2.687751][    T1] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]
[    2.687838][    T1] pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window]
[    2.688838][    T1] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
[    2.689838][    T1] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window]
[    2.690838][    T1] pci_bus 0000:00: root bus resource [mem 0x440000000-0x4bfffffff window]
[    2.691841][    T1] pci_bus 0000:00: root bus resource [bus 00-ff]
[    2.693074][    T1] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000
[    2.696096][    T1] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100
[    2.698651][    T1] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180
[    2.701427][    T1] pci 0000:00:01.1: reg 0x20: [io  0xc040-0xc04f]
[    2.702852][    T1] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io  0x01f0-0x01f7]
[    2.703832][    T1] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io  0x03f6]
[    2.704831][    T1] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io  0x0170-0x0177]
[    2.705831][    T1] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io  0x0376]
[    2.707539][    T1] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000
[    2.708255][    T1] pci 0000:00:01.3: quirk: [io  0x0600-0x063f] claimed by PIIX4 ACPI
[    2.708842][    T1] pci 0000:00:01.3: quirk: [io  0x0700-0x070f] claimed by PIIX4 SMB
[    2.712297][    T1] pci 0000:00:02.0: [1234:1111] type 00 class 0x030000
[    2.713848][    T1] pci 0000:00:02.0: reg 0x10: [mem 0xfd000000-0xfdffffff pref]
[    2.716762][    T1] pci 0000:00:02.0: reg 0x18: [mem 0xfebf0000-0xfebf0fff]
[    2.720836][    T1] pci 0000:00:02.0: reg 0x30: [mem 0xfebe0000-0xfebeffff pref]
[    2.721972][    T1] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
[    2.724393][    T1] pci 0000:00:03.0: [8086:100e] type 00 class 0x020000
[    2.725664][    T1] pci 0000:00:03.0: reg 0x10: [mem 0xfebc0000-0xfebdffff]
[    2.726602][    T1] pci 0000:00:03.0: reg 0x14: [io  0xc000-0xc03f]
[    2.730172][    T1] pci 0000:00:03.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref]
[    2.732372][    T1] pci 0000:00:04.0: [8086:25ab] type 00 class 0x088000
[    2.733239][    T1] pci 0000:00:04.0: reg 0x10: [mem 0xfebf1000-0xfebf100f]
[    2.745832][    T1] ACPI: PCI: Interrupt link LNKA configured for IRQ 10
[    2.748692][    T1] ACPI: PCI: Interrupt link LNKB configured for IRQ 10
[    2.750674][    T1] ACPI: PCI: Interrupt link LNKC configured for IRQ 11
[    2.752663][    T1] ACPI: PCI: Interrupt link LNKD configured for IRQ 11
[    2.753557][    T1] ACPI: PCI: Interrupt link LNKS configured for IRQ 9
[    2.762061][    T1] iommu: Default domain type: Translated
[    2.762834][    T1] iommu: DMA domain TLB invalidation policy: lazy mode
[    2.764532][    T1] pci 0000:00:02.0: vgaarb: setting as boot VGA device
[    2.764820][    T1] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none
[    2.764833][    T1] pci 0000:00:02.0: vgaarb: bridge control possible
[    2.765817][    T1] vgaarb: loaded
[    2.768023][    T1] SCSI subsystem initialized


To reproduce:

        # build kernel
	cd linux
	cp config-5.17.0-rc5-00050-gd67e27e3d2e9 .config
	make HOSTCC=gcc-9 CC=gcc-9 ARCH=x86_64 olddefconfig prepare modules_prepare bzImage modules
	make HOSTCC=gcc-9 CC=gcc-9 ARCH=x86_64 INSTALL_MOD_PATH=<mod-install-dir> modules_install
	cd <mod-install-dir>
	find lib/ | cpio -o -H newc --quiet | gzip > modules.cgz


        git clone https://github.com/intel/lkp-tests.git
        cd lkp-tests
        bin/lkp qemu -k <bzImage> -m modules.cgz job-script # job-script is attached in this email

        # if come across any failure that blocks the test,
        # please remove ~/.lkp and /lkp dir to run from a clean state.



---
0DAY/LKP+ Test Infrastructure                   Open Source Technology Center
https://lists.01.org/hyperkitty/list/lkp@lists.01.org       Intel Corporation

Thanks,
Oliver Sang


View attachment "config-5.17.0-rc5-00050-gd67e27e3d2e9" of type "text/plain" (166016 bytes)

View attachment "job-script" of type "text/plain" (4637 bytes)

Download attachment "dmesg.xz" of type "application/x-xz" (16768 bytes)

Powered by blists - more mailing lists

Powered by Openwall GNU/*/Linux Powered by OpenVZ