Running with gitlab-runner 13.0.0 (c127439c)
  on docker-auto-scale fa6cab46
section_start:1592232959:prepare_executor
Preparing the "docker+machine" executor
Using Docker executor with image registry.gitlab.com/gitlab-org/cluster-integration/auto-build-image:v0.2.3 ...
Starting service docker:19.03.11-dind ...
Pulling docker image docker:19.03.11-dind ...
Using docker image sha256:ded0f436a6a380f373f1840de86b6887cff86600946490b3a44a890bc3d95657 for docker:19.03.11-dind ...
Waiting for services to be up and running...

*** WARNING: Service runner-fa6cab46-project-4422333-concurrent-0-87541f585aace2e3-docker-0 probably didn't start properly.

Health check error:
service "runner-fa6cab46-project-4422333-concurrent-0-87541f585aace2e3-docker-0-wait-for-service" timeout

Health check container logs:


Service container logs:
2020-06-15T14:57:06.053050568Z time="2020-06-15T14:57:06.022337259Z" level=info msg="Starting up"
2020-06-15T14:57:06.053115531Z time="2020-06-15T14:57:06.025221293Z" level=warning msg="could not change group /var/run/docker.sock to docker: group docker not found"
2020-06-15T14:57:06.053120306Z time="2020-06-15T14:57:06.025412005Z" level=warning msg="[!] DON'T BIND ON ANY IP ADDRESS WITHOUT setting --tlsverify IF YOU DON'T KNOW WHAT YOU'RE DOING [!]"
2020-06-15T14:57:06.053123793Z time="2020-06-15T14:57:06.026221920Z" level=info msg="libcontainerd: started new containerd process" pid=18
2020-06-15T14:57:06.053127090Z time="2020-06-15T14:57:06.026264766Z" level=info msg="parsed scheme: \"unix\"" module=grpc
2020-06-15T14:57:06.053130668Z time="2020-06-15T14:57:06.026273858Z" level=info msg="scheme \"unix\" not registered, fallback to default scheme" module=grpc
2020-06-15T14:57:06.053134266Z time="2020-06-15T14:57:06.026291413Z" level=info msg="ccResolverWrapper: sending update to cc: {[{unix:///var/run/docker/containerd/containerd.sock 0  <nil>}] <nil>}" module=grpc
2020-06-15T14:57:06.053138405Z time="2020-06-15T14:57:06.026300287Z" level=info msg="ClientConn switching balancer to \"pick_first\"" module=grpc
2020-06-15T14:57:06.070874326Z time="2020-06-15T14:57:06.062814106Z" level=info msg="starting containerd" revision=7ad184331fa3e55e52b890ea95e65ba581ae3429 version=v1.2.13 
2020-06-15T14:57:06.070906350Z time="2020-06-15T14:57:06.063125673Z" level=info msg="loading plugin "io.containerd.content.v1.content"..." type=io.containerd.content.v1 
2020-06-15T14:57:06.070911169Z time="2020-06-15T14:57:06.063199865Z" level=info msg="loading plugin "io.containerd.snapshotter.v1.btrfs"..." type=io.containerd.snapshotter.v1 
2020-06-15T14:57:06.070916200Z time="2020-06-15T14:57:06.063379485Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.btrfs" error="path /var/lib/docker/containerd/daemon/io.containerd.snapshotter.v1.btrfs must be a btrfs filesystem to be used with the btrfs snapshotter" 
2020-06-15T14:57:06.070922237Z time="2020-06-15T14:57:06.063393353Z" level=info msg="loading plugin "io.containerd.snapshotter.v1.aufs"..." type=io.containerd.snapshotter.v1 
2020-06-15T14:57:06.132863506Z time="2020-06-15T14:57:06.079914727Z" level=warning msg="failed to load 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" 
2020-06-15T14:57:06.132899668Z time="2020-06-15T14:57:06.079939603Z" level=info msg="loading plugin "io.containerd.snapshotter.v1.native"..." type=io.containerd.snapshotter.v1 
2020-06-15T14:57:06.132904786Z time="2020-06-15T14:57:06.080054630Z" level=info msg="loading plugin "io.containerd.snapshotter.v1.overlayfs"..." type=io.containerd.snapshotter.v1 
2020-06-15T14:57:06.132908645Z time="2020-06-15T14:57:06.080194064Z" level=info msg="loading plugin "io.containerd.snapshotter.v1.zfs"..." type=io.containerd.snapshotter.v1 
2020-06-15T14:57:06.132912290Z time="2020-06-15T14:57:06.080421213Z" level=info msg="skip loading plugin "io.containerd.snapshotter.v1.zfs"..." type=io.containerd.snapshotter.v1 
2020-06-15T14:57:06.132916179Z time="2020-06-15T14:57:06.080429884Z" level=info msg="loading plugin "io.containerd.metadata.v1.bolt"..." type=io.containerd.metadata.v1 
2020-06-15T14:57:06.132936739Z time="2020-06-15T14:57:06.080469679Z" level=warning msg="could not use snapshotter zfs in metadata plugin" 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" 
2020-06-15T14:57:06.132940991Z time="2020-06-15T14:57:06.080476919Z" level=warning msg="could not use snapshotter btrfs in metadata plugin" error="path /var/lib/docker/containerd/daemon/io.containerd.snapshotter.v1.btrfs must be a btrfs filesystem to be used with the btrfs snapshotter" 
2020-06-15T14:57:06.132944771Z time="2020-06-15T14:57:06.080482601Z" level=warning msg="could not use snapshotter aufs in metadata plugin" 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" 
2020-06-15T14:57:06.133089883Z time="2020-06-15T14:57:06.109875902Z" level=info msg="loading plugin "io.containerd.differ.v1.walking"..." type=io.containerd.differ.v1 
2020-06-15T14:57:06.133097059Z time="2020-06-15T14:57:06.109930919Z" level=info msg="loading plugin "io.containerd.gc.v1.scheduler"..." type=io.containerd.gc.v1 
2020-06-15T14:57:06.133100561Z time="2020-06-15T14:57:06.109994019Z" level=info msg="loading plugin "io.containerd.service.v1.containers-service"..." type=io.containerd.service.v1 
2020-06-15T14:57:06.133113987Z time="2020-06-15T14:57:06.110009376Z" level=info msg="loading plugin "io.containerd.service.v1.content-service"..." type=io.containerd.service.v1 
2020-06-15T14:57:06.133117726Z time="2020-06-15T14:57:06.110020059Z" level=info msg="loading plugin "io.containerd.service.v1.diff-service"..." type=io.containerd.service.v1 
2020-06-15T14:57:06.133120980Z time="2020-06-15T14:57:06.110031436Z" level=info msg="loading plugin "io.containerd.service.v1.images-service"..." type=io.containerd.service.v1 
2020-06-15T14:57:06.133124266Z time="2020-06-15T14:57:06.110043930Z" level=info msg="loading plugin "io.containerd.service.v1.leases-service"..." type=io.containerd.service.v1 
2020-06-15T14:57:06.133127446Z time="2020-06-15T14:57:06.110054979Z" level=info msg="loading plugin "io.containerd.service.v1.namespaces-service"..." type=io.containerd.service.v1 
2020-06-15T14:57:06.133130779Z time="2020-06-15T14:57:06.110075523Z" level=info msg="loading plugin "io.containerd.service.v1.snapshots-service"..." type=io.containerd.service.v1 
2020-06-15T14:57:06.133133995Z time="2020-06-15T14:57:06.110086901Z" level=info msg="loading plugin "io.containerd.runtime.v1.linux"..." type=io.containerd.runtime.v1 
2020-06-15T14:57:06.133137283Z time="2020-06-15T14:57:06.110462285Z" level=info msg="loading plugin "io.containerd.runtime.v2.task"..." type=io.containerd.runtime.v2 
2020-06-15T14:57:06.133140494Z time="2020-06-15T14:57:06.110609670Z" level=info msg="loading plugin "io.containerd.monitor.v1.cgroups"..." type=io.containerd.monitor.v1 
2020-06-15T14:57:06.133143853Z time="2020-06-15T14:57:06.111021101Z" level=info msg="loading plugin "io.containerd.service.v1.tasks-service"..." type=io.containerd.service.v1 
2020-06-15T14:57:06.133147087Z time="2020-06-15T14:57:06.111048698Z" level=info msg="loading plugin "io.containerd.internal.v1.restart"..." type=io.containerd.internal.v1 
2020-06-15T14:57:06.133155916Z time="2020-06-15T14:57:06.111095951Z" level=info msg="loading plugin "io.containerd.grpc.v1.containers"..." type=io.containerd.grpc.v1 
2020-06-15T14:57:06.133159386Z time="2020-06-15T14:57:06.111107980Z" level=info msg="loading plugin "io.containerd.grpc.v1.content"..." type=io.containerd.grpc.v1 
2020-06-15T14:57:06.133162588Z time="2020-06-15T14:57:06.111118077Z" level=info msg="loading plugin "io.containerd.grpc.v1.diff"..." type=io.containerd.grpc.v1 
2020-06-15T14:57:06.133165769Z time="2020-06-15T14:57:06.111128720Z" level=info msg="loading plugin "io.containerd.grpc.v1.events"..." type=io.containerd.grpc.v1 
2020-06-15T14:57:06.133168963Z time="2020-06-15T14:57:06.111140050Z" level=info msg="loading plugin "io.containerd.grpc.v1.healthcheck"..." type=io.containerd.grpc.v1 
2020-06-15T14:57:06.133172183Z time="2020-06-15T14:57:06.111151255Z" level=info msg="loading plugin "io.containerd.grpc.v1.images"..." type=io.containerd.grpc.v1 
2020-06-15T14:57:06.133175356Z time="2020-06-15T14:57:06.111173319Z" level=info msg="loading plugin "io.containerd.grpc.v1.leases"..." type=io.containerd.grpc.v1 
2020-06-15T14:57:06.133178516Z time="2020-06-15T14:57:06.111184529Z" level=info msg="loading plugin "io.containerd.grpc.v1.namespaces"..." type=io.containerd.grpc.v1 
2020-06-15T14:57:06.133181726Z time="2020-06-15T14:57:06.111193887Z" level=info msg="loading plugin "io.containerd.internal.v1.opt"..." type=io.containerd.internal.v1 
2020-06-15T14:57:06.133184964Z time="2020-06-15T14:57:06.111472539Z" level=info msg="loading plugin "io.containerd.grpc.v1.snapshots"..." type=io.containerd.grpc.v1 
2020-06-15T14:57:06.133188155Z time="2020-06-15T14:57:06.111500683Z" level=info msg="loading plugin "io.containerd.grpc.v1.tasks"..." type=io.containerd.grpc.v1 
2020-06-15T14:57:06.133191340Z time="2020-06-15T14:57:06.111511182Z" level=info msg="loading plugin "io.containerd.grpc.v1.version"..." type=io.containerd.grpc.v1 
2020-06-15T14:57:06.133194546Z time="2020-06-15T14:57:06.111520708Z" level=info msg="loading plugin "io.containerd.grpc.v1.introspection"..." type=io.containerd.grpc.v1 
2020-06-15T14:57:06.133197837Z time="2020-06-15T14:57:06.111970006Z" level=info msg=serving... address="/var/run/docker/containerd/containerd-debug.sock" 
2020-06-15T14:57:06.133201069Z time="2020-06-15T14:57:06.112053949Z" level=info msg=serving... address="/var/run/docker/containerd/containerd.sock" 
2020-06-15T14:57:06.133204337Z time="2020-06-15T14:57:06.112064401Z" level=info msg="containerd successfully booted in 0.049867s" 
2020-06-15T14:57:06.133207860Z time="2020-06-15T14:57:06.122041094Z" level=info msg="Setting the storage driver from the $DOCKER_DRIVER environment variable (overlay2)"
2020-06-15T14:57:06.133211159Z time="2020-06-15T14:57:06.122244488Z" level=info msg="parsed scheme: \"unix\"" module=grpc
2020-06-15T14:57:06.133214460Z time="2020-06-15T14:57:06.122258637Z" level=info msg="scheme \"unix\" not registered, fallback to default scheme" module=grpc
2020-06-15T14:57:06.133218154Z time="2020-06-15T14:57:06.122275358Z" level=info msg="ccResolverWrapper: sending update to cc: {[{unix:///var/run/docker/containerd/containerd.sock 0  <nil>}] <nil>}" module=grpc
2020-06-15T14:57:06.133225086Z time="2020-06-15T14:57:06.122283999Z" level=info msg="ClientConn switching balancer to \"pick_first\"" module=grpc
2020-06-15T14:57:06.135935096Z time="2020-06-15T14:57:06.133937681Z" level=info msg="parsed scheme: \"unix\"" module=grpc
2020-06-15T14:57:06.135954296Z time="2020-06-15T14:57:06.133962429Z" level=info msg="scheme \"unix\" not registered, fallback to default scheme" module=grpc
2020-06-15T14:57:06.135958917Z time="2020-06-15T14:57:06.133980983Z" level=info msg="ccResolverWrapper: sending update to cc: {[{unix:///var/run/docker/containerd/containerd.sock 0  <nil>}] <nil>}" module=grpc
2020-06-15T14:57:06.135981595Z time="2020-06-15T14:57:06.133990091Z" level=info msg="ClientConn switching balancer to \"pick_first\"" module=grpc
2020-06-15T14:57:06.189338199Z time="2020-06-15T14:57:06.188010412Z" level=info msg="Loading containers: start."
2020-06-15T14:57:06.214903136Z time="2020-06-15T14:57:06.214746513Z" level=warning msg="Running modprobe bridge br_netfilter failed with message: ip: can't find device 'bridge'\nbridge                167936  1 br_netfilter\nstp                    16384  1 bridge\nllc                    16384  2 bridge,stp\nip: can't find device 'br_netfilter'\nbr_netfilter           24576  0 \nbridge                167936  1 br_netfilter\nmodprobe: can't change directory to '/lib/modules': No such file or directory\n, error: exit status 1"
2020-06-15T14:57:06.316375506Z time="2020-06-15T14:57:06.314271192Z" level=info msg="Default bridge (docker0) is assigned with an IP address 172.18.0.0/16. Daemon option --bip can be used to set a preferred IP address"
2020-06-15T14:57:06.392247583Z time="2020-06-15T14:57:06.388930133Z" level=info msg="Loading containers: done."
2020-06-15T14:57:06.405821487Z time="2020-06-15T14:57:06.405469992Z" level=info msg="Docker daemon" commit=42e35e61f3 graphdriver(s)=overlay2 version=19.03.11
2020-06-15T14:57:06.405840761Z time="2020-06-15T14:57:06.405608352Z" level=info msg="Daemon has completed initialization"
2020-06-15T14:57:06.438734345Z time="2020-06-15T14:57:06.438529206Z" level=info msg="API listen on [::]:2375"
2020-06-15T14:57:06.438800031Z time="2020-06-15T14:57:06.438605147Z" level=info msg="API listen on /var/run/docker.sock"

