Running with gitlab-runner 13.1.0-rc1 (b9d289ed)
  on docker-auto-scale 72989761
section_start:1592494287: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-72989761-project-4422333-concurrent-0-2fdf3fd6ffc44927-docker-0 probably didn't start properly.

Health check error:
service "runner-72989761-project-4422333-concurrent-0-2fdf3fd6ffc44927-docker-0-wait-for-service" timeout

Health check container logs:


Service container logs:
2020-06-18T15:32:33.163079920Z time="2020-06-18T15:32:33.159518024Z" level=info msg="Starting up"
2020-06-18T15:32:33.209612722Z time="2020-06-18T15:32:33.163192647Z" level=warning msg="could not change group /var/run/docker.sock to docker: group docker not found"
2020-06-18T15:32:33.209632587Z time="2020-06-18T15:32:33.163414194Z" level=warning msg="[!] DON'T BIND ON ANY IP ADDRESS WITHOUT setting --tlsverify IF YOU DON'T KNOW WHAT YOU'RE DOING [!]"
2020-06-18T15:32:33.209636886Z time="2020-06-18T15:32:33.165527843Z" level=info msg="libcontainerd: started new containerd process" pid=18
2020-06-18T15:32:33.209647125Z time="2020-06-18T15:32:33.165571891Z" level=info msg="parsed scheme: \"unix\"" module=grpc
2020-06-18T15:32:33.209651152Z time="2020-06-18T15:32:33.165580243Z" level=info msg="scheme \"unix\" not registered, fallback to default scheme" module=grpc
2020-06-18T15:32:33.209654662Z time="2020-06-18T15:32:33.165603909Z" level=info msg="ccResolverWrapper: sending update to cc: {[{unix:///var/run/docker/containerd/containerd.sock 0  <nil>}] <nil>}" module=grpc
2020-06-18T15:32:33.209658876Z time="2020-06-18T15:32:33.165612812Z" level=info msg="ClientConn switching balancer to \"pick_first\"" module=grpc
2020-06-18T15:32:33.209662386Z time="2020-06-18T15:32:33.199680707Z" level=info msg="starting containerd" revision=7ad184331fa3e55e52b890ea95e65ba581ae3429 version=v1.2.13 
2020-06-18T15:32:33.209665651Z time="2020-06-18T15:32:33.199979389Z" level=info msg="loading plugin "io.containerd.content.v1.content"..." type=io.containerd.content.v1 
2020-06-18T15:32:33.209668943Z time="2020-06-18T15:32:33.200057863Z" level=info msg="loading plugin "io.containerd.snapshotter.v1.btrfs"..." type=io.containerd.snapshotter.v1 
2020-06-18T15:32:33.209672796Z time="2020-06-18T15:32:33.200233260Z" 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-18T15:32:33.209678574Z time="2020-06-18T15:32:33.200245543Z" level=info msg="loading plugin "io.containerd.snapshotter.v1.aufs"..." type=io.containerd.snapshotter.v1 
2020-06-18T15:32:33.236615054Z time="2020-06-18T15:32:33.235162797Z" 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-18T15:32:33.236634394Z time="2020-06-18T15:32:33.235187248Z" level=info msg="loading plugin "io.containerd.snapshotter.v1.native"..." type=io.containerd.snapshotter.v1 
2020-06-18T15:32:33.236639387Z time="2020-06-18T15:32:33.235297031Z" level=info msg="loading plugin "io.containerd.snapshotter.v1.overlayfs"..." type=io.containerd.snapshotter.v1 
2020-06-18T15:32:33.236651225Z time="2020-06-18T15:32:33.235501250Z" level=info msg="loading plugin "io.containerd.snapshotter.v1.zfs"..." type=io.containerd.snapshotter.v1 
2020-06-18T15:32:33.236654871Z time="2020-06-18T15:32:33.235740403Z" level=info msg="skip loading plugin "io.containerd.snapshotter.v1.zfs"..." type=io.containerd.snapshotter.v1 
2020-06-18T15:32:33.236658382Z time="2020-06-18T15:32:33.235750019Z" level=info msg="loading plugin "io.containerd.metadata.v1.bolt"..." type=io.containerd.metadata.v1 
2020-06-18T15:32:33.236669070Z time="2020-06-18T15:32:33.235792478Z" 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-18T15:32:33.236673129Z time="2020-06-18T15:32:33.235799325Z" 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-18T15:32:33.236677023Z time="2020-06-18T15:32:33.235806009Z" 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-18T15:32:33.276033677Z time="2020-06-18T15:32:33.267010694Z" level=info msg="loading plugin "io.containerd.differ.v1.walking"..." type=io.containerd.differ.v1 
2020-06-18T15:32:33.276052806Z time="2020-06-18T15:32:33.267041699Z" level=info msg="loading plugin "io.containerd.gc.v1.scheduler"..." type=io.containerd.gc.v1 
2020-06-18T15:32:33.276057014Z time="2020-06-18T15:32:33.267070327Z" level=info msg="loading plugin "io.containerd.service.v1.containers-service"..." type=io.containerd.service.v1 
2020-06-18T15:32:33.276060760Z time="2020-06-18T15:32:33.267082637Z" level=info msg="loading plugin "io.containerd.service.v1.content-service"..." type=io.containerd.service.v1 
2020-06-18T15:32:33.276064286Z time="2020-06-18T15:32:33.267092617Z" level=info msg="loading plugin "io.containerd.service.v1.diff-service"..." type=io.containerd.service.v1 
2020-06-18T15:32:33.276067793Z time="2020-06-18T15:32:33.267103885Z" level=info msg="loading plugin "io.containerd.service.v1.images-service"..." type=io.containerd.service.v1 
2020-06-18T15:32:33.276071151Z time="2020-06-18T15:32:33.267115711Z" level=info msg="loading plugin "io.containerd.service.v1.leases-service"..." type=io.containerd.service.v1 
2020-06-18T15:32:33.276074335Z time="2020-06-18T15:32:33.267126172Z" level=info msg="loading plugin "io.containerd.service.v1.namespaces-service"..." type=io.containerd.service.v1 
2020-06-18T15:32:33.276077663Z time="2020-06-18T15:32:33.267136145Z" level=info msg="loading plugin "io.containerd.service.v1.snapshots-service"..." type=io.containerd.service.v1 
2020-06-18T15:32:33.276080863Z time="2020-06-18T15:32:33.267146605Z" level=info msg="loading plugin "io.containerd.runtime.v1.linux"..." type=io.containerd.runtime.v1 
2020-06-18T15:32:33.276084306Z time="2020-06-18T15:32:33.267323086Z" level=info msg="loading plugin "io.containerd.runtime.v2.task"..." type=io.containerd.runtime.v2 
2020-06-18T15:32:33.276087500Z time="2020-06-18T15:32:33.267463182Z" level=info msg="loading plugin "io.containerd.monitor.v1.cgroups"..." type=io.containerd.monitor.v1 
2020-06-18T15:32:33.276090765Z time="2020-06-18T15:32:33.267790203Z" level=info msg="loading plugin "io.containerd.service.v1.tasks-service"..." type=io.containerd.service.v1 
2020-06-18T15:32:33.276093971Z time="2020-06-18T15:32:33.267813003Z" level=info msg="loading plugin "io.containerd.internal.v1.restart"..." type=io.containerd.internal.v1 
2020-06-18T15:32:33.276106508Z time="2020-06-18T15:32:33.267849312Z" level=info msg="loading plugin "io.containerd.grpc.v1.containers"..." type=io.containerd.grpc.v1 
2020-06-18T15:32:33.276109989Z time="2020-06-18T15:32:33.267860376Z" level=info msg="loading plugin "io.containerd.grpc.v1.content"..." type=io.containerd.grpc.v1 
2020-06-18T15:32:33.276113122Z time="2020-06-18T15:32:33.267870673Z" level=info msg="loading plugin "io.containerd.grpc.v1.diff"..." type=io.containerd.grpc.v1 
2020-06-18T15:32:33.276116314Z time="2020-06-18T15:32:33.267881052Z" level=info msg="loading plugin "io.containerd.grpc.v1.events"..." type=io.containerd.grpc.v1 
2020-06-18T15:32:33.276119741Z time="2020-06-18T15:32:33.267891456Z" level=info msg="loading plugin "io.containerd.grpc.v1.healthcheck"..." type=io.containerd.grpc.v1 
2020-06-18T15:32:33.276122909Z time="2020-06-18T15:32:33.267902052Z" level=info msg="loading plugin "io.containerd.grpc.v1.images"..." type=io.containerd.grpc.v1 
2020-06-18T15:32:33.276126106Z time="2020-06-18T15:32:33.267911254Z" level=info msg="loading plugin "io.containerd.grpc.v1.leases"..." type=io.containerd.grpc.v1 
2020-06-18T15:32:33.276129239Z time="2020-06-18T15:32:33.267921492Z" level=info msg="loading plugin "io.containerd.grpc.v1.namespaces"..." type=io.containerd.grpc.v1 
2020-06-18T15:32:33.276132737Z time="2020-06-18T15:32:33.267930319Z" level=info msg="loading plugin "io.containerd.internal.v1.opt"..." type=io.containerd.internal.v1 
2020-06-18T15:32:33.276136088Z time="2020-06-18T15:32:33.268154995Z" level=info msg="loading plugin "io.containerd.grpc.v1.snapshots"..." type=io.containerd.grpc.v1 
2020-06-18T15:32:33.276139302Z time="2020-06-18T15:32:33.268168002Z" level=info msg="loading plugin "io.containerd.grpc.v1.tasks"..." type=io.containerd.grpc.v1 
2020-06-18T15:32:33.276142437Z time="2020-06-18T15:32:33.268177829Z" level=info msg="loading plugin "io.containerd.grpc.v1.version"..." type=io.containerd.grpc.v1 
2020-06-18T15:32:33.276145656Z time="2020-06-18T15:32:33.268187517Z" level=info msg="loading plugin "io.containerd.grpc.v1.introspection"..." type=io.containerd.grpc.v1 
2020-06-18T15:32:33.276148867Z time="2020-06-18T15:32:33.268487700Z" level=info msg=serving... address="/var/run/docker/containerd/containerd-debug.sock" 
2020-06-18T15:32:33.276152164Z time="2020-06-18T15:32:33.268570043Z" level=info msg=serving... address="/var/run/docker/containerd/containerd.sock" 
2020-06-18T15:32:33.276155342Z time="2020-06-18T15:32:33.268579249Z" level=info msg="containerd successfully booted in 0.070532s" 
2020-06-18T15:32:33.280527468Z time="2020-06-18T15:32:33.278771424Z" level=info msg="Setting the storage driver from the $DOCKER_DRIVER environment variable (overlay2)"
2020-06-18T15:32:33.280550919Z time="2020-06-18T15:32:33.279014824Z" level=info msg="parsed scheme: \"unix\"" module=grpc
2020-06-18T15:32:33.280555204Z time="2020-06-18T15:32:33.279039358Z" level=info msg="scheme \"unix\" not registered, fallback to default scheme" module=grpc
2020-06-18T15:32:33.280559011Z time="2020-06-18T15:32:33.279056355Z" level=info msg="ccResolverWrapper: sending update to cc: {[{unix:///var/run/docker/containerd/containerd.sock 0  <nil>}] <nil>}" module=grpc
2020-06-18T15:32:33.280579364Z time="2020-06-18T15:32:33.279065329Z" level=info msg="ClientConn switching balancer to \"pick_first\"" module=grpc
2020-06-18T15:32:33.287307872Z time="2020-06-18T15:32:33.287201308Z" level=info msg="parsed scheme: \"unix\"" module=grpc
2020-06-18T15:32:33.287386517Z time="2020-06-18T15:32:33.287340010Z" level=info msg="scheme \"unix\" not registered, fallback to default scheme" module=grpc
2020-06-18T15:32:33.287477950Z time="2020-06-18T15:32:33.287417399Z" level=info msg="ccResolverWrapper: sending update to cc: {[{unix:///var/run/docker/containerd/containerd.sock 0  <nil>}] <nil>}" module=grpc
2020-06-18T15:32:33.287561333Z time="2020-06-18T15:32:33.287509372Z" level=info msg="ClientConn switching balancer to \"pick_first\"" module=grpc
2020-06-18T15:32:33.369638555Z time="2020-06-18T15:32:33.368200125Z" level=info msg="Loading containers: start."
2020-06-18T15:32:33.392118939Z time="2020-06-18T15:32:33.391994914Z" 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-18T15:32:33.509540634Z time="2020-06-18T15:32:33.508084067Z" 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-18T15:32:33.561500325Z time="2020-06-18T15:32:33.558078305Z" level=info msg="Loading containers: done."
2020-06-18T15:32:33.579915926Z time="2020-06-18T15:32:33.579520423Z" level=info msg="Docker daemon" commit=42e35e61f3 graphdriver(s)=overlay2 version=19.03.11
2020-06-18T15:32:33.579968398Z time="2020-06-18T15:32:33.579698267Z" level=info msg="Daemon has completed initialization"
2020-06-18T15:32:33.636369299Z time="2020-06-18T15:32:33.635951512Z" level=info msg="API listen on [::]:2375"
2020-06-18T15:32:33.636391851Z time="2020-06-18T15:32:33.636027593Z" 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:1592494386:prepare_executor
section_start:1592494386:prepare_script
Preparing environment
Running on runner-72989761-project-4422333-concurrent-0 via runner-72989761-stg-srm-1592494287-26382c47...
section_end:1592494388:prepare_script
section_start:1592494388: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.
Checking out 2aa62563 as master...

