Actions-runner-controller: Runner restarts every hour
Hi, the github runners i have setup seem to be restarting every hour for no particular reason, they are not using used, seems like every hour or so they just restart.
The first question i want to ask is if this is expected behavior or a bug?
kaykhancheckpoint
All 14 comments
Registration token lifetime is 1 hour, and code check if runner is still registreable (https://github.com/summerwind/actions-runner-controller/blob/master/controllers/runner_controller.go#L123).
That step is not necessarily needed, as runner stays online (I think) even the token is expired.
@mumoshu / @summerwind is there something I'm missing there? I think there's no reason to refresh that token once per hour, only (always) just before creating new pod (or refreshing the old one)
Hi-Fi
on 29 Oct 2020
@kaykhancheckpoint At least this is expected behavior, but I doubt if it's necessary reading @Hi-Fi's comment.
That step is not necessarily needed, as runner stays online (I think) even the token is expired.
@Hi-Fi I have never confirmed that myself but that might be true!
mumoshu
on 5 Nov 2020
@kaykhancheckpoint Would you mind giving #155 a try? If possible, NAME=yourdockeruser/actions-runner-controller make docker-build docker-push would give you the image to test.
mumoshu
on 5 Nov 2020
Hi, I'm running the latest docker image summerwind/actions-runner:v2.274.1 and v0.12.0,
however i can still see the runners are restating often
kaykhancheckpoint
on 17 Nov 2020
@kaykhancheckpoint Each runner is configured to restart after each job run, to provide you a consistent build environment. Perhaps that's what you're seeing?
mumoshu
on 18 Nov 2020
@mumoshu Im aware they are restarted after each job, but what im seeing is that they are being recreated every hour regardless if a job has been completed or not.
Im made aware of this because i have a k8 slack bot which is posting messages to a slack channel and i can see these runners being created every hour.
kaykhancheckpoint
on 18 Nov 2020
Hmm... could be be so that each Update(ctx... call causes the restart? And I really mean restart, as thing that happens after run has completed is actually deletion of pod and recretion of it with same name.
Hi-Fi
on 18 Nov 2020
@Hi-Fi @mumoshu
Just to clarify my above point, im 100% certain these self-hosted-runners were not used. We use this wonderful project to run self-hoisted-runners for a single repo and that repo had no pipeline/github action run.
Im logging the pods to elasticsearch, ive just pulled down some logs during the time i recieved a message informing me that the github runners were deleted and created.
github-runner-2dflt-lq4n9, github-runner-2dflt-45vlj, github-runner-2dflt-45vlj
2020-11-18T14:17:46.603Z INFO controllers.Runner Deleted runner pod {"runner": "actions-runner-system/github-runner-2dflt-lvqvb", "repository": ""}
2020-11-18T14:17:46.440Z INFO controllers.Runner Deleted runner pod {"runner": "actions-runner-system/github-runner-2dflt-45vlj", "repository": ""}
2020-11-18T14:17:46.273Z INFO controllers.Runner Deleted runner pod {"runner": "actions-runner-system/github-runner-2dflt-lq4n9", "repository": ""}
logs kubernetes.namespace : "actions-runner-system"
Nov 18, 2020 @ 14:17:58.853time="2020-11-18T14:17:58.853525436Z" level=info msg="API listen on [::]:2375"
Nov 18, 2020 @ 14:17:58.853time="2020-11-18T14:17:58.853834176Z" level=info msg="API listen on /var/run/docker.sock"
Nov 18, 2020 @ 14:17:58.789time="2020-11-18T14:17:58.789088990Z" level=info msg="Daemon has completed initialization"
Nov 18, 2020 @ 14:17:58.788time="2020-11-18T14:17:58.788584934Z" level=info msg="Docker daemon" commit=4484c46 graphdriver(s)=overlay2 version=19.03.13
Nov 18, 2020 @ 14:17:58.742time="2020-11-18T14:17:58.742750128Z" level=info msg="Loading containers: done."
Nov 18, 2020 @ 14:17:58.712time="2020-11-18T14:17:58.712044265Z" level=info msg="API listen on [::]:2375"
Nov 18, 2020 @ 14:17:58.712time="2020-11-18T14:17:58.712129925Z" level=info msg="API listen on /var/run/docker.sock"
Nov 18, 2020 @ 14:17:58.622time="2020-11-18T14:17:58.622173948Z" level=info msg="Daemon has completed initialization"
Nov 18, 2020 @ 14:17:58.621time="2020-11-18T14:17:58.621502691Z" level=info msg="Docker daemon" commit=4484c46 graphdriver(s)=overlay2 version=19.03.13
Nov 18, 2020 @ 14:17:58.604time="2020-11-18T14:17:58.604312027Z" level=info msg="Default bridge (docker0) is assigned with an IP address 172.17.0.0/16. Daemon option --bip can be used to set a preferred IP address"
Nov 18, 2020 @ 14:17:58.572time="2020-11-18T14:17:58.572366643Z" level=info msg="Loading containers: done."
Nov 18, 2020 @ 14:17:58.535time="2020-11-18T14:17:58.535781163Z" level=info msg="API listen on [::]:2375"
Nov 18, 2020 @ 14:17:58.535time="2020-11-18T14:17:58.535873115Z" level=info msg="API listen on /var/run/docker.sock"
Nov 18, 2020 @ 14:17:58.514time="2020-11-18T14:17:58.514555165Z" level=warning msg="Running modprobe bridge br_netfilter failed with message: ip: can't find device 'bridge'\nbridge 135168 1 br_netfilter\nstp 16384 1 bridge\nllc 16384 2 bridge,stp\nip: can't find device 'br_netfilter'\nbr_netfilter 24576 0 \nbridge 135168 1 br_netfilter\nmodprobe: can't change directory to '/lib/modules': No such file or directory\n, error: exit status 1"
Nov 18, 2020 @ 14:17:58.5002020-11-18T14:17:58.499Z DEBUG controller-runtime.controller Successfully Reconciled {"controller": "runner", "request": "actions-runner-system/github-runner-2dflt-lvqvb"}
Nov 18, 2020 @ 14:17:58.476time="2020-11-18T14:17:58.476252196Z" level=info msg="Loading containers: start."
Nov 18, 2020 @ 14:17:58.475time="2020-11-18T14:17:58.475726242Z" level=warning msg="Your kernel does not support cgroup rt period"
Nov 18, 2020 @ 14:17:58.475time="2020-11-18T14:17:58.475901348Z" level=warning msg="Your kernel does not support cgroup rt runtime"
Nov 18, 2020 @ 14:17:58.466time="2020-11-18T14:17:58.466693838Z" level=warning msg="Your kernel does not support swap memory limit"
Nov 18, 2020 @ 14:17:58.419time="2020-11-18T14:17:58.419753706Z" level=info msg="Daemon has completed initialization"
Nov 18, 2020 @ 14:17:58.389time="2020-11-18T14:17:58.389141226Z" level=info msg="Docker daemon" commit=4484c46 graphdriver(s)=overlay2 version=19.03.13
Nov 18, 2020 @ 14:17:58.354time="2020-11-18T14:17:58.354741852Z" level=info msg="Loading containers: done."
Nov 18, 2020 @ 14:17:58.3502020-11-18T14:17:58.349Z DEBUG controller-runtime.controller Successfully Reconciled {"controller": "runnerreplicaset", "request": "actions-runner-system/github-runner-2dflt"}
Nov 18, 2020 @ 14:17:58.3502020-11-18T14:17:58.350Z INFO controllers.RunnerReplicaSet debug {"runner": "actions-runner-system/github-runner-2dflt", "desired": 3, "available": 3}
Nov 18, 2020 @ 14:17:58.3502020-11-18T14:17:58.350Z DEBUG controller-runtime.controller Successfully Reconciled {"controller": "runnerreplicaset", "request": "actions-runner-system/github-runner-2dflt"}
Nov 18, 2020 @ 14:17:58.350time="2020-11-18T14:17:58.350172274Z" level=info msg="scheme \"unix\" not registered, fallback to default scheme" module=grpc
Nov 18, 2020 @ 14:17:58.350time="2020-11-18T14:17:58.350253963Z" level=info msg="ccResolverWrapper: sending update to cc: {[{unix:///var/run/docker/containerd/containerd.sock 0 <nil>}] <nil>}" module=grpc
Nov 18, 2020 @ 14:17:58.350time="2020-11-18T14:17:58.350320483Z" level=info msg="ClientConn switching balancer to \"pick_first\"" module=grpc
Nov 18, 2020 @ 14:17:58.3492020-11-18T14:17:58.349Z DEBUG controller-runtime.controller Successfully Reconciled {"controller": "runnerdeployment", "request": "actions-runner-system/github-runner"}
Nov 18, 2020 @ 14:17:58.348time="2020-11-18T14:17:58.348769859Z" level=info msg="parsed scheme: \"unix\"" module=grpc
Nov 18, 2020 @ 14:17:58.3452020-11-18T14:17:58.345Z DEBUG controller-runtime.controller Successfully Reconciled {"controller": "runner", "request": "actions-runner-system/github-runner-2dflt-lvqvb"}
Nov 18, 2020 @ 14:17:58.3442020-11-18T14:17:58.344Z INFO controllers.RunnerReplicaSet debug {"runner": "actions-runner-system/github-runner-2dflt", "desired": 3, "available": 3}
Nov 18, 2020 @ 14:17:58.337time="2020-11-18T14:17:58.337217321Z" level=info msg="parsed scheme: \"unix\"" module=grpc
Nov 18, 2020 @ 14:17:58.337time="2020-11-18T14:17:58.337559438Z" level=info msg="scheme \"unix\" not registered, fallback to default scheme" module=grpc
Nov 18, 2020 @ 14:17:58.337time="2020-11-18T14:17:58.337639042Z" level=info msg="ccResolverWrapper: sending update to cc: {[{unix:///var/run/docker/containerd/containerd.sock 0 <nil>}] <nil>}" module=grpc
Nov 18, 2020 @ 14:17:58.337time="2020-11-18T14:17:58.337678093Z" level=info msg="ClientConn switching balancer to \"pick_first\"" module=grpc
Nov 18, 2020 @ 14:17:58.313time="2020-11-18T14:17:58.313148482Z" level=info msg="Default bridge (docker0) is assigned with an IP address 172.17.0.0/16. Daemon option --bip can be used to set a preferred IP address"
Nov 18, 2020 @ 14:17:58.299time="2020-11-18T14:17:58.299183121Z" level=info msg=serving... address=/var/run/docker/containerd/containerd-debug.sock
Nov 18, 2020 @ 14:17:58.299time="2020-11-18T14:17:58.299384214Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock.ttrpc
Nov 18, 2020 @ 14:17:58.299time="2020-11-18T14:17:58.299567482Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock
Nov 18, 2020 @ 14:17:58.299time="2020-11-18T14:17:58.299686401Z" level=info msg="containerd successfully booted in 0.471776s"
Nov 18, 2020 @ 14:17:58.298time="2020-11-18T14:17:58.298259880Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1
Nov 18, 2020 @ 14:17:58.298time="2020-11-18T14:17:58.298412318Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1
Nov 18, 2020 @ 14:17:58.298time="2020-11-18T14:17:58.298519217Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1
Nov 18, 2020 @ 14:17:58.298time="2020-11-18T14:17:58.298646682Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1
Nov 18, 2020 @ 14:17:58.294time="2020-11-18T14:17:58.293990513Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1
Nov 18, 2020 @ 14:17:58.294time="2020-11-18T14:17:58.294086648Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1
Nov 18, 2020 @ 14:17:58.294time="2020-11-18T14:17:58.294216263Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1
Nov 18, 2020 @ 14:17:58.294time="2020-11-18T14:17:58.294310061Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1
Nov 18, 2020 @ 14:17:58.293time="2020-11-18T14:17:58.293371577Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1
Nov 18, 2020 @ 14:17:58.293time="2020-11-18T14:17:58.293524774Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1
Nov 18, 2020 @ 14:17:58.293time="2020-11-18T14:17:58.293660763Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1
Nov 18, 2020 @ 14:17:58.293time="2020-11-18T14:17:58.293760843Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1
Nov 18, 2020 @ 14:17:58.293time="2020-11-18T14:17:58.293886533Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1
Nov 18, 2020 @ 14:17:58.288time="2020-11-18T14:17:58.288774903Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1
Nov 18, 2020 @ 14:17:58.272time="2020-11-18T14:17:58.272060204Z" level=info msg="Default bridge (docker0) is assigned with an IP address 172.17.0.0/16. Daemon option --bip can be used to set a preferred IP address"
Nov 18, 2020 @ 14:17:58.254time="2020-11-18T14:17:58.254331222Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1
Nov 18, 2020 @ 14:17:58.232time="2020-11-18T14:17:58.232566966Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1
Nov 18, 2020 @ 14:17:58.228time="2020-11-18T14:17:58.228229067Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2
Nov 18, 2020 @ 14:17:58.227time="2020-11-18T14:17:58.226942104Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1
Nov 18, 2020 @ 14:17:58.227time="2020-11-18T14:17:58.227905951Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1
Nov 18, 2020 @ 14:17:58.227
Nov 18, 2020 @ 14:17:58.227# Authentication
Nov 18, 2020 @ 14:17:58.227
Nov 18, 2020 @ 14:17:58.226time="2020-11-18T14:17:58.225977912Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1
Nov 18, 2020 @ 14:17:58.226time="2020-11-18T14:17:58.226119489Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1
Nov 18, 2020 @ 14:17:58.226time="2020-11-18T14:17:58.226453246Z" level=info msg="loading plugin \"io.containerd.service.v1.leases-service\"..." type=io.containerd.service.v1
Nov 18, 2020 @ 14:17:58.226time="2020-11-18T14:17:58.226761375Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1
Nov 18, 2020 @ 14:17:58.226| Self-hosted runner registration |
Nov 18, 2020 @ 14:17:58.226| |
Nov 18, 2020 @ 14:17:58.226--------------------------------------------------------------------------------
Nov 18, 2020 @ 14:17:58.225time="2020-11-18T14:17:58.224945415Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1
Nov 18, 2020 @ 14:17:58.225time="2020-11-18T14:17:58.225213983Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1
Nov 18, 2020 @ 14:17:58.225time="2020-11-18T14:17:58.225696940Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1
Nov 18, 2020 @ 14:17:58.225| | | _| | __| |_| | | | | '_ \ / _ \ / __| __| |/ _ \| '_ \/ __| |
Nov 18, 2020 @ 14:17:58.225| | |_| | | |_| _ | |_| | |_) | / ___ \ (__| |_| | (_) | | | \__ \ |
Nov 18, 2020 @ 14:17:58.225| \____|_|\__|_| |_|\__,_|_.__/ /_/ \_\___|\__|_|\___/|_| |_|___/ |
Nov 18, 2020 @ 14:17:58.225| |
Nov 18, 2020 @ 14:17:58.224time="2020-11-18T14:17:58.224667138Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1
Nov 18, 2020 @ 14:17:58.224--------------------------------------------------------------------------------
Nov 18, 2020 @ 14:17:58.224| ____ _ _ _ _ _ _ _ _ |
Nov 18, 2020 @ 14:17:58.224| / ___(_) |_| | | |_ _| |__ / \ ___| |_(_) ___ _ __ ___ |
Nov 18, 2020 @ 14:17:58.218
Nov 18, 2020 @ 14:17:58.192time="2020-11-18T14:17:58.191965779Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1
Nov 18, 2020 @ 14:17:58.192time="2020-11-18T14:17:58.192228688Z" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured"
Nov 18, 2020 @ 14:17:58.192time="2020-11-18T14:17:58.192334121Z" level=info msg="metadata content store policy set" policy=shared
Nov 18, 2020 @ 14:17:58.191time="2020-11-18T14:17:58.191069081Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1
Nov 18, 2020 @ 14:17:58.191time="2020-11-18T14:17:58.191818556Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/docker/containerd/daemon/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1
Nov 18, 2020 @ 14:17:58.190time="2020-11-18T14:17:58.190206119Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="modprobe aufs failed: \"ip: can't find device 'aufs'\\nmodprobe: can't change directory to '/lib/modules': No such file or directory\\n\": exit status 1: skip plugin" type=io.containerd.snapshotter.v1
Nov 18, 2020 @ 14:17:58.190time="2020-11-18T14:17:58.190398532Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1
Nov 18, 2020 @ 14:17:58.190time="2020-11-18T14:17:58.190671291Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1
Nov 18, 2020 @ 14:17:58.149time="2020-11-18T14:17:58.149187487Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." error="path /var/lib/docker/containerd/daemon/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
Nov 18, 2020 @ 14:17:58.149time="2020-11-18T14:17:58.149460426Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1
Nov 18, 2020 @ 14:17:58.149time="2020-11-18T14:17:58.149602274Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured"
Nov 18, 2020 @ 14:17:58.149time="2020-11-18T14:17:58.149677382Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1
Nov 18, 2020 @ 14:17:58.146time="2020-11-18T14:17:58.145892837Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1
Nov 18, 2020 @ 14:17:58.144time="2020-11-18T14:17:58.144378581Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1
Nov 18, 2020 @ 14:17:57.975time="2020-11-18T14:17:57.975787014Z" level=warning msg="Running modprobe bridge br_netfilter failed with message: ip: can't find device 'bridge'\nbridge 135168 1 br_netfilter\nstp 16384 1 bridge\nllc 16384 2 bridge,stp\nip: can't find device 'br_netfilter'\nbr_netfilter 24576 0 \nbridge 135168 1 br_netfilter\nmodprobe: can't change directory to '/lib/modules': No such file or directory\n, error: exit status 1"
Nov 18, 2020 @ 14:17:57.923time="2020-11-18T14:17:57.923040377Z" level=warning msg="Your kernel does not support cgroup rt period"
Nov 18, 2020 @ 14:17:57.923time="2020-11-18T14:17:57.923124336Z" level=warning msg="Your kernel does not support cgroup rt runtime"
Nov 18, 2020 @ 14:17:57.923time="2020-11-18T14:17:57.923871302Z" level=info msg="Loading containers: start."
Nov 18, 2020 @ 14:17:57.922time="2020-11-18T14:17:57.922893352Z" level=warning msg="Your kernel does not support swap memory limit"
Nov 18, 2020 @ 14:17:57.918time="2020-11-18T14:17:57.918200895Z" level=warning msg="Running modprobe bridge br_netfilter failed with message: ip: can't find device 'bridge'\nbridge 135168 1 br_netfilter\nstp 16384 1 bridge\nllc 16384 2 bridge,stp\nip: can't find device 'br_netfilter'\nbr_netfilter 24576 0 \nbridge 135168 1 br_netfilter\nmodprobe: can't change directory to '/lib/modules': No such file or directory\n, error: exit status 1"
Nov 18, 2020 @ 14:17:57.865time="2020-11-18T14:17:57.865277015Z" level=info msg="Loading containers: start."
Nov 18, 2020 @ 14:17:57.864time="2020-11-18T14:17:57.864680366Z" level=warning msg="Your kernel does not support swap memory limit"
Nov 18, 2020 @ 14:17:57.864time="2020-11-18T14:17:57.864861791Z" level=warning msg="Your kernel does not support cgroup rt period"
Nov 18, 2020 @ 14:17:57.864time="2020-11-18T14:17:57.864953186Z" level=warning msg="Your kernel does not support cgroup rt runtime"
Nov 18, 2020 @ 14:17:57.850time="2020-11-18T14:17:57.850278285Z" level=info msg="starting containerd" revision=8fba4e9a7d01810a393d5d25a3621dc101981175 version=v1.3.7
Nov 18, 2020 @ 14:17:57.734time="2020-11-18T14:17:57.733995603Z" level=info msg="scheme \"unix\" not registered, fallback to default scheme" module=grpc
Nov 18, 2020 @ 14:17:57.734time="2020-11-18T14:17:57.734120055Z" level=info msg="ccResolverWrapper: sending update to cc: {[{unix:///var/run/docker/containerd/containerd.sock 0 <nil>}] <nil>}" module=grpc
Nov 18, 2020 @ 14:17:57.734time="2020-11-18T14:17:57.734219184Z" level=info msg="ClientConn switching balancer to \"pick_first\"" module=grpc
Nov 18, 2020 @ 14:17:57.733time="2020-11-18T14:17:57.733851211Z" level=info msg="parsed scheme: \"unix\"" module=grpc
Nov 18, 2020 @ 14:17:57.732time="2020-11-18T14:17:57.732178130Z" level=info msg="parsed scheme: \"unix\"" module=grpc
Nov 18, 2020 @ 14:17:57.732time="2020-11-18T14:17:57.732341551Z" level=info msg="scheme \"unix\" not registered, fallback to default scheme" module=grpc
Nov 18, 2020 @ 14:17:57.732time="2020-11-18T14:17:57.732496777Z" level=info msg="ccResolverWrapper: sending update to cc: {[{unix:///var/run/docker/containerd/containerd.sock 0 <nil>}] <nil>}" module=grpc
Nov 18, 2020 @ 14:17:57.732time="2020-11-18T14:17:57.732617402Z" level=info msg="ClientConn switching balancer to \"pick_first\"" module=grpc
Nov 18, 2020 @ 14:17:57.715time="2020-11-18T14:17:57.715717610Z" level=info msg="ClientConn switching balancer to \"pick_first\"" module=grpc
Nov 18, 2020 @ 14:17:57.709time="2020-11-18T14:17:57.709188275Z" level=info msg="parsed scheme: \"unix\"" module=grpc
Nov 18, 2020 @ 14:17:57.709time="2020-11-18T14:17:57.709211023Z" level=info msg="scheme \"unix\" not registered, fallback to default scheme" module=grpc
Nov 18, 2020 @ 14:17:57.709time="2020-11-18T14:17:57.709229529Z" level=info msg="ccResolverWrapper: sending update to cc: {[{unix:///var/run/docker/containerd/containerd.sock 0 <nil>}] <nil>}" module=grpc
Nov 18, 2020 @ 14:17:57.708time="2020-11-18T14:17:57.707939931Z" level=info msg="ClientConn switching balancer to \"pick_first\"" module=grpc
Nov 18, 2020 @ 14:17:57.707time="2020-11-18T14:17:57.707684400Z" level=info msg="scheme \"unix\" not registered, fallback to default scheme" module=grpc
Nov 18, 2020 @ 14:17:57.707time="2020-11-18T14:17:57.707844335Z" level=info msg="ccResolverWrapper: sending update to cc: {[{unix:///var/run/docker/containerd/containerd.sock 0 <nil>}] <nil>}" module=grpc
Nov 18, 2020 @ 14:17:57.704time="2020-11-18T14:17:57.704067503Z" level=info msg="parsed scheme: \"unix\"" module=grpc
Nov 18, 2020 @ 14:17:57.698time="2020-11-18T14:17:57.697945460Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1
Nov 18, 2020 @ 14:17:57.698time="2020-11-18T14:17:57.698025796Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1
Nov 18, 2020 @ 14:17:57.698time="2020-11-18T14:17:57.698093053Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1
Nov 18, 2020 @ 14:17:57.698time="2020-11-18T14:17:57.698149750Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1
Nov 18, 2020 @ 14:17:57.698time="2020-11-18T14:17:57.698502512Z" level=info msg=serving... address=/var/run/docker/containerd/containerd-debug.sock
Nov 18, 2020 @ 14:17:57.698time="2020-11-18T14:17:57.698619057Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock.ttrpc
Nov 18, 2020 @ 14:17:57.698time="2020-11-18T14:17:57.698732030Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock
Nov 18, 2020 @ 14:17:57.698time="2020-11-18T14:17:57.698780907Z" level=info msg="containerd successfully booted in 0.309181s"
Nov 18, 2020 @ 14:17:57.697time="2020-11-18T14:17:57.697098159Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1
Nov 18, 2020 @ 14:17:57.697time="2020-11-18T14:17:57.697242275Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1
Nov 18, 2020 @ 14:17:57.697time="2020-11-18T14:17:57.697308290Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1
Nov 18, 2020 @ 14:17:57.697time="2020-11-18T14:17:57.697367953Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1
Nov 18, 2020 @ 14:17:57.697time="2020-11-18T14:17:57.697429543Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1
Nov 18, 2020 @ 14:17:57.697time="2020-11-18T14:17:57.697474250Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1
Nov 18, 2020 @ 14:17:57.697time="2020-11-18T14:17:57.697549690Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1
Nov 18, 2020 @ 14:17:57.697time="2020-11-18T14:17:57.697597765Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1
Nov 18, 2020 @ 14:17:57.697time="2020-11-18T14:17:57.697661623Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1
Nov 18, 2020 @ 14:17:57.697time="2020-11-18T14:17:57.697716809Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1
Nov 18, 2020 @ 14:17:57.691time="2020-11-18T14:17:57.690977289Z" level=info msg=serving... address=/var/run/docker/containerd/containerd-debug.sock
Nov 18, 2020 @ 14:17:57.691time="2020-11-18T14:17:57.691183931Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock.ttrpc
Nov 18, 2020 @ 14:17:57.691time="2020-11-18T14:17:57.691364942Z" level=info msg=serving... address=/var/run/docker/containerd/containerd.sock
Nov 18, 2020 @ 14:17:57.691time="2020-11-18T14:17:57.691475955Z" level=info msg="containerd successfully booted in 0.311682s"
Nov 18, 2020 @ 14:17:57.690time="2020-11-18T14:17:57.690098330Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1
Nov 18, 2020 @ 14:17:57.690time="2020-11-18T14:17:57.690253698Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1
Nov 18, 2020 @ 14:17:57.690time="2020-11-18T14:17:57.690406039Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1
Nov 18, 2020 @ 14:17:57.690time="2020-11-18T14:17:57.690532417Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1
Nov 18, 2020 @ 14:17:57.689time="2020-11-18T14:17:57.689242184Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1
Nov 18, 2020 @ 14:17:57.689time="2020-11-18T14:17:57.688963713Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1
Nov 18, 2020 @ 14:17:57.689time="2020-11-18T14:17:57.689110829Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1
Nov 18, 2020 @ 14:17:57.689time="2020-11-18T14:17:57.689237169Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1
Nov 18, 2020 @ 14:17:57.689time="2020-11-18T14:17:57.689390546Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1
Nov 18, 2020 @ 14:17:57.689time="2020-11-18T14:17:57.689498149Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1
Nov 18, 2020 @ 14:17:57.689time="2020-11-18T14:17:57.689636021Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1
Nov 18, 2020 @ 14:17:57.689time="2020-11-18T14:17:57.689737649Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1
Nov 18, 2020 @ 14:17:57.688time="2020-11-18T14:17:57.688316106Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1
Nov 18, 2020 @ 14:17:57.688time="2020-11-18T14:17:57.688521602Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1
Nov 18, 2020 @ 14:17:57.688time="2020-11-18T14:17:57.688715519Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1
Nov 18, 2020 @ 14:17:57.688time="2020-11-18T14:17:57.688853640Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1
Nov 18, 2020 @ 14:17:57.687time="2020-11-18T14:17:57.686992471Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1
Nov 18, 2020 @ 14:17:57.677time="2020-11-18T14:17:57.677190409Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2
Nov 18, 2020 @ 14:17:57.677time="2020-11-18T14:17:57.677459812Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1
Nov 18, 2020 @ 14:17:57.676time="2020-11-18T14:17:57.675950931Z" level=info msg="loading plugin \"io.containerd.service.v1.leases-service\"..." type=io.containerd.service.v1
Nov 18, 2020 @ 14:17:57.676time="2020-11-18T14:17:57.676081161Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1
Nov 18, 2020 @ 14:17:57.676time="2020-11-18T14:17:57.676214096Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1
Nov 18, 2020 @ 14:17:57.676time="2020-11-18T14:17:57.676355081Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1
Nov 18, 2020 @ 14:17:57.676time="2020-11-18T14:17:57.676740240Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2
Nov 18, 2020 @ 14:17:57.675time="2020-11-18T14:17:57.674952972Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1
Nov 18, 2020 @ 14:17:57.675time="2020-11-18T14:17:57.675171090Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1
Nov 18, 2020 @ 14:17:57.675time="2020-11-18T14:17:57.675327819Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1
Nov 18, 2020 @ 14:17:57.675time="2020-11-18T14:17:57.675699619Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1
Nov 18, 2020 @ 14:17:57.675time="2020-11-18T14:17:57.675847092Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1
Nov 18, 2020 @ 14:17:57.674time="2020-11-18T14:17:57.674784937Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1
Nov 18, 2020 @ 14:17:57.671time="2020-11-18T14:17:57.670907700Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1
Nov 18, 2020 @ 14:17:57.671time="2020-11-18T14:17:57.671044455Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1
Nov 18, 2020 @ 14:17:57.671time="2020-11-18T14:17:57.671151598Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1
Nov 18, 2020 @ 14:17:57.671time="2020-11-18T14:17:57.671447107Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1
Nov 18, 2020 @ 14:17:57.671time="2020-11-18T14:17:57.671516919Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1
Nov 18, 2020 @ 14:17:57.671time="2020-11-18T14:17:57.671592808Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1
Nov 18, 2020 @ 14:17:57.671time="2020-11-18T14:17:57.671686338Z" level=info msg="loading plugin \"io.containerd.service.v1.leases-service\"..." type=io.containerd.service.v1
Nov 18, 2020 @ 14:17:57.671time="2020-11-18T14:17:57.671763051Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1
Nov 18, 2020 @ 14:17:57.671time="2020-11-18T14:17:57.671782699Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1
Nov 18, 2020 @ 14:17:57.671time="2020-11-18T14:17:57.671802446Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1
Nov 18, 2020 @ 14:17:57.633time="2020-11-18T14:17:57.632896093Z" level=info msg="scheme \"unix\" not registered, fallback to default scheme" module=grpc
Nov 18, 2020 @ 14:17:57.633time="2020-11-18T14:17:57.632938844Z" level=info msg="ccResolverWrapper: sending update to cc: {[{unix:///var/run/docker/containerd/containerd.sock 0 <nil>}] <nil>}" module=grpc
Nov 18, 2020 @ 14:17:57.633time="2020-11-18T14:17:57.632952285Z" level=info msg="ClientConn switching balancer to \"pick_first\"" module=grpc
Nov 18, 2020 @ 14:17:57.632time="2020-11-18T14:17:57.632719197Z" level=info msg="libcontainerd: started new containerd process" pid=21
Nov 18, 2020 @ 14:17:57.632time="2020-11-18T14:17:57.632830228Z" level=info msg="parsed scheme: \"unix\"" module=grpc
Nov 18, 2020 @ 14:17:57.628time="2020-11-18T14:17:57.627946533Z" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured"
Nov 18, 2020 @ 14:17:57.628time="2020-11-18T14:17:57.627991853Z" level=info msg="metadata content store policy set" policy=shared
Nov 18, 2020 @ 14:17:57.627time="2020-11-18T14:17:57.626945794Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="modprobe aufs failed: \"ip: can't find device 'aufs'\\nmodprobe: can't change directory to '/lib/modules': No such file or directory\\n\": exit status 1: skip plugin" type=io.containerd.snapshotter.v1
Nov 18, 2020 @ 14:17:57.627time="2020-11-18T14:17:57.627014791Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1
Nov 18, 2020 @ 14:17:57.627time="2020-11-18T14:17:57.627163597Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1
Nov 18, 2020 @ 14:17:57.627time="2020-11-18T14:17:57.627363145Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1
Nov 18, 2020 @ 14:17:57.627time="2020-11-18T14:17:57.627823951Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/docker/containerd/daemon/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1
Nov 18, 2020 @ 14:17:57.627time="2020-11-18T14:17:57.627856006Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1
Nov 18, 2020 @ 14:17:57.624time="2020-11-18T14:17:57.624669734Z" level=warning msg="could not change group /var/run/docker.sock to docker: group docker not found"
Nov 18, 2020 @ 14:17:57.624time="2020-11-18T14:17:57.624813909Z" level=warning msg="[!] DON'T BIND ON ANY IP ADDRESS WITHOUT setting --tlsverify IF YOU DON'T KNOW WHAT YOU'RE DOING [!]"
Nov 18, 2020 @ 14:17:57.622--------------------------------------------------------------------------------
Nov 18, 2020 @ 14:17:57.622| ____ _ _ _ _ _ _ _ _ |
Nov 18, 2020 @ 14:17:57.622| / ___(_) |_| | | |_ _| |__ / \ ___| |_(_) ___ _ __ ___ |
Nov 18, 2020 @ 14:17:57.622| | | _| | __| |_| | | | | '_ \ / _ \ / __| __| |/ _ \| '_ \/ __| |
Nov 18, 2020 @ 14:17:57.622| | |_| | | |_| _ | |_| | |_) | / ___ \ (__| |_| | (_) | | | \__ \ |
Nov 18, 2020 @ 14:17:57.622| \____|_|\__|_| |_|\__,_|_.__/ /_/ \_\___|\__|_|\___/|_| |_|___/ |
Nov 18, 2020 @ 14:17:57.622| |
Nov 18, 2020 @ 14:17:57.622| Self-hosted runner registration |
Nov 18, 2020 @ 14:17:57.622| |
Nov 18, 2020 @ 14:17:57.622--------------------------------------------------------------------------------
Nov 18, 2020 @ 14:17:57.622
Nov 18, 2020 @ 14:17:57.622# Authentication
Nov 18, 2020 @ 14:17:57.622
Nov 18, 2020 @ 14:17:57.617
Nov 18, 2020 @ 14:17:57.617time="2020-11-18T14:17:57.617261534Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1
Nov 18, 2020 @ 14:17:57.617time="2020-11-18T14:17:57.617392716Z" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured"
Nov 18, 2020 @ 14:17:57.617time="2020-11-18T14:17:57.617434232Z" level=info msg="metadata content store policy set" policy=shared
Nov 18, 2020 @ 14:17:57.609time="2020-11-18T14:17:57.609458466Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/docker/containerd/daemon/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1
Nov 18, 2020 @ 14:17:57.608time="2020-11-18T14:17:57.608522256Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="modprobe aufs failed: \"ip: can't find device 'aufs'\\nmodprobe: can't change directory to '/lib/modules': No such file or directory\\n\": exit status 1: skip plugin" type=io.containerd.snapshotter.v1
Nov 18, 2020 @ 14:17:57.608time="2020-11-18T14:17:57.608555949Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1
Nov 18, 2020 @ 14:17:57.608time="2020-11-18T14:17:57.608705320Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1
Nov 18, 2020 @ 14:17:57.608time="2020-11-18T14:17:57.608857659Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1
Nov 18, 2020 @ 14:17:57.600time="2020-11-18T14:17:57.600322119Z" level=info msg="Starting up"
Nov 18, 2020 @ 14:17:57.591time="2020-11-18T14:17:57.591166935Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." error="path /var/lib/docker/containerd/daemon/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
Nov 18, 2020 @ 14:17:57.591time="2020-11-18T14:17:57.591325748Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1
Nov 18, 2020 @ 14:17:57.591time="2020-11-18T14:17:57.591421869Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured"
Nov 18, 2020 @ 14:17:57.591time="2020-11-18T14:17:57.591727415Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1
Nov 18, 2020 @ 14:17:57.590time="2020-11-18T14:17:57.589932284Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1
Nov 18, 2020 @ 14:17:57.589time="2020-11-18T14:17:57.589685357Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1
Nov 18, 2020 @ 14:17:57.584time="2020-11-18T14:17:57.583933926Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1
Nov 18, 2020 @ 14:17:57.584time="2020-11-18T14:17:57.584451573Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." error="path /var/lib/docker/containerd/daemon/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
Nov 18, 2020 @ 14:17:57.584time="2020-11-18T14:17:57.584606134Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1
Nov 18, 2020 @ 14:17:57.584time="2020-11-18T14:17:57.584726661Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured"
Nov 18, 2020 @ 14:17:57.584time="2020-11-18T14:17:57.584813509Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1
Nov 18, 2020 @ 14:17:57.581time="2020-11-18T14:17:57.581785987Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1
Nov 18, 2020 @ 14:17:57.5152020-11-18T14:17:57.515Z DEBUG controller-runtime.controller Successfully Reconciled {"controller": "runner", "request": "actions-runner-system/github-runner-2dflt-45vlj"}
Nov 18, 2020 @ 14:17:57.4022020-11-18T14:17:57.402Z INFO controllers.RunnerReplicaSet debug {"runner": "actions-runner-system/github-runner-2dflt", "desired": 3, "available": 3}
Nov 18, 2020 @ 14:17:57.4022020-11-18T14:17:57.402Z DEBUG controller-runtime.controller Successfully Reconciled {"controller": "runnerreplicaset", "request": "actions-runner-system/github-runner-2dflt"}
Nov 18, 2020 @ 14:17:57.4012020-11-18T14:17:57.401Z DEBUG controller-runtime.controller Successfully Reconciled {"controller": "runnerreplicaset", "request": "actions-runner-system/github-runner-2dflt"}
Nov 18, 2020 @ 14:17:57.4012020-11-18T14:17:57.401Z DEBUG controller-runtime.controller Successfully Reconciled {"controller": "runnerdeployment", "request": "actions-runner-system/github-runner"}
Nov 18, 2020 @ 14:17:57.399time="2020-11-18T14:17:57.396383849Z" level=info msg="starting containerd" revision=8fba4e9a7d01810a393d5d25a3621dc101981175 version=v1.3.7
Nov 18, 2020 @ 14:17:57.3972020-11-18T14:17:57.396Z INFO controllers.RunnerReplicaSet debug {"runner": "actions-runner-system/github-runner-2dflt", "desired": 3, "available": 3}
Nov 18, 2020 @ 14:17:57.3972020-11-18T14:17:57.397Z DEBUG controller-runtime.controller Successfully Reconciled {"controller": "runner", "request": "actions-runner-system/github-runner-2dflt-45vlj"}
Nov 18, 2020 @ 14:17:57.3922020-11-18T14:17:57.392Z DEBUG controller-runtime.controller Successfully Reconciled {"controller": "runner", "request": "actions-runner-system/github-runner-2dflt-lq4n9"}
Nov 18, 2020 @ 14:17:57.380time="2020-11-18T14:17:57.380780976Z" level=info msg="starting containerd" revision=8fba4e9a7d01810a393d5d25a3621dc101981175 version=v1.3.7
Nov 18, 2020 @ 14:17:57.370| Self-hosted runner registration |
Nov 18, 2020 @ 14:17:57.370| |
Nov 18, 2020 @ 14:17:57.370--------------------------------------------------------------------------------
Nov 18, 2020 @ 14:17:57.370
Nov 18, 2020 @ 14:17:57.370# Authentication
Nov 18, 2020 @ 14:17:57.370
Nov 18, 2020 @ 14:17:57.369| |
Nov 18, 2020 @ 14:17:57.367
Nov 18, 2020 @ 14:17:57.367--------------------------------------------------------------------------------
Nov 18, 2020 @ 14:17:57.367| ____ _ _ _ _ _ _ _ _ |
Nov 18, 2020 @ 14:17:57.367| / ___(_) |_| | | |_ _| |__ / \ ___| |_(_) ___ _ __ ___ |
Nov 18, 2020 @ 14:17:57.367| | | _| | __| |_| | | | | '_ \ / _ \ / __| __| |/ _ \| '_ \/ __| |
Nov 18, 2020 @ 14:17:57.367| | |_| | | |_| _ | |_| | |_) | / ___ \ (__| |_| | (_) | | | \__ \ |
Nov 18, 2020 @ 14:17:57.367| \____|_|\__|_| |_|\__,_|_.__/ /_/ \_\___|\__|_|\___/|_| |_|___/ |
Nov 18, 2020 @ 14:17:57.311time="2020-11-18T14:17:57.311259149Z" level=info msg="parsed scheme: \"unix\"" module=grpc
Nov 18, 2020 @ 14:17:57.311time="2020-11-18T14:17:57.311413866Z" level=info msg="scheme \"unix\" not registered, fallback to default scheme" module=grpc
Nov 18, 2020 @ 14:17:57.311time="2020-11-18T14:17:57.311537621Z" level=info msg="ccResolverWrapper: sending update to cc: {[{unix:///var/run/docker/containerd/containerd.sock 0 <nil>}] <nil>}" module=grpc
Nov 18, 2020 @ 14:17:57.311time="2020-11-18T14:17:57.311622598Z" level=info msg="ClientConn switching balancer to \"pick_first\"" module=grpc
Nov 18, 2020 @ 14:17:57.295time="2020-11-18T14:17:57.295900606Z" level=info msg="libcontainerd: started new containerd process" pid=26
Nov 18, 2020 @ 14:17:57.288time="2020-11-18T14:17:57.288015832Z" level=info msg="ClientConn switching balancer to \"pick_first\"" module=grpc
Nov 18, 2020 @ 14:17:57.287time="2020-11-18T14:17:57.287701223Z" level=info msg="parsed scheme: \"unix\"" module=grpc
Nov 18, 2020 @ 14:17:57.287time="2020-11-18T14:17:57.287807929Z" level=info msg="scheme \"unix\" not registered, fallback to default scheme" module=grpc
Nov 18, 2020 @ 14:17:57.287time="2020-11-18T14:17:57.287919278Z" level=info msg="ccResolverWrapper: sending update to cc: {[{unix:///var/run/docker/containerd/containerd.sock 0 <nil>}] <nil>}" module=grpc
Nov 18, 2020 @ 14:17:57.285time="2020-11-18T14:17:57.285199708Z" level=info msg="libcontainerd: started new containerd process" pid=20
Nov 18, 2020 @ 14:17:57.284time="2020-11-18T14:17:57.284022901Z" level=warning msg="could not change group /var/run/docker.sock to docker: group docker not found"
Nov 18, 2020 @ 14:17:57.284time="2020-11-18T14:17:57.284277513Z" level=warning msg="[!] DON'T BIND ON ANY IP ADDRESS WITHOUT setting --tlsverify IF YOU DON'T KNOW WHAT YOU'RE DOING [!]"
Nov 18, 2020 @ 14:17:57.277time="2020-11-18T14:17:57.277685256Z" level=warning msg="could not change group /var/run/docker.sock to docker: group docker not found"
Nov 18, 2020 @ 14:17:57.277time="2020-11-18T14:17:57.277856992Z" level=warning msg="[!] DON'T BIND ON ANY IP ADDRESS WITHOUT setting --tlsverify IF YOU DON'T KNOW WHAT YOU'RE DOING [!]"
Nov 18, 2020 @ 14:17:57.275time="2020-11-18T14:17:57.275284301Z" level=info msg="Starting up"
Nov 18, 2020 @ 14:17:57.272time="2020-11-18T14:17:57.272131192Z" level=info msg="Starting up"
Nov 18, 2020 @ 14:17:57.2592020-11-18T14:17:57.259Z DEBUG controller-runtime.controller Successfully Reconciled {"controller": "runnerdeployment", "request": "actions-runner-system/github-runner"}
Nov 18, 2020 @ 14:17:57.2592020-11-18T14:17:57.259Z DEBUG controller-runtime.controller Successfully Reconciled {"controller": "runnerreplicaset", "request": "actions-runner-system/github-runner-2dflt"}
Nov 18, 2020 @ 14:17:57.2592020-11-18T14:17:57.259Z INFO controllers.RunnerReplicaSet debug {"runner": "actions-runner-system/github-runner-2dflt", "desired": 3, "available": 3}
Nov 18, 2020 @ 14:17:57.2592020-11-18T14:17:57.259Z DEBUG controller-runtime.controller Successfully Reconciled {"controller": "runnerreplicaset", "request": "actions-runner-system/github-runner-2dflt"}
Nov 18, 2020 @ 14:17:57.2552020-11-18T14:17:57.254Z INFO controllers.RunnerReplicaSet debug {"runner": "actions-runner-system/github-runner-2dflt", "desired": 3, "available": 3}
Nov 18, 2020 @ 14:17:57.2552020-11-18T14:17:57.255Z DEBUG controller-runtime.controller Successfully Reconciled {"controller": "runner", "request": "actions-runner-system/github-runner-2dflt-lq4n9"}
Nov 18, 2020 @ 14:17:56.723Github endpoint URL https://github.com/
Nov 18, 2020 @ 14:17:56.5372020-11-18T14:17:56.537Z DEBUG controller-runtime.controller Successfully Reconciled {"controller": "runner", "request": "actions-runner-system/github-runner-2dflt-lvqvb"}
Nov 18, 2020 @ 14:17:56.433Github endpoint URL https://github.com/
Nov 18, 2020 @ 14:17:56.316Github endpoint URL https://github.com/
Nov 18, 2020 @ 14:17:56.1852020-11-18T14:17:56.185Z DEBUG controller-runtime.controller Successfully Reconciled {"controller": "runner", "request": "actions-runner-system/github-runner-2dflt-45vlj"}
Nov 18, 2020 @ 14:17:55.7312020-11-18T14:17:55.731Z DEBUG controller-runtime.controller Successfully Reconciled {"controller": "runner", "request": "actions-runner-system/github-runner-2dflt-lq4n9"}
Nov 18, 2020 @ 14:17:55.5982020-11-18T14:17:55.598Z DEBUG controller-runtime.controller Successfully Reconciled {"controller": "runner", "request": "actions-runner-system/github-runner-2dflt-lvqvb"}
Nov 18, 2020 @ 14:17:55.4382020-11-18T14:17:55.438Z DEBUG controller-runtime.controller Successfully Reconciled {"controller": "runner", "request": "actions-runner-system/github-runner-2dflt-45vlj"}
Nov 18, 2020 @ 14:17:55.3012020-11-18T14:17:55.301Z DEBUG controller-runtime.controller Successfully Reconciled {"controller": "runner", "request": "actions-runner-system/github-runner-2dflt-lq4n9"}
Nov 18, 2020 @ 14:17:55.1502020-11-18T14:17:55.149Z DEBUG controller-runtime.controller Successfully Reconciled {"controller": "runnerreplicaset", "request": "actions-runner-system/github-runner-2dflt"}
Nov 18, 2020 @ 14:17:55.1502020-11-18T14:17:55.150Z INFO controllers.RunnerReplicaSet debug {"runner": "actions-runner-system/github-runner-2dflt", "desired": 3, "available": 3}
Nov 18, 2020 @ 14:17:55.1502020-11-18T14:17:55.150Z DEBUG controller-runtime.controller Successfully Reconciled {"controller": "runnerreplicaset", "request": "actions-runner-system/github-runner-2dflt"}
Nov 18, 2020 @ 14:17:55.1502020-11-18T14:17:55.150Z DEBUG controller-runtime.controller Successfully Reconciled {"controller": "runnerdeployment", "request": "actions-runner-system/github-runner"}
Nov 18, 2020 @ 14:17:55.1462020-11-18T14:17:55.146Z DEBUG controller-runtime.controller Successfully Reconciled {"controller": "runner", "request": "actions-runner-system/github-runner-2dflt-lvqvb"}
Nov 18, 2020 @ 14:17:55.1452020-11-18T14:17:55.145Z DEBUG controller-runtime.controller Successfully Reconciled {"controller": "runnerreplicaset", "request": "actions-runner-system/github-runner-2dflt"}
Nov 18, 2020 @ 14:17:55.1452020-11-18T14:17:55.145Z INFO controllers.RunnerReplicaSet debug {"runner": "actions-runner-system/github-runner-2dflt", "desired": 3, "available": 3}
Nov 18, 2020 @ 14:17:55.1442020-11-18T14:17:55.144Z DEBUG controller-runtime.controller Successfully Reconciled {"controller": "runnerdeployment", "request": "actions-runner-system/github-runner"}
Nov 18, 2020 @ 14:17:55.1402020-11-18T14:17:55.139Z DEBUG controller-runtime.controller Successfully Reconciled {"controller": "runnerreplicaset", "request": "actions-runner-system/github-runner-2dflt"}
Nov 18, 2020 @ 14:17:55.1402020-11-18T14:17:55.140Z INFO controllers.RunnerReplicaSet debug {"runner": "actions-runner-system/github-runner-2dflt", "desired": 3, "available": 3}
Nov 18, 2020 @ 14:17:55.1392020-11-18T14:17:55.139Z DEBUG controller-runtime.controller Successfully Reconciled {"controller": "runner", "request": "actions-runner-system/github-runner-2dflt-45vlj"}
Nov 18, 2020 @ 14:17:55.0802020-11-18T14:17:55.080Z DEBUG controller-runtime.controller Successfully Reconciled {"controller": "runnerdeployment", "request": "actions-runner-system/github-runner"}
Nov 18, 2020 @ 14:17:55.0722020-11-18T14:17:55.072Z DEBUG controller-runtime.controller Successfully Reconciled {"controller": "runner", "request": "actions-runner-system/github-runner-2dflt-lq4n9"}
Nov 18, 2020 @ 14:17:55.0712020-11-18T14:17:55.071Z INFO controllers.RunnerReplicaSet debug {"runner": "actions-runner-system/github-runner-2dflt", "desired": 3, "available": 3}
Nov 18, 2020 @ 14:17:55.0642020-11-18T14:17:55.064Z DEBUG controller-runtime.controller Successfully Reconciled {"controller": "runner", "request": "actions-runner-system/github-runner-2dflt-lvqvb"}
Nov 18, 2020 @ 14:17:55.0642020-11-18T14:17:55.063Z DEBUG controller-runtime.manager.events Normal {"object": {"kind":"Runner","namespace":"actions-runner-system","name":"github-runner-2dflt-lvqvb","uid":"abb1dba6-991b-4943-8e03-2beaf78754c8","apiVersion":"actions.summerwind.dev/v1alpha1","resourceVersion":"68881058"}, "reason": "PodCreated", "message": "Created pod 'github-runner-2dflt-lvqvb'"}
Nov 18, 2020 @ 14:17:55.0632020-11-18T14:17:55.063Z INFO controllers.Runner Created runner pod {"runner": "actions-runner-system/github-runner-2dflt-lvqvb", "repository": ""}
Nov 18, 2020 @ 14:17:55.0592020-11-18T14:17:55.059Z INFO controllers.Runner Created runner pod {"runner": "actions-runner-system/github-runner-2dflt-45vlj", "repository": ""}
Nov 18, 2020 @ 14:17:55.0592020-11-18T14:17:55.059Z DEBUG controller-runtime.controller Successfully Reconciled {"controller": "runner", "request": "actions-runner-system/github-runner-2dflt-45vlj"}
Nov 18, 2020 @ 14:17:55.0592020-11-18T14:17:55.059Z DEBUG controller-runtime.manager.events Normal {"object": {"kind":"Runner","namespace":"actions-runner-system","name":"github-runner-2dflt-45vlj","uid":"0644b0ea-a59d-4c84-8a29-cf8fe5f8cee0","apiVersion":"actions.summerwind.dev/v1alpha1","resourceVersion":"68881057"}, "reason": "PodCreated", "message": "Created pod 'github-runner-2dflt-45vlj'"}
Nov 18, 2020 @ 14:17:55.0502020-11-18T14:17:55.050Z INFO controllers.Runner Created runner pod {"runner": "actions-runner-system/github-runner-2dflt-lq4n9", "repository": ""}
Nov 18, 2020 @ 14:17:55.0502020-11-18T14:17:55.050Z DEBUG controller-runtime.controller Successfully Reconciled {"controller": "runner", "request": "actions-runner-system/github-runner-2dflt-lq4n9"}
Nov 18, 2020 @ 14:17:55.0502020-11-18T14:17:55.050Z DEBUG controller-runtime.manager.events Normal {"object": {"kind":"Runner","namespace":"actions-runner-system","name":"github-runner-2dflt-lq4n9","uid":"e9cb24eb-6deb-42ec-8ff4-514370dc5e9a","apiVersion":"actions.summerwind.dev/v1alpha1","resourceVersion":"68881056"}, "reason": "PodCreated", "message": "Created pod 'github-runner-2dflt-lq4n9'"}
Nov 18, 2020 @ 14:17:55.0402020-11-18T14:17:55.040Z DEBUG controller-runtime.controller Successfully Reconciled {"controller": "runner", "request": "actions-runner-system/github-runner-2dflt-lq4n9"}
Nov 18, 2020 @ 14:17:48.3942020-11-18T14:17:48.391Z DEBUG controller-runtime.controller Successfully Reconciled {"controller": "runner", "request": "actions-runner-system/github-runner-2dflt-45vlj"}
Nov 18, 2020 @ 14:17:48.3772020-11-18T14:17:48.374Z DEBUG controller-runtime.controller Successfully Reconciled {"controller": "runner", "request": "actions-runner-system/github-runner-2dflt-lq4n9"}
Nov 18, 2020 @ 14:17:48.3562020-11-18T14:17:48.355Z DEBUG controller-runtime.controller Successfully Reconciled {"controller": "runner", "request": "actions-runner-system/github-runner-2dflt-lvqvb"}
Nov 18, 2020 @ 14:17:48.3372020-11-18T14:17:48.336Z DEBUG controller-runtime.controller Successfully Reconciled {"controller": "runner", "request": "actions-runner-system/github-runner-2dflt-lvqvb"}
Nov 18, 2020 @ 14:17:46.6062020-11-18T14:17:46.603Z INFO controllers.Runner Deleted runner pod {"runner": "actions-runner-system/github-runner-2dflt-lvqvb", "repository": ""}
Nov 18, 2020 @ 14:17:46.606
2020-11-18T14:17:46.603Z DEBUG controller-runtime.controller Successfully Reconciled {"controller": "runner", "request": "actions-runner-system/github-runner-2dflt-lvqvb"}
Nov 18, 2020 @ 14:17:46.6062020-11-18T14:17:46.603Z DEBUG controller-runtime.controller Successfully Reconciled {"controller": "runner", "request": "actions-runner-system/github-runner-2dflt-lq4n9"}
Nov 18, 2020 @ 14:17:46.6062020-11-18T14:17:46.603Z DEBUG controller-runtime.controller Successfully Reconciled {"controller": "runner", "request": "actions-runner-system/github-runner-2dflt-45vlj"}
Nov 18, 2020 @ 14:17:46.6062020-11-18T14:17:46.603Z DEBUG controller-runtime.manager.events Normal {"object": {"kind":"Runner","namespace":"actions-runner-system","name":"github-runner-2dflt-lvqvb","uid":"abb1dba6-991b-4943-8e03-2beaf78754c8","apiVersion":"actions.summerwind.dev/v1alpha1","resourceVersion":"68881058"}, "reason": "PodDeleted", "message": "Deleted pod 'github-runner-2dflt-lvqvb'"}
Nov 18, 2020 @ 14:17:46.6062020-11-18T14:17:46.604Z DEBUG controller-runtime.controller Successfully Reconciled {"controller": "runner", "request": "actions-runner-system/github-runner-2dflt-lvqvb"}
Nov 18, 2020 @ 14:17:46.4412020-11-18T14:17:46.440Z DEBUG controller-runtime.manager.events Normal {"object": {"kind":"Runner","namespace":"actions-runner-system","name":"github-runner-2dflt-45vlj","uid":"0644b0ea-a59d-4c84-8a29-cf8fe5f8cee0","apiVersion":"actions.summerwind.dev/v1alpha1","resourceVersion":"68881057"}, "reason": "PodDeleted", "message": "Deleted pod 'github-runner-2dflt-45vlj'"}
Nov 18, 2020 @ 14:17:46.4402020-11-18T14:17:46.440Z INFO controllers.Runner Deleted runner pod {"runner": "actions-runner-system/github-runner-2dflt-45vlj", "repository": ""}
Nov 18, 2020 @ 14:17:46.4402020-11-18T14:17:46.440Z DEBUG controller-runtime.controller Successfully Reconciled {"controller": "runner", "request": "actions-runner-system/github-runner-2dflt-45vlj"}
Nov 18, 2020 @ 14:17:46.2732020-11-18T14:17:46.273Z INFO controllers.Runner Deleted runner pod {"runner": "actions-runner-system/github-runner-2dflt-lq4n9", "repository": ""}
Nov 18, 2020 @ 14:17:46.2732020-11-18T14:17:46.273Z DEBUG controller-runtime.controller Successfully Reconciled {"controller": "runner", "request": "actions-runner-system/github-runner-2dflt-lq4n9"}
Nov 18, 2020 @ 14:17:46.2732020-11-18T14:17:46.273Z DEBUG controller-runtime.manager.events Normal {"object": {"kind":"Runner","namespace":"actions-runner-system","name":"github-runner-2dflt-lq4n9","uid":"e9cb24eb-6deb-42ec-8ff4-514370dc5e9a","apiVersion":"actions.summerwind.dev/v1alpha1","resourceVersion":"68881056"}, "reason": "PodDeleted", "message": "Deleted pod 'github-runner-2dflt-lq4n9'"}
Nov 18, 2020 @ 14:17:46.0072020-11-18T14:17:46.000Z INFO controllers.RunnerReplicaSet debug {"runner": "actions-runner-system/github-runner-2dflt", "desired": 3, "available": 3}
Nov 18, 2020 @ 14:17:46.0072020-11-18T14:17:46.000Z DEBUG controller-runtime.controller Successfully Reconciled {"controller": "runnerreplicaset", "request": "actions-runner-system/github-runner-2dflt"}
Nov 18, 2020 @ 14:17:46.0072020-11-18T14:17:46.000Z INFO controllers.Runner Updated registration token {"runner": "github-runner-2dflt-lvqvb", "repository": ""}
Nov 18, 2020 @ 14:17:46.0072020-11-18T14:17:46.000Z DEBUG controller-runtime.manager.events Normal {"object": {"kind":"Runner","namespace":"actions-runner-system","name":"github-runner-2dflt-lvqvb","uid":"abb1dba6-991b-4943-8e03-2beaf78754c8","apiVersion":"actions.summerwind.dev/v1alpha1","resourceVersion":"68865334"}, "reason": "RegistrationTokenUpdated", "message": "Successfully update registration token"}
Nov 18, 2020 @ 14:17:46.0002020-11-18T14:17:45.996Z DEBUG controller-runtime.manager.events Normal {"object": {"kind":"Runner","namespace":"actions-runner-system","name":"github-runner-2dflt-45vlj","uid":"0644b0ea-a59d-4c84-8a29-cf8fe5f8cee0","apiVersion":"actions.summerwind.dev/v1alpha1","resourceVersion":"68865339"}, "reason": "RegistrationTokenUpdated", "message": "Successfully update registration token"}
Nov 18, 2020 @ 14:17:45.9992020-11-18T14:17:45.995Z INFO controllers.Runner Updated registration token {"runner": "github-runner-2dflt-45vlj", "repository": ""}
Nov 18, 2020 @ 14:17:45.9992020-11-18T14:17:45.996Z INFO controllers.RunnerReplicaSet debug {"runner": "actions-runner-system/github-runner-2dflt", "desired": 3, "available": 3}
Nov 18, 2020 @ 14:17:45.9992020-11-18T14:17:45.996Z DEBUG controller-runtime.controller Successfully Reconciled {"controller": "runnerreplicaset", "request": "actions-runner-system/github-runner-2dflt"}
Nov 18, 2020 @ 14:17:45.9902020-11-18T14:17:45.989Z INFO controllers.RunnerReplicaSet debug {"runner": "actions-runner-system/github-runner-2dflt", "desired": 3, "available": 3}
Nov 18, 2020 @ 14:17:45.9902020-11-18T14:17:45.989Z DEBUG controller-runtime.controller Successfully Reconciled {"controller": "runnerreplicaset", "request": "actions-runner-system/github-runner-2dflt"}
Nov 18, 2020 @ 14:17:45.9902020-11-18T14:17:45.989Z INFO controllers.Runner Updated registration token {"runner": "github-runner-2dflt-lq4n9", "repository": ""}
Nov 18, 2020 @ 14:17:45.9902020-11-18T14:17:45.989Z DEBUG controller-runtime.manager.events Normal {"object": {"kind":"Runner","namespace":"actions-runner-system","name":"github-runner-2dflt-lq4n9","uid":"e9cb24eb-6deb-42ec-8ff4-514370dc5e9a","apiVersion":"actions.summerwind.dev/v1alpha1","resourceVersion":"68865343"}, "reason": "RegistrationTokenUpdated", "message": "Successfully update registration token"}
Nov 18, 2020 @ 14:16:50.1442020-11-18T14:16:50.144Z INFO controllers.Runner Updated registration token {"runner": "github-runner-2dflt-lvqvb", "repository": ""}
Nov 18, 2020 @ 14:16:50.1442020-11-18T14:16:50.144Z DEBUG controller-runtime.manager.events Normal {"object": {"kind":"Runner","namespace":"actions-runner-system","name":"github-runner-2dflt-lvqvb","uid":"abb1dba6-991b-4943-8e03-2beaf78754c8","apiVersion":"actions.summerwind.dev/v1alpha1","resourceVersion":"68865334"}, "reason": "RegistrationTokenUpdated", "message": "Successfully update registration token"}
Nov 18, 2020 @ 14:16:50.1402020-11-18T14:16:50.139Z INFO controllers.Runner Updated registration token {"runner": "github-runner-2dflt-lq4n9", "repository": ""}
Nov 18, 2020 @ 14:16:50.1402020-11-18T14:16:50.140Z DEBUG controller-runtime.manager.events Normal {"object": {"kind":"Runner","namespace":"actions-runner-system","name":"github-runner-2dflt-lq4n9","uid":"e9cb24eb-6deb-42ec-8ff4-514370dc5e9a","apiVersion":"actions.summerwind.dev/v1alpha1","resourceVersion":"68865343"}, "reason": "RegistrationTokenUpdated", "message": "Successfully update registration token"}
Nov 18, 2020 @ 14:16:50.0712020-11-18T14:16:50.071Z INFO controllers.Runner Updated registration token {"runner": "github-runner-2dflt-45vlj", "repository": ""}
Nov 18, 2020 @ 14:16:50.0712020-11-18T14:16:50.071Z DEBUG controller-runtime.manager.events Normal {"object": {"kind":"Runner","namespace":"actions-runner-system","name":"github-runner-2dflt-45vlj","uid":"0644b0ea-a59d-4c84-8a29-cf8fe5f8cee0","apiVersion":"actions.summerwind.dev/v1alpha1","resourceVersion":"68865339"}, "reason": "RegistrationTokenUpdated", "message": "Successfully update registration token"}
Nov 18, 2020 @ 14:16:49.8402020-11-18T14:16:49.839Z DEBUG controller-runtime.manager.events Normal {"object": {"kind":"Runner","namespace":"actions-runner-system","name":"github-runner-2dflt-lq4n9","uid":"e9cb24eb-6deb-42ec-8ff4-514370dc5e9a","apiVersion":"actions.summerwind.dev/v1alpha1","resourceVersion":"68865343"}, "reason": "RegistrationTokenUpdated", "message": "Successfully update registration token"}
Nov 18, 2020 @ 14:16:49.8402020-11-18T14:16:49.840Z DEBUG controller-runtime.controller Successfully Reconciled {"controller": "runnerdeployment", "request": "actions-runner-system/github-runner"}
Nov 18, 2020 @ 14:16:49.8392020-11-18T14:16:49.839Z INFO controllers.Runner Updated registration token {"runner": "github-runner-2dflt-lq4n9", "repository": ""}
Nov 18, 2020 @ 14:16:49.7482020-11-18T14:16:49.748Z INFO controllers.Runner Updated registration token {"runner": "github-runner-2dflt-lvqvb", "repository": ""}
Nov 18, 2020 @ 14:16:49.7482020-11-18T14:16:49.748Z DEBUG controller-runtime.manager.events Normal {"object": {"kind":"Runner","namespace":"actions-runner-system","name":"github-runner-2dflt-lvqvb","uid":"abb1dba6-991b-4943-8e03-2beaf78754c8","apiVersion":"actions.summerwind.dev/v1alpha1","resourceVersion":"68865334"}, "reason": "RegistrationTokenUpdated", "message": "Successfully update registration token"}
Nov 18, 2020 @ 14:16:49.7452020-11-18T14:16:49.744Z INFO controllers.Runner Updated registration token {"runner": "github-runner-2dflt-45vlj", "repository": ""}
Nov 18, 2020 @ 14:16:49.7452020-11-18T14:16:49.744Z DEBUG controller-runtime.manager.events Normal {"object": {"kind":"Runner","namespace":"actions-runner-system","name":"github-runner-2dflt-45vlj","uid":"0644b0ea-a59d-4c84-8a29-cf8fe5f8cee0","apiVersion":"actions.summerwind.dev/v1alpha1","resourceVersion":"68865339"}, "reason": "RegistrationTokenUpdated", "message": "Successfully update registration token"}
Nov 18, 2020 @ 14:16:49.7412020-11-18T14:16:49.740Z INFO controllers.RunnerReplicaSet debug {"runner": "actions-runner-system/github-runner-2dflt", "desired": 3, "available": 3}
Nov 18, 2020 @ 14:16:49.7412020-11-18T14:16:49.741Z DEBUG controller-runtime.controller Successfully Reconciled {"controller": "runnerreplicaset", "request": "actions-runner-system/github-runner-2dflt"}
Nov 18, 2020 @ 14:16:32.7992020-11-18T14:16:32.799Z INFO controllers.RunnerReplicaSet debug {"runner": "actions-runner-system/github-runner-2dflt", "desired": 3, "available": 3}
Nov 18, 2020 @ 14:16:32.7992020-11-18T14:16:32.799Z DEBUG controller-runtime.controller Successfully Reconciled {"controller": "runnerreplicaset", "request": "actions-runner-system/github-runner-2dflt"}
Nov 18, 2020 @ 14:16:32.7992020-11-18T14:16:32.799Z DEBUG controller-runtime.controller Successfully Reconciled {"controller": "runnerdeployment", "request": "actions-runner-system/github-runner"}
​
kaykhancheckpoint
on 18 Nov 2020
@kaykhancheckpoint Thanks a lot for sharing the details. I'll definitely take a deeper look ASAP đź‘Ť
mumoshu
on 19 Nov 2020
Apologies for highjacking the thread but we’ve also come across this behaviour that led us to question a few things.
I’ve seen it stated in a few issues that many believe runner pods should be destroyed after each successful GitHub Action run, whilst I would tend to agree with this I believe there’s also some argument for wanting the runners to stick around a little longer than that.
For instance many of our services build the same way and utilise similar dependancies which don’t change every ~hour. Having fresh new pods each time adds a decent chunk of time to our overall build time which we’d like to mitigate against, currently there is no programable way for me to override a desired min lifetime of the pod, say for instance I wanted to rotate every 6 hours instead of 1?
What is the consensus for this in the project?
nkaplatt
on 3 Dec 2020
@nkaplatt I see you want to introduce only security hole by willing runner to keep it's temporary data and also offer that to next job which is running on that runner.
Also you same time remote the possibility to have more than one runner running, as your goal is not fulfilling if job ends up on other runner.
GHA has cache action, that should be used instead. If really wanting runners to share data between runs (note that to share data between one pipeline's different jobs artifact upload/download should be used) you should mount each runner with RWM (read write many) volume that keeps the data you need available.
I don't see any reason why the controller should encourage the usage of GHA wrong way.
Hi-Fi
on 5 Dec 2020
@kaykhancheckpoint Thank you for the log. According that controller ends up to here. So the error (or "feature") is in this else and it should at some point make the return and not let the code to continue to last lines.
Hi-Fi
on 5 Dec 2020
Found out the issue: new pod gets new token (if that's updated) so the environment variables differ on that part. Just filtering that token out from env before comparison stopped restarts. Also at the same time it was possible to see that runner application can re-connect without token being updated hourly.
2020-12-07 04:32:21Z: Listening for Jobs
2020-12-07 04:33:35Z: Runner connect error: An error occurred while sending the request.. Retrying until reconnected.
2020-12-07 04:34:03Z: Runner reconnected.
2020-12-07 05:20:36Z: Runner connect error: The HTTP request timed out after 00:01:00.. Retrying until reconnected.
2020-12-07 05:21:56Z: Runner reconnected.
2020-12-07 05:27:56Z: Runner connect error: An error occurred while sending the request.. Retrying until reconnected.
2020-12-07 05:28:16Z: Runner reconnected.
2020-12-07 05:30:35Z: Runner connect error: The HTTP request timed out after 00:01:00.. Retrying until reconnected.
2020-12-07 05:31:43Z: Runner reconnected.
2020-12-07 10:09:22Z: Runner connect error: The HTTP request timed out after 00:01:00.. Retrying until reconnected.
2020-12-07 10:10:34Z: Runner reconnected.
I had a chance to test this new version, it resolved the issue for me.
thanks again,
@mumoshu @Hi-Fi
kaykhancheckpoint
on 16 Dec 2020
Related issues
stackdumper
·
6Comments
raj-saxena
·
5Comments
callum-tait-pbx
·
9Comments
jmmaloney4
·
6Comments
kimxogus
·
3Comments
Most helpful comment
Apologies for highjacking the thread but we’ve also come across this behaviour that led us to question a few things.
I’ve seen it stated in a few issues that many believe runner pods should be destroyed after each successful GitHub Action run, whilst I would tend to agree with this I believe there’s also some argument for wanting the runners to stick around a little longer than that.
For instance many of our services build the same way and utilise similar dependancies which don’t change every ~hour. Having fresh new pods each time adds a decent chunk of time to our overall build time which we’d like to mitigate against, currently there is no programable way for me to override a desired min lifetime of the pod, say for instance I wanted to rotate every 6 hours instead of 1?
What is the consensus for this in the project?