syzbot


BUG: soft lockup in ext4_file_write_iter

Status: auto-closed as invalid on 2021/10/22 22:26
Reported-by: syzbot+30dede0fe0fdd6d8a810@syzkaller.appspotmail.com
First crash: 583d, last: 583d
similar bugs (4):
Kernel Title Repro Cause bisect Fix bisect Count Last Reported Patched Status
upstream INFO: rcu detected stall in ext4_file_write_iter (3) 5 537d 626d 0/24 auto-closed as invalid on 2021/11/07 19:52
upstream INFO: rcu detected stall in ext4_file_write_iter (5) 3 277d 277d 0/24 auto-closed as invalid on 2022/06/25 07:58
upstream INFO: rcu detected stall in ext4_file_write_iter (4) C unreliable 58 372d 402d 0/24 closed as invalid on 2022/02/08 10:32
linux-4.19 INFO: rcu detected stall in ext4_file_write_iter C error 9 38d 1379d 0/1 upstream: reported C repro on 2019/04/20 13:09

Sample crash report:
binder: 22421:22435 ioctl c0306201 20000200 returned -22
watchdog: BUG: soft lockup - CPU#0 stuck for 22s! [syz-executor.5:22426]
Modules linked in:
irq event stamp: 3209940
hardirqs last  enabled at (3209939): [<ffffffff81003ce4>] trace_hardirqs_on_thunk+0x1a/0x1c
hardirqs last disabled at (3209940): [<ffffffff81003d00>] trace_hardirqs_off_thunk+0x1a/0x1c
softirqs last  enabled at (3208706): [<ffffffff88400678>] __do_softirq+0x678/0x980 kernel/softirq.c:318
softirqs last disabled at (3208107): [<ffffffff813926d5>] invoke_softirq kernel/softirq.c:372 [inline]
softirqs last disabled at (3208107): [<ffffffff813926d5>] irq_exit+0x215/0x260 kernel/softirq.c:412
CPU: 0 PID: 22426 Comm: syz-executor.5 Not tainted 4.19.195-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
RIP: 0010:__read_once_size include/linux/compiler.h:261 [inline]
RIP: 0010:trylock_clear_pending kernel/locking/qspinlock_paravirt.h:123 [inline]
RIP: 0010:pv_wait_head_or_lock kernel/locking/qspinlock_paravirt.h:436 [inline]
RIP: 0010:__pv_queued_spin_lock_slowpath+0x3b5/0xae0 kernel/locking/qspinlock.c:474
Code: 83 e3 07 41 be 01 00 00 00 48 b8 00 00 00 00 00 fc ff df 4c 8d 2c 01 eb 0c f3 90 41 83 ec 01 0f 84 38 04 00 00 41 0f b6 45 00 <38> d8 7f 08 84 c0 0f 85 75 05 00 00 0f b6 45 00 84 c0 75 db be 02
RSP: 0000:ffff88803d94f400 EFLAGS: 00000216 ORIG_RAX: ffffffffffffff13
RAX: 0000000000000000 RBX: 0000000000000000 RCX: 1ffff11011ef5aa5
RDX: 0000000000000001 RSI: ffffffff8167a625 RDI: 0000000000000286
RBP: ffff88808f7ad528 R08: 0000000000000001 R09: 0000000000000000
R10: 0000000000000001 R11: 0000000000000000 R12: 0000000000007b6f
R13: ffffed1011ef5aa5 R14: 0000000000000001 R15: ffff8880ba02bd80
FS:  00007f9461047700(0000) GS:ffff8880ba000000(0000) knlGS:0000000000000000
CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 00000000004e4da2 CR3: 00000000b29ff000 CR4: 00000000001426f0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
 pv_queued_spin_lock_slowpath arch/x86/include/asm/paravirt.h:679 [inline]
 queued_spin_lock_slowpath arch/x86/include/asm/qspinlock.h:53 [inline]
 queued_spin_lock include/asm-generic/qspinlock.h:88 [inline]
 do_raw_spin_lock+0x189/0x220 kernel/locking/spinlock_debug.c:113
 spin_lock include/linux/spinlock.h:329 [inline]
 zap_pte_range mm/memory.c:1311 [inline]
 zap_pmd_range mm/memory.c:1452 [inline]
 zap_pud_range mm/memory.c:1481 [inline]
 zap_p4d_range mm/memory.c:1502 [inline]
 unmap_page_range+0xa1c/0x2a70 mm/memory.c:1523
 unmap_single_vma+0x198/0x300 mm/memory.c:1568
 zap_page_range_single+0x247/0x380 mm/memory.c:1647
 unmap_mapping_range_vma mm/memory.c:2899 [inline]
 unmap_mapping_range_tree mm/memory.c:2920 [inline]
 unmap_mapping_pages+0x22d/0x2b0 mm/memory.c:2952
 invalidate_inode_pages2_range+0x43c/0x10f0 mm/truncate.c:718
 generic_file_direct_write+0x199/0x4a0 mm/filemap.c:3061
 __generic_file_write_iter+0x2d0/0x610 mm/filemap.c:3252
 ext4_file_write_iter+0x2fe/0xf20 fs/ext4/file.c:272
 call_write_iter include/linux/fs.h:1821 [inline]
 new_sync_write fs/read_write.c:474 [inline]
 __vfs_write+0x51b/0x770 fs/read_write.c:487
 vfs_write+0x1f3/0x540 fs/read_write.c:549
 ksys_write+0x12b/0x2a0 fs/read_write.c:599
 do_syscall_64+0xf9/0x620 arch/x86/entry/common.c:293
 entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x4665d9
