I execute docker run hello-world
in command prompt and post the added lines in dockerd.log and log.txt below.
C:\Users\lfthiang\AppData\Local\Docker\log\vm\dockerd.log
[2022-03-24T00:50:38.336864500Z][dockerd][I] time="2022-03-24T00:50:38.336724500Z" level=debug msg="Calling HEAD /_ping"
[2022-03-24T00:50:38.555511200Z][dockerd][I] time="2022-03-24T00:50:38.555380700Z" level=debug msg="Calling HEAD /_ping"
[2022-03-24T00:50:38.884708500Z][dockerd][I] time="2022-03-24T00:50:38.884532800Z" level=debug msg="Calling HEAD /_ping"
[2022-03-24T00:50:40.018507300Z][dockerd][I] time="2022-03-24T00:50:40.017853700Z" level=debug msg="Calling POST /v1.41/containers/create"
[2022-03-24T00:50:40.019918100Z][dockerd][I] time="2022-03-24T00:50:40.019251000Z" level=debug msg="form data: {\"AttachStderr\":true,\"AttachStdin\":false,\"AttachStdout\":true,\"Cmd\":null,\"Domainname\":\"\",\"Entrypoint\":null,\"Env\":null,\"HostConfig\":{\"AutoRemove\":false,\"Binds\":null,\"BlkioDeviceReadBps\":null,\"BlkioDeviceReadIOps\":null,\"BlkioDeviceWriteBps\":null,\"BlkioDeviceWriteIOps\":null,\"BlkioWeight\":0,\"BlkioWeightDevice\":[],\"CapAdd\":null,\"CapDrop\":null,\"Cgroup\":\"\",\"CgroupParent\":\"\",\"CgroupnsMode\":\"\",\"ConsoleSize\":[30,120],\"ContainerIDFile\":\"\",\"CpuCount\":0,\"CpuPercent\":0,\"CpuPeriod\":0,\"CpuQuota\":0,\"CpuRealtimePeriod\":0,\"CpuRealtimeRuntime\":0,\"CpuShares\":0,\"CpusetCpus\":\"\",\"CpusetMems\":\"\",\"DeviceCgroupRules\":null,\"DeviceRequests\":null,\"Devices\":[],\"Dns\":[],\"DnsOptions\":[],\"DnsSearch\":[],\"ExtraHosts\":null,\"GroupAdd\":null,\"IOMaximumBandwidth\":0,\"IOMaximumIOps\":0,\"IpcMode\":\"\",\"Isolation\":\"\",\"KernelMemory\":0,\"KernelMemoryTCP\":0,\"Links\":null,\"LogConfig\":{\
[2022-03-24T00:50:40.029806300Z][dockerd][I] time="2022-03-24T00:50:40.029520200Z" level=debug msg="Calling GET /v1.41/info"
[2022-03-24T00:50:42.673067700Z][dockerd][I] time="2022-03-24T00:50:42.672753300Z" level=debug msg="Calling POST /v1.41/images/create?fromImage=hello-world&tag=latest"
[2022-03-24T00:50:42.677528400Z][dockerd][I] time="2022-03-24T00:50:42.677283000Z" level=debug msg="Trying to pull hello-world from https://registry-1.docker.io v2"
[2022-03-24T00:50:42.698408400Z][dockerd][I] time="2022-03-24T00:50:42.698283200Z" level=warning msg="Error getting v2 registry: Get \"https://registry-1.docker.io/v2/\": Service Unavailable"
[2022-03-24T00:50:42.698454500Z][dockerd][I] time="2022-03-24T00:50:42.698321900Z" level=info msg="Attempting next endpoint for pull after error: Get \"https://registry-1.docker.io/v2/\": Service Unavailable"
[2022-03-24T00:50:42.700694600Z][dockerd][I] time="2022-03-24T00:50:42.700602100Z" level=error msg="Handler for POST /v1.41/images/create returned error: Get \"https://registry-1.docker.io/v2/\": Service Unavailable"
C:\Users\lfthiang\AppData\Local\Docker\log.txt
[2022-03-24T00:50:38.335336000Z][com.docker.proxy.exe][I] proxy >> HEAD /_ping
[2022-03-24T00:50:38.336971400Z][com.docker.proxy.exe][I] proxy << HEAD /_ping (1.7194ms)
[2022-03-24T00:50:38.553995400Z][com.docker.proxy.exe][I] proxy >> HEAD /_ping
[2022-03-24T00:50:38.555650800Z][com.docker.proxy.exe][I] proxy << HEAD /_ping (1.6554ms)
[2022-03-24T00:50:38.883178900Z][com.docker.proxy.exe][I] proxy >> HEAD /_ping
[2022-03-24T00:50:38.884752000Z][com.docker.proxy.exe][I] proxy << HEAD /_ping (1.5731ms)
[2022-03-24T00:50:38.899139500Z][com.docker.proxy.exe][I] (e98b9138) de1fa007-ProxyCMD C->S CSharpAPI POST /usage: {"command":"containerCreateCliLinux","count":1}
[2022-03-24T00:50:40.007126900Z][IPCServer ][Info ] (e98b9138) bc22ca38-CSharpAPI S<-C de1fa007-ProxyCMD POST /usage
github.com/docker/pinata/common/pkg/gui.(*guiClient).Usage(0xc0002a64e0, 0x174a85e)
C:/workspaces/PR-17529/src/github.com/docker/pinata/common/pkg/gui/client.go:129 +0x44
github.com/docker/pinata/common/pkg/proxy.(*GuiAnalytics).sendCommand(0xc000112b80, {0x174a85e, 0xf}, {0x1740090, 0x3}, 0x1)
C:/workspaces/PR-17529/src/github.com/docker/pinata/common/pkg/proxy/analytics.go:104 +0xe9
github.com/docker/pinata/common/pkg/proxy.(*GuiAnalytics).trackRouteForHTTPMethod(0x1856080, 0xc000454300, 0x160dc20, 0x1782700)
C:/workspaces/PR-17529/src/github.com/docker/pinata/common/pkg/proxy/analytics.go:97 +0xf4
github.com/docker/pinata/common/pkg/proxy.(*GuiAnalytics).Middleware.func1({0x1850cd0, 0xc00047c1c0}, 0xc000454300)
C:/workspaces/PR-17529/src/github.com/docker/pinata/common/pkg/proxy/analytics.go:59 +0x8d
github.com/gorilla/mux.(*Router).ServeHTTP(0xc0003940c0, {0x1850cd0, 0xc00047c1c0}, 0xc000454100)
C:/workspaces/PR-17529/src/github.com/docker/pinata/vendor/github.com/gorilla/mux/mux.go:210 +0x1cf
[2022-03-24T00:50:40.009301200Z][IPCServer ][Info ] (e98b9138) bc22ca38-CSharpAPI S->C de1fa007-ProxyCMD POST /usage (1ms): OK
[2022-03-24T00:50:40.012849700Z][com.docker.proxy.exe][I] (e98b9138) de1fa007-ProxyCMD C<-S bc22ca38-CSharpAPI POST /usage (1.1137102s): OK
[2022-03-24T00:50:40.013377300Z][com.docker.proxy.exe][I] usage containerCreateCliLinux + 1
[2022-03-24T00:50:40.013525100Z][com.docker.proxy.exe][I] proxy >> POST /v1.41/containers/create
[2022-03-24T00:50:40.025296300Z][com.docker.proxy.exe][I] proxy << POST /v1.41/containers/create (11.7712ms)
[2022-03-24T00:50:40.027178700Z][com.docker.proxy.exe][I] proxy >> GET /v1.41/info
[2022-03-24T00:50:40.042466700Z][com.docker.proxy.exe][I] proxy << GET /v1.41/info (15.288ms)
[2022-03-24T00:50:42.605365400Z][com.docker.proxy.exe][I] (11d94266) de1fa007-ProxyCMD C->S CSharpAPI POST /usage: {"command":"imagePullCliLinux","count":1}
[2022-03-24T00:50:42.665620600Z][IPCServer ][Info ] (11d94266) bc22ca38-CSharpAPI S<-C de1fa007-ProxyCMD POST /usage
github.com/docker/pinata/common/pkg/gui.(*guiClient).Usage(0xc00000cae0, 0x1745af0)
C:/workspaces/PR-17529/src/github.com/docker/pinata/common/pkg/gui/client.go:129 +0x44
github.com/docker/pinata/common/pkg/proxy.(*GuiAnalytics).sendCommand(0xc000112b80, {0x1745af0, 0x9}, {0x1740090, 0x3}, 0x1)
C:/workspaces/PR-17529/src/github.com/docker/pinata/common/pkg/proxy/analytics.go:104 +0xe9
github.com/docker/pinata/common/pkg/proxy.(*GuiAnalytics).trackRouteForHTTPMethod(0x1856080, 0xc000510500, 0x160dc20, 0x1782700)
C:/workspaces/PR-17529/src/github.com/docker/pinata/common/pkg/proxy/analytics.go:97 +0xf4
github.com/docker/pinata/common/pkg/proxy.(*GuiAnalytics).Middleware.func1({0x1850cd0, 0xc0005e61c0}, 0xc000510500)
C:/workspaces/PR-17529/src/github.com/docker/pinata/common/pkg/proxy/analytics.go:59 +0x8d
github.com/gorilla/mux.(*Router).ServeHTTP(0xc0003940c0, {0x1850cd0, 0xc0005e61c0}, 0xc000510300)
C:/workspaces/PR-17529/src/github.com/docker/pinata/vendor/github.com/gorilla/mux/mux.go:210 +0x1cf
[2022-03-24T00:50:42.666434300Z][IPCServer ][Info ] (11d94266) bc22ca38-CSharpAPI S->C de1fa007-ProxyCMD POST /usage (0ms): OK
[2022-03-24T00:50:42.668518300Z][com.docker.proxy.exe][I] (11d94266) de1fa007-ProxyCMD C<-S bc22ca38-CSharpAPI POST /usage (63.1529ms): OK
[2022-03-24T00:50:42.668518300Z][com.docker.proxy.exe][I] usage imagePullCliLinux + 1
[2022-03-24T00:50:42.668518300Z][com.docker.proxy.exe][I] proxy >> POST /v1.41/images/create?fromImage=hello-world&tag=latest
[2022-03-24T00:50:42.668518300Z][com.docker.proxy.exe][I] (7a639904) e7f3db32-ProxyPKG C->S BackendAPI GET /registry/access
[2022-03-24T00:50:42.669749300Z][com.docker.backend.exe][I] (7a639904) caad92f8-BackendAPI S<-C e7f3db32-ProxyPKG GET /registry/access
[2022-03-24T00:50:42.670262700Z][GoBackendProcess ][Info ] [common/pkg/backend.(*backendClient).GetAccess(0xc0001003c0)
[2022-03-24T00:50:42.670426700Z][GoBackendProcess ][Info ] [ common/pkg/backend/client.go:258 +0x62
[2022-03-24T00:50:42.670522500Z][GoBackendProcess ][Info ] [common/pkg/proxy.(*ramPullApprover).Approve(0x23e753c1a98, {{0x17459be, 0x9}, {0x0, 0x0}, {0x1743be6, 0x7}, {0xc0001201b4, 0xb}, {0xc0001201c4, ...}})
[2022-03-24T00:50:42.670572500Z][GoBackendProcess ][Info ] [ common/pkg/proxy/pull.go:34 +0x25
[2022-03-24T00:50:42.670602700Z][GoBackendProcess ][Info ] [common/pkg/proxy.(*proxyPullRewriter).RequestRewrite(0xc0003541d0, 0xc000510500)
[2022-03-24T00:50:42.670602700Z][GoBackendProcess ][Info ] [ common/pkg/proxy/pull.go:61 +0xf5
[2022-03-24T00:50:42.670602700Z][GoBackendProcess ][Info ] [common/pkg/proxy.(*passthrough).doHandleHTTP(0xc0001011d0, {0x23e7548c298, 0xc0006824a0}, {0x1850cd0, 0xc0005e61c0}, 0x2, {0xc00026f510, 0x14d62e6, 0x1db9a80})
[2022-03-24T00:50:42.670602700Z][GoBackendProcess ][Info ] [ common/pkg/proxy/passthrough.go:181 +0xfc
[2022-03-24T00:50:42.670602700Z][GoBackendProcess ][Info ] [common/pkg/proxy.(*passthrough).HandleHTTP(0xc0001011d0, {0x1850cd0, 0xc0005e61c0}, 0xc000510500)
[2022-03-24T00:50:42.670602700Z][GoBackendProcess ][Info ] [ common/pkg/proxy/passthrough.go:116 +0x41e
[2022-03-24T00:50:42.670602700Z][GoBackendProcess ][Info ] [common/pkg/proxy.withError.func1({0x1850cd0, 0xc0005e61c0}, 0xc00026f590)
[2022-03-24T00:50:42.670602700Z][GoBackendProcess ][Info ] [ common/pkg/proxy/routes.go:16 +0x3c
[2022-03-24T00:50:42.670602700Z][GoBackendProcess ][Info ] [common/pkg/proxy.NewRouter.func2.1({0x1850cd0, 0xc0005e61c0}, 0xc000510500)
[2022-03-24T00:50:42.670602700Z][GoBackendProcess ][Info ] [ common/pkg/proxy/proxy.go:107 +0x2f4
[2022-03-24T00:50:42.670602700Z][GoBackendProcess ][Info ] [common/pkg/proxy.NewRouter.func1.1({0x1850cd0, 0xc0005e61c0}, 0xc000510500)
[2022-03-24T00:50:42.670602700Z][GoBackendProcess ][Info ] [ common/pkg/proxy/proxy.go:76 +0x142
[2022-03-24T00:50:42.670602700Z][GoBackendProcess ][Info ] [common/pkg/proxy.(*restrictions).Middleware.func1({0x1850cd0, 0xc0005e61c0}, 0xc000510500)
[2022-03-24T00:50:42.671112400Z][GoBackendProcess ][Info ] [ common/pkg/proxy/restrictions.go:75 +0x154
[2022-03-24T00:50:42.671159900Z][GoBackendProcess ][Info ] [common/pkg/proxy.(*GuiAnalytics).Middleware.func1({0x1850cd0, 0xc0005e61c0}, 0xc000510500)
[2022-03-24T00:50:42.671159900Z][GoBackendProcess ][Info ] [ common/pkg/proxy/analytics.go:61 +0xac
[2022-03-24T00:50:42.671159900Z][GoBackendProcess ][Info ] [github.com/gorilla/mux.(*Router).ServeHTTP(0xc0003940c0, {0x1850cd0, 0xc0005e61c0}, 0xc000510300)
[2022-03-24T00:50:42.671159900Z][GoBackendProcess ][Info ] [ vendor/github.com/gorilla/mux/mux.go:210 +0x1cf
[2022-03-24T00:50:42.670602700Z][com.docker.backend.exe][I] (7a639904) caad92f8-BackendAPI S->C e7f3db32-ProxyPKG GET /registry/access (1.2681ms): {"allowed":[{"DNS":"","IP":{"IP":"0.0.0.0","Mask":"AAAAAA=="},"Type":1,"value":"0.0.0.0/0"},{"DNS":".","IP":{"IP":"","Mask":null},"Type":3,"value":"."}],"enabled":false,"refresh_after":"2022-03-24T20:50:42.6702627+08:00","refresh_grace_period_ends":"2022-03-25T08:50:42.6702627+08:00","refresh_grace_period_interval":3600000000000}
[2022-03-24T00:50:42.671159900Z][com.docker.proxy.exe][I] (7a639904) e7f3db32-ProxyPKG C<-S caad92f8-BackendAPI GET /registry/access (2.6416ms): {"allowed":[{"DNS":"","IP":{"IP":"0.0.0.0","Mask":"AAAAAA=="},"Type":1,"value":"0.0.0.0/0"},{"DNS":".","IP":{"IP":"","Mask":null},"Type":3,"value":"."}],"enabled":false,"refresh_after":"2022-03-24T20:50:42.6702627+08:00","refresh_grace_period_ends":"2022-03-25T08:50:42.6702627+08:00","refresh_grace_period_interval":2884905626637435000}
time="2022-03-24T00:50:42.683246374Z" level=info msg="ethernet: Connected Ethernet interface f6:16:36:bc:f9:c6"
time="2022-03-24T00:50:42.683246374Z" level=info msg="udp: UDP interface connected on 192.168.65.1"
time="2022-03-24T00:50:42.696243047Z" level=info msg="http: HTTP proxy --> 52.202.132.224:443 Host:registry-1.docker.io:443 (Origin): CONNECT "
time="2022-03-24T00:50:42.696243047Z" level=error msg="http: HTTP proxy --> 52.202.132.224:443 Host:registry-1.docker.io:443 (Origin): Failed to connect to 52.202.132.224:443"
time="2022-03-24T00:50:42.696243047Z" level=info msg="http: HTTP proxy: returning 503 Service_unavailable"
[2022-03-24T00:50:42.700892600Z][com.docker.proxy.exe][I] proxy << POST /v1.41/images/create?fromImage=hello-world&tag=latest (32.3743ms)
[2022-03-24T00:50:42.713013000Z][com.docker.backend.exe][I] (a5440789) 48957ae2-CLIAPI S<-C Go-http-client/1.1 POST /usage
[2022-03-24T00:50:42.713013000Z][com.docker.backend.exe][I] (a5440789) 48957ae2-CLIAPI S<-C Go-http-client/1.1 bind: {"command":"run","context":"moby","source":"cli","status":"failure"}
[2022-03-24T00:50:42.713013000Z][com.docker.backend.exe][I] (a5440789-1) 24450b8e-BackendCMD C->S CSharpAPI POST /usage/cli: {"command":"run","context":"moby","source":"cli","status":"failure"}
[2022-03-24T00:50:42.736442600Z][IPCServer ][Info ] (a5440789-1) bc22ca38-CSharpAPI S<-C 24450b8e-BackendCMD POST /usage/cli
github.com/docker/pinata/common/pkg/gui.(*guiClient).CliUsage(0xc0006a2540, 0x2504a20)
C:/workspaces/PR-17529/src/github.com/docker/pinata/common/pkg/gui/client.go:147 +0x44
github.com/docker/pinata/common/cmd/com.docker.backend/internal/server.appendCliRoutes.func2({0x2504a20, 0xc0006a2540})
C:/workspaces/PR-17529/src/github.com/docker/pinata/common/cmd/com.docker.backend/internal/server/routes.go:170 +0xb4
[2022-03-24T00:50:42.738726100Z][IPCServer ][Info ] (a5440789-1) bc22ca38-CSharpAPI S->C 24450b8e-BackendCMD POST /usage/cli (2ms): OK
[2022-03-24T00:50:42.739295200Z][com.docker.backend.exe][I] (a5440789-1) 24450b8e-BackendCMD C<-S bc22ca38-CSharpAPI POST /usage/cli (26.2822ms): OK
[2022-03-24T00:50:42.739295200Z][com.docker.backend.exe][I] (a5440789) 48957ae2-CLIAPI S->C Go-http-client/1.1 POST /usage (26.2822ms): OK
Also note that if I switch to Windows containers, I am able to execute docker run hello-world
in command prompt successfully without errors. The error occurs when I am using Linux containers,
It is very puzzling to me. I appreciate any help or advice. Thanks in advance.