Jul 12 00:20:51.466605 kernel: SELinux: Class mctp_socket not defined in policy. Jul 12 00:20:51.466625 kernel: SELinux: Class anon_inode not defined in policy. Jul 12 00:20:51.466635 kernel: SELinux: the above unknown classes and permissions will be allowed Jul 12 00:20:51.466645 kernel: SELinux: policy capability network_peer_controls=1 Jul 12 00:20:51.466655 kernel: SELinux: policy capability open_perms=1 Jul 12 00:20:51.466663 kernel: SELinux: policy capability extended_socket_class=1 Jul 12 00:20:51.466672 kernel: SELinux: policy capability always_check_network=0 Jul 12 00:20:51.466680 kernel: SELinux: policy capability cgroup_seclabel=1 Jul 12 00:20:51.466688 kernel: SELinux: policy capability nnp_nosuid_transition=1 Jul 12 00:20:51.466696 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Jul 12 00:20:51.466704 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Jul 12 00:20:51.466714 systemd[1]: Successfully loaded SELinux policy in 248.604ms. Jul 12 00:20:51.466725 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 22.660ms. Jul 12 00:20:51.466735 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 12 00:20:51.466745 systemd[1]: Detected virtualization microsoft. Jul 12 00:20:51.466755 systemd[1]: Detected architecture arm64. Jul 12 00:20:51.466763 systemd[1]: Detected first boot. Jul 12 00:20:51.466773 systemd[1]: Hostname set to . Jul 12 00:20:51.466782 systemd[1]: Initializing machine ID from random generator. Jul 12 00:20:51.466791 kernel: SELinux: Context system_u:object_r:container_file_t:s0:c1022,c1023 is not valid (left unmapped). Jul 12 00:20:51.466800 kernel: kauditd_printk_skb: 46 callbacks suppressed Jul 12 00:20:51.466809 kernel: audit: type=1400 audit(1752279644.778:88): avc: denied { associate } for pid=1110 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 12 00:20:51.466821 kernel: audit: type=1300 audit(1752279644.778:88): arch=c00000b7 syscall=5 success=yes exit=0 a0=400014589c a1=40000c6df8 a2=40000cd0c0 a3=32 items=0 ppid=1093 pid=1110 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 12 00:20:51.466830 kernel: audit: type=1327 audit(1752279644.778:88): proctitle=2F7573722F6C69622F73797374656D642F73797374656D2D67656E657261746F72732F746F7263782D67656E657261746F72002F72756E2F73797374656D642F67656E657261746F72002F72756E2F73797374656D642F67656E657261746F722E6561726C79002F72756E2F73797374656D642F67656E657261746F722E6C61 Jul 12 00:20:51.466840 kernel: audit: type=1400 audit(1752279644.793:89): avc: denied { associate } for pid=1110 comm="torcx-generator" name="bin" scontext=system_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:tmpfs_t:s0 tclass=filesystem permissive=1 Jul 12 00:20:51.466850 kernel: audit: type=1300 audit(1752279644.793:89): arch=c00000b7 syscall=34 success=yes exit=0 a0=ffffffffffffff9c a1=4000145975 a2=1ed a3=0 items=2 ppid=1093 pid=1110 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 12 00:20:51.466859 kernel: audit: type=1307 audit(1752279644.793:89): cwd="/" Jul 12 00:20:51.466869 kernel: audit: type=1302 audit(1752279644.793:89): 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 12 00:20:51.466878 kernel: audit: type=1302 audit(1752279644.793:89): 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 12 00:20:51.466888 kernel: audit: type=1327 audit(1752279644.793:89): proctitle=2F7573722F6C69622F73797374656D642F73797374656D2D67656E657261746F72732F746F7263782D67656E657261746F72002F72756E2F73797374656D642F67656E657261746F72002F72756E2F73797374656D642F67656E657261746F722E6561726C79002F72756E2F73797374656D642F67656E657261746F722E6C61 Jul 12 00:20:51.466897 systemd[1]: Populated /etc with preset unit settings. Jul 12 00:20:51.466906 systemd[1]: /usr/lib/systemd/system/locksmithd.service:8: Unit uses CPUShares=; please use CPUWeight= instead. Support for CPUShares= will be removed soon. Jul 12 00:20:51.466915 systemd[1]: /usr/lib/systemd/system/locksmithd.service:9: Unit uses MemoryLimit=; please use MemoryMax= instead. Support for MemoryLimit= will be removed soon. Jul 12 00:20:51.466926 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 12 00:20:51.466936 kernel: audit: type=1334 audit(1752279650.785:90): prog-id=12 op=LOAD Jul 12 00:20:51.466945 kernel: audit: type=1334 audit(1752279650.785:91): prog-id=3 op=UNLOAD Jul 12 00:20:51.466953 kernel: audit: type=1334 audit(1752279650.792:92): prog-id=13 op=LOAD Jul 12 00:20:51.466961 kernel: audit: type=1334 audit(1752279650.798:93): prog-id=14 op=LOAD Jul 12 00:20:51.466970 kernel: audit: type=1334 audit(1752279650.798:94): prog-id=4 op=UNLOAD Jul 12 00:20:51.466979 kernel: audit: type=1334 audit(1752279650.798:95): prog-id=5 op=UNLOAD Jul 12 00:20:51.466990 systemd[1]: initrd-switch-root.service: Deactivated successfully. Jul 12 00:20:51.466999 kernel: audit: type=1334 audit(1752279650.805:96): prog-id=15 op=LOAD Jul 12 00:20:51.467008 kernel: audit: type=1334 audit(1752279650.805:97): prog-id=12 op=UNLOAD Jul 12 00:20:51.467017 systemd[1]: Stopped initrd-switch-root.service. Jul 12 00:20:51.467027 kernel: audit: type=1334 audit(1752279650.810:98): prog-id=16 op=LOAD Jul 12 00:20:51.467035 kernel: audit: type=1334 audit(1752279650.816:99): prog-id=17 op=LOAD Jul 12 00:20:51.467045 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Jul 12 00:20:51.467054 systemd[1]: Created slice system-addon\x2dconfig.slice. Jul 12 00:20:51.467064 systemd[1]: Created slice system-addon\x2drun.slice. Jul 12 00:20:51.467073 systemd[1]: Created slice system-getty.slice. Jul 12 00:20:51.467083 systemd[1]: Created slice system-modprobe.slice. Jul 12 00:20:51.467093 systemd[1]: Created slice system-serial\x2dgetty.slice. Jul 12 00:20:51.467102 systemd[1]: Created slice system-system\x2dcloudinit.slice. Jul 12 00:20:51.467111 systemd[1]: Created slice system-systemd\x2dfsck.slice. Jul 12 00:20:51.467120 systemd[1]: Created slice user.slice. Jul 12 00:20:51.467129 systemd[1]: Started systemd-ask-password-console.path. Jul 12 00:20:51.467138 systemd[1]: Started systemd-ask-password-wall.path. Jul 12 00:20:51.467148 systemd[1]: Set up automount boot.automount. Jul 12 00:20:51.467157 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount. Jul 12 00:20:51.467167 systemd[1]: Stopped target initrd-switch-root.target. Jul 12 00:20:51.467177 systemd[1]: Stopped target initrd-fs.target. Jul 12 00:20:51.467186 systemd[1]: Stopped target initrd-root-fs.target. Jul 12 00:20:51.467195 systemd[1]: Reached target integritysetup.target. Jul 12 00:20:51.467204 systemd[1]: Reached target remote-cryptsetup.target. Jul 12 00:20:51.467214 systemd[1]: Reached target remote-fs.target. Jul 12 00:20:51.467223 systemd[1]: Reached target slices.target. Jul 12 00:20:51.467233 systemd[1]: Reached target swap.target. Jul 12 00:20:51.467243 systemd[1]: Reached target torcx.target. Jul 12 00:20:51.467252 systemd[1]: Reached target veritysetup.target. Jul 12 00:20:51.467262 systemd[1]: Listening on systemd-coredump.socket. Jul 12 00:20:51.467271 systemd[1]: Listening on systemd-initctl.socket. Jul 12 00:20:51.467280 systemd[1]: Listening on systemd-networkd.socket. Jul 12 00:20:51.467291 systemd[1]: Listening on systemd-udevd-control.socket. Jul 12 00:20:51.467301 systemd[1]: Listening on systemd-udevd-kernel.socket. Jul 12 00:20:51.467310 systemd[1]: Listening on systemd-userdbd.socket. Jul 12 00:20:51.467319 systemd[1]: Mounting dev-hugepages.mount... Jul 12 00:20:51.467328 systemd[1]: Mounting dev-mqueue.mount... Jul 12 00:20:51.467338 systemd[1]: Mounting media.mount... Jul 12 00:20:51.467347 systemd[1]: Mounting sys-kernel-debug.mount... Jul 12 00:20:51.467356 systemd[1]: Mounting sys-kernel-tracing.mount... Jul 12 00:20:51.467367 systemd[1]: Mounting tmp.mount... Jul 12 00:20:51.467377 systemd[1]: Starting flatcar-tmpfiles.service... Jul 12 00:20:51.467386 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. Jul 12 00:20:51.467396 systemd[1]: Starting kmod-static-nodes.service... Jul 12 00:20:51.467413 systemd[1]: Starting modprobe@configfs.service... Jul 12 00:20:51.467425 systemd[1]: Starting modprobe@dm_mod.service... Jul 12 00:20:51.467434 systemd[1]: Starting modprobe@drm.service... Jul 12 00:20:51.467443 systemd[1]: Starting modprobe@efi_pstore.service... Jul 12 00:20:51.467455 systemd[1]: Starting modprobe@fuse.service... Jul 12 00:20:51.467466 systemd[1]: Starting modprobe@loop.service... Jul 12 00:20:51.467475 systemd[1]: setup-nsswitch.service was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Jul 12 00:20:51.467485 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Jul 12 00:20:51.467494 systemd[1]: Stopped systemd-fsck-root.service. Jul 12 00:20:51.467503 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Jul 12 00:20:51.467513 systemd[1]: Stopped systemd-fsck-usr.service. Jul 12 00:20:51.467522 systemd[1]: Stopped systemd-journald.service. Jul 12 00:20:51.467531 systemd[1]: systemd-journald.service: Consumed 3.086s CPU time. Jul 12 00:20:51.467540 systemd[1]: Starting systemd-journald.service... Jul 12 00:20:51.467551 kernel: loop: module loaded Jul 12 00:20:51.467560 systemd[1]: Starting systemd-modules-load.service... Jul 12 00:20:51.467570 systemd[1]: Starting systemd-network-generator.service... Jul 12 00:20:51.467579 systemd[1]: Starting systemd-remount-fs.service... Jul 12 00:20:51.467589 systemd[1]: Starting systemd-udev-trigger.service... Jul 12 00:20:51.467598 kernel: fuse: init (API version 7.34) Jul 12 00:20:51.467607 systemd[1]: verity-setup.service: Deactivated successfully. Jul 12 00:20:51.467616 systemd[1]: Stopped verity-setup.service. Jul 12 00:20:51.467625 systemd[1]: Mounted dev-hugepages.mount. Jul 12 00:20:51.467635 systemd[1]: Mounted dev-mqueue.mount. Jul 12 00:20:51.467645 systemd[1]: Mounted media.mount. Jul 12 00:20:51.467655 systemd[1]: Mounted sys-kernel-debug.mount. Jul 12 00:20:51.467664 systemd[1]: Mounted sys-kernel-tracing.mount. Jul 12 00:20:51.467673 systemd[1]: Mounted tmp.mount. Jul 12 00:20:51.467683 systemd[1]: Finished flatcar-tmpfiles.service. Jul 12 00:20:51.467695 systemd-journald[1216]: Journal started Jul 12 00:20:51.467733 systemd-journald[1216]: Runtime Journal (/run/log/journal/5a5e5d588296464281ffd04349921f81) is 8.0M, max 78.5M, 70.5M free. Jul 12 00:20:42.989000 audit: MAC_POLICY_LOAD auid=4294967295 ses=4294967295 lsm=selinux res=1 Jul 12 00:20:43.671000 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 12 00:20:43.671000 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 12 00:20:43.671000 audit: BPF prog-id=10 op=LOAD Jul 12 00:20:43.671000 audit: BPF prog-id=10 op=UNLOAD Jul 12 00:20:43.671000 audit: BPF prog-id=11 op=LOAD Jul 12 00:20:43.671000 audit: BPF prog-id=11 op=UNLOAD Jul 12 00:20:44.778000 audit[1110]: AVC avc: denied { associate } for pid=1110 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 12 00:20:44.778000 audit[1110]: SYSCALL arch=c00000b7 syscall=5 success=yes exit=0 a0=400014589c a1=40000c6df8 a2=40000cd0c0 a3=32 items=0 ppid=1093 pid=1110 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 12 00:20:44.778000 audit: PROCTITLE proctitle=2F7573722F6C69622F73797374656D642F73797374656D2D67656E657261746F72732F746F7263782D67656E657261746F72002F72756E2F73797374656D642F67656E657261746F72002F72756E2F73797374656D642F67656E657261746F722E6561726C79002F72756E2F73797374656D642F67656E657261746F722E6C61 Jul 12 00:20:44.793000 audit[1110]: AVC avc: denied { associate } for pid=1110 comm="torcx-generator" name="bin" scontext=system_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:tmpfs_t:s0 tclass=filesystem permissive=1 Jul 12 00:20:44.793000 audit[1110]: SYSCALL arch=c00000b7 syscall=34 success=yes exit=0 a0=ffffffffffffff9c a1=4000145975 a2=1ed a3=0 items=2 ppid=1093 pid=1110 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 12 00:20:44.793000 audit: CWD cwd="/" Jul 12 00:20:44.793000 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 12 00:20:44.793000 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 12 00:20:44.793000 audit: PROCTITLE proctitle=2F7573722F6C69622F73797374656D642F73797374656D2D67656E657261746F72732F746F7263782D67656E657261746F72002F72756E2F73797374656D642F67656E657261746F72002F72756E2F73797374656D642F67656E657261746F722E6561726C79002F72756E2F73797374656D642F67656E657261746F722E6C61 Jul 12 00:20:50.785000 audit: BPF prog-id=12 op=LOAD Jul 12 00:20:50.785000 audit: BPF prog-id=3 op=UNLOAD Jul 12 00:20:50.792000 audit: BPF prog-id=13 op=LOAD Jul 12 00:20:50.798000 audit: BPF prog-id=14 op=LOAD Jul 12 00:20:50.798000 audit: BPF prog-id=4 op=UNLOAD Jul 12 00:20:50.798000 audit: BPF prog-id=5 op=UNLOAD Jul 12 00:20:50.805000 audit: BPF prog-id=15 op=LOAD Jul 12 00:20:50.805000 audit: BPF prog-id=12 op=UNLOAD Jul 12 00:20:50.810000 audit: BPF prog-id=16 op=LOAD Jul 12 00:20:50.816000 audit: BPF prog-id=17 op=LOAD Jul 12 00:20:50.816000 audit: BPF prog-id=13 op=UNLOAD Jul 12 00:20:50.816000 audit: BPF prog-id=14 op=UNLOAD Jul 12 00:20:50.817000 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 12 00:20:50.848000 audit: BPF prog-id=15 op=UNLOAD Jul 12 00:20:50.861000 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 12 00:20:50.861000 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 12 00:20:51.312000 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 12 00:20:51.328000 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 12 00:20:51.337000 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 12 00:20:51.337000 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 12 00:20:51.343000 audit: BPF prog-id=18 op=LOAD Jul 12 00:20:51.343000 audit: BPF prog-id=19 op=LOAD Jul 12 00:20:51.343000 audit: BPF prog-id=20 op=LOAD Jul 12 00:20:51.343000 audit: BPF prog-id=16 op=UNLOAD Jul 12 00:20:51.343000 audit: BPF prog-id=17 op=UNLOAD Jul 12 00:20:51.413000 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 12 00:20:51.463000 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 12 00:20:51.463000 audit[1216]: SYSCALL arch=c00000b7 syscall=211 success=yes exit=60 a0=3 a1=fffffd196d10 a2=4000 a3=1 items=0 ppid=1 pid=1216 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 12 00:20:51.463000 audit: PROCTITLE proctitle="/usr/lib/systemd/systemd-journald" Jul 12 00:20:50.784363 systemd[1]: Queued start job for default target multi-user.target. Jul 12 00:20:44.748706 /usr/lib/systemd/system-generators/torcx-generator[1110]: time="2025-07-12T00:20:44Z" 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.7 /usr/share/oem/torcx/store /var/lib/torcx/store/3510.3.7 /var/lib/torcx/store]" Jul 12 00:20:50.784374 systemd[1]: Unnecessary job was removed for dev-sda6.device. Jul 12 00:20:44.748980 /usr/lib/systemd/system-generators/torcx-generator[1110]: time="2025-07-12T00:20:44Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Jul 12 00:20:50.817610 systemd[1]: systemd-journald.service: Deactivated successfully. Jul 12 00:20:44.748999 /usr/lib/systemd/system-generators/torcx-generator[1110]: time="2025-07-12T00:20:44Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Jul 12 00:20:50.817979 systemd[1]: systemd-journald.service: Consumed 3.086s CPU time. Jul 12 00:20:44.749032 /usr/lib/systemd/system-generators/torcx-generator[1110]: time="2025-07-12T00:20:44Z" level=info msg="no vendor profile selected by /etc/flatcar/docker-1.12" Jul 12 00:20:44.749042 /usr/lib/systemd/system-generators/torcx-generator[1110]: time="2025-07-12T00:20:44Z" level=debug msg="skipped missing lower profile" missing profile=oem Jul 12 00:20:44.749073 /usr/lib/systemd/system-generators/torcx-generator[1110]: time="2025-07-12T00:20:44Z" level=warning msg="no next profile: unable to read profile file: open /etc/torcx/next-profile: no such file or directory" Jul 12 00:20:44.749084 /usr/lib/systemd/system-generators/torcx-generator[1110]: time="2025-07-12T00:20:44Z" level=debug msg="apply configuration parsed" lower profiles (vendor/oem)="[vendor]" upper profile (user)= Jul 12 00:20:44.749241 /usr/lib/systemd/system-generators/torcx-generator[1110]: time="2025-07-12T00:20:44Z" level=debug msg="mounted tmpfs" target=/run/torcx/unpack Jul 12 00:20:44.749272 /usr/lib/systemd/system-generators/torcx-generator[1110]: time="2025-07-12T00:20:44Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Jul 12 00:20:44.749283 /usr/lib/systemd/system-generators/torcx-generator[1110]: time="2025-07-12T00:20:44Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Jul 12 00:20:44.763684 /usr/lib/systemd/system-generators/torcx-generator[1110]: time="2025-07-12T00:20:44Z" 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 12 00:20:44.763723 /usr/lib/systemd/system-generators/torcx-generator[1110]: time="2025-07-12T00:20:44Z" 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 12 00:20:44.763742 /usr/lib/systemd/system-generators/torcx-generator[1110]: time="2025-07-12T00:20:44Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store/3510.3.7: no such file or directory" path=/usr/share/oem/torcx/store/3510.3.7 Jul 12 00:20:44.763756 /usr/lib/systemd/system-generators/torcx-generator[1110]: time="2025-07-12T00:20:44Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store: no such file or directory" path=/usr/share/oem/torcx/store Jul 12 00:20:44.763777 /usr/lib/systemd/system-generators/torcx-generator[1110]: time="2025-07-12T00:20:44Z" level=info msg="store skipped" err="open /var/lib/torcx/store/3510.3.7: no such file or directory" path=/var/lib/torcx/store/3510.3.7 Jul 12 00:20:44.763789 /usr/lib/systemd/system-generators/torcx-generator[1110]: time="2025-07-12T00:20:44Z" level=info msg="store skipped" err="open /var/lib/torcx/store: no such file or directory" path=/var/lib/torcx/store Jul 12 00:20:49.992166 /usr/lib/systemd/system-generators/torcx-generator[1110]: time="2025-07-12T00:20:49Z" level=debug msg="image unpacked" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Jul 12 00:20:49.992451 /usr/lib/systemd/system-generators/torcx-generator[1110]: time="2025-07-12T00:20:49Z" 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 12 00:20:49.992558 /usr/lib/systemd/system-generators/torcx-generator[1110]: time="2025-07-12T00:20:49Z" 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 12 00:20:49.992723 /usr/lib/systemd/system-generators/torcx-generator[1110]: time="2025-07-12T00:20:49Z" 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 12 00:20:49.992773 /usr/lib/systemd/system-generators/torcx-generator[1110]: time="2025-07-12T00:20:49Z" level=debug msg="profile applied" sealed profile=/run/torcx/profile.json upper profile= Jul 12 00:20:49.992827 /usr/lib/systemd/system-generators/torcx-generator[1110]: time="2025-07-12T00:20:49Z" 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 12 00:20:51.474000 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 12 00:20:51.484528 systemd[1]: Started systemd-journald.service. Jul 12 00:20:51.483000 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 12 00:20:51.484755 systemd[1]: Finished kmod-static-nodes.service. Jul 12 00:20:51.488000 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 12 00:20:51.489200 systemd[1]: modprobe@configfs.service: Deactivated successfully. Jul 12 00:20:51.489379 systemd[1]: Finished modprobe@configfs.service. Jul 12 00:20:51.493000 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 12 00:20:51.493000 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 12 00:20:51.493997 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 12 00:20:51.494149 systemd[1]: Finished modprobe@dm_mod.service. Jul 12 00:20:51.498000 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 12 00:20:51.498000 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 12 00:20:51.498691 systemd[1]: modprobe@drm.service: Deactivated successfully. Jul 12 00:20:51.498806 systemd[1]: Finished modprobe@drm.service. Jul 12 00:20:51.502000 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 12 00:20:51.502000 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 12 00:20:51.503152 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 12 00:20:51.503294 systemd[1]: Finished modprobe@efi_pstore.service. Jul 12 00:20:51.507000 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 12 00:20:51.507000 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 12 00:20:51.507959 systemd[1]: modprobe@fuse.service: Deactivated successfully. Jul 12 00:20:51.508074 systemd[1]: Finished modprobe@fuse.service. Jul 12 00:20:51.512000 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 12 00:20:51.512000 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 12 00:20:51.512921 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 12 00:20:51.513063 systemd[1]: Finished modprobe@loop.service. Jul 12 00:20:51.516000 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 12 00:20:51.516000 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 12 00:20:51.517384 systemd[1]: Finished systemd-modules-load.service. Jul 12 00:20:51.521000 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 12 00:20:51.521751 systemd[1]: Finished systemd-network-generator.service. Jul 12 00:20:51.526000 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 12 00:20:51.526857 systemd[1]: Finished systemd-remount-fs.service. Jul 12 00:20:51.530000 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 12 00:20:51.531377 systemd[1]: Finished systemd-udev-trigger.service. Jul 12 00:20:51.535000 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 12 00:20:51.536592 systemd[1]: Reached target network-pre.target. Jul 12 00:20:51.542926 systemd[1]: Mounting sys-fs-fuse-connections.mount... Jul 12 00:20:51.548452 systemd[1]: Mounting sys-kernel-config.mount... Jul 12 00:20:51.552732 systemd[1]: remount-root.service was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Jul 12 00:20:51.567727 systemd[1]: Starting systemd-hwdb-update.service... Jul 12 00:20:51.573060 systemd[1]: Starting systemd-journal-flush.service... Jul 12 00:20:51.578111 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jul 12 00:20:51.579041 systemd[1]: Starting systemd-random-seed.service... Jul 12 00:20:51.583751 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met. Jul 12 00:20:51.584747 systemd[1]: Starting systemd-sysctl.service... Jul 12 00:20:51.590058 systemd[1]: Starting systemd-sysusers.service... Jul 12 00:20:51.595504 systemd[1]: Starting systemd-udev-settle.service... Jul 12 00:20:51.601809 systemd[1]: Mounted sys-fs-fuse-connections.mount. Jul 12 00:20:51.607218 systemd[1]: Mounted sys-kernel-config.mount. Jul 12 00:20:51.614780 udevadm[1230]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Jul 12 00:20:51.620158 systemd[1]: Finished systemd-random-seed.service. Jul 12 00:20:51.624000 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 12 00:20:51.625457 systemd[1]: Reached target first-boot-complete.target. Jul 12 00:20:51.641662 systemd-journald[1216]: Runtime Journal (/run/log/journal/5a5e5d588296464281ffd04349921f81) is 8.0M, max 78.5M, 70.5M free. Jul 12 00:20:51.650539 systemd-journald[1216]: Received client request to flush runtime journal. Jul 12 00:20:51.651486 systemd[1]: Finished systemd-journal-flush.service. Jul 12 00:20:51.656000 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 12 00:20:51.685724 systemd[1]: Finished systemd-sysctl.service. Jul 12 00:20:51.690000 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 12 00:20:52.040891 systemd[1]: Finished systemd-sysusers.service. Jul 12 00:20:52.045000 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 12 00:20:52.762169 systemd[1]: Finished systemd-hwdb-update.service. Jul 12 00:20:52.767000 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 12 00:20:52.767000 audit: BPF prog-id=21 op=LOAD Jul 12 00:20:52.767000 audit: BPF prog-id=22 op=LOAD Jul 12 00:20:52.767000 audit: BPF prog-id=7 op=UNLOAD Jul 12 00:20:52.767000 audit: BPF prog-id=8 op=UNLOAD Jul 12 00:20:52.768849 systemd[1]: Starting systemd-udevd.service... Jul 12 00:20:52.786295 systemd-udevd[1232]: Using default interface naming scheme 'v252'. Jul 12 00:20:52.950699 systemd[1]: Started systemd-udevd.service. Jul 12 00:20:52.959000 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 12 00:20:52.960000 audit: BPF prog-id=23 op=LOAD Jul 12 00:20:52.963893 systemd[1]: Starting systemd-networkd.service... Jul 12 00:20:52.989661 systemd[1]: Condition check resulted in dev-ttyAMA0.device being skipped. Jul 12 00:20:53.009000 audit: BPF prog-id=24 op=LOAD Jul 12 00:20:53.009000 audit: BPF prog-id=25 op=LOAD Jul 12 00:20:53.009000 audit: BPF prog-id=26 op=LOAD Jul 12 00:20:53.010790 systemd[1]: Starting systemd-userdbd.service... Jul 12 00:20:53.034440 kernel: mousedev: PS/2 mouse device common for all mice Jul 12 00:20:53.060184 systemd[1]: Started systemd-userdbd.service. Jul 12 00:20:53.064000 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 12 00:20:53.100677 kernel: hv_vmbus: registering driver hyperv_fb Jul 12 00:20:53.100756 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Jul 12 00:20:53.100770 kernel: hv_vmbus: registering driver hv_balloon Jul 12 00:20:53.082000 audit[1252]: AVC avc: denied { confidentiality } for pid=1252 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 12 00:20:53.116114 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Jul 12 00:20:53.116165 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Jul 12 00:20:53.120427 kernel: hv_balloon: Memory hot add disabled on ARM64 Jul 12 00:20:53.127056 kernel: Console: switching to colour dummy device 80x25 Jul 12 00:20:53.132433 kernel: hv_utils: Registering HyperV Utility Driver Jul 12 00:20:53.132548 kernel: hv_vmbus: registering driver hv_utils Jul 12 00:20:53.133450 kernel: hv_utils: Heartbeat IC version 3.0 Jul 12 00:20:53.133480 kernel: hv_utils: Shutdown IC version 3.2 Jul 12 00:20:53.133493 kernel: hv_utils: TimeSync IC version 4.0 Jul 12 00:20:53.381794 kernel: Console: switching to colour frame buffer device 128x48 Jul 12 00:20:53.082000 audit[1252]: SYSCALL arch=c00000b7 syscall=105 success=yes exit=0 a0=aaab088c6580 a1=aa2c a2=ffff8d6724b0 a3=aaab0881d010 items=12 ppid=1232 pid=1252 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 12 00:20:53.082000 audit: CWD cwd="/" Jul 12 00:20:53.082000 audit: PATH item=0 name=(null) inode=6914 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 12 00:20:53.082000 audit: PATH item=1 name=(null) inode=11466 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 12 00:20:53.082000 audit: PATH item=2 name=(null) inode=11466 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 12 00:20:53.082000 audit: PATH item=3 name=(null) inode=11467 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 12 00:20:53.082000 audit: PATH item=4 name=(null) inode=11466 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 12 00:20:53.082000 audit: PATH item=5 name=(null) inode=11468 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 12 00:20:53.082000 audit: PATH item=6 name=(null) inode=11466 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 12 00:20:53.082000 audit: PATH item=7 name=(null) inode=11469 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 12 00:20:53.082000 audit: PATH item=8 name=(null) inode=11466 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 12 00:20:53.082000 audit: PATH item=9 name=(null) inode=11470 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 12 00:20:53.082000 audit: PATH item=10 name=(null) inode=11466 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 12 00:20:53.082000 audit: PATH item=11 name=(null) inode=11471 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 12 00:20:53.082000 audit: PROCTITLE proctitle="(udev-worker)" Jul 12 00:20:53.544045 systemd-networkd[1253]: lo: Link UP Jul 12 00:20:53.544056 systemd-networkd[1253]: lo: Gained carrier Jul 12 00:20:53.544498 systemd-networkd[1253]: Enumeration completed Jul 12 00:20:53.544600 systemd[1]: Started systemd-networkd.service. Jul 12 00:20:53.548000 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 12 00:20:53.550398 systemd[1]: Starting systemd-networkd-wait-online.service... Jul 12 00:20:53.571696 systemd-networkd[1253]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jul 12 00:20:53.597645 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device. Jul 12 00:20:53.607000 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 12 00:20:53.603299 systemd[1]: Finished systemd-udev-settle.service. Jul 12 00:20:53.609431 systemd[1]: Starting lvm2-activation-early.service... Jul 12 00:20:53.626816 kernel: mlx5_core a6e9:00:02.0 enP42729s1: Link up Jul 12 00:20:53.669767 kernel: hv_netvsc 0022487a-5375-0022-487a-53750022487a eth0: Data path switched to VF: enP42729s1 Jul 12 00:20:53.671077 systemd-networkd[1253]: enP42729s1: Link UP Jul 12 00:20:53.671444 systemd-networkd[1253]: eth0: Link UP Jul 12 00:20:53.671447 systemd-networkd[1253]: eth0: Gained carrier Jul 12 00:20:53.676240 systemd-networkd[1253]: enP42729s1: Gained carrier Jul 12 00:20:53.687864 systemd-networkd[1253]: eth0: DHCPv4 address 10.200.20.19/24, gateway 10.200.20.1 acquired from 168.63.129.16 Jul 12 00:20:53.911362 lvm[1311]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Jul 12 00:20:53.949597 systemd[1]: Finished lvm2-activation-early.service. Jul 12 00:20:53.954000 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 12 00:20:53.955145 systemd[1]: Reached target cryptsetup.target. Jul 12 00:20:53.960559 systemd[1]: Starting lvm2-activation.service... Jul 12 00:20:53.964724 lvm[1312]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Jul 12 00:20:53.987705 systemd[1]: Finished lvm2-activation.service. Jul 12 00:20:53.991000 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 12 00:20:53.992269 systemd[1]: Reached target local-fs-pre.target. Jul 12 00:20:53.996863 systemd[1]: var-lib-machines.mount was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Jul 12 00:20:53.996893 systemd[1]: Reached target local-fs.target. Jul 12 00:20:54.001166 systemd[1]: Reached target machines.target. Jul 12 00:20:54.007115 systemd[1]: Starting ldconfig.service... Jul 12 00:20:54.024875 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. Jul 12 00:20:54.024962 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 12 00:20:54.026096 systemd[1]: Starting systemd-boot-update.service... Jul 12 00:20:54.031415 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service... Jul 12 00:20:54.038086 systemd[1]: Starting systemd-machine-id-commit.service... Jul 12 00:20:54.042837 systemd[1]: systemd-sysext.service was skipped because no trigger condition checks were met. Jul 12 00:20:54.042898 systemd[1]: ensure-sysext.service was skipped because no trigger condition checks were met. Jul 12 00:20:54.044020 systemd[1]: Starting systemd-tmpfiles-setup.service... Jul 12 00:20:54.062367 systemd[1]: boot.automount: Got automount request for /boot, triggered by 1314 (bootctl) Jul 12 00:20:54.063561 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service... Jul 12 00:20:54.108078 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Jul 12 00:20:54.108637 systemd[1]: Finished systemd-machine-id-commit.service. Jul 12 00:20:54.113000 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 12 00:20:54.138027 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service. Jul 12 00:20:54.143000 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 12 00:20:54.218683 systemd-tmpfiles[1317]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. Jul 12 00:20:54.295961 systemd-tmpfiles[1317]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Jul 12 00:20:54.301753 systemd-fsck[1323]: fsck.fat 4.2 (2021-01-31) Jul 12 00:20:54.301753 systemd-fsck[1323]: /dev/sda1: 236 files, 117310/258078 clusters Jul 12 00:20:54.303518 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service. Jul 12 00:20:54.310000 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 12 00:20:54.313659 systemd[1]: Mounting boot.mount... Jul 12 00:20:54.324649 systemd[1]: Mounted boot.mount. Jul 12 00:20:54.334526 systemd[1]: Finished systemd-boot-update.service. Jul 12 00:20:54.339000 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 12 00:20:54.402073 systemd-tmpfiles[1317]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Jul 12 00:20:54.486015 systemd-tmpfiles[1317]: Failed to adjust quota for subvolume "/srv": Bad file descriptor Jul 12 00:20:54.509231 systemd-tmpfiles[1317]: Failed to adjust quota for subvolume "/var/lib/portables": Bad file descriptor Jul 12 00:20:54.537674 systemd-tmpfiles[1317]: Failed to adjust quota for subvolume "/var/lib/machines": Bad file descriptor Jul 12 00:20:54.545018 systemd-tmpfiles[1317]: Failed to adjust quota for subvolume "/var/tmp": Bad file descriptor Jul 12 00:20:54.572817 systemd[1]: Finished systemd-tmpfiles-setup.service. Jul 12 00:20:54.577000 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 12 00:20:54.578923 systemd[1]: Starting audit-rules.service... Jul 12 00:20:54.583845 systemd[1]: Starting clean-ca-certificates.service... Jul 12 00:20:54.589020 systemd[1]: Starting systemd-journal-catalog-update.service... Jul 12 00:20:54.594000 audit: BPF prog-id=27 op=LOAD Jul 12 00:20:54.595770 systemd[1]: Starting systemd-resolved.service... Jul 12 00:20:54.599000 audit: BPF prog-id=28 op=LOAD Jul 12 00:20:54.601441 systemd[1]: Starting systemd-timesyncd.service... Jul 12 00:20:54.606357 systemd[1]: Starting systemd-update-utmp.service... Jul 12 00:20:54.636000 audit[1335]: SYSTEM_BOOT pid=1335 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 12 00:20:54.640107 systemd[1]: Finished systemd-update-utmp.service. Jul 12 00:20:54.645000 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 12 00:20:54.663641 systemd[1]: Finished clean-ca-certificates.service. Jul 12 00:20:54.668000 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 12 00:20:54.669311 systemd[1]: update-ca-certificates.service was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Jul 12 00:20:54.686770 systemd[1]: Started systemd-timesyncd.service. Jul 12 00:20:54.691000 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 12 00:20:54.692073 systemd[1]: Reached target time-set.target. Jul 12 00:20:54.762693 systemd-resolved[1333]: Positive Trust Anchors: Jul 12 00:20:54.762710 systemd-resolved[1333]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jul 12 00:20:54.762758 systemd-resolved[1333]: 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 12 00:20:54.784231 systemd[1]: Finished systemd-journal-catalog-update.service. Jul 12 00:20:54.789000 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 12 00:20:54.822490 systemd-resolved[1333]: Using system hostname 'ci-3510.3.7-n-6ae6ff2d31'. Jul 12 00:20:54.824023 systemd[1]: Started systemd-resolved.service. Jul 12 00:20:54.828000 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 12 00:20:54.829134 systemd[1]: Reached target network.target. Jul 12 00:20:54.833953 systemd[1]: Reached target nss-lookup.target. Jul 12 00:20:55.021328 systemd-timesyncd[1334]: Contacted time server 206.226.67.233:123 (0.flatcar.pool.ntp.org). Jul 12 00:20:55.021641 systemd-timesyncd[1334]: Initial clock synchronization to Sat 2025-07-12 00:20:55.018907 UTC. Jul 12 00:20:55.324000 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 12 00:20:55.324000 audit[1350]: SYSCALL arch=c00000b7 syscall=206 success=yes exit=1056 a0=3 a1=ffffec540560 a2=420 a3=0 items=0 ppid=1329 pid=1350 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 12 00:20:55.324000 audit: PROCTITLE proctitle=2F7362696E2F617564697463746C002D52002F6574632F61756469742F61756469742E72756C6573 Jul 12 00:20:55.325670 augenrules[1350]: No rules Jul 12 00:20:55.326359 systemd[1]: Finished audit-rules.service. Jul 12 00:20:55.569873 systemd-networkd[1253]: eth0: Gained IPv6LL Jul 12 00:20:55.571514 systemd[1]: Finished systemd-networkd-wait-online.service. Jul 12 00:20:55.577174 systemd[1]: Reached target network-online.target. Jul 12 00:21:01.079843 ldconfig[1313]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Jul 12 00:21:01.094146 systemd[1]: Finished ldconfig.service. Jul 12 00:21:01.099996 systemd[1]: Starting systemd-update-done.service... Jul 12 00:21:01.138407 systemd[1]: Finished systemd-update-done.service. Jul 12 00:21:01.143249 systemd[1]: Reached target sysinit.target. Jul 12 00:21:01.147528 systemd[1]: Started motdgen.path. Jul 12 00:21:01.151605 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path. Jul 12 00:21:01.157665 systemd[1]: Started logrotate.timer. Jul 12 00:21:01.161529 systemd[1]: Started mdadm.timer. Jul 12 00:21:01.165334 systemd[1]: Started systemd-tmpfiles-clean.timer. Jul 12 00:21:01.170102 systemd[1]: update-engine-stub.timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Jul 12 00:21:01.170126 systemd[1]: Reached target paths.target. Jul 12 00:21:01.174232 systemd[1]: Reached target timers.target. Jul 12 00:21:01.178893 systemd[1]: Listening on dbus.socket. Jul 12 00:21:01.184015 systemd[1]: Starting docker.socket... Jul 12 00:21:01.189396 systemd[1]: Listening on sshd.socket. Jul 12 00:21:01.193510 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 12 00:21:01.193924 systemd[1]: Listening on docker.socket. Jul 12 00:21:01.198104 systemd[1]: Reached target sockets.target. Jul 12 00:21:01.202258 systemd[1]: Reached target basic.target. Jul 12 00:21:01.206232 systemd[1]: addon-config@usr-share-oem.service was skipped because no trigger condition checks were met. Jul 12 00:21:01.206257 systemd[1]: addon-run@usr-share-oem.service was skipped because no trigger condition checks were met. Jul 12 00:21:01.207167 systemd[1]: Starting containerd.service... Jul 12 00:21:01.211533 systemd[1]: Starting dbus.service... Jul 12 00:21:01.216184 systemd[1]: Starting enable-oem-cloudinit.service... Jul 12 00:21:01.221540 systemd[1]: Starting extend-filesystems.service... Jul 12 00:21:01.225654 systemd[1]: flatcar-setup-environment.service was skipped because of an unmet condition check (ConditionPathExists=/usr/share/oem/bin/flatcar-setup-environment). Jul 12 00:21:01.226639 systemd[1]: Starting motdgen.service... Jul 12 00:21:01.231028 systemd[1]: Started nvidia.service. Jul 12 00:21:01.236132 systemd[1]: Starting ssh-key-proc-cmdline.service... Jul 12 00:21:01.241387 systemd[1]: Starting sshd-keygen.service... Jul 12 00:21:01.247250 systemd[1]: Starting systemd-logind.service... Jul 12 00:21:01.251910 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 12 00:21:01.251968 systemd[1]: tcsd.service was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Jul 12 00:21:01.252353 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Jul 12 00:21:01.252984 systemd[1]: Starting update-engine.service... Jul 12 00:21:01.257756 systemd[1]: Starting update-ssh-keys-after-ignition.service... Jul 12 00:21:01.265640 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Jul 12 00:21:01.265844 systemd[1]: Finished ssh-key-proc-cmdline.service. Jul 12 00:21:01.286050 systemd[1]: motdgen.service: Deactivated successfully. Jul 12 00:21:01.286211 systemd[1]: Finished motdgen.service. Jul 12 00:21:01.298087 extend-filesystems[1361]: Found sda Jul 12 00:21:01.302557 extend-filesystems[1361]: Found sda1 Jul 12 00:21:01.302557 extend-filesystems[1361]: Found sda2 Jul 12 00:21:01.302557 extend-filesystems[1361]: Found sda3 Jul 12 00:21:01.302557 extend-filesystems[1361]: Found usr Jul 12 00:21:01.302557 extend-filesystems[1361]: Found sda4 Jul 12 00:21:01.302557 extend-filesystems[1361]: Found sda6 Jul 12 00:21:01.302557 extend-filesystems[1361]: Found sda7 Jul 12 00:21:01.302557 extend-filesystems[1361]: Found sda9 Jul 12 00:21:01.302557 extend-filesystems[1361]: Checking size of /dev/sda9 Jul 12 00:21:01.325077 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Jul 12 00:21:01.344482 jq[1360]: false Jul 12 00:21:01.344550 jq[1377]: true Jul 12 00:21:01.325239 systemd[1]: Condition check resulted in enable-oem-cloudinit.service being skipped. Jul 12 00:21:01.347315 systemd-logind[1374]: Watching system buttons on /dev/input/event1 (AT Translated Set 2 keyboard) Jul 12 00:21:01.347492 systemd-logind[1374]: New seat seat0. Jul 12 00:21:01.361160 jq[1393]: true Jul 12 00:21:01.361275 env[1383]: time="2025-07-12T00:21:01.356447673Z" level=info msg="starting containerd" revision=92b3a9d6f1b3bcc6dc74875cfdea653fe39f09c2 version=1.6.16 Jul 12 00:21:01.395253 extend-filesystems[1361]: Old size kept for /dev/sda9 Jul 12 00:21:01.395253 extend-filesystems[1361]: Found sr0 Jul 12 00:21:01.400334 systemd[1]: extend-filesystems.service: Deactivated successfully. Jul 12 00:21:01.400506 systemd[1]: Finished extend-filesystems.service. Jul 12 00:21:01.419012 env[1383]: time="2025-07-12T00:21:01.418856356Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Jul 12 00:21:01.419012 env[1383]: time="2025-07-12T00:21:01.419003581Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Jul 12 00:21:01.422599 env[1383]: time="2025-07-12T00:21:01.422372873Z" 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.186-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Jul 12 00:21:01.422599 env[1383]: time="2025-07-12T00:21:01.422511339Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Jul 12 00:21:01.423530 env[1383]: time="2025-07-12T00:21:01.422913058Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Jul 12 00:21:01.423530 env[1383]: time="2025-07-12T00:21:01.422938415Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured" Jul 12 00:21:01.423530 env[1383]: time="2025-07-12T00:21:01.422948974Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Jul 12 00:21:01.423530 env[1383]: time="2025-07-12T00:21:01.422993849Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Jul 12 00:21:01.423530 env[1383]: time="2025-07-12T00:21:01.423195189Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Jul 12 00:21:01.423530 env[1383]: time="2025-07-12T00:21:01.423301018Z" 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 12 00:21:01.423530 env[1383]: time="2025-07-12T00:21:01.423315136Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Jul 12 00:21:01.423530 env[1383]: time="2025-07-12T00:21:01.423405767Z" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured" Jul 12 00:21:01.423530 env[1383]: time="2025-07-12T00:21:01.423416886Z" level=info msg="metadata content store policy set" policy=shared Jul 12 00:21:01.448592 env[1383]: time="2025-07-12T00:21:01.447343178Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Jul 12 00:21:01.448592 env[1383]: time="2025-07-12T00:21:01.447416890Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Jul 12 00:21:01.448592 env[1383]: time="2025-07-12T00:21:01.447432009Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Jul 12 00:21:01.448592 env[1383]: time="2025-07-12T00:21:01.447467485Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Jul 12 00:21:01.448592 env[1383]: time="2025-07-12T00:21:01.447531918Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Jul 12 00:21:01.448592 env[1383]: time="2025-07-12T00:21:01.447547557Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Jul 12 00:21:01.448592 env[1383]: time="2025-07-12T00:21:01.447570554Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Jul 12 00:21:01.448592 env[1383]: time="2025-07-12T00:21:01.447958874Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Jul 12 00:21:01.448592 env[1383]: time="2025-07-12T00:21:01.447986871Z" level=info msg="loading plugin \"io.containerd.service.v1.leases-service\"..." type=io.containerd.service.v1 Jul 12 00:21:01.448592 env[1383]: time="2025-07-12T00:21:01.447999950Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Jul 12 00:21:01.448592 env[1383]: time="2025-07-12T00:21:01.448012149Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Jul 12 00:21:01.448592 env[1383]: time="2025-07-12T00:21:01.448025947Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Jul 12 00:21:01.448592 env[1383]: time="2025-07-12T00:21:01.448146735Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Jul 12 00:21:01.448592 env[1383]: time="2025-07-12T00:21:01.448228567Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Jul 12 00:21:01.448893 env[1383]: time="2025-07-12T00:21:01.448512377Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Jul 12 00:21:01.448893 env[1383]: time="2025-07-12T00:21:01.448540174Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Jul 12 00:21:01.448893 env[1383]: time="2025-07-12T00:21:01.448553813Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Jul 12 00:21:01.449183 env[1383]: time="2025-07-12T00:21:01.448987728Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Jul 12 00:21:01.449183 env[1383]: time="2025-07-12T00:21:01.449010366Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Jul 12 00:21:01.449183 env[1383]: time="2025-07-12T00:21:01.449076719Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Jul 12 00:21:01.449183 env[1383]: time="2025-07-12T00:21:01.449091398Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Jul 12 00:21:01.449183 env[1383]: time="2025-07-12T00:21:01.449114755Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Jul 12 00:21:01.449183 env[1383]: time="2025-07-12T00:21:01.449127714Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Jul 12 00:21:01.449183 env[1383]: time="2025-07-12T00:21:01.449139193Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Jul 12 00:21:01.449183 env[1383]: time="2025-07-12T00:21:01.449152751Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Jul 12 00:21:01.449183 env[1383]: time="2025-07-12T00:21:01.449166110Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Jul 12 00:21:01.449478 env[1383]: time="2025-07-12T00:21:01.449460439Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Jul 12 00:21:01.449556 env[1383]: time="2025-07-12T00:21:01.449543511Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Jul 12 00:21:01.449622 env[1383]: time="2025-07-12T00:21:01.449598385Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Jul 12 00:21:01.449673 env[1383]: time="2025-07-12T00:21:01.449661579Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Jul 12 00:21:01.449752 env[1383]: time="2025-07-12T00:21:01.449724252Z" 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 12 00:21:01.449807 env[1383]: time="2025-07-12T00:21:01.449794205Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Jul 12 00:21:01.449873 env[1383]: time="2025-07-12T00:21:01.449860758Z" level=error msg="failed to initialize a tracing processor \"otlp\"" error="no OpenTelemetry endpoint: skip plugin" Jul 12 00:21:01.449955 env[1383]: time="2025-07-12T00:21:01.449943030Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Jul 12 00:21:01.450259 env[1383]: time="2025-07-12T00:21:01.450200923Z" 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 12 00:21:01.471310 env[1383]: time="2025-07-12T00:21:01.450452097Z" level=info msg="Connect containerd service" Jul 12 00:21:01.471310 env[1383]: time="2025-07-12T00:21:01.450490733Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Jul 12 00:21:01.471310 env[1383]: time="2025-07-12T00:21:01.451140506Z" 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 12 00:21:01.471310 env[1383]: time="2025-07-12T00:21:01.451251335Z" level=info msg="Start subscribing containerd event" Jul 12 00:21:01.471310 env[1383]: time="2025-07-12T00:21:01.451288091Z" level=info msg="Start recovering state" Jul 12 00:21:01.471310 env[1383]: time="2025-07-12T00:21:01.451351364Z" level=info msg="Start event monitor" Jul 12 00:21:01.471310 env[1383]: time="2025-07-12T00:21:01.451368443Z" level=info msg="Start snapshots syncer" Jul 12 00:21:01.471310 env[1383]: time="2025-07-12T00:21:01.451377122Z" level=info msg="Start cni network conf syncer for default" Jul 12 00:21:01.471310 env[1383]: time="2025-07-12T00:21:01.451395200Z" level=info msg="Start streaming server" Jul 12 00:21:01.471310 env[1383]: time="2025-07-12T00:21:01.451753283Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Jul 12 00:21:01.471310 env[1383]: time="2025-07-12T00:21:01.451797278Z" level=info msg=serving... address=/run/containerd/containerd.sock Jul 12 00:21:01.459782 dbus-daemon[1359]: [system] SELinux support is enabled Jul 12 00:21:01.451931 systemd[1]: Started containerd.service. Jul 12 00:21:01.459910 systemd[1]: Started dbus.service. Jul 12 00:21:01.466149 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 12 00:21:01.466172 systemd[1]: Reached target system-config.target. Jul 12 00:21:01.474852 systemd[1]: user-cloudinit-proc-cmdline.service was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Jul 12 00:21:01.474872 systemd[1]: Reached target user-config.target. Jul 12 00:21:01.482364 env[1383]: time="2025-07-12T00:21:01.482324010Z" level=info msg="containerd successfully booted in 0.127708s" Jul 12 00:21:01.490699 dbus-daemon[1359]: [system] Successfully activated service 'org.freedesktop.systemd1' Jul 12 00:21:01.494201 systemd[1]: Started systemd-logind.service. Jul 12 00:21:01.506564 bash[1422]: Updated "/home/core/.ssh/authorized_keys" Jul 12 00:21:01.508570 systemd[1]: Finished update-ssh-keys-after-ignition.service. Jul 12 00:21:01.521341 systemd[1]: nvidia.service: Deactivated successfully. Jul 12 00:21:02.002184 update_engine[1376]: I0712 00:21:01.986956 1376 main.cc:92] Flatcar Update Engine starting Jul 12 00:21:02.047350 systemd[1]: Started update-engine.service. Jul 12 00:21:02.053966 update_engine[1376]: I0712 00:21:02.053941 1376 update_check_scheduler.cc:74] Next update check in 9m55s Jul 12 00:21:02.054309 systemd[1]: Started locksmithd.service. Jul 12 00:21:03.159807 locksmithd[1458]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Jul 12 00:21:03.597056 sshd_keygen[1375]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Jul 12 00:21:03.613407 systemd[1]: Finished sshd-keygen.service. Jul 12 00:21:03.619113 systemd[1]: Starting issuegen.service... Jul 12 00:21:03.624784 systemd[1]: Started waagent.service. Jul 12 00:21:03.629557 systemd[1]: issuegen.service: Deactivated successfully. Jul 12 00:21:03.629752 systemd[1]: Finished issuegen.service. Jul 12 00:21:03.635036 systemd[1]: Starting systemd-user-sessions.service... Jul 12 00:21:03.706979 systemd[1]: Finished systemd-user-sessions.service. Jul 12 00:21:03.714309 systemd[1]: Started getty@tty1.service. Jul 12 00:21:03.720096 systemd[1]: Started serial-getty@ttyAMA0.service. Jul 12 00:21:03.725654 systemd[1]: Reached target getty.target. Jul 12 00:21:03.730460 systemd[1]: Reached target multi-user.target. Jul 12 00:21:03.736217 systemd[1]: Starting systemd-update-utmp-runlevel.service... Jul 12 00:21:03.747937 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Jul 12 00:21:03.748109 systemd[1]: Finished systemd-update-utmp-runlevel.service. Jul 12 00:21:03.754102 systemd[1]: Startup finished in 769ms (kernel) + 12.910s (initrd) + 20.967s (userspace) = 34.647s. Jul 12 00:21:04.273828 login[1477]: pam_lastlog(login:session): file /var/log/lastlog is locked/write Jul 12 00:21:04.274179 login[1476]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Jul 12 00:21:04.410434 systemd[1]: Created slice user-500.slice. Jul 12 00:21:04.411550 systemd[1]: Starting user-runtime-dir@500.service... Jul 12 00:21:04.413675 systemd-logind[1374]: New session 1 of user core. Jul 12 00:21:04.492352 systemd[1]: Finished user-runtime-dir@500.service. Jul 12 00:21:04.493792 systemd[1]: Starting user@500.service... Jul 12 00:21:04.525518 (systemd)[1480]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Jul 12 00:21:04.674545 systemd[1480]: Queued start job for default target default.target. Jul 12 00:21:04.675567 systemd[1480]: Reached target paths.target. Jul 12 00:21:04.675658 systemd[1480]: Reached target sockets.target. Jul 12 00:21:04.675721 systemd[1480]: Reached target timers.target. Jul 12 00:21:04.675794 systemd[1480]: Reached target basic.target. Jul 12 00:21:04.675930 systemd[1]: Started user@500.service. Jul 12 00:21:04.676707 systemd[1]: Started session-1.scope. Jul 12 00:21:04.676800 systemd[1480]: Reached target default.target. Jul 12 00:21:04.676839 systemd[1480]: Startup finished in 145ms. Jul 12 00:21:05.275544 login[1477]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Jul 12 00:21:05.279662 systemd[1]: Started session-2.scope. Jul 12 00:21:05.280048 systemd-logind[1374]: New session 2 of user core. Jul 12 00:21:09.128295 waagent[1473]: 2025-07-12T00:21:09.128191Z INFO Daemon Daemon Azure Linux Agent Version:2.6.0.2 Jul 12 00:21:09.134943 waagent[1473]: 2025-07-12T00:21:09.134873Z INFO Daemon Daemon OS: flatcar 3510.3.7 Jul 12 00:21:09.139944 waagent[1473]: 2025-07-12T00:21:09.139884Z INFO Daemon Daemon Python: 3.9.16 Jul 12 00:21:09.146910 waagent[1473]: 2025-07-12T00:21:09.146831Z INFO Daemon Daemon Run daemon Jul 12 00:21:09.151334 waagent[1473]: 2025-07-12T00:21:09.151268Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='3510.3.7' Jul 12 00:21:09.167769 waagent[1473]: 2025-07-12T00:21:09.167633Z 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 12 00:21:09.182883 waagent[1473]: 2025-07-12T00:21:09.182730Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Jul 12 00:21:09.192985 waagent[1473]: 2025-07-12T00:21:09.192919Z INFO Daemon Daemon cloud-init is enabled: False Jul 12 00:21:09.198042 waagent[1473]: 2025-07-12T00:21:09.197983Z INFO Daemon Daemon Using waagent for provisioning Jul 12 00:21:09.204722 waagent[1473]: 2025-07-12T00:21:09.204662Z INFO Daemon Daemon Activate resource disk Jul 12 00:21:09.209447 waagent[1473]: 2025-07-12T00:21:09.209389Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Jul 12 00:21:09.223953 waagent[1473]: 2025-07-12T00:21:09.223892Z INFO Daemon Daemon Found device: None Jul 12 00:21:09.228659 waagent[1473]: 2025-07-12T00:21:09.228600Z ERROR Daemon Daemon Failed to mount resource disk [ResourceDiskError] unable to detect disk topology Jul 12 00:21:09.237298 waagent[1473]: 2025-07-12T00:21:09.237241Z ERROR Daemon Daemon Event: name=WALinuxAgent, op=ActivateResourceDisk, message=[ResourceDiskError] unable to detect disk topology, duration=0 Jul 12 00:21:09.249270 waagent[1473]: 2025-07-12T00:21:09.249206Z INFO Daemon Daemon Clean protocol and wireserver endpoint Jul 12 00:21:09.255044 waagent[1473]: 2025-07-12T00:21:09.254985Z INFO Daemon Daemon Running default provisioning handler Jul 12 00:21:09.267979 waagent[1473]: 2025-07-12T00:21:09.267862Z 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 12 00:21:09.282377 waagent[1473]: 2025-07-12T00:21:09.282256Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Jul 12 00:21:09.291723 waagent[1473]: 2025-07-12T00:21:09.291664Z INFO Daemon Daemon cloud-init is enabled: False Jul 12 00:21:09.296870 waagent[1473]: 2025-07-12T00:21:09.296812Z INFO Daemon Daemon Copying ovf-env.xml Jul 12 00:21:09.394291 waagent[1473]: 2025-07-12T00:21:09.394112Z INFO Daemon Daemon Successfully mounted dvd Jul 12 00:21:09.459431 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Jul 12 00:21:09.509377 waagent[1473]: 2025-07-12T00:21:09.509234Z INFO Daemon Daemon Detect protocol endpoint Jul 12 00:21:09.514326 waagent[1473]: 2025-07-12T00:21:09.514259Z INFO Daemon Daemon Clean protocol and wireserver endpoint Jul 12 00:21:09.520369 waagent[1473]: 2025-07-12T00:21:09.520310Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Jul 12 00:21:09.526976 waagent[1473]: 2025-07-12T00:21:09.526914Z INFO Daemon Daemon Test for route to 168.63.129.16 Jul 12 00:21:09.532580 waagent[1473]: 2025-07-12T00:21:09.532521Z INFO Daemon Daemon Route to 168.63.129.16 exists Jul 12 00:21:09.537778 waagent[1473]: 2025-07-12T00:21:09.537703Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Jul 12 00:21:09.620872 waagent[1473]: 2025-07-12T00:21:09.620800Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Jul 12 00:21:09.628675 waagent[1473]: 2025-07-12T00:21:09.628632Z INFO Daemon Daemon Wire protocol version:2012-11-30 Jul 12 00:21:09.634614 waagent[1473]: 2025-07-12T00:21:09.634558Z INFO Daemon Daemon Server preferred version:2015-04-05 Jul 12 00:21:10.070662 waagent[1473]: 2025-07-12T00:21:10.070498Z INFO Daemon Daemon Initializing goal state during protocol detection Jul 12 00:21:10.086165 waagent[1473]: 2025-07-12T00:21:10.086094Z INFO Daemon Daemon Forcing an update of the goal state.. Jul 12 00:21:10.093043 waagent[1473]: 2025-07-12T00:21:10.092984Z INFO Daemon Daemon Fetching goal state [incarnation 1] Jul 12 00:21:10.245346 waagent[1473]: 2025-07-12T00:21:10.245206Z INFO Daemon Daemon Found private key matching thumbprint CB5275664508354374D9ED2491BA8D0020BEF635 Jul 12 00:21:10.253654 waagent[1473]: 2025-07-12T00:21:10.253588Z INFO Daemon Daemon Certificate with thumbprint D81E8B07C2FB7D16A7114D0B6C7D526D40DC92FD has no matching private key. Jul 12 00:21:10.263410 waagent[1473]: 2025-07-12T00:21:10.263350Z INFO Daemon Daemon Fetch goal state completed Jul 12 00:21:10.334193 waagent[1473]: 2025-07-12T00:21:10.334117Z INFO Daemon Daemon Fetched new vmSettings [correlation ID: 7efd37a1-ec6c-4734-9244-3b9d766d8b63 New eTag: 1569803627778215367] Jul 12 00:21:10.345199 waagent[1473]: 2025-07-12T00:21:10.345130Z INFO Daemon Daemon Status Blob type 'None' is not valid, assuming BlockBlob Jul 12 00:21:10.394579 waagent[1473]: 2025-07-12T00:21:10.394522Z INFO Daemon Daemon Starting provisioning Jul 12 00:21:10.399956 waagent[1473]: 2025-07-12T00:21:10.399896Z INFO Daemon Daemon Handle ovf-env.xml. Jul 12 00:21:10.404487 waagent[1473]: 2025-07-12T00:21:10.404436Z INFO Daemon Daemon Set hostname [ci-3510.3.7-n-6ae6ff2d31] Jul 12 00:21:10.442644 waagent[1473]: 2025-07-12T00:21:10.442528Z INFO Daemon Daemon Publish hostname [ci-3510.3.7-n-6ae6ff2d31] Jul 12 00:21:10.449130 waagent[1473]: 2025-07-12T00:21:10.449060Z INFO Daemon Daemon Examine /proc/net/route for primary interface Jul 12 00:21:10.455400 waagent[1473]: 2025-07-12T00:21:10.455341Z INFO Daemon Daemon Primary interface is [eth0] Jul 12 00:21:10.471131 systemd[1]: systemd-networkd-wait-online.service: Deactivated successfully. Jul 12 00:21:10.471289 systemd[1]: Stopped systemd-networkd-wait-online.service. Jul 12 00:21:10.471347 systemd[1]: Stopping systemd-networkd-wait-online.service... Jul 12 00:21:10.471581 systemd[1]: Stopping systemd-networkd.service... Jul 12 00:21:10.477776 systemd-networkd[1253]: eth0: DHCPv6 lease lost Jul 12 00:21:10.479044 systemd[1]: systemd-networkd.service: Deactivated successfully. Jul 12 00:21:10.479220 systemd[1]: Stopped systemd-networkd.service. Jul 12 00:21:10.481185 systemd[1]: Starting systemd-networkd.service... Jul 12 00:21:10.508103 systemd-networkd[1526]: enP42729s1: Link UP Jul 12 00:21:10.508114 systemd-networkd[1526]: enP42729s1: Gained carrier Jul 12 00:21:10.509169 systemd-networkd[1526]: eth0: Link UP Jul 12 00:21:10.509179 systemd-networkd[1526]: eth0: Gained carrier Jul 12 00:21:10.509524 systemd-networkd[1526]: lo: Link UP Jul 12 00:21:10.509534 systemd-networkd[1526]: lo: Gained carrier Jul 12 00:21:10.509783 systemd-networkd[1526]: eth0: Gained IPv6LL Jul 12 00:21:10.510683 systemd-networkd[1526]: Enumeration completed Jul 12 00:21:10.510793 systemd[1]: Started systemd-networkd.service. Jul 12 00:21:10.512166 systemd-networkd[1526]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jul 12 00:21:10.512262 systemd[1]: Starting systemd-networkd-wait-online.service... Jul 12 00:21:10.516989 waagent[1473]: 2025-07-12T00:21:10.516806Z INFO Daemon Daemon Create user account if not exists Jul 12 00:21:10.524980 waagent[1473]: 2025-07-12T00:21:10.524105Z INFO Daemon Daemon User core already exists, skip useradd Jul 12 00:21:10.530241 waagent[1473]: 2025-07-12T00:21:10.530177Z INFO Daemon Daemon Configure sudoer Jul 12 00:21:10.534552 systemd-networkd[1526]: eth0: DHCPv4 address 10.200.20.19/24, gateway 10.200.20.1 acquired from 168.63.129.16 Jul 12 00:21:10.536108 waagent[1473]: 2025-07-12T00:21:10.535982Z INFO Daemon Daemon Configure sshd Jul 12 00:21:10.540350 systemd[1]: Finished systemd-networkd-wait-online.service. Jul 12 00:21:10.541009 waagent[1473]: 2025-07-12T00:21:10.540942Z INFO Daemon Daemon Deploy ssh public key. Jul 12 00:21:11.727967 waagent[1473]: 2025-07-12T00:21:11.727882Z INFO Daemon Daemon Provisioning complete Jul 12 00:21:11.748901 waagent[1473]: 2025-07-12T00:21:11.748834Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Jul 12 00:21:11.755589 waagent[1473]: 2025-07-12T00:21:11.755523Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Jul 12 00:21:11.766697 waagent[1473]: 2025-07-12T00:21:11.766635Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.6.0.2 is the most current agent Jul 12 00:21:12.059100 waagent[1535]: 2025-07-12T00:21:12.058974Z INFO ExtHandler ExtHandler Agent WALinuxAgent-2.6.0.2 is running as the goal state agent Jul 12 00:21:12.059822 waagent[1535]: 2025-07-12T00:21:12.059766Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jul 12 00:21:12.059959 waagent[1535]: 2025-07-12T00:21:12.059913Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Jul 12 00:21:12.072074 waagent[1535]: 2025-07-12T00:21:12.072004Z INFO ExtHandler ExtHandler Forcing an update of the goal state.. Jul 12 00:21:12.072239 waagent[1535]: 2025-07-12T00:21:12.072190Z INFO ExtHandler ExtHandler Fetching goal state [incarnation 1] Jul 12 00:21:12.137407 waagent[1535]: 2025-07-12T00:21:12.137269Z INFO ExtHandler ExtHandler Found private key matching thumbprint CB5275664508354374D9ED2491BA8D0020BEF635 Jul 12 00:21:12.137613 waagent[1535]: 2025-07-12T00:21:12.137560Z INFO ExtHandler ExtHandler Certificate with thumbprint D81E8B07C2FB7D16A7114D0B6C7D526D40DC92FD has no matching private key. Jul 12 00:21:12.137861 waagent[1535]: 2025-07-12T00:21:12.137812Z INFO ExtHandler ExtHandler Fetch goal state completed Jul 12 00:21:12.152990 waagent[1535]: 2025-07-12T00:21:12.152939Z INFO ExtHandler ExtHandler Fetched new vmSettings [correlation ID: 5c619170-5a76-4d49-ba92-8bd3f8662169 New eTag: 1569803627778215367] Jul 12 00:21:12.153498 waagent[1535]: 2025-07-12T00:21:12.153443Z INFO ExtHandler ExtHandler Status Blob type 'None' is not valid, assuming BlockBlob Jul 12 00:21:12.217860 waagent[1535]: 2025-07-12T00:21:12.217692Z INFO ExtHandler ExtHandler Distro: flatcar-3510.3.7; OSUtil: CoreOSUtil; AgentService: waagent; Python: 3.9.16; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.20.1; Jul 12 00:21:12.240029 waagent[1535]: 2025-07-12T00:21:12.239956Z INFO ExtHandler ExtHandler WALinuxAgent-2.6.0.2 running as process 1535 Jul 12 00:21:12.243750 waagent[1535]: 2025-07-12T00:21:12.243685Z INFO ExtHandler ExtHandler Cgroup monitoring is not supported on ['flatcar', '3510.3.7', '', 'Flatcar Container Linux by Kinvolk'] Jul 12 00:21:12.245059 waagent[1535]: 2025-07-12T00:21:12.245004Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Jul 12 00:21:12.367854 waagent[1535]: 2025-07-12T00:21:12.367761Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Jul 12 00:21:12.368253 waagent[1535]: 2025-07-12T00:21:12.368196Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Jul 12 00:21:12.375692 waagent[1535]: 2025-07-12T00:21:12.375623Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Jul 12 00:21:12.376193 waagent[1535]: 2025-07-12T00:21:12.376134Z ERROR ExtHandler ExtHandler Unable to setup the persistent firewall rules: [Errno 30] Read-only file system: '/lib/systemd/system/waagent-network-setup.service' Jul 12 00:21:12.377386 waagent[1535]: 2025-07-12T00:21:12.377318Z 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 12 00:21:12.378827 waagent[1535]: 2025-07-12T00:21:12.378715Z INFO ExtHandler ExtHandler Starting env monitor service. Jul 12 00:21:12.379450 waagent[1535]: 2025-07-12T00:21:12.379388Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jul 12 00:21:12.379692 waagent[1535]: 2025-07-12T00:21:12.379642Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Jul 12 00:21:12.380361 waagent[1535]: 2025-07-12T00:21:12.380304Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Jul 12 00:21:12.380731 waagent[1535]: 2025-07-12T00:21:12.380676Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Jul 12 00:21:12.380731 waagent[1535]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Jul 12 00:21:12.380731 waagent[1535]: eth0 00000000 0114C80A 0003 0 0 1024 00000000 0 0 0 Jul 12 00:21:12.380731 waagent[1535]: eth0 0014C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Jul 12 00:21:12.380731 waagent[1535]: eth0 0114C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Jul 12 00:21:12.380731 waagent[1535]: eth0 10813FA8 0114C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Jul 12 00:21:12.380731 waagent[1535]: eth0 FEA9FEA9 0114C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Jul 12 00:21:12.382938 waagent[1535]: 2025-07-12T00:21:12.382775Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Jul 12 00:21:12.383866 waagent[1535]: 2025-07-12T00:21:12.383801Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jul 12 00:21:12.384122 waagent[1535]: 2025-07-12T00:21:12.384072Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Jul 12 00:21:12.384730 waagent[1535]: 2025-07-12T00:21:12.384671Z INFO EnvHandler ExtHandler Configure routes Jul 12 00:21:12.384982 waagent[1535]: 2025-07-12T00:21:12.384934Z INFO EnvHandler ExtHandler Gateway:None Jul 12 00:21:12.385166 waagent[1535]: 2025-07-12T00:21:12.385123Z INFO EnvHandler ExtHandler Routes:None Jul 12 00:21:12.386104 waagent[1535]: 2025-07-12T00:21:12.386043Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Jul 12 00:21:12.386189 waagent[1535]: 2025-07-12T00:21:12.386126Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Jul 12 00:21:12.386911 waagent[1535]: 2025-07-12T00:21:12.386826Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Jul 12 00:21:12.386992 waagent[1535]: 2025-07-12T00:21:12.386934Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Jul 12 00:21:12.387430 waagent[1535]: 2025-07-12T00:21:12.387360Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Jul 12 00:21:12.398597 waagent[1535]: 2025-07-12T00:21:12.398527Z INFO ExtHandler ExtHandler Checking for agent updates (family: Prod) Jul 12 00:21:12.399340 waagent[1535]: 2025-07-12T00:21:12.399284Z WARNING ExtHandler ExtHandler Fetch failed: [HttpError] HTTPS is unavailable and required Jul 12 00:21:12.400307 waagent[1535]: 2025-07-12T00:21:12.400244Z INFO ExtHandler ExtHandler [PERIODIC] Request failed using the direct channel. Error: 'NoneType' object has no attribute 'getheaders' Jul 12 00:21:12.421685 waagent[1535]: 2025-07-12T00:21:12.421579Z ERROR EnvHandler ExtHandler Failed to get the PID of the DHCP client: invalid literal for int() with base 10: 'MainPID=1526' Jul 12 00:21:12.447563 waagent[1535]: 2025-07-12T00:21:12.447498Z INFO ExtHandler ExtHandler Default channel changed to HostGA channel. Jul 12 00:21:12.486913 waagent[1535]: 2025-07-12T00:21:12.486795Z INFO MonitorHandler ExtHandler Network interfaces: Jul 12 00:21:12.486913 waagent[1535]: Executing ['ip', '-a', '-o', 'link']: Jul 12 00:21:12.486913 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 12 00:21:12.486913 waagent[1535]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 00:22:48:7a:53:75 brd ff:ff:ff:ff:ff:ff Jul 12 00:21:12.486913 waagent[1535]: 3: enP42729s1: mtu 1500 qdisc mq master eth0 state UP mode DEFAULT group default qlen 1000\ link/ether 00:22:48:7a:53:75 brd ff:ff:ff:ff:ff:ff\ altname enP42729p0s2 Jul 12 00:21:12.486913 waagent[1535]: Executing ['ip', '-4', '-a', '-o', 'address']: Jul 12 00:21:12.486913 waagent[1535]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Jul 12 00:21:12.486913 waagent[1535]: 2: eth0 inet 10.200.20.19/24 metric 1024 brd 10.200.20.255 scope global eth0\ valid_lft forever preferred_lft forever Jul 12 00:21:12.486913 waagent[1535]: Executing ['ip', '-6', '-a', '-o', 'address']: Jul 12 00:21:12.486913 waagent[1535]: 1: lo inet6 ::1/128 scope host \ valid_lft forever preferred_lft forever Jul 12 00:21:12.486913 waagent[1535]: 2: eth0 inet6 fe80::222:48ff:fe7a:5375/64 scope link \ valid_lft forever preferred_lft forever Jul 12 00:21:12.700987 waagent[1535]: 2025-07-12T00:21:12.700920Z INFO ExtHandler ExtHandler Agent WALinuxAgent-2.6.0.2 discovered update WALinuxAgent-2.13.1.1 -- exiting Jul 12 00:21:12.770186 waagent[1473]: 2025-07-12T00:21:12.770071Z 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 12 00:21:12.775112 waagent[1473]: 2025-07-12T00:21:12.775054Z INFO Daemon Daemon Determined Agent WALinuxAgent-2.13.1.1 to be the latest agent Jul 12 00:21:13.973512 waagent[1565]: 2025-07-12T00:21:13.973411Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.13.1.1) Jul 12 00:21:13.975001 waagent[1565]: 2025-07-12T00:21:13.974938Z INFO ExtHandler ExtHandler OS: flatcar 3510.3.7 Jul 12 00:21:13.975143 waagent[1565]: 2025-07-12T00:21:13.975097Z INFO ExtHandler ExtHandler Python: 3.9.16 Jul 12 00:21:13.975271 waagent[1565]: 2025-07-12T00:21:13.975227Z INFO ExtHandler ExtHandler CPU Arch: aarch64 Jul 12 00:21:13.987985 waagent[1565]: 2025-07-12T00:21:13.987860Z INFO ExtHandler ExtHandler Distro: flatcar-3510.3.7; OSUtil: CoreOSUtil; AgentService: waagent; Python: 3.9.16; Arch: aarch64; systemd: True; systemd_version: systemd 252 (252); LISDrivers: Absent; logrotate: logrotate 3.20.1; Jul 12 00:21:13.988413 waagent[1565]: 2025-07-12T00:21:13.988355Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jul 12 00:21:13.988562 waagent[1565]: 2025-07-12T00:21:13.988518Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Jul 12 00:21:13.988786 waagent[1565]: 2025-07-12T00:21:13.988719Z INFO ExtHandler ExtHandler Initializing the goal state... Jul 12 00:21:14.002060 waagent[1565]: 2025-07-12T00:21:14.001995Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Jul 12 00:21:14.011000 waagent[1565]: 2025-07-12T00:21:14.010946Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.175 Jul 12 00:21:14.012095 waagent[1565]: 2025-07-12T00:21:14.012036Z INFO ExtHandler Jul 12 00:21:14.012245 waagent[1565]: 2025-07-12T00:21:14.012197Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: de492031-4ecf-4a25-8e42-8e1b3cd554ee eTag: 1569803627778215367 source: Fabric] Jul 12 00:21:14.013054 waagent[1565]: 2025-07-12T00:21:14.012994Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Jul 12 00:21:14.014313 waagent[1565]: 2025-07-12T00:21:14.014253Z INFO ExtHandler Jul 12 00:21:14.014444 waagent[1565]: 2025-07-12T00:21:14.014401Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Jul 12 00:21:14.021247 waagent[1565]: 2025-07-12T00:21:14.021200Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Jul 12 00:21:14.021686 waagent[1565]: 2025-07-12T00:21:14.021638Z WARNING ExtHandler ExtHandler Fetch failed: [HttpError] HTTPS is unavailable and required Jul 12 00:21:14.040449 waagent[1565]: 2025-07-12T00:21:14.040399Z INFO ExtHandler ExtHandler Default channel changed to HostGAPlugin channel. Jul 12 00:21:14.109022 waagent[1565]: 2025-07-12T00:21:14.108895Z INFO ExtHandler Downloaded certificate {'thumbprint': 'D81E8B07C2FB7D16A7114D0B6C7D526D40DC92FD', 'hasPrivateKey': False} Jul 12 00:21:14.110063 waagent[1565]: 2025-07-12T00:21:14.110002Z INFO ExtHandler Downloaded certificate {'thumbprint': 'CB5275664508354374D9ED2491BA8D0020BEF635', 'hasPrivateKey': True} Jul 12 00:21:14.111171 waagent[1565]: 2025-07-12T00:21:14.111112Z INFO ExtHandler Fetch goal state from WireServer completed Jul 12 00:21:14.112092 waagent[1565]: 2025-07-12T00:21:14.112031Z INFO ExtHandler ExtHandler Goal state initialization completed. Jul 12 00:21:14.128988 waagent[1565]: 2025-07-12T00:21:14.128880Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.0.15 3 Sep 2024 (Library: OpenSSL 3.0.15 3 Sep 2024) Jul 12 00:21:14.136363 waagent[1565]: 2025-07-12T00:21:14.136268Z INFO ExtHandler ExtHandler Using iptables [version 1.8.8] to manage firewall rules Jul 12 00:21:14.139607 waagent[1565]: 2025-07-12T00:21:14.139504Z INFO ExtHandler ExtHandler Did not find a legacy firewall rule: ['iptables', '-w', '-t', 'security', '-C', 'OUTPUT', '-d', '168.63.129.16', '-p', 'tcp', '-m', 'conntrack', '--ctstate', 'INVALID,NEW', '-j', 'ACCEPT'] Jul 12 00:21:14.139817 waagent[1565]: 2025-07-12T00:21:14.139767Z INFO ExtHandler ExtHandler Checking state of the firewall Jul 12 00:21:14.317296 waagent[1565]: 2025-07-12T00:21:14.317153Z INFO ExtHandler ExtHandler Created firewall rules for Azure Fabric: Jul 12 00:21:14.317296 waagent[1565]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Jul 12 00:21:14.317296 waagent[1565]: pkts bytes target prot opt in out source destination Jul 12 00:21:14.317296 waagent[1565]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Jul 12 00:21:14.317296 waagent[1565]: pkts bytes target prot opt in out source destination Jul 12 00:21:14.317296 waagent[1565]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Jul 12 00:21:14.317296 waagent[1565]: pkts bytes target prot opt in out source destination Jul 12 00:21:14.317296 waagent[1565]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Jul 12 00:21:14.317296 waagent[1565]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Jul 12 00:21:14.317296 waagent[1565]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Jul 12 00:21:14.318721 waagent[1565]: 2025-07-12T00:21:14.318660Z INFO ExtHandler ExtHandler Setting up persistent firewall rules Jul 12 00:21:14.321497 waagent[1565]: 2025-07-12T00:21:14.321385Z INFO ExtHandler ExtHandler The firewalld service is not present on the system Jul 12 00:21:14.321877 waagent[1565]: 2025-07-12T00:21:14.321823Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Jul 12 00:21:14.322305 waagent[1565]: 2025-07-12T00:21:14.322252Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Jul 12 00:21:14.329531 waagent[1565]: 2025-07-12T00:21:14.329480Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Jul 12 00:21:14.330096 waagent[1565]: 2025-07-12T00:21:14.330043Z ERROR ExtHandler ExtHandler Unable to setup the persistent firewall rules: [Errno 30] Read-only file system: '/lib/systemd/system/waagent-network-setup.service' Jul 12 00:21:14.338391 waagent[1565]: 2025-07-12T00:21:14.338334Z INFO ExtHandler ExtHandler WALinuxAgent-2.13.1.1 running as process 1565 Jul 12 00:21:14.341596 waagent[1565]: 2025-07-12T00:21:14.341538Z INFO ExtHandler ExtHandler [CGI] Cgroups is not currently supported on ['flatcar', '3510.3.7', '', 'Flatcar Container Linux by Kinvolk'] Jul 12 00:21:14.342503 waagent[1565]: 2025-07-12T00:21:14.342446Z INFO ExtHandler ExtHandler [CGI] Agent will reset the quotas in case cgroup usage went from enabled to disabled Jul 12 00:21:14.343500 waagent[1565]: 2025-07-12T00:21:14.343445Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False Jul 12 00:21:14.346474 waagent[1565]: 2025-07-12T00:21:14.346415Z INFO ExtHandler ExtHandler Checking if log collection is allowed at this time [False]. All three conditions must be met: 1. configuration enabled [True], 2. cgroups v1 enabled [False] OR cgroups v2 is in use and v2 resource limiting configuration enabled [False], 3. python supported: [True] Jul 12 00:21:14.348062 waagent[1565]: 2025-07-12T00:21:14.347992Z INFO ExtHandler ExtHandler Starting env monitor service. Jul 12 00:21:14.348295 waagent[1565]: 2025-07-12T00:21:14.348227Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jul 12 00:21:14.348864 waagent[1565]: 2025-07-12T00:21:14.348793Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Jul 12 00:21:14.349446 waagent[1565]: 2025-07-12T00:21:14.349378Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Jul 12 00:21:14.349759 waagent[1565]: 2025-07-12T00:21:14.349690Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Jul 12 00:21:14.349759 waagent[1565]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Jul 12 00:21:14.349759 waagent[1565]: eth0 00000000 0114C80A 0003 0 0 1024 00000000 0 0 0 Jul 12 00:21:14.349759 waagent[1565]: eth0 0014C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Jul 12 00:21:14.349759 waagent[1565]: eth0 0114C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Jul 12 00:21:14.349759 waagent[1565]: eth0 10813FA8 0114C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Jul 12 00:21:14.349759 waagent[1565]: eth0 FEA9FEA9 0114C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Jul 12 00:21:14.351987 waagent[1565]: 2025-07-12T00:21:14.351878Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Jul 12 00:21:14.352256 waagent[1565]: 2025-07-12T00:21:14.352174Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jul 12 00:21:14.354679 waagent[1565]: 2025-07-12T00:21:14.354528Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Jul 12 00:21:14.354962 waagent[1565]: 2025-07-12T00:21:14.354900Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Jul 12 00:21:14.355147 waagent[1565]: 2025-07-12T00:21:14.355086Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Jul 12 00:21:14.355970 waagent[1565]: 2025-07-12T00:21:14.355889Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Jul 12 00:21:14.356606 waagent[1565]: 2025-07-12T00:21:14.356538Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Jul 12 00:21:14.357261 waagent[1565]: 2025-07-12T00:21:14.357194Z INFO EnvHandler ExtHandler Configure routes Jul 12 00:21:14.357442 waagent[1565]: 2025-07-12T00:21:14.357383Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Jul 12 00:21:14.361193 waagent[1565]: 2025-07-12T00:21:14.361117Z INFO EnvHandler ExtHandler Gateway:None Jul 12 00:21:14.364437 waagent[1565]: 2025-07-12T00:21:14.364366Z INFO MonitorHandler ExtHandler Network interfaces: Jul 12 00:21:14.364437 waagent[1565]: Executing ['ip', '-a', '-o', 'link']: Jul 12 00:21:14.364437 waagent[1565]: 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 12 00:21:14.364437 waagent[1565]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 00:22:48:7a:53:75 brd ff:ff:ff:ff:ff:ff Jul 12 00:21:14.364437 waagent[1565]: 3: enP42729s1: mtu 1500 qdisc mq master eth0 state UP mode DEFAULT group default qlen 1000\ link/ether 00:22:48:7a:53:75 brd ff:ff:ff:ff:ff:ff\ altname enP42729p0s2 Jul 12 00:21:14.364437 waagent[1565]: Executing ['ip', '-4', '-a', '-o', 'address']: Jul 12 00:21:14.364437 waagent[1565]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Jul 12 00:21:14.364437 waagent[1565]: 2: eth0 inet 10.200.20.19/24 metric 1024 brd 10.200.20.255 scope global eth0\ valid_lft forever preferred_lft forever Jul 12 00:21:14.364437 waagent[1565]: Executing ['ip', '-6', '-a', '-o', 'address']: Jul 12 00:21:14.364437 waagent[1565]: 1: lo inet6 ::1/128 scope host \ valid_lft forever preferred_lft forever Jul 12 00:21:14.364437 waagent[1565]: 2: eth0 inet6 fe80::222:48ff:fe7a:5375/64 scope link \ valid_lft forever preferred_lft forever Jul 12 00:21:14.366594 waagent[1565]: 2025-07-12T00:21:14.366441Z INFO EnvHandler ExtHandler Routes:None Jul 12 00:21:14.373293 waagent[1565]: 2025-07-12T00:21:14.373226Z INFO ExtHandler ExtHandler Downloading agent manifest Jul 12 00:21:14.387085 waagent[1565]: 2025-07-12T00:21:14.387018Z INFO ExtHandler ExtHandler Jul 12 00:21:14.388039 waagent[1565]: 2025-07-12T00:21:14.387977Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 96694c56-ca5d-45ff-a12e-c9e76fa545b8 correlation bb87880f-fbe7-49d7-8772-df9fc9b643b3 created: 2025-07-12T00:19:44.869583Z] Jul 12 00:21:14.391327 waagent[1565]: 2025-07-12T00:21:14.391249Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Jul 12 00:21:14.395408 waagent[1565]: 2025-07-12T00:21:14.395345Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 8 ms] Jul 12 00:21:14.421349 waagent[1565]: 2025-07-12T00:21:14.421287Z INFO ExtHandler ExtHandler Looking for existing remote access users. Jul 12 00:21:14.427977 waagent[1565]: 2025-07-12T00:21:14.427808Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.13.1.1 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: E50DB202-495F-4942-92F3-00B5315B9D56;UpdateGSErrors: 0;AutoUpdate: 1;UpdateMode: SelfUpdate;] Jul 12 00:21:14.430382 waagent[1565]: 2025-07-12T00:21:14.430307Z INFO EnvHandler ExtHandler Using iptables [version 1.8.8] to manage firewall rules Jul 12 00:21:14.446992 waagent[1565]: 2025-07-12T00:21:14.446922Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Jul 12 00:21:31.692251 systemd[1]: Created slice system-sshd.slice. Jul 12 00:21:31.693326 systemd[1]: Started sshd@0-10.200.20.19:22-10.200.16.10:37304.service. Jul 12 00:21:32.334385 sshd[1609]: Accepted publickey for core from 10.200.16.10 port 37304 ssh2: RSA SHA256:rj2oXtEDU3WY8CkIl6DA12mRi0mqr9ysDwwzvF7nlFQ Jul 12 00:21:32.349714 sshd[1609]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Jul 12 00:21:32.354147 systemd[1]: Started session-3.scope. Jul 12 00:21:32.354429 systemd-logind[1374]: New session 3 of user core. Jul 12 00:21:32.747907 systemd[1]: Started sshd@1-10.200.20.19:22-10.200.16.10:37306.service. Jul 12 00:21:33.214632 sshd[1614]: Accepted publickey for core from 10.200.16.10 port 37306 ssh2: RSA SHA256:rj2oXtEDU3WY8CkIl6DA12mRi0mqr9ysDwwzvF7nlFQ Jul 12 00:21:33.215923 sshd[1614]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Jul 12 00:21:33.219614 systemd-logind[1374]: New session 4 of user core. Jul 12 00:21:33.220073 systemd[1]: Started session-4.scope. Jul 12 00:21:33.552819 sshd[1614]: pam_unix(sshd:session): session closed for user core Jul 12 00:21:33.555195 systemd[1]: sshd@1-10.200.20.19:22-10.200.16.10:37306.service: Deactivated successfully. Jul 12 00:21:33.555889 systemd[1]: session-4.scope: Deactivated successfully. Jul 12 00:21:33.556457 systemd-logind[1374]: Session 4 logged out. Waiting for processes to exit. Jul 12 00:21:33.557378 systemd-logind[1374]: Removed session 4. Jul 12 00:21:33.629623 systemd[1]: Started sshd@2-10.200.20.19:22-10.200.16.10:37312.service. Jul 12 00:21:34.093346 sshd[1620]: Accepted publickey for core from 10.200.16.10 port 37312 ssh2: RSA SHA256:rj2oXtEDU3WY8CkIl6DA12mRi0mqr9ysDwwzvF7nlFQ Jul 12 00:21:34.094587 sshd[1620]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Jul 12 00:21:34.098291 systemd-logind[1374]: New session 5 of user core. Jul 12 00:21:34.098710 systemd[1]: Started session-5.scope. Jul 12 00:21:34.435552 sshd[1620]: pam_unix(sshd:session): session closed for user core Jul 12 00:21:34.437942 systemd[1]: sshd@2-10.200.20.19:22-10.200.16.10:37312.service: Deactivated successfully. Jul 12 00:21:34.438588 systemd[1]: session-5.scope: Deactivated successfully. Jul 12 00:21:34.439114 systemd-logind[1374]: Session 5 logged out. Waiting for processes to exit. Jul 12 00:21:34.439913 systemd-logind[1374]: Removed session 5. Jul 12 00:21:34.515284 systemd[1]: Started sshd@3-10.200.20.19:22-10.200.16.10:37328.service. Jul 12 00:21:34.979045 sshd[1626]: Accepted publickey for core from 10.200.16.10 port 37328 ssh2: RSA SHA256:rj2oXtEDU3WY8CkIl6DA12mRi0mqr9ysDwwzvF7nlFQ Jul 12 00:21:34.980508 sshd[1626]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Jul 12 00:21:34.984505 systemd[1]: Started session-6.scope. Jul 12 00:21:34.985641 systemd-logind[1374]: New session 6 of user core. Jul 12 00:21:35.317555 sshd[1626]: pam_unix(sshd:session): session closed for user core Jul 12 00:21:35.320081 systemd[1]: sshd@3-10.200.20.19:22-10.200.16.10:37328.service: Deactivated successfully. Jul 12 00:21:35.320749 systemd[1]: session-6.scope: Deactivated successfully. Jul 12 00:21:35.321299 systemd-logind[1374]: Session 6 logged out. Waiting for processes to exit. Jul 12 00:21:35.322055 systemd-logind[1374]: Removed session 6. Jul 12 00:21:35.399125 systemd[1]: Started sshd@4-10.200.20.19:22-10.200.16.10:37336.service. Jul 12 00:21:35.869044 sshd[1632]: Accepted publickey for core from 10.200.16.10 port 37336 ssh2: RSA SHA256:rj2oXtEDU3WY8CkIl6DA12mRi0mqr9ysDwwzvF7nlFQ Jul 12 00:21:35.870289 sshd[1632]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Jul 12 00:21:35.873789 systemd-logind[1374]: New session 7 of user core. Jul 12 00:21:35.874465 systemd[1]: Started session-7.scope. Jul 12 00:21:36.373961 sudo[1635]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Jul 12 00:21:36.374158 sudo[1635]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Jul 12 00:21:36.430873 dbus-daemon[1359]: avc: received setenforce notice (enforcing=1) Jul 12 00:21:36.431729 sudo[1635]: pam_unix(sudo:session): session closed for user root Jul 12 00:21:36.533297 sshd[1632]: pam_unix(sshd:session): session closed for user core Jul 12 00:21:36.536030 systemd[1]: sshd@4-10.200.20.19:22-10.200.16.10:37336.service: Deactivated successfully. Jul 12 00:21:36.536820 systemd[1]: session-7.scope: Deactivated successfully. Jul 12 00:21:36.537384 systemd-logind[1374]: Session 7 logged out. Waiting for processes to exit. Jul 12 00:21:36.538386 systemd-logind[1374]: Removed session 7. Jul 12 00:21:36.607789 systemd[1]: Started sshd@5-10.200.20.19:22-10.200.16.10:37350.service. Jul 12 00:21:37.055352 sshd[1639]: Accepted publickey for core from 10.200.16.10 port 37350 ssh2: RSA SHA256:rj2oXtEDU3WY8CkIl6DA12mRi0mqr9ysDwwzvF7nlFQ Jul 12 00:21:37.056962 sshd[1639]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Jul 12 00:21:37.060608 systemd-logind[1374]: New session 8 of user core. Jul 12 00:21:37.061026 systemd[1]: Started session-8.scope. Jul 12 00:21:37.311340 sudo[1643]: 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 12 00:21:37.312087 sudo[1643]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Jul 12 00:21:37.314713 sudo[1643]: pam_unix(sudo:session): session closed for user root Jul 12 00:21:37.318598 sudo[1642]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/bin/systemctl restart audit-rules Jul 12 00:21:37.318828 sudo[1642]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Jul 12 00:21:37.326572 systemd[1]: Stopping audit-rules.service... Jul 12 00:21:37.325000 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 12 00:21:37.331304 kernel: kauditd_printk_skb: 89 callbacks suppressed Jul 12 00:21:37.331332 kernel: audit: type=1305 audit(1752279697.325:170): auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Jul 12 00:21:37.331489 auditctl[1646]: No rules Jul 12 00:21:37.341672 systemd[1]: audit-rules.service: Deactivated successfully. Jul 12 00:21:37.341836 systemd[1]: Stopped audit-rules.service. Jul 12 00:21:37.325000 audit[1646]: SYSCALL arch=c00000b7 syscall=206 success=yes exit=1056 a0=3 a1=fffff584b270 a2=420 a3=0 items=0 ppid=1 pid=1646 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 12 00:21:37.364647 kernel: audit: type=1300 audit(1752279697.325:170): arch=c00000b7 syscall=206 success=yes exit=1056 a0=3 a1=fffff584b270 a2=420 a3=0 items=0 ppid=1 pid=1646 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 12 00:21:37.343239 systemd[1]: Starting audit-rules.service... Jul 12 00:21:37.325000 audit: PROCTITLE proctitle=2F7362696E2F617564697463746C002D44 Jul 12 00:21:37.372041 kernel: audit: type=1327 audit(1752279697.325:170): proctitle=2F7362696E2F617564697463746C002D44 Jul 12 00:21:37.340000 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 12 00:21:37.390625 kernel: audit: type=1131 audit(1752279697.340:171): 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 12 00:21:37.390914 augenrules[1663]: No rules Jul 12 00:21:37.391449 systemd[1]: Finished audit-rules.service. Jul 12 00:21:37.389000 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 12 00:21:37.392728 sudo[1642]: pam_unix(sudo:session): session closed for user root Jul 12 00:21:37.390000 audit[1642]: USER_END pid=1642 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 12 00:21:37.431297 kernel: audit: type=1130 audit(1752279697.389:172): 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 12 00:21:37.431330 kernel: audit: type=1106 audit(1752279697.390:173): pid=1642 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 12 00:21:37.390000 audit[1642]: CRED_DISP pid=1642 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 12 00:21:37.448931 kernel: audit: type=1104 audit(1752279697.390:174): pid=1642 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 12 00:21:37.480885 sshd[1639]: pam_unix(sshd:session): session closed for user core Jul 12 00:21:37.479000 audit[1639]: USER_END pid=1639 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 12 00:21:37.483347 systemd-logind[1374]: Session 8 logged out. Waiting for processes to exit. Jul 12 00:21:37.484248 systemd[1]: session-8.scope: Deactivated successfully. Jul 12 00:21:37.485285 systemd[1]: sshd@5-10.200.20.19:22-10.200.16.10:37350.service: Deactivated successfully. Jul 12 00:21:37.486177 systemd-logind[1374]: Removed session 8. Jul 12 00:21:37.480000 audit[1639]: CRED_DISP pid=1639 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 12 00:21:37.525087 kernel: audit: type=1106 audit(1752279697.479:175): pid=1639 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 12 00:21:37.525124 kernel: audit: type=1104 audit(1752279697.480:176): pid=1639 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 12 00:21:37.483000 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.19:22-10.200.16.10:37350 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 12 00:21:37.543144 kernel: audit: type=1131 audit(1752279697.483:177): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@5-10.200.20.19:22-10.200.16.10:37350 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 12 00:21:37.562048 systemd[1]: Started sshd@6-10.200.20.19:22-10.200.16.10:37360.service. Jul 12 00:21:37.560000 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.19:22-10.200.16.10:37360 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 12 00:21:38.026000 audit[1669]: USER_ACCT pid=1669 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 12 00:21:38.028204 sshd[1669]: Accepted publickey for core from 10.200.16.10 port 37360 ssh2: RSA SHA256:rj2oXtEDU3WY8CkIl6DA12mRi0mqr9ysDwwzvF7nlFQ Jul 12 00:21:38.027000 audit[1669]: CRED_ACQ pid=1669 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 12 00:21:38.027000 audit[1669]: SYSCALL arch=c00000b7 syscall=64 success=yes exit=3 a0=5 a1=ffffc06151c0 a2=3 a3=1 items=0 ppid=1 pid=1669 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 12 00:21:38.027000 audit: PROCTITLE proctitle=737368643A20636F7265205B707269765D Jul 12 00:21:38.029674 sshd[1669]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Jul 12 00:21:38.033287 systemd-logind[1374]: New session 9 of user core. Jul 12 00:21:38.033667 systemd[1]: Started session-9.scope. Jul 12 00:21:38.035000 audit[1669]: USER_START pid=1669 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 12 00:21:38.037000 audit[1671]: CRED_ACQ pid=1671 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 12 00:21:38.290000 audit[1672]: USER_ACCT pid=1672 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 12 00:21:38.292580 sudo[1672]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/blkid -s UUID -o value /dev/disk/by-label/ROOT Jul 12 00:21:38.290000 audit[1672]: CRED_REFR pid=1672 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 12 00:21:38.292800 sudo[1672]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Jul 12 00:21:38.292000 audit[1672]: USER_START pid=1672 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 12 00:21:38.352925 sudo[1672]: pam_unix(sudo:session): session closed for user root Jul 12 00:21:38.351000 audit[1672]: USER_END pid=1672 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 12 00:21:38.351000 audit[1672]: CRED_DISP pid=1672 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 12 00:21:38.434851 sshd[1669]: pam_unix(sshd:session): session closed for user core Jul 12 00:21:38.434000 audit[1669]: USER_END pid=1669 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 12 00:21:38.434000 audit[1669]: CRED_DISP pid=1669 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 12 00:21:38.437062 systemd[1]: session-9.scope: Deactivated successfully. Jul 12 00:21:38.437763 systemd[1]: sshd@6-10.200.20.19:22-10.200.16.10:37360.service: Deactivated successfully. Jul 12 00:21:38.436000 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.19:22-10.200.16.10:37360 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 12 00:21:38.438430 systemd-logind[1374]: Session 9 logged out. Waiting for processes to exit. Jul 12 00:21:38.439110 systemd-logind[1374]: Removed session 9. Jul 12 00:21:38.519641 systemd[1]: Started sshd@7-10.200.20.19:22-10.200.16.10:37374.service. Jul 12 00:21:38.518000 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.19:22-10.200.16.10:37374 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 12 00:21:39.003000 audit[1676]: USER_ACCT pid=1676 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 12 00:21:39.005442 sshd[1676]: Accepted publickey for core from 10.200.16.10 port 37374 ssh2: RSA SHA256:rj2oXtEDU3WY8CkIl6DA12mRi0mqr9ysDwwzvF7nlFQ Jul 12 00:21:39.004000 audit[1676]: CRED_ACQ pid=1676 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 12 00:21:39.004000 audit[1676]: SYSCALL arch=c00000b7 syscall=64 success=yes exit=3 a0=5 a1=ffffd5071d50 a2=3 a3=1 items=0 ppid=1 pid=1676 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 12 00:21:39.004000 audit: PROCTITLE proctitle=737368643A20636F7265205B707269765D Jul 12 00:21:39.006919 sshd[1676]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Jul 12 00:21:39.010612 systemd-logind[1374]: New session 10 of user core. Jul 12 00:21:39.011048 systemd[1]: Started session-10.scope. Jul 12 00:21:39.013000 audit[1676]: USER_START pid=1676 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 12 00:21:39.014000 audit[1678]: CRED_ACQ pid=1678 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 12 00:21:39.278000 audit[1679]: USER_ACCT pid=1679 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 12 00:21:39.278000 audit[1679]: CRED_REFR pid=1679 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 12 00:21:39.280432 sudo[1679]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/blkid -s TYPE -o value /dev/disk/by-label/ROOT Jul 12 00:21:39.280629 sudo[1679]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Jul 12 00:21:39.280000 audit[1679]: USER_START pid=1679 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 12 00:21:39.283765 sudo[1679]: pam_unix(sudo:session): session closed for user root Jul 12 00:21:39.282000 audit[1679]: USER_END pid=1679 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 12 00:21:39.282000 audit[1679]: CRED_DISP pid=1679 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 12 00:21:39.368984 sshd[1676]: pam_unix(sshd:session): session closed for user core Jul 12 00:21:39.368000 audit[1676]: USER_END pid=1676 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 12 00:21:39.368000 audit[1676]: CRED_DISP pid=1676 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 12 00:21:39.370000 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.19:22-10.200.16.10:37374 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 12 00:21:39.371375 systemd-logind[1374]: Session 10 logged out. Waiting for processes to exit. Jul 12 00:21:39.371620 systemd[1]: sshd@7-10.200.20.19:22-10.200.16.10:37374.service: Deactivated successfully. Jul 12 00:21:39.372270 systemd[1]: session-10.scope: Deactivated successfully. Jul 12 00:21:39.372985 systemd-logind[1374]: Removed session 10. Jul 12 00:21:39.449856 systemd[1]: Started sshd@8-10.200.20.19:22-10.200.16.10:37378.service. Jul 12 00:21:39.448000 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.19:22-10.200.16.10:37378 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 12 00:21:39.934000 audit[1683]: USER_ACCT pid=1683 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 12 00:21:39.936163 sshd[1683]: Accepted publickey for core from 10.200.16.10 port 37378 ssh2: RSA SHA256:rj2oXtEDU3WY8CkIl6DA12mRi0mqr9ysDwwzvF7nlFQ Jul 12 00:21:39.935000 audit[1683]: CRED_ACQ pid=1683 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 12 00:21:39.935000 audit[1683]: SYSCALL arch=c00000b7 syscall=64 success=yes exit=3 a0=5 a1=ffffd048fe30 a2=3 a3=1 items=0 ppid=1 pid=1683 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 12 00:21:39.935000 audit: PROCTITLE proctitle=737368643A20636F7265205B707269765D Jul 12 00:21:39.937590 sshd[1683]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Jul 12 00:21:39.941315 systemd-logind[1374]: New session 11 of user core. Jul 12 00:21:39.941725 systemd[1]: Started session-11.scope. Jul 12 00:21:39.943000 audit[1683]: USER_START pid=1683 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 12 00:21:39.945000 audit[1685]: CRED_ACQ pid=1685 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 12 00:21:40.343421 sshd[1683]: pam_unix(sshd:session): session closed for user core Jul 12 00:21:40.343000 audit[1683]: USER_END pid=1683 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 12 00:21:40.343000 audit[1683]: CRED_DISP pid=1683 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 12 00:21:40.346207 systemd[1]: sshd@8-10.200.20.19:22-10.200.16.10:37378.service: Deactivated successfully. Jul 12 00:21:40.344000 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.19:22-10.200.16.10:37378 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 12 00:21:40.346880 systemd[1]: session-11.scope: Deactivated successfully. Jul 12 00:21:40.347410 systemd-logind[1374]: Session 11 logged out. Waiting for processes to exit. Jul 12 00:21:40.348203 systemd-logind[1374]: Removed session 11. Jul 12 00:21:41.444772 kernel: hv_balloon: Max. dynamic memory size: 4096 MB