| TITLE: INFO: task hung in jbd2_journal_commit_transaction |
| TYPE: HANG |
| |
| [ 1431.920513][ T1054] INFO: task jbd2/sda-8:3563 blocked for more than 140 seconds. |
| [ 1431.921721][ T1054] Not tainted 4.20.0-next-20190102+ #5 |
| [ 1431.922712][ T1054] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. |
| [ 1431.924004][ T1054] jbd2/sda-8 D22600 3563 2 0x80000000 |
| [ 1431.924959][ T1054] Call Trace: |
| [ 1431.925466][ T1054] __schedule+0x897/0x1e60 |
| [ 1431.932653][ T1054] schedule+0xfe/0x350 |
| [ 1431.936851][ T1054] io_schedule+0x1c/0x70 |
| [ 1431.937444][ T1054] bit_wait_io+0x17/0x90 |
| [ 1431.938033][ T1054] __wait_on_bit+0xb3/0x130 |
| [ 1431.939357][ T1054] out_of_line_wait_on_bit+0x1f4/0x3b0 |
| [ 1431.942911][ T1054] __wait_on_buffer+0x6f/0x90 |
| [ 1431.943574][ T1054] jbd2_journal_commit_transaction+0x658a/0x8ee4 |
| [ 1431.973827][ T1054] kjournald2+0x20f/0xb90 |
| [ 1431.981833][ T1054] kthread+0x357/0x430 |
| [ 1431.983851][ T1054] ret_from_fork+0x3a/0x50 |
| [ 1431.984508][ T1054] INFO: task kworker/u12:5:1980 blocked for more than 140 seconds. |
| [ 1431.985609][ T1054] Not tainted 4.20.0-next-20190102+ #5 |
| [ 1431.986435][ T1054] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. |
| [ 1431.987627][ T1054] kworker/u12:5 D21864 1980 2 0x80000000 |
| [ 1431.988513][ T1054] Workqueue: writeback wb_workfn (flush-8:0) |
| [ 1431.989343][ T1054] Call Trace: |
| [ 1431.989806][ T1054] __schedule+0x897/0x1e60 |
| [ 1431.996445][ T1054] schedule+0xfe/0x350 |
| [ 1431.999467][ T1054] io_schedule+0x1c/0x70 |
| [ 1432.000049][ T1054] rq_qos_wait+0x2f2/0x600 |
| [ 1432.004727][ T1054] wbt_wait+0x266/0x430 |
| [ 1432.007248][ T1054] __rq_qos_throttle+0x58/0xb0 |
| [ 1432.007889][ T1054] blk_mq_make_request+0x424/0x1e10 |
| [ 1432.014744][ T1054] generic_make_request+0x902/0x17f0 |
| [ 1432.019946][ T1054] submit_bio+0xba/0x480 |
| [ 1432.021946][ T1054] ext4_io_submit+0x197/0x230 |
| [ 1432.022621][ T1054] ext4_writepages+0x139d/0x4180 |
| [ 1432.032498][ T1054] do_writepages+0x99/0x1a0 |
| [ 1432.033840][ T1054] __writeback_single_inode+0x1c5/0x1620 |
| [ 1432.043200][ T1054] writeback_sb_inodes+0x762/0x1260 |
| [ 1432.048996][ T1054] __writeback_inodes_wb+0x16d/0x3d0 |
| [ 1432.051205][ T1054] wb_writeback+0xa2d/0xf70 |
| [ 1432.059220][ T1054] wb_workfn+0xccc/0x16f0 |
| [ 1432.068135][ T1054] process_one_work+0xd0c/0x1ce0 |
| [ 1432.077047][ T1054] worker_thread+0x143/0x14a0 |
| [ 1432.087752][ T1054] kthread+0x357/0x430 |
| [ 1432.089635][ T1054] ret_from_fork+0x3a/0x50 |
| [ 1432.090262][ T1054] INFO: task syz-executor2:6677 blocked for more than 140 seconds. |
| [ 1432.091339][ T1054] Not tainted 4.20.0-next-20190102+ #5 |
| [ 1432.092141][ T1054] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. |
| [ 1432.093322][ T1054] syz-executor2 D19624 6677 22450 0x00000004 |
| [ 1432.094200][ T1054] Call Trace: |
| [ 1432.094662][ T1054] __schedule+0x897/0x1e60 |
| [ 1432.099160][ T1054] schedule+0xfe/0x350 |
| [ 1432.104857][ T1054] io_schedule+0x1c/0x70 |
| [ 1432.105440][ T1054] wait_on_page_bit_common+0xad5/0x1370 |
| [ 1432.112856][ T1054] __filemap_fdatawait_range+0x614/0x890 |
| [ 1432.119095][ T1054] file_write_and_wait_range+0xd1/0x100 |
| [ 1432.119844][ T1054] ext4_sync_file+0x2da/0x14d0 |
| [ 1432.123727][ T1054] vfs_fsync_range+0x144/0x230 |
| [ 1432.124362][ T1054] ext4_file_write_iter+0x683/0x1400 |
| [ 1432.135479][ T1054] do_iter_readv_writev+0x902/0xbc0 |
| [ 1432.138406][ T1054] do_iter_write+0x184/0x610 |
| [ 1432.141349][ T1054] vfs_iter_write+0x77/0xb0 |
| [ 1432.141973][ T1054] iter_file_splice_write+0x885/0xfc0 |
| [ 1432.145035][ T1054] direct_splice_actor+0x126/0x1a0 |
| [ 1432.145746][ T1054] splice_direct_to_actor+0x3be/0x9d0 |
| [ 1432.149420][ T1054] do_splice_direct+0x2c7/0x420 |
| [ 1432.153962][ T1054] do_sendfile+0x61a/0xe60 |
| [ 1432.156833][ T1054] __x64_sys_sendfile64+0x15a/0x240 |
| [ 1432.159760][ T1054] do_syscall_64+0x1a3/0x800 |
| [ 1432.163622][ T1054] entry_SYSCALL_64_after_hwframe+0x49/0xbe |
| [ 1432.164427][ T1054] RIP: 0033:0x4576a9 |
| [ 1432.164967][ T1054] Code: 3b 41 10 76 7d 48 81 ec 88 00 00 00 48 89 ac 24 80 00 00 00 48 8d ac 24 80 00 00 00 48 8b 59 20 48 85 db 75 67 48 8b b4 24 a0 <00> 00 00 8b 8c 24 a8 00 00 00 48 89 e7 f3 a4 48 8b 94 24 98 00 00 |
| [ 1432.167624][ T1054] RSP: 002b:00007f66fbce3c88 EFLAGS: 00000246 ORIG_RAX: 0000000000000028 |
| [ 1432.168774][ T1054] RAX: ffffffffffffffda RBX: 000000000071bf00 RCX: 00000000004576a9 |
| [ 1432.169895][ T1054] RDX: 0000000020000040 RSI: 0000000000000005 RDI: 0000000000000005 |
| [ 1432.171034][ T1054] RBP: 0000000000000004 R08: 0000000000000000 R09: 0000000000000000 |
| [ 1432.172145][ T1054] R10: 00008080fffffffe R11: 0000000000000246 R12: 00007f66fbce46d4 |
| [ 1432.173246][ T1054] R13: 00000000004abcac R14: 00000000006eb9b8 R15: 00000000ffffffff |
| [ 1432.174341][ T1054] INFO: task syz-executor2:6693 blocked for more than 140 seconds. |
| [ 1432.175408][ T1054] Not tainted 4.20.0-next-20190102+ #5 |
| [ 1432.176221][ T1054] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. |
| [ 1432.177401][ T1054] syz-executor2 D22200 6693 22450 0x00000004 |
| [ 1432.178275][ T1054] Call Trace: |
| [ 1432.178737][ T1054] __schedule+0x897/0x1e60 |
| [ 1432.183888][ T1054] schedule+0xfe/0x350 |
| [ 1432.187237][ T1054] jbd2_log_wait_commit+0x320/0x4b0 |
| [ 1432.190123][ T1054] jbd2_complete_transaction+0x18c/0x1e0 |
| [ 1432.190947][ T1054] ext4_sync_file+0x1211/0x14d0 |
| [ 1432.194899][ T1054] vfs_fsync_range+0x144/0x230 |
| [ 1432.195553][ T1054] ext4_file_write_iter+0x683/0x1400 |
| [ 1432.206634][ T1054] do_iter_readv_writev+0x902/0xbc0 |
| [ 1432.209498][ T1054] do_iter_write+0x184/0x610 |
| [ 1432.212321][ T1054] vfs_iter_write+0x77/0xb0 |
| [ 1432.212930][ T1054] iter_file_splice_write+0x885/0xfc0 |
| [ 1432.215944][ T1054] direct_splice_actor+0x126/0x1a0 |
| [ 1432.216657][ T1054] splice_direct_to_actor+0x3be/0x9d0 |
| [ 1432.220393][ T1054] do_splice_direct+0x2c7/0x420 |
| [ 1432.224944][ T1054] do_sendfile+0x61a/0xe60 |
| [ 1432.227770][ T1054] __x64_sys_sendfile64+0x15a/0x240 |
| [ 1432.229896][ T1054] do_syscall_64+0x1a3/0x800 |
| [ 1432.233503][ T1054] entry_SYSCALL_64_after_hwframe+0x49/0xbe |
| [ 1432.234327][ T1054] RIP: 0033:0x4576a9 |
| [ 1432.234875][ T1054] Code: 3b 41 10 76 7d 48 81 ec 88 00 00 00 48 89 ac 24 80 00 00 00 48 8d ac 24 80 00 00 00 48 8b 59 20 48 85 db 75 67 48 8b b4 24 a0 <00> 00 00 8b 8c 24 a8 00 00 00 48 89 e7 f3 a4 48 8b 94 24 98 00 00 |
| [ 1432.237487][ T1054] RSP: 002b:00007f66fbcc2c88 EFLAGS: 00000246 ORIG_RAX: 0000000000000028 |
| [ 1432.238606][ T1054] RAX: ffffffffffffffda RBX: 000000000071bfa0 RCX: 00000000004576a9 |
| [ 1432.240004][ T1054] RDX: 0000000020000380 RSI: 0000000000000005 RDI: 0000000000000005 |
| [ 1432.241148][ T1054] RBP: 0000000000000004 R08: 0000000000000000 R09: 0000000000000000 |
| [ 1432.242318][ T1054] R10: 0001000000020000 R11: 0000000000000246 R12: 00007f66fbcc36d4 |
| [ 1432.243807][ T1054] R13: 00000000004abcac R14: 00000000006eb9b8 R15: 00000000ffffffff |
| [ 1432.245419][ T1054] |
| [ 1432.245419][ T1054] Showing all locks held in the system: |
| [ 1432.246771][ T1054] 1 lock held by khungtaskd/1054: |
| [ 1432.247458][ T1054] #0: 00000000c542499d (rcu_read_lock){....}, at: debug_show_all_locks+0xc6/0x41d |
| [ 1432.248686][ T1054] 1 lock held by rsyslogd/5859: |
| [ 1432.249607][ T1054] #0: 00000000567e80cf (&f->f_pos_lock){+.+.}, at: __fdget_pos+0x1b3/0x1f0 |
| [ 1432.251067][ T1054] 2 locks held by getty/5951: |
| [ 1432.251698][ T1054] #0: 000000003b0dfac2 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 |
| [ 1432.252896][ T1054] #1: 00000000281ebc43 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x30a/0x1eb0 |
| [ 1432.254207][ T1054] 2 locks held by getty/5952: |
| [ 1432.254995][ T1054] #0: 00000000f0d48c91 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 |
| [ 1432.256190][ T1054] #1: 00000000cff3c1fb (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x30a/0x1eb0 |
| [ 1432.257467][ T1054] 2 locks held by getty/5953: |
| [ 1432.258082][ T1054] #0: 00000000cb353a8f (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 |
| [ 1432.259287][ T1054] #1: 00000000373ded25 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x30a/0x1eb0 |
| [ 1432.260651][ T1054] 2 locks held by getty/5954: |
| [ 1432.261313][ T1054] #0: 000000005c8be537 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 |
| [ 1432.262536][ T1054] #1: 00000000d7aec815 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x30a/0x1eb0 |
| [ 1432.263836][ T1054] 2 locks held by getty/5955: |
| [ 1432.264474][ T1054] #0: 00000000eda8138a (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 |
| [ 1432.265680][ T1054] #1: 00000000f4fd60a6 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x30a/0x1eb0 |
| [ 1432.266973][ T1054] 2 locks held by getty/5956: |
| [ 1432.267617][ T1054] #0: 000000000745b5fb (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 |
| [ 1432.268834][ T1054] #1: 00000000a4c3442a (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x30a/0x1eb0 |
| [ 1432.270143][ T1054] 2 locks held by getty/5957: |
| [ 1432.270818][ T1054] #0: 000000002cb7c49e (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 |
| [ 1432.272044][ T1054] #1: 000000009258e26f (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x30a/0x1eb0 |
| [ 1432.273373][ T1054] 4 locks held by kworker/u12:5/1980: |
| [ 1432.274102][ T1054] #0: 000000005e902bc8 ((wq_completion)"writeback"){+.+.}, at: process_one_work+0xbc7/0x1ce0 |
| [ 1432.275494][ T1054] #1: 0000000056cd520a ((work_completion)(&(&wb->dwork)->work)){+.+.}, at: process_one_work+0xc1d/0x1ce0 |
| [ 1432.277027][ T1054] #2: 00000000146ba37b (&type->s_umount_key#30){++++}, at: trylock_super+0x22/0x110 |
| [ 1432.278316][ T1054] #3: 00000000fec9c2c2 (&sbi->s_journal_flag_rwsem){.+.+}, at: do_writepages+0x99/0x1a0 |
| [ 1432.279643][ T1054] 1 lock held by syz-executor2/6677: |
| [ 1432.280377][ T1054] #0: 000000007b67e744 (sb_writers#3){.+.+}, at: do_sendfile+0xad7/0xe60 |
| [ 1432.281560][ T1054] 1 lock held by syz-executor2/6693: |
| [ 1432.282283][ T1054] #0: 000000007b67e744 (sb_writers#3){.+.+}, at: do_sendfile+0xad7/0xe60 |
| [ 1432.283458][ T1054] |
| [ 1432.283784][ T1054] ============================================= |
| [ 1432.283784][ T1054] |
| [ 1432.284927][ T1054] NMI backtrace for cpu 3 |
| [ 1432.285533][ T1054] CPU: 3 PID: 1054 Comm: khungtaskd Not tainted 4.20.0-next-20190102+ #5 |
| [ 1432.286668][ T1054] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014 |
| [ 1432.287906][ T1054] Call Trace: |
| [ 1432.288362][ T1054] dump_stack+0x1db/0x2d0 |
| [ 1432.290598][ T1054] nmi_cpu_backtrace.cold+0x63/0xa4 |
| [ 1432.292117][ T1054] nmi_trigger_cpumask_backtrace+0x1be/0x236 |
| [ 1432.292936][ T1054] arch_trigger_cpumask_backtrace+0x14/0x20 |
| [ 1432.293741][ T1054] watchdog+0xbbb/0x1170 |
| [ 1432.304325][ T1054] kthread+0x357/0x430 |
| [ 1432.306510][ T1054] ret_from_fork+0x3a/0x50 |
| [ 1432.307177][ T1054] Sending NMI from CPU 3 to CPUs 0-2: |
| [ 1432.307971][ C0] NMI backtrace for cpu 0 skipped: idling at native_safe_halt+0x2/0x10 |
| [ 1432.307978][ C2] NMI backtrace for cpu 2 skipped: idling at native_safe_halt+0x2/0x10 |
| [ 1432.308914][ C1] NMI backtrace for cpu 1 |
| [ 1432.308920][ C1] CPU: 1 PID: 5857 Comm: rs:main Q:Reg Not tainted 4.20.0-next-20190102+ #5 |
| [ 1432.308926][ C1] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014 |
| [ 1432.308930][ C1] RIP: 0010:unwind_next_frame+0x36/0x50 |
| [ 1432.308941][ C1] Code: fa 55 48 c1 ea 03 48 89 e5 48 83 ec 08 0f b6 14 02 48 89 f8 83 e0 07 83 c0 03 38 d0 7c 04 84 d2 75 0f 8b 17 31 c0 85 d2 74 05 <e8> 35 f5 ff ff c9 c3 48 89 7d f8 e8 aa 54 8e 00 48 8b 7d f8 eb e2 |
| [ 1432.308945][ C1] RSP: 0018:ffff88805f667100 EFLAGS: 00000202 |
| [ 1432.308953][ C1] RAX: 0000000000000000 RBX: ffff88805f6671b8 RCX: 000000000000000f |
| [ 1432.308958][ C1] RDX: 0000000000000001 RSI: ffffffff8100c393 RDI: ffff88805f667118 |
| [ 1432.308963][ C1] RBP: ffff88805f667108 R08: 0000000000000001 R09: ffff88805f667168 |
| [ 1432.308968][ C1] R10: ffff88805f667140 R11: ffff88805f667150 R12: 0000000000000000 |
| [ 1432.308973][ C1] R13: 0000000000000000 R14: ffff88805c5d2500 R15: ffff8880664f37c0 |
| [ 1432.308979][ C1] FS: 00007fc30a025700(0000) GS:ffff88806c440000(0000) knlGS:0000000000000000 |
| [ 1432.308983][ C1] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 |
| [ 1432.308989][ C1] CR2: 00007f8d9477b190 CR3: 000000006300b001 CR4: 00000000001606e0 |
| [ 1432.308992][ C1] Call Trace: |
| [ 1432.308995][ C1] __save_stack_trace+0x7a/0xf0 |
| [ 1432.309002][ C1] save_stack_trace+0x1a/0x20 |
| [ 1432.309006][ C1] save_stack+0x45/0xd0 |
| [ 1432.309125][ C1] kasan_kmalloc+0xcf/0xe0 |
| [ 1432.309128][ C1] kasan_slab_alloc+0xf/0x20 |
| [ 1432.309132][ C1] kmem_cache_alloc+0x12d/0x710 |
| [ 1432.309136][ C1] jbd2__journal_start+0x1e4/0xa80 |
| [ 1432.309147][ C1] __ext4_journal_start_sb+0x1a7/0x630 |
| [ 1432.309163][ C1] ext4_da_write_begin+0x4ee/0x12d0 |
| [ 1432.309206][ C1] generic_perform_write+0x2a0/0x6b0 |
| [ 1432.309226][ C1] __generic_file_write_iter+0x25e/0x630 |
| [ 1432.309229][ C1] ext4_file_write_iter+0x381/0x1400 |
| [ 1432.309263][ C1] __vfs_write+0x764/0xb40 |
| [ 1432.309286][ C1] vfs_write+0x20c/0x580 |
| [ 1432.309290][ C1] ksys_write+0x105/0x260 |
| [ 1432.309309][ C1] __x64_sys_write+0x73/0xb0 |
| [ 1432.309312][ C1] do_syscall_64+0x1a3/0x800 |
| [ 1432.309328][ C1] entry_SYSCALL_64_after_hwframe+0x49/0xbe |
| [ 1432.309332][ C1] RIP: 0033:0x7fc30ba8319d |
| [ 1432.309343][ C1] Code: d1 20 00 00 75 10 b8 01 00 00 00 0f 05 48 3d 01 f0 ff ff 73 31 c3 48 83 ec 08 e8 be fa ff ff 48 89 04 24 b8 01 00 00 00 0f 05 <48> 8b 3c 24 48 89 c2 e8 07 fb ff ff 48 89 d0 48 83 c4 08 48 3d 01 |
| [ 1432.309347][ C1] RSP: 002b:00007fc30a023f90 EFLAGS: 00000293 ORIG_RAX: 0000000000000001 |
| [ 1432.309356][ C1] RAX: ffffffffffffffda RBX: 0000000000000400 RCX: 00007fc30ba8319d |
| [ 1432.309361][ C1] RDX: 0000000000000400 RSI: 00000000008d3ce0 RDI: 0000000000000002 |
| [ 1432.309366][ C1] RBP: 00000000008d3ce0 R08: 00000000008d408d R09: 00007fc30b3ffe07 |
| [ 1432.309372][ C1] R10: 0000000000000000 R11: 0000000000000293 R12: 0000000000000000 |
| [ 1432.309377][ C1] R13: 00007fc30a024410 R14: 00007fc304002a50 R15: 00000000008d3ac0 |
| [ 1432.309388][ T1054] Kernel panic - not syncing: hung_task: blocked tasks |
| [ 1432.400481][ T1054] CPU: 3 PID: 1054 Comm: khungtaskd Not tainted 4.20.0-next-20190102+ #5 |
| [ 1432.401805][ T1054] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014 |
| [ 1432.403220][ T1054] Call Trace: |
| [ 1432.403719][ T1054] dump_stack+0x1db/0x2d0 |
| [ 1432.405283][ T1054] panic+0x2cb/0x65c |
| [ 1432.411432][ T1054] watchdog+0xbcc/0x1170 |
| [ 1432.420886][ T1054] kthread+0x357/0x430 |
| [ 1432.422972][ T1054] ret_from_fork+0x3a/0x50 |
| [ 1432.423910][ T1054] Dumping ftrace buffer: |
| [ 1432.424680][ T1054] (ftrace buffer empty) |
| [ 1432.425305][ T1054] Kernel Offset: disabled |
| [ 1432.425916][ T1054] Rebooting in 86400 seconds.. |
| |
| REPORT: |
| INFO: task jbd2/sda-8:3563 blocked for more than 140 seconds. |
| Not tainted 4.20.0-next-20190102+ #5 |
| "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. |
| jbd2/sda-8 D22600 3563 2 0x80000000 |
| Call Trace: |
| __schedule+0x897/0x1e60 |
| schedule+0xfe/0x350 |
| io_schedule+0x1c/0x70 |
| bit_wait_io+0x17/0x90 |
| __wait_on_bit+0xb3/0x130 |
| out_of_line_wait_on_bit+0x1f4/0x3b0 |
| __wait_on_buffer+0x6f/0x90 |
| jbd2_journal_commit_transaction+0x658a/0x8ee4 |
| kjournald2+0x20f/0xb90 |
| kthread+0x357/0x430 |
| ret_from_fork+0x3a/0x50 |
| INFO: task kworker/u12:5:1980 blocked for more than 140 seconds. |
| Not tainted 4.20.0-next-20190102+ #5 |
| "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. |
| kworker/u12:5 D21864 1980 2 0x80000000 |
| Workqueue: writeback wb_workfn (flush-8:0) |
| Call Trace: |
| __schedule+0x897/0x1e60 |
| schedule+0xfe/0x350 |
| io_schedule+0x1c/0x70 |
| rq_qos_wait+0x2f2/0x600 |
| wbt_wait+0x266/0x430 |
| __rq_qos_throttle+0x58/0xb0 |
| blk_mq_make_request+0x424/0x1e10 |
| generic_make_request+0x902/0x17f0 |
| submit_bio+0xba/0x480 |
| ext4_io_submit+0x197/0x230 |
| ext4_writepages+0x139d/0x4180 |
| do_writepages+0x99/0x1a0 |
| __writeback_single_inode+0x1c5/0x1620 |
| writeback_sb_inodes+0x762/0x1260 |
| __writeback_inodes_wb+0x16d/0x3d0 |
| wb_writeback+0xa2d/0xf70 |
| wb_workfn+0xccc/0x16f0 |
| process_one_work+0xd0c/0x1ce0 |
| worker_thread+0x143/0x14a0 |
| kthread+0x357/0x430 |
| ret_from_fork+0x3a/0x50 |
| INFO: task syz-executor2:6677 blocked for more than 140 seconds. |
| Not tainted 4.20.0-next-20190102+ #5 |
| "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. |
| syz-executor2 D19624 6677 22450 0x00000004 |
| Call Trace: |
| __schedule+0x897/0x1e60 |
| schedule+0xfe/0x350 |
| io_schedule+0x1c/0x70 |
| wait_on_page_bit_common+0xad5/0x1370 |
| __filemap_fdatawait_range+0x614/0x890 |
| file_write_and_wait_range+0xd1/0x100 |
| ext4_sync_file+0x2da/0x14d0 |
| vfs_fsync_range+0x144/0x230 |
| ext4_file_write_iter+0x683/0x1400 |
| do_iter_readv_writev+0x902/0xbc0 |
| do_iter_write+0x184/0x610 |
| vfs_iter_write+0x77/0xb0 |
| iter_file_splice_write+0x885/0xfc0 |
| direct_splice_actor+0x126/0x1a0 |
| splice_direct_to_actor+0x3be/0x9d0 |
| do_splice_direct+0x2c7/0x420 |
| do_sendfile+0x61a/0xe60 |
| __x64_sys_sendfile64+0x15a/0x240 |
| do_syscall_64+0x1a3/0x800 |
| entry_SYSCALL_64_after_hwframe+0x49/0xbe |
| RIP: 0033:0x4576a9 |
| Code: 3b 41 10 76 7d 48 81 ec 88 00 00 00 48 89 ac 24 80 00 00 00 48 8d ac 24 80 00 00 00 48 8b 59 20 48 85 db 75 67 48 8b b4 24 a0 <00> 00 00 8b 8c 24 a8 00 00 00 48 89 e7 f3 a4 48 8b 94 24 98 00 00 |
| RSP: 002b:00007f66fbce3c88 EFLAGS: 00000246 ORIG_RAX: 0000000000000028 |
| RAX: ffffffffffffffda RBX: 000000000071bf00 RCX: 00000000004576a9 |
| RDX: 0000000020000040 RSI: 0000000000000005 RDI: 0000000000000005 |
| RBP: 0000000000000004 R08: 0000000000000000 R09: 0000000000000000 |
| R10: 00008080fffffffe R11: 0000000000000246 R12: 00007f66fbce46d4 |
| R13: 00000000004abcac R14: 00000000006eb9b8 R15: 00000000ffffffff |
| INFO: task syz-executor2:6693 blocked for more than 140 seconds. |
| Not tainted 4.20.0-next-20190102+ #5 |
| "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. |
| syz-executor2 D22200 6693 22450 0x00000004 |
| Call Trace: |
| __schedule+0x897/0x1e60 |
| schedule+0xfe/0x350 |
| jbd2_log_wait_commit+0x320/0x4b0 |
| jbd2_complete_transaction+0x18c/0x1e0 |
| ext4_sync_file+0x1211/0x14d0 |
| vfs_fsync_range+0x144/0x230 |
| ext4_file_write_iter+0x683/0x1400 |
| do_iter_readv_writev+0x902/0xbc0 |
| do_iter_write+0x184/0x610 |
| vfs_iter_write+0x77/0xb0 |
| iter_file_splice_write+0x885/0xfc0 |
| direct_splice_actor+0x126/0x1a0 |
| splice_direct_to_actor+0x3be/0x9d0 |
| do_splice_direct+0x2c7/0x420 |
| do_sendfile+0x61a/0xe60 |
| __x64_sys_sendfile64+0x15a/0x240 |
| do_syscall_64+0x1a3/0x800 |
| entry_SYSCALL_64_after_hwframe+0x49/0xbe |
| RIP: 0033:0x4576a9 |
| Code: 3b 41 10 76 7d 48 81 ec 88 00 00 00 48 89 ac 24 80 00 00 00 48 8d ac 24 80 00 00 00 48 8b 59 20 48 85 db 75 67 48 8b b4 24 a0 <00> 00 00 8b 8c 24 a8 00 00 00 48 89 e7 f3 a4 48 8b 94 24 98 00 00 |
| RSP: 002b:00007f66fbcc2c88 EFLAGS: 00000246 ORIG_RAX: 0000000000000028 |
| RAX: ffffffffffffffda RBX: 000000000071bfa0 RCX: 00000000004576a9 |
| RDX: 0000000020000380 RSI: 0000000000000005 RDI: 0000000000000005 |
| RBP: 0000000000000004 R08: 0000000000000000 R09: 0000000000000000 |
| R10: 0001000000020000 R11: 0000000000000246 R12: 00007f66fbcc36d4 |
| R13: 00000000004abcac R14: 00000000006eb9b8 R15: 00000000ffffffff |
| |
| Showing all locks held in the system: |
| 1 lock held by khungtaskd/1054: |
| #0: 00000000c542499d (rcu_read_lock){....}, at: debug_show_all_locks+0xc6/0x41d |
| 1 lock held by rsyslogd/5859: |
| #0: 00000000567e80cf (&f->f_pos_lock){+.+.}, at: __fdget_pos+0x1b3/0x1f0 |
| 2 locks held by getty/5951: |
| #0: 000000003b0dfac2 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 |
| #1: 00000000281ebc43 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x30a/0x1eb0 |
| 2 locks held by getty/5952: |
| #0: 00000000f0d48c91 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 |
| #1: 00000000cff3c1fb (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x30a/0x1eb0 |
| 2 locks held by getty/5953: |
| #0: 00000000cb353a8f (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 |
| #1: 00000000373ded25 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x30a/0x1eb0 |
| 2 locks held by getty/5954: |
| #0: 000000005c8be537 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 |
| #1: 00000000d7aec815 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x30a/0x1eb0 |
| 2 locks held by getty/5955: |
| #0: 00000000eda8138a (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 |
| #1: 00000000f4fd60a6 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x30a/0x1eb0 |
| 2 locks held by getty/5956: |
| #0: 000000000745b5fb (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 |
| #1: 00000000a4c3442a (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x30a/0x1eb0 |
| 2 locks held by getty/5957: |
| #0: 000000002cb7c49e (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 |
| #1: 000000009258e26f (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x30a/0x1eb0 |
| 4 locks held by kworker/u12:5/1980: |
| #0: 000000005e902bc8 ((wq_completion)"writeback"){+.+.}, at: process_one_work+0xbc7/0x1ce0 |
| #1: 0000000056cd520a ((work_completion)(&(&wb->dwork)->work)){+.+.}, at: process_one_work+0xc1d/0x1ce0 |
| #2: 00000000146ba37b (&type->s_umount_key#30){++++}, at: trylock_super+0x22/0x110 |
| #3: 00000000fec9c2c2 (&sbi->s_journal_flag_rwsem){.+.+}, at: do_writepages+0x99/0x1a0 |
| 1 lock held by syz-executor2/6677: |
| #0: 000000007b67e744 (sb_writers#3){.+.+}, at: do_sendfile+0xad7/0xe60 |
| 1 lock held by syz-executor2/6693: |
| #0: 000000007b67e744 (sb_writers#3){.+.+}, at: do_sendfile+0xad7/0xe60 |
| |
| ============================================= |
| |
| NMI backtrace for cpu 3 |
| CPU: 3 PID: 1054 Comm: khungtaskd Not tainted 4.20.0-next-20190102+ #5 |
| Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014 |
| Call Trace: |
| dump_stack+0x1db/0x2d0 |
| nmi_cpu_backtrace.cold+0x63/0xa4 |
| nmi_trigger_cpumask_backtrace+0x1be/0x236 |
| arch_trigger_cpumask_backtrace+0x14/0x20 |
| watchdog+0xbbb/0x1170 |
| kthread+0x357/0x430 |
| ret_from_fork+0x3a/0x50 |
| Sending NMI from CPU 3 to CPUs 0-2: |
| NMI backtrace for cpu 0 skipped: idling at native_safe_halt+0x2/0x10 |
| NMI backtrace for cpu 2 skipped: idling at native_safe_halt+0x2/0x10 |
| NMI backtrace for cpu 1 |
| CPU: 1 PID: 5857 Comm: rs:main Q:Reg Not tainted 4.20.0-next-20190102+ #5 |
| Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014 |
| RIP: 0010:unwind_next_frame+0x36/0x50 |
| Code: fa 55 48 c1 ea 03 48 89 e5 48 83 ec 08 0f b6 14 02 48 89 f8 83 e0 07 83 c0 03 38 d0 7c 04 84 d2 75 0f 8b 17 31 c0 85 d2 74 05 <e8> 35 f5 ff ff c9 c3 48 89 7d f8 e8 aa 54 8e 00 48 8b 7d f8 eb e2 |
| RSP: 0018:ffff88805f667100 EFLAGS: 00000202 |
| RAX: 0000000000000000 RBX: ffff88805f6671b8 RCX: 000000000000000f |
| RDX: 0000000000000001 RSI: ffffffff8100c393 RDI: ffff88805f667118 |
| RBP: ffff88805f667108 R08: 0000000000000001 R09: ffff88805f667168 |
| R10: ffff88805f667140 R11: ffff88805f667150 R12: 0000000000000000 |
| R13: 0000000000000000 R14: ffff88805c5d2500 R15: ffff8880664f37c0 |
| FS: 00007fc30a025700(0000) GS:ffff88806c440000(0000) knlGS:0000000000000000 |
| CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 |
| CR2: 00007f8d9477b190 CR3: 000000006300b001 CR4: 00000000001606e0 |
| Call Trace: |
| __save_stack_trace+0x7a/0xf0 |
| save_stack_trace+0x1a/0x20 |
| save_stack+0x45/0xd0 |
| kasan_kmalloc+0xcf/0xe0 |
| kasan_slab_alloc+0xf/0x20 |
| kmem_cache_alloc+0x12d/0x710 |
| jbd2__journal_start+0x1e4/0xa80 |
| __ext4_journal_start_sb+0x1a7/0x630 |
| ext4_da_write_begin+0x4ee/0x12d0 |
| generic_perform_write+0x2a0/0x6b0 |
| __generic_file_write_iter+0x25e/0x630 |
| ext4_file_write_iter+0x381/0x1400 |
| __vfs_write+0x764/0xb40 |
| vfs_write+0x20c/0x580 |
| ksys_write+0x105/0x260 |
| __x64_sys_write+0x73/0xb0 |
| do_syscall_64+0x1a3/0x800 |
| entry_SYSCALL_64_after_hwframe+0x49/0xbe |
| RIP: 0033:0x7fc30ba8319d |
| Code: d1 20 00 00 75 10 b8 01 00 00 00 0f 05 48 3d 01 f0 ff ff 73 31 c3 48 83 ec 08 e8 be fa ff ff 48 89 04 24 b8 01 00 00 00 0f 05 <48> 8b 3c 24 48 89 c2 e8 07 fb ff ff 48 89 d0 48 83 c4 08 48 3d 01 |
| RSP: 002b:00007fc30a023f90 EFLAGS: 00000293 ORIG_RAX: 0000000000000001 |
| RAX: ffffffffffffffda RBX: 0000000000000400 RCX: 00007fc30ba8319d |
| RDX: 0000000000000400 RSI: 00000000008d3ce0 RDI: 0000000000000002 |
| RBP: 00000000008d3ce0 R08: 00000000008d408d R09: 00007fc30b3ffe07 |
| R10: 0000000000000000 R11: 0000000000000293 R12: 0000000000000000 |
| R13: 00007fc30a024410 R14: 00007fc304002a50 R15: 00000000008d3ac0 |
