Re: [syzbot] [batman?] WARNING in call_netdevice_notifiers_info

From: syzbot
Date: Tue Aug 22 2023 - 09:25:29 EST

Hello,

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

t/netlink/af_netlink.c:2549
netlink_unicast_kernel net/netlink/af_netlink.c:1339 [inline]
netlink_unicast+0x539/0x800 net/netlink/af_netlink.c:1365
netlink_sendmsg+0x93c/0xe30 net/netlink/af_netlink.c:1914
sock_sendmsg_nosec net/socket.c:725 [inline]
sock_sendmsg+0xd9/0x180 net/socket.c:748
__sys_sendto+0x255/0x340 net/socket.c:2134
__do_sys_sendto net/socket.c:2146 [inline]
__se_sys_sendto net/socket.c:2142 [inline]
__x64_sys_sendto+0xe0/0x1b0 net/socket.c:2142
do_syscall_x64 arch/x86/entry/common.c:50 [inline]
do_syscall_64+0x38/0xb0 arch/x86/entry/common.c:80
entry_SYSCALL_64_after_hwframe+0x63/0xcd
RIP: 0033:0x7f372a07e7dc
Code: 1a 51 02 00 44 8b 4c 24 2c 4c 8b 44 24 20 89 c5 44 8b 54 24 28 48 8b 54 24 18 b8 2c 00 00 00 48 8b 74 24 10 8b 7c 24 08 0f 05 <48> 3d 00 f0 ff ff 77 34 89 ef 48 89 44 24 08 e8 60 51 02 00 48 8b
RSP: 002b:00007ffd0be0cda0 EFLAGS: 00000293 ORIG_RAX: 000000000000002c
RAX: ffffffffffffffda RBX: 00007f372acc4620 RCX: 00007f372a07e7dc
RDX: 000000000000002c RSI: 00007f372acc4670 RDI: 0000000000000003
RBP: 0000000000000000 R08: 00007ffd0be0cdf4 R09: 000000000000000c
R10: 0000000000000000 R11: 0000000000000293 R12: 0000000000000001
R13: 0000000000000000 R14: 00007f372acc4670 R15: 0000000000000000
</TASK>


