[0KRunning with gitlab-runner 13.0.0-rc2 (926834bc)
[0;m[0K on docker-auto-scale 72989761
[0;msection_start:1589920075:prepare_executor
[0K[0K[36;1mPreparing the "docker+machine" executor[0;m
[0;m[0KUsing Docker executor with image registry.gitlab.com/gitlab-org/cluster-integration/auto-build-image:v0.2.2 ...
[0;m[0KStarting service docker:19.03.8-dind ...
[0;m[0KPulling docker image docker:19.03.8-dind ...
[0;m[0KUsing docker image sha256:c814ba3a41a3de0a9a23b7d0bb36f64257b12aef5103b4ce1d5f1bfc3033aad3 for docker:19.03.8-dind ...
[0;m[0KWaiting for services to be up and running...
[0;m
[0;33m*** WARNING:[0;m Service runner-72989761-project-4422333-concurrent-0-4958968bcefb5799-docker-0 probably didn't start properly.
Health check error:
service "runner-72989761-project-4422333-concurrent-0-4958968bcefb5799-docker-0-wait-for-service" timeout
Health check container logs:
Service container logs:
2020-05-19T20:29:04.620885835Z time="2020-05-19T20:29:04.620365798Z" level=info msg="Starting up"
2020-05-19T20:29:04.622125640Z time="2020-05-19T20:29:04.622046399Z" level=warning msg="could not change group /var/run/docker.sock to docker: group docker not found"
2020-05-19T20:29:04.622410524Z time="2020-05-19T20:29:04.622356769Z" level=warning msg="[!] DON'T BIND ON ANY IP ADDRESS WITHOUT setting --tlsverify IF YOU DON'T KNOW WHAT YOU'RE DOING [!]"
2020-05-19T20:29:04.633030274Z time="2020-05-19T20:29:04.630545393Z" level=info msg="libcontainerd: started new containerd process" pid=18
2020-05-19T20:29:04.635919463Z time="2020-05-19T20:29:04.630604896Z" level=info msg="parsed scheme: \"unix\"" module=grpc
2020-05-19T20:29:04.635964419Z time="2020-05-19T20:29:04.630614002Z" level=info msg="scheme \"unix\" not registered, fallback to default scheme" module=grpc
2020-05-19T20:29:04.635969302Z time="2020-05-19T20:29:04.630633813Z" level=info msg="ccResolverWrapper: sending update to cc: {[{unix:///var/run/docker/containerd/containerd.sock 0 <nil>}] <nil>}" module=grpc
2020-05-19T20:29:04.635973899Z time="2020-05-19T20:29:04.630643079Z" level=info msg="ClientConn switching balancer to \"pick_first\"" module=grpc
2020-05-19T20:29:04.667356394Z time="2020-05-19T20:29:04.667230877Z" level=info msg="starting containerd" revision=7ad184331fa3e55e52b890ea95e65ba581ae3429 version=v1.2.13
2020-05-19T20:29:04.669237692Z time="2020-05-19T20:29:04.669161175Z" level=info msg="loading plugin "io.containerd.content.v1.content"..." type=io.containerd.content.v1
2020-05-19T20:29:04.670284345Z time="2020-05-19T20:29:04.670203536Z" level=info msg="loading plugin "io.containerd.snapshotter.v1.btrfs"..." type=io.containerd.snapshotter.v1
2020-05-19T20:29:04.670663117Z time="2020-05-19T20:29:04.670598680Z" 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-05-19T20:29:04.670756653Z time="2020-05-19T20:29:04.670693257Z" level=info msg="loading plugin "io.containerd.snapshotter.v1.aufs"..." type=io.containerd.snapshotter.v1
2020-05-19T20:29:04.676348968Z time="2020-05-19T20:29:04.676226918Z" 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-05-19T20:29:04.680165810Z time="2020-05-19T20:29:04.680058989Z" level=info msg="loading plugin "io.containerd.snapshotter.v1.native"..." type=io.containerd.snapshotter.v1
2020-05-19T20:29:04.683103475Z time="2020-05-19T20:29:04.682470144Z" level=info msg="loading plugin "io.containerd.snapshotter.v1.overlayfs"..." type=io.containerd.snapshotter.v1
2020-05-19T20:29:04.683117300Z time="2020-05-19T20:29:04.682623951Z" level=info msg="loading plugin "io.containerd.snapshotter.v1.zfs"..." type=io.containerd.snapshotter.v1
2020-05-19T20:29:04.683121753Z time="2020-05-19T20:29:04.682854029Z" level=info msg="skip loading plugin "io.containerd.snapshotter.v1.zfs"..." type=io.containerd.snapshotter.v1
2020-05-19T20:29:04.683125667Z time="2020-05-19T20:29:04.682863091Z" level=info msg="loading plugin "io.containerd.metadata.v1.bolt"..." type=io.containerd.metadata.v1
2020-05-19T20:29:04.683137530Z time="2020-05-19T20:29:04.682902051Z" 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-05-19T20:29:04.683141678Z time="2020-05-19T20:29:04.682909409Z" 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-05-19T20:29:04.683145832Z time="2020-05-19T20:29:04.682917049Z" 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-05-19T20:29:04.702545741Z time="2020-05-19T20:29:04.699911634Z" level=info msg="loading plugin "io.containerd.differ.v1.walking"..." type=io.containerd.differ.v1
2020-05-19T20:29:04.702581167Z time="2020-05-19T20:29:04.699946956Z" level=info msg="loading plugin "io.containerd.gc.v1.scheduler"..." type=io.containerd.gc.v1
2020-05-19T20:29:04.702585507Z time="2020-05-19T20:29:04.699977353Z" level=info msg="loading plugin "io.containerd.service.v1.containers-service"..." type=io.containerd.service.v1
2020-05-19T20:29:04.702589167Z time="2020-05-19T20:29:04.699988933Z" level=info msg="loading plugin "io.containerd.service.v1.content-service"..." type=io.containerd.service.v1
2020-05-19T20:29:04.702592672Z time="2020-05-19T20:29:04.699998926Z" level=info msg="loading plugin "io.containerd.service.v1.diff-service"..." type=io.containerd.service.v1
2020-05-19T20:29:04.702596181Z time="2020-05-19T20:29:04.700009660Z" level=info msg="loading plugin "io.containerd.service.v1.images-service"..." type=io.containerd.service.v1
2020-05-19T20:29:04.702599578Z time="2020-05-19T20:29:04.700022222Z" level=info msg="loading plugin "io.containerd.service.v1.leases-service"..." type=io.containerd.service.v1
2020-05-19T20:29:04.702602763Z time="2020-05-19T20:29:04.700032550Z" level=info msg="loading plugin "io.containerd.service.v1.namespaces-service"..." type=io.containerd.service.v1
2020-05-19T20:29:04.702606042Z time="2020-05-19T20:29:04.700109188Z" level=info msg="loading plugin "io.containerd.service.v1.snapshots-service"..." type=io.containerd.service.v1
2020-05-19T20:29:04.702609258Z time="2020-05-19T20:29:04.700122115Z" level=info msg="loading plugin "io.containerd.runtime.v1.linux"..." type=io.containerd.runtime.v1
2020-05-19T20:29:04.702612831Z time="2020-05-19T20:29:04.700338363Z" level=info msg="loading plugin "io.containerd.runtime.v2.task"..." type=io.containerd.runtime.v2
2020-05-19T20:29:04.702616002Z time="2020-05-19T20:29:04.700475328Z" level=info msg="loading plugin "io.containerd.monitor.v1.cgroups"..." type=io.containerd.monitor.v1
2020-05-19T20:29:04.702620686Z time="2020-05-19T20:29:04.700798164Z" level=info msg="loading plugin "io.containerd.service.v1.tasks-service"..." type=io.containerd.service.v1
2020-05-19T20:29:04.702623968Z time="2020-05-19T20:29:04.700825109Z" level=info msg="loading plugin "io.containerd.internal.v1.restart"..." type=io.containerd.internal.v1
2020-05-19T20:29:04.702638458Z time="2020-05-19T20:29:04.700860818Z" level=info msg="loading plugin "io.containerd.grpc.v1.containers"..." type=io.containerd.grpc.v1
2020-05-19T20:29:04.702641965Z time="2020-05-19T20:29:04.700873395Z" level=info msg="loading plugin "io.containerd.grpc.v1.content"..." type=io.containerd.grpc.v1
2020-05-19T20:29:04.702645125Z time="2020-05-19T20:29:04.700890012Z" level=info msg="loading plugin "io.containerd.grpc.v1.diff"..." type=io.containerd.grpc.v1
2020-05-19T20:29:04.702648254Z time="2020-05-19T20:29:04.700900236Z" level=info msg="loading plugin "io.containerd.grpc.v1.events"..." type=io.containerd.grpc.v1
2020-05-19T20:29:04.702651534Z time="2020-05-19T20:29:04.700909874Z" level=info msg="loading plugin "io.containerd.grpc.v1.healthcheck"..." type=io.containerd.grpc.v1
2020-05-19T20:29:04.702654672Z time="2020-05-19T20:29:04.700919754Z" level=info msg="loading plugin "io.containerd.grpc.v1.images"..." type=io.containerd.grpc.v1
2020-05-19T20:29:04.702657872Z time="2020-05-19T20:29:04.700928853Z" level=info msg="loading plugin "io.containerd.grpc.v1.leases"..." type=io.containerd.grpc.v1
2020-05-19T20:29:04.702661004Z time="2020-05-19T20:29:04.700937676Z" level=info msg="loading plugin "io.containerd.grpc.v1.namespaces"..." type=io.containerd.grpc.v1
2020-05-19T20:29:04.702664269Z time="2020-05-19T20:29:04.700947265Z" level=info msg="loading plugin "io.containerd.internal.v1.opt"..." type=io.containerd.internal.v1
2020-05-19T20:29:04.702667490Z time="2020-05-19T20:29:04.701196707Z" level=info msg="loading plugin "io.containerd.grpc.v1.snapshots"..." type=io.containerd.grpc.v1
2020-05-19T20:29:04.702670739Z time="2020-05-19T20:29:04.701210732Z" level=info msg="loading plugin "io.containerd.grpc.v1.tasks"..." type=io.containerd.grpc.v1
2020-05-19T20:29:04.702673958Z time="2020-05-19T20:29:04.701221477Z" level=info msg="loading plugin "io.containerd.grpc.v1.version"..." type=io.containerd.grpc.v1
2020-05-19T20:29:04.702677154Z time="2020-05-19T20:29:04.701234324Z" level=info msg="loading plugin "io.containerd.grpc.v1.introspection"..." type=io.containerd.grpc.v1
2020-05-19T20:29:04.702680425Z time="2020-05-19T20:29:04.701487627Z" level=info msg=serving... address="/var/run/docker/containerd/containerd-debug.sock"
2020-05-19T20:29:04.702683642Z time="2020-05-19T20:29:04.701556678Z" level=info msg=serving... address="/var/run/docker/containerd/containerd.sock"
2020-05-19T20:29:04.702686773Z time="2020-05-19T20:29:04.701618168Z" level=info msg="containerd successfully booted in 0.035122s"
2020-05-19T20:29:04.749719444Z time="2020-05-19T20:29:04.730734369Z" level=info msg="Setting the storage driver from the $DOCKER_DRIVER environment variable (overlay2)"
2020-05-19T20:29:04.922218484Z time="2020-05-19T20:29:04.730946605Z" level=info msg="parsed scheme: \"unix\"" module=grpc
2020-05-19T20:29:04.922241822Z time="2020-05-19T20:29:04.730959039Z" level=info msg="scheme \"unix\" not registered, fallback to default scheme" module=grpc
2020-05-19T20:29:04.922324854Z time="2020-05-19T20:29:04.730975240Z" level=info msg="ccResolverWrapper: sending update to cc: {[{unix:///var/run/docker/containerd/containerd.sock 0 <nil>}] <nil>}" module=grpc
2020-05-19T20:29:04.922351487Z time="2020-05-19T20:29:04.730984770Z" level=info msg="ClientConn switching balancer to \"pick_first\"" module=grpc
2020-05-19T20:29:04.922355581Z time="2020-05-19T20:29:04.745810489Z" level=info msg="parsed scheme: \"unix\"" module=grpc
2020-05-19T20:29:04.922358921Z time="2020-05-19T20:29:04.745843291Z" level=info msg="scheme \"unix\" not registered, fallback to default scheme" module=grpc
2020-05-19T20:29:04.922362418Z time="2020-05-19T20:29:04.745900552Z" level=info msg="ccResolverWrapper: sending update to cc: {[{unix:///var/run/docker/containerd/containerd.sock 0 <nil>}] <nil>}" module=grpc
2020-05-19T20:29:04.922366283Z time="2020-05-19T20:29:04.745914099Z" level=info msg="ClientConn switching balancer to \"pick_first\"" module=grpc
2020-05-19T20:29:04.994581344Z time="2020-05-19T20:29:04.993189988Z" level=info msg="Loading containers: start."
2020-05-19T20:29:05.021599123Z time="2020-05-19T20:29:05.020528323Z" 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-05-19T20:29:05.140610202Z time="2020-05-19T20:29:05.139319980Z" 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-05-19T20:29:05.236549070Z time="2020-05-19T20:29:05.235540024Z" level=info msg="Loading containers: done."
2020-05-19T20:29:05.258480480Z time="2020-05-19T20:29:05.258053273Z" level=info msg="Docker daemon" commit=afacb8b7f0 graphdriver(s)=overlay2 version=19.03.8
2020-05-19T20:29:05.258497658Z time="2020-05-19T20:29:05.258205701Z" level=info msg="Daemon has completed initialization"
2020-05-19T20:29:05.334679109Z time="2020-05-19T20:29:05.331535163Z" level=info msg="API listen on [::]:2375"
2020-05-19T20:29:05.334708031Z time="2020-05-19T20:29:05.331619433Z" level=info msg="API listen on /var/run/docker.sock"
[0;33m*********[0;m
[0KPulling docker image registry.gitlab.com/gitlab-org/cluster-integration/auto-build-image:v0.2.2 ...
[0;m[0KUsing docker image sha256:a9a470e7a925ecfd27cfbb60e98c0915f02a3eb8a81f15fb6b11af1baca21e63 for registry.gitlab.com/gitlab-org/cluster-integration/auto-build-image:v0.2.2 ...
[0;msection_end:1589920182:prepare_executor
[0Ksection_start:1589920182:prepare_script
[0K[0K[36;1mPreparing environment[0;m
[0;mRunning on runner-72989761-project-4422333-concurrent-0 via runner-72989761-stg-srm-1589920075-6c0c881f...
section_end:1589920187:prepare_script
[0Ksection_start:1589920187:get_sources
[0K[0K[36;1mGetting source from Git repository[0;m
[0;m[32;1m$ eval "$CI_PRE_CLONE_SCRIPT"[0;m
[32;1mFetching changes with git depth set to 50...[0;m
Initialized empty Git repository in /builds/gitlab-org/monitor/monitor-sandbox/.git/
[32;1mCreated fresh repository.[0;m
From https://ci-api.gstg.gitlab.net/gitlab-org/monitor/monitor-sandbox
* [new ref] refs/pipelines/12775753 -> refs/pipelines/12775753
* [new branch] master -> origin/master
[32;1mChecking out 095035c5 as master...[0;m
[32;1mSkipping Git submodules setup[0;m
section_end:1589920189:get_sources
[0Ksection_start:1589920189:restore_cache
[0K[0K[36;1mRestoring cache[0;m
[0;msection_end:1589920190:restore_cache
[0Ksection_start:1589920190:download_artifacts
[0K[0K[36;1mDownloading artifacts[0;m
[0;msection_end:1589920191:download_artifacts
[0Ksection_start:1589920191:build_script
[0K[0K[36;1mRunning before_script and script[0;m
[0;m[32;1m$ if [[ -z "$CI_COMMIT_TAG" ]]; then # collapsed multi-line command[0;m
[32;1m$ /build/build.sh[0;m
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
00e8c4593d23: Pulling fs layer
a4d4613b850e: Waiting
5fb493aa739b: Waiting
8b7e2ad468ac: Waiting
00e8c4593d23: 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
db7179d8c6cd: Verifying Checksum
db7179d8c6cd: Download complete
a073c86ecf9e: Pull complete
00e8c4593d23: Verifying Checksum
00e8c4593d23: Download complete
8b7e2ad468ac: Verifying Checksum
8b7e2ad468ac: Download complete
db7179d8c6cd: Pull complete
66b9cfaecc8c: Pull complete
a4d4613b850e: Pull complete
5fb493aa739b: Pull complete
8b7e2ad468ac: Pull complete
00e8c4593d23: Pull complete
Digest: sha256:f97564b9b56af29ba2e3b307f3a21815e18c6a070e9802d7ecf74d00ce17f27e
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 129kB
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
---> 9ffc10101b6d
Step 3/10 : ARG NODE_ENV
---> Using cache
---> 58491792d55b
Step 4/10 : ENV NODE_ENV $NODE_ENV
---> Using cache
---> e163079ca2da
Step 5/10 : COPY package.json /usr/src/app/
---> Using cache
---> 3e0281a9ff8b
Step 6/10 : RUN npm install
---> Using cache
---> 3ac96b57d1bc
Step 7/10 : COPY . /usr/src/app
---> 3a5fd0319fac
Step 8/10 : ENV PORT 5000
---> Running in 7f8432289765
Removing intermediate container 7f8432289765
---> ecde1c4a6ec7
Step 9/10 : EXPOSE $PORT
---> Running in dd69fb8d4aa8
Removing intermediate container dd69fb8d4aa8
---> 0c427d851fa5
Step 10/10 : CMD [ "npm", "start" ]
---> Running in 1ca6b9164da1
Removing intermediate container 1ca6b9164da1
---> 5b2e99835d66
[Warning] One or more build-args [BUILDPACK_URL] were not consumed
Successfully built 5b2e99835d66
Successfully tagged registry.staging.gitlab.com/gitlab-org/monitor/monitor-sandbox/master:095035c511e06b8d6f071c5cc48d9d42d32a66e1
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]
aa29f5e830ed: 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
d35df9c923f8: Layer already exists
8ddc9e599f93: Layer already exists
a7d484df787a: Layer already exists
8dfad2055603: Layer already exists
aa29f5e830ed: Pushed
095035c511e06b8d6f071c5cc48d9d42d32a66e1: digest: sha256:65995303ac045dd26403fb86c0b02698613f206369e6b6cedefd792d0420c2f9 size: 1785
The push refers to repository [registry.staging.gitlab.com/gitlab-org/monitor/monitor-sandbox/master]
aa29f5e830ed: Preparing
8ddc9e599f93: Preparing
a85ff65fd061: Preparing
eaba38c532c6: Preparing
d35df9c923f8: Preparing
a7d484df787a: Preparing
8dfad2055603: Preparing
a7d484df787a: Waiting
8dfad2055603: Waiting
8ddc9e599f93: Layer already exists
aa29f5e830ed: Layer already exists
eaba38c532c6: Layer already exists
a85ff65fd061: Layer already exists
d35df9c923f8: Layer already exists
8dfad2055603: Layer already exists
a7d484df787a: Layer already exists
latest: digest: sha256:65995303ac045dd26403fb86c0b02698613f206369e6b6cedefd792d0420c2f9 size: 1785
section_end:1589920211:build_script
[0Ksection_start:1589920211:after_script
[0K[0K[36;1mRunning after_script[0;m
[0;msection_end:1589920213:after_script
[0Ksection_start:1589920213:archive_cache
[0K[0K[36;1mSaving cache[0;m
[0;msection_end:1589920214:archive_cache
[0Ksection_start:1589920214:upload_artifacts_on_success
[0K[0K[36;1mUploading artifacts for successful job[0;m
[0;msection_end:1589920216:upload_artifacts_on_success
[0K[32;1mJob succeeded
[0;m