D1217 03:52:16.794661 46940 sampler.go:168] Time: Adjusting syscall overhead up to 8000 D1217 03:52:23.246574 46940 task_signals.go:467] [ 4] Notified of signal 23 D1217 03:52:23.246800 46940 task_signals.go:179] [ 4] Restarting syscall 202 after errno 512: interrupted by signal 23 D1217 03:52:23.246965 46940 task_signals.go:220] [ 4] Signal 23: delivering to handler D1217 03:52:28.794624 46940 sampler.go:168] Time: Adjusting syscall overhead up to 8000 D1217 03:52:28.794800 46940 sampler.go:197] Time: Adjusting syscall overhead down to 7000 I1217 03:52:42.757585 49317 main.go:209] *************************** I1217 03:52:42.757680 49317 main.go:210] Args: [/syzkaller/managers/ptrace-2-race/current/image -root /syzkaller/managers/ptrace-2-race/workdir/gvisor_root -watchdog-action=panic -network=none -debug -debug-log=/dev/stderr -platform=ptrace -file-access=exclusive -network=sandbox -net-raw -watchdog-action=panic -vfs2 -fuse -TESTONLY-unsafe-nonroot exec -user=0:0 -cap CAP_CHOWN -cap CAP_DAC_OVERRIDE -cap CAP_DAC_READ_SEARCH -cap CAP_FOWNER -cap CAP_FSETID -cap CAP_KILL -cap CAP_SETGID -cap CAP_SETUID -cap CAP_SETPCAP -cap CAP_LINUX_IMMUTABLE -cap CAP_NET_BIND_SERVICE -cap CAP_NET_BROADCAST -cap CAP_NET_ADMIN -cap CAP_NET_RAW -cap CAP_IPC_LOCK -cap CAP_IPC_OWNER -cap CAP_SYS_MODULE -cap CAP_SYS_RAWIO -cap CAP_SYS_CHROOT -cap CAP_SYS_PTRACE -cap CAP_SYS_PACCT -cap CAP_SYS_ADMIN -cap CAP_SYS_BOOT -cap CAP_SYS_NICE -cap CAP_SYS_RESOURCE -cap CAP_SYS_TIME -cap CAP_SYS_TTY_CONFIG -cap CAP_MKNOD -cap CAP_LEASE -cap CAP_AUDIT_WRITE -cap CAP_AUDIT_CONTROL -cap CAP_SETFCAP -cap CAP_MAC_OVERRIDE -cap CAP_MAC_ADMIN -cap CAP_SYSLOG -cap CAP_WAKE_ALARM -cap CAP_BLOCK_SUSPEND -cap CAP_AUDIT_READ ci-gvisor-ptrace-2-race-0 /syz-execprog -executor=/syz-executor -arch=amd64 -sandbox=none -procs=1 -repeat=1 -threaded=true -collide=true -cover=0 -fault_call=-1 -fault_nth=-1 /syzkaller188243381] I1217 03:52:42.758041 49317 main.go:211] Version release-20201208.0-60-gb645fcd241a8 I1217 03:52:42.758076 49317 main.go:212] GOOS: linux I1217 03:52:42.758108 49317 main.go:213] GOARCH: amd64 I1217 03:52:42.758151 49317 main.go:214] PID: 49317 I1217 03:52:42.758190 49317 main.go:215] UID: 0, GID: 0 I1217 03:52:42.758254 49317 main.go:216] Configuration: I1217 03:52:42.758327 49317 main.go:217] RootDir: /syzkaller/managers/ptrace-2-race/workdir/gvisor_root I1217 03:52:42.758379 49317 main.go:218] Platform: ptrace I1217 03:52:42.758450 49317 main.go:219] FileAccess: 0, overlay: false I1217 03:52:42.758516 49317 main.go:220] Network: 0, logging: false I1217 03:52:42.758575 49317 main.go:221] Strace: false, max size: 1024, syscalls: I1217 03:52:42.758608 49317 main.go:222] VFS2 enabled: true I1217 03:52:42.758637 49317 main.go:223] *************************** W1217 03:52:42.758689 49317 main.go:228] Block the TERM signal. This is only safe in tests! D1217 03:52:42.758961 49317 container.go:164] Load container, rootDir: "/syzkaller/managers/ptrace-2-race/workdir/gvisor_root", partial cid: ci-gvisor-ptrace-2-race-0 D1217 03:52:42.768611 49317 container.go:652] Signal container, cid: ci-gvisor-ptrace-2-race-0, signal: signal 0 (0) D1217 03:52:42.768719 49317 sandbox.go:861] Signal sandbox "ci-gvisor-ptrace-2-race-0" D1217 03:52:42.768830 49317 sandbox.go:349] Connecting to sandbox "ci-gvisor-ptrace-2-race-0" D1217 03:52:42.769321 49317 urpc.go:534] urpc: successfully marshalled 105 bytes. D1217 03:52:42.769587 46940 urpc.go:577] urpc: unmarshal success. D1217 03:52:42.770064 46940 controller.go:574] containerManager.Signal: cid: ci-gvisor-ptrace-2-race-0, PID: 0, signal: 0, mode: Process D1217 03:52:42.770458 46940 urpc.go:534] urpc: successfully marshalled 37 bytes. D1217 03:52:42.770680 49317 urpc.go:577] urpc: unmarshal success. D1217 03:52:42.770814 49317 exec.go:120] Exec arguments: /syz-execprog -executor=/syz-executor -arch=amd64 -sandbox=none -procs=1 -repeat=1 -threaded=true -collide=true -cover=0 -fault_call=-1 -fault_nth=-1 /syzkaller188243381 D1217 03:52:42.770909 49317 exec.go:121] Exec capablities: &{PermittedCaps:274877906943 InheritableCaps:274877906943 EffectiveCaps:274877906943 BoundingCaps:274877906943 AmbientCaps:0} D1217 03:52:42.771060 49317 container.go:588] Execute in container, cid: ci-gvisor-ptrace-2-race-0, args: /syz-execprog -executor=/syz-executor -arch=amd64 -sandbox=none -procs=1 -repeat=1 -threaded=true -collide=true -cover=0 -fault_call=-1 -fault_nth=-1 /syzkaller188243381 D1217 03:52:42.771146 49317 sandbox.go:314] Executing new process in container "ci-gvisor-ptrace-2-race-0" in sandbox "ci-gvisor-ptrace-2-race-0" D1217 03:52:42.771205 49317 sandbox.go:349] Connecting to sandbox "ci-gvisor-ptrace-2-race-0" D1217 03:52:42.772027 49317 urpc.go:534] urpc: successfully marshalled 622 bytes. D1217 03:52:42.772438 46940 urpc.go:577] urpc: unmarshal success. D1217 03:52:42.773635 46940 controller.go:330] containerManager.ExecuteAsync, cid: ci-gvisor-ptrace-2-race-0, args: /syz-execprog -executor=/syz-executor -arch=amd64 -sandbox=none -procs=1 -repeat=1 -threaded=true -collide=true -cover=0 -fault_call=-1 -fault_nth=-1 /syzkaller188243381 I1217 03:52:42.774266 46940 kernel.go:912] EXEC: [/syz-execprog -executor=/syz-executor -arch=amd64 -sandbox=none -procs=1 -repeat=1 -threaded=true -collide=true -cover=0 -fault_call=-1 -fault_nth=-1 /syzkaller188243381] D1217 03:52:42.774954 46940 transport_flipcall.go:127] send [channel @0xc000282240] Twalkgetattr{FID: 1, NewFID: 6, Names: [syz-execprog]} D1217 03:52:42.775353 1 transport_flipcall.go:234] recv [channel @0xc000480240] Twalkgetattr{FID: 1, NewFID: 6, Names: [syz-execprog]} D1217 03:52:42.775947 1 transport_flipcall.go:127] send [channel @0xc000480240] Rwalkgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, Attr: Attr{Mode: 0o100755, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 19378176, BlockSize: 4096, Blocks: 37848, ATime: {Sec: 1608144324, NanoSec: 843923176}, MTime: {Sec: 1608144324, NanoSec: 843923176}, CTime: {Sec: 1608177133, NanoSec: 290792681}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 4328266}]} D1217 03:52:42.776315 46940 transport_flipcall.go:234] recv [channel @0xc000282240] Rwalkgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, Attr: Attr{Mode: 0o100755, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 19378176, BlockSize: 4096, Blocks: 37848, ATime: {Sec: 1608144324, NanoSec: 843923176}, MTime: {Sec: 1608144324, NanoSec: 843923176}, CTime: {Sec: 1608177133, NanoSec: 290792681}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 4328266}]} D1217 03:52:42.776574 46940 transport_flipcall.go:127] send [channel @0xc000282240] Twalk{FID: 6, NewFID: 7, Names: []} D1217 03:52:42.776729 1 transport_flipcall.go:234] recv [channel @0xc000480240] Twalk{FID: 6, NewFID: 7, Names: []} D1217 03:52:42.776931 1 transport_flipcall.go:127] send [channel @0xc000480240] Rwalk{QIDs: []} D1217 03:52:42.777046 46940 transport_flipcall.go:234] recv [channel @0xc000282240] Rwalk{QIDs: []} D1217 03:52:42.777184 46940 transport_flipcall.go:127] send [channel @0xc000282240] Tlopen{FID: 7, Flags: ReadOnly} D1217 03:52:42.777361 1 transport_flipcall.go:234] recv [channel @0xc000480240] Tlopen{FID: 7, Flags: ReadOnly} D1217 03:52:42.777502 1 fsgofer.go:395] Open reusing control file, flags: ReadOnly, "//syz-execprog" D1217 03:52:42.777663 1 transport_flipcall.go:127] send [channel @0xc000480240] Rlopen{QID: QID{Type: 0, Version: 0, Path: 4328266}, IoUnit: 0, File: FD: 32} D1217 03:52:42.777951 46940 transport_flipcall.go:234] recv [channel @0xc000282240] Rlopen{QID: QID{Type: 0, Version: 0, Path: 4328266}, IoUnit: 0, File: FD: 34} D1217 03:52:42.779925 46940 syscalls.go:257] Allocating stack with size of 8388608 bytes D1217 03:52:42.781252 46940 loader.go:973] updated processes: map[{ci-gvisor-ptrace-2-race-0 0}:0xc0001940c0 {ci-gvisor-ptrace-2-race-0 12}:0xc00072b380] D1217 03:52:42.781537 46940 urpc.go:534] urpc: successfully marshalled 37 bytes. D1217 03:52:42.781674 49317 urpc.go:577] urpc: unmarshal success. D1217 03:52:42.781766 49317 container.go:640] Wait on process 12 in container, cid: ci-gvisor-ptrace-2-race-0 D1217 03:52:42.781860 49317 sandbox.go:816] Waiting for PID 12 in sandbox "ci-gvisor-ptrace-2-race-0" D1217 03:52:42.781930 49317 sandbox.go:349] Connecting to sandbox "ci-gvisor-ptrace-2-race-0" D1217 03:52:42.782228 49317 urpc.go:534] urpc: successfully marshalled 88 bytes. D1217 03:52:42.782444 46940 urpc.go:577] urpc: unmarshal success. D1217 03:52:42.782744 46940 controller.go:514] containerManager.Wait, cid: ci-gvisor-ptrace-2-race-0, pid: 12 D1217 03:52:43.009087 46940 task_signals.go:467] [ 12] Notified of signal 23 D1217 03:52:43.009331 46940 task_signals.go:179] [ 12] Restarting syscall 202 after errno 512: interrupted by signal 23 D1217 03:52:43.009486 46940 task_signals.go:220] [ 12] Signal 23: delivering to handler D1217 03:52:43.020900 46940 task_signals.go:467] [ 12] Notified of signal 23 D1217 03:52:43.021366 46940 task_signals.go:179] [ 12] Restarting syscall 202 after errno 512: interrupted by signal 23 D1217 03:52:43.021511 46940 task_signals.go:220] [ 12] Signal 23: delivering to handler D1217 03:52:43.211269 46940 task_signals.go:467] [ 12] Notified of signal 23 D1217 03:52:43.211596 46940 task_signals.go:220] [ 12] Signal 23: delivering to handler D1217 03:52:43.223232 46940 task_signals.go:467] [ 16] Notified of signal 23 D1217 03:52:43.227066 46940 task_signals.go:467] [ 15] Notified of signal 23 D1217 03:52:43.228111 46940 task_signals.go:220] [ 16] Signal 23: delivering to handler D1217 03:52:43.228456 46940 task_signals.go:220] [ 15] Signal 23: delivering to handler D1217 03:52:43.237483 46940 task_signals.go:467] [ 18] Notified of signal 23 D1217 03:52:43.237962 46940 task_signals.go:467] [ 15] Notified of signal 23 D1217 03:52:43.238247 46940 task_signals.go:467] [ 20] Notified of signal 23 D1217 03:52:43.238508 46940 task_signals.go:220] [ 15] Signal 23: delivering to handler D1217 03:52:43.239477 46940 task_signals.go:220] [ 18] Signal 23: delivering to handler D1217 03:52:43.239505 46940 task_signals.go:179] [ 20] Restarting syscall 202 after errno 512: interrupted by signal 23 D1217 03:52:43.239846 46940 task_signals.go:220] [ 20] Signal 23: delivering to handler D1217 03:52:43.243032 46940 task_signals.go:467] [ 18] Notified of signal 23 D1217 03:52:43.243793 46940 task_signals.go:220] [ 18] Signal 23: delivering to handler D1217 03:52:43.245043 46940 task_signals.go:467] [ 18] Notified of signal 23 D1217 03:52:43.245667 46940 task_signals.go:179] [ 18] Restarting syscall 202 after errno 512: interrupted by signal 23 D1217 03:52:43.245806 46940 task_signals.go:220] [ 18] Signal 23: delivering to handler D1217 03:52:43.251476 46940 task_signals.go:467] [ 15] Notified of signal 23 D1217 03:52:43.252561 46940 task_signals.go:220] [ 15] Signal 23: delivering to handler D1217 03:52:43.253431 46940 task_signals.go:467] [ 18] Notified of signal 23 D1217 03:52:43.253667 46940 task_signals.go:179] [ 18] Restarting syscall 202 after errno 512: interrupted by signal 23 D1217 03:52:43.253790 46940 task_signals.go:220] [ 18] Signal 23: delivering to handler D1217 03:52:43.280249 46940 task_signals.go:467] [ 18] Notified of signal 23 D1217 03:52:43.280576 46940 task_signals.go:220] [ 18] Signal 23: delivering to handler D1217 03:52:43.292757 46940 task_signals.go:467] [ 18] Notified of signal 23 D1217 03:52:43.293115 46940 task_signals.go:179] [ 18] Restarting syscall 202 after errno 512: interrupted by signal 23 D1217 03:52:43.293322 46940 task_signals.go:220] [ 18] Signal 23: delivering to handler D1217 03:52:43.293679 46940 task_signals.go:467] [ 12] Notified of signal 23 D1217 03:52:43.293888 46940 task_signals.go:467] [ 15] Notified of signal 23 D1217 03:52:43.294033 46940 task_signals.go:220] [ 12] Signal 23: delivering to handler D1217 03:52:43.294079 46940 task_signals.go:179] [ 15] Restarting syscall 202 after errno 512: interrupted by signal 23 D1217 03:52:43.294179 46940 task_signals.go:220] [ 15] Signal 23: delivering to handler D1217 03:52:43.294505 46940 task_signals.go:467] [ 18] Notified of signal 23 D1217 03:52:43.294760 46940 task_signals.go:220] [ 18] Signal 23: delivering to handler D1217 03:52:43.295375 46940 task_signals.go:467] [ 18] Notified of signal 23 D1217 03:52:43.295786 46940 task_signals.go:467] [ 12] Notified of signal 23 D1217 03:52:43.295997 46940 task_signals.go:220] [ 18] Signal 23: delivering to handler D1217 03:52:43.296177 46940 task_signals.go:179] [ 12] Restarting syscall 202 after errno 512: interrupted by signal 23 D1217 03:52:43.296356 46940 task_signals.go:220] [ 12] Signal 23: delivering to handler D1217 03:52:43.296982 46940 task_signals.go:467] [ 17] Notified of signal 23 D1217 03:52:43.297198 46940 task_signals.go:179] [ 17] Restarting syscall 202 after errno 512: interrupted by signal 23 D1217 03:52:43.297309 46940 task_signals.go:220] [ 17] Signal 23: delivering to handler D1217 03:52:43.298319 46940 task_signals.go:467] [ 17] Notified of signal 23 D1217 03:52:43.298673 46940 task_signals.go:179] [ 17] Restarting syscall 202 after errno 512: interrupted by signal 23 D1217 03:52:43.298794 46940 task_signals.go:220] [ 17] Signal 23: delivering to handler D1217 03:52:43.300244 46940 task_signals.go:467] [ 17] Notified of signal 23 D1217 03:52:43.300624 46940 task_signals.go:179] [ 17] Restarting syscall 202 after errno 512: interrupted by signal 23 D1217 03:52:43.300748 46940 task_signals.go:220] [ 17] Signal 23: delivering to handler D1217 03:52:43.312973 46940 task_signals.go:467] [ 17] Notified of signal 23 D1217 03:52:43.313498 46940 task_signals.go:220] [ 17] Signal 23: delivering to handler D1217 03:52:43.329015 46940 task_signals.go:467] [ 17] Notified of signal 23 D1217 03:52:43.329323 46940 task_signals.go:220] [ 17] Signal 23: delivering to handler D1217 03:52:43.333757 46940 task_signals.go:467] [ 18] Notified of signal 23 D1217 03:52:43.333950 46940 task_signals.go:467] [ 21] Notified of signal 23 D1217 03:52:43.334189 46940 task_signals.go:220] [ 21] Signal 23: delivering to handler D1217 03:52:43.334226 46940 task_signals.go:220] [ 18] Signal 23: delivering to handler D1217 03:52:43.334861 46940 task_signals.go:467] [ 17] Notified of signal 23 D1217 03:52:43.335367 46940 task_signals.go:467] [ 18] Notified of signal 23 D1217 03:52:43.335599 46940 task_signals.go:467] [ 12] Notified of signal 23 D1217 03:52:43.335791 46940 task_signals.go:220] [ 17] Signal 23: delivering to handler D1217 03:52:43.335900 46940 task_signals.go:220] [ 12] Signal 23: delivering to handler D1217 03:52:43.336002 46940 task_signals.go:467] [ 21] Notified of signal 23 D1217 03:52:43.336227 46940 task_signals.go:467] [ 19] Notified of signal 23 D1217 03:52:43.336475 46940 task_signals.go:179] [ 19] Restarting syscall 202 after errno 512: interrupted by signal 23 D1217 03:52:43.336639 46940 task_signals.go:220] [ 19] Signal 23: delivering to handler D1217 03:52:43.336682 46940 task_signals.go:220] [ 18] Signal 23: delivering to handler D1217 03:52:43.336781 46940 task_signals.go:467] [ 15] Notified of signal 23 D1217 03:52:43.337093 46940 task_signals.go:220] [ 15] Signal 23: delivering to handler D1217 03:52:43.337374 46940 task_signals.go:220] [ 21] Signal 23: delivering to handler D1217 03:52:43.338182 46940 task_signals.go:467] [ 18] Notified of signal 23 D1217 03:52:43.338368 46940 task_signals.go:467] [ 22] Notified of signal 23 D1217 03:52:43.338635 46940 task_signals.go:220] [ 22] Signal 23: delivering to handler D1217 03:52:43.338666 46940 task_signals.go:467] [ 15] Notified of signal 23 D1217 03:52:43.338856 46940 task_signals.go:220] [ 18] Signal 23: delivering to handler D1217 03:52:43.338910 46940 task_signals.go:179] [ 15] Restarting syscall 202 after errno 512: interrupted by signal 23 D1217 03:52:43.339023 46940 task_signals.go:220] [ 15] Signal 23: delivering to handler D1217 03:52:43.339642 46940 task_signals.go:467] [ 22] Notified of signal 23 D1217 03:52:43.339865 46940 task_signals.go:179] [ 22] Restarting syscall 202 after errno 512: interrupted by signal 23 D1217 03:52:43.339998 46940 task_signals.go:220] [ 22] Signal 23: delivering to handler D1217 03:52:43.341043 46940 task_signals.go:467] [ 22] Notified of signal 23 D1217 03:52:43.341334 46940 task_signals.go:179] [ 22] Restarting syscall 202 after errno 512: interrupted by signal 23 D1217 03:52:43.341473 46940 task_signals.go:220] [ 22] Signal 23: delivering to handler D1217 03:52:43.342487 46940 task_signals.go:467] [ 22] Notified of signal 23 D1217 03:52:43.342622 46940 task_signals.go:179] [ 22] Restarting syscall 202 after errno 512: interrupted by signal 23 D1217 03:52:43.342716 46940 task_signals.go:220] [ 22] Signal 23: delivering to handler D1217 03:52:43.364269 46940 task_signals.go:467] [ 22] Notified of signal 23 D1217 03:52:43.364533 46940 task_signals.go:220] [ 22] Signal 23: delivering to handler D1217 03:52:43.374975 46940 task_signals.go:467] [ 22] Notified of signal 23 D1217 03:52:43.375778 46940 task_signals.go:220] [ 22] Signal 23: delivering to handler D1217 03:52:43.375875 46940 task_signals.go:467] [ 15] Notified of signal 23 D1217 03:52:43.376091 46940 task_signals.go:467] [ 14] Notified of signal 23 D1217 03:52:43.376312 46940 task_signals.go:467] [ 20] Notified of signal 23 D1217 03:52:43.376508 46940 task_signals.go:220] [ 15] Signal 23: delivering to handler D1217 03:52:43.376574 46940 task_signals.go:179] [ 20] Restarting syscall 202 after errno 512: interrupted by signal 23 D1217 03:52:43.376740 46940 task_signals.go:220] [ 20] Signal 23: delivering to handler D1217 03:52:43.376804 46940 task_signals.go:220] [ 14] Signal 23: delivering to handler D1217 03:52:43.376940 46940 task_signals.go:467] [ 21] Notified of signal 23 D1217 03:52:43.377643 46940 task_signals.go:467] [ 17] Notified of signal 23 D1217 03:52:43.377821 46940 task_signals.go:220] [ 21] Signal 23: delivering to handler D1217 03:52:43.378190 46940 task_signals.go:179] [ 17] Restarting syscall 202 after errno 512: interrupted by signal 23 D1217 03:52:43.378379 46940 task_signals.go:220] [ 17] Signal 23: delivering to handler D1217 03:52:43.378719 46940 task_signals.go:467] [ 15] Notified of signal 23 D1217 03:52:43.378936 46940 task_signals.go:220] [ 15] Signal 23: delivering to handler D1217 03:52:43.380793 46940 task_signals.go:467] [ 15] Notified of signal 23 D1217 03:52:43.381065 46940 task_signals.go:179] [ 15] Restarting syscall 202 after errno 512: interrupted by signal 23 D1217 03:52:43.381183 46940 task_signals.go:220] [ 15] Signal 23: delivering to handler D1217 03:52:43.382432 46940 task_signals.go:467] [ 15] Notified of signal 23 D1217 03:52:43.382881 46940 task_signals.go:179] [ 15] Restarting syscall 202 after errno 512: interrupted by signal 23 D1217 03:52:43.382997 46940 task_signals.go:220] [ 15] Signal 23: delivering to handler D1217 03:52:43.404391 46940 transport_flipcall.go:127] send [channel @0xc000282240] Twalkgetattr{FID: 1, NewFID: 8, Names: [syzkaller188243381]} D1217 03:52:43.404730 1 transport_flipcall.go:234] recv [channel @0xc000480240] Twalkgetattr{FID: 1, NewFID: 8, Names: [syzkaller188243381]} D1217 03:52:43.405210 1 transport_flipcall.go:127] send [channel @0xc000480240] Rwalkgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, Attr: Attr{Mode: 0o100600, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 390, BlockSize: 4096, Blocks: 8, ATime: {Sec: 1608177162, NanoSec: 709543165}, MTime: {Sec: 1608177162, NanoSec: 709543165}, CTime: {Sec: 1608177162, NanoSec: 709543165}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 4328274}]} D1217 03:52:43.406196 46940 transport_flipcall.go:234] recv [channel @0xc000282240] Rwalkgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, Attr: Attr{Mode: 0o100600, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 390, BlockSize: 4096, Blocks: 8, ATime: {Sec: 1608177162, NanoSec: 709543165}, MTime: {Sec: 1608177162, NanoSec: 709543165}, CTime: {Sec: 1608177162, NanoSec: 709543165}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 4328274}]} D1217 03:52:43.407170 46940 transport_flipcall.go:127] send [channel @0xc000282240] Twalk{FID: 8, NewFID: 9, Names: []} D1217 03:52:43.407404 1 transport_flipcall.go:234] recv [channel @0xc000480240] Twalk{FID: 8, NewFID: 9, Names: []} D1217 03:52:43.407609 1 transport_flipcall.go:127] send [channel @0xc000480240] Rwalk{QIDs: []} D1217 03:52:43.407814 46940 transport_flipcall.go:234] recv [channel @0xc000282240] Rwalk{QIDs: []} D1217 03:52:43.408026 46940 transport_flipcall.go:127] send [channel @0xc000282240] Tlopen{FID: 9, Flags: ReadOnly} D1217 03:52:43.408255 1 transport_flipcall.go:234] recv [channel @0xc000480240] Tlopen{FID: 9, Flags: ReadOnly} D1217 03:52:43.408374 1 fsgofer.go:395] Open reusing control file, flags: ReadOnly, "//syzkaller188243381" D1217 03:52:43.408574 1 transport_flipcall.go:127] send [channel @0xc000480240] Rlopen{QID: QID{Type: 0, Version: 0, Path: 4328274}, IoUnit: 0, File: FD: 34} D1217 03:52:43.410164 46940 transport_flipcall.go:234] recv [channel @0xc000282240] Rlopen{QID: QID{Type: 0, Version: 0, Path: 4328274}, IoUnit: 0, File: FD: 28} D1217 03:52:43.410994 46940 task_signals.go:467] [ 15] Notified of signal 23 D1217 03:52:43.411238 46940 task_signals.go:220] [ 15] Signal 23: delivering to handler 2020/12/17 03:52:43 parsed 1 programs D1217 03:52:43.422474 46940 task_signals.go:467] [ 15] Notified of signal 23 D1217 03:52:43.422654 46940 task_signals.go:220] [ 15] Signal 23: delivering to handler D1217 03:52:43.429845 46940 task_stop.go:119] [ 15] Entering internal stop (*kernel.vforkStop)(nil) D1217 03:52:43.435063 46940 task_signals.go:478] [ 15] No task notified of signal 23 D1217 03:52:43.440576 46940 transport_flipcall.go:127] send [channel @0xc000282240] Twalkgetattr{FID: 1, NewFID: 10, Names: [syz-executor]} D1217 03:52:43.440882 1 transport_flipcall.go:234] recv [channel @0xc000480240] Twalkgetattr{FID: 1, NewFID: 10, Names: [syz-executor]} D1217 03:52:43.441276 1 transport_flipcall.go:127] send [channel @0xc000480240] Rwalkgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, Attr: Attr{Mode: 0o100755, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 1773192, BlockSize: 4096, Blocks: 3464, ATime: {Sec: 1608144326, NanoSec: 56036483}, MTime: {Sec: 1608144326, NanoSec: 56036483}, CTime: {Sec: 1608177133, NanoSec: 294793056}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 4328267}]} D1217 03:52:43.441619 46940 transport_flipcall.go:234] recv [channel @0xc000282240] Rwalkgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, Attr: Attr{Mode: 0o100755, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 1773192, BlockSize: 4096, Blocks: 3464, ATime: {Sec: 1608144326, NanoSec: 56036483}, MTime: {Sec: 1608144326, NanoSec: 56036483}, CTime: {Sec: 1608177133, NanoSec: 294793056}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 4328267}]} D1217 03:52:43.441891 46940 transport_flipcall.go:127] send [channel @0xc000282240] Twalk{FID: 10, NewFID: 11, Names: []} D1217 03:52:43.442064 1 transport_flipcall.go:234] recv [channel @0xc000480240] Twalk{FID: 10, NewFID: 11, Names: []} D1217 03:52:43.442228 1 transport_flipcall.go:127] send [channel @0xc000480240] Rwalk{QIDs: []} D1217 03:52:43.442386 46940 transport_flipcall.go:234] recv [channel @0xc000282240] Rwalk{QIDs: []} D1217 03:52:43.442524 46940 transport_flipcall.go:127] send [channel @0xc000282240] Tlopen{FID: 11, Flags: ReadOnly} D1217 03:52:43.442767 1 transport_flipcall.go:234] recv [channel @0xc000480240] Tlopen{FID: 11, Flags: ReadOnly} D1217 03:52:43.442900 1 fsgofer.go:395] Open reusing control file, flags: ReadOnly, "//syz-executor" D1217 03:52:43.443073 1 transport_flipcall.go:127] send [channel @0xc000480240] Rlopen{QID: QID{Type: 0, Version: 0, Path: 4328267}, IoUnit: 0, File: FD: 36} D1217 03:52:43.443414 46940 transport_flipcall.go:234] recv [channel @0xc000282240] Rlopen{QID: QID{Type: 0, Version: 0, Path: 4328267}, IoUnit: 0, File: FD: 29} D1217 03:52:43.444811 46940 syscalls.go:257] [ 24] Allocating stack with size of 8388608 bytes D1217 03:52:43.446246 46940 task_stop.go:139] [ 15] Leaving internal stop (*kernel.vforkStop)(nil) D1217 03:52:43.447376 46940 task_signals.go:220] [ 15] Signal 23: delivering to handler D1217 03:52:43.481001 46940 task_exit.go:221] [ 24] Transitioning from exit state TaskExitNone to TaskExitInitiated D1217 03:52:43.482176 46940 task_exit.go:221] [ 24] Transitioning from exit state TaskExitInitiated to TaskExitZombie D1217 03:52:43.482378 46940 task_signals.go:467] [ 12] Notified of signal 17 D1217 03:52:43.482634 46940 task_signals.go:179] [ 12] Restarting syscall 202 after errno 512: interrupted by signal 17 D1217 03:52:43.482790 46940 task_signals.go:220] [ 12] Signal 17: delivering to handler D1217 03:52:43.482871 46940 task_exit.go:221] [ 24] Transitioning from exit state TaskExitZombie to TaskExitDead 2020/12/17 03:52:43 executed programs: 0 D1217 03:52:43.497533 46940 task_signals.go:467] [ 18] Notified of signal 23 D1217 03:52:43.498803 46940 task_stop.go:119] [ 18] Entering internal stop (*kernel.vforkStop)(nil) D1217 03:52:43.509290 46940 syscalls.go:257] [ 26] Allocating stack with size of 8388608 bytes D1217 03:52:43.511562 46940 task_stop.go:139] [ 18] Leaving internal stop (*kernel.vforkStop)(nil) D1217 03:52:43.512843 46940 task_signals.go:220] [ 18] Signal 23: delivering to handler D1217 03:52:43.551999 46940 transport_flipcall.go:127] send [channel @0xc000282240] Twalkgetattr{FID: 1, NewFID: 12, Names: [syzcgroup]} D1217 03:52:43.552343 1 transport_flipcall.go:234] recv [channel @0xc000480240] Twalkgetattr{FID: 1, NewFID: 12, Names: [syzcgroup]} D1217 03:52:43.552578 1 transport_flipcall.go:127] send [channel @0xc000480240] Rlerror{Error: 2} D1217 03:52:43.552702 46940 transport_flipcall.go:234] recv [channel @0xc000282240] Rlerror{Error: 2} D1217 03:52:43.878992 46940 task_signals.go:467] [ 18] Notified of signal 23 D1217 03:52:43.879212 46940 task_signals.go:220] [ 18] Signal 23: delivering to handler D1217 03:52:43.941559 46940 task_stop.go:119] [ 30] Entering internal stop (*kernel.vforkStop)(nil) D1217 03:52:44.161142 46940 task_stop.go:119] [ 31] Entering internal stop (*kernel.vforkStop)(nil) ================== WARNING: DATA RACE Read at 0x00c0006ec0c9 by goroutine 461: gvisor.dev/gvisor/pkg/tcpip/transport/raw.(*endpoint).HandlePacket() pkg/tcpip/transport/raw/endpoint.go:664 +0x2a9 gvisor.dev/gvisor/pkg/tcpip/stack.(*transportDemuxer).deliverRawPacket() pkg/tcpip/stack/transport_demuxer.go:590 +0x875 gvisor.dev/gvisor/pkg/tcpip/stack.(*NIC).DeliverTransportPacket() pkg/tcpip/stack/nic.go:758 +0x174 gvisor.dev/gvisor/pkg/tcpip/network/ipv6.(*endpoint).handlePacket() pkg/tcpip/network/ipv6/ipv6.go:1133 +0xafa gvisor.dev/gvisor/pkg/tcpip/network/ipv6.(*endpoint).HandlePacket() pkg/tcpip/network/ipv6/ipv6.go:782 +0x1c4 gvisor.dev/gvisor/pkg/tcpip/stack.(*NIC).DeliverNetworkPacket() pkg/tcpip/stack/nic.go:722 +0x527 gvisor.dev/gvisor/pkg/tcpip/link/channel.(*Endpoint).InjectLinkAddr() pkg/tcpip/link/channel/channel.go:190 +0x5d9 gvisor.dev/gvisor/pkg/tcpip/link/tun.(*Device).Write() pkg/tcpip/link/tun/device.go:223 +0x2d6 gvisor.dev/gvisor/pkg/sentry/devices/tundev.(*tunFD).Write() pkg/sentry/devices/tundev/tundev.go:146 +0x22a gvisor.dev/gvisor/pkg/sentry/vfs.(*FileDescription).Write() pkg/sentry/vfs/file_description.go:630 +0x130 gvisor.dev/gvisor/pkg/sentry/syscalls/linux/vfs2.write() pkg/sentry/syscalls/linux/vfs2/read_write.go:364 +0xed gvisor.dev/gvisor/pkg/sentry/syscalls/linux/vfs2.Write() pkg/sentry/syscalls/linux/vfs2/read_write.go:333 +0x2a4 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).executeSyscall() pkg/sentry/kernel/task_syscall.go:104 +0x452 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallInvoke() pkg/sentry/kernel/task_syscall.go:239 +0xb9 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallEnter() pkg/sentry/kernel/task_syscall.go:199 +0x10e gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscall() pkg/sentry/kernel/task_syscall.go:174 +0x1e9 gvisor.dev/gvisor/pkg/sentry/kernel.(*runApp).execute() pkg/sentry/kernel/task_run.go:282 +0x12a6 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run() pkg/sentry/kernel/task_run.go:97 +0x397 Previous write at 0x00c0006ec0c9 by goroutine 235: gvisor.dev/gvisor/pkg/tcpip/transport/raw.(*endpoint).Connect() pkg/tcpip/transport/raw/endpoint.go:451 +0x2ab gvisor.dev/gvisor/pkg/sentry/socket/netstack.(*socketOpsCommon).Connect() pkg/sentry/socket/netstack/netstack.go:701 +0x52c gvisor.dev/gvisor/pkg/sentry/socket/netstack.(*SocketVFS2).Connect() :1 +0x84 gvisor.dev/gvisor/pkg/sentry/syscalls/linux/vfs2.Connect() pkg/sentry/syscalls/linux/vfs2/socket.go:278 +0x256 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).executeSyscall() pkg/sentry/kernel/task_syscall.go:104 +0x452 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallInvoke() pkg/sentry/kernel/task_syscall.go:239 +0xb9 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallEnter() pkg/sentry/kernel/task_syscall.go:199 +0x10e gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscall() pkg/sentry/kernel/task_syscall.go:174 +0x1e9 gvisor.dev/gvisor/pkg/sentry/kernel.(*runApp).execute() pkg/sentry/kernel/task_run.go:282 +0x12a6 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run() pkg/sentry/kernel/task_run.go:97 +0x397 Goroutine 461 (running) created at: gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).Start() pkg/sentry/kernel/task_start.go:323 +0x1a4 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).Clone() pkg/sentry/kernel/task_clone.go:381 +0x167f gvisor.dev/gvisor/pkg/sentry/syscalls/linux.clone() pkg/sentry/syscalls/linux/sys_thread.go:227 +0x179 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Clone() pkg/sentry/syscalls/linux/sys_clone_amd64.go:34 +0x84 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).executeSyscall() pkg/sentry/kernel/task_syscall.go:104 +0x452 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallInvoke() pkg/sentry/kernel/task_syscall.go:239 +0xb9 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallEnter() pkg/sentry/kernel/task_syscall.go:199 +0x10e gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscall() pkg/sentry/kernel/task_syscall.go:174 +0x1e9 gvisor.dev/gvisor/pkg/sentry/kernel.(*runApp).execute() pkg/sentry/kernel/task_run.go:282 +0x12a6 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run() pkg/sentry/kernel/task_run.go:97 +0x397 Goroutine 235 (running) created at: gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).Start() pkg/sentry/kernel/task_start.go:323 +0x1a4 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).Clone() pkg/sentry/kernel/task_clone.go:379 +0x161f gvisor.dev/gvisor/pkg/sentry/syscalls/linux.clone() pkg/sentry/syscalls/linux/sys_thread.go:227 +0x179 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Clone() pkg/sentry/syscalls/linux/sys_clone_amd64.go:34 +0x84 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).executeSyscall() pkg/sentry/kernel/task_syscall.go:104 +0x452 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallInvoke() pkg/sentry/kernel/task_syscall.go:239 +0xb9 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallEnter() pkg/sentry/kernel/task_syscall.go:199 +0x10e gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscall() pkg/sentry/kernel/task_syscall.go:174 +0x1e9 gvisor.dev/gvisor/pkg/sentry/kernel.(*runApp).execute() pkg/sentry/kernel/task_run.go:282 +0x12a6 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run() pkg/sentry/kernel/task_run.go:97 +0x397 ================== W1217 03:52:44.310761 49317 error.go:48] FATAL ERROR: waiting on pid 12: waiting on PID 12 in sandbox "ci-gvisor-ptrace-2-race-0": urpc method "containerManager.WaitPID" failed: EOF waiting on pid 12: waiting on PID 12 in sandbox "ci-gvisor-ptrace-2-race-0": urpc method "containerManager.WaitPID" failed: EOF W1217 03:52:44.311256 49317 main.go:245] Failure to execute command, err: 1 D1217 03:52:44.320977 1 server.go:535] p9.recv: EOF W1217 03:52:44.321056 46930 sandbox.go:795] Wait RPC to container "ci-gvisor-ptrace-2-race-0" failed: urpc method "containerManager.Wait" failed: EOF. Will try waiting on the sandbox process instead. I1217 03:52:44.322448 1 gofer.go:231] All 9P servers exited. I1217 03:52:44.322559 1 main.go:236] Exiting with status: 0 D1217 03:52:44.322671 46930 container.go:768] Destroy container, cid: ci-gvisor-ptrace-2-race-0 D1217 03:52:44.322829 46930 container.go:853] Destroying container, cid: ci-gvisor-ptrace-2-race-0 D1217 03:52:44.322908 46930 sandbox.go:1153] Destroying root container by destroying sandbox, cid: ci-gvisor-ptrace-2-race-0 D1217 03:52:44.322956 46930 sandbox.go:843] Destroy sandbox "ci-gvisor-ptrace-2-race-0" D1217 03:52:44.323036 46930 container.go:867] Killing gofer for container, cid: ci-gvisor-ptrace-2-race-0, PID: 46939 I1217 03:52:44.527781 46930 main.go:236] Exiting with status: 16896 VM DIAGNOSIS: I1217 03:52:44.248373 49540 main.go:209] *************************** I1217 03:52:44.248455 49540 main.go:210] Args: [/syzkaller/managers/ptrace-2-race/current/image -root /syzkaller/managers/ptrace-2-race/workdir/gvisor_root -watchdog-action=panic -network=none -debug -debug-log=/dev/stderr -platform=ptrace -file-access=exclusive -network=sandbox -net-raw -watchdog-action=panic -vfs2 -fuse -TESTONLY-unsafe-nonroot debug -stacks --ps ci-gvisor-ptrace-2-race-0] I1217 03:52:44.248622 49540 main.go:211] Version release-20201208.0-60-gb645fcd241a8 I1217 03:52:44.248688 49540 main.go:212] GOOS: linux I1217 03:52:44.248729 49540 main.go:213] GOARCH: amd64 I1217 03:52:44.248771 49540 main.go:214] PID: 49540 I1217 03:52:44.248815 49540 main.go:215] UID: 0, GID: 0 I1217 03:52:44.248901 49540 main.go:216] Configuration: I1217 03:52:44.248938 49540 main.go:217] RootDir: /syzkaller/managers/ptrace-2-race/workdir/gvisor_root I1217 03:52:44.249004 49540 main.go:218] Platform: ptrace I1217 03:52:44.249044 49540 main.go:219] FileAccess: 0, overlay: false I1217 03:52:44.249116 49540 main.go:220] Network: 0, logging: false I1217 03:52:44.249205 49540 main.go:221] Strace: false, max size: 1024, syscalls: I1217 03:52:44.249249 49540 main.go:222] VFS2 enabled: true I1217 03:52:44.249289 49540 main.go:223] *************************** W1217 03:52:44.249373 49540 main.go:228] Block the TERM signal. This is only safe in tests! D1217 03:52:44.249532 49540 container.go:164] Load container, rootDir: "/syzkaller/managers/ptrace-2-race/workdir/gvisor_root", partial cid: ci-gvisor-ptrace-2-race-0 D1217 03:52:44.259731 49540 container.go:652] Signal container, cid: ci-gvisor-ptrace-2-race-0, signal: signal 0 (0) D1217 03:52:44.259880 49540 sandbox.go:861] Signal sandbox "ci-gvisor-ptrace-2-race-0" D1217 03:52:44.259986 49540 sandbox.go:349] Connecting to sandbox "ci-gvisor-ptrace-2-race-0" D1217 03:52:44.260506 49540 urpc.go:534] urpc: successfully marshalled 105 bytes. I1217 03:52:44.321212 49540 debug.go:126] Found sandbox "ci-gvisor-ptrace-2-race-0", PID: 46940 I1217 03:52:44.321347 49540 debug.go:135] Retrieving sandbox stacks D1217 03:52:44.321432 49540 sandbox.go:979] Stacks sandbox "ci-gvisor-ptrace-2-race-0" D1217 03:52:44.321485 49540 sandbox.go:349] Connecting to sandbox "ci-gvisor-ptrace-2-race-0" W1217 03:52:44.321609 49540 error.go:48] FATAL ERROR: retrieving stacks: connecting to control server at PID 46940: connection refused retrieving stacks: connecting to control server at PID 46940: connection refused W1217 03:52:44.321973 49540 main.go:245] Failure to execute command, err: 1 Error collecting stacks: failed to run ["/syzkaller/managers/ptrace-2-race/current/image" "-root" "/syzkaller/managers/ptrace-2-race/workdir/gvisor_root" "-watchdog-action=panic" "-network=none" "-debug" "-debug-log=/dev/stderr" "-platform=ptrace" "-file-access=exclusive" "-network=sandbox" "-net-raw" "-watchdog-action=panic" "-vfs2" "-fuse" "-TESTONLY-unsafe-nonroot" "debug" "-stacks" "--ps" "ci-gvisor-ptrace-2-race-0"]: exit status 128 I1217 03:52:44.248373 49540 main.go:209] *************************** I1217 03:52:44.248455 49540 main.go:210] Args: [/syzkaller/managers/ptrace-2-race/current/image -root /syzkaller/managers/ptrace-2-race/workdir/gvisor_root -watchdog-action=panic -network=none -debug -debug-log=/dev/stderr -platform=ptrace -file-access=exclusive -network=sandbox -net-raw -watchdog-action=panic -vfs2 -fuse -TESTONLY-unsafe-nonroot debug -stacks --ps ci-gvisor-ptrace-2-race-0] I1217 03:52:44.248622 49540 main.go:211] Version release-20201208.0-60-gb645fcd241a8 I1217 03:52:44.248688 49540 main.go:212] GOOS: linux I1217 03:52:44.248729 49540 main.go:213] GOARCH: amd64 I1217 03:52:44.248771 49540 main.go:214] PID: 49540 I1217 03:52:44.248815 49540 main.go:215] UID: 0, GID: 0 I1217 03:52:44.248901 49540 main.go:216] Configuration: I1217 03:52:44.248938 49540 main.go:217] RootDir: /syzkaller/managers/ptrace-2-race/workdir/gvisor_root I1217 03:52:44.249004 49540 main.go:218] Platform: ptrace I1217 03:52:44.249044 49540 main.go:219] FileAccess: 0, overlay: false I1217 03:52:44.249116 49540 main.go:220] Network: 0, logging: false I1217 03:52:44.249205 49540 main.go:221] Strace: false, max size: 1024, syscalls: I1217 03:52:44.249249 49540 main.go:222] VFS2 enabled: true I1217 03:52:44.249289 49540 main.go:223] *************************** W1217 03:52:44.249373 49540 main.go:228] Block the TERM signal. This is only safe in tests! D1217 03:52:44.249532 49540 container.go:164] Load container, rootDir: "/syzkaller/managers/ptrace-2-race/workdir/gvisor_root", partial cid: ci-gvisor-ptrace-2-race-0 D1217 03:52:44.259731 49540 container.go:652] Signal container, cid: ci-gvisor-ptrace-2-race-0, signal: signal 0 (0) D1217 03:52:44.259880 49540 sandbox.go:861] Signal sandbox "ci-gvisor-ptrace-2-race-0" D1217 03:52:44.259986 49540 sandbox.go:349] Connecting to sandbox "ci-gvisor-ptrace-2-race-0" D1217 03:52:44.260506 49540 urpc.go:534] urpc: successfully marshalled 105 bytes. I1217 03:52:44.321212 49540 debug.go:126] Found sandbox "ci-gvisor-ptrace-2-race-0", PID: 46940 I1217 03:52:44.321347 49540 debug.go:135] Retrieving sandbox stacks D1217 03:52:44.321432 49540 sandbox.go:979] Stacks sandbox "ci-gvisor-ptrace-2-race-0" D1217 03:52:44.321485 49540 sandbox.go:349] Connecting to sandbox "ci-gvisor-ptrace-2-race-0" W1217 03:52:44.321609 49540 error.go:48] FATAL ERROR: retrieving stacks: connecting to control server at PID 46940: connection refused retrieving stacks: connecting to control server at PID 46940: connection refused W1217 03:52:44.321973 49540 main.go:245] Failure to execute command, err: 1 [ 0.000000] Linux version 4.9.0-14-amd64 (debian-kernel@lists.debian.org) (gcc version 6.3.0 20170516 (Debian 6.3.0-18+deb9u1) ) #1 SMP Debian 4.9.240-2 (2020-10-30) [ 0.000000] Command line: BOOT_IMAGE=/boot/vmlinuz-4.9.0-14-amd64 root=UUID=c138560a-f5cb-426a-b69d-79783fff446f ro net.ifnames=0 biosdevname=0 console=ttyS0,38400n8 elevator=noop scsi_mod.use_blk_mq=Y ima_tcb ima_hash=sha256 kvm-intel.nested=1 [ 0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x008: 'MPX bounds registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x010: 'MPX CSR' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256' [ 0.000000] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 [ 0.000000] x86/fpu: xstate_offset[3]: 832, xstate_sizes[3]: 64 [ 0.000000] x86/fpu: xstate_offset[4]: 896, xstate_sizes[4]: 64 [ 0.000000] x86/fpu: xstate_offset[5]: 960, xstate_sizes[5]: 64 [ 0.000000] x86/fpu: xstate_offset[6]: 1024, xstate_sizes[6]: 512 [ 0.000000] x86/fpu: xstate_offset[7]: 1536, xstate_sizes[7]: 1024 [ 0.000000] x86/fpu: Enabled xstate features 0xff, context size is 2560 bytes, using 'compacted' format. [ 0.000000] e820: BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bfff7fff] usable [ 0.000000] BIOS-e820: [mem 0x00000000bfff8000-0x00000000bfffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fffbc000-0x00000000ffffffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x0000009c3fffffff] usable [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] SMBIOS 2.4 present. [ 0.000000] DMI: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [ 0.000000] Hypervisor detected: KVM [ 0.000000] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved [ 0.000000] e820: remove [mem 0x000a0000-0x000fffff] usable [ 0.000000] e820: last_pfn = 0x9c40000 max_arch_pfn = 0x400000000 [ 0.000000] MTRR default type: write-back [ 0.000000] MTRR fixed ranges enabled: [ 0.000000] 00000-9FFFF write-back [ 0.000000] A0000-BFFFF uncachable [ 0.000000] C0000-FFFFF write-protect [ 0.000000] MTRR variable ranges enabled: [ 0.000000] 0 base 0000C0000000 mask 3FFFC0000000 uncachable [ 0.000000] 1 disabled [ 0.000000] 2 disabled [ 0.000000] 3 disabled [ 0.000000] 4 disabled [ 0.000000] 5 disabled [ 0.000000] 6 disabled [ 0.000000] 7 disabled [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WC UC- WT [ 0.000000] e820: last_pfn = 0xbfff8 max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f25a0-0x000f25af] mapped at [ffff9420c00f25a0] [ 0.000000] Base memory trampoline at [ffff9420c0099000] 99000 size 24576 [ 0.000000] Using GB pages for direct mapping [ 0.000000] RAMDISK: [mem 0x35d3d000-0x36e95fff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F2550 000014 (v00 Google) [ 0.000000] ACPI: RSDT 0x00000000BFFFE500 000038 (v01 Google GOOGRSDT 00000001 GOOG 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFFA600 0000F4 (v02 Google GOOGFACP 00000001 GOOG 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFF8D40 0018BA (v01 Google GOOGDSDT 00000001 GOOG 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFF8D00 000040 [ 0.000000] ACPI: FACS 0x00000000BFFF8D00 000040 [ 0.000000] ACPI: SRAT 0x00000000BFFFDDD0 0006D0 (v03 Google GOOGSRAT 00000001 GOOG 00000001) [ 0.000000] ACPI: APIC 0x00000000BFFFDA30 000366 (v05 Google GOOGAPIC 00000001 GOOG 00000001) [ 0.000000] ACPI: SSDT 0x00000000BFFFA700 00332E (v01 Google GOOGSSDT 00000001 GOOG 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFFDDA0 000028 (v01 Google GOOGWAET 00000001 GOOG 00000001) [ 0.000000] ACPI: Local APIC address 0xfee00000 [ 0.000000] SRAT: PXM 0 -> APIC 0x00 -> Node 0 [ 0.000000] SRAT: PXM 0 -> APIC 0x01 -> Node 0 [ 0.000000] SRAT: PXM 0 -> APIC 0x02 -> Node 0 [ 0.000000] SRAT: PXM 0 -> APIC 0x03 -> Node 0 [ 0.000000] SRAT: PXM 0 -> APIC 0x04 -> Node 0 [ 0.000000] SRAT: PXM 0 -> APIC 0x05 -> Node 0 [ 0.000000] SRAT: PXM 0 -> APIC 0x06 -> Node 0 [ 0.000000] SRAT: PXM 0 -> APIC 0x07 -> Node 0 [ 0.000000] SRAT: PXM 0 -> APIC 0x08 -> Node 0 [ 0.000000] SRAT: PXM 0 -> APIC 0x09 -> Node 0 [ 0.000000] SRAT: PXM 0 -> APIC 0x0a -> Node 0 [ 0.000000] SRAT: PXM 0 -> APIC 0x0b -> Node 0 [ 0.000000] SRAT: PXM 0 -> APIC 0x0c -> Node 0 [ 0.000000] SRAT: PXM 0 -> APIC 0x0d -> Node 0 [ 0.000000] SRAT: PXM 0 -> APIC 0x0e -> Node 0 [ 0.000000] SRAT: PXM 0 -> APIC 0x0f -> Node 0 [ 0.000000] SRAT: PXM 0 -> APIC 0x10 -> Node 0 [ 0.000000] SRAT: PXM 0 -> APIC 0x11 -> Node 0 [ 0.000000] SRAT: PXM 0 -> APIC 0x12 -> Node 0 [ 0.000000] SRAT: PXM 0 -> APIC 0x13 -> Node 0 [ 0.000000] SRAT: PXM 0 -> APIC 0x14 -> Node 0 [ 0.000000] SRAT: PXM 0 -> APIC 0x15 -> Node 0 [ 0.000000] SRAT: PXM 0 -> APIC 0x16 -> Node 0 [ 0.000000] SRAT: PXM 0 -> APIC 0x17 -> Node 0 [ 0.000000] SRAT: PXM 0 -> APIC 0x18 -> Node 0 [ 0.000000] SRAT: PXM 0 -> APIC 0x19 -> Node 0 [ 0.000000] SRAT: PXM 0 -> APIC 0x1a -> Node 0 [ 0.000000] SRAT: PXM 0 -> APIC 0x1b -> Node 0 [ 0.000000] SRAT: PXM 0 -> APIC 0x1c -> Node 0 [ 0.000000] SRAT: PXM 0 -> APIC 0x1d -> Node 0 [ 0.000000] SRAT: PXM 0 -> APIC 0x1e -> Node 0 [ 0.000000] SRAT: PXM 0 -> APIC 0x1f -> Node 0 [ 0.000000] SRAT: PXM 0 -> APIC 0x20 -> Node 0 [ 0.000000] SRAT: PXM 0 -> APIC 0x21 -> Node 0 [ 0.000000] SRAT: PXM 0 -> APIC 0x22 -> Node 0 [ 0.000000] SRAT: PXM 0 -> APIC 0x23 -> Node 0 [ 0.000000] SRAT: PXM 0 -> APIC 0x24 -> Node 0 [ 0.000000] SRAT: PXM 0 -> APIC 0x25 -> Node 0 [ 0.000000] SRAT: PXM 0 -> APIC 0x26 -> Node 0 [ 0.000000] SRAT: PXM 0 -> APIC 0x27 -> Node 0 [ 0.000000] SRAT: PXM 0 -> APIC 0x28 -> Node 0 [ 0.000000] SRAT: PXM 0 -> APIC 0x29 -> Node 0 [ 0.000000] SRAT: PXM 0 -> APIC 0x2a -> Node 0 [ 0.000000] SRAT: PXM 0 -> APIC 0x2b -> Node 0 [ 0.000000] SRAT: PXM 0 -> APIC 0x2c -> Node 0 [ 0.000000] SRAT: PXM 0 -> APIC 0x2d -> Node 0 [ 0.000000] SRAT: PXM 0 -> APIC 0x2e -> Node 0 [ 0.000000] SRAT: PXM 0 -> APIC 0x2f -> Node 0 [ 0.000000] SRAT: PXM 1 -> APIC 0x40 -> Node 1 [ 0.000000] SRAT: PXM 1 -> APIC 0x41 -> Node 1 [ 0.000000] SRAT: PXM 1 -> APIC 0x42 -> Node 1 [ 0.000000] SRAT: PXM 1 -> APIC 0x43 -> Node 1 [ 0.000000] SRAT: PXM 1 -> APIC 0x44 -> Node 1 [ 0.000000] SRAT: PXM 1 -> APIC 0x45 -> Node 1 [ 0.000000] SRAT: PXM 1 -> APIC 0x46 -> Node 1 [ 0.000000] SRAT: PXM 1 -> APIC 0x47 -> Node 1 [ 0.000000] SRAT: PXM 1 -> APIC 0x48 -> Node 1 [ 0.000000] SRAT: PXM 1 -> APIC 0x49 -> Node 1 [ 0.000000] SRAT: PXM 1 -> APIC 0x4a -> Node 1 [ 0.000000] SRAT: PXM 1 -> APIC 0x4b -> Node 1 [ 0.000000] SRAT: PXM 1 -> APIC 0x4c -> Node 1 [ 0.000000] SRAT: PXM 1 -> APIC 0x4d -> Node 1 [ 0.000000] SRAT: PXM 1 -> APIC 0x4e -> Node 1 [ 0.000000] SRAT: PXM 1 -> APIC 0x4f -> Node 1 [ 0.000000] SRAT: PXM 1 -> APIC 0x50 -> Node 1 [ 0.000000] SRAT: PXM 1 -> APIC 0x51 -> Node 1 [ 0.000000] SRAT: PXM 1 -> APIC 0x52 -> Node 1 [ 0.000000] SRAT: PXM 1 -> APIC 0x53 -> Node 1 [ 0.000000] SRAT: PXM 1 -> APIC 0x54 -> Node 1 [ 0.000000] SRAT: PXM 1 -> APIC 0x55 -> Node 1 [ 0.000000] SRAT: PXM 1 -> APIC 0x56 -> Node 1 [ 0.000000] SRAT: PXM 1 -> APIC 0x57 -> Node 1 [ 0.000000] SRAT: PXM 1 -> APIC 0x58 -> Node 1 [ 0.000000] SRAT: PXM 1 -> APIC 0x59 -> Node 1 [ 0.000000] SRAT: PXM 1 -> APIC 0x5a -> Node 1 [ 0.000000] SRAT: PXM 1 -> APIC 0x5b -> Node 1 [ 0.000000] SRAT: PXM 1 -> APIC 0x5c -> Node 1 [ 0.000000] SRAT: PXM 1 -> APIC 0x5d -> Node 1 [ 0.000000] SRAT: PXM 1 -> APIC 0x5e -> Node 1 [ 0.000000] SRAT: PXM 1 -> APIC 0x5f -> Node 1 [ 0.000000] SRAT: PXM 1 -> APIC 0x60 -> Node 1 [ 0.000000] SRAT: PXM 1 -> APIC 0x61 -> Node 1 [ 0.000000] SRAT: PXM 1 -> APIC 0x62 -> Node 1 [ 0.000000] SRAT: PXM 1 -> APIC 0x63 -> Node 1 [ 0.000000] SRAT: PXM 1 -> APIC 0x64 -> Node 1 [ 0.000000] SRAT: PXM 1 -> APIC 0x65 -> Node 1 [ 0.000000] SRAT: PXM 1 -> APIC 0x66 -> Node 1 [ 0.000000] SRAT: PXM 1 -> APIC 0x67 -> Node 1 [ 0.000000] SRAT: PXM 1 -> APIC 0x68 -> Node 1 [ 0.000000] SRAT: PXM 1 -> APIC 0x69 -> Node 1 [ 0.000000] SRAT: PXM 1 -> APIC 0x6a -> Node 1 [ 0.000000] SRAT: PXM 1 -> APIC 0x6b -> Node 1 [ 0.000000] SRAT: PXM 1 -> APIC 0x6c -> Node 1 [ 0.000000] SRAT: PXM 1 -> APIC 0x6d -> Node 1 [ 0.000000] SRAT: PXM 1 -> APIC 0x6e -> Node 1 [ 0.000000] SRAT: PXM 1 -> APIC 0x6f -> Node 1 [ 0.000000] ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x0009ffff] [ 0.000000] ACPI: SRAT: Node 0 PXM 0 [mem 0x00100000-0xbfffffff] [ 0.000000] ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0x4e3fffffff] [ 0.000000] ACPI: SRAT: Node 1 PXM 1 [mem 0x4e40000000-0x9c3fffffff] [ 0.000000] NUMA: Node 0 [mem 0x00000000-0x0009ffff] + [mem 0x00100000-0xbfffffff] -> [mem 0x00000000-0xbfffffff] [ 0.000000] NUMA: Node 0 [mem 0x00000000-0xbfffffff] + [mem 0x100000000-0x4e3fffffff] -> [mem 0x00000000-0x4e3fffffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x4e3fffb000-0x4e3fffffff] [ 0.000000] NODE_DATA(1) allocated [mem 0x9c3fffb000-0x9c3fffffff] [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: cpu 0, msr 9c:3fff3001, primary cpu clock [ 0.000000] kvm-clock: using sched offset of 2414621469 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000009c3fffffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bfff7fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000004e3fffffff] [ 0.000000] node 1: [mem 0x0000004e40000000-0x0000009c3fffffff] [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000004e3fffffff] [ 0.000000] On node 0 totalpages: 81788822 [ 0.000000] DMA zone: 64 pages used for memmap [ 0.000000] DMA zone: 21 pages reserved [ 0.000000] DMA zone: 3998 pages, LIFO batch:0 [ 0.000000] DMA32 zone: 12224 pages used for memmap [ 0.000000] DMA32 zone: 782328 pages, LIFO batch:31 [ 0.000000] Normal zone: 1265664 pages used for memmap [ 0.000000] Normal zone: 81002496 pages, LIFO batch:31 [ 0.000000] Initmem setup node 1 [mem 0x0000004e40000000-0x0000009c3fffffff] [ 0.000000] On node 1 totalpages: 81788928 [ 0.000000] Normal zone: 1277952 pages used for memmap [ 0.000000] Normal zone: 81788928 pages, LIFO batch:31 [ 0.000000] ACPI: PM-Timer IO Port: 0xb008 [ 0.000000] ACPI: Local APIC address 0xfee00000 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] ACPI: IRQ5 used by override. [ 0.000000] ACPI: IRQ9 used by override. [ 0.000000] ACPI: IRQ10 used by override. [ 0.000000] ACPI: IRQ11 used by override. [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] smpboot: Allowing 96 CPUs, 0 hotplug CPUs [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbfff8000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfffbbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffbc000-0xffffffff] [ 0.000000] e820: [mem 0xc0000000-0xfffbbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645519600211568 ns [ 0.000000] setup_percpu: NR_CPUS:512 nr_cpumask_bits:512 nr_cpu_ids:96 nr_node_ids:2 [ 0.000000] percpu: Embedded 35 pages/cpu s105304 r8192 d29864 u262144 [ 0.000000] pcpu-alloc: s105304 r8192 d29864 u262144 alloc=1*2097152 [ 0.000000] pcpu-alloc: [0] 00 01 02 03 04 05 06 07 [0] 08 09 10 11 12 13 14 15 [ 0.000000] pcpu-alloc: [0] 16 17 18 19 20 21 22 23 [0] 48 49 50 51 52 53 54 55 [ 0.000000] pcpu-alloc: [0] 56 57 58 59 60 61 62 63 [0] 64 65 66 67 68 69 70 71 [ 0.000000] pcpu-alloc: [1] 24 25 26 27 28 29 30 31 [1] 32 33 34 35 36 37 38 39 [ 0.000000] pcpu-alloc: [1] 40 41 42 43 44 45 46 47 [1] 72 73 74 75 76 77 78 79 [ 0.000000] pcpu-alloc: [1] 80 81 82 83 84 85 86 87 [1] 88 89 90 91 92 93 94 95 [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes) [ 0.000000] Built 2 zonelists in Node order, mobility grouping on. Total pages: 161021825 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: BOOT_IMAGE=/boot/vmlinuz-4.9.0-14-amd64 root=UUID=c138560a-f5cb-426a-b69d-79783fff446f ro net.ifnames=0 biosdevname=0 console=ttyS0,38400n8 elevator=noop scsi_mod.use_blk_mq=Y ima_tcb ima_hash=sha256 kvm-intel.nested=1 [ 0.000000] log_buf_len individual max cpu contribution: 4096 bytes [ 0.000000] log_buf_len total cpu_extra contributions: 389120 bytes [ 0.000000] log_buf_len min size: 131072 bytes [ 0.000000] log_buf_len: 524288 bytes [ 0.000000] early log buf free: 116052(88%) [ 0.000000] PID hash table entries: 4096 (order: 3, 32768 bytes) [ 0.000000] Calgary: detecting Calgary via BIOS EBDA area [ 0.000000] Calgary: Unable to locate Rio Grande table in EBDA - bailing! [ 0.000000] Memory: 643973500K/654311000K available (6292K kernel code, 1168K rwdata, 2872K rodata, 1428K init, 664K bss, 10337500K reserved, 0K cma-reserved) [ 0.000000] Kernel/User page tables isolation: enabled [ 0.000000] Hierarchical RCU implementation. [ 0.000000] Build-time adjustment of leaf fanout to 64. [ 0.000000] RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=96. [ 0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=64, nr_cpu_ids=96 [ 0.000000] NR_IRQS:33024 nr_irqs:1192 16 [ 0.000000] Console: colour VGA+ 80x25 [ 0.000000] console [ttyS0] enabled [ 0.000000] mempolicy: Disabling automatic NUMA balancing. Configure with numa_balancing= or the kernel.numa_balancing sysctl [ 0.000000] tsc: Detected 2000.184 MHz processor [ 6.639720] Calibrating delay loop (skipped) preset value.. 4000.36 BogoMIPS (lpj=8000736) [ 6.641007] pid_max: default: 98304 minimum: 768 [ 6.642017] ACPI: Core revision 20160831 [ 6.647070] ACPI: 2 ACPI AML tables successfully acquired and loaded [ 6.649313] Security Framework initialized [ 6.649961] Yama: disabled by default; enable with sysctl kernel.yama.* [ 6.650955] AppArmor: AppArmor disabled by boot time parameter [ 6.692378] Dentry cache hash table entries: 134217728 (order: 18, 1073741824 bytes) [ 7.198460] Inode-cache hash table entries: 67108864 (order: 17, 536870912 bytes) [ 7.444597] Mount-cache hash table entries: 2097152 (order: 12, 16777216 bytes) [ 7.448051] Mountpoint-cache hash table entries: 2097152 (order: 12, 16777216 bytes) [ 7.461350] mce: CPU supports 32 MCE banks [ 7.462254] Last level iTLB entries: 4KB 64, 2MB 8, 4MB 8 [ 7.463675] Last level dTLB entries: 4KB 64, 2MB 0, 4MB 0, 1GB 4 [ 7.464585] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization [ 7.466209] Spectre V2 : Mitigation: Full generic retpoline [ 7.467213] Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch [ 7.468735] Spectre V2 : Enabling Restricted Speculation for firmware calls [ 7.469812] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier [ 7.471334] Spectre V2 : User space: Mitigation: STIBP via seccomp and prctl [ 7.473026] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl and seccomp [ 7.474718] TAA: Mitigation: Clear CPU buffers [ 7.475455] MDS: Mitigation: Clear CPU buffers [ 7.483465] Freeing SMP alternatives memory: 24K [ 7.490558] ftrace: allocating 25372 entries in 100 pages [ 7.538349] smpboot: Max logical packages: 4 [ 7.539914] x2apic enabled [ 7.541738] Switched APIC routing to physical x2apic. [ 7.545552] ..TIMER: vector=0x30 apic1=0 pin1=0 apic2=-1 pin2=-1 [ 7.654865] smpboot: CPU0: Intel(R) Xeon(R) CPU @ 2.00GHz (family: 0x6, model: 0x55, stepping: 0x3) [ 7.656832] Performance Events: unsupported p6 CPU model 85 no PMU driver, software events only. [ 7.658319] KVM setup paravirtual spinlock [ 7.662229] x86: Booting SMP configuration: [ 7.663292] .... node #0, CPUs: #1 [ 7.664557] kvm-clock: cpu 1, msr 9c:3fff3041, secondary cpu clock [ 7.668569] #2 [ 7.669146] kvm-clock: cpu 2, msr 9c:3fff3081, secondary cpu clock [ 7.672909] #3 [ 7.673437] kvm-clock: cpu 3, msr 9c:3fff30c1, secondary cpu clock [ 7.676804] #4 [ 7.677340] kvm-clock: cpu 4, msr 9c:3fff3101, secondary cpu clock [ 7.680847] #5 [ 7.681484] kvm-clock: cpu 5, msr 9c:3fff3141, secondary cpu clock [ 7.684994] #6 [ 7.685587] kvm-clock: cpu 6, msr 9c:3fff3181, secondary cpu clock [ 7.689004] #7 [ 7.689520] kvm-clock: cpu 7, msr 9c:3fff31c1, secondary cpu clock [ 7.692836] #8 [ 7.693307] kvm-clock: cpu 8, msr 9c:3fff3201, secondary cpu clock [ 7.696643] #9 [ 7.697285] kvm-clock: cpu 9, msr 9c:3fff3241, secondary cpu clock [ 7.700516] #10 [ 7.700948] kvm-clock: cpu 10, msr 9c:3fff3281, secondary cpu clock [ 7.705145] #11 [ 7.705931] kvm-clock: cpu 11, msr 9c:3fff32c1, secondary cpu clock [ 7.709286] #12 [ 7.710044] kvm-clock: cpu 12, msr 9c:3fff3301, secondary cpu clock [ 7.713682] #13 [ 7.714130] kvm-clock: cpu 13, msr 9c:3fff3341, secondary cpu clock [ 7.717637] #14 [ 7.718217] kvm-clock: cpu 14, msr 9c:3fff3381, secondary cpu clock [ 7.721483] #15 [ 7.722156] kvm-clock: cpu 15, msr 9c:3fff33c1, secondary cpu clock [ 7.726055] #16 [ 7.726772] kvm-clock: cpu 16, msr 9c:3fff3401, secondary cpu clock [ 7.730424] #17 [ 7.730936] kvm-clock: cpu 17, msr 9c:3fff3441, secondary cpu clock [ 7.734464] #18 [ 7.735366] kvm-clock: cpu 18, msr 9c:3fff3481, secondary cpu clock [ 7.739694] #19 [ 7.740180] kvm-clock: cpu 19, msr 9c:3fff34c1, secondary cpu clock [ 7.744687] #20 [ 7.745171] kvm-clock: cpu 20, msr 9c:3fff3501, secondary cpu clock [ 7.748506] #21 [ 7.749119] kvm-clock: cpu 21, msr 9c:3fff3541, secondary cpu clock [ 7.752943] #22 [ 7.753575] kvm-clock: cpu 22, msr 9c:3fff3581, secondary cpu clock [ 7.757019] #23 [ 7.757632] kvm-clock: cpu 23, msr 9c:3fff35c1, secondary cpu clock [ 7.761397] .... node #1, CPUs: #24 [ 7.762240] kvm-clock: cpu 24, msr 9c:3fff3601, secondary cpu clock [ 7.784372] #25 [ 7.785275] kvm-clock: cpu 25, msr 9c:3fff3641, secondary cpu clock [ 7.789448] #26 [ 7.790240] kvm-clock: cpu 26, msr 9c:3fff3681, secondary cpu clock [ 7.793557] #27 [ 7.794082] kvm-clock: cpu 27, msr 9c:3fff36c1, secondary cpu clock [ 7.797475] #28 [ 7.798318] kvm-clock: cpu 28, msr 9c:3fff3701, secondary cpu clock [ 7.801616] #29 [ 7.802075] kvm-clock: cpu 29, msr 9c:3fff3741, secondary cpu clock [ 7.805461] #30 [ 7.806047] kvm-clock: cpu 30, msr 9c:3fff3781, secondary cpu clock [ 7.809406] #31 [ 7.810054] kvm-clock: cpu 31, msr 9c:3fff37c1, secondary cpu clock [ 7.813461] #32 [ 7.814196] kvm-clock: cpu 32, msr 9c:3fff3801, secondary cpu clock [ 7.817521] #33 [ 7.818050] kvm-clock: cpu 33, msr 9c:3fff3841, secondary cpu clock [ 7.822074] #34 [ 7.822951] kvm-clock: cpu 34, msr 9c:3fff3881, secondary cpu clock [ 7.826418] #35 [ 7.827069] kvm-clock: cpu 35, msr 9c:3fff38c1, secondary cpu clock [ 7.830474] #36 [ 7.830956] kvm-clock: cpu 36, msr 9c:3fff3901, secondary cpu clock [ 7.834253] #37 [ 7.834859] kvm-clock: cpu 37, msr 9c:3fff3941, secondary cpu clock [ 7.838258] #38 [ 7.838879] kvm-clock: cpu 38, msr 9c:3fff3981, secondary cpu clock [ 7.842200] #39 [ 7.842628] kvm-clock: cpu 39, msr 9c:3fff39c1, secondary cpu clock [ 7.846130] #40 [ 7.846754] kvm-clock: cpu 40, msr 9c:3fff3a01, secondary cpu clock [ 7.850034] #41 [ 7.850635] kvm-clock: cpu 41, msr 9c:3fff3a41, secondary cpu clock [ 7.853954] #42 [ 7.854705] kvm-clock: cpu 42, msr 9c:3fff3a81, secondary cpu clock [ 7.858060] #43 [ 7.858773] kvm-clock: cpu 43, msr 9c:3fff3ac1, secondary cpu clock [ 7.862826] #44 [ 7.863423] kvm-clock: cpu 44, msr 9c:3fff3b01, secondary cpu clock [ 7.866776] #45 [ 7.867415] kvm-clock: cpu 45, msr 9c:3fff3b41, secondary cpu clock [ 7.870800] #46 [ 7.871409] kvm-clock: cpu 46, msr 9c:3fff3b81, secondary cpu clock [ 7.874828] #47 [ 7.875339] kvm-clock: cpu 47, msr 9c:3fff3bc1, secondary cpu clock [ 7.878664] .... node #0, CPUs: #48 [ 7.879546] kvm-clock: cpu 48, msr 9c:3fff3c01, secondary cpu clock [ 7.885698] MDS CPU bug present and SMT on, data leak possible. See https://www.kernel.org/doc/html/latest/admin-guide/hw-vuln/mds.html for more details. [ 7.888121] TAA CPU bug present and SMT on, data leak possible. See https://www.kernel.org/doc/html/latest/admin-guide/hw-vuln/tsx_async_abort.html for more details. [ 7.891941] #49 [ 7.892517] kvm-clock: cpu 49, msr 9c:3fff3c41, secondary cpu clock [ 7.895879] #50 [ 7.896579] kvm-clock: cpu 50, msr 9c:3fff3c81, secondary cpu clock [ 7.900338] #51 [ 7.900919] kvm-clock: cpu 51, msr 9c:3fff3cc1, secondary cpu clock [ 7.904888] #52 [ 7.905544] kvm-clock: cpu 52, msr 9c:3fff3d01, secondary cpu clock [ 7.909573] #53 [ 7.910228] kvm-clock: cpu 53, msr 9c:3fff3d41, secondary cpu clock [ 7.914176] #54 [ 7.914773] kvm-clock: cpu 54, msr 9c:3fff3d81, secondary cpu clock [ 7.918400] #55 [ 7.918911] kvm-clock: cpu 55, msr 9c:3fff3dc1, secondary cpu clock [ 7.922250] #56 [ 7.922865] kvm-clock: cpu 56, msr 9c:3fff3e01, secondary cpu clock [ 7.927113] #57 [ 7.927837] kvm-clock: cpu 57, msr 9c:3fff3e41, secondary cpu clock [ 7.931512] #58 [ 7.932069] kvm-clock: cpu 58, msr 9c:3fff3e81, secondary cpu clock [ 7.935660] #59 [ 7.936344] kvm-clock: cpu 59, msr 9c:3fff3ec1, secondary cpu clock [ 7.940112] #60 [ 7.940662] kvm-clock: cpu 60, msr 9c:3fff3f01, secondary cpu clock [ 7.944173] #61 [ 7.944628] kvm-clock: cpu 61, msr 9c:3fff3f41, secondary cpu clock [ 7.948274] #62 [ 7.948995] kvm-clock: cpu 62, msr 9c:3fff3f81, secondary cpu clock [ 7.952983] #63 [ 7.953714] kvm-clock: cpu 63, msr 9c:3fff3fc1, secondary cpu clock [ 7.957140] #64 [ 7.957826] kvm-clock: cpu 64, msr 9c:3fff4001, secondary cpu clock [ 7.961392] #65 [ 7.962128] kvm-clock: cpu 65, msr 9c:3fff4041, secondary cpu clock [ 7.966154] #66 [ 7.966870] kvm-clock: cpu 66, msr 9c:3fff4081, secondary cpu clock [ 7.970926] #67 [ 7.971596] kvm-clock: cpu 67, msr 9c:3fff40c1, secondary cpu clock [ 7.975674] #68 [ 7.976431] kvm-clock: cpu 68, msr 9c:3fff4101, secondary cpu clock [ 7.979828] #69 [ 7.980662] kvm-clock: cpu 69, msr 9c:3fff4141, secondary cpu clock [ 7.984352] #70 [ 7.985279] kvm-clock: cpu 70, msr 9c:3fff4181, secondary cpu clock [ 7.988797] #71 [ 7.989425] kvm-clock: cpu 71, msr 9c:3fff41c1, secondary cpu clock [ 7.992908] .... node #1, CPUs: #72 [ 7.993980] kvm-clock: cpu 72, msr 9c:3fff4201, secondary cpu clock [ 7.997750] #73 [ 7.998644] kvm-clock: cpu 73, msr 9c:3fff4241, secondary cpu clock [ 8.002073] #74 [ 8.002556] kvm-clock: cpu 74, msr 9c:3fff4281, secondary cpu clock [ 8.006575] #75 [ 8.007222] kvm-clock: cpu 75, msr 9c:3fff42c1, secondary cpu clock [ 8.010591] #76 [ 8.011359] kvm-clock: cpu 76, msr 9c:3fff4301, secondary cpu clock [ 8.014920] #77 [ 8.015530] kvm-clock: cpu 77, msr 9c:3fff4341, secondary cpu clock [ 8.018830] #78 [ 8.019610] kvm-clock: cpu 78, msr 9c:3fff4381, secondary cpu clock [ 8.023046] #79 [ 8.023609] kvm-clock: cpu 79, msr 9c:3fff43c1, secondary cpu clock [ 8.026991] #80 [ 8.027700] kvm-clock: cpu 80, msr 9c:3fff4401, secondary cpu clock [ 8.031153] #81 [ 8.031712] kvm-clock: cpu 81, msr 9c:3fff4441, secondary cpu clock [ 8.035150] #82 [ 8.035704] kvm-clock: cpu 82, msr 9c:3fff4481, secondary cpu clock [ 8.039087] #83 [ 8.039702] kvm-clock: cpu 83, msr 9c:3fff44c1, secondary cpu clock [ 8.043229] #84 [ 8.044125] kvm-clock: cpu 84, msr 9c:3fff4501, secondary cpu clock [ 8.048294] #85 [ 8.049145] kvm-clock: cpu 85, msr 9c:3fff4541, secondary cpu clock [ 8.052670] #86 [ 8.053575] kvm-clock: cpu 86, msr 9c:3fff4581, secondary cpu clock [ 8.057740] #87 [ 8.058495] kvm-clock: cpu 87, msr 9c:3fff45c1, secondary cpu clock [ 8.062001] #88 [ 8.062655] kvm-clock: cpu 88, msr 9c:3fff4601, secondary cpu clock [ 8.066116] #89 [ 8.066783] kvm-clock: cpu 89, msr 9c:3fff4641, secondary cpu clock [ 8.070252] #90 [ 8.070935] kvm-clock: cpu 90, msr 9c:3fff4681, secondary cpu clock [ 8.074314] #91 [ 8.074880] kvm-clock: cpu 91, msr 9c:3fff46c1, secondary cpu clock [ 8.078373] #92 [ 8.079129] kvm-clock: cpu 92, msr 9c:3fff4701, secondary cpu clock [ 8.082522] #93 [ 8.083199] kvm-clock: cpu 93, msr 9c:3fff4741, secondary cpu clock [ 8.086881] #94 [ 8.087351] kvm-clock: cpu 94, msr 9c:3fff4781, secondary cpu clock [ 8.090697] #95 [ 8.091291] kvm-clock: cpu 95, msr 9c:3fff47c1, secondary cpu clock [ 8.094613] x86: Booted up 2 nodes, 96 CPUs [ 8.095514] smpboot: Total of 96 processors activated (384035.32 BogoMIPS) [ 8.122185] devtmpfs: initialized [ 8.123529] x86/mm: Memory block size: 2048MB [ 8.131676] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns [ 8.134714] futex hash table entries: 32768 (order: 9, 2097152 bytes) [ 8.138537] pinctrl core: initialized pinctrl subsystem [ 8.141627] NET: Registered protocol family 16 [ 8.154955] cpuidle: using governor ladder [ 8.170956] cpuidle: using governor menu [ 8.171971] ACPI: bus type PCI registered [ 8.172646] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 8.174590] PCI: Using configuration type 1 for base access [ 8.202334] HugeTLB registered 1 GB page size, pre-allocated 0 pages [ 8.203403] HugeTLB registered 2 MB page size, pre-allocated 0 pages [ 8.208353] ACPI: Added _OSI(Module Device) [ 8.209015] ACPI: Added _OSI(Processor Device) [ 8.209646] ACPI: Added _OSI(3.0 _SCP Extensions) [ 8.210288] ACPI: Added _OSI(Processor Aggregator Device) [ 8.211586] ACPI: Executed 2 blocks of module-level executable AML code [ 8.223224] ACPI: Interpreter enabled [ 8.223842] ACPI: (supports S0 S3 S4 S5) [ 8.224387] ACPI: Using IOAPIC for interrupt routing [ 8.225128] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 8.238465] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 8.239562] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI] [ 8.240631] acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM [ 8.241617] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 8.243939] PCI host bridge to bus 0000:00 [ 8.244614] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 8.245632] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 8.246613] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 8.247701] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfefff window] [ 8.248896] pci_bus 0000:00: root bus resource [bus 00-ff] [ 8.249791] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 8.250196] pci 0000:00:01.0: [8086:7110] type 00 class 0x060100 [ 8.310683] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 8.364668] pci 0000:00:01.3: quirk: [io 0xb000-0xb03f] claimed by PIIX4 ACPI [ 8.366446] pci 0000:00:03.0: [1af4:1004] type 00 class 0x000000 [ 8.380262] pci 0000:00:03.0: reg 0x10: [io 0xc000-0xc03f] [ 8.403785] pci 0000:00:03.0: reg 0x14: [mem 0xfebfc000-0xfebfc07f] [ 8.446868] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 [ 8.463951] pci 0000:00:04.0: reg 0x10: [io 0xc040-0xc07f] [ 8.481199] pci 0000:00:04.0: reg 0x14: [mem 0xfebfd000-0xfebfd7ff] [ 8.530093] pci 0000:00:05.0: [1af4:1005] type 00 class 0x00ff00 [ 8.551128] pci 0000:00:05.0: reg 0x10: [io 0xc080-0xc09f] [ 8.573279] pci 0000:00:05.0: reg 0x14: [mem 0xfebfe000-0xfebfe03f] [ 8.615338] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11) [ 8.618932] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11) [ 8.622752] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11) [ 8.626749] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11) [ 8.630516] ACPI: PCI Interrupt Link [LNKS] (IRQs *9) [ 8.645934] ACPI: Enabled 16 GPEs in block 00 to 0F [ 8.647568] vgaarb: loaded [ 8.648192] PCI: Using ACPI for IRQ routing [ 8.648828] PCI: pci_cache_line_size set to 64 bytes [ 8.648925] e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] [ 8.648927] e820: reserve RAM buffer [mem 0xbfff8000-0xbfffffff] [ 8.650179] clocksource: Switched to clocksource kvm-clock [ 8.661087] VFS: Disk quotas dquot_6.6.0 [ 8.662422] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 8.664457] pnp: PnP ACPI init [ 8.665328] pnp 00:00: Plug and Play ACPI device, IDs PNP0b00 (active) [ 8.665421] pnp 00:01: Plug and Play ACPI device, IDs PNP0303 (active) [ 8.665476] pnp 00:02: Plug and Play ACPI device, IDs PNP0f13 (active) [ 8.665537] pnp 00:03: Plug and Play ACPI device, IDs PNP0501 (active) [ 8.665586] pnp 00:04: Plug and Play ACPI device, IDs PNP0501 (active) [ 8.665632] pnp 00:05: Plug and Play ACPI device, IDs PNP0501 (active) [ 8.665677] pnp 00:06: Plug and Play ACPI device, IDs PNP0501 (active) [ 8.665866] pnp: PnP ACPI: found 7 devices [ 8.673378] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 8.674729] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 8.674730] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 8.674731] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 8.674732] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfefff window] [ 8.676394] NET: Registered protocol family 2 [ 8.679272] TCP established hash table entries: 524288 (order: 10, 4194304 bytes) [ 8.681736] TCP bind hash table entries: 65536 (order: 8, 1048576 bytes) [ 8.683488] TCP: Hash tables configured (established 524288 bind 65536) [ 8.684500] UDP hash table entries: 65536 (order: 9, 2097152 bytes) [ 8.686277] UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes) [ 8.688659] NET: Registered protocol family 1 [ 8.689329] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 8.690301] PCI: CLS 0 bytes, default 64 [ 8.690352] Unpacking initramfs... [ 8.966301] Freeing initrd memory: 17764K [ 8.968511] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 8.969441] software IO TLB: mapped [mem 0xbbff8000-0xbfff8000] (64MB) [ 8.973020] audit: initializing netlink subsys (disabled) [ 8.973795] audit: type=2000 audit(1606814185.159:1): initialized [ 8.976335] workingset: timestamp_bits=40 max_order=28 bucket_order=0 [ 8.981618] zbud: loaded [ 8.985149] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) [ 8.986792] io scheduler noop registered (default) [ 8.987675] io scheduler deadline registered [ 8.988755] io scheduler cfq registered [ 8.989393] pci_hotplug: PCI Hot Plug PCI Core version: 0.5 [ 8.990192] pciehp: PCI Express Hot Plug Controller Driver version: 0.4 [ 8.991194] intel_idle: does not run on family 6 model 85 [ 8.996336] GHES: HEST is not enabled! [ 8.997039] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 9.019448] 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 9.042737] 00:04: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 9.065303] 00:05: ttyS2 at I/O 0x3e8 (irq = 6, base_baud = 115200) is a 16550A [ 9.087793] 00:06: ttyS3 at I/O 0x2e8 (irq = 7, base_baud = 115200) is a 16550A [ 9.089188] Linux agpgart interface v0.103 [ 9.089791] AMD IOMMUv2 driver by Joerg Roedel [ 9.090713] AMD IOMMUv2 functionality not available on this system [ 9.104094] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 9.106813] i8042: Warning: Keylock active [ 9.108488] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 9.109263] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 9.110241] mousedev: PS/2 mouse device common for all mice [ 9.111242] rtc_cmos 00:00: RTC can wake from S4 [ 9.112405] rtc_cmos 00:00: rtc core: registered rtc_cmos as rtc0 [ 9.114037] rtc_cmos 00:00: alarms up to one day, 114 bytes nvram [ 9.122486] ledtrig-cpu: registered to indicate activity on CPUs [ 9.126460] NET: Registered protocol family 10 [ 9.128772] mip6: Mobile IPv6 [ 9.129550] NET: Registered protocol family 17 [ 9.130707] mpls_gso: MPLS GSO support [ 9.134716] registered taskstats version 1 [ 9.136733] zswap: loaded using pool lzo/zbud [ 9.140877] ima: No TPM chip found, activating TPM-bypass! [ 9.142170] ima: Allocated hash algorithm: sha256 [ 9.143754] rtc_cmos 00:00: setting system clock to 2020-12-01 09:16:25 UTC (1606814185) [ 9.145384] PM: Hibernation image not present or could not be loaded. [ 9.318463] random: fast init done [ 9.319421] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 [ 9.450089] Freeing unused kernel memory: 1428K [ 9.450880] Write protecting the kernel read-only data: 12288k [ 9.455207] Freeing unused kernel memory: 1884K [ 9.458789] Freeing unused kernel memory: 1224K [ 9.464920] x86/mm: Checked W+X mappings: passed, no W+X pages found. [ 9.496768] audit: type=1805 audit(1606814185.848:2): action="dont_measure" fsmagic="0x9fa0" res=1 [ 9.498113] audit: type=1805 audit(1606814185.848:3): action="dont_measure" fsmagic="0x62656572" res=1 [ 9.499498] audit: type=1805 audit(1606814185.852:4): action="dont_measure" fsmagic="0x64626720" res=1 [ 9.500795] audit: type=1805 audit(1606814185.852:5): action="dont_measure" fsmagic="0x01021994" res=1 [ 9.502233] audit: type=1805 audit(1606814185.856:6): action="dont_measure" fsmagic="0x858458f6" res=1 [ 9.503607] audit: type=1805 audit(1606814185.856:7): action="dont_measure" fsmagic="0x1cd1" res=1 [ 9.504928] audit: type=1805 audit(1606814185.856:8): action="dont_measure" fsmagic="0x42494e4d" res=1 [ 9.506335] audit: type=1805 audit(1606814185.860:9): action="dont_measure" fsmagic="0x73636673" res=1 [ 9.507649] audit: type=1805 audit(1606814185.860:10): action="dont_measure" fsmagic="0xf97cff8c" res=1 [ 9.509093] IMA: policy update completed [ 9.548036] random: systemd-udevd: uninitialized urandom read (16 bytes read) [ 9.549176] random: systemd-udevd: uninitialized urandom read (16 bytes read) [ 9.550171] random: systemd-udevd: uninitialized urandom read (16 bytes read) [ 9.606915] piix4_smbus 0000:00:01.3: SMBus base address uninitialized - upgrade BIOS or use force_addr=0xaddr [ 9.639227] ACPI: PCI Interrupt Link [LNKC] enabled at IRQ 11 [ 9.640130] virtio-pci 0000:00:03.0: virtio_pci: leaving for legacy driver [ 9.657179] ACPI: PCI Interrupt Link [LNKD] enabled at IRQ 10 [ 9.658308] virtio-pci 0000:00:04.0: virtio_pci: leaving for legacy driver [ 9.690813] ACPI: PCI Interrupt Link [LNKA] enabled at IRQ 10 [ 9.691865] virtio-pci 0000:00:05.0: virtio_pci: leaving for legacy driver [ 9.731534] AVX2 version of gcm_enc/dec engaged. [ 9.732519] AES CTR mode by8 optimization enabled [ 9.772631] SCSI subsystem initialized [ 9.872713] scsi host0: Virtio SCSI HBA [ 9.890775] scsi 0:0:1:0: Direct-Access Google PersistentDisk 1 PQ: 0 ANSI: 6 [ 9.984357] tsc: Refined TSC clocksource calibration: 1999.997 MHz [ 9.985746] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x39a856ed927, max_idle_ns: 881590446804 ns [ 10.242161] sd 0:0:1:0: [sda] 2097152000 512-byte logical blocks: (1.07 TB/1000 GiB) [ 10.243358] sd 0:0:1:0: [sda] 4096-byte physical blocks [ 10.244359] sd 0:0:1:0: [sda] Write Protect is off [ 10.245045] sd 0:0:1:0: [sda] Mode Sense: 1f 00 00 08 [ 10.245116] sd 0:0:1:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA [ 10.248238] sda: sda1 [ 10.249735] sd 0:0:1:0: [sda] Attached SCSI disk [ 10.279647] input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input2 [ 10.540223] EXT4-fs (sda1): mounted filesystem with ordered data mode. Opts: (null) [ 11.022645] ip_tables: (C) 2000-2006 Netfilter Core Team [ 11.096698] systemd[1]: systemd 232 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN) [ 11.099418] systemd[1]: Detected virtualization kvm. [ 11.100105] systemd[1]: Detected architecture x86-64. [ 11.118336] systemd[1]: No hostname configured. [ 11.119117] systemd[1]: Set hostname to . [ 11.322528] systemd[1]: Listening on udev Kernel Socket. [ 11.334294] systemd[1]: Reached target Remote File Systems. [ 11.346520] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. [ 11.358487] systemd[1]: Created slice User and Session Slice. [ 11.370402] systemd[1]: Created slice System Slice. [ 11.521838] EXT4-fs (sda1): re-mounted. Opts: (null) [ 11.878650] systemd-journald[1193]: Received request to flush runtime journal from PID 1 [ 11.899197] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3 [ 11.900376] ACPI: Power Button [PWRF] [ 11.900996] input: Sleep Button as /devices/LNXSYSTM:00/LNXSLPBN:00/input/input4 [ 11.902120] ACPI: Sleep Button [SLPF] [ 12.049831] random: crng init done [ 12.050500] RAPL PMU: API unit is 2^-32 Joules, 3 fixed counters, 10737418240 ms ovfl timer [ 12.050501] RAPL PMU: hw unit of domain pp0-core 2^-0 Joules [ 12.050502] RAPL PMU: hw unit of domain package 2^-0 Joules [ 12.050503] RAPL PMU: hw unit of domain dram 2^-16 Joules [ 12.056205] random: 7 urandom warning(s) missed due to ratelimiting [ 12.080894] sd 0:0:1:0: Attached scsi generic sg0 type 0 [ 12.489665] EDAC MC: Ver: 3.0.0 [ 24.189410] audit_printk_skb: 279 callbacks suppressed [ 24.189412] audit: type=1805 audit(1606814200.540:104): file="/bin/run-parts" hash="sha256:a37652fd9ee165d666902bd4e7a3967969dc6fc7ff0d7f017fab3260c93967e9" ppid=1467 pid=1468 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="sh" exe="/bin/dash" [ 24.223613] audit: type=1805 audit(1606814200.576:105): file="/etc/network/if-pre-up.d/ethtool" hash="sha256:736329a67c074e6acf2056508028977c2edd7d7fd408b0e054843920b18d1f7d" ppid=1468 pid=1469 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="run-parts" exe="/bin/run-parts" [ 24.262000] audit: type=1805 audit(1606814200.612:106): file="/bin/ip" hash="sha256:64c22760592fe8eb24d57e86d733b9ff3053b05f81fc402420762939ae833ced" ppid=1470 pid=1471 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="sh" exe="/bin/dash" [ 24.295166] audit: type=1805 audit(1606814200.648:107): file="/etc/network/if-up.d/ethtool" hash="sha256:445c0423f3e48697fd2f4161bab7b2ab7883aa398c68e26258c673adf6a0a032" ppid=1477 pid=1478 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="run-parts" exe="/bin/run-parts" [ 24.325787] audit: type=1805 audit(1606814200.676:108): file="/etc/network/if-up.d/openssh-server" hash="sha256:5071e3fc732bcec86283414ef02ce87604b9b43659b101160beb41624dab2bb5" ppid=1477 pid=1479 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="run-parts" exe="/bin/run-parts" [ 24.356846] audit: type=1805 audit(1606814200.708:109): file="/etc/network/if-up.d/upstart" hash="sha256:70af22cfd2d6f6afdd4988a6b3ad30c5d0e238fea5504f410796f509ed167d7e" ppid=1477 pid=1480 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="run-parts" exe="/bin/run-parts" [ 24.398301] audit: type=1805 audit(1606814200.752:110): file="/bin/systemctl" hash="sha256:1400724ed2115ed7002e0dc0bf55ad47b8208cfc5dc7a60c4329c1cbb4a81bbd" ppid=1480 pid=1483 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="upstart" exe="/bin/dash" [ 24.453502] audit: type=1805 audit(1606814200.804:111): file="/bin/readlink" hash="sha256:539ab7e307835455c670f725fafb9831df823ea59e64d11967b9bc43f7dec749" ppid=1480 pid=1484 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="upstart" exe="/bin/dash" [ 24.494044] audit: type=1805 audit(1606814200.844:112): file="/sbin/dhclient" hash="sha256:cf5ef9744cac3ee9c66096182e72844e526d6cc093912917c7cd0027af568734" ppid=1490 pid=1491 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="sh" exe="/bin/dash" [ 24.539344] audit: type=1805 audit(1606814200.892:113): file="/lib/x86_64-linux-gnu/libdns-export.so.162.1.3" hash="sha256:5db291c543db242696bf44b8ac1b6618c42b5ad07b07cb8d0fcc134c0ee47cde" ppid=1490 pid=1491 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="dhclient" exe="/sbin/dhclient" [ 63.030038] audit_printk_skb: 60 callbacks suppressed [ 63.030039] audit: type=1805 audit(1606814239.380:134): file="/sbin/augenrules" hash="sha256:ee794dde7900320b9e3d55e3d2c15216ba9eec60cec913fc6f9547a226b54934" ppid=1 pid=1608 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="(genrules)" exe="/lib/systemd/systemd" [ 63.031461] audit: type=1805 audit(1606814239.384:135): file="/sbin/auditd" hash="sha256:bcd093f329727d71cdfbb45d7aaf5839344f5b639ecca9b08aa288a52d6f48c6" ppid=1 pid=1607 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="(auditd)" exe="/lib/systemd/systemd" [ 63.033612] audit: type=1805 audit(1606814239.384:136): file="/lib/x86_64-linux-gnu/libwrap.so.0.7.6" hash="sha256:065b8519b6629ddc8a2192569f250b9cddeb143e565a1139636f47b6b225d097" ppid=1 pid=1607 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="auditd" exe="/sbin/auditd" [ 63.036199] audit: type=1805 audit(1606814239.388:137): file="/lib/x86_64-linux-gnu/libauparse.so.0.0.0" hash="sha256:f45cc3ede9a61a8fda6c68ab609c7507078261189266e6b62c0fc32ec7c0d8a6" ppid=1 pid=1607 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="auditd" exe="/sbin/auditd" [ 63.044941] audit: type=1805 audit(1606814239.396:138): file="/lib/x86_64-linux-gnu/libm-2.24.so" hash="sha256:bfba5a97dcaf2450aa9c2b0c505b42c4eb2db9f0312c594e43d81e79a5f8c4f1" ppid=1 pid=1607 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="auditd" exe="/sbin/auditd" [ 63.051279] audit: type=1805 audit(1606814239.404:139): file="/usr/lib/x86_64-linux-gnu/libgssapi_krb5.so.2.2" hash="sha256:e296a08d75af0526b34be9a3b91b95100738ad130481e96f169c29d75e4724f9" ppid=1 pid=1607 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="auditd" exe="/sbin/auditd" [ 63.062046] audit: type=1805 audit(1606814239.412:140): file="/usr/lib/x86_64-linux-gnu/libkrb5.so.3.3" hash="sha256:6f04fb8c85d914e7302f08fe577821ff5981aac0c2d6a06b27f467361309cda9" ppid=1 pid=1607 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="auditd" exe="/sbin/auditd" [ 63.065512] audit: type=1805 audit(1606814239.416:141): file="/usr/lib/x86_64-linux-gnu/libk5crypto.so.3.1" hash="sha256:93afd15edc1b1d9b9213097ee073fe585c3b49befc5c6b82f495be324090b8a6" ppid=1 pid=1607 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="auditd" exe="/sbin/auditd" [ 63.067523] audit: type=1805 audit(1606814239.420:142): file="/lib/x86_64-linux-gnu/libcom_err.so.2.1" hash="sha256:b4bd5c7239562701a201c0ad1cb07a843a03b8aa00b3fe93ff203abd36ea6bd9" ppid=1 pid=1607 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="auditd" exe="/sbin/auditd" [ 63.070325] audit: type=1805 audit(1606814239.424:143): file="/usr/lib/x86_64-linux-gnu/libkrb5support.so.0.1" hash="sha256:86ac49adf63f57787fa3809d4b37185f1afd52af57513fa8f2f437fb2a2f8115" ppid=1 pid=1607 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="auditd" exe="/sbin/auditd" [ 106.693819] raid6: sse2x1 gen() 9811 MB/s [ 106.765805] raid6: sse2x1 xor() 7343 MB/s [ 106.837799] raid6: sse2x2 gen() 12286 MB/s [ 106.909789] raid6: sse2x2 xor() 8284 MB/s [ 106.981784] raid6: sse2x4 gen() 13534 MB/s [ 107.053781] raid6: sse2x4 xor() 9046 MB/s [ 107.125792] raid6: avx2x1 gen() 17250 MB/s [ 107.197876] raid6: avx2x2 gen() 22731 MB/s [ 107.269766] raid6: avx2x4 gen() 23857 MB/s [ 107.341763] raid6: avx512x1 gen() 24255 MB/s [ 107.413738] raid6: avx512x1 xor() 13393 MB/s [ 107.485840] raid6: avx512x2 gen() 28792 MB/s [ 107.557824] raid6: avx512x2 xor() 16048 MB/s [ 107.629720] raid6: avx512x4 gen() 30083 MB/s [ 107.701709] raid6: avx512x4 xor() 17921 MB/s [ 107.706103] raid6: using algorithm avx512x4 gen() 30083 MB/s [ 107.713280] raid6: .... xor() 17921 MB/s, rmw enabled [ 107.719835] raid6: using avx512x2 recovery algorithm [ 107.731053] xor: automatically using best checksumming function avx [ 107.765158] Btrfs loaded, crc32c=crc32c-intel [ 107.778141] fuse init (API version 7.26) [ 107.801823] loop: module loaded [ 107.928383] PPP generic driver version 2.4.2 [ 107.973244] tun: Universal TUN/TAP device driver, 1.6 [ 107.979502] tun: (C) 1999-2004 Max Krasnyansky [ 107.997341] hidraw: raw HID events driver (C) Jiri Kosina [ 108.024470] VFIO - User Level meta-driver version: 0.3 [ 108.083071] NET: Registered protocol family 38 [ 108.113687] Bluetooth: Core ver 2.22 [ 108.118501] NET: Registered protocol family 31 [ 108.124580] Bluetooth: HCI device and connection manager initialized [ 108.131096] Bluetooth: HCI socket layer initialized [ 108.137501] Bluetooth: L2CAP socket layer initialized [ 108.144083] Bluetooth: SCO socket layer initialized [ 108.156246] Bluetooth: BNEP (Ethernet Emulation) ver 1.3 [ 108.163315] Bluetooth: BNEP filters: protocol multicast [ 108.168762] Bluetooth: BNEP socket layer initialized [ 108.192102] Bluetooth: CMTP (CAPI Emulation) ver 1.0 [ 108.197866] Bluetooth: CMTP socket layer initialized [ 108.210983] Bluetooth: HIDP (Human Interface Emulation) ver 1.2 [ 108.217693] Bluetooth: HIDP socket layer initialized [ 108.229529] Bluetooth: RFCOMM TTY layer initialized [ 108.234774] Bluetooth: RFCOMM socket layer initialized [ 108.240041] Bluetooth: RFCOMM ver 1.11 [ 108.253644] can: controller area network core (rev 20120528 abi 9) [ 108.260039] NET: Registered protocol family 29 [ 108.268198] can: request_module (can-proto-0) failed. [ 108.278100] can: broadcast manager protocol (rev 20161123 t) [ 108.286066] can: request_module (can-proto-0) failed. [ 108.292867] can: request_module (can-proto-0) failed. [ 108.302520] can: raw protocol (rev 20120528) [ 108.337435] sctp: Hash tables configured (bind 16384/16384) [ 108.390274] NET: Registered protocol family 4 [ 108.409058] NET: Registered protocol family 15 [ 108.522399] l2tp_core: L2TP core driver, V2.0 [ 108.530957] l2tp_ip: L2TP IP encapsulation support (L2TPv3) [ 108.545171] l2tp_ip6: L2TP IP encapsulation support for IPv6 (L2TPv3) [ 108.560845] Netfilter messages via NETLINK v0.30. [ 108.690657] L1TF CPU bug present and SMT on, data leak possible. See CVE-2018-3646 and https://www.kernel.org/doc/html/latest/admin-guide/hw-vuln/l1tf.html for details. [ 109.157931] Initializing XFRM netlink socket [ 111.102056] NET: Registered protocol family 35 [ 111.167932] NET: Registered protocol family 24 [ 111.184128] l2tp_netlink: L2TP netlink interface [ 111.195208] l2tp_ppp: PPPoL2TP kernel driver, V2.0 [ 111.205556] gre: GRE over IPv4 demultiplexor driver [ 111.214922] PPTP driver version 0.8.5 [ 111.280409] NET: Registered protocol family 33 [ 111.286209] Key type rxrpc registered [ 111.290015] Key type rxrpc_s registered [ 111.307031] Guest personality initialized and is inactive [ 111.313514] VMCI host device registered (name=vmci, major=10, minor=57) [ 111.321607] Initialized host personality [ 111.335242] NET: Registered protocol family 40 [ 111.355778] NET: Registered protocol family 3 [ 111.369264] NET: Registered protocol family 26 [ 111.382382] NET: Registered protocol family 6 [ 111.396025] nfc: nfc_init: NFC Core ver 0.1 [ 111.401384] NET: Registered protocol family 39 [ 111.414398] NET: Registered protocol family 11 [ 113.313734] mmap: host.test (15750) uses deprecated remap_file_pages() syscall. See Documentation/vm/remap_file_pages.txt. [ 113.874337] can: request_module (can-proto-0) failed. [ 113.881337] can: request_module (can-proto-0) failed. [ 113.887930] can: request_module (can-proto-0) failed. [ 114.592744] can: request_module (can-proto-0) failed. [ 114.600173] can: request_module (can-proto-0) failed. [ 114.606580] can: request_module (can-proto-0) failed. [ 272.002597] cgroup: cgroup: disabling cgroup2 socket matching due to net_prio or net_cls activation [43452.341695] can: request_module (can-proto-0) failed. [43452.365405] can: request_module (can-proto-0) failed. [43452.381107] can: request_module (can-proto-0) failed. [43471.093939] can: request_module (can-proto-0) failed. [43471.106074] can: request_module (can-proto-0) failed. [43471.118628] can: request_module (can-proto-0) failed. [43475.170827] can: request_module (can-proto-0) failed. [43475.195958] can: request_module (can-proto-0) failed. [43475.208483] can: request_module (can-proto-0) failed. [122288.353429] can: request_module (can-proto-0) failed. [122288.361961] can: request_module (can-proto-0) failed. [122288.368993] can: request_module (can-proto-0) failed. [122294.143587] can: request_module (can-proto-0) failed. [122294.154717] can: request_module (can-proto-0) failed. [122294.164141] can: request_module (can-proto-0) failed. [122296.055656] can: request_module (can-proto-0) failed. [122296.062971] can: request_module (can-proto-0) failed. [122296.071796] can: request_module (can-proto-0) failed. [172266.594320] can: request_module (can-proto-0) failed. [172266.602057] can: request_module (can-proto-0) failed. [172266.612342] can: request_module (can-proto-0) failed. [172273.992159] can: request_module (can-proto-0) failed. [172274.000812] can: request_module (can-proto-0) failed. [172274.010597] can: request_module (can-proto-0) failed. [172275.226158] can: request_module (can-proto-0) failed. [172275.234265] can: request_module (can-proto-0) failed. [172275.242126] can: request_module (can-proto-0) failed. [176405.702914] can: request_module (can-proto-0) failed. [176405.709506] can: request_module (can-proto-0) failed. [176405.715739] can: request_module (can-proto-0) failed. [176410.147513] can: request_module (can-proto-0) failed. [176410.153949] can: request_module (can-proto-0) failed. [176410.160738] can: request_module (can-proto-0) failed. [176412.307446] can: request_module (can-proto-0) failed. [176412.314384] can: request_module (can-proto-0) failed. [176412.320796] can: request_module (can-proto-0) failed. [271815.868246] can: request_module (can-proto-0) failed. [271815.885388] can: request_module (can-proto-0) failed. [271815.916333] can: request_module (can-proto-0) failed. [271830.748716] can: request_module (can-proto-0) failed. [271830.764714] can: request_module (can-proto-0) failed. [271830.781862] can: request_module (can-proto-0) failed. [271833.172502] can: request_module (can-proto-0) failed. [271833.194601] can: request_module (can-proto-0) failed. [271833.206941] can: request_module (can-proto-0) failed. [309074.567807] nr_pdflush_threads exported in /proc is scheduled for removal [337378.342605] can: request_module (can-proto-0) failed. [337378.352849] can: request_module (can-proto-0) failed. [337378.367090] can: request_module (can-proto-0) failed. [337385.331270] can: request_module (can-proto-0) failed. [337385.340175] can: request_module (can-proto-0) failed. [337385.348548] can: request_module (can-proto-0) failed. [337388.379290] can: request_module (can-proto-0) failed. [337388.387184] can: request_module (can-proto-0) failed. [337388.395744] can: request_module (can-proto-0) failed. [380756.133606] can: request_module (can-proto-0) failed. [380756.149734] can: request_module (can-proto-0) failed. [380756.162475] can: request_module (can-proto-0) failed. [380770.092937] can: request_module (can-proto-0) failed. [380770.103390] can: request_module (can-proto-0) failed. [380770.114730] can: request_module (can-proto-0) failed. [380772.900919] can: request_module (can-proto-0) failed. [380772.910236] can: request_module (can-proto-0) failed. [380772.920694] can: request_module (can-proto-0) failed. [424098.033220] can: request_module (can-proto-0) failed. [424098.049313] can: request_module (can-proto-0) failed. [424098.059736] can: request_module (can-proto-0) failed. [424115.098835] can: request_module (can-proto-0) failed. [424115.109180] can: request_module (can-proto-0) failed. [424115.117407] can: request_module (can-proto-0) failed. [424117.827492] can: request_module (can-proto-0) failed. [424117.845954] can: request_module (can-proto-0) failed. [424117.856036] can: request_module (can-proto-0) failed. [467439.537049] can: request_module (can-proto-0) failed. [467439.549735] can: request_module (can-proto-0) failed. [467439.569192] can: request_module (can-proto-0) failed. [467459.711860] can: request_module (can-proto-0) failed. [467459.732807] can: request_module (can-proto-0) failed. [467459.748457] can: request_module (can-proto-0) failed. [467462.890848] can: request_module (can-proto-0) failed. [467462.903389] can: request_module (can-proto-0) failed. [467462.914339] can: request_module (can-proto-0) failed. [510789.495061] can: request_module (can-proto-0) failed. [510789.518404] can: request_module (can-proto-0) failed. [510789.540298] can: request_module (can-proto-0) failed. [510810.405377] can: request_module (can-proto-0) failed. [510810.427791] can: request_module (can-proto-0) failed. [510810.443761] can: request_module (can-proto-0) failed. [510813.042666] can: request_module (can-proto-0) failed. [510813.059465] can: request_module (can-proto-0) failed. [510813.082673] can: request_module (can-proto-0) failed. [554177.289901] can: request_module (can-proto-0) failed. [554177.303556] can: request_module (can-proto-0) failed. [554177.320296] can: request_module (can-proto-0) failed. [554191.724065] can: request_module (can-proto-0) failed. [554191.742006] can: request_module (can-proto-0) failed. [554191.757726] can: request_module (can-proto-0) failed. [554195.413933] can: request_module (can-proto-0) failed. [554195.427627] can: request_module (can-proto-0) failed. [554195.439429] can: request_module (can-proto-0) failed. [597551.863327] can: request_module (can-proto-0) failed. [597551.877420] can: request_module (can-proto-0) failed. [597551.911703] can: request_module (can-proto-0) failed. [597565.972539] can: request_module (can-proto-0) failed. [597565.983412] can: request_module (can-proto-0) failed. [597565.997873] can: request_module (can-proto-0) failed. [597569.338180] can: request_module (can-proto-0) failed. [597569.345802] can: request_module (can-proto-0) failed. [597569.355881] can: request_module (can-proto-0) failed. [640888.084117] can: request_module (can-proto-0) failed. [640888.094555] can: request_module (can-proto-0) failed. [640888.108559] can: request_module (can-proto-0) failed. [640906.222514] can: request_module (can-proto-0) failed. [640906.234967] can: request_module (can-proto-0) failed. [640906.248002] can: request_module (can-proto-0) failed. [640908.904605] can: request_module (can-proto-0) failed. [640908.915909] can: request_module (can-proto-0) failed. [640908.927610] can: request_module (can-proto-0) failed. [684238.102492] can: request_module (can-proto-0) failed. [684238.111773] can: request_module (can-proto-0) failed. [684238.120275] can: request_module (can-proto-0) failed. [684247.116739] can: request_module (can-proto-0) failed. [684247.126760] can: request_module (can-proto-0) failed. [684247.135239] can: request_module (can-proto-0) failed. [684248.704473] can: request_module (can-proto-0) failed. [684248.713705] can: request_module (can-proto-0) failed. [684248.723566] can: request_module (can-proto-0) failed. [727586.160641] can: request_module (can-proto-0) failed. [727586.175412] can: request_module (can-proto-0) failed. [727586.188065] can: request_module (can-proto-0) failed. [727604.586092] can: request_module (can-proto-0) failed. [727604.605122] can: request_module (can-proto-0) failed. [727604.629035] can: request_module (can-proto-0) failed. [727607.101662] can: request_module (can-proto-0) failed. [727607.112371] can: request_module (can-proto-0) failed. [727607.147524] can: request_module (can-proto-0) failed. [779982.169138] can: request_module (can-proto-0) failed. [779982.179572] can: request_module (can-proto-0) failed. [779982.191750] can: request_module (can-proto-0) failed. [779995.202353] can: request_module (can-proto-0) failed. [779995.211805] can: request_module (can-proto-0) failed. [779995.220374] can: request_module (can-proto-0) failed. [779998.493611] can: request_module (can-proto-0) failed. [779998.502225] can: request_module (can-proto-0) failed. [779998.510817] can: request_module (can-proto-0) failed. [823326.617822] can: request_module (can-proto-0) failed. [823326.644056] can: request_module (can-proto-0) failed. [823326.655205] can: request_module (can-proto-0) failed. [823343.878313] can: request_module (can-proto-0) failed. [823343.892671] can: request_module (can-proto-0) failed. [823343.904426] can: request_module (can-proto-0) failed. [823346.995888] can: request_module (can-proto-0) failed. [823347.010299] can: request_module (can-proto-0) failed. [823347.022427] can: request_module (can-proto-0) failed. [866679.539735] can: request_module (can-proto-0) failed. [866679.550425] can: request_module (can-proto-0) failed. [866679.562504] can: request_module (can-proto-0) failed. [866693.257613] can: request_module (can-proto-0) failed. [866693.271305] can: request_module (can-proto-0) failed. [866693.295645] can: request_module (can-proto-0) failed. [866695.858773] can: request_module (can-proto-0) failed. [866695.871106] can: request_module (can-proto-0) failed. [866695.881770] can: request_module (can-proto-0) failed. [910068.366935] can: request_module (can-proto-0) failed. [910068.394696] can: request_module (can-proto-0) failed. [910068.412516] can: request_module (can-proto-0) failed. [910086.238675] can: request_module (can-proto-0) failed. [910086.254117] can: request_module (can-proto-0) failed. [910086.266140] can: request_module (can-proto-0) failed. [910090.059562] can: request_module (can-proto-0) failed. [910090.072699] can: request_module (can-proto-0) failed. [910090.086934] can: request_module (can-proto-0) failed. [1068283.244801] can: request_module (can-proto-0) failed. [1068283.254981] can: request_module (can-proto-0) failed. [1068283.268514] can: request_module (can-proto-0) failed. [1068298.297266] can: request_module (can-proto-0) failed. [1068298.312036] can: request_module (can-proto-0) failed. [1068298.322308] can: request_module (can-proto-0) failed. [1068300.408041] can: request_module (can-proto-0) failed. [1068300.419949] can: request_module (can-proto-0) failed. [1068300.430710] can: request_module (can-proto-0) failed. [1072036.582536] can: request_module (can-proto-0) failed. [1072036.589027] can: request_module (can-proto-0) failed. [1072036.595412] can: request_module (can-proto-0) failed. [1072040.357303] can: request_module (can-proto-0) failed. [1072040.364471] can: request_module (can-proto-0) failed. [1072040.371064] can: request_module (can-proto-0) failed. [1072041.188705] can: request_module (can-proto-0) failed. [1072041.196113] can: request_module (can-proto-0) failed. [1072041.202765] can: request_module (can-proto-0) failed. [1243380.249284] can: request_module (can-proto-0) failed. [1243380.264246] can: request_module (can-proto-0) failed. [1243380.276511] can: request_module (can-proto-0) failed. [1243400.279724] can: request_module (can-proto-0) failed. [1243400.295757] can: request_module (can-proto-0) failed. [1243400.310942] can: request_module (can-proto-0) failed. [1243403.629386] can: request_module (can-proto-0) failed. [1243403.642130] can: request_module (can-proto-0) failed. [1243403.672153] can: request_module (can-proto-0) failed. [1286717.450179] can: request_module (can-proto-0) failed. [1286717.458325] can: request_module (can-proto-0) failed. [1286717.467211] can: request_module (can-proto-0) failed. [1286724.427141] can: request_module (can-proto-0) failed. [1286724.436298] can: request_module (can-proto-0) failed. [1286724.444848] can: request_module (can-proto-0) failed. [1286727.201392] can: request_module (can-proto-0) failed. [1286727.209889] can: request_module (can-proto-0) failed. [1286727.220087] can: request_module (can-proto-0) failed. [1330063.223524] can: request_module (can-proto-0) failed. [1330063.250662] can: request_module (can-proto-0) failed. [1330063.277241] can: request_module (can-proto-0) failed. [1330077.062552] can: request_module (can-proto-0) failed. [1330077.082710] can: request_module (can-proto-0) failed. [1330077.099130] can: request_module (can-proto-0) failed. [1330080.063723] can: request_module (can-proto-0) failed. [1330080.073133] can: request_module (can-proto-0) failed. [1330080.088602] can: request_module (can-proto-0) failed.