Warning: Permanently added '10.128.1.138' (ED25519) to the list of known hosts.
2023/08/22 13:24:01 fuzzer started
2023/08/22 13:24:01 connecting to host at 10.128.0.169:36627
2023/08/22 13:24:01 checking machine...
2023/08/22 13:24:01 checking revisions...
2023/08/22 13:24:01 testing simple program...
[ 57.017895][ T5023] cgroup: Unknown subsys name 'net'
[ 57.181711][ T5023] cgroup: Unknown subsys name 'rlimit'
[ 58.562226][ T5023] Adding 124996k swap on ./swap-file. Priority:0 extents:1 across:124996k FS
[ 58.575443][ T5021] memfd_create() without MFD_EXEC nor MFD_NOEXEC_SEAL, pid=5021 'syz-fuzzer'
[ 58.704723][ T5028] Bluetooth: hci0: unexpected cc 0x0c03 length: 249 > 1
[ 58.712819][ T5028] Bluetooth: hci0: unexpected cc 0x1003 length: 249 > 9
[ 58.720864][ T5028] Bluetooth: hci0: unexpected cc 0x1001 length: 249 > 9
[ 58.729629][ T5028] Bluetooth: hci0: unexpected cc 0x0c23 length: 249 > 4
[ 58.737964][ T5028] Bluetooth: hci0: unexpected cc 0x0c25 length: 249 > 3
[ 58.746482][ T5028] Bluetooth: hci0: unexpected cc 0x0c38 length: 249 > 2
[ 58.873127][ T5026] chnl_net:caif_netlink_parms(): no params data found
[ 58.927097][ T5026] bridge0: port 1(bridge_slave_0) entered blocking state
[ 58.936670][ T5026] bridge0: port 1(bridge_slave_0) entered disabled state
[ 58.944713][ T5026] bridge_slave_0: entered allmulticast mode
[ 58.960073][ T5026] bridge_slave_0: entered promiscuous mode
[ 58.969547][ T5026] bridge0: port 2(bridge_slave_1) entered blocking state
[ 58.976767][ T5026] bridge0: port 2(bridge_slave_1) entered disabled state
[ 58.983915][ T5026] bridge_slave_1: entered allmulticast mode
[ 58.990913][ T5026] bridge_slave_1: entered promiscuous mode
[ 59.012878][ T5026] bond0: (slave bond_slave_0): Enslaving as an active interface with an up link
[ 59.024772][ T5026] bond0: (slave bond_slave_1): Enslaving as an active interface with an up link
[ 59.051026][ T5026] team0: Port device team_slave_0 added
[ 59.059189][ T5026] team0: Port device team_slave_1 added
[ 59.078141][ T5026] batman_adv: batadv0: Adding interface: batadv_slave_0
[ 59.085319][ T5026] 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.
[ 59.112318][ T5026] batman_adv: batadv0: Not using interface batadv_slave_0 (retrying later): interface not active
[ 59.125419][ T5026] batman_adv: batadv0: Adding interface: batadv_slave_1
[ 59.132742][ T5026] 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.
[ 59.159529][ T5026] batman_adv: batadv0: Not using interface batadv_slave_1 (retrying later): interface not active
[ 59.191088][ T5026] hsr_slave_0: entered promiscuous mode
[ 59.197913][ T5026] hsr_slave_1: entered promiscuous mode
[ 59.290486][ T5026] netdevsim netdevsim0 netdevsim0: renamed from eth0
[ 59.301306][ T5026] netdevsim netdevsim0 netdevsim1: renamed from eth1
[ 59.311233][ T5026] netdevsim netdevsim0 netdevsim2: renamed from eth2
[ 59.322571][ T5026] netdevsim netdevsim0 netdevsim3: renamed from eth3
[ 59.344435][ T5026] bridge0: port 2(bridge_slave_1) entered blocking state
[ 59.351796][ T5026] bridge0: port 2(bridge_slave_1) entered forwarding state
[ 59.361448][ T5026] bridge0: port 1(bridge_slave_0) entered blocking state
[ 59.369739][ T5026] bridge0: port 1(bridge_slave_0) entered forwarding state
[ 59.424526][ T5026] 8021q: adding VLAN 0 to HW filter on device bond0
[ 59.440733][ T5037] bridge0: port 1(bridge_slave_0) entered disabled state
[ 59.450802][ T5037] bridge0: port 2(bridge_slave_1) entered disabled state
[ 59.468337][ T5026] 8021q: adding VLAN 0 to HW filter on device team0
[ 59.482456][ T22] bridge0: port 1(bridge_slave_0) entered blocking state
[ 59.492153][ T22] bridge0: port 1(bridge_slave_0) entered forwarding state
[ 59.506339][ T5038] bridge0: port 2(bridge_slave_1) entered blocking state
[ 59.513883][ T5038] bridge0: port 2(bridge_slave_1) entered forwarding state
[ 59.676572][ T5026] 8021q: adding VLAN 0 to HW filter on device batadv0
[ 59.709625][ T5026] veth0_vlan: entered promiscuous mode
[ 59.721272][ T5026] veth1_vlan: entered promiscuous mode
[ 59.746020][ T5026] veth0_macvtap: entered promiscuous mode
[ 59.754854][ T5026] veth1_macvtap: entered promiscuous mode
[ 59.774188][ T5026] batman_adv: batadv0: Interface activated: batadv_slave_0
[ 59.783611][ T5026]
[ 59.785948][ T5026] ============================================
[ 59.792214][ T5026] WARNING: possible recursive locking detected
[ 59.798351][ T5026] 6.5.0-rc6-syzkaller-00126-gd44036cad311-dirty #0 Not tainted
[ 59.805885][ T5026] --------------------------------------------
[ 59.812077][ T5026] syz-executor.0/5026 is trying to acquire lock:
[ 59.818521][ T5026] ffffffff8e3e0f68 (rtnl_mutex){+.+.}-{3:3}, at: batadv_hardif_activate_interface.part.0+0x1a1/0x340
[ 59.829840][ T5026]
[ 59.829840][ T5026] but task is already holding lock:
[ 59.837516][ T5026] ffffffff8e3e0f68 (rtnl_mutex){+.+.}-{3:3}, at: rtnetlink_rcv_msg+0x3e2/0xd30
[ 59.847166][ T5026]
[ 59.847166][ T5026] other info that might help us debug this:
[ 59.855838][ T5026] Possible unsafe locking scenario:
[ 59.855838][ T5026]
[ 59.865689][ T5026] CPU0
[ 59.869922][ T5026] ----
[ 59.874173][ T5026] lock(rtnl_mutex);
[ 59.878846][ T5026] lock(rtnl_mutex);
[ 59.883701][ T5026]
[ 59.883701][ T5026] *** DEADLOCK ***
[ 59.883701][ T5026]
[ 59.892540][ T5026] May be due to missing lock nesting notation
[ 59.892540][ T5026]
[ 59.901828][ T5026] 1 lock held by syz-executor.0/5026:
[ 59.907302][ T5026] #0: ffffffff8e3e0f68 (rtnl_mutex){+.+.}-{3:3}, at: rtnetlink_rcv_msg+0x3e2/0xd30
[ 59.919046][ T5026]
[ 59.919046][ T5026] stack backtrace:
[ 59.926250][ T5026] CPU: 0 PID: 5026 Comm: syz-executor.0 Not tainted 6.5.0-rc6-syzkaller-00126-gd44036cad311-dirty #0
[ 59.938381][ T5026] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 07/26/2023
[ 59.948991][ T5026] Call Trace:
[ 59.952408][ T5026] <TASK>
[ 59.955527][ T5026] dump_stack_lvl+0xd9/0x1b0
[ 59.960423][ T5026] __lock_acquire+0x2971/0x5de0
[ 59.966018][ T5026] ? lockdep_hardirqs_on_prepare+0x410/0x410
[ 59.972780][ T5026] lock_acquire+0x1ae/0x510
[ 59.978452][ T5026] ? batadv_hardif_activate_interface.part.0+0x1a1/0x340
[ 59.986470][ T5026] ? lock_sync+0x190/0x190
[ 59.991286][ T5026] ? lockdep_hardirqs_on_prepare+0x410/0x410
[ 59.998306][ T5026] ? preempt_count_sub+0x150/0x150
[ 60.005708][ T5026] __mutex_lock+0x181/0x1340
[ 60.011037][ T5026] ? batadv_hardif_activate_interface.part.0+0x1a1/0x340
[ 60.018701][ T5026] ? batadv_hardif_activate_interface.part.0+0x1a1/0x340
[ 60.030519][ T5026] ? find_held_lock+0x2d/0x110
[ 60.036618][ T5026] ? mutex_lock_io_nested+0x11a0/0x11a0
[ 60.042288][ T5026] ? batadv_hardif_min_mtu+0x18e/0x3f0
[ 60.048474][ T5026] ? reacquire_held_locks+0x4b0/0x4b0
[ 60.054147][ T5026] ? batadv_hardif_activate_interface.part.0+0x1a1/0x340
[ 60.061299][ T5026] ? rtnl_lock+0x9/0x20
[ 60.067412][ T5026] batadv_hardif_activate_interface.part.0+0x1a1/0x340
[ 60.075096][ T5026] batadv_hard_if_event+0x6be/0x1670
[ 60.081119][ T5026] ? smc_pnet_netdev_event+0x85/0x7b0
[ 60.087273][ T5026] ? lockdep_rtnl_is_held+0x26/0x30
[ 60.093222][ T5026] notifier_call_chain+0xb6/0x3b0
[ 60.098670][ T5026] ? batadv_hardif_disable_interface+0xec0/0xec0
[ 60.105830][ T5026] call_netdevice_notifiers_info+0xb9/0x130
[ 60.112104][ T5026] __dev_notify_flags+0x12d/0x2e0
[ 60.118141][ T5026] ? dev_change_name+0x7c0/0x7c0
[ 60.123456][ T5026] ? dev_set_allmulti+0x30/0x30
[ 60.128510][ T5026] ? slab_free_freelist_hook+0x10b/0x1e0
[ 60.134258][ T5026] dev_change_flags+0x122/0x170
[ 60.139177][ T5026] do_setlink+0x1a2a/0x3fa0
[ 60.143797][ T5026] ? rtnl_bridge_getlink+0x8c0/0x8c0
[ 60.149364][ T5026] ? unwind_next_frame+0xe41/0x2020
[ 60.154588][ T5026] ? __module_address+0x55/0x3b0
[ 60.159565][ T5026] ? bpf_ksym_find+0x124/0x1b0
[ 60.164361][ T5026] ? write_profile+0x450/0x450
[ 60.169272][ T5026] ? is_bpf_text_address+0x9b/0x1a0
[ 60.174510][ T5026] ? kernel_text_address+0x85/0xf0
[ 60.180023][ T5026] ? __kernel_text_address+0xd/0x30
[ 60.185454][ T5026] ? unwind_get_return_address+0x55/0xa0
[ 60.191233][ T5026] ? __sanitizer_cov_trace_switch+0x54/0x90
[ 60.197426][ T5026] ? __nla_validate_parse+0x58a/0x2530
[ 60.203002][ T5026] ? nla_get_range_signed+0x370/0x370
[ 60.208760][ T5026] ? stack_trace_save+0x96/0xd0
[ 60.213729][ T5026] ? filter_irq_stacks+0x90/0x90
[ 60.218785][ T5026] ? __stack_depot_save+0x39/0x510
[ 60.224291][ T5026] ? __nla_parse+0x40/0x50
[ 60.228910][ T5026] __rtnl_newlink+0xc04/0x18c0
[ 60.233709][ T5026] ? rtnl_link_unregister+0x260/0x260
[ 60.239133][ T5026] rtnl_newlink+0x67/0xa0
[ 60.243561][ T5026] ? __rtnl_newlink+0x18c0/0x18c0
[ 60.248605][ T5026] rtnetlink_rcv_msg+0x439/0xd30
[ 60.253569][ T5026] ? rtnl_getlink+0xb40/0xb40
[ 60.258628][ T5026] ? netdev_core_pick_tx+0x390/0x390
[ 60.264034][ T5026] netlink_rcv_skb+0x16b/0x440
[ 60.269237][ T5026] ? rtnl_getlink+0xb40/0xb40
[ 60.273959][ T5026] ? netlink_ack+0x1370/0x1370
[ 60.278780][ T5026] ? netlink_deliver_tap+0x1b1/0xd00
[ 60.284179][ T5026] netlink_unicast+0x539/0x800
[ 60.288975][ T5026] ? netlink_attachskb+0x880/0x880
[ 60.294186][ T5026] ? __sanitizer_cov_trace_switch+0x54/0x90
[ 60.300111][ T5026] ? __phys_addr_symbol+0x30/0x70
[ 60.305192][ T5026] ? __check_object_size+0x323/0x740
[ 60.310487][ T5026] netlink_sendmsg+0x93c/0xe30
[ 60.315269][ T5026] ? netlink_unicast+0x800/0x800
[ 60.320225][ T5026] ? bpf_lsm_socket_sendmsg+0x9/0x10
[ 60.325521][ T5026] ? netlink_unicast+0x800/0x800
[ 60.330562][ T5026] sock_sendmsg+0xd9/0x180
[ 60.334990][ T5026] __sys_sendto+0x255/0x340
[ 60.339517][ T5026] ? __ia32_sys_getpeername+0xb0/0xb0
[ 60.345034][ T5026] ? reacquire_held_locks+0x4b0/0x4b0
[ 60.350448][ T5026] ? preempt_count_sub+0x150/0x150
[ 60.355769][ T5026] ? __rseq_handle_notify_resume+0x5f2/0x1010
[ 60.361888][ T5026] ? preempt_count_sub+0x150/0x150
[ 60.367153][ T5026] __x64_sys_sendto+0xe0/0x1b0
[ 60.372033][ T5026] ? syscall_enter_from_user_mode+0x26/0x80
[ 60.378058][ T5026] do_syscall_64+0x38/0xb0
[ 60.382630][ T5026] entry_SYSCALL_64_after_hwframe+0x63/0xcd
[ 60.388622][ T5026] RIP: 0033:0x7f372a07e7dc
[ 60.393141][ T5026] Code: 1a 51 02 00 44 8b 4c 24 2c 4c 8b 44 24 20 89 c5 44 8b 54 24 28 48 8b 54 24 18 b8 2c 00 00 00 48 8b 74 24 10 8b 7c 24 08 0f 05 <48> 3d 00 f0 ff ff 77 34 89 ef 48 89 44 24 08 e8 60 51 02 00 48 8b
[ 60.413916][ T5026] RSP: 002b:00007ffd0be0cda0 EFLAGS: 00000293 ORIG_RAX: 000000000000002c
[ 60.424512][ T5026] RAX: ffffffffffffffda RBX: 00007f372acc4620 RCX: 00007f372a07e7dc
[ 60.432609][ T5026] RDX: 000000000000002c RSI: 00007f372acc4670 RDI: 0000000000000003
[ 60.441019][ T5026] RBP: 0000000000000000 R08: 00007ffd0be0cdf4 R09: 000000000000000c
executing program
[ 60.449348][ T5026] R10: 0000000000000000 R11: 0000000000000293 R12: 0000000000000001
[ 60.457780][ T5026] R13: 0000000000000000 R14: 00007f372acc4670 R15: 0000000000000000
[ 60.466494][ T5026] </TASK>
[ 60.785109][ T4431] Bluetooth: hci0: command 0x0409 tx timeout
executing program
[ 62.865081][ T4431] Bluetooth: hci0: command 0x041b tx timeout
[ 64.945041][ T4431] Bluetooth: hci0: command 0x040f tx timeout
executing program
[ 67.025170][ T4431] Bluetooth: hci0: command 0x0419 tx timeout
executing program


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-2/linux/gopath/pkg/mod"
GONOPROXY=""
GONOSUMDB=""
GOOS="linux"
GOPATH="/syzkaller/jobs-2/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.20.1"
GCCGO="gccgo"
GOAMD64="v1"
AR="ar"
CC="gcc"
CXX="g++"
CGO_ENABLED="1"
GOMOD="/syzkaller/jobs-2/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 -fdebug-prefix-map=/tmp/go-build140395884=/tmp/go-build -gno-record-gcc-switches"

