faulty_version

faulty.txt

Running with gitlab-runner 13.3.1 (738bbe5a)
  on docker-auto-scale 72989761
section_start:1599519346:prepare_executor
Preparing the "docker+machine" executor
Using Docker executor with image docker:19.03.5 ...
Starting service docker:19.03.5-dind ...
Pulling docker image docker:19.03.5-dind ...
Using docker image sha256:8489eeb24a264b6bcdb17f3da00140cebe92ee36bd22365f37d07d59390df4ee for docker:19.03.5-dind ...
Waiting for services to be up and running...

*** WARNING: Service runner-72989761-project-20828281-concurrent-0-5087d3112f68423b-docker-0 probably didn't start properly.

Health check error:
service "runner-72989761-project-20828281-concurrent-0-5087d3112f68423b-docker-0-wait-for-service" timeout

Health check container logs:


Service container logs:
2020-09-07T22:56:09.044345132Z time="2020-09-07T22:56:09.013768309Z" level=info msg="Starting up"
2020-09-07T22:56:09.044409416Z time="2020-09-07T22:56:09.014992256Z" level=warning msg="could not change group /var/run/docker.sock to docker: group docker not found"
2020-09-07T22:56:09.044415798Z time="2020-09-07T22:56:09.015195672Z" level=warning msg="[!] DON'T BIND ON ANY IP ADDRESS WITHOUT setting --tlsverify IF YOU DON'T KNOW WHAT YOU'RE DOING [!]"
2020-09-07T22:56:09.044419330Z time="2020-09-07T22:56:09.016000030Z" level=info msg="libcontainerd: started new containerd process" pid=18
2020-09-07T22:56:09.044422828Z time="2020-09-07T22:56:09.016032028Z" level=info msg="parsed scheme: \"unix\"" module=grpc
2020-09-07T22:56:09.044426395Z time="2020-09-07T22:56:09.016039968Z" level=info msg="scheme \"unix\" not registered, fallback to default scheme" module=grpc
2020-09-07T22:56:09.044430003Z time="2020-09-07T22:56:09.016062687Z" level=info msg="ccResolverWrapper: sending update to cc: {[{unix:///var/run/docker/containerd/containerd.sock 0  <nil>}] <nil>}" module=grpc
2020-09-07T22:56:09.044434226Z time="2020-09-07T22:56:09.016072569Z" level=info msg="ClientConn switching balancer to \"pick_first\"" module=grpc
2020-09-07T22:56:09.062246641Z time="2020-09-07T22:56:09.054298355Z" level=info msg="starting containerd" revision=b34a5c8af56e510852c35414db4c1f4fa6172339 version=v1.2.10 
2020-09-07T22:56:09.062279312Z time="2020-09-07T22:56:09.054623744Z" level=info msg="loading plugin "io.containerd.content.v1.content"..." type=io.containerd.content.v1 
2020-09-07T22:56:09.062284136Z time="2020-09-07T22:56:09.054700428Z" level=info msg="loading plugin "io.containerd.snapshotter.v1.btrfs"..." type=io.containerd.snapshotter.v1 
2020-09-07T22:56:09.062288720Z time="2020-09-07T22:56:09.054884690Z" 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-09-07T22:56:09.062299499Z time="2020-09-07T22:56:09.054896363Z" level=info msg="loading plugin "io.containerd.snapshotter.v1.aufs"..." type=io.containerd.snapshotter.v1 
2020-09-07T22:56:09.115122363Z time="2020-09-07T22:56:09.070879208Z" 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-09-07T22:56:09.115292808Z time="2020-09-07T22:56:09.070906512Z" level=info msg="loading plugin "io.containerd.snapshotter.v1.native"..." type=io.containerd.snapshotter.v1 
2020-09-07T22:56:09.115297921Z time="2020-09-07T22:56:09.071016990Z" level=info msg="loading plugin "io.containerd.snapshotter.v1.overlayfs"..." type=io.containerd.snapshotter.v1 
2020-09-07T22:56:09.115301785Z time="2020-09-07T22:56:09.071345386Z" level=info msg="loading plugin "io.containerd.snapshotter.v1.zfs"..." type=io.containerd.snapshotter.v1 
2020-09-07T22:56:09.115305491Z time="2020-09-07T22:56:09.071578400Z" level=info msg="skip loading plugin "io.containerd.snapshotter.v1.zfs"..." type=io.containerd.snapshotter.v1 
2020-09-07T22:56:09.115309001Z time="2020-09-07T22:56:09.071587049Z" level=info msg="loading plugin "io.containerd.metadata.v1.bolt"..." type=io.containerd.metadata.v1 
2020-09-07T22:56:09.115331731Z time="2020-09-07T22:56:09.071629623Z" 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-09-07T22:56:09.115336168Z time="2020-09-07T22:56:09.071636977Z" 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-09-07T22:56:09.115340270Z time="2020-09-07T22:56:09.071644515Z" 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-09-07T22:56:09.115366068Z time="2020-09-07T22:56:09.098873024Z" level=info msg="loading plugin "io.containerd.differ.v1.walking"..." type=io.containerd.differ.v1 
2020-09-07T22:56:09.115370287Z time="2020-09-07T22:56:09.098910676Z" level=info msg="loading plugin "io.containerd.gc.v1.scheduler"..." type=io.containerd.gc.v1 
2020-09-07T22:56:09.115373762Z time="2020-09-07T22:56:09.098942227Z" level=info msg="loading plugin "io.containerd.service.v1.containers-service"..." type=io.containerd.service.v1 
2020-09-07T22:56:09.115377230Z time="2020-09-07T22:56:09.098955213Z" level=info msg="loading plugin "io.containerd.service.v1.content-service"..." type=io.containerd.service.v1 
2020-09-07T22:56:09.115380718Z time="2020-09-07T22:56:09.098965394Z" level=info msg="loading plugin "io.containerd.service.v1.diff-service"..." type=io.containerd.service.v1 
2020-09-07T22:56:09.115384537Z time="2020-09-07T22:56:09.098976523Z" level=info msg="loading plugin "io.containerd.service.v1.images-service"..." type=io.containerd.service.v1 
2020-09-07T22:56:09.115388003Z time="2020-09-07T22:56:09.098989742Z" level=info msg="loading plugin "io.containerd.service.v1.leases-service"..." type=io.containerd.service.v1 
2020-09-07T22:56:09.115391334Z time="2020-09-07T22:56:09.099000294Z" level=info msg="loading plugin "io.containerd.service.v1.namespaces-service"..." type=io.containerd.service.v1 
2020-09-07T22:56:09.115394774Z time="2020-09-07T22:56:09.099010450Z" level=info msg="loading plugin "io.containerd.service.v1.snapshots-service"..." type=io.containerd.service.v1 
2020-09-07T22:56:09.115398123Z time="2020-09-07T22:56:09.099023000Z" level=info msg="loading plugin "io.containerd.runtime.v1.linux"..." type=io.containerd.runtime.v1 
2020-09-07T22:56:09.115401558Z time="2020-09-07T22:56:09.099277025Z" level=info msg="loading plugin "io.containerd.runtime.v2.task"..." type=io.containerd.runtime.v2 
2020-09-07T22:56:09.115404846Z time="2020-09-07T22:56:09.099379295Z" level=info msg="loading plugin "io.containerd.monitor.v1.cgroups"..." type=io.containerd.monitor.v1 
2020-09-07T22:56:09.115408139Z time="2020-09-07T22:56:09.099708477Z" level=info msg="loading plugin "io.containerd.service.v1.tasks-service"..." type=io.containerd.service.v1 
2020-09-07T22:56:09.115411531Z time="2020-09-07T22:56:09.099733440Z" level=info msg="loading plugin "io.containerd.internal.v1.restart"..." type=io.containerd.internal.v1 
2020-09-07T22:56:09.115418760Z time="2020-09-07T22:56:09.099770408Z" level=info msg="loading plugin "io.containerd.grpc.v1.containers"..." type=io.containerd.grpc.v1 
2020-09-07T22:56:09.115422276Z time="2020-09-07T22:56:09.099783396Z" level=info msg="loading plugin "io.containerd.grpc.v1.content"..." type=io.containerd.grpc.v1 
2020-09-07T22:56:09.115425544Z time="2020-09-07T22:56:09.099795388Z" level=info msg="loading plugin "io.containerd.grpc.v1.diff"..." type=io.containerd.grpc.v1 
2020-09-07T22:56:09.115428830Z time="2020-09-07T22:56:09.099805354Z" level=info msg="loading plugin "io.containerd.grpc.v1.events"..." type=io.containerd.grpc.v1 
2020-09-07T22:56:09.115432118Z time="2020-09-07T22:56:09.099814776Z" level=info msg="loading plugin "io.containerd.grpc.v1.healthcheck"..." type=io.containerd.grpc.v1 
2020-09-07T22:56:09.115435405Z time="2020-09-07T22:56:09.099824631Z" level=info msg="loading plugin "io.containerd.grpc.v1.images"..." type=io.containerd.grpc.v1 
2020-09-07T22:56:09.115438659Z time="2020-09-07T22:56:09.099834586Z" level=info msg="loading plugin "io.containerd.grpc.v1.leases"..." type=io.containerd.grpc.v1 
2020-09-07T22:56:09.115441885Z time="2020-09-07T22:56:09.099843463Z" level=info msg="loading plugin "io.containerd.grpc.v1.namespaces"..." type=io.containerd.grpc.v1 
2020-09-07T22:56:09.115445168Z time="2020-09-07T22:56:09.099852595Z" level=info msg="loading plugin "io.containerd.internal.v1.opt"..." type=io.containerd.internal.v1 
2020-09-07T22:56:09.115448483Z time="2020-09-07T22:56:09.100102260Z" level=info msg="loading plugin "io.containerd.grpc.v1.snapshots"..." type=io.containerd.grpc.v1 
2020-09-07T22:56:09.115451772Z time="2020-09-07T22:56:09.100116996Z" level=info msg="loading plugin "io.containerd.grpc.v1.tasks"..." type=io.containerd.grpc.v1 
2020-09-07T22:56:09.115455017Z time="2020-09-07T22:56:09.100146105Z" level=info msg="loading plugin "io.containerd.grpc.v1.version"..." type=io.containerd.grpc.v1 
2020-09-07T22:56:09.115458314Z time="2020-09-07T22:56:09.100157170Z" level=info msg="loading plugin "io.containerd.grpc.v1.introspection"..." type=io.containerd.grpc.v1 
2020-09-07T22:56:09.115461656Z time="2020-09-07T22:56:09.100370934Z" level=info msg=serving... address="/var/run/docker/containerd/containerd-debug.sock" 
2020-09-07T22:56:09.115464962Z time="2020-09-07T22:56:09.100425381Z" level=info msg=serving... address="/var/run/docker/containerd/containerd.sock" 
2020-09-07T22:56:09.115468259Z time="2020-09-07T22:56:09.100433963Z" level=info msg="containerd successfully booted in 0.046767s" 
2020-09-07T22:56:09.125246466Z time="2020-09-07T22:56:09.125080439Z" level=info msg="Setting the storage driver from the $DOCKER_DRIVER environment variable (overlay2)"
2020-09-07T22:56:09.125887582Z time="2020-09-07T22:56:09.125536724Z" level=info msg="parsed scheme: \"unix\"" module=grpc
2020-09-07T22:56:09.125902079Z time="2020-09-07T22:56:09.125552361Z" level=info msg="scheme \"unix\" not registered, fallback to default scheme" module=grpc
2020-09-07T22:56:09.125906456Z time="2020-09-07T22:56:09.125569149Z" level=info msg="ccResolverWrapper: sending update to cc: {[{unix:///var/run/docker/containerd/containerd.sock 0  <nil>}] <nil>}" module=grpc
2020-09-07T22:56:09.125920932Z time="2020-09-07T22:56:09.125578254Z" level=info msg="ClientConn switching balancer to \"pick_first\"" module=grpc
2020-09-07T22:56:09.128973053Z time="2020-09-07T22:56:09.128861199Z" level=info msg="parsed scheme: \"unix\"" module=grpc
2020-09-07T22:56:09.129256711Z time="2020-09-07T22:56:09.129162882Z" level=info msg="scheme \"unix\" not registered, fallback to default scheme" module=grpc
2020-09-07T22:56:09.133207499Z time="2020-09-07T22:56:09.129289891Z" level=info msg="ccResolverWrapper: sending update to cc: {[{unix:///var/run/docker/containerd/containerd.sock 0  <nil>}] <nil>}" module=grpc
2020-09-07T22:56:09.148344307Z time="2020-09-07T22:56:09.147881731Z" level=info msg="ClientConn switching balancer to \"pick_first\"" module=grpc
2020-09-07T22:56:09.199826909Z time="2020-09-07T22:56:09.199706630Z" level=info msg="Loading containers: start."
2020-09-07T22:56:09.239240736Z time="2020-09-07T22:56:09.238578593Z" 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-09-07T22:56:09.333010638Z time="2020-09-07T22:56:09.331523953Z" 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-09-07T22:56:09.395255583Z time="2020-09-07T22:56:09.394157301Z" level=info msg="Loading containers: done."
2020-09-07T22:56:09.419068485Z time="2020-09-07T22:56:09.418669855Z" level=info msg="Docker daemon" commit=633a0ea838 graphdriver(s)=overlay2 version=19.03.5
2020-09-07T22:56:09.419087616Z time="2020-09-07T22:56:09.418843956Z" level=info msg="Daemon has completed initialization"
2020-09-07T22:56:09.477701598Z time="2020-09-07T22:56:09.477317551Z" level=info msg="API listen on [::]:2375"
2020-09-07T22:56:09.477742244Z time="2020-09-07T22:56:09.477404420Z" level=info msg="API listen on /var/run/docker.sock"

