" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1
time="2024-12-14T13:56:32.720312838Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1
time="2024-12-14T13:56:32.720324288Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1
time="2024-12-14T13:56:32.720340258Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2
time="2024-12-14T13:56:32.741210160Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1
time="2024-12-14T13:56:32.741319730Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1
time="2024-12-14T13:56:32.741351500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1
time="2024-12-14T13:56:32.741368870Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1
time="2024-12-14T13:56:32.741404660Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1
time="2024-12-14T13:56:32.741423390Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1
time="2024-12-14T13:56:32.741440220Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1
time="2024-12-14T13:56:32.741453360Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1
time="2024-12-14T13:56:32.741497350Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1
time="2024-12-14T13:56:32.741513360Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1
time="2024-12-14T13:56:32.741537550Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1
time="2024-12-14T13:56:32.741614080Z" level=info msg="Get image filesystem path \"/var/lib/docker/containerd/daemon/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\""
time="2024-12-14T13:56:32.741641660Z" level=info msg="Start snapshots syncer"
time="2024-12-14T13:56:32.741677840Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1
time="2024-12-14T13:56:32.743192981Z" level=info msg="starting cri plugin" config="{\"containerd\":{\"defaultRuntimeName\":\"runc\",\"runtimes\":{\"runc\":{\"runtimeType\":\"io.containerd.runc.v2\",\"runtimePath\":\"\",\"PodAnnotations\":null,\"ContainerAnnotations\":null,\"options\":{\"BinaryName\":\"\",\"CriuImagePath\":\"\",\"CriuWorkPath\":\"\",\"IoGid\":0,\"IoUid\":0,\"NoNewKeyring\":false,\"Root\":\"\",\"ShimCgroup\":\"\"},\"privileged_without_host_devices\":false,\"privileged_without_host_devices_all_devices_allowed\":false,\"baseRuntimeSpec\":\"\",\"cniConfDir\":\"\",\"cniMaxConfNum\":0,\"snapshotter\":\"\",\"sandboxer\":\"podsandbox\",\"io_type\":\"\"}},\"ignoreBlockIONotEnabledErrors\":false,\"ignoreRdtNotEnabledErrors\":false},\"cni\":{\"binDir\":\"/opt/cni/bin\",\"confDir\":\"/etc/cni/net.d\",\"maxConfNum\":1,\"setupSerially\":false,\"confTemplate\":\"\",\"ipPref\":\"\",\"useInternalLoopback\":false},\"enableSelinux\":false,\"selinuxCategoryRange\":1024,\"maxContainerLogSize\":16384,\"disableApparmor\":false,\"restrictOOMScoreAdj\":false,\"disableProcMount\":false,\"unsetSeccompProfile\":\"\",\"tolerateMissingHugetlbController\":true,\"disableHugetlbController\":true,\"device_ownership_from_security_context\":false,\"ignoreImageDefinedVolumes\":false,\"netnsMountsUnderStateDir\":false,\"enableUnprivilegedPorts\":true,\"enableUnprivilegedICMP\":true,\"enableCDI\":true,\"cdiSpecDirs\":[\"/etc/cdi\",\"/var/run/cdi\"],\"drainExecSyncIOTimeout\":\"0s\",\"ignoreDeprecationWarnings\":null,\"containerdRootDir\":\"/var/lib/docker/containerd/daemon\",\"containerdEndpoint\":\"/var/run/docker/containerd/containerd.sock\",\"rootDir\":\"/var/lib/docker/containerd/daemon/io.containerd.grpc.v1.cri\",\"stateDir\":\"/var/run/docker/containerd/daemon/io.containerd.grpc.v1.cri\"}"
time="2024-12-14T13:56:32.743276021Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1
time="2024-12-14T13:56:32.743372491Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1
time="2024-12-14T13:56:32.929878764Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1
time="2024-12-14T13:56:32.930030134Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1
time="2024-12-14T13:56:32.930061514Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1
time="2024-12-14T13:56:32.930083714Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1
time="2024-12-14T13:56:32.949461565Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1
time="2024-12-14T13:56:32.949504705Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1
time="2024-12-14T13:56:32.949520425Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1
time="2024-12-14T13:56:32.949554635Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1
time="2024-12-14T13:56:32.949568345Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1
time="2024-12-14T13:56:32.949579465Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1
time="2024-12-14T13:56:32.949624035Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1
time="2024-12-14T13:56:32.949640165Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1
time="2024-12-14T13:56:32.949646825Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1
time="2024-12-14T13:56:32.949654335Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1
time="2024-12-14T13:56:32.949661465Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1
time="2024-12-14T13:56:32.949670455Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1
time="2024-12-14T13:56:32.949682315Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1
time="2024-12-14T13:56:32.949708285Z" level=info msg="runtime interface created"
time="2024-12-14T13:56:32.949725035Z" level=info msg="created NRI interface"
time="2024-12-14T13:56:32.950041445Z" level=info msg=serving... address=/var/run/docker/containerd/containerd-debug.sock
time="2024-12-14T13:56:32.950156795Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock.ttrpc
time="2024-12-14T13:56:32.950229485Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock
time="2024-12-14T13:56:32.950251175Z" level=info msg="containerd successfully booted in 0.653907s"
time="2024-12-14T13:56:49.098051105Z" level=info msg="Loading containers: start."
time="2024-12-14T13:56:49.849615094Z" level=info msg="Loading containers: done."
time="2024-12-14T13:56:50.305377259Z" level=info msg="Docker daemon" commit=92a83937d0280dcbea92099b01e01aa4251c1777 containerd-snapshotter=false storage-driver=overlay2 version=dev
time="2024-12-14T13:56:50.305563628Z" level=info msg="Daemon has completed initialization"
time="2024-12-14T13:56:51.065380536Z" level=info msg="API listen on /var/run/docker.sock"
evaluating pipelines for package requirements
building test workspace in: '/tmp/melange-guest-2549854517-main' with apko
Error: rpc error: code = NotFound desc = federate identity: rpc error: code = NotFound desc = no identity found for (https://accounts.google.com, 109346087047205543085)
Error running `chainctl auth token`: exit status 1
2024/12/14 13:56:51 [DEBUG] GET https://apk.cgr.dev/wolfi-presubmit/f7f5d2c42c79ebbd75d4b56bd44865bb5977fd91/apk-configuration
2024/12/14 13:56:51 [DEBUG] GET https://packages.wolfi.dev/os/apk-configuration
setting apk repositories: [https://apk.cgr.dev/wolfi-presubmit/f7f5d2c42c79ebbd75d4b56bd44865bb5977fd91 https://packages.wolfi.dev/os]
image configuration:
  contents:
    build repositories: []
    runtime repositories: []
    keyring:      []
    packages:     [aws-eks-pod-identity-agent]
installing aws-eks-pod-identity-agent (0_git20241214-r0)
installing wolfi-keys (1-r8)
installing ca-certificates-bundle (20241010-r2)
installing wolfi-baselayout (20230201-r15)
installing glibc (2.40-r3)
installing ld-linux (2.40-r3)
installing libgcc (14.2.0-r6)
installing glibc-locale-posix (2.40-r3)
installing zlib (1.3.1-r4)
installing libcrypto3 (3.4.0-r4)
installing libssl3 (3.4.0-r4)
installing apk-tools (2.14.4-r1)
installing libxcrypt (4.4.36-r8)
installing libcrypt1 (2.40-r3)
installing busybox (1.37.0-r0)
installing wolfi-base (1-r6)
built image layer tarball as /tmp/apko-temp-3074318967/apko-x86_64.tar.gz
using /tmp/apko-temp-3074318967/apko-x86_64.tar.gz for image layer
OCI layer digest: sha256:ddd33b846e241b74fcfa0805221065b1e3192281d9d4653cafee681ea33b0749
OCI layer diffID: sha256:1a12d1ba695a09b5cd9fdcb612beb43d527803b84bbb15f022ffc852c46e1142
saving OCI image locally: apko.local/cache:c87f66ac319702a01c1d4611bb14a9e19449c3ccde7e86394beb409a63de5f2f
skipping local domain tagging apko.local/cache:c87f66ac319702a01c1d4611bb14a9e19449c3ccde7e86394beb409a63de5f2f as index.docker.io/library/melange:latest
populating workspace /tmp/melange-workspace-84235505 from aws-eks-pod-identity-agent
ImgRef = apko.local/cache:c87f66ac319702a01c1d4611bb14a9e19449c3ccde7e86394beb409a63de5f2f

box.store.v1.local type=io.containerd.sandbox.store.v1
time="2024-12-14T13:56:30.979910997Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1
time="2024-12-14T13:56:30.980017157Z" level=info msg="Get image filesystem path \"/var/lib/docker/containerd/daemon/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\""
time="2024-12-14T13:56:30.980041317Z" level=info msg="Start snapshots syncer"
time="2024-12-14T13:56:30.980066957Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1
time="2024-12-14T13:56:30.980312157Z" level=info msg="starting cri plugin" config="{\"containerd\":{\"defaultRuntimeName\":\"runc\",\"runtimes\":{\"runc\":{\"runtimeType\":\"io.containerd.runc.v2\",\"runtimePath\":\"\",\"PodAnnotations\":null,\"ContainerAnnotations\":null,\"options\":{\"BinaryName\":\"\",\"CriuImagePath\":\"\",\"CriuWorkPath\":\"\",\"IoGid\":0,\"IoUid\":0,\"NoNewKeyring\":false,\"Root\":\"\",\"ShimCgroup\":\"\"},\"privileged_without_host_devices\":false,\"privileged_without_host_devices_all_devices_allowed\":false,\"baseRuntimeSpec\":\"\",\"cniConfDir\":\"\",\"cniMaxConfNum\":0,\"snapshotter\":\"\",\"sandboxer\":\"podsandbox\",\"io_type\":\"\"}},\"ignoreBlockIONotEnabledErrors\":false,\"ignoreRdtNotEnabledErrors\":false},\"cni\":{\"binDir\":\"/opt/cni/bin\",\"confDir\":\"/etc/cni/net.d\",\"maxConfNum\":1,\"setupSerially\":false,\"confTemplate\":\"\",\"ipPref\":\"\",\"useInternalLoopback\":false},\"enableSelinux\":false,\"selinuxCategoryRange\":1024,\"maxContainerLogSize\":16384,\"disableApparmor\":false,\"restrictOOMScoreAdj\":false,\"disableProcMount\":false,\"unsetSeccompProfile\":\"\",\"tolerateMissingHugetlbController\":true,\"disableHugetlbController\":true,\"device_ownership_from_security_context\":false,\"ignoreImageDefinedVolumes\":false,\"netnsMountsUnderStateDir\":false,\"enableUnprivilegedPorts\":true,\"enableUnprivilegedICMP\":true,\"enableCDI\":true,\"cdiSpecDirs\":[\"/etc/cdi\",\"/var/run/cdi\"],\"drainExecSyncIOTimeout\":\"0s\",\"ignoreDeprecationWarnings\":null,\"containerdRootDir\":\"/var/lib/docker/containerd/daemon\",\"containerdEndpoint\":\"/var/run/docker/containerd/containerd.sock\",\"rootDir\":\"/var/lib/docker/containerd/daemon/io.containerd.grpc.v1.cri\",\"stateDir\":\"/var/run/docker/containerd/daemon/io.containerd.grpc.v1.cri\"}"
time="2024-12-14T13:56:30.980367157Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1
time="2024-12-14T13:56:30.980447717Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1
time="2024-12-14T13:56:30.980607237Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1
time="2024-12-14T13:56:30.980635397Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1
time="2024-12-14T13:56:30.980685637Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1
time="2024-12-14T13:56:30.980703117Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1
time="2024-12-14T13:56:30.980718397Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1
time="2024-12-14T13:56:30.980732997Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1
time="2024-12-14T13:56:30.980748277Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1
time="2024-12-14T13:56:30.980779757Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1
time="2024-12-14T13:56:30.980793757Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1
time="2024-12-14T13:56:30.980804637Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1
time="2024-12-14T13:56:30.980846717Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1
time="2024-12-14T13:56:30.980864717Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1
time="2024-12-14T13:56:30.980875797Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1
time="2024-12-14T13:56:30.980885237Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1
time="2024-12-14T13:56:30.980895157Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1
time="2024-12-14T13:56:30.980909477Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1
time="2024-12-14T13:56:30.980922997Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1
time="2024-12-14T13:56:30.980944997Z" level=info msg="runtime interface created"
time="2024-12-14T13:56:30.980951837Z" level=info msg="created NRI interface"
time="2024-12-14T13:56:30.982041037Z" level=info msg=serving... address=/var/run/docker/containerd/containerd-debug.sock
time="2024-12-14T13:56:30.982177077Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock.ttrpc
time="2024-12-14T13:56:30.982231437Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock
time="2024-12-14T13:56:30.982256277Z" level=info msg="containerd successfully booted in 0.092432s"
time="2024-12-14T13:56:32.030374282Z" level=info msg="Loading containers: start."
time="2024-12-14T13:56:32.371886843Z" level=info msg="Loading containers: done."
time="2024-12-14T13:56:32.420240449Z" level=info msg="Docker daemon" commit=92a83937d0280dcbea92099b01e01aa4251c1777 containerd-snapshotter=false storage-driver=overlay2 version=dev
time="2024-12-14T13:56:32.420440729Z" level=info msg="Daemon has completed initialization"
time="2024-12-14T13:56:32.510862620Z" level=info msg="API listen on /var/run/docker.sock"
evaluating pipelines for package requirements
building test workspace in: '/tmp/melange-guest-1401119485-main' with apko
Error: rpc error: code = NotFound desc = federate identity: rpc error: code = NotFound desc = no identity found for (https://accounts.google.com, 109346087047205543085)
Error running `chainctl auth token`: exit status 1
2024/12/14 13:56:32 [DEBUG] GET https://apk.cgr.dev/wolfi-presubmit/f7f5d2c42c79ebbd75d4b56bd44865bb5977fd91/apk-configuration
2024/12/14 13:56:33 [DEBUG] GET https://packages.wolfi.dev/os/apk-configuration
setting apk repositories: [https://apk.cgr.dev/wolfi-presubmit/f7f5d2c42c79ebbd75d4b56bd44865bb5977fd91 https://packages.wolfi.dev/os]
image configuration:
  contents:
    build repositories: []
    runtime repositories: []
    keyring:      []
    packages:     [aws-eks-pod-identity-agent]
installing aws-eks-pod-identity-agent (0_git20241214-r0)
installing wolfi-keys (1-r8)
installing ca-certificates-bundle (20241010-r2)
installing wolfi-baselayout (20230201-r15)
installing glibc (2.40-r3)
installing libgcc (14.2.0-r6)
installing ld-linux (2.40-r3)
installing glibc-locale-posix (2.40-r3)
installing zlib (1.3.1-r4)
installing libcrypto3 (3.4.0-r4)
installing libssl3 (3.4.0-r4)
installing apk-tools (2.14.4-r1)
installing libxcrypt (4.4.36-r8)
installing libcrypt1 (2.40-r3)
installing busybox (1.37.0-r0)
installing wolfi-base (1-r6)
built image layer tarball as /tmp/apko-temp-3241410747/apko-aarch64.tar.gz
using /tmp/apko-temp-3241410747/apko-aarch64.tar.gz for image layer
OCI layer digest: sha256:4aa1823e956e4c669355f497d7b2c7b3fe8c8406b4911e4fa9e9786670897dbc
OCI layer diffID: sha256:3a56909be57e0127aefb337f95ea40a04469aca9c338ebda201284d41e4e8073
saving OCI image locally: apko.local/cache:5d6f3fbd6f34b38eb6c2397bdbfb36f5abb39cbd99bcea2a2ad9b27e97433411
skipping local domain tagging apko.local/cache:5d6f3fbd6f34b38eb6c2397bdbfb36f5abb39cbd99bcea2a2ad9b27e97433411 as index.docker.io/library/melange:latest
populating workspace /tmp/melange-workspace-2514949019 from aws-eks-pod-identity-agent
ImgRef = apko.local/cache:5d6f3fbd6f34b38eb6c2397bdbfb36f5abb39cbd99bcea2a2ad9b27e97433411
time="2024-12-14T13:56:48.156943087Z" level=info msg="connecting to shim 6eca0e3abb536a93ed9910cfdcdf2d2269ed03747259c48b32e8fced4a36c06e" address="unix:///run/containerd/s/6a15625e754694daae0ec19ec657e82d484724c5ac67fe503e31cfc85cd798c2" namespace=moby protocol=ttrpc version=3
running the main test pipeline
The agent contains a proxy server and its initializer, for more information look at the server command

Usage:
  eks-pod-identity-agent [command]

Available Commands:
  completion  Generate the autocompletion script for the specified shell
  help        Help about any command
  initialize  Init configures the host, adding a new interface and updating route table
  server      A proxy server that exchanges kubernetes service account token with temporary AWS credentials by calling EKS Auth APIs

Flags:
  -h, --help               help for eks-pod-identity-agent
  -v, --verbosity string   Logging verbosity can be one of: panic, error, info, trace (default "info")

Use "eks-pod-identity-agent [command] --help" for more information about a command.
time="2024-12-14T13:56:49.637903983Z" level=info msg="ignoring event" container=6eca0e3abb536a93ed9910cfdcdf2d2269ed03747259c48b32e8fced4a36c06e module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete"
time="2024-12-14T13:56:49.637934703Z" level=info msg="shim disconnected" id=6eca0e3abb536a93ed9910cfdcdf2d2269ed03747259c48b32e8fced4a36c06e namespace=moby
time="2024-12-14T13:56:49.637974943Z" level=warning msg="cleaning up after shim disconnected" id=6eca0e3abb536a93ed9910cfdcdf2d2269ed03747259c48b32e8fced4a36c06e namespace=moby
time="2024-12-14T13:56:49.638011423Z" level=info msg="cleaning up dead shim" namespace=moby
pod 6eca0e3abb536a93ed9910cfdcdf2d2269ed03747259c48b32e8fced4a36c06e terminated