git status (err=<nil>)
HEAD detached at d216d8a03
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:32: run command via tools/syz-env for best compatibility, see:
Makefile:33: 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
bin/syz-sysgen
touch .descriptions
GOOS=linux GOARCH=amd64 go build "-ldflags=-s -w -X github.com/google/syzkaller/prog.GitRevision=d216d8a03b50bef82eac746d227230835f061640 -X 'github.com/google/syzkaller/prog.gitRevisionDate=20230818-113734'" "-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=d216d8a03b50bef82eac746d227230835f061640 -X 'github.com/google/syzkaller/prog.gitRevisionDate=20230818-113734'" "-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=d216d8a03b50bef82eac746d227230835f061640 -X 'github.com/google/syzkaller/prog.gitRevisionDate=20230818-113734'" "-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 -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=\"d216d8a03b50bef82eac746d227230835f061640\"


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


Tested on:

commit: d44036ca net: dsa: felix: fix oversize frame dropping ..
git tree: https://git.kernel.org/pub/scm/linux/kernel/git/netdev/net.git
kernel config: https://syzkaller.appspot.com/x/.config?x=aa796b6080b04102
dashboard link: https://syzkaller.appspot.com/bug?extid=f8812454d9b3ac00d282
compiler: gcc (Debian 12.2.0-14) 12.2.0, GNU ld (GNU Binutils for Debian) 2.40
patch: https://syzkaller.appspot.com/x/patch.diff?x=13c59b5fa80000