starting build "ae14b1a4-9a39-41df-a1ba-eb8a82b5a0be"

FETCHSOURCE
hint: Using 'master' as the name for the initial branch. This default branch name
hint: is subject to change. To configure the initial branch name to use in all
hint: of your new repositories, which will suppress this warning, call:
hint:
hint: 	git config --global init.defaultBranch <name>
hint:
hint: Names commonly chosen instead of 'master' are 'main', 'trunk' and
hint: 'development'. The just-created branch can be renamed via this command:
hint:
hint: 	git branch -m <name>
Initialized empty Git repository in /workspace/.git/
From https://github.com/google/trillian-examples
 * branch            a25b21d93fb2bbfad59e33c1f212b5b27d9a8ffa -> FETCH_HEAD
HEAD is now at a25b21d Bump github.com/docker/docker in the go_modules group
BUILD
Starting Step #0
Step #0: Already have image (with digest): gcr.io/cloud-builders/docker
Step #0: latest: Pulling from trillian-opensource-ci/testbase
Step #0: c1c1a7d83fb1: Pulling fs layer
Step #0: 8a305f523084: Pulling fs layer
Step #0: da238dd9d1f5: Pulling fs layer
Step #0: adadfc631984: Pulling fs layer
Step #0: 32a2f51ff3dd: Pulling fs layer
Step #0: c363784b64c5: Pulling fs layer
Step #0: adadfc631984: Waiting
Step #0: 4f4fb700ef54: Pulling fs layer
Step #0: 51cd13f10e27: Pulling fs layer
Step #0: c363784b64c5: Waiting
Step #0: 32a2f51ff3dd: Waiting
Step #0: 4f4fb700ef54: Waiting
Step #0: 81c2411ea07c: Pulling fs layer
Step #0: afb3cdb5ccfd: Pulling fs layer
Step #0: 36a2d4aa1e3e: Pulling fs layer
Step #0: 51cd13f10e27: Waiting
Step #0: 7d926b4843a5: Pulling fs layer
Step #0: 81c2411ea07c: Waiting
Step #0: 0039d554ec4f: Pulling fs layer
Step #0: afb3cdb5ccfd: Waiting
Step #0: aeaeeaffc6a7: Pulling fs layer
Step #0: 7d926b4843a5: Waiting
Step #0: 0039d554ec4f: Waiting
Step #0: 4d8e718d7cef: Pulling fs layer
Step #0: aeaeeaffc6a7: Waiting
Step #0: 4d8e718d7cef: Waiting
Step #0: 8a305f523084: Verifying Checksum
Step #0: 8a305f523084: Download complete
Step #0: c1c1a7d83fb1: Verifying Checksum
Step #0: c1c1a7d83fb1: Download complete
Step #0: da238dd9d1f5: Verifying Checksum
Step #0: da238dd9d1f5: Download complete
Step #0: c363784b64c5: Download complete
Step #0: 4f4fb700ef54: Verifying Checksum
Step #0: 4f4fb700ef54: Download complete
Step #0: 51cd13f10e27: Verifying Checksum
Step #0: 51cd13f10e27: Download complete
Step #0: 81c2411ea07c: Verifying Checksum
Step #0: 81c2411ea07c: Download complete
Step #0: 32a2f51ff3dd: Verifying Checksum
Step #0: 32a2f51ff3dd: Download complete
Step #0: 36a2d4aa1e3e: Verifying Checksum
Step #0: 36a2d4aa1e3e: Download complete
Step #0: c1c1a7d83fb1: Pull complete
Step #0: adadfc631984: Verifying Checksum
Step #0: adadfc631984: Download complete
Step #0: 7d926b4843a5: Download complete
Step #0: 8a305f523084: Pull complete
Step #0: 0039d554ec4f: Verifying Checksum
Step #0: 0039d554ec4f: Download complete
Step #0: aeaeeaffc6a7: Verifying Checksum
Step #0: aeaeeaffc6a7: Download complete
Step #0: 4d8e718d7cef: Verifying Checksum
Step #0: 4d8e718d7cef: Download complete
Step #0: afb3cdb5ccfd: Verifying Checksum
Step #0: afb3cdb5ccfd: Download complete
Step #0: da238dd9d1f5: Pull complete
Step #0: adadfc631984: Pull complete
Step #0: 32a2f51ff3dd: Pull complete
Step #0: c363784b64c5: Pull complete
Step #0: 4f4fb700ef54: Pull complete
Step #0: 51cd13f10e27: Pull complete
Step #0: 81c2411ea07c: Pull complete
Step #0: afb3cdb5ccfd: Pull complete
Step #0: 36a2d4aa1e3e: Pull complete
Step #0: 7d926b4843a5: Pull complete
Step #0: 0039d554ec4f: Pull complete
Step #0: aeaeeaffc6a7: Pull complete
Step #0: 4d8e718d7cef: Pull complete
Step #0: Digest: sha256:6bb2363ba8ad7d45b25bc86701f8f100a0d1cdee13b45399f5864ac3bee7549e
Step #0: Status: Downloaded newer image for gcr.io/trillian-opensource-ci/testbase:latest
Step #0: gcr.io/trillian-opensource-ci/testbase:latest
Finished Step #0
Starting Step #1
Step #1: Already have image (with digest): gcr.io/cloud-builders/docker
Step #1: Sending build context to Docker daemon  17.89MB