*********

Pulling docker image docker:19.03.5 ...
Using docker image sha256:31c774f62a9b520060d72c3c1901facdfdfd0fc4ae7c5e817cdaf6acd456432c for docker:19.03.5 ...
section_end:1599519401:prepare_executor
section_start:1599519401:prepare_script
Preparing environment
Running on runner-72989761-project-20828281-concurrent-0 via runner-72989761-srm-1599519303-fe187cfa...
section_end:1599519402:prepare_script
section_start:1599519402: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/endel-terraform/recipe-app-api-devops/.git/
Created fresh repository.
Checking out 62db99d8 as refs/merge-requests/7/head...

Skipping Git submodules setup
section_end:1599519403:get_sources
section_start:1599519403:step_script
Executing "step_script" stage of the job script
$ apk add python3-dev libffi-dev openssl-dev gcc libc-dev make
fetch http://dl-cdn.alpinelinux.org/alpine/v3.11/main/x86_64/APKINDEX.tar.gz
fetch http://dl-cdn.alpinelinux.org/alpine/v3.11/community/x86_64/APKINDEX.tar.gz
(1/29) Upgrading musl (1.1.24-r0 -> 1.1.24-r2)
(2/29) Upgrading libcrypto1.1 (1.1.1d-r3 -> 1.1.1g-r0)
(3/29) Upgrading libssl1.1 (1.1.1d-r3 -> 1.1.1g-r0)
(4/29) Installing libgcc (9.3.0-r0)
(5/29) Installing libstdc++ (9.3.0-r0)
(6/29) Installing binutils (2.33.1-r0)
(7/29) Installing gmp (6.1.2-r1)
(8/29) Installing isl (0.18-r0)
(9/29) Installing libgomp (9.3.0-r0)
(10/29) Installing libatomic (9.3.0-r0)
(11/29) Installing mpfr4 (4.0.2-r1)
(12/29) Installing mpc1 (1.1.0-r1)
(13/29) Installing gcc (9.3.0-r0)
(14/29) Installing musl-dev (1.1.24-r2)
(15/29) Installing libc-dev (0.7.2-r0)
(16/29) Installing linux-headers (4.19.36-r0)
(17/29) Installing libffi (3.2.1-r6)
(18/29) Installing pkgconf (1.6.3-r0)
(19/29) Installing libffi-dev (3.2.1-r6)
(20/29) Installing make (4.2.1-r2)
(21/29) Installing openssl-dev (1.1.1g-r0)
(22/29) Installing libbz2 (1.0.8-r1)
(23/29) Installing expat (2.2.9-r1)
(24/29) Installing gdbm (1.13-r1)
(25/29) Installing xz-libs (5.2.4-r0)
(26/29) Installing readline (8.0.1-r0)
(27/29) Installing sqlite-libs (3.30.1-r2)
(28/29) Installing python3 (3.8.2-r1)
(29/29) Installing python3-dev (3.8.2-r1)
Executing busybox-1.31.1-r9.trigger
Executing ca-certificates-20191127-r0.trigger
OK: 236 MiB in 47 packages
$ pip3 install docker-compose
Collecting docker-compose
  Downloading https://files.pythonhosted.org/packages/7c/77/81c70954ddc772abd366f04b4e94e3cd5b73670ef175c154144762c8fadb/docker_compose-1.27.0-py2.py3-none-any.whl (109kB)
