0s autopkgtest [16:05:11]: starting date and time: 2024-06-14 16:05:11+0000
  0s autopkgtest [16:05:11]: git checkout: 433ed4cb Merge branch 'skia/nova_flock' into 'ubuntu/5.34+prod'
  0s autopkgtest [16:05:11]: host juju-7f2275-prod-proposed-migration-environment-3; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.7138mv_e/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:dracut --apt-upgrade clevis --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=dracut/060+5-1ubuntu3.1 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-3@bos02-ppc64el-19.secgroup --name adt-noble-ppc64el-clevis-20240614-160511-juju-7f2275-prod-proposed-migration-environment-3-48862e4c-2c65-4279-b922-ddb6424f8da7 --image adt/ubuntu-noble-ppc64el-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-3 --net-id=net_prod-proposed-migration -e TERM=linux -e ''"'"'http_proxy=http://squid.internal:3128'"'"'' -e ''"'"'https_proxy=http://squid.internal:3128'"'"'' -e ''"'"'no_proxy=127.0.0.1,127.0.1.1,login.ubuntu.com,localhost,localdomain,novalocal,internal,archive.ubuntu.com,ports.ubuntu.com,security.ubuntu.com,ddebs.ubuntu.com,changelogs.ubuntu.com,keyserver.ubuntu.com,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,10.24.0.0/24,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com'"'"'' --mirror=http://ftpmaster.internal/ubuntu/
