Re: [syzbot] [usb?] INFO: rcu detected stall in schedule_timeout (6)

[syzbot]

Hello,

syzbot tried to test the proposed patch but the build/boot failed:

ed and is inactive
[    8.065856][    T1] VMCI host device registered (name=vmci, major=10, minor=118)
[    8.067088][    T1] Initialized host personality
[    8.068117][    T1] usbcore: registered new interface driver rtsx_usb
[    8.070125][    T1] usbcore: registered new interface driver viperboard
[    8.071892][    T1] usbcore: registered new interface driver dln2
[    8.073319][    T1] usbcore: registered new interface driver pn533_usb
[    8.078767][    T1] nfcsim 0.2 initialized
[    8.079834][    T1] usbcore: registered new interface driver port100
[    8.081361][    T1] usbcore: registered new interface driver nfcmrvl
[    8.088191][    T1] Loading iSCSI transport class v2.0-870.
[    8.105151][    T1] virtio_scsi virtio0: 1/0/0 default/read/poll queues
[    8.115364][    T1] ------------[ cut here ]------------
[    8.116193][    T1] refcount_t: decrement hit 0; leaking memory.
[    8.117360][    T1] WARNING: CPU: 0 PID: 1 at lib/refcount.c:31 refcount_warn_saturate+0xfa/0x1d0
[    8.118807][    T1] Modules linked in:
[    8.119367][    T1] CPU: 0 PID: 1 Comm: swapper/0 Not tainted 6.9.0-rc2-syzkaller-00080-gf9cebdd4b3ae #0
[    8.121072][    T1] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 06/07/2024
[    8.122601][    T1] RIP: 0010:refcount_warn_saturate+0xfa/0x1d0
[    8.123564][    T1] Code: b2 00 00 00 e8 87 a5 e9 fc 5b 5d c3 cc cc cc cc e8 7b a5 e9 fc c6 05 a1 6d e7 0a 01 90 48 c7 c7 e0 2d 1f 8c e8 97 22 ac fc 90 <0f> 0b 90 90 eb d9 e8 5b a5 e9 fc c6 05 7e 6d e7 0a 01 90 48 c7 c7
[    8.126607][    T1] RSP: 0000:ffffc90000066e18 EFLAGS: 00010246
[    8.127651][    T1] RAX: 9b910bae5b922500 RBX: ffff888020c11c7c RCX: ffff8880166d0000
[    8.128884][    T1] RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000000000000
[    8.130007][    T1] RBP: 0000000000000004 R08: ffffffff8157ffc2 R09: fffffbfff1c39af8
[    8.131294][    T1] R10: dffffc0000000000 R11: fffffbfff1c39af8 R12: ffffea000083cdc0
[    8.132681][    T1] R13: ffffea000083cdc8 R14: 1ffffd40001079b9 R15: 0000000000000000
[    8.133862][    T1] FS:  0000000000000000(0000) GS:ffff8880b9400000(0000) knlGS:0000000000000000
[    8.135323][    T1] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[    8.136308][    T1] CR2: ffff88823ffff000 CR3: 000000000e134000 CR4: 00000000003506f0
[    8.137722][    T1] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
[    8.138999][    T1] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
[    8.140319][    T1] Call Trace:
[    8.140799][    T1]  <TASK>
[    8.141321][    T1]  ? __warn+0x163/0x4e0
[    8.142184][    T1]  ? refcount_warn_saturate+0xfa/0x1d0
[    8.143462][    T1]  ? report_bug+0x2b3/0x500
[    8.144778][    T1]  ? refcount_warn_saturate+0xfa/0x1d0
[    8.145681][    T1]  ? handle_bug+0x3e/0x70
[    8.146414][    T1]  ? exc_invalid_op+0x1a/0x50
[    8.147536][    T1]  ? asm_exc_invalid_op+0x1a/0x20
[    8.148532][    T1]  ? __warn_printk+0x292/0x360
[    8.149212][    T1]  ? refcount_warn_saturate+0xfa/0x1d0
[    8.150077][    T1]  ? refcount_warn_saturate+0xf9/0x1d0
[    8.151091][    T1]  __free_pages_ok+0xc60/0xd90
[    8.151926][    T1]  make_alloc_exact+0xa3/0xf0
[    8.152681][    T1]  vring_alloc_queue_split+0x20a/0x600
[    8.153912][    T1]  ? __pfx_vring_alloc_queue_split+0x10/0x10
[    8.155093][    T1]  ? vp_find_vqs+0x4c/0x4e0
[    8.155873][    T1]  ? virtscsi_probe+0x3ea/0xf60
[    8.156657][    T1]  ? virtio_dev_probe+0x991/0xaf0
[    8.157342][    T1]  ? really_probe+0x2b8/0xad0
[    8.158003][    T1]  ? driver_probe_device+0x50/0x430
[    8.158765][    T1]  vring_create_virtqueue_split+0xc6/0x310
[    8.159700][    T1]  ? ret_from_fork+0x4b/0x80
[    8.160390][    T1]  ? __pfx_vring_create_virtqueue_split+0x10/0x10
[    8.161411][    T1]  vring_create_virtqueue+0xca/0x110
[    8.162280][    T1]  ? __pfx_vp_notify+0x10/0x10
[    8.163017][    T1]  ? __pfx_virtscsi_ctrl_done+0x10/0x10
[    8.163859][    T1]  setup_vq+0xe9/0x2d0
[    8.164548][    T1]  ? __pfx_vp_notify+0x10/0x10
[    8.165381][    T1]  ? __pfx_virtscsi_ctrl_done+0x10/0x10
[    8.166164][    T1]  ? __pfx_virtscsi_ctrl_done+0x10/0x10
[    8.166933][    T1]  ? __pfx_virtscsi_ctrl_done+0x10/0x10
[    8.167732][    T1]  vp_setup_vq+0xbf/0x330
[    8.168623][    T1]  ? __pfx_vp_config_changed+0x10/0x10
[    8.169661][    T1]  ? ioread16+0x2f/0x90
[    8.170432][    T1]  ? __pfx_virtscsi_ctrl_done+0x10/0x10
[    8.171351][    T1]  vp_find_vqs_msix+0x8b2/0xc80
[    8.172139][    T1]  vp_find_vqs+0x4c/0x4e0
[    8.172876][    T1]  virtscsi_init+0x8db/0xd00
[    8.173612][    T1]  ? __pfx_virtscsi_init+0x10/0x10
[    8.174332][    T1]  ? __pfx_default_calc_sets+0x10/0x10
[    8.175242][    T1]  ? scsi_host_alloc+0xa57/0xea0
[    8.176177][    T1]  ? vp_get+0xfd/0x140
[    8.176820][    T1]  virtscsi_probe+0x3ea/0xf60
[    8.177627][    T1]  ? __pfx_virtscsi_probe+0x10/0x10
[    8.178547][    T1]  ? kernfs_add_one+0x156/0x8b0
[    8.179550][    T1]  ? virtio_no_restricted_mem_acc+0x9/0x10
[    8.180421][    T1]  ? virtio_features_ok+0x10c/0x270
[    8.181247][    T1]  virtio_dev_probe+0x991/0xaf0
[    8.181952][    T1]  ? __pfx_virtio_dev_probe+0x10/0x10
[    8.182739][    T1]  really_probe+0x2b8/0xad0
[    8.183450][    T1]  __driver_probe_device+0x1a2/0x390
[    8.184253][    T1]  driver_probe_device+0x50/0x430
[    8.184939][    T1]  __driver_attach+0x45f/0x710
[    8.185658][    T1]  ? __pfx___driver_attach+0x10/0x10
[    8.186422][    T1]  bus_for_each_dev+0x239/0x2b0
[    8.187437][    T1]  ? __pfx___driver_attach+0x10/0x10
[    8.188193][    T1]  ? __pfx_bus_for_each_dev+0x10/0x10
[    8.189040][    T1]  ? do_raw_spin_unlock+0x13c/0x8b0
[    8.189802][    T1]  bus_add_driver+0x347/0x620
[    8.190543][    T1]  driver_register+0x23a/0x320
[    8.191437][    T1]  ? __pfx_virtio_scsi_init+0x10/0x10
[    8.192230][    T1]  virtio_scsi_init+0x65/0xe0
[    8.192916][    T1]  ? __pfx_virtio_scsi_init+0x10/0x10
[    8.193751][    T1]  do_one_initcall+0x248/0x880
[    8.194502][    T1]  ? __pfx_virtio_scsi_init+0x10/0x10
[    8.195284][    T1]  ? __pfx_lockdep_hardirqs_on_prepare+0x10/0x10
[    8.196190][    T1]  ? __pfx_do_one_initcall+0x10/0x10
[    8.197127][    T1]  ? __pfx_parse_args+0x10/0x10
[    8.197999][    T1]  ? do_initcalls+0x1c/0x80
[    8.198670][    T1]  ? rcu_is_watching+0x15/0xb0
[    8.199361][    T1]  do_initcall_level+0x157/0x210
[    8.200179][    T1]  do_initcalls+0x3f/0x80
[    8.200925][    T1]  kernel_init_freeable+0x435/0x5d0
[    8.201758][    T1]  ? __pfx_kernel_init_freeable+0x10/0x10
[    8.202801][    T1]  ? __pfx_lockdep_hardirqs_on_prepare+0x10/0x10
[    8.203709][    T1]  ? __pfx_kernel_init+0x10/0x10
[    8.204550][    T1]  ? __pfx_kernel_init+0x10/0x10
[    8.205234][    T1]  ? __pfx_kernel_init+0x10/0x10
[    8.205954][    T1]  kernel_init+0x1d/0x2b0
[    8.206612][    T1]  ret_from_fork+0x4b/0x80
[    8.207259][    T1]  ? __pfx_kernel_init+0x10/0x10
[    8.208083][    T1]  ret_from_fork_asm+0x1a/0x30
[    8.208765][    T1]  </TASK>
[    8.209243][    T1] Kernel panic - not syncing: kernel: panic_on_warn set ...
[    8.210243][    T1] CPU: 0 PID: 1 Comm: swapper/0 Not tainted 6.9.0-rc2-syzkaller-00080-gf9cebdd4b3ae #0
[    8.210893][    T1] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 06/07/2024
[    8.210893][    T1] Call Trace:
[    8.210893][    T1]  <TASK>
[    8.210893][    T1]  dump_stack_lvl+0x241/0x360
[    8.210893][    T1]  ? __pfx_dump_stack_lvl+0x10/0x10
[    8.210893][    T1]  ? __pfx__printk+0x10/0x10
[    8.210893][    T1]  ? _printk+0xd5/0x120
[    8.210893][    T1]  ? vscnprintf+0x5d/0x90
[    8.210893][    T1]  panic+0x349/0x860
[    8.210893][    T1]  ? __warn+0x172/0x4e0
[    8.210893][    T1]  ? __pfx_panic+0x10/0x10
[    8.210893][    T1]  ? show_trace_log_lvl+0x4e6/0x520
[    8.210893][    T1]  ? ret_from_fork_asm+0x1a/0x30
[    8.210893][    T1]  __warn+0x346/0x4e0
[    8.210893][    T1]  ? refcount_warn_saturate+0xfa/0x1d0
[    8.210893][    T1]  report_bug+0x2b3/0x500
[    8.210893][    T1]  ? refcount_warn_saturate+0xfa/0x1d0
[    8.210893][    T1]  handle_bug+0x3e/0x70
[    8.210893][    T1]  exc_invalid_op+0x1a/0x50
[    8.210893][    T1]  asm_exc_invalid_op+0x1a/0x20
[    8.210893][    T1] RIP: 0010:refcount_warn_saturate+0xfa/0x1d0
[    8.210893][    T1] Code: b2 00 00 00 e8 87 a5 e9 fc 5b 5d c3 cc cc cc cc e8 7b a5 e9 fc c6 05 a1 6d e7 0a 01 90 48 c7 c7 e0 2d 1f 8c e8 97 22 ac fc 90 <0f> 0b 90 90 eb d9 e8 5b a5 e9 fc c6 05 7e 6d e7 0a 01 90 48 c7 c7
[    8.210893][    T1] RSP: 0000:ffffc90000066e18 EFLAGS: 00010246
[    8.210893][    T1] RAX: 9b910bae5b922500 RBX: ffff888020c11c7c RCX: ffff8880166d0000
[    8.210893][    T1] RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000000000000
[    8.210893][    T1] RBP: 0000000000000004 R08: ffffffff8157ffc2 R09: fffffbfff1c39af8
[    8.210893][    T1] R10: dffffc0000000000 R11: fffffbfff1c39af8 R12: ffffea000083cdc0
[    8.210893][    T1] R13: ffffea000083cdc8 R14: 1ffffd40001079b9 R15: 0000000000000000
[    8.210893][    T1]  ? __warn_printk+0x292/0x360
[    8.210893][    T1]  ? refcount_warn_saturate+0xf9/0x1d0
[    8.210893][    T1]  __free_pages_ok+0xc60/0xd90
[    8.210893][    T1]  make_alloc_exact+0xa3/0xf0
[    8.210893][    T1]  vring_alloc_queue_split+0x20a/0x600
[    8.210893][    T1]  ? __pfx_vring_alloc_queue_split+0x10/0x10
[    8.210893][    T1]  ? vp_find_vqs+0x4c/0x4e0
[    8.210893][    T1]  ? virtscsi_probe+0x3ea/0xf60
[    8.210893][    T1]  ? virtio_dev_probe+0x991/0xaf0
[    8.210893][    T1]  ? really_probe+0x2b8/0xad0
[    8.210893][    T1]  ? driver_probe_device+0x50/0x430
[    8.210893][    T1]  vring_create_virtqueue_split+0xc6/0x310
[    8.251039][    T1]  ? ret_from_fork+0x4b/0x80
[    8.251039][    T1]  ? __pfx_vring_create_virtqueue_split+0x10/0x10
[    8.251039][    T1]  vring_create_virtqueue+0xca/0x110
[    8.251039][    T1]  ? __pfx_vp_notify+0x10/0x10
[    8.251039][    T1]  ? __pfx_virtscsi_ctrl_done+0x10/0x10
[    8.251039][    T1]  setup_vq+0xe9/0x2d0
[    8.251039][    T1]  ? __pfx_vp_notify+0x10/0x10
[    8.251039][    T1]  ? __pfx_virtscsi_ctrl_done+0x10/0x10
[    8.251039][    T1]  ? __pfx_virtscsi_ctrl_done+0x10/0x10
[    8.251039][    T1]  ? __pfx_virtscsi_ctrl_done+0x10/0x10
[    8.251039][    T1]  vp_setup_vq+0xbf/0x330
[    8.251039][    T1]  ? __pfx_vp_config_changed+0x10/0x10
[    8.251039][    T1]  ? ioread16+0x2f/0x90
[    8.251039][    T1]  ? __pfx_virtscsi_ctrl_done+0x10/0x10
[    8.251039][    T1]  vp_find_vqs_msix+0x8b2/0xc80
[    8.251039][    T1]  vp_find_vqs+0x4c/0x4e0
[    8.251039][    T1]  virtscsi_init+0x8db/0xd00
[    8.251039][    T1]  ? __pfx_virtscsi_init+0x10/0x10
[    8.251039][    T1]  ? __pfx_default_calc_sets+0x10/0x10
[    8.251039][    T1]  ? scsi_host_alloc+0xa57/0xea0
[    8.251039][    T1]  ? vp_get+0xfd/0x140
[    8.251039][    T1]  virtscsi_probe+0x3ea/0xf60
[    8.251039][    T1]  ? __pfx_virtscsi_probe+0x10/0x10
[    8.251039][    T1]  ? kernfs_add_one+0x156/0x8b0
[    8.251039][    T1]  ? virtio_no_restricted_mem_acc+0x9/0x10
[    8.251039][    T1]  ? virtio_features_ok+0x10c/0x270
[    8.251039][    T1]  virtio_dev_probe+0x991/0xaf0
[    8.251039][    T1]  ? __pfx_virtio_dev_probe+0x10/0x10
[    8.251039][    T1]  really_probe+0x2b8/0xad0
[    8.251039][    T1]  __driver_probe_device+0x1a2/0x390
[    8.251039][    T1]  driver_probe_device+0x50/0x430
[    8.251039][    T1]  __driver_attach+0x45f/0x710
[    8.251039][    T1]  ? __pfx___driver_attach+0x10/0x10
[    8.251039][    T1]  bus_for_each_dev+0x239/0x2b0
[    8.251039][    T1]  ? __pfx___driver_attach+0x10/0x10
[    8.251039][    T1]  ? __pfx_bus_for_each_dev+0x10/0x10
[    8.251039][    T1]  ? do_raw_spin_unlock+0x13c/0x8b0
[    8.251039][    T1]  bus_add_driver+0x347/0x620
[    8.251039][    T1]  driver_register+0x23a/0x320
[    8.251039][    T1]  ? __pfx_virtio_scsi_init+0x10/0x10
[    8.251039][    T1]  virtio_scsi_init+0x65/0xe0
[    8.251039][    T1]  ? __pfx_virtio_scsi_init+0x10/0x10
[    8.251039][    T1]  do_one_initcall+0x248/0x880
[    8.251039][    T1]  ? __pfx_virtio_scsi_init+0x10/0x10
[    8.301021][    T1]  ? __pfx_lockdep_hardirqs_on_prepare+0x10/0x10
[    8.301021][    T1]  ? __pfx_do_one_initcall+0x10/0x10
[    8.301021][    T1]  ? __pfx_parse_args+0x10/0x10
[    8.301021][    T1]  ? do_initcalls+0x1c/0x80
[    8.301021][    T1]  ? rcu_is_watching+0x15/0xb0
[    8.301021][    T1]  do_initcall_level+0x157/0x210
[    8.301021][    T1]  do_initcalls+0x3f/0x80
[    8.301021][    T1]  kernel_init_freeable+0x435/0x5d0
[    8.301021][    T1]  ? __pfx_kernel_init_freeable+0x10/0x10
[    8.301021][    T1]  ? __pfx_lockdep_hardirqs_on_prepare+0x10/0x10
[    8.301021][    T1]  ? __pfx_kernel_init+0x10/0x10
[    8.301021][    T1]  ? __pfx_kernel_init+0x10/0x10
[    8.301021][    T1]  ? __pfx_kernel_init+0x10/0x10
[    8.301021][    T1]  kernel_init+0x1d/0x2b0
[    8.301021][    T1]  ret_from_fork+0x4b/0x80
[    8.301021][    T1]  ? __pfx_kernel_init+0x10/0x10
[    8.301021][    T1]  ret_from_fork_asm+0x1a/0x30
[    8.301021][    T1]  </TASK>
[    8.301021][    T1] Kernel Offset: disabled
[    8.301021][    T1] Rebooting in 86400 seconds..