Skipping Git submodules setup
section_end:1592494389:get_sources
section_start:1592494389:step_script
Executing "step_script" stage of the job 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...
8186baa962344f7c374148aebfda921bf2a438be: 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
e2dd601c2848: Pulling fs layer
a4d4613b850e: Waiting
5fb493aa739b: Waiting
8b7e2ad468ac: Waiting
e2dd601c2848: Waiting
a073c86ecf9e: Verifying Checksum
a073c86ecf9e: Download complete
66b9cfaecc8c: Verifying Checksum
66b9cfaecc8c: Download complete
a4d4613b850e: Verifying Checksum
a4d4613b850e: Download complete
5fb493aa739b: Verifying Checksum
5fb493aa739b: Download complete
a073c86ecf9e: Pull complete
e2dd601c2848: Verifying Checksum
e2dd601c2848: Download complete
8b7e2ad468ac: Verifying Checksum
8b7e2ad468ac: Download complete
db7179d8c6cd: Verifying Checksum
db7179d8c6cd: Download complete
db7179d8c6cd: Pull complete
66b9cfaecc8c: Pull complete
a4d4613b850e: Pull complete
5fb493aa739b: Pull complete
8b7e2ad468ac: Pull complete
e2dd601c2848: Pull complete
Digest: sha256:e249620c86207a18d6e036b07c5c9b1e4600301bc2ed78178fa823fc6e30d266
Status: Downloaded newer image for registry.staging.gitlab.com/gitlab-org/monitor/monitor-sandbox/master:8186baa962344f7c374148aebfda921bf2a438be
registry.staging.gitlab.com/gitlab-org/monitor/monitor-sandbox/master:8186baa962344f7c374148aebfda921bf2a438be
Sending build context to Docker daemon  141.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
 ---> e56eacf76746