*********

Pulling docker image registry.gitlab.com/gitlab-org/cluster-integration/auto-build-image:v0.2.3 ...
Using docker image sha256:bf382c64e562582d759777009ed710114ab264654503261a7f6a95e0f5267b4f for registry.gitlab.com/gitlab-org/cluster-integration/auto-build-image:v0.2.3 ...
section_end:1592233058:prepare_executor
section_start:1592233058:prepare_script
Preparing environment
Running on runner-fa6cab46-project-4422333-concurrent-0 via runner-fa6cab46-stg-srm-1592232959-a9b93af0...
section_end:1592233060:prepare_script
section_start:1592233060:get_sources
Getting source from Git repository
$ eval "$CI_PRE_CLONE_SCRIPT"
Fetching changes with git depth set to 50...
Initialized empty Git repository in /builds/gitlab-org/monitor/monitor-sandbox/.git/
Created fresh repository.
From https://ci-api.gstg.gitlab.net/gitlab-org/monitor/monitor-sandbox
 * [new ref]         refs/pipelines/12784624 -> refs/pipelines/12784624
 * [new branch]      master                  -> origin/master
Checking out 8186baa9 as master...

Skipping Git submodules setup
section_end:1592233062:get_sources
section_start:1592233062:restore_cache
Restoring cache
section_end:1592233063:restore_cache
section_start:1592233063:download_artifacts
Downloading artifacts
section_end:1592233065:download_artifacts
section_start:1592233065:build_script
Running before_script and script
$ if [[ -z "$CI_COMMIT_TAG" ]]; then # collapsed multi-line command
$ /build/build.sh
Logging to GitLab Container Registry with CI credentials...
WARNING! Your password will be stored unencrypted in /root/.docker/config.json.
Configure a credential helper to remove this warning. See
https://docs.docker.com/engine/reference/commandline/login/#credentials-store

