warning: checkpointing journal with EXT4_IOC_CHECKPOINT_FLAG_ZEROOUT can be slow Bluetooth: hci6: command 0x0406 tx timeout INFO: task systemd-journal:111 blocked for more than 143 seconds. Not tainted 6.1.0-rc6-next-20221123 #1 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. task:systemd-journal state:D stack:25168 pid:111 ppid:1 flags:0x00004004 Call Trace: __schedule+0x926/0x25d0 schedule+0xde/0x1b0 start_this_handle+0x5d9/0x14c0 jbd2__journal_start+0x394/0x8d0 __ext4_journal_start_sb+0x395/0x470 ext4_dirty_inode+0xa1/0x110 __mark_inode_dirty+0x1aa/0xe70 generic_update_time+0x21b/0x2b0 file_update_time+0x22f/0x280 ext4_page_mkwrite+0x2ee/0x1a70 do_page_mkwrite+0x1a0/0x640 do_wp_page+0x2df/0x4500 __handle_mm_fault+0x11eb/0x3640 handle_mm_fault+0x2ce/0xae0 do_user_addr_fault+0x53a/0x1300 exc_page_fault+0x9c/0x1a0 asm_exc_page_fault+0x26/0x30 RIP: 0033:0x7f3f2e5f3de0 RSP: 002b:00007ffd185dfa30 EFLAGS: 00010246 RAX: 0000000000000000 RBX: 000055c6157b0290 RCX: 0000000000000000 RDX: 00007f3f2e42f000 RSI: 000055c6157b0480 RDI: 00007ffd185df9c8 RBP: 0000000000000001 R08: 0000000000000001 R09: 00007ffd185dfb58 R10: 00007f3f2e666a34 R11: 000055c6157b0290 R12: 000055c6157b0290 R13: 00007ffd185dfb50 R14: 0000000000000000 R15: 00007ffd185dfb50 INFO: task rs:main Q:Reg:207 blocked for more than 143 seconds. Not tainted 6.1.0-rc6-next-20221123 #1 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. task:rs:main Q:Reg state:D stack:26400 pid:207 ppid:1 flags:0x00004000 Call Trace: __schedule+0x926/0x25d0 schedule+0xde/0x1b0 start_this_handle+0x5d9/0x14c0 jbd2__journal_start+0x394/0x8d0 __ext4_journal_start_sb+0x395/0x470 ext4_dirty_inode+0xa1/0x110 __mark_inode_dirty+0x1aa/0xe70 generic_update_time+0x21b/0x2b0 file_modified_flags+0x2d4/0x330 ext4_buffered_write_iter+0xf9/0x460 ext4_file_write_iter+0x3ff/0x1910 vfs_write+0xa4f/0xe40 ksys_write+0x12b/0x260 do_syscall_64+0x3f/0x90 entry_SYSCALL_64_after_hwframe+0x72/0xdc RIP: 0033:0x7f3f83397fef RSP: 002b:00007f3f82553860 EFLAGS: 00000293 ORIG_RAX: 0000000000000001 RAX: ffffffffffffffda RBX: 00007f3f780215b0 RCX: 00007f3f83397fef RDX: 0000000000000082 RSI: 00007f3f78021850 RDI: 0000000000000007 RBP: 0000000000000082 R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000293 R12: 00007f3f78021850 R13: 0000000000000000 R14: 0000000000000082 R15: 00007f3f780215b0 INFO: task syz-executor.3:4481 blocked for more than 143 seconds. Not tainted 6.1.0-rc6-next-20221123 #1 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. task:syz-executor.3 state:D stack:24776 pid:4481 ppid:1 flags:0x00004004 Call Trace: __schedule+0x926/0x25d0 schedule+0xde/0x1b0 start_this_handle+0x5d9/0x14c0 jbd2__journal_start+0x394/0x8d0 __ext4_journal_start_sb+0x395/0x470 ext4_dirty_inode+0xa1/0x110 __mark_inode_dirty+0x1aa/0xe70 touch_atime+0x648/0x700 iterate_dir+0x462/0x700 __x64_sys_getdents64+0x13e/0x360 do_syscall_64+0x3f/0x90 entry_SYSCALL_64_after_hwframe+0x72/0xdc RIP: 0033:0x7fc71b9a19c7 RSP: 002b:00007ffd60dd2f48 EFLAGS: 00000293 ORIG_RAX: 00000000000000d9 RAX: ffffffffffffffda RBX: 00005555565f1c20 RCX: 00007fc71b9a19c7 RDX: 0000000000008000 RSI: 00005555565f1c50 RDI: 0000000000000003 RBP: 00005555565f1c50 R08: 0000000000000030 R09: 00007fc71ba62640 R10: 0000000000000231 R11: 0000000000000293 R12: ffffffffffffffbc R13: 00005555565f1c24 R14: 0000000000000016 R15: 00007ffd60dd4140 INFO: task syz-executor.5:10121 blocked for more than 143 seconds. Not tainted 6.1.0-rc6-next-20221123 #1 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. task:syz-executor.5 state:D stack:25048 pid:10121 ppid:4045 flags:0x00004004 Call Trace: __schedule+0x926/0x25d0 schedule+0xde/0x1b0 start_this_handle+0x5d9/0x14c0 jbd2__journal_start+0x394/0x8d0 __ext4_journal_start_sb+0x395/0x470 ext4_dirty_inode+0xa1/0x110 __mark_inode_dirty+0x1aa/0xe70 touch_atime+0x648/0x700 filemap_read+0x99d/0xb60 generic_file_read_iter+0x3d1/0x540 ext4_file_read_iter+0x186/0x4a0 generic_file_splice_read+0x18b/0x4d0 do_splice_to+0x1bc/0x240 splice_direct_to_actor+0x2b0/0x8c0 do_splice_direct+0x1bc/0x290 do_sendfile+0xb1d/0x1280 __x64_sys_sendfile64+0x248/0x2a0 do_syscall_64+0x3f/0x90 entry_SYSCALL_64_after_hwframe+0x72/0xdc RIP: 0033:0x7f3b59b72b19 RSP: 002b:00007f3b570e8188 EFLAGS: 00000246 ORIG_RAX: 0000000000000028 RAX: ffffffffffffffda RBX: 00007f3b59c85f60 RCX: 00007f3b59b72b19 RDX: 0000000000000000 RSI: 0000000000000003 RDI: 0000000000000006 RBP: 00007f3b59bccf6d R08: 0000000000000000 R09: 0000000000000000 R10: 00000000fffffdef R11: 0000000000000246 R12: 0000000000000000 R13: 00007ffd026c0d9f R14: 00007f3b570e8300 R15: 0000000000022000 INFO: task syz-executor.0:10129 blocked for more than 143 seconds. Not tainted 6.1.0-rc6-next-20221123 #1 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. task:syz-executor.0 state:D stack:26928 pid:10129 ppid:1511 flags:0x00004004 Call Trace: __schedule+0x926/0x25d0 schedule+0xde/0x1b0 start_this_handle+0x5d9/0x14c0 jbd2__journal_start+0x394/0x8d0 __ext4_journal_start_sb+0x395/0x470 __ext4_new_inode+0x2f34/0x55f0 ext4_create+0x2e4/0x4e0 lookup_open.isra.0+0xed0/0x1250 path_openat+0x946/0x29b0 do_filp_open+0x1ba/0x410 do_sys_openat2+0x171/0x4c0 __x64_sys_openat+0x143/0x200 do_syscall_64+0x3f/0x90 entry_SYSCALL_64_after_hwframe+0x72/0xdc RIP: 0033:0x7fd0b20c9b19 RSP: 002b:00007fd0af63f188 EFLAGS: 00000246 ORIG_RAX: 0000000000000101 RAX: ffffffffffffffda RBX: 00007fd0b21dcf60 RCX: 00007fd0b20c9b19 RDX: 0000000000004042 RSI: 0000000020000100 RDI: ffffffffffffff9c RBP: 00007fd0b2123f6d R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000100 R11: 0000000000000246 R12: 0000000000000000 R13: 00007ffcb7eadcdf R14: 00007fd0af63f300 R15: 0000000000022000 INFO: task syz-executor.0:10148 blocked for more than 143 seconds. Not tainted 6.1.0-rc6-next-20221123 #1 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. task:syz-executor.0 state:D stack:29080 pid:10148 ppid:1511 flags:0x00000004 Call Trace: __schedule+0x926/0x25d0 schedule+0xde/0x1b0 rwsem_down_write_slowpath+0x5d2/0x1250 down_write+0x1ec/0x220 path_openat+0x8dd/0x29b0 do_filp_open+0x1ba/0x410 do_sys_openat2+0x171/0x4c0 __x64_sys_openat+0x143/0x200 do_syscall_64+0x3f/0x90 entry_SYSCALL_64_after_hwframe+0x72/0xdc RIP: 0033:0x7fd0b20c9b19 RSP: 002b:00007fd0af5dc188 EFLAGS: 00000246 ORIG_RAX: 0000000000000101 RAX: ffffffffffffffda RBX: 00007fd0b21dd1a0 RCX: 00007fd0b20c9b19 RDX: 0000000000004042 RSI: 0000000020000100 RDI: ffffffffffffff9c RBP: 00007fd0b2123f6d R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000100 R11: 0000000000000246 R12: 0000000000000000 R13: 00007ffcb7eadcdf R14: 00007fd0af5dc300 R15: 0000000000022000 INFO: task syz-executor.1:10141 blocked for more than 143 seconds. Not tainted 6.1.0-rc6-next-20221123 #1 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. task:syz-executor.1 state:D stack:28104 pid:10141 ppid:7129 flags:0x00004004 Call Trace: __schedule+0x926/0x25d0 schedule+0xde/0x1b0 start_this_handle+0x5d9/0x14c0 jbd2__journal_start+0x394/0x8d0 __ext4_journal_start_sb+0x395/0x470 ext4_dirty_inode+0xa1/0x110 __mark_inode_dirty+0x1aa/0xe70 generic_update_time+0x21b/0x2b0 file_update_time+0x22f/0x280 ext4_page_mkwrite+0x2ee/0x1a70 do_page_mkwrite+0x1a0/0x640 __handle_mm_fault+0x12ac/0x3640 handle_mm_fault+0x2ce/0xae0 do_user_addr_fault+0x53a/0x1300 exc_page_fault+0x9c/0x1a0 asm_exc_page_fault+0x26/0x30 RIP: 0033:0x7f7dc3146673 RSP: 002b:00007ffccef09ed8 EFLAGS: 00010287 RAX: 0000001b30c29000 RBX: 00007f7dc2f20008 RCX: 0000001b30c20000 RDX: 0000001b30c29004 RSI: ffffffff842c865e RDI: 000000002f759a4c RBP: 0000000000000001 R08: 0000001b31c20000 R09: 0000001b30c28e40 R10: 0000000000001a4c R11: 000000002f759a50 R12: 0000000000000156 R13: 00007f7dc32b0000 R14: ffffffff842c865e R15: 00007f7dc32bbff0 INFO: task syz-executor.7:10142 blocked for more than 143 seconds. Not tainted 6.1.0-rc6-next-20221123 #1 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. task:syz-executor.7 state:D stack:28104 pid:10142 ppid:4229 flags:0x00004004 Call Trace: __schedule+0x926/0x25d0 schedule+0xde/0x1b0 start_this_handle+0x5d9/0x14c0 jbd2__journal_start+0x394/0x8d0 __ext4_journal_start_sb+0x395/0x470 ext4_dirty_inode+0xa1/0x110 __mark_inode_dirty+0x1aa/0xe70 generic_update_time+0x21b/0x2b0 file_update_time+0x22f/0x280 ext4_page_mkwrite+0x2ee/0x1a70 do_page_mkwrite+0x1a0/0x640 __handle_mm_fault+0x12ac/0x3640 handle_mm_fault+0x2ce/0xae0 do_user_addr_fault+0x53a/0x1300 exc_page_fault+0x9c/0x1a0 asm_exc_page_fault+0x26/0x30 RIP: 0033:0x7f73083b3673 RSP: 002b:00007fff69ab54d8 EFLAGS: 00010287 RAX: 0000001b33926000 RBX: 00007f730818d008 RCX: 0000001b33920000 RDX: 0000001b33926004 RSI: ffffffff81931002 RDI: 000000001c291f22 RBP: 0000000000000001 R08: 0000001b34920000 R09: 0000001b339255ec R10: 0000000000001f22 R11: 000000001c291f26 R12: 000000000000ac8b R13: 00007f730851d000 R14: ffffffff81931002 R15: 00007f7308528ff0 INFO: task syz-executor.7:10144 blocked for more than 143 seconds. Not tainted 6.1.0-rc6-next-20221123 #1 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. task:syz-executor.7 state:D stack:27032 pid:10144 ppid:4229 flags:0x00004004 Call Trace: __schedule+0x926/0x25d0 schedule+0xde/0x1b0 start_this_handle+0x5d9/0x14c0 jbd2__journal_start+0x394/0x8d0 __ext4_journal_start_sb+0x395/0x470 __ext4_new_inode+0x2f34/0x55f0 ext4_create+0x2e4/0x4e0 lookup_open.isra.0+0xed0/0x1250 path_openat+0x946/0x29b0 do_filp_open+0x1ba/0x410 do_sys_openat2+0x171/0x4c0 __x64_sys_openat+0x143/0x200 do_syscall_64+0x3f/0x90 entry_SYSCALL_64_after_hwframe+0x72/0xdc RIP: 0033:0x7f7308415b19 RSP: 002b:00007f730598b188 EFLAGS: 00000246 ORIG_RAX: 0000000000000101 RAX: ffffffffffffffda RBX: 00007f7308528f60 RCX: 00007f7308415b19 RDX: 00000000000024e1 RSI: 0000000020000040 RDI: ffffffffffffff9c RBP: 00007f730846ff6d R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000 R13: 00007fff69ab550f R14: 00007f730598b300 R15: 0000000000022000 INFO: task syz-executor.6:10143 blocked for more than 143 seconds. Not tainted 6.1.0-rc6-next-20221123 #1 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. task:syz-executor.6 state:D stack:27952 pid:10143 ppid:4063 flags:0x00004004 Call Trace: __schedule+0x926/0x25d0 schedule+0xde/0x1b0 start_this_handle+0x5d9/0x14c0 jbd2__journal_start+0x394/0x8d0 __ext4_journal_start_sb+0x395/0x470 ext4_dirty_inode+0xa1/0x110 __mark_inode_dirty+0x1aa/0xe70 generic_update_time+0x21b/0x2b0 file_update_time+0x22f/0x280 ext4_page_mkwrite+0x2ee/0x1a70 do_page_mkwrite+0x1a0/0x640 __handle_mm_fault+0x12ac/0x3640 handle_mm_fault+0x2ce/0xae0 do_user_addr_fault+0x53a/0x1300 exc_page_fault+0x9c/0x1a0 asm_exc_page_fault+0x26/0x30 RIP: 0033:0x7f83ec306673 RSP: 002b:00007ffed9b5e218 EFLAGS: 00010287 RAX: 0000001b3132a000 RBX: 00007f83ec0e0008 RCX: 0000001b31320000 RDX: 0000001b3132a004 RSI: ffffffff821219a1 RDI: 000000003fd8f51b RBP: 0000000000000001 R08: 0000001b32320000 R09: 0000001b3132978c R10: 000000000000151e R11: 000000003fd8f51f R12: 0000000000002fdd R13: 00007f83ec470000 R14: ffffffff821219a1 R15: 00007f83ec47bff0 Showing all locks held in the system: 1 lock held by rcu_tasks_kthre/11: #0: ffffffff85607f70 (rcu_tasks.tasks_gp_mutex){+.+.}-{3:3}, at: rcu_tasks_one_gp+0x26/0xc10 1 lock held by khungtaskd/25: #0: ffffffff85608a40 (rcu_read_lock){....}-{1:2}, at: debug_show_all_locks+0x57/0x264 2 locks held by systemd-journal/111: #0: ffff88801097a358 (&mm->mmap_lock){++++}-{3:3}, at: do_user_addr_fault+0x33b/0x1300 #1: ffff8880108a0530 (sb_pagefaults){.+.+}-{0:0}, at: do_page_mkwrite+0x1a0/0x640 1 lock held by in:imklog/206: 3 locks held by rs:main Q:Reg/207: #0: ffff88800ef72868 (&f->f_pos_lock){+.+.}-{3:3}, at: __fdget_pos+0xeb/0x110 #1: ffff8880108a0438 (sb_writers#3){.+.+}-{0:0}, at: ksys_write+0x12b/0x260 #2: ffff88801bd90400 (&sb->s_type->i_mutex_key#6){++++}-{3:3}, at: ext4_buffered_write_iter+0xb0/0x460 4 locks held by kworker/u4:6/896: #0: ffff888008fc0138 ((wq_completion)writeback){+.+.}-{0:0}, at: process_one_work+0x915/0x16a0 #1: ffff88801db67db0 ((work_completion)(&(&wb->dwork)->work)){+.+.}-{0:0}, at: process_one_work+0x949/0x16a0 #2: ffff8880108a00e0 (&type->s_umount_key#42){++++}-{3:3}, at: trylock_super+0x21/0x110 #3: ffff8880108a2b90 (&sbi->s_writepages_rwsem){.+.+}-{0:0}, at: do_writepages+0x1b4/0x6a0 2 locks held by syz-executor.3/4481: #0: ffff88803cea97e0 (&type->i_mutex_dir_key#3){++++}-{3:3}, at: iterate_dir+0xd1/0x700 #1: ffff8880108a0438 (sb_writers#3){.+.+}-{0:0}, at: iterate_dir+0x462/0x700 2 locks held by syz-executor.5/10121: #0: ffff8880108a0438 (sb_writers#3){.+.+}-{0:0}, at: __x64_sys_sendfile64+0x248/0x2a0 #1: ffff8880108a0438 (sb_writers#3){.+.+}-{0:0}, at: filemap_read+0x99d/0xb60 2 locks held by syz-executor.0/10129: #0: ffff8880108a0438 (sb_writers#3){.+.+}-{0:0}, at: path_openat+0x2755/0x29b0 #1: ffff88801dedd380 (&type->i_mutex_dir_key#3){++++}-{3:3}, at: path_openat+0x8dd/0x29b0 2 locks held by syz-executor.0/10148: #0: ffff8880108a0438 (sb_writers#3){.+.+}-{0:0}, at: path_openat+0x2755/0x29b0 #1: ffff88801dedd380 (&type->i_mutex_dir_key#3){++++}-{3:3}, at: path_openat+0x8dd/0x29b0 2 locks held by syz-executor.1/10141: #0: ffff8880104b5ed8 (&mm->mmap_lock){++++}-{3:3}, at: do_user_addr_fault+0x33b/0x1300 #1: ffff8880108a0530 (sb_pagefaults){.+.+}-{0:0}, at: do_page_mkwrite+0x1a0/0x640 2 locks held by syz-executor.7/10142: #0: ffff8880104b6758 (&mm->mmap_lock){++++}-{3:3}, at: do_user_addr_fault+0x33b/0x1300 #1: ffff8880108a0530 (sb_pagefaults){.+.+}-{0:0}, at: do_page_mkwrite+0x1a0/0x640 2 locks held by syz-executor.7/10144: #0: ffff8880108a0438 (sb_writers#3){.+.+}-{0:0}, at: path_openat+0x2755/0x29b0 #1: ffff88801dfc2bc0 (&type->i_mutex_dir_key#3){++++}-{3:3}, at: path_openat+0x8dd/0x29b0 2 locks held by syz-executor.6/10143: #0: ffff8880104b3458 (&mm->mmap_lock){++++}-{3:3}, at: do_user_addr_fault+0x33b/0x1300 #1: ffff8880108a0530 (sb_pagefaults){.+.+}-{0:0}, at: do_page_mkwrite+0x1a0/0x640 5 locks held by syz-executor.6/10145: #0: ffff8880108a4170 (&journal->j_barrier){+.+.}-{3:3}, at: jbd2_journal_lock_updates+0x162/0x310 #1: ffff8880108a43f8 (&journal->j_checkpoint_mutex){+.+.}-{3:3}, at: jbd2_journal_flush+0x48f/0xc10 #2: ffff888009d52960 (&dd->lock){+.+.}-{2:2}, at: dd_insert_requests+0x100/0xce0 #3: ffffffff85608a40 (rcu_read_lock){....}-{1:2}, at: ieee80211_iterate_active_interfaces_atomic+0x4/0x180 #4: ffffffff85a47478 (hwsim_radio_lock){+.-.}-{2:2}, at: mac80211_hwsim_tx_frame_no_nl.isra.0+0x6f1/0x1360 2 locks held by syz-executor.2/10153: #0: ffff8880108a0438 (sb_writers#3){.+.+}-{0:0}, at: filename_create+0xf1/0x4a0 #1: ffff88803cce97e0 (&type->i_mutex_dir_key#3/1){+.+.}-{3:3}, at: filename_create+0x1aa/0x4a0 2 locks held by syz-executor.4/10154: #0: ffff8880108a0438 (sb_writers#3){.+.+}-{0:0}, at: filename_create+0xf1/0x4a0 #1: ffff88801dfd35b0 (&type->i_mutex_dir_key#3/1){+.+.}-{3:3}, at: filename_create+0x1aa/0x4a0 1 lock held by cron/10155: #0: ffff8880108a0438 (sb_writers#3){.+.+}-{0:0}, at: path_openat+0x15fb/0x29b0 ============================================= systemd[1]: Starting Load/Save RF Kill Switch Status... systemd[1]: systemd-journald.service: Main process exited, code=killed, status=6/ABRT systemd[1]: systemd-journald.service: Failed with result 'watchdog'. systemd[1]: systemd-journald.service: Consumed 1.420s CPU time. systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. systemd[1]: Stopping Flush Journal to Persistent Storage... systemd[1]: Started Load/Save RF Kill Switch Status.