Step 8/10 : ENV PORT 5000
 ---> Running in 77b789acd95c
Removing intermediate container 77b789acd95c
 ---> eb7a6fbd88b3
Step 9/10 : EXPOSE $PORT
 ---> Running in ecc1b5978ce6
Removing intermediate container ecc1b5978ce6
 ---> 6635673c7a09
Step 10/10 : CMD [ "npm", "start" ]
 ---> Running in 862f1edb596c
Removing intermediate container 862f1edb596c
 ---> 5ee1c15233a1
[Warning] One or more build-args [BUILDPACK_URL] were not consumed
Successfully built 5ee1c15233a1
Successfully tagged registry.staging.gitlab.com/gitlab-org/monitor/monitor-sandbox/master:2aa625630104304b3710b49d6c00c9ff3161d67f
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]
120539355601: Preparing
8ddc9e599f93: Preparing
a85ff65fd061: Preparing
eaba38c532c6: Preparing
d35df9c923f8: Preparing
a7d484df787a: Preparing
8dfad2055603: Preparing
a7d484df787a: Waiting
8dfad2055603: Waiting
eaba38c532c6: Layer already exists
a85ff65fd061: Layer already exists
8ddc9e599f93: Layer already exists
d35df9c923f8: Layer already exists
a7d484df787a: Layer already exists
8dfad2055603: Layer already exists
120539355601: Pushed
2aa625630104304b3710b49d6c00c9ff3161d67f: digest: sha256:b3b124d33ea585b4497f2009012972295874d1714e997af88995dc34cfbb1753 size: 1785
The push refers to repository [registry.staging.gitlab.com/gitlab-org/monitor/monitor-sandbox/master]
120539355601: Preparing
8ddc9e599f93: Preparing
a85ff65fd061: Preparing
eaba38c532c6: Preparing
d35df9c923f8: Preparing
a7d484df787a: Preparing
8dfad2055603: Preparing
a7d484df787a: Waiting
8dfad2055603: Waiting
120539355601: Layer already exists
8ddc9e599f93: Layer already exists
d35df9c923f8: Layer already exists
eaba38c532c6: Layer already exists
a85ff65fd061: Layer already exists
8dfad2055603: Layer already exists
a7d484df787a: Layer already exists
latest: digest: sha256:b3b124d33ea585b4497f2009012972295874d1714e997af88995dc34cfbb1753 size: 1785
section_end:1592494408:step_script
Job succeeded