syzkaller build log:
go env (err=<nil>)
GO111MODULE='auto'
GOARCH='amd64'
GOBIN=''
GOCACHE='/syzkaller/.cache/go-build'
GOENV='/syzkaller/.config/go/env'
GOEXE=''
GOEXPERIMENT=''
GOFLAGS=''
GOHOSTARCH='amd64'
GOHOSTOS='linux'
GOINSECURE=''
GOMODCACHE='/syzkaller/jobs/linux/gopath/pkg/mod'
GONOPROXY=''
GONOSUMDB=''
GOOS='linux'
GOPATH='/syzkaller/jobs/linux/gopath'
GOPRIVATE=''
GOPROXY='https://proxy.golang.org,direct'
GOROOT='/usr/local/go'
GOSUMDB='sum.golang.org'
GOTMPDIR=''
GOTOOLCHAIN='auto'
GOTOOLDIR='/usr/local/go/pkg/tool/linux_amd64'
GOVCS=''
GOVERSION='go1.21.4'
GCCGO='gccgo'
GOAMD64='v1'
AR='ar'
CC='gcc'
CXX='g++'
CGO_ENABLED='1'
GOMOD='/syzkaller/jobs/linux/gopath/src/github.com/google/syzkaller/go.mod'
GOWORK=''
CGO_CFLAGS='-O2 -g'
CGO_CPPFLAGS=''
CGO_CXXFLAGS='-O2 -g'
CGO_FFLAGS='-O2 -g'
CGO_LDFLAGS='-O2 -g'
PKG_CONFIG='pkg-config'
GOGCCFLAGS='-fPIC -m64 -pthread -Wl,--no-gc-sections -fmessage-length=0 -ffile-prefix-map=/tmp/go-build1076745083=/tmp/go-build -gno-record-gcc-switches'

