Running with gitlab-runner 12.8.0 (1b659122)
  on pica01-test fyUVS7Hz
section_start:1585888582:prepare_executor
Using Docker executor with image registry.picasoft.net/pica-ci-base ...
Starting service docker:19.03.0-dind ...
Pulling docker image docker:19.03.0-dind ...
Using docker image sha256:fd0c64832f7e46b63a180e6000dbba7ad7a63542c5764841cba73429ba74a39e for docker:19.03.0-dind ...
Waiting for services to be up and running...

*** WARNING: Service runner-fyUVS7Hz-project-1304-concurrent-0-docker-0 probably didn't start properly.

Health check error:
service "runner-fyUVS7Hz-project-1304-concurrent-0-docker-0-wait-for-service" timeout

Health check container logs:


Service container logs:
2020-04-03T04:36:39.790015483Z time="2020-04-03T04:36:39.789748155Z" level=info msg="Starting up"
2020-04-03T04:36:39.792573601Z time="2020-04-03T04:36:39.791492052Z" level=warning msg="could not change group /var/run/docker.sock to docker: group docker not found"
2020-04-03T04:36:39.792625998Z time="2020-04-03T04:36:39.792512487Z" level=warning msg="[!] DON'T BIND ON ANY IP ADDRESS WITHOUT setting --tlsverify IF YOU DON'T KNOW WHAT YOU'RE DOING [!]"
2020-04-03T04:36:39.796510577Z time="2020-04-03T04:36:39.795757918Z" level=info msg="libcontainerd: started new containerd process" pid=19
2020-04-03T04:36:39.796527349Z time="2020-04-03T04:36:39.796419391Z" level=info msg="parsed scheme: \"unix\"" module=grpc
2020-04-03T04:36:39.796533184Z time="2020-04-03T04:36:39.796436701Z" level=info msg="scheme \"unix\" not registered, fallback to default scheme" module=grpc
2020-04-03T04:36:39.796627335Z time="2020-04-03T04:36:39.796459402Z" level=info msg="ccResolverWrapper: sending update to cc: {[{unix:///var/run/docker/containerd/containerd.sock 0  <nil>}] }" module=grpc
2020-04-03T04:36:39.796640033Z time="2020-04-03T04:36:39.796475127Z" level=info msg="ClientConn switching balancer to \"pick_first\"" module=grpc
2020-04-03T04:36:39.796722561Z time="2020-04-03T04:36:39.796606034Z" level=info msg="pickfirstBalancer: HandleSubConnStateChange: 0xc0001539b0, CONNECTING" module=grpc
2020-04-03T04:36:39.825573538Z time="2020-04-03T04:36:39.825459152Z" level=info msg="starting containerd" revision=894b81a4b802e4eb2a91d1ce216b8817763c29fb version=v1.2.6 
2020-04-03T04:36:39.825964002Z time="2020-04-03T04:36:39.825902317Z" level=info msg="loading plugin "io.containerd.content.v1.content"..." type=io.containerd.content.v1 
2020-04-03T04:36:39.826237246Z time="2020-04-03T04:36:39.826144015Z" level=info msg="loading plugin "io.containerd.snapshotter.v1.btrfs"..." type=io.containerd.snapshotter.v1 
2020-04-03T04:36:39.826550864Z time="2020-04-03T04:36:39.826478523Z" 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-04-03T04:36:39.826632827Z time="2020-04-03T04:36:39.826574144Z" level=info msg="loading plugin "io.containerd.snapshotter.v1.aufs"..." type=io.containerd.snapshotter.v1 
2020-04-03T04:36:39.845081471Z time="2020-04-03T04:36:39.844627641Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.aufs" error="modprobe aufs failed: "ip: can't find device 'aufs'\naufs                  274432  0 \nmodprobe: can't change directory to '/lib/modules': No such file or directory\n": exit status 1" 
2020-04-03T04:36:39.845102528Z time="2020-04-03T04:36:39.844666620Z" level=info msg="loading plugin "io.containerd.snapshotter.v1.native"..." type=io.containerd.snapshotter.v1 
2020-04-03T04:36:39.845390839Z time="2020-04-03T04:36:39.845319943Z" level=info msg="loading plugin "io.containerd.snapshotter.v1.overlayfs"..." type=io.containerd.snapshotter.v1 
2020-04-03T04:36:39.845637164Z time="2020-04-03T04:36:39.845552240Z" level=info msg="loading plugin "io.containerd.snapshotter.v1.zfs"..." type=io.containerd.snapshotter.v1 
2020-04-03T04:36:39.846026677Z time="2020-04-03T04:36:39.845938760Z" level=warning msg="failed to load 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" 
2020-04-03T04:36:39.846047599Z time="2020-04-03T04:36:39.845975725Z" level=info msg="loading plugin "io.containerd.metadata.v1.bolt"..." type=io.containerd.metadata.v1 
2020-04-03T04:36:39.846132667Z time="2020-04-03T04:36:39.846048903Z" 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-04-03T04:36:39.846147730Z time="2020-04-03T04:36:39.846062128Z" level=warning msg="could not use snapshotter aufs in metadata plugin" error="modprobe aufs failed: "ip: can't find device 'aufs'\naufs                  274432  0 \nmodprobe: can't change directory to '/lib/modules': No such file or directory\n": exit status 1" 
2020-04-03T04:36:39.846154644Z time="2020-04-03T04:36:39.846071654Z" 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" 
2020-04-03T04:36:39.869307033Z time="2020-04-03T04:36:39.869004893Z" level=info msg="loading plugin "io.containerd.differ.v1.walking"..." type=io.containerd.differ.v1 
2020-04-03T04:36:39.869326365Z time="2020-04-03T04:36:39.869054465Z" level=info msg="loading plugin "io.containerd.gc.v1.scheduler"..." type=io.containerd.gc.v1 
2020-04-03T04:36:39.869332366Z time="2020-04-03T04:36:39.869110597Z" level=info msg="loading plugin "io.containerd.service.v1.containers-service"..." type=io.containerd.service.v1 
2020-04-03T04:36:39.869337392Z time="2020-04-03T04:36:39.869127819Z" level=info msg="loading plugin "io.containerd.service.v1.content-service"..." type=io.containerd.service.v1 
2020-04-03T04:36:39.869342360Z time="2020-04-03T04:36:39.869139240Z" level=info msg="loading plugin "io.containerd.service.v1.diff-service"..." type=io.containerd.service.v1 
2020-04-03T04:36:39.869347333Z time="2020-04-03T04:36:39.869154867Z" level=info msg="loading plugin "io.containerd.service.v1.images-service"..." type=io.containerd.service.v1 
2020-04-03T04:36:39.869352543Z time="2020-04-03T04:36:39.869179987Z" level=info msg="loading plugin "io.containerd.service.v1.leases-service"..." type=io.containerd.service.v1 
2020-04-03T04:36:39.869357309Z time="2020-04-03T04:36:39.869193882Z" level=info msg="loading plugin "io.containerd.service.v1.namespaces-service"..." type=io.containerd.service.v1 
2020-04-03T04:36:39.869362192Z time="2020-04-03T04:36:39.869206319Z" level=info msg="loading plugin "io.containerd.service.v1.snapshots-service"..." type=io.containerd.service.v1 
2020-04-03T04:36:39.869367048Z time="2020-04-03T04:36:39.869252415Z" level=info msg="loading plugin "io.containerd.runtime.v1.linux"..." type=io.containerd.runtime.v1 
2020-04-03T04:36:39.869591517Z time="2020-04-03T04:36:39.869503548Z" level=info msg="loading plugin "io.containerd.runtime.v2.task"..." type=io.containerd.runtime.v2 
2020-04-03T04:36:39.869722337Z time="2020-04-03T04:36:39.869626245Z" level=info msg="loading plugin "io.containerd.monitor.v1.cgroups"..." type=io.containerd.monitor.v1 
2020-04-03T04:36:39.871326601Z time="2020-04-03T04:36:39.870919732Z" level=info msg="loading plugin "io.containerd.service.v1.tasks-service"..." type=io.containerd.service.v1 
2020-04-03T04:36:39.871340147Z time="2020-04-03T04:36:39.870974389Z" level=info msg="loading plugin "io.containerd.internal.v1.restart"..." type=io.containerd.internal.v1 
2020-04-03T04:36:39.871359533Z time="2020-04-03T04:36:39.871031410Z" level=info msg="loading plugin "io.containerd.grpc.v1.containers"..." type=io.containerd.grpc.v1 
2020-04-03T04:36:39.871364618Z time="2020-04-03T04:36:39.871073453Z" level=info msg="loading plugin "io.containerd.grpc.v1.content"..." type=io.containerd.grpc.v1 
2020-04-03T04:36:39.871384808Z time="2020-04-03T04:36:39.871099982Z" level=info msg="loading plugin "io.containerd.grpc.v1.diff"..." type=io.containerd.grpc.v1 
2020-04-03T04:36:39.871391397Z time="2020-04-03T04:36:39.871146015Z" level=info msg="loading plugin "io.containerd.grpc.v1.events"..." type=io.containerd.grpc.v1 
2020-04-03T04:36:39.871396381Z time="2020-04-03T04:36:39.871158106Z" level=info msg="loading plugin "io.containerd.grpc.v1.healthcheck"..." type=io.containerd.grpc.v1 
2020-04-03T04:36:39.871401089Z time="2020-04-03T04:36:39.871178941Z" level=info msg="loading plugin "io.containerd.grpc.v1.images"..." type=io.containerd.grpc.v1 
2020-04-03T04:36:39.871405609Z time="2020-04-03T04:36:39.871195357Z" level=info msg="loading plugin "io.containerd.grpc.v1.leases"..." type=io.containerd.grpc.v1 
2020-04-03T04:36:39.871410090Z time="2020-04-03T04:36:39.871211227Z" level=info msg="loading plugin "io.containerd.grpc.v1.namespaces"..." type=io.containerd.grpc.v1 
2020-04-03T04:36:39.871414648Z time="2020-04-03T04:36:39.871243032Z" level=info msg="loading plugin "io.containerd.internal.v1.opt"..." type=io.containerd.internal.v1 
2020-04-03T04:36:39.871803741Z time="2020-04-03T04:36:39.871479983Z" level=info msg="loading plugin "io.containerd.grpc.v1.snapshots"..." type=io.containerd.grpc.v1 
2020-04-03T04:36:39.872244528Z time="2020-04-03T04:36:39.871528303Z" level=info msg="loading plugin "io.containerd.grpc.v1.tasks"..." type=io.containerd.grpc.v1 
2020-04-03T04:36:39.872292185Z time="2020-04-03T04:36:39.871540289Z" level=info msg="loading plugin "io.containerd.grpc.v1.version"..." type=io.containerd.grpc.v1 
2020-04-03T04:36:39.872299502Z time="2020-04-03T04:36:39.871572355Z" level=info msg="loading plugin "io.containerd.grpc.v1.introspection"..." type=io.containerd.grpc.v1 
2020-04-03T04:36:39.872496213Z time="2020-04-03T04:36:39.872155334Z" level=info msg=serving... address="/var/run/docker/containerd/containerd-debug.sock" 
2020-04-03T04:36:39.872505687Z time="2020-04-03T04:36:39.872241662Z" level=info msg=serving... address="/var/run/docker/containerd/containerd.sock" 
2020-04-03T04:36:39.872510818Z time="2020-04-03T04:36:39.872256790Z" level=info msg="containerd successfully booted in 0.047432s" 
2020-04-03T04:36:39.878140101Z time="2020-04-03T04:36:39.877986184Z" level=info msg="pickfirstBalancer: HandleSubConnStateChange: 0xc0001539b0, READY" module=grpc
2020-04-03T04:36:39.880596987Z time="2020-04-03T04:36:39.880460087Z" level=info msg="Setting the storage driver from the $DOCKER_DRIVER environment variable (overlay2)"
2020-04-03T04:36:39.880938742Z time="2020-04-03T04:36:39.880866712Z" level=info msg="parsed scheme: \"unix\"" module=grpc
2020-04-03T04:36:39.880964328Z time="2020-04-03T04:36:39.880884543Z" level=info msg="scheme \"unix\" not registered, fallback to default scheme" module=grpc
2020-04-03T04:36:39.880970338Z time="2020-04-03T04:36:39.880904234Z" level=info msg="ccResolverWrapper: sending update to cc: {[{unix:///var/run/docker/containerd/containerd.sock 0  <nil>}] }" module=grpc
2020-04-03T04:36:39.880994512Z time="2020-04-03T04:36:39.880933916Z" level=info msg="ClientConn switching balancer to \"pick_first\"" module=grpc
2020-04-03T04:36:39.881428854Z time="2020-04-03T04:36:39.881292871Z" level=info msg="pickfirstBalancer: HandleSubConnStateChange: 0xc0007927f0, CONNECTING" module=grpc
2020-04-03T04:36:39.882210803Z time="2020-04-03T04:36:39.882112377Z" level=info msg="pickfirstBalancer: HandleSubConnStateChange: 0xc0007927f0, READY" module=grpc
2020-04-03T04:36:39.882223647Z time="2020-04-03T04:36:39.881662433Z" level=info msg="blockingPicker: the picked transport is not ready, loop back to repick" module=grpc
2020-04-03T04:36:39.882910059Z time="2020-04-03T04:36:39.882775251Z" level=info msg="parsed scheme: \"unix\"" module=grpc
2020-04-03T04:36:39.882920650Z time="2020-04-03T04:36:39.882795674Z" level=info msg="scheme \"unix\" not registered, fallback to default scheme" module=grpc
2020-04-03T04:36:39.883003518Z time="2020-04-03T04:36:39.882811869Z" level=info msg="ccResolverWrapper: sending update to cc: {[{unix:///var/run/docker/containerd/containerd.sock 0  <nil>}] }" module=grpc
2020-04-03T04:36:39.883097903Z time="2020-04-03T04:36:39.882961448Z" level=info msg="ClientConn switching balancer to \"pick_first\"" module=grpc
2020-04-03T04:36:39.883228999Z time="2020-04-03T04:36:39.883141418Z" level=info msg="pickfirstBalancer: HandleSubConnStateChange: 0xc0006c0bc0, CONNECTING" module=grpc
2020-04-03T04:36:39.883296861Z time="2020-04-03T04:36:39.883158373Z" level=info msg="blockingPicker: the picked transport is not ready, loop back to repick" module=grpc
2020-04-03T04:36:39.883651644Z time="2020-04-03T04:36:39.883585688Z" level=info msg="pickfirstBalancer: HandleSubConnStateChange: 0xc0006c0bc0, READY" module=grpc
2020-04-03T04:36:39.997722481Z time="2020-04-03T04:36:39.997558644Z" level=warning msg="Your kernel does not support swap memory limit"
2020-04-03T04:36:39.997742491Z time="2020-04-03T04:36:39.997633084Z" level=warning msg="Your kernel does not support cgroup rt period"
2020-04-03T04:36:39.997747959Z time="2020-04-03T04:36:39.997671554Z" level=warning msg="Your kernel does not support cgroup rt runtime"
2020-04-03T04:36:40.004650193Z time="2020-04-03T04:36:40.001381521Z" level=info msg="Loading containers: start."
2020-04-03T04:36:40.034453777Z time="2020-04-03T04:36:40.034280220Z" level=warning msg="Running modprobe bridge br_netfilter failed with message: ip: can't find device 'bridge'\nbridge                188416  1 br_netfilter\nstp                    16384  1 bridge\nllc                    16384  2 bridge,stp\nip: can't find device 'br_netfilter'\nbr_netfilter           24576  0 \nbridge                188416  1 br_netfilter\nmodprobe: can't change directory to '/lib/modules': No such file or directory\n, error: exit status 1"
2020-04-03T04:36:40.047659830Z time="2020-04-03T04:36:40.046254331Z" level=warning msg="Running modprobe nf_nat failed with message: `ip: can't find device 'nf_nat'\nnf_nat_ipv4            16384  3 iptable_nat,ipt_MASQUERADE,nft_chain_nat_ipv4\nnf_nat                 36864  2 xt_nat,nf_nat_ipv4\nnf_conntrack          172032  6 xt_nat,xt_conntrack,ipt_MASQUERADE,nf_conntrack_netlink,nf_nat_ipv4,nf_nat\nlibcrc32c              16384  4 btrfs,xfs,nf_nat,nf_conntrack\nmodprobe: can't change directory to '/lib/modules': No such file or directory`, error: exit status 1"
2020-04-03T04:36:40.067295103Z time="2020-04-03T04:36:40.064566922Z" level=warning msg="Running modprobe xt_conntrack failed with message: `ip: can't find device 'xt_conntrack'\nxt_conntrack           16384  3 \nnf_conntrack          172032  6 xt_nat,xt_conntrack,ipt_MASQUERADE,nf_conntrack_netlink,nf_nat_ipv4,nf_nat\nx_tables               45056  8 iptable_filter,xt_nat,xt_tcpudp,xt_conntrack,ipt_MASQUERADE,xt_addrtype,nft_compat,ip_tables\nmodprobe: can't change directory to '/lib/modules': No such file or directory`, error: exit status 1"
2020-04-03T04:36:40.251764547Z time="2020-04-03T04:36:40.250927145Z" 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-04-03T04:36:40.330427734Z time="2020-04-03T04:36:40.330295904Z" level=info msg="Loading containers: done."
2020-04-03T04:36:40.374984204Z time="2020-04-03T04:36:40.374795575Z" level=info msg="Docker daemon" commit=aeac9490dc graphdriver(s)=overlay2 version=19.03.0
2020-04-03T04:36:40.375052245Z time="2020-04-03T04:36:40.374942017Z" level=info msg="Daemon has completed initialization"
2020-04-03T04:36:40.428821921Z time="2020-04-03T04:36:40.428668192Z" level=info msg="API listen on /var/run/docker.sock"
2020-04-03T04:36:40.428854897Z time="2020-04-03T04:36:40.428690830Z" level=info msg="API listen on [::]:2375"