Collecting dockerpty<1,>=0.4.1 (from docker-compose)
  Downloading https://files.pythonhosted.org/packages/8d/ee/e9ecce4c32204a6738e0a5d5883d3413794d7498fe8b06f44becc028d3ba/dockerpty-0.4.1.tar.gz
Collecting docopt<1,>=0.6.1 (from docker-compose)
  Downloading https://files.pythonhosted.org/packages/a2/55/8f8cab2afd404cf578136ef2cc5dfb50baa1761b68c9da1fb1e4eed343c9/docopt-0.6.2.tar.gz
Collecting python-dotenv<1,>=0.13.0 (from docker-compose)
  Downloading https://files.pythonhosted.org/packages/f2/16/28d434b28c5be29a6af8fd0e3a2bda3bd30500ef0cd17bc79f7a6793a8d4/python_dotenv-0.14.0-py2.py3-none-any.whl
Collecting docker[ssh]<5,>=4.3.1 (from docker-compose)
  Downloading https://files.pythonhosted.org/packages/9e/8c/8d42dbd83679483db207535f4fb02dc84325fa78b290f057694b057fcd21/docker-4.3.1-py2.py3-none-any.whl (145kB)
Collecting PyYAML<6,>=3.10 (from docker-compose)
  Downloading https://files.pythonhosted.org/packages/64/c2/b80047c7ac2478f9501676c988a5411ed5572f35d1beff9cae07d321512c/PyYAML-5.3.1.tar.gz (269kB)
