buildkit icon indicating copy to clipboard operation
buildkit copied to clipboard
verified publisher icon moby

Flaky test: `TestWarningsBeforeError`

Open crazy-max opened this issue 1 year ago • 0 comments

Happened on windows integration tests: https://github.com/moby/buildkit/actions/runs/11162974334/job/31028930617?pr=5390#step:8:2175

=== FAIL: frontend/dockerfile TestIntegration/TestWarningsBeforeError/worker=containerd/frontend=client/warntype=progress (0.30s)
    dockerfile_lint_test.go:1523: 
        	Error Trace:	D:/a/buildkit/buildkit/frontend/dockerfile/dockerfile_lint_test.go:1462
        	            				D:/a/buildkit/buildkit/frontend/dockerfile/dockerfile_lint_test.go:1523
        	Error:      	Error message not equal:
        	            	expected: "failed to solve: dockerfile parse error on line 4: unknown instruction: BADCMD"
        	            	actual  : "failed to run Build function: dockerfile parse error on line 4: unknown instruction: BADCMD"
        	Test:       	TestIntegration/TestWarningsBeforeError/worker=containerd/frontend=client/warntype=progress
        --- FAIL: TestIntegration/TestWarningsBeforeError/worker=containerd/frontend=client/warntype=progress (0.30s)