Login Succeeded
Building Dockerfile-based application...
Error response from daemon: manifest for registry.staging.gitlab.com/gitlab-org/monitor/monitor-sandbox/master:0000000000000000000000000000000000000000 not found: manifest unknown: manifest unknown
latest: Pulling from gitlab-org/monitor/monitor-sandbox/master
a073c86ecf9e: Pulling fs layer
db7179d8c6cd: Pulling fs layer
66b9cfaecc8c: Pulling fs layer
a4d4613b850e: Pulling fs layer
5fb493aa739b: Pulling fs layer
8b7e2ad468ac: Pulling fs layer
bcaa53c39be9: Pulling fs layer
a4d4613b850e: Waiting
5fb493aa739b: Waiting
8b7e2ad468ac: Waiting
bcaa53c39be9: Waiting
66b9cfaecc8c: Verifying Checksum
66b9cfaecc8c: Download complete
a073c86ecf9e: Verifying Checksum
a073c86ecf9e: Download complete
a4d4613b850e: Verifying Checksum
a4d4613b850e: Download complete
5fb493aa739b: Verifying Checksum
5fb493aa739b: Download complete
bcaa53c39be9: Verifying Checksum
bcaa53c39be9: Download complete
db7179d8c6cd: Verifying Checksum
db7179d8c6cd: Download complete
a073c86ecf9e: Pull complete
8b7e2ad468ac: Verifying Checksum
8b7e2ad468ac: Download complete
db7179d8c6cd: Pull complete
66b9cfaecc8c: Pull complete
a4d4613b850e: Pull complete
5fb493aa739b: Pull complete
8b7e2ad468ac: Pull complete
bcaa53c39be9: Pull complete
Digest: sha256:30c54c7059b250e021964c65446c9c16dd0238d2d3872df8bec36be78d6237ff
Status: Downloaded newer image for registry.staging.gitlab.com/gitlab-org/monitor/monitor-sandbox/master:latest
registry.staging.gitlab.com/gitlab-org/monitor/monitor-sandbox/master:latest
Sending build context to Docker daemon  139.3kB