Collecting cached-property<2,>=1.2.0 (from docker-compose)
  Downloading https://files.pythonhosted.org/packages/3b/86/85c1be2e8db9e13ef9a350aecd6dea292bd612fa288c2f40d035bb750ded/cached_property-1.5.1-py2.py3-none-any.whl
Collecting texttable<2,>=0.9.0 (from docker-compose)
  Downloading https://files.pythonhosted.org/packages/06/f5/46201c428aebe0eecfa83df66bf3e6caa29659dbac5a56ddfd83cae0d4a4/texttable-1.6.3-py2.py3-none-any.whl
Collecting websocket-client<1,>=0.32.0 (from docker-compose)
  Downloading https://files.pythonhosted.org/packages/4c/5f/f61b420143ed1c8dc69f9eaec5ff1ac36109d52c80de49d66e0c36c3dfdf/websocket_client-0.57.0-py2.py3-none-any.whl (200kB)
Collecting jsonschema<4,>=2.5.1 (from docker-compose)
  Downloading https://files.pythonhosted.org/packages/c5/8f/51e89ce52a085483359217bc72cdbf6e75ee595d5b1d4b5ade40c7e018b8/jsonschema-3.2.0-py2.py3-none-any.whl (56kB)
Collecting requests<3,>=2.20.0 (from docker-compose)
  Downloading https://files.pythonhosted.org/packages/45/1e/0c169c6a5381e241ba7404532c16a21d86ab872c9bed8bdcd4c423954103/requests-2.24.0-py2.py3-none-any.whl (61kB)
Collecting distro<2,>=1.5.0 (from docker-compose)
  Downloading https://files.pythonhosted.org/packages/25/b7/b3c4270a11414cb22c6352ebc7a83aaa3712043be29daa05018fd5a5c956/distro-1.5.0-py2.py3-none-any.whl
Collecting six>=1.3.0 (from dockerpty<1,>=0.4.1->docker-compose)
  Downloading https://files.pythonhosted.org/packages/ee/ff/48bde5c0f013094d729fe4b0316ba2a24774b3ff1c52d924a8a4cb04078a/six-1.15.0-py2.py3-none-any.whl
Collecting paramiko>=2.4.2; extra == "ssh" (from docker[ssh]<5,>=4.3.1->docker-compose)
  Downloading https://files.pythonhosted.org/packages/95/19/124e9287b43e6ff3ebb9cdea3e5e8e88475a873c05ccdf8b7e20d2c4201e/paramiko-2.7.2-py2.py3-none-any.whl (206kB)
Collecting attrs>=17.4.0 (from jsonschema<4,>=2.5.1->docker-compose)
  Downloading https://files.pythonhosted.org/packages/14/df/479736ae1ef59842f512548bacefad1abed705e400212acba43f9b0fa556/attrs-20.2.0-py2.py3-none-any.whl (48kB)
Collecting pyrsistent>=0.14.0 (from jsonschema<4,>=2.5.1->docker-compose)
  Downloading https://files.pythonhosted.org/packages/9f/0d/cbca4d0bbc5671822a59f270e4ce3f2195f8a899c97d0d5abb81b191efb5/pyrsistent-0.16.0.tar.gz (108kB)
Requirement already satisfied: setuptools in /usr/lib/python3.8/site-packages (from jsonschema<4,>=2.5.1->docker-compose) (41.2.0)
Collecting chardet<4,>=3.0.2 (from requests<3,>=2.20.0->docker-compose)
  Downloading https://files.pythonhosted.org/packages/bc/a9/01ffebfb562e4274b6487b4bb1ddec7ca55ec7510b22e4c51f14098443b8/chardet-3.0.4-py2.py3-none-any.whl (133kB)
Collecting urllib3!=1.25.0,!=1.25.1,<1.26,>=1.21.1 (from requests<3,>=2.20.0->docker-compose)
  Downloading https://files.pythonhosted.org/packages/9f/f0/a391d1463ebb1b233795cabfc0ef38d3db4442339de68f847026199e69d7/urllib3-1.25.10-py2.py3-none-any.whl (127kB)
Collecting certifi>=2017.4.17 (from requests<3,>=2.20.0->docker-compose)
  Downloading https://files.pythonhosted.org/packages/5e/c4/6c4fe722df5343c33226f0b4e0bb042e4dc13483228b4718baf286f86d87/certifi-2020.6.20-py2.py3-none-any.whl (156kB)
Collecting idna<3,>=2.5 (from requests<3,>=2.20.0->docker-compose)
  Downloading https://files.pythonhosted.org/packages/a2/38/928ddce2273eaa564f6f50de919327bf3a00f091b5baba8dfa9460f3a8a8/idna-2.10-py2.py3-none-any.whl (58kB)