141s autopkgtest [16:07:32]: testbed dpkg architecture: ppc64el
145s autopkgtest [16:07:36]: testbed apt version: 2.7.14build2
145s autopkgtest [16:07:36]: @@@@@@@@@@@@@@@@@@@@ test bed setup
146s Get:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease [265 kB]
147s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/main Sources [28.4 kB]
147s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/universe Sources [16.4 kB]
147s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/multiverse Sources [5468 B]
147s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/restricted Sources [10.5 kB]
147s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el Packages [34.9 kB]
147s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el c-n-f Metadata [3116 B]
147s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/restricted ppc64el Packages [2776 B]
147s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/restricted ppc64el c-n-f Metadata [116 B]
147s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/universe ppc64el Packages [42.9 kB]
147s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/universe ppc64el c-n-f Metadata [8652 B]
147s Get:12 http://ftpmaster.internal/ubuntu noble-proposed/multiverse ppc64el Packages [764 B]
147s Get:13 http://ftpmaster.internal/ubuntu noble-proposed/multiverse ppc64el c-n-f Metadata [116 B]
149s Fetched 419 kB in 1s (573 kB/s)
149s Reading package lists...
152s Reading package lists...
152s Building dependency tree...
152s Reading state information...
152s Calculating upgrade...
152s The following packages will be upgraded:
152s   dracut-install
153s 1 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
153s Need to get 35.1 kB of archives.
153s After this operation, 0 B of additional disk space will be used.
153s Get:1 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el dracut-install ppc64el 060+5-1ubuntu3.1 [35.1 kB]
153s Fetched 35.1 kB in 0s (167 kB/s)
153s (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 ... 72208 files and directories currently installed.)
153s Preparing to unpack .../dracut-install_060+5-1ubuntu3.1_ppc64el.deb ...
153s Unpacking dracut-install (060+5-1ubuntu3.1) over (060+5-1ubuntu3) ...
153s Setting up dracut-install (060+5-1ubuntu3.1) ...
153s Reading package lists...
153s Building dependency tree...
153s Reading state information...
154s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
154s Hit:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease
154s Hit:2 http://ftpmaster.internal/ubuntu noble InRelease
154s Hit:3 http://ftpmaster.internal/ubuntu noble-updates InRelease
154s Hit:4 http://ftpmaster.internal/ubuntu noble-security InRelease
155s Reading package lists...
155s Reading package lists...
156s Building dependency tree...
156s Reading state information...
156s Calculating upgrade...
156s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
156s Reading package lists...
156s Building dependency tree...
156s Reading state information...
156s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
162s autopkgtest [16:07:53]: testbed running kernel: Linux 6.8.0-35-generic #35-Ubuntu SMP Mon May 20 15:51:32 UTC 2024
162s autopkgtest [16:07:53]: @@@@@@@@@@@@@@@@@@@@ apt-source clevis
168s Get:1 http://ftpmaster.internal/ubuntu noble/universe clevis 20-1 (dsc) [2535 B]
168s Get:2 http://ftpmaster.internal/ubuntu noble/universe clevis 20-1 (tar) [68.1 kB]
168s Get:3 http://ftpmaster.internal/ubuntu noble/universe clevis 20-1 (diff) [6916 B]
168s gpgv: Signature made Tue Apr  9 12:21:52 2024 UTC
168s gpgv:                using RSA key 597308FBBDBA035D8C7C95DDC42C58EB591492FD
168s gpgv: Can't check signature: No public key
168s dpkg-source: warning: cannot verify inline signature for ./clevis_20-1.dsc: no acceptable signature found
168s autopkgtest [16:07:59]: testing package clevis version 20-1
169s autopkgtest [16:08:00]: build not needed
169s autopkgtest [16:08:00]: test run-testsuite: preparing testbed
171s Reading package lists...
171s Building dependency tree...
171s Reading state information...
171s Starting pkgProblemResolver with broken count: 0
171s Starting 2 pkgProblemResolver with broken count: 0
171s Done
171s The following additional packages will be installed:
171s   clevis clevis-luks cracklib-runtime cryptsetup jose libcrack2
171s   libhttp-parser2.9 libio-pty-perl libipc-run-perl libjose0 libluksmeta0
171s   libpwquality-common libpwquality-tools libpwquality1 libtime-duration-perl
171s   libtimedate-perl luksmeta moreutils socat tang tang-common
171s Suggested packages:
171s   keyutils
171s Recommended packages:
171s   wamerican | wordlist cryptsetup-initramfs
171s The following NEW packages will be installed:
171s   autopkgtest-satdep clevis clevis-luks cracklib-runtime cryptsetup jose
171s   libcrack2 libhttp-parser2.9 libio-pty-perl libipc-run-perl libjose0
171s   libluksmeta0 libpwquality-common libpwquality-tools libpwquality1
171s   libtime-duration-perl libtimedate-perl luksmeta moreutils socat tang
171s   tang-common
171s 0 upgraded, 22 newly installed, 0 to remove and 0 not upgraded.
171s Need to get 1286 kB/1286 kB of archives.
171s After this operation, 5900 kB of additional disk space will be used.
171s Get:1 /tmp/autopkgtest.T2KxaD/1-autopkgtest-satdep.deb autopkgtest-satdep ppc64el 0 [760 B]
172s Get:2 http://ftpmaster.internal/ubuntu noble/universe ppc64el libjose0 ppc64el 13-1 [51.9 kB]
172s Get:3 http://ftpmaster.internal/ubuntu noble/main ppc64el libcrack2 ppc64el 2.9.6-5.1build2 [31.1 kB]
172s Get:4 http://ftpmaster.internal/ubuntu noble/main ppc64el cracklib-runtime ppc64el 2.9.6-5.1build2 [148 kB]
172s Get:5 http://ftpmaster.internal/ubuntu noble/universe ppc64el jose ppc64el 13-1 [51.4 kB]
172s Get:6 http://ftpmaster.internal/ubuntu noble/main ppc64el libpwquality-common all 1.4.5-3build1 [7748 B]
172s Get:7 http://ftpmaster.internal/ubuntu noble/main ppc64el libpwquality1 ppc64el 1.4.5-3build1 [17.0 kB]
172s Get:8 http://ftpmaster.internal/ubuntu noble/universe ppc64el libpwquality-tools ppc64el 1.4.5-3build1 [9890 B]
172s Get:9 http://ftpmaster.internal/ubuntu noble/universe ppc64el libluksmeta0 ppc64el 9-4 [9924 B]
172s Get:10 http://ftpmaster.internal/ubuntu noble/universe ppc64el luksmeta ppc64el 9-4 [13.1 kB]
172s Get:11 http://ftpmaster.internal/ubuntu noble/universe ppc64el clevis ppc64el 20-1 [25.0 kB]
172s Get:12 http://ftpmaster.internal/ubuntu noble/universe ppc64el clevis-luks ppc64el 20-1 [24.6 kB]
172s Get:13 http://ftpmaster.internal/ubuntu noble/main ppc64el cryptsetup ppc64el 2:2.7.0-1ubuntu4 [208 kB]
172s Get:14 http://ftpmaster.internal/ubuntu noble/main ppc64el libhttp-parser2.9 ppc64el 2.9.4-6build1 [24.4 kB]
172s Get:15 http://ftpmaster.internal/ubuntu noble/main ppc64el libio-pty-perl ppc64el 1:1.20-1build2 [31.9 kB]
172s Get:16 http://ftpmaster.internal/ubuntu noble/main ppc64el libipc-run-perl all 20231003.0-1 [92.1 kB]
172s Get:17 http://ftpmaster.internal/ubuntu noble/main ppc64el libtime-duration-perl all 1.21-2 [12.3 kB]
172s Get:18 http://ftpmaster.internal/ubuntu noble/main ppc64el libtimedate-perl all 2.3300-2 [34.0 kB]
172s Get:19 http://ftpmaster.internal/ubuntu noble/universe ppc64el moreutils ppc64el 0.69-1 [59.1 kB]
172s Get:20 http://ftpmaster.internal/ubuntu noble/universe ppc64el tang-common ppc64el 14-2ubuntu1 [23.7 kB]
172s Get:21 http://ftpmaster.internal/ubuntu noble/universe ppc64el tang all 14-2ubuntu1 [2800 B]
172s Get:22 http://ftpmaster.internal/ubuntu noble/main ppc64el socat ppc64el 1.8.0.0-4build3 [407 kB]
172s Preconfiguring packages ...
172s Fetched 1286 kB in 1s (1839 kB/s)
172s Selecting previously unselected package libjose0:ppc64el.
173s (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 ... 72208 files and directories currently installed.)
173s Preparing to unpack .../00-libjose0_13-1_ppc64el.deb ...
173s Unpacking libjose0:ppc64el (13-1) ...
173s Selecting previously unselected package libcrack2:ppc64el.
173s Preparing to unpack .../01-libcrack2_2.9.6-5.1build2_ppc64el.deb ...
173s Unpacking libcrack2:ppc64el (2.9.6-5.1build2) ...
173s Selecting previously unselected package cracklib-runtime.
173s Preparing to unpack .../02-cracklib-runtime_2.9.6-5.1build2_ppc64el.deb ...
173s Unpacking cracklib-runtime (2.9.6-5.1build2) ...
173s Selecting previously unselected package jose.
173s Preparing to unpack .../03-jose_13-1_ppc64el.deb ...
173s Unpacking jose (13-1) ...
173s Selecting previously unselected package libpwquality-common.
173s Preparing to unpack .../04-libpwquality-common_1.4.5-3build1_all.deb ...
173s Unpacking libpwquality-common (1.4.5-3build1) ...
173s Selecting previously unselected package libpwquality1:ppc64el.
173s Preparing to unpack .../05-libpwquality1_1.4.5-3build1_ppc64el.deb ...
173s Unpacking libpwquality1:ppc64el (1.4.5-3build1) ...
173s Selecting previously unselected package libpwquality-tools.
173s Preparing to unpack .../06-libpwquality-tools_1.4.5-3build1_ppc64el.deb ...
173s Unpacking libpwquality-tools (1.4.5-3build1) ...
173s Selecting previously unselected package libluksmeta0:ppc64el.
173s Preparing to unpack .../07-libluksmeta0_9-4_ppc64el.deb ...
173s Unpacking libluksmeta0:ppc64el (9-4) ...
173s Selecting previously unselected package luksmeta.
173s Preparing to unpack .../08-luksmeta_9-4_ppc64el.deb ...
173s Unpacking luksmeta (9-4) ...
173s Selecting previously unselected package clevis.
173s Preparing to unpack .../09-clevis_20-1_ppc64el.deb ...
173s Unpacking clevis (20-1) ...
173s Selecting previously unselected package clevis-luks.
173s Preparing to unpack .../10-clevis-luks_20-1_ppc64el.deb ...
173s Unpacking clevis-luks (20-1) ...
173s Selecting previously unselected package cryptsetup.
173s Preparing to unpack .../11-cryptsetup_2%3a2.7.0-1ubuntu4_ppc64el.deb ...
173s Unpacking cryptsetup (2:2.7.0-1ubuntu4) ...
173s Selecting previously unselected package libhttp-parser2.9:ppc64el.
173s Preparing to unpack .../12-libhttp-parser2.9_2.9.4-6build1_ppc64el.deb ...
173s Unpacking libhttp-parser2.9:ppc64el (2.9.4-6build1) ...
173s Selecting previously unselected package libio-pty-perl.
173s Preparing to unpack .../13-libio-pty-perl_1%3a1.20-1build2_ppc64el.deb ...
173s Unpacking libio-pty-perl (1:1.20-1build2) ...
173s Selecting previously unselected package libipc-run-perl.
173s Preparing to unpack .../14-libipc-run-perl_20231003.0-1_all.deb ...
173s Unpacking libipc-run-perl (20231003.0-1) ...
173s Selecting previously unselected package libtime-duration-perl.
173s Preparing to unpack .../15-libtime-duration-perl_1.21-2_all.deb ...
173s Unpacking libtime-duration-perl (1.21-2) ...
173s Selecting previously unselected package libtimedate-perl.
173s Preparing to unpack .../16-libtimedate-perl_2.3300-2_all.deb ...
173s Unpacking libtimedate-perl (2.3300-2) ...
173s Selecting previously unselected package moreutils.
173s Preparing to unpack .../17-moreutils_0.69-1_ppc64el.deb ...
173s Unpacking moreutils (0.69-1) ...
173s Selecting previously unselected package tang-common.
173s Preparing to unpack .../18-tang-common_14-2ubuntu1_ppc64el.deb ...
173s Unpacking tang-common (14-2ubuntu1) ...
173s Selecting previously unselected package tang.
173s Preparing to unpack .../19-tang_14-2ubuntu1_all.deb ...
173s Unpacking tang (14-2ubuntu1) ...
173s Selecting previously unselected package socat.
173s Preparing to unpack .../20-socat_1.8.0.0-4build3_ppc64el.deb ...
173s Unpacking socat (1.8.0.0-4build3) ...
173s Selecting previously unselected package autopkgtest-satdep.
173s Preparing to unpack .../21-1-autopkgtest-satdep.deb ...
173s Unpacking autopkgtest-satdep (0) ...
173s Setting up libpwquality-common (1.4.5-3build1) ...
173s Setting up cryptsetup (2:2.7.0-1ubuntu4) ...
174s Setting up libio-pty-perl (1:1.20-1build2) ...
174s Setting up libjose0:ppc64el (13-1) ...
174s Setting up jose (13-1) ...
174s Setting up socat (1.8.0.0-4build3) ...
174s Setting up libipc-run-perl (20231003.0-1) ...
174s Setting up libtime-duration-perl (1.21-2) ...
174s Setting up libcrack2:ppc64el (2.9.6-5.1build2) ...
174s Setting up libtimedate-perl (2.3300-2) ...
174s Setting up libluksmeta0:ppc64el (9-4) ...
174s Setting up libhttp-parser2.9:ppc64el (2.9.4-6build1) ...
174s Setting up moreutils (0.69-1) ...
174s Setting up luksmeta (9-4) ...
174s Setting up tang-common (14-2ubuntu1) ...
174s Setting up cracklib-runtime (2.9.6-5.1build2) ...
174s Setting up libpwquality1:ppc64el (1.4.5-3build1) ...
174s Setting up tang (14-2ubuntu1) ...
174s Created symlink /etc/systemd/system/sockets.target.wants/tangd.socket → /usr/lib/systemd/system/tangd.socket.

