Re: [syzbot] WARNING in ieee80211_ibss_csa_beacon

[syzbot <syzbot+b6c9fe29aefe68e4ad34@syzkaller.appspotmail.com>]

Hello,

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

 active interface with an up link
[   50.263416][ T3638] bond0: (slave bond_slave_1): Enslaving as an active interface with an up link
[   50.285671][ T3638] team0: Port device team_slave_0 added
[   50.292792][ T3638] team0: Port device team_slave_1 added
[   50.310141][ T3638] batman_adv: batadv0: Adding interface: batadv_slave_0
[   50.317225][ T3638] batman_adv: batadv0: The MTU of interface batadv_slave_0 is too small (1500) to handle the transport of batman-adv packets. Packets going over this interface will be fragmented on layer2 which could impact the performance. Setting the MTU to 1560 would solve the problem.
[   50.343683][ T3638] batman_adv: batadv0: Not using interface batadv_slave_0 (retrying later): interface not active
[   50.356731][ T3638] batman_adv: batadv0: Adding interface: batadv_slave_1
[   50.364022][ T3638] batman_adv: batadv0: The MTU of interface batadv_slave_1 is too small (1500) to handle the transport of batman-adv packets. Packets going over this interface will be fragmented on layer2 which could impact the performance. Setting the MTU to 1560 would solve the problem.
[   50.390221][ T3638] batman_adv: batadv0: Not using interface batadv_slave_1 (retrying later): interface not active
[   50.416765][ T3638] device hsr_slave_0 entered promiscuous mode
[   50.423796][ T3638] device hsr_slave_1 entered promiscuous mode
[   50.500816][ T3638] netdevsim netdevsim0 netdevsim0: renamed from eth0
[   50.511693][ T3638] netdevsim netdevsim0 netdevsim1: renamed from eth1
[   50.521112][ T3638] netdevsim netdevsim0 netdevsim2: renamed from eth2
[   50.530709][ T3638] netdevsim netdevsim0 netdevsim3: renamed from eth3
[   50.551898][ T3638] bridge0: port 2(bridge_slave_1) entered blocking state
[   50.559135][ T3638] bridge0: port 2(bridge_slave_1) entered forwarding state
[   50.566985][ T3638] bridge0: port 1(bridge_slave_0) entered blocking state
[   50.574182][ T3638] bridge0: port 1(bridge_slave_0) entered forwarding state
[   50.620324][ T3638] 8021q: adding VLAN 0 to HW filter on device bond0
[   50.634712][   T14] IPv6: ADDRCONF(NETDEV_CHANGE): veth0: link becomes ready
[   50.644519][   T14] bridge0: port 1(bridge_slave_0) entered disabled state
[   50.653256][   T14] bridge0: port 2(bridge_slave_1) entered disabled state
[   50.661875][   T14] IPv6: ADDRCONF(NETDEV_CHANGE): bond0: link becomes ready
[   50.675639][ T3638] 8021q: adding VLAN 0 to HW filter on device team0
[   50.686683][   T14] IPv6: ADDRCONF(NETDEV_CHANGE): bridge_slave_0: link becomes ready
[   50.696048][   T14] bridge0: port 1(bridge_slave_0) entered blocking state
[   50.703313][   T14] bridge0: port 1(bridge_slave_0) entered forwarding state
[   50.716587][  T143] IPv6: ADDRCONF(NETDEV_CHANGE): bridge_slave_1: link becomes ready
[   50.726078][  T143] bridge0: port 2(bridge_slave_1) entered blocking state
[   50.733163][  T143] bridge0: port 2(bridge_slave_1) entered forwarding state
[   50.751866][   T14] IPv6: ADDRCONF(NETDEV_CHANGE): team_slave_0: link becomes ready
[   50.766290][   T14] IPv6: ADDRCONF(NETDEV_CHANGE): team0: link becomes ready
[   50.775160][   T14] IPv6: ADDRCONF(NETDEV_CHANGE): team_slave_1: link becomes ready
[   50.787909][ T3650] IPv6: ADDRCONF(NETDEV_CHANGE): hsr_slave_0: link becomes ready
[   50.800188][ T3638] hsr0: Slave B (hsr_slave_1) is not up; please bring it up to get a fully working HSR network
[   50.812187][ T3638] IPv6: ADDRCONF(NETDEV_CHANGE): hsr0: link becomes ready
[   50.821509][   T14] IPv6: ADDRCONF(NETDEV_CHANGE): hsr_slave_1: link becomes ready
[   50.845246][ T3638] 8021q: adding VLAN 0 to HW filter on device batadv0
[   50.853191][    T6] IPv6: ADDRCONF(NETDEV_CHANGE): vxcan0: link becomes ready
[   50.861297][    T6] IPv6: ADDRCONF(NETDEV_CHANGE): vxcan1: link becomes ready
[   50.976504][  T143] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_virt_wifi: link becomes ready
[   50.990346][    T6] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_vlan: link becomes ready
[   51.001733][    T6] IPv6: ADDRCONF(NETDEV_CHANGE): vlan0: link becomes ready
[   51.009609][    T6] IPv6: ADDRCONF(NETDEV_CHANGE): vlan1: link becomes ready
[   51.018756][ T3638] device veth0_vlan entered promiscuous mode
[   51.033199][ T3638] device veth1_vlan entered promiscuous mode
[   51.053599][ T3650] IPv6: ADDRCONF(NETDEV_CHANGE): macvlan0: link becomes ready
[   51.063508][ T3650] IPv6: ADDRCONF(NETDEV_CHANGE): macvlan1: link becomes ready
[   51.072555][ T3650] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_macvtap: link becomes ready
[   51.084230][ T3638] device veth0_macvtap entered promiscuous mode
[   51.093816][ T3638] device veth1_macvtap entered promiscuous mode
[   51.116192][ T3638] batman_adv: batadv0: Interface activated: batadv_slave_0
[   51.124686][    T6] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_to_batadv: link becomes ready
[   51.136685][    T6] IPv6: ADDRCONF(NETDEV_CHANGE): macvtap0: link becomes ready
[   51.149935][ T3638] batman_adv: batadv0: Interface activated: batadv_slave_1
[   51.158614][ T3650] IPv6: ADDRCONF(NETDEV_CHANGE): batadv_slave_1: link becomes ready
[   51.168462][ T3650] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_to_batadv: link becomes ready
[   51.182170][ T3638] netdevsim netdevsim0 netdevsim0: set [1, 0] type 2 family 0 port 6081 - 0
[   51.192643][ T3638] netdevsim netdevsim0 netdevsim1: set [1, 0] type 2 family 0 port 6081 - 0
[   51.202462][ T3638] netdevsim netdevsim0 netdevsim2: set [1, 0] type 2 family 0 port 6081 - 0
[   51.212143][ T3638] netdevsim netdevsim0 netdevsim3: set [1, 0] type 2 family 0 port 6081 - 0
[   51.299438][   T29] wlan0: Created IBSS using preconfigured BSSID 50:50:50:50:50:50
[   51.310951][   T29] wlan0: Creating new IBSS network, BSSID 50:50:50:50:50:50
[   51.322462][  T143] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[   51.335671][ T2494] wlan1: Created IBSS using preconfigured BSSID 50:50:50:50:50:50
[   51.344772][ T2494] wlan1: Creating new IBSS network, BSSID 50:50:50:50:50:50
[   51.353568][  T143] IPv6: ADDRCONF(NETDEV_CHANGE): wlan1: link becomes ready
2022/08/11 13:00:39 building call list...
[   51.514478][ T3638] ------------[ cut here ]------------
[   51.520140][ T3638] ODEBUG: assert_init not available (active state 0) object type: timer_list hint: 0x0
[   51.530079][ T3638] WARNING: CPU: 0 PID: 3638 at lib/debugobjects.c:505 debug_object_assert_init+0x1fa/0x250
[   51.540259][ T3638] Modules linked in:
[   51.544336][ T3638] CPU: 0 PID: 3638 Comm: syz-executor.0 Not tainted 5.19.0-syzkaller #0
[   51.552766][ T3638] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 07/22/2022
[   51.563627][ T3638] RIP: 0010:debug_object_assert_init+0x1fa/0x250
[   51.570150][ T3638] Code: e8 eb 83 d1 fd 4c 8b 45 00 48 c7 c7 60 9f 6a 8a 48 c7 c6 60 9c 6a 8a 48 c7 c2 00 a1 6a 8a 31 c9 49 89 d9 31 c0 e8 66 73 4e fd <0f> 0b ff 05 9a 20 8a 09 48 83 c5 38 48 89 e8 48 c1 e8 03 42 80 3c
[   51.590417][ T3638] RSP: 0018:ffffc90003b0f8c8 EFLAGS: 00010046
[   51.596584][ T3638] RAX: af76cc1e655f6400 RBX: 0000000000000000 RCX: ffff888014741d40
[   51.604841][ T3638] RDX: 0000000000000000 RSI: 0000000080000000 RDI: 0000000000000000
[   51.612846][ T3638] RBP: ffffffff8a0fc700 R08: ffffffff8165f59d R09: ffffed1017384f14
[   51.621025][ T3638] R10: ffffed1017384f14 R11: 1ffff11017384f13 R12: dffffc0000000000
[   51.629090][ T3638] R13: ffff88807f3d09d0 R14: 0000000000000011 R15: ffffffff90029988
[   51.637103][ T3638] FS:  0000000000000000(0000) GS:ffff8880b9c00000(0000) knlGS:0000000000000000
[   51.646131][ T3638] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[   51.652815][ T3638] CR2: 000000c00060a001 CR3: 0000000020940000 CR4: 00000000003506f0
[   51.660973][ T3638] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
[   51.668940][ T3638] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
[   51.677688][ T3638] Call Trace:
[   51.680962][ T3638]  <TASK>
[   51.683992][ T3638]  del_timer+0x3d/0x2d0
[   51.688212][ T3638]  ? try_to_grab_pending+0xb1/0x700
[   51.693412][ T3638]  try_to_grab_pending+0xbf/0x700
[   51.698619][ T3638]  __cancel_work_timer+0x81/0x5b0
[   51.703983][ T3638]  ? mgmt_send_event_skb+0x2ee/0x4e0
[   51.709401][ T3638]  ? kmem_cache_free+0x95/0x1d0
[   51.714437][ T3638]  ? mgmt_send_event_skb+0x2ee/0x4e0
[   51.719724][ T3638]  mgmt_index_removed+0x244/0x330
[   51.725464][ T3638]  hci_unregister_dev+0x28e/0x460
[   51.730623][ T3638]  ? vhci_open+0x360/0x360
[   51.735209][ T3638]  vhci_release+0x7f/0xd0
[   51.739538][ T3638]  __fput+0x3b9/0x820
[   51.743800][ T3638]  task_work_run+0x146/0x1c0
[   51.748501][ T3638]  do_exit+0x4ed/0x1f30
[   51.752680][ T3638]  ? rcu_read_lock_sched_held+0x41/0xb0
[   51.758365][ T3638]  do_group_exit+0x23b/0x2f0
[   51.762963][ T3638]  ? _raw_spin_unlock_irq+0x1f/0x40
[   51.768653][ T3638]  ? lockdep_hardirqs_on+0x8d/0x130
[   51.774245][ T3638]  get_signal+0x16a3/0x1700
[   51.779122][ T3638]  arch_do_signal_or_restart+0x29/0x5d0
[   51.784691][ T3638]  exit_to_user_mode_loop+0x74/0x150
[   51.790147][ T3638]  exit_to_user_mode_prepare+0xb2/0x140
[   51.796127][ T3638]  syscall_exit_to_user_mode+0x26/0x60
[   51.802152][ T3638]  do_syscall_64+0x49/0x90
[   51.806773][ T3638]  entry_SYSCALL_64_after_hwframe+0x63/0xcd
[   51.812939][ T3638] RIP: 0033:0x4191dc
[   51.817111][ T3638] Code: Unable to access opcode bytes at RIP 0x4191b2.
[   51.823960][ T3638] RSP: 002b:00007fffcb50dcd0 EFLAGS: 00000246 ORIG_RAX: 0000000000000000
[   51.832477][ T3638] RAX: fffffffffffffe00 RBX: 00007fffcb50dd90 RCX: 00000000004191dc
[   51.840566][ T3638] RDX: 0000000000000050 RSI: 0000000000568020 RDI: 00000000000000f9
[   51.848559][ T3638] RBP: 0000000000000003 R08: 0000000000000000 R09: 0079746972756365
[   51.857099][ T3638] R10: 00000000005436a0 R11: 0000000000000246 R12: 0000000000000032
[   51.865523][ T3638] R13: 000000000000c8b1 R14: 0000000000000000 R15: 00007fffcb50ddd0
[   51.873863][ T3638]  </TASK>
[   51.876995][ T3638] Kernel panic - not syncing: panic_on_warn set ...
[   51.883575][ T3638] CPU: 0 PID: 3638 Comm: syz-executor.0 Not tainted 5.19.0-syzkaller #0
[   51.892096][ T3638] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 07/22/2022
[   51.902161][ T3638] Call Trace:
[   51.905527][ T3638]  <TASK>
[   51.908545][ T3638]  dump_stack_lvl+0x131/0x1c8
[   51.913322][ T3638]  panic+0x26b/0x693
[   51.917497][ T3638]  ? __warn+0x131/0x220
[   51.921826][ T3638]  ? debug_object_assert_init+0x1fa/0x250
[   51.927674][ T3638]  __warn+0x1fa/0x220
[   51.931700][ T3638]  ? debug_object_assert_init+0x1fa/0x250
[   51.937520][ T3638]  report_bug+0x1b3/0x2d0
[   51.941878][ T3638]  handle_bug+0x3d/0x70
[   51.946174][ T3638]  exc_invalid_op+0x16/0x40
[   51.950780][ T3638]  asm_exc_invalid_op+0x16/0x20
[   51.955734][ T3638] RIP: 0010:debug_object_assert_init+0x1fa/0x250
[   51.962102][ T3638] Code: e8 eb 83 d1 fd 4c 8b 45 00 48 c7 c7 60 9f 6a 8a 48 c7 c6 60 9c 6a 8a 48 c7 c2 00 a1 6a 8a 31 c9 49 89 d9 31 c0 e8 66 73 4e fd <0f> 0b ff 05 9a 20 8a 09 48 83 c5 38 48 89 e8 48 c1 e8 03 42 80 3c
[   51.981793][ T3638] RSP: 0018:ffffc90003b0f8c8 EFLAGS: 00010046
[   51.988057][ T3638] RAX: af76cc1e655f6400 RBX: 0000000000000000 RCX: ffff888014741d40
[   51.996245][ T3638] RDX: 0000000000000000 RSI: 0000000080000000 RDI: 0000000000000000
[   52.005054][ T3638] RBP: ffffffff8a0fc700 R08: ffffffff8165f59d R09: ffffed1017384f14
[   52.013065][ T3638] R10: ffffed1017384f14 R11: 1ffff11017384f13 R12: dffffc0000000000
[   52.021767][ T3638] R13: ffff88807f3d09d0 R14: 0000000000000011 R15: ffffffff90029988
[   52.031524][ T3638]  ? __wake_up_klogd+0xcd/0x100
[   52.036840][ T3638]  ? debug_object_assert_init+0x1fa/0x250
[   52.042873][ T3638]  del_timer+0x3d/0x2d0
[   52.047348][ T3638]  ? try_to_grab_pending+0xb1/0x700
[   52.052749][ T3638]  try_to_grab_pending+0xbf/0x700
[   52.057800][ T3638]  __cancel_work_timer+0x81/0x5b0
[   52.062831][ T3638]  ? mgmt_send_event_skb+0x2ee/0x4e0
[   52.068345][ T3638]  ? kmem_cache_free+0x95/0x1d0
[   52.073224][ T3638]  ? mgmt_send_event_skb+0x2ee/0x4e0
[   52.078681][ T3638]  mgmt_index_removed+0x244/0x330
[   52.083977][ T3638]  hci_unregister_dev+0x28e/0x460
[   52.089128][ T3638]  ? vhci_open+0x360/0x360
[   52.093621][ T3638]  vhci_release+0x7f/0xd0
[   52.097961][ T3638]  __fput+0x3b9/0x820
[   52.102128][ T3638]  task_work_run+0x146/0x1c0
[   52.106746][ T3638]  do_exit+0x4ed/0x1f30
[   52.110908][ T3638]  ? rcu_read_lock_sched_held+0x41/0xb0
[   52.116673][ T3638]  do_group_exit+0x23b/0x2f0
[   52.121452][ T3638]  ? _raw_spin_unlock_irq+0x1f/0x40
[   52.126661][ T3638]  ? lockdep_hardirqs_on+0x8d/0x130
[   52.131858][ T3638]  get_signal+0x16a3/0x1700
[   52.136804][ T3638]  arch_do_signal_or_restart+0x29/0x5d0
[   52.142556][ T3638]  exit_to_user_mode_loop+0x74/0x150
[   52.147840][ T3638]  exit_to_user_mode_prepare+0xb2/0x140
[   52.153731][ T3638]  syscall_exit_to_user_mode+0x26/0x60
[   52.159196][ T3638]  do_syscall_64+0x49/0x90
[   52.163644][ T3638]  entry_SYSCALL_64_after_hwframe+0x63/0xcd
[   52.169629][ T3638] RIP: 0033:0x4191dc
[   52.173526][ T3638] Code: Unable to access opcode bytes at RIP 0x4191b2.
[   52.180458][ T3638] RSP: 002b:00007fffcb50dcd0 EFLAGS: 00000246 ORIG_RAX: 0000000000000000
[   52.189133][ T3638] RAX: fffffffffffffe00 RBX: 00007fffcb50dd90 RCX: 00000000004191dc
[   52.197189][ T3638] RDX: 0000000000000050 RSI: 0000000000568020 RDI: 00000000000000f9
[   52.205251][ T3638] RBP: 0000000000000003 R08: 0000000000000000 R09: 0079746972756365
[   52.213269][ T3638] R10: 00000000005436a0 R11: 0000000000000246 R12: 0000000000000032
[   52.221332][ T3638] R13: 000000000000c8b1 R14: 0000000000000000 R15: 00007fffcb50ddd0
[   52.229405][ T3638]  </TASK>
[   52.232869][ T3638] Kernel Offset: disabled
[   52.237327][ T3638] 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=""
GOTOOLDIR="/usr/local/go/pkg/tool/linux_amd64"
GOVCS=""
GOVERSION="go1.17"
GCCGO="gccgo"
AR="ar"
CC="gcc"
CXX="g++"
CGO_ENABLED="1"
GOMOD="/syzkaller/jobs/linux/gopath/src/github.com/google/syzkaller/go.mod"
CGO_CFLAGS="-g -O2"
CGO_CPPFLAGS=""
CGO_CXXFLAGS="-g -O2"
CGO_FFLAGS="-g -O2"
CGO_LDFLAGS="-g -O2"
PKG_CONFIG="pkg-config"
GOGCCFLAGS="-fPIC -m64 -pthread -fmessage-length=0 -fdebug-prefix-map=/tmp/go-build1977670166=/tmp/go-build -gno-record-gcc-switches"

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


