container-test-run-certificates
checks.aarch64-linux.certificates
· build #748
· raw
1Machine state will be reset. To keep it, pass --keep-machine-state2start all VLans3(finished: start all VLans, in 0.00 seconds)45Test will time out and terminate in 3600.0 seconds6run the VM test script7additionally exposed symbols:8 ca, client, server,9 vlan1,10 start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh11ca: systemd-nspawn running (pid 50)12ca: Waiting for journal at /build/vm-state-ca/var/log/journal...13ca: waiting for unit step-ca.service14nixos-nspawn(ca): TAP vde-tap1 not found; container will be isolated from VDE15nixos-nspawn(ca): A common reason for this is that /dev/net is not available in the Nix sandbox. Try adding /dev/net to extra-sandbox-paths.16Note: in a future version of systemd-nspawn the default set of permitted socket address families will be restricted to AF_INET, AF_INET6 and AF_UNIX. Use --restrict-address-families= to configure the set of permitted socket address families, or set RestrictAddressFamilies= in a .nspawn file.17░ Spawning container ca on /build/vm-state-ca.18ca # No journal files were found.19ca # No journal boot entry found for the specified boot (+0).20ca # [1547471.053229] ca systemd-journald[81]: Journal started21ca # [1547471.053280] ca systemd-journald[81]: Runtime Journal (/run/log/journal/4fbe97242856403eb27822ab7f91190e) is 8M, max 2.5G, 2.4G free.22ca # [1547471.064071] ca systemd[1]: Finished Apply Kernel Variables.23ca # [1547471.077701] ca systemd[1]: Finished Create Static Device Nodes in /dev gracefully.24ca # [1547471.093667] ca systemd[1]: Starting Flush Journal to Persistent Storage...25ca # [1547471.094715] ca systemd[1]: Starting Network Name Resolution...26ca # [1547471.095388] ca systemd[1]: Starting Create Static Device Nodes in /dev...27ca # [1547471.105470] ca systemd-journald[81]: Time spent on flushing to /var/log/journal/4fbe97242856403eb27822ab7f91190e is 1.414ms for 7 entries.28ca # [1547471.105470] ca systemd-journald[81]: System Journal (/var/log/journal/4fbe97242856403eb27822ab7f91190e) is 8M, max 4G, 3.9G free.29ca # [1547471.127778] ca systemd[1]: Finished Flush Journal to Persistent Storage.30ca # [1547471.128245] ca systemd[1]: Finished Create Static Device Nodes in /dev.31ca # [1547471.129335] ca systemd[1]: Reached target Preparation for Local File Systems.32ca # [1547471.129456] ca systemd[1]: Reached target Local File Systems.33ca # [1547471.130322] ca systemd[1]: Listening on Boot Loader Control Service Socket.34ca # [1547471.130426] ca systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container35ca # [1547471.131340] ca systemd[1]: Starting Save Transient machine-id to Disk...36ca # [1547471.132074] ca systemd[1]: Starting Create System Files and Directories...37ca # [1547471.132104] ca systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys38ca # [1547471.150582] ca systemd-tmpfiles[142]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted39ca # [1547471.150781] ca systemd-tmpfiles[142]: fchmod() of /var/log/journal failed: Operation not permitted40ca # [1547471.150917] ca systemd-tmpfiles[142]: fchmod() of /var/log/journal/4fbe97242856403eb27822ab7f91190e failed: Operation not permitted41ca # [1547471.151117] ca systemd-tmpfiles[142]: fchmod() of /run/log/journal failed: Operation not permitted42ca # [1547471.152776] ca systemd[1]: Finished Create System Files and Directories.43ca # [1547471.153990] ca systemd[1]: Starting Rebuild Journal Catalog...44ca # [1547471.154806] ca systemd[1]: Starting Record System Boot/Shutdown in UTMP...45ca # [1547471.168678] ca systemd[1]: Finished Save Transient machine-id to Disk.46ca # [1547471.171686] ca systemd[1]: Finished Record System Boot/Shutdown in UTMP.47ca # [1547471.173795] ca systemd[1]: Finished Rebuild Journal Catalog.48ca # [1547471.174859] ca systemd[1]: Starting Update is Completed...49ca # [1547471.186038] ca systemd[1]: Finished Update is Completed.50ca # [1547471.231307] ca systemd[1]: Finished Firewall.51ca # [1547471.231414] ca systemd[1]: Reached target Preparation for Network.52ca # [1547471.231646] ca systemd[1]: Listening on Network Management Resolve Hook Socket.53ca # [1547471.232733] ca systemd[1]: Starting Network Management...54ca # [1547472.059587] ca systemd[1]: etc-machine\x2did.mount: Deactivated successfully.55ca # [1547472.089723] ca systemd-networkd[199]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted56ca # [1547472.089815] ca systemd-networkd[199]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted57ca # [1547472.099716] ca systemd-networkd[199]: /etc/systemd/network/99-ethernet-default-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.58ca # [1547472.099883] ca systemd-networkd[199]: /etc/systemd/network/99-wireless-client-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.59ca # [1547472.100085] ca systemd-networkd[199]: lo: Link UP60ca # [1547472.100091] ca systemd-networkd[199]: lo: Gained carrier61ca # [1547472.100288] ca systemd-networkd[199]: eth1: Configuring with /etc/systemd/network/40-eth1.network.62ca # [1547472.100679] ca systemd[1]: Started Network Management.63ca # [1547472.100749] ca systemd-networkd[199]: eth1: Link UP64ca # [1547472.101059] ca systemd-networkd[199]: eth1: Gained carrier65ca # [1547472.102215] ca systemd[1]: Starting Enable Persistent Storage in systemd-networkd...66ca # [1547472.134141] ca systemd[1]: Finished Enable Persistent Storage in systemd-networkd.67ca # [1547472.550100] ca systemd-resolved[116]: Positive Trust Anchors:68ca # [1547472.550112] ca systemd-resolved[116]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d69ca # [1547472.550115] ca systemd-resolved[116]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b1670ca # [1547472.550165] ca systemd-resolved[116]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test71ca # [1547472.573510] ca systemd-resolved[116]: Using system hostname 'ca'.72ca # [1547472.575000] ca systemd[1]: Started Network Name Resolution.73ca # [1547472.575159] ca systemd[1]: Reached target Network.74ca # [1547472.575271] ca systemd[1]: Reached target Network is Online.75ca # [1547472.575364] ca systemd[1]: Reached target System Initialization.76ca # [1547472.575761] ca systemd[1]: Started Renew ACME Certificate for ca.foo.77ca # [1547472.575824] ca systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container78ca # [1547472.575877] ca systemd[1]: Started Daily Cleanup of Temporary Directories.79ca # [1547472.575918] ca systemd[1]: Reached target Timer Units.80ca # [1547472.576183] ca systemd[1]: Listening on D-Bus System Message Bus Socket.81ca # [1547472.576393] ca systemd[1]: Listening on Nix Daemon Socket.82ca # [1547472.576617] ca systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.83ca # [1547472.576664] ca systemd[1]: Reached target Socket Units.84ca # [1547472.576747] ca systemd[1]: Reached target Basic System.85ca # [1547472.579120] ca systemd[1]: Starting Set up the ACME certificate renewal infrastructure...86ca # [1547472.580713] ca systemd[1]: Starting Import lastlog data into lastlog2 database...87ca # [1547472.580827] ca systemd[1]: nginx-config-reload.service skipped, unmet condition check ConditionPathExists=/var/lib/acme/ca.foo/fullchain.pem88ca # [1547472.582362] ca systemd[1]: Starting Name Service Cache Daemon (nsncd)...89ca # [1547472.584085] ca systemd[1]: Starting step-ca service...90ca # [1547472.621478] ca systemd[1]: Starting D-Bus System Message Bus...91ca # [1547472.699347] ca systemd[1]: Finished Import lastlog data into lastlog2 database.92ca # [1547472.818851] ca acme-setup-privileged[204]: + set -euo pipefail93ca # [1547472.818851] ca acme-setup-privileged[204]: + cd /var/lib/acme94ca # [1547472.818851] ca acme-setup-privileged[204]: + chmod -R u=rwX,g=,o= .lego/accounts95ca # [1547472.820696] ca acme-setup-privileged[204]: + chown -R acme .lego/accounts96ca # [1547472.822208] ca acme-setup-privileged[204]: + for fixpath in ca.foo .lego/ca.foo97ca # [1547472.822208] ca acme-setup-privileged[204]: + '[' -d ca.foo ']'98ca # [1547472.822321] ca acme-setup-privileged[204]: + for fixpath in ca.foo .lego/ca.foo99ca # [1547472.822321] ca acme-setup-privileged[204]: + '[' -d .lego/ca.foo ']'100ca # [1547472.871170] ca nsncd[206]: Sep 22 05:18:12.856 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"101ca # [1547472.871258] ca systemd[1]: Started Name Service Cache Daemon (nsncd).102ca # [1547472.871369] ca systemd[1]: Reached target Host and Network Name Lookups.103ca # [1547472.871444] ca systemd[1]: Reached target User and Group Name Lookups.104ca # [1547472.872811] ca systemd[1]: Starting User Login Management...105ca # [1547472.873939] ca systemd[1]: Starting Permit User Sessions...106ca # [1547472.885871] ca systemd[1]: Finished Permit User Sessions.107ca # [1547472.886992] ca systemd[1]: Started Console Getty.108ca # [1547472.887039] ca systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0109ca # [1547472.887061] ca systemd[1]: Reached target Login Prompts.110ca # [1547473.060193] ca dbus-broker-launch[211]: Looking up NSS user entry for 'systemd-timesync'...111ca # [1547473.061827] ca dbus-broker-launch[211]: NSS returned no entry for 'systemd-timesync'112ca # [1547473.061827] ca dbus-broker-launch[211]: Invalid user-name in /nix/store/v6jly29gdhx37hki3h1m0qb297q1vkvf-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"113ca # [1547473.062196] ca systemd[1]: Started D-Bus System Message Bus.114ca # [1547473.071500] ca dbus-broker-launch[211]: Ready115ca # [1547473.767580] ca systemd-logind[238]: New seat seat0.116ca # [1547473.767740] ca systemd[1]: Started User Login Management.117ca # [1547473.768964] ca systemd[1]: Starting linger-users.service...118ca # [1547473.818196] ca systemd[1]: linger-users.service: Deactivated successfully.119ca # [1547473.818403] ca systemd[1]: Finished linger-users.service.120ca # [1547473.861124] ca acme-setup-start[222]: + set -euo pipefail121ca # [1547473.861124] ca acme-setup-start[222]: + test -e ca/key.pem122ca # [1547473.861739] ca acme-setup-start[222]: + minica --ca-key ca/key.pem --ca-cert ca/cert.pem --domains selfsigned.local123ca # [1547473.886339] ca systemd[1]: Finished Set up the ACME certificate renewal infrastructure.124ca # [1547473.888312] ca systemd[1]: Starting Ensure certificate for ca.foo...125ca # [1547474.068921] ca step-ca[207]: badger 2026/09/22 05:18:14 INFO: All 0 tables opened in 0s126ca # [1547474.071932] ca step-ca[207]: 2026/09/22 05:18:14 Building new tls configuration using step-ca x509 Signer Interface127ca # [1547474.076352] ca step-ca[207]: 2026/09/22 05:18:14 Starting Smallstep CA/0.30.2 (linux/arm64)128ca # [1547474.076352] ca step-ca[207]: 2026/09/22 05:18:14 Documentation: https://u.step.sm/docs/ca129ca # [1547474.076352] ca step-ca[207]: 2026/09/22 05:18:14 Community Discord: https://u.step.sm/discord130ca # [1547474.076352] ca step-ca[207]: 2026/09/22 05:18:14 Config file: /etc/smallstep/ca.json131ca # [1547474.076352] ca step-ca[207]: 2026/09/22 05:18:14 The primary server URL is https://ca.foo:1443132ca # [1547474.076352] ca step-ca[207]: 2026/09/22 05:18:14 Root certificates are available at https://ca.foo:1443/roots.pem133ca # [1547474.076352] ca step-ca[207]: 2026/09/22 05:18:14 X.509 Root Fingerprint: c582c8c53bc53d1ec96f07cacb825a2684d6f20d93b9d08434f91323d8b6de70134ca # [1547474.076973] ca systemd[1]: Started step-ca service.135ca # [1547474.077387] ca step-ca[207]: 2026/09/22 05:18:14 Serving HTTPS on 0.0.0.0:1443 ...136ca # [1547474.080184] ca systemd-networkd[199]: eth1: Gained IPv6LL137ca # [1547474.933657] ca acme-ca.foo-start[259]: Waiting to acquire lock in /run/acme/138ca # [1547474.936654] ca acme-ca.foo-start[259]: + '[' -e out/acme-success ']'139ca # [1547474.936754] ca acme-ca.foo-start[259]: + minica --ca-cert=ca/cert.pem --ca-key=ca/key.pem --domains=ca.foo --ip-addresses=140ca # [1547474.957125] ca acme-ca.foo-start[297]: + cd ca.foo141ca # [1547474.957697] ca acme-ca.foo-start[297]: + cp -vp cert.pem ../out/cert.pem142ca # [1547474.959047] ca acme-ca.foo-start[298]: 'cert.pem' -> '../out/cert.pem'143ca # [1547474.959378] ca acme-ca.foo-start[297]: + cp -vp key.pem ../out/key.pem144ca # [1547474.960649] ca acme-ca.foo-start[297]: 'key.pem' -> '../out/key.pem'145ca # [1547474.960874] ca acme-ca.foo-start[259]: + cat out/cert.pem ca/cert.pem146ca # [1547474.962788] ca acme-ca.foo-start[259]: + cp ca/cert.pem out/chain.pem147ca # [1547474.964821] ca acme-ca.foo-start[259]: + cat out/key.pem out/fullchain.pem148ca # [1547474.966496] ca acme-ca.foo-start[259]: + for fixpath in out certificates149ca # [1547474.966496] ca acme-ca.foo-start[259]: + '[' -d out ']'150ca # [1547474.966604] ca acme-ca.foo-start[259]: + chmod -R u=rwX,g=rX,o= out151ca # [1547474.968611] ca acme-ca.foo-start[259]: + chown -R acme:nginx out152ca # [1547474.971981] ca acme-ca.foo-start[259]: + for fixpath in out certificates153ca # [1547474.971981] ca acme-ca.foo-start[259]: + '[' -d certificates ']'154ca # [1547474.977433] ca systemd[1]: Finished Ensure certificate for ca.foo.155ca # [1547474.979818] ca systemd[1]: Starting Nginx Web Server...156ca # [1547476.019350] ca nginx-pre-start[309]: nginx: the configuration file /nix/store/a4vwbl3sw1llln3lazibgrd2yckzyqz6-nginx.conf syntax is ok157ca # [1547476.020042] ca nginx-pre-start[309]: nginx: configuration file /nix/store/a4vwbl3sw1llln3lazibgrd2yckzyqz6-nginx.conf test is successful158ca # [1547476.026623] ca systemd[1]: Started Nginx Web Server.159ca # [1547476.027199] ca systemd[1]: Reached target Multi-User System.160ca # [1547476.028631] ca systemd[1]: Starting Order (and renew) ACME certificate for ca.foo...161ca # [1547477.128935] ca acme-order-renew-ca.foo-start[312]: Waiting to acquire lock in /run/acme/162ca # [1547477.132305] ca acme-order-renew-ca.foo-start[312]: + set -euo pipefail163ca # [1547477.132387] ca acme-order-renew-ca.foo-start[312]: + exec164ca # [1547477.132437] ca acme-order-renew-ca.foo-start[312]: + /nix/store/fxkh6pl0y4nfbhdqdnhfkvsq7pbxq655-flock-0.4.0/bin/flock 11165ca # [1547477.133758] ca acme-order-renew-ca.foo-start[323]: ++ find accounts -path '*/keys/*.key' -print -quit166ca # [1547477.137784] ca acme-order-renew-ca.foo-start[312]: + '[' -n '' ']'167ca # [1547477.137817] ca acme-order-renew-ca.foo-start[312]: + exec168ca # [1547477.137906] ca acme-order-renew-ca.foo-start[312]: + '[' -e certificates/ca.foo.key ']'169ca # [1547477.137949] ca acme-order-renew-ca.foo-start[312]: + lego run --accept-tos --path . --email none@none.tld --http --http.webroot /var/lib/acme/acme-challenge --server https://ca.foo:1443/acme/acme/directory --key-type ec256 -d ca.foo --no-random-sleep --renew-force --ari-disable --force-cert-domains170ca # [1547477.158166] ca acme-order-renew-ca.foo-start[324]: 2026-09-22T05:18:17.143953021Z INFO Private key saved. filepath=accounts/ca.foo_1443/none@none.tld/none@none.tld.key171ca # [1547477.190492] ca step-ca[207]: time="2026-09-22T05:18:17Z" level=info duration="114.602µs" duration-ns=114602 fields.time="2026-09-22T05:18:17Z" method=GET name=ca path=/acme/acme/directory protocol=HTTP/1.1 referer= remote-address="2001:db8:1::1" request-id=c95fa806-d171-4950-b13a-26c3439cec99 response="{\"newNonce\":\"https://ca.foo:1443/acme/acme/new-nonce\",\"newAccount\":\"https://ca.foo:1443/acme/acme/new-account\",\"newOrder\":\"https://ca.foo:1443/acme/acme/new-order\",\"revokeCert\":\"https://ca.foo:1443/acme/acme/revoke-cert\",\"keyChange\":\"https://ca.foo:1443/acme/acme/key-change\"}" size=277 status=200 user-agent="lego-cli/ xenolf-acme/5.4.1 (release; linux; arm64)" user-id=172ca # [1547477.190846] ca acme-order-renew-ca.foo-start[324]: 2026-09-22T05:18:17.176649346Z INFO Registering the account. email=none@none.tld173ca # [1547477.197976] ca step-ca[207]: time="2026-09-22T05:18:17Z" level=info duration=6.820853ms duration-ns=6820853 fields.time="2026-09-22T05:18:17Z" method=HEAD name=ca nonce=RVgwQ2NnZ2MxSkNzNlJJNU93T0k4YmFJUEZrYlRqWWc path=/acme/acme/new-nonce protocol=HTTP/1.1 referer= remote-address="2001:db8:1::1" request-id=71dcaa2b-6680-44f7-8f09-fd4694dbee2d size=0 status=200 user-agent="lego-cli/ xenolf-acme/5.4.1 (release; linux; arm64)" user-id=174ca # [1547477.200613] ca step-ca[207]: time="2026-09-22T05:18:17Z" level=info duration=1.932906ms duration-ns=1932906 fields.time="2026-09-22T05:18:17Z" method=POST name=ca nonce=NUpXT2xQblVVVG4xVGNvbkJ5M2tZVkI0eGdYbnViRms path=/acme/acme/new-account protocol=HTTP/1.1 referer= remote-address="2001:db8:1::1" request-id=416878aa-bd29-44be-9b03-893ec05ec8ef response="{\"contact\":[\"mailto:none@none.tld\"],\"status\":\"valid\",\"orders\":\"https://ca.foo:1443/acme/acme/account/ZvDMZr7tXwg5cVWzdtkkDOWRUyIlurBK/orders\"}" size=143 status=201 user-agent="lego-cli/ xenolf-acme/5.4.1 (release; linux; arm64)" user-id=175ca # [1547477.201041] ca acme-order-renew-ca.foo-start[324]: 2026-09-22T05:18:17.186856804Z WARN !!!! HEADS UP !!!!176ca # [1547477.201041] ca acme-order-renew-ca.foo-start[324]: Your account credentials have been saved in your177ca # [1547477.201041] ca acme-order-renew-ca.foo-start[324]: configuration directory at "accounts".178ca # [1547477.201041] ca acme-order-renew-ca.foo-start[324]: You should make a secure backup of this folder now. This179ca # [1547477.201041] ca acme-order-renew-ca.foo-start[324]: configuration directory will also contain private keys180ca # [1547477.201041] ca acme-order-renew-ca.foo-start[324]: generated by lego and certificates obtained from the ACME181ca # [1547477.201041] ca acme-order-renew-ca.foo-start[324]: server. Making regular backups of this folder is ideal.182ca # [1547477.201196] ca acme-order-renew-ca.foo-start[324]: 2026-09-22T05:18:17.186985406Z INFO Obtaining bundled SAN certificate. domains=ca.foo183ca # [1547477.204391] ca step-ca[207]: time="2026-09-22T05:18:17Z" level=info duration=2.742158ms duration-ns=2742158 fields.time="2026-09-22T05:18:17Z" method=POST name=ca nonce=bjhrQjhVVlFwYU5lbjM4ZEVRYk5IVVF6emxmSDlXVUo path=/acme/acme/new-order protocol=HTTP/1.1 referer= remote-address="2001:db8:1::1" request-id=2bacedef-328e-4e2e-93f0-df9938fff666 response="{\"id\":\"T0HujlRSTUVCMZKDjnVDztmBNSGp2oH3\",\"status\":\"pending\",\"expires\":\"2026-09-23T05:18:17Z\",\"identifiers\":[{\"type\":\"dns\",\"value\":\"ca.foo\"}],\"notBefore\":\"2026-09-22T05:17:17Z\",\"notAfter\":\"2026-12-21T05:18:17Z\",\"authorizations\":[\"https://ca.foo:1443/acme/acme/authz/9RFcbWtJKIeQqtO0ZPoFdf9jQMM3PGU4\"],\"finalize\":\"https://ca.foo:1443/acme/acme/order/T0HujlRSTUVCMZKDjnVDztmBNSGp2oH3/finalize\"}" size=392 status=201 user-agent="lego-cli/ xenolf-acme/5.4.1 (release; linux; arm64)" user-id=184ca # [1547477.264031] ca step-ca[207]: time="2026-09-22T05:18:17Z" level=info duration=2.296431ms duration-ns=2296431 fields.time="2026-09-22T05:18:17Z" method=POST name=ca nonce=UHpVMXlZd2pqNDBWaTVCWFZWMFF6d2dxS2FrMUlGcnQ path=/acme/acme/authz/9RFcbWtJKIeQqtO0ZPoFdf9jQMM3PGU4 protocol=HTTP/1.1 referer= remote-address="2001:db8:1::1" request-id=9d3fb20a-2088-47b2-9f58-6c37e6e5999b response="{\"identifier\":{\"type\":\"dns\",\"value\":\"ca.foo\"},\"status\":\"pending\",\"challenges\":[{\"type\":\"dns-01\",\"status\":\"pending\",\"token\":\"06lqORDYQc4cq9yJAwzhD0CFKchw2TMC\",\"url\":\"https://ca.foo:1443/acme/acme/challenge/9RFcbWtJKIeQqtO0ZPoFdf9jQMM3PGU4/oYXmMGxGguMWcyS8Uc24feLzXqJLtgJf\"},{\"type\":\"http-01\",\"status\":\"pending\",\"token\":\"06lqORDYQc4cq9yJAwzhD0CFKchw2TMC\",\"url\":\"https://ca.foo:1443/acme/acme/challenge/9RFcbWtJKIeQqtO0ZPoFdf9jQMM3PGU4/yZLqDsln6LCRU3yeolO7zhN3SrK1rTKi\"},{\"type\":\"tls-alpn-01\",\"status\":\"pending\",\"token\":\"06lqORDYQc4cq9yJAwzhD0CFKchw2TMC\",\"url\":\"https://ca.foo:1443/acme/acme/challenge/9RFcbWtJKIeQqtO0ZPoFdf9jQMM3PGU4/y25HdGxwG6sSyPPc9wohdtLK69Ots3nW\"}],\"wildcard\":false,\"expires\":\"2026-09-23T05:18:17Z\"}" size=719 status=200 user-agent="lego-cli/ xenolf-acme/5.4.1 (release; linux; arm64)" user-id=185ca # [1547477.264897] ca acme-order-renew-ca.foo-start[324]: 2026-09-22T05:18:17.250679831Z INFO Use solver. domain=ca.foo type=http-01186ca # [1547477.264897] ca acme-order-renew-ca.foo-start[324]: 2026-09-22T05:18:17.250736712Z INFO http01: Trying to solve HTTP-01. domain=ca.foo187ca # [1547477.269736] ca step-ca[207]: time="2026-09-22T05:18:17Z" level=info duration=3.882653ms duration-ns=3882653 fields.time="2026-09-22T05:18:17Z" method=POST name=ca nonce=STZwZFh1T1FEMFQ4aVQ4UzQzc1pndEFkakdNZWFzOWk path=/acme/acme/challenge/9RFcbWtJKIeQqtO0ZPoFdf9jQMM3PGU4/yZLqDsln6LCRU3yeolO7zhN3SrK1rTKi protocol=HTTP/1.1 referer= remote-address="2001:db8:1::1" request-id=9fc35974-235a-490a-b104-eb32515a999e response="{\"type\":\"http-01\",\"status\":\"valid\",\"token\":\"06lqORDYQc4cq9yJAwzhD0CFKchw2TMC\",\"validated\":\"2026-09-22T05:18:17Z\",\"url\":\"https://ca.foo:1443/acme/acme/challenge/9RFcbWtJKIeQqtO0ZPoFdf9jQMM3PGU4/yZLqDsln6LCRU3yeolO7zhN3SrK1rTKi\"}" size=228 status=200 user-agent="lego-cli/ xenolf-acme/5.4.1 (release; linux; arm64)" user-id=188ca # [1547477.270027] ca acme-order-renew-ca.foo-start[324]: 2026-09-22T05:18:17.255831461Z INFO The server validated our request. domain=ca.foo189ca # [1547477.270106] ca acme-order-renew-ca.foo-start[324]: 2026-09-22T05:18:17.255927343Z INFO Validations succeeded; requesting certificates. domains=ca.foo190ca # [1547477.276256] ca step-ca[207]: time="2026-09-22T05:18:17Z" level=info duration=5.225111ms duration-ns=5225111 fields.time="2026-09-22T05:18:17Z" method=POST name=ca nonce=aXJYc3piS2VGNWlQdDBmSnpyS0FueEFyQ2hHR3FraUw path=/acme/acme/order/T0HujlRSTUVCMZKDjnVDztmBNSGp2oH3/finalize protocol=HTTP/1.1 referer= remote-address="2001:db8:1::1" request-id=6349fb02-bc86-4a55-b39a-30663b268d22 response="{\"id\":\"T0HujlRSTUVCMZKDjnVDztmBNSGp2oH3\",\"status\":\"valid\",\"expires\":\"2026-09-23T05:18:17Z\",\"identifiers\":[{\"type\":\"dns\",\"value\":\"ca.foo\"}],\"notBefore\":\"2026-09-22T05:17:17Z\",\"notAfter\":\"2026-12-21T05:18:17Z\",\"authorizations\":[\"https://ca.foo:1443/acme/acme/authz/9RFcbWtJKIeQqtO0ZPoFdf9jQMM3PGU4\"],\"finalize\":\"https://ca.foo:1443/acme/acme/order/T0HujlRSTUVCMZKDjnVDztmBNSGp2oH3/finalize\",\"certificate\":\"https://ca.foo:1443/acme/acme/certificate/p5UU6PcTCRYofSzR5kYatF8gLIQc0jyz\"}" size=481 status=200 user-agent="lego-cli/ xenolf-acme/5.4.1 (release; linux; arm64)" user-id=191ca # [1547477.278433] ca step-ca[207]: time="2026-09-22T05:18:17Z" level=info certificate="MIIB1DCCAXqgAwIBAgIRALwgUa19GV8R+ayj4UvyYIMwCgYIKoZIzj0EAwIwHzEdMBsGA1UEAxMUQ2xhbiBJbnRlcm1lZGlhdGUgQ0EwHhcNMjYwOTIyMDUxNzE3WhcNMjYxMjIxMDUxODE3WjARMQ8wDQYDVQQDEwZjYS5mb28wWTATBgcqhkjOPQIBBggqhkjOPQMBBwNCAASqoIx131JehIY+s3KemdjfRF4camJYNymX5ubH95H2lEiKOzov7dxLdauIgjZbmUGKC4blxb4GQ5KRLUH3WE+ko4GkMIGhMA4GA1UdDwEB/wQEAwIHgDAdBgNVHSUEFjAUBggrBgEFBQcDAQYIKwYBBQUHAwIwHQYDVR0OBBYEFDYl4+3ELeC61CRY4hoJ4kK2fBHnMB8GA1UdIwQYMBaAFEYdTKXt/8tFSpcgoAc1Mhb1223AMBEGA1UdEQQKMAiCBmNhLmZvbzAdBgwrBgEEAYKkZMYoQAEEDTALAgEGBARhY21lBAAwCgYIKoZIzj0EAwIDSAAwRQIhAMikUabtyR5q0qqnSFF38xzFEh0zUh2i23uslB1HZAbWAiB9naeOPP4WKZ6u2mF0khWRdoNiURAtV7N37vYPnVDK6A==" duration=1.512061ms duration-ns=1512061 fields.time="2026-09-22T05:18:17Z" issuer="Clan Intermediate CA" method=POST name=ca nonce=THhVc3NYd0RBYk1KWEY2dnI3NUR5WkFWUlFjZ3ZnbE8 path=/acme/acme/certificate/p5UU6PcTCRYofSzR5kYatF8gLIQc0jyz protocol=HTTP/1.1 provisioner=acme public-key="ECDSA P-256" referer= remote-address="2001:db8:1::1" request-id=9dad7a3c-0392-4e16-86fd-3210ad8a3f56 sans="map[dns:[ca.foo]]" serial=250062673327403607672623402614495797379 size=1344 status=200 subject=ca.foo user-agent="lego-cli/ xenolf-acme/5.4.1 (release; linux; arm64)" user-id= valid-from="2026-09-22T05:17:17Z" valid-to="2026-12-21T05:18:17Z"192ca # [1547477.278808] ca acme-order-renew-ca.foo-start[324]: 2026-09-22T05:18:17.264607380Z INFO Server responded with a certificate. domains=ca.foo193ca # [1547477.278906] ca acme-order-renew-ca.foo-start[324]: 2026-09-22T05:18:17.264718862Z INFO Writing file. filepath=certificates/ca.foo.crt194ca # [1547477.279065] ca acme-order-renew-ca.foo-start[324]: 2026-09-22T05:18:17.264874224Z INFO Writing file. filepath=certificates/ca.foo.issuer.crt195ca # [1547477.279129] ca acme-order-renew-ca.foo-start[324]: 2026-09-22T05:18:17.264954865Z INFO Writing file. filepath=certificates/ca.foo.key196ca # [1547477.279298] ca acme-order-renew-ca.foo-start[324]: 2026-09-22T05:18:17.265107467Z INFO Writing file. filepath=certificates/ca.foo.json197ca # [1547477.283790] ca acme-order-renew-ca.foo-start[312]: + touch out/acme-success198ca # [1547477.285795] ca acme-order-renew-ca.foo-start[312]: + cmp -s certificates/ca.foo.crt out/fullchain.pem199ca # [1547477.287076] ca acme-order-renew-ca.foo-start[312]: + touch out/renewed200ca # [1547477.288577] ca acme-order-renew-ca.foo-start[312]: + echo Installing new certificate201ca # [1547477.288577] ca acme-order-renew-ca.foo-start[312]: Installing new certificate202ca # [1547477.288577] ca acme-order-renew-ca.foo-start[312]: + cp -vp certificates/ca.foo.crt out/fullchain.pem203ca # [1547477.290370] ca acme-order-renew-ca.foo-start[356]: 'certificates/ca.foo.crt' -> 'out/fullchain.pem'204ca # [1547477.290786] ca acme-order-renew-ca.foo-start[312]: + cp -vp certificates/ca.foo.key out/key.pem205ca # [1547477.292825] ca acme-order-renew-ca.foo-start[357]: 'certificates/ca.foo.key' -> 'out/key.pem'206ca # [1547477.293168] ca acme-order-renew-ca.foo-start[312]: + cp -vp certificates/ca.foo.issuer.crt out/chain.pem207ca # [1547477.295413] ca acme-order-renew-ca.foo-start[358]: 'certificates/ca.foo.issuer.crt' -> 'out/chain.pem'208ca # [1547477.295778] ca acme-order-renew-ca.foo-start[312]: + ln -sf fullchain.pem out/cert.pem209ca # [1547477.297770] ca acme-order-renew-ca.foo-start[312]: + cat out/key.pem out/fullchain.pem210ca # [1547477.299984] ca acme-order-renew-ca.foo-start[312]: + for fixpath in out certificates211ca # [1547477.299984] ca acme-order-renew-ca.foo-start[312]: + '[' -d out ']'212ca # [1547477.300089] ca acme-order-renew-ca.foo-start[312]: + chmod -R u=rwX,g=rX,o= out213ca # [1547477.301850] ca acme-order-renew-ca.foo-start[312]: + chown -R acme:nginx out214ca # [1547477.304963] ca acme-order-renew-ca.foo-start[312]: + for fixpath in out certificates215ca # [1547477.304963] ca acme-order-renew-ca.foo-start[312]: + '[' -d certificates ']'216ca # [1547477.305054] ca acme-order-renew-ca.foo-start[312]: + chmod -R u=rwX,g=rX,o= certificates217ca # [1547477.306423] ca acme-order-renew-ca.foo-start[312]: + chown -R acme:nginx certificates218ca # [1547477.309814] ca acme-order-renew-ca.foo-start[312]: + chmod -R u=rwX,g=,o= accounts/.219ca # [1547477.479670] ca systemd[1]: Reloading Nginx Web Server...220ca # [1547477.485064] ca systemd[1]: acme-order-renew-ca.foo.service: Deactivated successfully.221ca # [1547477.485253] ca systemd[1]: Finished Order (and renew) ACME certificate for ca.foo.222ca # [1547477.485427] ca systemd[1]: acme-order-renew-ca.foo.service: Consumed 1.297s CPU time over 1.456s wall clock time.223ca # [1547478.468471] ca nginx[374]: nginx: the configuration file /nix/store/a4vwbl3sw1llln3lazibgrd2yckzyqz6-nginx.conf syntax is ok224ca # [1547478.469006] ca nginx[374]: nginx: configuration file /nix/store/a4vwbl3sw1llln3lazibgrd2yckzyqz6-nginx.conf test is successful225ca: (finished: waiting for unit step-ca.service, in 9.67 seconds)226ca: waiting for file '/var/lib/acme/ca.foo/fullchain.pem'227ca: (finished: waiting for file '/var/lib/acme/ca.foo/fullchain.pem', in 0.01 seconds)228ca: must succeed: systemctl restart nginx.service229ca # [1547479.477816] ca systemd[1]: Reloaded Nginx Web Server.230ca # [1547479.478286] ca systemd[1]: Startup finished in 8.835s.231ca # [1547479.758296] ca systemd[1]: Stopping Nginx Web Server...232ca # [1547479.848572] ca systemd[1]: nginx.service: Deactivated successfully.233ca # [1547479.848970] ca systemd[1]: Stopped Nginx Web Server.234ca # [1547479.849012] ca systemd[1]: nginx.service: Consumed 3.922s CPU time over 4.868s wall clock time.235ca # [1547479.853645] ca systemd[1]: Starting Nginx Web Server...236ca: (finished: must succeed: systemctl restart nginx.service, in 1.14 seconds)237server: systemd-nspawn running (pid 468)238server: Waiting for journal at /build/vm-state-server/var/log/journal...239client: systemd-nspawn running (pid 471)240client: Waiting for journal at /build/vm-state-client/var/log/journal...241server: waiting for file '/var/lib/acme/test.foo/fullchain.pem'242nixos-nspawn(server): TAP vde-tap1 not found; container will be isolated from VDE243nixos-nspawn(server): A common reason for this is that /dev/net is not available in the Nix sandbox. Try adding /dev/net to extra-sandbox-paths.244nixos-nspawn(client): TAP vde-tap1 not found; container will be isolated from VDE245nixos-nspawn(client): A common reason for this is that /dev/net is not available in the Nix sandbox. Try adding /dev/net to extra-sandbox-paths.246Note: in a future version of systemd-nspawn the default set of permitted socket address families will be restricted to AF_INET, AF_INET6 and AF_UNIX. Use --restrict-address-families= to configure the set of permitted socket address families, or set RestrictAddressFamilies= in a .nspawn file.247Note: in a future version of systemd-nspawn the default set of permitted socket address families will be restricted to AF_INET, AF_INET6 and AF_UNIX. Use --restrict-address-families= to configure the set of permitted socket address families, or set RestrictAddressFamilies= in a .nspawn file.248░ Spawning container server on /build/vm-state-server.249░ Spawning container client on /build/vm-state-client.250ca # [1547480.881570] ca nginx-pre-start[398]: nginx: the configuration file /nix/store/a4vwbl3sw1llln3lazibgrd2yckzyqz6-nginx.conf syntax is ok251ca # [1547480.882125] ca nginx-pre-start[398]: nginx: configuration file /nix/store/a4vwbl3sw1llln3lazibgrd2yckzyqz6-nginx.conf test is successful252ca # [1547480.887912] ca systemd[1]: Started Nginx Web Server.253server # [1547481.817420] server systemd-journald[72]: Journal started254server # [1547481.817474] server systemd-journald[72]: Runtime Journal (/run/log/journal/bf7ce074a136447881091df8efb8168a) is 8M, max 2.5G, 2.4G free.255server # [1547481.829851] server systemd[1]: Finished Apply Kernel Variables.256server # [1547481.842581] server systemd[1]: Finished Create Static Device Nodes in /dev gracefully.257server # [1547481.864204] server systemd[1]: Starting Flush Journal to Persistent Storage...258server # [1547481.865099] server systemd[1]: Starting Network Name Resolution...259server # [1547481.865824] server systemd[1]: Starting Create Static Device Nodes in /dev...260server # [1547481.874454] server systemd-journald[72]: Time spent on flushing to /var/log/journal/bf7ce074a136447881091df8efb8168a is 2.205ms for 7 entries.261server # [1547481.874454] server systemd-journald[72]: System Journal (/var/log/journal/bf7ce074a136447881091df8efb8168a) is 8M, max 4G, 3.9G free.262server # [1547481.884554] server systemd[1]: Finished Create Static Device Nodes in /dev.263server # [1547481.885862] server systemd[1]: Reached target Preparation for Local File Systems.264server # [1547481.885990] server systemd[1]: Reached target Local File Systems.265server # [1547481.886885] server systemd[1]: Listening on Boot Loader Control Service Socket.266server # [1547481.886947] server systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container267server # [1547481.888022] server systemd[1]: Starting Save Transient machine-id to Disk...268server # [1547481.888063] server systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys269server # [1547481.894317] server systemd[1]: Finished Flush Journal to Persistent Storage.270server # [1547481.896183] server systemd[1]: Starting Create System Files and Directories...271client # [1547481.797601] client systemd-journald[72]: Journal started272client # [1547481.797652] client systemd-journald[72]: Runtime Journal (/run/log/journal/d3a140dbf3d245f28b969a19b92e04d9) is 8M, max 2.5G, 2.4G free.273client # [1547481.805716] client systemd[1]: Finished Apply Kernel Variables.274client # [1547481.819136] client systemd[1]: Finished Create Static Device Nodes in /dev gracefully.275client # [1547481.834092] client systemd[1]: Starting Flush Journal to Persistent Storage...276client # [1547481.834871] client systemd[1]: Starting Network Name Resolution...277client # [1547481.835751] client systemd[1]: Starting Create Static Device Nodes in /dev...278client # [1547481.844036] client systemd-journald[72]: Time spent on flushing to /var/log/journal/d3a140dbf3d245f28b969a19b92e04d9 is 1.824ms for 7 entries.279client # [1547481.844036] client systemd-journald[72]: System Journal (/var/log/journal/d3a140dbf3d245f28b969a19b92e04d9) is 8M, max 4G, 3.9G free.280client # [1547481.860556] client systemd[1]: Finished Create Static Device Nodes in /dev.281client # [1547481.861350] client systemd[1]: Reached target Preparation for Local File Systems.282client # [1547481.861474] client systemd[1]: Reached target Local File Systems.283client # [1547481.862389] client systemd[1]: Listening on Boot Loader Control Service Socket.284client # [1547481.862449] client systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container285client # [1547481.863553] client systemd[1]: Starting Save Transient machine-id to Disk...286client # [1547481.863594] client systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys287client # [1547481.868234] client systemd[1]: Finished Flush Journal to Persistent Storage.288client # [1547481.869329] client systemd[1]: Starting Create System Files and Directories...289client # [1547481.884272] client systemd-tmpfiles[138]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted290client # [1547481.884508] client systemd-tmpfiles[138]: fchmod() of /var/log/journal failed: Operation not permitted291client # [1547481.884660] client systemd-tmpfiles[138]: fchmod() of /var/log/journal/d3a140dbf3d245f28b969a19b92e04d9 failed: Operation not permitted292client # [1547481.884893] client systemd-tmpfiles[138]: fchmod() of /run/log/journal failed: Operation not permitted293client # [1547481.886599] client systemd[1]: Finished Create System Files and Directories.294client # [1547481.887793] client systemd[1]: Starting Rebuild Journal Catalog...295client # [1547481.888560] client systemd[1]: Starting Record System Boot/Shutdown in UTMP...296server # [1547481.912891] server systemd-tmpfiles[140]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted297server # [1547481.913109] server systemd-tmpfiles[140]: fchmod() of /var/log/journal failed: Operation not permitted298server # [1547481.913256] server systemd-tmpfiles[140]: fchmod() of /var/log/journal/bf7ce074a136447881091df8efb8168a failed: Operation not permitted299server # [1547481.913472] server systemd-tmpfiles[140]: fchmod() of /run/log/journal failed: Operation not permitted300client # [1547481.905440] client systemd[1]: Finished Record System Boot/Shutdown in UTMP.301client # [1547481.910617] client systemd[1]: Finished Rebuild Journal Catalog.302client # [1547481.911792] client systemd[1]: Starting Update is Completed...303client # [1547481.923173] client systemd[1]: Finished Update is Completed.304client # [1547481.943456] client systemd[1]: Finished Firewall.305client # [1547481.943572] client systemd[1]: Reached target Preparation for Network.306client # [1547481.943794] client systemd[1]: Listening on Network Management Resolve Hook Socket.307client # [1547481.944835] client systemd[1]: Starting Network Management...308client # [1547481.964374] client systemd[1]: Finished Save Transient machine-id to Disk.309server # [1547481.915103] server systemd[1]: Finished Create System Files and Directories.310server # [1547481.916336] server systemd[1]: Starting Rebuild Journal Catalog...311server # [1547481.917125] server systemd[1]: Starting Record System Boot/Shutdown in UTMP...312server # [1547481.928657] server systemd[1]: Finished Record System Boot/Shutdown in UTMP.313server # [1547481.935463] server systemd[1]: Finished Rebuild Journal Catalog.314server # [1547481.936581] server systemd[1]: Starting Update is Completed...315server # [1547481.947827] server systemd[1]: Finished Update is Completed.316server # [1547481.964731] server systemd[1]: Finished Save Transient machine-id to Disk.317server # [1547481.978252] server systemd[1]: Finished Firewall.318server # [1547481.978409] server systemd[1]: Reached target Preparation for Network.319server # [1547481.978628] server systemd[1]: Listening on Network Management Resolve Hook Socket.320server # [1547481.979637] server systemd[1]: Starting Network Management...321server # [1547482.791699] server systemd-networkd[190]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted322server # [1547482.791790] server systemd-networkd[190]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted323server # [1547482.801740] server systemd-networkd[190]: /etc/systemd/network/99-ethernet-default-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.324server # [1547482.801905] server systemd-networkd[190]: /etc/systemd/network/99-wireless-client-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.325server # [1547482.802053] server systemd-networkd[190]: lo: Link UP326server # [1547482.802058] server systemd-networkd[190]: lo: Gained carrier327server # [1547482.802252] server systemd-networkd[190]: eth1: Configuring with /etc/systemd/network/40-eth1.network.328server # [1547482.802707] server systemd-networkd[190]: eth1: Link UP329server # [1547482.803020] server systemd-networkd[190]: eth1: Gained carrier330server # [1547482.813324] server systemd[1]: etc-machine\x2did.mount: Deactivated successfully.331server # [1547482.814045] server systemd[1]: Started Network Management.332server # [1547482.815208] server systemd[1]: Starting Enable Persistent Storage in systemd-networkd...333server # [1547482.872200] server systemd[1]: Finished Enable Persistent Storage in systemd-networkd.334client # [1547482.771387] client systemd-networkd[185]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted335client # [1547482.771477] client systemd-networkd[185]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted336client # [1547482.783810] client systemd-networkd[185]: /etc/systemd/network/99-ethernet-default-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.337client # [1547482.783978] client systemd-networkd[185]: /etc/systemd/network/99-wireless-client-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.338client # [1547482.784151] client systemd-networkd[185]: lo: Link UP339client # [1547482.784156] client systemd-networkd[185]: lo: Gained carrier340client # [1547482.784331] client systemd-networkd[185]: eth1: Configuring with /etc/systemd/network/40-eth1.network.341client # [1547482.784830] client systemd-networkd[185]: eth1: Link UP342client # [1547482.785158] client systemd-networkd[185]: eth1: Gained carrier343client # [1547482.809902] client systemd[1]: etc-machine\x2did.mount: Deactivated successfully.344client # [1547482.811361] client systemd[1]: Started Network Management.345client # [1547482.813486] client systemd[1]: Starting Enable Persistent Storage in systemd-networkd...346client # [1547482.872206] client systemd[1]: Finished Enable Persistent Storage in systemd-networkd.347client # [1547483.202377] client systemd-resolved[112]: Positive Trust Anchors:348client # [1547483.202390] client systemd-resolved[112]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d349client # [1547483.202393] client systemd-resolved[112]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16350client # [1547483.202429] client systemd-resolved[112]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test351client # [1547483.225525] client systemd-resolved[112]: Using system hostname 'client'.352client # [1547483.226985] client systemd[1]: Started Network Name Resolution.353client # [1547483.227132] client systemd[1]: Reached target Network.354client # [1547483.227264] client systemd[1]: Reached target System Initialization.355client # [1547483.227367] client systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container356client # [1547483.227418] client systemd[1]: Started Daily Cleanup of Temporary Directories.357client # [1547483.227458] client systemd[1]: Reached target Timer Units.358client # [1547483.227693] client systemd[1]: Listening on D-Bus System Message Bus Socket.359client # [1547483.227941] client systemd[1]: Listening on Nix Daemon Socket.360client # [1547483.228218] client systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.361client # [1547483.228280] client systemd[1]: Reached target Socket Units.362client # [1547483.228374] client systemd[1]: Reached target Basic System.363client # [1547483.230376] client systemd[1]: Starting Import lastlog data into lastlog2 database...364client # [1547483.231895] client systemd[1]: Starting Name Service Cache Daemon (nsncd)...365client # [1547483.234330] client systemd[1]: Starting D-Bus System Message Bus...366client # [1547483.296426] client systemd[1]: Finished Import lastlog data into lastlog2 database.367client # [1547483.513701] client nsncd[192]: Sep 22 05:18:23.499 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"368client # [1547483.513812] client systemd[1]: Started Name Service Cache Daemon (nsncd).369client # [1547483.513917] client systemd[1]: Reached target Host and Network Name Lookups.370client # [1547483.514026] client systemd[1]: Reached target User and Group Name Lookups.371server # [1547483.254614] server systemd-resolved[117]: Positive Trust Anchors:372server # [1547483.254625] server systemd-resolved[117]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d373server # [1547483.254628] server systemd-resolved[117]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16374server # [1547483.254665] server systemd-resolved[117]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test375server # [1547483.277762] server systemd-resolved[117]: Using system hostname 'server'.376server # [1547483.279191] server systemd[1]: Started Network Name Resolution.377server # [1547483.279292] server systemd[1]: Reached target Network.378server # [1547483.279368] server systemd[1]: Reached target Network is Online.379server # [1547483.279425] server systemd[1]: Reached target System Initialization.380server # [1547483.279693] server systemd[1]: Started Renew ACME Certificate for test.foo.381server # [1547483.279743] server systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container382server # [1547483.279780] server systemd[1]: Started Daily Cleanup of Temporary Directories.383server # [1547483.279802] server systemd[1]: Reached target Timer Units.384server # [1547483.279962] server systemd[1]: Listening on D-Bus System Message Bus Socket.385server # [1547483.280135] server systemd[1]: Listening on Nix Daemon Socket.386server # [1547483.280289] server systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.387server # [1547483.280317] server systemd[1]: Reached target Socket Units.388server # [1547483.280383] server systemd[1]: Reached target Basic System.389server # [1547483.281952] server systemd[1]: Starting Set up the ACME certificate renewal infrastructure...390server # [1547483.282987] server systemd[1]: Starting Import lastlog data into lastlog2 database...391server # [1547483.283032] server systemd[1]: nginx-config-reload.service skipped, unmet condition check ConditionPathExists=/var/lib/acme/test.foo/fullchain.pem392server # [1547483.283985] server systemd[1]: Starting Name Service Cache Daemon (nsncd)...393server # [1547483.285570] server systemd[1]: Starting D-Bus System Message Bus...394server # [1547483.307074] server systemd[1]: Finished Import lastlog data into lastlog2 database.395server # [1547483.435490] server acme-setup-privileged[195]: + set -euo pipefail396server # [1547483.435490] server acme-setup-privileged[195]: + cd /var/lib/acme397server # [1547483.435490] server acme-setup-privileged[195]: + chmod -R u=rwX,g=,o= .lego/accounts398server # [1547483.437302] server acme-setup-privileged[195]: + chown -R acme .lego/accounts399server # [1547483.439300] server acme-setup-privileged[195]: + for fixpath in test.foo .lego/test.foo400server # [1547483.439300] server acme-setup-privileged[195]: + '[' -d test.foo ']'401server # [1547483.439430] server acme-setup-privileged[195]: + for fixpath in test.foo .lego/test.foo402server # [1547483.439430] server acme-setup-privileged[195]: + '[' -d .lego/test.foo ']'403server # [1547483.482642] server systemd[1]: Started Name Service Cache Daemon (nsncd).404server # [1547483.482765] server systemd[1]: Reached target Host and Network Name Lookups.405server # [1547483.482850] server nsncd[197]: Sep 22 05:18:23.468 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"406server # [1547483.482882] server systemd[1]: Reached target User and Group Name Lookups.407server # [1547483.488682] server systemd[1]: Starting User Login Management...408server # [1547483.490201] server systemd[1]: Starting Permit User Sessions...409server # [1547483.501896] server systemd[1]: Finished Permit User Sessions.410server # [1547483.503136] server systemd[1]: Started Console Getty.411server # [1547483.503185] server systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0412server # [1547483.503208] server systemd[1]: Reached target Login Prompts.413client # [1547483.516049] client systemd[1]: Starting User Login Management...414client # [1547483.517655] client systemd[1]: Starting Permit User Sessions...415client # [1547483.529792] client systemd[1]: Finished Permit User Sessions.416client # [1547483.531752] client systemd[1]: Started Console Getty.417client # [1547483.531826] client systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0418client # [1547483.531864] client systemd[1]: Reached target Login Prompts.419client # [1547483.710321] client dbus-broker-launch[193]: Looking up NSS user entry for 'systemd-timesync'...420client # [1547483.712114] client dbus-broker-launch[193]: NSS returned no entry for 'systemd-timesync'421client # [1547483.712114] client dbus-broker-launch[193]: Invalid user-name in /nix/store/jnwijn22akqm9wx5cbsh5r175688pxrd-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"422client # [1547483.712575] client systemd[1]: Started D-Bus System Message Bus.423client # [1547483.721679] client dbus-broker-launch[193]: Ready424server # [1547483.694774] server dbus-broker-launch[198]: Looking up NSS user entry for 'systemd-timesync'...425server # [1547483.696174] server dbus-broker-launch[198]: NSS returned no entry for 'systemd-timesync'426server # [1547483.696174] server dbus-broker-launch[198]: Invalid user-name in /nix/store/hhf78xjbhahwklqhs8v1mlicy0h1q0m9-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"427server # [1547483.696607] server systemd[1]: Started D-Bus System Message Bus.428server # [1547483.705201] server dbus-broker-launch[198]: Ready429client # [1547484.444546] client systemd-logind[208]: New seat seat0.430client # [1547484.444789] client systemd[1]: Started User Login Management.431client # [1547484.446860] client systemd[1]: Starting linger-users.service...432client # [1547484.504555] client systemd[1]: linger-users.service: Deactivated successfully.433client # [1547484.504961] client systemd[1]: Finished linger-users.service.434client # [1547484.506220] client systemd[1]: Reached target Multi-User System.435client # [1547484.506647] client systemd[1]: Startup finished in 3.118s.436server # [1547484.416457] server systemd-networkd[190]: eth1: Gained IPv6LL437server # [1547484.452572] server systemd-logind[222]: New seat seat0.438server # [1547484.452717] server systemd[1]: Started User Login Management.439server # [1547484.492806] server systemd[1]: Starting linger-users.service...440server # [1547484.507457] server systemd[1]: linger-users.service: Deactivated successfully.441server # [1547484.507555] server systemd[1]: Finished linger-users.service.442server # [1547484.563922] server acme-setup-start[211]: + set -euo pipefail443server # [1547484.563922] server acme-setup-start[211]: + test -e ca/key.pem444server # [1547484.564460] server acme-setup-start[211]: + minica --ca-key ca/key.pem --ca-cert ca/cert.pem --domains selfsigned.local445server # [1547484.628329] server systemd[1]: Finished Set up the ACME certificate renewal infrastructure.446server # [1547484.630512] server systemd[1]: Starting Ensure certificate for test.foo...447client # [1547484.800154] client systemd-networkd[185]: eth1: Gained IPv6LL448server # [1547485.593289] server acme-test.foo-start[248]: Waiting to acquire lock in /run/acme/449server # [1547485.596265] server acme-test.foo-start[248]: + '[' -e out/acme-success ']'450server # [1547485.596362] server acme-test.foo-start[248]: + minica --ca-cert=ca/cert.pem --ca-key=ca/key.pem --domains=test.foo --ip-addresses=451server # [1547485.611825] server acme-test.foo-start[258]: + cd test.foo452server # [1547485.612463] server acme-test.foo-start[258]: + cp -vp cert.pem ../out/cert.pem453server # [1547485.613964] server acme-test.foo-start[259]: 'cert.pem' -> '../out/cert.pem'454server # [1547485.614308] server acme-test.foo-start[258]: + cp -vp key.pem ../out/key.pem455server # [1547485.615595] server acme-test.foo-start[258]: 'key.pem' -> '../out/key.pem'456server # [1547485.615836] server acme-test.foo-start[248]: + cat out/cert.pem ca/cert.pem457server # [1547485.617726] server acme-test.foo-start[248]: + cp ca/cert.pem out/chain.pem458server # [1547485.619677] server acme-test.foo-start[248]: + cat out/key.pem out/fullchain.pem459server # [1547485.621641] server acme-test.foo-start[248]: + for fixpath in out certificates460server # [1547485.621641] server acme-test.foo-start[248]: + '[' -d out ']'461server # [1547485.621762] server acme-test.foo-start[248]: + chmod -R u=rwX,g=rX,o= out462server # [1547485.623377] server acme-test.foo-start[248]: + chown -R acme:nginx out463server # [1547485.627004] server acme-test.foo-start[248]: + for fixpath in out certificates464server # [1547485.627004] server acme-test.foo-start[248]: + '[' -d certificates ']'465server # [1547485.632402] server systemd[1]: Finished Ensure certificate for test.foo.466server # [1547485.634806] server systemd[1]: Starting Nginx Web Server...467server # [1547486.610373] server nginx-pre-start[270]: nginx: the configuration file /nix/store/c5sy3a3rn41k7ykx229snncrivc8g1fx-nginx.conf syntax is ok468server # [1547486.610983] server nginx-pre-start[270]: nginx: configuration file /nix/store/c5sy3a3rn41k7ykx229snncrivc8g1fx-nginx.conf test is successful469server # [1547486.616764] server systemd[1]: Started Nginx Web Server.470server # [1547486.617146] server systemd[1]: Reached target Multi-User System.471server # [1547486.618346] server systemd[1]: Starting Order (and renew) ACME certificate for test.foo...472server # [1547487.789922] server acme-order-renew-test.foo-start[273]: Waiting to acquire lock in /run/acme/473server # [1547487.792816] server acme-order-renew-test.foo-start[273]: + set -euo pipefail474server # [1547487.792894] server acme-order-renew-test.foo-start[273]: + exec475server # [1547487.792961] server acme-order-renew-test.foo-start[273]: + /nix/store/fxkh6pl0y4nfbhdqdnhfkvsq7pbxq655-flock-0.4.0/bin/flock 11476server # [1547487.794318] server acme-order-renew-test.foo-start[284]: ++ find accounts -path '*/keys/*.key' -print -quit477server # [1547487.796410] server acme-order-renew-test.foo-start[273]: + '[' -n '' ']'478server # [1547487.796494] server acme-order-renew-test.foo-start[273]: + exec479server # [1547487.796546] server acme-order-renew-test.foo-start[273]: + '[' -e certificates/test.foo.key ']'480server # [1547487.796546] server acme-order-renew-test.foo-start[273]: + lego run --accept-tos --path . --email none@none.tld --http --http.webroot /var/lib/acme/acme-challenge --server https://ca.foo/acme/acme/directory --key-type ec256 -d test.foo --no-random-sleep --renew-force --ari-disable --force-cert-domains481server # [1547487.816241] server acme-order-renew-test.foo-start[285]: 2026-09-22T05:18:27.802028444Z INFO Private key saved. filepath=accounts/ca.foo/none@none.tld/none@none.tld.key482server # [1547487.846666] server acme-order-renew-test.foo-start[285]: 2026-09-22T05:18:27.832457137Z INFO Registering the account. email=none@none.tld483server # [1547487.864418] server acme-order-renew-test.foo-start[285]: 2026-09-22T05:18:27.850193898Z WARN !!!! HEADS UP !!!!484server # [1547487.864418] server acme-order-renew-test.foo-start[285]: Your account credentials have been saved in your485server # [1547487.864418] server acme-order-renew-test.foo-start[285]: configuration directory at "accounts".486server # [1547487.864418] server acme-order-renew-test.foo-start[285]: You should make a secure backup of this folder now. This487server # [1547487.864418] server acme-order-renew-test.foo-start[285]: configuration directory will also contain private keys488server # [1547487.864418] server acme-order-renew-test.foo-start[285]: generated by lego and certificates obtained from the ACME489server # [1547487.864418] server acme-order-renew-test.foo-start[285]: server. Making regular backups of this folder is ideal.490server # [1547487.864761] server acme-order-renew-test.foo-start[285]: 2026-09-22T05:18:27.850448781Z INFO Obtaining bundled SAN certificate. domains=test.foo491server # [1547487.933981] server acme-order-renew-test.foo-start[285]: 2026-09-22T05:18:27.919778283Z INFO Use solver. domain=test.foo type=http-01492server # [1547487.933981] server acme-order-renew-test.foo-start[285]: 2026-09-22T05:18:27.919815044Z INFO http01: Trying to solve HTTP-01. domain=test.foo493server # [1547487.940669] server acme-order-renew-test.foo-start[285]: 2026-09-22T05:18:27.926304132Z INFO The server validated our request. domain=test.foo494server # [1547487.940669] server acme-order-renew-test.foo-start[285]: 2026-09-22T05:18:27.926469374Z INFO Validations succeeded; requesting certificates. domains=test.foo495server # [1547487.958566] server acme-order-renew-test.foo-start[285]: 2026-09-22T05:18:27.944354097Z INFO Server responded with a certificate. domains=test.foo496server # [1547487.958728] server acme-order-renew-test.foo-start[285]: 2026-09-22T05:18:27.944470259Z INFO Writing file. filepath=certificates/test.foo.crt497server # [1547487.958826] server acme-order-renew-test.foo-start[285]: 2026-09-22T05:18:27.944635061Z INFO Writing file. filepath=certificates/test.foo.issuer.crt498server # [1547487.958905] server acme-order-renew-test.foo-start[285]: 2026-09-22T05:18:27.944716022Z INFO Writing file. filepath=certificates/test.foo.key499server # [1547487.959062] server acme-order-renew-test.foo-start[285]: 2026-09-22T05:18:27.944874624Z INFO Writing file. filepath=certificates/test.foo.json500server # [1547487.965102] server acme-order-renew-test.foo-start[273]: + touch out/acme-success501server # [1547487.967061] server acme-order-renew-test.foo-start[273]: + cmp -s certificates/test.foo.crt out/fullchain.pem502server # [1547487.968455] server acme-order-renew-test.foo-start[273]: + touch out/renewed503server # [1547487.970346] server acme-order-renew-test.foo-start[273]: + echo Installing new certificate504server # [1547487.970346] server acme-order-renew-test.foo-start[273]: Installing new certificate505server # [1547487.970407] server acme-order-renew-test.foo-start[273]: + cp -vp certificates/test.foo.crt out/fullchain.pem506server # [1547487.972422] server acme-order-renew-test.foo-start[317]: 'certificates/test.foo.crt' -> 'out/fullchain.pem'507server # [1547487.972788] server acme-order-renew-test.foo-start[273]: + cp -vp certificates/test.foo.key out/key.pem508server # [1547487.974592] server acme-order-renew-test.foo-start[318]: 'certificates/test.foo.key' -> 'out/key.pem'509server # [1547487.974916] server acme-order-renew-test.foo-start[273]: + cp -vp certificates/test.foo.issuer.crt out/chain.pem510server # [1547487.976786] server acme-order-renew-test.foo-start[319]: 'certificates/test.foo.issuer.crt' -> 'out/chain.pem'511server # [1547487.977109] server acme-order-renew-test.foo-start[273]: + ln -sf fullchain.pem out/cert.pem512server # [1547487.978940] server acme-order-renew-test.foo-start[273]: + cat out/key.pem out/fullchain.pem513server # [1547487.980856] server acme-order-renew-test.foo-start[273]: + for fixpath in out certificates514server # [1547487.980890] server acme-order-renew-test.foo-start[273]: + '[' -d out ']'515server # [1547487.980890] server acme-order-renew-test.foo-start[273]: + chmod -R u=rwX,g=rX,o= out516server # [1547487.982681] server acme-order-renew-test.foo-start[273]: + chown -R acme:nginx out517server # [1547487.986144] server acme-order-renew-test.foo-start[273]: + for fixpath in out certificates518server # [1547487.986144] server acme-order-renew-test.foo-start[273]: + '[' -d certificates ']'519server # [1547487.986144] server acme-order-renew-test.foo-start[273]: + chmod -R u=rwX,g=rX,o= certificates520server # [1547487.988188] server acme-order-renew-test.foo-start[273]: + chown -R acme:nginx certificates521server # [1547487.991308] server acme-order-renew-test.foo-start[273]: + chmod -R u=rwX,g=,o= accounts/.522ca # [1547487.846164] ca step-ca[207]: time="2026-09-22T05:18:27Z" level=info duration="60.72µs" duration-ns=60720 fields.time="2026-09-22T05:18:27Z" method=GET name=ca path=/acme/acme/directory protocol=HTTP/1.1 referer= remote-address="::1" request-id=d50e3ccf-ca01-4e40-8a20-826aeec941bb response="{\"newNonce\":\"https://ca.foo/acme/acme/new-nonce\",\"newAccount\":\"https://ca.foo/acme/acme/new-account\",\"newOrder\":\"https://ca.foo/acme/acme/new-order\",\"revokeCert\":\"https://ca.foo/acme/acme/revoke-cert\",\"keyChange\":\"https://ca.foo/acme/acme/key-change\"}" size=252 status=200 user-agent="lego-cli/ xenolf-acme/5.4.1 (release; linux; arm64)" user-id=523ca # [1547487.856566] ca step-ca[207]: time="2026-09-22T05:18:27Z" level=info duration=6.813573ms duration-ns=6813573 fields.time="2026-09-22T05:18:27Z" method=HEAD name=ca nonce=YVg2aUFvT0JrUXhuM1M5Yk5jdFZtWEdaZkszcHJPeXk path=/acme/acme/new-nonce protocol=HTTP/1.1 referer= remote-address=127.0.0.1 request-id=d8556153-c3e3-43d5-b12c-954046c6d76a size=0 status=200 user-agent="lego-cli/ xenolf-acme/5.4.1 (release; linux; arm64)" user-id=524ca # [1547487.863186] ca step-ca[207]: time="2026-09-22T05:18:27Z" level=info duration=3.007401ms duration-ns=3007401 fields.time="2026-09-22T05:18:27Z" method=POST name=ca nonce=QTR2QWw2dFlxRDJhNEl0M2Q3YkpFOTFWTFVOOElCTGc path=/acme/acme/new-account protocol=HTTP/1.1 referer= remote-address="::1" request-id=fc82864c-db0a-4dd2-a422-d94e9451d914 response="{\"contact\":[\"mailto:none@none.tld\"],\"status\":\"valid\",\"orders\":\"https://ca.foo/acme/acme/account/zXH9ScMAcgi3bxm52hpCt5Bq2v60FiRW/orders\"}" size=138 status=201 user-agent="lego-cli/ xenolf-acme/5.4.1 (release; linux; arm64)" user-id=525ca # [1547487.872124] ca step-ca[207]: time="2026-09-22T05:18:27Z" level=info duration=4.127736ms duration-ns=4127736 fields.time="2026-09-22T05:18:27Z" method=POST name=ca nonce=d0JBSlU2Z0w0SGR0Y2ZBM1hSU2tBWDh5QWo2OHREbmE path=/acme/acme/new-order protocol=HTTP/1.1 referer= remote-address=127.0.0.1 request-id=c5b0cf67-659c-4520-bb90-8c0718acd144 response="{\"id\":\"0J3pmsvgzrAVwyzMUd474qjHYueTKfpc\",\"status\":\"pending\",\"expires\":\"2026-09-23T05:18:27Z\",\"identifiers\":[{\"type\":\"dns\",\"value\":\"test.foo\"}],\"notBefore\":\"2026-09-22T05:17:27Z\",\"notAfter\":\"2026-12-21T05:18:27Z\",\"authorizations\":[\"https://ca.foo/acme/acme/authz/7uUefmLaNdh5wt0UbPCdC6m19GWjOlXD\"],\"finalize\":\"https://ca.foo/acme/acme/order/0J3pmsvgzrAVwyzMUd474qjHYueTKfpc/finalize\"}" size=384 status=201 user-agent="lego-cli/ xenolf-acme/5.4.1 (release; linux; arm64)" user-id=526ca # [1547487.933441] ca step-ca[207]: time="2026-09-22T05:18:27Z" level=info duration=2.049148ms duration-ns=2049148 fields.time="2026-09-22T05:18:27Z" method=POST name=ca nonce=Q1BlTHE0S29HamZDcEpnR0JaZnFxQ1ZjVFJadFF0eUE path=/acme/acme/authz/7uUefmLaNdh5wt0UbPCdC6m19GWjOlXD protocol=HTTP/1.1 referer= remote-address="::1" request-id=e2250de6-03fb-43ac-99e9-070df831e63c response="{\"identifier\":{\"type\":\"dns\",\"value\":\"test.foo\"},\"status\":\"pending\",\"challenges\":[{\"type\":\"dns-01\",\"status\":\"pending\",\"token\":\"uRC2L3rDCGnD6iznzgYOmTHpiKv5HpUW\",\"url\":\"https://ca.foo/acme/acme/challenge/7uUefmLaNdh5wt0UbPCdC6m19GWjOlXD/JmLHdoAIDqxRnDVbtU2ls83R0TxM1hyq\"},{\"type\":\"http-01\",\"status\":\"pending\",\"token\":\"uRC2L3rDCGnD6iznzgYOmTHpiKv5HpUW\",\"url\":\"https://ca.foo/acme/acme/challenge/7uUefmLaNdh5wt0UbPCdC6m19GWjOlXD/6IyCIKpXwIJcKTNNTd6qT0Tgaa1dOJ0t\"},{\"type\":\"tls-alpn-01\",\"status\":\"pending\",\"token\":\"uRC2L3rDCGnD6iznzgYOmTHpiKv5HpUW\",\"url\":\"https://ca.foo/acme/acme/challenge/7uUefmLaNdh5wt0UbPCdC6m19GWjOlXD/g2e4v5F05rRuv3ApZax1DeUgjXRIsrm4\"}],\"wildcard\":false,\"expires\":\"2026-09-23T05:18:27Z\"}" size=706 status=200 user-agent="lego-cli/ xenolf-acme/5.4.1 (release; linux; arm64)" user-id=527ca # [1547487.939793] ca step-ca[207]: time="2026-09-22T05:18:27Z" level=info duration=3.808532ms duration-ns=3808532 fields.time="2026-09-22T05:18:27Z" method=POST name=ca nonce=YUFhNWd2dzZmeXFaM2FFWVJjb2FaQUk1TFdJdHJPaGw path=/acme/acme/challenge/7uUefmLaNdh5wt0UbPCdC6m19GWjOlXD/6IyCIKpXwIJcKTNNTd6qT0Tgaa1dOJ0t protocol=HTTP/1.1 referer= remote-address=127.0.0.1 request-id=a9d5c068-7c1a-4a69-972c-1b91098cc793 response="{\"type\":\"http-01\",\"status\":\"valid\",\"token\":\"uRC2L3rDCGnD6iznzgYOmTHpiKv5HpUW\",\"validated\":\"2026-09-22T05:18:27Z\",\"url\":\"https://ca.foo/acme/acme/challenge/7uUefmLaNdh5wt0UbPCdC6m19GWjOlXD/6IyCIKpXwIJcKTNNTd6qT0Tgaa1dOJ0t\"}" size=223 status=200 user-agent="lego-cli/ xenolf-acme/5.4.1 (release; linux; arm64)" user-id=528ca # [1547487.953268] ca step-ca[207]: time="2026-09-22T05:18:27Z" level=info duration=8.242472ms duration-ns=8242472 fields.time="2026-09-22T05:18:27Z" method=POST name=ca nonce=OEJqNGtVeTV5S1lXY21IYW51eE1udjg0YW14YWViQVM path=/acme/acme/order/0J3pmsvgzrAVwyzMUd474qjHYueTKfpc/finalize protocol=HTTP/1.1 referer= remote-address="::1" request-id=c6ec741b-f57b-4fb1-ba09-9e7f29348e99 response="{\"id\":\"0J3pmsvgzrAVwyzMUd474qjHYueTKfpc\",\"status\":\"valid\",\"expires\":\"2026-09-23T05:18:27Z\",\"identifiers\":[{\"type\":\"dns\",\"value\":\"test.foo\"}],\"notBefore\":\"2026-09-22T05:17:27Z\",\"notAfter\":\"2026-12-21T05:18:27Z\",\"authorizations\":[\"https://ca.foo/acme/acme/authz/7uUefmLaNdh5wt0UbPCdC6m19GWjOlXD\"],\"finalize\":\"https://ca.foo/acme/acme/order/0J3pmsvgzrAVwyzMUd474qjHYueTKfpc/finalize\",\"certificate\":\"https://ca.foo/acme/acme/certificate/jsUuoBIPw51oVZShyXlZwkzKJGSGrB9P\"}" size=468 status=200 user-agent="lego-cli/ xenolf-acme/5.4.1 (release; linux; arm64)" user-id=529ca # [1547487.957819] ca step-ca[207]: time="2026-09-22T05:18:27Z" level=info certificate="MIIB1zCCAX2gAwIBAgIQfOr+y8l5Gmg03XtOLO5t+jAKBggqhkjOPQQDAjAfMR0wGwYDVQQDExRDbGFuIEludGVybWVkaWF0ZSBDQTAeFw0yNjA5MjIwNTE3MjdaFw0yNjEyMjEwNTE4MjdaMBMxETAPBgNVBAMTCHRlc3QuZm9vMFkwEwYHKoZIzj0CAQYIKoZIzj0DAQcDQgAEIQVzv6SKBdEch/E3lq0ACp7ohMW9jGkMPthyVtiGlLXuYoKQUvNpFkhUdISk4BSNA6poZzheZCqgWiGx5YXNSKOBpjCBozAOBgNVHQ8BAf8EBAMCB4AwHQYDVR0lBBYwFAYIKwYBBQUHAwEGCCsGAQUFBwMCMB0GA1UdDgQWBBQ9Baux5ya9zIDpPpbrIWZfMAueiTAfBgNVHSMEGDAWgBRGHUyl7f/LRUqXIKAHNTIW9dttwDATBgNVHREEDDAKggh0ZXN0LmZvbzAdBgwrBgEEAYKkZMYoQAEEDTALAgEGBARhY21lBAAwCgYIKoZIzj0EAwIDSAAwRQIgdZMU7dgGGySsX4gRJsUn3aSDADqJfNKNi13o5z1a64YCIQCKUS88dtmOCr2XYqLB4lpsUq9+dteg5+Cu65H+5HrXaw==" duration=1.766344ms duration-ns=1766344 fields.time="2026-09-22T05:18:27Z" issuer="Clan Intermediate CA" method=POST name=ca nonce=aHBYVGtHWEExamIwZW1wdzNuUzF2T21qSHpuUWt1QkI path=/acme/acme/certificate/jsUuoBIPw51oVZShyXlZwkzKJGSGrB9P protocol=HTTP/1.1 provisioner=acme public-key="ECDSA P-256" referer= remote-address=127.0.0.1 request-id=aedef5ab-1218-484a-af23-d96254d99900 sans="map[dns:[test.foo]]" serial=166044436819935927527159429362954956282 size=1348 status=200 subject=test.foo user-agent="lego-cli/ xenolf-acme/5.4.1 (release; linux; arm64)" user-id= valid-from="2026-09-22T05:17:27Z" valid-to="2026-12-21T05:18:27Z"530server # [1547488.235433] server systemd[1]: Reloading Nginx Web Server...531server # [1547488.240519] server systemd[1]: acme-order-renew-test.foo.service: Deactivated successfully.532server # [1547488.240712] server systemd[1]: Finished Order (and renew) ACME certificate for test.foo.533server # [1547488.240938] server systemd[1]: acme-order-renew-test.foo.service: Consumed 1.308s CPU time over 1.622s wall clock time.534server # [1547489.407287] server nginx[335]: nginx: the configuration file /nix/store/c5sy3a3rn41k7ykx229snncrivc8g1fx-nginx.conf syntax is ok535server # [1547489.407843] server nginx[335]: nginx: configuration file /nix/store/c5sy3a3rn41k7ykx229snncrivc8g1fx-nginx.conf test is successful536server # [1547490.775040] server systemd[1]: Reloaded Nginx Web Server.537server # [1547490.775430] server systemd[1]: Startup finished in 9.380s.538server: (finished: waiting for file '/var/lib/acme/test.foo/fullchain.pem', in 10.17 seconds)539server: must succeed: systemctl restart nginx.service540server # [1547491.077068] server systemd[1]: Stopping Nginx Web Server...541server # [1547491.172030] server systemd[1]: nginx.service: Deactivated successfully.542server # [1547491.172317] server systemd[1]: Stopped Nginx Web Server.543server # [1547491.172385] server systemd[1]: nginx.service: Consumed 6.167s CPU time over 5.537s wall clock time.544server # [1547491.175925] server systemd[1]: Starting Nginx Web Server...545server: (finished: must succeed: systemctl restart nginx.service, in 1.26 seconds)546client: waiting for success: curl -v https://test.foo547* Host test.foo:443 was resolved.548* IPv6: 2001:db8:1::3549* IPv4: 192.168.1.3550* Trying [2001:db8:1::3]:443...551* connect to 2001:db8:1::3 port 443 from 2001:db8:1::2 port 35948 failed: Connection refused552* Trying 192.168.1.3:443...553* connect to 192.168.1.3 port 443 from 192.168.1.2 port 48784 failed: Connection refused554* Failed to connect to test.foo:443 after 3 ms: Could not connect to server555* closing connection #0556curl: (7) Failed to connect to test.foo:443 after 3 ms: Could not connect to server557server # [1547492.313156] server nginx-pre-start[358]: nginx: the configuration file /nix/store/c5sy3a3rn41k7ykx229snncrivc8g1fx-nginx.conf syntax is ok558server # [1547492.313906] server nginx-pre-start[358]: nginx: configuration file /nix/store/c5sy3a3rn41k7ykx229snncrivc8g1fx-nginx.conf test is successful559server # [1547492.321444] server systemd[1]: Started Nginx Web Server.560* Host test.foo:443 was resolved.561* IPv6: 2001:db8:1::3562* IPv4: 192.168.1.3563* Trying [2001:db8:1::3]:443...564* connect to 2001:db8:1::3 port 443 from 2001:db8:1::2 port 35954 failed: Connection refused565* Trying 192.168.1.3:443...566* connect to 192.168.1.3 port 443 from 192.168.1.2 port 48800 failed: Connection refused567* Failed to connect to test.foo:443 after 2 ms: Could not connect to server568* closing connection #0569curl: (7) Failed to connect to test.foo:443 after 2 ms: Could not connect to server570* Host test.foo:443 was resolved.571* IPv6: 2001:db8:1::3572* IPv4: 192.168.1.3573* Trying [2001:db8:1::3]:443...574* ALPN: curl offers h2,http/1.1575} [5 bytes data]576* TLSv1.3 (OUT), TLS handshake, Client hello (1):577} [1552 bytes data]578* SSL Trust Anchors:579* OpenSSL default paths (fallback)580{ [5 bytes data]581* TLSv1.3 (IN), TLS handshake, Server hello (2):582{ [1210 bytes data]583* TLSv1.3 (IN), TLS change cipher, Change cipher spec (1):584{ [1 bytes data]585* TLSv1.3 (IN), TLS handshake, Encrypted Extensions (8):586{ [19 bytes data]587* TLSv1.3 (IN), TLS handshake, Certificate (11):588{ [931 bytes data]589* TLSv1.3 (IN), TLS handshake, CERT verify (15):590{ [78 bytes data]591* TLSv1.3 (IN), TLS handshake, Finished (20):592{ [52 bytes data]593* TLSv1.3 (OUT), TLS change cipher, Change cipher spec (1):594} [1 bytes data]595* TLSv1.3 (OUT), TLS handshake, Finished (20):596} [52 bytes data]597* SSL connection using TLSv1.3 / TLS_AES_256_GCM_SHA384 / X25519MLKEM768 / id-ecPublicKey598* ALPN: server accepted h2599* Server certificate:600* subject: CN=test.foo601* start date: Sep 22 05:17:27 2026 GMT602* expire date: Dec 21 05:18:27 2026 GMT603* issuer: CN=Clan Intermediate CA604* Certificate level 0: Public key type EC/prime256v1 (256/128 Bits/secBits), signed using ecdsa-with-SHA256605* Certificate level 1: Public key type EC/prime256v1 (256/128 Bits/secBits), signed using ecdsa-with-SHA256606* Certificate level 2: Public key type EC/prime256v1 (256/128 Bits/secBits), signed using ecdsa-with-SHA256607* subjectAltName: "test.foo" matches cert's "test.foo"608* OpenSSL verify result: 0609* SSL certificate verified via OpenSSL.610* Established connection to test.foo (2001:db8:1::3 port 443) from 2001:db8:1::2 port 35966 611 % Total % Received % Xferd Average Speed Time Time Time Current612 Dload Upload Total Spent Left Speed613 0 0 0 0 0 0 0 0 0* using HTTP/2614* [HTTP/2] [1] OPENED stream for https://test.foo/615* [HTTP/2] [1] [:method: GET]616* [HTTP/2] [1] [:scheme: https]617* [HTTP/2] [1] [:authority: test.foo]618* [HTTP/2] [1] [:path: /]619* [HTTP/2] [1] [user-agent: curl/8.22.0]620* [HTTP/2] [1] [accept: */*]621} [5 bytes data]622623624625626627* Request completely sent off628{ [5 bytes data]629* TLSv1.3 (IN), TLS handshake, Newsession Ticket (4):630{ [265 bytes data]631* TLSv1.3 (IN), TLS handshake, Newsession Ticket (4):632{ [265 bytes data]633634635636637638639640{ [5 bytes data]641100 20 100 20 0 0 667 0 0642* Connection #0 to host test.foo:443 left intact643client: (finished: waiting for success: curl -v https://test.foo, in 2.09 seconds)644client: must succeed: openssl s_client -connect test.foo:443 -servername test.foo </dev/null 2>/dev/null | openssl x509 -text -noout 1>&2645Certificate:646 Data:647 Version: 3 (0x2)648 Serial Number:649 7c:ea:fe:cb:c9:79:1a:68:34:dd:7b:4e:2c:ee:6d:fa650 Signature Algorithm: ecdsa-with-SHA256651 Issuer: CN=Clan Intermediate CA652 Validity653 Not Before: Sep 22 05:17:27 2026 GMT654 Not After : Dec 21 05:18:27 2026 GMT655 Subject: CN=test.foo656 Subject Public Key Info:657 Public Key Algorithm: id-ecPublicKey658 Public-Key: (256 bit)659 pub:660 04:21:05:73:bf:a4:8a:05:d1:1c:87:f1:37:96:ad:661 00:0a:9e:e8:84:c5:bd:8c:69:0c:3e:d8:72:56:d8:662 86:94:b5:ee:62:82:90:52:f3:69:16:48:54:74:84:663 a4:e0:14:8d:03:aa:68:67:38:5e:64:2a:a0:5a:21:664 b1:e5:85:cd:48665 ASN1 OID: prime256v1666 NIST CURVE: P-256667 X509v3 extensions:668 X509v3 Key Usage: critical669 Digital Signature670 X509v3 Extended Key Usage: 671 TLS Web Server Authentication, TLS Web Client Authentication672 X509v3 Subject Key Identifier: 673 3D:05:AB:B1:E7:26:BD:CC:80:E9:3E:96:EB:21:66:5F:30:0B:9E:89674 X509v3 Authority Key Identifier: 675 46:1D:4C:A5:ED:FF:CB:45:4A:97:20:A0:07:35:32:16:F5:DB:6D:C0676 X509v3 Subject Alternative Name: 677 DNS:test.foo678 1.3.6.1.4.1.37476.9000.64.1: 679 0......acme..680 Signature Algorithm: ecdsa-with-SHA256681 Signature Value:682 30:45:02:20:75:93:14:ed:d8:06:1b:24:ac:5f:88:11:26:c5:683 27:dd:a4:83:00:3a:89:7c:d2:8d:8b:5d:e8:e7:3d:5a:eb:86:684 02:21:00:8a:51:2f:3c:76:d9:8e:0a:bd:97:62:a2:c1:e2:5a:685 6c:52:af:7e:76:d7:a0:e7:e0:ae:eb:91:fe:e4:7a:d7:6b686client: (finished: must succeed: openssl s_client -connect test.foo:443 -servername test.foo </dev/null 2>/dev/null | openssl x509 -text -noout 1>&2, in 0.04 seconds)687(finished: run the VM test script, in 24.39 seconds)688test script finished in 24.46s689cleanup690kill NspawnMachine (pid 50)691kill NspawnMachine (pid 471)692Container ca terminated by signal KILL.693kill NspawnMachine (pid 468)694Container client terminated by signal KILL.695Container server terminated by signal KILL.696(finished: cleanup, in 0.54 seconds)