*********

Authenticating with credentials from $DOCKER_AUTH_CONFIG
Pulling docker image registry.picasoft.net/pica-ci-base ...
Using docker image sha256:31f9b58ca7552cdd0ba64e295952a2bef4a246425c48d8ee557bea136107806e for registry.picasoft.net/pica-ci-base ...
section_end:1585888646:prepare_executor
section_start:1585888646:prepare_script
Running on runner-fyUVS7Hz-project-1304-concurrent-0 via pica01-test...
section_end:1585888649:prepare_script
section_start:1585888649:get_sources
Fetching changes...
Initialized empty Git repository in /builds/picasoft/projets/dockerfiles/.git/
Created fresh repository.
From https://gitlab.utc.fr/picasoft/projets/dockerfiles
 * [new ref]         refs/pipelines/59174     -> refs/pipelines/59174
 * [new branch]      acme-copy-certs-dev      -> origin/acme-copy-certs-dev
 * [new branch]      dev-ci                   -> origin/dev-ci
 * [new branch]      dev-lufi                 -> origin/dev-lufi
 * [new branch]      dev-sympa                -> origin/dev-sympa
 * [new branch]      master                   -> origin/master
 * [new branch]      patch-1                  -> origin/patch-1
 * [new branch]      pica-openldap-dev        -> origin/pica-openldap-dev
 * [new branch]      tx-services-p19-guillaume -> origin/tx-services-p19-guillaume
 * [new branch]      tx-services-p19-liam     -> origin/tx-services-p19-liam
 * [new tag]         MTA-SASL-poc             -> MTA-SASL-poc
 * [new tag]         pica-mail-auth_plaintext -> pica-mail-auth_plaintext
 * [new tag]         v1.0.0                   -> v1.0.0
 * [new tag]         v1.0.1                   -> v1.0.1