Collecting cryptography>=2.5 (from paramiko>=2.4.2; extra == "ssh"->docker[ssh]<5,>=4.3.1->docker-compose)
  Downloading https://files.pythonhosted.org/packages/12/be/c9cc7d7ab71dbcc9e4e517ead0cdd48e8c9a48d7b8bdddb738e90d08279a/cryptography-3.1.tar.gz (534kB)
  Installing build dependencies: started
  Installing build dependencies: finished with status 'done'
  Getting requirements to build wheel: started
  Getting requirements to build wheel: finished with status 'done'
    Preparing wheel metadata: started
    Preparing wheel metadata: finished with status 'done'
Collecting bcrypt>=3.1.3 (from paramiko>=2.4.2; extra == "ssh"->docker[ssh]<5,>=4.3.1->docker-compose)
  Downloading https://files.pythonhosted.org/packages/d8/ba/21c475ead997ee21502d30f76fd93ad8d5858d19a3fad7cd153de698c4dd/bcrypt-3.2.0.tar.gz (42kB)
  Installing build dependencies: started
  Installing build dependencies: finished with status 'done'
  Getting requirements to build wheel: started
  Getting requirements to build wheel: finished with status 'done'
    Preparing wheel metadata: started
    Preparing wheel metadata: finished with status 'done'
Collecting pynacl>=1.0.1 (from paramiko>=2.4.2; extra == "ssh"->docker[ssh]<5,>=4.3.1->docker-compose)
  Downloading https://files.pythonhosted.org/packages/cf/5a/25aeb636baeceab15c8e57e66b8aa930c011ec1c035f284170cacb05025e/PyNaCl-1.4.0.tar.gz (3.4MB)
  Installing build dependencies: started
  Installing build dependencies: finished with status 'done'
  Getting requirements to build wheel: started
  Getting requirements to build wheel: finished with status 'done'
    Preparing wheel metadata: started
    Preparing wheel metadata: finished with status 'done'
Collecting cffi!=1.11.3,>=1.8 (from cryptography>=2.5->paramiko>=2.4.2; extra == "ssh"->docker[ssh]<5,>=4.3.1->docker-compose)
  Using cached https://files.pythonhosted.org/packages/f7/09/88bbe20b76ca76be052c366fe77aa5e3cd6e5f932766e5597fecdd95b2a8/cffi-1.14.2.tar.gz
Collecting pycparser (from cffi!=1.11.3,>=1.8->cryptography>=2.5->paramiko>=2.4.2; extra == "ssh"->docker[ssh]<5,>=4.3.1->docker-compose)
  Using cached https://files.pythonhosted.org/packages/ae/e7/d9c3a176ca4b02024debf82342dab36efadfc5776f9c8db077e8f6e71821/pycparser-2.20-py2.py3-none-any.whl
Building wheels for collected packages: cryptography, bcrypt, pynacl
  Building wheel for cryptography (PEP 517): started
  Building wheel for cryptography (PEP 517): finished with status 'done'
  Created wheel for cryptography: filename=cryptography-3.1-cp38-cp38-linux_x86_64.whl size=758584 sha256=cf3a62a6572cb7ddf7a8e5fdb8fee4534b4eb1ae6254b47c13fd93a179772f24
  Stored in directory: /root/.cache/pip/wheels/e7/11/7c/a81932ebc1516820af531b80dd6620bdd4798012be1cf07e03
  Building wheel for bcrypt (PEP 517): started
  Building wheel for bcrypt (PEP 517): finished with status 'done'
  Created wheel for bcrypt: filename=bcrypt-3.2.0-cp38-cp38-linux_x86_64.whl size=56570 sha256=08ede1120c084750b56379973749bdee11a6cfbe5a81e02881051fffa2c63b8f
  Stored in directory: /root/.cache/pip/wheels/df/ff/0f/e588ec95ea51f480627d66264f5587f4105694ab7ddd61e711
  Building wheel for pynacl (PEP 517): started
  Building wheel for pynacl (PEP 517): still running...
  Building wheel for pynacl (PEP 517): finished with status 'done'
  Created wheel for pynacl: filename=PyNaCl-1.4.0-cp38-cp38-linux_x86_64.whl size=877418 sha256=a7ef92b14454f35eaa66895b829a9e44ce001bac057257c522728c5127f356c3
  Stored in directory: /root/.cache/pip/wheels/c2/d9/ca/81e002fb0cee770c198715dace9edab919daba3ae49bd287a0
Successfully built cryptography bcrypt pynacl
Installing collected packages: six, dockerpty, docopt, python-dotenv, chardet, urllib3, certifi, idna, requests, websocket-client, pycparser, cffi, cryptography, bcrypt, pynacl, paramiko, docker, PyYAML, cached-property, texttable, attrs, pyrsistent, jsonschema, distro, docker-compose
  Running setup.py install for dockerpty: started
    Running setup.py install for dockerpty: finished with status 'done'
  Running setup.py install for docopt: started
    Running setup.py install for docopt: finished with status 'done'
  Running setup.py install for cffi: started
    Running setup.py install for cffi: finished with status 'done'
  Running setup.py install for PyYAML: started
    Running setup.py install for PyYAML: finished with status 'done'
  Running setup.py install for pyrsistent: started
    Running setup.py install for pyrsistent: finished with status 'done'
Successfully installed PyYAML-5.3.1 attrs-20.2.0 bcrypt-3.2.0 cached-property-1.5.1 certifi-2020.6.20 cffi-1.14.2 chardet-3.0.4 cryptography-3.1 distro-1.5.0 docker-4.3.1 docker-compose-1.27.0 dockerpty-0.4.1 docopt-0.6.2 idna-2.10 jsonschema-3.2.0 paramiko-2.7.2 pycparser-2.20 pynacl-1.4.0 pyrsistent-0.16.0 python-dotenv-0.14.0 requests-2.24.0 six-1.15.0 texttable-1.6.3 urllib3-1.25.10 websocket-client-0.57.0
WARNING: You are using pip version 19.2.3, however version 20.2.2 is available.
You should consider upgrading via the 'pip install --upgrade pip' command.
$ docker-compose run --rm app sh -c "python manage.py wait_for_db && python manage.py test && flake8"
Creating network "recipe-app-api-devops_default" with the default driver
Pulling db (postgres:10-alpine)...
10-alpine: Pulling from library/postgres
Digest: sha256:4fadacdb3bd2e23c51bc36cf5e1b5eabc04b192ee64579d27dbe78f826059c22
Status: Downloaded newer image for postgres:10-alpine
Building app
Step 1/22 : FROM python:3.7-alpine
3.7-alpine: Pulling from library/python
Digest: sha256:d1375bf0b889822c603622dc137b24fb7064e6c1863de8cc4262b61901ce4390
Status: Downloaded newer image for python:3.7-alpine
 ---> 078114edb6be