175s Setting up libpwquality-tools (1.4.5-3build1) ...
175s Setting up clevis (20-1) ...
175s Setting up clevis-luks (20-1) ...
175s Setting up autopkgtest-satdep (0) ...
175s Processing triggers for man-db (2.12.0-4build2) ...
176s Processing triggers for libc-bin (2.39-0ubuntu8.2) ...
179s (Reading database ... 72639 files and directories currently installed.)
179s Removing autopkgtest-satdep (0) ...
180s autopkgtest [16:08:11]: test run-testsuite: [-----------------------
180s I: Running test ./assume-yes
186s I: ... run time: 6
186s I: Running test ./assume-yes-luks2
188s I: ... run time: 2
188s I: Running test ./backup-restore-luks1
189s I: ... run time: 1
189s I: Running test ./backup-restore-luks2
191s I: ... run time: 2
191s I: Running test ./bad-sss
191s I: ... run time: 0
191s I: Running test ./bind-already-used-luksmeta-slot
192s I: ... run time: 1
192s I: Running test ./bind-binary-keyfile-luks1
198s + TEST=./bind-binary-keyfile-luks1
198s + . tests-common-functions
198s ++ . tang-common-test-functions
198s +++ SOCAT=/usr/bin/socat
198s +++ TANGD_KEYGEN=/usr/libexec/tangd-keygen
198s +++ TANGD_UPDATE=
198s +++ TANGD=/usr/libexec/tangd
198s ++ export 'DEFAULT_PASS=   just-some-   test-password-here 1.+?~!@#$%^&*();:'\''"[]{}_=/`\   '
198s ++ DEFAULT_PASS='   just-some-   test-password-here 1.+?~!@#$%^&*();:'\''"[]{}_=/`\   '
198s + . clevis-luks-common-functions
198s ++ CLEVIS_UUID=cb6e8904-81ff-40da-a84a-07ab9ab5715e
198s ++ JOSE_PASSWORD_LENGTH=40
198s ++ enable_debugging
198s ++ '[' -e /usr/lib/dracut-lib.sh ']'
198s + trap on_exit EXIT
198s ++ mktemp -d
198s + TMP=/tmp/tmp.J6Rb3GzJTn
198s ++ tang_new_random_port
198s ++ tang_sanity_check
198s ++ '[' -n /usr/bin/socat ']'
198s ++ '[' -n /usr/libexec/tangd-keygen ']'
198s ++ '[' -n /usr/libexec/tangd ']'
198s ++ return 0
198s ++ shuf -i 1024-65535 -n 1
198s + port=43558
198s + tang_run /tmp/tmp.J6Rb3GzJTn 43558
198s + tang_sanity_check
198s + '[' -n /usr/bin/socat ']'
198s + '[' -n /usr/libexec/tangd-keygen ']'
198s + '[' -n /usr/libexec/tangd ']'
198s + return 0
198s + local basedir=/tmp/tmp.J6Rb3GzJTn
198s + local port=43558
198s + local sig_name=
198s + local exc_name=
198s + '[' -z /tmp/tmp.J6Rb3GzJTn ']'
198s + '[' -z 43558 ']'
198s + tang_new_keys /tmp/tmp.J6Rb3GzJTn '' '' ''
198s + tang_sanity_check
198s + '[' -n /usr/bin/socat ']'
198s + '[' -n /usr/libexec/tangd-keygen ']'
198s + '[' -n /usr/libexec/tangd ']'
198s + return 0
198s + local basedir=/tmp/tmp.J6Rb3GzJTn
198s + local rotate=
198s + local sig_name=
198s + local exc_name=
198s + '[' -z /tmp/tmp.J6Rb3GzJTn ']'
198s + local db=/tmp/tmp.J6Rb3GzJTn/db
198s + mkdir -p /tmp/tmp.J6Rb3GzJTn/db
198s + '[' -n '' ']'
198s + /usr/libexec/tangd-keygen /tmp/tmp.J6Rb3GzJTn/db
198s + '[' -n '' ']'
198s + return 0
198s + local KEYS=/tmp/tmp.J6Rb3GzJTn/cache
198s + '[' -z '' ']'
198s + KEYS=/tmp/tmp.J6Rb3GzJTn/db
198s + local pid pidfile
198s + pidfile=/tmp/tmp.J6Rb3GzJTn/tang.pid
198s + pid=6137
198s + /usr/bin/socat -v -v TCP-LISTEN:43558,reuseaddr,fork 'exec:/usr/libexec/tangd /tmp/tmp.J6Rb3GzJTn/db'
198s 2024/06/14 16:08:23 socat[6137] E bind(6, {AF=10 [0000:0000:0000:0000:0000:0000:0000:0000]:43558}, 28): Address already in use
198s + echo 6137
198s + tang_wait_until_ready 43558
198s + tang_sanity_check
198s + '[' -n /usr/bin/socat ']'
198s + '[' -n /usr/libexec/tangd-keygen ']'
198s + '[' -n /usr/libexec/tangd ']'
198s + return 0
198s + local port=43558
198s + '[' -z 43558 ']'
198s + local max_timeout_in_s=5
198s + local start elapsed
198s + start=0
198s + curl --output /dev/null --silent --fail http://localhost:43558/adv
198s + elapsed=0
198s + '[' 0 -gt 5 ']'
198s + sleep 0.1
198s + echo -n .
198s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
198s + elapsed=0
198s + '[' 0 -gt 5 ']'
198s + sleep 0.1
198s + echo -n .
198s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
198s + elapsed=0
198s + '[' 0 -gt 5 ']'
198s + sleep 0.1
198s + echo -n .
198s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
198s + elapsed=0
198s + '[' 0 -gt 5 ']'
198s + sleep 0.1
198s + echo -n .
198s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
198s + elapsed=0
198s + '[' 0 -gt 5 ']'
198s + sleep 0.1
198s + echo -n .
198s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
198s + elapsed=1
198s + '[' 1 -gt 5 ']'
198s + sleep 0.1
198s + echo -n .
198s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
198s + elapsed=1
198s + '[' 1 -gt 5 ']'
198s + sleep 0.1
198s + echo -n .
198s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
198s + elapsed=1
198s + '[' 1 -gt 5 ']'
198s + sleep 0.1
198s + echo -n .
198s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
198s + elapsed=1
198s + '[' 1 -gt 5 ']'
198s + sleep 0.1
198s + echo -n .
198s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
198s + elapsed=1
198s + '[' 1 -gt 5 ']'
198s + sleep 0.1
198s + echo -n .
198s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
198s + elapsed=1
198s + '[' 1 -gt 5 ']'
198s + sleep 0.1
198s + echo -n .
198s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
198s + elapsed=1
198s + '[' 1 -gt 5 ']'
198s + sleep 0.1
198s + echo -n .
198s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
198s + elapsed=1
198s + '[' 1 -gt 5 ']'
198s + sleep 0.1
198s + echo -n .
198s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
198s + elapsed=1
198s + '[' 1 -gt 5 ']'
198s + sleep 0.1
198s + echo -n .
198s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
198s + elapsed=1
198s + '[' 1 -gt 5 ']'
198s + sleep 0.1
198s + echo -n .
198s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
198s + elapsed=2
198s + '[' 2 -gt 5 ']'
198s + sleep 0.1
198s + echo -n .
198s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
198s + elapsed=2
198s + '[' 2 -gt 5 ']'
198s + sleep 0.1
198s + echo -n .
198s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
198s + elapsed=2
198s + '[' 2 -gt 5 ']'
198s + sleep 0.1
198s + echo -n .
198s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
198s + elapsed=2
198s + '[' 2 -gt 5 ']'
198s + sleep 0.1
198s + echo -n .
198s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
198s + elapsed=2
198s + '[' 2 -gt 5 ']'
198s + sleep 0.1
198s + echo -n .
198s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
198s + elapsed=2
198s + '[' 2 -gt 5 ']'
198s + sleep 0.1
198s + echo -n .
198s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
198s + elapsed=2
198s + '[' 2 -gt 5 ']'
198s + sleep 0.1
198s + echo -n .
198s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
198s + elapsed=2
198s + '[' 2 -gt 5 ']'
198s + sleep 0.1
198s + echo -n .
198s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
198s + elapsed=2
198s + '[' 2 -gt 5 ']'
198s + sleep 0.1
198s + echo -n .
198s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
198s + elapsed=3
198s + '[' 3 -gt 5 ']'
198s + sleep 0.1
198s + echo -n .
198s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
198s + elapsed=3
198s + '[' 3 -gt 5 ']'
198s + sleep 0.1
198s + echo -n .
198s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
198s + elapsed=3
198s + '[' 3 -gt 5 ']'
198s + sleep 0.1
198s + echo -n .
198s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
198s + elapsed=3
198s + '[' 3 -gt 5 ']'
198s + sleep 0.1
198s + echo -n .
198s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
198s + elapsed=3
198s + '[' 3 -gt 5 ']'
198s + sleep 0.1
198s + echo -n .
198s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
198s + elapsed=3
198s + '[' 3 -gt 5 ']'
198s + sleep 0.1
198s + echo -n .
198s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
198s + elapsed=3
198s + '[' 3 -gt 5 ']'
198s + sleep 0.1
198s + echo -n .
198s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
198s + elapsed=3
198s + '[' 3 -gt 5 ']'
198s + sleep 0.1
198s + echo -n .
198s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
198s + elapsed=3
198s + '[' 3 -gt 5 ']'
198s + sleep 0.1
198s + echo -n .
198s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
198s + elapsed=4
198s + '[' 4 -gt 5 ']'
198s + sleep 0.1
198s + echo -n .
198s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
198s + elapsed=4
198s + '[' 4 -gt 5 ']'
198s + sleep 0.1
198s + echo -n .
198s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
198s + elapsed=4
198s + '[' 4 -gt 5 ']'
198s + sleep 0.1
198s + echo -n .
198s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
198s + elapsed=4
198s + '[' 4 -gt 5 ']'
198s + sleep 0.1
198s + echo -n .
198s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
198s + elapsed=4
198s + '[' 4 -gt 5 ']'
198s + sleep 0.1
198s + echo -n .
198s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
198s + elapsed=4
198s + '[' 4 -gt 5 ']'
198s + sleep 0.1
198s + echo -n .
198s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
198s + elapsed=4
198s + '[' 4 -gt 5 ']'
198s + sleep 0.1
198s + echo -n .
198s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
198s + elapsed=4
198s + '[' 4 -gt 5 ']'
198s + sleep 0.1
198s + echo -n .
198s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
198s + elapsed=4
198s + '[' 4 -gt 5 ']'
198s + sleep 0.1
198s + echo -n .
198s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
198s + elapsed=5
198s + '[' 5 -gt 5 ']'
198s + sleep 0.1
198s + echo -n .
198s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
198s + elapsed=5
198s + '[' 5 -gt 5 ']'
198s + sleep 0.1
198s + echo -n .
198s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
198s + elapsed=5
198s + '[' 5 -gt 5 ']'
198s + sleep 0.1
198s + echo -n .
198s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
198s + elapsed=5
198s + '[' 5 -gt 5 ']'
198s + sleep 0.1
198s + echo -n .
198s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
198s + elapsed=5
198s + '[' 5 -gt 5 ']'
198s + sleep 0.1
198s + echo -n .
198s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
198s + elapsed=5
198s + '[' 5 -gt 5 ']'
198s + sleep 0.1
198s + echo -n .I: ... run time: 6
198s 
198s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
198s + elapsed=5
198s + '[' 5 -gt 5 ']'
198s + sleep 0.1
198s + echo -n .
198s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
198s + elapsed=5
198s + '[' 5 -gt 5 ']'
198s + sleep 0.1
198s + echo -n .
198s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
198s + elapsed=5
198s + '[' 5 -gt 5 ']'
198s + sleep 0.1
198s + echo -n .
198s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
198s + elapsed=5
198s + '[' 5 -gt 5 ']'
198s + sleep 0.1
198s + echo -n .
198s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
198s + elapsed=6
198s + '[' 6 -gt 5 ']'
198s + tang_error 'Timeout (5s) waiting for tang server'
198s + echo 'Timeout (5s) waiting for tang server'
198s Timeout (5s) waiting for tang server
198s + exit 1
198s + on_exit
198s + local exit_status=1
198s + tang_stop /tmp/tmp.J6Rb3GzJTn
198s + tang_sanity_check
198s + '[' -n /usr/bin/socat ']'
198s + '[' -n /usr/libexec/tangd-keygen ']'
198s + '[' -n /usr/libexec/tangd ']'
198s + return 0
198s + local basedir=/tmp/tmp.J6Rb3GzJTn
198s + '[' -z /tmp/tmp.J6Rb3GzJTn ']'
198s + local pidfile=/tmp/tmp.J6Rb3GzJTn/tang.pid
198s + '[' -f /tmp/tmp.J6Rb3GzJTn/tang.pid ']'
198s + local pid
198s + pid=6137
198s + kill -9 6137
198s + :
198s + '[' -d /tmp/tmp.J6Rb3GzJTn ']'
198s + rm -rf /tmp/tmp.J6Rb3GzJTn
198s + exit 1
198s I: Running test ./bind-key-file-non-interactive-luks1
198s I: ... run time: 0
198s I: Running test ./bind-luks1
199s I: ... run time: 1
199s I: Running test ./bind-luks1-avoid-luksmeta-corruption
214s I: ... run time: 15
214s I: Running test ./bind-luks2
214s I: ... run time: 0
214s I: Running test ./bind-pass-with-newline-keyfile-luks1
215s I: ... run time: 1
215s I: Running test ./bind-pass-with-newline-luks1
215s I: ... run time: 0
215s I: Running test ./bind-wrong-pass-luks1
216s I: ... run time: 1
216s I: Running test ./bind-wrong-pass-luks2
216s I: ... run time: 0
216s I: Running test ./edit-tang-luks1
220s I: ... run time: 4
220s I: Running test ./edit-tang-luks2
225s I: ... run time: 5
225s I: Running test ./list-recursive-luks1
226s I: ... run time: 1
226s I: Running test ./list-recursive-luks2
226s I: ... run time: 0
226s I: Running test ./list-sss-tang-luks1
227s I: ... run time: 1
227s I: Running test ./list-sss-tang-luks2
229s I: ... run time: 2
229s I: Running test ./list-tang-luks1
229s I: ... run time: 0
229s I: Running test ./list-tang-luks2
230s I: ... run time: 1
230s I: Running test ./pass-tang-luks1
231s I: ... run time: 1
231s I: Running test ./pass-tang-luks2
232s I: ... run time: 1
232s I: Running test ./regen-inplace-luks1
233s I: ... run time: 1
233s I: Running test ./regen-inplace-luks2
234s I: ... run time: 1
234s I: Running test ./regen-not-inplace-luks1
236s I: ... run time: 2
236s I: Running test ./regen-not-inplace-luks2
237s I: ... run time: 1
237s I: Running test ./report-sss-luks1
240s I: ... run time: 3
240s I: Running test ./report-sss-luks2
243s I: ... run time: 3
243s I: Running test ./report-tang-luks1
244s I: ... run time: 1
244s I: Running test ./report-tang-luks2
246s I: ... run time: 2
246s I: Running test ./unbind-luks1
246s I: ... run time: 0
246s I: Running test ./unbind-luks2
247s I: ... run time: 1
247s I: Running test ./unbind-unbound-slot-luks1
247s I: ... run time: 0
247s I: Running test ./unbind-unbound-slot-luks2
248s I: ... run time: 1
248s I: Running test ./unlock-arbitrary-parameter
248s I: ... run time: 0
248s I: Running test ./unlock-tang-luks1
250s I: ... run time: 2
250s I: Running test ./unlock-tang-luks2
252s I: ... run time: 2
252s I: Running test pin-tang
253s autopkgtest [16:09:24]: test run-testsuite: -----------------------]
253s autopkgtest [16:09:24]: test run-testsuite:  - - - - - - - - - - results - - - - - - - - - -
253s run-testsuite        FAIL non-zero exit status 1
253s autopkgtest [16:09:24]: test run-testsuite:  - - - - - - - - - - stderr - - - - - - - - - -
253s + TEST=./bind-binary-keyfile-luks1
253s + . tests-common-functions
253s ++ . tang-common-test-functions
253s +++ SOCAT=/usr/bin/socat
253s +++ TANGD_KEYGEN=/usr/libexec/tangd-keygen
253s +++ TANGD_UPDATE=
253s +++ TANGD=/usr/libexec/tangd
253s ++ export 'DEFAULT_PASS=   just-some-   test-password-here 1.+?~!@#$%^&*();:'\''"[]{}_=/`\   '
253s ++ DEFAULT_PASS='   just-some-   test-password-here 1.+?~!@#$%^&*();:'\''"[]{}_=/`\   '
253s + . clevis-luks-common-functions
253s ++ CLEVIS_UUID=cb6e8904-81ff-40da-a84a-07ab9ab5715e
253s ++ JOSE_PASSWORD_LENGTH=40
253s ++ enable_debugging
253s ++ '[' -e /usr/lib/dracut-lib.sh ']'
253s + trap on_exit EXIT
253s ++ mktemp -d
253s + TMP=/tmp/tmp.J6Rb3GzJTn
253s ++ tang_new_random_port
253s ++ tang_sanity_check
253s ++ '[' -n /usr/bin/socat ']'
253s ++ '[' -n /usr/libexec/tangd-keygen ']'
253s ++ '[' -n /usr/libexec/tangd ']'
253s ++ return 0
253s ++ shuf -i 1024-65535 -n 1
253s + port=43558
253s + tang_run /tmp/tmp.J6Rb3GzJTn 43558
253s + tang_sanity_check
253s + '[' -n /usr/bin/socat ']'
253s + '[' -n /usr/libexec/tangd-keygen ']'
253s + '[' -n /usr/libexec/tangd ']'
253s + return 0
253s + local basedir=/tmp/tmp.J6Rb3GzJTn
253s + local port=43558
253s + local sig_name=
253s + local exc_name=
253s + '[' -z /tmp/tmp.J6Rb3GzJTn ']'
253s + '[' -z 43558 ']'
253s + tang_new_keys /tmp/tmp.J6Rb3GzJTn '' '' ''
253s + tang_sanity_check
253s + '[' -n /usr/bin/socat ']'
253s + '[' -n /usr/libexec/tangd-keygen ']'
253s + '[' -n /usr/libexec/tangd ']'
253s + return 0
253s + local basedir=/tmp/tmp.J6Rb3GzJTn
253s + local rotate=
253s + local sig_name=
253s + local exc_name=
253s + '[' -z /tmp/tmp.J6Rb3GzJTn ']'
253s + local db=/tmp/tmp.J6Rb3GzJTn/db
253s + mkdir -p /tmp/tmp.J6Rb3GzJTn/db
253s + '[' -n '' ']'
253s + /usr/libexec/tangd-keygen /tmp/tmp.J6Rb3GzJTn/db
253s + '[' -n '' ']'
253s + return 0
253s + local KEYS=/tmp/tmp.J6Rb3GzJTn/cache
253s + '[' -z '' ']'
253s + KEYS=/tmp/tmp.J6Rb3GzJTn/db
253s + local pid pidfile
253s + pidfile=/tmp/tmp.J6Rb3GzJTn/tang.pid
253s + pid=6137
253s + /usr/bin/socat -v -v TCP-LISTEN:43558,reuseaddr,fork 'exec:/usr/libexec/tangd /tmp/tmp.J6Rb3GzJTn/db'
253s 2024/06/14 16:08:23 socat[6137] E bind(6, {AF=10 [0000:0000:0000:0000:0000:0000:0000:0000]:43558}, 28): Address already in use
253s + echo 6137
253s + tang_wait_until_ready 43558
253s + tang_sanity_check
253s + '[' -n /usr/bin/socat ']'
253s + '[' -n /usr/libexec/tangd-keygen ']'
253s + '[' -n /usr/libexec/tangd ']'
253s + return 0
253s + local port=43558
253s + '[' -z 43558 ']'
253s + local max_timeout_in_s=5
253s + local start elapsed
253s + start=0
253s + curl --output /dev/null --silent --fail http://localhost:43558/adv
253s + elapsed=0
253s + '[' 0 -gt 5 ']'
253s + sleep 0.1
253s + echo -n .
253s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
253s + elapsed=0
253s + '[' 0 -gt 5 ']'
253s + sleep 0.1
253s + echo -n .
253s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
253s + elapsed=0
253s + '[' 0 -gt 5 ']'
253s + sleep 0.1
253s + echo -n .
253s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
253s + elapsed=0
253s + '[' 0 -gt 5 ']'
253s + sleep 0.1
253s + echo -n .
253s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
253s + elapsed=0
253s + '[' 0 -gt 5 ']'
253s + sleep 0.1
253s + echo -n .
253s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
253s + elapsed=1
253s + '[' 1 -gt 5 ']'
253s + sleep 0.1
253s + echo -n .
253s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
253s + elapsed=1
253s + '[' 1 -gt 5 ']'
253s + sleep 0.1
253s + echo -n .
253s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
253s + elapsed=1
253s + '[' 1 -gt 5 ']'
253s + sleep 0.1
253s + echo -n .
253s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
253s + elapsed=1
253s + '[' 1 -gt 5 ']'
253s + sleep 0.1
253s + echo -n .
253s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
253s + elapsed=1
253s + '[' 1 -gt 5 ']'
253s + sleep 0.1
253s + echo -n .
253s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
253s + elapsed=1
253s + '[' 1 -gt 5 ']'
253s + sleep 0.1
253s + echo -n .
253s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
253s + elapsed=1
253s + '[' 1 -gt 5 ']'
253s + sleep 0.1
253s + echo -n .
253s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
253s + elapsed=1
253s + '[' 1 -gt 5 ']'
253s + sleep 0.1
253s + echo -n .
253s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
253s + elapsed=1
253s + '[' 1 -gt 5 ']'
253s + sleep 0.1
253s + echo -n .
253s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
253s + elapsed=1
253s + '[' 1 -gt 5 ']'
253s + sleep 0.1
253s + echo -n .
253s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
253s + elapsed=2
253s + '[' 2 -gt 5 ']'
253s + sleep 0.1
253s + echo -n .
253s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
253s + elapsed=2
253s + '[' 2 -gt 5 ']'
253s + sleep 0.1
253s + echo -n .
253s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
253s + elapsed=2
253s + '[' 2 -gt 5 ']'
253s + sleep 0.1
253s + echo -n .
253s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
253s + elapsed=2
253s + '[' 2 -gt 5 ']'
253s + sleep 0.1
253s + echo -n .
253s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
253s + elapsed=2
253s + '[' 2 -gt 5 ']'
253s + sleep 0.1
253s + echo -n .
253s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
253s + elapsed=2
253s + '[' 2 -gt 5 ']'
253s + sleep 0.1
253s + echo -n .
253s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
253s + elapsed=2
253s + '[' 2 -gt 5 ']'
253s + sleep 0.1
253s + echo -n .
253s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
253s + elapsed=2
253s + '[' 2 -gt 5 ']'
253s + sleep 0.1
253s + echo -n .
253s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
253s + elapsed=2
253s + '[' 2 -gt 5 ']'
253s + sleep 0.1
253s + echo -n .
253s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
253s + elapsed=3
253s + '[' 3 -gt 5 ']'
253s + sleep 0.1
253s + echo -n .
253s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
253s + elapsed=3
253s + '[' 3 -gt 5 ']'
253s + sleep 0.1
253s + echo -n .
253s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
253s + elapsed=3
253s + '[' 3 -gt 5 ']'
253s + sleep 0.1
253s + echo -n .
253s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
253s + elapsed=3
253s + '[' 3 -gt 5 ']'
253s + sleep 0.1
253s + echo -n .
253s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
253s + elapsed=3
253s + '[' 3 -gt 5 ']'
253s + sleep 0.1
253s + echo -n .
253s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
253s + elapsed=3
253s + '[' 3 -gt 5 ']'
253s + sleep 0.1
253s + echo -n .
253s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
253s + elapsed=3
253s + '[' 3 -gt 5 ']'
253s + sleep 0.1
253s + echo -n .
253s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
253s + elapsed=3
253s + '[' 3 -gt 5 ']'
253s + sleep 0.1
253s + echo -n .
253s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
253s + elapsed=3
253s + '[' 3 -gt 5 ']'
253s + sleep 0.1
253s + echo -n .
253s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
253s + elapsed=4
253s + '[' 4 -gt 5 ']'
253s + sleep 0.1
253s + echo -n .
253s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
253s + elapsed=4
253s + '[' 4 -gt 5 ']'
253s + sleep 0.1
253s + echo -n .
253s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
253s + elapsed=4
253s + '[' 4 -gt 5 ']'
253s + sleep 0.1
253s + echo -n .
253s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
253s + elapsed=4
253s + '[' 4 -gt 5 ']'
253s + sleep 0.1
253s + echo -n .
253s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
253s + elapsed=4
253s + '[' 4 -gt 5 ']'
253s + sleep 0.1
253s + echo -n .
253s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
253s + elapsed=4
253s + '[' 4 -gt 5 ']'
253s + sleep 0.1
253s + echo -n .
253s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
253s + elapsed=4
253s + '[' 4 -gt 5 ']'
253s + sleep 0.1
253s + echo -n .
253s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
253s + elapsed=4
253s + '[' 4 -gt 5 ']'
253s + sleep 0.1
253s + echo -n .
253s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
253s + elapsed=4
253s + '[' 4 -gt 5 ']'
253s + sleep 0.1
253s + echo -n .
253s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
253s + elapsed=5
253s + '[' 5 -gt 5 ']'
253s + sleep 0.1
253s + echo -n .
253s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
253s + elapsed=5
253s + '[' 5 -gt 5 ']'
253s + sleep 0.1
253s + echo -n .
253s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
253s + elapsed=5
253s + '[' 5 -gt 5 ']'
253s + sleep 0.1
253s + echo -n .
253s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
253s + elapsed=5
253s + '[' 5 -gt 5 ']'
253s + sleep 0.1
253s + echo -n .
253s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
253s + elapsed=5
253s + '[' 5 -gt 5 ']'
253s + sleep 0.1
253s + echo -n .
253s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
253s + elapsed=5
253s + '[' 5 -gt 5 ']'
253s + sleep 0.1
253s + echo -n .
253s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
253s + elapsed=5
253s + '[' 5 -gt 5 ']'
253s + sleep 0.1
253s + echo -n .
253s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
253s + elapsed=5
253s + '[' 5 -gt 5 ']'
253s + sleep 0.1
253s + echo -n .
253s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
253s + elapsed=5
253s + '[' 5 -gt 5 ']'
253s + sleep 0.1
253s + echo -n .
253s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
253s + elapsed=5
253s + '[' 5 -gt 5 ']'
253s + sleep 0.1
253s + echo -n .
253s .+ curl --output /dev/null --silent --fail http://localhost:43558/adv
253s + elapsed=6
253s + '[' 6 -gt 5 ']'
253s + tang_error 'Timeout (5s) waiting for tang server'
253s + echo 'Timeout (5s) waiting for tang server'
253s Timeout (5s) waiting for tang server
253s + exit 1
253s + on_exit
253s + local exit_status=1
253s + tang_stop /tmp/tmp.J6Rb3GzJTn
253s + tang_sanity_check
253s + '[' -n /usr/bin/socat ']'
253s + '[' -n /usr/libexec/tangd-keygen ']'
253s + '[' -n /usr/libexec/tangd ']'
253s + return 0
253s + local basedir=/tmp/tmp.J6Rb3GzJTn
253s + '[' -z /tmp/tmp.J6Rb3GzJTn ']'
253s + local pidfile=/tmp/tmp.J6Rb3GzJTn/tang.pid
253s + '[' -f /tmp/tmp.J6Rb3GzJTn/tang.pid ']'
253s + local pid
253s + pid=6137
253s + kill -9 6137
253s + :
253s + '[' -d /tmp/tmp.J6Rb3GzJTn ']'
253s + rm -rf /tmp/tmp.J6Rb3GzJTn
253s + exit 1
254s autopkgtest [16:09:25]: @@@@@@@@@@@@@@@@@@@@ summary
254s run-testsuite        FAIL non-zero exit status 1
265s nova [W] Using flock in scalingstack-bos02-ppc64el
265s Creating nova instance adt-noble-ppc64el-clevis-20240614-160511-juju-7f2275-prod-proposed-migration-environment-3-48862e4c-2c65-4279-b922-ddb6424f8da7 from image adt/ubuntu-noble-ppc64el-server-20240614.img (UUID 49409fbf-0afb-4167-b6d3-ba666e9f40d8)...