=== FAIL: frontend/dockerfile TestIntegration/TestWarningsBeforeError/worker=containerd/frontend=client (0.74s)
    sandbox.go:135: stdout: D:\a\buildkit\buildkit\bin\containerd.exe --config C:\Users\RUNNER~1\AppData\Local\Temp\bktest_containerd3339088479\config.toml
    sandbox.go:135: stderr: D:\a\buildkit\buildkit\bin\containerd.exe --config C:\Users\RUNNER~1\AppData\Local\Temp\bktest_containerd3339088479\config.toml
    sandbox.go:138: > StartCmd 2024-10-03 13:59:14.2466889 +0000 UTC m=+1440.263738501 D:\a\buildkit\buildkit\bin\containerd.exe --config C:\Users\RUNNER~1\AppData\Local\Temp\bktest_containerd3339088479\config.toml
    sandbox.go:138: time="2024-10-03T13:59:14Z" level=warning msg="containerd config version `1` has been deprecated and will be converted on each startup in containerd v2.0, use `containerd config migrate` after upgrading to containerd 2.0 to avoid conversion on startup"
    sandbox.go:138: time="2024-10-03T13:59:14.296866200Z" level=info msg="starting containerd" revision=472731909fa34bd7bc9c087e4c27943f9835f111 version=v1.7.21
    sandbox.go:138: time="2024-10-03T13:59:14.296866200Z" level=debug msg="Stackdump - waiting signal at Global\\stackdump-5792"
    sandbox.go:138: time="2024-10-03T13:59:14.310902000Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1
    sandbox.go:138: time="2024-10-03T13:59:14.310902000Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1
    sandbox.go:138: time="2024-10-03T13:59:14.311572200Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1
    sandbox.go:138: time="2024-10-03T13:59:14.311572200Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.windows-lcow\"..." type=io.containerd.snapshotter.v1
    sandbox.go:138: time="2024-10-03T13:59:14.312124500Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.windows\"..." type=io.containerd.snapshotter.v1
    sandbox.go:138: time="2024-10-03T13:59:14.312676200Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1
    sandbox.go:138: time="2024-10-03T13:59:14.313267700Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1
    sandbox.go:138: time="2024-10-03T13:59:14.313267700Z" level=info msg="metadata content store policy set" policy=shared
    sandbox.go:138: time="2024-10-03T13:59:14.327662500Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1
    sandbox.go:138: time="2024-10-03T13:59:14.328530300Z" level=info msg="loading plugin \"io.containerd.differ.v1.windows-lcow\"..." type=io.containerd.differ.v1
    sandbox.go:138: time="2024-10-03T13:59:14.328530300Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1
    sandbox.go:138: time="2024-10-03T13:59:14.328530300Z" level=info msg="loading plugin \"io.containerd.differ.v1.windows\"..." type=io.containerd.differ.v1
    sandbox.go:138: time="2024-10-03T13:59:14.328530300Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1
    sandbox.go:138: time="2024-10-03T13:59:14.328530300Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1
    sandbox.go:138: time="2024-10-03T13:59:14.328530300Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2
    sandbox.go:138: time="2024-10-03T13:59:14.329082500Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2
    sandbox.go:138: time="2024-10-03T13:59:14.329082500Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1
    sandbox.go:138: time="2024-10-03T13:59:14.329082500Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1
    sandbox.go:138: time="2024-10-03T13:59:14.329082500Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1
    sandbox.go:138: time="2024-10-03T13:59:14.329082500Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1
    sandbox.go:138: time="2024-10-03T13:59:14.329082500Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1
    sandbox.go:138: time="2024-10-03T13:59:14.329599000Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1
    sandbox.go:138: time="2024-10-03T13:59:14.329599000Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1
    sandbox.go:138: time="2024-10-03T13:59:14.329599000Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1
    sandbox.go:138: time="2024-10-03T13:59:14.329666100Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1
    sandbox.go:138: time="2024-10-03T13:59:14.329687100Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1
    sandbox.go:138: time="2024-10-03T13:59:14.329687100Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1
    sandbox.go:138: time="2024-10-03T13:59:14.329687100Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1
    sandbox.go:138: time="2024-10-03T13:59:14.329753500Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1
    sandbox.go:138: time="2024-10-03T13:59:14.329753500Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1
    sandbox.go:138: time="2024-10-03T13:59:14.329753500Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1
    sandbox.go:138: time="2024-10-03T13:59:14.329753500Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1
    sandbox.go:138: time="2024-10-03T13:59:14.329753500Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1
    sandbox.go:138: time="2024-10-03T13:59:14.329820800Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1
    sandbox.go:138: time="2024-10-03T13:59:14.329843800Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1
    sandbox.go:138: time="2024-10-03T13:59:14.329843800Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1
    sandbox.go:138: time="2024-10-03T13:59:14.329843800Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1
    sandbox.go:138: time="2024-10-03T13:59:14.329906000Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1
    sandbox.go:138: time="2024-10-03T13:59:14.329906000Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1
    sandbox.go:138: time="2024-10-03T13:59:14.329906000Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1
    sandbox.go:138: time="2024-10-03T13:59:14.329967400Z" level=warning msg="multiple differs match for platform, set `differ` option to choose, skipping \"windows\""
    sandbox.go:138: time="2024-10-03T13:59:14.329967400Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1
    sandbox.go:138: time="2024-10-03T13:59:14.329967400Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1
    sandbox.go:138: time="2024-10-03T13:59:14.330056600Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1
    sandbox.go:138: time="2024-10-03T13:59:14.330117900Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1
    sandbox.go:138: time="2024-10-03T13:59:14.330214000Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.tracing.processor.v1
    sandbox.go:138: time="2024-10-03T13:59:14.330214000Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1
    sandbox.go:138: time="2024-10-03T13:59:14.330214000Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1
    sandbox.go:138: time="2024-10-03T13:59:14.330214000Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1
    sandbox.go:138: time="2024-10-03T13:59:14.330214000Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1
    sandbox.go:138: time="2024-10-03T13:59:14.330214000Z" level=info msg="NRI interface is disabled by configuration."
    sandbox.go:138: time="2024-10-03T13:59:14.330214000Z" level=info msg=serving... address="\\\\.\\pipe\\containerd-bktest_containerd3339088479debug"
    sandbox.go:138: time="2024-10-03T13:59:14.330214000Z" level=info msg=serving... address="\\\\.\\pipe\\containerd-bktest_containerd3339088479.ttrpc"
    sandbox.go:138: time="2024-10-03T13:59:14.330214000Z" level=info msg=serving... address="\\\\.\\pipe\\containerd-bktest_containerd3339088479"
    sandbox.go:138: time="2024-10-03T13:59:14.330214000Z" level=info msg="containerd successfully booted in 0.065347s"
    sandbox.go:138: time="2024-10-03T13:59:14.518137800Z" level=debug msg="prepare snapshot" key=sres1kcc1im4d3a5wtewcc48r parent=
    sandbox.go:138: time="2024-10-03T13:59:14.525118500Z" level=debug msg=createSnapshot
    sandbox.go:138: time="2024-10-03T13:59:14.573750500Z" level=debug msg="get snapshot mounts" key=sres1kcc1im4d3a5wtewcc48r
    sandbox.go:138: time="2024-10-03T13:59:14.614128400Z" level=debug msg="get snapshot mounts" key=sres1kcc1im4d3a5wtewcc48r
    sandbox.go:138: time="2024-10-03T13:59:14.629234800Z" level=debug msg="(*service).Write started" ref=history-ref_622176300-J_I5
    sandbox.go:138: time="2024-10-03T13:59:14.696906400Z" level=debug msg="(*service).Write started" ref=history-ref_680970900-cVpu
    sandbox.go:138: time="2024-10-03T13:59:14.852534900Z" level=debug msg="get snapshot mounts" key=sres1kcc1im4d3a5wtewcc48r
    sandbox.go:138: time="2024-10-03T13:59:14.869712100Z" level=debug msg="(*service).Write started" ref=history-ref_868053800-Z4VS
    sandbox.go:135: stdout: D:\a\buildkit\buildkit\bin\buildkitd.exe --containerd-worker-gc=false --containerd-worker=true --containerd-worker-addr npipe:////./pipe/containerd-bktest_containerd3339088479 --containerd-worker-labels=org.mobyproject.buildkit.worker.sandbox=true --config=C:\Users\RUNNER~1\AppData\Local\Temp\bktest_config3904960777\buildkitd.toml --root C:\Users\RUNNER~1\AppData\Local\Temp\bktest_buildkitd1136501169 --addr npipe:////./pipe/buildkitd-bktest_buildkitd1136501169 --debug
    sandbox.go:135: stderr: D:\a\buildkit\buildkit\bin\buildkitd.exe --containerd-worker-gc=false --containerd-worker=true --containerd-worker-addr npipe:////./pipe/containerd-bktest_containerd3339088479 --containerd-worker-labels=org.mobyproject.buildkit.worker.sandbox=true --config=C:\Users\RUNNER~1\AppData\Local\Temp\bktest_config3904960777\buildkitd.toml --root C:\Users\RUNNER~1\AppData\Local\Temp\bktest_buildkitd1136501169 --addr npipe:////./pipe/buildkitd-bktest_buildkitd1136501169 --debug
    sandbox.go:138: > StartCmd 2024-10-03 13:59:14.3603633 +0000 UTC m=+1440.377412101 D:\a\buildkit\buildkit\bin\buildkitd.exe --containerd-worker-gc=false --containerd-worker=true --containerd-worker-addr npipe:////./pipe/containerd-bktest_containerd3339088479 --containerd-worker-labels=org.mobyproject.buildkit.worker.sandbox=true --config=C:\Users\RUNNER~1\AppData\Local\Temp\bktest_config3904960777\buildkitd.toml --root C:\Users\RUNNER~1\AppData\Local\Temp\bktest_buildkitd1136501169 --addr npipe:////./pipe/buildkitd-bktest_buildkitd1136501169 --debug
    sandbox.go:138: time="2024-10-03T13:59:14Z" level=warning msg="failed to get disk size: The system cannot find the path specified."
    sandbox.go:138: time="2024-10-03T13:59:14Z" level=warning msg="using null network as the default"
    sandbox.go:138: time="2024-10-03T13:59:14Z" level=debug msg="remote introspection plugin filters" filters="[type==io.containerd.runtime.v1 type==io.containerd.runtime.v2]"
    sandbox.go:138: time="2024-10-03T13:59:14Z" level=info msg="found worker \"rtcyn3il3ohxuqnbtt2qw9vpi\", labels=map[org.mobyproject.buildkit.worker.containerd.namespace:buildkit org.mobyproject.buildkit.worker.containerd.uuid:3e750e64-c7f4-4865-9545-85a78dc876a5 org.mobyproject.buildkit.worker.executor:containerd org.mobyproject.buildkit.worker.hostname:fv-az1111-261 org.mobyproject.buildkit.worker.network: org.mobyproject.buildkit.worker.sandbox:true org.mobyproject.buildkit.worker.selinux.enabled:false org.mobyproject.buildkit.worker.snapshotter:windows], platforms=[windows/amd64]"
    sandbox.go:138: time="2024-10-03T13:59:14Z" level=info msg="found 1 workers, default=\"rtcyn3il3ohxuqnbtt2qw9vpi\""
    sandbox.go:138: time="2024-10-03T13:59:14Z" level=warning msg="currently, only the default worker can be used."
    sandbox.go:138: time="2024-10-03T13:59:14Z" level=info msg="running server on //./pipe/buildkitd-bktest_buildkitd1136501169"
    sandbox.go:138: time="2024-10-03T13:59:14Z" level=debug msg="session started" spanID=407a94c827d3bdef traceID=3e7317a194bba1d33126f89819fcd05d
    sandbox.go:138: time="2024-10-03T13:59:14Z" level=debug msg="new ref for local: sres1kcc1im4d3a5wtewcc48r" span="[internal] load build definition from Dockerfile" spanID=a78178edf586edb7 traceID=3d0f95416d80d053fd6cb46f176c360c
    sandbox.go:138: time="2024-10-03T13:59:14Z" level=debug msg="Calling proc (1)"
    sandbox.go:138: time="2024-10-03T13:59:14Z" level=debug msg="Calling proc (2)"
    sandbox.go:138: time="2024-10-03T13:59:14Z" level=debug msg="diffcopy took: 3.6929ms" span="[internal] load build definition from Dockerfile" spanID=42a71a7e89519b0c traceID=3d0f95416d80d053fd6cb46f176c360c
    sandbox.go:138: time="2024-10-03T13:59:14Z" level=debug msg="saved sres1kcc1im4d3a5wtewcc48r as dockerfile:dockerfile:" span="[internal] load build definition from Dockerfile" spanID=a78178edf586edb7 traceID=3d0f95416d80d053fd6cb46f176c360c
    sandbox.go:138: time="2024-10-03T13:59:14Z" level=debug msg="Calling proc (1)"
    sandbox.go:138: time="2024-10-03T13:59:14Z" level=debug msg="Calling proc (2)"
    sandbox.go:138: time="2024-10-03T13:59:14Z" level=debug msg="Calling proc (1)"
    sandbox.go:138: time="2024-10-03T13:59:14Z" level=debug msg="Calling proc (2)"
    sandbox.go:138: time="2024-10-03T13:59:14Z" level=error msg="/moby.buildkit.v1.frontend.LLBBridge/ReadFile returned error: rpc error: code = Unknown desc = open Dockerfile.dockerignore: The system cannot find the file specified." spanID=a75c30e510baf4df traceID=66be2a9bcaa757fc835a6300738e181c
    sandbox.go:138: open Dockerfile.dockerignore: The system cannot find the file specified.
    sandbox.go:138: 4948 2321320 buildkitd --containerd-worker-gc=false --containerd-worker=true --containerd-worker-addr npipe:////./pipe/containerd-bktest_containerd3339088479 --containerd-worker-labels=org.mobyproject.buildkit.worker.sandbox=true --config=C:\Users\RUNNER~1\AppData\Local\Temp\bktest_config3904960777\buildkitd.toml --root C:\Users\RUNNER~1\AppData\Local\Temp\bktest_buildkitd1136501169 --addr npipe:////./pipe/buildkitd-bktest_buildkitd1136501169 --debug
    sandbox.go:138: github.com/moby/buildkit/cache/util.ReadFile.func1
    sandbox.go:138: 	/src/cache/util/fsutil.go:68
    sandbox.go:138: github.com/moby/buildkit/cache/util.withMount
    sandbox.go:138: 	/src/cache/util/fsutil.go:40
    sandbox.go:138: github.com/moby/buildkit/cache/util.ReadFile
    sandbox.go:138: 	/src/cache/util/fsutil.go:54
    sandbox.go:138: github.com/moby/buildkit/frontend/gateway.(*llbBridgeForwarder).ReadFile
    sandbox.go:138: 	/src/frontend/gateway/gateway.go:935
    sandbox.go:138: github.com/moby/buildkit/control/gateway.(*GatewayForwarder).ReadFile
    sandbox.go:138: 	/src/control/gateway/gateway.go:122
    sandbox.go:138: github.com/moby/buildkit/frontend/gateway/pb._LLBBridge_ReadFile_Handler.func1
    sandbox.go:138: 	/src/frontend/gateway/pb/gateway_grpc.pb.go:383
    sandbox.go:138: google.golang.org/grpc.getChainUnaryHandler.func1
    sandbox.go:138: 	/src/vendor/google.golang.org/grpc/server.go:1211
    sandbox.go:138: main.unaryInterceptor
    sandbox.go:138: 	/src/cmd/buildkitd/main.go:711
    sandbox.go:138: google.golang.org/grpc.NewServer.chainUnaryServerInterceptors.chainUnaryInterceptors.func1
    sandbox.go:138: 	/src/vendor/google.golang.org/grpc/server.go:1202
    sandbox.go:138: github.com/moby/buildkit/frontend/gateway/pb._LLBBridge_ReadFile_Handler
    sandbox.go:138: 	/src/frontend/gateway/pb/gateway_grpc.pb.go:385
    sandbox.go:138: google.golang.org/grpc.(*Server).processUnaryRPC
    sandbox.go:138: 	/src/vendor/google.golang.org/grpc/server.go:1394
    sandbox.go:138: google.golang.org/grpc.(*Server).handleStream
    sandbox.go:138: 	/src/vendor/google.golang.org/grpc/server.go:1805
    sandbox.go:138: google.golang.org/grpc.(*Server).serveStreams.func2.1
    sandbox.go:138: 	/src/vendor/google.golang.org/grpc/server.go:1029
    sandbox.go:138: runtime.goexit
    sandbox.go:138: 	/usr/local/go/src/runtime/asm_amd64.s:1695

crazy-max avatar Oct 03 '24 14:10 crazy-max