May 17 00:44:16.293414 kernel: mlx5_core b02c:00:02.0: poll_health:739:(pid 0): device's health compromised - reached miss count
May 17 00:44:16.293438 kernel: SELinux:  Class mctp_socket not defined in policy.
May 17 00:44:16.293450 kernel: SELinux:  Class anon_inode not defined in policy.
May 17 00:44:16.293459 kernel: SELinux: the above unknown classes and permissions will be allowed
May 17 00:44:16.293467 kernel: SELinux:  policy capability network_peer_controls=1
May 17 00:44:16.293474 kernel: SELinux:  policy capability open_perms=1
May 17 00:44:16.293483 kernel: SELinux:  policy capability extended_socket_class=1
May 17 00:44:16.293491 kernel: SELinux:  policy capability always_check_network=0
May 17 00:44:16.293499 kernel: SELinux:  policy capability cgroup_seclabel=1
May 17 00:44:16.293509 kernel: SELinux:  policy capability nnp_nosuid_transition=1
May 17 00:44:16.293517 kernel: SELinux:  policy capability genfs_seclabel_symlinks=0
May 17 00:44:16.293525 kernel: SELinux:  policy capability ioctl_skip_cloexec=0
May 17 00:44:16.293536 systemd[1]: Successfully loaded SELinux policy in 258.382ms.
May 17 00:44:16.293546 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 25.044ms.
May 17 00:44:16.293559 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)
May 17 00:44:16.293568 systemd[1]: Detected virtualization microsoft.
May 17 00:44:16.293577 systemd[1]: Detected architecture arm64.
May 17 00:44:16.293586 systemd[1]: Detected first boot.
May 17 00:44:16.293596 systemd[1]: Hostname set to <ci-3510.3.7-n-05b9cd7458>.
May 17 00:44:16.293616 systemd[1]: Initializing machine ID from random generator.
May 17 00:44:16.293628 kernel: SELinux:  Context system_u:object_r:container_file_t:s0:c1022,c1023 is not valid (left unmapped).
May 17 00:44:16.293637 kernel: kauditd_printk_skb: 45 callbacks suppressed
May 17 00:44:16.293647 kernel: audit: type=1400 audit(1747442649.591:87): avc:  denied  { associate } for  pid=1137 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"
May 17 00:44:16.293658 kernel: audit: type=1300 audit(1747442649.591:87): arch=c00000b7 syscall=5 success=yes exit=0 a0=400014589c a1=40000c6df8 a2=40000cd0c0 a3=32 items=0 ppid=1120 pid=1137 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)
May 17 00:44:16.293667 kernel: audit: type=1327 audit(1747442649.591:87): proctitle=2F7573722F6C69622F73797374656D642F73797374656D2D67656E657261746F72732F746F7263782D67656E657261746F72002F72756E2F73797374656D642F67656E657261746F72002F72756E2F73797374656D642F67656E657261746F722E6561726C79002F72756E2F73797374656D642F67656E657261746F722E6C61
May 17 00:44:16.293677 kernel: audit: type=1400 audit(1747442649.604:88): avc:  denied  { associate } for  pid=1137 comm="torcx-generator" name="lib" scontext=system_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:tmpfs_t:s0 tclass=filesystem permissive=1
May 17 00:44:16.293687 kernel: audit: type=1300 audit(1747442649.604:88): arch=c00000b7 syscall=34 success=yes exit=0 a0=ffffffffffffff9c a1=4000022105 a2=1ed a3=0 items=2 ppid=1120 pid=1137 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)
May 17 00:44:16.293697 kernel: audit: type=1307 audit(1747442649.604:88): cwd="/"
May 17 00:44:16.293706 kernel: audit: type=1302 audit(1747442649.604:88): 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
May 17 00:44:16.293716 kernel: audit: type=1302 audit(1747442649.604:88): 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
May 17 00:44:16.293725 kernel: audit: type=1327 audit(1747442649.604:88): proctitle=2F7573722F6C69622F73797374656D642F73797374656D2D67656E657261746F72732F746F7263782D67656E657261746F72002F72756E2F73797374656D642F67656E657261746F72002F72756E2F73797374656D642F67656E657261746F722E6561726C79002F72756E2F73797374656D642F67656E657261746F722E6C61
May 17 00:44:16.293734 systemd[1]: Populated /etc with preset unit settings.
May 17 00:44:16.293743 systemd[1]: /usr/lib/systemd/system/locksmithd.service:8: Unit uses CPUShares=; please use CPUWeight= instead. Support for CPUShares= will be removed soon.
May 17 00:44:16.293755 systemd[1]: /usr/lib/systemd/system/locksmithd.service:9: Unit uses MemoryLimit=; please use MemoryMax= instead. Support for MemoryLimit= will be removed soon.
May 17 00:44:16.293765 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.
May 17 00:44:16.293774 kernel: audit: type=1334 audit(1747442655.531:89): prog-id=12 op=LOAD
May 17 00:44:16.293783 kernel: audit: type=1334 audit(1747442655.531:90): prog-id=3 op=UNLOAD
May 17 00:44:16.293791 kernel: audit: type=1334 audit(1747442655.538:91): prog-id=13 op=LOAD
May 17 00:44:16.293800 kernel: audit: type=1334 audit(1747442655.543:92): prog-id=14 op=LOAD
May 17 00:44:16.293809 kernel: audit: type=1334 audit(1747442655.543:93): prog-id=4 op=UNLOAD
May 17 00:44:16.293819 kernel: audit: type=1334 audit(1747442655.543:94): prog-id=5 op=UNLOAD
May 17 00:44:16.293829 kernel: audit: type=1334 audit(1747442655.549:95): prog-id=15 op=LOAD
May 17 00:44:16.293838 kernel: audit: type=1334 audit(1747442655.549:96): prog-id=12 op=UNLOAD
May 17 00:44:16.293847 kernel: audit: type=1334 audit(1747442655.555:97): prog-id=16 op=LOAD
May 17 00:44:16.293856 systemd[1]: iscsid.service: Deactivated successfully.
May 17 00:44:16.293865 kernel: audit: type=1334 audit(1747442655.560:98): prog-id=17 op=LOAD
May 17 00:44:16.293874 systemd[1]: Stopped iscsid.service.
May 17 00:44:16.293884 systemd[1]: initrd-switch-root.service: Deactivated successfully.
May 17 00:44:16.293893 systemd[1]: Stopped initrd-switch-root.service.
May 17 00:44:16.293904 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.
May 17 00:44:16.293914 systemd[1]: Created slice system-addon\x2dconfig.slice.
May 17 00:44:16.293923 systemd[1]: Created slice system-addon\x2drun.slice.
May 17 00:44:16.293933 systemd[1]: Created slice system-getty.slice.
May 17 00:44:16.293942 systemd[1]: Created slice system-modprobe.slice.
May 17 00:44:16.293953 systemd[1]: Created slice system-serial\x2dgetty.slice.
May 17 00:44:16.293962 systemd[1]: Created slice system-system\x2dcloudinit.slice.
May 17 00:44:16.293972 systemd[1]: Created slice system-systemd\x2dfsck.slice.
May 17 00:44:16.293982 systemd[1]: Created slice user.slice.
May 17 00:44:16.293992 systemd[1]: Started systemd-ask-password-console.path.
May 17 00:44:16.294001 systemd[1]: Started systemd-ask-password-wall.path.
May 17 00:44:16.294010 systemd[1]: Set up automount boot.automount.
May 17 00:44:16.294020 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount.
May 17 00:44:16.294029 systemd[1]: Stopped target initrd-switch-root.target.
May 17 00:44:16.294038 systemd[1]: Stopped target initrd-fs.target.
May 17 00:44:16.294048 systemd[1]: Stopped target initrd-root-fs.target.
May 17 00:44:16.294059 systemd[1]: Reached target integritysetup.target.
May 17 00:44:16.294068 systemd[1]: Reached target remote-cryptsetup.target.
May 17 00:44:16.294077 systemd[1]: Reached target remote-fs.target.
May 17 00:44:16.294087 systemd[1]: Reached target slices.target.
May 17 00:44:16.294096 systemd[1]: Reached target swap.target.
May 17 00:44:16.294105 systemd[1]: Reached target torcx.target.
May 17 00:44:16.294115 systemd[1]: Reached target veritysetup.target.
May 17 00:44:16.294124 systemd[1]: Listening on systemd-coredump.socket.
May 17 00:44:16.294135 systemd[1]: Listening on systemd-initctl.socket.
May 17 00:44:16.294145 systemd[1]: Listening on systemd-networkd.socket.
May 17 00:44:16.294155 systemd[1]: Listening on systemd-udevd-control.socket.
May 17 00:44:16.294164 systemd[1]: Listening on systemd-udevd-kernel.socket.
May 17 00:44:16.294174 systemd[1]: Listening on systemd-userdbd.socket.
May 17 00:44:16.294184 systemd[1]: Mounting dev-hugepages.mount...
May 17 00:44:16.294195 systemd[1]: Mounting dev-mqueue.mount...
May 17 00:44:16.294204 systemd[1]: Mounting media.mount...
May 17 00:44:16.294213 systemd[1]: Mounting sys-kernel-debug.mount...
May 17 00:44:16.294223 systemd[1]: Mounting sys-kernel-tracing.mount...
May 17 00:44:16.294232 systemd[1]: Mounting tmp.mount...
May 17 00:44:16.294241 systemd[1]: Starting flatcar-tmpfiles.service...
May 17 00:44:16.294251 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met.
May 17 00:44:16.294260 systemd[1]: Starting kmod-static-nodes.service...
May 17 00:44:16.294270 systemd[1]: Starting modprobe@configfs.service...
May 17 00:44:16.294281 systemd[1]: Starting modprobe@dm_mod.service...
May 17 00:44:16.294291 systemd[1]: Starting modprobe@drm.service...
May 17 00:44:16.294300 systemd[1]: Starting modprobe@efi_pstore.service...
May 17 00:44:16.294310 systemd[1]: Starting modprobe@fuse.service...
May 17 00:44:16.294319 systemd[1]: Starting modprobe@loop.service...
May 17 00:44:16.294329 systemd[1]: setup-nsswitch.service was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf).
May 17 00:44:16.294338 systemd[1]: systemd-fsck-root.service: Deactivated successfully.
May 17 00:44:16.294348 systemd[1]: Stopped systemd-fsck-root.service.
May 17 00:44:16.294359 systemd[1]: systemd-fsck-usr.service: Deactivated successfully.
May 17 00:44:16.294369 systemd[1]: Stopped systemd-fsck-usr.service.
May 17 00:44:16.294379 systemd[1]: Stopped systemd-journald.service.
May 17 00:44:16.294388 systemd[1]: systemd-journald.service: Consumed 2.736s CPU time.
May 17 00:44:16.294398 systemd[1]: Starting systemd-journald.service...
May 17 00:44:16.294407 systemd[1]: Starting systemd-modules-load.service...
May 17 00:44:16.294417 systemd[1]: Starting systemd-network-generator.service...
May 17 00:44:16.294427 kernel: loop: module loaded
May 17 00:44:16.294436 systemd[1]: Starting systemd-remount-fs.service...
May 17 00:44:16.294447 systemd[1]: Starting systemd-udev-trigger.service...
May 17 00:44:16.294457 systemd[1]: verity-setup.service: Deactivated successfully.
May 17 00:44:16.294466 systemd[1]: Stopped verity-setup.service.
May 17 00:44:16.294476 systemd[1]: Mounted dev-hugepages.mount.
May 17 00:44:16.294485 systemd[1]: Mounted dev-mqueue.mount.
May 17 00:44:16.294495 systemd[1]: Mounted media.mount.
May 17 00:44:16.294504 kernel: fuse: init (API version 7.34)
May 17 00:44:16.294513 systemd[1]: Mounted sys-kernel-debug.mount.
May 17 00:44:16.294523 systemd[1]: Mounted sys-kernel-tracing.mount.
May 17 00:44:16.294534 systemd[1]: Mounted tmp.mount.
May 17 00:44:16.294543 systemd[1]: Finished flatcar-tmpfiles.service.
May 17 00:44:16.294553 systemd[1]: Finished kmod-static-nodes.service.
May 17 00:44:16.294567 systemd-journald[1215]: Journal started
May 17 00:44:16.294621 systemd-journald[1215]: Runtime Journal (/run/log/journal/92051cbd834f4dd99ac99c167001bdfb) is 8.0M, max 78.5M, 70.5M free.
May 17 00:44:07.787000 audit: MAC_POLICY_LOAD auid=4294967295 ses=4294967295 lsm=selinux res=1
May 17 00:44:08.371000 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
May 17 00:44:08.371000 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
May 17 00:44:08.371000 audit: BPF prog-id=10 op=LOAD
May 17 00:44:08.371000 audit: BPF prog-id=10 op=UNLOAD
May 17 00:44:08.371000 audit: BPF prog-id=11 op=LOAD
May 17 00:44:08.371000 audit: BPF prog-id=11 op=UNLOAD
May 17 00:44:09.591000 audit[1137]: AVC avc:  denied  { associate } for  pid=1137 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"
May 17 00:44:09.591000 audit[1137]: SYSCALL arch=c00000b7 syscall=5 success=yes exit=0 a0=400014589c a1=40000c6df8 a2=40000cd0c0 a3=32 items=0 ppid=1120 pid=1137 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)
May 17 00:44:09.591000 audit: PROCTITLE proctitle=2F7573722F6C69622F73797374656D642F73797374656D2D67656E657261746F72732F746F7263782D67656E657261746F72002F72756E2F73797374656D642F67656E657261746F72002F72756E2F73797374656D642F67656E657261746F722E6561726C79002F72756E2F73797374656D642F67656E657261746F722E6C61
May 17 00:44:09.604000 audit[1137]: AVC avc:  denied  { associate } for  pid=1137 comm="torcx-generator" name="lib" scontext=system_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:tmpfs_t:s0 tclass=filesystem permissive=1
May 17 00:44:09.604000 audit[1137]: SYSCALL arch=c00000b7 syscall=34 success=yes exit=0 a0=ffffffffffffff9c a1=4000022105 a2=1ed a3=0 items=2 ppid=1120 pid=1137 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)
May 17 00:44:09.604000 audit: CWD cwd="/"
May 17 00:44:09.604000 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
May 17 00:44:09.604000 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
May 17 00:44:09.604000 audit: PROCTITLE proctitle=2F7573722F6C69622F73797374656D642F73797374656D2D67656E657261746F72732F746F7263782D67656E657261746F72002F72756E2F73797374656D642F67656E657261746F72002F72756E2F73797374656D642F67656E657261746F722E6561726C79002F72756E2F73797374656D642F67656E657261746F722E6C61
May 17 00:44:15.531000 audit: BPF prog-id=12 op=LOAD
May 17 00:44:15.531000 audit: BPF prog-id=3 op=UNLOAD
May 17 00:44:15.538000 audit: BPF prog-id=13 op=LOAD
May 17 00:44:15.543000 audit: BPF prog-id=14 op=LOAD
May 17 00:44:15.543000 audit: BPF prog-id=4 op=UNLOAD
May 17 00:44:15.543000 audit: BPF prog-id=5 op=UNLOAD
May 17 00:44:15.549000 audit: BPF prog-id=15 op=LOAD
May 17 00:44:15.549000 audit: BPF prog-id=12 op=UNLOAD
May 17 00:44:15.555000 audit: BPF prog-id=16 op=LOAD
May 17 00:44:15.560000 audit: BPF prog-id=17 op=LOAD
May 17 00:44:15.560000 audit: BPF prog-id=13 op=UNLOAD
May 17 00:44:15.561000 audit: BPF prog-id=14 op=UNLOAD
May 17 00:44:15.566000 audit: BPF prog-id=18 op=LOAD
May 17 00:44:15.566000 audit: BPF prog-id=15 op=UNLOAD
May 17 00:44:15.572000 audit: BPF prog-id=19 op=LOAD
May 17 00:44:15.577000 audit: BPF prog-id=20 op=LOAD
May 17 00:44:15.577000 audit: BPF prog-id=16 op=UNLOAD
May 17 00:44:15.577000 audit: BPF prog-id=17 op=UNLOAD
May 17 00:44:15.578000 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'
May 17 00:44:15.599000 audit: BPF prog-id=18 op=UNLOAD
May 17 00:44:15.603000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=iscsid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
May 17 00:44:15.619000 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'
May 17 00:44:15.619000 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'
May 17 00:44:16.071000 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'
May 17 00:44:16.085000 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'
May 17 00:44:16.093000 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'
May 17 00:44:16.093000 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'
May 17 00:44:16.100000 audit: BPF prog-id=21 op=LOAD
May 17 00:44:16.100000 audit: BPF prog-id=22 op=LOAD
May 17 00:44:16.100000 audit: BPF prog-id=23 op=LOAD
May 17 00:44:16.100000 audit: BPF prog-id=19 op=UNLOAD
May 17 00:44:16.100000 audit: BPF prog-id=20 op=UNLOAD
May 17 00:44:16.208000 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'
May 17 00:44:16.285000 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'
May 17 00:44:16.290000 audit: CONFIG_CHANGE op=set audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1
May 17 00:44:16.290000 audit[1215]: SYSCALL arch=c00000b7 syscall=211 success=yes exit=60 a0=5 a1=fffffe5aa730 a2=4000 a3=1 items=0 ppid=1 pid=1215 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)
May 17 00:44:16.290000 audit: PROCTITLE proctitle="/usr/lib/systemd/systemd-journald"
May 17 00:44:16.294000 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'
May 17 00:44:09.495506 /usr/lib/systemd/system-generators/torcx-generator[1137]: time="2025-05-17T00:44:09Z" 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]"
May 17 00:44:15.530316 systemd[1]: Queued start job for default target multi-user.target.
May 17 00:44:09.495787 /usr/lib/systemd/system-generators/torcx-generator[1137]: time="2025-05-17T00:44:09Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json
May 17 00:44:15.530332 systemd[1]: Unnecessary job was removed for dev-sda6.device.
May 17 00:44:09.495804 /usr/lib/systemd/system-generators/torcx-generator[1137]: time="2025-05-17T00:44:09Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json
May 17 00:44:15.579023 systemd[1]: systemd-journald.service: Deactivated successfully.
May 17 00:44:09.495837 /usr/lib/systemd/system-generators/torcx-generator[1137]: time="2025-05-17T00:44:09Z" level=info msg="no vendor profile selected by /etc/flatcar/docker-1.12"
May 17 00:44:15.579392 systemd[1]: systemd-journald.service: Consumed 2.736s CPU time.
May 17 00:44:09.495847 /usr/lib/systemd/system-generators/torcx-generator[1137]: time="2025-05-17T00:44:09Z" level=debug msg="skipped missing lower profile" missing profile=oem
May 17 00:44:09.495876 /usr/lib/systemd/system-generators/torcx-generator[1137]: time="2025-05-17T00:44:09Z" level=warning msg="no next profile: unable to read profile file: open /etc/torcx/next-profile: no such file or directory"
May 17 00:44:09.495887 /usr/lib/systemd/system-generators/torcx-generator[1137]: time="2025-05-17T00:44:09Z" level=debug msg="apply configuration parsed" lower profiles (vendor/oem)="[vendor]" upper profile (user)=
May 17 00:44:09.496048 /usr/lib/systemd/system-generators/torcx-generator[1137]: time="2025-05-17T00:44:09Z" level=debug msg="mounted tmpfs" target=/run/torcx/unpack
May 17 00:44:09.496078 /usr/lib/systemd/system-generators/torcx-generator[1137]: time="2025-05-17T00:44:09Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json
May 17 00:44:09.496090 /usr/lib/systemd/system-generators/torcx-generator[1137]: time="2025-05-17T00:44:09Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json
May 17 00:44:09.574647 /usr/lib/systemd/system-generators/torcx-generator[1137]: time="2025-05-17T00:44:09Z" 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
May 17 00:44:09.574738 /usr/lib/systemd/system-generators/torcx-generator[1137]: time="2025-05-17T00:44:09Z" 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
May 17 00:44:09.574789 /usr/lib/systemd/system-generators/torcx-generator[1137]: time="2025-05-17T00:44:09Z" 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
May 17 00:44:09.574805 /usr/lib/systemd/system-generators/torcx-generator[1137]: time="2025-05-17T00:44:09Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store: no such file or directory" path=/usr/share/oem/torcx/store
May 17 00:44:09.574841 /usr/lib/systemd/system-generators/torcx-generator[1137]: time="2025-05-17T00:44:09Z" 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
May 17 00:44:09.574855 /usr/lib/systemd/system-generators/torcx-generator[1137]: time="2025-05-17T00:44:09Z" level=info msg="store skipped" err="open /var/lib/torcx/store: no such file or directory" path=/var/lib/torcx/store
May 17 00:44:14.551643 /usr/lib/systemd/system-generators/torcx-generator[1137]: time="2025-05-17T00:44:14Z" level=debug msg="image unpacked" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl
May 17 00:44:14.551907 /usr/lib/systemd/system-generators/torcx-generator[1137]: time="2025-05-17T00:44:14Z" 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
May 17 00:44:14.552005 /usr/lib/systemd/system-generators/torcx-generator[1137]: time="2025-05-17T00:44:14Z" 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
May 17 00:44:14.552162 /usr/lib/systemd/system-generators/torcx-generator[1137]: time="2025-05-17T00:44:14Z" 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
May 17 00:44:14.552211 /usr/lib/systemd/system-generators/torcx-generator[1137]: time="2025-05-17T00:44:14Z" level=debug msg="profile applied" sealed profile=/run/torcx/profile.json upper profile=
May 17 00:44:14.552265 /usr/lib/systemd/system-generators/torcx-generator[1137]: time="2025-05-17T00:44:14Z" 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
May 17 00:44:16.304883 systemd[1]: Started systemd-journald.service.
May 17 00:44:16.304000 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'
May 17 00:44:16.305927 systemd[1]: modprobe@configfs.service: Deactivated successfully.
May 17 00:44:16.306072 systemd[1]: Finished modprobe@configfs.service.
May 17 00:44:16.310000 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'
May 17 00:44:16.310000 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'
May 17 00:44:16.311006 systemd[1]: modprobe@dm_mod.service: Deactivated successfully.
May 17 00:44:16.311140 systemd[1]: Finished modprobe@dm_mod.service.
May 17 00:44:16.316000 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'
May 17 00:44:16.316000 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'
May 17 00:44:16.317054 systemd[1]: modprobe@drm.service: Deactivated successfully.
May 17 00:44:16.317198 systemd[1]: Finished modprobe@drm.service.
May 17 00:44:16.321000 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'
May 17 00:44:16.321000 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'
May 17 00:44:16.321936 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.
May 17 00:44:16.322074 systemd[1]: Finished modprobe@efi_pstore.service.
May 17 00:44:16.326000 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'
May 17 00:44:16.326000 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'
May 17 00:44:16.326953 systemd[1]: modprobe@fuse.service: Deactivated successfully.
May 17 00:44:16.327089 systemd[1]: Finished modprobe@fuse.service.
May 17 00:44:16.331000 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'
May 17 00:44:16.331000 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'
May 17 00:44:16.332401 systemd[1]: modprobe@loop.service: Deactivated successfully.
May 17 00:44:16.332524 systemd[1]: Finished modprobe@loop.service.
May 17 00:44:16.336000 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'
May 17 00:44:16.336000 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'
May 17 00:44:16.337773 systemd[1]: Finished systemd-network-generator.service.
May 17 00:44:16.342000 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'
May 17 00:44:16.343407 systemd[1]: Finished systemd-remount-fs.service.
May 17 00:44:16.348000 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'
May 17 00:44:16.349875 systemd[1]: Reached target network-pre.target.
May 17 00:44:16.355995 systemd[1]: Mounting sys-fs-fuse-connections.mount...
May 17 00:44:16.361902 systemd[1]: Mounting sys-kernel-config.mount...
May 17 00:44:16.366146 systemd[1]: remount-root.service was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/).
May 17 00:44:16.381263 systemd[1]: Starting systemd-hwdb-update.service...
May 17 00:44:16.386758 systemd[1]: Starting systemd-journal-flush.service...
May 17 00:44:16.391270 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).
May 17 00:44:16.392673 systemd[1]: Starting systemd-random-seed.service...
May 17 00:44:16.396852 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met.
May 17 00:44:16.398194 systemd[1]: Starting systemd-sysusers.service...
May 17 00:44:16.405361 systemd[1]: Finished systemd-modules-load.service.
May 17 00:44:16.410000 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'
May 17 00:44:16.410783 systemd[1]: Mounted sys-fs-fuse-connections.mount.
May 17 00:44:16.415883 systemd[1]: Mounted sys-kernel-config.mount.
May 17 00:44:16.421567 systemd[1]: Starting systemd-sysctl.service...
May 17 00:44:16.428060 systemd[1]: Finished systemd-udev-trigger.service.
May 17 00:44:16.432000 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'
May 17 00:44:16.434501 systemd[1]: Starting systemd-udev-settle.service...
May 17 00:44:16.442653 udevadm[1257]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in.
May 17 00:44:16.478238 systemd-journald[1215]: Runtime Journal (/run/log/journal/92051cbd834f4dd99ac99c167001bdfb) is 8.0M, max 78.5M, 70.5M free.
May 17 00:44:16.482154 systemd[1]: Finished systemd-random-seed.service.
May 17 00:44:16.488623 systemd-journald[1215]: Received client request to flush runtime journal.
May 17 00:44:16.489000 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'
May 17 00:44:16.490281 systemd[1]: Reached target first-boot-complete.target.
May 17 00:44:16.495364 systemd[1]: Finished systemd-journal-flush.service.
May 17 00:44:16.499000 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'
May 17 00:44:16.531160 systemd[1]: Finished systemd-sysctl.service.
May 17 00:44:16.535000 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'
May 17 00:44:17.301047 systemd[1]: Finished systemd-sysusers.service.
May 17 00:44:17.305000 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'
May 17 00:44:18.039036 systemd[1]: Finished systemd-hwdb-update.service.
May 17 00:44:18.043000 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'
May 17 00:44:18.043000 audit: BPF prog-id=24 op=LOAD
May 17 00:44:18.043000 audit: BPF prog-id=25 op=LOAD
May 17 00:44:18.044000 audit: BPF prog-id=7 op=UNLOAD
May 17 00:44:18.044000 audit: BPF prog-id=8 op=UNLOAD
May 17 00:44:18.045334 systemd[1]: Starting systemd-udevd.service...
May 17 00:44:18.063789 systemd-udevd[1259]: Using default interface naming scheme 'v252'.
May 17 00:44:18.254283 systemd[1]: Started systemd-udevd.service.
May 17 00:44:18.263000 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'
May 17 00:44:18.265000 audit: BPF prog-id=26 op=LOAD
May 17 00:44:18.267100 systemd[1]: Starting systemd-networkd.service...
May 17 00:44:18.312484 systemd[1]: Condition check resulted in dev-ttyAMA0.device being skipped.
May 17 00:44:18.348703 systemd[1]: Starting systemd-userdbd.service...
May 17 00:44:18.347000 audit: BPF prog-id=27 op=LOAD
May 17 00:44:18.347000 audit: BPF prog-id=28 op=LOAD
May 17 00:44:18.347000 audit: BPF prog-id=29 op=LOAD
May 17 00:44:18.385000 audit[1268]: AVC avc:  denied  { confidentiality } for  pid=1268 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
May 17 00:44:18.392653 kernel: hv_vmbus: registering driver hv_balloon
May 17 00:44:18.401902 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0
May 17 00:44:18.402008 kernel: hv_balloon: Memory hot add disabled on ARM64
May 17 00:44:18.405640 kernel: hv_vmbus: registering driver hyperv_fb
May 17 00:44:18.421381 kernel: mousedev: PS/2 mouse device common for all mice
May 17 00:44:18.424688 kernel: hyperv_fb: Synthvid Version major 3, minor 5
May 17 00:44:18.429638 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608
May 17 00:44:18.432127 systemd[1]: Started systemd-userdbd.service.
May 17 00:44:18.447266 kernel: Console: switching to colour dummy device 80x25
May 17 00:44:18.449635 kernel: Console: switching to colour frame buffer device 128x48
May 17 00:44:18.449000 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'
May 17 00:44:18.385000 audit[1268]: SYSCALL arch=c00000b7 syscall=105 success=yes exit=0 a0=aaaab7f682c0 a1=aa2c a2=ffffafab24b0 a3=aaaab7ec6010 items=12 ppid=1259 pid=1268 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)
May 17 00:44:18.385000 audit: CWD cwd="/"
May 17 00:44:18.385000 audit: PATH item=0 name=(null) inode=6131 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
May 17 00:44:18.385000 audit: PATH item=1 name=(null) inode=10704 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
May 17 00:44:18.385000 audit: PATH item=2 name=(null) inode=10704 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
May 17 00:44:18.385000 audit: PATH item=3 name=(null) inode=10705 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
May 17 00:44:18.385000 audit: PATH item=4 name=(null) inode=10704 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
May 17 00:44:18.385000 audit: PATH item=5 name=(null) inode=10706 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
May 17 00:44:18.385000 audit: PATH item=6 name=(null) inode=10704 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
May 17 00:44:18.385000 audit: PATH item=7 name=(null) inode=10707 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
May 17 00:44:18.385000 audit: PATH item=8 name=(null) inode=10704 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
May 17 00:44:18.385000 audit: PATH item=9 name=(null) inode=10708 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
May 17 00:44:18.385000 audit: PATH item=10 name=(null) inode=10704 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
May 17 00:44:18.385000 audit: PATH item=11 name=(null) inode=10709 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
May 17 00:44:18.385000 audit: PROCTITLE proctitle="(udev-worker)"
May 17 00:44:18.494163 kernel: hv_utils: Registering HyperV Utility Driver
May 17 00:44:18.500979 kernel: hv_vmbus: registering driver hv_utils
May 17 00:44:18.501018 kernel: hv_utils: Heartbeat IC version 3.0
May 17 00:44:18.501030 kernel: hv_utils: Shutdown IC version 3.2
May 17 00:44:18.504388 kernel: hv_utils: TimeSync IC version 4.0
May 17 00:44:18.756357 systemd-networkd[1280]: lo: Link UP
May 17 00:44:18.756627 systemd-networkd[1280]: lo: Gained carrier
May 17 00:44:18.757109 systemd-networkd[1280]: Enumeration completed
May 17 00:44:18.757295 systemd[1]: Started systemd-networkd.service.
May 17 00:44:18.761000 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'
May 17 00:44:18.763589 systemd[1]: Starting systemd-networkd-wait-online.service...
May 17 00:44:18.787514 systemd-networkd[1280]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network.
May 17 00:44:18.828804 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device.
May 17 00:44:18.838675 systemd[1]: Finished systemd-udev-settle.service.
May 17 00:44:18.843000 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'
May 17 00:44:18.846304 systemd[1]: Starting lvm2-activation-early.service...
May 17 00:44:18.850165 kernel: mlx5_core b02c:00:02.0 enP45100s1: Link up
May 17 00:44:18.898160 kernel: hv_netvsc 002248b5-486b-0022-48b5-486b002248b5 eth0: Data path switched to VF: enP45100s1
May 17 00:44:18.899541 systemd-networkd[1280]: enP45100s1: Link UP
May 17 00:44:18.899918 systemd-networkd[1280]: eth0: Link UP
May 17 00:44:18.899969 systemd-networkd[1280]: eth0: Gained carrier
May 17 00:44:18.908666 systemd-networkd[1280]: enP45100s1: Gained carrier
May 17 00:44:18.918268 systemd-networkd[1280]: eth0: DHCPv4 address 10.200.20.22/24, gateway 10.200.20.1 acquired from 168.63.129.16
May 17 00:44:19.256078 lvm[1338]:   WARNING: Failed to connect to lvmetad. Falling back to device scanning.
May 17 00:44:19.282061 systemd[1]: Finished lvm2-activation-early.service.
May 17 00:44:19.285000 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'
May 17 00:44:19.287271 systemd[1]: Reached target cryptsetup.target.
May 17 00:44:19.292840 systemd[1]: Starting lvm2-activation.service...
May 17 00:44:19.297117 lvm[1340]:   WARNING: Failed to connect to lvmetad. Falling back to device scanning.
May 17 00:44:19.325131 systemd[1]: Finished lvm2-activation.service.
May 17 00:44:19.328000 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'
May 17 00:44:19.329854 systemd[1]: Reached target local-fs-pre.target.
May 17 00:44:19.334596 systemd[1]: var-lib-machines.mount was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw).
May 17 00:44:19.334625 systemd[1]: Reached target local-fs.target.
May 17 00:44:19.339274 systemd[1]: Reached target machines.target.
May 17 00:44:19.345658 systemd[1]: Starting ldconfig.service...
May 17 00:44:19.349572 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met.
May 17 00:44:19.349635 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).
May 17 00:44:19.350906 systemd[1]: Starting systemd-boot-update.service...
May 17 00:44:19.356283 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service...
May 17 00:44:19.363472 systemd[1]: Starting systemd-machine-id-commit.service...
May 17 00:44:19.367871 systemd[1]: systemd-sysext.service was skipped because no trigger condition checks were met.
May 17 00:44:19.367927 systemd[1]: ensure-sysext.service was skipped because no trigger condition checks were met.
May 17 00:44:19.369156 systemd[1]: Starting systemd-tmpfiles-setup.service...
May 17 00:44:19.415821 systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
May 17 00:44:19.416426 systemd[1]: Finished systemd-machine-id-commit.service.
May 17 00:44:19.420000 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'
May 17 00:44:19.451573 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service.
May 17 00:44:19.456000 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'
May 17 00:44:19.464301 systemd[1]: boot.automount: Got automount request for /boot, triggered by 1342 (bootctl)
May 17 00:44:19.465483 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service...
May 17 00:44:19.578888 systemd-tmpfiles[1345]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring.
May 17 00:44:19.705702 systemd-tmpfiles[1345]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring.
May 17 00:44:19.724550 systemd-fsck[1351]: fsck.fat 4.2 (2021-01-31)
May 17 00:44:19.724550 systemd-fsck[1351]: /dev/sda1: 236 files, 117182/258078 clusters
May 17 00:44:19.728427 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service.
May 17 00:44:19.733000 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'
May 17 00:44:19.737801 systemd[1]: Mounting boot.mount...
May 17 00:44:19.746292 systemd[1]: Mounted boot.mount.
May 17 00:44:19.756208 systemd[1]: Finished systemd-boot-update.service.
May 17 00:44:19.759000 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'
May 17 00:44:19.781298 systemd-tmpfiles[1345]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring.
May 17 00:44:19.861384 systemd-tmpfiles[1345]: Failed to adjust quota for subvolume "/srv": Bad file descriptor
May 17 00:44:19.925564 systemd-tmpfiles[1345]: Failed to adjust quota for subvolume "/var/lib/portables": Bad file descriptor
May 17 00:44:19.989462 systemd-tmpfiles[1345]: Failed to adjust quota for subvolume "/var/lib/machines": Bad file descriptor
May 17 00:44:19.996951 systemd-tmpfiles[1345]: Failed to adjust quota for subvolume "/var/tmp": Bad file descriptor
May 17 00:44:20.013575 systemd[1]: Finished systemd-tmpfiles-setup.service.
May 17 00:44:20.017000 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'
May 17 00:44:20.019914 systemd[1]: Starting audit-rules.service...
May 17 00:44:20.024848 systemd[1]: Starting clean-ca-certificates.service...
May 17 00:44:20.030445 systemd[1]: Starting systemd-journal-catalog-update.service...
May 17 00:44:20.035000 audit: BPF prog-id=30 op=LOAD
May 17 00:44:20.038758 systemd[1]: Starting systemd-resolved.service...
May 17 00:44:20.042000 audit: BPF prog-id=31 op=LOAD
May 17 00:44:20.044665 systemd[1]: Starting systemd-timesyncd.service...
May 17 00:44:20.051452 systemd[1]: Starting systemd-update-utmp.service...
May 17 00:44:20.125942 systemd[1]: Started systemd-timesyncd.service.
May 17 00:44:20.129000 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'
May 17 00:44:20.130749 systemd[1]: Reached target time-set.target.
May 17 00:44:20.137000 audit[1363]: SYSTEM_BOOT pid=1363 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'
May 17 00:44:20.141953 systemd[1]: Finished systemd-update-utmp.service.
May 17 00:44:20.145000 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'
May 17 00:44:20.176014 systemd[1]: Finished clean-ca-certificates.service.
May 17 00:44:20.180000 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'
May 17 00:44:20.181295 systemd-networkd[1280]: eth0: Gained IPv6LL
May 17 00:44:20.181345 systemd[1]: update-ca-certificates.service was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt).
May 17 00:44:20.183363 systemd[1]: Finished systemd-networkd-wait-online.service.
May 17 00:44:20.187000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-networkd-wait-online comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
May 17 00:44:20.233986 systemd-resolved[1360]: Positive Trust Anchors:
May 17 00:44:20.234003 systemd-resolved[1360]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
May 17 00:44:20.234029 systemd-resolved[1360]: 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
May 17 00:44:20.276247 systemd[1]: Finished systemd-journal-catalog-update.service.
May 17 00:44:20.280000 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'
May 17 00:44:20.296862 systemd-resolved[1360]: Using system hostname 'ci-3510.3.7-n-05b9cd7458'.
May 17 00:44:20.298920 systemd[1]: Started systemd-resolved.service.
May 17 00:44:20.302000 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'
May 17 00:44:20.304325 systemd[1]: Reached target network.target.
May 17 00:44:20.309505 systemd[1]: Reached target network-online.target.
May 17 00:44:20.314395 systemd[1]: Reached target nss-lookup.target.
May 17 00:44:20.462000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=add_rule key=(null) list=5 res=1
May 17 00:44:20.462000 audit[1378]: SYSCALL arch=c00000b7 syscall=206 success=yes exit=1056 a0=3 a1=fffff31e0e00 a2=420 a3=0 items=0 ppid=1357 pid=1378 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)
May 17 00:44:20.462000 audit: PROCTITLE proctitle=2F7362696E2F617564697463746C002D52002F6574632F61756469742F61756469742E72756C6573
May 17 00:44:20.464231 augenrules[1378]: No rules
May 17 00:44:20.464807 systemd[1]: Finished audit-rules.service.
May 17 00:44:20.528082 systemd-timesyncd[1362]: Contacted time server 198.46.254.130:123 (0.flatcar.pool.ntp.org).
May 17 00:44:20.528183 systemd-timesyncd[1362]: Initial clock synchronization to Sat 2025-05-17 00:44:20.544167 UTC.
May 17 00:44:28.429392 ldconfig[1341]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start.
May 17 00:44:28.440444 systemd[1]: Finished ldconfig.service.
May 17 00:44:28.446534 systemd[1]: Starting systemd-update-done.service...
May 17 00:44:28.470281 systemd[1]: Finished systemd-update-done.service.
May 17 00:44:28.475271 systemd[1]: Reached target sysinit.target.
May 17 00:44:28.479545 systemd[1]: Started motdgen.path.
May 17 00:44:28.483098 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path.
May 17 00:44:28.489269 systemd[1]: Started logrotate.timer.
May 17 00:44:28.493299 systemd[1]: Started mdadm.timer.
May 17 00:44:28.496796 systemd[1]: Started systemd-tmpfiles-clean.timer.
May 17 00:44:28.501544 systemd[1]: update-engine-stub.timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate).
May 17 00:44:28.501575 systemd[1]: Reached target paths.target.
May 17 00:44:28.505977 systemd[1]: Reached target timers.target.
May 17 00:44:28.510898 systemd[1]: Listening on dbus.socket.
May 17 00:44:28.516111 systemd[1]: Starting docker.socket...
May 17 00:44:28.535322 systemd[1]: Listening on sshd.socket.
May 17 00:44:28.539400 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).
May 17 00:44:28.539892 systemd[1]: Listening on docker.socket.
May 17 00:44:28.544227 systemd[1]: Reached target sockets.target.
May 17 00:44:28.548706 systemd[1]: Reached target basic.target.
May 17 00:44:28.553322 systemd[1]: addon-config@usr-share-oem.service was skipped because no trigger condition checks were met.
May 17 00:44:28.553348 systemd[1]: addon-run@usr-share-oem.service was skipped because no trigger condition checks were met.
May 17 00:44:28.554486 systemd[1]: Starting containerd.service...
May 17 00:44:28.559719 systemd[1]: Starting dbus.service...
May 17 00:44:28.564115 systemd[1]: Starting enable-oem-cloudinit.service...
May 17 00:44:28.569864 systemd[1]: Starting extend-filesystems.service...
May 17 00:44:28.576824 systemd[1]: flatcar-setup-environment.service was skipped because of an unmet condition check (ConditionPathExists=/usr/share/oem/bin/flatcar-setup-environment).
May 17 00:44:28.578231 systemd[1]: Starting motdgen.service...
May 17 00:44:28.583129 systemd[1]: Started nvidia.service.
May 17 00:44:28.589227 systemd[1]: Starting ssh-key-proc-cmdline.service...
May 17 00:44:28.594504 systemd[1]: Starting sshd-keygen.service...
May 17 00:44:28.600342 systemd[1]: Starting systemd-logind.service...
May 17 00:44:28.606050 systemd[1]: systemd-pcrphase.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f).
May 17 00:44:28.606107 systemd[1]: tcsd.service was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0).
May 17 00:44:28.606599 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details.
May 17 00:44:28.607366 systemd[1]: Starting update-engine.service...
May 17 00:44:28.612833 systemd[1]: Starting update-ssh-keys-after-ignition.service...
May 17 00:44:28.620253 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully.
May 17 00:44:28.620438 systemd[1]: Finished ssh-key-proc-cmdline.service.
May 17 00:44:28.679776 systemd[1]: motdgen.service: Deactivated successfully.
May 17 00:44:28.679962 systemd[1]: Finished motdgen.service.
May 17 00:44:28.690645 extend-filesystems[1389]: Found sda
May 17 00:44:28.696718 extend-filesystems[1389]: Found sda1
May 17 00:44:28.696718 extend-filesystems[1389]: Found sda2
May 17 00:44:28.696718 extend-filesystems[1389]: Found sda3
May 17 00:44:28.696718 extend-filesystems[1389]: Found usr
May 17 00:44:28.696718 extend-filesystems[1389]: Found sda4
May 17 00:44:28.696718 extend-filesystems[1389]: Found sda6
May 17 00:44:28.696718 extend-filesystems[1389]: Found sda7
May 17 00:44:28.696718 extend-filesystems[1389]: Found sda9
May 17 00:44:28.696718 extend-filesystems[1389]: Checking size of /dev/sda9
May 17 00:44:28.744244 jq[1388]: false
May 17 00:44:28.744345 jq[1404]: true
May 17 00:44:28.705651 systemd-logind[1399]: Watching system buttons on /dev/input/event1 (AT Translated Set 2 keyboard)
May 17 00:44:28.706712 systemd-logind[1399]: New seat seat0.
May 17 00:44:28.743575 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'.
May 17 00:44:28.743771 systemd[1]: Condition check resulted in enable-oem-cloudinit.service being skipped.
May 17 00:44:28.766947 env[1407]: time="2025-05-17T00:44:28.766882384Z" level=info msg="starting containerd" revision=92b3a9d6f1b3bcc6dc74875cfdea653fe39f09c2 version=1.6.16
May 17 00:44:28.776927 jq[1431]: true
May 17 00:44:28.805611 env[1407]: time="2025-05-17T00:44:28.805561301Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1
May 17 00:44:28.806106 env[1407]: time="2025-05-17T00:44:28.806081556Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1
May 17 00:44:28.808602 env[1407]: time="2025-05-17T00:44:28.808560516Z" 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.182-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1
May 17 00:44:28.808698 env[1407]: time="2025-05-17T00:44:28.808683715Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1
May 17 00:44:28.809169 env[1407]: time="2025-05-17T00:44:28.809135847Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1
May 17 00:44:28.809248 env[1407]: time="2025-05-17T00:44:28.809229308Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured"
May 17 00:44:28.809295 env[1407]: time="2025-05-17T00:44:28.809283262Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1
May 17 00:44:28.809394 env[1407]: time="2025-05-17T00:44:28.809381125Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1
May 17 00:44:28.809662 env[1407]: time="2025-05-17T00:44:28.809644696Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1
May 17 00:44:28.809875 env[1407]: time="2025-05-17T00:44:28.809855632Z" 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
May 17 00:44:28.809951 env[1407]: time="2025-05-17T00:44:28.809939406Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1
May 17 00:44:28.810038 env[1407]: time="2025-05-17T00:44:28.810024701Z" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured"
May 17 00:44:28.810084 env[1407]: time="2025-05-17T00:44:28.810073252Z" level=info msg="metadata content store policy set" policy=shared
May 17 00:44:28.829139 env[1407]: time="2025-05-17T00:44:28.829087761Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1
May 17 00:44:28.829293 env[1407]: time="2025-05-17T00:44:28.829277523Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1
May 17 00:44:28.829352 env[1407]: time="2025-05-17T00:44:28.829340404Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1
May 17 00:44:28.829439 env[1407]: time="2025-05-17T00:44:28.829426900Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1
May 17 00:44:28.829565 env[1407]: time="2025-05-17T00:44:28.829551060Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1
May 17 00:44:28.829630 env[1407]: time="2025-05-17T00:44:28.829618383Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1
May 17 00:44:28.829683 env[1407]: time="2025-05-17T00:44:28.829672258Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1
May 17 00:44:28.830185 env[1407]: time="2025-05-17T00:44:28.830127231Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1
May 17 00:44:28.830264 env[1407]: time="2025-05-17T00:44:28.830250111Z" level=info msg="loading plugin \"io.containerd.service.v1.leases-service\"..." type=io.containerd.service.v1
May 17 00:44:28.830320 env[1407]: time="2025-05-17T00:44:28.830308749Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1
May 17 00:44:28.830384 env[1407]: time="2025-05-17T00:44:28.830373310Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1
May 17 00:44:28.830436 env[1407]: time="2025-05-17T00:44:28.830424823Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1
May 17 00:44:28.830603 env[1407]: time="2025-05-17T00:44:28.830588929Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2
May 17 00:44:28.830735 env[1407]: time="2025-05-17T00:44:28.830706605Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1
May 17 00:44:28.831071 env[1407]: time="2025-05-17T00:44:28.831048466Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1
May 17 00:44:28.831177 env[1407]: time="2025-05-17T00:44:28.831141406Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1
May 17 00:44:28.831233 env[1407]: time="2025-05-17T00:44:28.831221097Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1
May 17 00:44:28.831324 env[1407]: time="2025-05-17T00:44:28.831312716Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1
May 17 00:44:28.831461 env[1407]: time="2025-05-17T00:44:28.831447844Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1
May 17 00:44:28.831518 env[1407]: time="2025-05-17T00:44:28.831505801Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1
May 17 00:44:28.831568 env[1407]: time="2025-05-17T00:44:28.831555673Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1
May 17 00:44:28.831618 env[1407]: time="2025-05-17T00:44:28.831606706Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1
May 17 00:44:28.831672 env[1407]: time="2025-05-17T00:44:28.831660100Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1
May 17 00:44:28.831725 env[1407]: time="2025-05-17T00:44:28.831712494Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1
May 17 00:44:28.831775 env[1407]: time="2025-05-17T00:44:28.831763327Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1
May 17 00:44:28.831846 env[1407]: time="2025-05-17T00:44:28.831834173Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1
May 17 00:44:28.831987 env[1407]: time="2025-05-17T00:44:28.831974423Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1
May 17 00:44:28.832045 env[1407]: time="2025-05-17T00:44:28.832033662Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1
May 17 00:44:28.832102 env[1407]: time="2025-05-17T00:44:28.832090058Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1
May 17 00:44:28.832174 env[1407]: time="2025-05-17T00:44:28.832140731Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1
May 17 00:44:28.832232 env[1407]: time="2025-05-17T00:44:28.832217660Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="no OpenTelemetry endpoint: skip plugin" type=io.containerd.tracing.processor.v1
May 17 00:44:28.832279 env[1407]: time="2025-05-17T00:44:28.832267412Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1
May 17 00:44:28.832342 env[1407]: time="2025-05-17T00:44:28.832328011Z" level=error msg="failed to initialize a tracing processor \"otlp\"" error="no OpenTelemetry endpoint: skip plugin"
May 17 00:44:28.832416 env[1407]: time="2025-05-17T00:44:28.832403500Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1
May 17 00:44:28.832706 env[1407]: time="2025-05-17T00:44:28.832657864Z" 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}"
May 17 00:44:28.868829 env[1407]: time="2025-05-17T00:44:28.832902222Z" level=info msg="Connect containerd service"
May 17 00:44:28.868829 env[1407]: time="2025-05-17T00:44:28.832945130Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\""
May 17 00:44:28.868829 env[1407]: time="2025-05-17T00:44:28.833551761Z" 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"
May 17 00:44:28.868829 env[1407]: time="2025-05-17T00:44:28.833786993Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc
May 17 00:44:28.868829 env[1407]: time="2025-05-17T00:44:28.833820014Z" level=info msg=serving... address=/run/containerd/containerd.sock
May 17 00:44:28.868829 env[1407]: time="2025-05-17T00:44:28.840475228Z" level=info msg="Start subscribing containerd event"
May 17 00:44:28.868829 env[1407]: time="2025-05-17T00:44:28.840559803Z" level=info msg="Start recovering state"
May 17 00:44:28.868829 env[1407]: time="2025-05-17T00:44:28.840638534Z" level=info msg="Start event monitor"
May 17 00:44:28.868829 env[1407]: time="2025-05-17T00:44:28.840660548Z" level=info msg="Start snapshots syncer"
May 17 00:44:28.868829 env[1407]: time="2025-05-17T00:44:28.840671195Z" level=info msg="Start cni network conf syncer for default"
May 17 00:44:28.868829 env[1407]: time="2025-05-17T00:44:28.840679760Z" level=info msg="Start streaming server"
May 17 00:44:28.868829 env[1407]: time="2025-05-17T00:44:28.848603713Z" level=info msg="containerd successfully booted in 0.082519s"
May 17 00:44:28.869068 extend-filesystems[1389]: Old size kept for /dev/sda9
May 17 00:44:28.869068 extend-filesystems[1389]: Found sr0
May 17 00:44:28.833934 systemd[1]: Started containerd.service.
May 17 00:44:28.860107 systemd[1]: extend-filesystems.service: Deactivated successfully.
May 17 00:44:28.860476 systemd[1]: Finished extend-filesystems.service.
May 17 00:44:28.954877 bash[1453]: Updated "/home/core/.ssh/authorized_keys"
May 17 00:44:28.955762 systemd[1]: Finished update-ssh-keys-after-ignition.service.
May 17 00:44:29.017806 dbus-daemon[1387]: [system] SELinux support is enabled
May 17 00:44:29.017982 systemd[1]: Started dbus.service.
May 17 00:44:29.023371 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).
May 17 00:44:29.023400 systemd[1]: Reached target system-config.target.
May 17 00:44:29.031685 systemd[1]: user-cloudinit-proc-cmdline.service was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url).
May 17 00:44:29.031708 systemd[1]: Reached target user-config.target.
May 17 00:44:29.036545 systemd[1]: Started systemd-logind.service.
May 17 00:44:29.052214 systemd[1]: nvidia.service: Deactivated successfully.
May 17 00:44:29.572798 update_engine[1402]: I0517 00:44:29.559603  1402 main.cc:92] Flatcar Update Engine starting
May 17 00:44:29.635784 systemd[1]: Started update-engine.service.
May 17 00:44:29.636174 update_engine[1402]: I0517 00:44:29.636049  1402 update_check_scheduler.cc:74] Next update check in 2m18s
May 17 00:44:29.641951 systemd[1]: Started locksmithd.service.
May 17 00:44:30.358069 sshd_keygen[1403]: ssh-keygen: generating new host keys: RSA ECDSA ED25519
May 17 00:44:30.376205 systemd[1]: Finished sshd-keygen.service.
May 17 00:44:30.382636 systemd[1]: Starting issuegen.service...
May 17 00:44:30.387384 systemd[1]: Started waagent.service.
May 17 00:44:30.391787 systemd[1]: issuegen.service: Deactivated successfully.
May 17 00:44:30.391953 systemd[1]: Finished issuegen.service.
May 17 00:44:30.398184 systemd[1]: Starting systemd-user-sessions.service...
May 17 00:44:30.434392 systemd[1]: Finished systemd-user-sessions.service.
May 17 00:44:30.441870 systemd[1]: Started getty@tty1.service.
May 17 00:44:30.448805 systemd[1]: Started serial-getty@ttyAMA0.service.
May 17 00:44:30.453726 systemd[1]: Reached target getty.target.
May 17 00:44:30.458254 systemd[1]: Reached target multi-user.target.
May 17 00:44:30.464114 systemd[1]: Starting systemd-update-utmp-runlevel.service...
May 17 00:44:30.472023 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully.
May 17 00:44:30.472200 systemd[1]: Finished systemd-update-utmp-runlevel.service.
May 17 00:44:30.484734 systemd[1]: Startup finished in 753ms (kernel) + 12.703s (initrd) + 23.120s (userspace) = 36.577s.
May 17 00:44:31.241185 login[1508]: pam_lastlog(login:session): file /var/log/lastlog is locked/write
May 17 00:44:31.241520 login[1507]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0)
May 17 00:44:31.344648 systemd[1]: Created slice user-500.slice.
May 17 00:44:31.345801 systemd[1]: Starting user-runtime-dir@500.service...
May 17 00:44:31.349197 systemd-logind[1399]: New session 1 of user core.
May 17 00:44:31.368087 locksmithd[1489]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot"
May 17 00:44:31.428261 systemd[1]: Finished user-runtime-dir@500.service.
May 17 00:44:31.429710 systemd[1]: Starting user@500.service...
May 17 00:44:31.474773 (systemd)[1511]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0)
May 17 00:44:31.653899 systemd[1511]: Queued start job for default target default.target.
May 17 00:44:31.654426 systemd[1511]: Reached target paths.target.
May 17 00:44:31.654444 systemd[1511]: Reached target sockets.target.
May 17 00:44:31.654453 systemd[1511]: Reached target timers.target.
May 17 00:44:31.654461 systemd[1511]: Reached target basic.target.
May 17 00:44:31.654501 systemd[1511]: Reached target default.target.
May 17 00:44:31.654525 systemd[1511]: Startup finished in 173ms.
May 17 00:44:31.654574 systemd[1]: Started user@500.service.
May 17 00:44:31.655532 systemd[1]: Started session-1.scope.
May 17 00:44:32.242696 login[1508]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0)
May 17 00:44:32.246740 systemd-logind[1399]: New session 2 of user core.
May 17 00:44:32.247217 systemd[1]: Started session-2.scope.
May 17 00:44:37.528662 waagent[1504]: 2025-05-17T00:44:37.528544Z INFO Daemon Daemon Azure Linux Agent Version:2.6.0.2
May 17 00:44:37.536255 waagent[1504]: 2025-05-17T00:44:37.536129Z INFO Daemon Daemon OS: flatcar 3510.3.7
May 17 00:44:37.541684 waagent[1504]: 2025-05-17T00:44:37.541595Z INFO Daemon Daemon Python: 3.9.16
May 17 00:44:37.546606 waagent[1504]: 2025-05-17T00:44:37.546484Z INFO Daemon Daemon Run daemon
May 17 00:44:37.551548 waagent[1504]: 2025-05-17T00:44:37.551456Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='3510.3.7'
May 17 00:44:37.569067 waagent[1504]: 2025-05-17T00:44:37.568905Z 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.
May 17 00:44:37.584294 waagent[1504]: 2025-05-17T00:44:37.584088Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service'
May 17 00:44:37.594732 waagent[1504]: 2025-05-17T00:44:37.594638Z INFO Daemon Daemon cloud-init is enabled: False
May 17 00:44:37.600847 waagent[1504]: 2025-05-17T00:44:37.600756Z INFO Daemon Daemon Using waagent for provisioning
May 17 00:44:37.606878 waagent[1504]: 2025-05-17T00:44:37.606794Z INFO Daemon Daemon Activate resource disk
May 17 00:44:37.612191 waagent[1504]: 2025-05-17T00:44:37.612083Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb
May 17 00:44:37.626995 waagent[1504]: 2025-05-17T00:44:37.626900Z INFO Daemon Daemon Found device: None
May 17 00:44:37.632116 waagent[1504]: 2025-05-17T00:44:37.632025Z ERROR Daemon Daemon Failed to mount resource disk [ResourceDiskError] unable to detect disk topology
May 17 00:44:37.640877 waagent[1504]: 2025-05-17T00:44:37.640784Z ERROR Daemon Daemon Event: name=WALinuxAgent, op=ActivateResourceDisk, message=[ResourceDiskError] unable to detect disk topology, duration=0
May 17 00:44:37.654012 waagent[1504]: 2025-05-17T00:44:37.653936Z INFO Daemon Daemon Clean protocol and wireserver endpoint
May 17 00:44:37.660812 waagent[1504]: 2025-05-17T00:44:37.660715Z INFO Daemon Daemon Running default provisioning handler
May 17 00:44:37.675308 waagent[1504]: 2025-05-17T00:44:37.675135Z 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.
May 17 00:44:37.690959 waagent[1504]: 2025-05-17T00:44:37.690807Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service'
May 17 00:44:37.700820 waagent[1504]: 2025-05-17T00:44:37.700724Z INFO Daemon Daemon cloud-init is enabled: False
May 17 00:44:37.706252 waagent[1504]: 2025-05-17T00:44:37.706132Z INFO Daemon Daemon Copying ovf-env.xml
May 17 00:44:37.796329 waagent[1504]: 2025-05-17T00:44:37.796123Z INFO Daemon Daemon Successfully mounted dvd
May 17 00:44:37.933583 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully.
May 17 00:44:37.979281 waagent[1504]: 2025-05-17T00:44:37.979106Z INFO Daemon Daemon Detect protocol endpoint
May 17 00:44:37.984650 waagent[1504]: 2025-05-17T00:44:37.984556Z INFO Daemon Daemon Clean protocol and wireserver endpoint
May 17 00:44:37.990466 waagent[1504]: 2025-05-17T00:44:37.990381Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler
May 17 00:44:37.997220 waagent[1504]: 2025-05-17T00:44:37.997122Z INFO Daemon Daemon Test for route to 168.63.129.16
May 17 00:44:38.002776 waagent[1504]: 2025-05-17T00:44:38.002698Z INFO Daemon Daemon Route to 168.63.129.16 exists
May 17 00:44:38.008294 waagent[1504]: 2025-05-17T00:44:38.008214Z INFO Daemon Daemon Wire server endpoint:168.63.129.16
May 17 00:44:38.177566 waagent[1504]: 2025-05-17T00:44:38.177498Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05
May 17 00:44:38.185009 waagent[1504]: 2025-05-17T00:44:38.184958Z INFO Daemon Daemon Wire protocol version:2012-11-30
May 17 00:44:38.190597 waagent[1504]: 2025-05-17T00:44:38.190504Z INFO Daemon Daemon Server preferred version:2015-04-05
May 17 00:44:39.054347 waagent[1504]: 2025-05-17T00:44:39.054187Z INFO Daemon Daemon Initializing goal state during protocol detection
May 17 00:44:39.087417 waagent[1504]: 2025-05-17T00:44:39.087321Z INFO Daemon Daemon Forcing an update of the goal state..
May 17 00:44:39.093339 waagent[1504]: 2025-05-17T00:44:39.093235Z INFO Daemon Daemon Fetching goal state [incarnation 1]
May 17 00:44:39.308532 waagent[1504]: 2025-05-17T00:44:39.308363Z INFO Daemon Daemon Found private key matching thumbprint 349F76D3F78626E633078FAD4247F09831793925
May 17 00:44:39.317583 waagent[1504]: 2025-05-17T00:44:39.317486Z INFO Daemon Daemon Certificate with thumbprint 35A60C9F0EA99CA36B6D537E29AD189AFB7831EF has no matching private key.
May 17 00:44:39.327376 waagent[1504]: 2025-05-17T00:44:39.327286Z INFO Daemon Daemon Fetch goal state completed
May 17 00:44:39.380922 waagent[1504]: 2025-05-17T00:44:39.380865Z INFO Daemon Daemon Fetched new vmSettings [correlation ID: 3cee693f-803d-4d7e-a0a4-ca48ec6a3e7d New eTag: 1714516889334212388]
May 17 00:44:39.391579 waagent[1504]: 2025-05-17T00:44:39.391489Z INFO Daemon Daemon Status Blob type 'None' is not valid, assuming BlockBlob
May 17 00:44:39.446020 waagent[1504]: 2025-05-17T00:44:39.445916Z INFO Daemon Daemon Starting provisioning
May 17 00:44:39.451422 waagent[1504]: 2025-05-17T00:44:39.451310Z INFO Daemon Daemon Handle ovf-env.xml.
May 17 00:44:39.456634 waagent[1504]: 2025-05-17T00:44:39.456532Z INFO Daemon Daemon Set hostname [ci-3510.3.7-n-05b9cd7458]
May 17 00:44:39.496817 waagent[1504]: 2025-05-17T00:44:39.496635Z INFO Daemon Daemon Publish hostname [ci-3510.3.7-n-05b9cd7458]
May 17 00:44:39.505348 waagent[1504]: 2025-05-17T00:44:39.505211Z INFO Daemon Daemon Examine /proc/net/route for primary interface
May 17 00:44:39.513473 waagent[1504]: 2025-05-17T00:44:39.513385Z INFO Daemon Daemon Primary interface is [eth0]
May 17 00:44:39.534377 systemd[1]: systemd-networkd-wait-online.service: Deactivated successfully.
May 17 00:44:39.534615 systemd[1]: Stopped systemd-networkd-wait-online.service.
May 17 00:44:39.534712 systemd[1]: Stopping systemd-networkd-wait-online.service...
May 17 00:44:39.535023 systemd[1]: Stopping systemd-networkd.service...
May 17 00:44:39.541198 systemd-networkd[1280]: eth0: DHCPv6 lease lost
May 17 00:44:39.543684 systemd[1]: systemd-networkd.service: Deactivated successfully.
May 17 00:44:39.543976 systemd[1]: Stopped systemd-networkd.service.
May 17 00:44:39.546866 systemd[1]: Starting systemd-networkd.service...
May 17 00:44:39.581344 systemd-networkd[1556]: enP45100s1: Link UP
May 17 00:44:39.581385 systemd-networkd[1556]: enP45100s1: Gained carrier
May 17 00:44:39.582906 systemd-networkd[1556]: eth0: Link UP
May 17 00:44:39.582916 systemd-networkd[1556]: eth0: Gained carrier
May 17 00:44:39.583366 systemd-networkd[1556]: lo: Link UP
May 17 00:44:39.583375 systemd-networkd[1556]: lo: Gained carrier
May 17 00:44:39.583720 systemd-networkd[1556]: eth0: Gained IPv6LL
May 17 00:44:39.585178 systemd-networkd[1556]: Enumeration completed
May 17 00:44:39.585327 systemd[1]: Started systemd-networkd.service.
May 17 00:44:39.587635 systemd[1]: Starting systemd-networkd-wait-online.service...
May 17 00:44:39.588331 waagent[1504]: 2025-05-17T00:44:39.587920Z INFO Daemon Daemon Create user account if not exists
May 17 00:44:39.596408 systemd-networkd[1556]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network.
May 17 00:44:39.597632 waagent[1504]: 2025-05-17T00:44:39.597507Z INFO Daemon Daemon User core already exists, skip useradd
May 17 00:44:39.607976 waagent[1504]: 2025-05-17T00:44:39.607775Z INFO Daemon Daemon Configure sudoer
May 17 00:44:39.613950 waagent[1504]: 2025-05-17T00:44:39.613849Z INFO Daemon Daemon Configure sshd
May 17 00:44:39.619177 systemd-networkd[1556]: eth0: DHCPv4 address 10.200.20.22/24, gateway 10.200.20.1 acquired from 168.63.129.16
May 17 00:44:39.621012 waagent[1504]: 2025-05-17T00:44:39.620883Z INFO Daemon Daemon Deploy ssh public key.
May 17 00:44:39.623132 systemd[1]: Finished systemd-networkd-wait-online.service.
May 17 00:44:40.828116 waagent[1504]: 2025-05-17T00:44:40.828051Z INFO Daemon Daemon Provisioning complete
May 17 00:44:40.845813 waagent[1504]: 2025-05-17T00:44:40.845745Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping
May 17 00:44:40.852536 waagent[1504]: 2025-05-17T00:44:40.852436Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions.
May 17 00:44:40.863823 waagent[1504]: 2025-05-17T00:44:40.863723Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.6.0.2 is the most current agent
May 17 00:44:41.183074 waagent[1565]: 2025-05-17T00:44:41.182968Z INFO ExtHandler ExtHandler Agent WALinuxAgent-2.6.0.2 is running as the goal state agent
May 17 00:44:41.184036 waagent[1565]: 2025-05-17T00:44:41.183965Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file
May 17 00:44:41.184200 waagent[1565]: 2025-05-17T00:44:41.184130Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16
May 17 00:44:41.198605 waagent[1565]: 2025-05-17T00:44:41.198449Z INFO ExtHandler ExtHandler Forcing an update of the goal state..
May 17 00:44:41.198838 waagent[1565]: 2025-05-17T00:44:41.198782Z INFO ExtHandler ExtHandler Fetching goal state [incarnation 1]
May 17 00:44:41.278774 waagent[1565]: 2025-05-17T00:44:41.278616Z INFO ExtHandler ExtHandler Found private key matching thumbprint 349F76D3F78626E633078FAD4247F09831793925
May 17 00:44:41.279021 waagent[1565]: 2025-05-17T00:44:41.278963Z INFO ExtHandler ExtHandler Certificate with thumbprint 35A60C9F0EA99CA36B6D537E29AD189AFB7831EF has no matching private key.
May 17 00:44:41.279311 waagent[1565]: 2025-05-17T00:44:41.279253Z INFO ExtHandler ExtHandler Fetch goal state completed
May 17 00:44:41.294772 waagent[1565]: 2025-05-17T00:44:41.294709Z INFO ExtHandler ExtHandler Fetched new vmSettings [correlation ID: c3f97091-ea6e-4bca-98ad-6d53db6f5845 New eTag: 1714516889334212388]
May 17 00:44:41.295462 waagent[1565]: 2025-05-17T00:44:41.295394Z INFO ExtHandler ExtHandler Status Blob type 'None' is not valid, assuming BlockBlob
May 17 00:44:41.384603 waagent[1565]: 2025-05-17T00:44:41.384449Z 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;
May 17 00:44:41.408162 waagent[1565]: 2025-05-17T00:44:41.408059Z INFO ExtHandler ExtHandler WALinuxAgent-2.6.0.2 running as process 1565
May 17 00:44:41.412283 waagent[1565]: 2025-05-17T00:44:41.412197Z INFO ExtHandler ExtHandler Cgroup monitoring is not supported on ['flatcar', '3510.3.7', '', 'Flatcar Container Linux by Kinvolk']
May 17 00:44:41.413738 waagent[1565]: 2025-05-17T00:44:41.413665Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules
May 17 00:44:41.523253 waagent[1565]: 2025-05-17T00:44:41.523140Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service
May 17 00:44:41.523997 waagent[1565]: 2025-05-17T00:44:41.523922Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup
May 17 00:44:41.532744 waagent[1565]: 2025-05-17T00:44:41.532665Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now
May 17 00:44:41.533445 waagent[1565]: 2025-05-17T00:44:41.533373Z ERROR ExtHandler ExtHandler Unable to setup the persistent firewall rules: [Errno 30] Read-only file system: '/lib/systemd/system/waagent-network-setup.service'
May 17 00:44:41.534923 waagent[1565]: 2025-05-17T00:44:41.534847Z 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]
May 17 00:44:41.536652 waagent[1565]: 2025-05-17T00:44:41.536559Z INFO ExtHandler ExtHandler Starting env monitor service.
May 17 00:44:41.537316 waagent[1565]: 2025-05-17T00:44:41.537140Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file
May 17 00:44:41.537961 waagent[1565]: 2025-05-17T00:44:41.537895Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16
May 17 00:44:41.538685 waagent[1565]: 2025-05-17T00:44:41.538627Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled.
May 17 00:44:41.539085 waagent[1565]: 2025-05-17T00:44:41.539017Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route:
May 17 00:44:41.539085 waagent[1565]: Iface        Destination        Gateway         Flags        RefCnt        Use        Metric        Mask                MTU        Window        IRTT
May 17 00:44:41.539085 waagent[1565]: eth0        00000000        0114C80A        0003        0        0        1024        00000000        0        0        0
May 17 00:44:41.539085 waagent[1565]: eth0        0014C80A        00000000        0001        0        0        1024        00FFFFFF        0        0        0
May 17 00:44:41.539085 waagent[1565]: eth0        0114C80A        00000000        0005        0        0        1024        FFFFFFFF        0        0        0
May 17 00:44:41.539085 waagent[1565]: eth0        10813FA8        0114C80A        0007        0        0        1024        FFFFFFFF        0        0        0
May 17 00:44:41.539085 waagent[1565]: eth0        FEA9FEA9        0114C80A        0007        0        0        1024        FFFFFFFF        0        0        0
May 17 00:44:41.541752 waagent[1565]: 2025-05-17T00:44:41.541578Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service.
May 17 00:44:41.542644 waagent[1565]: 2025-05-17T00:44:41.542568Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file
May 17 00:44:41.542828 waagent[1565]: 2025-05-17T00:44:41.542776Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16
May 17 00:44:41.543455 waagent[1565]: 2025-05-17T00:44:41.543391Z INFO EnvHandler ExtHandler Configure routes
May 17 00:44:41.543612 waagent[1565]: 2025-05-17T00:44:41.543566Z INFO EnvHandler ExtHandler Gateway:None
May 17 00:44:41.543727 waagent[1565]: 2025-05-17T00:44:41.543686Z INFO EnvHandler ExtHandler Routes:None
May 17 00:44:41.544672 waagent[1565]: 2025-05-17T00:44:41.544612Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread
May 17 00:44:41.544811 waagent[1565]: 2025-05-17T00:44:41.544745Z INFO ExtHandler ExtHandler Start Extension Telemetry service.
May 17 00:44:41.545656 waagent[1565]: 2025-05-17T00:44:41.545562Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True
May 17 00:44:41.545818 waagent[1565]: 2025-05-17T00:44:41.545752Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status.
May 17 00:44:41.546113 waagent[1565]: 2025-05-17T00:44:41.546051Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread
May 17 00:44:41.559345 waagent[1565]: 2025-05-17T00:44:41.559266Z INFO ExtHandler ExtHandler Checking for agent updates (family: Prod)
May 17 00:44:41.560131 waagent[1565]: 2025-05-17T00:44:41.560082Z WARNING ExtHandler ExtHandler Fetch failed: [HttpError] HTTPS is unavailable and required
May 17 00:44:41.561274 waagent[1565]: 2025-05-17T00:44:41.561214Z INFO ExtHandler ExtHandler [PERIODIC] Request failed using the direct channel. Error: 'NoneType' object has no attribute 'getheaders'
May 17 00:44:41.582431 waagent[1565]: 2025-05-17T00:44:41.582303Z ERROR EnvHandler ExtHandler Failed to get the PID of the DHCP client: invalid literal for int() with base 10: 'MainPID=1556'
May 17 00:44:41.633116 waagent[1565]: 2025-05-17T00:44:41.633046Z INFO ExtHandler ExtHandler Default channel changed to HostGA channel.
May 17 00:44:41.653329 waagent[1565]: 2025-05-17T00:44:41.653140Z INFO MonitorHandler ExtHandler Network interfaces:
May 17 00:44:41.653329 waagent[1565]: Executing ['ip', '-a', '-o', 'link']:
May 17 00:44:41.653329 waagent[1565]: 1: lo: <LOOPBACK,UP,LOWER_UP> 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
May 17 00:44:41.653329 waagent[1565]: 2: eth0: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\    link/ether 00:22:48:b5:48:6b brd ff:ff:ff:ff:ff:ff
May 17 00:44:41.653329 waagent[1565]: 3: enP45100s1: <BROADCAST,MULTICAST,SLAVE,UP,LOWER_UP> mtu 1500 qdisc mq master eth0 state UP mode DEFAULT group default qlen 1000\    link/ether 00:22:48:b5:48:6b brd ff:ff:ff:ff:ff:ff\    altname enP45100p0s2
May 17 00:44:41.653329 waagent[1565]: Executing ['ip', '-4', '-a', '-o', 'address']:
May 17 00:44:41.653329 waagent[1565]: 1: lo    inet 127.0.0.1/8 scope host lo\       valid_lft forever preferred_lft forever
May 17 00:44:41.653329 waagent[1565]: 2: eth0    inet 10.200.20.22/24 metric 1024 brd 10.200.20.255 scope global eth0\       valid_lft forever preferred_lft forever
May 17 00:44:41.653329 waagent[1565]: Executing ['ip', '-6', '-a', '-o', 'address']:
May 17 00:44:41.653329 waagent[1565]: 1: lo    inet6 ::1/128 scope host \       valid_lft forever preferred_lft forever
May 17 00:44:41.653329 waagent[1565]: 2: eth0    inet6 fe80::222:48ff:feb5:486b/64 scope link \       valid_lft forever preferred_lft forever
May 17 00:44:41.964991 waagent[1565]: 2025-05-17T00:44:41.964923Z INFO ExtHandler ExtHandler Agent WALinuxAgent-2.6.0.2 discovered update WALinuxAgent-2.13.1.1 -- exiting
May 17 00:44:42.868653 waagent[1504]: 2025-05-17T00:44:42.868533Z 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
May 17 00:44:42.873778 waagent[1504]: 2025-05-17T00:44:42.873718Z INFO Daemon Daemon Determined Agent WALinuxAgent-2.13.1.1 to be the latest agent
May 17 00:44:44.166107 waagent[1603]: 2025-05-17T00:44:44.166005Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.13.1.1)
May 17 00:44:44.167696 waagent[1603]: 2025-05-17T00:44:44.167626Z INFO ExtHandler ExtHandler OS: flatcar 3510.3.7
May 17 00:44:44.167832 waagent[1603]: 2025-05-17T00:44:44.167788Z INFO ExtHandler ExtHandler Python: 3.9.16
May 17 00:44:44.167957 waagent[1603]: 2025-05-17T00:44:44.167916Z INFO ExtHandler ExtHandler CPU Arch: aarch64
May 17 00:44:44.181781 waagent[1603]: 2025-05-17T00:44:44.181637Z 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;
May 17 00:44:44.182248 waagent[1603]: 2025-05-17T00:44:44.182193Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file
May 17 00:44:44.182395 waagent[1603]: 2025-05-17T00:44:44.182352Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16
May 17 00:44:44.182601 waagent[1603]: 2025-05-17T00:44:44.182556Z INFO ExtHandler ExtHandler Initializing the goal state...
May 17 00:44:44.196704 waagent[1603]: 2025-05-17T00:44:44.196620Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1]
May 17 00:44:44.221488 waagent[1603]: 2025-05-17T00:44:44.221432Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.164
May 17 00:44:44.222660 waagent[1603]: 2025-05-17T00:44:44.222602Z INFO ExtHandler
May 17 00:44:44.222807 waagent[1603]: 2025-05-17T00:44:44.222763Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: 18ba29bf-99c9-438d-9667-568f15ecb756 eTag: 1714516889334212388 source: Fabric]
May 17 00:44:44.223597 waagent[1603]: 2025-05-17T00:44:44.223539Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them.
May 17 00:44:44.224807 waagent[1603]: 2025-05-17T00:44:44.224750Z INFO ExtHandler
May 17 00:44:44.224936 waagent[1603]: 2025-05-17T00:44:44.224893Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1]
May 17 00:44:44.232149 waagent[1603]: 2025-05-17T00:44:44.232098Z INFO ExtHandler ExtHandler Downloading artifacts profile blob
May 17 00:44:44.232679 waagent[1603]: 2025-05-17T00:44:44.232632Z WARNING ExtHandler ExtHandler Fetch failed: [HttpError] HTTPS is unavailable and required
May 17 00:44:44.252275 waagent[1603]: 2025-05-17T00:44:44.252214Z INFO ExtHandler ExtHandler Default channel changed to HostGAPlugin channel.
May 17 00:44:44.327320 waagent[1603]: 2025-05-17T00:44:44.327173Z INFO ExtHandler Downloaded certificate {'thumbprint': '35A60C9F0EA99CA36B6D537E29AD189AFB7831EF', 'hasPrivateKey': False}
May 17 00:44:44.328438 waagent[1603]: 2025-05-17T00:44:44.328375Z INFO ExtHandler Downloaded certificate {'thumbprint': '349F76D3F78626E633078FAD4247F09831793925', 'hasPrivateKey': True}
May 17 00:44:44.329550 waagent[1603]: 2025-05-17T00:44:44.329492Z INFO ExtHandler Fetch goal state from WireServer completed
May 17 00:44:44.330509 waagent[1603]: 2025-05-17T00:44:44.330448Z INFO ExtHandler ExtHandler Goal state initialization completed.
May 17 00:44:44.348619 waagent[1603]: 2025-05-17T00:44:44.348492Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.0.15 3 Sep 2024 (Library: OpenSSL 3.0.15 3 Sep 2024)
May 17 00:44:44.357681 waagent[1603]: 2025-05-17T00:44:44.357553Z INFO ExtHandler ExtHandler Using iptables [version 1.8.8] to manage firewall rules
May 17 00:44:44.361652 waagent[1603]: 2025-05-17T00:44:44.361521Z 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']
May 17 00:44:44.361880 waagent[1603]: 2025-05-17T00:44:44.361830Z INFO ExtHandler ExtHandler Checking state of the firewall
May 17 00:44:44.402549 waagent[1603]: 2025-05-17T00:44:44.402409Z WARNING ExtHandler ExtHandler The firewall rules for Azure Fabric are not setup correctly (the environment thread will fix it): The following rules are missing: ['ACCEPT DNS', 'DROP'] due to: ['iptables: Bad rule (does a matching rule exist in that chain?).\n', 'iptables: Bad rule (does a matching rule exist in that chain?).\n']. Current state:
May 17 00:44:44.402549 waagent[1603]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes)
May 17 00:44:44.402549 waagent[1603]:     pkts      bytes target     prot opt in     out     source               destination
May 17 00:44:44.402549 waagent[1603]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes)
May 17 00:44:44.402549 waagent[1603]:     pkts      bytes target     prot opt in     out     source               destination
May 17 00:44:44.402549 waagent[1603]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes)
May 17 00:44:44.402549 waagent[1603]:     pkts      bytes target     prot opt in     out     source               destination
May 17 00:44:44.402549 waagent[1603]:       54     7806 ACCEPT     tcp  --  *      *       0.0.0.0/0            168.63.129.16        owner UID match 0
May 17 00:44:44.403730 waagent[1603]: 2025-05-17T00:44:44.403666Z INFO ExtHandler ExtHandler Setting up persistent firewall rules
May 17 00:44:44.406656 waagent[1603]: 2025-05-17T00:44:44.406529Z INFO ExtHandler ExtHandler The firewalld service is not present on the system
May 17 00:44:44.406929 waagent[1603]: 2025-05-17T00:44:44.406880Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service
May 17 00:44:44.407301 waagent[1603]: 2025-05-17T00:44:44.407249Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup
May 17 00:44:44.415174 waagent[1603]: 2025-05-17T00:44:44.415083Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now
May 17 00:44:44.415760 waagent[1603]: 2025-05-17T00:44:44.415701Z ERROR ExtHandler ExtHandler Unable to setup the persistent firewall rules: [Errno 30] Read-only file system: '/lib/systemd/system/waagent-network-setup.service'
May 17 00:44:44.424405 waagent[1603]: 2025-05-17T00:44:44.424311Z INFO ExtHandler ExtHandler WALinuxAgent-2.13.1.1 running as process 1603
May 17 00:44:44.427653 waagent[1603]: 2025-05-17T00:44:44.427575Z INFO ExtHandler ExtHandler [CGI] Cgroups is not currently supported on ['flatcar', '3510.3.7', '', 'Flatcar Container Linux by Kinvolk']
May 17 00:44:44.428592 waagent[1603]: 2025-05-17T00:44:44.428534Z INFO ExtHandler ExtHandler [CGI] Agent will reset the quotas in case cgroup usage went from enabled to disabled
May 17 00:44:44.429525 waagent[1603]: 2025-05-17T00:44:44.429470Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False
May 17 00:44:44.432430 waagent[1603]: 2025-05-17T00:44:44.432370Z 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]
May 17 00:44:44.433861 waagent[1603]: 2025-05-17T00:44:44.433790Z INFO ExtHandler ExtHandler Starting env monitor service.
May 17 00:44:44.434365 waagent[1603]: 2025-05-17T00:44:44.434307Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file
May 17 00:44:44.434597 waagent[1603]: 2025-05-17T00:44:44.434551Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16
May 17 00:44:44.435232 waagent[1603]: 2025-05-17T00:44:44.435176Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled.
May 17 00:44:44.435600 waagent[1603]: 2025-05-17T00:44:44.435550Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route:
May 17 00:44:44.435600 waagent[1603]: Iface        Destination        Gateway         Flags        RefCnt        Use        Metric        Mask                MTU        Window        IRTT
May 17 00:44:44.435600 waagent[1603]: eth0        00000000        0114C80A        0003        0        0        1024        00000000        0        0        0
May 17 00:44:44.435600 waagent[1603]: eth0        0014C80A        00000000        0001        0        0        1024        00FFFFFF        0        0        0
May 17 00:44:44.435600 waagent[1603]: eth0        0114C80A        00000000        0005        0        0        1024        FFFFFFFF        0        0        0
May 17 00:44:44.435600 waagent[1603]: eth0        10813FA8        0114C80A        0007        0        0        1024        FFFFFFFF        0        0        0
May 17 00:44:44.435600 waagent[1603]: eth0        FEA9FEA9        0114C80A        0007        0        0        1024        FFFFFFFF        0        0        0
May 17 00:44:44.438341 waagent[1603]: 2025-05-17T00:44:44.438195Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file
May 17 00:44:44.438653 waagent[1603]: 2025-05-17T00:44:44.438556Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16
May 17 00:44:44.439078 waagent[1603]: 2025-05-17T00:44:44.439009Z INFO EnvHandler ExtHandler Configure routes
May 17 00:44:44.439283 waagent[1603]: 2025-05-17T00:44:44.439219Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service.
May 17 00:44:44.439892 waagent[1603]: 2025-05-17T00:44:44.439831Z INFO EnvHandler ExtHandler Gateway:None
May 17 00:44:44.440199 waagent[1603]: 2025-05-17T00:44:44.440085Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread
May 17 00:44:44.440442 waagent[1603]: 2025-05-17T00:44:44.440384Z INFO ExtHandler ExtHandler Start Extension Telemetry service.
May 17 00:44:44.441309 waagent[1603]: 2025-05-17T00:44:44.440958Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True
May 17 00:44:44.441400 waagent[1603]: 2025-05-17T00:44:44.441333Z INFO EnvHandler ExtHandler Routes:None
May 17 00:44:44.443242 waagent[1603]: 2025-05-17T00:44:44.443004Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status.
May 17 00:44:44.444063 waagent[1603]: 2025-05-17T00:44:44.443982Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread
May 17 00:44:44.464521 waagent[1603]: 2025-05-17T00:44:44.464437Z INFO ExtHandler ExtHandler Downloading agent manifest
May 17 00:44:44.469377 waagent[1603]: 2025-05-17T00:44:44.469302Z INFO MonitorHandler ExtHandler Network interfaces:
May 17 00:44:44.469377 waagent[1603]: Executing ['ip', '-a', '-o', 'link']:
May 17 00:44:44.469377 waagent[1603]: 1: lo: <LOOPBACK,UP,LOWER_UP> 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
May 17 00:44:44.469377 waagent[1603]: 2: eth0: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\    link/ether 00:22:48:b5:48:6b brd ff:ff:ff:ff:ff:ff
May 17 00:44:44.469377 waagent[1603]: 3: enP45100s1: <BROADCAST,MULTICAST,SLAVE,UP,LOWER_UP> mtu 1500 qdisc mq master eth0 state UP mode DEFAULT group default qlen 1000\    link/ether 00:22:48:b5:48:6b brd ff:ff:ff:ff:ff:ff\    altname enP45100p0s2
May 17 00:44:44.469377 waagent[1603]: Executing ['ip', '-4', '-a', '-o', 'address']:
May 17 00:44:44.469377 waagent[1603]: 1: lo    inet 127.0.0.1/8 scope host lo\       valid_lft forever preferred_lft forever
May 17 00:44:44.469377 waagent[1603]: 2: eth0    inet 10.200.20.22/24 metric 1024 brd 10.200.20.255 scope global eth0\       valid_lft forever preferred_lft forever
May 17 00:44:44.469377 waagent[1603]: Executing ['ip', '-6', '-a', '-o', 'address']:
May 17 00:44:44.469377 waagent[1603]: 1: lo    inet6 ::1/128 scope host \       valid_lft forever preferred_lft forever
May 17 00:44:44.469377 waagent[1603]: 2: eth0    inet6 fe80::222:48ff:feb5:486b/64 scope link \       valid_lft forever preferred_lft forever
May 17 00:44:44.470827 waagent[1603]: 2025-05-17T00:44:44.470752Z INFO EnvHandler ExtHandler Using iptables [version 1.8.8] to manage firewall rules
May 17 00:44:44.492753 waagent[1603]: 2025-05-17T00:44:44.492675Z INFO ExtHandler ExtHandler
May 17 00:44:44.493794 waagent[1603]: 2025-05-17T00:44:44.493734Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: a0e29cad-07d2-4883-99e1-a8bb525e41ca correlation af7b981f-1c91-4182-acfc-20e73bb8e45a created: 2025-05-17T00:43:07.621773Z]
May 17 00:44:44.496092 waagent[1603]: 2025-05-17T00:44:44.496012Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything.
May 17 00:44:44.499527 waagent[1603]: 2025-05-17T00:44:44.499459Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 6 ms]
May 17 00:44:44.520615 waagent[1603]: 2025-05-17T00:44:44.520548Z INFO ExtHandler ExtHandler Looking for existing remote access users.
May 17 00:44:44.526506 waagent[1603]: 2025-05-17T00:44:44.526407Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.13.1.1 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: 6143D20D-E050-4A06-B040-CB836634AB46;UpdateGSErrors: 0;AutoUpdate: 1;UpdateMode: SelfUpdate;]
May 17 00:44:44.536841 waagent[1603]: 2025-05-17T00:44:44.536711Z WARNING EnvHandler ExtHandler The firewall is not configured correctly. The following rules are missing: ['ACCEPT DNS', 'DROP'] due to: ['iptables: Bad rule (does a matching rule exist in that chain?).\n', 'iptables: Bad rule (does a matching rule exist in that chain?).\n']. Will reset it. Current state:
May 17 00:44:44.536841 waagent[1603]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes)
May 17 00:44:44.536841 waagent[1603]:     pkts      bytes target     prot opt in     out     source               destination
May 17 00:44:44.536841 waagent[1603]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes)
May 17 00:44:44.536841 waagent[1603]:     pkts      bytes target     prot opt in     out     source               destination
May 17 00:44:44.536841 waagent[1603]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes)
May 17 00:44:44.536841 waagent[1603]:     pkts      bytes target     prot opt in     out     source               destination
May 17 00:44:44.536841 waagent[1603]:       83    14277 ACCEPT     tcp  --  *      *       0.0.0.0/0            168.63.129.16        owner UID match 0
May 17 00:44:44.592096 waagent[1603]: 2025-05-17T00:44:44.591976Z INFO EnvHandler ExtHandler The firewall was setup successfully:
May 17 00:44:44.592096 waagent[1603]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes)
May 17 00:44:44.592096 waagent[1603]:     pkts      bytes target     prot opt in     out     source               destination
May 17 00:44:44.592096 waagent[1603]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes)
May 17 00:44:44.592096 waagent[1603]:     pkts      bytes target     prot opt in     out     source               destination
May 17 00:44:44.592096 waagent[1603]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes)
May 17 00:44:44.592096 waagent[1603]:     pkts      bytes target     prot opt in     out     source               destination
May 17 00:44:44.592096 waagent[1603]:        0        0 ACCEPT     tcp  --  *      *       0.0.0.0/0            168.63.129.16        tcp dpt:53
May 17 00:44:44.592096 waagent[1603]:        4      595 ACCEPT     tcp  --  *      *       0.0.0.0/0            168.63.129.16        owner UID match 0
May 17 00:44:44.592096 waagent[1603]:        0        0 DROP       tcp  --  *      *       0.0.0.0/0            168.63.129.16        ctstate INVALID,NEW
May 17 00:44:44.595059 waagent[1603]: 2025-05-17T00:44:44.595002Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300
May 17 00:44:54.522484 systemd[1]: Created slice system-sshd.slice.
May 17 00:44:54.523578 systemd[1]: Started sshd@0-10.200.20.22:22-10.200.16.10:57520.service.
May 17 00:44:55.154903 sshd[1650]: Accepted publickey for core from 10.200.16.10 port 57520 ssh2: RSA SHA256:kTalk4vvVOHJD+odK+kI4Z4CxTmNI3TSVyFiPn8PnHg
May 17 00:44:55.170756 sshd[1650]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0)
May 17 00:44:55.175331 systemd[1]: Started session-3.scope.
May 17 00:44:55.176119 systemd-logind[1399]: New session 3 of user core.
May 17 00:44:55.559134 systemd[1]: Started sshd@1-10.200.20.22:22-10.200.16.10:57534.service.
May 17 00:44:55.991825 sshd[1655]: Accepted publickey for core from 10.200.16.10 port 57534 ssh2: RSA SHA256:kTalk4vvVOHJD+odK+kI4Z4CxTmNI3TSVyFiPn8PnHg
May 17 00:44:55.993113 sshd[1655]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0)
May 17 00:44:55.996933 systemd-logind[1399]: New session 4 of user core.
May 17 00:44:55.997394 systemd[1]: Started session-4.scope.
May 17 00:44:56.309855 sshd[1655]: pam_unix(sshd:session): session closed for user core
May 17 00:44:56.312392 systemd[1]: sshd@1-10.200.20.22:22-10.200.16.10:57534.service: Deactivated successfully.
May 17 00:44:56.313071 systemd[1]: session-4.scope: Deactivated successfully.
May 17 00:44:56.313610 systemd-logind[1399]: Session 4 logged out. Waiting for processes to exit.
May 17 00:44:56.314500 systemd-logind[1399]: Removed session 4.
May 17 00:44:56.383480 systemd[1]: Started sshd@2-10.200.20.22:22-10.200.16.10:57540.service.
May 17 00:44:56.827386 sshd[1661]: Accepted publickey for core from 10.200.16.10 port 57540 ssh2: RSA SHA256:kTalk4vvVOHJD+odK+kI4Z4CxTmNI3TSVyFiPn8PnHg
May 17 00:44:56.828624 sshd[1661]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0)
May 17 00:44:56.832414 systemd-logind[1399]: New session 5 of user core.
May 17 00:44:56.832818 systemd[1]: Started session-5.scope.
May 17 00:44:57.149999 sshd[1661]: pam_unix(sshd:session): session closed for user core
May 17 00:44:57.152549 systemd[1]: sshd@2-10.200.20.22:22-10.200.16.10:57540.service: Deactivated successfully.
May 17 00:44:57.153275 systemd[1]: session-5.scope: Deactivated successfully.
May 17 00:44:57.153810 systemd-logind[1399]: Session 5 logged out. Waiting for processes to exit.
May 17 00:44:57.154610 systemd-logind[1399]: Removed session 5.
May 17 00:44:57.218316 systemd[1]: Started sshd@3-10.200.20.22:22-10.200.16.10:57544.service.
May 17 00:44:57.634758 sshd[1667]: Accepted publickey for core from 10.200.16.10 port 57544 ssh2: RSA SHA256:kTalk4vvVOHJD+odK+kI4Z4CxTmNI3TSVyFiPn8PnHg
May 17 00:44:57.635897 sshd[1667]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0)
May 17 00:44:57.639628 systemd-logind[1399]: New session 6 of user core.
May 17 00:44:57.640008 systemd[1]: Started session-6.scope.
May 17 00:44:57.938289 sshd[1667]: pam_unix(sshd:session): session closed for user core
May 17 00:44:57.940677 systemd[1]: sshd@3-10.200.20.22:22-10.200.16.10:57544.service: Deactivated successfully.
May 17 00:44:57.941362 systemd[1]: session-6.scope: Deactivated successfully.
May 17 00:44:57.941889 systemd-logind[1399]: Session 6 logged out. Waiting for processes to exit.
May 17 00:44:57.942706 systemd-logind[1399]: Removed session 6.
May 17 00:44:58.012590 systemd[1]: Started sshd@4-10.200.20.22:22-10.200.16.10:57552.service.
May 17 00:44:58.462313 sshd[1673]: Accepted publickey for core from 10.200.16.10 port 57552 ssh2: RSA SHA256:kTalk4vvVOHJD+odK+kI4Z4CxTmNI3TSVyFiPn8PnHg
May 17 00:44:58.463631 sshd[1673]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0)
May 17 00:44:58.467489 systemd-logind[1399]: New session 7 of user core.
May 17 00:44:58.467883 systemd[1]: Started session-7.scope.
May 17 00:44:59.036064 sudo[1676]:     core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1
May 17 00:44:59.036581 sudo[1676]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500)
May 17 00:44:59.093330 dbus-daemon[1387]: avc:  received setenforce notice (enforcing=1)
May 17 00:44:59.095115 sudo[1676]: pam_unix(sudo:session): session closed for user root
May 17 00:44:59.197412 sshd[1673]: pam_unix(sshd:session): session closed for user core
May 17 00:44:59.200276 systemd-logind[1399]: Session 7 logged out. Waiting for processes to exit.
May 17 00:44:59.200562 systemd[1]: sshd@4-10.200.20.22:22-10.200.16.10:57552.service: Deactivated successfully.
May 17 00:44:59.201253 systemd[1]: session-7.scope: Deactivated successfully.
May 17 00:44:59.201909 systemd-logind[1399]: Removed session 7.
May 17 00:44:59.266548 systemd[1]: Started sshd@5-10.200.20.22:22-10.200.16.10:43486.service.
May 17 00:44:59.682877 sshd[1680]: Accepted publickey for core from 10.200.16.10 port 43486 ssh2: RSA SHA256:kTalk4vvVOHJD+odK+kI4Z4CxTmNI3TSVyFiPn8PnHg
May 17 00:44:59.684556 sshd[1680]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0)
May 17 00:44:59.688194 systemd-logind[1399]: New session 8 of user core.
May 17 00:44:59.688762 systemd[1]: Started session-8.scope.
May 17 00:44:59.921856 sudo[1684]:     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
May 17 00:44:59.922430 sudo[1684]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500)
May 17 00:44:59.925347 sudo[1684]: pam_unix(sudo:session): session closed for user root
May 17 00:44:59.931972 sudo[1683]:     core : PWD=/home/core ; USER=root ; COMMAND=/usr/bin/systemctl restart audit-rules
May 17 00:44:59.932186 sudo[1683]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500)
May 17 00:44:59.940778 systemd[1]: Stopping audit-rules.service...
May 17 00:44:59.941000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1
May 17 00:44:59.946055 kernel: kauditd_printk_skb: 97 callbacks suppressed
May 17 00:44:59.946083 kernel: audit: type=1305 audit(1747442699.941:177): auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1
May 17 00:44:59.946294 auditctl[1687]: No rules
May 17 00:44:59.941000 audit[1687]: SYSCALL arch=c00000b7 syscall=206 success=yes exit=1056 a0=3 a1=fffff07f2b70 a2=420 a3=0 items=0 ppid=1 pid=1687 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)
May 17 00:44:59.956732 systemd[1]: audit-rules.service: Deactivated successfully.
May 17 00:44:59.956906 systemd[1]: Stopped audit-rules.service.
May 17 00:44:59.958665 systemd[1]: Starting audit-rules.service...
May 17 00:44:59.978790 kernel: audit: type=1300 audit(1747442699.941:177): arch=c00000b7 syscall=206 success=yes exit=1056 a0=3 a1=fffff07f2b70 a2=420 a3=0 items=0 ppid=1 pid=1687 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)
May 17 00:44:59.941000 audit: PROCTITLE proctitle=2F7362696E2F617564697463746C002D44
May 17 00:44:59.988059 kernel: audit: type=1327 audit(1747442699.941:177): proctitle=2F7362696E2F617564697463746C002D44
May 17 00:44:59.955000 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'
May 17 00:44:59.999014 augenrules[1704]: No rules
May 17 00:45:00.000015 systemd[1]: Finished audit-rules.service.
May 17 00:45:00.003818 kernel: audit: type=1131 audit(1747442699.955:178): 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'
May 17 00:44:59.997000 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'
May 17 00:45:00.004033 sudo[1683]: pam_unix(sudo:session): session closed for user root
May 17 00:45:00.019361 kernel: audit: type=1130 audit(1747442699.997:179): 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'
May 17 00:45:00.019432 kernel: audit: type=1106 audit(1747442700.002:180): pid=1683 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'
May 17 00:45:00.002000 audit[1683]: USER_END pid=1683 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'
May 17 00:45:00.003000 audit[1683]: CRED_DISP pid=1683 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'
May 17 00:45:00.053064 kernel: audit: type=1104 audit(1747442700.003:181): pid=1683 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'
May 17 00:45:00.086558 sshd[1680]: pam_unix(sshd:session): session closed for user core
May 17 00:45:00.086000 audit[1680]: USER_END pid=1680 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'
May 17 00:45:00.110760 systemd[1]: sshd@5-10.200.20.22:22-10.200.16.10:43486.service: Deactivated successfully.
May 17 00:45:00.086000 audit[1680]: CRED_DISP pid=1680 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'
May 17 00:45:00.129425 kernel: audit: type=1106 audit(1747442700.086:182): pid=1680 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'
May 17 00:45:00.129471 kernel: audit: type=1104 audit(1747442700.086:183): pid=1680 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'
May 17 00:45:00.111457 systemd[1]: session-8.scope: Deactivated successfully.
May 17 00:45:00.129905 systemd-logind[1399]: Session 8 logged out. Waiting for processes to exit.
May 17 00:45:00.109000 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.22:22-10.200.16.10:43486 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
May 17 00:45:00.149885 kernel: audit: type=1131 audit(1747442700.109:184): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@5-10.200.20.22:22-10.200.16.10:43486 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
May 17 00:45:00.150329 systemd-logind[1399]: Removed session 8.
May 17 00:45:00.155413 systemd[1]: Started sshd@6-10.200.20.22:22-10.200.16.10:43498.service.
May 17 00:45:00.154000 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.22:22-10.200.16.10:43498 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
May 17 00:45:00.565000 audit[1710]: USER_ACCT pid=1710 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'
May 17 00:45:00.566547 sshd[1710]: Accepted publickey for core from 10.200.16.10 port 43498 ssh2: RSA SHA256:kTalk4vvVOHJD+odK+kI4Z4CxTmNI3TSVyFiPn8PnHg
May 17 00:45:00.566000 audit[1710]: CRED_ACQ pid=1710 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'
May 17 00:45:00.566000 audit[1710]: SYSCALL arch=c00000b7 syscall=64 success=yes exit=3 a0=5 a1=ffffea41bb40 a2=3 a3=1 items=0 ppid=1 pid=1710 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)
May 17 00:45:00.566000 audit: PROCTITLE proctitle=737368643A20636F7265205B707269765D
May 17 00:45:00.568170 sshd[1710]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0)
May 17 00:45:00.572328 systemd[1]: Started session-9.scope.
May 17 00:45:00.573194 systemd-logind[1399]: New session 9 of user core.
May 17 00:45:00.576000 audit[1710]: USER_START pid=1710 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'
May 17 00:45:00.577000 audit[1712]: CRED_ACQ pid=1712 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'
May 17 00:45:00.801000 audit[1713]: USER_ACCT pid=1713 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'
May 17 00:45:00.802000 audit[1713]: CRED_REFR pid=1713 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'
May 17 00:45:00.803079 sudo[1713]:     core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/blkid -s UUID -o value /dev/disk/by-label/ROOT
May 17 00:45:00.803293 sudo[1713]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500)
May 17 00:45:00.803000 audit[1713]: USER_START pid=1713 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'
May 17 00:45:00.879000 audit[1713]: USER_END pid=1713 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'
May 17 00:45:00.880440 sudo[1713]: pam_unix(sudo:session): session closed for user root
May 17 00:45:00.879000 audit[1713]: CRED_DISP pid=1713 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'
May 17 00:45:00.963103 sshd[1710]: pam_unix(sshd:session): session closed for user core
May 17 00:45:00.963000 audit[1710]: USER_END pid=1710 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'
May 17 00:45:00.963000 audit[1710]: CRED_DISP pid=1710 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'
May 17 00:45:00.966174 systemd-logind[1399]: Session 9 logged out. Waiting for processes to exit.
May 17 00:45:00.966175 systemd[1]: session-9.scope: Deactivated successfully.
May 17 00:45:00.966731 systemd[1]: sshd@6-10.200.20.22:22-10.200.16.10:43498.service: Deactivated successfully.
May 17 00:45:00.965000 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.22:22-10.200.16.10:43498 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
May 17 00:45:00.967692 systemd-logind[1399]: Removed session 9.
May 17 00:45:01.044000 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.22:22-10.200.16.10:43504 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
May 17 00:45:01.045226 systemd[1]: Started sshd@7-10.200.20.22:22-10.200.16.10:43504.service.
May 17 00:45:01.487000 audit[1717]: USER_ACCT pid=1717 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'
May 17 00:45:01.488826 sshd[1717]: Accepted publickey for core from 10.200.16.10 port 43504 ssh2: RSA SHA256:kTalk4vvVOHJD+odK+kI4Z4CxTmNI3TSVyFiPn8PnHg
May 17 00:45:01.488000 audit[1717]: CRED_ACQ pid=1717 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'
May 17 00:45:01.488000 audit[1717]: SYSCALL arch=c00000b7 syscall=64 success=yes exit=3 a0=5 a1=fffffc366aa0 a2=3 a3=1 items=0 ppid=1 pid=1717 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)
May 17 00:45:01.488000 audit: PROCTITLE proctitle=737368643A20636F7265205B707269765D
May 17 00:45:01.490403 sshd[1717]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0)
May 17 00:45:01.494287 systemd-logind[1399]: New session 10 of user core.
May 17 00:45:01.494699 systemd[1]: Started session-10.scope.
May 17 00:45:01.497000 audit[1717]: USER_START pid=1717 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'
May 17 00:45:01.498000 audit[1719]: CRED_ACQ pid=1719 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'
May 17 00:45:01.740000 audit[1720]: USER_ACCT pid=1720 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'
May 17 00:45:01.741978 sudo[1720]:     core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/blkid -s TYPE -o value /dev/disk/by-label/ROOT
May 17 00:45:01.740000 audit[1720]: CRED_REFR pid=1720 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'
May 17 00:45:01.742486 sudo[1720]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500)
May 17 00:45:01.742000 audit[1720]: USER_START pid=1720 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'
May 17 00:45:01.744000 audit[1720]: USER_END pid=1720 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'
May 17 00:45:01.744000 audit[1720]: CRED_DISP pid=1720 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'
May 17 00:45:01.745297 sudo[1720]: pam_unix(sudo:session): session closed for user root
May 17 00:45:01.817362 sshd[1717]: pam_unix(sshd:session): session closed for user core
May 17 00:45:01.817000 audit[1717]: USER_END pid=1717 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'
May 17 00:45:01.817000 audit[1717]: CRED_DISP pid=1717 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'
May 17 00:45:01.818000 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.22:22-10.200.16.10:43504 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
May 17 00:45:01.819901 systemd[1]: sshd@7-10.200.20.22:22-10.200.16.10:43504.service: Deactivated successfully.
May 17 00:45:01.820594 systemd[1]: session-10.scope: Deactivated successfully.
May 17 00:45:01.821127 systemd-logind[1399]: Session 10 logged out. Waiting for processes to exit.
May 17 00:45:01.822051 systemd-logind[1399]: Removed session 10.
May 17 00:45:01.886126 systemd[1]: Started sshd@8-10.200.20.22:22-10.200.16.10:43512.service.
May 17 00:45:01.885000 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.22:22-10.200.16.10:43512 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
May 17 00:45:02.302000 audit[1724]: USER_ACCT pid=1724 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'
May 17 00:45:02.303671 sshd[1724]: Accepted publickey for core from 10.200.16.10 port 43512 ssh2: RSA SHA256:kTalk4vvVOHJD+odK+kI4Z4CxTmNI3TSVyFiPn8PnHg
May 17 00:45:02.303000 audit[1724]: CRED_ACQ pid=1724 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'
May 17 00:45:02.303000 audit[1724]: SYSCALL arch=c00000b7 syscall=64 success=yes exit=3 a0=5 a1=ffffcc8b8d80 a2=3 a3=1 items=0 ppid=1 pid=1724 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)
May 17 00:45:02.303000 audit: PROCTITLE proctitle=737368643A20636F7265205B707269765D
May 17 00:45:02.305174 sshd[1724]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0)
May 17 00:45:02.308815 systemd-logind[1399]: New session 11 of user core.
May 17 00:45:02.309283 systemd[1]: Started session-11.scope.
May 17 00:45:02.312000 audit[1724]: USER_START pid=1724 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'
May 17 00:45:02.313000 audit[1726]: CRED_ACQ pid=1726 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'
May 17 00:45:02.699930 sshd[1724]: pam_unix(sshd:session): session closed for user core
May 17 00:45:02.699000 audit[1724]: USER_END pid=1724 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'
May 17 00:45:02.699000 audit[1724]: CRED_DISP pid=1724 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'
May 17 00:45:02.702310 systemd[1]: sshd@8-10.200.20.22:22-10.200.16.10:43512.service: Deactivated successfully.
May 17 00:45:02.701000 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.22:22-10.200.16.10:43512 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
May 17 00:45:02.702953 systemd[1]: session-11.scope: Deactivated successfully.
May 17 00:45:02.703539 systemd-logind[1399]: Session 11 logged out. Waiting for processes to exit.
May 17 00:45:02.704179 systemd-logind[1399]: Removed session 11.