INFO: task kworker/1:7:2811 blocked for more than 143 seconds.
Not tainted 5.2.0-rc1+ #10
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
kworker/1:7 D23896 2811 2 0x80004000
Workqueue: events request_firmware_work_func
Call Trace:
schedule+0x96/0x240 kernel/sched/core.c:3508
schedule_preempt_disabled+0xc/0x20 kernel/sched/core.c:3567
__mutex_lock_common kernel/locking/mutex.c:1002 [inline]
__mutex_lock+0x7fb/0x12b0 kernel/locking/mutex.c:1072
device_lock include/linux/device.h:1207 [inline]
p54u_load_firmware_cb.cold+0xfb/0x13d drivers/net/wireless/intersil/p54/p54usb.c:941
request_firmware_work_func+0x126/0x242 drivers/base/firmware_loader/main.c:785
process_one_work+0x905/0x1570 kernel/workqueue.c:2268
worker_thread+0x96/0xe20 kernel/workqueue.c:2414
kthread+0x30b/0x410 kernel/kthread.c:254
ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:352
Showing all locks held in the system:
11 locks held by kworker/1:0/17:
1 lock held by khungtaskd/23:
#0: 000000003d1f1d51 (rcu_read_lock){....}, at: debug_show_all_locks+0x53/0x269 kernel/locking/lockdep.c:5146
1 lock held by rsyslogd/1610:
2 locks held by getty/1700:
#0: 000000007634c964 (&tty->ldisc_sem){++++}, at: tty_ldisc_ref_wait+0x22/0x80 drivers/tty/tty_ldisc.c:272
#1: 000000002b6171b1 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x217/0x1a50 drivers/tty/n_tty.c:2156
2 locks held by getty/1701:
#0: 00000000bd3e1946 (&tty->ldisc_sem){++++}, at: tty_ldisc_ref_wait+0x22/0x80 drivers/tty/tty_ldisc.c:272
#1: 000000007b7718b7 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x217/0x1a50 drivers/tty/n_tty.c:2156
2 locks held by getty/1702:
#0: 00000000487caea2 (&tty->ldisc_sem){++++}, at: tty_ldisc_ref_wait+0x22/0x80 drivers/tty/tty_ldisc.c:272
#1: 000000002708df5d (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x217/0x1a50 drivers/tty/n_tty.c:2156
2 locks held by getty/1703:
#0: 00000000394cf9f8 (&tty->ldisc_sem){++++}, at: tty_ldisc_ref_wait+0x22/0x80 drivers/tty/tty_ldisc.c:272
#1: 00000000288a0bc3 (&ldata->atomic_read_lock
){+.+.}, at: n_tty_read+0x217/0x1a50 drivers/tty/n_tty.c:2156
2 locks held by getty/1704:
#0: 00000000a8739bf1 (&tty->ldisc_sem){++++}, at: tty_ldisc_ref_wait+0x22/0x80 drivers/tty/tty_ldisc.c:272
#1: 00000000e2029088 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x217/0x1a50 drivers/tty/n_tty.c:2156
2 locks held by getty/1705:
#0: 00000000cc783b9a (&tty->ldisc_sem){++++}, at: tty_ldisc_ref_wait+0x22/0x80 drivers/tty/tty_ldisc.c:272
#1: 000000001de2a033 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x217/0x1a50 drivers/tty/n_tty.c:2156
2 locks held by getty/1706:
#0: 0000000042022256 (&tty->ldisc_sem){++++}, at: tty_ldisc_ref_wait+0x22/0x80 drivers/tty/tty_ldisc.c:272
#1: 0000000044d2a72a (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x217/0x1a50 drivers/tty/n_tty.c:2156
6 locks held by kworker/0:3/2741:
6 locks held by kworker/1:4/2761:
#0: 0000000074804929 ((wq_completion)usb_hub_wq){+.+.}, at: __write_once_size include/linux/compiler.h:221 [inline]
#0: 0000000074804929 ((wq_completion)usb_hub_wq){+.+.}, at: arch_atomic64_set arch/x86/include/asm/atomic64_64.h:34 [inline]
#0: 0000000074804929 ((wq_completion)usb_hub_wq){+.+.}, at: atomic64_set include/asm-generic/atomic-instrumented.h:855 [inline]
#0: 0000000074804929 ((wq_completion)usb_hub_wq){+.+.}, at: atomic_long_set include/asm-generic/atomic-long.h:40 [inline]
#0: 0000000074804929 ((wq_completion)usb_hub_wq){+.+.}, at: set_work_data kernel/workqueue.c:619 [inline]
#0: 0000000074804929 ((wq_completion)usb_hub_wq){+.+.}, at: set_work_pool_and_clear_pending kernel/workqueue.c:646 [inline]
#0: 0000000074804929 ((wq_completion)usb_hub_wq){+.+.}, at: process_one_work+0x81a/0x1570 kernel/workqueue.c:2239
#1: 00000000b9e03930 ((work_completion)(&hub->events)){+.+.}, at: process_one_work+0x84e/0x1570 kernel/workqueue.c:2243
#2: 000000001924d700 (&dev->mutex){....}, at: device_lock include/linux/device.h:1207 [inline]
#2: 000000001924d700 (&dev->mutex){....}, at: hub_event+0x179/0x3590 drivers/usb/core/hub.c:5378
#3: 000000004ed53211 (&dev->mutex){....}, at: device_lock include/linux/device.h:1207 [inline]
#3: 000000004ed53211 (&dev->mutex){....}, at: __device_attach+0x7b/0x360 drivers/base/dd.c:820
#4: 00000000f5160fc0 (&dev->mutex){....}, at: device_lock include/linux/device.h:1207 [inline]
#4: 00000000f5160fc0 (&dev->mutex){....}, at: __device_attach+0x7b/0x360 drivers/base/dd.c:820
#5: 000000003bf9237e (&dev->mutex){....}, at: device_lock include/linux/device.h:1207 [inline]
#5: 000000003bf9237e (&dev->mutex){....}, at: __device_attach+0x7b/0x360 drivers/base/dd.c:820
6 locks held by kworker/1:5/2780:
5 locks held by kworker/1:6/2807:
#0: 0000000074804929 ((wq_completion)usb_hub_wq){+.+.}, at: __write_once_size include/linux/compiler.h:221 [inline]
#0: 0000000074804929 ((wq_completion)usb_hub_wq){+.+.}, at: arch_atomic64_set arch/x86/include/asm/atomic64_64.h:34 [inline]
#0: 0000000074804929 ((wq_completion)usb_hub_wq){+.+.}, at: atomic64_set include/asm-generic/atomic-instrumented.h:855 [inline]
#0: 0000000074804929 ((wq_completion)usb_hub_wq){+.+.}, at: atomic_long_set include/asm-generic/atomic-long.h:40 [inline]
#0: 0000000074804929 ((wq_completion)usb_hub_wq){+.+.}, at: set_work_data kernel/workqueue.c:619 [inline]
#0: 0000000074804929 ((wq_completion)usb_hub_wq){+.+.}, at: set_work_pool_and_clear_pending kernel/workqueue.c:646 [inline]
#0: 0000000074804929 ((wq_completion)usb_hub_wq){+.+.}, at: process_one_work+0x81a/0x1570 kernel/workqueue.c:2239
#1: 0000000066ebb596 ((work_completion)(&hub->events)){+.+.}, at: process_one_work+0x84e/0x1570 kernel/workqueue.c:2243
#2: 00000000b228fa1f (&dev->mutex){....}, at: device_lock include/linux/device.h:1207 [inline]
#2: 00000000b228fa1f (&dev->mutex){....}, at: hub_event+0x179/0x3590 drivers/usb/core/hub.c:5378
#3: 00000000caaaf8bf (&port_dev->status_lock){+.+.}, at: usb_lock_port drivers/usb/core/hub.c:2994 [inline]
#3: 00000000caaaf8bf (&port_dev->status_lock){+.+.}, at: hub_port_connect drivers/usb/core/hub.c:5020 [inline]
#3: 00000000caaaf8bf (&port_dev->status_lock){+.+.}, at: hub_port_connect_change drivers/usb/core/hub.c:5204 [inline]
#3: 00000000caaaf8bf (&port_dev->status_lock){+.+.}, at: port_event drivers/usb/core/hub.c:5350 [inline]
#3: 00000000caaaf8bf (&port_dev->status_lock){+.+.}, at: hub_event+0x18e8/0x3590 drivers/usb/core/hub.c:5432
#4: 000000001c265dfd (hcd->address0_mutex){+.+.}, at: hub_port_init+0x1b6/0x2c00 drivers/usb/core/hub.c:4529
3 locks held by kworker/1:7/2811:
#0: 00000000e59a24bf ((wq_completion)events){+.+.}, at: __write_once_size include/linux/compiler.h:221 [inline]
#0: 00000000e59a24bf ((wq_completion)events){+.+.}, at: arch_atomic64_set arch/x86/include/asm/atomic64_64.h:34 [inline]
#0: 00000000e59a24bf ((wq_completion)events){+.+.}, at: atomic64_set include/asm-generic/atomic-instrumented.h:855 [inline]
#0: 00000000e59a24bf ((wq_completion)events){+.+.}, at: atomic_long_set include/asm-generic/atomic-long.h:40 [inline]
#0: 00000000e59a24bf ((wq_completion)events){+.+.}, at: set_work_data kernel/workqueue.c:619 [inline]
#0: 00000000e59a24bf ((wq_completion)events){+.+.}, at: set_work_pool_and_clear_pending kernel/workqueue.c:646 [inline]
#0: 00000000e59a24bf ((wq_completion)events){+.+.}, at: process_one_work+0x81a/0x1570 kernel/workqueue.c:2239
#1: 000000006858a46c ((work_completion)(&fw_work->work)){+.+.}, at: process_one_work+0x84e/0x1570 kernel/workqueue.c:2243
#2: 00000000e9c37f4b (&dev->mutex){....}, at: device_lock include/linux/device.h:1207 [inline]
#2: 00000000e9c37f4b (&dev->mutex){....}, at: p54u_load_firmware_cb.cold+0xfb/0x13d drivers/net/wireless/intersil/p54/p54usb.c:941
3 locks held by kworker/1:3/13004:
#0: 0000000074804929 ((wq_completion)usb_hub_wq){+.+.}, at: __write_once_size include/linux/compiler.h:221 [inline]
#0: 0000000074804929 ((wq_completion)usb_hub_wq){+.+.}, at: arch_atomic64_set arch/x86/include/asm/atomic64_64.h:34 [inline]
#0: 0000000074804929 ((wq_completion)usb_hub_wq){+.+.}, at: atomic64_set include/asm-generic/atomic-instrumented.h:855 [inline]
#0: 0000000074804929 ((wq_completion)usb_hub_wq){+.+.}, at: atomic_long_set include/asm-generic/atomic-long.h:40 [inline]
#0: 0000000074804929 ((wq_completion)usb_hub_wq){+.+.}, at: set_work_data kernel/workqueue.c:619 [inline]
#0: 0000000074804929 ((wq_completion)usb_hub_wq){+.+.}, at: set_work_pool_and_clear_pending kernel/workqueue.c:646 [inline]
#0: 0000000074804929 ((wq_completion)usb_hub_wq){+.+.}, at: process_one_work+0x81a/0x1570 kernel/workqueue.c:2239
#1: 00000000cc271ac6 ((work_completion)(&hub->events)){+.+.}, at: process_one_work+0x84e/0x1570 kernel/workqueue.c:2243
#2: 0000000025afe3d2 (&dev->mutex){....}, at: device_lock include/linux/device.h:1207 [inline]
#2: 0000000025afe3d2 (&dev->mutex){....}, at: hub_event+0x179/0x3590 drivers/usb/core/hub.c:5378
1 lock held by syz-executor.1/15164:
=============================================
NMI backtrace for cpu 0
CPU: 0 PID: 23 Comm: khungtaskd Not tainted 5.2.0-rc1+ #10
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
Call Trace:
__dump_stack lib/dump_stack.c:77 [inline]
dump_stack+0xca/0x13e lib/dump_stack.c:113
nmi_cpu_backtrace.cold+0x48/0x87 lib/nmi_backtrace.c:101
nmi_trigger_cpumask_backtrace+0x1a6/0x1bd lib/nmi_backtrace.c:62
trigger_all_cpu_backtrace include/linux/nmi.h:146 [inline]
check_hung_uninterruptible_tasks kernel/hung_task.c:204 [inline]
watchdog+0x989/0xe20 kernel/hung_task.c:288
kthread+0x30b/0x410 kernel/kthread.c:254
ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:352
Sending NMI from CPU 0 to CPUs 1:
NMI backtrace for cpu 1
CPU: 1 PID: 13004 Comm: kworker/1:3 Not tainted 5.2.0-rc1+ #10
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
Workqueue: usb_hub_wq hub_event
RIP: 0010:debug_spin_lock_before kernel/locking/spinlock_debug.c:83 [inline]
RIP: 0010:do_raw_spin_lock+0x6d/0x280 kernel/locking/spinlock_debug.c:112
Code: 14 03 c7 02 f1 f1 f1 f1 c7 42 04 04 f3 f3 f3 48 89 fa 65 48 8b 0c 25 28 00 00 00 48 89 4c 24 60 31 c9 48 c1 ea 03 0f b6 14 02 <48> 89 f8 83 e0 07 83 c0 03 38 d0 7c 08 84 d2 0f 85 c6 01 00 00 81
RSP: 0018:ffff8881db309c78 EFLAGS: 00000803
RAX: dffffc0000000000 RBX: 1ffff1103b661390 RCX: 0000000000000000
RDX: 0000000000000000 RSI: ffff8881d94c50d8 RDI: ffffffff893839ec
RBP: ffffffff893839e8 R08: 0000000000000001 R09: 0000000000000001
R10: ffff8881d94c50d8 R11: ffff8881d94c4800 R12: 0000000000000002
R13: dffffc0000000000 R14: 1ffff1103b6613aa R15: ffff8881b4cb7dd0
FS: 0000000000000000(0000) GS:ffff8881db300000(0000) knlGS:0000000000000000
CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 00007fa0979e4000 CR3: 00000001d5381000 CR4: 00000000001406e0
Call Trace:
__raw_spin_lock_irqsave include/linux/spinlock_api_smp.h:117 [inline]
_raw_spin_lock_irqsave+0x3a/0x50 kernel/locking/spinlock.c:159
debug_object_deactivate lib/debugobjects.c:540 [inline]
debug_object_deactivate+0x104/0x360 lib/debugobjects.c:529
debug_hrtimer_deactivate kernel/time/hrtimer.c:412 [inline]
debug_deactivate kernel/time/hrtimer.c:462 [inline]
__run_hrtimer kernel/time/hrtimer.c:1359 [inline]
__hrtimer_run_queues+0x1a1/0xbc0 kernel/time/hrtimer.c:1451
hrtimer_interrupt+0x2e8/0x730 kernel/time/hrtimer.c:1509
local_apic_timer_interrupt arch/x86/kernel/apic/apic.c:1040 [inline]
smp_apic_timer_interrupt+0xc1/0x480 arch/x86/kernel/apic/apic.c:1065
apic_timer_interrupt+0xf/0x20 arch/x86/entry/entry_64.S:806
RIP: 0010:arch_local_irq_restore arch/x86/include/asm/irqflags.h:85 [inline]
RIP: 0010:console_trylock_spinning kernel/printk/printk.c:1740 [inline]
RIP: 0010:vprintk_emit+0x3cd/0x3e0 kernel/printk/printk.c:1984
Code: 00 83 fb ff 75 d6 e9 db fc ff ff e8 4d 01 15 00 e8 f8 26 1a 00 41 56 9d e9 b1 fd ff ff e8 3b 01 15 00 e8 e6 26 1a 00 41 56 9d 2a ff ff ff 0f 1f 40 00 66 2e 0f 1f 84 00 00 00 00 00 55 48 89
RSP: 0018:ffff8881c7a0f490 EFLAGS: 00000212 ORIG_RAX: ffffffffffffff13
RAX: 0000000000000007 RBX: 0000000000000200 RCX: 1ffff1103b298a10
RDX: 0000000000000000 RSI: ffff8881d94c5088 RDI: ffff8881d94c5034
RBP: ffff8881c7a0f4d8 R08: ffff8881d94c4800 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000000043
R13: ffff8881d9e64800 R14: 0000000000000212 R15: 0000000000000000
dev_vprintk_emit+0x4fc/0x541 drivers/base/core.c:3190
dev_printk_emit+0xba/0xf1 drivers/base/core.c:3201
__dev_printk+0x1db/0x203 drivers/base/core.c:3213
_dev_warn+0xd7/0x109 drivers/base/core.c:3257
usb_parse_configuration drivers/usb/core/config.c:629 [inline]
usb_get_configuration.cold+0x3bc/0x1696 drivers/usb/core/config.c:882
usb_enumerate_device drivers/usb/core/hub.c:2367 [inline]
usb_new_device+0x8d3/0xd50 drivers/usb/core/hub.c:2503
hub_port_connect drivers/usb/core/hub.c:5089 [inline]
hub_port_connect_change drivers/usb/core/hub.c:5204 [inline]
port_event drivers/usb/core/hub.c:5350 [inline]
hub_event+0x1ada/0x3590 drivers/usb/core/hub.c:5432
process_one_work+0x905/0x1570 kernel/workqueue.c:2268
process_scheduled_works kernel/workqueue.c:2330 [inline]
worker_thread+0x7ab/0xe20 kernel/workqueue.c:2416
kthread+0x30b/0x410 kernel/kthread.c:254
ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:352