Code: ff ff c3 66 2e 0f 1f 84 00 00 00 00 00 0f 1f 40 00 48 89 f8 48 89 f7 48 89 d6 48 89 ca 4d 89 c2 4d 89 c8 4c 8b 4c 24 08 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 c7 c1 bc ff ff ff f7 d8 64 89 01 48
RSP: 002b:00007f9461047188 EFLAGS: 00000246 ORIG_RAX: 0000000000000001
RAX: ffffffffffffffda RBX: 000000000056bf80 RCX: 00000000004665d9
RDX: 00000000175d900f RSI: 0000000020000200 RDI: 0000000000000006
RBP: 00000000004bfcb9 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 000000000056bf80
R13: 00007ffea07c734f R14: 00007f9461047300 R15: 0000000000022000
Sending NMI from CPU 0 to CPUs 1:
NMI backtrace for cpu 1
CPU: 1 PID: 22428 Comm: syz-executor.0 Not tainted 4.19.195-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
RIP: 0010:__lock_release kernel/locking/lockdep.c:3714 [inline]
RIP: 0010:lock_release+0x394/0x8b0 kernel/locking/lockdep.c:3927
Code: 00 8d 55 01 44 89 fe 4c 89 f7 e8 67 ea ff ff 85 c0 75 35 48 b8 00 00 00 00 00 fc ff df 48 89 da 83 e3 07 48 c1 ea 03 83 c3 03 <0f> b6 04 02 38 c3 7c 08 84 c0 0f 85 d3 02 00 00 8b 15 36 bc d8 0b
RSP: 0018:ffff8880ba107910 EFLAGS: 00000006
RAX: dffffc0000000000 RBX: 0000000000000003 RCX: 1ffff11007b2d147
RDX: 1ffffffff1a480ec RSI: 0000000000000007 RDI: ffff88803d9680c0
RBP: 0000000000000006 R08: ffffffff8cd51e80 R09: 0000000000000001
R10: 0000000000000000 R11: ffffffff8c66205b R12: 1ffff11017420f25
R13: 600fd093b2ff9a58 R14: ffff88803d9680c0 R15: 0000000000000007
FS:  00007f8cc0ee7700(0000) GS:ffff8880ba100000(0000) knlGS:0000000000000000
CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 00007efdf7277000 CR3: 0000000097c44000 CR4: 00000000001426e0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
 <IRQ>
 seqcount_lockdep_reader_access include/linux/seqlock.h:82 [inline]
 read_seqcount_begin include/linux/seqlock.h:164 [inline]
 ktime_get+0xe6/0x2f0 kernel/time/timekeeping.c:756
 clockevents_program_event+0x141/0x350 kernel/time/clockevents.c:336
 tick_program_event+0xac/0x140 kernel/time/tick-oneshot.c:48
 hrtimer_interrupt+0x40a/0x9e0 kernel/time/hrtimer.c:1552
 local_apic_timer_interrupt arch/x86/kernel/apic/apic.c:1071 [inline]
 smp_apic_timer_interrupt+0x10c/0x550 arch/x86/kernel/apic/apic.c:1096
 apic_timer_interrupt+0xf/0x20 arch/x86/entry/entry_64.S:894