Step 2/22 : LABEL maitainer="Jan Endel"
 ---> Running in efa3f9249284
Removing intermediate container efa3f9249284
 ---> cf1660658228
Step 3/22 : ENV PYTHONUNBUFFERED 1
 ---> Running in 56dd4e616014
Removing intermediate container 56dd4e616014
 ---> de83f6a5aede
Step 4/22 : ENV PATH="/scripts:${PATH}"
 ---> Running in 4d7e92e492de
Removing intermediate container 4d7e92e492de
 ---> fdb92053b1bf
Step 5/22 : RUN pip install --upgrade pip
 ---> Running in 8377d3e6caa0
Requirement already up-to-date: pip in /usr/local/lib/python3.7/site-packages (20.2.2)
Removing intermediate container 8377d3e6caa0
 ---> 8a1e801b6411
Step 6/22 : COPY ./requirements.txt /requirements.txt
 ---> 4d7f992cce9a
Step 7/22 : RUN apk add --update --no-cache postgresql-client jpeg-dev
 ---> Running in 932c45f7775d
fetch http://dl-cdn.alpinelinux.org/alpine/v3.12/main/x86_64/APKINDEX.tar.gz
fetch http://dl-cdn.alpinelinux.org/alpine/v3.12/community/x86_64/APKINDEX.tar.gz
(1/10) Installing libjpeg-turbo (2.0.5-r0)
(2/10) Installing pkgconf (1.7.2-r0)
(3/10) Installing libjpeg-turbo-dev (2.0.5-r0)
(4/10) Installing jpeg-dev (9d-r0)
(5/10) Installing libedit (20191231.3.1-r0)
(6/10) Installing db (5.3.28-r1)
(7/10) Installing libsasl (2.1.27-r6)
(8/10) Installing libldap (2.4.50-r0)
(9/10) Installing libpq (12.4-r0)
(10/10) Installing postgresql-client (12.4-r0)
Executing busybox-1.31.1-r16.trigger
OK: 17 MiB in 45 packages
Removing intermediate container 932c45f7775d
 ---> b09b245d9756
Step 8/22 : RUN apk add --update --no-cache --virtual .tmp-build-deps       gcc libc-dev linux-headers postgresql-dev musl-dev zlib zlib-dev
 ---> Running in 7859db9f87d5
fetch http://dl-cdn.alpinelinux.org/alpine/v3.12/main/x86_64/APKINDEX.tar.gz
fetch http://dl-cdn.alpinelinux.org/alpine/v3.12/community/x86_64/APKINDEX.tar.gz
(1/27) Installing libgcc (9.3.0-r2)
(2/27) Installing libstdc++ (9.3.0-r2)
(3/27) Installing binutils (2.34-r1)
(4/27) Installing gmp (6.2.0-r0)
(5/27) Installing isl (0.18-r0)
(6/27) Installing libgomp (9.3.0-r2)
(7/27) Installing libatomic (9.3.0-r2)
(8/27) Installing libgphobos (9.3.0-r2)
(9/27) Installing mpfr4 (4.0.2-r4)
(10/27) Installing mpc1 (1.1.0-r1)
(11/27) Installing gcc (9.3.0-r2)
(12/27) Installing musl-dev (1.1.24-r9)
(13/27) Installing libc-dev (0.7.2-r3)
(14/27) Installing linux-headers (5.4.5-r1)
(15/27) Installing libxml2 (2.9.10-r4)
(16/27) Installing llvm10-libs (10.0.0-r2)
(17/27) Installing clang-libs (10.0.0-r2)
(18/27) Installing clang (10.0.0-r2)
(19/27) Installing llvm10 (10.0.0-r2)
(20/27) Installing openssl-dev (1.1.1g-r0)
(21/27) Installing icu-libs (67.1-r0)
(22/27) Installing icu (67.1-r0)
(23/27) Installing icu-dev (67.1-r0)
(24/27) Installing postgresql-libs (12.4-r0)
(25/27) Installing postgresql-dev (12.4-r0)
(26/27) Installing zlib-dev (1.2.11-r3)
(27/27) Installing .tmp-build-deps (20200907.225927)
Executing busybox-1.31.1-r16.trigger
OK: 365 MiB in 72 packages
Removing intermediate container 7859db9f87d5
 ---> f95b45742404
Step 9/22 : RUN pip install -r /requirements.txt
 ---> Running in de81c95d345d
Collecting Django<2.2.0,>=2.1.3
  Downloading Django-2.1.15-py3-none-any.whl (7.3 MB)
Collecting djangorestframework<3.10.0,>=3.9.0
  Downloading djangorestframework-3.9.4-py2.py3-none-any.whl (911 kB)
Collecting psycopg2<2.8.0,>=2.7.5
  Downloading psycopg2-2.7.7.tar.gz (427 kB)
Collecting Pillow<5.4.0,>=5.3.0
  Downloading Pillow-5.3.0.tar.gz (15.6 MB)
Collecting uwsgi<2.1.0,>=2.0.18
  Downloading uWSGI-2.0.19.1.tar.gz (803 kB)
Collecting flake8<3.7.0,>=3.6.0
  Downloading flake8-3.6.0-py2.py3-none-any.whl (68 kB)
Collecting pytz
  Downloading pytz-2020.1-py2.py3-none-any.whl (510 kB)