go list -f '{{.Stale}}' ./sys/syz-sysgen | grep -q false || go install ./sys/syz-sysgen
make .descriptions
bin/syz-sysgen
touch .descriptions
GOOS=linux GOARCH=amd64 go build "-ldflags=-s -w -X github.com/google/syzkaller/prog.GitRevision=607e3baf1c25928040d05fc22eff6fce7edd709e -X 'github.com/google/syzkaller/prog.gitRevisionDate=20210324-183421'" "-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=607e3baf1c25928040d05fc22eff6fce7edd709e -X 'github.com/google/syzkaller/prog.gitRevisionDate=20210324-183421'" "-tags=syz_target syz_os_linux syz_arch_amd64 " -o ./bin/linux_amd64/syz-execprog github.com/google/syzkaller/tools/syz-execprog
GOOS=linux GOARCH=amd64 go build "-ldflags=-s -w -X github.com/google/syzkaller/prog.GitRevision=607e3baf1c25928040d05fc22eff6fce7edd709e -X 'github.com/google/syzkaller/prog.gitRevisionDate=20210324-183421'" "-tags=syz_target syz_os_linux syz_arch_amd64 " -o ./bin/linux_amd64/syz-stress github.com/google/syzkaller/tools/syz-stress
mkdir -p ./bin/linux_amd64
gcc -o ./bin/linux_amd64/syz-executor executor/executor.cc \
	-m64 -O2 -pthread -Wall -Werror -Wparentheses -Wunused-const-variable -Wframe-larger-than=16384 -static -fpermissive -w -DGOOS_linux=1 -DGOARCH_amd64=1 \
	-DHOSTGOOS_linux=1 -DGIT_REVISION=\"607e3baf1c25928040d05fc22eff6fce7edd709e\"


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


Tested on:

commit:         64737995 wifi: mac80211: Don't finalize CSA in IBSS mo..
git tree:       https://github.com/siddhpant/linux.git warning_ibss_csa_beacon
kernel config:  https://syzkaller.appspot.com/x/.config?x=9b770cb261c0c061
dashboard link: https://syzkaller.appspot.com/bug?extid=b6c9fe29aefe68e4ad34
compiler:       Debian clang version 13.0.1-++20220126092033+75e33f71c2da-1~exp1~20220126212112.63, GNU ld (GNU Binutils for Debian) 2.35.2

Note: no patches were applied.