git status (err=<nil>)
HEAD detached at 9026e14289
nothing to commit, working tree clean


tput: No value for $TERM and no -T specified
tput: No value for $TERM and no -T specified
Makefile:31: run command via tools/syz-env for best compatibility, see:
Makefile:32: https://github.com/google/syzkaller/blob/master/docs/contributing.md#using-syz-env
go list -f '{{.Stale}}' ./sys/syz-sysgen | grep -q false || go install ./sys/syz-sysgen
make .descriptions
tput: No value for $TERM and no -T specified
tput: No value for $TERM and no -T specified
Makefile:31: run command via tools/syz-env for best compatibility, see:
Makefile:32: https://github.com/google/syzkaller/blob/master/docs/contributing.md#using-syz-env
bin/syz-sysgen
touch .descriptions
GOOS=linux GOARCH=amd64 go build "-ldflags=-s -w -X github.com/google/syzkaller/prog.GitRevision=9026e14289eaf45a00ddddb8730f2092b956d99a -X 'github.com/google/syzkaller/prog.gitRevisionDate=20240510-174117'" "-tags=syz_target syz_os_linux syz_arch_amd64 " -o ./bin/linux_amd64/syz-fuzzer github.com/google/syzkaller/syz-fuzzer
GOOS=linux GOARCH=amd64 go build "-ldflags=-s -w -X github.com/google/syzkaller/prog.GitRevision=9026e14289eaf45a00ddddb8730f2092b956d99a -X 'github.com/google/syzkaller/prog.gitRevisionDate=20240510-174117'" "-tags=syz_target syz_os_linux syz_arch_amd64 " -o ./bin/linux_amd64/syz-execprog github.com/google/syzkaller/tools/syz-execprog
mkdir -p ./bin/linux_amd64
gcc -o ./bin/linux_amd64/syz-executor executor/executor.cc \
	-m64 -std=c++11 -I. -Iexecutor/_include -O2 -pthread -Wall -Werror -Wparentheses -Wunused-const-variable -Wframe-larger-than=16384 -Wno-stringop-overflow -Wno-array-bounds -Wno-format-overflow -Wno-unused-but-set-variable -Wno-unused-command-line-argument -static-pie -fpermissive -w -DGOOS_linux=1 -DGOARCH_amd64=1 \
	-DHOSTGOOS_linux=1 -DGIT_REVISION=\"9026e14289eaf45a00ddddb8730f2092b956d99a\"


Error text is too large and was truncated, full error text is at:
https://syzkaller.appspot.com/x/error.txt?x=12219041980000


Tested on:

commit:         f9cebdd4 usb: gadget: dummy_hcd: make hrtimer expire i..
git tree:       https://github.com/9elements/linux.git test_patch_dummy_hcd_hrtimer_fix
kernel config:  https://syzkaller.appspot.com/x/.config?x=12fe7aa3e6d549f1
dashboard link: https://syzkaller.appspot.com/bug?extid=c793a7eca38803212c61
compiler:       Debian clang version 15.0.6, GNU ld (GNU Binutils for Debian) 2.40

Note: no patches were applied.