Feb 9 18:29:24.945881 kernel: SELinux: Class mctp_socket not defined in policy. Feb 9 18:29:24.945901 kernel: SELinux: Class anon_inode not defined in policy. Feb 9 18:29:24.945911 kernel: SELinux: the above unknown classes and permissions will be allowed Feb 9 18:29:24.945921 kernel: SELinux: policy capability network_peer_controls=1 Feb 9 18:29:24.945929 kernel: SELinux: policy capability open_perms=1 Feb 9 18:29:24.945937 kernel: SELinux: policy capability extended_socket_class=1 Feb 9 18:29:24.945946 kernel: SELinux: policy capability always_check_network=0 Feb 9 18:29:24.945954 kernel: SELinux: policy capability cgroup_seclabel=1 Feb 9 18:29:24.945962 kernel: SELinux: policy capability nnp_nosuid_transition=1 Feb 9 18:29:24.945970 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Feb 9 18:29:24.945979 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Feb 9 18:29:24.945988 systemd[1]: Successfully loaded SELinux policy in 255.844ms. Feb 9 18:29:24.945998 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 19.878ms. Feb 9 18:29:24.946008 systemd[1]: systemd 252 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL -ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE -TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified) Feb 9 18:29:24.946021 systemd[1]: Detected virtualization microsoft. Feb 9 18:29:24.946030 systemd[1]: Detected architecture arm64. Feb 9 18:29:24.946038 systemd[1]: Detected first boot. Feb 9 18:29:24.946047 systemd[1]: Hostname set to . Feb 9 18:29:24.946056 systemd[1]: Initializing machine ID from random generator. Feb 9 18:29:24.946065 kernel: SELinux: Context system_u:object_r:container_file_t:s0:c1022,c1023 is not valid (left unmapped). Feb 9 18:29:24.946073 kernel: kauditd_printk_skb: 47 callbacks suppressed Feb 9 18:29:24.946084 kernel: audit: type=1400 audit(1707503355.125:89): avc: denied { associate } for pid=1134 comm="torcx-generator" name="docker" dev="tmpfs" ino=2 scontext=system_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:tmpfs_t:s0 tclass=filesystem permissive=1 srawcon="system_u:object_r:container_file_t:s0:c1022,c1023" Feb 9 18:29:24.946095 kernel: audit: type=1300 audit(1707503355.125:89): arch=c00000b7 syscall=5 success=yes exit=0 a0=4000022824 a1=4000028ac8 a2=4000026d00 a3=32 items=0 ppid=1117 pid=1134 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="torcx-generator" exe="/usr/lib/systemd/system-generators/torcx-generator" subj=system_u:system_r:kernel_t:s0 key=(null) Feb 9 18:29:24.946105 kernel: audit: type=1327 audit(1707503355.125:89): proctitle=2F7573722F6C69622F73797374656D642F73797374656D2D67656E657261746F72732F746F7263782D67656E657261746F72002F72756E2F73797374656D642F67656E657261746F72002F72756E2F73797374656D642F67656E657261746F722E6561726C79002F72756E2F73797374656D642F67656E657261746F722E6C61 Feb 9 18:29:24.946114 kernel: audit: type=1400 audit(1707503355.134:90): avc: denied { associate } for pid=1134 comm="torcx-generator" name="usr" scontext=system_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:tmpfs_t:s0 tclass=filesystem permissive=1 Feb 9 18:29:24.946124 kernel: audit: type=1300 audit(1707503355.134:90): arch=c00000b7 syscall=34 success=yes exit=0 a0=ffffffffffffff9c a1=4000022909 a2=1ed a3=0 items=2 ppid=1117 pid=1134 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="torcx-generator" exe="/usr/lib/systemd/system-generators/torcx-generator" subj=system_u:system_r:kernel_t:s0 key=(null) Feb 9 18:29:24.946132 kernel: audit: type=1307 audit(1707503355.134:90): cwd="/" Feb 9 18:29:24.946143 kernel: audit: type=1302 audit(1707503355.134:90): item=0 name=(null) inode=2 dev=00:1c mode=040755 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:unlabeled_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Feb 9 18:29:24.946152 kernel: audit: type=1302 audit(1707503355.134:90): item=1 name=(null) inode=3 dev=00:1c mode=040755 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:unlabeled_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Feb 9 18:29:24.946161 kernel: audit: type=1327 audit(1707503355.134:90): proctitle=2F7573722F6C69622F73797374656D642F73797374656D2D67656E657261746F72732F746F7263782D67656E657261746F72002F72756E2F73797374656D642F67656E657261746F72002F72756E2F73797374656D642F67656E657261746F722E6561726C79002F72756E2F73797374656D642F67656E657261746F722E6C61 Feb 9 18:29:24.946170 systemd[1]: Populated /etc with preset unit settings. Feb 9 18:29:24.946179 systemd[1]: /usr/lib/systemd/system/locksmithd.service:8: Unit uses CPUShares=; please use CPUWeight= instead. Support for CPUShares= will be removed soon. Feb 9 18:29:24.946189 systemd[1]: /usr/lib/systemd/system/locksmithd.service:9: Unit uses MemoryLimit=; please use MemoryMax= instead. Support for MemoryLimit= will be removed soon. Feb 9 18:29:24.946204 systemd[1]: /run/systemd/system/docker.socket:8: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Feb 9 18:29:24.946215 kernel: audit: type=1334 audit(1707503364.132:91): prog-id=12 op=LOAD Feb 9 18:29:24.946224 systemd[1]: initrd-switch-root.service: Deactivated successfully. Feb 9 18:29:24.946233 kernel: audit: type=1334 audit(1707503364.132:92): prog-id=3 op=UNLOAD Feb 9 18:29:24.946242 systemd[1]: Stopped initrd-switch-root.service. Feb 9 18:29:24.946251 kernel: audit: type=1334 audit(1707503364.132:93): prog-id=13 op=LOAD Feb 9 18:29:24.946260 kernel: audit: type=1334 audit(1707503364.132:94): prog-id=14 op=LOAD Feb 9 18:29:24.946271 kernel: audit: type=1334 audit(1707503364.132:95): prog-id=4 op=UNLOAD Feb 9 18:29:24.946281 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Feb 9 18:29:24.946290 kernel: audit: type=1334 audit(1707503364.132:96): prog-id=5 op=UNLOAD Feb 9 18:29:24.946299 systemd[1]: Created slice system-addon\x2dconfig.slice. Feb 9 18:29:24.946308 kernel: audit: type=1334 audit(1707503364.133:97): prog-id=15 op=LOAD Feb 9 18:29:24.946317 kernel: audit: type=1334 audit(1707503364.133:98): prog-id=12 op=UNLOAD Feb 9 18:29:24.946326 kernel: audit: type=1334 audit(1707503364.133:99): prog-id=16 op=LOAD Feb 9 18:29:24.946334 kernel: audit: type=1334 audit(1707503364.133:100): prog-id=17 op=LOAD Feb 9 18:29:24.946343 systemd[1]: Created slice system-addon\x2drun.slice. Feb 9 18:29:24.946354 systemd[1]: Created slice system-getty.slice. Feb 9 18:29:24.946363 systemd[1]: Created slice system-modprobe.slice. Feb 9 18:29:24.946373 systemd[1]: Created slice system-serial\x2dgetty.slice. Feb 9 18:29:24.946382 systemd[1]: Created slice system-system\x2dcloudinit.slice. Feb 9 18:29:24.946392 systemd[1]: Created slice system-systemd\x2dfsck.slice. Feb 9 18:29:24.946401 systemd[1]: Created slice user.slice. Feb 9 18:29:24.946410 systemd[1]: Started systemd-ask-password-console.path. Feb 9 18:29:24.946419 systemd[1]: Started systemd-ask-password-wall.path. Feb 9 18:29:24.946428 systemd[1]: Set up automount boot.automount. Feb 9 18:29:24.946438 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount. Feb 9 18:29:24.946448 systemd[1]: Stopped target initrd-switch-root.target. Feb 9 18:29:24.946457 systemd[1]: Stopped target initrd-fs.target. Feb 9 18:29:24.946466 systemd[1]: Stopped target initrd-root-fs.target. Feb 9 18:29:24.946475 systemd[1]: Reached target integritysetup.target. Feb 9 18:29:24.946484 systemd[1]: Reached target remote-cryptsetup.target. Feb 9 18:29:24.946493 systemd[1]: Reached target remote-fs.target. Feb 9 18:29:24.946502 systemd[1]: Reached target slices.target. Feb 9 18:29:24.946513 systemd[1]: Reached target swap.target. Feb 9 18:29:24.946522 systemd[1]: Reached target torcx.target. Feb 9 18:29:24.946531 systemd[1]: Reached target veritysetup.target. Feb 9 18:29:24.946541 systemd[1]: Listening on systemd-coredump.socket. Feb 9 18:29:24.946550 systemd[1]: Listening on systemd-initctl.socket. Feb 9 18:29:24.946560 systemd[1]: Listening on systemd-networkd.socket. Feb 9 18:29:24.946571 systemd[1]: Listening on systemd-udevd-control.socket. Feb 9 18:29:24.946580 systemd[1]: Listening on systemd-udevd-kernel.socket. Feb 9 18:29:24.946589 systemd[1]: Listening on systemd-userdbd.socket. Feb 9 18:29:24.946606 systemd[1]: Mounting dev-hugepages.mount... Feb 9 18:29:24.946617 systemd[1]: Mounting dev-mqueue.mount... Feb 9 18:29:24.946627 systemd[1]: Mounting media.mount... Feb 9 18:29:24.946636 systemd[1]: Mounting sys-kernel-debug.mount... Feb 9 18:29:24.946646 systemd[1]: Mounting sys-kernel-tracing.mount... Feb 9 18:29:24.946657 systemd[1]: Mounting tmp.mount... Feb 9 18:29:24.946667 systemd[1]: Starting flatcar-tmpfiles.service... Feb 9 18:29:24.946677 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. Feb 9 18:29:24.946686 systemd[1]: Starting kmod-static-nodes.service... Feb 9 18:29:24.946696 systemd[1]: Starting modprobe@configfs.service... Feb 9 18:29:24.946705 systemd[1]: Starting modprobe@dm_mod.service... Feb 9 18:29:24.946715 systemd[1]: Starting modprobe@drm.service... Feb 9 18:29:24.946724 systemd[1]: Starting modprobe@efi_pstore.service... Feb 9 18:29:24.946733 systemd[1]: Starting modprobe@fuse.service... Feb 9 18:29:24.946744 systemd[1]: Starting modprobe@loop.service... Feb 9 18:29:24.946754 systemd[1]: setup-nsswitch.service was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Feb 9 18:29:24.946763 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Feb 9 18:29:24.946773 systemd[1]: Stopped systemd-fsck-root.service. Feb 9 18:29:24.946782 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Feb 9 18:29:24.946791 systemd[1]: Stopped systemd-fsck-usr.service. Feb 9 18:29:24.946801 systemd[1]: Stopped systemd-journald.service. Feb 9 18:29:24.946810 systemd[1]: systemd-journald.service: Consumed 2.777s CPU time. Feb 9 18:29:24.946820 systemd[1]: Starting systemd-journald.service... Feb 9 18:29:24.946830 kernel: loop: module loaded Feb 9 18:29:24.946839 systemd[1]: Starting systemd-modules-load.service... Feb 9 18:29:24.946848 systemd[1]: Starting systemd-network-generator.service... Feb 9 18:29:24.946858 systemd[1]: Starting systemd-remount-fs.service... Feb 9 18:29:24.946867 kernel: fuse: init (API version 7.34) Feb 9 18:29:24.946876 systemd[1]: Starting systemd-udev-trigger.service... Feb 9 18:29:24.946886 systemd[1]: verity-setup.service: Deactivated successfully. Feb 9 18:29:24.946895 systemd[1]: Stopped verity-setup.service. Feb 9 18:29:24.946905 systemd[1]: Mounted dev-hugepages.mount. Feb 9 18:29:24.946915 systemd[1]: Mounted dev-mqueue.mount. Feb 9 18:29:24.946924 systemd[1]: Mounted media.mount. Feb 9 18:29:24.946933 systemd[1]: Mounted sys-kernel-debug.mount. Feb 9 18:29:24.946943 systemd[1]: Mounted sys-kernel-tracing.mount. Feb 9 18:29:24.946952 systemd[1]: Mounted tmp.mount. Feb 9 18:29:24.946961 systemd[1]: Finished flatcar-tmpfiles.service. Feb 9 18:29:24.946970 systemd[1]: Finished kmod-static-nodes.service. Feb 9 18:29:24.946980 systemd[1]: modprobe@configfs.service: Deactivated successfully. Feb 9 18:29:24.946990 systemd[1]: Finished modprobe@configfs.service. Feb 9 18:29:24.946999 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Feb 9 18:29:24.947012 systemd-journald[1240]: Journal started Feb 9 18:29:24.947053 systemd-journald[1240]: Runtime Journal (/run/log/journal/02f4bec27668455cbbd3e1d2ce44d1d5) is 8.0M, max 78.6M, 70.6M free. Feb 9 18:29:13.158000 audit: MAC_POLICY_LOAD auid=4294967295 ses=4294967295 lsm=selinux res=1 Feb 9 18:29:13.701000 audit[1]: AVC avc: denied { bpf } for pid=1 comm="systemd" capability=39 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=capability2 permissive=1 Feb 9 18:29:13.701000 audit[1]: AVC avc: denied { perfmon } for pid=1 comm="systemd" capability=38 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=capability2 permissive=1 Feb 9 18:29:13.701000 audit: BPF prog-id=10 op=LOAD Feb 9 18:29:13.701000 audit: BPF prog-id=10 op=UNLOAD Feb 9 18:29:13.701000 audit: BPF prog-id=11 op=LOAD Feb 9 18:29:13.701000 audit: BPF prog-id=11 op=UNLOAD Feb 9 18:29:15.125000 audit[1134]: AVC avc: denied { associate } for pid=1134 comm="torcx-generator" name="docker" dev="tmpfs" ino=2 scontext=system_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:tmpfs_t:s0 tclass=filesystem permissive=1 srawcon="system_u:object_r:container_file_t:s0:c1022,c1023" Feb 9 18:29:15.125000 audit[1134]: SYSCALL arch=c00000b7 syscall=5 success=yes exit=0 a0=4000022824 a1=4000028ac8 a2=4000026d00 a3=32 items=0 ppid=1117 pid=1134 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="torcx-generator" exe="/usr/lib/systemd/system-generators/torcx-generator" subj=system_u:system_r:kernel_t:s0 key=(null) Feb 9 18:29:15.125000 audit: PROCTITLE proctitle=2F7573722F6C69622F73797374656D642F73797374656D2D67656E657261746F72732F746F7263782D67656E657261746F72002F72756E2F73797374656D642F67656E657261746F72002F72756E2F73797374656D642F67656E657261746F722E6561726C79002F72756E2F73797374656D642F67656E657261746F722E6C61 Feb 9 18:29:15.134000 audit[1134]: AVC avc: denied { associate } for pid=1134 comm="torcx-generator" name="usr" scontext=system_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:tmpfs_t:s0 tclass=filesystem permissive=1 Feb 9 18:29:15.134000 audit[1134]: SYSCALL arch=c00000b7 syscall=34 success=yes exit=0 a0=ffffffffffffff9c a1=4000022909 a2=1ed a3=0 items=2 ppid=1117 pid=1134 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="torcx-generator" exe="/usr/lib/systemd/system-generators/torcx-generator" subj=system_u:system_r:kernel_t:s0 key=(null) Feb 9 18:29:15.134000 audit: CWD cwd="/" Feb 9 18:29:15.134000 audit: PATH item=0 name=(null) inode=2 dev=00:1c mode=040755 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:unlabeled_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Feb 9 18:29:15.134000 audit: PATH item=1 name=(null) inode=3 dev=00:1c mode=040755 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:unlabeled_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Feb 9 18:29:15.134000 audit: PROCTITLE proctitle=2F7573722F6C69622F73797374656D642F73797374656D2D67656E657261746F72732F746F7263782D67656E657261746F72002F72756E2F73797374656D642F67656E657261746F72002F72756E2F73797374656D642F67656E657261746F722E6561726C79002F72756E2F73797374656D642F67656E657261746F722E6C61 Feb 9 18:29:24.132000 audit: BPF prog-id=12 op=LOAD Feb 9 18:29:24.132000 audit: BPF prog-id=3 op=UNLOAD Feb 9 18:29:24.132000 audit: BPF prog-id=13 op=LOAD Feb 9 18:29:24.132000 audit: BPF prog-id=14 op=LOAD Feb 9 18:29:24.132000 audit: BPF prog-id=4 op=UNLOAD Feb 9 18:29:24.132000 audit: BPF prog-id=5 op=UNLOAD Feb 9 18:29:24.133000 audit: BPF prog-id=15 op=LOAD Feb 9 18:29:24.133000 audit: BPF prog-id=12 op=UNLOAD Feb 9 18:29:24.133000 audit: BPF prog-id=16 op=LOAD Feb 9 18:29:24.133000 audit: BPF prog-id=17 op=LOAD Feb 9 18:29:24.133000 audit: BPF prog-id=13 op=UNLOAD Feb 9 18:29:24.133000 audit: BPF prog-id=14 op=UNLOAD Feb 9 18:29:24.134000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:29:24.163000 audit: BPF prog-id=15 op=UNLOAD Feb 9 18:29:24.179000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=initrd-switch-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:29:24.179000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=initrd-switch-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:29:24.712000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-fsck-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:29:24.730000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-fsck-usr comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:29:24.742000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:29:24.742000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:29:24.749000 audit: BPF prog-id=18 op=LOAD Feb 9 18:29:24.749000 audit: BPF prog-id=19 op=LOAD Feb 9 18:29:24.749000 audit: BPF prog-id=20 op=LOAD Feb 9 18:29:24.749000 audit: BPF prog-id=16 op=UNLOAD Feb 9 18:29:24.749000 audit: BPF prog-id=17 op=UNLOAD Feb 9 18:29:24.841000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=verity-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:29:24.915000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=flatcar-tmpfiles comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:29:24.926000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=kmod-static-nodes comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:29:24.943000 audit: CONFIG_CHANGE op=set audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1 Feb 9 18:29:24.943000 audit[1240]: SYSCALL arch=c00000b7 syscall=211 success=yes exit=60 a0=3 a1=ffffd03c17e0 a2=4000 a3=1 items=0 ppid=1 pid=1240 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="systemd-journal" exe="/usr/lib/systemd/systemd-journald" subj=system_u:system_r:kernel_t:s0 key=(null) Feb 9 18:29:24.943000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@configfs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:29:24.943000 audit: PROCTITLE proctitle="/usr/lib/systemd/systemd-journald" Feb 9 18:29:24.943000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@configfs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:29:24.131656 systemd[1]: Queued start job for default target multi-user.target. Feb 9 18:29:15.076378 /usr/lib/systemd/system-generators/torcx-generator[1134]: time="2024-02-09T18:29:15Z" level=debug msg="common configuration parsed" base_dir=/var/lib/torcx/ conf_dir=/etc/torcx/ run_dir=/run/torcx/ store_paths="[/usr/share/torcx/store /usr/share/oem/torcx/store/3510.3.2 /usr/share/oem/torcx/store /var/lib/torcx/store/3510.3.2 /var/lib/torcx/store]" Feb 9 18:29:24.134581 systemd[1]: systemd-journald.service: Deactivated successfully. Feb 9 18:29:15.099530 /usr/lib/systemd/system-generators/torcx-generator[1134]: time="2024-02-09T18:29:15Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Feb 9 18:29:24.134919 systemd[1]: systemd-journald.service: Consumed 2.777s CPU time. Feb 9 18:29:15.099550 /usr/lib/systemd/system-generators/torcx-generator[1134]: time="2024-02-09T18:29:15Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Feb 9 18:29:15.099589 /usr/lib/systemd/system-generators/torcx-generator[1134]: time="2024-02-09T18:29:15Z" level=info msg="no vendor profile selected by /etc/flatcar/docker-1.12" Feb 9 18:29:15.099617 /usr/lib/systemd/system-generators/torcx-generator[1134]: time="2024-02-09T18:29:15Z" level=debug msg="skipped missing lower profile" missing profile=oem Feb 9 18:29:15.099656 /usr/lib/systemd/system-generators/torcx-generator[1134]: time="2024-02-09T18:29:15Z" level=warning msg="no next profile: unable to read profile file: open /etc/torcx/next-profile: no such file or directory" Feb 9 18:29:15.099668 /usr/lib/systemd/system-generators/torcx-generator[1134]: time="2024-02-09T18:29:15Z" level=debug msg="apply configuration parsed" lower profiles (vendor/oem)="[vendor]" upper profile (user)= Feb 9 18:29:15.099832 /usr/lib/systemd/system-generators/torcx-generator[1134]: time="2024-02-09T18:29:15Z" level=debug msg="mounted tmpfs" target=/run/torcx/unpack Feb 9 18:29:15.099865 /usr/lib/systemd/system-generators/torcx-generator[1134]: time="2024-02-09T18:29:15Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Feb 9 18:29:15.099877 /usr/lib/systemd/system-generators/torcx-generator[1134]: time="2024-02-09T18:29:15Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Feb 9 18:29:15.110556 /usr/lib/systemd/system-generators/torcx-generator[1134]: time="2024-02-09T18:29:15Z" level=debug msg="new archive/reference added to cache" format=tgz name=docker path="/usr/share/torcx/store/docker:20.10.torcx.tgz" reference=20.10 Feb 9 18:29:15.110589 /usr/lib/systemd/system-generators/torcx-generator[1134]: time="2024-02-09T18:29:15Z" level=debug msg="new archive/reference added to cache" format=tgz name=docker path="/usr/share/torcx/store/docker:com.coreos.cl.torcx.tgz" reference=com.coreos.cl Feb 9 18:29:15.110626 /usr/lib/systemd/system-generators/torcx-generator[1134]: time="2024-02-09T18:29:15Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store/3510.3.2: no such file or directory" path=/usr/share/oem/torcx/store/3510.3.2 Feb 9 18:29:15.110641 /usr/lib/systemd/system-generators/torcx-generator[1134]: time="2024-02-09T18:29:15Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store: no such file or directory" path=/usr/share/oem/torcx/store Feb 9 18:29:15.110662 /usr/lib/systemd/system-generators/torcx-generator[1134]: time="2024-02-09T18:29:15Z" level=info msg="store skipped" err="open /var/lib/torcx/store/3510.3.2: no such file or directory" path=/var/lib/torcx/store/3510.3.2 Feb 9 18:29:15.110675 /usr/lib/systemd/system-generators/torcx-generator[1134]: time="2024-02-09T18:29:15Z" level=info msg="store skipped" err="open /var/lib/torcx/store: no such file or directory" path=/var/lib/torcx/store Feb 9 18:29:23.013386 /usr/lib/systemd/system-generators/torcx-generator[1134]: time="2024-02-09T18:29:23Z" level=debug msg="image unpacked" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Feb 9 18:29:23.013657 /usr/lib/systemd/system-generators/torcx-generator[1134]: time="2024-02-09T18:29:23Z" level=debug msg="binaries propagated" assets="[/bin/containerd /bin/containerd-shim /bin/ctr /bin/docker /bin/docker-containerd /bin/docker-containerd-shim /bin/docker-init /bin/docker-proxy /bin/docker-runc /bin/dockerd /bin/runc /bin/tini]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Feb 9 18:29:23.013751 /usr/lib/systemd/system-generators/torcx-generator[1134]: time="2024-02-09T18:29:23Z" level=debug msg="networkd units propagated" assets="[/lib/systemd/network/50-docker.network /lib/systemd/network/90-docker-veth.network]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Feb 9 18:29:23.013901 /usr/lib/systemd/system-generators/torcx-generator[1134]: time="2024-02-09T18:29:23Z" level=debug msg="systemd units propagated" assets="[/lib/systemd/system/containerd.service /lib/systemd/system/docker.service /lib/systemd/system/docker.socket /lib/systemd/system/sockets.target.wants /lib/systemd/system/multi-user.target.wants]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Feb 9 18:29:23.013948 /usr/lib/systemd/system-generators/torcx-generator[1134]: time="2024-02-09T18:29:23Z" level=debug msg="profile applied" sealed profile=/run/torcx/profile.json upper profile= Feb 9 18:29:23.014000 /usr/lib/systemd/system-generators/torcx-generator[1134]: time="2024-02-09T18:29:23Z" level=debug msg="system state sealed" content="[TORCX_LOWER_PROFILES=\"vendor\" TORCX_UPPER_PROFILE=\"\" TORCX_PROFILE_PATH=\"/run/torcx/profile.json\" TORCX_BINDIR=\"/run/torcx/bin\" TORCX_UNPACKDIR=\"/run/torcx/unpack\"]" path=/run/metadata/torcx Feb 9 18:29:24.955521 systemd[1]: Finished modprobe@dm_mod.service. Feb 9 18:29:24.960000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:29:24.960000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:29:24.971831 systemd[1]: Started systemd-journald.service. Feb 9 18:29:24.971000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:29:24.972706 systemd[1]: modprobe@drm.service: Deactivated successfully. Feb 9 18:29:24.972832 systemd[1]: Finished modprobe@drm.service. Feb 9 18:29:24.977000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@drm comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:29:24.977000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@drm comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:29:24.978048 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Feb 9 18:29:24.978170 systemd[1]: Finished modprobe@efi_pstore.service. Feb 9 18:29:24.983000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:29:24.983000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:29:24.983817 systemd[1]: modprobe@fuse.service: Deactivated successfully. Feb 9 18:29:24.983928 systemd[1]: Finished modprobe@fuse.service. Feb 9 18:29:24.988000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@fuse comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:29:24.988000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@fuse comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:29:24.989247 systemd[1]: modprobe@loop.service: Deactivated successfully. Feb 9 18:29:24.989363 systemd[1]: Finished modprobe@loop.service. Feb 9 18:29:24.993000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:29:24.993000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:29:24.994389 systemd[1]: Finished systemd-modules-load.service. Feb 9 18:29:24.999000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:29:25.000136 systemd[1]: Finished systemd-network-generator.service. Feb 9 18:29:25.005000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-network-generator comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:29:25.005944 systemd[1]: Finished systemd-remount-fs.service. Feb 9 18:29:25.010000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-remount-fs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:29:25.011287 systemd[1]: Finished systemd-udev-trigger.service. Feb 9 18:29:25.015000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-udev-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:29:25.016479 systemd[1]: Reached target network-pre.target. Feb 9 18:29:25.022251 systemd[1]: Mounting sys-fs-fuse-connections.mount... Feb 9 18:29:25.027586 systemd[1]: Mounting sys-kernel-config.mount... Feb 9 18:29:25.031595 systemd[1]: remount-root.service was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Feb 9 18:29:25.032807 systemd[1]: Starting systemd-hwdb-update.service... Feb 9 18:29:25.038070 systemd[1]: Starting systemd-journal-flush.service... Feb 9 18:29:25.042402 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Feb 9 18:29:25.043324 systemd[1]: Starting systemd-random-seed.service... Feb 9 18:29:25.047588 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met. Feb 9 18:29:25.048576 systemd[1]: Starting systemd-sysctl.service... Feb 9 18:29:25.053567 systemd[1]: Starting systemd-sysusers.service... Feb 9 18:29:25.059149 systemd[1]: Starting systemd-udev-settle.service... Feb 9 18:29:25.065704 systemd[1]: Mounted sys-fs-fuse-connections.mount. Feb 9 18:29:25.071379 systemd[1]: Mounted sys-kernel-config.mount. Feb 9 18:29:25.078245 udevadm[1254]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Feb 9 18:29:25.105003 systemd-journald[1240]: Runtime Journal (/run/log/journal/02f4bec27668455cbbd3e1d2ce44d1d5) is 8.0M, max 78.6M, 70.6M free. Feb 9 18:29:25.120014 systemd-journald[1240]: Received client request to flush runtime journal. Feb 9 18:29:25.114229 systemd[1]: Finished systemd-journal-flush.service. Feb 9 18:29:25.119000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journal-flush comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:29:25.125020 systemd[1]: Finished systemd-random-seed.service. Feb 9 18:29:25.130000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-random-seed comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:29:25.130510 systemd[1]: Reached target first-boot-complete.target. Feb 9 18:29:25.219415 systemd[1]: Finished systemd-sysctl.service. Feb 9 18:29:25.224000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:29:25.868887 systemd[1]: Finished systemd-sysusers.service. Feb 9 18:29:25.873000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-sysusers comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:29:25.874852 systemd[1]: Starting systemd-tmpfiles-setup-dev.service... Feb 9 18:29:26.253151 systemd[1]: Finished systemd-tmpfiles-setup-dev.service. Feb 9 18:29:26.258000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:29:26.724254 systemd[1]: Finished systemd-hwdb-update.service. Feb 9 18:29:26.728000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-hwdb-update comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:29:26.729000 audit: BPF prog-id=21 op=LOAD Feb 9 18:29:26.729000 audit: BPF prog-id=22 op=LOAD Feb 9 18:29:26.729000 audit: BPF prog-id=7 op=UNLOAD Feb 9 18:29:26.729000 audit: BPF prog-id=8 op=UNLOAD Feb 9 18:29:26.730413 systemd[1]: Starting systemd-udevd.service... Feb 9 18:29:26.748271 systemd-udevd[1258]: Using default interface naming scheme 'v252'. Feb 9 18:29:26.926308 systemd[1]: Started systemd-udevd.service. Feb 9 18:29:26.935000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-udevd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:29:26.936000 audit: BPF prog-id=23 op=LOAD Feb 9 18:29:26.938041 systemd[1]: Starting systemd-networkd.service... Feb 9 18:29:26.964098 systemd[1]: Condition check resulted in dev-ttyAMA0.device being skipped. Feb 9 18:29:27.025627 kernel: mousedev: PS/2 mouse device common for all mice Feb 9 18:29:27.045624 kernel: hv_vmbus: registering driver hyperv_fb Feb 9 18:29:27.050648 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Feb 9 18:29:27.058629 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Feb 9 18:29:27.058678 kernel: Console: switching to colour dummy device 80x25 Feb 9 18:29:27.064617 kernel: Console: switching to colour frame buffer device 128x48 Feb 9 18:29:27.096000 audit: BPF prog-id=24 op=LOAD Feb 9 18:29:27.096000 audit: BPF prog-id=25 op=LOAD Feb 9 18:29:27.096000 audit: BPF prog-id=26 op=LOAD Feb 9 18:29:27.097448 systemd[1]: Starting systemd-userdbd.service... Feb 9 18:29:27.115000 audit[1270]: AVC avc: denied { confidentiality } for pid=1270 comm="(udev-worker)" lockdown_reason="use of tracefs" scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=lockdown permissive=1 Feb 9 18:29:27.133288 kernel: hv_utils: Registering HyperV Utility Driver Feb 9 18:29:27.133346 kernel: hv_vmbus: registering driver hv_balloon Feb 9 18:29:27.133359 kernel: hv_vmbus: registering driver hv_utils Feb 9 18:29:27.142995 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Feb 9 18:29:27.143045 kernel: hv_utils: Heartbeat IC version 3.0 Feb 9 18:29:27.143058 kernel: hv_balloon: Memory hot add disabled on ARM64 Feb 9 18:29:27.151721 kernel: hv_utils: TimeSync IC version 4.0 Feb 9 18:29:27.151764 kernel: hv_utils: Shutdown IC version 3.2 Feb 9 18:29:27.115000 audit[1270]: SYSCALL arch=c00000b7 syscall=105 success=yes exit=0 a0=aaaac54e53f0 a1=aa2c a2=ffff83fa24b0 a3=aaaac541a010 items=12 ppid=1258 pid=1270 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="(udev-worker)" exe="/usr/bin/udevadm" subj=system_u:system_r:kernel_t:s0 key=(null) Feb 9 18:29:26.839744 systemd-journald[1240]: Time jumped backwards, rotating. Feb 9 18:29:27.115000 audit: CWD cwd="/" Feb 9 18:29:27.115000 audit: PATH item=0 name=(null) inode=6436 dev=00:0a mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Feb 9 18:29:27.115000 audit: PATH item=1 name=(null) inode=11411 dev=00:0a mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Feb 9 18:29:27.115000 audit: PATH item=2 name=(null) inode=11411 dev=00:0a mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Feb 9 18:29:27.115000 audit: PATH item=3 name=(null) inode=11412 dev=00:0a mode=0100640 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Feb 9 18:29:27.115000 audit: PATH item=4 name=(null) inode=11411 dev=00:0a mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Feb 9 18:29:27.115000 audit: PATH item=5 name=(null) inode=11413 dev=00:0a mode=0100440 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Feb 9 18:29:27.115000 audit: PATH item=6 name=(null) inode=11411 dev=00:0a mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Feb 9 18:29:27.115000 audit: PATH item=7 name=(null) inode=11414 dev=00:0a mode=0100640 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Feb 9 18:29:27.115000 audit: PATH item=8 name=(null) inode=11411 dev=00:0a mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Feb 9 18:29:27.115000 audit: PATH item=9 name=(null) inode=11415 dev=00:0a mode=0100640 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Feb 9 18:29:27.115000 audit: PATH item=10 name=(null) inode=11411 dev=00:0a mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Feb 9 18:29:27.115000 audit: PATH item=11 name=(null) inode=11416 dev=00:0a mode=0100440 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Feb 9 18:29:27.115000 audit: PROCTITLE proctitle="(udev-worker)" Feb 9 18:29:26.854649 systemd[1]: Started systemd-userdbd.service. Feb 9 18:29:26.857000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-userdbd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:29:27.137969 kernel: BTRFS info: devid 1 device path /dev/disk/by-label/OEM changed to /dev/sda6 scanned by (udev-worker) (1272) Feb 9 18:29:27.165157 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device. Feb 9 18:29:27.173328 systemd[1]: Finished systemd-udev-settle.service. Feb 9 18:29:27.177000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-udev-settle comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:29:27.180084 systemd[1]: Starting lvm2-activation-early.service... Feb 9 18:29:27.214532 systemd-networkd[1279]: lo: Link UP Feb 9 18:29:27.214758 systemd-networkd[1279]: lo: Gained carrier Feb 9 18:29:27.215366 systemd-networkd[1279]: Enumeration completed Feb 9 18:29:27.215527 systemd[1]: Started systemd-networkd.service. Feb 9 18:29:27.218000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:29:27.221562 systemd[1]: Starting systemd-networkd-wait-online.service... Feb 9 18:29:27.242660 systemd-networkd[1279]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Feb 9 18:29:27.290963 kernel: mlx5_core d3c6:00:02.0 enP54214s1: Link up Feb 9 18:29:27.317968 kernel: hv_netvsc 000d3ac4-0921-000d-3ac4-0921000d3ac4 eth0: Data path switched to VF: enP54214s1 Feb 9 18:29:27.318669 systemd-networkd[1279]: enP54214s1: Link UP Feb 9 18:29:27.319010 systemd-networkd[1279]: eth0: Link UP Feb 9 18:29:27.319020 systemd-networkd[1279]: eth0: Gained carrier Feb 9 18:29:27.323389 systemd-networkd[1279]: enP54214s1: Gained carrier Feb 9 18:29:27.333036 systemd-networkd[1279]: eth0: DHCPv4 address 10.200.20.14/24, gateway 10.200.20.1 acquired from 168.63.129.16 Feb 9 18:29:27.534906 lvm[1338]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Feb 9 18:29:27.569844 systemd[1]: Finished lvm2-activation-early.service. Feb 9 18:29:27.573000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=lvm2-activation-early comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:29:27.575777 systemd[1]: Reached target cryptsetup.target. Feb 9 18:29:27.582333 systemd[1]: Starting lvm2-activation.service... Feb 9 18:29:27.586204 lvm[1341]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Feb 9 18:29:27.613840 systemd[1]: Finished lvm2-activation.service. Feb 9 18:29:27.616000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=lvm2-activation comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:29:27.618758 systemd[1]: Reached target local-fs-pre.target. Feb 9 18:29:27.623861 systemd[1]: var-lib-machines.mount was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Feb 9 18:29:27.623890 systemd[1]: Reached target local-fs.target. Feb 9 18:29:27.629042 systemd[1]: Reached target machines.target. Feb 9 18:29:27.635315 systemd[1]: Starting ldconfig.service... Feb 9 18:29:27.639533 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. Feb 9 18:29:27.639603 systemd[1]: systemd-boot-system-token.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/LoaderFeatures-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Feb 9 18:29:27.640754 systemd[1]: Starting systemd-boot-update.service... Feb 9 18:29:27.646938 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service... Feb 9 18:29:27.654796 systemd[1]: Starting systemd-machine-id-commit.service... Feb 9 18:29:27.660435 systemd[1]: systemd-sysext.service was skipped because no trigger condition checks were met. Feb 9 18:29:27.660528 systemd[1]: ensure-sysext.service was skipped because no trigger condition checks were met. Feb 9 18:29:27.661676 systemd[1]: Starting systemd-tmpfiles-setup.service... Feb 9 18:29:27.687477 systemd-tmpfiles[1346]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. Feb 9 18:29:27.694166 systemd[1]: boot.automount: Got automount request for /boot, triggered by 1343 (bootctl) Feb 9 18:29:27.695601 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service... Feb 9 18:29:27.709699 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Feb 9 18:29:27.710640 systemd[1]: Finished systemd-machine-id-commit.service. Feb 9 18:29:27.715000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-machine-id-commit comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:29:27.724355 systemd-tmpfiles[1346]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Feb 9 18:29:27.725630 systemd-tmpfiles[1346]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Feb 9 18:29:27.732825 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service. Feb 9 18:29:27.737000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-fsck@dev-disk-by\x2dlabel-OEM comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:29:27.858687 systemd-fsck[1352]: fsck.fat 4.2 (2021-01-31) Feb 9 18:29:27.858687 systemd-fsck[1352]: /dev/sda1: 236 files, 113719/258078 clusters Feb 9 18:29:27.860732 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service. Feb 9 18:29:27.867000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:29:27.870386 systemd[1]: Mounting boot.mount... Feb 9 18:29:27.878892 systemd[1]: Mounted boot.mount. Feb 9 18:29:27.889492 systemd[1]: Finished systemd-boot-update.service. Feb 9 18:29:27.893000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-boot-update comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:29:27.944528 systemd-tmpfiles[1346]: Failed to adjust quota for subvolume "/srv": Bad file descriptor Feb 9 18:29:28.000592 systemd-tmpfiles[1346]: Failed to adjust quota for subvolume "/var/lib/portables": Bad file descriptor Feb 9 18:29:28.022100 systemd-tmpfiles[1346]: Failed to adjust quota for subvolume "/var/lib/machines": Bad file descriptor Feb 9 18:29:28.032748 systemd-tmpfiles[1346]: Failed to adjust quota for subvolume "/var/tmp": Bad file descriptor Feb 9 18:29:28.046968 systemd[1]: Finished systemd-tmpfiles-setup.service. Feb 9 18:29:28.050000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-tmpfiles-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:29:28.054287 systemd[1]: Starting audit-rules.service... Feb 9 18:29:28.062228 systemd[1]: Starting clean-ca-certificates.service... Feb 9 18:29:28.068492 systemd[1]: Starting systemd-journal-catalog-update.service... Feb 9 18:29:28.076000 audit: BPF prog-id=27 op=LOAD Feb 9 18:29:28.079673 systemd[1]: Starting systemd-resolved.service... Feb 9 18:29:28.083000 audit: BPF prog-id=28 op=LOAD Feb 9 18:29:28.086420 systemd[1]: Starting systemd-timesyncd.service... Feb 9 18:29:28.092041 systemd[1]: Starting systemd-update-utmp.service... Feb 9 18:29:28.139000 audit[1364]: SYSTEM_BOOT pid=1364 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg=' comm="systemd-update-utmp" exe="/usr/lib/systemd/systemd-update-utmp" hostname=? addr=? terminal=? res=success' Feb 9 18:29:28.149000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-update-utmp comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:29:28.144843 systemd[1]: Finished systemd-update-utmp.service. Feb 9 18:29:28.188122 systemd[1]: Started systemd-timesyncd.service. Feb 9 18:29:28.191000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-timesyncd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:29:28.193427 systemd[1]: Reached target time-set.target. Feb 9 18:29:28.210668 systemd-resolved[1361]: Positive Trust Anchors: Feb 9 18:29:28.210682 systemd-resolved[1361]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Feb 9 18:29:28.210709 systemd-resolved[1361]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa corp home internal intranet lan local private test Feb 9 18:29:28.230414 systemd[1]: Finished clean-ca-certificates.service. Feb 9 18:29:28.236063 systemd-resolved[1361]: Using system hostname 'ci-3510.3.2-a-4669ca5177'. Feb 9 18:29:28.235000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=clean-ca-certificates comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:29:28.237190 systemd[1]: update-ca-certificates.service was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Feb 9 18:29:28.237488 systemd[1]: Started systemd-resolved.service. Feb 9 18:29:28.240000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-resolved comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:29:28.242877 systemd[1]: Reached target network.target. Feb 9 18:29:28.248370 systemd[1]: Reached target nss-lookup.target. Feb 9 18:29:28.344675 systemd[1]: Finished systemd-journal-catalog-update.service. Feb 9 18:29:28.348000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journal-catalog-update comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:29:28.491000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=add_rule key=(null) list=5 res=1 Feb 9 18:29:28.491000 audit[1379]: SYSCALL arch=c00000b7 syscall=206 success=yes exit=1056 a0=3 a1=ffffdfc4a900 a2=420 a3=0 items=0 ppid=1358 pid=1379 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="auditctl" exe="/usr/sbin/auditctl" subj=system_u:system_r:kernel_t:s0 key=(null) Feb 9 18:29:28.491000 audit: PROCTITLE proctitle=2F7362696E2F617564697463746C002D52002F6574632F61756469742F61756469742E72756C6573 Feb 9 18:29:28.493440 augenrules[1379]: No rules Feb 9 18:29:28.494250 systemd[1]: Finished audit-rules.service. Feb 9 18:29:28.516634 systemd-timesyncd[1363]: Contacted time server 104.234.70.173:123 (0.flatcar.pool.ntp.org). Feb 9 18:29:28.516893 systemd-timesyncd[1363]: Initial clock synchronization to Fri 2024-02-09 18:29:28.510025 UTC. Feb 9 18:29:28.782085 systemd-networkd[1279]: eth0: Gained IPv6LL Feb 9 18:29:28.783589 systemd[1]: Finished systemd-networkd-wait-online.service. Feb 9 18:29:28.789327 systemd[1]: Reached target network-online.target. Feb 9 18:29:38.400327 ldconfig[1342]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Feb 9 18:29:38.416595 systemd[1]: Finished ldconfig.service. Feb 9 18:29:38.423077 systemd[1]: Starting systemd-update-done.service... Feb 9 18:29:38.527408 systemd[1]: Finished systemd-update-done.service. Feb 9 18:29:38.532364 systemd[1]: Reached target sysinit.target. Feb 9 18:29:38.536881 systemd[1]: Started motdgen.path. Feb 9 18:29:38.540716 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path. Feb 9 18:29:38.547051 systemd[1]: Started logrotate.timer. Feb 9 18:29:38.551338 systemd[1]: Started mdadm.timer. Feb 9 18:29:38.555478 systemd[1]: Started systemd-tmpfiles-clean.timer. Feb 9 18:29:38.560872 systemd[1]: update-engine-stub.timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Feb 9 18:29:38.560901 systemd[1]: Reached target paths.target. Feb 9 18:29:38.565740 systemd[1]: Reached target timers.target. Feb 9 18:29:38.573029 systemd[1]: Listening on dbus.socket. Feb 9 18:29:38.578624 systemd[1]: Starting docker.socket... Feb 9 18:29:38.595778 systemd[1]: Listening on sshd.socket. Feb 9 18:29:38.600213 systemd[1]: systemd-pcrphase-sysinit.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Feb 9 18:29:38.600677 systemd[1]: Listening on docker.socket. Feb 9 18:29:38.605402 systemd[1]: Reached target sockets.target. Feb 9 18:29:38.609806 systemd[1]: Reached target basic.target. Feb 9 18:29:38.614193 systemd[1]: addon-config@usr-share-oem.service was skipped because no trigger condition checks were met. Feb 9 18:29:38.614219 systemd[1]: addon-run@usr-share-oem.service was skipped because no trigger condition checks were met. Feb 9 18:29:38.615240 systemd[1]: Starting containerd.service... Feb 9 18:29:38.619863 systemd[1]: Starting dbus.service... Feb 9 18:29:38.624483 systemd[1]: Starting enable-oem-cloudinit.service... Feb 9 18:29:38.630295 systemd[1]: Starting extend-filesystems.service... Feb 9 18:29:38.638308 systemd[1]: flatcar-setup-environment.service was skipped because of an unmet condition check (ConditionPathExists=/usr/share/oem/bin/flatcar-setup-environment). Feb 9 18:29:38.639380 systemd[1]: Starting motdgen.service... Feb 9 18:29:38.644482 systemd[1]: Started nvidia.service. Feb 9 18:29:38.650333 systemd[1]: Starting ssh-key-proc-cmdline.service... Feb 9 18:29:38.656280 systemd[1]: Starting sshd-keygen.service... Feb 9 18:29:38.664154 systemd[1]: Starting systemd-logind.service... Feb 9 18:29:38.668827 systemd[1]: systemd-pcrphase.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Feb 9 18:29:38.668887 systemd[1]: tcsd.service was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Feb 9 18:29:38.669331 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Feb 9 18:29:38.669982 systemd[1]: Starting update-engine.service... Feb 9 18:29:38.675418 systemd[1]: Starting update-ssh-keys-after-ignition.service... Feb 9 18:29:38.683709 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Feb 9 18:29:38.683885 systemd[1]: Finished ssh-key-proc-cmdline.service. Feb 9 18:29:38.691169 jq[1406]: true Feb 9 18:29:38.691395 jq[1389]: false Feb 9 18:29:38.725721 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Feb 9 18:29:38.725894 systemd[1]: Condition check resulted in enable-oem-cloudinit.service being skipped. Feb 9 18:29:38.741005 systemd[1]: motdgen.service: Deactivated successfully. Feb 9 18:29:38.741195 systemd[1]: Finished motdgen.service. Feb 9 18:29:38.756335 systemd-logind[1401]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Feb 9 18:29:38.758796 systemd-logind[1401]: New seat seat0. Feb 9 18:29:38.779114 jq[1411]: true Feb 9 18:29:38.790846 extend-filesystems[1390]: Found sda Feb 9 18:29:38.790846 extend-filesystems[1390]: Found sda1 Feb 9 18:29:38.790846 extend-filesystems[1390]: Found sda2 Feb 9 18:29:38.790846 extend-filesystems[1390]: Found sda3 Feb 9 18:29:38.790846 extend-filesystems[1390]: Found usr Feb 9 18:29:38.790846 extend-filesystems[1390]: Found sda4 Feb 9 18:29:38.790846 extend-filesystems[1390]: Found sda6 Feb 9 18:29:38.790846 extend-filesystems[1390]: Found sda7 Feb 9 18:29:38.790846 extend-filesystems[1390]: Found sda9 Feb 9 18:29:38.790846 extend-filesystems[1390]: Checking size of /dev/sda9 Feb 9 18:29:38.840994 env[1412]: time="2024-02-09T18:29:38.831388881Z" level=info msg="starting containerd" revision=92b3a9d6f1b3bcc6dc74875cfdea653fe39f09c2 version=1.6.16 Feb 9 18:29:38.854654 env[1412]: time="2024-02-09T18:29:38.854603144Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Feb 9 18:29:38.854870 env[1412]: time="2024-02-09T18:29:38.854851808Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Feb 9 18:29:38.856165 env[1412]: time="2024-02-09T18:29:38.856131238Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="aufs is not supported (modprobe aufs failed: exit status 1 \"modprobe: FATAL: Module aufs not found in directory /lib/modules/5.15.148-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Feb 9 18:29:38.856246 env[1412]: time="2024-02-09T18:29:38.856232215Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Feb 9 18:29:38.856598 env[1412]: time="2024-02-09T18:29:38.856579657Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Feb 9 18:29:38.856673 env[1412]: time="2024-02-09T18:29:38.856657919Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured" Feb 9 18:29:38.856720 env[1412]: time="2024-02-09T18:29:38.856708147Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Feb 9 18:29:38.856822 env[1412]: time="2024-02-09T18:29:38.856807325Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Feb 9 18:29:38.857129 env[1412]: time="2024-02-09T18:29:38.857108217Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Feb 9 18:29:38.857314 env[1412]: time="2024-02-09T18:29:38.857294975Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Feb 9 18:29:38.857374 env[1412]: time="2024-02-09T18:29:38.857360800Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Feb 9 18:29:38.857458 env[1412]: time="2024-02-09T18:29:38.857442181Z" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured" Feb 9 18:29:38.857509 env[1412]: time="2024-02-09T18:29:38.857496689Z" level=info msg="metadata content store policy set" policy=shared Feb 9 18:29:38.877098 env[1412]: time="2024-02-09T18:29:38.877028706Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Feb 9 18:29:38.877283 env[1412]: time="2024-02-09T18:29:38.877268292Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Feb 9 18:29:38.877368 env[1412]: time="2024-02-09T18:29:38.877356712Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Feb 9 18:29:38.877466 env[1412]: time="2024-02-09T18:29:38.877452570Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Feb 9 18:29:38.877593 env[1412]: time="2024-02-09T18:29:38.877569943Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Feb 9 18:29:38.877652 env[1412]: time="2024-02-09T18:29:38.877638888Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Feb 9 18:29:38.877713 env[1412]: time="2024-02-09T18:29:38.877701274Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Feb 9 18:29:38.878186 env[1412]: time="2024-02-09T18:29:38.878157450Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Feb 9 18:29:38.878292 env[1412]: time="2024-02-09T18:29:38.878276063Z" level=info msg="loading plugin \"io.containerd.service.v1.leases-service\"..." type=io.containerd.service.v1 Feb 9 18:29:38.878438 env[1412]: time="2024-02-09T18:29:38.878422190Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Feb 9 18:29:38.878511 env[1412]: time="2024-02-09T18:29:38.878499053Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Feb 9 18:29:38.878572 env[1412]: time="2024-02-09T18:29:38.878551121Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Feb 9 18:29:38.878746 env[1412]: time="2024-02-09T18:29:38.878731360Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Feb 9 18:29:38.878901 env[1412]: time="2024-02-09T18:29:38.878874128Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Feb 9 18:29:38.879282 env[1412]: time="2024-02-09T18:29:38.879253042Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Feb 9 18:29:38.879379 env[1412]: time="2024-02-09T18:29:38.879365897Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Feb 9 18:29:38.879442 env[1412]: time="2024-02-09T18:29:38.879420484Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Feb 9 18:29:38.879543 env[1412]: time="2024-02-09T18:29:38.879530299Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Feb 9 18:29:38.879656 env[1412]: time="2024-02-09T18:29:38.879641634Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Feb 9 18:29:38.879725 env[1412]: time="2024-02-09T18:29:38.879700661Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Feb 9 18:29:38.879790 env[1412]: time="2024-02-09T18:29:38.879769885Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Feb 9 18:29:38.879869 env[1412]: time="2024-02-09T18:29:38.879855426Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Feb 9 18:29:38.885223 env[1412]: time="2024-02-09T18:29:38.885196936Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Feb 9 18:29:38.885317 env[1412]: time="2024-02-09T18:29:38.885303192Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Feb 9 18:29:38.885384 env[1412]: time="2024-02-09T18:29:38.885370817Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Feb 9 18:29:38.885446 env[1412]: time="2024-02-09T18:29:38.885427044Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Feb 9 18:29:38.885620 env[1412]: time="2024-02-09T18:29:38.885605484Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Feb 9 18:29:38.885715 env[1412]: time="2024-02-09T18:29:38.885701222Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Feb 9 18:29:38.885785 env[1412]: time="2024-02-09T18:29:38.885772726Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Feb 9 18:29:38.885835 env[1412]: time="2024-02-09T18:29:38.885823794Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Feb 9 18:29:38.885902 env[1412]: time="2024-02-09T18:29:38.885886460Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="no OpenTelemetry endpoint: skip plugin" type=io.containerd.tracing.processor.v1 Feb 9 18:29:38.885967 env[1412]: time="2024-02-09T18:29:38.885936609Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Feb 9 18:29:38.886229 env[1412]: time="2024-02-09T18:29:38.886015471Z" level=error msg="failed to initialize a tracing processor \"otlp\"" error="no OpenTelemetry endpoint: skip plugin" Feb 9 18:29:38.886343 env[1412]: time="2024-02-09T18:29:38.886327640Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Feb 9 18:29:38.886709 env[1412]: time="2024-02-09T18:29:38.886651567Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:true] PrivilegedWithoutHostDevices:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginConfTemplate: IPPreference:} Registry:{ConfigPath: Mirrors:map[] Configs:map[] Auths:map[] Headers:map[]} ImageDecryption:{KeyModel:node} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:true SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.6 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false UnsetSeccompProfile: TolerateMissingHugetlbController:true DisableHugetlbController:true DeviceOwnershipFromSecurityContext:false IgnoreImageDefinedVolumes:false NetNSMountsUnderStateDir:false EnableUnprivilegedPorts:false EnableUnprivilegedICMP:false} ContainerdRootDir:/var/lib/containerd ContainerdEndpoint:/run/containerd/containerd.sock RootDir:/var/lib/containerd/io.containerd.grpc.v1.cri StateDir:/run/containerd/io.containerd.grpc.v1.cri}" Feb 9 18:29:38.934117 extend-filesystems[1390]: Old size kept for /dev/sda9 Feb 9 18:29:38.934117 extend-filesystems[1390]: Found sr0 Feb 9 18:29:38.946369 bash[1444]: Updated "/home/core/.ssh/authorized_keys" Feb 9 18:29:38.946467 env[1412]: time="2024-02-09T18:29:38.886888873Z" level=info msg="Connect containerd service" Feb 9 18:29:38.946467 env[1412]: time="2024-02-09T18:29:38.886961776Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Feb 9 18:29:38.946467 env[1412]: time="2024-02-09T18:29:38.887655499Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" Feb 9 18:29:38.946467 env[1412]: time="2024-02-09T18:29:38.887721964Z" level=info msg="Start subscribing containerd event" Feb 9 18:29:38.946467 env[1412]: time="2024-02-09T18:29:38.887763315Z" level=info msg="Start recovering state" Feb 9 18:29:38.946467 env[1412]: time="2024-02-09T18:29:38.887828380Z" level=info msg="Start event monitor" Feb 9 18:29:38.946467 env[1412]: time="2024-02-09T18:29:38.887844297Z" level=info msg="Start snapshots syncer" Feb 9 18:29:38.946467 env[1412]: time="2024-02-09T18:29:38.887853415Z" level=info msg="Start cni network conf syncer for default" Feb 9 18:29:38.946467 env[1412]: time="2024-02-09T18:29:38.887861333Z" level=info msg="Start streaming server" Feb 9 18:29:38.946467 env[1412]: time="2024-02-09T18:29:38.888267481Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Feb 9 18:29:38.946467 env[1412]: time="2024-02-09T18:29:38.888306912Z" level=info msg=serving... address=/run/containerd/containerd.sock Feb 9 18:29:38.946467 env[1412]: time="2024-02-09T18:29:38.913442460Z" level=info msg="containerd successfully booted in 0.095264s" Feb 9 18:29:38.888482 systemd[1]: Started containerd.service. Feb 9 18:29:38.893714 systemd[1]: extend-filesystems.service: Deactivated successfully. Feb 9 18:29:38.893875 systemd[1]: Finished extend-filesystems.service. Feb 9 18:29:38.905387 systemd[1]: Finished update-ssh-keys-after-ignition.service. Feb 9 18:29:38.996252 systemd[1]: nvidia.service: Deactivated successfully. Feb 9 18:29:38.997014 dbus-daemon[1388]: [system] SELinux support is enabled Feb 9 18:29:38.997143 systemd[1]: Started dbus.service. Feb 9 18:29:39.002665 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). Feb 9 18:29:39.002686 systemd[1]: Reached target system-config.target. Feb 9 18:29:39.010488 systemd[1]: user-cloudinit-proc-cmdline.service was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Feb 9 18:29:39.010509 systemd[1]: Reached target user-config.target. Feb 9 18:29:39.016820 dbus-daemon[1388]: [system] Successfully activated service 'org.freedesktop.systemd1' Feb 9 18:29:39.017122 systemd[1]: Started systemd-logind.service. Feb 9 18:29:39.678858 update_engine[1404]: I0209 18:29:39.668605 1404 main.cc:92] Flatcar Update Engine starting Feb 9 18:29:39.769382 systemd[1]: Started update-engine.service. Feb 9 18:29:39.769625 update_engine[1404]: I0209 18:29:39.769412 1404 update_check_scheduler.cc:74] Next update check in 4m20s Feb 9 18:29:39.778343 systemd[1]: Started locksmithd.service. Feb 9 18:29:40.679977 sshd_keygen[1405]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Feb 9 18:29:40.696547 systemd[1]: Finished sshd-keygen.service. Feb 9 18:29:40.703095 systemd[1]: Starting issuegen.service... Feb 9 18:29:40.708225 systemd[1]: Started waagent.service. Feb 9 18:29:40.713645 systemd[1]: issuegen.service: Deactivated successfully. Feb 9 18:29:40.713798 systemd[1]: Finished issuegen.service. Feb 9 18:29:40.720069 systemd[1]: Starting systemd-user-sessions.service... Feb 9 18:29:40.757869 systemd[1]: Finished systemd-user-sessions.service. Feb 9 18:29:40.771119 systemd[1]: Started getty@tty1.service. Feb 9 18:29:40.777299 systemd[1]: Started serial-getty@ttyAMA0.service. Feb 9 18:29:40.783067 systemd[1]: Reached target getty.target. Feb 9 18:29:40.787765 systemd[1]: Reached target multi-user.target. Feb 9 18:29:40.794284 systemd[1]: Starting systemd-update-utmp-runlevel.service... Feb 9 18:29:40.805896 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Feb 9 18:29:40.806076 systemd[1]: Finished systemd-update-utmp-runlevel.service. Feb 9 18:29:40.811679 systemd[1]: Startup finished in 745ms (kernel) + 13.924s (initrd) + 28.558s (userspace) = 43.228s. Feb 9 18:29:41.256627 locksmithd[1483]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Feb 9 18:29:41.680484 login[1502]: pam_lastlog(login:session): file /var/log/lastlog is locked/write Feb 9 18:29:41.692042 login[1501]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Feb 9 18:29:41.710157 systemd[1]: Created slice user-500.slice. Feb 9 18:29:41.711291 systemd[1]: Starting user-runtime-dir@500.service... Feb 9 18:29:41.713364 systemd-logind[1401]: New session 1 of user core. Feb 9 18:29:41.747405 systemd[1]: Finished user-runtime-dir@500.service. Feb 9 18:29:41.748809 systemd[1]: Starting user@500.service... Feb 9 18:29:41.765834 (systemd)[1505]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Feb 9 18:29:41.986184 systemd[1505]: Queued start job for default target default.target. Feb 9 18:29:41.987106 systemd[1505]: Reached target paths.target. Feb 9 18:29:41.987130 systemd[1505]: Reached target sockets.target. Feb 9 18:29:41.987139 systemd[1505]: Reached target timers.target. Feb 9 18:29:41.987148 systemd[1505]: Reached target basic.target. Feb 9 18:29:41.987193 systemd[1505]: Reached target default.target. Feb 9 18:29:41.987216 systemd[1505]: Startup finished in 215ms. Feb 9 18:29:41.987263 systemd[1]: Started user@500.service. Feb 9 18:29:41.988199 systemd[1]: Started session-1.scope. Feb 9 18:29:42.681907 login[1502]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Feb 9 18:29:42.685145 systemd-logind[1401]: New session 2 of user core. Feb 9 18:29:42.685988 systemd[1]: Started session-2.scope. Feb 9 18:29:45.762099 waagent[1499]: 2024-02-09T18:29:45.761977Z INFO Daemon Daemon Azure Linux Agent Version:2.6.0.2 Feb 9 18:29:45.769335 waagent[1499]: 2024-02-09T18:29:45.769254Z INFO Daemon Daemon OS: flatcar 3510.3.2 Feb 9 18:29:45.774246 waagent[1499]: 2024-02-09T18:29:45.774179Z INFO Daemon Daemon Python: 3.9.16 Feb 9 18:29:45.782958 waagent[1499]: 2024-02-09T18:29:45.779103Z INFO Daemon Daemon Run daemon Feb 9 18:29:45.787949 waagent[1499]: 2024-02-09T18:29:45.784070Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='3510.3.2' Feb 9 18:29:45.801474 waagent[1499]: 2024-02-09T18:29:45.801333Z INFO Daemon Daemon Unable to get cloud-init enabled status from systemctl: Command '['systemctl', 'is-enabled', 'cloud-init-local.service']' returned non-zero exit status 1. Feb 9 18:29:45.817528 waagent[1499]: 2024-02-09T18:29:45.817395Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Feb 9 18:29:45.827330 waagent[1499]: 2024-02-09T18:29:45.827256Z INFO Daemon Daemon cloud-init is enabled: False Feb 9 18:29:45.832694 waagent[1499]: 2024-02-09T18:29:45.832628Z INFO Daemon Daemon Using waagent for provisioning Feb 9 18:29:45.838677 waagent[1499]: 2024-02-09T18:29:45.838607Z INFO Daemon Daemon Activate resource disk Feb 9 18:29:45.843648 waagent[1499]: 2024-02-09T18:29:45.843581Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Feb 9 18:29:45.858435 waagent[1499]: 2024-02-09T18:29:45.858352Z INFO Daemon Daemon Found device: None Feb 9 18:29:45.863213 waagent[1499]: 2024-02-09T18:29:45.863145Z ERROR Daemon Daemon Failed to mount resource disk [ResourceDiskError] unable to detect disk topology Feb 9 18:29:45.872279 waagent[1499]: 2024-02-09T18:29:45.872208Z ERROR Daemon Daemon Event: name=WALinuxAgent, op=ActivateResourceDisk, message=[ResourceDiskError] unable to detect disk topology, duration=0 Feb 9 18:29:45.884932 waagent[1499]: 2024-02-09T18:29:45.884864Z INFO Daemon Daemon Clean protocol and wireserver endpoint Feb 9 18:29:45.891210 waagent[1499]: 2024-02-09T18:29:45.891132Z INFO Daemon Daemon Running default provisioning handler Feb 9 18:29:45.904649 waagent[1499]: 2024-02-09T18:29:45.904499Z INFO Daemon Daemon Unable to get cloud-init enabled status from systemctl: Command '['systemctl', 'is-enabled', 'cloud-init-local.service']' returned non-zero exit status 1. Feb 9 18:29:45.921744 waagent[1499]: 2024-02-09T18:29:45.921612Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Feb 9 18:29:45.933320 waagent[1499]: 2024-02-09T18:29:45.933239Z INFO Daemon Daemon cloud-init is enabled: False Feb 9 18:29:45.939223 waagent[1499]: 2024-02-09T18:29:45.939151Z INFO Daemon Daemon Copying ovf-env.xml Feb 9 18:29:46.061825 waagent[1499]: 2024-02-09T18:29:46.061689Z INFO Daemon Daemon Successfully mounted dvd Feb 9 18:29:46.177103 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Feb 9 18:29:46.247511 waagent[1499]: 2024-02-09T18:29:46.247363Z INFO Daemon Daemon Detect protocol endpoint Feb 9 18:29:46.252657 waagent[1499]: 2024-02-09T18:29:46.252580Z INFO Daemon Daemon Clean protocol and wireserver endpoint Feb 9 18:29:46.258690 waagent[1499]: 2024-02-09T18:29:46.258624Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Feb 9 18:29:46.265518 waagent[1499]: 2024-02-09T18:29:46.265455Z INFO Daemon Daemon Test for route to 168.63.129.16 Feb 9 18:29:46.271147 waagent[1499]: 2024-02-09T18:29:46.271087Z INFO Daemon Daemon Route to 168.63.129.16 exists Feb 9 18:29:46.276642 waagent[1499]: 2024-02-09T18:29:46.276584Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Feb 9 18:29:46.357618 waagent[1499]: 2024-02-09T18:29:46.357523Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Feb 9 18:29:46.365677 waagent[1499]: 2024-02-09T18:29:46.365632Z INFO Daemon Daemon Wire protocol version:2012-11-30 Feb 9 18:29:46.371997 waagent[1499]: 2024-02-09T18:29:46.371925Z INFO Daemon Daemon Server preferred version:2015-04-05 Feb 9 18:29:47.337699 waagent[1499]: 2024-02-09T18:29:47.337553Z INFO Daemon Daemon Initializing goal state during protocol detection Feb 9 18:29:47.353717 waagent[1499]: 2024-02-09T18:29:47.353628Z INFO Daemon Daemon Forcing an update of the goal state.. Feb 9 18:29:47.359773 waagent[1499]: 2024-02-09T18:29:47.359700Z INFO Daemon Daemon Fetching goal state [incarnation 1] Feb 9 18:29:47.435797 waagent[1499]: 2024-02-09T18:29:47.435669Z INFO Daemon Daemon Found private key matching thumbprint F265ED1E2F8F3F1371BF510E973034A4F93E0405 Feb 9 18:29:47.445428 waagent[1499]: 2024-02-09T18:29:47.445340Z INFO Daemon Daemon Certificate with thumbprint C763C640614097ECE19EBA9B47C3F13E80C4BC62 has no matching private key. Feb 9 18:29:47.455641 waagent[1499]: 2024-02-09T18:29:47.455555Z INFO Daemon Daemon Fetch goal state completed Feb 9 18:29:47.499609 waagent[1499]: 2024-02-09T18:29:47.499549Z INFO Daemon Daemon Fetched new vmSettings [correlation ID: 05e4c534-4316-48f1-b050-1f1222339446 New eTag: 7153572902334800953] Feb 9 18:29:47.511634 waagent[1499]: 2024-02-09T18:29:47.511549Z INFO Daemon Daemon Status Blob type 'None' is not valid, assuming BlockBlob Feb 9 18:29:47.528677 waagent[1499]: 2024-02-09T18:29:47.528596Z INFO Daemon Daemon Starting provisioning Feb 9 18:29:47.534075 waagent[1499]: 2024-02-09T18:29:47.534002Z INFO Daemon Daemon Handle ovf-env.xml. Feb 9 18:29:47.539307 waagent[1499]: 2024-02-09T18:29:47.539243Z INFO Daemon Daemon Set hostname [ci-3510.3.2-a-4669ca5177] Feb 9 18:29:47.572320 waagent[1499]: 2024-02-09T18:29:47.572181Z INFO Daemon Daemon Publish hostname [ci-3510.3.2-a-4669ca5177] Feb 9 18:29:47.579553 waagent[1499]: 2024-02-09T18:29:47.579474Z INFO Daemon Daemon Examine /proc/net/route for primary interface Feb 9 18:29:47.586752 waagent[1499]: 2024-02-09T18:29:47.586685Z INFO Daemon Daemon Primary interface is [eth0] Feb 9 18:29:47.603584 systemd[1]: systemd-networkd-wait-online.service: Deactivated successfully. Feb 9 18:29:47.603757 systemd[1]: Stopped systemd-networkd-wait-online.service. Feb 9 18:29:47.603813 systemd[1]: Stopping systemd-networkd-wait-online.service... Feb 9 18:29:47.604080 systemd[1]: Stopping systemd-networkd.service... Feb 9 18:29:47.608994 systemd-networkd[1279]: eth0: DHCPv6 lease lost Feb 9 18:29:47.610819 systemd[1]: systemd-networkd.service: Deactivated successfully. Feb 9 18:29:47.611025 systemd[1]: Stopped systemd-networkd.service. Feb 9 18:29:47.613044 systemd[1]: Starting systemd-networkd.service... Feb 9 18:29:47.639197 systemd-networkd[1548]: enP54214s1: Link UP Feb 9 18:29:47.639425 systemd-networkd[1548]: enP54214s1: Gained carrier Feb 9 18:29:47.640360 systemd-networkd[1548]: eth0: Link UP Feb 9 18:29:47.640423 systemd-networkd[1548]: eth0: Gained carrier Feb 9 18:29:47.640786 systemd-networkd[1548]: lo: Link UP Feb 9 18:29:47.640838 systemd-networkd[1548]: lo: Gained carrier Feb 9 18:29:47.641384 systemd-networkd[1548]: eth0: Gained IPv6LL Feb 9 18:29:47.642541 systemd-networkd[1548]: Enumeration completed Feb 9 18:29:47.642701 systemd[1]: Started systemd-networkd.service. Feb 9 18:29:47.644312 systemd[1]: Starting systemd-networkd-wait-online.service... Feb 9 18:29:47.644315 systemd-networkd[1548]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Feb 9 18:29:47.649901 waagent[1499]: 2024-02-09T18:29:47.649717Z INFO Daemon Daemon Create user account if not exists Feb 9 18:29:47.656517 waagent[1499]: 2024-02-09T18:29:47.656422Z INFO Daemon Daemon User core already exists, skip useradd Feb 9 18:29:47.663245 waagent[1499]: 2024-02-09T18:29:47.663165Z INFO Daemon Daemon Configure sudoer Feb 9 18:29:47.668783 waagent[1499]: 2024-02-09T18:29:47.668712Z INFO Daemon Daemon Configure sshd Feb 9 18:29:47.674115 waagent[1499]: 2024-02-09T18:29:47.674050Z INFO Daemon Daemon Deploy ssh public key. Feb 9 18:29:47.675047 systemd-networkd[1548]: eth0: DHCPv4 address 10.200.20.14/24, gateway 10.200.20.1 acquired from 168.63.129.16 Feb 9 18:29:47.681087 systemd[1]: Finished systemd-networkd-wait-online.service. Feb 9 18:29:48.976094 waagent[1499]: 2024-02-09T18:29:48.976003Z INFO Daemon Daemon Provisioning complete Feb 9 18:29:48.994525 waagent[1499]: 2024-02-09T18:29:48.994458Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Feb 9 18:29:49.001870 waagent[1499]: 2024-02-09T18:29:49.001793Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Feb 9 18:29:49.014152 waagent[1499]: 2024-02-09T18:29:49.014073Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.6.0.2 is the most current agent Feb 9 18:29:49.314560 waagent[1557]: 2024-02-09T18:29:49.314466Z INFO ExtHandler ExtHandler Agent WALinuxAgent-2.6.0.2 is running as the goal state agent Feb 9 18:29:49.315639 waagent[1557]: 2024-02-09T18:29:49.315585Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Feb 9 18:29:49.315866 waagent[1557]: 2024-02-09T18:29:49.315818Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Feb 9 18:29:49.328138 waagent[1557]: 2024-02-09T18:29:49.328069Z INFO ExtHandler ExtHandler Forcing an update of the goal state.. Feb 9 18:29:49.328398 waagent[1557]: 2024-02-09T18:29:49.328349Z INFO ExtHandler ExtHandler Fetching goal state [incarnation 1] Feb 9 18:29:49.394884 waagent[1557]: 2024-02-09T18:29:49.394760Z INFO ExtHandler ExtHandler Found private key matching thumbprint F265ED1E2F8F3F1371BF510E973034A4F93E0405 Feb 9 18:29:49.395258 waagent[1557]: 2024-02-09T18:29:49.395205Z INFO ExtHandler ExtHandler Certificate with thumbprint C763C640614097ECE19EBA9B47C3F13E80C4BC62 has no matching private key. Feb 9 18:29:49.395563 waagent[1557]: 2024-02-09T18:29:49.395514Z INFO ExtHandler ExtHandler Fetch goal state completed Feb 9 18:29:49.408887 waagent[1557]: 2024-02-09T18:29:49.408829Z INFO ExtHandler ExtHandler Fetched new vmSettings [correlation ID: 971bc95d-5290-4ffa-bb63-d22e8923b5b8 New eTag: 7153572902334800953] Feb 9 18:29:49.409660 waagent[1557]: 2024-02-09T18:29:49.409603Z INFO ExtHandler ExtHandler Status Blob type 'None' is not valid, assuming BlockBlob Feb 9 18:29:49.553270 waagent[1557]: 2024-02-09T18:29:49.553138Z INFO ExtHandler ExtHandler Distro: flatcar-3510.3.2; OSUtil: CoreOSUtil; AgentService: waagent; Python: 3.9.16; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.20.1; Feb 9 18:29:49.563596 waagent[1557]: 2024-02-09T18:29:49.563515Z INFO ExtHandler ExtHandler WALinuxAgent-2.6.0.2 running as process 1557 Feb 9 18:29:49.567511 waagent[1557]: 2024-02-09T18:29:49.567427Z INFO ExtHandler ExtHandler Cgroup monitoring is not supported on ['flatcar', '3510.3.2', '', 'Flatcar Container Linux by Kinvolk'] Feb 9 18:29:49.568972 waagent[1557]: 2024-02-09T18:29:49.568899Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Feb 9 18:29:49.651839 waagent[1557]: 2024-02-09T18:29:49.651782Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Feb 9 18:29:49.652420 waagent[1557]: 2024-02-09T18:29:49.652364Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Feb 9 18:29:49.659889 waagent[1557]: 2024-02-09T18:29:49.659839Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Feb 9 18:29:49.660495 waagent[1557]: 2024-02-09T18:29:49.660440Z ERROR ExtHandler ExtHandler Unable to setup the persistent firewall rules: [Errno 30] Read-only file system: '/lib/systemd/system/waagent-network-setup.service' Feb 9 18:29:49.661762 waagent[1557]: 2024-02-09T18:29:49.661700Z INFO ExtHandler ExtHandler Checking if log collection is allowed at this time [False]. All three conditions must be met: configuration enabled [False], cgroups enabled [False], python supported: [True] Feb 9 18:29:49.663324 waagent[1557]: 2024-02-09T18:29:49.663252Z INFO ExtHandler ExtHandler Starting env monitor service. Feb 9 18:29:49.663567 waagent[1557]: 2024-02-09T18:29:49.663501Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Feb 9 18:29:49.663970 waagent[1557]: 2024-02-09T18:29:49.663882Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Feb 9 18:29:49.664876 waagent[1557]: 2024-02-09T18:29:49.664796Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Feb 9 18:29:49.665568 waagent[1557]: 2024-02-09T18:29:49.665494Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Feb 9 18:29:49.665801 waagent[1557]: 2024-02-09T18:29:49.665731Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Feb 9 18:29:49.665801 waagent[1557]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Feb 9 18:29:49.665801 waagent[1557]: eth0 00000000 0114C80A 0003 0 0 1024 00000000 0 0 0 Feb 9 18:29:49.665801 waagent[1557]: eth0 0014C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Feb 9 18:29:49.665801 waagent[1557]: eth0 0114C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Feb 9 18:29:49.665801 waagent[1557]: eth0 10813FA8 0114C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Feb 9 18:29:49.665801 waagent[1557]: eth0 FEA9FEA9 0114C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Feb 9 18:29:49.668001 waagent[1557]: 2024-02-09T18:29:49.667829Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Feb 9 18:29:49.668311 waagent[1557]: 2024-02-09T18:29:49.668235Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Feb 9 18:29:49.668542 waagent[1557]: 2024-02-09T18:29:49.668469Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Feb 9 18:29:49.668897 waagent[1557]: 2024-02-09T18:29:49.668835Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Feb 9 18:29:49.669791 waagent[1557]: 2024-02-09T18:29:49.669723Z INFO EnvHandler ExtHandler Configure routes Feb 9 18:29:49.670427 waagent[1557]: 2024-02-09T18:29:49.670350Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Feb 9 18:29:49.670603 waagent[1557]: 2024-02-09T18:29:49.670531Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Feb 9 18:29:49.670775 waagent[1557]: 2024-02-09T18:29:49.670715Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Feb 9 18:29:49.671250 waagent[1557]: 2024-02-09T18:29:49.671184Z INFO EnvHandler ExtHandler Gateway:None Feb 9 18:29:49.672712 waagent[1557]: 2024-02-09T18:29:49.672650Z INFO EnvHandler ExtHandler Routes:None Feb 9 18:29:49.687041 waagent[1557]: 2024-02-09T18:29:49.686977Z INFO ExtHandler ExtHandler Checking for agent updates (family: Prod) Feb 9 18:29:49.687819 waagent[1557]: 2024-02-09T18:29:49.687770Z WARNING ExtHandler ExtHandler Fetch failed: [HttpError] HTTPS is unavailable and required Feb 9 18:29:49.688918 waagent[1557]: 2024-02-09T18:29:49.688860Z INFO ExtHandler ExtHandler [PERIODIC] Request failed using the direct channel. Error: 'NoneType' object has no attribute 'getheaders' Feb 9 18:29:49.725423 waagent[1557]: 2024-02-09T18:29:49.725345Z ERROR EnvHandler ExtHandler Failed to get the PID of the DHCP client: invalid literal for int() with base 10: 'MainPID=1548' Feb 9 18:29:49.731279 waagent[1557]: 2024-02-09T18:29:49.731202Z INFO ExtHandler ExtHandler Default channel changed to HostGA channel. Feb 9 18:29:49.765984 waagent[1557]: 2024-02-09T18:29:49.765805Z INFO MonitorHandler ExtHandler Network interfaces: Feb 9 18:29:49.765984 waagent[1557]: Executing ['ip', '-a', '-o', 'link']: Feb 9 18:29:49.765984 waagent[1557]: 1: lo: mtu 65536 qdisc noqueue state UNKNOWN mode DEFAULT group default qlen 1000\ link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00 Feb 9 18:29:49.765984 waagent[1557]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 00:0d:3a:c4:09:21 brd ff:ff:ff:ff:ff:ff Feb 9 18:29:49.765984 waagent[1557]: 3: enP54214s1: mtu 1500 qdisc mq master eth0 state UP mode DEFAULT group default qlen 1000\ link/ether 00:0d:3a:c4:09:21 brd ff:ff:ff:ff:ff:ff\ altname enP54214p0s2 Feb 9 18:29:49.765984 waagent[1557]: Executing ['ip', '-4', '-a', '-o', 'address']: Feb 9 18:29:49.765984 waagent[1557]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Feb 9 18:29:49.765984 waagent[1557]: 2: eth0 inet 10.200.20.14/24 metric 1024 brd 10.200.20.255 scope global eth0\ valid_lft forever preferred_lft forever Feb 9 18:29:49.765984 waagent[1557]: Executing ['ip', '-6', '-a', '-o', 'address']: Feb 9 18:29:49.765984 waagent[1557]: 1: lo inet6 ::1/128 scope host \ valid_lft forever preferred_lft forever Feb 9 18:29:49.765984 waagent[1557]: 2: eth0 inet6 fe80::20d:3aff:fec4:921/64 scope link \ valid_lft forever preferred_lft forever Feb 9 18:29:49.919241 waagent[1557]: 2024-02-09T18:29:49.919145Z INFO ExtHandler ExtHandler Agent WALinuxAgent-2.6.0.2 discovered update WALinuxAgent-2.9.1.1 -- exiting Feb 9 18:29:50.017584 waagent[1499]: 2024-02-09T18:29:50.017428Z INFO Daemon Daemon Agent WALinuxAgent-2.6.0.2 launched with command '/usr/share/oem/python/bin/python -u /usr/share/oem/bin/waagent -run-exthandlers' is successfully running Feb 9 18:29:50.021355 waagent[1499]: 2024-02-09T18:29:50.021299Z INFO Daemon Daemon Determined Agent WALinuxAgent-2.9.1.1 to be the latest agent Feb 9 18:29:51.151579 waagent[1586]: 2024-02-09T18:29:51.151482Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.9.1.1) Feb 9 18:29:51.152611 waagent[1586]: 2024-02-09T18:29:51.152555Z INFO ExtHandler ExtHandler OS: flatcar 3510.3.2 Feb 9 18:29:51.152833 waagent[1586]: 2024-02-09T18:29:51.152786Z INFO ExtHandler ExtHandler Python: 3.9.16 Feb 9 18:29:51.161022 waagent[1586]: 2024-02-09T18:29:51.160899Z INFO ExtHandler ExtHandler Distro: flatcar-3510.3.2; OSUtil: CoreOSUtil; AgentService: waagent; Python: 3.9.16; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.20.1; Feb 9 18:29:51.161493 waagent[1586]: 2024-02-09T18:29:51.161439Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Feb 9 18:29:51.161730 waagent[1586]: 2024-02-09T18:29:51.161681Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Feb 9 18:29:51.174135 waagent[1586]: 2024-02-09T18:29:51.174069Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Feb 9 18:29:51.182608 waagent[1586]: 2024-02-09T18:29:51.182553Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.143 Feb 9 18:29:51.183745 waagent[1586]: 2024-02-09T18:29:51.183689Z INFO ExtHandler Feb 9 18:29:51.183994 waagent[1586]: 2024-02-09T18:29:51.183922Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: 75941947-0b8c-483b-958d-ed53992f0af8 eTag: 7153572902334800953 source: Fabric] Feb 9 18:29:51.184859 waagent[1586]: 2024-02-09T18:29:51.184800Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Feb 9 18:29:51.186284 waagent[1586]: 2024-02-09T18:29:51.186226Z INFO ExtHandler Feb 9 18:29:51.186500 waagent[1586]: 2024-02-09T18:29:51.186453Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Feb 9 18:29:51.192576 waagent[1586]: 2024-02-09T18:29:51.192530Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Feb 9 18:29:51.193152 waagent[1586]: 2024-02-09T18:29:51.193101Z WARNING ExtHandler ExtHandler Fetch failed: [HttpError] HTTPS is unavailable and required Feb 9 18:29:51.213076 waagent[1586]: 2024-02-09T18:29:51.213020Z INFO ExtHandler ExtHandler Default channel changed to HostGAPlugin channel. Feb 9 18:29:51.282802 waagent[1586]: 2024-02-09T18:29:51.282667Z INFO ExtHandler Downloaded certificate {'thumbprint': 'F265ED1E2F8F3F1371BF510E973034A4F93E0405', 'hasPrivateKey': True} Feb 9 18:29:51.284074 waagent[1586]: 2024-02-09T18:29:51.284014Z INFO ExtHandler Downloaded certificate {'thumbprint': 'C763C640614097ECE19EBA9B47C3F13E80C4BC62', 'hasPrivateKey': False} Feb 9 18:29:51.285284 waagent[1586]: 2024-02-09T18:29:51.285226Z INFO ExtHandler Fetch goal state completed Feb 9 18:29:51.307212 waagent[1586]: 2024-02-09T18:29:51.307145Z INFO ExtHandler ExtHandler WALinuxAgent-2.9.1.1 running as process 1586 Feb 9 18:29:51.310859 waagent[1586]: 2024-02-09T18:29:51.310797Z INFO ExtHandler ExtHandler Cgroup monitoring is not supported on ['flatcar', '3510.3.2', '', 'Flatcar Container Linux by Kinvolk'] Feb 9 18:29:51.312441 waagent[1586]: 2024-02-09T18:29:51.312383Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Feb 9 18:29:51.317594 waagent[1586]: 2024-02-09T18:29:51.317544Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Feb 9 18:29:51.318091 waagent[1586]: 2024-02-09T18:29:51.318036Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Feb 9 18:29:51.326044 waagent[1586]: 2024-02-09T18:29:51.325990Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Feb 9 18:29:51.326620 waagent[1586]: 2024-02-09T18:29:51.326566Z ERROR ExtHandler ExtHandler Unable to setup the persistent firewall rules: [Errno 30] Read-only file system: '/lib/systemd/system/waagent-network-setup.service' Feb 9 18:29:51.332646 waagent[1586]: 2024-02-09T18:29:51.332553Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Feb 9 18:29:51.336348 waagent[1586]: 2024-02-09T18:29:51.336292Z INFO ExtHandler ExtHandler Checking if log collection is allowed at this time [False]. All three conditions must be met: configuration enabled [True], cgroups enabled [False], python supported: [True] Feb 9 18:29:51.338019 waagent[1586]: 2024-02-09T18:29:51.337923Z INFO ExtHandler ExtHandler Starting env monitor service. Feb 9 18:29:51.338393 waagent[1586]: 2024-02-09T18:29:51.338316Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Feb 9 18:29:51.338849 waagent[1586]: 2024-02-09T18:29:51.338782Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Feb 9 18:29:51.339471 waagent[1586]: 2024-02-09T18:29:51.339400Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Feb 9 18:29:51.339784 waagent[1586]: 2024-02-09T18:29:51.339725Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Feb 9 18:29:51.339784 waagent[1586]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Feb 9 18:29:51.339784 waagent[1586]: eth0 00000000 0114C80A 0003 0 0 1024 00000000 0 0 0 Feb 9 18:29:51.339784 waagent[1586]: eth0 0014C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Feb 9 18:29:51.339784 waagent[1586]: eth0 0114C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Feb 9 18:29:51.339784 waagent[1586]: eth0 10813FA8 0114C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Feb 9 18:29:51.339784 waagent[1586]: eth0 FEA9FEA9 0114C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Feb 9 18:29:51.342124 waagent[1586]: 2024-02-09T18:29:51.342014Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Feb 9 18:29:51.342339 waagent[1586]: 2024-02-09T18:29:51.342271Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Feb 9 18:29:51.342685 waagent[1586]: 2024-02-09T18:29:51.342612Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Feb 9 18:29:51.345722 waagent[1586]: 2024-02-09T18:29:51.345581Z INFO EnvHandler ExtHandler Configure routes Feb 9 18:29:51.346015 waagent[1586]: 2024-02-09T18:29:51.345928Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Feb 9 18:29:51.346209 waagent[1586]: 2024-02-09T18:29:51.346145Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Feb 9 18:29:51.346816 waagent[1586]: 2024-02-09T18:29:51.346738Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Feb 9 18:29:51.347297 waagent[1586]: 2024-02-09T18:29:51.347228Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Feb 9 18:29:51.347544 waagent[1586]: 2024-02-09T18:29:51.347473Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Feb 9 18:29:51.348256 waagent[1586]: 2024-02-09T18:29:51.348182Z INFO EnvHandler ExtHandler Gateway:None Feb 9 18:29:51.351452 waagent[1586]: 2024-02-09T18:29:51.351383Z INFO EnvHandler ExtHandler Routes:None Feb 9 18:29:51.357637 waagent[1586]: 2024-02-09T18:29:51.357565Z INFO MonitorHandler ExtHandler Network interfaces: Feb 9 18:29:51.357637 waagent[1586]: Executing ['ip', '-a', '-o', 'link']: Feb 9 18:29:51.357637 waagent[1586]: 1: lo: mtu 65536 qdisc noqueue state UNKNOWN mode DEFAULT group default qlen 1000\ link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00 Feb 9 18:29:51.357637 waagent[1586]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 00:0d:3a:c4:09:21 brd ff:ff:ff:ff:ff:ff Feb 9 18:29:51.357637 waagent[1586]: 3: enP54214s1: mtu 1500 qdisc mq master eth0 state UP mode DEFAULT group default qlen 1000\ link/ether 00:0d:3a:c4:09:21 brd ff:ff:ff:ff:ff:ff\ altname enP54214p0s2 Feb 9 18:29:51.357637 waagent[1586]: Executing ['ip', '-4', '-a', '-o', 'address']: Feb 9 18:29:51.357637 waagent[1586]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Feb 9 18:29:51.357637 waagent[1586]: 2: eth0 inet 10.200.20.14/24 metric 1024 brd 10.200.20.255 scope global eth0\ valid_lft forever preferred_lft forever Feb 9 18:29:51.357637 waagent[1586]: Executing ['ip', '-6', '-a', '-o', 'address']: Feb 9 18:29:51.357637 waagent[1586]: 1: lo inet6 ::1/128 scope host \ valid_lft forever preferred_lft forever Feb 9 18:29:51.357637 waagent[1586]: 2: eth0 inet6 fe80::20d:3aff:fec4:921/64 scope link \ valid_lft forever preferred_lft forever Feb 9 18:29:51.367450 waagent[1586]: 2024-02-09T18:29:51.367371Z INFO ExtHandler ExtHandler No requested version specified, checking for all versions for agent update (family: Prod) Feb 9 18:29:51.368556 waagent[1586]: 2024-02-09T18:29:51.368492Z INFO ExtHandler ExtHandler Downloading manifest Feb 9 18:29:51.387746 waagent[1586]: 2024-02-09T18:29:51.387691Z INFO ExtHandler ExtHandler Feb 9 18:29:51.388023 waagent[1586]: 2024-02-09T18:29:51.387964Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 145c6b5e-2813-4d55-b600-2ce17a6e577b correlation 87e96e4d-e8af-4069-ac19-6ffb32b711c2 created: 2024-02-09T18:28:19.254082Z] Feb 9 18:29:51.389132 waagent[1586]: 2024-02-09T18:29:51.389071Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Feb 9 18:29:51.391056 waagent[1586]: 2024-02-09T18:29:51.391003Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 3 ms] Feb 9 18:29:51.413682 waagent[1586]: 2024-02-09T18:29:51.413579Z INFO ExtHandler ExtHandler Looking for existing remote access users. Feb 9 18:29:51.435398 waagent[1586]: 2024-02-09T18:29:51.435306Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.9.1.1 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: 867A3865-020C-49BC-8CCF-A3E310BA8EBE;DroppedPackets: 0;UpdateGSErrors: 0;AutoUpdate: 1] Feb 9 18:29:51.616851 waagent[1586]: 2024-02-09T18:29:51.616721Z INFO EnvHandler ExtHandler Successfully added Azure fabric firewall rules. Current Firewall rules: Feb 9 18:29:51.616851 waagent[1586]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Feb 9 18:29:51.616851 waagent[1586]: pkts bytes target prot opt in out source destination Feb 9 18:29:51.616851 waagent[1586]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Feb 9 18:29:51.616851 waagent[1586]: pkts bytes target prot opt in out source destination Feb 9 18:29:51.616851 waagent[1586]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Feb 9 18:29:51.616851 waagent[1586]: pkts bytes target prot opt in out source destination Feb 9 18:29:51.616851 waagent[1586]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Feb 9 18:29:51.616851 waagent[1586]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Feb 9 18:29:51.616851 waagent[1586]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Feb 9 18:29:51.624052 waagent[1586]: 2024-02-09T18:29:51.623921Z INFO EnvHandler ExtHandler Current Firewall rules: Feb 9 18:29:51.624052 waagent[1586]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Feb 9 18:29:51.624052 waagent[1586]: pkts bytes target prot opt in out source destination Feb 9 18:29:51.624052 waagent[1586]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Feb 9 18:29:51.624052 waagent[1586]: pkts bytes target prot opt in out source destination Feb 9 18:29:51.624052 waagent[1586]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Feb 9 18:29:51.624052 waagent[1586]: pkts bytes target prot opt in out source destination Feb 9 18:29:51.624052 waagent[1586]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Feb 9 18:29:51.624052 waagent[1586]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Feb 9 18:29:51.624052 waagent[1586]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Feb 9 18:29:51.624550 waagent[1586]: 2024-02-09T18:29:51.624496Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Feb 9 18:30:14.971447 kernel: hv_balloon: Max. dynamic memory size: 4096 MB Feb 9 18:30:24.590419 update_engine[1404]: I0209 18:30:24.590005 1404 update_attempter.cc:509] Updating boot flags... Feb 9 18:30:26.703275 systemd[1]: Created slice system-sshd.slice. Feb 9 18:30:26.704328 systemd[1]: Started sshd@0-10.200.20.14:22-10.200.12.6:56210.service. Feb 9 18:30:27.278772 sshd[1705]: Accepted publickey for core from 10.200.12.6 port 56210 ssh2: RSA SHA256:AExcTof2ms2RC04cAfR/26ykZOGA1PeppPBnNP0o6qE Feb 9 18:30:27.304229 sshd[1705]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Feb 9 18:30:27.308790 systemd[1]: Started session-3.scope. Feb 9 18:30:27.309734 systemd-logind[1401]: New session 3 of user core. Feb 9 18:30:27.658887 systemd[1]: Started sshd@1-10.200.20.14:22-10.200.12.6:49648.service. Feb 9 18:30:28.069851 sshd[1710]: Accepted publickey for core from 10.200.12.6 port 49648 ssh2: RSA SHA256:AExcTof2ms2RC04cAfR/26ykZOGA1PeppPBnNP0o6qE Feb 9 18:30:28.071376 sshd[1710]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Feb 9 18:30:28.074858 systemd-logind[1401]: New session 4 of user core. Feb 9 18:30:28.075286 systemd[1]: Started session-4.scope. Feb 9 18:30:28.363207 sshd[1710]: pam_unix(sshd:session): session closed for user core Feb 9 18:30:28.365362 systemd[1]: sshd@1-10.200.20.14:22-10.200.12.6:49648.service: Deactivated successfully. Feb 9 18:30:28.366096 systemd[1]: session-4.scope: Deactivated successfully. Feb 9 18:30:28.366614 systemd-logind[1401]: Session 4 logged out. Waiting for processes to exit. Feb 9 18:30:28.367526 systemd-logind[1401]: Removed session 4. Feb 9 18:30:28.449191 systemd[1]: Started sshd@2-10.200.20.14:22-10.200.12.6:49654.service. Feb 9 18:30:28.878463 sshd[1716]: Accepted publickey for core from 10.200.12.6 port 49654 ssh2: RSA SHA256:AExcTof2ms2RC04cAfR/26ykZOGA1PeppPBnNP0o6qE Feb 9 18:30:28.879685 sshd[1716]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Feb 9 18:30:28.883753 systemd[1]: Started session-5.scope. Feb 9 18:30:28.884787 systemd-logind[1401]: New session 5 of user core. Feb 9 18:30:29.188492 sshd[1716]: pam_unix(sshd:session): session closed for user core Feb 9 18:30:29.190655 systemd[1]: sshd@2-10.200.20.14:22-10.200.12.6:49654.service: Deactivated successfully. Feb 9 18:30:29.191348 systemd[1]: session-5.scope: Deactivated successfully. Feb 9 18:30:29.191828 systemd-logind[1401]: Session 5 logged out. Waiting for processes to exit. Feb 9 18:30:29.192539 systemd-logind[1401]: Removed session 5. Feb 9 18:30:29.260486 systemd[1]: Started sshd@3-10.200.20.14:22-10.200.12.6:49670.service. Feb 9 18:30:29.690071 sshd[1722]: Accepted publickey for core from 10.200.12.6 port 49670 ssh2: RSA SHA256:AExcTof2ms2RC04cAfR/26ykZOGA1PeppPBnNP0o6qE Feb 9 18:30:29.691364 sshd[1722]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Feb 9 18:30:29.695025 systemd-logind[1401]: New session 6 of user core. Feb 9 18:30:29.695434 systemd[1]: Started session-6.scope. Feb 9 18:30:30.003710 sshd[1722]: pam_unix(sshd:session): session closed for user core Feb 9 18:30:30.006424 systemd-logind[1401]: Session 6 logged out. Waiting for processes to exit. Feb 9 18:30:30.006575 systemd[1]: session-6.scope: Deactivated successfully. Feb 9 18:30:30.007301 systemd[1]: sshd@3-10.200.20.14:22-10.200.12.6:49670.service: Deactivated successfully. Feb 9 18:30:30.008323 systemd-logind[1401]: Removed session 6. Feb 9 18:30:30.069832 systemd[1]: Started sshd@4-10.200.20.14:22-10.200.12.6:49678.service. Feb 9 18:30:30.468818 sshd[1728]: Accepted publickey for core from 10.200.12.6 port 49678 ssh2: RSA SHA256:AExcTof2ms2RC04cAfR/26ykZOGA1PeppPBnNP0o6qE Feb 9 18:30:30.470329 sshd[1728]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Feb 9 18:30:30.473871 systemd-logind[1401]: New session 7 of user core. Feb 9 18:30:30.474329 systemd[1]: Started session-7.scope. Feb 9 18:30:30.926253 sudo[1731]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Feb 9 18:30:30.926444 sudo[1731]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Feb 9 18:30:30.955076 dbus-daemon[1388]: avc: received setenforce notice (enforcing=1) Feb 9 18:30:30.956043 sudo[1731]: pam_unix(sudo:session): session closed for user root Feb 9 18:30:31.032035 sshd[1728]: pam_unix(sshd:session): session closed for user core Feb 9 18:30:31.034848 systemd[1]: sshd@4-10.200.20.14:22-10.200.12.6:49678.service: Deactivated successfully. Feb 9 18:30:31.035616 systemd[1]: session-7.scope: Deactivated successfully. Feb 9 18:30:31.036113 systemd-logind[1401]: Session 7 logged out. Waiting for processes to exit. Feb 9 18:30:31.036809 systemd-logind[1401]: Removed session 7. Feb 9 18:30:31.098521 systemd[1]: Started sshd@5-10.200.20.14:22-10.200.12.6:49682.service. Feb 9 18:30:31.497818 sshd[1735]: Accepted publickey for core from 10.200.12.6 port 49682 ssh2: RSA SHA256:AExcTof2ms2RC04cAfR/26ykZOGA1PeppPBnNP0o6qE Feb 9 18:30:31.499157 sshd[1735]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Feb 9 18:30:31.502821 systemd-logind[1401]: New session 8 of user core. Feb 9 18:30:31.503272 systemd[1]: Started session-8.scope. Feb 9 18:30:31.728599 sudo[1739]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/bin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules Feb 9 18:30:31.728804 sudo[1739]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Feb 9 18:30:31.731298 sudo[1739]: pam_unix(sudo:session): session closed for user root Feb 9 18:30:31.735254 sudo[1738]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/bin/systemctl restart audit-rules Feb 9 18:30:31.735436 sudo[1738]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Feb 9 18:30:31.743573 systemd[1]: Stopping audit-rules.service... Feb 9 18:30:31.743000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Feb 9 18:30:31.747092 auditctl[1742]: No rules Feb 9 18:30:31.748627 kernel: kauditd_printk_skb: 90 callbacks suppressed Feb 9 18:30:31.748666 kernel: audit: type=1305 audit(1707503431.743:172): auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Feb 9 18:30:31.748929 systemd[1]: audit-rules.service: Deactivated successfully. Feb 9 18:30:31.749106 systemd[1]: Stopped audit-rules.service. Feb 9 18:30:31.750507 systemd[1]: Starting audit-rules.service... Feb 9 18:30:31.743000 audit[1742]: SYSCALL arch=c00000b7 syscall=206 success=yes exit=1056 a0=3 a1=ffffeec160e0 a2=420 a3=0 items=0 ppid=1 pid=1742 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="auditctl" exe="/usr/sbin/auditctl" subj=system_u:system_r:kernel_t:s0 key=(null) Feb 9 18:30:31.785573 kernel: audit: type=1300 audit(1707503431.743:172): arch=c00000b7 syscall=206 success=yes exit=1056 a0=3 a1=ffffeec160e0 a2=420 a3=0 items=0 ppid=1 pid=1742 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="auditctl" exe="/usr/sbin/auditctl" subj=system_u:system_r:kernel_t:s0 key=(null) Feb 9 18:30:31.743000 audit: PROCTITLE proctitle=2F7362696E2F617564697463746C002D44 Feb 9 18:30:31.793022 kernel: audit: type=1327 audit(1707503431.743:172): proctitle=2F7362696E2F617564697463746C002D44 Feb 9 18:30:31.748000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=audit-rules comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:30:31.812605 kernel: audit: type=1131 audit(1707503431.748:173): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=audit-rules comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:30:31.812671 kernel: audit: type=1130 audit(1707503431.803:174): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=audit-rules comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:30:31.803000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=audit-rules comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:30:31.804667 systemd[1]: Finished audit-rules.service. Feb 9 18:30:31.812785 augenrules[1759]: No rules Feb 9 18:30:31.810733 sudo[1738]: pam_unix(sudo:session): session closed for user root Feb 9 18:30:31.809000 audit[1738]: USER_END pid=1738 uid=500 auid=500 ses=8 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_limits,pam_env,pam_unix,pam_permit,pam_systemd acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Feb 9 18:30:31.844789 kernel: audit: type=1106 audit(1707503431.809:175): pid=1738 uid=500 auid=500 ses=8 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_limits,pam_env,pam_unix,pam_permit,pam_systemd acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Feb 9 18:30:31.844877 kernel: audit: type=1104 audit(1707503431.809:176): pid=1738 uid=500 auid=500 ses=8 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Feb 9 18:30:31.809000 audit[1738]: CRED_DISP pid=1738 uid=500 auid=500 ses=8 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Feb 9 18:30:31.875172 sshd[1735]: pam_unix(sshd:session): session closed for user core Feb 9 18:30:31.874000 audit[1735]: USER_END pid=1735 uid=0 auid=500 ses=8 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_loginuid,pam_env,pam_lastlog,pam_limits,pam_env,pam_unix,pam_permit,pam_systemd,pam_mail acct="core" exe="/usr/sbin/sshd" hostname=10.200.12.6 addr=10.200.12.6 terminal=ssh res=success' Feb 9 18:30:31.899894 systemd[1]: sshd@5-10.200.20.14:22-10.200.12.6:49682.service: Deactivated successfully. Feb 9 18:30:31.875000 audit[1735]: CRED_DISP pid=1735 uid=0 auid=500 ses=8 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=10.200.12.6 addr=10.200.12.6 terminal=ssh res=success' Feb 9 18:30:31.920489 kernel: audit: type=1106 audit(1707503431.874:177): pid=1735 uid=0 auid=500 ses=8 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_loginuid,pam_env,pam_lastlog,pam_limits,pam_env,pam_unix,pam_permit,pam_systemd,pam_mail acct="core" exe="/usr/sbin/sshd" hostname=10.200.12.6 addr=10.200.12.6 terminal=ssh res=success' Feb 9 18:30:31.920565 kernel: audit: type=1104 audit(1707503431.875:178): pid=1735 uid=0 auid=500 ses=8 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=10.200.12.6 addr=10.200.12.6 terminal=ssh res=success' Feb 9 18:30:31.900620 systemd[1]: session-8.scope: Deactivated successfully. Feb 9 18:30:31.899000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@5-10.200.20.14:22-10.200.12.6:49682 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:30:31.921191 systemd-logind[1401]: Session 8 logged out. Waiting for processes to exit. Feb 9 18:30:31.940341 systemd-logind[1401]: Removed session 8. Feb 9 18:30:31.940960 kernel: audit: type=1131 audit(1707503431.899:179): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@5-10.200.20.14:22-10.200.12.6:49682 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:30:31.960529 systemd[1]: Started sshd@6-10.200.20.14:22-10.200.12.6:49696.service. Feb 9 18:30:31.959000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@6-10.200.20.14:22-10.200.12.6:49696 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:30:32.389000 audit[1765]: USER_ACCT pid=1765 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:accounting grantors=pam_access,pam_unix,pam_faillock,pam_permit acct="core" exe="/usr/sbin/sshd" hostname=10.200.12.6 addr=10.200.12.6 terminal=ssh res=success' Feb 9 18:30:32.390446 sshd[1765]: Accepted publickey for core from 10.200.12.6 port 49696 ssh2: RSA SHA256:AExcTof2ms2RC04cAfR/26ykZOGA1PeppPBnNP0o6qE Feb 9 18:30:32.390000 audit[1765]: CRED_ACQ pid=1765 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=10.200.12.6 addr=10.200.12.6 terminal=ssh res=success' Feb 9 18:30:32.390000 audit[1765]: SYSCALL arch=c00000b7 syscall=64 success=yes exit=3 a0=5 a1=ffffd7fa63d0 a2=3 a3=1 items=0 ppid=1 pid=1765 auid=500 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=9 comm="sshd" exe="/usr/sbin/sshd" subj=system_u:system_r:kernel_t:s0 key=(null) Feb 9 18:30:32.390000 audit: PROCTITLE proctitle=737368643A20636F7265205B707269765D Feb 9 18:30:32.392046 sshd[1765]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Feb 9 18:30:32.396140 systemd[1]: Started session-9.scope. Feb 9 18:30:32.396444 systemd-logind[1401]: New session 9 of user core. Feb 9 18:30:32.399000 audit[1765]: USER_START pid=1765 uid=0 auid=500 ses=9 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_open grantors=pam_loginuid,pam_env,pam_lastlog,pam_limits,pam_env,pam_unix,pam_permit,pam_systemd,pam_mail acct="core" exe="/usr/sbin/sshd" hostname=10.200.12.6 addr=10.200.12.6 terminal=ssh res=success' Feb 9 18:30:32.400000 audit[1767]: CRED_ACQ pid=1767 uid=0 auid=500 ses=9 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=10.200.12.6 addr=10.200.12.6 terminal=ssh res=success' Feb 9 18:30:32.635000 audit[1768]: USER_ACCT pid=1768 uid=500 auid=500 ses=9 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:accounting grantors=pam_unix,pam_faillock,pam_permit acct="core" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Feb 9 18:30:32.636661 sudo[1768]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/blkid -s UUID -o value /dev/disk/by-label/ROOT Feb 9 18:30:32.635000 audit[1768]: CRED_REFR pid=1768 uid=500 auid=500 ses=9 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Feb 9 18:30:32.636863 sudo[1768]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Feb 9 18:30:32.637000 audit[1768]: USER_START pid=1768 uid=500 auid=500 ses=9 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_open grantors=pam_limits,pam_env,pam_unix,pam_permit,pam_systemd acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Feb 9 18:30:32.709855 sudo[1768]: pam_unix(sudo:session): session closed for user root Feb 9 18:30:32.708000 audit[1768]: USER_END pid=1768 uid=500 auid=500 ses=9 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_limits,pam_env,pam_unix,pam_permit,pam_systemd acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Feb 9 18:30:32.709000 audit[1768]: CRED_DISP pid=1768 uid=500 auid=500 ses=9 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Feb 9 18:30:32.778376 sshd[1765]: pam_unix(sshd:session): session closed for user core Feb 9 18:30:32.778000 audit[1765]: USER_END pid=1765 uid=0 auid=500 ses=9 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_loginuid,pam_env,pam_lastlog,pam_limits,pam_env,pam_unix,pam_permit,pam_systemd,pam_mail acct="core" exe="/usr/sbin/sshd" hostname=10.200.12.6 addr=10.200.12.6 terminal=ssh res=success' Feb 9 18:30:32.778000 audit[1765]: CRED_DISP pid=1765 uid=0 auid=500 ses=9 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=10.200.12.6 addr=10.200.12.6 terminal=ssh res=success' Feb 9 18:30:32.781174 systemd-logind[1401]: Session 9 logged out. Waiting for processes to exit. Feb 9 18:30:32.780000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@6-10.200.20.14:22-10.200.12.6:49696 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:30:32.781433 systemd[1]: sshd@6-10.200.20.14:22-10.200.12.6:49696.service: Deactivated successfully. Feb 9 18:30:32.782253 systemd[1]: session-9.scope: Deactivated successfully. Feb 9 18:30:32.782886 systemd-logind[1401]: Removed session 9. Feb 9 18:30:32.851000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@7-10.200.20.14:22-10.200.12.6:49704 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:30:32.852446 systemd[1]: Started sshd@7-10.200.20.14:22-10.200.12.6:49704.service. Feb 9 18:30:33.255000 audit[1772]: USER_ACCT pid=1772 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:accounting grantors=pam_access,pam_unix,pam_faillock,pam_permit acct="core" exe="/usr/sbin/sshd" hostname=10.200.12.6 addr=10.200.12.6 terminal=ssh res=success' Feb 9 18:30:33.256497 sshd[1772]: Accepted publickey for core from 10.200.12.6 port 49704 ssh2: RSA SHA256:AExcTof2ms2RC04cAfR/26ykZOGA1PeppPBnNP0o6qE Feb 9 18:30:33.256000 audit[1772]: CRED_ACQ pid=1772 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=10.200.12.6 addr=10.200.12.6 terminal=ssh res=success' Feb 9 18:30:33.256000 audit[1772]: SYSCALL arch=c00000b7 syscall=64 success=yes exit=3 a0=5 a1=fffffc974060 a2=3 a3=1 items=0 ppid=1 pid=1772 auid=500 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=10 comm="sshd" exe="/usr/sbin/sshd" subj=system_u:system_r:kernel_t:s0 key=(null) Feb 9 18:30:33.256000 audit: PROCTITLE proctitle=737368643A20636F7265205B707269765D Feb 9 18:30:33.258021 sshd[1772]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Feb 9 18:30:33.261490 systemd-logind[1401]: New session 10 of user core. Feb 9 18:30:33.261913 systemd[1]: Started session-10.scope. Feb 9 18:30:33.264000 audit[1772]: USER_START pid=1772 uid=0 auid=500 ses=10 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_open grantors=pam_loginuid,pam_env,pam_lastlog,pam_limits,pam_env,pam_unix,pam_permit,pam_systemd,pam_mail acct="core" exe="/usr/sbin/sshd" hostname=10.200.12.6 addr=10.200.12.6 terminal=ssh res=success' Feb 9 18:30:33.265000 audit[1774]: CRED_ACQ pid=1774 uid=0 auid=500 ses=10 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=10.200.12.6 addr=10.200.12.6 terminal=ssh res=success' Feb 9 18:30:33.488000 audit[1775]: USER_ACCT pid=1775 uid=500 auid=500 ses=10 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:accounting grantors=pam_unix,pam_faillock,pam_permit acct="core" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Feb 9 18:30:33.488000 audit[1775]: CRED_REFR pid=1775 uid=500 auid=500 ses=10 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Feb 9 18:30:33.489249 sudo[1775]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/blkid -s TYPE -o value /dev/disk/by-label/ROOT Feb 9 18:30:33.489446 sudo[1775]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Feb 9 18:30:33.489000 audit[1775]: USER_START pid=1775 uid=500 auid=500 ses=10 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_open grantors=pam_limits,pam_env,pam_unix,pam_permit,pam_systemd acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Feb 9 18:30:33.491000 audit[1775]: USER_END pid=1775 uid=500 auid=500 ses=10 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_limits,pam_env,pam_unix,pam_permit,pam_systemd acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Feb 9 18:30:33.491000 audit[1775]: CRED_DISP pid=1775 uid=500 auid=500 ses=10 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Feb 9 18:30:33.492082 sudo[1775]: pam_unix(sudo:session): session closed for user root Feb 9 18:30:33.555217 sshd[1772]: pam_unix(sshd:session): session closed for user core Feb 9 18:30:33.555000 audit[1772]: USER_END pid=1772 uid=0 auid=500 ses=10 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_loginuid,pam_env,pam_lastlog,pam_limits,pam_env,pam_unix,pam_permit,pam_systemd,pam_mail acct="core" exe="/usr/sbin/sshd" hostname=10.200.12.6 addr=10.200.12.6 terminal=ssh res=success' Feb 9 18:30:33.555000 audit[1772]: CRED_DISP pid=1772 uid=0 auid=500 ses=10 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=10.200.12.6 addr=10.200.12.6 terminal=ssh res=success' Feb 9 18:30:33.556000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@7-10.200.20.14:22-10.200.12.6:49704 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:30:33.557492 systemd[1]: sshd@7-10.200.20.14:22-10.200.12.6:49704.service: Deactivated successfully. Feb 9 18:30:33.558157 systemd[1]: session-10.scope: Deactivated successfully. Feb 9 18:30:33.558666 systemd-logind[1401]: Session 10 logged out. Waiting for processes to exit. Feb 9 18:30:33.559579 systemd-logind[1401]: Removed session 10. Feb 9 18:30:33.628121 systemd[1]: Started sshd@8-10.200.20.14:22-10.200.12.6:49718.service. Feb 9 18:30:33.627000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@8-10.200.20.14:22-10.200.12.6:49718 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:30:34.033000 audit[1779]: USER_ACCT pid=1779 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:accounting grantors=pam_access,pam_unix,pam_faillock,pam_permit acct="core" exe="/usr/sbin/sshd" hostname=10.200.12.6 addr=10.200.12.6 terminal=ssh res=success' Feb 9 18:30:34.034861 sshd[1779]: Accepted publickey for core from 10.200.12.6 port 49718 ssh2: RSA SHA256:AExcTof2ms2RC04cAfR/26ykZOGA1PeppPBnNP0o6qE Feb 9 18:30:34.034000 audit[1779]: CRED_ACQ pid=1779 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=10.200.12.6 addr=10.200.12.6 terminal=ssh res=success' Feb 9 18:30:34.034000 audit[1779]: SYSCALL arch=c00000b7 syscall=64 success=yes exit=3 a0=5 a1=fffff1d0e940 a2=3 a3=1 items=0 ppid=1 pid=1779 auid=500 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=11 comm="sshd" exe="/usr/sbin/sshd" subj=system_u:system_r:kernel_t:s0 key=(null) Feb 9 18:30:34.034000 audit: PROCTITLE proctitle=737368643A20636F7265205B707269765D Feb 9 18:30:34.036347 sshd[1779]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Feb 9 18:30:34.039993 systemd-logind[1401]: New session 11 of user core. Feb 9 18:30:34.040420 systemd[1]: Started session-11.scope. Feb 9 18:30:34.043000 audit[1779]: USER_START pid=1779 uid=0 auid=500 ses=11 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_open grantors=pam_loginuid,pam_env,pam_lastlog,pam_limits,pam_env,pam_unix,pam_permit,pam_systemd,pam_mail acct="core" exe="/usr/sbin/sshd" hostname=10.200.12.6 addr=10.200.12.6 terminal=ssh res=success' Feb 9 18:30:34.044000 audit[1781]: CRED_ACQ pid=1781 uid=0 auid=500 ses=11 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=10.200.12.6 addr=10.200.12.6 terminal=ssh res=success' Feb 9 18:30:34.376082 sshd[1779]: pam_unix(sshd:session): session closed for user core Feb 9 18:30:34.376000 audit[1779]: USER_END pid=1779 uid=0 auid=500 ses=11 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_loginuid,pam_env,pam_lastlog,pam_limits,pam_env,pam_unix,pam_permit,pam_systemd,pam_mail acct="core" exe="/usr/sbin/sshd" hostname=10.200.12.6 addr=10.200.12.6 terminal=ssh res=success' Feb 9 18:30:34.376000 audit[1779]: CRED_DISP pid=1779 uid=0 auid=500 ses=11 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=10.200.12.6 addr=10.200.12.6 terminal=ssh res=success' Feb 9 18:30:34.378482 systemd[1]: sshd@8-10.200.20.14:22-10.200.12.6:49718.service: Deactivated successfully. Feb 9 18:30:34.377000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@8-10.200.20.14:22-10.200.12.6:49718 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:30:34.379173 systemd[1]: session-11.scope: Deactivated successfully. Feb 9 18:30:34.379689 systemd-logind[1401]: Session 11 logged out. Waiting for processes to exit. Feb 9 18:30:34.380460 systemd-logind[1401]: Removed session 11. Feb 9 18:30:38.423762 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:38.439967 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:38.454499 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:38.469903 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:38.484633 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:38.500621 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:38.500834 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:38.518244 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:38.518360 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:38.535957 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:38.536160 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:38.551938 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:38.560199 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:38.568770 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:38.577344 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:38.586024 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:38.594449 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:38.611623 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:38.611817 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:38.611956 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:38.628802 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:38.636913 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:38.645317 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:38.653603 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:38.670804 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:38.670928 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:38.671036 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:38.689428 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:38.714693 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:38.714823 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:38.714910 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:38.715009 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:38.731936 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:38.732089 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:38.748636 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:38.748792 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:38.765993 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:38.766123 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:38.782882 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:38.799580 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:38.799704 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:38.799792 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:38.819852 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:38.820070 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:38.838908 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:38.856913 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:38.874430 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:38.926072 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:38.934820 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:38.951305 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:38.951404 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:38.951489 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:38.962966 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:38.963126 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:38.963214 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:38.963297 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:38.963383 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:38.963465 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:38.963548 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:38.963629 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:38.975716 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:38.983689 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:38.983816 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:38.999791 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.007991 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.008199 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.031816 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.032033 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.032122 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.047292 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.057051 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.057246 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.073268 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.073462 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.089650 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.097839 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.106503 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.114155 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.122194 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.122301 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.137789 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.154176 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.154291 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.162349 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.170396 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.178338 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.186624 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.194600 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.194701 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.210923 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.226957 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.227066 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.235162 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.243271 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.267024 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.267125 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.267207 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.276555 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.276744 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.292630 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.292760 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.308844 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.309081 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.324625 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.332786 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.349179 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.349325 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.357501 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.365482 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.381776 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.393090 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.393179 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.393261 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.406112 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.414714 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.423849 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.432697 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.474101 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.474209 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.474294 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#164 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.474409 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.474494 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#164 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.474575 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.491709 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#164 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.491927 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.508789 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#164 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.508910 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.527324 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#164 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.527450 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.543698 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#164 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.543890 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.551672 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#164 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.559702 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.567616 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#164 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.575687 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.583786 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#164 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.591889 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.608292 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#164 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.608399 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.616521 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#164 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.633289 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.633487 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#164 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.641564 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.649672 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#164 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.657976 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.665812 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#164 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.674090 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.682207 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#164 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.690587 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.698961 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#164 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.707502 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.715857 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#164 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.724394 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.732558 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#164 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.740755 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.748713 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#164 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.756928 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.765903 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#164 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.774006 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.782288 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#164 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.790338 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:30:39.798388 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#164 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001