allright_version

allright.txt

Running with gitlab-runner 13.3.1 (738bbe5a)
  on docker-auto-scale ed2dce3a
section_start:1599426572: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-ed2dce3a-project-20828281-concurrent-0-cb41a48c8ee1ea00-docker-0 probably didn't start properly.

Health check error:
service "runner-ed2dce3a-project-20828281-concurrent-0-cb41a48c8ee1ea00-docker-0-wait-for-service" timeout

Health check container logs:


Service container logs:
2020-09-06T21:09:55.131801290Z time="2020-09-06T21:09:55.101773554Z" level=info msg="Starting up"
2020-09-06T21:09:55.134672196Z time="2020-09-06T21:09:55.104839908Z" level=warning msg="could not change group /var/run/docker.sock to docker: group docker not found"
2020-09-06T21:09:55.134678027Z time="2020-09-06T21:09:55.105058684Z" level=warning msg="[!] DON'T BIND ON ANY IP ADDRESS WITHOUT setting --tlsverify IF YOU DON'T KNOW WHAT YOU'RE DOING [!]"
2020-09-06T21:09:55.134681531Z time="2020-09-06T21:09:55.106015924Z" level=info msg="libcontainerd: started new containerd process" pid=19
2020-09-06T21:09:55.134684922Z time="2020-09-06T21:09:55.106053893Z" level=info msg="parsed scheme: \"unix\"" module=grpc
2020-09-06T21:09:55.134688502Z time="2020-09-06T21:09:55.106061937Z" level=info msg="scheme \"unix\" not registered, fallback to default scheme" module=grpc
2020-09-06T21:09:55.134691882Z time="2020-09-06T21:09:55.106092026Z" level=info msg="ccResolverWrapper: sending update to cc: {[{unix:///var/run/docker/containerd/containerd.sock 0  <nil>}] <nil>}" module=grpc
2020-09-06T21:09:55.134695981Z time="2020-09-06T21:09:55.106101447Z" level=info msg="ClientConn switching balancer to \"pick_first\"" module=grpc
2020-09-06T21:09:55.157677696Z time="2020-09-06T21:09:55.145268399Z" level=info msg="starting containerd" revision=b34a5c8af56e510852c35414db4c1f4fa6172339 version=v1.2.10 
2020-09-06T21:09:55.157713706Z time="2020-09-06T21:09:55.146655463Z" level=info msg="loading plugin "io.containerd.content.v1.content"..." type=io.containerd.content.v1 
2020-09-06T21:09:55.157718602Z time="2020-09-06T21:09:55.146765774Z" level=info msg="loading plugin "io.containerd.snapshotter.v1.btrfs"..." type=io.containerd.snapshotter.v1 
2020-09-06T21:09:55.157723256Z time="2020-09-06T21:09:55.146983782Z" 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-06T21:09:55.157729519Z time="2020-09-06T21:09:55.146996873Z" level=info msg="loading plugin "io.containerd.snapshotter.v1.aufs"..." type=io.containerd.snapshotter.v1 
2020-09-06T21:09:55.169505447Z time="2020-09-06T21:09:55.168610826Z" 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-06T21:09:55.169524213Z time="2020-09-06T21:09:55.168637146Z" level=info msg="loading plugin "io.containerd.snapshotter.v1.native"..." type=io.containerd.snapshotter.v1 
2020-09-06T21:09:55.169528381Z time="2020-09-06T21:09:55.168759558Z" level=info msg="loading plugin "io.containerd.snapshotter.v1.overlayfs"..." type=io.containerd.snapshotter.v1 
2020-09-06T21:09:55.169531927Z time="2020-09-06T21:09:55.168897818Z" level=info msg="loading plugin "io.containerd.snapshotter.v1.zfs"..." type=io.containerd.snapshotter.v1 
2020-09-06T21:09:55.169537673Z time="2020-09-06T21:09:55.169127248Z" level=info msg="skip loading plugin "io.containerd.snapshotter.v1.zfs"..." type=io.containerd.snapshotter.v1 
2020-09-06T21:09:55.169541254Z time="2020-09-06T21:09:55.169136389Z" level=info msg="loading plugin "io.containerd.metadata.v1.bolt"..." type=io.containerd.metadata.v1 
2020-09-06T21:09:55.169552381Z time="2020-09-06T21:09:55.169177997Z" 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-06T21:09:55.169635077Z time="2020-09-06T21:09:55.169185404Z" 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-06T21:09:55.169642701Z time="2020-09-06T21:09:55.169192282Z" 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-06T21:09:55.207396463Z time="2020-09-06T21:09:55.197323500Z" level=info msg="loading plugin "io.containerd.differ.v1.walking"..." type=io.containerd.differ.v1 
2020-09-06T21:09:55.207431744Z time="2020-09-06T21:09:55.197358901Z" level=info msg="loading plugin "io.containerd.gc.v1.scheduler"..." type=io.containerd.gc.v1 
2020-09-06T21:09:55.207436058Z time="2020-09-06T21:09:55.197390833Z" level=info msg="loading plugin "io.containerd.service.v1.containers-service"..." type=io.containerd.service.v1 
2020-09-06T21:09:55.207439795Z time="2020-09-06T21:09:55.197403480Z" level=info msg="loading plugin "io.containerd.service.v1.content-service"..." type=io.containerd.service.v1 
2020-09-06T21:09:55.207443370Z time="2020-09-06T21:09:55.197413750Z" level=info msg="loading plugin "io.containerd.service.v1.diff-service"..." type=io.containerd.service.v1 
2020-09-06T21:09:55.207447058Z time="2020-09-06T21:09:55.197424860Z" level=info msg="loading plugin "io.containerd.service.v1.images-service"..." type=io.containerd.service.v1 
2020-09-06T21:09:55.207450384Z time="2020-09-06T21:09:55.197436542Z" level=info msg="loading plugin "io.containerd.service.v1.leases-service"..." type=io.containerd.service.v1 
2020-09-06T21:09:55.207453600Z time="2020-09-06T21:09:55.197447915Z" level=info msg="loading plugin "io.containerd.service.v1.namespaces-service"..." type=io.containerd.service.v1 
2020-09-06T21:09:55.207457188Z time="2020-09-06T21:09:55.197457928Z" level=info msg="loading plugin "io.containerd.service.v1.snapshots-service"..." type=io.containerd.service.v1 
2020-09-06T21:09:55.207460585Z time="2020-09-06T21:09:55.197469452Z" level=info msg="loading plugin "io.containerd.runtime.v1.linux"..." type=io.containerd.runtime.v1 
2020-09-06T21:09:55.207463867Z time="2020-09-06T21:09:55.197711489Z" level=info msg="loading plugin "io.containerd.runtime.v2.task"..." type=io.containerd.runtime.v2 
2020-09-06T21:09:55.207467089Z time="2020-09-06T21:09:55.197814993Z" level=info msg="loading plugin "io.containerd.monitor.v1.cgroups"..." type=io.containerd.monitor.v1 
2020-09-06T21:09:55.207470305Z time="2020-09-06T21:09:55.198127257Z" level=info msg="loading plugin "io.containerd.service.v1.tasks-service"..." type=io.containerd.service.v1 
2020-09-06T21:09:55.207473613Z time="2020-09-06T21:09:55.198152268Z" level=info msg="loading plugin "io.containerd.internal.v1.restart"..." type=io.containerd.internal.v1 
2020-09-06T21:09:55.207487851Z time="2020-09-06T21:09:55.198188561Z" level=info msg="loading plugin "io.containerd.grpc.v1.containers"..." type=io.containerd.grpc.v1 
2020-09-06T21:09:55.207491388Z time="2020-09-06T21:09:55.198199975Z" level=info msg="loading plugin "io.containerd.grpc.v1.content"..." type=io.containerd.grpc.v1 
2020-09-06T21:09:55.207494672Z time="2020-09-06T21:09:55.198210025Z" level=info msg="loading plugin "io.containerd.grpc.v1.diff"..." type=io.containerd.grpc.v1 
2020-09-06T21:09:55.207497921Z time="2020-09-06T21:09:55.198219836Z" level=info msg="loading plugin "io.containerd.grpc.v1.events"..." type=io.containerd.grpc.v1 
2020-09-06T21:09:55.207501092Z time="2020-09-06T21:09:55.198229866Z" level=info msg="loading plugin "io.containerd.grpc.v1.healthcheck"..." type=io.containerd.grpc.v1 
2020-09-06T21:09:55.207504341Z time="2020-09-06T21:09:55.198239780Z" level=info msg="loading plugin "io.containerd.grpc.v1.images"..." type=io.containerd.grpc.v1 
2020-09-06T21:09:55.207507573Z time="2020-09-06T21:09:55.198249402Z" level=info msg="loading plugin "io.containerd.grpc.v1.leases"..." type=io.containerd.grpc.v1 
2020-09-06T21:09:55.207510694Z time="2020-09-06T21:09:55.198259145Z" level=info msg="loading plugin "io.containerd.grpc.v1.namespaces"..." type=io.containerd.grpc.v1 
2020-09-06T21:09:55.207513933Z time="2020-09-06T21:09:55.198268250Z" level=info msg="loading plugin "io.containerd.internal.v1.opt"..." type=io.containerd.internal.v1 
2020-09-06T21:09:55.207517125Z time="2020-09-06T21:09:55.198512523Z" level=info msg="loading plugin "io.containerd.grpc.v1.snapshots"..." type=io.containerd.grpc.v1 
2020-09-06T21:09:55.207520277Z time="2020-09-06T21:09:55.198526505Z" level=info msg="loading plugin "io.containerd.grpc.v1.tasks"..." type=io.containerd.grpc.v1 
2020-09-06T21:09:55.207523417Z time="2020-09-06T21:09:55.198536821Z" level=info msg="loading plugin "io.containerd.grpc.v1.version"..." type=io.containerd.grpc.v1 
2020-09-06T21:09:55.207526597Z time="2020-09-06T21:09:55.198547365Z" level=info msg="loading plugin "io.containerd.grpc.v1.introspection"..." type=io.containerd.grpc.v1 
2020-09-06T21:09:55.207529823Z time="2020-09-06T21:09:55.200069607Z" level=info msg=serving... address="/var/run/docker/containerd/containerd-debug.sock" 
2020-09-06T21:09:55.207532994Z time="2020-09-06T21:09:55.200133041Z" level=info msg=serving... address="/var/run/docker/containerd/containerd.sock" 
2020-09-06T21:09:55.207536234Z time="2020-09-06T21:09:55.200143709Z" level=info msg="containerd successfully booted in 0.055479s" 
2020-09-06T21:09:55.213666802Z time="2020-09-06T21:09:55.211723920Z" level=info msg="Setting the storage driver from the $DOCKER_DRIVER environment variable (overlay2)"
2020-09-06T21:09:55.213688027Z time="2020-09-06T21:09:55.211920410Z" level=info msg="parsed scheme: \"unix\"" module=grpc
2020-09-06T21:09:55.213692556Z time="2020-09-06T21:09:55.211932585Z" level=info msg="scheme \"unix\" not registered, fallback to default scheme" module=grpc
2020-09-06T21:09:55.213696150Z time="2020-09-06T21:09:55.211948970Z" level=info msg="ccResolverWrapper: sending update to cc: {[{unix:///var/run/docker/containerd/containerd.sock 0  <nil>}] <nil>}" module=grpc
2020-09-06T21:09:55.213718529Z time="2020-09-06T21:09:55.211958087Z" level=info msg="ClientConn switching balancer to \"pick_first\"" module=grpc
2020-09-06T21:09:55.214652789Z time="2020-09-06T21:09:55.214036190Z" level=info msg="parsed scheme: \"unix\"" module=grpc
2020-09-06T21:09:55.215341786Z time="2020-09-06T21:09:55.214052671Z" level=info msg="scheme \"unix\" not registered, fallback to default scheme" module=grpc
2020-09-06T21:09:55.216950045Z time="2020-09-06T21:09:55.215410639Z" level=info msg="ccResolverWrapper: sending update to cc: {[{unix:///var/run/docker/containerd/containerd.sock 0  <nil>}] <nil>}" module=grpc
2020-09-06T21:09:55.216972753Z time="2020-09-06T21:09:55.215496969Z" level=info msg="ClientConn switching balancer to \"pick_first\"" module=grpc
2020-09-06T21:09:55.282489090Z time="2020-09-06T21:09:55.280917357Z" level=info msg="Loading containers: start."
2020-09-06T21:09:55.311400410Z time="2020-09-06T21:09:55.311267818Z" 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-06T21:09:55.415683878Z time="2020-09-06T21:09:55.414400203Z" 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-06T21:09:55.494682951Z time="2020-09-06T21:09:55.494289969Z" level=info msg="Loading containers: done."
2020-09-06T21:09:55.517139997Z time="2020-09-06T21:09:55.516723695Z" level=info msg="Docker daemon" commit=633a0ea838 graphdriver(s)=overlay2 version=19.03.5
2020-09-06T21:09:55.517159064Z time="2020-09-06T21:09:55.516893485Z" level=info msg="Daemon has completed initialization"
2020-09-06T21:09:55.561549645Z time="2020-09-06T21:09:55.559372997Z" level=info msg="API listen on [::]:2375"
2020-09-06T21:09:55.561624393Z time="2020-09-06T21:09:55.559453559Z" 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:1599426627:prepare_executor
section_start:1599426627:prepare_script
Preparing environment
Running on runner-ed2dce3a-project-20828281-concurrent-0 via runner-ed2dce3a-srm-1599426530-3665a392...
section_end:1599426628:prepare_script
section_start:1599426628: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:1599426629:get_sources
section_start:1599426629: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/43/bf/a9fe1f4b6e0f2ceac7eb7e2dab2fd03edd2fd5ffa8cd90d55ba1453af8b7/docker_compose-1.26.2-py2.py3-none-any.whl (139kB)
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 docker[ssh]<5,>=4.2.2 (from docker-compose)
  Downloading https://files.pythonhosted.org/packages/9e/8c/8d42dbd83679483db207535f4fb02dc84325fa78b290f057694b057fcd21/docker-4.3.1-py2.py3-none-any.whl (145kB)
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 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 dockerpty<1,>=0.4.1 (from docker-compose)
  Downloading https://files.pythonhosted.org/packages/8d/ee/e9ecce4c32204a6738e0a5d5883d3413794d7498fe8b06f44becc028d3ba/dockerpty-0.4.1.tar.gz
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 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 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 six<2,>=1.3.0 (from docker-compose)
  Downloading https://files.pythonhosted.org/packages/ee/ff/48bde5c0f013094d729fe4b0316ba2a24774b3ff1c52d924a8a4cb04078a/six-1.15.0-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 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 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 paramiko>=2.4.2; extra == "ssh" (from docker[ssh]<5,>=4.2.2->docker-compose)
  Downloading https://files.pythonhosted.org/packages/95/19/124e9287b43e6ff3ebb9cdea3e5e8e88475a873c05ccdf8b7e20d2c4201e/paramiko-2.7.2-py2.py3-none-any.whl (206kB)
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 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 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 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 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 pynacl>=1.0.1 (from paramiko>=2.4.2; extra == "ssh"->docker[ssh]<5,>=4.2.2->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 cryptography>=2.5 (from paramiko>=2.4.2; extra == "ssh"->docker[ssh]<5,>=4.2.2->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.2.2->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 cffi>=1.4.1 (from pynacl>=1.0.1->paramiko>=2.4.2; extra == "ssh"->docker[ssh]<5,>=4.2.2->docker-compose)
  Using cached https://files.pythonhosted.org/packages/f7/09/88bbe20b76ca76be052c366fe77aa5e3cd6e5f932766e5597fecdd95b2a8/cffi-1.14.2.tar.gz
Collecting pycparser (from cffi>=1.4.1->pynacl>=1.0.1->paramiko>=2.4.2; extra == "ssh"->docker[ssh]<5,>=4.2.2->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: pynacl, cryptography, bcrypt
  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=877423 sha256=e2db39ffede0bb3c74c0f13b82b59f9bb93f3bce3c1a7a64b6175d3d719efbb8
  Stored in directory: /root/.cache/pip/wheels/c2/d9/ca/81e002fb0cee770c198715dace9edab919daba3ae49bd287a0
  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=758607 sha256=d0a61e558ea5279f000ed590aeb61090d4bd4ca86682495d2518efd9f36c60e4
  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=56568 sha256=96b02ea3598d3cb761d894ce66adbd47ba543f138d3c51ecd59d10968051ae93
  Stored in directory: /root/.cache/pip/wheels/df/ff/0f/e588ec95ea51f480627d66264f5587f4105694ab7ddd61e711
Successfully built pynacl cryptography bcrypt
Installing collected packages: docopt, chardet, certifi, idna, urllib3, requests, six, websocket-client, pycparser, cffi, pynacl, cryptography, bcrypt, paramiko, docker, python-dotenv, dockerpty, PyYAML, pyrsistent, attrs, jsonschema, cached-property, distro, texttable, docker-compose
  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 dockerpty: started
    Running setup.py install for dockerpty: 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.26.2 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
Creating recipe-app-api-devops_db_1 ... 

Creating recipe-app-api-devops_db_1 ... done
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 c0d394546781
Removing intermediate container c0d394546781
 ---> 6bc61ef498d6
Step 3/22 : ENV PYTHONUNBUFFERED 1
 ---> Running in 3fa5d54983de
Removing intermediate container 3fa5d54983de
 ---> d6adbb5a4de4
Step 4/22 : ENV PATH="/scripts:${PATH}"
 ---> Running in 9aac9c225a59
Removing intermediate container 9aac9c225a59
 ---> 32872255600c
Step 5/22 : RUN pip install --upgrade pip
 ---> Running in c36314e0add1
Requirement already up-to-date: pip in /usr/local/lib/python3.7/site-packages (20.2.2)
Removing intermediate container c36314e0add1
 ---> 05a9f70e83a0
Step 6/22 : COPY ./requirements.txt /requirements.txt
 ---> 6fd3ba6306e2
Step 7/22 : RUN apk add --update --no-cache postgresql-client jpeg-dev
 ---> Running in 0a612b3a0bb3
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 0a612b3a0bb3
 ---> 430c03d17be1
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 7ef373f664d3
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 (20200906.211313)
Executing busybox-1.31.1-r16.trigger
OK: 365 MiB in 72 packages
Removing intermediate container 7ef373f664d3
 ---> f51d0e7944ae
Step 9/22 : RUN pip install -r /requirements.txt
 ---> Running in c126234ea282
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 pyflakes<2.1.0,>=2.0.0
  Downloading pyflakes-2.0.0-py2.py3-none-any.whl (53 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 pycodestyle<2.5.0,>=2.4.0
  Downloading pycodestyle-2.4.0-py2.py3-none-any.whl (62 kB)
Collecting mccabe<0.7.0,>=0.6.0
  Downloading mccabe-0.6.1-py2.py3-none-any.whl (8.6 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=a0fcc902093896b3daa59e46fe74510d454e8d32af5bb0d69387bca42b4648ad
  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=9e4c0a60fb76205575847c75a2c81b10233a88b3621bd63c02522121129db6f5
  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=546203 sha256=d96721ed2605bb0117e6dfda2a9835fb6e68104de0a0926353af013f38e99a23
  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, pyflakes, pycodestyle, mccabe, 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 c126234ea282
 ---> 0c740fdafa2d
Step 10/22 : RUN apk del .tmp-build-deps
 ---> Running in 590e27784047
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 (20200906.211313)
(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 590e27784047
 ---> 3e293273bf7a
Step 11/22 : RUN mkdir /app
 ---> Running in 7efa3bb3a305
Removing intermediate container 7efa3bb3a305
 ---> 5c7c2a171aa3
Step 12/22 : WORKDIR /app
 ---> Running in a6c0b37786a3
Removing intermediate container a6c0b37786a3
 ---> 8298342ed29c
Step 13/22 : COPY ./app /app
 ---> 69f88bfef9c2
Step 14/22 : COPY ./scripts /scripts
 ---> 154310f9bc10
Step 15/22 : RUN chmod +x /scripts/*
 ---> Running in bdc4cf9b65c8
Removing intermediate container bdc4cf9b65c8
 ---> 05652f8e1d57
Step 16/22 : RUN mkdir -p /vol/web/media
 ---> Running in 6d14030aed56
Removing intermediate container 6d14030aed56
 ---> 2bcad3d39931
Step 17/22 : RUN mkdir -p /vol/web/static
 ---> Running in bafd7213e121
Removing intermediate container bafd7213e121
 ---> 3339a7cc8cc5
Step 18/22 : RUN adduser -D user
 ---> Running in bbda8641aeec
Removing intermediate container bbda8641aeec
 ---> bcac45887b5b
Step 19/22 : RUN chown -R user:user /vol/
 ---> Running in 78c734dfe3e9
Removing intermediate container 78c734dfe3e9
 ---> c267e1c474fc
Step 20/22 : RUN chmod -R 755 /vol/web
 ---> Running in a70b41884871
Removing intermediate container a70b41884871
 ---> 4e2d7c38c228
Step 21/22 : USER user
 ---> Running in 5f1adddc79e3
Removing intermediate container 5f1adddc79e3
 ---> 2b0ec8f05696
Step 22/22 : CMD ["entrypoint.sh"]
 ---> Running in d0ac6a09082c
Removing intermediate container d0ac6a09082c
 ---> 503d78f93cdb
Successfully built 503d78f93cdb
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`.
Waiting for database...
Database available!
Creating test database for alias 'default'...
System check identified no issues (0 silenced).
...Waiting for database...
Database unavailable, waiting 1 second...
Database unavailable, waiting 1 second...
Database unavailable, waiting 1 second...
Database unavailable, waiting 1 second...
Database unavailable, waiting 1 second...
Database available!
.Waiting for database...
Database available!
...............................................
----------------------------------------------------------------------
Ran 51 tests in 5.918s

OK
Destroying test database for alias 'default'...
section_end:1599426920:step_script
Job succeeded