Collecting mccabe<0.7.0,>=0.6.0
  Downloading mccabe-0.6.1-py2.py3-none-any.whl (8.6 kB)
Requirement already satisfied: setuptools>=30 in /usr/local/lib/python3.7/site-packages (from flake8<3.7.0,>=3.6.0->-r /requirements.txt (line 7)) (49.6.0)
Collecting pyflakes<2.1.0,>=2.0.0
  Downloading pyflakes-2.0.0-py2.py3-none-any.whl (53 kB)
Collecting pycodestyle<2.5.0,>=2.4.0
  Downloading pycodestyle-2.4.0-py2.py3-none-any.whl (62 kB)
Building wheels for collected packages: psycopg2, Pillow, uwsgi
  Building wheel for psycopg2 (setup.py): started
  Building wheel for psycopg2 (setup.py): finished with status 'done'
  Created wheel for psycopg2: filename=psycopg2-2.7.7-cp37-cp37m-linux_x86_64.whl size=224737 sha256=190f6fe9bbd36b1ef557ea880036cbc2668a2163ee2ad83bc80de35af6016316
  Stored in directory: /root/.cache/pip/wheels/3d/c8/cd/d43fc1edb0127f01f4dee6789fd45d8e93e211e0fd9a04b824
  Building wheel for Pillow (setup.py): started
  Building wheel for Pillow (setup.py): finished with status 'done'
  Created wheel for Pillow: filename=Pillow-5.3.0-cp37-cp37m-linux_x86_64.whl size=491734 sha256=3e14c4b93a313c32a801b852a733cb2adf80d9501006166f4033872fc0b7861b
  Stored in directory: /root/.cache/pip/wheels/37/1c/ef/a8aa77ac404747027e681948bc0a8925b96e96f8caa36cb00d
  Building wheel for uwsgi (setup.py): started
  Building wheel for uwsgi (setup.py): finished with status 'done'
  Created wheel for uwsgi: filename=uWSGI-2.0.19.1-cp37-cp37m-linux_x86_64.whl size=546204 sha256=37761363230f5b08f204352470de1e88fe442f613ca288f5504e72e4f9427132
  Stored in directory: /root/.cache/pip/wheels/0c/f9/df/fb7fb12204a8f5c67682f31e10485863c24123a2a626e71203
Successfully built psycopg2 Pillow uwsgi
Installing collected packages: pytz, Django, djangorestframework, psycopg2, Pillow, uwsgi, mccabe, pyflakes, pycodestyle, flake8
Successfully installed Django-2.1.15 Pillow-5.3.0 djangorestframework-3.9.4 flake8-3.6.0 mccabe-0.6.1 psycopg2-2.7.7 pycodestyle-2.4.0 pyflakes-2.0.0 pytz-2020.1 uwsgi-2.0.19.1
Removing intermediate container de81c95d345d
 ---> 7dcd01bbc879
Step 10/22 : RUN apk del .tmp-build-deps
 ---> Running in 8f7dc6f80466
WARNING: Ignoring APKINDEX.2c4ac24e.tar.gz: No such file or directory
WARNING: Ignoring APKINDEX.40a3604f.tar.gz: No such file or directory
(1/27) Purging .tmp-build-deps (20200907.225927)
(2/27) Purging gcc (9.3.0-r2)
(3/27) Purging binutils (2.34-r1)
(4/27) Purging libatomic (9.3.0-r2)
(5/27) Purging libgomp (9.3.0-r2)
(6/27) Purging libgphobos (9.3.0-r2)
(7/27) Purging libc-dev (0.7.2-r3)
(8/27) Purging linux-headers (5.4.5-r1)
(9/27) Purging postgresql-dev (12.4-r0)
(10/27) Purging clang (10.0.0-r2)
(11/27) Purging openssl-dev (1.1.1g-r0)
(12/27) Purging icu-dev (67.1-r0)
(13/27) Purging icu (67.1-r0)
(14/27) Purging icu-libs (67.1-r0)
(15/27) Purging postgresql-libs (12.4-r0)
(16/27) Purging musl-dev (1.1.24-r9)
(17/27) Purging zlib-dev (1.2.11-r3)
(18/27) Purging llvm10 (10.0.0-r2)
(19/27) Purging clang-libs (10.0.0-r2)
(20/27) Purging llvm10-libs (10.0.0-r2)
(21/27) Purging libstdc++ (9.3.0-r2)
(22/27) Purging libgcc (9.3.0-r2)
(23/27) Purging isl (0.18-r0)
(24/27) Purging mpc1 (1.1.0-r1)
(25/27) Purging mpfr4 (4.0.2-r4)
(26/27) Purging gmp (6.2.0-r0)
(27/27) Purging libxml2 (2.9.10-r4)
Executing busybox-1.31.1-r16.trigger
OK: 17 MiB in 45 packages
Removing intermediate container 8f7dc6f80466
 ---> c25965ea3af7
Step 11/22 : RUN mkdir /app
 ---> Running in a564ac0835ee
Removing intermediate container a564ac0835ee
 ---> 360e7d6d5c3e
Step 12/22 : WORKDIR /app
 ---> Running in b1626dd9c162
Removing intermediate container b1626dd9c162
 ---> 52cfb7440cf3
Step 13/22 : COPY ./app /app
 ---> 94ce73877050
Step 14/22 : COPY ./scripts /scripts
 ---> 73e3a323a5c8