RIP: 0010:rht_key_hashfn include/linux/rhashtable.h:132 [inline]
RIP: 0010:__rhashtable_lookup include/linux/rhashtable.h:480 [inline]
RIP: 0010:rhltable_lookup include/linux/rhashtable.h:566 [inline]
RIP: 0010:sta_info_hash_lookup+0xe9/0x710 net/mac80211/sta_info.c:163
Code: 8d 7f 02 4c 01 f0 48 89 74 24 28 48 89 7c 24 30 48 89 44 24 58 e8 f7 76 cc f9 49 8d 7d 0c 48 89 f8 48 c1 e8 03 42 0f b6 14 30 <48> 89 f8 83 e0 07 83 c0 03 38 d0 7c 08 84 d2 0f 85 ad 05 00 00 41
RSP: 0018:ffff8880ba107c10 EFLAGS: 00000a03 ORIG_RAX: ffffffffffffff13
RAX: 1ffff11014ab10c1 RBX: 0000000000000001 RCX: ffff88809e66f59e
RDX: 0000000000000000 RSI: ffffffff87960839 RDI: ffff8880a558860c
RBP: ffff88809e66f59a R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000005 R11: 0000000000000000 R12: ffff8880471326a0
R13: ffff8880a5588600 R14: dffffc0000000000 R15: ffff88809e66f59a
 sta_info_get_bss+0xb1/0x340 net/mac80211/sta_info.c:200
 __ieee80211_rx_handle_packet net/mac80211/rx.c:4545 [inline]
 ieee80211_rx_napi+0x106d/0x2470 net/mac80211/rx.c:4675
 ieee80211_rx include/net/mac80211.h:4109 [inline]
 ieee80211_tasklet_handler+0x101/0x160 net/mac80211/main.c:229
 tasklet_action_common.constprop.0+0x265/0x360 kernel/softirq.c:522
 __do_softirq+0x265/0x980 kernel/softirq.c:292
 invoke_softirq kernel/softirq.c:372 [inline]
 irq_exit+0x215/0x260 kernel/softirq.c:412
 exiting_irq arch/x86/include/asm/apic.h:536 [inline]
 smp_apic_timer_interrupt+0x136/0x550 arch/x86/kernel/apic/apic.c:1098
 apic_timer_interrupt+0xf/0x20 arch/x86/entry/entry_64.S:894
 </IRQ>
