Public bug reported: [Impact]
This is happening on at least stonking and resolute. It was not always happening on resolute, so it is possibly caused by another package or component in autopkgtest. Example failure from resolute: https://autopkgtest.ubuntu.com/results/autopkgtest-resolute/resolute/amd64/s/systemd/20260617_100833_83166@/log.gz Example failure from stonking: https://autopkgtest.ubuntu.com/results/autopkgtest-stonking/stonking/amd64/s/systemd/20260617_214852_6d89d@/log.gz 7442s integration-tests - systemd:TEST-70-TPM2 time out (After 1800 seconds) 7442s 97/98 integration-tests - systemd:TEST-70-TPM2 TIMEOUT 1800.31s (exit status 254 or 0xfe) 7442s >>> MALLOC_PERTURB_=136 UBSAN_OPTIONS=halt_on_error=1:abort_on_error=1:print_summary=1:print_stacktrace=1 ASAN_OPTIONS=halt_on_error=1:abort_on_error=1:print_summary=1 MSAN_OPTIONS=halt_on_error=1:abort_on_error=1:print_summary=1:print_stacktrace=1 MESON_TEST_ITERATION=1 /tmp/autopkgtest.NgkF01/build.nGk/src/test/integration-tests/integration-test-wrapper.py --meson-source-dir /tmp/autopkgtest.NgkF01/build.nGk/src --meson-build-dir /var/tmp/integration-tests.A6hYkKxhS2/btrfs/build --name TEST-70-TPM2 --storage volatile --firmware auto --exit-code 123 --coredump-exclude-regex '' --sanitizer-exclude-regex '' --unit TEST-70-TPM2.service --vm --tpm --mkosi /usr/bin/mkosi -- 7442s ――――――――――――――――――――――――――――――――――――― ✀ ――――――――――――――――――――――――――――――――――――― 7442s Listing only the last 100 lines from a long log. 7442s [ 236.395637] TEST-70-TPM2.sh[493]: + systemd-cryptenroll --unlock-tpm2-device=auto --tpm2-device=auto --tpm2-with-pin=yes /tmp/systemd-cryptenroll-piR.image 7442s [ 236.399224] TEST-70-TPM2.sh[3091]: Failed to find TPM2 pcrlock policy file 'pcrlock.json': No such file or directory 7442s [ 236.400437] TEST-70-TPM2.sh[3091]: Successfully called mlockall(). 7442s [ 236.401501] TEST-70-TPM2.sh[3091]: Loaded shared library 'libcryptsetup.so.12' via dlopen(). 7442s [ 236.402434] TEST-70-TPM2.sh[3091]: Allocating context for crypt device /tmp/systemd-cryptenroll-piR.image. 7442s [ 236.403651] TEST-70-TPM2.sh[3091]: Trying to open device /tmp/systemd-cryptenroll-piR.image with direct-io. 7442s [ 236.405379] TEST-70-TPM2.sh[3091]: Direct-io read works. 7442s [ 236.406600] TEST-70-TPM2.sh[3091]: Initialising device-mapper backend library. 7442s [ 236.407621] TEST-70-TPM2.sh[3091]: Trying to load LUKS2 crypt type from device /tmp/systemd-cryptenroll-piR.image. 7442s [ 236.408917] TEST-70-TPM2.sh[3091]: Crypto backend (OpenSSL 3.5.5 27 Jan 2026 [default][legacy][threads][argon2]) initialized in cryptsetup library version 2.8.4. 7442s [ 236.410528] TEST-70-TPM2.sh[3091]: Detected kernel Linux 7.0.0-22-generic x86_64. 7442s [ 236.411499] TEST-70-TPM2.sh[3091]: Loading LUKS2 header (repair disabled). 7442s [ 236.412592] TEST-70-TPM2.sh[3091]: Acquiring read lock for device /tmp/systemd-cryptenroll-piR.image. 7442s [ 236.414416] TEST-70-TPM2.sh[3091]: Verifying lock handle for /tmp/systemd-cryptenroll-piR.image. 7442s [ 236.415544] TEST-70-TPM2.sh[3091]: Device /tmp/systemd-cryptenroll-piR.image READ lock taken. 7442s [ 236.416725] TEST-70-TPM2.sh[3091]: Trying to read primary LUKS2 header at offset 0x0. 7442s [ 236.418485] TEST-70-TPM2.sh[3091]: Opening locked device /tmp/systemd-cryptenroll-piR.image 7442s [ 236.419531] TEST-70-TPM2.sh[3091]: Verifying locked device handle (regular file) 7442s [ 236.421370] TEST-70-TPM2.sh[3091]: LUKS2 header version 2 of size 16384 bytes, checksum sha256. 7442s [ 236.422595] TEST-70-TPM2.sh[3091]: Checksum:17a300830e08fad7548c3f296147082205c1367b17309c3d5a8fe242d1f03ce8 (on-disk) 7442s [ 236.424200] TEST-70-TPM2.sh[3091]: Checksum:17a300830e08fad7548c3f296147082205c1367b17309c3d5a8fe242d1f03ce8 (in-memory) 7442s [ 236.425387] TEST-70-TPM2.sh[3091]: Trying to read secondary LUKS2 header at offset 0x4000. 7442s [ 236.426632] TEST-70-TPM2.sh[3091]: Reusing open ro fd on device /tmp/systemd-cryptenroll-piR.image 7442s [ 236.427952] TEST-70-TPM2.sh[3091]: LUKS2 header version 2 of size 16384 bytes, checksum sha256. 7442s [ 236.429498] TEST-70-TPM2.sh[3091]: Checksum:8541b77a533f8eb532a6a28f60d8cafefc67f39e1dfa8e66a1fe0d5a92695f7c (on-disk) 7442s [ 236.430601] TEST-70-TPM2.sh[3091]: Checksum:8541b77a533f8eb532a6a28f60d8cafefc67f39e1dfa8e66a1fe0d5a92695f7c (in-memory) 7442s [ 236.432376] TEST-70-TPM2.sh[3091]: Device size 20971520, offset 16777216. 7442s [ 236.433626] TEST-70-TPM2.sh[3091]: Device /tmp/systemd-cryptenroll-piR.image READ lock released. 7442s [ 236.434911] TEST-70-TPM2.sh[3091]: Only 2 active CPUs detected, PBKDF threads decreased from 4 to 2. 7442s [ 236.436563] TEST-70-TPM2.sh[3091]: Not enough physical memory detected, PBKDF max memory decreased from 1048576kB to 811942kB. 7442s [ 236.437917] TEST-70-TPM2.sh[3091]: PBKDF argon2id, time_ms 2000 (iterations 0), max_memory_kb 811942, parallel_threads 2. 7442s [ 236.439323] TEST-70-TPM2.sh[3091]: Requesting JSON for token 0. 7442s [ 236.440583] TEST-70-TPM2.sh[3091]: Requesting JSON for token 1. 7442s [ 236.441924] TEST-70-TPM2.sh[3091]: Requesting JSON for token 2. 7442s [ 236.442966] TEST-70-TPM2.sh[3091]: Requesting JSON for token 3. 7442s [ 236.443958] TEST-70-TPM2.sh[3091]: Requesting JSON for token 4. 7442s [ 236.445335] TEST-70-TPM2.sh[3091]: Requesting JSON for token 5. 7442s [ 236.445921] TEST-70-TPM2.sh[3091]: Requesting JSON for token 6. 7442s [ 236.447412] TEST-70-TPM2.sh[3091]: Requesting JSON for token 7. 7442s [ 236.448427] TEST-70-TPM2.sh[3091]: Requesting JSON for token 8. 7442s [ 236.449485] TEST-70-TPM2.sh[3091]: Requesting JSON for token 9. 7442s [ 236.450502] TEST-70-TPM2.sh[3091]: Requesting JSON for token 10. 7442s [ 236.451633] TEST-70-TPM2.sh[3091]: Requesting JSON for token 11. 7442s [ 236.453223] TEST-70-TPM2.sh[3091]: Requesting JSON for token 12. 7442s [ 236.454526] TEST-70-TPM2.sh[3091]: Requesting JSON for token 13. 7442s [ 236.455444] TEST-70-TPM2.sh[3091]: Requesting JSON for token 14. 7442s [ 236.456438] TEST-70-TPM2.sh[3091]: Requesting JSON for token 15. 7442s [ 236.457437] TEST-70-TPM2.sh[3091]: Requesting JSON for token 16. 7442s [ 236.458504] TEST-70-TPM2.sh[3091]: Requesting JSON for token 17. 7442s [ 236.459642] TEST-70-TPM2.sh[3091]: Requesting JSON for token 18. 7442s [ 236.460546] TEST-70-TPM2.sh[3091]: Requesting JSON for token 19. 7442s [ 236.461466] TEST-70-TPM2.sh[3091]: Requesting JSON for token 20. 7442s [ 236.462516] TEST-70-TPM2.sh[3091]: Requesting JSON for token 21. 7442s [ 236.463752] TEST-70-TPM2.sh[3091]: Requesting JSON for token 22. 7442s [ 236.464732] TEST-70-TPM2.sh[3091]: Requesting JSON for token 23. 7442s [ 236.466455] TEST-70-TPM2.sh[3091]: Requesting JSON for token 24. 7442s [ 236.467640] TEST-70-TPM2.sh[3091]: Requesting JSON for token 25. 7442s [ 236.469323] TEST-70-TPM2.sh[3091]: Requesting JSON for token 26. 7442s [ 236.469989] TEST-70-TPM2.sh[3091]: Requesting JSON for token 27. 7442s [ 236.471414] TEST-70-TPM2.sh[3091]: Requesting JSON for token 28. 7442s [ 236.472632] TEST-70-TPM2.sh[3091]: Requesting JSON for token 29. 7442s [ 236.473908] TEST-70-TPM2.sh[3091]: Requesting JSON for token 30. 7442s [ 236.474713] TEST-70-TPM2.sh[3091]: Requesting JSON for token 31. 7442s [ 236.476471] TEST-70-TPM2.sh[3091]: Requesting JSON for token 0. 7442s [ 236.477487] TEST-70-TPM2.sh[3091]: Requesting JSON for token 1. 7442s [ 236.478479] TEST-70-TPM2.sh[3091]: Automatically discovered security TPM2 token unlocks volume. 7442s [ 236.479641] TEST-70-TPM2.sh[3091]: Loaded shared library 'libtss2-esys.so.0' via dlopen(). 7442s [ 236.480650] TEST-70-TPM2.sh[3091]: Loaded shared library 'libtss2-rc.so.0' via dlopen(). 7442s [ 236.481623] TEST-70-TPM2.sh[3091]: Loaded shared library 'libtss2-mu.so.0' via dlopen(). 7442s [ 236.482602] TEST-70-TPM2.sh[3091]: Using TPM2 TCTI driver 'device' with device '/dev/tpmrm0'. 7442s [ 236.484505] TEST-70-TPM2.sh[3091]: Loaded 'libtss2-tcti-device.so.0' via dlopen() 7442s [ 236.485620] TEST-70-TPM2.sh[3091]: Loaded TCTI module 'tcti-device' (TCTI module for communication with Linux kernel interface.) [Version 2] 7442s [ 236.486637] TEST-70-TPM2.sh[3091]: TPM successfully started up. 7442s [ 236.488398] TEST-70-TPM2.sh[3091]: Getting TPM2 capability 0x0000 property 0x0001 count 127. 7442s [ 236.489474] TEST-70-TPM2.sh[3091]: Getting TPM2 capability 0x0002 property 0x011f count 256. 7442s [ 236.511245] TEST-70-TPM2.sh[3091]: Getting TPM2 capability 0x0008 property 0x0000 count 508. 7442s [ 236.522277] TEST-70-TPM2.sh[3091]: Getting TPM2 capability 0x0005 property 0x0000 count 1. 7442s [ 236.549564] TEST-70-TPM2.sh[3091]: Loading HMAC key into TPM for shard 0. 7442s [ 236.551210] TEST-70-TPM2.sh[3091]: Loading object into TPM. 7442s [ 236.696908] TEST-70-TPM2.sh[3091]: Starting HMAC encryption session. 7442s [ 236.973416] TEST-70-TPM2.sh[3091]: Starting policy session. 7442s [ 237.353942] TEST-70-TPM2.sh[3091]: Building sealing policy. 7442s [ 237.355459] TEST-70-TPM2.sh[3091]: Reading PCR selection: [sha256(11)] 7442s [ 237.967530] TEST-70-TPM2.sh[3091]: Read PCR selection: [sha256(11)] 7442s [ 237.978253] TEST-70-TPM2.sh[3091]: PCR value: 11:sha256=c0673eb45a2f35de887fb1317b25a86f0c223eef05a57ffe841d7d414cb0828f 7442s [ 237.979944] TEST-70-TPM2.sh[3091]: Adding PCR signature policy. 7442s [ 237.981754] TEST-70-TPM2.sh[3091]: Loading external key into TPM. 7442s [ 238.476222] TEST-70-TPM2.sh[3091]: Object name: 000b30a141f9101be509a9d37acac0c141ee93f5fd9e9cd7a6499a4fe3859fc521fc 7442s [ 238.477623] TEST-70-TPM2.sh[3091]: Submitting PCR hash policy. 7442s [ 239.117590] TEST-70-TPM2.sh[3091]: Acquiring policy digest. 7442s [ 239.783456] TEST-70-TPM2.sh[3091]: Session policy digest: 3a7e59b9238692252a87275f7ad93c81f149d3ee93a94e60ec2bbbd0bb4457d3 7442s [ 241.209411] TEST-70-TPM2.sh[3091]: Acquiring policy digest. 7442s Finishing after writing 52687 entries 7442s qemu-system-x86_64: terminating on signal 15 from pid 30266 (/usr/bin/python3) 7442s qemu-system-x86_64: tpm-emulator: Could not cleanly shutdown the TPM: Invalid argument 7442s No coredumps found. 7442s Test failed, relevant logs can be viewed with: 7442s 7442s journalctl --file /var/tmp/integration-tests.A6hYkKxhS2/btrfs/build/test/journal/TEST-70-TPM2-1.journal --no-hostname -o short-monotonic -u TEST-70-TPM2.service -p info 7442s ―――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――― 7442s 8705s integration-tests - systemd:TEST-86-MULTI-PROFILE-UKI time out (After 1800 seconds) 8705s 98/98 integration-tests - systemd:TEST-86-MULTI-PROFILE-UKI TIMEOUT 1800.44s (exit status 254 or 0xfe) 8705s >>> UBSAN_OPTIONS=halt_on_error=1:abort_on_error=1:print_summary=1:print_stacktrace=1 ASAN_OPTIONS=halt_on_error=1:abort_on_error=1:print_summary=1 MSAN_OPTIONS=halt_on_error=1:abort_on_error=1:print_summary=1:print_stacktrace=1 MALLOC_PERTURB_=8 MESON_TEST_ITERATION=1 /tmp/autopkgtest.NgkF01/build.nGk/src/test/integration-tests/integration-test-wrapper.py --meson-source-dir /tmp/autopkgtest.NgkF01/build.nGk/src --meson-build-dir /var/tmp/integration-tests.A6hYkKxhS2/btrfs/build --name TEST-86-MULTI-PROFILE-UKI --storage persistent --firmware auto --exit-code 123 --coredump-exclude-regex '' --sanitizer-exclude-regex '' --unit TEST-86-MULTI-PROFILE-UKI.service --vm --tpm --mkosi /usr/bin/mkosi -- 8705s ――――――――――――――――――――――――――――――――――――― ✀ ――――――――――――――――――――――――――――――――――――― 8705s qemu-system-x86_64: warning: host doesn't support requested feature: CPUID[eax=80000001h].ECX.cmp-legacy [bit 1] 8705s qemu-system-x86_64: warning: host doesn't support requested feature: CPUID[eax=80000001h].ECX.cmp-legacy [bit 1] 8705s qemu-system-x86_64: terminating on signal 15 from pid 30266 (/usr/bin/python3) 8705s Finishing after writing 0 entries 8705s qemu-system-x86_64: tpm-emulator: Could not cleanly shutdown the TPM: Invalid argument 8705s No coredumps found. 8705s Test failed, relevant logs can be viewed with: 8705s 8705s journalctl --file /var/tmp/integration-tests.A6hYkKxhS2/btrfs/build/test/journal/TEST-86-MULTI-PROFILE-UKI-1.journal --no-hostname -o short-monotonic -u TEST-86-MULTI-PROFILE-UKI.service -p info 8705s 8705s ―――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――― 8705s 8706s 8706s Summary of Failures: 8706s 8706s 97/98 integration-tests - systemd:TEST-70-TPM2 TIMEOUT 1800.31s (exit status 254 or 0xfe) 8706s 98/98 integration-tests - systemd:TEST-86-MULTI-PROFILE-UKI TIMEOUT 1800.44s (exit status 254 or 0xfe) 8706s Upstream bugs have been opened: https://github.com/systemd/systemd/issues/42606 https://github.com/systemd/systemd/issues/42604 [Test Plan] For resolute, we will skip these tests until we can find a fix upstream. Right now the baseline for systemd autopkgtests is failing on resolute, so while it is not ideal to skip tests, skipping them will allow us to reset the baseline to prevent further regressions. [Where problems could occur] This is limited to tests so has no exposure to users. But, if something external to systemd changes that allows the tests to run normally, skipping becomes unnecessary or could mask other issues. Hence, this will be monitored, and when we have a reliable fix for stonking, we can re-visit for resolute. ** Affects: systemd (Ubuntu) Importance: Medium Assignee: Nick Rosbrook (enr0n) Status: Confirmed ** Affects: systemd (Ubuntu Resolute) Importance: Undecided Status: New ** Affects: systemd (Ubuntu Stonking) Importance: Medium Assignee: Nick Rosbrook (enr0n) Status: Confirmed ** Also affects: systemd (Ubuntu Resolute) Importance: Undecided Status: New ** Also affects: systemd (Ubuntu Stonking) Importance: Undecided Status: New ** Changed in: systemd (Ubuntu Stonking) Status: New => Confirmed ** Changed in: systemd (Ubuntu Stonking) Assignee: (unassigned) => Nick Rosbrook (enr0n) ** Changed in: systemd (Ubuntu Stonking) Importance: Undecided => Medium -- You received this bug notification because you are a member of Ubuntu Bugs, which is subscribed to Ubuntu. https://bugs.launchpad.net/bugs/2157344 Title: systemd: TEST-70-TPM2 and TEST-86-MULTI-PROFILE-UKI timeout To manage notifications about this bug go to: https://bugs.launchpad.net/ubuntu/+source/systemd/+bug/2157344/+subscriptions -- ubuntu-bugs mailing list [email protected] https://lists.ubuntu.com/mailman/listinfo/ubuntu-bugs