Step 15/22 : RUN chmod +x /scripts/*
 ---> Running in 02a46a25867b
Removing intermediate container 02a46a25867b
 ---> 2329e6bfdefd
Step 16/22 : RUN mkdir -p /vol/web/media
 ---> Running in dda40d9ff85b
Removing intermediate container dda40d9ff85b
 ---> 6fbe93a2fc95
Step 17/22 : RUN mkdir -p /vol/web/static
 ---> Running in a330a48c7d25
Removing intermediate container a330a48c7d25
 ---> 7c617e0a840f
Step 18/22 : RUN adduser -D user
 ---> Running in 61003e6f3a45
Removing intermediate container 61003e6f3a45
 ---> 4176b923556a
Step 19/22 : RUN chown -R user:user /vol/
 ---> Running in d4b7e7376fc3
Removing intermediate container d4b7e7376fc3
 ---> 2960a22dba4d
Step 20/22 : RUN chmod -R 755 /vol/web
 ---> Running in ca3c2e9a425b
Removing intermediate container ca3c2e9a425b
 ---> 0d043719edeb
Step 21/22 : USER user
 ---> Running in 2510ecfffc12
Removing intermediate container 2510ecfffc12
 ---> b4d1931e0a2d
Step 22/22 : CMD ["entrypoint.sh"]
 ---> Running in a8aa872d39d8
Removing intermediate container a8aa872d39d8
 ---> 92779f98e17a

Successfully built 92779f98e17a
Successfully tagged recipe-app-api-devops_app:latest
Image for service app was built because it did not already exist. To rebuild this image you must use `docker-compose build` or `docker-compose up --build`.
Creating recipe-app-api-devops_db_1 ... 

Creating recipe-app-api-devops_db_1 ... done
Creating recipe-app-api-devops_app_run ... 

Creating recipe-app-api-devops_app_run ... done
Waiting for database...
Database available!
Creating test database for alias 'default'...
/usr/local/lib/python3.7/site-packages/django/db/backends/postgresql/base.py:269: RuntimeWarning: Normally Django will use a connection to the 'postgres' database to avoid running initialization queries against the production database when it's not needed (for example, when running tests). Django was unable to create a connection to the 'postgres' database and will use the first PostgreSQL database instead.
  RuntimeWarning
Traceback (most recent call last):
  File "/usr/local/lib/python3.7/site-packages/django/db/backends/base/base.py", line 216, in ensure_connection
    self.connect()
  File "/usr/local/lib/python3.7/site-packages/django/db/backends/base/base.py", line 194, in connect
    self.connection = self.get_new_connection(conn_params)
  File "/usr/local/lib/python3.7/site-packages/django/db/backends/postgresql/base.py", line 178, in get_new_connection
    connection = Database.connect(**conn_params)
  File "/usr/local/lib/python3.7/site-packages/psycopg2/__init__.py", line 130, in connect
    conn = _connect(dsn, connection_factory=connection_factory, **kwasync)
psycopg2.OperationalError: could not connect to server: Connection refused
	Is the server running on host "db" (172.19.0.2) and accepting
	TCP/IP connections on port 5432?


The above exception was the direct cause of the following exception:

Traceback (most recent call last):
  File "manage.py", line 15, in <module>
    execute_from_command_line(sys.argv)
  File "/usr/local/lib/python3.7/site-packages/django/core/management/__init__.py", line 381, in execute_from_command_line
    utility.execute()
  File "/usr/local/lib/python3.7/site-packages/django/core/management/__init__.py", line 375, in execute
    self.fetch_command(subcommand).run_from_argv(self.argv)
  File "/usr/local/lib/python3.7/site-packages/django/core/management/commands/test.py", line 26, in run_from_argv
    super().run_from_argv(argv)
  File "/usr/local/lib/python3.7/site-packages/django/core/management/base.py", line 316, in run_from_argv
    self.execute(*args, **cmd_options)
  File "/usr/local/lib/python3.7/site-packages/django/core/management/base.py", line 353, in execute
    output = self.handle(*args, **options)
  File "/usr/local/lib/python3.7/site-packages/django/core/management/commands/test.py", line 56, in handle
    failures = test_runner.run_tests(test_labels)
  File "/usr/local/lib/python3.7/site-packages/django/test/runner.py", line 604, in run_tests
    old_config = self.setup_databases()
  File "/usr/local/lib/python3.7/site-packages/django/test/runner.py", line 551, in setup_databases
    self.parallel, **kwargs
  File "/usr/local/lib/python3.7/site-packages/django/test/utils.py", line 174, in setup_databases
    serialize=connection.settings_dict.get('TEST', {}).get('SERIALIZE', True),
  File "/usr/local/lib/python3.7/site-packages/django/db/backends/base/creation.py", line 54, in create_test_db
    self._create_test_db(verbosity, autoclobber, keepdb)
  File "/usr/local/lib/python3.7/site-packages/django/db/backends/base/creation.py", line 164, in _create_test_db
    with self._nodb_connection.cursor() as cursor:
  File "/usr/local/lib/python3.7/site-packages/django/db/backends/base/base.py", line 255, in cursor
    return self._cursor()
  File "/usr/local/lib/python3.7/site-packages/django/db/backends/base/base.py", line 232, in _cursor
    self.ensure_connection()
  File "/usr/local/lib/python3.7/site-packages/django/db/backends/base/base.py", line 216, in ensure_connection
    self.connect()
  File "/usr/local/lib/python3.7/site-packages/django/db/utils.py", line 89, in __exit__
    raise dj_exc_value.with_traceback(traceback) from exc_value
  File "/usr/local/lib/python3.7/site-packages/django/db/backends/base/base.py", line 216, in ensure_connection
    self.connect()
  File "/usr/local/lib/python3.7/site-packages/django/db/backends/base/base.py", line 194, in connect
    self.connection = self.get_new_connection(conn_params)
  File "/usr/local/lib/python3.7/site-packages/django/db/backends/postgresql/base.py", line 178, in get_new_connection
    connection = Database.connect(**conn_params)
  File "/usr/local/lib/python3.7/site-packages/psycopg2/__init__.py", line 130, in connect
    conn = _connect(dsn, connection_factory=connection_factory, **kwasync)
django.db.utils.OperationalError: could not connect to server: Connection refused
	Is the server running on host "db" (172.19.0.2) and accepting
	TCP/IP connections on port 5432?

section_end:1599519688:step_script
ERROR: Job failed: exit code 1