RBP: 000000000072bea0 R08: 0000000020000280 R09: 0000000000000000
R10: 0000000020000200 R11: 0000000000000246 R12: 0000000000000015
R13: 00000000004bb763 R14: 00000000004c8108 R15: 0000000000000003
kasan: CONFIG_KASAN_INLINE enabled
kasan: GPF could be caused by NULL-ptr deref or user memory access
general protection fault: 0000 [#1] SMP KASAN
CPU: 0 PID: 30013 Comm: kworker/0:2 Not tainted 4.17.0+ #91
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
Workqueue:            (null) (rcu_par_gp)
RIP: 0010:__delayacct_blkio_end+0x4f/0xc0 kernel/delayacct.c:83
Code: 48 c1 ea 03 80 3c 02 00 0f 85 80 00 00 00 48 8b 9b 30 12 00 00 48 b8 00 00 00 00 00 fc ff df 48 8d 7b 38 48 89 fa 48 c1 ea 03 <0f> b6 04 02 84 c0 74 04 3c 03 7e 51 44 8b 63 38 31 ff 41 83 e4 01 
RSP: 0018:ffff8801dae06c30 EFLAGS: 00010002
RAX: dffffc0000000000 RBX: 0000000000000000 RCX: ffff880183758138
RDX: 0000000000000007 RSI: ffffffff817b4b41 RDI: 0000000000000038
RBP: ffff8801dae06c48 R08: ffffed003b5c46d7 R09: 0000000000000001
R10: ffff8801dae06cd8 R11: ffff8801dae236b3 R12: ffff8801d8bc6740
R13: ffff880183758100 R14: ffff88018375813c R15: 0000000000000002
FS:  0000000000000000(0000) GS:ffff8801dae00000(0000) knlGS:0000000000000000
CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 0000000000000130 CR3: 0000000184374000 CR4: 00000000001406f0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
 <IRQ>
 delayacct_blkio_end include/linux/delayacct.h:128 [inline]
 try_to_wake_up+0xaa5/0x1280 kernel/sched/core.c:2054
 default_wake_function+0x30/0x50 kernel/sched/core.c:3736
 autoremove_wake_function+0x7a/0x350 kernel/sched/wait.c:373
 wake_bit_function+0xcb/0x160 kernel/sched/wait_bit.c:30
 __wake_up_common+0x189/0x730 kernel/sched/wait.c:90
 __wake_up_common_lock+0x1b4/0x300 kernel/sched/wait.c:119
 __wake_up+0xe/0x10 kernel/sched/wait.c:143
 __wake_up_bit+0x1a6/0x270 kernel/sched/wait_bit.c:125
 wake_up_bit+0x3d/0x40 kernel/sched/wait_bit.c:148
 journal_end_buffer_io_sync+0xa4/0xe0 fs/jbd2/commit.c:44
 end_bio_bh_io_sync+0xf3/0x140 fs/buffer.c:2969
 bio_endio+0x51c/0x9c0 block/bio.c:1836
 req_bio_endio block/blk-core.c:281 [inline]
 blk_update_request+0x3aa/0xcb0 block/blk-core.c:3091
 scsi_end_request+0xd3/0x870 drivers/scsi/scsi_lib.c:672
 scsi_io_completion+0xcb2/0x1d10 drivers/scsi/scsi_lib.c:898
 scsi_finish_command+0x542/0x8d0 drivers/scsi/scsi.c:248
 scsi_softirq_done+0x3e2/0x4c0 drivers/scsi/scsi_lib.c:1683
 __blk_mq_complete_request block/blk-mq.c:583 [inline]
 blk_mq_complete_request+0x355/0x630 block/blk-mq.c:620
 scsi_mq_done+0xe2/0x430 drivers/scsi/scsi_lib.c:1994
 virtscsi_complete_cmd+0x573/0x740 drivers/scsi/virtio_scsi.c:207
 virtscsi_vq_done+0xc3/0x170 drivers/scsi/virtio_scsi.c:223
 virtscsi_req_done+0xa7/0xd0 drivers/scsi/virtio_scsi.c:238
 vring_interrupt+0x128/0x170 drivers/virtio/virtio_ring.c:950
 __handle_irq_event_percpu+0x1c0/0xad0 kernel/irq/handle.c:149
 handle_irq_event_percpu+0x98/0x1c0 kernel/irq/handle.c:189
 handle_irq_event+0xa7/0x135 kernel/irq/handle.c:206
 handle_edge_irq+0x20f/0x870 kernel/irq/chip.c:791
 generic_handle_irq_desc include/linux/irqdesc.h:159 [inline]
 handle_irq+0x18c/0x2e7 arch/x86/kernel/irq_64.c:77
 do_IRQ+0x78/0x190 arch/x86/kernel/irq.c:245
 common_interrupt+0xf/0xf arch/x86/entry/entry_64.S:642
 </IRQ>
RIP: 0010:arch_local_irq_enable arch/x86/include/asm/paravirt.h:793 [inline]
RIP: 0010:__raw_spin_unlock_irq include/linux/spinlock_api_smp.h:168 [inline]
RIP: 0010:_raw_spin_unlock_irq+0x56/0x70 kernel/locking/spinlock.c:192
Code: f1 88 48 b8 00 00 00 00 00 fc ff df 48 89 fa 48 c1 ea 03 80 3c 02 00 75 1d 48 83 3d 6b 53 6f 01 00 74 11 fb 66 0f 1f 44 00 00 <65> ff 0d 33 98 7f 78 5b 5d c3 0f 0b e8 39 db 34 fa eb dc 0f 1f 80 
RSP: 0018:ffff88017ee57748 EFLAGS: 00000282 ORIG_RAX: ffffffffffffffda
RAX: dffffc0000000000 RBX: ffff8801dae2c9c0 RCX: 0000000000000000
RDX: 1ffffffff11e351f RSI: 0000000000000001 RDI: ffffffff88f1a8f8
RBP: ffff88017ee57750 R08: ffffed003b5c5939 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000000 R12: ffff8801d8bc6740
R13: ffff8801d837c080 R14: ffff8801aef77080 R15: ffff8801d8bc6740
 finish_lock_switch kernel/sched/core.c:2603 [inline]
 finish_task_switch+0x1ca/0x840 kernel/sched/core.c:2701
 context_switch kernel/sched/core.c:2859 [inline]
 __schedule+0x809/0x1e30 kernel/sched/core.c:3498
 schedule+0xef/0x430 kernel/sched/core.c:3542
 worker_thread+0x3c7/0x13a0 kernel/workqueue.c:2317
 kthread+0x345/0x410 kernel/kthread.c:240
 ret_from_fork+0x3a/0x50 arch/x86/entry/entry_64.S:412
Modules linked in:
Dumping ftrace buffer:
   (ftrace buffer empty)

======================================================
WARNING: possible circular locking dependency detected
4.17.0+ #91 Not tainted
------------------------------------------------------
kworker/0:2/30013 is trying to acquire lock:
00000000b6d1e5fb ((console_sem).lock){-.-.}, at: down_trylock+0x13/0x70 kernel/locking/semaphore.c:136

but task is already holding lock:
0000000078ed34ba (&p->pi_lock){-.-.}, at: try_to_wake_up+0xca/0x1280 kernel/sched/core.c:1983

which lock already depends on the new lock.


the existing dependency chain (in reverse order) is:

-> #1 (&p->pi_lock){-.-.}:
       __raw_spin_lock_irqsave include/linux/spinlock_api_smp.h:110 [inline]
       _raw_spin_lock_irqsave+0x96/0xc0 kernel/locking/spinlock.c:152
       try_to_wake_up+0xca/0x1280 kernel/sched/core.c:1983
       wake_up_process+0x10/0x20 kernel/sched/core.c:2146
       __up.isra.1+0x1b8/0x290 kernel/locking/semaphore.c:262
       up+0x12f/0x1b0 kernel/locking/semaphore.c:187
       __up_console_sem+0xbe/0x1b0 kernel/printk/printk.c:242
       console_unlock+0x79a/0x10a0 kernel/printk/printk.c:2411
       vprintk_emit+0x6b2/0xde0 kernel/printk/printk.c:1907
       vprintk_default+0x28/0x30 kernel/printk/printk.c:1948
       vprintk_func+0x7a/0xe7 kernel/printk/printk_safe.c:382
       printk+0x9e/0xba kernel/printk/printk.c:1981
       load_umh+0x51/0xbd net/bpfilter/bpfilter_kern.c:95
       do_one_initcall+0x127/0x913 init/main.c:884
       do_initcall_level init/main.c:952 [inline]
       do_initcalls init/main.c:960 [inline]
       do_basic_setup init/main.c:978 [inline]
       kernel_init_freeable+0x49b/0x58e init/main.c:1135
       kernel_init+0x11/0x1b3 init/main.c:1061
       ret_from_fork+0x3a/0x50 arch/x86/entry/entry_64.S:412

-> #0 ((console_sem).lock){-.-.}:
       lock_acquire+0x1dc/0x520 kernel/locking/lockdep.c:3924
       __raw_spin_lock_irqsave include/linux/spinlock_api_smp.h:110 [inline]
       _raw_spin_lock_irqsave+0x96/0xc0 kernel/locking/spinlock.c:152
       down_trylock+0x13/0x70 kernel/locking/semaphore.c:136
       __down_trylock_console_sem+0xae/0x200 kernel/printk/printk.c:225
       console_trylock+0x15/0xa0 kernel/printk/printk.c:2230
       console_trylock_spinning kernel/printk/printk.c:1643 [inline]
       vprintk_emit+0x699/0xde0 kernel/printk/printk.c:1906
       vprintk_default+0x28/0x30 kernel/printk/printk.c:1948
       vprintk_func+0x7a/0xe7 kernel/printk/printk_safe.c:382
       printk+0x9e/0xba kernel/printk/printk.c:1981
       kasan_die_handler.cold.22+0x11/0x30 arch/x86/mm/kasan_init_64.c:251
       notifier_call_chain+0x178/0x380 kernel/notifier.c:93
       __atomic_notifier_call_chain kernel/notifier.c:183 [inline]
       atomic_notifier_call_chain+0x98/0x190 kernel/notifier.c:193
       notify_die+0x1a3/0x2b0 kernel/notifier.c:549
       do_general_protection+0x248/0x2f0 arch/x86/kernel/traps.c:559
       general_protection+0x1e/0x30 arch/x86/entry/entry_64.S:1159
       __delayacct_blkio_end+0x4f/0xc0 kernel/delayacct.c:83
       delayacct_blkio_end include/linux/delayacct.h:128 [inline]
       try_to_wake_up+0xaa5/0x1280 kernel/sched/core.c:2054
       default_wake_function+0x30/0x50 kernel/sched/core.c:3736
       autoremove_wake_function+0x7a/0x350 kernel/sched/wait.c:373
       wake_bit_function+0xcb/0x160 kernel/sched/wait_bit.c:30
       __wake_up_common+0x189/0x730 kernel/sched/wait.c:90
       __wake_up_common_lock+0x1b4/0x300 kernel/sched/wait.c:119
       __wake_up+0xe/0x10 kernel/sched/wait.c:143
       __wake_up_bit+0x1a6/0x270 kernel/sched/wait_bit.c:125
       wake_up_bit+0x3d/0x40 kernel/sched/wait_bit.c:148
       journal_end_buffer_io_sync+0xa4/0xe0 fs/jbd2/commit.c:44
       end_bio_bh_io_sync+0xf3/0x140 fs/buffer.c:2969
       bio_endio+0x51c/0x9c0 block/bio.c:1836
       req_bio_endio block/blk-core.c:281 [inline]
       blk_update_request+0x3aa/0xcb0 block/blk-core.c:3091
       scsi_end_request+0xd3/0x870 drivers/scsi/scsi_lib.c:672
       scsi_io_completion+0xcb2/0x1d10 drivers/scsi/scsi_lib.c:898
       scsi_finish_command+0x542/0x8d0 drivers/scsi/scsi.c:248
       scsi_softirq_done+0x3e2/0x4c0 drivers/scsi/scsi_lib.c:1683
       __blk_mq_complete_request block/blk-mq.c:583 [inline]
       blk_mq_complete_request+0x355/0x630 block/blk-mq.c:620
       scsi_mq_done+0xe2/0x430 drivers/scsi/scsi_lib.c:1994
       virtscsi_complete_cmd+0x573/0x740 drivers/scsi/virtio_scsi.c:207
       virtscsi_vq_done+0xc3/0x170 drivers/scsi/virtio_scsi.c:223
       virtscsi_req_done+0xa7/0xd0 drivers/scsi/virtio_scsi.c:238
       vring_interrupt+0x128/0x170 drivers/virtio/virtio_ring.c:950
       __handle_irq_event_percpu+0x1c0/0xad0 kernel/irq/handle.c:149
       handle_irq_event_percpu+0x98/0x1c0 kernel/irq/handle.c:189
       handle_irq_event+0xa7/0x135 kernel/irq/handle.c:206
       handle_edge_irq+0x20f/0x870 kernel/irq/chip.c:791
       generic_handle_irq_desc include/linux/irqdesc.h:159 [inline]
       handle_irq+0x18c/0x2e7 arch/x86/kernel/irq_64.c:77
       do_IRQ+0x78/0x190 arch/x86/kernel/irq.c:245
       ret_from_intr+0x0/0x1e
       arch_local_irq_enable arch/x86/include/asm/paravirt.h:793 [inline]
       __raw_spin_unlock_irq include/linux/spinlock_api_smp.h:168 [inline]
       _raw_spin_unlock_irq+0x56/0x70 kernel/locking/spinlock.c:192
       finish_lock_switch kernel/sched/core.c:2603 [inline]
       finish_task_switch+0x1ca/0x840 kernel/sched/core.c:2701
       context_switch kernel/sched/core.c:2859 [inline]
       __schedule+0x809/0x1e30 kernel/sched/core.c:3498
       schedule+0xef/0x430 kernel/sched/core.c:3542
       worker_thread+0x3c7/0x13a0 kernel/workqueue.c:2317
       kthread+0x345/0x410 kernel/kthread.c:240
       ret_from_fork+0x3a/0x50 arch/x86/entry/entry_64.S:412

other info that might help us debug this:

 Possible unsafe locking scenario:

       CPU0                    CPU1
       ----                    ----
  lock(&p->pi_lock);
                               lock((console_sem).lock);
                               lock(&p->pi_lock);
  lock((console_sem).lock);

 *** DEADLOCK ***

4 locks held by kworker/0:2/30013:
 #0: 000000000682b1d5 (&(&virtscsi_vq->vq_lock)->rlock){-.-.}, at: virtscsi_vq_done+0x9d/0x170 drivers/scsi/virtio_scsi.c:219
 #1: 00000000b5db4211 (bit_wait_table + i){-.-.}, at: __wake_up_common_lock+0x190/0x300 kernel/sched/wait.c:118
 #2: 0000000078ed34ba (&p->pi_lock){-.-.}, at: try_to_wake_up+0xca/0x1280 kernel/sched/core.c:1983
 #3: 000000001adfefd7 (rcu_read_lock){....}, at: atomic_notifier_call_chain+0x0/0x190 include/linux/compiler.h:188

stack backtrace:
CPU: 0 PID: 30013 Comm: kworker/0:2 Not tainted 4.17.0+ #91
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
Workqueue:            (null) (rcu_par_gp)
Call Trace:
 <IRQ>
 __dump_stack lib/dump_stack.c:77 [inline]
 dump_stack+0x1b9/0x294 lib/dump_stack.c:113
 print_circular_bug.isra.36.cold.56+0x1bd/0x27d kernel/locking/lockdep.c:1227
 check_prev_add kernel/locking/lockdep.c:1867 [inline]
 check_prevs_add kernel/locking/lockdep.c:1980 [inline]
 validate_chain kernel/locking/lockdep.c:2421 [inline]
 __lock_acquire+0x343e/0x5140 kernel/locking/lockdep.c:3435
 lock_acquire+0x1dc/0x520 kernel/locking/lockdep.c:3924
 __raw_spin_lock_irqsave include/linux/spinlock_api_smp.h:110 [inline]
 _raw_spin_lock_irqsave+0x96/0xc0 kernel/locking/spinlock.c:152
 down_trylock+0x13/0x70 kernel/locking/semaphore.c:136
 __down_trylock_console_sem+0xae/0x200 kernel/printk/printk.c:225
 console_trylock+0x15/0xa0 kernel/printk/printk.c:2230
 console_trylock_spinning kernel/printk/printk.c:1643 [inline]
 vprintk_emit+0x699/0xde0 kernel/printk/printk.c:1906
 vprintk_default+0x28/0x30 kernel/printk/printk.c:1948
 vprintk_func+0x7a/0xe7 kernel/printk/printk_safe.c:382
 printk+0x9e/0xba kernel/printk/printk.c:1981
 kasan_die_handler.cold.22+0x11/0x30 arch/x86/mm/kasan_init_64.c:251
 notifier_call_chain+0x178/0x380 kernel/notifier.c:93
 __atomic_notifier_call_chain kernel/notifier.c:183 [inline]
 atomic_notifier_call_chain+0x98/0x190 kernel/notifier.c:193
 notify_die+0x1a3/0x2b0 kernel/notifier.c:549
 do_general_protection+0x248/0x2f0 arch/x86/kernel/traps.c:559
 general_protection+0x1e/0x30 arch/x86/entry/entry_64.S:1159
RIP: 0010:__delayacct_blkio_end+0x4f/0xc0 kernel/delayacct.c:83
Code: 48 c1 ea 03 80 3c 02 00 0f 85 80 00 00 00 48 8b 9b 30 12 00 00 48 b8 00 00 00 00 00 fc ff df 48 8d 7b 38 48 89 fa 48 c1 ea 03 <0f> b6 04 02 84 c0 74 04 3c 03 7e 51 44 8b 63 38 31 ff 41 83 e4 01 
RSP: 0018:ffff8801dae06c30 EFLAGS: 00010002
RAX: dffffc0000000000 RBX: 0000000000000000 RCX: ffff880183758138
RDX: 0000000000000007 RSI: ffffffff817b4b41 RDI: 0000000000000038
RBP: ffff8801dae06c48 R08: ffffed003b5c46d7 R09: 0000000000000001
R10: ffff8801dae06cd8 R11: ffff8801dae236b3 R12: ffff8801d8bc6740
R13: ffff880183758100 R14: ffff88018375813c R15: 0000000000000002
 delayacct_blkio_end include/linux/delayacct.h:128 [inline]
 try_to_wake_up+0xaa5/0x1280 kernel/sched/core.c:2054
 default_wake_function+0x30/0x50 kernel/sched/core.c:3736
 autoremove_wake_function+0x7a/0x350 kernel/sched/wait.c:373
 wake_bit_function+0xcb/0x160 kernel/sched/wait_bit.c:30
 __wake_up_common+0x189/0x730 kernel/sched/wait.c:90
 __wake_up_common_lock+0x1b4/0x300 kernel/sched/wait.c:119
 __wake_up+0xe/0x10 kernel/sched/wait.c:143
 __wake_up_bit+0x1a6/0x270 kernel/sched/wait_bit.c:125
 wake_up_bit+0x3d/0x40 kernel/sched/wait_bit.c:148
 journal_end_buffer_io_sync+0xa4/0xe0 fs/jbd2/commit.c:44
 end_bio_bh_io_sync+0xf3/0x140 fs/buffer.c:2969
 ? invalida
Lost 177 message(s)!
---[ end trace d7d1c6dfb6619782 ]---
RIP: 0010:__delayacct_blkio_end+0x4f/0xc0 kernel/delayacct.c:83
Code: 48 c1 ea 03 80 3c 02 00 0f 85 80 00 00 00 48 8b 9b 30 12 00 00 48 b8 00 00 00 00 00 fc ff df 48 8d 7b 38 48 89 fa 48 c1 ea 03 <0f> b6 04 02 84 c0 74 04 3c 03 7e 51 44 8b 63 38 31 ff 41 83 e4 01 
RSP: 0018:ffff8801dae06c30 EFLAGS: 00010002
RAX: dffffc0000000000 RBX: 0000000000000000 RCX: ffff880183758138
RDX: 0000000000000007 RSI: ffffffff817b4b41 RDI: 0000000000000038
RBP: ffff8801dae06c48 R08: ffffed003b5c46d7 R09: 0000000000000001
R10: ffff8801dae06cd8 R11: ffff8801dae236b3 R12: ffff8801d8bc6740
R13: ffff880183758100 R14: ffff88018375813c R15: 0000000000000002
FS:  0000000000000000(0000) GS:ffff8801dae00000(0000) knlGS:0000000000000000
CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 0000000000000130 CR3: 0000000184374000 CR4: 00000000001406f0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400