Jul 2 01:50:13.142920 kernel: audit: type=1334 audit(1719885004.338:79): prog-id=9 op=UNLOAD Jul 2 01:50:13.142939 kernel: SELinux: Class mctp_socket not defined in policy. Jul 2 01:50:13.142950 kernel: SELinux: Class anon_inode not defined in policy. Jul 2 01:50:13.142960 kernel: SELinux: the above unknown classes and permissions will be allowed Jul 2 01:50:13.142968 kernel: SELinux: policy capability network_peer_controls=1 Jul 2 01:50:13.142976 kernel: SELinux: policy capability open_perms=1 Jul 2 01:50:13.142986 kernel: SELinux: policy capability extended_socket_class=1 Jul 2 01:50:13.142994 kernel: SELinux: policy capability always_check_network=0 Jul 2 01:50:13.143002 kernel: SELinux: policy capability cgroup_seclabel=1 Jul 2 01:50:13.143010 kernel: SELinux: policy capability nnp_nosuid_transition=1 Jul 2 01:50:13.143019 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Jul 2 01:50:13.143027 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Jul 2 01:50:13.143036 kernel: audit: type=1403 audit(1719885005.148:80): auid=4294967295 ses=4294967295 lsm=selinux res=1 Jul 2 01:50:13.143047 systemd[1]: Successfully loaded SELinux policy in 244.750ms. Jul 2 01:50:13.143058 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 24.806ms. Jul 2 01:50:13.143070 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) Jul 2 01:50:13.143079 systemd[1]: Detected virtualization microsoft. Jul 2 01:50:13.143088 systemd[1]: Detected architecture arm64. Jul 2 01:50:13.143097 systemd[1]: Detected first boot. Jul 2 01:50:13.143107 systemd[1]: Hostname set to . Jul 2 01:50:13.143116 systemd[1]: Initializing machine ID from random generator. Jul 2 01:50:13.143125 kernel: audit: type=1400 audit(1719885005.721:81): 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 Jul 2 01:50:13.143136 kernel: audit: type=1400 audit(1719885005.721:82): 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 Jul 2 01:50:13.143145 kernel: audit: type=1334 audit(1719885005.736:83): prog-id=10 op=LOAD Jul 2 01:50:13.143154 kernel: audit: type=1334 audit(1719885005.736:84): prog-id=10 op=UNLOAD Jul 2 01:50:13.143162 kernel: audit: type=1334 audit(1719885005.753:85): prog-id=11 op=LOAD Jul 2 01:50:13.143171 kernel: audit: type=1334 audit(1719885005.753:86): prog-id=11 op=UNLOAD Jul 2 01:50:13.143180 kernel: SELinux: Context system_u:object_r:container_file_t:s0:c1022,c1023 is not valid (left unmapped). Jul 2 01:50:13.143189 kernel: audit: type=1400 audit(1719885006.905:87): avc: denied { associate } for pid=1105 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" Jul 2 01:50:13.143199 systemd[1]: Populated /etc with preset unit settings. Jul 2 01:50:13.143209 systemd[1]: /usr/lib/systemd/system/locksmithd.service:8: Unit uses CPUShares=; please use CPUWeight= instead. Support for CPUShares= will be removed soon. Jul 2 01:50:13.143219 systemd[1]: /usr/lib/systemd/system/locksmithd.service:9: Unit uses MemoryLimit=; please use MemoryMax= instead. Support for MemoryLimit= will be removed soon. Jul 2 01:50:13.143229 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. Jul 2 01:50:13.143238 kernel: kauditd_printk_skb: 8 callbacks suppressed Jul 2 01:50:13.143248 kernel: audit: type=1334 audit(1719885012.504:89): prog-id=12 op=LOAD Jul 2 01:50:13.143256 kernel: audit: type=1334 audit(1719885012.504:90): prog-id=3 op=UNLOAD Jul 2 01:50:13.143266 kernel: audit: type=1334 audit(1719885012.509:91): prog-id=13 op=LOAD Jul 2 01:50:13.143275 kernel: audit: type=1334 audit(1719885012.514:92): prog-id=14 op=LOAD Jul 2 01:50:13.143283 kernel: audit: type=1334 audit(1719885012.514:93): prog-id=4 op=UNLOAD Jul 2 01:50:13.143292 systemd[1]: iscsiuio.service: Deactivated successfully. Jul 2 01:50:13.143303 kernel: audit: type=1334 audit(1719885012.514:94): prog-id=5 op=UNLOAD Jul 2 01:50:13.143312 systemd[1]: Stopped iscsiuio.service. Jul 2 01:50:13.143322 kernel: audit: type=1334 audit(1719885012.520:95): prog-id=15 op=LOAD Jul 2 01:50:13.143332 kernel: audit: type=1334 audit(1719885012.520:96): prog-id=12 op=UNLOAD Jul 2 01:50:13.143341 kernel: audit: type=1334 audit(1719885012.525:97): prog-id=16 op=LOAD Jul 2 01:50:13.143350 systemd[1]: iscsid.service: Deactivated successfully. Jul 2 01:50:13.143359 kernel: audit: type=1334 audit(1719885012.530:98): prog-id=17 op=LOAD Jul 2 01:50:13.143369 systemd[1]: Stopped iscsid.service. Jul 2 01:50:13.143378 systemd[1]: initrd-switch-root.service: Deactivated successfully. Jul 2 01:50:13.143388 systemd[1]: Stopped initrd-switch-root.service. Jul 2 01:50:13.143397 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Jul 2 01:50:13.143407 systemd[1]: Created slice system-addon\x2dconfig.slice. Jul 2 01:50:13.143418 systemd[1]: Created slice system-addon\x2drun.slice. Jul 2 01:50:13.143427 systemd[1]: Created slice system-getty.slice. Jul 2 01:50:13.143437 systemd[1]: Created slice system-modprobe.slice. Jul 2 01:50:13.143447 systemd[1]: Created slice system-serial\x2dgetty.slice. Jul 2 01:50:13.143457 systemd[1]: Created slice system-system\x2dcloudinit.slice. Jul 2 01:50:13.143466 systemd[1]: Created slice system-systemd\x2dfsck.slice. Jul 2 01:50:13.143476 systemd[1]: Created slice user.slice. Jul 2 01:50:13.143485 systemd[1]: Started systemd-ask-password-console.path. Jul 2 01:50:13.143495 systemd[1]: Started systemd-ask-password-wall.path. Jul 2 01:50:13.143517 systemd[1]: Set up automount boot.automount. Jul 2 01:50:13.143527 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount. Jul 2 01:50:13.143537 systemd[1]: Stopped target initrd-switch-root.target. Jul 2 01:50:13.143547 systemd[1]: Stopped target initrd-fs.target. Jul 2 01:50:13.143556 systemd[1]: Stopped target initrd-root-fs.target. Jul 2 01:50:13.143566 systemd[1]: Reached target integritysetup.target. Jul 2 01:50:13.143575 systemd[1]: Reached target remote-cryptsetup.target. Jul 2 01:50:13.143584 systemd[1]: Reached target remote-fs.target. Jul 2 01:50:13.143595 systemd[1]: Reached target slices.target. Jul 2 01:50:13.143604 systemd[1]: Reached target swap.target. Jul 2 01:50:13.143613 systemd[1]: Reached target torcx.target. Jul 2 01:50:13.143624 systemd[1]: Reached target veritysetup.target. Jul 2 01:50:13.143635 systemd[1]: Listening on systemd-coredump.socket. Jul 2 01:50:13.143644 systemd[1]: Listening on systemd-initctl.socket. Jul 2 01:50:13.143654 systemd[1]: Listening on systemd-networkd.socket. Jul 2 01:50:13.143665 systemd[1]: Listening on systemd-udevd-control.socket. Jul 2 01:50:13.143674 systemd[1]: Listening on systemd-udevd-kernel.socket. Jul 2 01:50:13.143684 systemd[1]: Listening on systemd-userdbd.socket. Jul 2 01:50:13.143693 systemd[1]: Mounting dev-hugepages.mount... Jul 2 01:50:13.143703 systemd[1]: Mounting dev-mqueue.mount... Jul 2 01:50:13.143712 systemd[1]: Mounting media.mount... Jul 2 01:50:13.143723 systemd[1]: Mounting sys-kernel-debug.mount... Jul 2 01:50:13.143733 systemd[1]: Mounting sys-kernel-tracing.mount... Jul 2 01:50:13.143743 systemd[1]: Mounting tmp.mount... Jul 2 01:50:13.143752 systemd[1]: Starting flatcar-tmpfiles.service... Jul 2 01:50:13.143762 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. Jul 2 01:50:13.143772 systemd[1]: Starting kmod-static-nodes.service... Jul 2 01:50:13.143782 systemd[1]: Starting modprobe@configfs.service... Jul 2 01:50:13.143791 systemd[1]: Starting modprobe@dm_mod.service... Jul 2 01:50:13.143801 systemd[1]: Starting modprobe@drm.service... Jul 2 01:50:13.143812 systemd[1]: Starting modprobe@efi_pstore.service... Jul 2 01:50:13.143822 systemd[1]: Starting modprobe@fuse.service... Jul 2 01:50:13.143831 systemd[1]: Starting modprobe@loop.service... Jul 2 01:50:13.143841 systemd[1]: setup-nsswitch.service was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Jul 2 01:50:13.143851 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Jul 2 01:50:13.143861 systemd[1]: Stopped systemd-fsck-root.service. Jul 2 01:50:13.143871 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Jul 2 01:50:13.143881 systemd[1]: Stopped systemd-fsck-usr.service. Jul 2 01:50:13.143891 systemd[1]: Stopped systemd-journald.service. Jul 2 01:50:13.143901 kernel: loop: module loaded Jul 2 01:50:13.143910 systemd[1]: systemd-journald.service: Consumed 2.351s CPU time. Jul 2 01:50:13.143920 systemd[1]: Starting systemd-journald.service... Jul 2 01:50:13.143929 kernel: fuse: init (API version 7.34) Jul 2 01:50:13.143939 systemd[1]: Starting systemd-modules-load.service... Jul 2 01:50:13.143948 systemd[1]: Starting systemd-network-generator.service... Jul 2 01:50:13.143958 systemd[1]: Starting systemd-remount-fs.service... Jul 2 01:50:13.143967 systemd[1]: Starting systemd-udev-trigger.service... Jul 2 01:50:13.143977 systemd[1]: verity-setup.service: Deactivated successfully. Jul 2 01:50:13.143987 systemd[1]: Stopped verity-setup.service. Jul 2 01:50:13.143999 systemd-journald[1211]: Journal started Jul 2 01:50:13.144034 systemd-journald[1211]: Runtime Journal (/run/log/journal/29f6f74b89004a9b99228fb532631efc) is 8.0M, max 78.6M, 70.6M free. Jul 2 01:50:05.148000 audit: MAC_POLICY_LOAD auid=4294967295 ses=4294967295 lsm=selinux res=1 Jul 2 01:50:05.721000 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 Jul 2 01:50:05.721000 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 Jul 2 01:50:05.736000 audit: BPF prog-id=10 op=LOAD Jul 2 01:50:05.736000 audit: BPF prog-id=10 op=UNLOAD Jul 2 01:50:05.753000 audit: BPF prog-id=11 op=LOAD Jul 2 01:50:05.753000 audit: BPF prog-id=11 op=UNLOAD Jul 2 01:50:06.905000 audit[1105]: AVC avc: denied { associate } for pid=1105 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" Jul 2 01:50:06.905000 audit[1105]: SYSCALL arch=c00000b7 syscall=5 success=yes exit=0 a0=4000145714 a1=40000c6ab0 a2=40000cc940 a3=32 items=0 ppid=1088 pid=1105 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) Jul 2 01:50:06.905000 audit: PROCTITLE proctitle=2F7573722F6C69622F73797374656D642F73797374656D2D67656E657261746F72732F746F7263782D67656E657261746F72002F72756E2F73797374656D642F67656E657261746F72002F72756E2F73797374656D642F67656E657261746F722E6561726C79002F72756E2F73797374656D642F67656E657261746F722E6C61 Jul 2 01:50:06.914000 audit[1105]: AVC avc: denied { associate } for pid=1105 comm="torcx-generator" name="lib" scontext=system_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:tmpfs_t:s0 tclass=filesystem permissive=1 Jul 2 01:50:06.914000 audit[1105]: SYSCALL arch=c00000b7 syscall=34 success=yes exit=0 a0=ffffffffffffff9c a1=40001457f9 a2=1ed a3=0 items=2 ppid=1088 pid=1105 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) Jul 2 01:50:06.914000 audit: CWD cwd="/" Jul 2 01:50:06.914000 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 Jul 2 01:50:06.914000 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 Jul 2 01:50:06.914000 audit: PROCTITLE proctitle=2F7573722F6C69622F73797374656D642F73797374656D2D67656E657261746F72732F746F7263782D67656E657261746F72002F72756E2F73797374656D642F67656E657261746F72002F72756E2F73797374656D642F67656E657261746F722E6561726C79002F72756E2F73797374656D642F67656E657261746F722E6C61 Jul 2 01:50:12.504000 audit: BPF prog-id=12 op=LOAD Jul 2 01:50:12.504000 audit: BPF prog-id=3 op=UNLOAD Jul 2 01:50:12.509000 audit: BPF prog-id=13 op=LOAD Jul 2 01:50:12.514000 audit: BPF prog-id=14 op=LOAD Jul 2 01:50:12.514000 audit: BPF prog-id=4 op=UNLOAD Jul 2 01:50:12.514000 audit: BPF prog-id=5 op=UNLOAD Jul 2 01:50:12.520000 audit: BPF prog-id=15 op=LOAD Jul 2 01:50:12.520000 audit: BPF prog-id=12 op=UNLOAD Jul 2 01:50:12.525000 audit: BPF prog-id=16 op=LOAD Jul 2 01:50:12.530000 audit: BPF prog-id=17 op=LOAD Jul 2 01:50:12.530000 audit: BPF prog-id=13 op=UNLOAD Jul 2 01:50:12.530000 audit: BPF prog-id=14 op=UNLOAD Jul 2 01:50:12.532000 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' Jul 2 01:50:12.559000 audit: BPF prog-id=15 op=UNLOAD Jul 2 01:50:12.568000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=iscsiuio comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 01:50:12.586000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=iscsid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 01:50:12.600000 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' Jul 2 01:50:12.600000 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' Jul 2 01:50:13.014000 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' Jul 2 01:50:13.029000 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' Jul 2 01:50:13.040000 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' Jul 2 01:50:13.040000 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' Jul 2 01:50:13.050000 audit: BPF prog-id=18 op=LOAD Jul 2 01:50:13.050000 audit: BPF prog-id=19 op=LOAD Jul 2 01:50:13.050000 audit: BPF prog-id=20 op=LOAD Jul 2 01:50:13.050000 audit: BPF prog-id=16 op=UNLOAD Jul 2 01:50:13.050000 audit: BPF prog-id=17 op=UNLOAD Jul 2 01:50:13.140000 audit: CONFIG_CHANGE op=set audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1 Jul 2 01:50:13.140000 audit[1211]: SYSCALL arch=c00000b7 syscall=211 success=yes exit=60 a0=4 a1=fffff4b42850 a2=4000 a3=1 items=0 ppid=1 pid=1211 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) Jul 2 01:50:13.140000 audit: PROCTITLE proctitle="/usr/lib/systemd/systemd-journald" Jul 2 01:50:13.144000 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' Jul 2 01:50:12.503580 systemd[1]: Queued start job for default target multi-user.target. Jul 2 01:50:06.835924 /usr/lib/systemd/system-generators/torcx-generator[1105]: time="2024-07-02T01:50:06Z" 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.5 /usr/share/oem/torcx/store /var/lib/torcx/store/3510.3.5 /var/lib/torcx/store]" Jul 2 01:50:12.532270 systemd[1]: systemd-journald.service: Deactivated successfully. Jul 2 01:50:06.857841 /usr/lib/systemd/system-generators/torcx-generator[1105]: time="2024-07-02T01:50:06Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Jul 2 01:50:12.532616 systemd[1]: systemd-journald.service: Consumed 2.351s CPU time. Jul 2 01:50:06.857859 /usr/lib/systemd/system-generators/torcx-generator[1105]: time="2024-07-02T01:50:06Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Jul 2 01:50:06.857898 /usr/lib/systemd/system-generators/torcx-generator[1105]: time="2024-07-02T01:50:06Z" level=info msg="no vendor profile selected by /etc/flatcar/docker-1.12" Jul 2 01:50:06.857907 /usr/lib/systemd/system-generators/torcx-generator[1105]: time="2024-07-02T01:50:06Z" level=debug msg="skipped missing lower profile" missing profile=oem Jul 2 01:50:06.857937 /usr/lib/systemd/system-generators/torcx-generator[1105]: time="2024-07-02T01:50:06Z" level=warning msg="no next profile: unable to read profile file: open /etc/torcx/next-profile: no such file or directory" Jul 2 01:50:13.153128 systemd[1]: Started systemd-journald.service. Jul 2 01:50:06.857949 /usr/lib/systemd/system-generators/torcx-generator[1105]: time="2024-07-02T01:50:06Z" level=debug msg="apply configuration parsed" lower profiles (vendor/oem)="[vendor]" upper profile (user)= Jul 2 01:50:06.858113 /usr/lib/systemd/system-generators/torcx-generator[1105]: time="2024-07-02T01:50:06Z" level=debug msg="mounted tmpfs" target=/run/torcx/unpack Jul 2 01:50:06.858144 /usr/lib/systemd/system-generators/torcx-generator[1105]: time="2024-07-02T01:50:06Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Jul 2 01:50:06.858156 /usr/lib/systemd/system-generators/torcx-generator[1105]: time="2024-07-02T01:50:06Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Jul 2 01:50:06.890576 /usr/lib/systemd/system-generators/torcx-generator[1105]: time="2024-07-02T01:50:06Z" 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 Jul 2 01:50:06.890635 /usr/lib/systemd/system-generators/torcx-generator[1105]: time="2024-07-02T01:50:06Z" 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 Jul 2 01:50:06.890670 /usr/lib/systemd/system-generators/torcx-generator[1105]: time="2024-07-02T01:50:06Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store/3510.3.5: no such file or directory" path=/usr/share/oem/torcx/store/3510.3.5 Jul 2 01:50:06.890684 /usr/lib/systemd/system-generators/torcx-generator[1105]: time="2024-07-02T01:50:06Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store: no such file or directory" path=/usr/share/oem/torcx/store Jul 2 01:50:06.890709 /usr/lib/systemd/system-generators/torcx-generator[1105]: time="2024-07-02T01:50:06Z" level=info msg="store skipped" err="open /var/lib/torcx/store/3510.3.5: no such file or directory" path=/var/lib/torcx/store/3510.3.5 Jul 2 01:50:06.890722 /usr/lib/systemd/system-generators/torcx-generator[1105]: time="2024-07-02T01:50:06Z" level=info msg="store skipped" err="open /var/lib/torcx/store: no such file or directory" path=/var/lib/torcx/store Jul 2 01:50:11.565767 /usr/lib/systemd/system-generators/torcx-generator[1105]: time="2024-07-02T01:50:11Z" level=debug msg="image unpacked" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Jul 2 01:50:11.566015 /usr/lib/systemd/system-generators/torcx-generator[1105]: time="2024-07-02T01:50:11Z" 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 Jul 2 01:50:11.566116 /usr/lib/systemd/system-generators/torcx-generator[1105]: time="2024-07-02T01:50:11Z" 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 Jul 2 01:50:11.566272 /usr/lib/systemd/system-generators/torcx-generator[1105]: time="2024-07-02T01:50:11Z" 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 Jul 2 01:50:11.566315 /usr/lib/systemd/system-generators/torcx-generator[1105]: time="2024-07-02T01:50:11Z" level=debug msg="profile applied" sealed profile=/run/torcx/profile.json upper profile= Jul 2 01:50:11.566366 /usr/lib/systemd/system-generators/torcx-generator[1105]: time="2024-07-02T01:50:11Z" 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 Jul 2 01:50:13.154000 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' Jul 2 01:50:13.155125 systemd[1]: Mounted dev-hugepages.mount. Jul 2 01:50:13.159240 systemd[1]: Mounted dev-mqueue.mount. Jul 2 01:50:13.163280 systemd[1]: Mounted media.mount. Jul 2 01:50:13.166844 systemd[1]: Mounted sys-kernel-debug.mount. Jul 2 01:50:13.170998 systemd[1]: Mounted sys-kernel-tracing.mount. Jul 2 01:50:13.175282 systemd[1]: Mounted tmp.mount. Jul 2 01:50:13.179164 systemd[1]: Finished flatcar-tmpfiles.service. Jul 2 01:50:13.183000 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' Jul 2 01:50:13.183806 systemd[1]: Finished kmod-static-nodes.service. Jul 2 01:50:13.187000 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' Jul 2 01:50:13.188423 systemd[1]: modprobe@configfs.service: Deactivated successfully. Jul 2 01:50:13.188705 systemd[1]: Finished modprobe@configfs.service. Jul 2 01:50:13.193000 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' Jul 2 01:50:13.193000 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' Jul 2 01:50:13.193979 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 2 01:50:13.194111 systemd[1]: Finished modprobe@dm_mod.service. Jul 2 01:50:13.198000 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' Jul 2 01:50:13.198000 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' Jul 2 01:50:13.198958 systemd[1]: modprobe@drm.service: Deactivated successfully. Jul 2 01:50:13.199073 systemd[1]: Finished modprobe@drm.service. Jul 2 01:50:13.202000 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' Jul 2 01:50:13.202000 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' Jul 2 01:50:13.203473 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 2 01:50:13.203608 systemd[1]: Finished modprobe@efi_pstore.service. Jul 2 01:50:13.208000 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' Jul 2 01:50:13.208000 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' Jul 2 01:50:13.208485 systemd[1]: modprobe@fuse.service: Deactivated successfully. Jul 2 01:50:13.208617 systemd[1]: Finished modprobe@fuse.service. Jul 2 01:50:13.212000 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' Jul 2 01:50:13.212000 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' Jul 2 01:50:13.213036 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 2 01:50:13.213155 systemd[1]: Finished modprobe@loop.service. Jul 2 01:50:13.216000 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' Jul 2 01:50:13.216000 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' Jul 2 01:50:13.217492 systemd[1]: Finished systemd-modules-load.service. Jul 2 01:50:13.221000 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' Jul 2 01:50:13.222200 systemd[1]: Finished systemd-network-generator.service. Jul 2 01:50:13.226000 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' Jul 2 01:50:13.227279 systemd[1]: Finished systemd-remount-fs.service. Jul 2 01:50:13.231000 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' Jul 2 01:50:13.232343 systemd[1]: Reached target network-pre.target. Jul 2 01:50:13.237710 systemd[1]: Mounting sys-fs-fuse-connections.mount... Jul 2 01:50:13.242983 systemd[1]: Mounting sys-kernel-config.mount... Jul 2 01:50:13.247033 systemd[1]: remount-root.service was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Jul 2 01:50:13.248301 systemd[1]: Starting systemd-hwdb-update.service... Jul 2 01:50:13.253421 systemd[1]: Starting systemd-journal-flush.service... Jul 2 01:50:13.257772 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jul 2 01:50:13.258760 systemd[1]: Starting systemd-random-seed.service... Jul 2 01:50:13.262704 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met. Jul 2 01:50:13.263696 systemd[1]: Starting systemd-sysctl.service... Jul 2 01:50:13.268324 systemd[1]: Starting systemd-sysusers.service... Jul 2 01:50:13.274357 systemd[1]: Finished systemd-udev-trigger.service. Jul 2 01:50:13.278000 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' Jul 2 01:50:13.279091 systemd[1]: Mounted sys-fs-fuse-connections.mount. Jul 2 01:50:13.283809 systemd[1]: Mounted sys-kernel-config.mount. Jul 2 01:50:13.289895 systemd[1]: Starting systemd-udev-settle.service... Jul 2 01:50:13.296576 udevadm[1225]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Jul 2 01:50:13.329608 systemd-journald[1211]: Runtime Journal (/run/log/journal/29f6f74b89004a9b99228fb532631efc) is 8.0M, max 78.6M, 70.6M free. Jul 2 01:50:13.336652 systemd-journald[1211]: Received client request to flush runtime journal. Jul 2 01:50:13.337602 systemd[1]: Finished systemd-journal-flush.service. Jul 2 01:50:13.341000 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' Jul 2 01:50:13.343915 systemd[1]: Finished systemd-random-seed.service. Jul 2 01:50:13.349000 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' Jul 2 01:50:13.350499 systemd[1]: Reached target first-boot-complete.target. Jul 2 01:50:13.356354 systemd[1]: Finished systemd-sysctl.service. Jul 2 01:50:13.360000 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' Jul 2 01:50:13.799000 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' Jul 2 01:50:13.795991 systemd[1]: Finished systemd-sysusers.service. Jul 2 01:50:13.801380 systemd[1]: Starting systemd-tmpfiles-setup-dev.service... Jul 2 01:50:14.058988 systemd[1]: Finished systemd-tmpfiles-setup-dev.service. Jul 2 01:50:14.064000 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' Jul 2 01:50:14.379014 systemd[1]: Finished systemd-hwdb-update.service. Jul 2 01:50:14.382000 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' Jul 2 01:50:14.383000 audit: BPF prog-id=21 op=LOAD Jul 2 01:50:14.383000 audit: BPF prog-id=22 op=LOAD Jul 2 01:50:14.383000 audit: BPF prog-id=7 op=UNLOAD Jul 2 01:50:14.383000 audit: BPF prog-id=8 op=UNLOAD Jul 2 01:50:14.384854 systemd[1]: Starting systemd-udevd.service... Jul 2 01:50:14.402416 systemd-udevd[1229]: Using default interface naming scheme 'v252'. Jul 2 01:50:14.719265 systemd[1]: Started systemd-udevd.service. Jul 2 01:50:14.728000 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' Jul 2 01:50:14.729000 audit: BPF prog-id=23 op=LOAD Jul 2 01:50:14.731976 systemd[1]: Starting systemd-networkd.service... Jul 2 01:50:14.752944 systemd[1]: Condition check resulted in dev-ttyAMA0.device being skipped. Jul 2 01:50:14.783360 systemd[1]: Starting systemd-userdbd.service... Jul 2 01:50:14.782000 audit: BPF prog-id=24 op=LOAD Jul 2 01:50:14.782000 audit: BPF prog-id=25 op=LOAD Jul 2 01:50:14.782000 audit: BPF prog-id=26 op=LOAD Jul 2 01:50:14.808538 kernel: mousedev: PS/2 mouse device common for all mice Jul 2 01:50:14.814000 audit[1230]: AVC avc: denied { confidentiality } for pid=1230 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 Jul 2 01:50:14.823548 kernel: hv_vmbus: registering driver hv_balloon Jul 2 01:50:14.832599 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Jul 2 01:50:14.832659 kernel: hv_balloon: Memory hot add disabled on ARM64 Jul 2 01:50:14.851358 systemd[1]: Started systemd-userdbd.service. Jul 2 01:50:14.859769 kernel: hv_vmbus: registering driver hyperv_fb Jul 2 01:50:14.859821 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Jul 2 01:50:14.866683 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Jul 2 01:50:14.866000 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' Jul 2 01:50:14.876334 kernel: Console: switching to colour dummy device 80x25 Jul 2 01:50:14.884374 kernel: Console: switching to colour frame buffer device 128x48 Jul 2 01:50:14.884421 kernel: hv_utils: Registering HyperV Utility Driver Jul 2 01:50:14.884435 kernel: hv_vmbus: registering driver hv_utils Jul 2 01:50:14.884446 kernel: hv_utils: Heartbeat IC version 3.0 Jul 2 01:50:14.884458 kernel: hv_utils: Shutdown IC version 3.2 Jul 2 01:50:14.884470 kernel: hv_utils: TimeSync IC version 4.0 Jul 2 01:50:14.814000 audit[1230]: SYSCALL arch=c00000b7 syscall=105 success=yes exit=0 a0=aaab12541a10 a1=aa2c a2=ffffb7a424b0 a3=aaab124a3010 items=12 ppid=1229 pid=1230 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) Jul 2 01:50:14.763971 systemd-journald[1211]: Time jumped backwards, rotating. Jul 2 01:50:14.814000 audit: CWD cwd="/" Jul 2 01:50:14.814000 audit: PATH item=0 name=(null) inode=6673 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 Jul 2 01:50:14.814000 audit: PATH item=1 name=(null) inode=10465 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 Jul 2 01:50:14.814000 audit: PATH item=2 name=(null) inode=10465 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 Jul 2 01:50:14.814000 audit: PATH item=3 name=(null) inode=10466 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 Jul 2 01:50:14.814000 audit: PATH item=4 name=(null) inode=10465 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 Jul 2 01:50:14.814000 audit: PATH item=5 name=(null) inode=10467 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 Jul 2 01:50:14.814000 audit: PATH item=6 name=(null) inode=10465 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 Jul 2 01:50:14.814000 audit: PATH item=7 name=(null) inode=10468 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 Jul 2 01:50:14.814000 audit: PATH item=8 name=(null) inode=10465 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 Jul 2 01:50:14.814000 audit: PATH item=9 name=(null) inode=10469 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 Jul 2 01:50:14.814000 audit: PATH item=10 name=(null) inode=10465 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 Jul 2 01:50:14.814000 audit: PATH item=11 name=(null) inode=10470 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 Jul 2 01:50:14.814000 audit: PROCTITLE proctitle="(udev-worker)" Jul 2 01:50:14.970149 systemd-networkd[1250]: lo: Link UP Jul 2 01:50:14.970405 systemd-networkd[1250]: lo: Gained carrier Jul 2 01:50:14.970837 systemd-networkd[1250]: Enumeration completed Jul 2 01:50:14.971085 systemd[1]: Started systemd-networkd.service. Jul 2 01:50:14.974000 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' Jul 2 01:50:14.976866 systemd[1]: Starting systemd-networkd-wait-online.service... Jul 2 01:50:15.000561 systemd-networkd[1250]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jul 2 01:50:15.000964 kernel: BTRFS info: devid 1 device path /dev/disk/by-label/OEM changed to /dev/sda6 scanned by (udev-worker) (1237) Jul 2 01:50:15.031004 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device. Jul 2 01:50:15.040359 systemd[1]: Finished systemd-udev-settle.service. Jul 2 01:50:15.044000 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' Jul 2 01:50:15.046142 systemd[1]: Starting lvm2-activation-early.service... Jul 2 01:50:15.053922 kernel: mlx5_core 6103:00:02.0 enP24835s1: Link up Jul 2 01:50:15.078922 kernel: hv_netvsc 0022487e-0483-0022-487e-04830022487e eth0: Data path switched to VF: enP24835s1 Jul 2 01:50:15.079953 systemd-networkd[1250]: enP24835s1: Link UP Jul 2 01:50:15.080307 systemd-networkd[1250]: eth0: Link UP Jul 2 01:50:15.080319 systemd-networkd[1250]: eth0: Gained carrier Jul 2 01:50:15.084349 systemd-networkd[1250]: enP24835s1: Gained carrier Jul 2 01:50:15.096992 systemd-networkd[1250]: eth0: DHCPv4 address 10.200.20.10/24, gateway 10.200.20.1 acquired from 168.63.129.16 Jul 2 01:50:15.322874 lvm[1309]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Jul 2 01:50:15.362790 systemd[1]: Finished lvm2-activation-early.service. Jul 2 01:50:15.366000 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' Jul 2 01:50:15.367136 systemd[1]: Reached target cryptsetup.target. Jul 2 01:50:15.372158 systemd[1]: Starting lvm2-activation.service... Jul 2 01:50:15.376019 lvm[1310]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Jul 2 01:50:15.401800 systemd[1]: Finished lvm2-activation.service. Jul 2 01:50:15.404000 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' Jul 2 01:50:15.405842 systemd[1]: Reached target local-fs-pre.target. Jul 2 01:50:15.409787 systemd[1]: var-lib-machines.mount was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Jul 2 01:50:15.409812 systemd[1]: Reached target local-fs.target. Jul 2 01:50:15.413471 systemd[1]: Reached target machines.target. Jul 2 01:50:15.418592 systemd[1]: Starting ldconfig.service... Jul 2 01:50:15.444792 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. Jul 2 01:50:15.444879 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). Jul 2 01:50:15.446002 systemd[1]: Starting systemd-boot-update.service... Jul 2 01:50:15.450597 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service... Jul 2 01:50:15.456298 systemd[1]: Starting systemd-machine-id-commit.service... Jul 2 01:50:15.460413 systemd[1]: systemd-sysext.service was skipped because no trigger condition checks were met. Jul 2 01:50:15.460468 systemd[1]: ensure-sysext.service was skipped because no trigger condition checks were met. Jul 2 01:50:15.461513 systemd[1]: Starting systemd-tmpfiles-setup.service... Jul 2 01:50:15.473469 systemd-tmpfiles[1315]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. Jul 2 01:50:15.482952 systemd[1]: boot.automount: Got automount request for /boot, triggered by 1312 (bootctl) Jul 2 01:50:15.483974 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service... Jul 2 01:50:15.575859 systemd-tmpfiles[1315]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Jul 2 01:50:15.584064 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Jul 2 01:50:15.584644 systemd[1]: Finished systemd-machine-id-commit.service. Jul 2 01:50:15.590844 systemd-tmpfiles[1315]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Jul 2 01:50:15.590000 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' Jul 2 01:50:15.639458 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service. Jul 2 01:50:15.644000 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' Jul 2 01:50:15.799917 systemd-fsck[1322]: fsck.fat 4.2 (2021-01-31) Jul 2 01:50:15.799917 systemd-fsck[1322]: /dev/sda1: 236 files, 117047/258078 clusters Jul 2 01:50:15.801466 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service. Jul 2 01:50:15.806000 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' Jul 2 01:50:15.810199 systemd[1]: Mounting boot.mount... Jul 2 01:50:15.817641 systemd[1]: Mounted boot.mount. Jul 2 01:50:15.831000 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' Jul 2 01:50:15.827093 systemd[1]: Finished systemd-boot-update.service. Jul 2 01:50:15.878041 systemd-tmpfiles[1315]: Failed to adjust quota for subvolume "/srv": Bad file descriptor Jul 2 01:50:15.916756 systemd-tmpfiles[1315]: Failed to adjust quota for subvolume "/var/lib/portables": Bad file descriptor Jul 2 01:50:15.944064 systemd-tmpfiles[1315]: Failed to adjust quota for subvolume "/var/lib/machines": Bad file descriptor Jul 2 01:50:15.949994 systemd-tmpfiles[1315]: Failed to adjust quota for subvolume "/var/tmp": Bad file descriptor Jul 2 01:50:15.967810 systemd[1]: Finished systemd-tmpfiles-setup.service. Jul 2 01:50:15.971000 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' Jul 2 01:50:15.973887 systemd[1]: Starting audit-rules.service... Jul 2 01:50:15.978541 systemd[1]: Starting clean-ca-certificates.service... Jul 2 01:50:15.983887 systemd[1]: Starting systemd-journal-catalog-update.service... Jul 2 01:50:15.988000 audit: BPF prog-id=27 op=LOAD Jul 2 01:50:15.991291 systemd[1]: Starting systemd-resolved.service... Jul 2 01:50:15.994000 audit: BPF prog-id=28 op=LOAD Jul 2 01:50:15.996964 systemd[1]: Starting systemd-timesyncd.service... Jul 2 01:50:16.001840 systemd[1]: Starting systemd-update-utmp.service... Jul 2 01:50:16.032000 audit[1334]: SYSTEM_BOOT pid=1334 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' Jul 2 01:50:16.036032 systemd[1]: Finished systemd-update-utmp.service. Jul 2 01:50:16.039000 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' Jul 2 01:50:16.065621 systemd[1]: Finished clean-ca-certificates.service. Jul 2 01:50:16.069000 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' Jul 2 01:50:16.070099 systemd[1]: update-ca-certificates.service was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Jul 2 01:50:16.082978 systemd[1]: Started systemd-timesyncd.service. Jul 2 01:50:16.086000 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' Jul 2 01:50:16.087290 systemd[1]: Reached target time-set.target. Jul 2 01:50:16.139795 systemd[1]: Finished systemd-journal-catalog-update.service. Jul 2 01:50:16.144000 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' Jul 2 01:50:16.155990 systemd-resolved[1331]: Positive Trust Anchors: Jul 2 01:50:16.155999 systemd-resolved[1331]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jul 2 01:50:16.156027 systemd-resolved[1331]: 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 Jul 2 01:50:16.221506 systemd-resolved[1331]: Using system hostname 'ci-3510.3.5-a-020439589a'. Jul 2 01:50:16.226000 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' Jul 2 01:50:16.223120 systemd[1]: Started systemd-resolved.service. Jul 2 01:50:16.227315 systemd[1]: Reached target network.target. Jul 2 01:50:16.231477 systemd[1]: Reached target nss-lookup.target. Jul 2 01:50:16.323000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=add_rule key=(null) list=5 res=1 Jul 2 01:50:16.323000 audit[1349]: SYSCALL arch=c00000b7 syscall=206 success=yes exit=1056 a0=3 a1=fffffefd2220 a2=420 a3=0 items=0 ppid=1328 pid=1349 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) Jul 2 01:50:16.323000 audit: PROCTITLE proctitle=2F7362696E2F617564697463746C002D52002F6574632F61756469742F61756469742E72756C6573 Jul 2 01:50:16.339204 augenrules[1349]: No rules Jul 2 01:50:16.339907 systemd[1]: Finished audit-rules.service. Jul 2 01:50:16.437014 systemd-timesyncd[1333]: Contacted time server 72.30.35.88:123 (0.flatcar.pool.ntp.org). Jul 2 01:50:16.437354 systemd-timesyncd[1333]: Initial clock synchronization to Tue 2024-07-02 01:50:16.425091 UTC. Jul 2 01:50:16.756039 systemd-networkd[1250]: eth0: Gained IPv6LL Jul 2 01:50:16.758981 systemd[1]: Finished systemd-networkd-wait-online.service. Jul 2 01:50:16.764282 systemd[1]: Reached target network-online.target. Jul 2 01:50:22.468440 ldconfig[1311]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Jul 2 01:50:22.484112 systemd[1]: Finished ldconfig.service. Jul 2 01:50:22.490306 systemd[1]: Starting systemd-update-done.service... Jul 2 01:50:22.515610 systemd[1]: Finished systemd-update-done.service. Jul 2 01:50:22.520510 systemd[1]: Reached target sysinit.target. Jul 2 01:50:22.524694 systemd[1]: Started motdgen.path. Jul 2 01:50:22.528329 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path. Jul 2 01:50:22.534492 systemd[1]: Started logrotate.timer. Jul 2 01:50:22.538408 systemd[1]: Started mdadm.timer. Jul 2 01:50:22.541910 systemd[1]: Started systemd-tmpfiles-clean.timer. Jul 2 01:50:22.546411 systemd[1]: update-engine-stub.timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Jul 2 01:50:22.546446 systemd[1]: Reached target paths.target. Jul 2 01:50:22.550514 systemd[1]: Reached target timers.target. Jul 2 01:50:22.555198 systemd[1]: Listening on dbus.socket. Jul 2 01:50:22.560148 systemd[1]: Starting docker.socket... Jul 2 01:50:22.565687 systemd[1]: Listening on sshd.socket. Jul 2 01:50:22.569779 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). Jul 2 01:50:22.570250 systemd[1]: Listening on docker.socket. Jul 2 01:50:22.574566 systemd[1]: Reached target sockets.target. Jul 2 01:50:22.579301 systemd[1]: Reached target basic.target. Jul 2 01:50:22.583352 systemd[1]: addon-config@usr-share-oem.service was skipped because no trigger condition checks were met. Jul 2 01:50:22.583382 systemd[1]: addon-run@usr-share-oem.service was skipped because no trigger condition checks were met. Jul 2 01:50:22.584392 systemd[1]: Starting containerd.service... Jul 2 01:50:22.588853 systemd[1]: Starting dbus.service... Jul 2 01:50:22.592976 systemd[1]: Starting enable-oem-cloudinit.service... Jul 2 01:50:22.598178 systemd[1]: Starting extend-filesystems.service... Jul 2 01:50:22.605094 systemd[1]: flatcar-setup-environment.service was skipped because of an unmet condition check (ConditionPathExists=/usr/share/oem/bin/flatcar-setup-environment). Jul 2 01:50:22.606213 systemd[1]: Starting motdgen.service... Jul 2 01:50:22.610586 systemd[1]: Started nvidia.service. Jul 2 01:50:22.615668 systemd[1]: Starting ssh-key-proc-cmdline.service... Jul 2 01:50:22.621466 systemd[1]: Starting sshd-keygen.service... Jul 2 01:50:22.628034 systemd[1]: Starting systemd-logind.service... Jul 2 01:50:22.632010 systemd[1]: systemd-pcrphase.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Jul 2 01:50:22.632064 systemd[1]: tcsd.service was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Jul 2 01:50:22.632390 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Jul 2 01:50:22.633109 systemd[1]: Starting update-engine.service... Jul 2 01:50:22.637695 systemd[1]: Starting update-ssh-keys-after-ignition.service... Jul 2 01:50:22.646158 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Jul 2 01:50:22.646718 systemd[1]: Finished ssh-key-proc-cmdline.service. Jul 2 01:50:22.680146 systemd[1]: motdgen.service: Deactivated successfully. Jul 2 01:50:22.680323 systemd[1]: Finished motdgen.service. Jul 2 01:50:22.689264 jq[1359]: false Jul 2 01:50:22.689495 jq[1375]: true Jul 2 01:50:22.705540 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Jul 2 01:50:22.705711 systemd[1]: Condition check resulted in enable-oem-cloudinit.service being skipped. Jul 2 01:50:22.709220 systemd-logind[1371]: Watching system buttons on /dev/input/event1 (AT Translated Set 2 keyboard) Jul 2 01:50:22.709835 extend-filesystems[1360]: Found sda Jul 2 01:50:22.709835 extend-filesystems[1360]: Found sda1 Jul 2 01:50:22.709835 extend-filesystems[1360]: Found sda2 Jul 2 01:50:22.709835 extend-filesystems[1360]: Found sda3 Jul 2 01:50:22.735733 extend-filesystems[1360]: Found usr Jul 2 01:50:22.735733 extend-filesystems[1360]: Found sda4 Jul 2 01:50:22.735733 extend-filesystems[1360]: Found sda6 Jul 2 01:50:22.735733 extend-filesystems[1360]: Found sda7 Jul 2 01:50:22.735733 extend-filesystems[1360]: Found sda9 Jul 2 01:50:22.735733 extend-filesystems[1360]: Checking size of /dev/sda9 Jul 2 01:50:22.710593 systemd-logind[1371]: New seat seat0. Jul 2 01:50:22.765591 jq[1383]: true Jul 2 01:50:22.766132 env[1378]: time="2024-07-02T01:50:22.747445164Z" level=info msg="starting containerd" revision=92b3a9d6f1b3bcc6dc74875cfdea653fe39f09c2 version=1.6.16 Jul 2 01:50:22.776929 env[1378]: time="2024-07-02T01:50:22.776873343Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Jul 2 01:50:22.777030 env[1378]: time="2024-07-02T01:50:22.777008830Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Jul 2 01:50:22.778360 env[1378]: time="2024-07-02T01:50:22.778315971Z" 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.161-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Jul 2 01:50:22.778360 env[1378]: time="2024-07-02T01:50:22.778355350Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Jul 2 01:50:22.778625 env[1378]: time="2024-07-02T01:50:22.778600818Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Jul 2 01:50:22.778652 env[1378]: time="2024-07-02T01:50:22.778625525Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured" Jul 2 01:50:22.778652 env[1378]: time="2024-07-02T01:50:22.778636639Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Jul 2 01:50:22.778691 env[1378]: time="2024-07-02T01:50:22.778684534Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Jul 2 01:50:22.778916 env[1378]: time="2024-07-02T01:50:22.778881828Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Jul 2 01:50:22.779056 env[1378]: time="2024-07-02T01:50:22.779031908Z" 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 Jul 2 01:50:22.779076 env[1378]: time="2024-07-02T01:50:22.779055055Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Jul 2 01:50:22.779116 env[1378]: time="2024-07-02T01:50:22.779099432Z" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured" Jul 2 01:50:22.779145 env[1378]: time="2024-07-02T01:50:22.779115463Z" level=info msg="metadata content store policy set" policy=shared Jul 2 01:50:22.800011 env[1378]: time="2024-07-02T01:50:22.798495057Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Jul 2 01:50:22.800011 env[1378]: time="2024-07-02T01:50:22.798537954Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Jul 2 01:50:22.800011 env[1378]: time="2024-07-02T01:50:22.798565819Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Jul 2 01:50:22.800011 env[1378]: time="2024-07-02T01:50:22.798599321Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Jul 2 01:50:22.800011 env[1378]: time="2024-07-02T01:50:22.798614433Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Jul 2 01:50:22.800011 env[1378]: time="2024-07-02T01:50:22.798686195Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Jul 2 01:50:22.800011 env[1378]: time="2024-07-02T01:50:22.798703225Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Jul 2 01:50:22.800011 env[1378]: time="2024-07-02T01:50:22.799120042Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Jul 2 01:50:22.800011 env[1378]: time="2024-07-02T01:50:22.799139592Z" level=info msg="loading plugin \"io.containerd.service.v1.leases-service\"..." type=io.containerd.service.v1 Jul 2 01:50:22.800011 env[1378]: time="2024-07-02T01:50:22.799161820Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Jul 2 01:50:22.800011 env[1378]: time="2024-07-02T01:50:22.799175253Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Jul 2 01:50:22.800011 env[1378]: time="2024-07-02T01:50:22.799190885Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Jul 2 01:50:22.800011 env[1378]: time="2024-07-02T01:50:22.799328571Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Jul 2 01:50:22.800011 env[1378]: time="2024-07-02T01:50:22.799418003Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Jul 2 01:50:22.800301 extend-filesystems[1360]: Old size kept for /dev/sda9 Jul 2 01:50:22.800301 extend-filesystems[1360]: Found sr0 Jul 2 01:50:22.820585 env[1378]: time="2024-07-02T01:50:22.799657355Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Jul 2 01:50:22.820585 env[1378]: time="2024-07-02T01:50:22.799682542Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Jul 2 01:50:22.820585 env[1378]: time="2024-07-02T01:50:22.799705010Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Jul 2 01:50:22.820585 env[1378]: time="2024-07-02T01:50:22.799746627Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Jul 2 01:50:22.820585 env[1378]: time="2024-07-02T01:50:22.799758861Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Jul 2 01:50:22.820585 env[1378]: time="2024-07-02T01:50:22.799780489Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Jul 2 01:50:22.820585 env[1378]: time="2024-07-02T01:50:22.799792803Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Jul 2 01:50:22.820585 env[1378]: time="2024-07-02T01:50:22.799805116Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Jul 2 01:50:22.820585 env[1378]: time="2024-07-02T01:50:22.799817669Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Jul 2 01:50:22.820585 env[1378]: time="2024-07-02T01:50:22.799829543Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Jul 2 01:50:22.820585 env[1378]: time="2024-07-02T01:50:22.799841497Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Jul 2 01:50:22.820585 env[1378]: time="2024-07-02T01:50:22.799865804Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Jul 2 01:50:22.820585 env[1378]: time="2024-07-02T01:50:22.800219294Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Jul 2 01:50:22.820585 env[1378]: time="2024-07-02T01:50:22.800254835Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Jul 2 01:50:22.820585 env[1378]: time="2024-07-02T01:50:22.800269588Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Jul 2 01:50:22.805004 systemd[1]: extend-filesystems.service: Deactivated successfully. Jul 2 01:50:22.820977 env[1378]: time="2024-07-02T01:50:22.800280462Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Jul 2 01:50:22.820977 env[1378]: time="2024-07-02T01:50:22.800295134Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="no OpenTelemetry endpoint: skip plugin" type=io.containerd.tracing.processor.v1 Jul 2 01:50:22.820977 env[1378]: time="2024-07-02T01:50:22.800307008Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Jul 2 01:50:22.820977 env[1378]: time="2024-07-02T01:50:22.800333833Z" level=error msg="failed to initialize a tracing processor \"otlp\"" error="no OpenTelemetry endpoint: skip plugin" Jul 2 01:50:22.820977 env[1378]: time="2024-07-02T01:50:22.800374691Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Jul 2 01:50:22.808924 systemd[1]: Finished extend-filesystems.service. Jul 2 01:50:22.816529 systemd[1]: Started containerd.service. Jul 2 01:50:22.821128 env[1378]: time="2024-07-02T01:50:22.800587098Z" 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}" Jul 2 01:50:22.821128 env[1378]: time="2024-07-02T01:50:22.800649544Z" level=info msg="Connect containerd service" Jul 2 01:50:22.821128 env[1378]: time="2024-07-02T01:50:22.800684805Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Jul 2 01:50:22.821128 env[1378]: time="2024-07-02T01:50:22.809120573Z" 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" Jul 2 01:50:22.821128 env[1378]: time="2024-07-02T01:50:22.809367121Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Jul 2 01:50:22.821128 env[1378]: time="2024-07-02T01:50:22.809403342Z" level=info msg=serving... address=/run/containerd/containerd.sock Jul 2 01:50:22.821128 env[1378]: time="2024-07-02T01:50:22.809440802Z" level=info msg="containerd successfully booted in 0.062682s" Jul 2 01:50:22.821128 env[1378]: time="2024-07-02T01:50:22.809811444Z" level=info msg="Start subscribing containerd event" Jul 2 01:50:22.821128 env[1378]: time="2024-07-02T01:50:22.809853821Z" level=info msg="Start recovering state" Jul 2 01:50:22.821128 env[1378]: time="2024-07-02T01:50:22.809965401Z" level=info msg="Start event monitor" Jul 2 01:50:22.821128 env[1378]: time="2024-07-02T01:50:22.809983911Z" level=info msg="Start snapshots syncer" Jul 2 01:50:22.821128 env[1378]: time="2024-07-02T01:50:22.809994785Z" level=info msg="Start cni network conf syncer for default" Jul 2 01:50:22.821128 env[1378]: time="2024-07-02T01:50:22.810004740Z" level=info msg="Start streaming server" Jul 2 01:50:22.889992 bash[1424]: Updated "/home/core/.ssh/authorized_keys" Jul 2 01:50:22.891052 systemd[1]: Finished update-ssh-keys-after-ignition.service. Jul 2 01:50:22.933434 dbus-daemon[1358]: [system] SELinux support is enabled Jul 2 01:50:22.933599 systemd[1]: Started dbus.service. Jul 2 01:50:22.939328 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). Jul 2 01:50:22.939349 systemd[1]: Reached target system-config.target. Jul 2 01:50:22.948001 systemd[1]: user-cloudinit-proc-cmdline.service was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Jul 2 01:50:22.948022 systemd[1]: Reached target user-config.target. Jul 2 01:50:22.955330 systemd[1]: Started systemd-logind.service. Jul 2 01:50:22.966122 systemd[1]: nvidia.service: Deactivated successfully. Jul 2 01:50:23.321991 update_engine[1373]: I0702 01:50:23.299980 1373 main.cc:92] Flatcar Update Engine starting Jul 2 01:50:23.355402 systemd[1]: Started update-engine.service. Jul 2 01:50:23.361506 update_engine[1373]: I0702 01:50:23.355423 1373 update_check_scheduler.cc:74] Next update check in 4m7s Jul 2 01:50:23.362140 systemd[1]: Started locksmithd.service. Jul 2 01:50:24.201553 sshd_keygen[1374]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Jul 2 01:50:24.217700 systemd[1]: Finished sshd-keygen.service. Jul 2 01:50:24.223640 systemd[1]: Starting issuegen.service... Jul 2 01:50:24.228303 systemd[1]: Started waagent.service. Jul 2 01:50:24.232931 systemd[1]: issuegen.service: Deactivated successfully. Jul 2 01:50:24.233095 systemd[1]: Finished issuegen.service. Jul 2 01:50:24.238863 systemd[1]: Starting systemd-user-sessions.service... Jul 2 01:50:24.289012 systemd[1]: Finished systemd-user-sessions.service. Jul 2 01:50:24.295282 systemd[1]: Started getty@tty1.service. Jul 2 01:50:24.300662 systemd[1]: Started serial-getty@ttyAMA0.service. Jul 2 01:50:24.305378 systemd[1]: Reached target getty.target. Jul 2 01:50:24.309501 systemd[1]: Reached target multi-user.target. Jul 2 01:50:24.315301 systemd[1]: Starting systemd-update-utmp-runlevel.service... Jul 2 01:50:24.323009 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Jul 2 01:50:24.323168 systemd[1]: Finished systemd-update-utmp-runlevel.service. Jul 2 01:50:24.328585 systemd[1]: Startup finished in 713ms (kernel) + 12.110s (initrd) + 19.740s (userspace) = 32.563s. Jul 2 01:50:24.524491 locksmithd[1459]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Jul 2 01:50:24.894389 login[1477]: pam_lastlog(login:session): file /var/log/lastlog is locked/write Jul 2 01:50:24.908221 login[1478]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Jul 2 01:50:24.961639 systemd[1]: Created slice user-500.slice. Jul 2 01:50:24.962799 systemd[1]: Starting user-runtime-dir@500.service... Jul 2 01:50:24.965718 systemd-logind[1371]: New session 1 of user core. Jul 2 01:50:24.999417 systemd[1]: Finished user-runtime-dir@500.service. Jul 2 01:50:25.000991 systemd[1]: Starting user@500.service... Jul 2 01:50:25.046397 (systemd)[1482]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Jul 2 01:50:25.228472 systemd[1482]: Queued start job for default target default.target. Jul 2 01:50:25.229009 systemd[1482]: Reached target paths.target. Jul 2 01:50:25.229029 systemd[1482]: Reached target sockets.target. Jul 2 01:50:25.229038 systemd[1482]: Reached target timers.target. Jul 2 01:50:25.229047 systemd[1482]: Reached target basic.target. Jul 2 01:50:25.229091 systemd[1482]: Reached target default.target. Jul 2 01:50:25.229115 systemd[1482]: Startup finished in 177ms. Jul 2 01:50:25.229167 systemd[1]: Started user@500.service. Jul 2 01:50:25.230114 systemd[1]: Started session-1.scope. Jul 2 01:50:25.895061 login[1477]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Jul 2 01:50:25.898433 systemd-logind[1371]: New session 2 of user core. Jul 2 01:50:25.899227 systemd[1]: Started session-2.scope. Jul 2 01:50:29.142406 waagent[1474]: 2024-07-02T01:50:29.142300Z INFO Daemon Daemon Azure Linux Agent Version:2.6.0.2 Jul 2 01:50:29.149009 waagent[1474]: 2024-07-02T01:50:29.148943Z INFO Daemon Daemon OS: flatcar 3510.3.5 Jul 2 01:50:29.153766 waagent[1474]: 2024-07-02T01:50:29.153707Z INFO Daemon Daemon Python: 3.9.16 Jul 2 01:50:29.158242 waagent[1474]: 2024-07-02T01:50:29.158147Z INFO Daemon Daemon Run daemon Jul 2 01:50:29.162653 waagent[1474]: 2024-07-02T01:50:29.162593Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='3510.3.5' Jul 2 01:50:29.179079 waagent[1474]: 2024-07-02T01:50:29.178977Z 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. Jul 2 01:50:29.193506 waagent[1474]: 2024-07-02T01:50:29.193404Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Jul 2 01:50:29.203227 waagent[1474]: 2024-07-02T01:50:29.203168Z INFO Daemon Daemon cloud-init is enabled: False Jul 2 01:50:29.208206 waagent[1474]: 2024-07-02T01:50:29.208150Z INFO Daemon Daemon Using waagent for provisioning Jul 2 01:50:29.213850 waagent[1474]: 2024-07-02T01:50:29.213795Z INFO Daemon Daemon Activate resource disk Jul 2 01:50:29.218666 waagent[1474]: 2024-07-02T01:50:29.218610Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Jul 2 01:50:29.232755 waagent[1474]: 2024-07-02T01:50:29.232700Z INFO Daemon Daemon Found device: None Jul 2 01:50:29.237211 waagent[1474]: 2024-07-02T01:50:29.237154Z ERROR Daemon Daemon Failed to mount resource disk [ResourceDiskError] unable to detect disk topology Jul 2 01:50:29.245545 waagent[1474]: 2024-07-02T01:50:29.245489Z ERROR Daemon Daemon Event: name=WALinuxAgent, op=ActivateResourceDisk, message=[ResourceDiskError] unable to detect disk topology, duration=0 Jul 2 01:50:29.257574 waagent[1474]: 2024-07-02T01:50:29.257518Z INFO Daemon Daemon Clean protocol and wireserver endpoint Jul 2 01:50:29.263587 waagent[1474]: 2024-07-02T01:50:29.263531Z INFO Daemon Daemon Running default provisioning handler Jul 2 01:50:29.275937 waagent[1474]: 2024-07-02T01:50:29.275807Z 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. Jul 2 01:50:29.290868 waagent[1474]: 2024-07-02T01:50:29.290760Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Jul 2 01:50:29.300404 waagent[1474]: 2024-07-02T01:50:29.300345Z INFO Daemon Daemon cloud-init is enabled: False Jul 2 01:50:29.305363 waagent[1474]: 2024-07-02T01:50:29.305303Z INFO Daemon Daemon Copying ovf-env.xml Jul 2 01:50:29.374615 waagent[1474]: 2024-07-02T01:50:29.374495Z INFO Daemon Daemon Successfully mounted dvd Jul 2 01:50:29.477625 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Jul 2 01:50:29.515161 waagent[1474]: 2024-07-02T01:50:29.515024Z INFO Daemon Daemon Detect protocol endpoint Jul 2 01:50:29.519973 waagent[1474]: 2024-07-02T01:50:29.519910Z INFO Daemon Daemon Clean protocol and wireserver endpoint Jul 2 01:50:29.525517 waagent[1474]: 2024-07-02T01:50:29.525459Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Jul 2 01:50:29.532762 waagent[1474]: 2024-07-02T01:50:29.532705Z INFO Daemon Daemon Test for route to 168.63.129.16 Jul 2 01:50:29.539101 waagent[1474]: 2024-07-02T01:50:29.539047Z INFO Daemon Daemon Route to 168.63.129.16 exists Jul 2 01:50:29.544343 waagent[1474]: 2024-07-02T01:50:29.544287Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Jul 2 01:50:29.724987 waagent[1474]: 2024-07-02T01:50:29.724919Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Jul 2 01:50:29.732539 waagent[1474]: 2024-07-02T01:50:29.732496Z INFO Daemon Daemon Wire protocol version:2012-11-30 Jul 2 01:50:29.738288 waagent[1474]: 2024-07-02T01:50:29.738235Z INFO Daemon Daemon Server preferred version:2015-04-05 Jul 2 01:50:30.235692 waagent[1474]: 2024-07-02T01:50:30.235540Z INFO Daemon Daemon Initializing goal state during protocol detection Jul 2 01:50:30.250212 waagent[1474]: 2024-07-02T01:50:30.250144Z INFO Daemon Daemon Forcing an update of the goal state.. Jul 2 01:50:30.256650 waagent[1474]: 2024-07-02T01:50:30.256588Z INFO Daemon Daemon Fetching goal state [incarnation 1] Jul 2 01:50:30.327205 waagent[1474]: 2024-07-02T01:50:30.327079Z INFO Daemon Daemon Found private key matching thumbprint 3AF5931F8C96ED414080EEFDE6852BCE2D88D9EA Jul 2 01:50:30.335662 waagent[1474]: 2024-07-02T01:50:30.335591Z INFO Daemon Daemon Certificate with thumbprint 633651CF634D9CBEDFAFED4C6E12C4FC4810028B has no matching private key. Jul 2 01:50:30.345008 waagent[1474]: 2024-07-02T01:50:30.344948Z INFO Daemon Daemon Fetch goal state completed Jul 2 01:50:30.393726 waagent[1474]: 2024-07-02T01:50:30.393670Z INFO Daemon Daemon Fetched new vmSettings [correlation ID: 095abe9f-4def-4884-9463-0c2a760be7de New eTag: 10130090537853753790] Jul 2 01:50:30.404319 waagent[1474]: 2024-07-02T01:50:30.404256Z INFO Daemon Daemon Status Blob type 'None' is not valid, assuming BlockBlob Jul 2 01:50:30.421483 waagent[1474]: 2024-07-02T01:50:30.421425Z INFO Daemon Daemon Starting provisioning Jul 2 01:50:30.426330 waagent[1474]: 2024-07-02T01:50:30.426272Z INFO Daemon Daemon Handle ovf-env.xml. Jul 2 01:50:30.430996 waagent[1474]: 2024-07-02T01:50:30.430943Z INFO Daemon Daemon Set hostname [ci-3510.3.5-a-020439589a] Jul 2 01:50:30.484262 waagent[1474]: 2024-07-02T01:50:30.484135Z INFO Daemon Daemon Publish hostname [ci-3510.3.5-a-020439589a] Jul 2 01:50:30.490580 waagent[1474]: 2024-07-02T01:50:30.490500Z INFO Daemon Daemon Examine /proc/net/route for primary interface Jul 2 01:50:30.497108 waagent[1474]: 2024-07-02T01:50:30.497053Z INFO Daemon Daemon Primary interface is [eth0] Jul 2 01:50:30.512472 systemd[1]: systemd-networkd-wait-online.service: Deactivated successfully. Jul 2 01:50:30.512648 systemd[1]: Stopped systemd-networkd-wait-online.service. Jul 2 01:50:30.512703 systemd[1]: Stopping systemd-networkd-wait-online.service... Jul 2 01:50:30.512944 systemd[1]: Stopping systemd-networkd.service... Jul 2 01:50:30.522936 systemd-networkd[1250]: eth0: DHCPv6 lease lost Jul 2 01:50:30.524132 systemd[1]: systemd-networkd.service: Deactivated successfully. Jul 2 01:50:30.524298 systemd[1]: Stopped systemd-networkd.service. Jul 2 01:50:30.526150 systemd[1]: Starting systemd-networkd.service... Jul 2 01:50:30.552392 systemd-networkd[1525]: enP24835s1: Link UP Jul 2 01:50:30.552402 systemd-networkd[1525]: enP24835s1: Gained carrier Jul 2 01:50:30.553482 systemd-networkd[1525]: eth0: Link UP Jul 2 01:50:30.553492 systemd-networkd[1525]: eth0: Gained carrier Jul 2 01:50:30.553797 systemd-networkd[1525]: lo: Link UP Jul 2 01:50:30.553807 systemd-networkd[1525]: lo: Gained carrier Jul 2 01:50:30.554041 systemd-networkd[1525]: eth0: Gained IPv6LL Jul 2 01:50:30.555164 systemd-networkd[1525]: Enumeration completed Jul 2 01:50:30.555261 systemd[1]: Started systemd-networkd.service. Jul 2 01:50:30.556750 systemd-networkd[1525]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jul 2 01:50:30.556812 systemd[1]: Starting systemd-networkd-wait-online.service... Jul 2 01:50:30.560563 waagent[1474]: 2024-07-02T01:50:30.560416Z INFO Daemon Daemon Create user account if not exists Jul 2 01:50:30.568048 waagent[1474]: 2024-07-02T01:50:30.567974Z INFO Daemon Daemon User core already exists, skip useradd Jul 2 01:50:30.574319 waagent[1474]: 2024-07-02T01:50:30.574250Z INFO Daemon Daemon Configure sudoer Jul 2 01:50:30.578783 waagent[1474]: 2024-07-02T01:50:30.578723Z INFO Daemon Daemon Configure sshd Jul 2 01:50:30.582802 waagent[1474]: 2024-07-02T01:50:30.582746Z INFO Daemon Daemon Deploy ssh public key. Jul 2 01:50:30.587589 systemd-networkd[1525]: eth0: DHCPv4 address 10.200.20.10/24, gateway 10.200.20.1 acquired from 168.63.129.16 Jul 2 01:50:30.589836 systemd[1]: Finished systemd-networkd-wait-online.service. Jul 2 01:50:31.925550 waagent[1474]: 2024-07-02T01:50:31.925487Z INFO Daemon Daemon Provisioning complete Jul 2 01:50:31.944652 waagent[1474]: 2024-07-02T01:50:31.944589Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Jul 2 01:50:31.950672 waagent[1474]: 2024-07-02T01:50:31.950614Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Jul 2 01:50:31.961289 waagent[1474]: 2024-07-02T01:50:31.961226Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.6.0.2 is the most current agent Jul 2 01:50:32.252276 waagent[1535]: 2024-07-02T01:50:32.252192Z INFO ExtHandler ExtHandler Agent WALinuxAgent-2.6.0.2 is running as the goal state agent Jul 2 01:50:32.253036 waagent[1535]: 2024-07-02T01:50:32.252983Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jul 2 01:50:32.253169 waagent[1535]: 2024-07-02T01:50:32.253126Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Jul 2 01:50:32.265174 waagent[1535]: 2024-07-02T01:50:32.265106Z INFO ExtHandler ExtHandler Forcing an update of the goal state.. Jul 2 01:50:32.265347 waagent[1535]: 2024-07-02T01:50:32.265300Z INFO ExtHandler ExtHandler Fetching goal state [incarnation 1] Jul 2 01:50:32.331981 waagent[1535]: 2024-07-02T01:50:32.331835Z INFO ExtHandler ExtHandler Found private key matching thumbprint 3AF5931F8C96ED414080EEFDE6852BCE2D88D9EA Jul 2 01:50:32.332183 waagent[1535]: 2024-07-02T01:50:32.332130Z INFO ExtHandler ExtHandler Certificate with thumbprint 633651CF634D9CBEDFAFED4C6E12C4FC4810028B has no matching private key. Jul 2 01:50:32.332398 waagent[1535]: 2024-07-02T01:50:32.332353Z INFO ExtHandler ExtHandler Fetch goal state completed Jul 2 01:50:32.347930 waagent[1535]: 2024-07-02T01:50:32.347867Z INFO ExtHandler ExtHandler Fetched new vmSettings [correlation ID: 150eacb2-4f50-4e06-9929-ce12ca34cc81 New eTag: 10130090537853753790] Jul 2 01:50:32.348455 waagent[1535]: 2024-07-02T01:50:32.348399Z INFO ExtHandler ExtHandler Status Blob type 'None' is not valid, assuming BlockBlob Jul 2 01:50:32.421418 waagent[1535]: 2024-07-02T01:50:32.421287Z INFO ExtHandler ExtHandler Distro: flatcar-3510.3.5; OSUtil: CoreOSUtil; AgentService: waagent; Python: 3.9.16; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.20.1; Jul 2 01:50:32.444328 waagent[1535]: 2024-07-02T01:50:32.444252Z INFO ExtHandler ExtHandler WALinuxAgent-2.6.0.2 running as process 1535 Jul 2 01:50:32.448036 waagent[1535]: 2024-07-02T01:50:32.447976Z INFO ExtHandler ExtHandler Cgroup monitoring is not supported on ['flatcar', '3510.3.5', '', 'Flatcar Container Linux by Kinvolk'] Jul 2 01:50:32.449329 waagent[1535]: 2024-07-02T01:50:32.449275Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Jul 2 01:50:32.553523 waagent[1535]: 2024-07-02T01:50:32.553428Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Jul 2 01:50:32.553911 waagent[1535]: 2024-07-02T01:50:32.553842Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Jul 2 01:50:32.561521 waagent[1535]: 2024-07-02T01:50:32.561456Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Jul 2 01:50:32.562032 waagent[1535]: 2024-07-02T01:50:32.561975Z ERROR ExtHandler ExtHandler Unable to setup the persistent firewall rules: [Errno 30] Read-only file system: '/lib/systemd/system/waagent-network-setup.service' Jul 2 01:50:32.563228 waagent[1535]: 2024-07-02T01:50:32.563163Z 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] Jul 2 01:50:32.564636 waagent[1535]: 2024-07-02T01:50:32.564565Z INFO ExtHandler ExtHandler Starting env monitor service. Jul 2 01:50:32.565277 waagent[1535]: 2024-07-02T01:50:32.565219Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jul 2 01:50:32.565514 waagent[1535]: 2024-07-02T01:50:32.565464Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Jul 2 01:50:32.566154 waagent[1535]: 2024-07-02T01:50:32.566097Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Jul 2 01:50:32.566514 waagent[1535]: 2024-07-02T01:50:32.566461Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Jul 2 01:50:32.566514 waagent[1535]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Jul 2 01:50:32.566514 waagent[1535]: eth0 00000000 0114C80A 0003 0 0 1024 00000000 0 0 0 Jul 2 01:50:32.566514 waagent[1535]: eth0 0014C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Jul 2 01:50:32.566514 waagent[1535]: eth0 0114C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Jul 2 01:50:32.566514 waagent[1535]: eth0 10813FA8 0114C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Jul 2 01:50:32.566514 waagent[1535]: eth0 FEA9FEA9 0114C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Jul 2 01:50:32.568674 waagent[1535]: 2024-07-02T01:50:32.568519Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Jul 2 01:50:32.569482 waagent[1535]: 2024-07-02T01:50:32.569422Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jul 2 01:50:32.569727 waagent[1535]: 2024-07-02T01:50:32.569677Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Jul 2 01:50:32.570359 waagent[1535]: 2024-07-02T01:50:32.570299Z INFO EnvHandler ExtHandler Configure routes Jul 2 01:50:32.570578 waagent[1535]: 2024-07-02T01:50:32.570530Z INFO EnvHandler ExtHandler Gateway:None Jul 2 01:50:32.570761 waagent[1535]: 2024-07-02T01:50:32.570719Z INFO EnvHandler ExtHandler Routes:None Jul 2 01:50:32.571686 waagent[1535]: 2024-07-02T01:50:32.571630Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Jul 2 01:50:32.571766 waagent[1535]: 2024-07-02T01:50:32.571706Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Jul 2 01:50:32.572498 waagent[1535]: 2024-07-02T01:50:32.572418Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Jul 2 01:50:32.572582 waagent[1535]: 2024-07-02T01:50:32.572521Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Jul 2 01:50:32.573047 waagent[1535]: 2024-07-02T01:50:32.572974Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Jul 2 01:50:32.584344 waagent[1535]: 2024-07-02T01:50:32.584269Z INFO ExtHandler ExtHandler Checking for agent updates (family: Prod) Jul 2 01:50:32.584956 waagent[1535]: 2024-07-02T01:50:32.584870Z WARNING ExtHandler ExtHandler Fetch failed: [HttpError] HTTPS is unavailable and required Jul 2 01:50:32.585926 waagent[1535]: 2024-07-02T01:50:32.585850Z INFO ExtHandler ExtHandler [PERIODIC] Request failed using the direct channel. Error: 'NoneType' object has no attribute 'getheaders' Jul 2 01:50:32.626517 waagent[1535]: 2024-07-02T01:50:32.626453Z INFO ExtHandler ExtHandler Default channel changed to HostGA channel. Jul 2 01:50:32.631111 waagent[1535]: 2024-07-02T01:50:32.631042Z ERROR EnvHandler ExtHandler Failed to get the PID of the DHCP client: invalid literal for int() with base 10: 'MainPID=1525' Jul 2 01:50:32.695659 waagent[1535]: 2024-07-02T01:50:32.695540Z INFO MonitorHandler ExtHandler Network interfaces: Jul 2 01:50:32.695659 waagent[1535]: Executing ['ip', '-a', '-o', 'link']: Jul 2 01:50:32.695659 waagent[1535]: 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 Jul 2 01:50:32.695659 waagent[1535]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 00:22:48:7e:04:83 brd ff:ff:ff:ff:ff:ff Jul 2 01:50:32.695659 waagent[1535]: 3: enP24835s1: mtu 1500 qdisc mq master eth0 state UP mode DEFAULT group default qlen 1000\ link/ether 00:22:48:7e:04:83 brd ff:ff:ff:ff:ff:ff\ altname enP24835p0s2 Jul 2 01:50:32.695659 waagent[1535]: Executing ['ip', '-4', '-a', '-o', 'address']: Jul 2 01:50:32.695659 waagent[1535]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Jul 2 01:50:32.695659 waagent[1535]: 2: eth0 inet 10.200.20.10/24 metric 1024 brd 10.200.20.255 scope global eth0\ valid_lft forever preferred_lft forever Jul 2 01:50:32.695659 waagent[1535]: Executing ['ip', '-6', '-a', '-o', 'address']: Jul 2 01:50:32.695659 waagent[1535]: 1: lo inet6 ::1/128 scope host \ valid_lft forever preferred_lft forever Jul 2 01:50:32.695659 waagent[1535]: 2: eth0 inet6 fe80::222:48ff:fe7e:483/64 scope link \ valid_lft forever preferred_lft forever Jul 2 01:50:32.868657 waagent[1535]: 2024-07-02T01:50:32.868565Z INFO ExtHandler ExtHandler Agent WALinuxAgent-2.6.0.2 discovered update WALinuxAgent-2.11.1.4 -- exiting Jul 2 01:50:32.964780 waagent[1474]: 2024-07-02T01:50:32.964626Z 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 Jul 2 01:50:32.969544 waagent[1474]: 2024-07-02T01:50:32.969495Z INFO Daemon Daemon Determined Agent WALinuxAgent-2.11.1.4 to be the latest agent Jul 2 01:50:34.145470 waagent[1564]: 2024-07-02T01:50:34.145369Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.11.1.4) Jul 2 01:50:34.146209 waagent[1564]: 2024-07-02T01:50:34.146149Z INFO ExtHandler ExtHandler OS: flatcar 3510.3.5 Jul 2 01:50:34.146348 waagent[1564]: 2024-07-02T01:50:34.146302Z INFO ExtHandler ExtHandler Python: 3.9.16 Jul 2 01:50:34.146473 waagent[1564]: 2024-07-02T01:50:34.146431Z INFO ExtHandler ExtHandler CPU Arch: aarch64 Jul 2 01:50:34.154383 waagent[1564]: 2024-07-02T01:50:34.154277Z INFO ExtHandler ExtHandler Distro: flatcar-3510.3.5; OSUtil: CoreOSUtil; AgentService: waagent; Python: 3.9.16; Arch: aarch64; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.20.1; Jul 2 01:50:34.154756 waagent[1564]: 2024-07-02T01:50:34.154700Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jul 2 01:50:34.154913 waagent[1564]: 2024-07-02T01:50:34.154855Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Jul 2 01:50:34.167612 waagent[1564]: 2024-07-02T01:50:34.167546Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Jul 2 01:50:34.177163 waagent[1564]: 2024-07-02T01:50:34.177112Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.151 Jul 2 01:50:34.178170 waagent[1564]: 2024-07-02T01:50:34.178113Z INFO ExtHandler Jul 2 01:50:34.178318 waagent[1564]: 2024-07-02T01:50:34.178273Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: dee9db48-fc9b-49ea-a612-d4ce8682b0a7 eTag: 10130090537853753790 source: Fabric] Jul 2 01:50:34.179096 waagent[1564]: 2024-07-02T01:50:34.179038Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Jul 2 01:50:34.180305 waagent[1564]: 2024-07-02T01:50:34.180247Z INFO ExtHandler Jul 2 01:50:34.180436 waagent[1564]: 2024-07-02T01:50:34.180392Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Jul 2 01:50:34.186751 waagent[1564]: 2024-07-02T01:50:34.186702Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Jul 2 01:50:34.187206 waagent[1564]: 2024-07-02T01:50:34.187159Z WARNING ExtHandler ExtHandler Fetch failed: [HttpError] HTTPS is unavailable and required Jul 2 01:50:34.211235 waagent[1564]: 2024-07-02T01:50:34.211186Z INFO ExtHandler ExtHandler Default channel changed to HostGAPlugin channel. Jul 2 01:50:34.277577 waagent[1564]: 2024-07-02T01:50:34.277439Z INFO ExtHandler Downloaded certificate {'thumbprint': '633651CF634D9CBEDFAFED4C6E12C4FC4810028B', 'hasPrivateKey': False} Jul 2 01:50:34.278616 waagent[1564]: 2024-07-02T01:50:34.278553Z INFO ExtHandler Downloaded certificate {'thumbprint': '3AF5931F8C96ED414080EEFDE6852BCE2D88D9EA', 'hasPrivateKey': True} Jul 2 01:50:34.279733 waagent[1564]: 2024-07-02T01:50:34.279673Z INFO ExtHandler Fetch goal state completed Jul 2 01:50:34.299444 waagent[1564]: 2024-07-02T01:50:34.299341Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.0.7 1 Nov 2022 (Library: OpenSSL 3.0.7 1 Nov 2022) Jul 2 01:50:34.311123 waagent[1564]: 2024-07-02T01:50:34.311043Z INFO ExtHandler ExtHandler WALinuxAgent-2.11.1.4 running as process 1564 Jul 2 01:50:34.314557 waagent[1564]: 2024-07-02T01:50:34.314497Z INFO ExtHandler ExtHandler Cgroup monitoring is not supported on ['flatcar', '3510.3.5', '', 'Flatcar Container Linux by Kinvolk'] Jul 2 01:50:34.316068 waagent[1564]: 2024-07-02T01:50:34.316010Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Jul 2 01:50:34.320634 waagent[1564]: 2024-07-02T01:50:34.320577Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Jul 2 01:50:34.321031 waagent[1564]: 2024-07-02T01:50:34.320972Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Jul 2 01:50:34.328128 waagent[1564]: 2024-07-02T01:50:34.328072Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Jul 2 01:50:34.328590 waagent[1564]: 2024-07-02T01:50:34.328534Z ERROR ExtHandler ExtHandler Unable to setup the persistent firewall rules: [Errno 30] Read-only file system: '/lib/systemd/system/waagent-network-setup.service' Jul 2 01:50:34.334264 waagent[1564]: 2024-07-02T01:50:34.334168Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Jul 2 01:50:34.335323 waagent[1564]: 2024-07-02T01:50:34.335262Z 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] Jul 2 01:50:34.336901 waagent[1564]: 2024-07-02T01:50:34.336820Z INFO ExtHandler ExtHandler Starting env monitor service. Jul 2 01:50:34.337811 waagent[1564]: 2024-07-02T01:50:34.337741Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Jul 2 01:50:34.338199 waagent[1564]: 2024-07-02T01:50:34.338138Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jul 2 01:50:34.338444 waagent[1564]: 2024-07-02T01:50:34.338395Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jul 2 01:50:34.338661 waagent[1564]: 2024-07-02T01:50:34.338615Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Jul 2 01:50:34.338856 waagent[1564]: 2024-07-02T01:50:34.338812Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Jul 2 01:50:34.339094 waagent[1564]: 2024-07-02T01:50:34.339023Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Jul 2 01:50:34.339368 waagent[1564]: 2024-07-02T01:50:34.339308Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Jul 2 01:50:34.340342 waagent[1564]: 2024-07-02T01:50:34.340271Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Jul 2 01:50:34.340971 waagent[1564]: 2024-07-02T01:50:34.340886Z INFO EnvHandler ExtHandler Configure routes Jul 2 01:50:34.341196 waagent[1564]: 2024-07-02T01:50:34.341149Z INFO EnvHandler ExtHandler Gateway:None Jul 2 01:50:34.341386 waagent[1564]: 2024-07-02T01:50:34.341342Z INFO EnvHandler ExtHandler Routes:None Jul 2 01:50:34.341713 waagent[1564]: 2024-07-02T01:50:34.341663Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Jul 2 01:50:34.341713 waagent[1564]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Jul 2 01:50:34.341713 waagent[1564]: eth0 00000000 0114C80A 0003 0 0 1024 00000000 0 0 0 Jul 2 01:50:34.341713 waagent[1564]: eth0 0014C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Jul 2 01:50:34.341713 waagent[1564]: eth0 0114C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Jul 2 01:50:34.341713 waagent[1564]: eth0 10813FA8 0114C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Jul 2 01:50:34.341713 waagent[1564]: eth0 FEA9FEA9 0114C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Jul 2 01:50:34.344163 waagent[1564]: 2024-07-02T01:50:34.343986Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Jul 2 01:50:34.344693 waagent[1564]: 2024-07-02T01:50:34.344604Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Jul 2 01:50:34.346396 waagent[1564]: 2024-07-02T01:50:34.346334Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Jul 2 01:50:34.361695 waagent[1564]: 2024-07-02T01:50:34.361616Z INFO ExtHandler ExtHandler Downloading agent manifest Jul 2 01:50:34.381989 waagent[1564]: 2024-07-02T01:50:34.381916Z INFO MonitorHandler ExtHandler Network interfaces: Jul 2 01:50:34.381989 waagent[1564]: Executing ['ip', '-a', '-o', 'link']: Jul 2 01:50:34.381989 waagent[1564]: 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 Jul 2 01:50:34.381989 waagent[1564]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 00:22:48:7e:04:83 brd ff:ff:ff:ff:ff:ff Jul 2 01:50:34.381989 waagent[1564]: 3: enP24835s1: mtu 1500 qdisc mq master eth0 state UP mode DEFAULT group default qlen 1000\ link/ether 00:22:48:7e:04:83 brd ff:ff:ff:ff:ff:ff\ altname enP24835p0s2 Jul 2 01:50:34.381989 waagent[1564]: Executing ['ip', '-4', '-a', '-o', 'address']: Jul 2 01:50:34.381989 waagent[1564]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Jul 2 01:50:34.381989 waagent[1564]: 2: eth0 inet 10.200.20.10/24 metric 1024 brd 10.200.20.255 scope global eth0\ valid_lft forever preferred_lft forever Jul 2 01:50:34.381989 waagent[1564]: Executing ['ip', '-6', '-a', '-o', 'address']: Jul 2 01:50:34.381989 waagent[1564]: 1: lo inet6 ::1/128 scope host \ valid_lft forever preferred_lft forever Jul 2 01:50:34.381989 waagent[1564]: 2: eth0 inet6 fe80::222:48ff:fe7e:483/64 scope link \ valid_lft forever preferred_lft forever Jul 2 01:50:34.398622 waagent[1564]: 2024-07-02T01:50:34.398520Z INFO ExtHandler ExtHandler Jul 2 01:50:34.398790 waagent[1564]: 2024-07-02T01:50:34.398730Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 003105f5-1319-421e-a591-395a70f06d71 correlation bbb1ed2f-01c6-4892-b913-e9bc04f357dc created: 2024-07-02T01:49:08.895233Z] Jul 2 01:50:34.400003 waagent[1564]: 2024-07-02T01:50:34.399932Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Jul 2 01:50:34.401930 waagent[1564]: 2024-07-02T01:50:34.401863Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 3 ms] Jul 2 01:50:34.423163 waagent[1564]: 2024-07-02T01:50:34.423104Z INFO ExtHandler ExtHandler Looking for existing remote access users. Jul 2 01:50:34.449501 waagent[1564]: 2024-07-02T01:50:34.449407Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.11.1.4 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: A84A5DE4-68D4-4345-B43F-076F36A2CE81;DroppedPackets: 0;UpdateGSErrors: 0;AutoUpdate: 1] Jul 2 01:50:34.617111 waagent[1564]: 2024-07-02T01:50:34.616994Z INFO EnvHandler ExtHandler Created firewall rules for the Azure Fabric: Jul 2 01:50:34.617111 waagent[1564]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Jul 2 01:50:34.617111 waagent[1564]: pkts bytes target prot opt in out source destination Jul 2 01:50:34.617111 waagent[1564]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Jul 2 01:50:34.617111 waagent[1564]: pkts bytes target prot opt in out source destination Jul 2 01:50:34.617111 waagent[1564]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Jul 2 01:50:34.617111 waagent[1564]: pkts bytes target prot opt in out source destination Jul 2 01:50:34.617111 waagent[1564]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Jul 2 01:50:34.617111 waagent[1564]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Jul 2 01:50:34.617111 waagent[1564]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Jul 2 01:50:34.624542 waagent[1564]: 2024-07-02T01:50:34.624430Z INFO EnvHandler ExtHandler Current Firewall rules: Jul 2 01:50:34.624542 waagent[1564]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Jul 2 01:50:34.624542 waagent[1564]: pkts bytes target prot opt in out source destination Jul 2 01:50:34.624542 waagent[1564]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Jul 2 01:50:34.624542 waagent[1564]: pkts bytes target prot opt in out source destination Jul 2 01:50:34.624542 waagent[1564]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Jul 2 01:50:34.624542 waagent[1564]: pkts bytes target prot opt in out source destination Jul 2 01:50:34.624542 waagent[1564]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Jul 2 01:50:34.624542 waagent[1564]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Jul 2 01:50:34.624542 waagent[1564]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Jul 2 01:50:34.625306 waagent[1564]: 2024-07-02T01:50:34.625260Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Jul 2 01:51:02.811134 kernel: hv_balloon: Max. dynamic memory size: 4096 MB Jul 2 01:51:08.804014 update_engine[1373]: I0702 01:51:08.803961 1373 update_attempter.cc:509] Updating boot flags... Jul 2 01:51:15.892698 systemd[1]: Created slice system-sshd.slice. Jul 2 01:51:15.893706 systemd[1]: Started sshd@0-10.200.20.10:22-10.200.16.10:45960.service. Jul 2 01:51:16.612820 sshd[1661]: Accepted publickey for core from 10.200.16.10 port 45960 ssh2: RSA SHA256:dIfkHgYeMkxYvU2An9TnjkrclLrmoTNY/YaaZP40c9o Jul 2 01:51:16.630730 sshd[1661]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Jul 2 01:51:16.635392 systemd[1]: Started session-3.scope. Jul 2 01:51:16.635773 systemd-logind[1371]: New session 3 of user core. Jul 2 01:51:17.028383 systemd[1]: Started sshd@1-10.200.20.10:22-10.200.16.10:45962.service. Jul 2 01:51:17.465584 sshd[1666]: Accepted publickey for core from 10.200.16.10 port 45962 ssh2: RSA SHA256:dIfkHgYeMkxYvU2An9TnjkrclLrmoTNY/YaaZP40c9o Jul 2 01:51:17.467099 sshd[1666]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Jul 2 01:51:17.471045 systemd[1]: Started session-4.scope. Jul 2 01:51:17.471540 systemd-logind[1371]: New session 4 of user core. Jul 2 01:51:17.781696 sshd[1666]: pam_unix(sshd:session): session closed for user core Jul 2 01:51:17.784080 systemd[1]: sshd@1-10.200.20.10:22-10.200.16.10:45962.service: Deactivated successfully. Jul 2 01:51:17.784749 systemd[1]: session-4.scope: Deactivated successfully. Jul 2 01:51:17.785302 systemd-logind[1371]: Session 4 logged out. Waiting for processes to exit. Jul 2 01:51:17.785949 systemd-logind[1371]: Removed session 4. Jul 2 01:51:17.854030 systemd[1]: Started sshd@2-10.200.20.10:22-10.200.16.10:45978.service. Jul 2 01:51:18.291080 sshd[1672]: Accepted publickey for core from 10.200.16.10 port 45978 ssh2: RSA SHA256:dIfkHgYeMkxYvU2An9TnjkrclLrmoTNY/YaaZP40c9o Jul 2 01:51:18.292369 sshd[1672]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Jul 2 01:51:18.295931 systemd-logind[1371]: New session 5 of user core. Jul 2 01:51:18.296318 systemd[1]: Started session-5.scope. Jul 2 01:51:18.603580 sshd[1672]: pam_unix(sshd:session): session closed for user core Jul 2 01:51:18.606075 systemd[1]: sshd@2-10.200.20.10:22-10.200.16.10:45978.service: Deactivated successfully. Jul 2 01:51:18.606709 systemd[1]: session-5.scope: Deactivated successfully. Jul 2 01:51:18.607194 systemd-logind[1371]: Session 5 logged out. Waiting for processes to exit. Jul 2 01:51:18.607998 systemd-logind[1371]: Removed session 5. Jul 2 01:51:18.685753 systemd[1]: Started sshd@3-10.200.20.10:22-10.200.16.10:51082.service. Jul 2 01:51:19.157108 sshd[1678]: Accepted publickey for core from 10.200.16.10 port 51082 ssh2: RSA SHA256:dIfkHgYeMkxYvU2An9TnjkrclLrmoTNY/YaaZP40c9o Jul 2 01:51:19.158296 sshd[1678]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Jul 2 01:51:19.161942 systemd-logind[1371]: New session 6 of user core. Jul 2 01:51:19.162365 systemd[1]: Started session-6.scope. Jul 2 01:51:19.508010 sshd[1678]: pam_unix(sshd:session): session closed for user core Jul 2 01:51:19.510239 systemd[1]: sshd@3-10.200.20.10:22-10.200.16.10:51082.service: Deactivated successfully. Jul 2 01:51:19.510879 systemd[1]: session-6.scope: Deactivated successfully. Jul 2 01:51:19.511413 systemd-logind[1371]: Session 6 logged out. Waiting for processes to exit. Jul 2 01:51:19.512191 systemd-logind[1371]: Removed session 6. Jul 2 01:51:19.579011 systemd[1]: Started sshd@4-10.200.20.10:22-10.200.16.10:51090.service. Jul 2 01:51:20.010266 sshd[1684]: Accepted publickey for core from 10.200.16.10 port 51090 ssh2: RSA SHA256:dIfkHgYeMkxYvU2An9TnjkrclLrmoTNY/YaaZP40c9o Jul 2 01:51:20.011461 sshd[1684]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Jul 2 01:51:20.015160 systemd-logind[1371]: New session 7 of user core. Jul 2 01:51:20.015549 systemd[1]: Started session-7.scope. Jul 2 01:51:20.491256 sudo[1687]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Jul 2 01:51:20.491457 sudo[1687]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Jul 2 01:51:20.551057 dbus-daemon[1358]: avc: received setenforce notice (enforcing=1) Jul 2 01:51:20.551862 sudo[1687]: pam_unix(sudo:session): session closed for user root Jul 2 01:51:20.635800 sshd[1684]: pam_unix(sshd:session): session closed for user core Jul 2 01:51:20.638641 systemd[1]: sshd@4-10.200.20.10:22-10.200.16.10:51090.service: Deactivated successfully. Jul 2 01:51:20.639434 systemd[1]: session-7.scope: Deactivated successfully. Jul 2 01:51:20.639952 systemd-logind[1371]: Session 7 logged out. Waiting for processes to exit. Jul 2 01:51:20.640633 systemd-logind[1371]: Removed session 7. Jul 2 01:51:20.707969 systemd[1]: Started sshd@5-10.200.20.10:22-10.200.16.10:51096.service. Jul 2 01:51:21.145448 sshd[1691]: Accepted publickey for core from 10.200.16.10 port 51096 ssh2: RSA SHA256:dIfkHgYeMkxYvU2An9TnjkrclLrmoTNY/YaaZP40c9o Jul 2 01:51:21.146711 sshd[1691]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Jul 2 01:51:21.150436 systemd-logind[1371]: New session 8 of user core. Jul 2 01:51:21.150843 systemd[1]: Started session-8.scope. Jul 2 01:51:21.394530 sudo[1695]: 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 Jul 2 01:51:21.394730 sudo[1695]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Jul 2 01:51:21.397185 sudo[1695]: pam_unix(sudo:session): session closed for user root Jul 2 01:51:21.401243 sudo[1694]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/bin/systemctl restart audit-rules Jul 2 01:51:21.401420 sudo[1694]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Jul 2 01:51:21.409134 systemd[1]: Stopping audit-rules.service... Jul 2 01:51:21.408000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Jul 2 01:51:21.410275 auditctl[1698]: No rules Jul 2 01:51:21.414073 kernel: kauditd_printk_skb: 92 callbacks suppressed Jul 2 01:51:21.414123 kernel: audit: type=1305 audit(1719885081.408:172): auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Jul 2 01:51:21.414372 systemd[1]: audit-rules.service: Deactivated successfully. Jul 2 01:51:21.414672 systemd[1]: Stopped audit-rules.service. Jul 2 01:51:21.416339 systemd[1]: Starting audit-rules.service... Jul 2 01:51:21.408000 audit[1698]: SYSCALL arch=c00000b7 syscall=206 success=yes exit=1056 a0=3 a1=ffffe78adea0 a2=420 a3=0 items=0 ppid=1 pid=1698 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) Jul 2 01:51:21.434945 augenrules[1715]: No rules Jul 2 01:51:21.435760 systemd[1]: Finished audit-rules.service. Jul 2 01:51:21.447638 kernel: audit: type=1300 audit(1719885081.408:172): arch=c00000b7 syscall=206 success=yes exit=1056 a0=3 a1=ffffe78adea0 a2=420 a3=0 items=0 ppid=1 pid=1698 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) Jul 2 01:51:21.447852 sudo[1694]: pam_unix(sudo:session): session closed for user root Jul 2 01:51:21.408000 audit: PROCTITLE proctitle=2F7362696E2F617564697463746C002D44 Jul 2 01:51:21.455069 kernel: audit: type=1327 audit(1719885081.408:172): proctitle=2F7362696E2F617564697463746C002D44 Jul 2 01:51:21.455104 kernel: audit: type=1131 audit(1719885081.412: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' Jul 2 01:51:21.412000 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' Jul 2 01:51:21.434000 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' Jul 2 01:51:21.485685 kernel: audit: type=1130 audit(1719885081.434: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' Jul 2 01:51:21.485731 kernel: audit: type=1106 audit(1719885081.446:175): pid=1694 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' Jul 2 01:51:21.446000 audit[1694]: USER_END pid=1694 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' Jul 2 01:51:21.446000 audit[1694]: CRED_DISP pid=1694 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' Jul 2 01:51:21.519148 kernel: audit: type=1104 audit(1719885081.446:176): pid=1694 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' Jul 2 01:51:21.519313 sshd[1691]: pam_unix(sshd:session): session closed for user core Jul 2 01:51:21.518000 audit[1691]: USER_END pid=1691 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.16.10 addr=10.200.16.10 terminal=ssh res=success' Jul 2 01:51:21.518000 audit[1691]: CRED_DISP pid=1691 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.16.10 addr=10.200.16.10 terminal=ssh res=success' Jul 2 01:51:21.543155 systemd[1]: sshd@5-10.200.20.10:22-10.200.16.10:51096.service: Deactivated successfully. Jul 2 01:51:21.543846 systemd[1]: session-8.scope: Deactivated successfully. Jul 2 01:51:21.544867 systemd-logind[1371]: Session 8 logged out. Waiting for processes to exit. Jul 2 01:51:21.545724 systemd-logind[1371]: Removed session 8. Jul 2 01:51:21.560478 kernel: audit: type=1106 audit(1719885081.518:177): pid=1691 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.16.10 addr=10.200.16.10 terminal=ssh res=success' Jul 2 01:51:21.560516 kernel: audit: type=1104 audit(1719885081.518:178): pid=1691 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.16.10 addr=10.200.16.10 terminal=ssh res=success' Jul 2 01:51:21.560531 kernel: audit: type=1131 audit(1719885081.541:179): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@5-10.200.20.10:22-10.200.16.10:51096 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 01:51:21.541000 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.10:22-10.200.16.10:51096 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 01:51:21.602382 systemd[1]: Started sshd@6-10.200.20.10:22-10.200.16.10:51098.service. Jul 2 01:51:21.600000 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.10:22-10.200.16.10:51098 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 01:51:22.066000 audit[1721]: USER_ACCT pid=1721 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.16.10 addr=10.200.16.10 terminal=ssh res=success' Jul 2 01:51:22.067638 sshd[1721]: Accepted publickey for core from 10.200.16.10 port 51098 ssh2: RSA SHA256:dIfkHgYeMkxYvU2An9TnjkrclLrmoTNY/YaaZP40c9o Jul 2 01:51:22.067000 audit[1721]: CRED_ACQ pid=1721 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.16.10 addr=10.200.16.10 terminal=ssh res=success' Jul 2 01:51:22.067000 audit[1721]: SYSCALL arch=c00000b7 syscall=64 success=yes exit=3 a0=5 a1=ffffc80af7d0 a2=3 a3=1 items=0 ppid=1 pid=1721 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) Jul 2 01:51:22.067000 audit: PROCTITLE proctitle=737368643A20636F7265205B707269765D Jul 2 01:51:22.069119 sshd[1721]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Jul 2 01:51:22.072664 systemd-logind[1371]: New session 9 of user core. Jul 2 01:51:22.073117 systemd[1]: Started session-9.scope. Jul 2 01:51:22.076000 audit[1721]: USER_START pid=1721 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.16.10 addr=10.200.16.10 terminal=ssh res=success' Jul 2 01:51:22.077000 audit[1723]: CRED_ACQ pid=1723 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.16.10 addr=10.200.16.10 terminal=ssh res=success' Jul 2 01:51:22.330000 audit[1724]: USER_ACCT pid=1724 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' Jul 2 01:51:22.331341 sudo[1724]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/blkid -s UUID -o value /dev/disk/by-label/ROOT Jul 2 01:51:22.330000 audit[1724]: CRED_REFR pid=1724 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' Jul 2 01:51:22.331863 sudo[1724]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Jul 2 01:51:22.332000 audit[1724]: USER_START pid=1724 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' Jul 2 01:51:22.432681 sudo[1724]: pam_unix(sudo:session): session closed for user root Jul 2 01:51:22.431000 audit[1724]: USER_END pid=1724 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' Jul 2 01:51:22.431000 audit[1724]: CRED_DISP pid=1724 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' Jul 2 01:51:22.517505 sshd[1721]: pam_unix(sshd:session): session closed for user core Jul 2 01:51:22.517000 audit[1721]: USER_END pid=1721 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.16.10 addr=10.200.16.10 terminal=ssh res=success' Jul 2 01:51:22.517000 audit[1721]: CRED_DISP pid=1721 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.16.10 addr=10.200.16.10 terminal=ssh res=success' Jul 2 01:51:22.518000 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.10:22-10.200.16.10:51098 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 01:51:22.519663 systemd[1]: sshd@6-10.200.20.10:22-10.200.16.10:51098.service: Deactivated successfully. Jul 2 01:51:22.520321 systemd[1]: session-9.scope: Deactivated successfully. Jul 2 01:51:22.520837 systemd-logind[1371]: Session 9 logged out. Waiting for processes to exit. Jul 2 01:51:22.521585 systemd-logind[1371]: Removed session 9. Jul 2 01:51:22.593000 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.10:22-10.200.16.10:51108 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 01:51:22.594134 systemd[1]: Started sshd@7-10.200.20.10:22-10.200.16.10:51108.service. Jul 2 01:51:23.056000 audit[1728]: USER_ACCT pid=1728 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.16.10 addr=10.200.16.10 terminal=ssh res=success' Jul 2 01:51:23.057655 sshd[1728]: Accepted publickey for core from 10.200.16.10 port 51108 ssh2: RSA SHA256:dIfkHgYeMkxYvU2An9TnjkrclLrmoTNY/YaaZP40c9o Jul 2 01:51:23.057000 audit[1728]: CRED_ACQ pid=1728 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.16.10 addr=10.200.16.10 terminal=ssh res=success' Jul 2 01:51:23.057000 audit[1728]: SYSCALL arch=c00000b7 syscall=64 success=yes exit=3 a0=5 a1=fffffc7f16e0 a2=3 a3=1 items=0 ppid=1 pid=1728 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) Jul 2 01:51:23.057000 audit: PROCTITLE proctitle=737368643A20636F7265205B707269765D Jul 2 01:51:23.059137 sshd[1728]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Jul 2 01:51:23.062593 systemd-logind[1371]: New session 10 of user core. Jul 2 01:51:23.063050 systemd[1]: Started session-10.scope. Jul 2 01:51:23.065000 audit[1728]: USER_START pid=1728 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.16.10 addr=10.200.16.10 terminal=ssh res=success' Jul 2 01:51:23.066000 audit[1730]: CRED_ACQ pid=1730 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.16.10 addr=10.200.16.10 terminal=ssh res=success' Jul 2 01:51:23.318000 audit[1731]: USER_ACCT pid=1731 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' Jul 2 01:51:23.319983 sudo[1731]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/blkid -s TYPE -o value /dev/disk/by-label/ROOT Jul 2 01:51:23.319000 audit[1731]: CRED_REFR pid=1731 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' Jul 2 01:51:23.320497 sudo[1731]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Jul 2 01:51:23.320000 audit[1731]: USER_START pid=1731 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' Jul 2 01:51:23.323016 sudo[1731]: pam_unix(sudo:session): session closed for user root Jul 2 01:51:23.322000 audit[1731]: USER_END pid=1731 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' Jul 2 01:51:23.322000 audit[1731]: CRED_DISP pid=1731 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' Jul 2 01:51:23.478000 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.10:22-10.200.16.10:51112 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 01:51:23.479599 systemd[1]: Started sshd@8-10.200.20.10:22-10.200.16.10:51112.service. Jul 2 01:51:23.915000 audit[1734]: USER_ACCT pid=1734 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.16.10 addr=10.200.16.10 terminal=ssh res=success' Jul 2 01:51:23.916809 sshd[1734]: Accepted publickey for core from 10.200.16.10 port 51112 ssh2: RSA SHA256:dIfkHgYeMkxYvU2An9TnjkrclLrmoTNY/YaaZP40c9o Jul 2 01:51:23.917000 audit[1734]: CRED_ACQ pid=1734 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.16.10 addr=10.200.16.10 terminal=ssh res=success' Jul 2 01:51:23.917000 audit[1734]: SYSCALL arch=c00000b7 syscall=64 success=yes exit=3 a0=5 a1=ffffccde39f0 a2=3 a3=1 items=0 ppid=1 pid=1734 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) Jul 2 01:51:23.917000 audit: PROCTITLE proctitle=737368643A20636F7265205B707269765D Jul 2 01:51:25.137823 sshd[1728]: pam_unix(sshd:session): session closed for user core Jul 2 01:51:25.138345 sshd[1734]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Jul 2 01:51:25.138000 audit[1728]: USER_END pid=1728 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.16.10 addr=10.200.16.10 terminal=ssh res=success' Jul 2 01:51:25.138000 audit[1728]: CRED_DISP pid=1728 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.16.10 addr=10.200.16.10 terminal=ssh res=success' Jul 2 01:51:25.139000 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.10:22-10.200.16.10:51108 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 01:51:25.140515 systemd[1]: sshd@7-10.200.20.10:22-10.200.16.10:51108.service: Deactivated successfully. Jul 2 01:51:25.141196 systemd[1]: session-10.scope: Deactivated successfully. Jul 2 01:51:25.141791 systemd-logind[1371]: Session 10 logged out. Waiting for processes to exit. Jul 2 01:51:25.144485 systemd-logind[1371]: New session 11 of user core. Jul 2 01:51:25.144864 systemd[1]: Started session-11.scope. Jul 2 01:51:25.145731 systemd-logind[1371]: Removed session 10. Jul 2 01:51:25.148000 audit[1734]: USER_START pid=1734 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.16.10 addr=10.200.16.10 terminal=ssh res=success' Jul 2 01:51:25.149000 audit[1737]: CRED_ACQ pid=1737 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.16.10 addr=10.200.16.10 terminal=ssh res=success' Jul 2 01:51:25.464314 sshd[1734]: pam_unix(sshd:session): session closed for user core Jul 2 01:51:25.464000 audit[1734]: USER_END pid=1734 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.16.10 addr=10.200.16.10 terminal=ssh res=success' Jul 2 01:51:25.464000 audit[1734]: CRED_DISP pid=1734 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.16.10 addr=10.200.16.10 terminal=ssh res=success' Jul 2 01:51:25.466769 systemd[1]: sshd@8-10.200.20.10:22-10.200.16.10:51112.service: Deactivated successfully. Jul 2 01:51:25.466000 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.10:22-10.200.16.10:51112 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 01:51:25.467435 systemd[1]: session-11.scope: Deactivated successfully. Jul 2 01:51:25.467963 systemd-logind[1371]: Session 11 logged out. Waiting for processes to exit. Jul 2 01:51:25.468727 systemd-logind[1371]: Removed session 11. Jul 2 01:51:30.556544 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:30.571750 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:30.586901 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:30.601540 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:30.616703 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:30.631120 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:30.645948 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:30.666082 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:30.666220 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:30.666321 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:30.666418 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:30.678089 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:30.678234 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:30.694131 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:30.694237 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:30.710511 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:30.710620 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:30.726420 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:30.726616 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:30.742129 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:30.750060 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:30.750159 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:30.765750 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:30.765855 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:30.781665 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:30.789822 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:30.789941 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:30.804964 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:30.805062 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:30.820038 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:30.820196 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:30.835017 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:30.835189 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:30.857921 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:30.858128 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:30.858282 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:30.873215 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:30.873332 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:30.888566 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:30.888668 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:30.903922 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:30.904022 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:30.918905 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:30.919047 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:30.933893 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:30.941156 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:30.941261 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:30.955477 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:30.955667 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:30.977879 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:30.978039 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:30.985371 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:30.985469 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:30.999781 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:30.999910 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:31.014883 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:31.015088 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:31.029901 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:31.037338 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:31.037466 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:31.051586 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:31.051780 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:31.067913 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:31.083809 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:31.083941 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:31.091644 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:31.114091 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:31.121761 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:31.121855 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:31.129278 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:31.147924 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:31.148144 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:31.148245 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:31.148331 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:31.159076 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:31.166899 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:31.167081 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:31.181282 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:31.181472 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:31.195693 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:31.195884 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:31.217979 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:31.218097 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:31.225500 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:31.225597 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:31.240517 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:31.240727 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:31.255580 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:31.255681 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:31.270625 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:31.278482 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:31.278591 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:31.293970 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:31.294086 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:31.312489 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:31.312687 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:31.328804 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:31.336909 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:31.337085 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:31.351307 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:31.351405 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:31.365629 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:31.365733 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:31.379896 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:31.380086 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:31.395206 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:31.395306 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:31.409878 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Jul 2 01:51:31.409988 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001