syzbot


INFO: task hung in do_get_write_access (2)

Status: auto-obsoleted due to no activity on 2024/08/19 05:35
Subsystems: ext4
[Documentation on labels]
First crash: 251d, last: 251d
Similar bugs (2)
Kernel Title Repro Cause bisect Fix bisect Count Last Reported Patched Status
upstream INFO: task hung in do_get_write_access (3) ext4 syz error 101 9d05h 65d 0/28 upstream: reported syz repro on 2024/11/23 20:42
upstream INFO: task hung in do_get_write_access ext4 1 1153d 1153d 0/28 closed as invalid on 2022/02/08 09:40

Sample crash report:
INFO: task syz-executor.4:2531 blocked for more than 154 seconds.
      Not tainted 6.9.0-rc7-syzkaller-00158-g51474ab44abf #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:syz-executor.4  state:D
 stack:24432 pid:2531  tgid:2531  ppid:2522   flags:0x00004002
Call Trace:
 <TASK>
 context_switch kernel/sched/core.c:5409 [inline]
 __schedule+0xca6/0x3170 kernel/sched/core.c:6746
 __schedule_loop kernel/sched/core.c:6823 [inline]
 schedule+0xe7/0x350 kernel/sched/core.c:6838
 io_schedule+0xbf/0x130 kernel/sched/core.c:9044
 bit_wait_io+0x15/0xe0 kernel/sched/wait_bit.c:209
 __wait_on_bit_lock+0x112/0x1a0 kernel/sched/wait_bit.c:90
 out_of_line_wait_on_bit_lock+0xda/0x110 kernel/sched/wait_bit.c:117
 wait_on_bit_lock_io include/linux/wait_bit.h:208 [inline]
 __lock_buffer+0x67/0x70 fs/buffer.c:71
 lock_buffer include/linux/buffer_head.h:401 [inline]
 do_get_write_access+0x8c3/0x1270 fs/jbd2/transaction.c:987
 jbd2_journal_get_write_access+0x1f9/0x2b0 fs/jbd2/transaction.c:1260
 __ext4_journal_get_write_access+0x6a/0x3b0 fs/ext4/ext4_jbd2.c:239
 ext4_orphan_add+0x88e/0x1440 fs/ext4/orphan.c:139
 __ext4_unlink+0x92b/0xcf0 fs/ext4/namei.c:3290
 ext4_unlink+0x422/0x5b0 fs/ext4/namei.c:3321
 vfs_unlink+0x2fb/0x910 fs/namei.c:4335
 do_unlinkat+0x5c0/0x750 fs/namei.c:4399
 __do_sys_unlink fs/namei.c:4447 [inline]
 __se_sys_unlink fs/namei.c:4445 [inline]
 __x64_sys_unlink+0xc7/0x110 fs/namei.c:4445
 do_syscall_x64 arch/x86/entry/common.c:52 [inline]
 do_syscall_64+0xcf/0x260 arch/x86/entry/common.c:83
 entry_SYSCALL_64_after_hwframe+0x77/0x7f
RIP: 0033:0x7f0700e74697
RSP: 002b:00007fffb80603a8 EFLAGS: 00000206
 ORIG_RAX: 0000000000000057
RAX: ffffffffffffffda RBX: 0000000000000000 RCX: 00007f0700e74697
RDX: 00007fffb80603d0 RSI: 00007fffb8060460 RDI: 00007fffb8060460
RBP: 00007fffb8060460 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000100 R11: 0000000000000206 R12: 00007fffb8061520
R13: 00007f0700ec0336 R14: 00000000000185e1 R15: 0000000000000000
 </TASK>

Showing all locks held in the system:
1 lock held by khungtaskd/29:
 #0: 
ffffffff884b4560
 (
rcu_read_lock
){....}-{1:2}
, at: rcu_lock_acquire include/linux/rcupdate.h:329 [inline]
, at: rcu_read_lock include/linux/rcupdate.h:781 [inline]
, at: debug_show_all_locks+0x75/0x340 kernel/locking/lockdep.c:6614
3 locks held by kworker/1:2/694:
1 lock held by dhcpcd/2439:
 #0: 
