[info] Using makefile-style concurrent boot in runlevel 2. [ 42.114222][ T26] audit: type=1800 audit(1574153291.676:21): pid=7441 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="startpar" name="bootlogs" dev="sda1" ino=2452 res=0 [ 42.164454][ T26] audit: type=1800 audit(1574153291.676:22): pid=7441 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="startpar" name="motd" dev="sda1" ino=2480 res=0 [....] Starting enhanced syslogd: rsyslogd[?25l[?1c7[ ok 8[?25h[?0c. [....] Starting periodic command scheduler: cron[?25l[?1c7[ ok 8[?25h[?0c. [....] Starting OpenBSD Secure Shell server: sshd[?25l[?1c7[ ok 8[?25h[?0c. Debian GNU/Linux 7 syzkaller ttyS0 Warning: Permanently added '10.128.1.35' (ECDSA) to the list of known hosts. 2019/11/19 08:48:22 fuzzer started 2019/11/19 08:48:23 dialing manager at 10.128.0.105:42849 2019/11/19 08:48:24 syscalls: 2566 2019/11/19 08:48:24 code coverage: enabled 2019/11/19 08:48:24 comparison tracing: enabled 2019/11/19 08:48:24 extra coverage: extra coverage is not supported by the kernel 2019/11/19 08:48:24 setuid sandbox: enabled 2019/11/19 08:48:24 namespace sandbox: enabled 2019/11/19 08:48:24 Android sandbox: /sys/fs/selinux/policy does not exist 2019/11/19 08:48:24 fault injection: enabled 2019/11/19 08:48:24 leak checking: CONFIG_DEBUG_KMEMLEAK is not enabled 2019/11/19 08:48:24 net packet injection: enabled 2019/11/19 08:48:24 net device setup: enabled 2019/11/19 08:48:24 concurrency sanitizer: enabled 2019/11/19 08:48:24 devlink PCI setup: PCI device 0000:00:10.0 is not available 2019/11/19 08:48:24 adding functions to KCSAN blacklist: 'pipe_poll' 'do_syslog' 'tick_nohz_idle_stop_tick' 'pid_update_inode' '__hrtimer_run_queues' 08:48:26 executing program 0: mmap(&(0x7f0000000000/0xff5000)=nil, 0xff5000, 0x0, 0x5c832, 0xffffffffffffffff, 0x0) mprotect(&(0x7f0000000000/0x800000)=nil, 0x800000, 0x4) r0 = socket$inet6_tcp(0xa, 0x1, 0x0) r1 = fcntl$dupfd(r0, 0x0, r0) ioctl$PERF_EVENT_IOC_ENABLE(r1, 0x8912, 0x400200) syz_open_procfs(0xffffffffffffffff, &(0x7f00000000c0)='net/sctp\x00') 08:48:26 executing program 1: r0 = socket(0x10, 0x3, 0x0) socketpair$unix(0x1, 0x1, 0x0, &(0x7f0000000000)={0xffffffffffffffff, 0xffffffffffffffff}) r2 = dup(r1) ioctl$PERF_EVENT_IOC_ENABLE(r2, 0x8912, 0x400200) r3 = socket$inet_udp(0x2, 0x2, 0x0) ioctl$sock_SIOCGIFINDEX(r3, 0x8933, &(0x7f0000000600)={'bridge_slave_1\x00', 0x0}) sendmsg$nl_route_sched(0xffffffffffffffff, &(0x7f0000000240)={0x0, 0x0, &(0x7f00000000c0)={&(0x7f0000000640)=ANY=[@ANYBLOB="700100002400070500"/20, @ANYRES32=r4, @ANYBLOB="00000000ffffffff00000000280008001c000100000000000000000e000b1100000000000000000002000000080002000000020008000100726564001c01020004010200b205a34f0459cc6abb699f4e08e3b1b5f75780560a59a8480dae55818041c02d8471389f5a076eaa787f8246aef232f41fb4fd095e664069035a011afb427b4799dc17338322cbd91968a88f2fbcbba734486e4d3c392ec5c40433021f006b4ddf02645062bacedcbcd61e4be8eea0e577dae0a23161a078fedf38770e05530900000003000000000000002a57f6adad6ed581b27ccc9155e93c542353f1681423161cb1e44cc5b75a30515c051d3a8f0d2bcb9e9ebfa6290dd5fd8cd5150410922c3f86a3cee1d9e807ad3428f51c99eb16018bf7a6cd83dca6114af8a94fab0a3986fded7a3835a987bd9f53499def9115ff45ce9d94aa0000000000000000140001000700000000000000000000000000000600000000000000f2b2773fc8046b11aa05120a632745a3f74adae7d70e40dea88a7910b63cf9d8382b64c77417c81f472fbef97c339ae0f34c6dcd56f87fcc63b932823d451b9e71e27706f8fd02b1b3d2cd"], 0x170}}, 0x0) sendmmsg$alg(r0, &(0x7f0000000140), 0x4924b68, 0x0) syzkaller login: [ 56.752361][ T7615] IPVS: ftp: loaded support on port[0] = 21 [ 56.881322][ T7615] chnl_net:caif_netlink_parms(): no params data found [ 56.899327][ T7618] IPVS: ftp: loaded support on port[0] = 21 [ 56.939594][ T7615] bridge0: port 1(bridge_slave_0) entered blocking state [ 56.946911][ T7615] bridge0: port 1(bridge_slave_0) entered disabled state [ 56.955175][ T7615] device bridge_slave_0 entered promiscuous mode [ 56.973100][ T7615] bridge0: port 2(bridge_slave_1) entered blocking state [ 56.980277][ T7615] bridge0: port 2(bridge_slave_1) entered disabled state 08:48:26 executing program 2: r0 = socket$inet_udplite(0x2, 0x2, 0x88) sendmmsg$inet(r0, &(0x7f0000004b00)=[{{&(0x7f0000000040)={0x2, 0x4e23, @remote}, 0x10, 0x0}}, {{&(0x7f0000000240)={0x2, 0x4e22, @remote}, 0x10, 0x0, 0x0, &(0x7f0000001bc0)=[@ip_retopts={{0x18, 0x0, 0x7, {[@cipso={0x86, 0x8, 0x0, [{0x0, 0x2}]}]}}}], 0x18}}], 0x2, 0x0) [ 56.991694][ T7615] device bridge_slave_1 entered promiscuous mode [ 57.022295][ T7615] bond0: (slave bond_slave_0): Enslaving as an active interface with an up link [ 57.037560][ T7615] bond0: (slave bond_slave_1): Enslaving as an active interface with an up link [ 57.084400][ T7615] team0: Port device team_slave_0 added [ 57.102184][ T7615] team0: Port device team_slave_1 added [ 57.126503][ T7618] chnl_net:caif_netlink_parms(): no params data found [ 57.180502][ T7621] IPVS: ftp: loaded support on port[0] = 21 08:48:26 executing program 3: r0 = openat$ptmx(0xffffffffffffff9c, &(0x7f0000000300)='/dev/ptmx\x00', 0x8000000000000802, 0x0) ioctl$TIOCSETD(r0, 0x5423, &(0x7f00000003c0)=0xd) ppoll(&(0x7f00000000c0)=[{r0}], 0x1, 0x0, 0x0, 0x0) write(r0, 0x0, 0x0) [ 57.234044][ T7615] device hsr_slave_0 entered promiscuous mode [ 57.272878][ T7615] device hsr_slave_1 entered promiscuous mode [ 57.408910][ T7618] bridge0: port 1(bridge_slave_0) entered blocking state [ 57.419971][ T7618] bridge0: port 1(bridge_slave_0) entered disabled state [ 57.440873][ T7618] device bridge_slave_0 entered promiscuous mode [ 57.479724][ T7623] IPVS: ftp: loaded support on port[0] = 21 [ 57.503452][ T7618] bridge0: port 2(bridge_slave_1) entered blocking state [ 57.510648][ T7618] bridge0: port 2(bridge_slave_1) entered disabled state [ 57.559903][ T7618] device bridge_slave_1 entered promiscuous mode [ 57.614581][ T7615] bridge0: port 2(bridge_slave_1) entered blocking state [ 57.621692][ T7615] bridge0: port 2(bridge_slave_1) entered forwarding state [ 57.629070][ T7615] bridge0: port 1(bridge_slave_0) entered blocking state [ 57.636179][ T7615] bridge0: port 1(bridge_slave_0) entered forwarding state [ 57.715917][ T7618] bond0: (slave bond_slave_0): Enslaving as an active interface with an up link 08:48:27 executing program 4: sendto$inet6(0xffffffffffffffff, &(0x7f0000000280)="88122bee8b917d7ff16334a46d8488b1cac8173c0e0eba338d6bc2064546b54f234e160f862eb7bbc20ffb5bcb78cd1bf9d501182c288f3e5b2bf136eb7854a4a5051022caab24b59f5495b3d62b5abbc683fba25ea2a73520309f9a9fd1bf2fdcb042380efcd1924dcd377b16bd1b1c563ac1983c8388cb4fb7b55ca8c81cd9b4d660c40fe5105b72a8a8af3c10f61c3014ed9a9bcb91326a092229327b2ba84ea157b2cb2bb1426fb36799ab2dc520c911ba457f959e191ccce6e53eba7e500b", 0xc1, 0x0, 0x0, 0x0) r0 = bpf$MAP_CREATE(0x100000000000000, &(0x7f00000000c0)={0xe, 0x4, 0x4, 0x4, 0x0, 0xffffffffffffffff, 0x0, [0x0, 0x0, 0x0, 0x0, 0x2000000]}, 0x3c) bpf$MAP_UPDATE_ELEM(0x2, &(0x7f0000000180)={r0, &(0x7f0000000340), &(0x7f0000000400)}, 0x20) [ 57.791919][ T7618] bond0: (slave bond_slave_1): Enslaving as an active interface with an up link [ 57.876444][ T7621] chnl_net:caif_netlink_parms(): no params data found [ 57.921837][ T7618] team0: Port device team_slave_0 added [ 57.951803][ T45] bridge0: port 1(bridge_slave_0) entered disabled state [ 57.962035][ T45] bridge0: port 2(bridge_slave_1) entered disabled state [ 58.015551][ T7618] team0: Port device team_slave_1 added [ 58.069375][ T7615] 8021q: adding VLAN 0 to HW filter on device bond0 [ 58.153282][ T7615] 8021q: adding VLAN 0 to HW filter on device team0 [ 58.184394][ T7618] device hsr_slave_0 entered promiscuous mode [ 58.212936][ T7618] device hsr_slave_1 entered promiscuous mode [ 58.262123][ T7618] debugfs: Directory 'hsr0' with parent '/' already present! [ 58.270152][ T3018] IPv6: ADDRCONF(NETDEV_CHANGE): veth1: link becomes ready [ 58.293656][ T3018] IPv6: ADDRCONF(NETDEV_CHANGE): veth0: link becomes ready [ 58.423007][ T12] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_to_bridge: link becomes ready [ 58.442935][ T12] IPv6: ADDRCONF(NETDEV_CHANGE): bridge_slave_0: link becomes ready [ 58.485925][ T12] bridge0: port 1(bridge_slave_0) entered blocking state [ 58.493039][ T12] bridge0: port 1(bridge_slave_0) entered forwarding state [ 58.603417][ T5] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_to_bridge: link becomes ready [ 58.614951][ T7649] IPVS: ftp: loaded support on port[0] = 21 [ 58.634377][ T5] IPv6: ADDRCONF(NETDEV_CHANGE): bridge_slave_1: link becomes ready [ 58.662346][ T5] bridge0: port 2(bridge_slave_1) entered blocking state [ 58.669548][ T5] bridge0: port 2(bridge_slave_1) entered forwarding state 08:48:28 executing program 5: r0 = socket$key(0xf, 0x3, 0x2) sendmsg$key(r0, &(0x7f0000cd0fc8)={0x0, 0x0, &(0x7f00000000c0)={&(0x7f0000000000)={0x2, 0xd, 0x0, 0x0, 0x14, 0x0, 0x0, 0x0, [@sadb_x_policy={0x8, 0x12, 0x0, 0x3, 0x0, 0x0, 0x0, {0x6, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, @in=@multicast2, @in6=@mcast2}}, @sadb_address={0x5, 0x5, 0x0, 0x0, 0x0, @in6={0xa, 0x0, 0x0, @ipv4={[], [], @dev}}}, @sadb_address={0x5, 0x6, 0x0, 0x0, 0x0, @in6={0xa, 0x0, 0x0, @remote={0xfe, 0x80, [], 0xffffffffffffffff}}}]}, 0xa0}}, 0x0) socketpair$unix(0x1, 0x5, 0x0, &(0x7f0000000040)={0xffffffffffffffff, 0xffffffffffffffff}) r2 = dup(r1) ioctl$PERF_EVENT_IOC_ENABLE(r2, 0x8912, 0x400200) r3 = socket$key(0xf, 0x3, 0x2) sendmsg$key(r3, &(0x7f0000cd0fc8)={0x0, 0x0, &(0x7f00000000c0)={&(0x7f0000000000)={0x2, 0xf, 0x0, 0x0, 0x14, 0x0, 0x0, 0x0, [@sadb_x_policy={0x8, 0x12, 0x0, 0x3, 0x0, 0x0, 0x0, {0x6, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, @in, @in6=@mcast2}}, @sadb_address={0x5, 0x5, 0x0, 0x0, 0x0, @in6={0xa, 0x0, 0x0, @ipv4={[], [], @dev}}}, @sadb_address={0x5, 0x6, 0x0, 0x0, 0x0, @in6={0xa, 0x0, 0x0, @remote={0xfe, 0x80, [], 0xffffffffffffffff}}}]}, 0xa0}}, 0x0) [ 58.741943][ T5] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_to_bond: link becomes ready [ 58.780957][ T5] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_to_bond: link becomes ready [ 58.834475][ T5] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_to_team: link becomes ready [ 58.873712][ T5] IPv6: ADDRCONF(NETDEV_CHANGE): team_slave_0: link becomes ready [ 58.917481][ T5] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_to_team: link becomes ready [ 58.962697][ T5] IPv6: ADDRCONF(NETDEV_CHANGE): team_slave_1: link becomes ready [ 59.020720][ T7621] bridge0: port 1(bridge_slave_0) entered blocking state [ 59.031155][ T7621] bridge0: port 1(bridge_slave_0) entered disabled state [ 59.078096][ T7621] device bridge_slave_0 entered promiscuous mode [ 59.086972][ T7582] ================================================================== [ 59.095106][ T7582] BUG: KCSAN: data-race in tomoyo_supervisor / tomoyo_supervisor [ 59.102815][ T7582] [ 59.105147][ T7582] read to 0xffffffff86142124 of 4 bytes by task 7645 on cpu 0: [ 59.112700][ T7582] tomoyo_supervisor+0x1b0/0xd20 [ 59.116971][ T7615] hsr0: Slave A (hsr_slave_0) is not up; please bring it up to get a fully working HSR network [ 59.117642][ T7582] tomoyo_path_permission+0x121/0x160 [ 59.117667][ T7582] tomoyo_path_perm+0x23e/0x390 [ 59.138266][ T7582] tomoyo_inode_getattr+0x26/0x40 [ 59.143384][ T7582] security_inode_getattr+0x9b/0xd0 [ 59.148589][ T7582] vfs_getattr+0x2e/0x70 [ 59.152838][ T7582] vfs_statx+0x102/0x190 [ 59.157086][ T7582] __do_sys_newstat+0x51/0xb0 [ 59.161766][ T7582] __x64_sys_newstat+0x3a/0x50 [ 59.166535][ T7582] do_syscall_64+0xcc/0x370 [ 59.171047][ T7582] entry_SYSCALL_64_after_hwframe+0x44/0xa9 [ 59.176924][ T7582] [ 59.179254][ T7582] write to 0xffffffff86142124 of 4 bytes by task 7582 on cpu 1: [ 59.181706][ T7615] hsr0: Slave B (hsr_slave_1) is not up; please bring it up to get a fully working HSR network [ 59.186880][ T7582] tomoyo_supervisor+0x1c9/0xd20 [ 59.186904][ T7582] tomoyo_path_permission+0x121/0x160 [ 59.207496][ T7582] tomoyo_path_perm+0x23e/0x390 [ 59.212370][ T7582] tomoyo_inode_getattr+0x26/0x40 [ 59.217393][ T7582] security_inode_getattr+0x9b/0xd0 [ 59.222618][ T7582] vfs_getattr+0x2e/0x70 [ 59.226874][ T7582] vfs_statx_fd+0x7a/0xd0 [ 59.231235][ T7582] __do_sys_newfstat+0x49/0xa0 [ 59.235998][ T7582] __x64_sys_newfstat+0x3a/0x50 [ 59.240847][ T7582] do_syscall_64+0xcc/0x370 [ 59.245353][ T7582] entry_SYSCALL_64_after_hwframe+0x44/0xa9 [ 59.251228][ T7582] [ 59.253546][ T7582] Reported by Kernel Concurrency Sanitizer on: [ 59.259694][ T7582] CPU: 1 PID: 7582 Comm: udevd Not tainted 5.4.0-rc7+ #0 [ 59.267234][ T7582] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [ 59.270290][ T7623] chnl_net:caif_netlink_parms(): no params data found [ 59.277407][ T7582] ================================================================== [ 59.277416][ T7582] Kernel panic - not syncing: panic_on_warn set ... [ 59.277434][ T7582] CPU: 1 PID: 7582 Comm: udevd Not tainted 5.4.0-rc7+ #0 [ 59.277442][ T7582] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [ 59.277446][ T7582] Call Trace: [ 59.277471][ T7582] dump_stack+0x11d/0x181 [ 59.277541][ T7582] panic+0x210/0x640 [ 59.327653][ T7582] ? vprintk_func+0x8d/0x140 [ 59.332252][ T7582] kcsan_report.cold+0xc/0xd [ 59.336864][ T7582] kcsan_setup_watchpoint+0x3fe/0x460 [ 59.339364][ T3018] IPv6: ADDRCONF(NETDEV_CHANGE): team0: link becomes ready [ 59.342252][ T7582] __tsan_unaligned_write4+0xc4/0x100 [ 59.342271][ T7582] tomoyo_supervisor+0x1c9/0xd20 [ 59.342289][ T7582] ? tomoyo_path_matches_pattern+0xa1/0x160 [ 59.342317][ T7582] ? __sanitizer_cov_trace_const_cmp1+0x1a/0x20 [ 59.352343][ T3018] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_to_hsr: link becomes ready [ 59.354870][ T7582] ? tomoyo_compare_name_union+0x4c/0xa0 [ 59.383634][ T3018] IPv6: ADDRCONF(NETDEV_CHANGE): hsr_slave_0: link becomes ready [ 59.385526][ T7582] ? __read_once_size.constprop.0+0x12/0x20 [ 59.399818][ T7582] ? tomoyo_compare_name_union+0xa0/0xa0 [ 59.405465][ T7582] tomoyo_path_permission+0x121/0x160 [ 59.410848][ T7582] tomoyo_path_perm+0x23e/0x390 [ 59.415742][ T7582] tomoyo_inode_getattr+0x26/0x40 [ 59.420770][ T7582] security_inode_getattr+0x9b/0xd0 [ 59.425975][ T7582] vfs_getattr+0x2e/0x70 [ 59.430229][ T7582] vfs_statx_fd+0x7a/0xd0 [ 59.434587][ T7582] __do_sys_newfstat+0x49/0xa0 [ 59.439360][ T7582] ? putname+0xb1/0xe0 [ 59.443442][ T7582] ? do_sys_open+0x2d8/0x4f0 [ 59.448133][ T7582] ? __sanitizer_cov_trace_const_cmp8+0x18/0x20 [ 59.452498][ T3018] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_to_hsr: link becomes ready [ 59.454452][ T7582] ? debug_smp_processor_id+0x4c/0x172 [ 59.467808][ T7582] __x64_sys_newfstat+0x3a/0x50 [ 59.472669][ T7582] do_syscall_64+0xcc/0x370 [ 59.477184][ T7582] entry_SYSCALL_64_after_hwframe+0x44/0xa9 [ 59.483077][ T7582] RIP: 0033:0x7f058a4cccb4 [ 59.487515][ T7582] Code: 00 f7 d8 64 89 02 83 c8 ff c3 90 90 90 90 90 90 90 90 90 90 90 90 83 ff 01 89 f0 77 19 48 63 f8 48 89 d6 b8 05 00 00 00 0f 05 <48> 3d 00 f0 ff ff 77 18 f3 c3 66 90 48 8b 05 51 51 2b 00 64 c7 00 [ 59.507163][ T7582] RSP: 002b:00007ffe63c08ab8 EFLAGS: 00000246 ORIG_RAX: 0000000000000005 [ 59.515580][ T7582] RAX: ffffffffffffffda RBX: 000000000152ee80 RCX: 00007f058a4cccb4 [ 59.519995][ T3018] IPv6: ADDRCONF(NETDEV_CHANGE): hsr_slave_1: link becomes ready [ 59.523554][ T7582] RDX: 00007ffe63c08ac0 RSI: 00007ffe63c08ac0 RDI: 0000000000000005 [ 59.523564][ T7582] RBP: 0000000000003fff R08: 0000000000000001 R09: 0000000000000000 [ 59.523573][ T7582] R10: 0000000000000000 R11: 0000000000000246 R12: 000000000000000a [ 59.523581][ T7582] R13: 0000000000000000 R14: 0000000000000000 R15: 00007ffe63c09050 [ 59.525008][ T7582] Kernel Offset: disabled [ 59.569828][ T7582] Rebooting in 86400 seconds..