0s autopkgtest [10:07:03]: starting date and time: 2024-07-10 10:07:03+0000 0s autopkgtest [10:07:03]: git checkout: fd3bed09 nova: allow more retries for quota issues 0s autopkgtest [10:07:03]: host juju-7f2275-prod-proposed-migration-environment-3; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.8ia82ohy/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:cryptsetup --apt-upgrade clevis --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=cryptsetup/2:2.7.0-1ubuntu4.1 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-3@bos03-arm64-16.secgroup --name adt-noble-arm64-clevis-20240710-100703-juju-7f2275-prod-proposed-migration-environment-3-7d91c234-bcec-41c1-b16f-01b6581c51ed --image adt/ubuntu-noble-arm64-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/ 60s autopkgtest [10:08:03]: testbed dpkg architecture: arm64 60s autopkgtest [10:08:03]: testbed apt version: 2.7.14build2 60s autopkgtest [10:08:03]: @@@@@@@@@@@@@@@@@@@@ test bed setup 61s Get:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease [265 kB] 62s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/restricted Sources [12.5 kB] 62s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/main Sources [46.0 kB] 62s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/universe Sources [13.4 kB] 62s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/main arm64 Packages [106 kB] 62s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main arm64 c-n-f Metadata [1992 B] 62s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/restricted arm64 Packages [96.2 kB] 62s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/restricted arm64 c-n-f Metadata [116 B] 62s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/universe arm64 Packages [51.0 kB] 62s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/universe arm64 c-n-f Metadata [1240 B] 62s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/multiverse arm64 c-n-f Metadata [116 B] 63s Fetched 594 kB in 1s (800 kB/s) 63s Reading package lists... 66s Reading package lists... 66s Building dependency tree... 66s Reading state information... 66s Calculating upgrade... 67s The following packages will be upgraded: 67s cryptsetup-bin libcryptsetup12 67s 2 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 67s Need to get 472 kB of archives. 67s After this operation, 0 B of additional disk space will be used. 67s Get:1 http://ftpmaster.internal/ubuntu noble-proposed/main arm64 libcryptsetup12 arm64 2:2.7.0-1ubuntu4.1 [262 kB] 67s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/main arm64 cryptsetup-bin arm64 2:2.7.0-1ubuntu4.1 [210 kB] 68s Fetched 472 kB in 1s (858 kB/s) 68s (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 ... 77975 files and directories currently installed.) 68s Preparing to unpack .../libcryptsetup12_2%3a2.7.0-1ubuntu4.1_arm64.deb ... 68s Unpacking libcryptsetup12:arm64 (2:2.7.0-1ubuntu4.1) over (2:2.7.0-1ubuntu4) ... 68s Preparing to unpack .../cryptsetup-bin_2%3a2.7.0-1ubuntu4.1_arm64.deb ... 68s Unpacking cryptsetup-bin (2:2.7.0-1ubuntu4.1) over (2:2.7.0-1ubuntu4) ... 68s Setting up libcryptsetup12:arm64 (2:2.7.0-1ubuntu4.1) ... 68s Setting up cryptsetup-bin (2:2.7.0-1ubuntu4.1) ... 68s Processing triggers for man-db (2.12.0-4build2) ... 69s Processing triggers for libc-bin (2.39-0ubuntu8.2) ... 69s Reading package lists... 69s Building dependency tree... 69s Reading state information... 70s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 70s Hit:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease 70s Hit:2 http://ftpmaster.internal/ubuntu noble InRelease 71s Hit:3 http://ftpmaster.internal/ubuntu noble-updates InRelease 71s Hit:4 http://ftpmaster.internal/ubuntu noble-security InRelease 71s Reading package lists... 71s Reading package lists... 72s Building dependency tree... 72s Reading state information... 72s Calculating upgrade... 72s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 72s Reading package lists... 73s Building dependency tree... 73s Reading state information... 73s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 76s autopkgtest [10:08:19]: testbed running kernel: Linux 6.8.0-36-generic #36-Ubuntu SMP PREEMPT_DYNAMIC Mon Jun 10 13:20:23 UTC 2024 76s autopkgtest [10:08:19]: @@@@@@@@@@@@@@@@@@@@ apt-source clevis 81s Get:1 http://ftpmaster.internal/ubuntu noble/universe clevis 20-1 (dsc) [2535 B] 81s Get:2 http://ftpmaster.internal/ubuntu noble/universe clevis 20-1 (tar) [68.1 kB] 81s Get:3 http://ftpmaster.internal/ubuntu noble/universe clevis 20-1 (diff) [6916 B] 81s gpgv: Signature made Tue Apr 9 12:21:52 2024 UTC 81s gpgv: using RSA key 597308FBBDBA035D8C7C95DDC42C58EB591492FD 81s gpgv: Can't check signature: No public key 81s dpkg-source: warning: cannot verify inline signature for ./clevis_20-1.dsc: no acceptable signature found 82s autopkgtest [10:08:25]: testing package clevis version 20-1 82s autopkgtest [10:08:25]: build not needed 83s autopkgtest [10:08:26]: test run-testsuite: preparing testbed 84s Reading package lists... 84s Building dependency tree... 84s Reading state information... 84s Starting pkgProblemResolver with broken count: 0 84s Starting 2 pkgProblemResolver with broken count: 0 84s Done 85s The following additional packages will be installed: 85s clevis clevis-luks cracklib-runtime cryptsetup jose libcrack2 85s libhttp-parser2.9 libio-pty-perl libipc-run-perl libjose0 libluksmeta0 85s libpwquality-common libpwquality-tools libpwquality1 libtime-duration-perl 85s libtimedate-perl luksmeta moreutils socat tang tang-common 85s Suggested packages: 85s keyutils 85s Recommended packages: 85s wamerican | wordlist cryptsetup-initramfs 85s The following NEW packages will be installed: 85s autopkgtest-satdep clevis clevis-luks cracklib-runtime cryptsetup jose 85s libcrack2 libhttp-parser2.9 libio-pty-perl libipc-run-perl libjose0 85s libluksmeta0 libpwquality-common libpwquality-tools libpwquality1 85s libtime-duration-perl libtimedate-perl luksmeta moreutils socat tang 85s tang-common 85s 0 upgraded, 22 newly installed, 0 to remove and 0 not upgraded. 85s Need to get 1222 kB/1223 kB of archives. 85s After this operation, 5693 kB of additional disk space will be used. 85s Get:1 /tmp/autopkgtest.2QuMqq/1-autopkgtest-satdep.deb autopkgtest-satdep arm64 0 [752 B] 85s Get:2 http://ftpmaster.internal/ubuntu noble/universe arm64 libjose0 arm64 13-1 [44.5 kB] 85s Get:3 http://ftpmaster.internal/ubuntu noble/main arm64 libcrack2 arm64 2.9.6-5.1build2 [28.9 kB] 85s Get:4 http://ftpmaster.internal/ubuntu noble/main arm64 cracklib-runtime arm64 2.9.6-5.1build2 [148 kB] 86s Get:5 http://ftpmaster.internal/ubuntu noble/universe arm64 jose arm64 13-1 [48.1 kB] 86s Get:6 http://ftpmaster.internal/ubuntu noble/main arm64 libpwquality-common all 1.4.5-3build1 [7748 B] 86s Get:7 http://ftpmaster.internal/ubuntu noble/main arm64 libpwquality1 arm64 1.4.5-3build1 [13.3 kB] 86s Get:8 http://ftpmaster.internal/ubuntu noble/universe arm64 libpwquality-tools arm64 1.4.5-3build1 [9812 B] 86s Get:9 http://ftpmaster.internal/ubuntu noble/universe arm64 libluksmeta0 arm64 9-4 [8140 B] 86s Get:10 http://ftpmaster.internal/ubuntu noble/universe arm64 luksmeta arm64 9-4 [12.8 kB] 86s Get:11 http://ftpmaster.internal/ubuntu noble/universe arm64 clevis arm64 20-1 [23.9 kB] 86s Get:12 http://ftpmaster.internal/ubuntu noble/universe arm64 clevis-luks arm64 20-1 [24.6 kB] 86s Get:13 http://ftpmaster.internal/ubuntu noble-proposed/main arm64 cryptsetup arm64 2:2.7.0-1ubuntu4.1 [208 kB] 86s Get:14 http://ftpmaster.internal/ubuntu noble/main arm64 libhttp-parser2.9 arm64 2.9.4-6build1 [21.9 kB] 86s Get:15 http://ftpmaster.internal/ubuntu noble/main arm64 libio-pty-perl arm64 1:1.20-1build2 [31.1 kB] 86s Get:16 http://ftpmaster.internal/ubuntu noble/main arm64 libipc-run-perl all 20231003.0-1 [92.1 kB] 86s Get:17 http://ftpmaster.internal/ubuntu noble/main arm64 libtime-duration-perl all 1.21-2 [12.3 kB] 86s Get:18 http://ftpmaster.internal/ubuntu noble/main arm64 libtimedate-perl all 2.3300-2 [34.0 kB] 86s Get:19 http://ftpmaster.internal/ubuntu noble/universe arm64 moreutils arm64 0.69-1 [56.7 kB] 86s Get:20 http://ftpmaster.internal/ubuntu noble/universe arm64 tang-common arm64 14-2ubuntu1 [22.2 kB] 86s Get:21 http://ftpmaster.internal/ubuntu noble/universe arm64 tang all 14-2ubuntu1 [2800 B] 86s Get:22 http://ftpmaster.internal/ubuntu noble/main arm64 socat arm64 1.8.0.0-4build3 [372 kB] 86s Preconfiguring packages ... 86s Fetched 1222 kB in 1s (1477 kB/s) 86s Selecting previously unselected package libjose0:arm64. 86s (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 ... 77975 files and directories currently installed.) 86s Preparing to unpack .../00-libjose0_13-1_arm64.deb ... 86s Unpacking libjose0:arm64 (13-1) ... 86s Selecting previously unselected package libcrack2:arm64. 86s Preparing to unpack .../01-libcrack2_2.9.6-5.1build2_arm64.deb ... 86s Unpacking libcrack2:arm64 (2.9.6-5.1build2) ... 86s Selecting previously unselected package cracklib-runtime. 86s Preparing to unpack .../02-cracklib-runtime_2.9.6-5.1build2_arm64.deb ... 86s Unpacking cracklib-runtime (2.9.6-5.1build2) ... 87s Selecting previously unselected package jose. 87s Preparing to unpack .../03-jose_13-1_arm64.deb ... 87s Unpacking jose (13-1) ... 87s Selecting previously unselected package libpwquality-common. 87s Preparing to unpack .../04-libpwquality-common_1.4.5-3build1_all.deb ... 87s Unpacking libpwquality-common (1.4.5-3build1) ... 87s Selecting previously unselected package libpwquality1:arm64. 87s Preparing to unpack .../05-libpwquality1_1.4.5-3build1_arm64.deb ... 87s Unpacking libpwquality1:arm64 (1.4.5-3build1) ... 87s Selecting previously unselected package libpwquality-tools. 87s Preparing to unpack .../06-libpwquality-tools_1.4.5-3build1_arm64.deb ... 87s Unpacking libpwquality-tools (1.4.5-3build1) ... 87s Selecting previously unselected package libluksmeta0:arm64. 87s Preparing to unpack .../07-libluksmeta0_9-4_arm64.deb ... 87s Unpacking libluksmeta0:arm64 (9-4) ... 87s Selecting previously unselected package luksmeta. 87s Preparing to unpack .../08-luksmeta_9-4_arm64.deb ... 87s Unpacking luksmeta (9-4) ... 87s Selecting previously unselected package clevis. 87s Preparing to unpack .../09-clevis_20-1_arm64.deb ... 87s Unpacking clevis (20-1) ... 87s Selecting previously unselected package clevis-luks. 87s Preparing to unpack .../10-clevis-luks_20-1_arm64.deb ... 87s Unpacking clevis-luks (20-1) ... 87s Selecting previously unselected package cryptsetup. 87s Preparing to unpack .../11-cryptsetup_2%3a2.7.0-1ubuntu4.1_arm64.deb ... 87s Unpacking cryptsetup (2:2.7.0-1ubuntu4.1) ... 87s Selecting previously unselected package libhttp-parser2.9:arm64. 87s Preparing to unpack .../12-libhttp-parser2.9_2.9.4-6build1_arm64.deb ... 87s Unpacking libhttp-parser2.9:arm64 (2.9.4-6build1) ... 87s Selecting previously unselected package libio-pty-perl. 87s Preparing to unpack .../13-libio-pty-perl_1%3a1.20-1build2_arm64.deb ... 87s Unpacking libio-pty-perl (1:1.20-1build2) ... 87s Selecting previously unselected package libipc-run-perl. 87s Preparing to unpack .../14-libipc-run-perl_20231003.0-1_all.deb ... 87s Unpacking libipc-run-perl (20231003.0-1) ... 87s Selecting previously unselected package libtime-duration-perl. 87s Preparing to unpack .../15-libtime-duration-perl_1.21-2_all.deb ... 87s Unpacking libtime-duration-perl (1.21-2) ... 87s Selecting previously unselected package libtimedate-perl. 87s Preparing to unpack .../16-libtimedate-perl_2.3300-2_all.deb ... 87s Unpacking libtimedate-perl (2.3300-2) ... 87s Selecting previously unselected package moreutils. 87s Preparing to unpack .../17-moreutils_0.69-1_arm64.deb ... 87s Unpacking moreutils (0.69-1) ... 87s Selecting previously unselected package tang-common. 87s Preparing to unpack .../18-tang-common_14-2ubuntu1_arm64.deb ... 87s Unpacking tang-common (14-2ubuntu1) ... 87s Selecting previously unselected package tang. 87s Preparing to unpack .../19-tang_14-2ubuntu1_all.deb ... 87s Unpacking tang (14-2ubuntu1) ... 87s Selecting previously unselected package socat. 87s Preparing to unpack .../20-socat_1.8.0.0-4build3_arm64.deb ... 87s Unpacking socat (1.8.0.0-4build3) ... 87s Selecting previously unselected package autopkgtest-satdep. 87s Preparing to unpack .../21-1-autopkgtest-satdep.deb ... 87s Unpacking autopkgtest-satdep (0) ... 87s Setting up libpwquality-common (1.4.5-3build1) ... 87s Setting up cryptsetup (2:2.7.0-1ubuntu4.1) ... 88s Setting up libio-pty-perl (1:1.20-1build2) ... 88s Setting up libjose0:arm64 (13-1) ... 88s Setting up jose (13-1) ... 88s Setting up socat (1.8.0.0-4build3) ... 88s Setting up libipc-run-perl (20231003.0-1) ... 88s Setting up libtime-duration-perl (1.21-2) ... 88s Setting up libcrack2:arm64 (2.9.6-5.1build2) ... 88s Setting up libtimedate-perl (2.3300-2) ... 88s Setting up libluksmeta0:arm64 (9-4) ... 88s Setting up libhttp-parser2.9:arm64 (2.9.4-6build1) ... 88s Setting up moreutils (0.69-1) ... 88s Setting up luksmeta (9-4) ... 88s Setting up tang-common (14-2ubuntu1) ... 88s Setting up cracklib-runtime (2.9.6-5.1build2) ... 88s Setting up libpwquality1:arm64 (1.4.5-3build1) ... 88s Setting up tang (14-2ubuntu1) ... 88s Created symlink /etc/systemd/system/sockets.target.wants/tangd.socket → /usr/lib/systemd/system/tangd.socket. 88s Setting up libpwquality-tools (1.4.5-3build1) ... 88s Setting up clevis (20-1) ... 88s Setting up clevis-luks (20-1) ... 88s Setting up autopkgtest-satdep (0) ... 88s Processing triggers for man-db (2.12.0-4build2) ... 89s Processing triggers for libc-bin (2.39-0ubuntu8.2) ... 93s (Reading database ... 78406 files and directories currently installed.) 93s Removing autopkgtest-satdep (0) ... 93s autopkgtest [10:08:36]: test run-testsuite: [----------------------- 94s I: Running test ./assume-yes 103s I: ... run time: 9 103s I: Running test ./assume-yes-luks2 105s I: ... run time: 2 105s I: Running test ./backup-restore-luks1 107s I: ... run time: 2 107s I: Running test ./backup-restore-luks2 110s I: ... run time: 3 110s I: Running test ./bad-sss 110s I: ... run time: 0 110s I: Running test ./bind-already-used-luksmeta-slot 111s I: ... run time: 1 111s I: Running test ./bind-binary-keyfile-luks1 112s I: ... run time: 1 112s I: Running test ./bind-key-file-non-interactive-luks1 113s I: ... run time: 1 113s I: Running test ./bind-luks1 114s I: ... run time: 0 114s I: Running test ./bind-luks1-avoid-luksmeta-corruption 136s I: ... run time: 23 136s I: Running test ./bind-luks2 136s I: ... run time: 0 136s I: Running test ./bind-pass-with-newline-keyfile-luks1 137s I: ... run time: 1 137s I: Running test ./bind-pass-with-newline-luks1 138s I: ... run time: 1 138s I: Running test ./bind-wrong-pass-luks1 138s I: ... run time: 0 138s I: Running test ./bind-wrong-pass-luks2 138s I: ... run time: 0 138s I: Running test ./edit-tang-luks1 145s I: ... run time: 7 145s I: Running test ./edit-tang-luks2 151s I: ... run time: 6 151s I: Running test ./list-recursive-luks1 152s I: ... run time: 1 152s I: Running test ./list-recursive-luks2 154s I: ... run time: 2 154s I: Running test ./list-sss-tang-luks1 155s I: ... run time: 1 155s I: Running test ./list-sss-tang-luks2 157s I: ... run time: 2 157s I: Running test ./list-tang-luks1 157s I: ... run time: 0 157s I: Running test ./list-tang-luks2 158s I: ... run time: 1 158s I: Running test ./pass-tang-luks1 159s I: ... run time: 1 159s I: Running test ./pass-tang-luks2 160s I: ... run time: 1 160s I: Running test ./regen-inplace-luks1 162s I: ... run time: 1 162s I: Running test ./regen-inplace-luks2 163s I: ... run time: 2 163s I: Running test ./regen-not-inplace-luks1 165s I: ... run time: 2 165s I: Running test ./regen-not-inplace-luks2 166s I: ... run time: 1 166s I: Running test ./report-sss-luks1 169s I: ... run time: 3 169s I: Running test ./report-sss-luks2 172s I: ... run time: 3 172s I: Running test ./report-tang-luks1 174s I: ... run time: 2 174s I: Running test ./report-tang-luks2 176s I: ... run time: 2 176s I: Running test ./unbind-luks1 176s I: ... run time: 0 176s I: Running test ./unbind-luks2 177s I: ... run time: 1 177s I: Running test ./unbind-unbound-slot-luks1 177s I: ... run time: 0 177s I: Running test ./unbind-unbound-slot-luks2 178s I: ... run time: 1 178s I: Running test ./unlock-arbitrary-parameter 178s I: ... run time: 0 178s I: Running test ./unlock-tang-luks1 185s ++ basename ./unlock-tang-luks1 185s + TEST=unlock-tang-luks1 185s + . tests-common-functions 185s ++ . tang-common-test-functions 185s +++ SOCAT=/usr/bin/socat 185s +++ TANGD_KEYGEN=/usr/libexec/tangd-keygen 185s +++ TANGD_UPDATE= 185s +++ TANGD=/usr/libexec/tangd 185s ++ export 'DEFAULT_PASS= just-some- test-password-here 1.+?~!@#$%^&*();:'\''"[]{}_=/`\ ' 185s ++ DEFAULT_PASS=' just-some- test-password-here 1.+?~!@#$%^&*();:'\''"[]{}_=/`\ ' 185s + . clevis-luks-common-functions 185s ++ CLEVIS_UUID=cb6e8904-81ff-40da-a84a-07ab9ab5715e 185s ++ JOSE_PASSWORD_LENGTH=40 185s ++ enable_debugging 185s ++ '[' -e /usr/lib/dracut-lib.sh ']' 185s + trap on_exit EXIT 185s + trap on_exit ERR 185s ++ mktemp -d 185s + TMP=/tmp/tmp.Uxc9ujfGCH 185s ++ tang_new_random_port 185s ++ tang_sanity_check 185s ++ '[' -n /usr/bin/socat ']' 185s ++ '[' -n /usr/libexec/tangd-keygen ']' 185s ++ '[' -n /usr/libexec/tangd ']' 185s ++ return 0 185s ++ shuf -i 1024-65535 -n 1 185s + port=46072 185s + tang_run /tmp/tmp.Uxc9ujfGCH 46072 185s + tang_sanity_check 185s + '[' -n /usr/bin/socat ']' 185s + '[' -n /usr/libexec/tangd-keygen ']' 185s + '[' -n /usr/libexec/tangd ']' 185s + return 0 185s + local basedir=/tmp/tmp.Uxc9ujfGCH 185s + local port=46072 185s + local sig_name= 185s + local exc_name= 185s + '[' -z /tmp/tmp.Uxc9ujfGCH ']' 185s + '[' -z 46072 ']' 185s + tang_new_keys /tmp/tmp.Uxc9ujfGCH '' '' '' 185s + tang_sanity_check 185s + '[' -n /usr/bin/socat ']' 185s + '[' -n /usr/libexec/tangd-keygen ']' 185s + '[' -n /usr/libexec/tangd ']' 185s + return 0 185s + local basedir=/tmp/tmp.Uxc9ujfGCH 185s + local rotate= 185s + local sig_name= 185s + local exc_name= 185s + '[' -z /tmp/tmp.Uxc9ujfGCH ']' 185s + local db=/tmp/tmp.Uxc9ujfGCH/db 185s + mkdir -p /tmp/tmp.Uxc9ujfGCH/db 185s + '[' -n '' ']' 185s + /usr/libexec/tangd-keygen /tmp/tmp.Uxc9ujfGCH/db 185s + '[' -n '' ']' 185s + return 0 185s + local KEYS=/tmp/tmp.Uxc9ujfGCH/cache 185s + '[' -z '' ']' 185s + KEYS=/tmp/tmp.Uxc9ujfGCH/db 185s + local pid pidfile 185s + pidfile=/tmp/tmp.Uxc9ujfGCH/tang.pid 185s + pid=17707 185s + echo 17707 185s + /usr/bin/socat -v -v TCP-LISTEN:46072,reuseaddr,fork 'exec:/usr/libexec/tangd /tmp/tmp.Uxc9ujfGCH/db' 185s + tang_wait_until_ready 46072 185s + tang_sanity_check 185s + '[' -n /usr/bin/socat ']' 185s + '[' -n /usr/libexec/tangd-keygen ']' 185s + '[' -n /usr/libexec/tangd ']' 185s + return 0 185s + local port=46072 185s + '[' -z 46072 ']' 185s + local max_timeout_in_s=5 185s + local start elapsed 185s + start=1 185s + curl --output /dev/null --silent --fail http://localhost:46072/adv 185s 2024/07/10 10:10:02 socat[17707] E bind(6, {AF=10 [0000:0000:0000:0000:0000:0000:0000:0000]:46072}, 28): Address already in use 185s + elapsed=0 185s + '[' 0 -gt 5 ']' 185s + sleep 0.1 185s + echo -n . 185s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 185s + elapsed=0 185s + '[' 0 -gt 5 ']' 185s + sleep 0.1 185s + echo -n . 185s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 185s + elapsed=0 185s + '[' 0 -gt 5 ']' 185s + sleep 0.1 185s + echo -n . 185s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 185s + elapsed=0 185s + '[' 0 -gt 5 ']' 185s + sleep 0.1 185s + echo -n . 185s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 185s + elapsed=0 185s + '[' 0 -gt 5 ']' 185s + sleep 0.1 185s + echo -n . 185s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 185s + elapsed=0 185s + '[' 0 -gt 5 ']' 185s + sleep 0.1 185s + echo -n . 185s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 185s + elapsed=0 185s + '[' 0 -gt 5 ']' 185s + sleep 0.1 185s + echo -n . 185s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 185s + elapsed=0 185s + '[' 0 -gt 5 ']' 185s + sleep 0.1 185s + echo -n . 185s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 185s + elapsed=0 185s + '[' 0 -gt 5 ']' 185s + sleep 0.1 185s + echo -n . 185s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 185s + elapsed=1 185s + '[' 1 -gt 5 ']' 185s + sleep 0.1 185s + echo -n . 185s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 185s + elapsed=1 185s + '[' 1 -gt 5 ']' 185s + sleep 0.1 185s + echo -n . 185s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 185s + elapsed=1 185s + '[' 1 -gt 5 ']' 185s + sleep 0.1 185s + echo -n . 185s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 185s + elapsed=1 185s + '[' 1 -gt 5 ']' 185s + sleep 0.1 185s + echo -n . 185s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 185s + elapsed=1 185s + '[' 1 -gt 5 ']' 185s + sleep 0.1 185s + echo -n . 185s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 185s + elapsed=1 185s + '[' 1 -gt 5 ']' 185s + sleep 0.1 185s + echo -n . 185s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 185s + elapsed=1 185s + '[' 1 -gt 5 ']' 185s + sleep 0.1 185s + echo -n . 185s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 185s + elapsed=1 185s + '[' 1 -gt 5 ']' 185s + sleep 0.1 185s + echo -n . 185s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 185s + elapsed=1 185s + '[' 1 -gt 5 ']' 185s + sleep 0.1 185s + echo -n . 185s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 185s + elapsed=2 185s + '[' 2 -gt 5 ']' 185s + sleep 0.1 185s + echo -n . 185s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 185s + elapsed=2 185s + '[' 2 -gt 5 ']' 185s + sleep 0.1 185s + echo -n . 185s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 185s + elapsed=2 185s + '[' 2 -gt 5 ']' 185s + sleep 0.1 185s + echo -n . 185s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 185s + elapsed=2 185s + '[' 2 -gt 5 ']' 185s + sleep 0.1 185s + echo -n . 185s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 185s + elapsed=2 185s + '[' 2 -gt 5 ']' 185s + sleep 0.1 185s + echo -n . 185s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 185s + elapsed=2 185s + '[' 2 -gt 5 ']' 185s + sleep 0.1 185s + echo -n . 185s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 185s + elapsed=2 185s + '[' 2 -gt 5 ']' 185s + sleep 0.1 185s + echo -n . 185s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 185s + elapsed=2 185s + '[' 2 -gt 5 ']' 185s + sleep 0.1 185s + echo -n . 185s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 185s + elapsed=2 185s + '[' 2 -gt 5 ']' 185s + sleep 0.1 185s + echo -n . 185s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 185s + elapsed=2 185s + '[' 2 -gt 5 ']' 185s + sleep 0.1 185s + echo -n . 185s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 185s + elapsed=3 185s + '[' 3 -gt 5 ']' 185s + sleep 0.1 185s + echo -n . 185s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 185s + elapsed=3 185s + '[' 3 -gt 5 ']' 185s + sleep 0.1 185s + echo -n . 185s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 185s + elapsed=3 185s + '[' 3 -gt 5 ']' 185s + sleep 0.1 185s + echo -n . 185s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 185s + elapsed=3 185s + '[' 3 -gt 5 ']' 185s + sleep 0.1 185s + echo -n . 185s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 185s + elapsed=3 185s + '[' 3 -gt 5 ']' 185s + sleep 0.1 185s + echo -n . 185s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 185s + elapsed=3 185s + '[' 3 -gt 5 ']' 185s + sleep 0.1 185s + echo -n . 185s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 185s + elapsed=3 185s + '[' 3 -gt 5 ']' 185s + sleep 0.1 185s + echo -n . 185s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 185s + elapsed=3 185s + '[' 3 -gt 5 ']' 185s + sleep 0.1 185s + echo -n . 185s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 185s + elapsed=3 185s + '[' 3 -gt 5 ']' 185s + sleep 0.1 185s + echo -n . 185s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 185s + elapsed=4 185s + '[' 4 -gt 5 ']' 185s + sleep 0.1 185s + echo -n . 185s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 185s + elapsed=4 185s + '[' 4 -gt 5 ']' 185s + sleep 0.1 185s + echo -n . 185s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 185s + elapsed=4 185s + '[' 4 -gt 5 ']' 185s + sleep 0.1 185s + echo -n . 185s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 185s + elapsed=4 185s + '[' 4 -gt 5 ']' 185s + sleep 0.1 185s + echo -n . 185s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 185s + elapsed=4 185s + '[' 4 -gt 5 ']' 185s + sleep 0.1 185s + echo -n . 185s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 185s + elapsed=4 185s + '[' 4 -gt 5 ']' 185s + sleep 0.1 185s + echo -n . 185s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 185s + elapsed=4 185s + '[' 4 -gt 5 ']' 185s + sleep 0.1 185s + echo -n . 185s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 185s + elapsed=4 185s + '[' 4 -gt 5 ']' 185s + sleep 0.1 185s + echo -n . 185s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 185s + elapsed=4 185s + '[' 4 -gt 5 ']' 185s + sleep 0.1 185s + echo -n . 185s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 185s + elapsed=5 185s + '[' 5 -gt 5 ']' 185s + sleep 0.1 185s + echo -n . 185s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 185s + elapsed=5 185s + '[' 5 -gt 5 ']' 185s + sleep 0.1 185s + echo -n . 185s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 185s + elapsed=5 185s + '[' 5 -gt 5 ']' 185s + sleep 0.1 185s + echo -n . 185s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 185s + elapsed=5 185s + '[' 5 -gt 5 ']' 185s + sleep 0.1 185s + echo -n . 185s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 185s + elapsed=5 185s + '[' 5 -gt 5 ']' 185s + sleep 0.1 185s + echo -n . 185s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 185s + elapsed=5 185s + '[' 5 -gt 5 ']' 185s + sleep 0.1 185s + echo -n . 185s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 185s + elapsed=5 185s + '[' 5 -gt 5 ']' 185s + sleep 0.1 185s + echo -n . 185s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 185s + elapsed=5 185s + '[' 5 -gt 5 ']' 185s + sleep 0.1 185s + echo -n . 185s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 185s + elapsed=5 185s + '[' 5 -gt 5 ']' 185s + sleep 0.1 185s + echo -n . 185s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 185s + elapsed=6 185s + '[' 6 -gt 5 ']' 185s + tang_error 'Timeout (5s) waiting for tang server' 185s + echo 'Timeout (5s) waiting for tang server' 185s Timeout (5s) waiting for tang server 185s + exit 1 185s + on_exit 185s + '[' '!' -d /tmp/tmp.Uxc9ujfGCH ']' 185s + tang_stop /tmp/tmp.Uxc9ujfGCH 185s + tang_sanity_check 185s + '[' -n /usr/bin/socat ']' 185s + '[' -n /usr/libexec/tangd-keygen ']' 185s + '[' -n /usr/libexec/tangd ']' 185s + return 0 185s + local basedir=/tmp/tmp.Uxc9ujfGCH 185s + '[' -z /tmp/tmp.Uxc9ujfGCH ']' 185s + local pidfile=/tmp/tmp.Uxc9ujfGCH/tang.pid 185s + '[' -f /tmp/tmp.Uxc9ujfGCH/tang.pid ']' 185s + local pid 185s + pid=17707 185s + kill -9 17707 185s + : 185s + rm -rf /tmp/tmp.Uxc9ujfGCH 185s I: ... run time: 7 185s I: Running test ./unlock-tang-luks2 187s I: ... run time: 2 187s I: Running test pin-tang 188s autopkgtest [10:10:11]: test run-testsuite: -----------------------] 189s autopkgtest [10:10:12]: test run-testsuite: - - - - - - - - - - results - - - - - - - - - - 189s run-testsuite FAIL non-zero exit status 1 189s autopkgtest [10:10:12]: test run-testsuite: - - - - - - - - - - stderr - - - - - - - - - - 189s ++ basename ./unlock-tang-luks1 189s + TEST=unlock-tang-luks1 189s + . tests-common-functions 189s ++ . tang-common-test-functions 189s +++ SOCAT=/usr/bin/socat 189s +++ TANGD_KEYGEN=/usr/libexec/tangd-keygen 189s +++ TANGD_UPDATE= 189s +++ TANGD=/usr/libexec/tangd 189s ++ export 'DEFAULT_PASS= just-some- test-password-here 1.+?~!@#$%^&*();:'\''"[]{}_=/`\ ' 189s ++ DEFAULT_PASS=' just-some- test-password-here 1.+?~!@#$%^&*();:'\''"[]{}_=/`\ ' 189s + . clevis-luks-common-functions 189s ++ CLEVIS_UUID=cb6e8904-81ff-40da-a84a-07ab9ab5715e 189s ++ JOSE_PASSWORD_LENGTH=40 189s ++ enable_debugging 189s ++ '[' -e /usr/lib/dracut-lib.sh ']' 189s + trap on_exit EXIT 189s + trap on_exit ERR 189s ++ mktemp -d 189s + TMP=/tmp/tmp.Uxc9ujfGCH 189s ++ tang_new_random_port 189s ++ tang_sanity_check 189s ++ '[' -n /usr/bin/socat ']' 189s ++ '[' -n /usr/libexec/tangd-keygen ']' 189s ++ '[' -n /usr/libexec/tangd ']' 189s ++ return 0 189s ++ shuf -i 1024-65535 -n 1 189s + port=46072 189s + tang_run /tmp/tmp.Uxc9ujfGCH 46072 189s + tang_sanity_check 189s + '[' -n /usr/bin/socat ']' 189s + '[' -n /usr/libexec/tangd-keygen ']' 189s + '[' -n /usr/libexec/tangd ']' 189s + return 0 189s + local basedir=/tmp/tmp.Uxc9ujfGCH 189s + local port=46072 189s + local sig_name= 189s + local exc_name= 189s + '[' -z /tmp/tmp.Uxc9ujfGCH ']' 189s + '[' -z 46072 ']' 189s + tang_new_keys /tmp/tmp.Uxc9ujfGCH '' '' '' 189s + tang_sanity_check 189s + '[' -n /usr/bin/socat ']' 189s + '[' -n /usr/libexec/tangd-keygen ']' 189s + '[' -n /usr/libexec/tangd ']' 189s + return 0 189s + local basedir=/tmp/tmp.Uxc9ujfGCH 189s + local rotate= 189s + local sig_name= 189s + local exc_name= 189s + '[' -z /tmp/tmp.Uxc9ujfGCH ']' 189s + local db=/tmp/tmp.Uxc9ujfGCH/db 189s + mkdir -p /tmp/tmp.Uxc9ujfGCH/db 189s + '[' -n '' ']' 189s + /usr/libexec/tangd-keygen /tmp/tmp.Uxc9ujfGCH/db 189s + '[' -n '' ']' 189s + return 0 189s + local KEYS=/tmp/tmp.Uxc9ujfGCH/cache 189s + '[' -z '' ']' 189s + KEYS=/tmp/tmp.Uxc9ujfGCH/db 189s + local pid pidfile 189s + pidfile=/tmp/tmp.Uxc9ujfGCH/tang.pid 189s + pid=17707 189s + echo 17707 189s + /usr/bin/socat -v -v TCP-LISTEN:46072,reuseaddr,fork 'exec:/usr/libexec/tangd /tmp/tmp.Uxc9ujfGCH/db' 189s + tang_wait_until_ready 46072 189s + tang_sanity_check 189s + '[' -n /usr/bin/socat ']' 189s + '[' -n /usr/libexec/tangd-keygen ']' 189s + '[' -n /usr/libexec/tangd ']' 189s + return 0 189s + local port=46072 189s + '[' -z 46072 ']' 189s + local max_timeout_in_s=5 189s + local start elapsed 189s + start=1 189s + curl --output /dev/null --silent --fail http://localhost:46072/adv 189s 2024/07/10 10:10:02 socat[17707] E bind(6, {AF=10 [0000:0000:0000:0000:0000:0000:0000:0000]:46072}, 28): Address already in use 189s + elapsed=0 189s + '[' 0 -gt 5 ']' 189s + sleep 0.1 189s + echo -n . 189s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 189s + elapsed=0 189s + '[' 0 -gt 5 ']' 189s + sleep 0.1 189s + echo -n . 189s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 189s + elapsed=0 189s + '[' 0 -gt 5 ']' 189s + sleep 0.1 189s + echo -n . 189s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 189s + elapsed=0 189s + '[' 0 -gt 5 ']' 189s + sleep 0.1 189s + echo -n . 189s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 189s + elapsed=0 189s + '[' 0 -gt 5 ']' 189s + sleep 0.1 189s + echo -n . 189s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 189s + elapsed=0 189s + '[' 0 -gt 5 ']' 189s + sleep 0.1 189s + echo -n . 189s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 189s + elapsed=0 189s + '[' 0 -gt 5 ']' 189s + sleep 0.1 189s + echo -n . 189s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 189s + elapsed=0 189s + '[' 0 -gt 5 ']' 189s + sleep 0.1 189s + echo -n . 189s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 189s + elapsed=0 189s + '[' 0 -gt 5 ']' 189s + sleep 0.1 189s + echo -n . 189s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 189s + elapsed=1 189s + '[' 1 -gt 5 ']' 189s + sleep 0.1 189s + echo -n . 189s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 189s + elapsed=1 189s + '[' 1 -gt 5 ']' 189s + sleep 0.1 189s + echo -n . 189s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 189s + elapsed=1 189s + '[' 1 -gt 5 ']' 189s + sleep 0.1 189s + echo -n . 189s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 189s + elapsed=1 189s + '[' 1 -gt 5 ']' 189s + sleep 0.1 189s + echo -n . 189s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 189s + elapsed=1 189s + '[' 1 -gt 5 ']' 189s + sleep 0.1 189s + echo -n . 189s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 189s + elapsed=1 189s + '[' 1 -gt 5 ']' 189s + sleep 0.1 189s + echo -n . 189s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 189s + elapsed=1 189s + '[' 1 -gt 5 ']' 189s + sleep 0.1 189s + echo -n . 189s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 189s + elapsed=1 189s + '[' 1 -gt 5 ']' 189s + sleep 0.1 189s + echo -n . 189s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 189s + elapsed=1 189s + '[' 1 -gt 5 ']' 189s + sleep 0.1 189s + echo -n . 189s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 189s + elapsed=2 189s + '[' 2 -gt 5 ']' 189s + sleep 0.1 189s + echo -n . 189s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 189s + elapsed=2 189s + '[' 2 -gt 5 ']' 189s + sleep 0.1 189s + echo -n . 189s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 189s + elapsed=2 189s + '[' 2 -gt 5 ']' 189s + sleep 0.1 189s + echo -n . 189s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 189s + elapsed=2 189s + '[' 2 -gt 5 ']' 189s + sleep 0.1 189s + echo -n . 189s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 189s + elapsed=2 189s + '[' 2 -gt 5 ']' 189s + sleep 0.1 189s + echo -n . 189s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 189s + elapsed=2 189s + '[' 2 -gt 5 ']' 189s + sleep 0.1 189s + echo -n . 189s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 189s + elapsed=2 189s + '[' 2 -gt 5 ']' 189s + sleep 0.1 189s + echo -n . 189s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 189s + elapsed=2 189s + '[' 2 -gt 5 ']' 189s + sleep 0.1 189s + echo -n . 189s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 189s + elapsed=2 189s + '[' 2 -gt 5 ']' 189s + sleep 0.1 189s + echo -n . 189s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 189s + elapsed=2 189s + '[' 2 -gt 5 ']' 189s + sleep 0.1 189s + echo -n . 189s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 189s + elapsed=3 189s + '[' 3 -gt 5 ']' 189s + sleep 0.1 189s + echo -n . 189s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 189s + elapsed=3 189s + '[' 3 -gt 5 ']' 189s + sleep 0.1 189s + echo -n . 189s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 189s + elapsed=3 189s + '[' 3 -gt 5 ']' 189s + sleep 0.1 189s + echo -n . 189s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 189s + elapsed=3 189s + '[' 3 -gt 5 ']' 189s + sleep 0.1 189s + echo -n . 189s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 189s + elapsed=3 189s + '[' 3 -gt 5 ']' 189s + sleep 0.1 189s + echo -n . 189s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 189s + elapsed=3 189s + '[' 3 -gt 5 ']' 189s + sleep 0.1 189s + echo -n . 189s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 189s + elapsed=3 189s + '[' 3 -gt 5 ']' 189s + sleep 0.1 189s + echo -n . 189s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 189s + elapsed=3 189s + '[' 3 -gt 5 ']' 189s + sleep 0.1 189s + echo -n . 189s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 189s + elapsed=3 189s + '[' 3 -gt 5 ']' 189s + sleep 0.1 189s + echo -n . 189s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 189s + elapsed=4 189s + '[' 4 -gt 5 ']' 189s + sleep 0.1 189s + echo -n . 189s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 189s + elapsed=4 189s + '[' 4 -gt 5 ']' 189s + sleep 0.1 189s + echo -n . 189s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 189s + elapsed=4 189s + '[' 4 -gt 5 ']' 189s + sleep 0.1 189s + echo -n . 189s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 189s + elapsed=4 189s + '[' 4 -gt 5 ']' 189s + sleep 0.1 189s + echo -n . 189s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 189s + elapsed=4 189s + '[' 4 -gt 5 ']' 189s + sleep 0.1 189s + echo -n . 189s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 189s + elapsed=4 189s + '[' 4 -gt 5 ']' 189s + sleep 0.1 189s + echo -n . 189s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 189s + elapsed=4 189s + '[' 4 -gt 5 ']' 189s + sleep 0.1 189s + echo -n . 189s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 189s + elapsed=4 189s + '[' 4 -gt 5 ']' 189s + sleep 0.1 189s + echo -n . 189s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 189s + elapsed=4 189s + '[' 4 -gt 5 ']' 189s + sleep 0.1 189s + echo -n . 189s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 189s + elapsed=5 189s + '[' 5 -gt 5 ']' 189s + sleep 0.1 189s + echo -n . 189s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 189s + elapsed=5 189s + '[' 5 -gt 5 ']' 189s + sleep 0.1 189s + echo -n . 189s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 189s + elapsed=5 189s + '[' 5 -gt 5 ']' 189s + sleep 0.1 189s + echo -n . 189s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 189s + elapsed=5 189s + '[' 5 -gt 5 ']' 189s + sleep 0.1 189s + echo -n . 189s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 189s + elapsed=5 189s + '[' 5 -gt 5 ']' 189s + sleep 0.1 189s + echo -n . 189s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 189s + elapsed=5 189s + '[' 5 -gt 5 ']' 189s + sleep 0.1 189s + echo -n . 189s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 189s + elapsed=5 189s + '[' 5 -gt 5 ']' 189s + sleep 0.1 189s + echo -n . 189s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 189s + elapsed=5 189s + '[' 5 -gt 5 ']' 189s + sleep 0.1 189s + echo -n . 189s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 189s + elapsed=5 189s + '[' 5 -gt 5 ']' 189s + sleep 0.1 189s + echo -n . 189s .+ curl --output /dev/null --silent --fail http://localhost:46072/adv 189s + elapsed=6 189s + '[' 6 -gt 5 ']' 189s + tang_error 'Timeout (5s) waiting for tang server' 189s + echo 'Timeout (5s) waiting for tang server' 189s Timeout (5s) waiting for tang server 189s + exit 1 189s + on_exit 189s + '[' '!' -d /tmp/tmp.Uxc9ujfGCH ']' 189s + tang_stop /tmp/tmp.Uxc9ujfGCH 189s + tang_sanity_check 189s + '[' -n /usr/bin/socat ']' 189s + '[' -n /usr/libexec/tangd-keygen ']' 189s + '[' -n /usr/libexec/tangd ']' 189s + return 0 189s + local basedir=/tmp/tmp.Uxc9ujfGCH 189s + '[' -z /tmp/tmp.Uxc9ujfGCH ']' 189s + local pidfile=/tmp/tmp.Uxc9ujfGCH/tang.pid 189s + '[' -f /tmp/tmp.Uxc9ujfGCH/tang.pid ']' 189s + local pid 189s + pid=17707 189s + kill -9 17707 189s + : 189s + rm -rf /tmp/tmp.Uxc9ujfGCH 189s autopkgtest [10:10:12]: @@@@@@@@@@@@@@@@@@@@ summary 189s run-testsuite FAIL non-zero exit status 1 201s nova [W] Skipping flock in bos03-arm64 201s Creating nova instance adt-noble-arm64-clevis-20240710-100703-juju-7f2275-prod-proposed-migration-environment-3-7d91c234-bcec-41c1-b16f-01b6581c51ed from image adt/ubuntu-noble-arm64-server-20240710.img (UUID 178e15c1-0a54-49eb-a973-c820a3fba62e)...