Step 1/10 : FROM node:8.11-alpine
8.11-alpine: Pulling from library/node
a073c86ecf9e: Already exists
db7179d8c6cd: Already exists
66b9cfaecc8c: Already exists
Digest: sha256:1c38d992f89ae389450bc69799a0f2b7d1f9f2e6c445080a4648448e6449e8e4
Status: Downloaded newer image for node:8.11-alpine
 ---> 8adf3c3eb26c
Step 2/10 : WORKDIR /usr/src/app
 ---> Using cache
 ---> 1de956eb2cee
Step 3/10 : ARG NODE_ENV
 ---> Using cache
 ---> 1a19938d7e95
Step 4/10 : ENV NODE_ENV $NODE_ENV
 ---> Using cache
 ---> 1b79d9aea042
Step 5/10 : COPY package.json /usr/src/app/
 ---> Using cache
 ---> a9fb416ed36f
Step 6/10 : RUN npm install
 ---> Using cache
 ---> 92ea06787b28
Step 7/10 : COPY . /usr/src/app
 ---> 60ddd3f2e0de
Step 8/10 : ENV PORT 5000
 ---> Running in 71a13f2ef095
Removing intermediate container 71a13f2ef095
 ---> 8a1801d46913
Step 9/10 : EXPOSE $PORT
 ---> Running in a67876194ae0