RIP: 0010:csd_lock_wait kernel/smp.c:108 [inline]
RIP: 0010:smp_call_function_single+0x1e0/0x420 kernel/smp.c:302
Code: 00 48 8b 4c 24 08 48 8b 54 24 10 48 8d 74 24 40 8b 7c 24 1c e8 a1 f9 ff ff 41 89 c5 eb 07 e8 77 03 0a 00 f3 90 44 8b 64 24 58 <31> ff 41 83 e4 01 44 89 e6 e8 d2 04 0a 00 45 85 e4 75 e1 e8 58 03
RSP: 0018:ffff88809ee16d20 EFLAGS: 00000212 ORIG_RAX: ffffffffffffff13
RAX: 0000000000040000 RBX: 1ffff11013dc2da8 RCX: ffffc9000e773000
RDX: 000000000003192d RSI: ffffffff81587bb9 RDI: 0000000000000005
RBP: ffff88809ee16de8 R08: 0000000000000001 R09: 0000000000000000
R10: 0000000000000005 R11: 0000000000000000 R12: 0000000000000000
R13: 0000000000000000 R14: 0000000000000001 R15: 0000000000000002
 smp_call_function_many+0x743/0x8d0 kernel/smp.c:434
 flush_tlb_others arch/x86/include/asm/paravirt.h:309 [inline]
 flush_tlb_mm_range+0x179/0x320 arch/x86/mm/tlb.c:728
 flush_tlb_page arch/x86/include/asm/tlbflush.h:576 [inline]
 ptep_clear_flush+0x123/0x160 mm/pgtable-generic.c:87
 try_to_unmap_one+0x64b/0x2990 mm/rmap.c:1506
 rmap_walk_anon+0x435/0xb90 mm/rmap.c:1833
 rmap_walk_locked+0x12a/0x190 mm/rmap.c:1914
 try_to_unmap+0x1ce/0x380 mm/rmap.c:1722
 unmap_page mm/huge_memory.c:2429 [inline]
 split_huge_page_to_list+0x6a2/0x2fa0 mm/huge_memory.c:2750
 split_huge_page include/linux/huge_mm.h:146 [inline]
 deferred_split_scan+0x625/0xbc0 mm/huge_memory.c:2891
 do_shrink_slab+0x397/0xa40 mm/vmscan.c:537
 shrink_slab+0x16f/0x550 mm/vmscan.c:693
 drop_slab_node+0x33/0x70 mm/vmscan.c:724
 drop_slab+0x4b/0x90 mm/vmscan.c:734
 drop_caches_sysctl_handler+0xf6/0x110 fs/drop_caches.c:65
 proc_sys_call_handler.isra.0+0x1f3/0x3b0 fs/proc/proc_sysctl.c:597
 __vfs_write+0xf7/0x770 fs/read_write.c:485
 __kernel_write+0x109/0x370 fs/read_write.c:506
 write_pipe_buf+0x153/0x1f0 fs/splice.c:798
 splice_from_pipe_feed fs/splice.c:503 [inline]
 __splice_from_pipe+0x389/0x800 fs/splice.c:627
 splice_from_pipe fs/splice.c:662 [inline]
 default_file_splice_write+0xd8/0x180 fs/splice.c:810
 do_splice_from fs/splice.c:852 [inline]
 direct_splice_actor+0x115/0x160 fs/splice.c:1025
 splice_direct_to_actor+0x33f/0x8d0 fs/splice.c:980
 do_splice_direct+0x1a7/0x270 fs/splice.c:1068
 do_sendfile+0x550/0xc30 fs/read_write.c:1447
 __do_sys_sendfile64 fs/read_write.c:1508 [inline]
 __se_sys_sendfile64+0x147/0x160 fs/read_write.c:1494
 do_syscall_64+0xf9/0x620 arch/x86/entry/common.c:293
 entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x4665d9
Code: ff ff c3 66 2e 0f 1f 84 00 00 00 00 00 0f 1f 40 00 48 89 f8 48 89 f7 48 89 d6 48 89 ca 4d 89 c2 4d 89 c8 4c 8b 4c 24 08 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 c7 c1 bc ff ff ff f7 d8 64 89 01 48
RSP: 002b:00007f8cc0ee7188 EFLAGS: 00000246 ORIG_RAX: 0000000000000028
RAX: ffffffffffffffda RBX: 000000000056bf80 RCX: 00000000004665d9
RDX: 0000000000000000 RSI: 0000000000000005 RDI: 0000000000000006
RBP: 00000000004bfcb9 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000001 R11: 0000000000000246 R12: 000000000056bf80
R13: 00007fffb588d68f R14: 00007f8cc0ee7300 R15: 0000000000022000

Crashes (1):
Manager Time Kernel Commit Syzkaller Config Log Report Syz repro C repro VM info Assets Title
ci2-linux-4-19 2021/06/24 22:25 linux-4.19.y eb575cd5d7f6 0edbbe31 .config console log report info BUG: soft lockup in ext4_file_write_iter
* Struck through repros no longer work on HEAD.