ffffffff89617348
 (
rtnl_mutex
){+.+.}-{3:3}
, at: rtnl_lock net/core/rtnetlink.c:79 [inline]
, at: rtnetlink_rcv_msg+0x372/0xe60 net/core/rtnetlink.c:6592
2 locks held by getty/2461:
 #0: 
ffff8881077540a0
 (
&tty->ldisc_sem
){++++}-{0:0}
, at: tty_ldisc_ref_wait+0x24/0x80 drivers/tty/tty_ldisc.c:243
 #1: 
ffffc900000432f0
 (
&ldata->atomic_read_lock
){+.+.}-{3:3}
, at: n_tty_read+0xfc8/0x1490 drivers/tty/n_tty.c:2201
4 locks held by syz-fuzzer/2509:
 #0: 
ffff888117d78ac8
 (
&f->f_pos_lock
){+.+.}-{3:3}
, at: __fdget_pos+0xeb/0x180 fs/file.c:1191
 #1: 
ffff88811cb3f0f8
 (
&type->i_mutex_dir_key
#3
){++++}-{3:3}
, at: iterate_dir+0xe8/0x5c0 fs/readdir.c:103
 #2: 
ffff888113cd23f8
 (
sb_writers
#4
){.+.+}-{0:0}
, at: file_accessed include/linux/fs.h:2459 [inline]
, at: iterate_dir+0x441/0x5c0 fs/readdir.c:113
 #3: 
ffff888113cd6950
 (
jbd2_handle
){++++}-{0:0}
, at: start_this_handle+0x10db/0x15e0 fs/jbd2/transaction.c:463
1 lock held by syz-executor.3/2523:
 #0: 
ffffffff884bfc78
 (
rcu_state.exp_mutex
){+.+.}-{3:3}
, at: exp_funnel_lock kernel/rcu/tree_exp.h:291 [inline]
, at: synchronize_rcu_expedited+0x682/0x7a0 kernel/rcu/tree_exp.h:939
4 locks held by syz-executor.2/2525:
 #0: 
ffff888113cd23f8
 (
sb_writers
#4
){.+.+}-{0:0}
, at: do_unlinkat+0x1d3/0x750 fs/namei.c:4382
 #1: 
ffff888139455d28
 (
&type->i_mutex_dir_key
#3
/1
){+.+.}-{3:3}
, at: inode_lock_nested include/linux/fs.h:830 [inline]
, at: do_unlinkat+0x288/0x750 fs/namei.c:4386
 #2: 
ffff8881394570f8
 (
&sb->s_type->i_mutex_key
#8
){++++}-{3:3}
, at: inode_lock include/linux/fs.h:795 [inline]
, at: vfs_unlink+0xd3/0x910 fs/namei.c:4324
 #3: 
ffff888113cd6950
 (
jbd2_handle
){++++}-{0:0}
, at: start_this_handle+0x10db/0x15e0 fs/jbd2/transaction.c:463
4 locks held by syz-executor.4/2531:
4 locks held by kworker/1:3/4824:
2 locks held by syz-executor.0/4903:
1 lock held by syz-executor.1/4909:
 #0: 
ffffffff884bfc78
 (
rcu_state.exp_mutex
){+.+.}-{3:3}
, at: exp_funnel_lock kernel/rcu/tree_exp.h:323 [inline]
, at: synchronize_rcu_expedited+0x403/0x7a0 kernel/rcu/tree_exp.h:939
6 locks held by kworker/0:6/4935:
 #0: 
ffff888104ea3d48
 (
(wq_completion)usb_hub_wq
){+.+.}-{0:0}
, at: process_one_work+0x1296/0x1ac0 kernel/workqueue.c:3242
 #1: ffffc9000b46fd80
 (
(work_completion)(&hub->events)
){+.+.}-{0:0}
, at: process_one_work+0x906/0x1ac0 kernel/workqueue.c:3243
 #2: 
