Containerd logs

containerd.log

-- Logs begin at Sat 2020-02-01 04:17:19 CET. --
Mar 17 09:07:09 socle-mst-001 systemd[1]: Started containerd container runtime.
Mar 17 09:07:09 socle-mst-001 containerd[19663]: time="2020-03-17T09:07:09.726483498+01:00" level=info msg="starting containerd" revision=d76c121f76a5fc8a462dc64594aea72fe18e1178 version=v1.3.3
Mar 17 09:07:09 socle-mst-001 containerd[19663]: time="2020-03-17T09:07:09.726558024+01:00" level=debug msg="changing OOM score to -999"
Mar 17 09:07:09 socle-mst-001 containerd[19663]: time="2020-03-17T09:07:09.753412336+01:00" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1
Mar 17 09:07:09 socle-mst-001 containerd[19663]: time="2020-03-17T09:07:09.753740547+01:00" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1
Mar 17 09:07:09 socle-mst-001 containerd[19663]: time="2020-03-17T09:07:09.755099646+01:00" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1
Mar 17 09:07:09 socle-mst-001 containerd[19663]: time="2020-03-17T09:07:09.755225053+01:00" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1
Mar 17 09:07:09 socle-mst-001 containerd[19663]: time="2020-03-17T09:07:09.755294084+01:00" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured"
Mar 17 09:07:09 socle-mst-001 containerd[19663]: time="2020-03-17T09:07:09.755358568+01:00" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1
Mar 17 09:07:09 socle-mst-001 containerd[19663]: time="2020-03-17T09:07:09.757276547+01:00" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="modprobe aufs failed: \"modprobe: FATAL: Module aufs not found in directory /lib/modules/4.19.68-coreos\\n\": exit status 1: skip plugin" type=io.containerd.snapshotter.v1
Mar 17 09:07:09 socle-mst-001 containerd[19663]: time="2020-03-17T09:07:09.757411596+01:00" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1
Mar 17 09:07:09 socle-mst-001 containerd[19663]: time="2020-03-17T09:07:09.757517901+01:00" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1
Mar 17 09:07:09 socle-mst-001 containerd[19663]: time="2020-03-17T09:07:09.757684681+01:00" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1
Mar 17 09:07:09 socle-mst-001 containerd[19663]: time="2020-03-17T09:07:09.758183063+01:00" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1
Mar 17 09:07:09 socle-mst-001 containerd[19663]: time="2020-03-17T09:07:09.758264285+01:00" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1
Mar 17 09:07:09 socle-mst-001 containerd[19663]: time="2020-03-17T09:07:09.758362669+01:00" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured"
Mar 17 09:07:09 socle-mst-001 containerd[19663]: time="2020-03-17T09:07:09.758485183+01:00" level=info msg="metadata content store policy set" policy=shared
Mar 17 09:07:09 socle-mst-001 containerd[19663]: time="2020-03-17T09:07:09.758778916+01:00" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1
Mar 17 09:07:09 socle-mst-001 containerd[19663]: time="2020-03-17T09:07:09.758871032+01:00" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1
Mar 17 09:07:09 socle-mst-001 containerd[19663]: time="2020-03-17T09:07:09.758950801+01:00" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1
Mar 17 09:07:09 socle-mst-001 containerd[19663]: time="2020-03-17T09:07:09.759034090+01:00" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1
Mar 17 09:07:09 socle-mst-001 containerd[19663]: time="2020-03-17T09:07:09.759093883+01:00" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1
Mar 17 09:07:09 socle-mst-001 containerd[19663]: time="2020-03-17T09:07:09.759150132+01:00" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1
Mar 17 09:07:09 socle-mst-001 containerd[19663]: time="2020-03-17T09:07:09.759205940+01:00" level=info msg="loading plugin \"io.containerd.service.v1.leases-service\"..." type=io.containerd.service.v1
Mar 17 09:07:09 socle-mst-001 containerd[19663]: time="2020-03-17T09:07:09.759266885+01:00" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1
Mar 17 09:07:09 socle-mst-001 containerd[19663]: time="2020-03-17T09:07:09.759324370+01:00" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1
Mar 17 09:07:09 socle-mst-001 containerd[19663]: time="2020-03-17T09:07:09.759414254+01:00" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1
Mar 17 09:07:09 socle-mst-001 containerd[19663]: time="2020-03-17T09:07:09.759560508+01:00" level=debug msg="loading tasks in namespace" namespace=k8s.io
Mar 17 09:07:09 socle-mst-001 containerd[19663]: time="2020-03-17T09:07:09.768670500+01:00" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2
Mar 17 09:07:09 socle-mst-001 containerd[19663]: time="2020-03-17T09:07:09.768858662+01:00" level=debug msg="loading tasks in namespace" namespace=k8s.io
Mar 17 09:07:09 socle-mst-001 containerd[19663]: time="2020-03-17T09:07:09.775907084+01:00" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1
Mar 17 09:07:09 socle-mst-001 containerd[19663]: time="2020-03-17T09:07:09.776312312+01:00" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1
Mar 17 09:07:09 socle-mst-001 containerd[19663]: time="2020-03-17T09:07:09.776706193+01:00" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1
Mar 17 09:07:09 socle-mst-001 containerd[19663]: time="2020-03-17T09:07:09.776860153+01:00" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1
Mar 17 09:07:09 socle-mst-001 containerd[19663]: time="2020-03-17T09:07:09.776926004+01:00" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1
Mar 17 09:07:09 socle-mst-001 containerd[19663]: time="2020-03-17T09:07:09.776983941+01:00" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1
Mar 17 09:07:09 socle-mst-001 containerd[19663]: time="2020-03-17T09:07:09.777041119+01:00" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1
Mar 17 09:07:09 socle-mst-001 containerd[19663]: time="2020-03-17T09:07:09.777097811+01:00" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1
Mar 17 09:07:09 socle-mst-001 containerd[19663]: time="2020-03-17T09:07:09.777155854+01:00" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1
Mar 17 09:07:09 socle-mst-001 containerd[19663]: time="2020-03-17T09:07:09.777213015+01:00" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1
Mar 17 09:07:09 socle-mst-001 containerd[19663]: time="2020-03-17T09:07:09.777275139+01:00" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1
Mar 17 09:07:09 socle-mst-001 containerd[19663]: time="2020-03-17T09:07:09.777330419+01:00" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1
Mar 17 09:07:09 socle-mst-001 containerd[19663]: time="2020-03-17T09:07:09.777400745+01:00" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1
Mar 17 09:07:09 socle-mst-001 containerd[19663]: time="2020-03-17T09:07:09.777459609+01:00" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1
Mar 17 09:07:09 socle-mst-001 containerd[19663]: time="2020-03-17T09:07:09.777534642+01:00" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1
Mar 17 09:07:09 socle-mst-001 containerd[19663]: time="2020-03-17T09:07:09.777596890+01:00" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1
Mar 17 09:07:09 socle-mst-001 containerd[19663]: time="2020-03-17T09:07:09.777857357+01:00" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type: Engine: PodAnnotations:[] Root: Options:<nil> PrivilegedWithoutHostDevices:false} UntrustedWorkloadRuntime:{Type: Engine: PodAnnotations:[] Root: Options:<nil> PrivilegedWithoutHostDevices:false} Runtimes:map[runc:{Type:io.containerd.runc.v1 Engine: PodAnnotations:[] Root: Options:<nil> PrivilegedWithoutHostDevices:false}] NoPivot:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginConfTemplate:} Registry:{Mirrors:map[docker.io:{Endpoints:[https://harbor.mutual.k8s.internal/v2/mirrors/docker.io https://registry.service.cs.internal:5000/v2/docker.io]} k8s.gcr.io:{Endpoints:[https://harbor.mutual.k8s.internal/v2/mirrors/k8s.gcr.io https://registry.service.cs.internal:5000/v2/k8s.gcr.io]} quay.io:{Endpoints:[https://harbor.mutual.k8s.internal/v2/mirrors/quay.io https://registry.service.cs.internal:5000/v2/quay.io]}] Configs:map[] Auths:map[]} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:false SandboxImage:registry.service.cs.internal:5000/k8s.gcr.io/pause:3.1 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false} ContainerdRootDir:/var/lib/containerd ContainerdEndpoint:/run/containerd/containerd.sock RootDir:/var/lib/containerd/io.containerd.grpc.v1.cri StateDir:/run/containerd/io.containerd.grpc.v1.cri}"
Mar 17 09:07:09 socle-mst-001 containerd[19663]: time="2020-03-17T09:07:09.778000386+01:00" level=info msg="Connect containerd service"
Mar 17 09:07:09 socle-mst-001 containerd[19663]: time="2020-03-17T09:07:09.778165628+01:00" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\""
Mar 17 09:07:09 socle-mst-001 containerd[19663]: time="2020-03-17T09:07:09.778509639+01:00" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1
Mar 17 09:07:09 socle-mst-001 containerd[19663]: time="2020-03-17T09:07:09.778835809+01:00" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc
Mar 17 09:07:09 socle-mst-001 containerd[19663]: time="2020-03-17T09:07:09.778995227+01:00" level=info msg=serving... address=/run/containerd/containerd.sock
Mar 17 09:07:09 socle-mst-001 containerd[19663]: time="2020-03-17T09:07:09.779066508+01:00" level=info msg="containerd successfully booted in 0.053337s"
Mar 17 09:07:09 socle-mst-001 containerd[19663]: time="2020-03-17T09:07:09.781464145+01:00" level=info msg="Start subscribing containerd event"
Mar 17 09:07:09 socle-mst-001 containerd[19663]: time="2020-03-17T09:07:09.781957399+01:00" level=info msg="Start recovering state"
Mar 17 09:07:10 socle-mst-001 containerd[19663]: time="2020-03-17T09:07:10.000422936+01:00" level=info msg="Start event monitor"
Mar 17 09:07:10 socle-mst-001 containerd[19663]: time="2020-03-17T09:07:10.000611377+01:00" level=info msg="Start snapshots syncer"
Mar 17 09:07:10 socle-mst-001 containerd[19663]: time="2020-03-17T09:07:10.000754053+01:00" level=info msg="Start streaming server"
Mar 17 09:07:12 socle-mst-001 containerd[19663]: time="2020-03-17T09:07:12.780337849+01:00" level=debug msg=ImageFsInfo
Mar 17 09:07:14 socle-mst-001 containerd[19663]: time="2020-03-17T09:07:14.842447879+01:00" level=info msg="PullImage \"k8s.gcr.io/pause:3.1\""
Mar 17 09:07:14 socle-mst-001 containerd[19663]: time="2020-03-17T09:07:14.843809095+01:00" level=debug msg=resolving host=harbor.mutual.k8s.internal
Mar 17 09:07:14 socle-mst-001 containerd[19663]: time="2020-03-17T09:07:14.843838446+01:00" level=debug msg="do request" host=harbor.mutual.k8s.internal 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=containerd/v1.3.3 request.method=HEAD url="https://harbor.mutual.k8s.internal/v2/mirrors/k8s.gcr.io/pause/manifests/3.1"
Mar 17 09:07:15 socle-mst-001 containerd[19663]: time="2020-03-17T09:07:15.001330212+01:00" level=debug msg="fetch response received" host=harbor.mutual.k8s.internal response.header.content-length=167 response.header.content-type="application/json; charset=utf-8" response.header.date="Tue, 17 Mar 2020 08:07:23 GMT" response.header.docker-distribution-api-version=registry/2.0 response.header.server=nginx/1.17.8 response.header.set-cookie="sid=dad582640d20bf9c6838967cb489cf40; Path=/; HttpOnly" response.header.set-cookie.1="_xsrf=V2E3bFh4VXZKN3FCWm1MTmEyZTJVcmtIaXNLSXFHcFg=|1584432443574792307|79cd810c59f0b3237985b1a496b00e080356e831760932bc8cdba2ff0926deeb; Expires=Tue, 17 Mar 2020 09:07:23 UTC; Max-Age=3600; Path=/" response.header.strict-transport-security="max-age=15724800; includeSubDomains" response.header.www-authenticate="Bearer realm=\"https://harbor.mutual.k8s.internal/service/token\",service=\"harbor-registry\",scope=\"repository:mirrors/k8s.gcr.io/pause:pull\"" response.status="401 Unauthorized" url="https://harbor.mutual.k8s.internal/v2/mirrors/k8s.gcr.io/pause/manifests/3.1"
Mar 17 09:07:15 socle-mst-001 containerd[19663]: time="2020-03-17T09:07:15.001640268+01:00" level=debug msg=Unauthorized header="Bearer realm=\"https://harbor.mutual.k8s.internal/service/token\",service=\"harbor-registry\",scope=\"repository:mirrors/k8s.gcr.io/pause:pull\"" host=harbor.mutual.k8s.internal
Mar 17 09:07:15 socle-mst-001 containerd[19663]: time="2020-03-17T09:07:15.001808458+01:00" level=debug msg="do request" host=harbor.mutual.k8s.internal 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=containerd/v1.3.3 request.method=HEAD url="https://harbor.mutual.k8s.internal/v2/mirrors/k8s.gcr.io/pause/manifests/3.1"
Mar 17 09:07:15 socle-mst-001 containerd[19663]: time="2020-03-17T09:07:15.025712822+01:00" level=error msg="PullImage \"k8s.gcr.io/pause:3.1\" failed" error="failed to pull and unpack image \"k8s.gcr.io/pause:3.1\": failed to resolve reference \"k8s.gcr.io/pause:3.1\": failed to authorize: failed to fetch anonymous token: unexpected status: 500 Internal Server Error"
Mar 17 09:07:15 socle-mst-001 containerd[19663]: time="2020-03-17T09:07:15.053142176+01:00" level=debug msg="garbage collected" d=2.433969ms