ubi0: max/mean erase counter: 1/1, WL threshold: 4096, image sequence number: 3767702837
ubi0: available PEBs: 28, total reserved PEBs: 4, PEBs reserved for bad PEB handling: 0
ubi0: background thread "ubi_bgt0d" started, PID 9394
overlayfs: failed to resolve './file0': -2
INFO: rcu_preempt self-detected stall on CPU
1-...: (1 GPs behind) idle=7f2/140000000000002/0 softirq=113151/113152 fqs=12
(t=10500 jiffies g=32495 c=32494 q=175)
rcu_preempt kthread starved for 9462 jiffies! g32495 c32494 f0x0 RCU_GP_WAIT_FQS(3) ->state=0x0 ->cpu=0
rcu_preempt R running task 29232 8 2 0x80000000
Call Trace:
schedule+0x8d/0x1b0 kernel/sched/core.c:3428
schedule_timeout+0x43c/0xe40 kernel/time/timer.c:1746
rcu_gp_kthread+0xb8e/0x1ce0 kernel/rcu/tree.c:2255
kthread+0x30d/0x420 kernel/kthread.c:232
ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:404
NMI backtrace for cpu 1
CPU: 1 PID: 9395 Comm: syz-executor.5 Not tainted 4.14.172-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
Call Trace:
__dump_stack lib/dump_stack.c:17 [inline]
dump_stack+0x13e/0x194 lib/dump_stack.c:58
nmi_cpu_backtrace.cold+0x57/0x93 lib/nmi_backtrace.c:101
nmi_trigger_cpumask_backtrace+0x139/0x17e lib/nmi_backtrace.c:62
trigger_single_cpu_backtrace include/linux/nmi.h:158 [inline]
rcu_dump_cpu_stacks+0x16d/0x1b8 kernel/rcu/tree.c:1396
print_cpu_stall kernel/rcu/tree.c:1542 [inline]
check_cpu_stall kernel/rcu/tree.c:1610 [inline]
__rcu_pending kernel/rcu/tree.c:3390 [inline]
rcu_pending kernel/rcu/tree.c:3452 [inline]
rcu_check_callbacks.cold+0x490/0xdaa kernel/rcu/tree.c:2792
update_process_times+0x29/0x60 kernel/time/timer.c:1590
tick_sched_handle+0x7d/0x150 kernel/time/tick-sched.c:165
tick_sched_timer+0x3e/0x120 kernel/time/tick-sched.c:1223
__run_hrtimer kernel/time/hrtimer.c:1223 [inline]
__hrtimer_run_queues+0x25c/0xbb0 kernel/time/hrtimer.c:1287
hrtimer_interrupt+0x1e6/0x5e0 kernel/time/hrtimer.c:1321
local_apic_timer_interrupt arch/x86/kernel/apic/apic.c:1075 [inline]
smp_apic_timer_interrupt+0x117/0x5e0 arch/x86/kernel/apic/apic.c:1100
apic_timer_interrupt+0x8f/0xa0 arch/x86/entry/entry_64.S:792
RIP: 0010:sctp_transport_timeout+0x44/0xd0 net/sctp/transport.c:645
RSP: 0018:ffff8880aeb07d18 EFLAGS: 00000246 ORIG_RAX: ffffffffffffff10
RAX: dffffc0000000000 RBX: 0000000000000064 RCX: 00000000bb0d8d15
RDX: 1ffff1101087f41f RSI: 0000000000000000 RDI: ffff8880843fa18c
RBP: ffff8880843fa040 R08: 00000000000020c3 R09: ffffffff8a06bed8
R10: ffff8880612a6920 R11: ffff8880612a6000 R12: ffff88805a47a700
R13: ffff88808eba8888 R14: 0000000000000000 R15: ffff888063028100
sctp_generate_heartbeat_event+0x154/0x3f0 net/sctp/sm_sideeffect.c:383
call_timer_fn+0x14a/0x650 kernel/time/timer.c:1279
expire_timers kernel/time/timer.c:1318 [inline]
__run_timers kernel/time/timer.c:1636 [inline]
__run_timers kernel/time/timer.c:1604 [inline]
run_timer_softirq+0x52a/0x1390 kernel/time/timer.c:1649
__do_softirq+0x254/0x9bf kernel/softirq.c:288
invoke_softirq kernel/softirq.c:368 [inline]
irq_exit+0x15b/0x1a0 kernel/softirq.c:409
exiting_irq arch/x86/include/asm/apic.h:648 [inline]
smp_apic_timer_interrupt+0x141/0x5e0 arch/x86/kernel/apic/apic.c:1102
apic_timer_interrupt+0x8f/0xa0 arch/x86/entry/entry_64.S:792
RIP: 0010:arch_local_irq_restore arch/x86/include/asm/paravirt.h:779 [inline]
RIP: 0010:lock_acquire+0x1ec/0x3f0 kernel/locking/lockdep.c:3997
RSP: 0018:ffff888210397928 EFLAGS: 00000286 ORIG_RAX: ffffffffffffff10
RAX: 1ffffffff0fa2ce1 RBX: ffff8880612a6000 RCX: 00000000bb25ac0f
RDX: dffffc0000000000 RSI: ffff8880612a68d0 RDI: 0000000000000286
RBP: ffffffff87e505f8 R08: 00000000000025e0 R09: ffffffff8a06e7c0
R10: ffff8880612a68d0 R11: ffff8880612a6000 R12: 0000000000000000
R13: 0000000000000000 R14: 0000000000000001 R15: 0000000000000002
__raw_read_lock include/linux/rwlock_api_smp.h:149 [inline]
_raw_read_lock+0x2d/0x40 kernel/locking/spinlock.c:224
proc_lookup_de+0x24/0x160 fs/proc/generic.c:219
proc_tgid_net_lookup+0x5e/0xa0 fs/proc/proc_net.c:138
lookup_open+0x5d1/0x1750 fs/namei.c:3220
do_last fs/namei.c:3334 [inline]
path_openat+0x1878/0x3c50 fs/namei.c:3569
do_filp_open+0x18e/0x250 fs/namei.c:3603
do_sys_open+0x29d/0x3f0 fs/open.c:1084
do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292
entry_SYSCALL_64_after_hwframe+0x42/0xb7
RIP: 0033:0x416211
RSP: 002b:00007f9d6cf1abb0 EFLAGS: 00000293 ORIG_RAX: 0000000000000002
RAX: ffffffffffffffda RBX: 00007f9d6cf1b6d4 RCX: 0000000000416211
RDX: 00007f9d6cf1abe9 RSI: 0000000000000002 RDI: 00007f9d6cf1abd0
RBP: 000000000076bf20 R08: 0000000000000000 R09: 0000000000000019
R10: 000000000000000e R11: 0000000000000293 R12: 00000000ffffffff
R13: 0000000000000c19 R14: 00000000004ce070 R15: 000000000076bf2c
INFO: rcu_sched detected stalls on CPUs/tasks:
1-...: (1 GPs behind) idle=7f2/140000000000002/0 softirq=113151/113152 fqs=12
(detected by 0, t=10553 jiffies, g=24474, c=24473, q=3)
Sending NMI from CPU 0 to CPUs 1:
NMI backtrace for cpu 1
CPU: 1 PID: 9395 Comm: syz-executor.5 Not tainted 4.14.172-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
task: ffff8880612a6000 task.stack: ffff888210390000
RIP: 0010:mark_lock+0xc4/0x10b0 kernel/locking/lockdep.c:3217
RSP: 0018:ffff8880aeb07298 EFLAGS: 00000096
RAX: 0000000000000001 RBX: 0000000000000003 RCX: 1ffff1100c254d23
RDX: 1ffffffff1425b6e RSI: ffff8880612a68f8 RDI: ffffffff8a12db70
RBP: ffff8880612a68f8 R08: 0000010ea7215d5a R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000000001
R13: ffff8880612a6918 R14: ffff8880612a6000 R15: 0000000000000002
FS: 00007f9d6cf1b700(0000) GS:ffff8880aeb00000(0000) knlGS:0000000000000000
CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 000000000071c934 CR3: 0000000098ff3000 CR4: 00000000001406e0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
mark_held_locks+0xa6/0xf0 kernel/locking/lockdep.c:2850
__trace_hardirqs_on_caller kernel/locking/lockdep.c:2871 [inline]
trace_hardirqs_on_caller+0x196/0x590 kernel/locking/lockdep.c:2926
trace_hardirqs_on_thunk+0x1a/0x1c
retint_kernel+0x2d/0x2d
RIP: 0010:on_stack arch/x86/include/asm/stacktrace.h:44 [inline]
RIP: 0010:stack_access_ok+0x13/0x130 arch/x86/kernel/unwind_orc.c:279
RSP: 0018:ffff8880aeb07420 EFLAGS: 00000a06 ORIG_RAX: ffffffffffffff09
RAX: dffffc0000000000 RBX: 1ffff11015d60e87 RCX: ffff8880aeb07550
RDX: 0000000000000008 RSI: ffff888210397ed0 RDI: ffff8880aeb07550
RBP: ffff888210397ed0 R08: 0000000000000001 R09: ffff888210397e50
R10: ffff8880aeb07585 R11: 0000000000058071 R12: ffff8880aeb07550
R13: dffffc0000000000 R14: ffff888210397f00 R15: 0000000000000001
deref_stack_reg+0x56/0xc0 arch/x86/kernel/unwind_orc.c:289
unwind_next_frame+0xeff/0x17a0 arch/x86/kernel/unwind_orc.c:474
__save_stack_trace+0x6b/0xd0 arch/x86/kernel/stacktrace.c:44
save_stack+0x32/0xa0 mm/kasan/kasan.c:447
set_track mm/kasan/kasan.c:459 [inline]
kasan_kmalloc mm/kasan/kasan.c:551 [inline]
kasan_kmalloc+0xbf/0xe0 mm/kasan/kasan.c:529
__do_kmalloc_node mm/slab.c:3682 [inline]
__kmalloc_node_track_caller+0x4c/0x70 mm/slab.c:3696
__kmalloc_reserve.isra.0+0x35/0xd0 net/core/skbuff.c:137
__alloc_skb+0xca/0x4c0 net/core/skbuff.c:205
alloc_skb include/linux/skbuff.h:980 [inline]
_sctp_make_chunk+0x44/0x250 net/sctp/sm_make_chunk.c:1388
sctp_make_control+0x30/0x150 net/sctp/sm_make_chunk.c:1429
sctp_make_heartbeat+0x79/0x240 net/sctp/sm_make_chunk.c:1148
sctp_sf_heartbeat.isra.0+0x21/0x170 net/sctp/sm_statefuns.c:971
sctp_sf_sendbeat_8_3+0x34e/0x4f0 net/sctp/sm_statefuns.c:1015
sctp_do_sm+0xf6/0x4a90 net/sctp/sm_sideeffect.c:1147
sctp_generate_heartbeat_event+0x1da/0x3f0 net/sctp/sm_sideeffect.c:391
call_timer_fn+0x14a/0x650 kernel/time/timer.c:1279
expire_timers kernel/time/timer.c:1318 [inline]
__run_timers kernel/time/timer.c:1636 [inline]
__run_timers kernel/time/timer.c:1604 [inline]
run_timer_softirq+0x52a/0x1390 kernel/time/timer.c:1649
__do_softirq+0x254/0x9bf kernel/softirq.c:288
invoke_softirq kernel/softirq.c:368 [inline]
irq_exit+0x15b/0x1a0 kernel/softirq.c:409
exiting_irq arch/x86/include/asm/apic.h:648 [inline]
smp_apic_timer_interrupt+0x141/0x5e0 arch/x86/kernel/apic/apic.c:1102
apic_timer_interrupt+0x8f/0xa0 arch/x86/entry/entry_64.S:792
RIP: 0010:arch_local_irq_restore arch/x86/include/asm/paravirt.h:779 [inline]
RIP: 0010:lock_acquire+0x1ec/0x3f0 kernel/locking/lockdep.c:3997
RSP: 0018:ffff888210397928 EFLAGS: 00000286 ORIG_RAX: ffffffffffffff10
RAX: 1ffffffff0fa2ce1 RBX: ffff8880612a6000 RCX: 00000000bb25ac0f
RDX: dffffc0000000000 RSI: ffff8880612a68d0 RDI: 0000000000000286
RBP: ffffffff87e505f8 R08: 00000000000025e0 R09: ffffffff8a06e7c0
R10: ffff8880612a68d0 R11: ffff8880612a6000 R12: 0000000000000000
R13: 0000000000000000 R14: 0000000000000001 R15: 0000000000000002
__raw_read_lock include/linux/rwlock_api_smp.h:149 [inline]
_raw_read_lock+0x2d/0x40 kernel/locking/spinlock.c:224
proc_lookup_de+0x24/0x160 fs/proc/generic.c:219
proc_tgid_net_lookup+0x5e/0xa0 fs/proc/proc_net.c:138
lookup_open+0x5d1/0x1750 fs/namei.c:3220
do_last fs/namei.c:3334 [inline]
path_openat+0x1878/0x3c50 fs/namei.c:3569
do_filp_open+0x18e/0x250 fs/namei.c:3603
do_sys_open+0x29d/0x3f0 fs/open.c:1084
do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292
entry_SYSCALL_64_after_hwframe+0x42/0xb7
RIP: 0033:0x416211
RSP: 002b:00007f9d6cf1abb0 EFLAGS: 00000293 ORIG_RAX: 0000000000000002
RAX: ffffffffffffffda RBX: 00007f9d6cf1b6d4 RCX: 0000000000416211
RDX: 00007f9d6cf1abe9 RSI: 0000000000000002 RDI: 00007f9d6cf1abd0
RBP: 000000000076bf20 R08: 0000000000000000 R09: 0000000000000019
R10: 000000000000000e R11: 0000000000000293 R12: 00000000ffffffff
R13: 0000000000000c19 R14: 00000000004ce070 R15: 000000000076bf2c
Code: ff df 48 89 fa 48 c1 ea 03 80 3c 02 00 0f 85 c2 0c 00 00 4c 85 63 30 0f 84 a0 00 00 00 41 bc 01 00 00 00 48 83 c4 18 44 89 e0 5b <5d> 41 5c 41 5d 41 5e 41 5f c3 48 c7 c0 40 7b 58 8a 48 ba 00 00
rcu_sched kthread starved for 9516 jiffies! g24474 c24473 f0x0 RCU_GP_WAIT_FQS(3) ->state=0x0 ->cpu=0
rcu_sched R running task 29280 9 2 0x80000000
Call Trace:
schedule+0x8d/0x1b0 kernel/sched/core.c:3428
schedule_timeout+0x43c/0xe40 kernel/time/timer.c:1746
rcu_gp_kthread+0xb8e/0x1ce0 kernel/rcu/tree.c:2255
kthread+0x30d/0x420 kernel/kthread.c:232
ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:404
INFO: rcu_bh detected stalls on CPUs/tasks:
1-...: (1 GPs behind) idle=7f2/140000000000002/0 softirq=105629/113152 fqs=12
(detected by 0, t=10640 jiffies, g=-185, c=-186, q=10)
Sending NMI from CPU 0 to CPUs 1:
NMI backtrace for cpu 1
CPU: 1 PID: 9395 Comm: syz-executor.5 Not tainted 4.14.172-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
task: ffff8880612a6000 task.stack: ffff888210390000
RIP: 0010:irq_work_run_list+0xff/0x160 kernel/irq_work.c:141
RSP: 0018:ffff8880aeb07b70 EFLAGS: 00000046
RAX: 0000000000000002 RBX: ffff8881f4b70f30 RCX: 1ffffffff0f417ad
RDX: 0000000000000000 RSI: ffffffff86e453c0 RDI: ffffffff87a0bd68
RBP: ffff8881f4b70f38 R08: 0000000000000001 R09: 0000000000000005
R10: ffff8880612a6998 R11: ffff8880612a6000 R12: 0000000000000002
R13: dffffc0000000000 R14: 0000000000000003 R15: 0000000000000000
FS: 00007f9d6cf1b700(0000) GS:ffff8880aeb00000(0000) knlGS:0000000000000000
CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 000000000071c934 CR3: 0000000098ff3000 CR4: 00000000001406e0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
irq_work_run+0x4e/0xb0 kernel/irq_work.c:171
smp_irq_work_interrupt+0xa3/0x4e0 arch/x86/kernel/irq_work.c:21
irq_work_interrupt+0x8f/0xa0 arch/x86/entry/entry_64.S:823
RIP: 0010:pv_wait_node kernel/locking/qspinlock_paravirt.h:279 [inline]
RIP: 0010:__pv_queued_spin_lock_slowpath+0x4e4/0x950 kernel/locking/qspinlock.c:430
RSP: 0018:ffff8880aeb07c90 EFLAGS: 00000206 ORIG_RAX: ffffffffffffff09
RAX: 0000000000006550 RBX: ffff8880aeb2c0c0 RCX: 0000000000000004
RDX: 0000000000000000 RSI: 0000000000000003 RDI: dffffc0000000000
RBP: ffff88808eba8888 R08: ffffed1015d65819 R09: 0000000000000000
R10: 0000000000000001 R11: 0000000000000000 R12: ffff8880aea2c0c0
R13: ffffed1015d45820 R14: ffff8880aeb2c0c8 R15: ffff8880aea2c104
pv_queued_spin_lock_slowpath arch/x86/include/asm/paravirt.h:669 [inline]
queued_spin_lock_slowpath arch/x86/include/asm/qspinlock.h:52 [inline]
queued_spin_lock include/asm-generic/qspinlock.h:90 [inline]
do_raw_spin_lock+0x189/0x230 kernel/locking/spinlock_debug.c:113
spin_lock include/linux/spinlock.h:317 [inline]
sctp_generate_heartbeat_event+0x96/0x3f0 net/sctp/sm_sideeffect.c:371
call_timer_fn+0x14a/0x650 kernel/time/timer.c:1279
expire_timers kernel/time/timer.c:1318 [inline]
__run_timers kernel/time/timer.c:1636 [inline]
__run_timers kernel/time/timer.c:1604 [inline]
run_timer_softirq+0x52a/0x1390 kernel/time/timer.c:1649
__do_softirq+0x254/0x9bf kernel/softirq.c:288
invoke_softirq kernel/softirq.c:368 [inline]
irq_exit+0x15b/0x1a0 kernel/softirq.c:409
exiting_irq arch/x86/include/asm/apic.h:648 [inline]
smp_apic_timer_interrupt+0x141/0x5e0 arch/x86/kernel/apic/apic.c:1102
apic_timer_interrupt+0x8f/0xa0 arch/x86/entry/entry_64.S:792
RIP: 0010:arch_local_irq_restore arch/x86/include/asm/paravirt.h:779 [inline]
RIP: 0010:lock_acquire+0x1ec/0x3f0 kernel/locking/lockdep.c:3997
RSP: 0018:ffff888210397928 EFLAGS: 00000286 ORIG_RAX: ffffffffffffff10
RAX: 1ffffffff0fa2ce1 RBX: ffff8880612a6000 RCX: 00000000bb25ac0f
RDX: dffffc0000000000 RSI: ffff8880612a68d0 RDI: 0000000000000286
RBP: ffffffff87e505f8 R08: 00000000000025e0 R09: ffffffff8a06e7c0
R10: ffff8880612a68d0 R11: ffff8880612a6000 R12: 0000000000000000
R13: 0000000000000000 R14: 0000000000000001 R15: 0000000000000002
__raw_read_lock include/linux/rwlock_api_smp.h:149 [inline]
_raw_read_lock+0x2d/0x40 kernel/locking/spinlock.c:224
proc_lookup_de+0x24/0x160 fs/proc/generic.c:219
proc_tgid_net_lookup+0x5e/0xa0 fs/proc/proc_net.c:138
lookup_open+0x5d1/0x1750 fs/namei.c:3220
do_last fs/namei.c:3334 [inline]
path_openat+0x1878/0x3c50 fs/namei.c:3569
do_filp_open+0x18e/0x250 fs/namei.c:3603
do_sys_open+0x29d/0x3f0 fs/open.c:1084
do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292
entry_SYSCALL_64_after_hwframe+0x42/0xb7
RIP: 0033:0x416211
RSP: 002b:00007f9d6cf1abb0 EFLAGS: 00000293 ORIG_RAX: 0000000000000002
RAX: ffffffffffffffda RBX: 00007f9d6cf1b6d4 RCX: 0000000000416211
RDX: 00007f9d6cf1abe9 RSI: 0000000000000002 RDI: 00007f9d6cf1abd0
RBP: 000000000076bf20 R08: 0000000000000000 R09: 0000000000000019
R10: 000000000000000e R11: 0000000000000293 R12: 00000000ffffffff
R13: 0000000000000c19 R14: 00000000004ce070 R15: 000000000076bf2c
Code: e0 48 87 03 48 8d 7d 08 48 89 f8 48 c1 e8 03 42 80 3c 28 00 75 37 48 89 df ff 55 08 4c 89 f2 4c 89 e0 48 83 e2 fc f0 48 0f b1 13 <4d> 85 ff 75 97 5b 5d 41 5c 41 5d 41 5e 41 5f e9 5d c4 f7 ff e8
rcu_bh kthread starved for 9603 jiffies! g18446744073709551431 c18446744073709551430 f0x0 RCU_GP_WAIT_FQS(3) ->state=0x0 ->cpu=0
rcu_bh R running task 29984 10 2 0x80000000
Call Trace:
schedule+0x8d/0x1b0 kernel/sched/core.c:3428
schedule_timeout+0x43c/0xe40 kernel/time/timer.c:1746
rcu_gp_kthread+0xb8e/0x1ce0 kernel/rcu/tree.c:2255
kthread+0x30d/0x420 kernel/kthread.c:232
ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:404