ffff8881097ce190
 (
&dev->mutex
){....}-{3:3}
, at: device_lock include/linux/device.h:990 [inline]
, at: hub_event+0x1be/0x4f50 drivers/usb/core/hub.c:5849
 #3: 
ffff888103ef3190
 (
&dev->mutex){....}-{3:3}
, at: device_lock include/linux/device.h:990 [inline]
, at: usb_disconnect+0x10a/0x920 drivers/usb/core/hub.c:2295
 #4: 
ffff888116214160 (&dev->mutex){....}-{3:3}, at: device_lock include/linux/device.h:990 [inline]
ffff888116214160 (&dev->mutex){....}-{3:3}, at: __device_driver_lock drivers/base/dd.c:1093 [inline]
ffff888116214160 (&dev->mutex){....}-{3:3}, at: device_release_driver_internal+0xa4/0x610 drivers/base/dd.c:1290
 #5: 
ffffffff89617348
 (
rtnl_mutex
){+.+.}-{3:3}
, at: unregister_netdev+0x12/0x30 net/core/dev.c:11180

=============================================

NMI backtrace for cpu 0
CPU: 0 PID: 29 Comm: khungtaskd Not tainted 6.9.0-rc7-syzkaller-00158-g51474ab44abf #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 04/02/2024
Call Trace:
 <TASK>
 __dump_stack lib/dump_stack.c:88 [inline]
 dump_stack_lvl+0x116/0x1f0 lib/dump_stack.c:114
 nmi_cpu_backtrace+0x27b/0x390 lib/nmi_backtrace.c:113
 nmi_trigger_cpumask_backtrace+0x29c/0x300 lib/nmi_backtrace.c:62
 trigger_all_cpu_backtrace include/linux/nmi.h:160 [inline]
 check_hung_uninterruptible_tasks kernel/hung_task.c:223 [inline]
 watchdog+0xf86/0x1240 kernel/hung_task.c:380
 kthread+0x2c1/0x3a0 kernel/kthread.c:388
 ret_from_fork+0x45/0x80 arch/x86/kernel/process.c:147
 ret_from_fork_asm+0x1a/0x30 arch/x86/entry/entry_64.S:244
 </TASK>
