type=io.containerd.service.v1
time="2024-12-24T13:54:26.130293995Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1
time="2024-12-24T13:54:26.130314395Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1
time="2024-12-24T13:54:26.130329865Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2
time="2024-12-24T13:54:26.130637105Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1
time="2024-12-24T13:54:26.130666835Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1
time="2024-12-24T13:54:26.130683885Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1
time="2024-12-24T13:54:26.130709285Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1
time="2024-12-24T13:54:26.130743225Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1
time="2024-12-24T13:54:26.130763495Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1
time="2024-12-24T13:54:26.130779565Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1
time="2024-12-24T13:54:26.130794725Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1
time="2024-12-24T13:54:26.130808845Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1
time="2024-12-24T13:54:26.130831615Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1
time="2024-12-24T13:54:26.130848875Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1
time="2024-12-24T13:54:26.130911855Z" level=info msg="Get image filesystem path \"/var/lib/docker/containerd/daemon/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\""
time="2024-12-24T13:54:26.130934885Z" level=info msg="Start snapshots syncer"
time="2024-12-24T13:54:26.130966125Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1
time="2024-12-24T13:54:26.131188125Z" 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-24T13:54:26.131252885Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1
time="2024-12-24T13:54:26.131323885Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1
time="2024-12-24T13:54:26.131514675Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1
time="2024-12-24T13:54:26.131540425Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1
time="2024-12-24T13:54:26.131552595Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1
time="2024-12-24T13:54:26.131575135Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1
time="2024-12-24T13:54:26.131595415Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1
time="2024-12-24T13:54:26.131606475Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1
time="2024-12-24T13:54:26.131614565Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1
time="2024-12-24T13:54:26.131635835Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1
time="2024-12-24T13:54:26.131655005Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1
time="2024-12-24T13:54:26.131668565Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1
time="2024-12-24T13:54:26.131749965Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1
time="2024-12-24T13:54:26.131804375Z" 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-24T13:54:26.131821865Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1
time="2024-12-24T13:54:26.131838145Z" 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-24T13:54:26.131852395Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1
time="2024-12-24T13:54:26.131879085Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1
time="2024-12-24T13:54:26.131899125Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1
time="2024-12-24T13:54:26.131925465Z" level=info msg="runtime interface created"
time="2024-12-24T13:54:26.131945685Z" level=info msg="created NRI interface"
time="2024-12-24T13:54:26.132571435Z" level=info msg=serving... address=/var/run/docker/containerd/containerd-debug.sock
time="2024-12-24T13:54:26.132710165Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock.ttrpc
time="2024-12-24T13:54:26.132812245Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock
time="2024-12-24T13:54:26.133500434Z" level=info msg="containerd successfully booted in 0.501193s"
time="2024-12-24T13:54:26.614269978Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider"
time="2024-12-24T13:54:31.181661678Z" level=info msg="Loading containers: start."
time="2024-12-24T13:54:31.877796606Z" level=info msg="Loading containers: done."
time="2024-12-24T13:54:31.983556934Z" level=info msg="Docker daemon" commit=c710b88579fcb5e0d53f96dcae976d79323b9166-unsupported containerd-snapshotter=false storage-driver=overlay2 version=dev
time="2024-12-24T13:54:31.983729934Z" level=info msg="Daemon has completed initialization"
time="2024-12-24T13:54:32.021942653Z" level=info msg="API listen on /var/run/docker.sock"
evaluating pipelines for package requirements
building test workspace in: '/tmp/melange-guest-53487277-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/24 13:54:32 [DEBUG] GET https://apk.cgr.dev/wolfi-presubmit/48a898bd19bfeb97ebee67644323da5b543eba89/apk-configuration
2024/12/24 13:54:32 [DEBUG] GET https://packages.wolfi.dev/os/apk-configuration
setting apk repositories: [https://apk.cgr.dev/wolfi-presubmit/48a898bd19bfeb97ebee67644323da5b543eba89 https://packages.wolfi.dev/os]
image configuration:
  contents:
    build repositories: []
    runtime repositories: []
    keyring:      []
    packages:     [ddp-tool]
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 ddp-tool (1.0.34.0_git20241224-r0)
installing wolfi-keys (1-r8)
installing zlib (1.3.1-r4)
installing libcrypto3 (3.4.0-r5)
installing libssl3 (3.4.0-r5)
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-2556432487/apko-x86_64.tar.gz
using /tmp/apko-temp-2556432487/apko-x86_64.tar.gz for image layer
OCI layer digest: sha256:f8fc10ada8c361d1045494227abba798304b5f8f68172cb3b73aff30b1d25d42
OCI layer diffID: sha256:b34b28efb5630f445a1ec0629c308baabf52a29695706efe2ac3c828e4eb4e6f
saving OCI image locally: apko.local/cache:a825943620b2382005ad881c1238dcac2215a00e70e174c547f131ca32cd9efd
skipping local domain tagging apko.local/cache:a825943620b2382005ad881c1238dcac2215a00e70e174c547f131ca32cd9efd as index.docker.io/library/melange:latest
populating workspace /tmp/melange-workspace-2372395188 from ddp-tool
ImgRef = apko.local/cache:a825943620b2382005ad881c1238dcac2215a00e70e174c547f131ca32cd9efd

.images-service type=io.containerd.service.v1
time="2024-12-24T13:54:23.720536029Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1
time="2024-12-24T13:54:23.720551189Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1
time="2024-12-24T13:54:23.720565869Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1
time="2024-12-24T13:54:23.720575909Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1
time="2024-12-24T13:54:23.720588189Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2
time="2024-12-24T13:54:23.720784269Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1
time="2024-12-24T13:54:23.720810629Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1
time="2024-12-24T13:54:23.720842829Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1
time="2024-12-24T13:54:23.720857069Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1
time="2024-12-24T13:54:23.720872629Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1
time="2024-12-24T13:54:23.720886509Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1
time="2024-12-24T13:54:23.720900869Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1
time="2024-12-24T13:54:23.720915509Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1
time="2024-12-24T13:54:23.720932269Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1
time="2024-12-24T13:54:23.720959109Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1
time="2024-12-24T13:54:23.720970269Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1
time="2024-12-24T13:54:23.721040789Z" level=info msg="Get image filesystem path \"/var/lib/docker/containerd/daemon/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\""
time="2024-12-24T13:54:23.721060229Z" level=info msg="Start snapshots syncer"
time="2024-12-24T13:54:23.721090269Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1
time="2024-12-24T13:54:23.721294029Z" 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-24T13:54:23.721344429Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1
time="2024-12-24T13:54:23.721417229Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1
time="2024-12-24T13:54:23.721692309Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1
time="2024-12-24T13:54:23.721889029Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1
time="2024-12-24T13:54:23.721925309Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1
time="2024-12-24T13:54:23.721952349Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1
time="2024-12-24T13:54:23.721978029Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1
time="2024-12-24T13:54:23.722001749Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1
time="2024-12-24T13:54:23.722016189Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1
time="2024-12-24T13:54:23.722061669Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1
time="2024-12-24T13:54:23.722083669Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1
time="2024-12-24T13:54:23.722101589Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1
time="2024-12-24T13:54:23.722429589Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1
time="2024-12-24T13:54:23.722468029Z" 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-24T13:54:23.722478509Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1
time="2024-12-24T13:54:23.722493109Z" 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-24T13:54:23.722505589Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1
time="2024-12-24T13:54:23.722520869Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1
time="2024-12-24T13:54:23.722536589Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1
time="2024-12-24T13:54:23.722562189Z" level=info msg="runtime interface created"
time="2024-12-24T13:54:23.722570309Z" level=info msg="created NRI interface"
time="2024-12-24T13:54:23.722857909Z" level=info msg=serving... address=/var/run/docker/containerd/containerd-debug.sock
time="2024-12-24T13:54:23.722924669Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock.ttrpc
time="2024-12-24T13:54:23.722990509Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock
time="2024-12-24T13:54:23.723017069Z" level=info msg="containerd successfully booted in 0.421973s"
time="2024-12-24T13:54:24.288512265Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider"
time="2024-12-24T13:54:26.363917063Z" level=info msg="Loading containers: start."
time="2024-12-24T13:54:26.682134199Z" level=info msg="Loading containers: done."
time="2024-12-24T13:54:27.888066705Z" level=info msg="Docker daemon" commit=c710b88579fcb5e0d53f96dcae976d79323b9166-unsupported containerd-snapshotter=false storage-driver=overlay2 version=dev
time="2024-12-24T13:54:27.888238465Z" level=info msg="Daemon has completed initialization"
time="2024-12-24T13:54:27.993394897Z" level=info msg="API listen on /var/run/docker.sock"
evaluating pipelines for package requirements
building test workspace in: '/tmp/melange-guest-1963901350-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/24 13:54:28 [DEBUG] GET https://apk.cgr.dev/wolfi-presubmit/48a898bd19bfeb97ebee67644323da5b543eba89/apk-configuration
2024/12/24 13:54:28 [DEBUG] GET https://packages.wolfi.dev/os/apk-configuration
setting apk repositories: [https://apk.cgr.dev/wolfi-presubmit/48a898bd19bfeb97ebee67644323da5b543eba89 https://packages.wolfi.dev/os]
image configuration:
  contents:
    build repositories: []
    runtime repositories: []
    keyring:      []
    packages:     [ddp-tool]
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 ddp-tool (1.0.34.0_git20241224-r0)
installing wolfi-keys (1-r8)
installing zlib (1.3.1-r4)
installing libcrypto3 (3.4.0-r5)
installing libssl3 (3.4.0-r5)
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-412693757/apko-aarch64.tar.gz
using /tmp/apko-temp-412693757/apko-aarch64.tar.gz for image layer
OCI layer digest: sha256:1ea5c8ab77e732a0ecb78d71eaa92d689d8ffb39105517515dc94534e507f319
OCI layer diffID: sha256:23bf2596f814e883668987417e2e2d8e9aaf779d1f57068b488357da19e29ba0
saving OCI image locally: apko.local/cache:f98a1cdb27a73388317286dbdf7682e39b97234f63dd5b06b286c8680ce06e0b