-
Notifications
You must be signed in to change notification settings - Fork 18.9k
Description
Description
I noticed this while reviewing #48264, but got the same when building v27.1.2, so it looks not related to that PR.
With the containerd image-store enabled, and with the daemon running with debug;
docker build -t localhost:5001/owner/name --platform=linux/arm64,linux/amd64 -<<'EOF'
FROM alpine
RUN echo foo > bar.txt
EOFFull logs:
Details
INFO[2024-08-15T09:11:09.246792462Z] API listen on /var/run/docker.sock
DEBU[2024-08-15T09:11:18.865332633Z] Calling HEAD /_ping
DEBU[2024-08-15T09:11:18.929086050Z] Calling HEAD /_ping
DEBU[2024-08-15T09:11:18.929710550Z] Calling POST /grpc
DEBU[2024-08-15T09:11:18.930500092Z] Calling HEAD /_ping
DEBU[2024-08-15T09:11:18.932915258Z] Calling HEAD /_ping
DEBU[2024-08-15T09:11:18.933246717Z] Calling POST /grpc
DEBU[2024-08-15T09:11:18.933899092Z] Calling HEAD /_ping
DEBU[2024-08-15T09:11:18.934110717Z] Calling GET /v1.46/version
DEBU[2024-08-15T09:11:18.942892008Z] Calling POST /grpc
DEBU[2024-08-15T09:11:18.989298925Z] Calling POST /session
DEBU[2024-08-15T09:11:18.994573008Z] Calling POST /grpc
DEBU[2024-08-15T09:11:18.997238550Z] Calling POST /session
DEBU[2024-08-15T09:11:18.997758592Z] resolve exporter moby with map[name:localhost:5001/owner/name]
DEBU[2024-08-15T09:11:19.002685675Z] prepare snapshot key=o1mv5eeaqnh2l4gcn0102mcav parent=
DEBU[2024-08-15T09:11:19.007756925Z] new ref for local: o1mv5eeaqnh2l4gcn0102mcav span="[internal] load build definition from Dockerfile"
DEBU[2024-08-15T09:11:19.008106675Z] get snapshot mounts key=o1mv5eeaqnh2l4gcn0102mcav
DEBU[2024-08-15T09:11:19.011835925Z] diffcopy took: 3.211875ms span="[internal] load build definition from Dockerfile"
DEBU[2024-08-15T09:11:19.013580508Z] saved o1mv5eeaqnh2l4gcn0102mcav as dockerfile:dockerfile: span="[internal] load build definition from Dockerfile"
DEBU[2024-08-15T09:11:19.015552758Z] get snapshot mounts key=o1mv5eeaqnh2l4gcn0102mcav
DEBU[2024-08-15T09:11:19.016734967Z] checked for cached auth handler namespace cached=false key="docker.io/library/alpine::pull" name=docker.io/library/alpine scope=pull
DEBU[2024-08-15T09:11:19.017085842Z] checked for cached auth handler namespace cached=true key="docker.io/library/alpine::pull" name=docker.io/library/alpine scope=pull
DEBU[2024-08-15T09:11:19.018925258Z] fetch digest="sha256:0a4eaa0eecf5f8c050e5bba433f58c052be7587ee8af3e8b3910ef9ab5fbe9f5" mediatype=application/vnd.docker.distribution.manifest.list.v2+json size=1853
DEBU[2024-08-15T09:11:19.019011758Z] fetch digest="sha256:0a4eaa0eecf5f8c050e5bba433f58c052be7587ee8af3e8b3910ef9ab5fbe9f5" mediatype=application/vnd.docker.distribution.manifest.list.v2+json size=1853
DEBU[2024-08-15T09:11:19.019394175Z] (*service).Write started expected="sha256:0a4eaa0eecf5f8c050e5bba433f58c052be7587ee8af3e8b3910ef9ab5fbe9f5" ref="index-sha256:0a4eaa0eecf5f8c050e5bba433f58c052be7587ee8af3e8b3910ef9ab5fbe9f5" total=1853
DEBU[2024-08-15T09:11:19.019766342Z] (*service).Write started expected="sha256:0a4eaa0eecf5f8c050e5bba433f58c052be7587ee8af3e8b3910ef9ab5fbe9f5" ref="index-sha256:0a4eaa0eecf5f8c050e5bba433f58c052be7587ee8af3e8b3910ef9ab5fbe9f5" total=1853
DEBU[2024-08-15T09:11:19.022777342Z] fetch digest="sha256:eddacbc7e24bf8799a4ed3cdcfa50d4b88a323695ad80f317b6629883b2c2a78" mediatype=application/vnd.docker.distribution.manifest.v2+json size=528
DEBU[2024-08-15T09:11:19.023233300Z] (*service).Write started expected="sha256:eddacbc7e24bf8799a4ed3cdcfa50d4b88a323695ad80f317b6629883b2c2a78" ref="manifest-sha256:eddacbc7e24bf8799a4ed3cdcfa50d4b88a323695ad80f317b6629883b2c2a78" total=528
DEBU[2024-08-15T09:11:19.023955425Z] fetch digest="sha256:24ba417e25e780ff13c888ccb1badec5b027944666ff695681909bafe09a3944" mediatype=application/vnd.docker.distribution.manifest.v2+json size=528
DEBU[2024-08-15T09:11:19.024310842Z] (*service).Write started expected="sha256:24ba417e25e780ff13c888ccb1badec5b027944666ff695681909bafe09a3944" ref="manifest-sha256:24ba417e25e780ff13c888ccb1badec5b027944666ff695681909bafe09a3944" total=528
DEBU[2024-08-15T09:11:19.026410633Z] do request digest="sha256:24ba417e25e780ff13c888ccb1badec5b027944666ff695681909bafe09a3944" mediatype=application/vnd.docker.distribution.manifest.v2+json request.header.accept="application/vnd.docker.distribution.manifest.v2+json, */*" request.header.user-agent=buildkit/v0.15 request.method=GET size=528 url="https://registry-1.docker.io/v2/library/alpine/manifests/sha256:24ba417e25e780ff13c888ccb1badec5b027944666ff695681909bafe09a3944"
DEBU[2024-08-15T09:11:19.026985175Z] fetch digest="sha256:324bc02ae1231fd9255658c128086395d3fa0aedd5a41ab6b034fd649d1a9260" mediatype=application/vnd.docker.container.image.v1+json size=1471
DEBU[2024-08-15T09:11:19.027155175Z] (*service).Write started expected="sha256:324bc02ae1231fd9255658c128086395d3fa0aedd5a41ab6b034fd649d1a9260" ref="config-sha256:324bc02ae1231fd9255658c128086395d3fa0aedd5a41ab6b034fd649d1a9260" total=1471
DEBU[2024-08-15T09:11:19.031315300Z] prepare snapshot key=u6r0h67qzm42c2qaylyyongo9 parent=
DEBU[2024-08-15T09:11:19.033341050Z] new ref for local: u6r0h67qzm42c2qaylyyongo9 span="[internal] load .dockerignore"
DEBU[2024-08-15T09:11:19.033469383Z] get snapshot mounts key=u6r0h67qzm42c2qaylyyongo9
DEBU[2024-08-15T09:11:19.034332092Z] diffcopy took: 694.292µs span="[internal] load .dockerignore"
DEBU[2024-08-15T09:11:19.034965883Z] saved u6r0h67qzm42c2qaylyyongo9 as context:context-.dockerignore: span="[internal] load .dockerignore"
DEBU[2024-08-15T09:11:19.036103258Z] get snapshot mounts key=u6r0h67qzm42c2qaylyyongo9
DEBU[2024-08-15T09:11:19.407523217Z] fetch response received digest="sha256:24ba417e25e780ff13c888ccb1badec5b027944666ff695681909bafe09a3944" mediatype=application/vnd.docker.distribution.manifest.v2+json response.header.content-length=157 response.header.content-type=application/json response.header.date="Thu, 15 Aug 2024 09:11:19 GMT" response.header.docker-distribution-api-version=registry/2.0 response.header.docker-ratelimit-source=94.210.180.92 response.header.strict-transport-security="max-age=31536000" response.header.www-authenticate="Bearer realm=\"https://auth.docker.io/token\",service=\"registry.docker.io\",scope=\"repository:library/alpine:pull\"" response.status="401 Unauthorized" size=528 url="https://registry-1.docker.io/v2/library/alpine/manifests/sha256:24ba417e25e780ff13c888ccb1badec5b027944666ff695681909bafe09a3944"
DEBU[2024-08-15T09:11:19.407739717Z] Unauthorized digest="sha256:24ba417e25e780ff13c888ccb1badec5b027944666ff695681909bafe09a3944" header="Bearer realm=\"https://auth.docker.io/token\",service=\"registry.docker.io\",scope=\"repository:library/alpine:pull\"" mediatype=application/vnd.docker.distribution.manifest.v2+json size=528
DEBU[2024-08-15T09:11:19.415148675Z] do request digest="sha256:24ba417e25e780ff13c888ccb1badec5b027944666ff695681909bafe09a3944" mediatype=application/vnd.docker.distribution.manifest.v2+json request.header.accept="application/vnd.docker.distribution.manifest.v2+json, */*" request.header.user-agent=buildkit/v0.15 request.method=GET size=528 url="https://registry-1.docker.io/v2/library/alpine/manifests/sha256:24ba417e25e780ff13c888ccb1badec5b027944666ff695681909bafe09a3944"
DEBU[2024-08-15T09:11:20.179156676Z] fetch response received digest="sha256:24ba417e25e780ff13c888ccb1badec5b027944666ff695681909bafe09a3944" mediatype=application/vnd.docker.distribution.manifest.v2+json response.header.content-length=528 response.header.content-type=application/vnd.docker.distribution.manifest.v2+json response.header.date="Thu, 15 Aug 2024 09:11:20 GMT" response.header.docker-content-digest="sha256:24ba417e25e780ff13c888ccb1badec5b027944666ff695681909bafe09a3944" response.header.docker-distribution-api-version=registry/2.0 response.header.docker-ratelimit-source=94.210.180.92 response.header.etag="\"sha256:24ba417e25e780ff13c888ccb1badec5b027944666ff695681909bafe09a3944\"" response.header.ratelimit-limit="100;w=21600" response.header.ratelimit-remaining="92;w=21600" response.header.strict-transport-security="max-age=31536000" response.status="200 OK" size=528 url="https://registry-1.docker.io/v2/library/alpine/manifests/sha256:24ba417e25e780ff13c888ccb1badec5b027944666ff695681909bafe09a3944"
DEBU[2024-08-15T09:11:20.189930634Z] fetch digest="sha256:0b4426ad4bf25e13fb09112b9dcb5d5b09b3c5684599654583913b2714a705a2" mediatype=application/vnd.docker.container.image.v1+json size=1486
DEBU[2024-08-15T09:11:20.190209259Z] (*service).Write started expected="sha256:0b4426ad4bf25e13fb09112b9dcb5d5b09b3c5684599654583913b2714a705a2" ref="config-sha256:0b4426ad4bf25e13fb09112b9dcb5d5b09b3c5684599654583913b2714a705a2" total=1486
DEBU[2024-08-15T09:11:20.193719134Z] do request digest="sha256:0b4426ad4bf25e13fb09112b9dcb5d5b09b3c5684599654583913b2714a705a2" mediatype=application/vnd.docker.container.image.v1+json request.header.accept="application/vnd.docker.container.image.v1+json, */*" request.header.user-agent=buildkit/v0.15 request.method=GET size=1486 url="https://registry-1.docker.io/v2/library/alpine/blobs/sha256:0b4426ad4bf25e13fb09112b9dcb5d5b09b3c5684599654583913b2714a705a2"
DEBU[2024-08-15T09:11:20.438045426Z] fetch response received digest="sha256:0b4426ad4bf25e13fb09112b9dcb5d5b09b3c5684599654583913b2714a705a2" mediatype=application/vnd.docker.container.image.v1+json response.header.accept-ranges=bytes response.header.age=2028353 response.header.cache-control="public, max-age=14400" response.header.cf-cache-status=HIT response.header.cf-ray=8b380e80a968b906-AMS response.header.connection=keep-alive response.header.content-length=1486 response.header.content-type=application/octet-stream response.header.date="Thu, 15 Aug 2024 09:11:20 GMT" response.header.etag="\"95c74746de118470c1322ede65a23cff\"" response.header.expires="Thu, 15 Aug 2024 13:11:20 GMT" response.header.last-modified="Mon, 22 Jul 2024 21:44:41 GMT" response.header.server=cloudflare response.header.vary=Accept-Encoding response.header.x-amz-id-2="Uu7efCiF2jaus4OUWPX7utf/dh5Gid9VYwTThBjq/yQdVACQSnycvnvKJfVOtAshdXa8W1SowE0=" response.header.x-amz-request-id=HYMVN6BH3D1A2AXC response.header.x-amz-server-side-encryption=AES256 response.header.x-amz-version-id=EPYTJ1RbQ2C0C1K4XzMFWbPL_uOARwnx response.status="200 OK" size=1486 url="https://registry-1.docker.io/v2/library/alpine/blobs/sha256:0b4426ad4bf25e13fb09112b9dcb5d5b09b3c5684599654583913b2714a705a2"
DEBU[2024-08-15T09:11:20.453605384Z] checked for cached auth handler namespace cached=true key="docker.io/library/alpine::pull" name=docker.io/library/alpine scope=pull
DEBU[2024-08-15T09:11:20.454047592Z] checked for cached auth handler namespace cached=true key="docker.io/library/alpine::pull" name=docker.io/library/alpine scope=pull
DEBU[2024-08-15T09:11:20.458107009Z] resolving host=registry-1.docker.io
DEBU[2024-08-15T09:11:20.458180842Z] do request host=registry-1.docker.io request.header.accept="application/vnd.docker.distribution.manifest.v2+json, application/vnd.docker.distribution.manifest.list.v2+json, application/vnd.oci.image.manifest.v1+json, application/vnd.oci.image.index.v1+json, */*" request.header.user-agent=buildkit/v0.15 request.method=HEAD url="https://registry-1.docker.io/v2/library/alpine/manifests/sha256:0a4eaa0eecf5f8c050e5bba433f58c052be7587ee8af3e8b3910ef9ab5fbe9f5"
DEBU[2024-08-15T09:11:20.458144759Z] resolving host=registry-1.docker.io
DEBU[2024-08-15T09:11:20.458691676Z] do request host=registry-1.docker.io request.header.accept="application/vnd.docker.distribution.manifest.v2+json, application/vnd.docker.distribution.manifest.list.v2+json, application/vnd.oci.image.manifest.v1+json, application/vnd.oci.image.index.v1+json, */*" request.header.user-agent=buildkit/v0.15 request.method=HEAD url="https://registry-1.docker.io/v2/library/alpine/manifests/sha256:0a4eaa0eecf5f8c050e5bba433f58c052be7587ee8af3e8b3910ef9ab5fbe9f5"
DEBU[2024-08-15T09:11:20.606639634Z] fetch response received host=registry-1.docker.io response.header.content-length=1853 response.header.content-type=application/vnd.docker.distribution.manifest.list.v2+json response.header.date="Thu, 15 Aug 2024 09:11:20 GMT" response.header.docker-content-digest="sha256:0a4eaa0eecf5f8c050e5bba433f58c052be7587ee8af3e8b3910ef9ab5fbe9f5" response.header.docker-distribution-api-version=registry/2.0 response.header.docker-ratelimit-source=94.210.180.92 response.header.etag="\"sha256:0a4eaa0eecf5f8c050e5bba433f58c052be7587ee8af3e8b3910ef9ab5fbe9f5\"" response.header.ratelimit-limit="100;w=21600" response.header.ratelimit-remaining="91;w=21600" response.header.strict-transport-security="max-age=31536000" response.status="200 OK" url="https://registry-1.docker.io/v2/library/alpine/manifests/sha256:0a4eaa0eecf5f8c050e5bba433f58c052be7587ee8af3e8b3910ef9ab5fbe9f5"
DEBU[2024-08-15T09:11:20.607212301Z] resolved desc.digest="sha256:0a4eaa0eecf5f8c050e5bba433f58c052be7587ee8af3e8b3910ef9ab5fbe9f5" host=registry-1.docker.io
DEBU[2024-08-15T09:11:20.607746134Z] fetch digest="sha256:0a4eaa0eecf5f8c050e5bba433f58c052be7587ee8af3e8b3910ef9ab5fbe9f5" mediatype=application/vnd.docker.distribution.manifest.list.v2+json size=1853
DEBU[2024-08-15T09:11:20.609841676Z] (*service).Write started expected="sha256:0a4eaa0eecf5f8c050e5bba433f58c052be7587ee8af3e8b3910ef9ab5fbe9f5" ref="index-sha256:0a4eaa0eecf5f8c050e5bba433f58c052be7587ee8af3e8b3910ef9ab5fbe9f5" total=1853
DEBU[2024-08-15T09:11:20.617187676Z] fetch digest="sha256:eddacbc7e24bf8799a4ed3cdcfa50d4b88a323695ad80f317b6629883b2c2a78" mediatype=application/vnd.docker.distribution.manifest.v2+json size=528
DEBU[2024-08-15T09:11:20.617648467Z] (*service).Write started expected="sha256:eddacbc7e24bf8799a4ed3cdcfa50d4b88a323695ad80f317b6629883b2c2a78" ref="manifest-sha256:eddacbc7e24bf8799a4ed3cdcfa50d4b88a323695ad80f317b6629883b2c2a78" total=528
DEBU[2024-08-15T09:11:20.622314009Z] fetch digest="sha256:324bc02ae1231fd9255658c128086395d3fa0aedd5a41ab6b034fd649d1a9260" mediatype=application/vnd.docker.container.image.v1+json size=1471
DEBU[2024-08-15T09:11:20.622688634Z] (*service).Write started expected="sha256:324bc02ae1231fd9255658c128086395d3fa0aedd5a41ab6b034fd649d1a9260" ref="config-sha256:324bc02ae1231fd9255658c128086395d3fa0aedd5a41ab6b034fd649d1a9260" total=1471
DEBU[2024-08-15T09:11:20.630350676Z] checked for cached auth handler namespace cached=true key="docker.io/library/alpine::pull" name=docker.io/library/alpine scope=pull
DEBU[2024-08-15T09:11:20.630537759Z] checked for cached auth handler namespace cached=true key="docker.io/library/alpine::pull" name=docker.io/library/alpine scope=pull
DEBU[2024-08-15T09:11:20.642611467Z] stat snapshot key="sha256:78561cef0761903dd2f7d09856150a6d4fb48967a8f113f3e33d79effbf59a07"
DEBU[2024-08-15T09:11:20.643461842Z] stat snapshot key="sha256:78561cef0761903dd2f7d09856150a6d4fb48967a8f113f3e33d79effbf59a07"
DEBU[2024-08-15T09:11:20.645829009Z] prepare snapshot key=rfxnrnsxshr63s7mypltprv71 parent="sha256:78561cef0761903dd2f7d09856150a6d4fb48967a8f113f3e33d79effbf59a07"
DEBU[2024-08-15T09:11:20.650811259Z] Assigning addresses for endpoint rhqojzg06cvx2mhiuy07oz74r's interface on network bridge
DEBU[2024-08-15T09:11:20.650854967Z] RequestAddress(LocalDefault/172.18.0.0/16, <nil>, map[])
DEBU[2024-08-15T09:11:20.650908467Z] Request address PoolID:172.18.0.0/16 Bits: 65536, Unselected: 65533, Sequence: (0xc0000000, 1)->(0x0, 2046)->(0x1, 1)->end Curr:0 Serial:false PrefAddress:invalid IP
DEBU[2024-08-15T09:11:20.651598676Z] get snapshot mounts key=rfxnrnsxshr63s7mypltprv71
DEBU[2024-08-15T09:11:20.655803301Z] Assigning addresses for endpoint rhqojzg06cvx2mhiuy07oz74r's interface on network bridge
DEBU[2024-08-15T09:11:20.659711051Z] Programming external connectivity on endpoint rhqojzg06cvx2mhiuy07oz74r (cc20817b420ad2d17b28bfea741f810eb24f98c2387efec47e5e6c59bf06d9a1)
DEBU[2024-08-15T09:11:20.662771384Z] > creating j1kxgdupn9r3i58opkupmz8bm [/bin/sh -c echo foo > bar.txt] span="[linux/amd64 2/2] RUN echo foo > bar.txt"
DEBU[2024-08-15T09:11:20.747078384Z] fetch response received host=registry-1.docker.io response.header.content-length=1853 response.header.content-type=application/vnd.docker.distribution.manifest.list.v2+json response.header.date="Thu, 15 Aug 2024 09:11:20 GMT" response.header.docker-content-digest="sha256:0a4eaa0eecf5f8c050e5bba433f58c052be7587ee8af3e8b3910ef9ab5fbe9f5" response.header.docker-distribution-api-version=registry/2.0 response.header.docker-ratelimit-source=94.210.180.92 response.header.etag="\"sha256:0a4eaa0eecf5f8c050e5bba433f58c052be7587ee8af3e8b3910ef9ab5fbe9f5\"" response.header.ratelimit-limit="100;w=21600" response.header.ratelimit-remaining="91;w=21600" response.header.strict-transport-security="max-age=31536000" response.status="200 OK" url="https://registry-1.docker.io/v2/library/alpine/manifests/sha256:0a4eaa0eecf5f8c050e5bba433f58c052be7587ee8af3e8b3910ef9ab5fbe9f5"
DEBU[2024-08-15T09:11:20.747231509Z] resolved desc.digest="sha256:0a4eaa0eecf5f8c050e5bba433f58c052be7587ee8af3e8b3910ef9ab5fbe9f5" host=registry-1.docker.io
DEBU[2024-08-15T09:11:20.747393467Z] fetch digest="sha256:0a4eaa0eecf5f8c050e5bba433f58c052be7587ee8af3e8b3910ef9ab5fbe9f5" mediatype=application/vnd.docker.distribution.manifest.list.v2+json size=1853
DEBU[2024-08-15T09:11:20.747706967Z] (*service).Write started expected="sha256:0a4eaa0eecf5f8c050e5bba433f58c052be7587ee8af3e8b3910ef9ab5fbe9f5" ref="index-sha256:0a4eaa0eecf5f8c050e5bba433f58c052be7587ee8af3e8b3910ef9ab5fbe9f5" total=1853
DEBU[2024-08-15T09:11:20.752174217Z] fetch digest="sha256:24ba417e25e780ff13c888ccb1badec5b027944666ff695681909bafe09a3944" mediatype=application/vnd.docker.distribution.manifest.v2+json size=528
DEBU[2024-08-15T09:11:20.752348676Z] (*service).Write started expected="sha256:24ba417e25e780ff13c888ccb1badec5b027944666ff695681909bafe09a3944" ref="manifest-sha256:24ba417e25e780ff13c888ccb1badec5b027944666ff695681909bafe09a3944" total=528
DEBU[2024-08-15T09:11:20.754357301Z] sandbox set key processing took 20.269916ms for container rhqojzg06cvx2mhiuy07oz74r
DEBU[2024-08-15T09:11:20.754408217Z] fetch digest="sha256:0b4426ad4bf25e13fb09112b9dcb5d5b09b3c5684599654583913b2714a705a2" mediatype=application/vnd.docker.container.image.v1+json size=1486
DEBU[2024-08-15T09:11:20.754602384Z] (*service).Write started expected="sha256:0b4426ad4bf25e13fb09112b9dcb5d5b09b3c5684599654583913b2714a705a2" ref="config-sha256:0b4426ad4bf25e13fb09112b9dcb5d5b09b3c5684599654583913b2714a705a2" total=1486
DEBU[2024-08-15T09:11:20.759351051Z] checked for cached auth handler namespace cached=true key="docker.io/library/alpine::pull" name=docker.io/library/alpine scope=pull
DEBU[2024-08-15T09:11:20.759503051Z] checked for cached auth handler namespace cached=true key="docker.io/library/alpine::pull" name=docker.io/library/alpine scope=pull
DEBU[2024-08-15T09:11:20.770251134Z] stat snapshot key="sha256:9110f7b5208f035f4d4f99b5169338169e1df9bb2519d1b047f50f54430bacc2"
DEBU[2024-08-15T09:11:20.771540801Z] fetch digest="sha256:690e87867337b8441990047e169b892933e9006bdbcbed52ab7a356945477a4d" mediatype=application/vnd.docker.image.rootfs.diff.tar.gzip size=4086934
DEBU[2024-08-15T09:11:20.771794926Z] (*service).Write started expected="sha256:690e87867337b8441990047e169b892933e9006bdbcbed52ab7a356945477a4d" ref="layer-sha256:690e87867337b8441990047e169b892933e9006bdbcbed52ab7a356945477a4d" total=4086934
DEBU[2024-08-15T09:11:20.774837426Z] Revoking external connectivity on endpoint rhqojzg06cvx2mhiuy07oz74r (cc20817b420ad2d17b28bfea741f810eb24f98c2387efec47e5e6c59bf06d9a1)
DEBU[2024-08-15T09:11:20.775244801Z] do request digest="sha256:690e87867337b8441990047e169b892933e9006bdbcbed52ab7a356945477a4d" mediatype=application/vnd.docker.image.rootfs.diff.tar.gzip request.header.accept="application/vnd.docker.image.rootfs.diff.tar.gzip, */*" request.header.user-agent=containerd/1.7.20+unknown request.method=GET size=4086934 url="https://registry-1.docker.io/v2/library/alpine/blobs/sha256:690e87867337b8441990047e169b892933e9006bdbcbed52ab7a356945477a4d"
DEBU[2024-08-15T09:11:20.829122967Z] Releasing addresses for endpoint rhqojzg06cvx2mhiuy07oz74r's interface on network bridge
DEBU[2024-08-15T09:11:20.829149926Z] ReleaseAddress(LocalDefault/172.18.0.0/16, 172.18.0.2)
DEBU[2024-08-15T09:11:20.829167009Z] Released address Address:172.18.0.2 Sequence:Bits: 65536, Unselected: 65533, Sequence: (0xc0000000, 1)->(0x0, 2046)->(0x1, 1)->end Curr:3
DEBU[2024-08-15T09:11:20.911765551Z] fetch response received digest="sha256:690e87867337b8441990047e169b892933e9006bdbcbed52ab7a356945477a4d" mediatype=application/vnd.docker.image.rootfs.diff.tar.gzip response.header.accept-ranges=bytes response.header.age=2028353 response.header.cache-control="public, max-age=14400" response.header.cf-cache-status=HIT response.header.cf-ray=8b380e83ac86b906-AMS response.header.connection=keep-alive response.header.content-length=4086934 response.header.content-type=application/octet-stream response.header.date="Thu, 15 Aug 2024 09:11:20 GMT" response.header.etag="\"ecc07dd545dc69bc8e9443239c92a093\"" response.header.expires="Thu, 15 Aug 2024 13:11:20 GMT" response.header.last-modified="Mon, 22 Jul 2024 21:44:38 GMT" response.header.server=cloudflare response.header.vary=Accept-Encoding response.header.x-amz-id-2="ocaqk8+8YOlIVKPMqU08qVUcsFd4CSGPjXTmGnqQmROqf0Vd35gSQxPZnP+/iYHkjMSqfuoa2yo=" response.header.x-amz-request-id=693D6GA0XHEDNC1X response.header.x-amz-server-side-encryption=AES256 response.header.x-amz-version-id=Yg7.JmGNufBvosTtiNoC3GM.wiuw3y06 response.status="200 OK" size=4086934 url="https://registry-1.docker.io/v2/library/alpine/blobs/sha256:690e87867337b8441990047e169b892933e9006bdbcbed52ab7a356945477a4d"
DEBU[2024-08-15T09:11:21.031606218Z] prepare snapshot key="extract-i84rvchmucch7uoyt6xz7fx84 sha256:9110f7b5208f035f4d4f99b5169338169e1df9bb2519d1b047f50f54430bacc2" parent=
DEBU[2024-08-15T09:11:21.033361551Z] get snapshot mounts key="extract-i84rvchmucch7uoyt6xz7fx84 sha256:9110f7b5208f035f4d4f99b5169338169e1df9bb2519d1b047f50f54430bacc2"
DEBU[2024-08-15T09:11:21.033803093Z] using pigz for decompression
DEBU[2024-08-15T09:11:21.087920801Z] diff applied d=54.217458ms digest="sha256:690e87867337b8441990047e169b892933e9006bdbcbed52ab7a356945477a4d" media=application/vnd.docker.image.rootfs.diff.tar.gzip size=4086934
DEBU[2024-08-15T09:11:21.088445551Z] stat snapshot key="extract-i84rvchmucch7uoyt6xz7fx84 sha256:9110f7b5208f035f4d4f99b5169338169e1df9bb2519d1b047f50f54430bacc2"
DEBU[2024-08-15T09:11:21.088800426Z] commit snapshot key="extract-i84rvchmucch7uoyt6xz7fx84 sha256:9110f7b5208f035f4d4f99b5169338169e1df9bb2519d1b047f50f54430bacc2" name="sha256:9110f7b5208f035f4d4f99b5169338169e1df9bb2519d1b047f50f54430bacc2"
DEBU[2024-08-15T09:11:21.094695843Z] prepare snapshot key=mkjbe0sfh55adajiujupp10z1 parent="sha256:9110f7b5208f035f4d4f99b5169338169e1df9bb2519d1b047f50f54430bacc2"
DEBU[2024-08-15T09:11:21.096182593Z] Assigning addresses for endpoint zzbrz99f4pun6xrn6mrl2uaq8's interface on network bridge
DEBU[2024-08-15T09:11:21.096207468Z] RequestAddress(LocalDefault/172.18.0.0/16, <nil>, map[])
DEBU[2024-08-15T09:11:21.096227634Z] Request address PoolID:172.18.0.0/16 Bits: 65536, Unselected: 65533, Sequence: (0xc0000000, 1)->(0x0, 2046)->(0x1, 1)->end Curr:3 Serial:false PrefAddress:invalid IP
DEBU[2024-08-15T09:11:21.096349676Z] get snapshot mounts key=mkjbe0sfh55adajiujupp10z1
DEBU[2024-08-15T09:11:21.097715468Z] Assigning addresses for endpoint zzbrz99f4pun6xrn6mrl2uaq8's interface on network bridge
DEBU[2024-08-15T09:11:21.101424509Z] Programming external connectivity on endpoint zzbrz99f4pun6xrn6mrl2uaq8 (424fa19401e3a3ca2c93ca1b3777649909a338303834ab453599274f6ef6cc6f)
DEBU[2024-08-15T09:11:21.102758509Z] > creating kxi8e422e71hm6o40qa7yvziq [/bin/sh -c echo foo > bar.txt] span="[linux/arm64 2/2] RUN echo foo > bar.txt"
DEBU[2024-08-15T09:11:21.175169259Z] sandbox set key processing took 26.421167ms for container zzbrz99f4pun6xrn6mrl2uaq8
DEBU[2024-08-15T09:11:21.187969009Z] Revoking external connectivity on endpoint zzbrz99f4pun6xrn6mrl2uaq8 (424fa19401e3a3ca2c93ca1b3777649909a338303834ab453599274f6ef6cc6f)
WARN[2024-08-15T09:11:21.210401009Z] forcibly turning on oci-mediatype mode for attestations span="exporting to image"
DEBU[2024-08-15T09:11:21.212723468Z] stat snapshot key=mkjbe0sfh55adajiujupp10z1
DEBU[2024-08-15T09:11:21.212959093Z] commit snapshot key=mkjbe0sfh55adajiujupp10z1 name=d2bkvsbzr5q2ikk2d2e0yv8la
DEBU[2024-08-15T09:11:21.215693259Z] stat snapshot key=rfxnrnsxshr63s7mypltprv71
DEBU[2024-08-15T09:11:21.215859093Z] commit snapshot key=rfxnrnsxshr63s7mypltprv71 name=z96wdtx6pmnjmfhuk0uh6nkjq
DEBU[2024-08-15T09:11:21.219924509Z] stat snapshot key="sha256:78561cef0761903dd2f7d09856150a6d4fb48967a8f113f3e33d79effbf59a07"
DEBU[2024-08-15T09:11:21.223321134Z] prepare view snapshot key="sha256:9110f7b5208f035f4d4f99b5169338169e1df9bb2519d1b047f50f54430bacc2-view" parent="sha256:9110f7b5208f035f4d4f99b5169338169e1df9bb2519d1b047f50f54430bacc2"
DEBU[2024-08-15T09:11:21.225717759Z] prepare view snapshot key="sha256:78561cef0761903dd2f7d09856150a6d4fb48967a8f113f3e33d79effbf59a07-view" parent="sha256:78561cef0761903dd2f7d09856150a6d4fb48967a8f113f3e33d79effbf59a07"
DEBU[2024-08-15T09:11:21.226769093Z] prepare view snapshot key=d2bkvsbzr5q2ikk2d2e0yv8la-view parent=d2bkvsbzr5q2ikk2d2e0yv8la
DEBU[2024-08-15T09:11:21.230594759Z] (*service).Write started ref=d2bkvsbzr5q2ikk2d2e0yv8la
DEBU[2024-08-15T09:11:21.232790134Z] prepare view snapshot key=z96wdtx6pmnjmfhuk0uh6nkjq-view parent=z96wdtx6pmnjmfhuk0uh6nkjq
DEBU[2024-08-15T09:11:21.235913509Z] (*service).Write started ref=z96wdtx6pmnjmfhuk0uh6nkjq
DEBU[2024-08-15T09:11:21.241490968Z] Releasing addresses for endpoint zzbrz99f4pun6xrn6mrl2uaq8's interface on network bridge
DEBU[2024-08-15T09:11:21.241550634Z] ReleaseAddress(LocalDefault/172.18.0.0/16, 172.18.0.2)
DEBU[2024-08-15T09:11:21.241574134Z] Released address Address:172.18.0.2 Sequence:Bits: 65536, Unselected: 65533, Sequence: (0xc0000000, 1)->(0x0, 2046)->(0x1, 1)->end Curr:3
DEBU[2024-08-15T09:11:21.249520301Z] stat snapshot key="sha256:78561cef0761903dd2f7d09856150a6d4fb48967a8f113f3e33d79effbf59a07"
DEBU[2024-08-15T09:11:21.250070843Z] (*service).Write started expected="sha256:5e8b7eefeb1a82ac3e4a02a39713b06630af5f84783319b4aa542269162c7061" ref="sha256:5e8b7eefeb1a82ac3e4a02a39713b06630af5f84783319b4aa542269162c7061" total=668
DEBU[2024-08-15T09:11:21.253123926Z] (*service).Write started expected="sha256:c9735f57ceaaa960414a39f01d2d57fa57fdac45a30ed37a10618b540a7d48e9" ref="sha256:c9735f57ceaaa960414a39f01d2d57fa57fdac45a30ed37a10618b540a7d48e9" total=804
DEBU[2024-08-15T09:11:21.257470718Z] (*service).Write started expected="sha256:1a6bcfbf3758876634d8e61d470cb2b6827ee7cbd1b0452d88888d8284dddba7" ref="sha256:1a6bcfbf3758876634d8e61d470cb2b6827ee7cbd1b0452d88888d8284dddba7" total=1054
DEBU[2024-08-15T09:11:21.260482093Z] (*service).Write started expected="sha256:ab970d24214c450ed45829c8354a2fb18782ed90bdc6866edbf2e905bf4ef751" ref="sha256:ab970d24214c450ed45829c8354a2fb18782ed90bdc6866edbf2e905bf4ef751" total=566
DEBU[2024-08-15T09:11:21.264622468Z] (*service).Write started expected="sha256:1a062ccb568cd72fddfbc1e3288674f1bf50d4beb362ba788f1b1fe462d68bb9" ref="sha256:1a062ccb568cd72fddfbc1e3288674f1bf50d4beb362ba788f1b1fe462d68bb9" total=167
DEBU[2024-08-15T09:11:21.267530093Z] (*service).Write started expected="sha256:d9e539c242e7242a49e916515b2652a01bdcce938ac3a0b7857ae7789c862f8d" ref="sha256:d9e539c242e7242a49e916515b2652a01bdcce938ac3a0b7857ae7789c862f8d" total=668
DEBU[2024-08-15T09:11:21.272506093Z] (*service).Write started expected="sha256:2fcf6b7a19bdd117bde86bc1d7931f2532b80ba3b91e5dda0a1abb92b6f4d4e6" ref="sha256:2fcf6b7a19bdd117bde86bc1d7931f2532b80ba3b91e5dda0a1abb92b6f4d4e6" total=804
DEBU[2024-08-15T09:11:21.275534009Z] (*service).Write started expected="sha256:b482805971bba275e7df9bade225c2d7e00da1d70f504051bd52dbd5a58a0ea5" ref="sha256:b482805971bba275e7df9bade225c2d7e00da1d70f504051bd52dbd5a58a0ea5" total=1054
DEBU[2024-08-15T09:11:21.278450968Z] (*service).Write started expected="sha256:0ab9a2ba6869096da889820765da62e6a84cc973a0f290d9ac0d2700211a86ac" ref="sha256:0ab9a2ba6869096da889820765da62e6a84cc973a0f290d9ac0d2700211a86ac" total=566
DEBU[2024-08-15T09:11:21.282461218Z] (*service).Write started expected="sha256:566af6fab4898e28ff756ae3d0c3794a5ada779707c9ddade6b95946e8dcf9b9" ref="sha256:566af6fab4898e28ff756ae3d0c3794a5ada779707c9ddade6b95946e8dcf9b9" total=167
DEBU[2024-08-15T09:11:21.286425968Z] (*service).Write started expected="sha256:0f4252a64b9b30ce2f689bb258b2fef065112f3bf986484df2b7f72e4a880644" ref="sha256:0f4252a64b9b30ce2f689bb258b2fef065112f3bf986484df2b7f72e4a880644" total=1607
DEBU[2024-08-15T09:11:21.289999384Z] create image name="localhost:5001/owner/name:latest" target="sha256:0f4252a64b9b30ce2f689bb258b2fef065112f3bf986484df2b7f72e4a880644"
DEBU[2024-08-15T09:11:21.293383801Z] stat snapshot key="sha256:9110f7b5208f035f4d4f99b5169338169e1df9bb2519d1b047f50f54430bacc2"
DEBU[2024-08-15T09:11:21.293608759Z] stat snapshot key="sha256:7e91700ab423c5cdc6079bb13656f44f50d148101ae9db97fcbb202e66dd7965"
DEBU[2024-08-15T09:11:21.293797801Z] prepare snapshot key="extract-293684509-9eBL sha256:7e91700ab423c5cdc6079bb13656f44f50d148101ae9db97fcbb202e66dd7965" parent="sha256:9110f7b5208f035f4d4f99b5169338169e1df9bb2519d1b047f50f54430bacc2"
DEBU[2024-08-15T09:11:21.295555801Z] get snapshot mounts key="extract-293684509-9eBL sha256:7e91700ab423c5cdc6079bb13656f44f50d148101ae9db97fcbb202e66dd7965"
DEBU[2024-08-15T09:11:21.297857384Z] diff applied d=2.114ms digest="sha256:e1242d71724cd21a880fc330608ef7f8d30473b19e16b84c883300d1bfebbeb4" media=application/vnd.oci.image.layer.v1.tar+gzip size=109
DEBU[2024-08-15T09:11:21.298056926Z] stat snapshot key="extract-293684509-9eBL sha256:7e91700ab423c5cdc6079bb13656f44f50d148101ae9db97fcbb202e66dd7965"
DEBU[2024-08-15T09:11:21.298248426Z] commit snapshot key="extract-293684509-9eBL sha256:7e91700ab423c5cdc6079bb13656f44f50d148101ae9db97fcbb202e66dd7965" name="sha256:7e91700ab423c5cdc6079bb13656f44f50d148101ae9db97fcbb202e66dd7965"
DEBU[2024-08-15T09:11:21.300254426Z] stat snapshot key="sha256:78561cef0761903dd2f7d09856150a6d4fb48967a8f113f3e33d79effbf59a07"
DEBU[2024-08-15T09:11:21.300754593Z] stat snapshot key="sha256:78561cef0761903dd2f7d09856150a6d4fb48967a8f113f3e33d79effbf59a07"
DEBU[2024-08-15T09:11:21.304220509Z] encountered unknown type application/vnd.in-toto+json; children may not be fetched
DEBU[2024-08-15T09:11:21.305172468Z] encountered unknown type application/vnd.in-toto+json; children may not be fetched
WARN[2024-08-15T09:11:21.305995176Z] image named with invalid reference produced by buildkit error="invalid reference format" name= span="exporting to image"
DEBU[2024-08-15T09:11:21.306870009Z] stat snapshot key="sha256:78561cef0761903dd2f7d09856150a6d4fb48967a8f113f3e33d79effbf59a07"
DEBU[2024-08-15T09:11:21.307579718Z] (*service).Write started ref=history-ref_306360051-zsue
DEBU[2024-08-15T09:11:21.307878259Z] stat snapshot key="sha256:78561cef0761903dd2f7d09856150a6d4fb48967a8f113f3e33d79effbf59a07"
DEBU[2024-08-15T09:11:21.308407093Z] stat snapshot key="sha256:78561cef0761903dd2f7d09856150a6d4fb48967a8f113f3e33d79effbf59a07"
DEBU[2024-08-15T09:11:21.308926218Z] stat snapshot key="sha256:78561cef0761903dd2f7d09856150a6d4fb48967a8f113f3e33d79effbf59a07"
DEBU[2024-08-15T09:11:21.309216384Z] stat snapshot key="sha256:78561cef0761903dd2f7d09856150a6d4fb48967a8f113f3e33d79effbf59a07"
DEBU[2024-08-15T09:11:21.309617384Z] stat snapshot key="sha256:78561cef0761903dd2f7d09856150a6d4fb48967a8f113f3e33d79effbf59a07"
DEBU[2024-08-15T09:11:21.311831801Z] (*service).Write started ref=history-ref_308140759-aK4U
DEBU[2024-08-15T09:11:21.313881884Z] (*service).Write started ref=history-ref_309942759-PLid
DEBU[2024-08-15T09:11:21.323937009Z] (*service).Write started expected="sha256:0f4252a64b9b30ce2f689bb258b2fef065112f3bf986484df2b7f72e4a880644" ref="history-migrate-sha256:0f4252a64b9b30ce2f689bb258b2fef065112f3bf986484df2b7f72e4a880644"
DEBU[2024-08-15T09:11:21.327702843Z] (*service).Write started expected="sha256:ab970d24214c450ed45829c8354a2fb18782ed90bdc6866edbf2e905bf4ef751" ref="history-migrate-sha256:ab970d24214c450ed45829c8354a2fb18782ed90bdc6866edbf2e905bf4ef751"
DEBU[2024-08-15T09:11:21.330234926Z] (*service).Write started expected="sha256:1a062ccb568cd72fddfbc1e3288674f1bf50d4beb362ba788f1b1fe462d68bb9" ref="history-migrate-sha256:1a062ccb568cd72fddfbc1e3288674f1bf50d4beb362ba788f1b1fe462d68bb9"
DEBU[2024-08-15T09:11:21.332273218Z] (*service).Write started expected="sha256:0ab9a2ba6869096da889820765da62e6a84cc973a0f290d9ac0d2700211a86ac" ref="history-migrate-sha256:0ab9a2ba6869096da889820765da62e6a84cc973a0f290d9ac0d2700211a86ac"
DEBU[2024-08-15T09:11:21.334487218Z] (*service).Write started expected="sha256:566af6fab4898e28ff756ae3d0c3794a5ada779707c9ddade6b95946e8dcf9b9" ref="history-migrate-sha256:566af6fab4898e28ff756ae3d0c3794a5ada779707c9ddade6b95946e8dcf9b9"
DEBU[2024-08-15T09:11:21.336305926Z] (*service).Write started expected="sha256:5e8b7eefeb1a82ac3e4a02a39713b06630af5f84783319b4aa542269162c7061" ref="history-migrate-sha256:5e8b7eefeb1a82ac3e4a02a39713b06630af5f84783319b4aa542269162c7061"
DEBU[2024-08-15T09:11:21.338769468Z] (*service).Write started expected="sha256:c9735f57ceaaa960414a39f01d2d57fa57fdac45a30ed37a10618b540a7d48e9" ref="history-migrate-sha256:c9735f57ceaaa960414a39f01d2d57fa57fdac45a30ed37a10618b540a7d48e9"
DEBU[2024-08-15T09:11:21.340343259Z] (*service).Write started expected="sha256:d9e539c242e7242a49e916515b2652a01bdcce938ac3a0b7857ae7789c862f8d" ref="history-migrate-sha256:d9e539c242e7242a49e916515b2652a01bdcce938ac3a0b7857ae7789c862f8d"
DEBU[2024-08-15T09:11:21.342360676Z] (*service).Write started expected="sha256:2fcf6b7a19bdd117bde86bc1d7931f2532b80ba3b91e5dda0a1abb92b6f4d4e6" ref="history-migrate-sha256:2fcf6b7a19bdd117bde86bc1d7931f2532b80ba3b91e5dda0a1abb92b6f4d4e6"
DEBU[2024-08-15T09:11:21.366331134Z] remove snapshot key=d2bkvsbzr5q2ikk2d2e0yv8la-view snapshotter=overlayfs
DEBU[2024-08-15T09:11:21.366388593Z] remove snapshot key=z96wdtx6pmnjmfhuk0uh6nkjq-view snapshotter=overlayfs
DEBU[2024-08-15T09:11:21.366908134Z] schedule snapshotter cleanup snapshotter=overlayfs
DEBU[2024-08-15T09:11:21.367291968Z] removed snapshot key=moby/14/z96wdtx6pmnjmfhuk0uh6nkjq-view snapshotter=overlayfs
DEBU[2024-08-15T09:11:21.367610426Z] removed snapshot key=moby/13/d2bkvsbzr5q2ikk2d2e0yv8la-view snapshotter=overlayfs
DEBU[2024-08-15T09:11:23.995928469Z] 2024/08/15 09:11:23 WARNING: [core] [Channel #14 SubChannel #15] grpc: addrConn.createTransport failed to connect to {Addr: "localhost", ServerName: "localhost", }. Err: connection error: desc = "transport: Error while dialing: only one connection allowed" library=grpc
DEBU[2024-08-15T09:11:23.997967344Z] 2024/08/15 09:11:23 WARNING: [core] [Channel #17 SubChannel #18] grpc: addrConn.createTransport failed to connect to {Addr: "localhost", ServerName: "localhost", }. Err: connection error: desc = "transport: Error while dialing: only one connection allowed" library=grpc
DEBU[2024-08-15T09:11:24.352976636Z] (*service).Write started ref=history-ref_349499511-NeTL
Errors / warnings:
DEBU[2024-08-15T09:11:21.304220509Z] encountered unknown type application/vnd.in-toto+json; children may not be fetched
DEBU[2024-08-15T09:11:21.305172468Z] encountered unknown type application/vnd.in-toto+json; children may not be fetched
WARN[2024-08-15T09:11:21.305995176Z] image named with invalid reference produced by buildkit error="invalid reference format" name= span="exporting to image"
DEBU[2024-08-15T09:11:23.995928469Z] 2024/08/15 09:11:23 WARNING: [core] [Channel #14 SubChannel #15] grpc: addrConn.createTransport failed to connect to {Addr: "localhost", ServerName: "localhost", }. Err: connection error: desc = "transport: Error while dialing: only one connection allowed" library=grpc
DEBU[2024-08-15T09:11:23.997967344Z] 2024/08/15 09:11:23 WARNING: [core] [Channel #17 SubChannel #18] grpc: addrConn.createTransport failed to connect to {Addr: "localhost", ServerName: "localhost", }. Err: connection error: desc = "transport: Error while dialing: only one connection allowed" library=grpc
The first of the above look to originate from either Buildkit or containerd; from one of these;
| bklog.G(ctx).Warnf("encountered unknown type %v; children may not be fetched", desc.MediaType) |
| return nil, errors.Errorf("encountered unknown type %v; children may not be fetched", desc.MediaType) |
| log.G(ctx).Debugf("encountered unknown type %v; children may not be fetched", desc.MediaType) |
The second one comes from the exporter; it looks like the name isn't set;
moby/builder/builder-next/exporter/wrapper.go
Lines 84 to 99 in f9522e5
| if i.callbacks.Named != nil { | |
| for _, name := range strings.Split(out[string(exptypes.OptKeyName)], ",") { | |
| ref, err := reference.ParseNormalizedNamed(name) | |
| if err != nil { | |
| // Shouldn't happen, but log if it does and continue. | |
| log.G(ctx).WithFields(log.Fields{ | |
| "name": name, | |
| "error": err, | |
| }).Warn("image named with invalid reference produced by buildkit") | |
| continue | |
| } | |
| namedTagged := reference.TagNameOnly(ref).(reference.NamedTagged) | |
| i.callbacks.Named(ctx, namedTagged, desc) | |
| } | |
| } |
The third one could be related to OTEL trying to phone home; not sure what part though (containerd or moby?) we need to look if we can disable that, but also why we end up with the error. Perhaps because BuildKit builds both architectures in parallel?
Perhaps because BuildKit builds both architectures in parallel?
Answering myself; that's not it; the same happens when running the build without a platform specified (so only building a single one);
docker build -t localhost:5001/owner/name -<<'EOF'
FROM alpine
RUN echo foo > bar.txt
EOFMetadata
Metadata
Assignees
Labels
Type
Projects
Status
Status