Removing intermediate container a67876194ae0
 ---> dcc9eacc3b2a
Step 10/10 : CMD [ "npm", "start" ]
 ---> Running in c6ec0a49f686
Removing intermediate container c6ec0a49f686
 ---> 46f2370faf6d
[Warning] One or more build-args [BUILDPACK_URL] were not consumed
Successfully built 46f2370faf6d
Successfully tagged registry.staging.gitlab.com/gitlab-org/monitor/monitor-sandbox/master:8186baa962344f7c374148aebfda921bf2a438be
Successfully tagged registry.staging.gitlab.com/gitlab-org/monitor/monitor-sandbox/master:latest
The push refers to repository [registry.staging.gitlab.com/gitlab-org/monitor/monitor-sandbox/master]
dc23eaccdb7f: Preparing
8ddc9e599f93: Preparing
a85ff65fd061: Preparing
eaba38c532c6: Preparing
d35df9c923f8: Preparing
a7d484df787a: Preparing
8dfad2055603: Preparing
a7d484df787a: Waiting
8dfad2055603: Waiting
8ddc9e599f93: Layer already exists
eaba38c532c6: Layer already exists
a85ff65fd061: Layer already exists
d35df9c923f8: Layer already exists
a7d484df787a: Layer already exists
8dfad2055603: Layer already exists
dc23eaccdb7f: Pushed
8186baa962344f7c374148aebfda921bf2a438be: digest: sha256:da9836dd22db830147552189babbc3b834155ad4d40247836f4142b08c0a6303 size: 1785
The push refers to repository [registry.staging.gitlab.com/gitlab-org/monitor/monitor-sandbox/master]
dc23eaccdb7f: Preparing
8ddc9e599f93: Preparing
a85ff65fd061: Preparing
eaba38c532c6: Preparing
d35df9c923f8: Preparing
a7d484df787a: Preparing
8dfad2055603: Preparing
a7d484df787a: Waiting
8dfad2055603: Waiting
8ddc9e599f93: Layer already exists
eaba38c532c6: Layer already exists
a85ff65fd061: Layer already exists
d35df9c923f8: Layer already exists
dc23eaccdb7f: Layer already exists
8dfad2055603: Layer already exists
a7d484df787a: Layer already exists
latest: digest: sha256:da9836dd22db830147552189babbc3b834155ad4d40247836f4142b08c0a6303 size: 1785
section_end:1592233084:build_script
section_start:1592233084:after_script
Running after_script
section_end:1592233086:after_script
section_start:1592233086:archive_cache
Saving cache
section_end:1592233087:archive_cache
section_start:1592233087:upload_artifacts_on_success
Uploading artifacts for successful job
section_end:1592233088:upload_artifacts_on_success
Job succeeded