Checking out 9f548720 as master...

Skipping Git submodules setup
section_end:1585888654:get_sources
section_start:1585888654:restore_cache
Checking cache for default-1...
No URL provided, cache will not be downloaded from shared cache server. Instead a local version of cache will be extracted. 
Successfully extracted cache
section_end:1585888655:restore_cache
section_start:1585888655:download_artifacts
section_end:1585888657:download_artifacts
section_start:1585888657:build_script
Authenticating with credentials from $DOCKER_AUTH_CONFIG
$ chmod +x ./get-modified-image.sh
$ ./get-modified-image.sh
cat: can't open '/docker-compose.yml': No such file or directory
BusyBox v1.30.1 (2019-06-12 17:51:55 UTC) multi-call binary.

Usage: grep [-HhnlLoqvsriwFE] [-m N] [-A/B/C N] PATTERN/-e PATTERN.../-f FILE [FILE]...

Search for PATTERN in FILEs (or stdin)

	-H	Add 'filename:' prefix
	-h	Do not add 'filename:' prefix
	-n	Add 'line_no:' prefix
	-l	Show only names of files that match
	-L	Show only names of files that don't match
	-c	Show only count of matching lines
	-o	Show only the matching part of line
	-q	Quiet. Return 0 if PATTERN is found, 1 otherwise
	-v	Select non-matching lines
	-s	Suppress open and read errors
	-r	Recurse
	-i	Ignore case
	-w	Match whole words only
	-x	Match whole lines only
	-F	PATTERN is a literal (not regexp)
	-E	PATTERN is an extended regexp
	-m N	Match up to N times per file
	-A N	Print N lines of trailing context
	-B N	Print N lines of leading context
	-C N	Same as '-A N -B N'
	-e PTRN	Pattern to match
	-f FILE	Read pattern from file
section_end:1585888658:build_script
section_start:1585888658:after_script
section_end:1585888659:after_script
section_start:1585888659:upload_artifacts_on_failure
section_end:1585888661:upload_artifacts_on_failure
ERROR: Job failed: exit code 1