Sending NMI from CPU 0 to CPUs 1:
cdc_wdm 2-1:1.0: nonzero urb status received: -71
NMI backtrace for cpu 1
CPU: 1 PID: 42 Comm: kworker/u8:3 Not tainted 6.9.0-rc7-syzkaller-00158-g51474ab44abf #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 04/02/2024
Workqueue: netns cleanup_net
RIP: 0010:io_serial_out+0x8f/0xb0 drivers/tty/serial/8250/8250_port.c:413
Code: 48 8d 7d 40 44 89 e1 48 b8 00 00 00 00 00 fc ff df 48 89 fa d3 e3 48 c1 ea 03 80 3c 02 00 75 1c 66 03 5d 40 44 89 e8 89 da ee <5b> 5d 41 5c 41 5d c3 cc cc cc cc e8 61 bd 0b ff eb a0 e8 ea bd 0b
RSP: 0018:ffffc900001a8590 EFLAGS: 00000002
RAX: 0000000000000020 RBX: 00000000000003f8 RCX: 0000000000000000
RDX: 00000000000003f8 RSI: ffffffff82991835 RDI: ffffffff8d4d4780
RBP: ffffffff8d4d4740 R08: 0000000000000001 R09: 000000000000001f
R10: 0000000000000000 R11: 0000000000000004 R12: 0000000000000000
R13: 0000000000000020 R14: ffffffff829917d0 R15: 0000000000000000
FS:  0000000000000000(0000) GS:ffff8881f6500000(0000) knlGS:0000000000000000
CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 00007ffe008b8b0c CR3: 00000001155b6000 CR4: 00000000003506f0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
 <NMI>
 </NMI>
 <IRQ>
 serial_out drivers/tty/serial/8250/8250.h:122 [inline]
 serial8250_console_fifo_write drivers/tty/serial/8250/8250_port.c:3325 [inline]
 serial8250_console_write+0xce7/0x1090 drivers/tty/serial/8250/8250_port.c:3396
 console_emit_next_record kernel/printk/printk.c:2913 [inline]
 console_flush_all+0x53c/0xd70 kernel/printk/printk.c:2979
 console_unlock+0xae/0x290 kernel/printk/printk.c:3048
 vprintk_emit kernel/printk/printk.c:2348 [inline]
 vprintk_emit+0x11a/0x5a0 kernel/printk/printk.c:2303
 dev_vprintk_emit drivers/base/core.c:4950 [inline]
 dev_printk_emit+0xfb/0x140 drivers/base/core.c:4961
 __dev_printk+0xf5/0x270 drivers/base/core.c:4973
 _dev_err+0xe5/0x120 drivers/base/core.c:5016
 wdm_int_callback+0x10f/0xaa0 drivers/usb/class/cdc-wdm.c:269
 __usb_hcd_giveback_urb+0x364/0x5c0 drivers/usb/core/hcd.c:1648
 usb_hcd_giveback_urb+0x396/0x450 drivers/usb/core/hcd.c:1732
 dummy_timer+0x17f6/0x3900 drivers/usb/gadget/udc/dummy_hcd.c:1987
 __run_hrtimer kernel/time/hrtimer.c:1692 [inline]
 __hrtimer_run_queues+0x20c/0xcc0 kernel/time/hrtimer.c:1756
 hrtimer_interrupt+0x31b/0x800 kernel/time/hrtimer.c:1818
 local_apic_timer_interrupt arch/x86/kernel/apic/apic.c:1032 [inline]
 __sysvec_apic_timer_interrupt+0x10f/0x450 arch/x86/kernel/apic/apic.c:1049
 instr_sysvec_apic_timer_interrupt arch/x86/kernel/apic/apic.c:1043 [inline]
 sysvec_apic_timer_interrupt+0x8b/0xb0 arch/x86/kernel/apic/apic.c:1043
 </IRQ>
 <TASK>
 asm_sysvec_apic_timer_interrupt+0x1a/0x20 arch/x86/include/asm/idtentry.h:702
RIP: 0010:lock_acquire+0x1f2/0x560 kernel/locking/lockdep.c:5722
Code: c1 05 0a 43 d1 7e 83 f8 01 0f 85 ea 02 00 00 9c 58 f6 c4 02 0f 85 d5 02 00 00 48 85 ed 74 01 fb 48 b8 00 00 00 00 00 fc ff df <48> 01 c3 48 c7 03 00 00 00 00 48 c7 43 08 00 00 00 00 48 8b 84 24
RSP: 0018:ffffc900002c7be0 EFLAGS: 00000206
RAX: dffffc0000000000 RBX: 1ffff92000058f7e RCX: 0000000000000001
RDX: 0000000000000001 RSI: ffffffff86c7d200 RDI: ffffffff86e73ce0
RBP: 0000000000000200 R08: 0000000000000001 R09: fffffbfff172643f
R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000000001
R13: 0000000000000000 R14: ffff888100aad948 R15: 0000000000000000
 process_one_work+0x12c6/0x1ac0 kernel/workqueue.c:3242
 process_scheduled_works kernel/workqueue.c:3348 [inline]
 worker_thread+0x6c8/0xf70 kernel/workqueue.c:3429
 kthread+0x2c1/0x3a0 kernel/kthread.c:388
 ret_from_fork+0x45/0x80 arch/x86/kernel/process.c:147
 ret_from_fork_asm+0x1a/0x30 arch/x86/entry/entry_64.S:244
 </TASK>

Crashes (1):
Time Kernel Commit Syzkaller Config Log Report Syz repro C repro VM info Assets (help?) Manager Title
2024/05/21 05:32 https://git.kernel.org/pub/scm/linux/kernel/git/gregkh/usb.git usb-testing 51474ab44abf c0f1611a .config console log report info [disk image] [vmlinux] [kernel image] ci2-upstream-usb INFO: task hung in do_get_write_access
* Struck through repros no longer work on HEAD.