| 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
| ||
|
Message-ID: <20180419024159.jf7nmqobrazmu2aq@wfg-t540p.sh.intel.com>
Date: Thu, 19 Apr 2018 10:41:59 +0800
From: Fengguang Wu <fengguang.wu@...el.com>
To: devel@...ica.org
Cc: Bob Moore <robert.moore@...el.com>,
Erik Schmauss <erik.schmauss@...el.com>,
"Rafael J. Wysocki" <rafael.j.wysocki@...el.com>,
linux-acpi@...r.kernel.org, linux-mm@...ck.org,
linux-kernel@...r.kernel.org, lkp@...org
Subject: [acpica] watchdog: BUG: soft lockup - CPU#0 stuck for 22s!
[swapper:1]
Hello,
FYI this soft lockup happens in mainline kernel 4.17.0-rc1.
It at least dates back to v4.1 .
It occurs in 8 out of 9 boots.
[ 59.276648] osst :I: Tape driver with OnStream support version 0.99.4
[ 59.276648] osst :I: $Id: osst.c,v 1.73 2005/01/01 21:13:34 wriede Exp $
[ 59.493429] Rounding down aligned max_sectors from 4294967295 to 4294967288
[ 59.538489] e1000: Intel(R) PRO/1000 Network Driver - version 7.3.21-k8-NAPI
[ 59.551970] e1000: Copyright (c) 1999-2006 Intel Corporation.
[ 84.192080] watchdog: BUG: soft lockup - CPU#0 stuck for 22s! [swapper:1]
[ 84.192080] Modules linked in:
[ 84.192080] irq event stamp: 3978566
[ 84.192080] hardirqs last enabled at (3978565): [<ffffffff88145038>] __slab_alloc+0x48/0x80
[ 84.192080] hardirqs last disabled at (3978566): [<ffffffff898008ac>] interrupt_entry+0xac/0xc0:
interrupt_entry at arch/x86/entry/entry_64.S:627
[ 84.192080] softirqs last enabled at (3978494): [<ffffffff89a0045b>] __do_softirq+0x45b/0x5e5
[ 84.192080] softirqs last disabled at (3978487): [<ffffffff87ed30be>] irq_exit+0x12e/0x160:
invoke_softirq at kernel/softirq.c:365
(inlined by) irq_exit at kernel/softirq.c:405
[ 84.192080] CPU: 0 PID: 1 Comm: swapper Not tainted 4.17.0-rc1 #259
[ 84.192080] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 84.192080] RIP: 0010:__slab_alloc+0x4d/0x80
[ 84.192080] RSP: 0000:ffff88001709f088 EFLAGS: 00000246 ORIG_RAX: ffffffffffffff13
[ 84.192080] RAX: 0000000000000007 RBX: 0000000000000246 RCX: 0000000000000018
[ 84.192080] RDX: 0000000000000000 RSI: ffff880017092f48 RDI: 0000000000000246
[ 84.192080] RBP: ffff88000abc5008 R08: 0000000000000001 R09: 0000000000000000
[ 84.192080] R10: 0000000000000000 R11: 0000000000000000 R12: 00000000014080c0
[ 84.192080] R13: ffffffff8898e7d1 R14: ffff88001c48ab00 R15: ffff8800168fe034
[ 84.192080] FS: 0000000000000000(0000) GS:ffffffff8a457000(0000) knlGS:0000000000000000
[ 84.192080] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 84.192080] CR2: 0000000000000000 CR3: 0000000012a25001 CR4: 00000000000206f0
[ 84.192080] Call Trace:
[ 84.192080] ? acpi_ut_create_generic_state+0x54/0x8c:
acpi_ut_create_generic_state at drivers/acpi/acpica/utstate.c:91
[ 84.192080] kmem_cache_alloc+0xeb/0x120:
slab_alloc_node at mm/slub.c:2707
(inlined by) slab_alloc at mm/slub.c:2749
(inlined by) kmem_cache_alloc at mm/slub.c:2754
[ 84.192080] acpi_ut_create_generic_state+0x54/0x8c:
acpi_ut_create_generic_state at drivers/acpi/acpica/utstate.c:91
[ 84.192080] acpi_ut_create_update_state+0xe/0x9c:
acpi_ut_create_update_state at drivers/acpi/acpica/utstate.c:166
[ 84.192080] acpi_ut_create_update_state_and_push+0x13/0x31:
acpi_ut_create_update_state_and_push at drivers/acpi/acpica/utmisc.c:172
[ 84.192080] acpi_ut_update_object_reference+0x253/0x433:
acpi_ut_update_object_reference at drivers/acpi/acpica/utdelete.c:549
[ 84.192080] ? acpi_ut_update_ref_count+0x8b3/0x8b3:
acpi_ut_update_object_reference at drivers/acpi/acpica/utdelete.c:467
[ 84.192080] ? sched_clock_cpu+0x71/0x80:
__preempt_count_sub at arch/x86/include/asm/preempt.h:81
(inlined by) sched_clock_cpu at kernel/sched/clock.c:363
[ 84.192080] acpi_ds_do_implicit_return+0xbd/0xca:
acpi_ds_do_implicit_return at drivers/acpi/acpica/dsutils.c:117
[ 84.192080] acpi_ds_is_result_used+0x63/0x2be:
acpi_ds_is_result_used at drivers/acpi/acpica/dsutils.c:168
[ 84.192080] acpi_ds_delete_result_if_not_used+0x8c/0xde:
acpi_ds_delete_result_if_not_used at drivers/acpi/acpica/dsutils.c:321
[ 84.192080] ? acpi_ds_is_result_used+0x2be/0x2be:
acpi_ds_delete_result_if_not_used at drivers/acpi/acpica/dsutils.c:306
[ 84.192080] ? acpi_ds_result_push+0x108/0x277:
acpi_ds_result_stack_push at drivers/acpi/acpica/dswstate.c:208
(inlined by) acpi_ds_result_push at drivers/acpi/acpica/dswstate.c:128
[ 84.192080] acpi_ds_exec_end_op+0xb9d/0xbef:
acpi_ds_exec_end_op at drivers/acpi/acpica/dswexec.c:719
[ 84.192080] ? acpi_ds_exec_begin_op+0x3f8/0x3f8:
acpi_ds_exec_end_op at drivers/acpi/acpica/dswexec.c:325
[ 84.192080] acpi_ps_parse_loop+0x1095/0x112d:
acpi_ps_parse_loop at drivers/acpi/acpica/psloop.c:644
[ 84.192080] ? acpi_ps_get_next_arg+0x9f6/0x9f6:
acpi_ps_parse_loop at drivers/acpi/acpica/psloop.c:414
[ 84.192080] ? trace_hardirqs_on_caller+0x3e4/0x550:
__trace_hardirqs_on_caller at kernel/locking/lockdep.c:2836
(inlined by) trace_hardirqs_on_caller at kernel/locking/lockdep.c:2883
[ 84.192080] ? kmem_cache_alloc+0xb1/0x120:
slab_post_alloc_hook at mm/slab.h:444
(inlined by) slab_alloc_node at mm/slub.c:2741
(inlined by) slab_alloc at mm/slub.c:2749
(inlined by) kmem_cache_alloc at mm/slub.c:2754
[ 84.192080] acpi_ps_parse_aml+0x1e0/0x6a6:
acpi_ps_parse_aml at drivers/acpi/acpica/psparse.c:475
[ 84.192080] acpi_ps_execute_method+0x48d/0x4ea:
acpi_ps_execute_method at drivers/acpi/acpica/psxface.c:187
[ 84.192080] ? acpi_ut_acquire_mutex+0x6c/0x10a:
acpi_ut_acquire_mutex at drivers/acpi/acpica/utmutex.c:243
[ 84.192080] acpi_ns_evaluate+0x663/0x8a9:
acpi_ns_evaluate at drivers/acpi/acpica/nseval.c:204
[ 84.192080] ? acpi_ut_evaluate_object+0x74/0x337:
kmem_cache_alloc_trace at include/linux/slab.h:415
(inlined by) kmalloc at include/linux/slab.h:512
(inlined by) kzalloc at include/linux/slab.h:701
(inlined by) acpi_os_allocate_zeroed at include/acpi/platform/aclinuxex.h:57
(inlined by) acpi_ut_evaluate_object at drivers/acpi/acpica/uteval.c:50
[ 84.192080] acpi_ut_evaluate_object+0xe9/0x337:
acpi_ut_evaluate_object at drivers/acpi/acpica/uteval.c:61
[ 84.192080] acpi_rs_get_prt_method_data+0x74/0xca:
acpi_rs_get_prt_method_data at drivers/acpi/acpica/rsutils.c:448
[ 84.192080] ? acpi_rs_set_resource_source+0x154/0x154:
acpi_rs_get_prt_method_data at drivers/acpi/acpica/rsutils.c:435
[ 84.192080] ? acpi_rs_validate_parameters+0x63/0x96:
acpi_rs_validate_parameters at drivers/acpi/acpica/rsxface.c:90
[ 84.192080] acpi_get_irq_routing_table+0x76/0xad:
acpi_get_irq_routing_table at drivers/acpi/acpica/rsxface.c:124
[ 84.192080] ? acpi_rs_match_vendor_resource+0x2ea/0x2ea:
acpi_get_irq_routing_table at drivers/acpi/acpica/rsxface.c:124
[ 84.192080] ? deref_stack_reg+0x31/0x100:
deref_stack_reg at arch/x86/kernel/unwind_orc.c:316
[ 84.192080] ? deref_stack_reg+0xc7/0x100:
deref_stack_reg at arch/x86/kernel/unwind_orc.c:320 (discriminator 2)
[ 84.192080] acpi_pci_irq_find_prt_entry+0x166/0xbf0:
acpi_pci_irq_find_prt_entry at drivers/acpi/pci_irq.c:235
[ 84.192080] ? sched_clock_local+0x88/0xb0
[ 84.192080] ? acpi_penalize_sci_irq+0x30/0x30:
acpi_pci_irq_find_prt_entry at drivers/acpi/pci_irq.c:221
[ 84.192080] acpi_pci_irq_lookup+0x7e/0x640:
acpi_pci_irq_lookup at drivers/acpi/pci_irq.c:319
[ 84.192080] ? _raw_spin_unlock_irqrestore+0x2d/0x60:
arch_local_irq_restore at arch/x86/include/asm/paravirt.h:783
(inlined by) __raw_spin_unlock_irqrestore at include/linux/spinlock_api_smp.h:160
(inlined by) _raw_spin_unlock_irqrestore at kernel/locking/spinlock.c:184
[ 84.192080] ? acpi_pci_irq_find_prt_entry+0xbf0/0xbf0:
acpi_pci_irq_lookup at drivers/acpi/pci_irq.c:312
[ 84.192080] ? pci_conf1_read+0xa3/0x1f0:
pci_conf1_read at arch/x86/pci/direct.c:50
[ 84.192080] acpi_pci_irq_enable+0x1a1/0x520:
acpi_pci_irq_enable at drivers/acpi/pci_irq.c:439
[ 84.192080] ? acpi_pci_irq_lookup+0x640/0x640:
acpi_pci_irq_enable at drivers/acpi/pci_irq.c:409
[ 84.192080] ? pci_enable_resources+0x7f/0x240:
pci_enable_resources at drivers/pci/setup-res.c:461
[ 84.192080] ? pci_resize_resource+0x350/0x350:
pci_enable_resources at drivers/pci/setup-res.c:456
[ 84.192080] do_pci_enable_device+0xda/0x180:
do_pci_enable_device at drivers/pci/pci.c:1316
[ 84.192080] ? pci_load_and_free_saved_state+0xa0/0xa0:
do_pci_enable_device at drivers/pci/pci.c:1301
[ 84.192080] ? find_held_lock+0x3e/0x1c0:
find_held_lock at kernel/locking/lockdep.c:3536
[ 84.192080] ? __lock_is_held+0xb5/0x140:
__lock_is_held at kernel/locking/lockdep.c:3740
[ 84.192080] pci_enable_device_flags+0x248/0x360:
pci_enable_device_flags at drivers/pci/pci.c:1405
[ 84.192080] ? pci_enable_bridge+0x1e0/0x1e0:
pci_enable_device_flags at drivers/pci/pci.c:1372
[ 84.192080] e1000_probe+0x172/0x3a60:
e1000_probe at drivers/net/ethernet/intel/e1000/e1000_main.c:970
[ 84.192080] ? sched_clock_local+0x88/0xb0
[ 84.192080] ? e1000_io_slot_reset+0x120/0x120:
e1000_probe at drivers/net/ethernet/intel/e1000/e1000_main.c:947
[ 84.192080] ? lock_downgrade+0x5b0/0x5b0:
lock_release at kernel/locking/lockdep.c:3929
[ 84.192080] ? _raw_spin_unlock_irqrestore+0x2d/0x60:
arch_local_irq_restore at arch/x86/include/asm/paravirt.h:783
(inlined by) __raw_spin_unlock_irqrestore at include/linux/spinlock_api_smp.h:160
(inlined by) _raw_spin_unlock_irqrestore at kernel/locking/spinlock.c:184
[ 84.192080] ? trace_hardirqs_on_caller+0x3e4/0x550:
__trace_hardirqs_on_caller at kernel/locking/lockdep.c:2836
(inlined by) trace_hardirqs_on_caller at kernel/locking/lockdep.c:2883
[ 84.192080] pci_device_probe+0x268/0x460:
local_pci_probe at drivers/pci/pci-driver.c:305
(inlined by) pci_call_probe at drivers/pci/pci-driver.c:358
(inlined by) __pci_device_probe at drivers/pci/pci-driver.c:383
(inlined by) pci_device_probe at drivers/pci/pci-driver.c:423
[ 84.192080] driver_probe_device+0x545/0x800:
really_probe at drivers/base/dd.c:449
(inlined by) driver_probe_device at drivers/base/dd.c:590
[ 84.192080] __driver_attach+0x136/0x170:
__driver_attach at drivers/base/dd.c:824
[ 84.192080] ? driver_probe_device+0x800/0x800:
__driver_attach at drivers/base/dd.c:794
[ 84.192080] bus_for_each_dev+0x122/0x1d0:
bus_for_each_dev at drivers/base/bus.c:310
[ 84.192080] ? lock_downgrade+0x5b0/0x5b0:
lock_release at kernel/locking/lockdep.c:3929
[ 84.192080] ? store_drivers_autoprobe+0x130/0x130:
bus_for_each_dev at drivers/base/bus.c:300
[ 84.192080] bus_add_driver+0x42a/0x5c0:
bus_add_driver at drivers/base/bus.c:668
[ 84.192080] ? cavium_ptp_driver_init+0x15/0x15:
e1000_init_module at drivers/net/ethernet/intel/e1000/e1000_main.c:250
[ 84.192080] driver_register+0x182/0x3a0:
driver_register at drivers/base/driver.c:167
[ 84.192080] ? cavium_ptp_driver_init+0x15/0x15:
e1000_init_module at drivers/net/ethernet/intel/e1000/e1000_main.c:250
[ 84.192080] e1000_init_module+0x43/0x75:
e1000_init_module at drivers/net/ethernet/intel/e1000/e1000_main.c:257
[ 84.192080] do_one_initcall+0x97/0x230:
do_trace_initcall_finish at init/main.c:867
(inlined by) do_one_initcall at init/main.c:884
[ 84.192080] ? parameq+0xf0/0xf0:
parse_args at kernel/params.c:173
[ 84.192080] ? initcall_blacklisted+0x150/0x150:
do_one_initcall at init/main.c:874
[ 84.192080] ? __wake_up_common+0x4e0/0x4e0:
__wake_up_common_lock at kernel/sched/wait.c:109
[ 84.192080] ? lock_downgrade+0x5b0/0x5b0:
lock_release at kernel/locking/lockdep.c:3929
[ 84.192080] kernel_init_freeable+0x25f/0x2fb:
do_initcall_level at init/main.c:951
(inlined by) do_initcalls at init/main.c:959
(inlined by) do_basic_setup at init/main.c:977
(inlined by) kernel_init_freeable at init/main.c:1127
[ 84.192080] ? rest_init+0x150/0x150:
kernel_init at init/main.c:1050
[ 84.192080] kernel_init+0xa/0x110:
kernel_init at init/main.c:1055
[ 84.192080] ? rest_init+0x150/0x150:
kernel_init at init/main.c:1050
[ 84.192080] ret_from_fork+0x24/0x30:
ret_from_fork at arch/x86/entry/entry_64.S:418
[ 84.192080] Code: 44 00 00 e8 16 54 e1 ff 48 89 ef 4c 89 f1 4c 89 ea 44 89 e6 e8 d5 fb ff ff f6 c7 02 48 89 c5 74 1b e8 88 51 e1 ff 48 89 df 57 9d <0f> 1f 44 00 00 5b 48 89 e8 5d 41 5c 41 5d 41 5e c3 48 89 df 57
[ 84.192080] Kernel panic - not syncing: softlockup: hung tasks
[ 84.192080] CPU: 0 PID: 1 Comm: swapper Tainted: G L 4.17.0-rc1 #259
[ 84.192080] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 84.192080] Call Trace:
[ 84.192080] <IRQ>
[ 84.192080] panic+0x1a8/0x2e3:
panic at kernel/panic.c:195
[ 84.192080] ? refcount_error_report+0x28d/0x28d:
panic at kernel/panic.c:136
[ 84.192080] watchdog_timer_fn+0x248/0x250:
watchdog_timer_fn at kernel/watchdog.c:428
[ 84.192080] ? softlockup_update_smpboot_threads+0x80/0x80:
watchdog_timer_fn at kernel/watchdog.c:340
[ 84.192080] __hrtimer_run_queues+0x1b5/0x480
[ 84.192080] hrtimer_run_queues+0x85/0xa0:
hrtimer_run_queues at kernel/time/hrtimer.c:1621
[ 84.192080] run_local_timers+0x5/0x50:
run_local_timers at kernel/time/timer.c:1706
[ 84.192080] update_process_times+0x1b/0x50:
update_process_times at kernel/time/timer.c:1636
[ 84.192080] tick_periodic+0x46/0x140
[ 84.192080] tick_handle_periodic+0x29/0x90:
clockevent_state_oneshot at include/linux/clockchips.h:152
(inlined by) tick_handle_periodic at kernel/time/tick-common.c:116
[ 84.192080] smp_apic_timer_interrupt+0x77/0xc0:
exiting_irq at arch/x86/include/asm/apic.h:525
(inlined by) smp_apic_timer_interrupt at arch/x86/kernel/apic/apic.c:1052
[ 84.192080] apic_timer_interrupt+0xf/0x20:
apic_timer_interrupt at arch/x86/entry/entry_64.S:863
[ 84.192080] </IRQ>
[ 84.192080] RIP: 0010:__slab_alloc+0x4d/0x80
[ 84.192080] RSP: 0000:ffff88001709f088 EFLAGS: 00000246 ORIG_RAX: ffffffffffffff13
[ 84.192080] RAX: 0000000000000007 RBX: 0000000000000246 RCX: 0000000000000018
[ 84.192080] RDX: 0000000000000000 RSI: ffff880017092f48 RDI: 0000000000000246
[ 84.192080] RBP: ffff88000abc5008 R08: 0000000000000001 R09: 0000000000000000
[ 84.192080] R10: 0000000000000000 R11: 0000000000000000 R12: 00000000014080c0
[ 84.192080] R13: ffffffff8898e7d1 R14: ffff88001c48ab00 R15: ffff8800168fe034
[ 84.192080] ? acpi_ut_create_generic_state+0x54/0x8c:
acpi_ut_create_generic_state at drivers/acpi/acpica/utstate.c:91
[ 84.192080] kmem_cache_alloc+0xeb/0x120:
slab_alloc_node at mm/slub.c:2707
(inlined by) slab_alloc at mm/slub.c:2749
(inlined by) kmem_cache_alloc at mm/slub.c:2754
[ 84.192080] acpi_ut_create_generic_state+0x54/0x8c:
acpi_ut_create_generic_state at drivers/acpi/acpica/utstate.c:91
[ 84.192080] acpi_ut_create_update_state+0xe/0x9c:
acpi_ut_create_update_state at drivers/acpi/acpica/utstate.c:166
[ 84.192080] acpi_ut_create_update_state_and_push+0x13/0x31:
acpi_ut_create_update_state_and_push at drivers/acpi/acpica/utmisc.c:172
[ 84.192080] acpi_ut_update_object_reference+0x253/0x433:
acpi_ut_update_object_reference at drivers/acpi/acpica/utdelete.c:549
[ 84.192080] ? acpi_ut_update_ref_count+0x8b3/0x8b3:
acpi_ut_update_object_reference at drivers/acpi/acpica/utdelete.c:467
[ 84.192080] ? sched_clock_cpu+0x71/0x80:
__preempt_count_sub at arch/x86/include/asm/preempt.h:81
(inlined by) sched_clock_cpu at kernel/sched/clock.c:363
[ 84.192080] acpi_ds_do_implicit_return+0xbd/0xca:
acpi_ds_do_implicit_return at drivers/acpi/acpica/dsutils.c:117
[ 84.192080] acpi_ds_is_result_used+0x63/0x2be:
acpi_ds_is_result_used at drivers/acpi/acpica/dsutils.c:168
[ 84.192080] acpi_ds_delete_result_if_not_used+0x8c/0xde:
acpi_ds_delete_result_if_not_used at drivers/acpi/acpica/dsutils.c:321
[ 84.192080] ? acpi_ds_is_result_used+0x2be/0x2be:
acpi_ds_delete_result_if_not_used at drivers/acpi/acpica/dsutils.c:306
[ 84.192080] ? acpi_ds_result_push+0x108/0x277:
acpi_ds_result_stack_push at drivers/acpi/acpica/dswstate.c:208
(inlined by) acpi_ds_result_push at drivers/acpi/acpica/dswstate.c:128
[ 84.192080] acpi_ds_exec_end_op+0xb9d/0xbef:
acpi_ds_exec_end_op at drivers/acpi/acpica/dswexec.c:719
[ 84.192080] ? acpi_ds_exec_begin_op+0x3f8/0x3f8:
acpi_ds_exec_end_op at drivers/acpi/acpica/dswexec.c:325
[ 84.192080] acpi_ps_parse_loop+0x1095/0x112d:
acpi_ps_parse_loop at drivers/acpi/acpica/psloop.c:644
[ 84.192080] ? acpi_ps_get_next_arg+0x9f6/0x9f6:
acpi_ps_parse_loop at drivers/acpi/acpica/psloop.c:414
[ 84.192080] ? trace_hardirqs_on_caller+0x3e4/0x550:
__trace_hardirqs_on_caller at kernel/locking/lockdep.c:2836
(inlined by) trace_hardirqs_on_caller at kernel/locking/lockdep.c:2883
[ 84.192080] ? kmem_cache_alloc+0xb1/0x120:
slab_post_alloc_hook at mm/slab.h:444
(inlined by) slab_alloc_node at mm/slub.c:2741
(inlined by) slab_alloc at mm/slub.c:2749
(inlined by) kmem_cache_alloc at mm/slub.c:2754
[ 84.192080] acpi_ps_parse_aml+0x1e0/0x6a6:
acpi_ps_parse_aml at drivers/acpi/acpica/psparse.c:475
[ 84.192080] acpi_ps_execute_method+0x48d/0x4ea:
acpi_ps_execute_method at drivers/acpi/acpica/psxface.c:187
[ 84.192080] ? acpi_ut_acquire_mutex+0x6c/0x10a:
acpi_ut_acquire_mutex at drivers/acpi/acpica/utmutex.c:243
[ 84.192080] acpi_ns_evaluate+0x663/0x8a9:
acpi_ns_evaluate at drivers/acpi/acpica/nseval.c:204
[ 84.192080] ? acpi_ut_evaluate_object+0x74/0x337:
kmem_cache_alloc_trace at include/linux/slab.h:415
(inlined by) kmalloc at include/linux/slab.h:512
(inlined by) kzalloc at include/linux/slab.h:701
(inlined by) acpi_os_allocate_zeroed at include/acpi/platform/aclinuxex.h:57
(inlined by) acpi_ut_evaluate_object at drivers/acpi/acpica/uteval.c:50
[ 84.192080] acpi_ut_evaluate_object+0xe9/0x337:
acpi_ut_evaluate_object at drivers/acpi/acpica/uteval.c:61
[ 84.192080] acpi_rs_get_prt_method_data+0x74/0xca:
acpi_rs_get_prt_method_data at drivers/acpi/acpica/rsutils.c:448
[ 84.192080] ? acpi_rs_set_resource_source+0x154/0x154:
acpi_rs_get_prt_method_data at drivers/acpi/acpica/rsutils.c:435
[ 84.192080] ? acpi_rs_validate_parameters+0x63/0x96:
acpi_rs_validate_parameters at drivers/acpi/acpica/rsxface.c:90
[ 84.192080] acpi_get_irq_routing_table+0x76/0xad:
acpi_get_irq_routing_table at drivers/acpi/acpica/rsxface.c:124
[ 84.192080] ? acpi_rs_match_vendor_resource+0x2ea/0x2ea:
acpi_get_irq_routing_table at drivers/acpi/acpica/rsxface.c:124
[ 84.192080] ? deref_stack_reg+0x31/0x100:
deref_stack_reg at arch/x86/kernel/unwind_orc.c:316
[ 84.192080] ? deref_stack_reg+0xc7/0x100:
deref_stack_reg at arch/x86/kernel/unwind_orc.c:320 (discriminator 2)
[ 84.192080] acpi_pci_irq_find_prt_entry+0x166/0xbf0:
acpi_pci_irq_find_prt_entry at drivers/acpi/pci_irq.c:235
[ 84.192080] ? sched_clock_local+0x88/0xb0
[ 84.192080] ? acpi_penalize_sci_irq+0x30/0x30:
acpi_pci_irq_find_prt_entry at drivers/acpi/pci_irq.c:221
[ 84.192080] acpi_pci_irq_lookup+0x7e/0x640:
acpi_pci_irq_lookup at drivers/acpi/pci_irq.c:319
[ 84.192080] ? _raw_spin_unlock_irqrestore+0x2d/0x60:
arch_local_irq_restore at arch/x86/include/asm/paravirt.h:783
(inlined by) __raw_spin_unlock_irqrestore at include/linux/spinlock_api_smp.h:160
(inlined by) _raw_spin_unlock_irqrestore at kernel/locking/spinlock.c:184
[ 84.192080] ? acpi_pci_irq_find_prt_entry+0xbf0/0xbf0:
acpi_pci_irq_lookup at drivers/acpi/pci_irq.c:312
[ 84.192080] ? pci_conf1_read+0xa3/0x1f0:
pci_conf1_read at arch/x86/pci/direct.c:50
[ 84.192080] acpi_pci_irq_enable+0x1a1/0x520:
acpi_pci_irq_enable at drivers/acpi/pci_irq.c:439
[ 84.192080] ? acpi_pci_irq_lookup+0x640/0x640:
acpi_pci_irq_enable at drivers/acpi/pci_irq.c:409
[ 84.192080] ? pci_enable_resources+0x7f/0x240:
pci_enable_resources at drivers/pci/setup-res.c:461
[ 84.192080] ? pci_resize_resource+0x350/0x350:
pci_enable_resources at drivers/pci/setup-res.c:456
[ 84.192080] do_pci_enable_device+0xda/0x180:
do_pci_enable_device at drivers/pci/pci.c:1316
[ 84.192080] ? pci_load_and_free_saved_state+0xa0/0xa0:
do_pci_enable_device at drivers/pci/pci.c:1301
[ 84.192080] ? find_held_lock+0x3e/0x1c0:
find_held_lock at kernel/locking/lockdep.c:3536
[ 84.192080] ? __lock_is_held+0xb5/0x140:
__lock_is_held at kernel/locking/lockdep.c:3740
[ 84.192080] pci_enable_device_flags+0x248/0x360:
pci_enable_device_flags at drivers/pci/pci.c:1405
[ 84.192080] ? pci_enable_bridge+0x1e0/0x1e0:
pci_enable_device_flags at drivers/pci/pci.c:1372
[ 84.192080] e1000_probe+0x172/0x3a60:
e1000_probe at drivers/net/ethernet/intel/e1000/e1000_main.c:970
[ 84.192080] ? sched_clock_local+0x88/0xb0
[ 84.192080] ? e1000_io_slot_reset+0x120/0x120:
e1000_probe at drivers/net/ethernet/intel/e1000/e1000_main.c:947
[ 84.192080] ? lock_downgrade+0x5b0/0x5b0:
lock_release at kernel/locking/lockdep.c:3929
[ 84.192080] ? _raw_spin_unlock_irqrestore+0x2d/0x60:
arch_local_irq_restore at arch/x86/include/asm/paravirt.h:783
(inlined by) __raw_spin_unlock_irqrestore at include/linux/spinlock_api_smp.h:160
(inlined by) _raw_spin_unlock_irqrestore at kernel/locking/spinlock.c:184
[ 84.192080] ? trace_hardirqs_on_caller+0x3e4/0x550:
__trace_hardirqs_on_caller at kernel/locking/lockdep.c:2836
(inlined by) trace_hardirqs_on_caller at kernel/locking/lockdep.c:2883
[ 84.192080] pci_device_probe+0x268/0x460:
local_pci_probe at drivers/pci/pci-driver.c:305
(inlined by) pci_call_probe at drivers/pci/pci-driver.c:358
(inlined by) __pci_device_probe at drivers/pci/pci-driver.c:383
(inlined by) pci_device_probe at drivers/pci/pci-driver.c:423
[ 84.192080] driver_probe_device+0x545/0x800:
really_probe at drivers/base/dd.c:449
(inlined by) driver_probe_device at drivers/base/dd.c:590
[ 84.192080] __driver_attach+0x136/0x170:
__driver_attach at drivers/base/dd.c:824
[ 84.192080] ? driver_probe_device+0x800/0x800:
__driver_attach at drivers/base/dd.c:794
[ 84.192080] bus_for_each_dev+0x122/0x1d0:
bus_for_each_dev at drivers/base/bus.c:310
[ 84.192080] ? lock_downgrade+0x5b0/0x5b0:
lock_release at kernel/locking/lockdep.c:3929
[ 84.192080] ? store_drivers_autoprobe+0x130/0x130:
bus_for_each_dev at drivers/base/bus.c:300
[ 84.192080] bus_add_driver+0x42a/0x5c0:
bus_add_driver at drivers/base/bus.c:668
[ 84.192080] ? cavium_ptp_driver_init+0x15/0x15:
e1000_init_module at drivers/net/ethernet/intel/e1000/e1000_main.c:250
[ 84.192080] driver_register+0x182/0x3a0:
driver_register at drivers/base/driver.c:167
[ 84.192080] ? cavium_ptp_driver_init+0x15/0x15:
e1000_init_module at drivers/net/ethernet/intel/e1000/e1000_main.c:250
[ 84.192080] e1000_init_module+0x43/0x75:
e1000_init_module at drivers/net/ethernet/intel/e1000/e1000_main.c:257
[ 84.192080] do_one_initcall+0x97/0x230:
do_trace_initcall_finish at init/main.c:867
(inlined by) do_one_initcall at init/main.c:884
[ 84.192080] ? parameq+0xf0/0xf0:
parse_args at kernel/params.c:173
[ 84.192080] ? initcall_blacklisted+0x150/0x150:
do_one_initcall at init/main.c:874
[ 84.192080] ? __wake_up_common+0x4e0/0x4e0:
__wake_up_common_lock at kernel/sched/wait.c:109
[ 84.192080] ? lock_downgrade+0x5b0/0x5b0:
lock_release at kernel/locking/lockdep.c:3929
[ 84.192080] kernel_init_freeable+0x25f/0x2fb:
do_initcall_level at init/main.c:951
(inlined by) do_initcalls at init/main.c:959
(inlined by) do_basic_setup at init/main.c:977
(inlined by) kernel_init_freeable at init/main.c:1127
Attached the full dmesg, kconfig and reproduce scripts.
Thanks,
Fengguang
View attachment "dmesg-vm-lkp-wsx03-quantal-x86_64-4:20180417204156:x86_64-randconfig-u0-04171929:4.17.0-rc1:259" of type "text/plain" (39353 bytes)
View attachment ".config" of type "text/plain" (111202 bytes)
View attachment "job-script" of type "text/plain" (4045 bytes)
View attachment "reproduce-vm-lkp-wsx03-quantal-x86_64-4:20180417204156:x86_64-randconfig-u0-04171929:4.17.0-rc1:259" of type "text/plain" (1856 bytes)
Powered by blists - more mailing lists