Step #1: Step 1/14 : FROM golang:1.22.5-bullseye@sha256:583d5af8289d30de50aa0dcf4985d8b8746e52622becd6e1a62cfe191d5275a5 AS testbase
Step #1: docker.io/library/golang:1.22.5-bullseye@sha256:583d5af8289d30de50aa0dcf4985d8b8746e52622becd6e1a62cfe191d5275a5: Pulling from library/golang
Step #1: 73226dab8db5: Already exists
Step #1: d53dfa00a586: Pulling fs layer
Step #1: bf8ed7043ae2: Pulling fs layer
Step #1: 8a97df9b72d4: Pulling fs layer
Step #1: 32a2f51ff3dd: Pulling fs layer
Step #1: 1e10701146c7: Pulling fs layer
Step #1: 4f4fb700ef54: Pulling fs layer
Step #1: 32a2f51ff3dd: Waiting
Step #1: 1e10701146c7: Waiting
Step #1: 4f4fb700ef54: Waiting
Step #1: d53dfa00a586: Verifying Checksum
Step #1: d53dfa00a586: Download complete
Step #1: bf8ed7043ae2: Verifying Checksum
Step #1: bf8ed7043ae2: Download complete
Step #1: d53dfa00a586: Pull complete
Step #1: 1e10701146c7: Download complete
Step #1: 4f4fb700ef54: Verifying Checksum
Step #1: 4f4fb700ef54: Download complete
Step #1: 8a97df9b72d4: Verifying Checksum
Step #1: 8a97df9b72d4: Download complete
Step #1: 32a2f51ff3dd: Verifying Checksum
Step #1: 32a2f51ff3dd: Download complete
Step #1: bf8ed7043ae2: Pull complete
Step #1: 8a97df9b72d4: Pull complete
Step #1: 32a2f51ff3dd: Pull complete
Step #1: 1e10701146c7: Pull complete
Step #1: 4f4fb700ef54: Pull complete
Step #1: Digest: sha256:583d5af8289d30de50aa0dcf4985d8b8746e52622becd6e1a62cfe191d5275a5
Step #1: Status: Downloaded newer image for golang:1.22.5-bullseye@sha256:583d5af8289d30de50aa0dcf4985d8b8746e52622becd6e1a62cfe191d5275a5
Step #1:  ---> bba1b93ce143
Step #1: Step 2/14 : WORKDIR /testbase
Step #1:  ---> Running in 42ee1bd1147a
Step #1: Removing intermediate container 42ee1bd1147a
Step #1:  ---> dda7066609e1
Step #1: Step 3/14 : ARG GOFLAGS=""
Step #1:  ---> Running in bb3d5716b829
Step #1: Removing intermediate container bb3d5716b829
Step #1:  ---> f48ac08f8abb
Step #1: Step 4/14 : ENV GOFLAGS=$GOFLAGS
Step #1:  ---> Running in eac6ea655810
Step #1: Removing intermediate container eac6ea655810
Step #1:  ---> 415070f6e2dc
Step #1: Step 5/14 : RUN echo "deb http://deb.debian.org/debian bullseye-backports main contrib non-free" >> /etc/apt/sources.list
Step #1:  ---> Running in eb103c433249
Step #1: Removing intermediate container eb103c433249
Step #1:  ---> 7c22b98acf5c
Step #1: Step 6/14 : RUN apt-get update && apt-get -y install curl docker-compose lsof netcat unzip wget xxd
Step #1:  ---> Running in 48caa7fc5ee2
Step #1: Get:1 http://deb.debian.org/debian bullseye InRelease [116 kB]
Step #1: Get:2 http://deb.debian.org/debian-security bullseye-security InRelease [48.4 kB]
Step #1: Get:3 http://deb.debian.org/debian bullseye-updates InRelease [44.1 kB]
Step #1: Get:4 http://deb.debian.org/debian bullseye-backports InRelease [49.0 kB]
Step #1: Get:5 http://deb.debian.org/debian bullseye/main amd64 Packages [8067 kB]
Step #1: Get:6 http://deb.debian.org/debian-security bullseye-security/main amd64 Packages [280 kB]
Step #1: Get:7 http://deb.debian.org/debian bullseye-updates/main amd64 Packages [18.8 kB]
Step #1: Get:8 http://deb.debian.org/debian bullseye-backports/non-free amd64 Packages [14.4 kB]
Step #1: Get:9 http://deb.debian.org/debian bullseye-backports/main amd64 Packages [403 kB]
Step #1: Get:10 http://deb.debian.org/debian bullseye-backports/contrib amd64 Packages [6164 B]
Step #1: Fetched 9046 kB in 2s (4749 kB/s)
Step #1: Reading package lists...
Step #1: Reading package lists...
Step #1: Building dependency tree...
Step #1: Reading state information...
Step #1: curl is already the newest version (7.74.0-1.3+deb11u12).
Step #1: wget is already the newest version (1.21-1+deb11u1).
Step #1: The following additional packages will be installed:
Step #1:   apparmor cgroupfs-mount containerd dbus dmsetup docker.io gettext-base
Step #1:   iptables libapparmor1 libargon2-1 libcap2 libcryptsetup12 libdbus-1-3
Step #1:   libdevmapper1.02.1 libintl-perl libintl-xs-perl libip4tc2 libip6tc2
Step #1:   libjansson4 libjson-c5 libkmod2 libmnl0 libmodule-find-perl
Step #1:   libmodule-scandeps-perl libnetfilter-conntrack3 libnfnetlink0 libnftables1
Step #1:   libnftnl11 libnss-systemd libpam-systemd libproc-processtable-perl
Step #1:   libsort-naturally-perl libterm-readkey-perl libxtables12 libyaml-0-2
Step #1:   needrestart netcat-openbsd nftables python3-attr python3-cached-property
Step #1:   python3-certifi python3-chardet python3-distutils python3-docker
Step #1:   python3-dockerpty python3-docopt python3-idna python3-importlib-metadata
Step #1:   python3-jsonschema python3-lib2to3 python3-more-itertools
Step #1:   python3-pkg-resources python3-pyrsistent python3-requests python3-setuptools
Step #1:   python3-six python3-texttable python3-urllib3 python3-websocket python3-yaml
Step #1:   python3-zipp runc systemd systemd-sysv systemd-timesyncd tini xz-utils
Step #1: Suggested packages:
Step #1:   apparmor-profiles-extra apparmor-utils containernetworking-plugins
Step #1:   default-dbus-session-bus | dbus-session-bus docker-doc aufs-tools
Step #1:   btrfs-progs debootstrap rinse rootlesskit xfsprogs zfs-fuse | zfsutils-linux
Step #1:   firewalld kmod needrestart-session | libnotify-bin iucode-tool
Step #1:   python-attr-doc python-jsonschema-doc python3-cryptography python3-openssl
Step #1:   python3-socks python-requests-doc python-setuptools-doc systemd-container
Step #1:   policykit-1 zip
Step #1: Recommended packages:
Step #1:   criu
Step #1: The following NEW packages will be installed:
Step #1:   apparmor cgroupfs-mount containerd dbus dmsetup docker-compose docker.io
Step #1:   gettext-base iptables libapparmor1 libargon2-1 libcap2 libcryptsetup12
Step #1:   libdbus-1-3 libdevmapper1.02.1 libintl-perl libintl-xs-perl libip4tc2
Step #1:   libip6tc2 libjansson4 libjson-c5 libkmod2 libmnl0 libmodule-find-perl
Step #1:   libmodule-scandeps-perl libnetfilter-conntrack3 libnfnetlink0 libnftables1
Step #1:   libnftnl11 libnss-systemd libpam-systemd libproc-processtable-perl
Step #1:   libsort-naturally-perl libterm-readkey-perl libxtables12 libyaml-0-2 lsof
Step #1:   needrestart netcat netcat-openbsd nftables python3-attr
Step #1:   python3-cached-property python3-certifi python3-chardet python3-distutils
Step #1:   python3-docker python3-dockerpty python3-docopt python3-idna
Step #1:   python3-importlib-metadata python3-jsonschema python3-lib2to3
Step #1:   python3-more-itertools python3-pkg-resources python3-pyrsistent
Step #1:   python3-requests python3-setuptools python3-six python3-texttable
Step #1:   python3-urllib3 python3-websocket python3-yaml python3-zipp runc systemd
Step #1:   systemd-sysv systemd-timesyncd tini unzip xxd xz-utils
Step #1: 0 upgraded, 72 newly installed, 0 to remove and 0 not upgraded.
Step #1: Need to get 72.6 MB of archives.
Step #1: After this operation, 309 MB of additional disk space will be used.
Step #1: Get:1 http://deb.debian.org/debian bullseye/main amd64 libjansson4 amd64 2.13.1-1.1 [39.7 kB]
Step #1: Get:2 http://deb.debian.org/debian bullseye/main amd64 libmnl0 amd64 1.0.4-3 [12.5 kB]
Step #1: Get:3 http://deb.debian.org/debian bullseye/main amd64 libnftnl11 amd64 1.1.9-1 [63.7 kB]
Step #1: Get:4 http://deb.debian.org/debian bullseye/main amd64 libxtables12 amd64 1.8.7-1 [45.1 kB]
Step #1: Get:5 http://deb.debian.org/debian bullseye/main amd64 libnftables1 amd64 0.9.8-3.1+deb11u2 [262 kB]
Step #1: Get:6 http://deb.debian.org/debian bullseye/main amd64 nftables amd64 0.9.8-3.1+deb11u2 [69.0 kB]
Step #1: Get:7 http://deb.debian.org/debian bullseye/main amd64 libapparmor1 amd64 2.13.6-10 [99.3 kB]
Step #1: Get:8 http://deb.debian.org/debian bullseye/main amd64 libcap2 amd64 1:2.44-1 [23.6 kB]
Step #1: Get:9 http://deb.debian.org/debian bullseye/main amd64 libargon2-1 amd64 0~20171227-0.2 [19.6 kB]
Step #1: Get:10 http://deb.debian.org/debian bullseye/main amd64 dmsetup amd64 2:1.02.175-2.1 [92.1 kB]
Step #1: Get:11 http://deb.debian.org/debian bullseye/main amd64 libdevmapper1.02.1 amd64 2:1.02.175-2.1 [143 kB]
Step #1: Get:12 http://deb.debian.org/debian bullseye/main amd64 libjson-c5 amd64 0.15-2+deb11u1 [42.9 kB]
Step #1: Get:13 http://deb.debian.org/debian bullseye/main amd64 libcryptsetup12 amd64 2:2.3.7-1+deb11u1 [248 kB]
Step #1: Get:14 http://deb.debian.org/debian bullseye/main amd64 libip4tc2 amd64 1.8.7-1 [34.6 kB]
Step #1: Get:15 http://deb.debian.org/debian bullseye/main amd64 libkmod2 amd64 28-1 [55.6 kB]
Step #1: Get:16 http://deb.debian.org/debian bullseye/main amd64 systemd amd64 247.3-7+deb11u5 [4502 kB]
Step #1: Get:17 http://deb.debian.org/debian bullseye/main amd64 systemd-sysv amd64 247.3-7+deb11u5 [114 kB]
Step #1: Get:18 http://deb.debian.org/debian bullseye/main amd64 libdbus-1-3 amd64 1.12.28-0+deb11u1 [223 kB]
Step #1: Get:19 http://deb.debian.org/debian bullseye/main amd64 dbus amd64 1.12.28-0+deb11u1 [244 kB]
Step #1: Get:20 http://deb.debian.org/debian bullseye/main amd64 runc amd64 1.0.0~rc93+ds1-5+deb11u3 [2429 kB]
Step #1: Get:21 http://deb.debian.org/debian bullseye/main amd64 containerd amd64 1.4.13~ds1-1~deb11u4 [20.9 MB]
Step #1: Get:22 http://deb.debian.org/debian bullseye/main amd64 libip6tc2 amd64 1.8.7-1 [35.0 kB]
Step #1: Get:23 http://deb.debian.org/debian bullseye/main amd64 libnfnetlink0 amd64 1.0.1-3+b1 [13.9 kB]
Step #1: Get:24 http://deb.debian.org/debian bullseye/main amd64 libnetfilter-conntrack3 amd64 1.0.8-3 [40.6 kB]
Step #1: Get:25 http://deb.debian.org/debian bullseye/main amd64 iptables amd64 1.8.7-1 [382 kB]
Step #1: Get:26 http://deb.debian.org/debian bullseye/main amd64 tini amd64 0.19.0-1 [255 kB]
Step #1: Get:27 http://deb.debian.org/debian bullseye/main amd64 docker.io amd64 20.10.5+dfsg1-1+deb11u2 [36.9 MB]
Step #1: Get:28 http://deb.debian.org/debian bullseye/main amd64 gettext-base amd64 0.21-4 [175 kB]
Step #1: Get:29 http://deb.debian.org/debian bullseye/main amd64 libnss-systemd amd64 247.3-7+deb11u5 [199 kB]
Step #1: Get:30 http://deb.debian.org/debian bullseye/main amd64 libpam-systemd amd64 247.3-7+deb11u5 [283 kB]
Step #1: Get:31 http://deb.debian.org/debian bullseye/main amd64 lsof amd64 4.93.2+dfsg-1.1 [319 kB]
Step #1: Get:32 http://deb.debian.org/debian bullseye/main amd64 systemd-timesyncd amd64 247.3-7+deb11u5 [132 kB]
Step #1: Get:33 http://deb.debian.org/debian bullseye/main amd64 xz-utils amd64 5.2.5-2.1~deb11u1 [220 kB]
Step #1: Get:34 http://deb.debian.org/debian bullseye/main amd64 apparmor amd64 2.13.6-10 [640 kB]
Step #1: Get:35 http://deb.debian.org/debian bullseye/main amd64 cgroupfs-mount all 1.4 [6276 B]
Step #1: Get:36 http://deb.debian.org/debian bullseye/main amd64 python3-pkg-resources all 52.0.0-4 [190 kB]
Step #1: Get:37 http://deb.debian.org/debian bullseye/main amd64 python3-cached-property all 1.5.2-1 [12.5 kB]
Step #1: Get:38 http://deb.debian.org/debian bullseye/main amd64 python3-lib2to3 all 3.9.2-1 [77.8 kB]
Step #1: Get:39 http://deb.debian.org/debian bullseye/main amd64 python3-distutils all 3.9.2-1 [143 kB]
Step #1: Get:40 http://deb.debian.org/debian bullseye/main amd64 python3-certifi all 2020.6.20-1 [151 kB]
Step #1: Get:41 http://deb.debian.org/debian bullseye/main amd64 python3-chardet all 4.0.0-1 [99.0 kB]
Step #1: Get:42 http://deb.debian.org/debian bullseye/main amd64 python3-idna all 2.10-1+deb11u1 [39.0 kB]
Step #1: Get:43 http://deb.debian.org/debian bullseye/main amd64 python3-six all 1.16.0-2 [17.5 kB]
Step #1: Get:44 http://deb.debian.org/debian bullseye/main amd64 python3-urllib3 all 1.26.5-1~exp1 [114 kB]
Step #1: Get:45 http://deb.debian.org/debian bullseye/main amd64 python3-requests all 2.25.1+dfsg-2 [69.3 kB]
Step #1: Get:46 http://deb.debian.org/debian bullseye/main amd64 python3-websocket all 0.57.0-1 [34.3 kB]
Step #1: Get:47 http://deb.debian.org/debian bullseye/main amd64 python3-docker all 4.1.0-1.2 [85.2 kB]
Step #1: Get:48 http://deb.debian.org/debian bullseye/main amd64 python3-dockerpty all 0.4.1-2 [11.1 kB]
Step #1: Get:49 http://deb.debian.org/debian bullseye/main amd64 python3-docopt all 0.6.2-3 [26.6 kB]
Step #1: Get:50 http://deb.debian.org/debian bullseye/main amd64 python3-attr all 20.3.0-1 [52.9 kB]
Step #1: Get:51 http://deb.debian.org/debian bullseye/main amd64 python3-more-itertools all 4.2.0-3 [42.7 kB]
Step #1: Get:52 http://deb.debian.org/debian bullseye/main amd64 python3-zipp all 1.0.0-3 [6060 B]
Step #1: Get:53 http://deb.debian.org/debian bullseye/main amd64 python3-importlib-metadata all 1.6.0-2 [10.3 kB]
Step #1: Get:54 http://deb.debian.org/debian bullseye/main amd64 python3-setuptools all 52.0.0-4 [366 kB]
Step #1: Get:55 http://deb.debian.org/debian bullseye/main amd64 python3-pyrsistent amd64 0.15.5-1+b3 [59.1 kB]
Step #1: Get:56 http://deb.debian.org/debian bullseye/main amd64 python3-jsonschema all 3.2.0-3 [46.3 kB]
Step #1: Get:57 http://deb.debian.org/debian bullseye/main amd64 python3-texttable all 1.6.3-2 [12.4 kB]
Step #1: Get:58 http://deb.debian.org/debian bullseye/main amd64 libyaml-0-2 amd64 0.2.2-1 [49.6 kB]
Step #1: Get:59 http://deb.debian.org/debian bullseye/main amd64 python3-yaml amd64 5.3.1-5 [138 kB]
Step #1: Get:60 http://deb.debian.org/debian bullseye/main amd64 docker-compose all 1.25.0-1 [119 kB]
Step #1: Get:61 http://deb.debian.org/debian bullseye/main amd64 libintl-perl all 1.26-3+deb11u1 [742 kB]
Step #1: Get:62 http://deb.debian.org/debian bullseye/main amd64 libintl-xs-perl amd64 1.26-3+deb11u1 [40.7 kB]
Step #1: Get:63 http://deb.debian.org/debian bullseye/main amd64 libmodule-find-perl all 0.15-1 [11.0 kB]
Step #1: Get:64 http://deb.debian.org/debian bullseye/main amd64 libmodule-scandeps-perl all 1.30-1 [44.9 kB]
Step #1: Get:65 http://deb.debian.org/debian bullseye/main amd64 libproc-processtable-perl amd64 0.59-2+b1 [45.9 kB]
Step #1: Get:66 http://deb.debian.org/debian bullseye/main amd64 libsort-naturally-perl all 1.03-2 [13.5 kB]
Step #1: Get:67 http://deb.debian.org/debian bullseye/main amd64 libterm-readkey-perl amd64 2.38-1+b2 [27.8 kB]
Step #1: Get:68 http://deb.debian.org/debian bullseye/main amd64 needrestart all 3.5-4+deb11u3 [62.5 kB]
Step #1: Get:69 http://deb.debian.org/debian bullseye/main amd64 netcat-openbsd amd64 1.217-3 [41.1 kB]
Step #1: Get:70 http://deb.debian.org/debian bullseye/main amd64 netcat all 1.10-46 [9728 B]
Step #1: Get:71 http://deb.debian.org/debian bullseye/main amd64 unzip amd64 6.0-26+deb11u1 [172 kB]
Step #1: Get:72 http://deb.debian.org/debian bullseye/main amd64 xxd amd64 2:8.2.2434-3+deb11u1 [192 kB]
Step #1: �[91mdebconf: delaying package configuration, since apt-utils is not installed
Step #1: �[0mFetched 72.6 MB in 2s (45.5 MB/s)
Step #1: Selecting previously unselected package libjansson4:amd64.
Step #1: (Reading database ... 
(Reading database ... 5%
(Reading database ... 10%
(Reading database ... 15%
(Reading database ... 20%
(Reading database ... 25%
(Reading database ... 30%
(Reading database ... 35%
(Reading database ... 40%
(Reading database ... 45%
(Reading database ... 50%
(Reading database ... 55%
(Reading database ... 60%
(Reading database ... 65%
(Reading database ... 70%
(Reading database ... 75%
(Reading database ... 80%
(Reading database ... 85%
(Reading database ... 90%
(Reading database ... 95%
(Reading database ... 100%
(Reading database ... 15725 files and directories currently installed.)
Step #1: Preparing to unpack .../00-libjansson4_2.13.1-1.1_amd64.deb ...
Step #1: Unpacking libjansson4:amd64 (2.13.1-1.1) ...
Step #1: Selecting previously unselected package libmnl0:amd64.
Step #1: Preparing to unpack .../01-libmnl0_1.0.4-3_amd64.deb ...
Step #1: Unpacking libmnl0:amd64 (1.0.4-3) ...
Step #1: Selecting previously unselected package libnftnl11:amd64.
Step #1: Preparing to unpack .../02-libnftnl11_1.1.9-1_amd64.deb ...
Step #1: Unpacking libnftnl11:amd64 (1.1.9-1) ...
Step #1: Selecting previously unselected package libxtables12:amd64.
Step #1: Preparing to unpack .../03-libxtables12_1.8.7-1_amd64.deb ...
Step #1: Unpacking libxtables12:amd64 (1.8.7-1) ...
Step #1: Selecting previously unselected package libnftables1:amd64.
Step #1: Preparing to unpack .../04-libnftables1_0.9.8-3.1+deb11u2_amd64.deb ...
Step #1: Unpacking libnftables1:amd64 (0.9.8-3.1+deb11u2) ...
Step #1: Selecting previously unselected package nftables.
Step #1: Preparing to unpack .../05-nftables_0.9.8-3.1+deb11u2_amd64.deb ...
Step #1: Unpacking nftables (0.9.8-3.1+deb11u2) ...
Step #1: Selecting previously unselected package libapparmor1:amd64.
Step #1: Preparing to unpack .../06-libapparmor1_2.13.6-10_amd64.deb ...
Step #1: Unpacking libapparmor1:amd64 (2.13.6-10) ...
Step #1: Selecting previously unselected package libcap2:amd64.
Step #1: Preparing to unpack .../07-libcap2_1%3a2.44-1_amd64.deb ...
Step #1: Unpacking libcap2:amd64 (1:2.44-1) ...
Step #1: Selecting previously unselected package libargon2-1:amd64.
Step #1: Preparing to unpack .../08-libargon2-1_0~20171227-0.2_amd64.deb ...
Step #1: Unpacking libargon2-1:amd64 (0~20171227-0.2) ...
Step #1: Selecting previously unselected package dmsetup.
Step #1: Preparing to unpack .../09-dmsetup_2%3a1.02.175-2.1_amd64.deb ...
Step #1: Unpacking dmsetup (2:1.02.175-2.1) ...
Step #1: Selecting previously unselected package libdevmapper1.02.1:amd64.
Step #1: Preparing to unpack .../10-libdevmapper1.02.1_2%3a1.02.175-2.1_amd64.deb ...
Step #1: Unpacking libdevmapper1.02.1:amd64 (2:1.02.175-2.1) ...
Step #1: Selecting previously unselected package libjson-c5:amd64.
Step #1: Preparing to unpack .../11-libjson-c5_0.15-2+deb11u1_amd64.deb ...
Step #1: Unpacking libjson-c5:amd64 (0.15-2+deb11u1) ...
Step #1: Selecting previously unselected package libcryptsetup12:amd64.
Step #1: Preparing to unpack .../12-libcryptsetup12_2%3a2.3.7-1+deb11u1_amd64.deb ...
Step #1: Unpacking libcryptsetup12:amd64 (2:2.3.7-1+deb11u1) ...
Step #1: Selecting previously unselected package libip4tc2:amd64.
Step #1: Preparing to unpack .../13-libip4tc2_1.8.7-1_amd64.deb ...
Step #1: Unpacking libip4tc2:amd64 (1.8.7-1) ...
Step #1: Selecting previously unselected package libkmod2:amd64.
Step #1: Preparing to unpack .../14-libkmod2_28-1_amd64.deb ...
Step #1: Unpacking libkmod2:amd64 (28-1) ...
Step #1: Selecting previously unselected package systemd.
Step #1: Preparing to unpack .../15-systemd_247.3-7+deb11u5_amd64.deb ...
Step #1: Unpacking systemd (247.3-7+deb11u5) ...
Step #1: Setting up libapparmor1:amd64 (2.13.6-10) ...
Step #1: Setting up libcap2:amd64 (1:2.44-1) ...
Step #1: Setting up libargon2-1:amd64 (0~20171227-0.2) ...
Step #1: Setting up libjson-c5:amd64 (0.15-2+deb11u1) ...
Step #1: Setting up libip4tc2:amd64 (1.8.7-1) ...
Step #1: Setting up libkmod2:amd64 (28-1) ...
Step #1: Setting up libdevmapper1.02.1:amd64 (2:1.02.175-2.1) ...
Step #1: Setting up libcryptsetup12:amd64 (2:2.3.7-1+deb11u1) ...
Step #1: Setting up systemd (247.3-7+deb11u5) ...
Step #1: Created symlink /etc/systemd/system/getty.target.wants/getty@tty1.service → /lib/systemd/system/getty@.service.
Step #1: Created symlink /etc/systemd/system/multi-user.target.wants/remote-fs.target → /lib/systemd/system/remote-fs.target.
Step #1: Created symlink /etc/systemd/system/sysinit.target.wants/systemd-pstore.service → /lib/systemd/system/systemd-pstore.service.
Step #1: Initializing machine ID from KVM UUID.
Step #1: Setting up dmsetup (2:1.02.175-2.1) ...
Step #1: Selecting previously unselected package systemd-sysv.
Step #1: (Reading database ... 
(Reading database ... 5%
(Reading database ... 10%
(Reading database ... 15%
(Reading database ... 20%
(Reading database ... 25%
(Reading database ... 30%
(Reading database ... 35%
(Reading database ... 40%
(Reading database ... 45%
(Reading database ... 50%
(Reading database ... 55%
(Reading database ... 60%
(Reading database ... 65%
(Reading database ... 70%
(Reading database ... 75%
(Reading database ... 80%
(Reading database ... 85%
(Reading database ... 90%
(Reading database ... 95%
(Reading database ... 100%
(Reading database ... 16642 files and directories currently installed.)
Step #1: Preparing to unpack .../00-systemd-sysv_247.3-7+deb11u5_amd64.deb ...
Step #1: Unpacking systemd-sysv (247.3-7+deb11u5) ...
Step #1: Selecting previously unselected package libdbus-1-3:amd64.
Step #1: Preparing to unpack .../01-libdbus-1-3_1.12.28-0+deb11u1_amd64.deb ...
Step #1: Unpacking libdbus-1-3:amd64 (1.12.28-0+deb11u1) ...
Step #1: Selecting previously unselected package dbus.
Step #1: Preparing to unpack .../02-dbus_1.12.28-0+deb11u1_amd64.deb ...
Step #1: Unpacking dbus (1.12.28-0+deb11u1) ...
Step #1: Selecting previously unselected package runc.
Step #1: Preparing to unpack .../03-runc_1.0.0~rc93+ds1-5+deb11u3_amd64.deb ...
Step #1: Unpacking runc (1.0.0~rc93+ds1-5+deb11u3) ...
Step #1: Selecting previously unselected package containerd.
Step #1: Preparing to unpack .../04-containerd_1.4.13~ds1-1~deb11u4_amd64.deb ...
Step #1: Unpacking containerd (1.4.13~ds1-1~deb11u4) ...
Step #1: Selecting previously unselected package libip6tc2:amd64.
Step #1: Preparing to unpack .../05-libip6tc2_1.8.7-1_amd64.deb ...
Step #1: Unpacking libip6tc2:amd64 (1.8.7-1) ...
Step #1: Selecting previously unselected package libnfnetlink0:amd64.
Step #1: Preparing to unpack .../06-libnfnetlink0_1.0.1-3+b1_amd64.deb ...
Step #1: Unpacking libnfnetlink0:amd64 (1.0.1-3+b1) ...
Step #1: Selecting previously unselected package libnetfilter-conntrack3:amd64.
Step #1: Preparing to unpack .../07-libnetfilter-conntrack3_1.0.8-3_amd64.deb ...
Step #1: Unpacking libnetfilter-conntrack3:amd64 (1.0.8-3) ...
Step #1: Selecting previously unselected package iptables.
Step #1: Preparing to unpack .../08-iptables_1.8.7-1_amd64.deb ...
Step #1: Unpacking iptables (1.8.7-1) ...
Step #1: Selecting previously unselected package tini.
Step #1: Preparing to unpack .../09-tini_0.19.0-1_amd64.deb ...
Step #1: Unpacking tini (0.19.0-1) ...
Step #1: Selecting previously unselected package docker.io.
Step #1: Preparing to unpack .../10-docker.io_20.10.5+dfsg1-1+deb11u2_amd64.deb ...
Step #1: Unpacking docker.io (20.10.5+dfsg1-1+deb11u2) ...
Step #1: Selecting previously unselected package gettext-base.
Step #1: Preparing to unpack .../11-gettext-base_0.21-4_amd64.deb ...
Step #1: Unpacking gettext-base (0.21-4) ...
Step #1: Selecting previously unselected package libnss-systemd:amd64.
Step #1: Preparing to unpack .../12-libnss-systemd_247.3-7+deb11u5_amd64.deb ...
Step #1: Unpacking libnss-systemd:amd64 (247.3-7+deb11u5) ...
Step #1: Selecting previously unselected package libpam-systemd:amd64.
Step #1: Preparing to unpack .../13-libpam-systemd_247.3-7+deb11u5_amd64.deb ...
Step #1: Unpacking libpam-systemd:amd64 (247.3-7+deb11u5) ...
Step #1: Selecting previously unselected package lsof.
Step #1: Preparing to unpack .../14-lsof_4.93.2+dfsg-1.1_amd64.deb ...
Step #1: Unpacking lsof (4.93.2+dfsg-1.1) ...
Step #1: Selecting previously unselected package systemd-timesyncd.
Step #1: Preparing to unpack .../15-systemd-timesyncd_247.3-7+deb11u5_amd64.deb ...
Step #1: Unpacking systemd-timesyncd (247.3-7+deb11u5) ...
Step #1: Selecting previously unselected package xz-utils.
Step #1: Preparing to unpack .../16-xz-utils_5.2.5-2.1~deb11u1_amd64.deb ...
Step #1: Unpacking xz-utils (5.2.5-2.1~deb11u1) ...
Step #1: Selecting previously unselected package apparmor.
Step #1: Preparing to unpack .../17-apparmor_2.13.6-10_amd64.deb ...
Step #1: Unpacking apparmor (2.13.6-10) ...
Step #1: Selecting previously unselected package cgroupfs-mount.
Step #1: Preparing to unpack .../18-cgroupfs-mount_1.4_all.deb ...
Step #1: Unpacking cgroupfs-mount (1.4) ...
Step #1: Selecting previously unselected package python3-pkg-resources.
Step #1: Preparing to unpack .../19-python3-pkg-resources_52.0.0-4_all.deb ...
Step #1: Unpacking python3-pkg-resources (52.0.0-4) ...
Step #1: Selecting previously unselected package python3-cached-property.
Step #1: Preparing to unpack .../20-python3-cached-property_1.5.2-1_all.deb ...
Step #1: Unpacking python3-cached-property (1.5.2-1) ...
Step #1: Selecting previously unselected package python3-lib2to3.
Step #1: Preparing to unpack .../21-python3-lib2to3_3.9.2-1_all.deb ...
Step #1: Unpacking python3-lib2to3 (3.9.2-1) ...
Step #1: Selecting previously unselected package python3-distutils.
Step #1: Preparing to unpack .../22-python3-distutils_3.9.2-1_all.deb ...
Step #1: Unpacking python3-distutils (3.9.2-1) ...
Step #1: Selecting previously unselected package python3-certifi.
Step #1: Preparing to unpack .../23-python3-certifi_2020.6.20-1_all.deb ...
Step #1: Unpacking python3-certifi (2020.6.20-1) ...
Step #1: Selecting previously unselected package python3-chardet.
Step #1: Preparing to unpack .../24-python3-chardet_4.0.0-1_all.deb ...
Step #1: Unpacking python3-chardet (4.0.0-1) ...
Step #1: Selecting previously unselected package python3-idna.
Step #1: Preparing to unpack .../25-python3-idna_2.10-1+deb11u1_all.deb ...
Step #1: Unpacking python3-idna (2.10-1+deb11u1) ...
Step #1: Selecting previously unselected package python3-six.
Step #1: Preparing to unpack .../26-python3-six_1.16.0-2_all.deb ...
Step #1: Unpacking python3-six (1.16.0-2) ...
Step #1: Selecting previously unselected package python3-urllib3.
Step #1: Preparing to unpack .../27-python3-urllib3_1.26.5-1~exp1_all.deb ...
Step #1: Unpacking python3-urllib3 (1.26.5-1~exp1) ...
Step #1: Selecting previously unselected package python3-requests.
Step #1: Preparing to unpack .../28-python3-requests_2.25.1+dfsg-2_all.deb ...
Step #1: Unpacking python3-requests (2.25.1+dfsg-2) ...
Step #1: Selecting previously unselected package python3-websocket.
Step #1: Preparing to unpack .../29-python3-websocket_0.57.0-1_all.deb ...
Step #1: Unpacking python3-websocket (0.57.0-1) ...
Step #1: Selecting previously unselected package python3-docker.
Step #1: Preparing to unpack .../30-python3-docker_4.1.0-1.2_all.deb ...
Step #1: Unpacking python3-docker (4.1.0-1.2) ...
Step #1: Selecting previously unselected package python3-dockerpty.
Step #1: Preparing to unpack .../31-python3-dockerpty_0.4.1-2_all.deb ...
Step #1: Unpacking python3-dockerpty (0.4.1-2) ...
Step #1: Selecting previously unselected package python3-docopt.
Step #1: Preparing to unpack .../32-python3-docopt_0.6.2-3_all.deb ...
Step #1: Unpacking python3-docopt (0.6.2-3) ...
Step #1: Selecting previously unselected package python3-attr.
Step #1: Preparing to unpack .../33-python3-attr_20.3.0-1_all.deb ...
Step #1: Unpacking python3-attr (20.3.0-1) ...
Step #1: Selecting previously unselected package python3-more-itertools.
Step #1: Preparing to unpack .../34-python3-more-itertools_4.2.0-3_all.deb ...
Step #1: Unpacking python3-more-itertools (4.2.0-3) ...
Step #1: Selecting previously unselected package python3-zipp.
Step #1: Preparing to unpack .../35-python3-zipp_1.0.0-3_all.deb ...
Step #1: Unpacking python3-zi
...
[Logs truncated due to log size limitations. For full logs, see https://storage.cloud.google.com/trillian-cloudbuild-logs/log-ae14b1a4-9a39-41df-a1ba-eb8a82b5a0be.txt.]
...
mit-build-and-test-with-coverage": go: downloading github.com/felixge/httpsnoop v1.0.4
Step #9 - "usbarmory": arm-none-eabi-objcopy -j .text -j .rodata -j .shstrtab -j .typelink \
Step #9 - "usbarmory":     -j .itablink -j .gopclntab -j .go.buildinfo -j .noptrdata -j .data \
Step #9 - "usbarmory":     -j .bss --set-section-flags .bss=alloc,load,contents \
Step #9 - "usbarmory":     -j .noptrbss --set-section-flags .noptrbss=alloc,load,contents \
Step #9 - "usbarmory":     armory-boot -O binary armory-boot.bin
Step #9 - "usbarmory": echo 
Step #9 - "usbarmory": 
Step #9 - "usbarmory": echo /go/pkg/mod/github.com/usbarmory/tamago@v0.0.0-20221104085030-4122a878196a
Step #9 - "usbarmory": /go/pkg/mod/github.com/usbarmory/tamago@v0.0.0-20221104085030-4122a878196a
Step #9 - "usbarmory": cp -f /go/pkg/mod/github.com/usbarmory/tamago@v0.0.0-20221104085030-4122a878196a/board/usbarmory/mk2/imximage.cfg armory-boot.dcd
Step #9 - "usbarmory": mkimage -n armory-boot.dcd -T imximage -e 0x90010000  -d armory-boot.bin armory-boot.imx
Step #9 - "usbarmory": Image Type:   Freescale IMX Boot Image
Step #9 - "usbarmory": Image Ver:    2 (i.MX53/6/7 compatible)
Step #9 - "usbarmory": Mode:         DCD
Step #9 - "usbarmory": Data Size:    2727936 Bytes = 2664.00 KiB = 2.60 MiB
Step #9 - "usbarmory": Load Address: 9000f420
Step #9 - "usbarmory": Entry Point:  90010000
Step #9 - "usbarmory": HAB Blocks:   0x9000f400 0x00000000 0x00295c00
Step #9 - "usbarmory": DCD Blocks:   0x00910000 0x0000002c 0x000003d0
Step #9 - "usbarmory": # Copy entry point from ELF file
Step #9 - "usbarmory": dd if=armory-boot of=armory-boot.imx bs=1 count=4 skip=24 seek=4 conv=notrunc
Step #9 - "usbarmory": 4+0 records in
Step #9 - "usbarmory": 4+0 records out
Step #9 - "usbarmory": 4 bytes copied, 0.00010292 s, 38.9 kB/s
Step #9 - "usbarmory": �[1m---[�[7mBuild armory unikernel ext4 image�[m]----------------------------------------------�[0m
Step #9 - "usbarmory": Please run `resize2fs -s /tmp/armory.ext4' to disable 64-bit mode.
Step #9 - "usbarmory": tune2fs 1.46.2 (28-Feb-2021)
Step #9 - "usbarmory": tune2fs 1.46.2 (28-Feb-2021)
Step #9 - "usbarmory": Created image in /tmp/armory.ext4:
Step #9 - "usbarmory": -rw-r--r-- 1 root root 13M Aug  2 22:58 /tmp/armory.ext4
Step #11 - "helloworld-integration-test": I0802 22:58:05.737685    2615 main.go:110] Creating tree tree_state:ACTIVE tree_type:LOG max_root_duration:{seconds:3600}
Step #11 - "helloworld-integration-test": W0802 22:58:05.737840    2615 rpcflags.go:36] Using an insecure gRPC connection to Trillian
Step #11 - "helloworld-integration-test": I0802 22:58:05.738109    2615 admin.go:50] CreateTree...
Step #11 - "helloworld-integration-test": I0802 22:58:05.753966    2615 admin.go:95] Initialising Log 3626302375552181281...
Step #11 - "helloworld-integration-test": I0802 22:58:05.788770    2615 admin.go:106] Initialised Log (3626302375552181281) with new SignedTreeHead:
Step #11 - "helloworld-integration-test": log_root:"\x00\x01\x00\x00\x00\x00\x00\x00\x00\x00 \xe3\xb0\xc4B\x98\xfc\x1c\x14\x9a\xfb\xf4șo\xb9$'\xaeA\xe4d\x9b\x93L\xa4\x95\x99\x1bxR\xb8U\x17\xe8\x0b|A\x0f\xc6\xd1\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"
Step #8 - "presubmit-build-docker":  ---> Running in 4b9a43ac146c
Finished Step #9 - "usbarmory"
Step #8 - "presubmit-build-docker": Removing intermediate container 4b9a43ac146c
Step #8 - "presubmit-build-docker":  ---> cfbf064635c9
Step #8 - "presubmit-build-docker": Step 3/12 : ENV GOFLAGS=$GOFLAGS
Step #8 - "presubmit-build-docker":  ---> Running in 32e255565861
Step #8 - "presubmit-build-docker": Removing intermediate container 32e255565861
Step #8 - "presubmit-build-docker":  ---> 36db492fd6f2
Step #8 - "presubmit-build-docker": Step 4/12 : WORKDIR /build
Step #8 - "presubmit-build-docker":  ---> Running in 768ea55683f6
Step #8 - "presubmit-build-docker": Removing intermediate container 768ea55683f6
Step #8 - "presubmit-build-docker":  ---> b10cf50c5b38
Step #8 - "presubmit-build-docker": Step 5/12 : COPY go.mod .
Step #8 - "presubmit-build-docker":  ---> 7b553ced5c77
Step #8 - "presubmit-build-docker": Step 6/12 : COPY go.sum .
Step #8 - "presubmit-build-docker":  ---> 4cfbdbd2db13
Step #8 - "presubmit-build-docker": Step 7/12 : RUN go mod download
Step #8 - "presubmit-build-docker":  ---> Running in 6853035d2e66
Step #11 - "helloworld-integration-test": ok  	github.com/google/trillian-examples/helloworld	1.651s
Finished Step #11 - "helloworld-integration-test"
Step #5 - "presubmit-build-and-test-with-coverage": go: downloading github.com/docker/go-units v0.5.0
Step #5 - "presubmit-build-and-test-with-coverage": go: downloading github.com/moby/docker-image-spec v1.3.1
Step #5 - "presubmit-build-and-test-with-coverage": go: downloading github.com/pkg/errors v0.9.1
Step #5 - "presubmit-build-and-test-with-coverage": go: downloading github.com/opencontainers/image-spec v1.1.0-rc5
Step #5 - "presubmit-build-and-test-with-coverage": go: downloading github.com/docker/go-connections v0.5.0
Step #5 - "presubmit-build-and-test-with-coverage": go: downloading github.com/gogo/protobuf v1.3.2
Step #5 - "presubmit-build-and-test-with-coverage": go: downloading github.com/opencontainers/go-digest v1.0.0
Step #5 - "presubmit-build-and-test-with-coverage": go: downloading github.com/distribution/reference v0.5.0
Step #10 - "firmware-integration-test": === RUN   TestFTIntegration
Step #10 - "firmware-integration-test": I0802 22:58:35.281196    5487 ft_personality.go:55] Connecting to local DB at "/tmp/TestFTIntegration2097120807/002/ft-cas.db"
Step #10 - "firmware-integration-test": I0802 22:58:35.290891    5487 ft_personality.go:69] Connecting to Trillian Log...
Step #10 - "firmware-integration-test": I0802 22:58:35.299933    5487 admin.go:50] CreateTree...
Step #10 - "firmware-integration-test": I0802 22:58:35.309937    5487 admin.go:95] Initialising Log 2565746706089009368...
Step #10 - "firmware-integration-test": I0802 22:58:35.320294    5487 admin.go:106] Initialised Log (2565746706089009368) with new SignedTreeHead:
Step #10 - "firmware-integration-test": log_root:"\x00\x01\x00\x00\x00\x00\x00\x00\x00\x00 \xe3\xb0\xc4B\x98\xfc\x1c\x14\x9a\xfb\xf4șo\xb9$'\xaeA\xe4d\x9b\x93L\xa4\x95\x99\x1bxR\xb8U\x17\xe8\x0b\x83\"sy\xc4\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"
Step #10 - "firmware-integration-test": I0802 22:58:35.330244    5487 client.go:62] Got tree tree_id:2565746706089009368 tree_state:ACTIVE tree_type:LOG display_name:"ft" description:"binary transparency log" storage_settings:{type_url:"type.googleapis.com/mysqlpb.StorageOptions"} max_root_duration:{seconds:3600} create_time:{seconds:1722639515 nanos:301000000} update_time:{seconds:1722639515 nanos:301000000}
Step #10 - "firmware-integration-test": I0802 22:58:35.330373    5487 ft_personality.go:90] Starting FT personality server...
Step #10 - "firmware-integration-test": === RUN   TestFTIntegration/Log_initial_firmware
Step #10 - "firmware-integration-test": I0802 22:58:40.280378    5487 publish.go:60] Measurement: badb92d12e948325c26b039e7a6e64b09b900a5cac7c1e1614dbfbd038619f669a24871b4b4348584523bdddc8c36e68e06a9c88ffc27482c94f58c9659c32fa
Step #10 - "firmware-integration-test": I0802 22:58:40.283966    5487 publish.go:79] Submitting entry...
Step #10 - "firmware-integration-test": I0802 22:58:40.305394    5487 publish.go:84] Successfully submitted entry, waiting for inclusion...
Step #10 - "firmware-integration-test": I0802 22:58:41.310813    5487 utils.go:77] Inclusion proof for leafhash 0x5348412d323536 verified
Step #10 - "firmware-integration-test": I0802 22:58:41.310848    5487 publish.go:94] Successfully logged {"Type":102,"Statement":"eyJEZXZpY2VJRCI6ImR1bW15IiwiRmlybXdhcmVSZXZpc2lvbiI6MSwiRmlybXdhcmVJbWFnZVNIQTUxMiI6InZ5OGhrMnRtb0daWWczRnVwTEhPMm1DVEJLMTIzVWoyUWpFb3ZEYlV5dysxNy9xcHdmTGpLS1hQd2wwc3VKb3pmVUtGcUx3K0l0dTVtOTIrMFo1d2xRPT0iLCJFeHBlY3RlZEZpcm13YXJlTWVhc3VyZW1lbnQiOiJ1dHVTMFM2VWd5WENhd09lZW01a3NKdVFDbHlzZkI0V0ZOdjcwRGhobjJhYUpJY2JTME5JV0VVanZkM0l3MjVvNEdxY2lQL0NkSUxKVDFqSlpad3krZz09IiwiQnVpbGRUaW1lc3RhbXAiOiIyMDIwLTExLTI0IDEwOjAwOjAwKzAwOjAwIn0=","Signature":"t4tvFZmUezckh3qCi0vfPUP5auqFL9ZtH7mJ2i3R0ej6KZQAJ+2etSRmXnoH+pr5V77AtHu8uDk4duxLyGh2ItRiXCuXMrsyrt8b53TMKXmP6kDCAditmTpAWSReaYtemCToXw6qNwhweL+DDeqhx4SRzg/ONACwBRc1fcSagqXj9Lnl/LF2xc8oOxnrOcSXbiowdnl8hDtnKNx0wQqguMEhiHki8/QWwqLqJ6vpiCLnyHijSB7eilsGUFXfnKjoMD10tk/wS3nq2t4IBTOIykfTMj3rtRXRlF8aniaWRSqA7+GqMHiuDB8v0RFeoa/D5HIDhaq4G3eHO1umSFv1wA=="}
Step #10 - "firmware-integration-test": I0802 22:58:41.310857    5487 publish.go:97] Creating update package file "/tmp/TestFTIntegration2097120807/001/update.ota"...
Step #10 - "firmware-integration-test": I0802 22:58:41.311013    5487 publish.go:126] Successfully created update package file "/tmp/TestFTIntegration2097120807/001/update.ota"
Step #10 - "firmware-integration-test": === RUN   TestFTIntegration/Force_flashing_device_(init)
Step #10 - "firmware-integration-test": W0802 22:58:41.311161    5487 flash_tool.go:128] device needs to be force initialised: couldn't read bundle file "/tmp/TestFTIntegration2097120807/001/dummy_device/bundle.json": open /tmp/TestFTIntegration2097120807/001/dummy_device/bundle.json: no such file or directory
Step #10 - "firmware-integration-test": W0802 22:58:41.311177    5487 flash_tool.go:78] failed to validate update: failed to open the device checkpoint: failed to verify signatures on checkpoint: malformed note
Step #10 - "firmware-integration-test": I0802 22:58:41.311182    5487 flash_tool.go:100] Update verified, about to apply to device...
Step #10 - "firmware-integration-test": I0802 22:58:41.311282    5487 flash_tool.go:106] Update applied.
Step #10 - "firmware-integration-test": === RUN   TestFTIntegration/Boot_device_with_initial_firmware
Step #10 - "firmware-integration-test": I0802 22:58:41.311324    5487 rom.go:50] ----RESET----
Step #10 - "firmware-integration-test": I0802 22:58:41.311329    5487 rom.go:51] Powering up bananas, configuring Romulans, feeding the watchdogs
Step #10 - "firmware-integration-test": I0802 22:58:41.311333    5487 rom.go:53] Configuring flash and loading FT artifacts from "/tmp/TestFTIntegration2097120807/001/dummy_device"...
Step #10 - "firmware-integration-test": I0802 22:58:41.311588    5487 rom.go:82] Bundle verification passed, prepared to boot
Step #10 - "firmware-integration-test": [app] print: Hello world!
Step #10 - "firmware-integration-test": return value = 0, duration = 17.53µs
Step #10 - "firmware-integration-test": === RUN   TestFTIntegration/Log_updated_firmware
Step #10 - "firmware-integration-test": I0802 22:58:41.313418    5487 publish.go:60] Measurement: badb92d12e948325c26b039e7a6e64b09b900a5cac7c1e1614dbfbd038619f669a24871b4b4348584523bdddc8c36e68e06a9c88ffc27482c94f58c9659c32fa
Step #10 - "firmware-integration-test": I0802 22:58:41.316515    5487 publish.go:79] Submitting entry...
Step #10 - "firmware-integration-test": I0802 22:58:41.330427    5487 publish.go:84] Successfully submitted entry, waiting for inclusion...
Step #10 - "firmware-integration-test": I0802 22:58:43.337392    5487 utils.go:62] Consistency proof between 1 and 2 verified
Step #10 - "firmware-integration-test": I0802 22:58:43.341513    5487 utils.go:77] Inclusion proof for leafhash 0x5348412d323536 verified
Step #10 - "firmware-integration-test": I0802 22:58:43.341545    5487 publish.go:94] Successfully logged {"Type":102,"Statement":"eyJEZXZpY2VJRCI6ImR1bW15IiwiRmlybXdhcmVSZXZpc2lvbiI6MiwiRmlybXdhcmVJbWFnZVNIQTUxMiI6InZ5OGhrMnRtb0daWWczRnVwTEhPMm1DVEJLMTIzVWoyUWpFb3ZEYlV5dysxNy9xcHdmTGpLS1hQd2wwc3VKb3pmVUtGcUx3K0l0dTVtOTIrMFo1d2xRPT0iLCJFeHBlY3RlZEZpcm13YXJlTWVhc3VyZW1lbnQiOiJ1dHVTMFM2VWd5WENhd09lZW01a3NKdVFDbHlzZkI0V0ZOdjcwRGhobjJhYUpJY2JTME5JV0VVanZkM0l3MjVvNEdxY2lQL0NkSUxKVDFqSlpad3krZz09IiwiQnVpbGRUaW1lc3RhbXAiOiIyMDIwLTExLTI0IDEwOjE1OjAwKzAwOjAwIn0=","Signature":"s2bLTvyDvMNlKj8iX1d9lj0Xi4FBtFA2giCQsQF8+TX9dPCrEQ9saqV5bgsn6aio0TkDBBIUxNPckYTS16116tIJ3n2Oa8e6dnlFvAUIoYAfl7V9uuryI1q41CeGX6+czAgYSD6cWfiZb01DtHsxfMovMENwdgVyJPOH2/24dYaMTZbdlWyYjh+L+03Fom19+rPOZWGHsbhtCf7ppY6Zkl4lkz4eJ6vZSDh2FRbqiKOIxpxW+ba4gHbYlbnVFmFJed6YRrCjmKQbXKbkToO6KQAhSzG/+d8E1KyWKoD3aaXqPHGoehzLAhq6HRC0t5h2xxSSDofAUtxpaCU9m689rQ=="}
Step #10 - "firmware-integration-test": I0802 22:58:43.341553    5487 publish.go:97] Creating update package file "/tmp/TestFTIntegration2097120807/001/update.ota"...
Step #10 - "firmware-integration-test": I0802 22:58:43.341654    5487 publish.go:126] Successfully created update package file "/tmp/TestFTIntegration2097120807/001/update.ota"
Step #10 - "firmware-integration-test": === RUN   TestFTIntegration/Flashing_device_(update)
Step #10 - "firmware-integration-test": I0802 22:58:43.344826    5487 flash_tool.go:100] Update verified, about to apply to device...
Step #10 - "firmware-integration-test": I0802 22:58:43.344985    5487 flash_tool.go:106] Update applied.
Step #10 - "firmware-integration-test": === RUN   TestFTIntegration/Booting_updated_device
Step #10 - "firmware-integration-test": I0802 22:58:43.345027    5487 rom.go:50] ----RESET----
Step #10 - "firmware-integration-test": I0802 22:58:43.345033    5487 rom.go:51] Powering up bananas, configuring Romulans, feeding the watchdogs
Step #10 - "firmware-integration-test": I0802 22:58:43.345036    5487 rom.go:53] Configuring flash and loading FT artifacts from "/tmp/TestFTIntegration2097120807/001/dummy_device"...
Step #10 - "firmware-integration-test": I0802 22:58:43.345250    5487 rom.go:82] Bundle verification passed, prepared to boot
Step #10 - "firmware-integration-test": [app] print: Hello world!
Step #10 - "firmware-integration-test": return value = 0, duration = 10.92µs
Step #10 - "firmware-integration-test": === RUN   TestFTIntegration/Replace_FW,_boot_device
Step #10 - "firmware-integration-test": I0802 22:58:43.345723    5487 rom.go:50] ----RESET----
Step #10 - "firmware-integration-test": I0802 22:58:43.345734    5487 rom.go:51] Powering up bananas, configuring Romulans, feeding the watchdogs
Step #10 - "firmware-integration-test": I0802 22:58:43.345737    5487 rom.go:53] Configuring flash and loading FT artifacts from "/tmp/TestFTIntegration2097120807/001/dummy_device"...
Step #10 - "firmware-integration-test":     ft_integration_test.go:340: Got expected error: "ROM: failed to verify bundle: firmware measurement does not match metadata (0x3f067545cf2d0cb78c8371903070ad604e3522189fe83941ad51d3f98d301ebf7d962e147ddc49d4eb8a8c89725f25f33e3c57e6b376727f5b0309744d21780d != 0xbadb92d12e948325c26b039e7a6e64b09b900a5cac7c1e1614dbfbd038619f669a24871b4b4348584523bdddc8c36e68e06a9c88ffc27482c94f58c9659c32fa)"
Step #10 - "firmware-integration-test": === RUN   TestFTIntegration/Replace_FW,_update_hash_(but_not_sign),_and_boot
Step #10 - "firmware-integration-test": I0802 22:58:43.346571    5487 rom.go:50] ----RESET----
Step #10 - "firmware-integration-test": I0802 22:58:43.346585    5487 rom.go:51] Powering up bananas, configuring Romulans, feeding the watchdogs
Step #10 - "firmware-integration-test": I0802 22:58:43.346588    5487 rom.go:53] Configuring flash and loading FT artifacts from "/tmp/TestFTIntegration2097120807/001/dummy_device"...
Step #10 - "firmware-integration-test":     ft_integration_test.go:340: Got expected error: "ROM: failed to verify bundle: failed to verify signature on SignedStatement: failed to verify signature crypto/rsa: verification error"
Step #10 - "firmware-integration-test": === RUN   TestFTIntegration/Replace_FW,_update_hash,_sign_manifest,_and_boot
Step #10 - "firmware-integration-test": I0802 22:58:43.348406    5487 rom.go:50] ----RESET----
Step #10 - "firmware-integration-test": I0802 22:58:43.348418    5487 rom.go:51] Powering up bananas, configuring Romulans, feeding the watchdogs
Step #10 - "firmware-integration-test": I0802 22:58:43.348421    5487 rom.go:53] Configuring flash and loading FT artifacts from "/tmp/TestFTIntegration2097120807/001/dummy_device"...
Step #10 - "firmware-integration-test":     ft_integration_test.go:340: Got expected error: "ROM: failed to verify bundle: invalid inclusion proof in bundle: calculated root:\n[235 202 109 135 106 191 156 170 75 35 211 169 159 209 145 155 154 119 49 2 77 80 183 119 180 15 82 54 201 67 28 193]\n does not match expected root:\n[232 5 140 32 230 30 168 37 41 40 15 133 130 147 171 84 179 11 213 162 58 243 30 23 45 158 90 20 122 79 174 161]"
Step #10 - "firmware-integration-test": === RUN   TestFTIntegration/Log_malware,_device_boots,_but_monitor_sees_all!
Step #10 - "firmware-integration-test": I0802 22:58:43.348720    5487 publish.go:60] Measurement: 3f067545cf2d0cb78c8371903070ad604e3522189fe83941ad51d3f98d301ebf7d962e147ddc49d4eb8a8c89725f25f33e3c57e6b376727f5b0309744d21780d
Step #10 - "firmware-integration-test": W0802 22:58:43.349101    5487 ft_monitor.go:86] State file "/tmp/TestFTIntegration2097120807/003/ft-monitor.state" did not exist; first log checkpoint will be trusted implicitly
Step #10 - "firmware-integration-test": I0802 22:58:43.349139    5487 ft_monitor.go:97] Monitoring FT log ("http://localhost:43563") starting from index 0
Step #10 - "firmware-integration-test": I0802 22:58:43.350414    5487 publish.go:79] Submitting entry...
Step #10 - "firmware-integration-test": I0802 22:58:43.367534    5487 publish.go:84] Successfully submitted entry, waiting for inclusion...
Step #10 - "firmware-integration-test": I0802 22:58:44.354871    5487 ft_monitor.go:141] Found firmware (@0): dummy/v1 built at 2020-11-24 10:00:00+00:00 with image hash 0xbf2f21936b66a0665883716ea4b1ceda609304ad76dd48f6423128bc36d4cb0fb5effaa9c1f2e328a5cfc25d2cb89a337d4285a8bc3e22dbb99bddbed19e7095
Step #10 - "firmware-integration-test": I0802 22:58:44.359255    5487 ft_monitor.go:141] Found firmware (@1): dummy/v2 built at 2020-11-24 10:15:00+00:00 with image hash 0xbf2f21936b66a0665883716ea4b1ceda609304ad76dd48f6423128bc36d4cb0fb5effaa9c1f2e328a5cfc25d2cb89a337d4285a8bc3e22dbb99bddbed19e7095
Step #10 - "firmware-integration-test": I0802 22:58:45.356753    5487 ft_monitor.go:141] Found firmware (@2): dummy/v1 built at 2020-11-24 10:30:00+00:00 with image hash 0xefb19feba9ea0e0d5de73ac16d8aa9c4ceb092ecd13eab5548f49a61e85c367a2f2c8ce1eb36b67e1407148406705e67663dc5b6d3f05a45475f6e4a2b69e285
Step #10 - "firmware-integration-test": === NAME  TestFTIntegration
Step #10 - "firmware-integration-test":     ft_integration_test.go:231: Found malware firmware @2
Step #10 - "firmware-integration-test": I0802 22:58:45.373238    5487 utils.go:62] Consistency proof between 2 and 3 verified
Step #10 - "firmware-integration-test": I0802 22:58:45.376616    5487 utils.go:77] Inclusion proof for leafhash 0x5348412d323536 verified
Step #10 - "firmware-integration-test": I0802 22:58:45.376645    5487 publish.go:94] Successfully logged {"Type":102,"Statement":"eyJEZXZpY2VJRCI6ImR1bW15IiwiRmlybXdhcmVSZXZpc2lvbiI6MSwiRmlybXdhcmVJbWFnZVNIQTUxMiI6Ijc3R2Y2Nm5xRGcxZDV6ckJiWXFweE02d2t1elJQcXRWU1BTYVllaGNObm92TEl6aDZ6YTJmaFFIRklRR2NGNW5aajNGdHRQd1drVkhYMjVLSzJuaWhRPT0iLCJFeHBlY3RlZEZpcm13YXJlTWVhc3VyZW1lbnQiOiJQd1oxUmM4dERMZU1nM0dRTUhDdFlFNDFJaGlmNkRsQnJWSFQrWTB3SHI5OWxpNFVmZHhKMU91S2pJbHlYeVh6UGp4WDVyTjJjbjliQXdsMFRTRjREUT09IiwiQnVpbGRUaW1lc3RhbXAiOiIyMDIwLTExLTI0IDEwOjMwOjAwKzAwOjAwIn0=","Signature":"YYuojVYKf6KNGdIsDnVYWV+m7srU9LdZ6Wwo8b4EllyMsDwg9T20fMdsqDXe2Vtv0Ai0IHPTYhSOKASCMC6LRgBZ2OLJMn+QZdjRF6JsVmWafwpG+BGkTIoNAMfb42rUTH1xU6tzUE3kbJCntQVSMM9VVrPU2FTE8IkkPKqmnEe0E6wSRTKrEREtOqrijMgNQotq1SgD/iE3Pm7lsIvDfFy1J9IpqqDmKCv9O3eXVbKYwGIn1loM17DvEJizyn7X512VE2wWEQFW35DhgxqTAtTelO2exMf2IZQVNagiPDQsOH5NuDf9vSFuj+88k17lWnjGuAOIJOQnxzOKV1zJ7A=="}
Step #10 - "firmware-integration-test": I0802 22:58:45.376654    5487 publish.go:97] Creating update package file "/tmp/TestFTIntegration2097120807/001/update.ota"...
Step #10 - "firmware-integration-test": I0802 22:58:45.376802    5487 publish.go:126] Successfully created update package file "/tmp/TestFTIntegration2097120807/001/update.ota"
Step #10 - "firmware-integration-test": I0802 22:58:50.382481    5487 flash_tool.go:100] Update verified, about to apply to device...
Step #10 - "firmware-integration-test": I0802 22:58:50.382754    5487 flash_tool.go:106] Update applied.
Step #10 - "firmware-integration-test": I0802 22:58:50.382769    5487 rom.go:50] ----RESET----
Step #10 - "firmware-integration-test": I0802 22:58:50.382775    5487 rom.go:51] Powering up bananas, configuring Romulans, feeding the watchdogs
Step #10 - "firmware-integration-test": I0802 22:58:50.382779    5487 rom.go:53] Configuring flash and loading FT artifacts from "/tmp/TestFTIntegration2097120807/001/dummy_device"...
Step #10 - "firmware-integration-test": I0802 22:58:50.383034    5487 rom.go:82] Bundle verification passed, prepared to boot
Step #10 - "firmware-integration-test": [app] print: H4x0r3d w0rld!
Step #10 - "firmware-integration-test": [app] print: ░░░░░░░░░░░░░░░░░░░▒▓▓█████████████▓▓▒░░░░░░░░░░░░░░░░░░░░░░
Step #10 - "firmware-integration-test": [app] print: ░░░░░░░░░░░░░░▒████▓▓▒▒░▒▒▒░▒▒▒▒▒▒▓▓████▓▒░░░░░░░░░░░░░░░░░░
Step #10 - "firmware-integration-test": [app] print: ░░░░░░░░░░░▒███▓░░░░░░░░░░░░░░░░░░░░░░▒███████▓▓▒░░░░░░░░░░░
Step #10 - "firmware-integration-test": [app] print: ░░░░░░░░░▒██▓░░░░▒▒███▓▓▒░░░░░░░░░░░░░▓▓▒▒▒▒▒▓██████▓░░░░░░░
Step #10 - "firmware-integration-test": [app] print: ░░░░░░░▒██▓░░░▓███▓▒░░░░░░░░▒▒▒▒▒▒▒▒▒▒░░░░░░▒▓▓███▓████▒▒░░░
Step #10 - "firmware-integration-test": [app] print: ░░░░░░██▓░░▒▓██▓░░▒▓██████▓░░░░░░░▒░░░░░░▒██▓▒░░░▓███▒▓▒░░░░
Step #10 - "firmware-integration-test": [app] print: ░░░░░██░░▓███▒░░▒██▒░░░░▒▒██▓░░░░░░░░░░░██▒░░░░▒████▒█░░░░░░
Step #10 - "firmware-integration-test": [app] print: ░░░░██░▒▓▒▓▓░░░██░░░░░░░░░░░█▓░░░░░░░░░██░░░░░░▒███░░█▒░░░░░
Step #10 - "firmware-integration-test": [app] print: ░░░▓█░░░░░░░░░██░░░░░░░░░░░░▓█░░░░░░░░██░░░░░░░░░░░░░█▒░░░░░
Step #10 - "firmware-integration-test": [app] print: ░░░█▓░░░░░░░░░█▒░░████░░░░░░░█▒░░░░░░░██░░░░░░░░░░░░███░░░░░
Step #10 - "firmware-integration-test": [app] print: ░░▒█░░░░░░░▒▓▒█▓░▓████▓░░░░░▒█░░░░░░░░▒█▒░░░░░░░░░░██░█▒░░░░
Step #10 - "firmware-integration-test": [app] print: ░░██░░░░░▒▓▒▓▒██▒▒▓▓▓░░░░░░░██░░░░░░░░░▒████▓███████▓░█▒░░░░
Step #10 - "firmware-integration-test": [app] print: ░░█▓░░░░░▒░░░▒░▒██▓▒░░░░░▒██▓░░░░░░░░░░░░░░██▓░░░░░░▒██▓░░░░
Step #10 - "firmware-integration-test": [app] print: ░░█░░░░░░░░░▓▒░░░░▒▓██████▓░░░░░░░░░░░░░░▒██░░░▓█▓▓▒░░░██░░░
Step #10 - "firmware-integration-test": [app] print: ░▒█░░░░░░░░░░░░░░░░░░░░░░░░░░▓▒▓▒▒▒▒▒▓▓▓▓██░░▓█▓░▒▒██▒░░██░░
Step #10 - "firmware-integration-test": [app] print: ░▓█░░░░░░░░░░░░░░░░░░░░░░░░░░▒▒▒▒▒▒▓▓▒░░██░░██▓░▒░▒░██░░▒█░░
Step #10 - "firmware-integration-test": [app] print: ░██░░░░░░░░░░░░░░░░░░░░░░░▒▓▒▒▒▒▒▒▒▒░░░██░░▓█░█▓░█▒█▓█▓░░█░░
Step #10 - "firmware-integration-test": [app] print: ░██░░░░░░░░░░░░░░░░░░░░░░░░░▒▒▒▒▒░▒▒░░▓█▓░░██░█▒▒█▒█▒▓█░░█░░
Step #10 - "firmware-integration-test": [app] print: ░██░░░░░░░░░░░░░░░░░░░░░░░░▒░░░░░░░░░░▓█░░░█▒░░░░▒▒░░▒█░▓█░░
Step #10 - "firmware-integration-test": [app] print: ░▒█░░░░░░░░░░░░░░░░░░░░░░░░▒▒▒▒▒▒▒▒▒▒░░█▒░░█▒░░░░░░░░▓█░█▓░░
Step #10 - "firmware-integration-test": [app] print: ░░█▓░▒▒░░░░░░░░░░░░░░░░░░░░░░░░░░░░░░░░▓█░░█▒░░░░░░░░█░▒█░░░
Step #10 - "firmware-integration-test": [app] print: ░░▓█░░▒░░▒▒░░▒░░░░░░░░░░░░░░░░░░░░░░░░░░█░░█▒░░░░░░░█▓░█▓░░░
Step #10 - "firmware-integration-test": [app] print: ░░░█▒░░▒░░▒░░▒▒░░░░░░░░░░░░░░░░░░░░░░░░░█░░█▒░░░░░░▓█░░█░░░░
Step #10 - "firmware-integration-test": [app] print: ░░░██░░░▒░▒░░░▒▒░░░░░░░░░░░░░░░░░░░░░░░░█░░█▒░░░░░░██░░█░░░░
Step #10 - "firmware-integration-test": [app] print: ░░░░█▓░░░▒░▒░░░░▒▒░░░░░▒▒▒▒▒▒░░░░░░░░░░▒█░▒█░░░░░░░█▒░░█▓░░░
Step #10 - "firmware-integration-test": [app] print: ░░░░▓█░░░░▒▒░░░░░▒▒░░░░░░▒▒▒▓▓▓▒░░░░░░░██░██░░░░░░░██░░▓█░░░
Step #10 - "firmware-integration-test": [app] print: ░░░░░██░░░▒░▒░░░░░▒░░░░░░░░▒░▒▒▓█▒░░░░▒█░░█▓▒▓▓▓▒░░▓█░░░█▒░░
Step #10 - "firmware-integration-test": [app] print: ░░░░░▒█▒░░░▒▒▒░░░░▒░░░░░░░░░░▒▒▒░▒▓░░░██░▒█░░░░▒▓▓░░██░░█▒░░
Step #10 - "firmware-integration-test": [app] print: ░░░░░░▒█▒░░░▒░▒░░░▒░░░░░░▒▒▒░░░░▒▒░░░▒█░░██░░░░░░░█░▒█░░█▒░░
Step #10 - "firmware-integration-test": [app] print: ░░░░░░░▓█░░░▒░▒░░░░▒▒░░░░▓▒▒▓▓▓▒░░▓▒░██░░██▒▒▒▒▓▒▓▓███░░█▒░░
Step #10 - "firmware-integration-test": [app] print: ░░░░░░░░██░░░▒░▒░░░░░▒▒░░░░░░░░▓█▓░░░█▓░░██░▓█░█░█░░█▒░░█▒░░
Step #10 - "firmware-integration-test": [app] print: ░░░░░░░░░██░░░░▒▒░░░░░░▒▒░░░░░░░░▒█▓░█▓░░▓█▒▒█▒█░█▒██░░▒█░░░
Step #10 - "firmware-integration-test": [app] print: ░░░░░░░░░░██▒░░░░▒░░░▒░░░▒▒░░░░░░░░▒▓██░░░██░░░░▒▒██░░░██░░░
Step #10 - "firmware-integration-test": [app] print: ░░░░░░░░░░░▓██░░░░░░░░▒▒░░░▒░░░░░░░░░▓█░░░░▓███▓▓██░░░██░░░░
Step #10 - "firmware-integration-test": [app] print: ░░░░░░░░░░░░░▓██▒░░░░░░▒▒▒▒▒░░░░░░░░░░██░░░░░░▒▒▒░░░░██░░░░░
Step #10 - "firmware-integration-test": [app] print: ░░░░░░░░░░░░░░░▓███▒░░░░░░░▒▒▒▒▒▒▒▒░░░░▓██▒░░░░░░░▒███░░░░░░
Step #10 - "firmware-integration-test": [app] print: ░░░░░░░░░░░░░░░░░▒▓███▓▒░░░░░░░▒░░▒▒▒▒░░░▒██▓██████▓░░░░░░░░
Step #10 - "firmware-integration-test": [app] print: ░░░░░░░░░░░░░░░░░░░░░▒████▓▒▒░░░░░░░░░░░░░░░▓██▒░░░░░░░░░░░░
Step #10 - "firmware-integration-test": [app] print: ░░░░░░░░░░░░░░░░░░░░░░░░░▒▓████▓░░░░░░░▓█████▒░░░░░░░░░░░░░░
Step #10 - "firmware-integration-test": [app] print: ░░░░░░░░░░░░░░░░░░░░░░░░░░░░░░▒█████████▒░░░░░░░░░░░░░░░░░░░
Step #10 - "firmware-integration-test": [app] print: ░░░░░░░░░░░░░░░░░░░░░░░░░░░░░░░░░░▒▒▒░░░░░░░░░░░░░░░░░░░░░░░
Step #10 - "firmware-integration-test": return value = 4919, duration = 86.95µs
Step #10 - "firmware-integration-test": === RUN   TestFTIntegration/Firmware_update_with_witness_verification
Step #10 - "firmware-integration-test": I0802 22:58:50.383818    5487 ft_witness.go:53] Starting FT witness server...
Step #10 - "firmware-integration-test": I0802 22:58:50.383955    5487 witness.go:105] Polling FT log "http://localhost:43563"...
Step #10 - "firmware-integration-test": I0802 22:58:52.383973    5487 publish.go:60] Measurement: badb92d12e948325c26b039e7a6e64b09b900a5cac7c1e1614dbfbd038619f669a24871b4b4348584523bdddc8c36e68e06a9c88ffc27482c94f58c9659c32fa
Step #10 - "firmware-integration-test": I0802 22:58:52.386195    5487 publish.go:79] Submitting entry...
Step #10 - "firmware-integration-test": I0802 22:58:52.400738    5487 publish.go:84] Successfully submitted entry, waiting for inclusion...
Step #10 - "firmware-integration-test": I0802 22:58:54.405806    5487 utils.go:62] Consistency proof between 3 and 4 verified
Step #10 - "firmware-integration-test": I0802 22:58:54.409078    5487 utils.go:77] Inclusion proof for leafhash 0x5348412d323536 verified
Step #10 - "firmware-integration-test": I0802 22:58:54.409109    5487 publish.go:94] Successfully logged {"Type":102,"Statement":"eyJEZXZpY2VJRCI6ImR1bW15IiwiRmlybXdhcmVSZXZpc2lvbiI6MywiRmlybXdhcmVJbWFnZVNIQTUxMiI6InZ5OGhrMnRtb0daWWczRnVwTEhPMm1DVEJLMTIzVWoyUWpFb3ZEYlV5dysxNy9xcHdmTGpLS1hQd2wwc3VKb3pmVUtGcUx3K0l0dTVtOTIrMFo1d2xRPT0iLCJFeHBlY3RlZEZpcm13YXJlTWVhc3VyZW1lbnQiOiJ1dHVTMFM2VWd5WENhd09lZW01a3NKdVFDbHlzZkI0V0ZOdjcwRGhobjJhYUpJY2JTME5JV0VVanZkM0l3MjVvNEdxY2lQL0NkSUxKVDFqSlpad3krZz09IiwiQnVpbGRUaW1lc3RhbXAiOiIyMDIxLTAyLTE2IDEwOjAwOjAwKzAwOjAwIn0=","Signature":"HdUjDX4B5A02+j/XXJ7UPp/6TSTAQa6fJqRecrZ6x5bKLISkEcwDZEC4iZGd1um43Pmo4Nr/cI9q9Dt3lUev9WkPdpw6vdzD8/jyDinQfLr3XH+3WIROJggozSNsQlQ1Ml1GJihJMamvrtBwUAZq5in6/wSUO+QJLGdB5Z4w2XH7SX/NRGB/V3SIeuspmXjVSS52gGy97gc4SU8CZBg8qU92OMxGbDWymHnLk+SmcGmsfcWOwg6QZVhnz9yeR44hF31nPX51a+QBoEvKPB48zA95d9VzkSMx9YIBj+X4sbPWUB5P57tVm6yr2d3RmUj6P2apOSptkK2/7NOQHQxe2g=="}
Step #10 - "firmware-integration-test": I0802 22:58:54.409119    5487 publish.go:97] Creating update package file "/tmp/TestFTIntegration2097120807/001/update.ota"...
Step #10 - "firmware-integration-test": I0802 22:58:54.409245    5487 publish.go:126] Successfully created update package file "/tmp/TestFTIntegration2097120807/001/update.ota"
Step #10 - "firmware-integration-test": I0802 22:58:59.418471    5487 flash_tool.go:100] Update verified, about to apply to device...
Step #10 - "firmware-integration-test": I0802 22:58:59.418709    5487 flash_tool.go:106] Update applied.
Step #10 - "firmware-integration-test": E0802 22:58:59.418777    5487 ft_witness.go:63] witness.Poll(): context canceled
Step #10 - "firmware-integration-test": I0802 22:58:59.418800    5487 ft_witness.go:77] Server shutting down
Step #10 - "firmware-integration-test": I0802 22:58:59.418887    5487 ft_personality.go:104] Server shutting down
Step #10 - "firmware-integration-test": --- PASS: TestFTIntegration (24.14s)
Step #10 - "firmware-integration-test":     --- PASS: TestFTIntegration/Log_initial_firmware (1.03s)
Step #10 - "firmware-integration-test":     --- PASS: TestFTIntegration/Force_flashing_device_(init) (0.00s)
Step #10 - "firmware-integration-test":     --- PASS: TestFTIntegration/Boot_device_with_initial_firmware (0.00s)
Step #10 - "firmware-integration-test":     --- PASS: TestFTIntegration/Log_updated_firmware (2.03s)
Step #10 - "firmware-integration-test":     --- PASS: TestFTIntegration/Flashing_device_(update) (0.00s)
Step #10 - "firmware-integration-test":     --- PASS: TestFTIntegration/Booting_updated_device (0.00s)
Step #10 - "firmware-integration-test":     --- PASS: TestFTIntegration/Replace_FW,_boot_device (0.00s)
Step #10 - "firmware-integration-test":     --- PASS: TestFTIntegration/Replace_FW,_update_hash_(but_not_sign),_and_boot (0.00s)
Ste