nixbot

builds

succeeded container-test-run-certificates checks.aarch64-linux.certificates · build #582 · 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_ssh11start all VMs12ca: systemd-nspawn running (pid 52)13ca: Waiting for journal at /build/vm-state-ca/var/log/journal...14server: systemd-nspawn running (pid 57)15client: systemd-nspawn running (pid 55)16server: Waiting for journal at /build/vm-state-server/var/log/journal...17client: Waiting for journal at /build/vm-state-client/var/log/journal...18(finished: start all VMs, in 0.00 seconds)19nixos-nspawn(client): TAP vde-tap1 not found; container will be isolated from VDE20nixos-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.21nixos-nspawn(ca): TAP vde-tap1 not found; container will be isolated from VDE22nixos-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.23nixos-nspawn(server): TAP vde-tap1 not found; container will be isolated from VDE24nixos-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.25Note: 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.26Note: 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.27Note: 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.28░ Spawning container server on /build/vm-state-server.29░ Spawning container client on /build/vm-state-client.30░ Spawning container ca on /build/vm-state-ca.31server # [27732.532758] server systemd-journald[69]: Journal started32server # [27732.532815] server systemd-journald[69]: Runtime Journal (/run/log/journal/6b5f57384d89440e8044ed61e9078250) is 8M, max 2.5G, 2.4G free.33server # [27732.537055] server systemd[1]: Finished Create Static Device Nodes in /dev gracefully.34client # [27732.531911] client systemd-journald[69]: Journal started35server # [27732.548063] server systemd[1]: Starting Flush Journal to Persistent Storage...36client # [27732.531969] client systemd-journald[69]: Runtime Journal (/run/log/journal/2a709b1c49914f43b6a56d3cecf2acf4) is 8M, max 2.5G, 2.4G free.37server # [27732.549016] server systemd[1]: Starting Network Name Resolution...38client # [27732.535325] client systemd[1]: Finished Create Static Device Nodes in /dev gracefully.39server # [27732.549928] server systemd[1]: Starting Create Static Device Nodes in /dev...40client # [27732.544712] client systemd[1]: Starting Flush Journal to Persistent Storage...41server # [27732.557226] server systemd-journald[69]: Time spent on flushing to /var/log/journal/6b5f57384d89440e8044ed61e9078250 is 2.763ms for 6 entries.42client # [27732.546007] client systemd[1]: Starting Network Name Resolution...43client # [27732.546991] client systemd[1]: Starting Create Static Device Nodes in /dev...44client # [27732.555368] client systemd-journald[69]: Time spent on flushing to /var/log/journal/2a709b1c49914f43b6a56d3cecf2acf4 is 1.528ms for 6 entries.45client # [27732.555368] client systemd-journald[69]: System Journal (/var/log/journal/2a709b1c49914f43b6a56d3cecf2acf4) is 8M, max 4G, 3.9G free.46client # [27732.562474] client systemd[1]: Finished Create Static Device Nodes in /dev.47client # [27732.562722] client systemd[1]: Reached target Preparation for Local File Systems.48client # [27732.562819] client systemd[1]: Reached target Local File Systems.49client # [27732.563566] client systemd[1]: Listening on Boot Loader Control Service Socket.50client # [27732.563614] client systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container51client # [27732.564518] client systemd[1]: Starting Save Transient machine-id to Disk...52client # [27732.564549] client systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys53client # [27732.590603] client systemd[1]: Finished Flush Journal to Persistent Storage.54client # [27732.592214] client systemd[1]: Starting Create System Files and Directories...55ca # [27732.531896] ca systemd-journald[79]: Journal started56ca # [27732.531959] ca systemd-journald[79]: Runtime Journal (/run/log/journal/4334bc2b675e4b41bf01b3d2df8e1f83) is 8M, max 2.5G, 2.4G free.57ca # [27732.534862] ca systemd[1]: Finished Create Static Device Nodes in /dev gracefully.58ca # [27732.544307] ca systemd[1]: Starting Flush Journal to Persistent Storage...59ca # [27732.545177] ca systemd[1]: Starting Network Name Resolution...60ca # [27732.546496] ca systemd[1]: Starting Create Static Device Nodes in /dev...61ca # [27732.557502] ca systemd-journald[79]: Time spent on flushing to /var/log/journal/4334bc2b675e4b41bf01b3d2df8e1f83 is 1.767ms for 6 entries.62client # [27732.607774] client systemd-tmpfiles[124]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted63client # [27732.608337] client systemd-tmpfiles[124]: fchmod() of /var/log/journal failed: Operation not permitted64client # [27732.608617] client systemd-tmpfiles[124]: fchmod() of /var/log/journal/2a709b1c49914f43b6a56d3cecf2acf4 failed: Operation not permitted65client # [27732.608827] client systemd-tmpfiles[124]: fchmod() of /run/log/journal failed: Operation not permitted66server # [27732.557226] server systemd-journald[69]: System Journal (/var/log/journal/6b5f57384d89440e8044ed61e9078250) is 8M, max 4G, 3.9G free.67server # [27732.570880] server systemd[1]: Finished Create Static Device Nodes in /dev.68server # [27732.571580] server systemd[1]: Reached target Preparation for Local File Systems.69server # [27732.571705] server systemd[1]: Reached target Local File Systems.70server # [27732.572553] server systemd[1]: Listening on Boot Loader Control Service Socket.71server # [27732.572602] server systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container72server # [27732.573442] server systemd[1]: Starting Save Transient machine-id to Disk...73server # [27732.573477] server systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys74server # [27732.595850] server systemd[1]: Finished Flush Journal to Persistent Storage.75server # [27732.596913] server systemd[1]: Starting Create System Files and Directories...76server # [27732.612202] server systemd-tmpfiles[129]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted77server # [27732.612422] server systemd-tmpfiles[129]: fchmod() of /var/log/journal failed: Operation not permitted78server # [27732.612572] server systemd-tmpfiles[129]: fchmod() of /var/log/journal/6b5f57384d89440e8044ed61e9078250 failed: Operation not permitted79server # [27732.612789] server systemd-tmpfiles[129]: fchmod() of /run/log/journal failed: Operation not permitted80ca # [27732.557502] ca systemd-journald[79]: System Journal (/var/log/journal/4334bc2b675e4b41bf01b3d2df8e1f83) is 8M, max 4G, 3.9G free.81ca # [27732.562498] ca systemd[1]: Finished Create Static Device Nodes in /dev.82ca # [27732.562761] ca systemd[1]: Reached target Preparation for Local File Systems.83ca # [27732.562848] ca systemd[1]: Reached target Local File Systems.84ca # [27732.563582] ca systemd[1]: Listening on Boot Loader Control Service Socket.85ca # [27732.563630] ca systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container86ca # [27732.564495] ca systemd[1]: Starting Save Transient machine-id to Disk...87ca # [27732.564533] ca systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys88ca # [27732.602599] ca systemd[1]: Finished Flush Journal to Persistent Storage.89ca # [27732.604178] ca systemd[1]: Starting Create System Files and Directories...90ca # [27732.619579] ca systemd-tmpfiles[140]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted91ca # [27732.619786] ca systemd-tmpfiles[140]: fchmod() of /var/log/journal failed: Operation not permitted92ca # [27732.619926] ca systemd-tmpfiles[140]: fchmod() of /var/log/journal/4334bc2b675e4b41bf01b3d2df8e1f83 failed: Operation not permitted93ca # [27732.620141] ca systemd-tmpfiles[140]: fchmod() of /run/log/journal failed: Operation not permitted94client # [27732.610184] client systemd[1]: Finished Create System Files and Directories.95client # [27732.611250] client systemd[1]: Starting Rebuild Journal Catalog...96client # [27732.612057] client systemd[1]: Starting Record System Boot/Shutdown in UTMP...97client # [27732.620710] client systemd[1]: Finished Save Transient machine-id to Disk.98client # [27732.624989] client systemd[1]: Finished Record System Boot/Shutdown in UTMP.99client # [27732.631908] client systemd[1]: Finished Rebuild Journal Catalog.100client # [27732.632992] client systemd[1]: Starting Update is Completed...101client # [27732.641857] client systemd[1]: Finished Update is Completed.102client # [27732.679976] client systemd[1]: Finished Firewall.103client # [27732.680138] client systemd[1]: Reached target Preparation for Network.104client # [27732.680349] client systemd[1]: Listening on Network Management Resolve Hook Socket.105client # [27732.681332] client systemd[1]: Starting Network Management...106ca # [27732.620720] ca systemd[1]: Finished Save Transient machine-id to Disk.107ca # [27732.621119] ca systemd[1]: Finished Create System Files and Directories.108ca # [27732.622827] ca systemd[1]: Starting Rebuild Journal Catalog...109ca # [27732.623612] ca systemd[1]: Starting Record System Boot/Shutdown in UTMP...110ca # [27732.634809] ca systemd[1]: Finished Record System Boot/Shutdown in UTMP.111ca # [27732.641134] ca systemd[1]: Finished Rebuild Journal Catalog.112ca # [27732.642103] ca systemd[1]: Starting Update is Completed...113ca # [27732.652145] ca systemd[1]: Finished Update is Completed.114ca # [27732.684368] ca systemd[1]: Finished Firewall.115ca # [27732.684924] ca systemd[1]: Reached target Preparation for Network.116ca # [27732.685202] ca systemd[1]: Listening on Network Management Resolve Hook Socket.117ca # [27732.686230] ca systemd[1]: Starting Network Management...118server # [27732.615250] server systemd[1]: Finished Create System Files and Directories.119server # [27732.616582] server systemd[1]: Starting Rebuild Journal Catalog...120server # [27732.617304] server systemd[1]: Starting Record System Boot/Shutdown in UTMP...121server # [27732.620703] server systemd[1]: Finished Save Transient machine-id to Disk.122server # [27732.631500] server systemd[1]: Finished Record System Boot/Shutdown in UTMP.123server # [27732.636862] server systemd[1]: Finished Rebuild Journal Catalog.124server # [27732.638226] server systemd[1]: Starting Update is Completed...125server # [27732.647402] server systemd[1]: Finished Update is Completed.126server # [27732.679291] server systemd[1]: Finished Firewall.127server # [27732.679443] server systemd[1]: Reached target Preparation for Network.128server # [27732.679658] server systemd[1]: Listening on Network Management Resolve Hook Socket.129server # [27732.680732] server systemd[1]: Starting Network Management...130client # [27733.055491] client systemd-networkd[183]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted131client # [27733.055581] client systemd-networkd[183]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted132client # [27733.063839] client systemd-networkd[183]: /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.133client # [27733.064016] client systemd-networkd[183]: /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.134client # [27733.064166] client systemd-networkd[183]: lo: Link UP135client # [27733.064169] client systemd-networkd[183]: lo: Gained carrier136client # [27733.064362] client systemd-networkd[183]: eth1: Configuring with /etc/systemd/network/40-eth1.network.137client # [27733.064733] client systemd[1]: Started Network Management.138client # [27733.064822] client systemd-networkd[183]: eth1: Link UP139client # [27733.065139] client systemd-networkd[183]: eth1: Gained carrier140client # [27733.065805] client systemd[1]: Starting Enable Persistent Storage in systemd-networkd...141client # [27733.109371] client systemd[1]: Finished Enable Persistent Storage in systemd-networkd.142client # [27733.210435] client systemd-resolved[92]: Positive Trust Anchors:143client # [27733.210446] client systemd-resolved[92]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d144client # [27733.210448] client systemd-resolved[92]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16145client # [27733.210484] client systemd-resolved[92]: 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 test146client # [27733.232050] client systemd-resolved[92]: Using system hostname 'client'.147client # [27733.233357] client systemd[1]: Started Network Name Resolution.148client # [27733.233437] client systemd[1]: Reached target Network.149client # [27733.233503] client systemd[1]: Reached target System Initialization.150client # [27733.233555] client systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container151client # [27733.233589] client systemd[1]: Started Daily Cleanup of Temporary Directories.152client # [27733.233607] client systemd[1]: Reached target Timer Units.153client # [27733.233742] client systemd[1]: Listening on D-Bus System Message Bus Socket.154client # [27733.233858] client systemd[1]: Listening on Nix Daemon Socket.155client # [27733.233969] client systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.156client # [27733.233996] client systemd[1]: Reached target Socket Units.157client # [27733.234030] client systemd[1]: Reached target Basic System.158client # [27733.272500] client systemd[1]: Starting Import lastlog data into lastlog2 database...159client # [27733.273763] client systemd[1]: Starting Name Service Cache Daemon (nsncd)...160client # [27733.275176] client systemd[1]: Starting D-Bus System Message Bus...161client # [27733.295136] client systemd[1]: Finished Import lastlog data into lastlog2 database.162ca # [27733.067118] ca systemd-networkd[197]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted163ca # [27733.067208] ca systemd-networkd[197]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted164ca # [27733.074940] ca systemd-networkd[197]: /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.165ca # [27733.075109] ca systemd-networkd[197]: /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.166ca # [27733.075236] ca systemd-networkd[197]: lo: Link UP167ca # [27733.075239] ca systemd-networkd[197]: lo: Gained carrier168ca # [27733.075387] ca systemd-networkd[197]: eth1: Configuring with /etc/systemd/network/40-eth1.network.169ca # [27733.075750] ca systemd[1]: Started Network Management.170ca # [27733.100356] ca systemd-networkd[197]: eth1: Link UP171ca # [27733.100704] ca systemd[1]: Starting Enable Persistent Storage in systemd-networkd...172ca # [27733.100721] ca systemd-networkd[197]: eth1: Gained carrier173ca # [27733.134478] ca systemd[1]: Finished Enable Persistent Storage in systemd-networkd.174ca # [27733.200191] ca systemd-resolved[102]: Positive Trust Anchors:175ca # [27733.200202] ca systemd-resolved[102]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d176ca # [27733.200205] ca systemd-resolved[102]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16177ca # [27733.200239] ca systemd-resolved[102]: 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 test178ca # [27733.222047] ca systemd-resolved[102]: Using system hostname 'ca'.179ca # [27733.223948] ca systemd[1]: Started Network Name Resolution.180ca # [27733.224099] ca systemd[1]: Reached target Network.181ca # [27733.224218] ca systemd[1]: Reached target Network is Online.182ca # [27733.224299] ca systemd[1]: Reached target System Initialization.183ca # [27733.225163] ca systemd[1]: Started Renew ACME Certificate for ca.foo.184ca # [27733.225233] ca systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container185ca # [27733.225285] ca systemd[1]: Started Daily Cleanup of Temporary Directories.186ca # [27733.225327] ca systemd[1]: Reached target Timer Units.187ca # [27733.225772] ca systemd[1]: Listening on D-Bus System Message Bus Socket.188ca # [27733.225966] ca systemd[1]: Listening on Nix Daemon Socket.189ca # [27733.226194] ca systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.190ca # [27733.226242] ca systemd[1]: Reached target Socket Units.191ca # [27733.226334] ca systemd[1]: Reached target Basic System.192ca # [27733.228938] ca systemd[1]: Starting Set up the ACME certificate renewal infrastructure...193ca # [27733.230231] ca systemd[1]: Starting Import lastlog data into lastlog2 database...194ca # [27733.230299] ca systemd[1]: nginx-config-reload.service skipped, unmet condition check ConditionPathExists=/var/lib/acme/ca.foo/fullchain.pem195ca # [27733.231772] ca systemd[1]: Starting Name Service Cache Daemon (nsncd)...196ca # [27733.233535] ca systemd[1]: Starting step-ca service...197ca # [27733.273540] ca systemd[1]: Starting D-Bus System Message Bus...198ca # [27733.291389] ca systemd[1]: Finished Import lastlog data into lastlog2 database.199server # [27733.066764] server systemd-networkd[187]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted200server # [27733.066852] server systemd-networkd[187]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted201server # [27733.074545] server systemd-networkd[187]: /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.202server # [27733.074711] server systemd-networkd[187]: /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.203server # [27733.074839] server systemd-networkd[187]: lo: Link UP204server # [27733.074844] server systemd-networkd[187]: lo: Gained carrier205server # [27733.075023] server systemd-networkd[187]: eth1: Configuring with /etc/systemd/network/40-eth1.network.206server # [27733.075375] server systemd[1]: Started Network Management.207server # [27733.100276] server systemd-networkd[187]: eth1: Link UP208server # [27733.100704] server systemd-networkd[187]: eth1: Gained carrier209server # [27733.100898] server systemd[1]: Starting Enable Persistent Storage in systemd-networkd...210server # [27733.134487] server systemd[1]: Finished Enable Persistent Storage in systemd-networkd.211server # [27733.214451] server systemd-resolved[95]: Positive Trust Anchors:212server # [27733.214462] server systemd-resolved[95]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d213server # [27733.214465] server systemd-resolved[95]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16214server # [27733.214499] server systemd-resolved[95]: 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 test215server # [27733.236683] server systemd-resolved[95]: Using system hostname 'server'.216server # [27733.238020] server systemd[1]: Started Network Name Resolution.217server # [27733.238152] server systemd[1]: Reached target Network.218server # [27733.238259] server systemd[1]: Reached target Network is Online.219server # [27733.238338] server systemd[1]: Reached target System Initialization.220server # [27733.238717] server systemd[1]: Started Renew ACME Certificate for test.foo.221server # [27733.238778] server systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container222server # [27733.238832] server systemd[1]: Started Daily Cleanup of Temporary Directories.223server # [27733.238879] server systemd[1]: Reached target Timer Units.224server # [27733.239094] server systemd[1]: Listening on D-Bus System Message Bus Socket.225server # [27733.239285] server systemd[1]: Listening on Nix Daemon Socket.226server # [27733.239498] server systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.227server # [27733.239546] server systemd[1]: Reached target Socket Units.228server # [27733.239626] server systemd[1]: Reached target Basic System.229server # [27733.273634] server systemd[1]: Starting Set up the ACME certificate renewal infrastructure...230server # [27733.275003] server systemd[1]: Starting Import lastlog data into lastlog2 database...231server # [27733.275069] server systemd[1]: nginx-config-reload.service skipped, unmet condition check ConditionPathExists=/var/lib/acme/test.foo/fullchain.pem232server # [27733.276445] server systemd[1]: Starting Name Service Cache Daemon (nsncd)...233server # [27733.278706] server systemd[1]: Starting D-Bus System Message Bus...234server # [27733.297790] server systemd[1]: Finished Import lastlog data into lastlog2 database.235server # [27733.370337] server acme-setup-privileged[192]: + set -euo pipefail236server # [27733.370337] server acme-setup-privileged[192]: + cd /var/lib/acme237server # [27733.370337] server acme-setup-privileged[192]: + chmod -R u=rwX,g=,o= .lego/accounts238server # [27733.372166] server acme-setup-privileged[192]: + chown -R acme .lego/accounts239server # [27733.374293] server acme-setup-privileged[192]: + for fixpath in test.foo .lego/test.foo240server # [27733.374293] server acme-setup-privileged[192]: + '[' -d test.foo ']'241server # [27733.374409] server acme-setup-privileged[192]: + for fixpath in test.foo .lego/test.foo242server # [27733.374409] server acme-setup-privileged[192]: + '[' -d .lego/test.foo ']'243server # [27733.399549] server nsncd[194]: Sep 04 15:09:13.385 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"244server # [27733.399533] server systemd[1]: Started Name Service Cache Daemon (nsncd).245server # [27733.399632] server systemd[1]: Reached target Host and Network Name Lookups.246server # [27733.399731] server systemd[1]: Reached target User and Group Name Lookups.247server # [27733.449270] server systemd[1]: Starting User Login Management...248server # [27733.450392] server systemd[1]: Starting Permit User Sessions...249server # [27733.461678] server systemd[1]: Finished Permit User Sessions.250server # [27733.463597] server systemd[1]: Started Console Getty.251server # [27733.463667] server systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0252server # [27733.463709] server systemd[1]: Reached target Login Prompts.253server # [27733.510487] server dbus-broker-launch[195]: Looking up NSS user entry for 'systemd-timesync'...254server # [27733.528197] server systemd[1]: etc-machine\x2did.mount: Deactivated successfully.255server # [27733.530255] server dbus-broker-launch[195]: NSS returned no entry for 'systemd-timesync'256server # [27733.530255] server dbus-broker-launch[195]: Invalid user-name in /nix/store/xamxpwkpq9pj51cyi13j1rn4cfjsvpj5-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"257server # [27733.530712] server systemd[1]: Started D-Bus System Message Bus.258server # [27733.539271] server dbus-broker-launch[195]: Ready259client # [27733.413718] client nsncd[189]: Sep 04 15:09:13.399 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"260client # [27733.413794] client systemd[1]: Started Name Service Cache Daemon (nsncd).261client # [27733.413887] client systemd[1]: Reached target Host and Network Name Lookups.262client # [27733.413994] client systemd[1]: Reached target User and Group Name Lookups.263client # [27733.449680] client systemd[1]: Starting User Login Management...264client # [27733.451199] client systemd[1]: Starting Permit User Sessions...265client # [27733.461734] client systemd[1]: Finished Permit User Sessions.266client # [27733.463449] client systemd[1]: Started Console Getty.267client # [27733.463529] client systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0268client # [27733.463571] client systemd[1]: Reached target Login Prompts.269client # [27733.515061] client dbus-broker-launch[190]: Looking up NSS user entry for 'systemd-timesync'...270client # [27733.527796] client systemd[1]: etc-machine\x2did.mount: Deactivated successfully.271client # [27733.529903] client dbus-broker-launch[190]: NSS returned no entry for 'systemd-timesync'272client # [27733.529903] client dbus-broker-launch[190]: Invalid user-name in /nix/store/hcmnwjy5lp6bjqw8pvq2l5h1hh90qrh4-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"273client # [27733.530335] client systemd[1]: Started D-Bus System Message Bus.274client # [27733.538130] client dbus-broker-launch[190]: Ready275ca # [27733.396856] ca acme-setup-privileged[202]: + set -euo pipefail276ca # [27733.396856] ca acme-setup-privileged[202]: + cd /var/lib/acme277ca # [27733.396856] ca acme-setup-privileged[202]: + chmod -R u=rwX,g=,o= .lego/accounts278ca # [27733.398778] ca acme-setup-privileged[202]: + chown -R acme .lego/accounts279ca # [27733.400621] ca acme-setup-privileged[202]: + for fixpath in ca.foo .lego/ca.foo280ca # [27733.400700] ca acme-setup-privileged[202]: + '[' -d ca.foo ']'281ca # [27733.400700] ca acme-setup-privileged[202]: + for fixpath in ca.foo .lego/ca.foo282ca # [27733.400700] ca acme-setup-privileged[202]: + '[' -d .lego/ca.foo ']'283ca # [27733.426334] ca nsncd[204]: Sep 04 15:09:13.412 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"284ca # [27733.449262] ca systemd[1]: Started Name Service Cache Daemon (nsncd).285ca # [27733.449425] ca systemd[1]: Reached target Host and Network Name Lookups.286ca # [27733.449489] ca systemd[1]: Reached target User and Group Name Lookups.287ca # [27733.450875] ca systemd[1]: Starting User Login Management...288ca # [27733.451748] ca systemd[1]: Starting Permit User Sessions...289ca # [27733.463060] ca systemd[1]: Finished Permit User Sessions.290ca # [27733.464738] ca systemd[1]: Started Console Getty.291ca # [27733.464806] ca systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0292ca # [27733.464844] ca systemd[1]: Reached target Login Prompts.293ca # [27733.525605] ca dbus-broker-launch[209]: Looking up NSS user entry for 'systemd-timesync'...294ca # [27733.527913] ca systemd[1]: etc-machine\x2did.mount: Deactivated successfully.295ca # [27733.530138] ca dbus-broker-launch[209]: NSS returned no entry for 'systemd-timesync'296ca # [27733.530138] ca dbus-broker-launch[209]: Invalid user-name in /nix/store/kb2b8z8qy8fbf7i5cavy9vvn05jlnfay-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"297ca # [27733.530559] ca systemd[1]: Started D-Bus System Message Bus.298ca # [27733.539465] ca dbus-broker-launch[209]: Ready299server # [27733.932717] server systemd-logind[220]: New seat seat0.300server # [27733.932957] server systemd[1]: Started User Login Management.301server # [27733.935397] server systemd[1]: Starting linger-users.service...302server # [27733.949567] server systemd[1]: linger-users.service: Deactivated successfully.303server # [27733.949847] server systemd[1]: Finished linger-users.service.304server # [27734.009101] server acme-setup-start[208]: + set -euo pipefail305server # [27734.009101] server acme-setup-start[208]: + test -e ca/key.pem306server # [27734.009101] server acme-setup-start[208]: + minica --ca-key ca/key.pem --ca-cert ca/cert.pem --domains selfsigned.local307server # [27734.030291] server systemd[1]: Finished Set up the ACME certificate renewal infrastructure.308server # [27734.081373] server systemd[1]: Starting Ensure certificate for test.foo...309client # [27733.941807] client systemd-logind[205]: New seat seat0.310client # [27733.942050] client systemd[1]: Started User Login Management.311client # [27733.944405] client systemd[1]: Starting linger-users.service...312client # [27733.958270] client systemd[1]: linger-users.service: Deactivated successfully.313client # [27733.958426] client systemd[1]: Finished linger-users.service.314client # [27733.959227] client systemd[1]: Reached target Multi-User System.315client # [27733.959529] client systemd[1]: Startup finished in 1.878s.316ca # [27733.932782] ca systemd-logind[231]: New seat seat0.317ca # [27733.933194] ca systemd[1]: Started User Login Management.318ca # [27733.935051] ca systemd[1]: Starting linger-users.service...319ca # [27733.949632] ca systemd[1]: linger-users.service: Deactivated successfully.320ca # [27733.949883] ca systemd[1]: Finished linger-users.service.321ca # [27734.000832] ca acme-setup-start[220]: + set -euo pipefail322ca # [27734.000832] ca acme-setup-start[220]: + test -e ca/key.pem323ca # [27734.000832] ca acme-setup-start[220]: + minica --ca-key ca/key.pem --ca-cert ca/cert.pem --domains selfsigned.local324ca # [27734.020961] ca systemd[1]: Finished Set up the ACME certificate renewal infrastructure.325ca # [27734.022952] ca systemd[1]: Starting Ensure certificate for ca.foo...326ca # [27734.124358] ca step-ca[205]: badger 2026/09/04 15:09:14 INFO: All 0 tables opened in 0s327ca # [27734.129431] ca step-ca[205]: 2026/09/04 15:09:14 Building new tls configuration using step-ca x509 Signer Interface328ca # [27734.135499] ca step-ca[205]: 2026/09/04 15:09:14 Starting Smallstep CA/0.30.2 (linux/arm64)329ca # [27734.135499] ca step-ca[205]: 2026/09/04 15:09:14 Documentation: https://u.step.sm/docs/ca330ca # [27734.135499] ca step-ca[205]: 2026/09/04 15:09:14 Community Discord: https://u.step.sm/discord331ca # [27734.135499] ca step-ca[205]: 2026/09/04 15:09:14 Config file: /etc/smallstep/ca.json332ca # [27734.135499] ca step-ca[205]: 2026/09/04 15:09:14 The primary server URL is https://ca.foo:1443333ca # [27734.135624] ca step-ca[205]: 2026/09/04 15:09:14 Root certificates are available at https://ca.foo:1443/roots.pem334ca # [27734.135624] ca step-ca[205]: 2026/09/04 15:09:14 X.509 Root Fingerprint: f0dc1d5e5cc59b71e44fb3e5996d4e2d60a72d6859cf66e500ddfeb7389647da335ca # [27734.136146] ca systemd[1]: Started step-ca service.336ca # [27734.136277] ca step-ca[205]: 2026/09/04 15:09:14 Serving HTTPS on 0.0.0.0:1443 ...337ca: must succeed: systemctl restart acme-order-renew-ca.foo.service 338server # [27734.464240] server systemd-networkd[187]: eth1: Gained IPv6LL339server # [27734.546387] server acme-test.foo-start[245]: Waiting to acquire lock in /run/acme/340server # [27734.550037] server acme-test.foo-start[245]: + '[' -e out/acme-success ']'341server # [27734.550037] server acme-test.foo-start[245]: + minica --ca-cert=ca/cert.pem --ca-key=ca/key.pem --domains=test.foo --ip-addresses=342server # [27734.565871] server acme-test.foo-start[254]: + cd test.foo343server # [27734.566152] server acme-test.foo-start[254]: + cp -vp cert.pem ../out/cert.pem344server # [27734.567376] server acme-test.foo-start[255]: 'cert.pem' -> '../out/cert.pem'345server # [27734.567663] server acme-test.foo-start[254]: + cp -vp key.pem ../out/key.pem346server # [27734.568935] server acme-test.foo-start[254]: 'key.pem' -> '../out/key.pem'347server # [27734.569189] server acme-test.foo-start[245]: + cat out/cert.pem ca/cert.pem348server # [27734.570953] server acme-test.foo-start[245]: + cp ca/cert.pem out/chain.pem349server # [27734.572267] server acme-test.foo-start[245]: + cat out/key.pem out/fullchain.pem350server # [27734.574202] server acme-test.foo-start[245]: + for fixpath in out certificates351server # [27734.574202] server acme-test.foo-start[245]: + '[' -d out ']'352server # [27734.574322] server acme-test.foo-start[245]: + chmod -R u=rwX,g=rX,o= out353server # [27734.575924] server acme-test.foo-start[245]: + chown -R acme:nginx out354server # [27734.578895] server acme-test.foo-start[245]: + for fixpath in out certificates355server # [27734.578950] server acme-test.foo-start[245]: + '[' -d certificates ']'356server # [27734.582518] server systemd[1]: Finished Ensure certificate for test.foo.357server # [27734.585231] server systemd[1]: Starting Nginx Web Server...358ca # [27734.368199] ca systemd-networkd[197]: eth1: Gained IPv6LL359ca # [27734.555797] ca acme-ca.foo-start[262]: Waiting to acquire lock in /run/acme/360ca # [27734.559316] ca acme-ca.foo-start[262]: + '[' -e out/acme-success ']'361ca # [27734.559316] ca acme-ca.foo-start[262]: + minica --ca-cert=ca/cert.pem --ca-key=ca/key.pem --domains=ca.foo --ip-addresses=362ca # [27734.575258] ca acme-ca.foo-start[294]: + cd ca.foo363ca # [27734.575823] ca acme-ca.foo-start[294]: + cp -vp cert.pem ../out/cert.pem364ca # [27734.576681] ca acme-ca.foo-start[295]: 'cert.pem' -> '../out/cert.pem'365ca # [27734.576973] ca acme-ca.foo-start[294]: + cp -vp key.pem ../out/key.pem366ca # [27734.577974] ca acme-ca.foo-start[294]: 'key.pem' -> '../out/key.pem'367ca # [27734.578161] ca acme-ca.foo-start[262]: + cat out/cert.pem ca/cert.pem368ca # [27734.580006] ca acme-ca.foo-start[262]: + cp ca/cert.pem out/chain.pem369ca # [27734.581377] ca acme-ca.foo-start[262]: + cat out/key.pem out/fullchain.pem370ca # [27734.582794] ca acme-ca.foo-start[262]: + for fixpath in out certificates371ca # [27734.582824] ca acme-ca.foo-start[262]: + '[' -d out ']'372ca # [27734.582824] ca acme-ca.foo-start[262]: + chmod -R u=rwX,g=rX,o= out373ca # [27734.584427] ca acme-ca.foo-start[262]: + chown -R acme:nginx out374ca # [27734.587742] ca acme-ca.foo-start[262]: + for fixpath in out certificates375ca # [27734.587784] ca acme-ca.foo-start[262]: + '[' -d certificates ']'376ca # [27734.592259] ca systemd[1]: Finished Ensure certificate for ca.foo.377ca # [27734.594057] ca systemd[1]: Starting Nginx Web Server...378client # [27734.656209] client systemd-networkd[183]: eth1: Gained IPv6LL379ca # [27735.087097] ca nginx-pre-start[306]: nginx: the configuration file /nix/store/jwfw4qdij81lq64xr33fi49z93gggc3p-nginx.conf syntax is ok380ca # [27735.087747] ca nginx-pre-start[306]: nginx: configuration file /nix/store/jwfw4qdij81lq64xr33fi49z93gggc3p-nginx.conf test is successful381ca # [27735.120656] ca systemd[1]: Started Nginx Web Server.382ca # [27735.121428] ca systemd[1]: Reached target Multi-User System.383ca # [27735.123723] ca systemd[1]: Starting Order (and renew) ACME certificate for ca.foo...384server # [27735.077089] server nginx-pre-start[266]: nginx: the configuration file /nix/store/1hcqj3qwgk1l5wg86fhwxyq6ddsn1ggy-nginx.conf syntax is ok385server # [27735.077712] server nginx-pre-start[266]: nginx: configuration file /nix/store/1hcqj3qwgk1l5wg86fhwxyq6ddsn1ggy-nginx.conf test is successful386server # [27735.082654] server systemd[1]: Started Nginx Web Server.387server # [27735.083412] server systemd[1]: Reached target Multi-User System.388server # [27735.085767] server systemd[1]: Starting Order (and renew) ACME certificate for test.foo...389ca # [27735.623845] ca acme-order-renew-ca.foo-start[309]: Waiting to acquire lock in /run/acme/390ca # [27735.627450] ca acme-order-renew-ca.foo-start[309]: + set -euo pipefail391ca # [27735.627530] ca acme-order-renew-ca.foo-start[309]: + echo 88dc4fc401a6091a1bd9392ca # [27735.627647] ca acme-order-renew-ca.foo-start[309]: + cmp -s domainhash.txt certificates/domainhash.txt393ca # [27735.629255] ca acme-order-renew-ca.foo-start[309]: + lego --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 run394ca # [27735.647574] ca acme-order-renew-ca.foo-start[320]: 2026/09/04 15:09:15 No key found for account none@none.tld. Generating a P256 key.395ca # [27735.647889] ca acme-order-renew-ca.foo-start[320]: 2026/09/04 15:09:15 Saved key to accounts/ca.foo_1443/none@none.tld/keys/none@none.tld.key396ca # [27735.672203] ca step-ca[205]: time="2026-09-04T15:09:15Z" level=info duration="172.242µs" duration-ns=172242 fields.time="2026-09-04T15:09:15Z" method=GET name=ca path=/acme/acme/directory protocol=HTTP/1.1 referer= remote-address="2001:db8:1::1" request-id=f0501628-a830-4d0b-bf13-ddc288c844af 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/4.35.2 xenolf-acme/4.35.2 (release; linux; arm64)" user-id=397ca # [27735.672564] ca acme-order-renew-ca.foo-start[320]: 2026/09/04 15:09:15 [INFO] acme: Registering account for none@none.tld398ca # [27735.726332] ca step-ca[205]: time="2026-09-04T15:09:15Z" level=info duration=53.559033ms duration-ns=53559033 fields.time="2026-09-04T15:09:15Z" method=HEAD name=ca nonce=RVQ2aWs4a3YxdUhHSldlSEI1cU5BRngxNXdENEs1Vmg path=/acme/acme/new-nonce protocol=HTTP/1.1 referer= remote-address="2001:db8:1::1" request-id=7c5d4c9e-15cd-4afe-8f18-797f5d26d5a1 size=0 status=200 user-agent="lego-cli/4.35.2 xenolf-acme/4.35.2 (release; linux; arm64)" user-id=399ca # [27735.739899] ca step-ca[205]: time="2026-09-04T15:09:15Z" level=info duration=12.264332ms duration-ns=12264332 fields.time="2026-09-04T15:09:15Z" method=POST name=ca nonce=cWZQZzBwdDFaWU5uelVnYXFtNzc5eDdEMmRvS3pnUUQ path=/acme/acme/new-account protocol=HTTP/1.1 referer= remote-address="2001:db8:1::1" request-id=ea56be2d-6550-4673-8295-f22a18265f2e response="{\"contact\":[\"mailto:none@none.tld\"],\"status\":\"valid\",\"orders\":\"https://ca.foo:1443/acme/acme/account/XZUnOf3mK38ebRAStxf2Y8lRpxgofK2K/orders\"}" size=143 status=201 user-agent="lego-cli/4.35.2 xenolf-acme/4.35.2 (release; linux; arm64)" user-id=400ca # [27735.740406] ca acme-order-renew-ca.foo-start[320]: !!!! HEADS UP !!!!401ca # [27735.740406] ca acme-order-renew-ca.foo-start[320]: Your account credentials have been saved in your402ca # [27735.740406] ca acme-order-renew-ca.foo-start[320]: configuration directory at "accounts".403ca # [27735.740406] ca acme-order-renew-ca.foo-start[320]: You should make a secure backup of this folder now. This404ca # [27735.740406] ca acme-order-renew-ca.foo-start[320]: configuration directory will also contain private keys405ca # [27735.740406] ca acme-order-renew-ca.foo-start[320]: generated by lego and certificates obtained from the ACME406ca # [27735.740406] ca acme-order-renew-ca.foo-start[320]: server. Making regular backups of this folder is ideal.407ca # [27735.740526] ca acme-order-renew-ca.foo-start[320]: 2026/09/04 15:09:15 [INFO] [ca.foo] acme: Obtaining bundled SAN certificate408ca # [27735.746709] ca step-ca[205]: time="2026-09-04T15:09:15Z" level=info duration=5.254394ms duration-ns=5254394 fields.time="2026-09-04T15:09:15Z" method=POST name=ca nonce=c3RhMEVvZ2tIc083cW1mSjcwZkRFSUtsRXVmSnVGZ0w path=/acme/acme/new-order protocol=HTTP/1.1 referer= remote-address="2001:db8:1::1" request-id=d190c1b4-4d08-47d0-b0b9-6829d23b27e4 response="{\"id\":\"U63qXSas76YjObzOIAYLFYYENdYhB3dj\",\"status\":\"pending\",\"expires\":\"2026-09-05T15:09:15Z\",\"identifiers\":[{\"type\":\"dns\",\"value\":\"ca.foo\"}],\"notBefore\":\"2026-09-04T15:08:15Z\",\"notAfter\":\"2026-12-03T15:09:15Z\",\"authorizations\":[\"https://ca.foo:1443/acme/acme/authz/Q6Q6lvYHJwxmb183XIDM62BwCR4Tigsg\"],\"finalize\":\"https://ca.foo:1443/acme/acme/order/U63qXSas76YjObzOIAYLFYYENdYhB3dj/finalize\"}" size=392 status=201 user-agent="lego-cli/4.35.2 xenolf-acme/4.35.2 (release; linux; arm64)" user-id=409ca # [27735.811164] ca step-ca[205]: time="2026-09-04T15:09:15Z" level=info duration=6.271648ms duration-ns=6271648 fields.time="2026-09-04T15:09:15Z" method=POST name=ca nonce=RG5udzFWSU9tNUNkVXBLNW1mOHBGWFhYUkFXTkJHd1A path=/acme/acme/authz/Q6Q6lvYHJwxmb183XIDM62BwCR4Tigsg protocol=HTTP/1.1 referer= remote-address="2001:db8:1::1" request-id=246bc94b-d361-48a7-8098-2209c275fb1e response="{\"identifier\":{\"type\":\"dns\",\"value\":\"ca.foo\"},\"status\":\"pending\",\"challenges\":[{\"type\":\"dns-01\",\"status\":\"pending\",\"token\":\"jguzkJte1Ro8MSs1Z8AvXzplV3mC5rau\",\"url\":\"https://ca.foo:1443/acme/acme/challenge/Q6Q6lvYHJwxmb183XIDM62BwCR4Tigsg/uh3cZFQvPmhXn5FfFTR5WCtZWxplCZiD\"},{\"type\":\"http-01\",\"status\":\"pending\",\"token\":\"jguzkJte1Ro8MSs1Z8AvXzplV3mC5rau\",\"url\":\"https://ca.foo:1443/acme/acme/challenge/Q6Q6lvYHJwxmb183XIDM62BwCR4Tigsg/xEKSEn4XM6sufX3KrBYRMXYvKuSfmoow\"},{\"type\":\"tls-alpn-01\",\"status\":\"pending\",\"token\":\"jguzkJte1Ro8MSs1Z8AvXzplV3mC5rau\",\"url\":\"https://ca.foo:1443/acme/acme/challenge/Q6Q6lvYHJwxmb183XIDM62BwCR4Tigsg/fq68A8KzSAWmQKeHE9Ag31VNKS077Mkq\"}],\"wildcard\":false,\"expires\":\"2026-09-05T15:09:15Z\"}" size=719 status=200 user-agent="lego-cli/4.35.2 xenolf-acme/4.35.2 (release; linux; arm64)" user-id=410ca # [27735.811565] ca acme-order-renew-ca.foo-start[320]: 2026/09/04 15:09:15 [INFO] [ca.foo] AuthURL: https://ca.foo:1443/acme/acme/authz/Q6Q6lvYHJwxmb183XIDM62BwCR4Tigsg411ca # [27735.811565] ca acme-order-renew-ca.foo-start[320]: 2026/09/04 15:09:15 [INFO] [ca.foo] acme: Could not find solver for: tls-alpn-01412ca # [27735.811565] ca acme-order-renew-ca.foo-start[320]: 2026/09/04 15:09:15 [INFO] [ca.foo] acme: use http-01 solver413ca # [27735.811565] ca acme-order-renew-ca.foo-start[320]: 2026/09/04 15:09:15 [INFO] [ca.foo] acme: Trying to solve HTTP-01414ca # [27735.817891] ca step-ca[205]: time="2026-09-04T15:09:15Z" level=info duration=5.424076ms duration-ns=5424076 fields.time="2026-09-04T15:09:15Z" method=POST name=ca nonce=OHJxYkgyWjNqM2hKZnRCM1JPNWNLT0oweWtBTlFKUXY path=/acme/acme/challenge/Q6Q6lvYHJwxmb183XIDM62BwCR4Tigsg/xEKSEn4XM6sufX3KrBYRMXYvKuSfmoow protocol=HTTP/1.1 referer= remote-address="2001:db8:1::1" request-id=2cc98ba8-4ad3-409b-9aea-3077d38535e3 response="{\"type\":\"http-01\",\"status\":\"valid\",\"token\":\"jguzkJte1Ro8MSs1Z8AvXzplV3mC5rau\",\"validated\":\"2026-09-04T15:09:15Z\",\"url\":\"https://ca.foo:1443/acme/acme/challenge/Q6Q6lvYHJwxmb183XIDM62BwCR4Tigsg/xEKSEn4XM6sufX3KrBYRMXYvKuSfmoow\"}" size=228 status=200 user-agent="lego-cli/4.35.2 xenolf-acme/4.35.2 (release; linux; arm64)" user-id=415ca # [27735.818251] ca acme-order-renew-ca.foo-start[320]: 2026/09/04 15:09:15 [INFO] [ca.foo] The server validated our request416ca # [27735.818355] ca acme-order-renew-ca.foo-start[320]: 2026/09/04 15:09:15 [INFO] [ca.foo] acme: Validations succeeded; requesting certificates417ca # [27735.828935] ca step-ca[205]: time="2026-09-04T15:09:15Z" level=info duration=8.871965ms duration-ns=8871965 fields.time="2026-09-04T15:09:15Z" method=POST name=ca nonce=S2Z2UUNOZFJVclNyVWFQMFp0Q0xVVzFBeW1EejA4Rkc path=/acme/acme/order/U63qXSas76YjObzOIAYLFYYENdYhB3dj/finalize protocol=HTTP/1.1 referer= remote-address="2001:db8:1::1" request-id=65edfb7e-e725-45c2-b8f8-f7e68323254d response="{\"id\":\"U63qXSas76YjObzOIAYLFYYENdYhB3dj\",\"status\":\"valid\",\"expires\":\"2026-09-05T15:09:15Z\",\"identifiers\":[{\"type\":\"dns\",\"value\":\"ca.foo\"}],\"notBefore\":\"2026-09-04T15:08:15Z\",\"notAfter\":\"2026-12-03T15:09:15Z\",\"authorizations\":[\"https://ca.foo:1443/acme/acme/authz/Q6Q6lvYHJwxmb183XIDM62BwCR4Tigsg\"],\"finalize\":\"https://ca.foo:1443/acme/acme/order/U63qXSas76YjObzOIAYLFYYENdYhB3dj/finalize\",\"certificate\":\"https://ca.foo:1443/acme/acme/certificate/C31UabbTrX7lV5RjQJE1IEJCqIdDVrTW\"}" size=481 status=200 user-agent="lego-cli/4.35.2 xenolf-acme/4.35.2 (release; linux; arm64)" user-id=418ca # [27735.831689] ca step-ca[205]: time="2026-09-04T15:09:15Z" level=info certificate=MIIB0zCCAXqgAwIBAgIRAPSyRtfviO2ADk1/uDw0mQowCgYIKoZIzj0EAwIwHzEdMBsGA1UEAxMUQ2xhbiBJbnRlcm1lZGlhdGUgQ0EwHhcNMjYwOTA0MTUwODE1WhcNMjYxMjAzMTUwOTE1WjARMQ8wDQYDVQQDEwZjYS5mb28wWTATBgcqhkjOPQIBBggqhkjOPQMBBwNCAARhPgp3eFzgmKxSRXMmardhwoerbZ2amjltiM3obiZbxBAXd8xjZ7jAqfhHpzxoJCM9H8LcBeYYw4sTh4Wqd05zo4GkMIGhMA4GA1UdDwEB/wQEAwIHgDAdBgNVHSUEFjAUBggrBgEFBQcDAQYIKwYBBQUHAwIwHQYDVR0OBBYEFK/zR8l+veokOoGlGnycfVLqH+axMB8GA1UdIwQYMBaAFNFqRJ1BBy/MzAovHdKP6cHdSJ0SMBEGA1UdEQQKMAiCBmNhLmZvbzAdBgwrBgEEAYKkZMYoQAEEDTALAgEGBARhY21lBAAwCgYIKoZIzj0EAwIDRwAwRAIgfXtPUW2eEv7PxbtLCFPE7wYJHD5KurigGpdM3QtmLW0CIDu1QUTSzJrFmrc7KqBGwXNzuYiE+hnMNyl6ICAwWfO9 duration=1.784985ms duration-ns=1784985 fields.time="2026-09-04T15:09:15Z" issuer="Clan Intermediate CA" method=POST name=ca nonce=WkdrWm95UmY1Z0lxcUdXZ25KSmJOTVlqZnpyejBhb0U path=/acme/acme/certificate/C31UabbTrX7lV5RjQJE1IEJCqIdDVrTW protocol=HTTP/1.1 provisioner=acme public-key="ECDSA P-256" referer= remote-address="2001:db8:1::1" request-id=90d8e813-241a-4510-bddb-45a871a74c8b sans="map[dns:[ca.foo]]" serial=325257296689198321312008972827264063754 size=1340 status=200 subject=ca.foo user-agent="lego-cli/4.35.2 xenolf-acme/4.35.2 (release; linux; arm64)" user-id= valid-from="2026-09-04T15:08:15Z" valid-to="2026-12-03T15:09:15Z"419ca # [27735.831999] ca acme-order-renew-ca.foo-start[320]: 2026/09/04 15:09:15 [INFO] [ca.foo] Server responded with a certificate.420ca # [27735.838494] ca acme-order-renew-ca.foo-start[309]: + mv domainhash.txt certificates/421ca # [27735.840664] ca acme-order-renew-ca.foo-start[309]: + touch out/acme-success422ca # [27735.842525] ca acme-order-renew-ca.foo-start[309]: + cmp -s certificates/ca.foo.crt out/fullchain.pem423ca # [27735.843799] ca acme-order-renew-ca.foo-start[309]: + touch out/renewed424ca # [27735.845653] ca acme-order-renew-ca.foo-start[309]: + echo Installing new certificate425ca # [27735.845653] ca acme-order-renew-ca.foo-start[309]: Installing new certificate426ca # [27735.845653] ca acme-order-renew-ca.foo-start[309]: + cp -vp certificates/ca.foo.crt out/fullchain.pem427ca # [27735.847259] ca acme-order-renew-ca.foo-start[353]: 'certificates/ca.foo.crt' -> 'out/fullchain.pem'428ca # [27735.847595] ca acme-order-renew-ca.foo-start[309]: + cp -vp certificates/ca.foo.key out/key.pem429ca # [27735.848993] ca acme-order-renew-ca.foo-start[354]: 'certificates/ca.foo.key' -> 'out/key.pem'430ca # [27735.849288] ca acme-order-renew-ca.foo-start[309]: + cp -vp certificates/ca.foo.issuer.crt out/chain.pem431ca # [27735.850945] ca acme-order-renew-ca.foo-start[355]: 'certificates/ca.foo.issuer.crt' -> 'out/chain.pem'432ca # [27735.851211] ca acme-order-renew-ca.foo-start[309]: + ln -sf fullchain.pem out/cert.pem433ca # [27735.853086] ca acme-order-renew-ca.foo-start[309]: + cat out/key.pem out/fullchain.pem434ca # [27735.854923] ca acme-order-renew-ca.foo-start[309]: + for fixpath in out certificates435ca # [27735.854923] ca acme-order-renew-ca.foo-start[309]: + '[' -d out ']'436ca # [27735.855027] ca acme-order-renew-ca.foo-start[309]: + chmod -R u=rwX,g=rX,o= out437ca # [27735.856520] ca acme-order-renew-ca.foo-start[309]: + chown -R acme:nginx out438ca # [27735.859407] ca acme-order-renew-ca.foo-start[309]: + for fixpath in out certificates439ca # [27735.859459] ca acme-order-renew-ca.foo-start[309]: + '[' -d certificates ']'440ca # [27735.859459] ca acme-order-renew-ca.foo-start[309]: + chmod -R u=rwX,g=rX,o= certificates441ca # [27735.861396] ca acme-order-renew-ca.foo-start[309]: + chown -R acme:nginx certificates442ca # [27735.864253] ca acme-order-renew-ca.foo-start[309]: + chmod -R u=rwX,g=,o= accounts/.443server # [27735.601265] server acme-order-renew-test.foo-start[269]: Waiting to acquire lock in /run/acme/444server # [27735.604936] server acme-order-renew-test.foo-start[269]: + set -euo pipefail445server # [27735.605015] server acme-order-renew-test.foo-start[269]: + echo ad12aa6741ce4bd2c108446server # [27735.605137] server acme-order-renew-test.foo-start[269]: + cmp -s domainhash.txt certificates/domainhash.txt447server # [27735.606803] server acme-order-renew-test.foo-start[269]: + lego --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 run448server # [27735.625559] server acme-order-renew-test.foo-start[280]: 2026/09/04 15:09:15 No key found for account none@none.tld. Generating a P256 key.449server # [27735.625892] server acme-order-renew-test.foo-start[280]: 2026/09/04 15:09:15 Saved key to accounts/ca.foo/none@none.tld/keys/none@none.tld.key450ca # [27736.028810] ca systemd[1]: Reloading Nginx Web Server...451ca # [27736.033104] ca systemd[1]: acme-order-renew-ca.foo.service: Deactivated successfully.452ca # [27736.033312] ca systemd[1]: Finished Order (and renew) ACME certificate for ca.foo.453ca # [27736.511428] ca nginx[371]: nginx: the configuration file /nix/store/jwfw4qdij81lq64xr33fi49z93gggc3p-nginx.conf syntax is ok454ca # [27736.511927] ca nginx[371]: nginx: configuration file /nix/store/jwfw4qdij81lq64xr33fi49z93gggc3p-nginx.conf test is successful455server # [27736.678361] server acme-order-renew-test.foo-start[280]: 2026/09/04 15:09:16 Could not create client: get directory at 'https://ca.foo/acme/acme/directory': Get "https://ca.foo/acme/acme/directory": GET https://ca.foo/acme/acme/directory giving up after 2 attempt(s): Get "https://ca.foo/acme/acme/directory": tls: failed to verify certificate: x509: certificate signed by unknown authority456server # [27736.682745] server acme-order-renew-test.foo-start[269]: + echo Failed to fetch certificates. This may mean your DNS records are set up incorrectly. Self-signed certs are in place and dependant services will still start.457server # [27736.682745] server acme-order-renew-test.foo-start[269]: Failed to fetch certificates. This may mean your DNS records are set up incorrectly. Self-signed certs are in place and dependant services will still start.458server # [27736.683012] server acme-order-renew-test.foo-start[269]: + exit 10459server # [27736.686183] server systemd[1]: acme-order-renew-test.foo.service: Main process exited, code=exited, status=10/n/a460server # [27736.686383] server systemd[1]: acme-order-renew-test.foo.service: Failed with result 'exit-code'.461server # [27736.686775] server systemd[1]: Failed to start Order (and renew) ACME certificate for test.foo.462server # [27736.692108] server systemd[1]: Startup finished in 4.608s.463ca # [27737.024649] ca systemd[1]: Reloaded Nginx Web Server.464ca # [27737.025125] ca systemd[1]: Startup finished in 4.906s.465ca # [27737.249668] ca systemd[1]: Starting Order (and renew) ACME certificate for ca.foo...466ca: (finished: must succeed: systemctl restart acme-order-renew-ca.foo.service , in 3.39 seconds)467ca # [27737.748437] ca acme-order-renew-ca.foo-start[386]: Waiting to acquire lock in /run/acme/468ca # [27737.751262] ca acme-order-renew-ca.foo-start[386]: + set -euo pipefail469ca # [27737.751337] ca acme-order-renew-ca.foo-start[386]: + echo 88dc4fc401a6091a1bd9470ca # [27737.751450] ca acme-order-renew-ca.foo-start[386]: + cmp -s domainhash.txt certificates/domainhash.txt471ca # [27737.753021] ca acme-order-renew-ca.foo-start[386]: + '[' -e certificates/ca.foo.key ']'472ca # [27737.753073] ca acme-order-renew-ca.foo-start[386]: + '[' -e certificates/ca.foo.crt ']'473ca # [27737.753593] ca acme-order-renew-ca.foo-start[394]: ++ find accounts -name none@none.tld.key474ca # [27737.756674] ca acme-order-renew-ca.foo-start[386]: + '[' -n accounts/ca.foo_1443/none@none.tld/keys/none@none.tld.key ']'475ca # [27737.756726] ca acme-order-renew-ca.foo-start[386]: + lego --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 renew --no-random-sleep --dynamic476ca # [27737.803094] ca step-ca[205]: time="2026-09-04T15:09:17Z" level=info duration="52.841µs" duration-ns=52841 fields.time="2026-09-04T15:09:17Z" method=GET name=ca path=/acme/acme/directory protocol=HTTP/1.1 referer= remote-address="2001:db8:1::1" request-id=2ef48e95-7b70-4b84-ac7c-357d5c876e5a 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/4.35.2 xenolf-acme/4.35.2 (release; linux; arm64)" user-id=477ca # [27737.803937] ca acme-order-renew-ca.foo-start[395]: 2026/09/04 15:09:17 [WARN] [ca.foo] acme: renewalInfo[get/post]: server does not advertise a renewal info endpoint478ca # [27737.803937] ca acme-order-renew-ca.foo-start[395]: 2026/09/04 15:09:17 [INFO] [ca.foo] The certificate expires at 2026-12-03T15:09:15Z, the renewal can be performed in 1439h59m37.210737758s: no renewal.479ca # [27737.804186] ca acme-order-renew-ca.foo-start[386]: + mv domainhash.txt certificates/480ca # [27737.806485] ca acme-order-renew-ca.foo-start[386]: + touch out/acme-success481ca # [27737.808301] ca acme-order-renew-ca.foo-start[386]: + cmp -s certificates/ca.foo.crt out/fullchain.pem482ca # [27737.809659] ca acme-order-renew-ca.foo-start[386]: + for fixpath in out certificates483ca # [27737.809659] ca acme-order-renew-ca.foo-start[386]: + '[' -d out ']'484ca # [27737.809747] ca acme-order-renew-ca.foo-start[386]: + chmod -R u=rwX,g=rX,o= out485ca # [27737.811504] ca acme-order-renew-ca.foo-start[386]: + chown -R acme:nginx out486ca # [27737.815162] ca acme-order-renew-ca.foo-start[386]: + for fixpath in out certificates487ca # [27737.815162] ca acme-order-renew-ca.foo-start[386]: + '[' -d certificates ']'488ca # [27737.815255] ca acme-order-renew-ca.foo-start[386]: + chmod -R u=rwX,g=rX,o= certificates489ca # [27737.816928] ca acme-order-renew-ca.foo-start[386]: + chown -R acme:nginx certificates490ca # [27737.819822] ca acme-order-renew-ca.foo-start[386]: + chmod -R u=rwX,g=,o= accounts/.491ca # [27737.969198] ca systemd[1]: acme-order-renew-ca.foo.service: Deactivated successfully.492ca # [27737.969555] ca systemd[1]: Finished Order (and renew) ACME certificate for ca.foo.493server: must succeed: systemctl restart acme-test.foo.service494server # [27740.992755] server systemd[1]: acme-test.foo.service: Deactivated successfully.495server # [27740.993130] server systemd[1]: Stopped Ensure certificate for test.foo.496server # [27740.994386] server systemd[1]: Stopping Ensure certificate for test.foo...497server # [27740.996780] server systemd[1]: Starting Ensure certificate for test.foo...498server: (finished: must succeed: systemctl restart acme-test.foo.service, in 0.55 seconds)499client: waiting for success: curl -v https://test.foo500* Trying [2001:db8:1::3]:443...501* Host test.foo:443 was resolved.502* IPv6: 2001:db8:1::3503* IPv4: 192.168.1.3504* ALPN: curl offers h2,http/1.1505} [5 bytes data]506* TLSv1.3 (OUT), TLS handshake, Client hello (1):507} [1552 bytes data]508* SSL Trust Anchors:509* OpenSSL default paths (fallback)510{ [5 bytes data]511* TLSv1.3 (IN), TLS handshake, Server hello (2):512{ [1210 bytes data]513* TLSv1.3 (IN), TLS change cipher, Change cipher spec (1):514{ [1 bytes data]515* TLSv1.3 (IN), TLS handshake, Encrypted Extensions (8):516{ [19 bytes data]517* TLSv1.3 (IN), TLS handshake, Certificate (11):518{ [1008 bytes data]519* TLSv1.3 (IN), TLS handshake, CERT verify (15):520{ [110 bytes data]521* TLSv1.3 (IN), TLS handshake, Finished (20):522{ [52 bytes data]523* TLSv1.3 (OUT), TLS change cipher, Change cipher spec (1):524} [1 bytes data]525* TLSv1.3 (OUT), TLS handshake, Finished (20):526} [52 bytes data]527* SSL connection using TLSv1.3 / TLS_AES_256_GCM_SHA384 / X25519MLKEM768 / id-ecPublicKey528* ALPN: server accepted h2529* Server certificate:530* subject: CN=test.foo531* start date: Sep 4 15:09:14 2026 GMT532* expire date: Oct 4 15:09:14 2028 GMT533* issuer: CN=minica root ca 29729b534* Certificate level 0: Public key type EC/secp384r1 (384/192 Bits/secBits), signed using ecdsa-with-SHA384535* Certificate level 1: Public key type EC/secp384r1 (384/192 Bits/secBits), signed using ecdsa-with-SHA384536* subjectAltName: "test.foo" matches cert's "test.foo"537* OpenSSL verify result: 13538* SSL certificate OpenSSL verify result: self-signed certificate in certificate chain (19)539* closing connection #0540curl: (60) SSL certificate OpenSSL verify result: self-signed certificate in certificate chain (19)541More details here: https://curl.se/docs/sslcerts.html542543curl failed to verify the legitimacy of the server and therefore could not544establish a secure connection to it. To learn more about this situation and545how to fix it, please visit the webpage mentioned above.546server # [27741.490161] server acme-test.foo-start[314]: Waiting to acquire lock in /run/acme/547server # [27741.493385] server acme-test.foo-start[314]: + '[' -e out/acme-success ']'548server # [27741.493385] server acme-test.foo-start[314]: + minica --ca-cert=ca/cert.pem --ca-key=ca/key.pem --domains=test.foo --ip-addresses=549server # [27741.508345] server acme-test.foo-start[324]: + cd test.foo550server # [27741.508801] server acme-test.foo-start[324]: + cp -vp cert.pem ../out/cert.pem551server # [27741.510108] server acme-test.foo-start[325]: 'cert.pem' -> '../out/cert.pem'552server # [27741.510419] server acme-test.foo-start[324]: + cp -vp key.pem ../out/key.pem553server # [27741.511824] server acme-test.foo-start[324]: 'key.pem' -> '../out/key.pem'554server # [27741.512090] server acme-test.foo-start[314]: + cat out/cert.pem ca/cert.pem555server # [27741.513901] server acme-test.foo-start[314]: + cp ca/cert.pem out/chain.pem556server # [27741.515541] server acme-test.foo-start[314]: + cat out/key.pem out/fullchain.pem557server # [27741.517390] server acme-test.foo-start[314]: + for fixpath in out certificates558server # [27741.517390] server acme-test.foo-start[314]: + '[' -d out ']'559server # [27741.517484] server acme-test.foo-start[314]: + chmod -R u=rwX,g=rX,o= out560server # [27741.519141] server acme-test.foo-start[314]: + chown -R acme:nginx out561server # [27741.522223] server acme-test.foo-start[314]: + for fixpath in out certificates562server # [27741.522316] server acme-test.foo-start[314]: + '[' -d certificates ']'563server # [27741.525776] server systemd[1]: Finished Ensure certificate for test.foo.564server # [27741.530381] server systemd[1]: Starting Order (and renew) ACME certificate for test.foo...565server # [27742.032096] server acme-order-renew-test.foo-start[332]: Waiting to acquire lock in /run/acme/566server # [27742.035541] server acme-order-renew-test.foo-start[332]: + set -euo pipefail567server # [27742.035635] server acme-order-renew-test.foo-start[332]: + echo ad12aa6741ce4bd2c108568server # [27742.035728] server acme-order-renew-test.foo-start[332]: + cmp -s domainhash.txt certificates/domainhash.txt569server # [27742.037030] server acme-order-renew-test.foo-start[332]: + lego --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 run570server # [27742.092269] server acme-order-renew-test.foo-start[340]: 2026/09/04 15:09:22 [INFO] acme: Registering account for none@none.tld571server # [27742.209008] server acme-order-renew-test.foo-start[340]: !!!! HEADS UP !!!!572server # [27742.209008] server acme-order-renew-test.foo-start[340]: Your account credentials have been saved in your573server # [27742.209008] server acme-order-renew-test.foo-start[340]: configuration directory at "accounts".574server # [27742.209008] server acme-order-renew-test.foo-start[340]: You should make a secure backup of this folder now. This575server # [27742.209008] server acme-order-renew-test.foo-start[340]: configuration directory will also contain private keys576server # [27742.209008] server acme-order-renew-test.foo-start[340]: generated by lego and certificates obtained from the ACME577server # [27742.209008] server acme-order-renew-test.foo-start[340]: server. Making regular backups of this folder is ideal.578server # [27742.209368] server acme-order-renew-test.foo-start[340]: 2026/09/04 15:09:22 [INFO] [test.foo] acme: Obtaining bundled SAN certificate579server # [27742.280670] server acme-order-renew-test.foo-start[340]: 2026/09/04 15:09:22 [INFO] [test.foo] AuthURL: https://ca.foo/acme/acme/authz/wsmzM8E4FXvcs2Q07w63pbiYgIbDzjzV580server # [27742.280670] server acme-order-renew-test.foo-start[340]: 2026/09/04 15:09:22 [INFO] [test.foo] acme: Could not find solver for: tls-alpn-01581server # [27742.280670] server acme-order-renew-test.foo-start[340]: 2026/09/04 15:09:22 [INFO] [test.foo] acme: use http-01 solver582server # [27742.280670] server acme-order-renew-test.foo-start[340]: 2026/09/04 15:09:22 [INFO] [test.foo] acme: Trying to solve HTTP-01583server # [27742.289965] server acme-order-renew-test.foo-start[340]: 2026/09/04 15:09:22 [INFO] [test.foo] The server validated our request584server # [27742.290038] server acme-order-renew-test.foo-start[340]: 2026/09/04 15:09:22 [INFO] [test.foo] acme: Validations succeeded; requesting certificates585ca # [27742.091675] ca step-ca[205]: time="2026-09-04T15:09:22Z" level=info duration="61.121µs" duration-ns=61121 fields.time="2026-09-04T15:09:22Z" method=GET name=ca path=/acme/acme/directory protocol=HTTP/1.1 referer= remote-address="::1" request-id=520e6999-600d-4ae6-beb1-671f1da3f397 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/4.35.2 xenolf-acme/4.35.2 (release; linux; arm64)" user-id=586ca # [27742.201217] ca step-ca[205]: time="2026-09-04T15:09:22Z" level=info duration=104.948435ms duration-ns=104948435 fields.time="2026-09-04T15:09:22Z" method=HEAD name=ca nonce=dnRpaGhOZTBYZ0ZDTGZnOUR4ank5cGdoaHhvTUExQzM path=/acme/acme/new-nonce protocol=HTTP/1.1 referer= remote-address=127.0.0.1 request-id=c8f2bc39-f9a8-48dc-addf-4903e11ceec0 size=0 status=200 user-agent="lego-cli/4.35.2 xenolf-acme/4.35.2 (release; linux; arm64)" user-id=587ca # [27742.208158] ca step-ca[205]: time="2026-09-04T15:09:22Z" level=info duration=2.412114ms duration-ns=2412114 fields.time="2026-09-04T15:09:22Z" method=POST name=ca nonce=eVhQVUdJb0hXdGRDOEVpWnJxOGhacDE5UlZKOGIyT1Y path=/acme/acme/new-account protocol=HTTP/1.1 referer= remote-address="::1" request-id=d7841407-5480-4052-bcd6-0665623b0bcb response="{\"contact\":[\"mailto:none@none.tld\"],\"status\":\"valid\",\"orders\":\"https://ca.foo/acme/acme/account/vpSJ6mgGivXKDhKonz0pw2Vk9w9HZGpY/orders\"}" size=138 status=201 user-agent="lego-cli/4.35.2 xenolf-acme/4.35.2 (release; linux; arm64)" user-id=588ca # [27742.217327] ca step-ca[205]: time="2026-09-04T15:09:22Z" level=info duration=4.22266ms duration-ns=4222660 fields.time="2026-09-04T15:09:22Z" method=POST name=ca nonce=VVo4WXpIdkt4dGRmZ1kwVGc4NEJaV1QwQnFscGpMWXY path=/acme/acme/new-order protocol=HTTP/1.1 referer= remote-address=127.0.0.1 request-id=e25e1163-ab06-40fe-b3cc-037dc43cd538 response="{\"id\":\"7q2ErtaHMuUQ4EHF5GKauf1NmsRXG1iu\",\"status\":\"pending\",\"expires\":\"2026-09-05T15:09:22Z\",\"identifiers\":[{\"type\":\"dns\",\"value\":\"test.foo\"}],\"notBefore\":\"2026-09-04T15:08:22Z\",\"notAfter\":\"2026-12-03T15:09:22Z\",\"authorizations\":[\"https://ca.foo/acme/acme/authz/wsmzM8E4FXvcs2Q07w63pbiYgIbDzjzV\"],\"finalize\":\"https://ca.foo/acme/acme/order/7q2ErtaHMuUQ4EHF5GKauf1NmsRXG1iu/finalize\"}" size=384 status=201 user-agent="lego-cli/4.35.2 xenolf-acme/4.35.2 (release; linux; arm64)" user-id=589ca # [27742.280039] ca step-ca[205]: time="2026-09-04T15:09:22Z" level=info duration=1.947267ms duration-ns=1947267 fields.time="2026-09-04T15:09:22Z" method=POST name=ca nonce=cjhDOUlrUHpYMUxGcmwwc0I2NnY3RlFJdmR1ZUFqY2U path=/acme/acme/authz/wsmzM8E4FXvcs2Q07w63pbiYgIbDzjzV protocol=HTTP/1.1 referer= remote-address="::1" request-id=8fe8c724-8d26-4c75-92a2-4885f53efdf5 response="{\"identifier\":{\"type\":\"dns\",\"value\":\"test.foo\"},\"status\":\"pending\",\"challenges\":[{\"type\":\"dns-01\",\"status\":\"pending\",\"token\":\"W5s9LXcUccLnHbQF1tl6OXJxVOUgSbFx\",\"url\":\"https://ca.foo/acme/acme/challenge/wsmzM8E4FXvcs2Q07w63pbiYgIbDzjzV/ZI8EJmomhg0H5dVhT7FgAPvRK8024JMY\"},{\"type\":\"http-01\",\"status\":\"pending\",\"token\":\"W5s9LXcUccLnHbQF1tl6OXJxVOUgSbFx\",\"url\":\"https://ca.foo/acme/acme/challenge/wsmzM8E4FXvcs2Q07w63pbiYgIbDzjzV/lpXNSQcTh8XXLkZneoA371RP9mQHxDcb\"},{\"type\":\"tls-alpn-01\",\"status\":\"pending\",\"token\":\"W5s9LXcUccLnHbQF1tl6OXJxVOUgSbFx\",\"url\":\"https://ca.foo/acme/acme/challenge/wsmzM8E4FXvcs2Q07w63pbiYgIbDzjzV/5wJLxzLP7fVS7n5AmCANdfRFPmzRDuit\"}],\"wildcard\":false,\"expires\":\"2026-09-05T15:09:22Z\"}" size=706 status=200 user-agent="lego-cli/4.35.2 xenolf-acme/4.35.2 (release; linux; arm64)" user-id=590ca # [27742.289297] ca step-ca[205]: time="2026-09-04T15:09:22Z" level=info duration=4.550304ms duration-ns=4550304 fields.time="2026-09-04T15:09:22Z" method=POST name=ca nonce=T0JKeDRJNUtoVHk4aXdrdFYzRFlYVmdCY29na3RSVFc path=/acme/acme/challenge/wsmzM8E4FXvcs2Q07w63pbiYgIbDzjzV/lpXNSQcTh8XXLkZneoA371RP9mQHxDcb protocol=HTTP/1.1 referer= remote-address=127.0.0.1 request-id=45b88d3c-41f7-4b26-ad0c-1e937e2ecf78 response="{\"type\":\"http-01\",\"status\":\"valid\",\"token\":\"W5s9LXcUccLnHbQF1tl6OXJxVOUgSbFx\",\"validated\":\"2026-09-04T15:09:22Z\",\"url\":\"https://ca.foo/acme/acme/challenge/wsmzM8E4FXvcs2Q07w63pbiYgIbDzjzV/lpXNSQcTh8XXLkZneoA371RP9mQHxDcb\"}" size=223 status=200 user-agent="lego-cli/4.35.2 xenolf-acme/4.35.2 (release; linux; arm64)" user-id=591ca # [27742.303603] ca step-ca[205]: time="2026-09-04T15:09:22Z" level=info duration=8.647762ms duration-ns=8647762 fields.time="2026-09-04T15:09:22Z" method=POST name=ca nonce=ek9KdXhXaVpaUU1oMGFkMzFoZXh6SmoyODVOVjJqUzk path=/acme/acme/order/7q2ErtaHMuUQ4EHF5GKauf1NmsRXG1iu/finalize protocol=HTTP/1.1 referer= remote-address="::1" request-id=59485e2d-caba-420e-b11f-91130febfdaf response="{\"id\":\"7q2ErtaHMuUQ4EHF5GKauf1NmsRXG1iu\",\"status\":\"valid\",\"expires\":\"2026-09-05T15:09:22Z\",\"identifiers\":[{\"type\":\"dns\",\"value\":\"test.foo\"}],\"notBefore\":\"2026-09-04T15:08:22Z\",\"notAfter\":\"2026-12-03T15:09:22Z\",\"authorizations\":[\"https://ca.foo/acme/acme/authz/wsmzM8E4FXvcs2Q07w63pbiYgIbDzjzV\"],\"finalize\":\"https://ca.foo/acme/acme/order/7q2ErtaHMuUQ4EHF5GKauf1NmsRXG1iu/finalize\",\"certificate\":\"https://ca.foo/acme/acme/certificate/7Mx0G5G9nBf6PYnNLBmaeq5oJ1vG3YoE\"}" size=468 status=200 user-agent="lego-cli/4.35.2 xenolf-acme/4.35.2 (release; linux; arm64)" user-id=592ca # [27742.310158] ca step-ca[205]: time="2026-09-04T15:09:22Z" level=info certificate=MIIB1jCCAX2gAwIBAgIQTY9Bt5L4NhEbs4iM8vVNyjAKBggqhkjOPQQDAjAfMR0wGwYDVQQDExRDbGFuIEludGVybWVkaWF0ZSBDQTAeFw0yNjA5MDQxNTA4MjJaFw0yNjEyMDMxNTA5MjJaMBMxETAPBgNVBAMTCHRlc3QuZm9vMFkwEwYHKoZIzj0CAQYIKoZIzj0DAQcDQgAE49lh6uG7jTyHDvCtWgKaiHWfAIKL9U7UWiwD4GQgy8nd0Y+IsQDpSFJd/ulO+S94gl03Wfl//Rv49LKASo9xKqOBpjCBozAOBgNVHQ8BAf8EBAMCB4AwHQYDVR0lBBYwFAYIKwYBBQUHAwEGCCsGAQUFBwMCMB0GA1UdDgQWBBRTr79qSzOeFD0246wzvrWvzkjzQDAfBgNVHSMEGDAWgBTRakSdQQcvzMwKLx3Sj+nB3UidEjATBgNVHREEDDAKggh0ZXN0LmZvbzAdBgwrBgEEAYKkZMYoQAEEDTALAgEGBARhY21lBAAwCgYIKoZIzj0EAwIDRwAwRAIgX8l/vAA0MWb/LgXLGeBnis2WhBDEI1Mpf06sxUBlBYICIHIYDmSfpXJzB+X2Iy5+lJVyEXX5B/E4r/MQX24nfjbX duration=2.08155ms duration-ns=2081550 fields.time="2026-09-04T15:09:22Z" issuer="Clan Intermediate CA" method=POST name=ca nonce=SWtmVUZ6YzM5ZjVxOXhHeWZSTGxEVHdQYkJXemR4VW0 path=/acme/acme/certificate/7Mx0G5G9nBf6PYnNLBmaeq5oJ1vG3YoE protocol=HTTP/1.1 provisioner=acme public-key="ECDSA P-256" referer= remote-address=127.0.0.1 request-id=5e97a9f8-a583-4b14-bef1-896637d2de5a sans="map[dns:[test.foo]]" serial=103094387027071860393985962288398814666 size=1344 status=200 subject=test.foo user-agent="lego-cli/4.35.2 xenolf-acme/4.35.2 (release; linux; arm64)" user-id= valid-from="2026-09-04T15:08:22Z" valid-to="2026-12-03T15:09:22Z"593server # [27742.310714] server acme-order-renew-test.foo-start[340]: 2026/09/04 15:09:22 [INFO] [test.foo] Server responded with a certificate.594server # [27742.315801] server acme-order-renew-test.foo-start[332]: + mv domainhash.txt certificates/595server # [27742.318067] server acme-order-renew-test.foo-start[332]: + touch out/acme-success596server # [27742.319942] server acme-order-renew-test.foo-start[332]: + cmp -s certificates/test.foo.crt out/fullchain.pem597server # [27742.321133] server acme-order-renew-test.foo-start[332]: + touch out/renewed598server # [27742.322871] server acme-order-renew-test.foo-start[332]: + echo Installing new certificate599server # [27742.322871] server acme-order-renew-test.foo-start[332]: Installing new certificate600server # [27742.322871] server acme-order-renew-test.foo-start[332]: + cp -vp certificates/test.foo.crt out/fullchain.pem601server # [27742.324400] server acme-order-renew-test.foo-start[372]: 'certificates/test.foo.crt' -> 'out/fullchain.pem'602server # [27742.324764] server acme-order-renew-test.foo-start[332]: + cp -vp certificates/test.foo.key out/key.pem603server # [27742.326217] server acme-order-renew-test.foo-start[373]: 'certificates/test.foo.key' -> 'out/key.pem'604server # [27742.326524] server acme-order-renew-test.foo-start[332]: + cp -vp certificates/test.foo.issuer.crt out/chain.pem605server # [27742.327953] server acme-order-renew-test.foo-start[374]: 'certificates/test.foo.issuer.crt' -> 'out/chain.pem'606server # [27742.328292] server acme-order-renew-test.foo-start[332]: + ln -sf fullchain.pem out/cert.pem607server # [27742.329931] server acme-order-renew-test.foo-start[332]: + cat out/key.pem out/fullchain.pem608server # [27742.332179] server acme-order-renew-test.foo-start[332]: + for fixpath in out certificates609server # [27742.332179] server acme-order-renew-test.foo-start[332]: + '[' -d out ']'610server # [27742.332274] server acme-order-renew-test.foo-start[332]: + chmod -R u=rwX,g=rX,o= out611server # [27742.333837] server acme-order-renew-test.foo-start[332]: + chown -R acme:nginx out612server # [27742.336864] server acme-order-renew-test.foo-start[332]: + for fixpath in out certificates613server # [27742.336864] server acme-order-renew-test.foo-start[332]: + '[' -d certificates ']'614server # [27742.337002] server acme-order-renew-test.foo-start[332]: + chmod -R u=rwX,g=rX,o= certificates615server # [27742.338701] server acme-order-renew-test.foo-start[332]: + chown -R acme:nginx certificates616server # [27742.342309] server acme-order-renew-test.foo-start[332]: + chmod -R u=rwX,g=,o= accounts/.617server # [27742.485854] server systemd[1]: Reloading Nginx Web Server...618server # [27742.490612] server systemd[1]: acme-order-renew-test.foo.service: Deactivated successfully.619server # [27742.490930] server systemd[1]: Finished Order (and renew) ACME certificate for test.foo.620* Host test.foo:443 was resolved.621* IPv6: 2001:db8:1::3622* IPv4: 192.168.1.3623* Trying [2001:db8:1::3]:443...624* ALPN: curl offers h2,http/1.1625} [5 bytes data]626* TLSv1.3 (OUT), TLS handshake, Client hello (1):627} [1552 bytes data]628* SSL Trust Anchors:629* OpenSSL default paths (fallback)630{ [5 bytes data]631* TLSv1.3 (IN), TLS handshake, Server hello (2):632{ [1210 bytes data]633* TLSv1.3 (IN), TLS change cipher, Change cipher spec (1):634{ [1 bytes data]635* TLSv1.3 (IN), TLS handshake, Encrypted Extensions (8):636{ [19 bytes data]637* TLSv1.3 (IN), TLS handshake, Certificate (11):638{ [1008 bytes data]639* TLSv1.3 (IN), TLS handshake, CERT verify (15):640{ [111 bytes data]641* TLSv1.3 (IN), TLS handshake, Finished (20):642{ [52 bytes data]643* TLSv1.3 (OUT), TLS change cipher, Change cipher spec (1):644} [1 bytes data]645* TLSv1.3 (OUT), TLS handshake, Finished (20):646} [52 bytes data]647* SSL connection using TLSv1.3 / TLS_AES_256_GCM_SHA384 / X25519MLKEM768 / id-ecPublicKey648* ALPN: server accepted h2649* Server certificate:650* subject: CN=test.foo651* start date: Sep 4 15:09:14 2026 GMT652* expire date: Oct 4 15:09:14 2028 GMT653* issuer: CN=minica root ca 29729b654* Certificate level 0: Public key type EC/secp384r1 (384/192 Bits/secBits), signed using ecdsa-with-SHA384655* Certificate level 1: Public key type EC/secp384r1 (384/192 Bits/secBits), signed using ecdsa-with-SHA384656* subjectAltName: "test.foo" matches cert's "test.foo"657* OpenSSL verify result: 13658* SSL certificate OpenSSL verify result: self-signed certificate in certificate chain (19)659* closing connection #0660curl: (60) SSL certificate OpenSSL verify result: self-signed certificate in certificate chain (19)661More details here: https://curl.se/docs/sslcerts.html662663curl failed to verify the legitimacy of the server and therefore could not664establish a secure connection to it. To learn more about this situation and665how to fix it, please visit the webpage mentioned above.666server # [27743.015968] server nginx[390]: nginx: the configuration file /nix/store/1hcqj3qwgk1l5wg86fhwxyq6ddsn1ggy-nginx.conf syntax is ok667server # [27743.016485] server nginx[390]: nginx: configuration file /nix/store/1hcqj3qwgk1l5wg86fhwxyq6ddsn1ggy-nginx.conf test is successful668* Host test.foo:443 was resolved.669* IPv6: 2001:db8:1::3670* IPv4: 192.168.1.3671* Trying [2001:db8:1::3]:443...672* ALPN: curl offers h2,http/1.1673} [5 bytes data]674* TLSv1.3 (OUT), TLS handshake, Client hello (1):675} [1552 bytes data]676* SSL Trust Anchors:677* OpenSSL default paths (fallback)678{ [5 bytes data]679* TLSv1.3 (IN), TLS handshake, Server hello (2):680{ [1210 bytes data]681* TLSv1.3 (IN), TLS change cipher, Change cipher spec (1):682{ [1 bytes data]683* TLSv1.3 (IN), TLS handshake, Encrypted Extensions (8):684{ [19 bytes data]685* TLSv1.3 (IN), TLS handshake, Certificate (11):686{ [930 bytes data]687* TLSv1.3 (IN), TLS handshake, CERT verify (15):688{ [80 bytes data]689* TLSv1.3 (IN), TLS handshake, Finished (20):690{ [52 bytes data]691* TLSv1.3 (OUT), TLS change cipher, Change cipher spec (1):692} [1 bytes data]693* TLSv1.3 (OUT), TLS handshake, Finished (20):694} [52 bytes data]695* SSL connection using TLSv1.3 / TLS_AES_256_GCM_SHA384 / X25519MLKEM768 / id-ecPublicKey696* ALPN: server accepted h2697* Server certificate:698* subject: CN=test.foo699* start date: Sep 4 15:08:22 2026 GMT700* expire date: Dec 3 15:09:22 2026 GMT701* issuer: CN=Clan Intermediate CA702* Certificate level 0: Public key type EC/prime256v1 (256/128 Bits/secBits), signed using ecdsa-with-SHA256703* Certificate level 1: Public key type EC/prime256v1 (256/128 Bits/secBits), signed using ecdsa-with-SHA256704* Certificate level 2: Public key type EC/prime256v1 (256/128 Bits/secBits), signed using ecdsa-with-SHA256705* subjectAltName: "test.foo" matches cert's "test.foo"706* OpenSSL verify result: 0707* SSL certificate verified via OpenSSL.708* Established connection to test.foo (2001:db8:1::3 port 443) from 2001:db8:1::2 port 56148 709 % Total % Received % Xferd Average Speed Time Time Time Current710 Dload Upload Total Spent Left Speed711 0 0 0 0 0 0 0 0 0* using HTTP/2712* [HTTP/2] [1] OPENED stream for https://test.foo/713* [HTTP/2] [1] [:method: GET]714* [HTTP/2] [1] [:scheme: https]715* [HTTP/2] [1] [:authority: test.foo]716* [HTTP/2] [1] [:path: /]717* [HTTP/2] [1] [user-agent: curl/8.21.0]718* [HTTP/2] [1] [accept: */*]719} [5 bytes data]720721722723724725* Request completely sent off726{ [5 bytes data]727* TLSv1.3 (IN), TLS handshake, Newsession Ticket (4):728{ [265 bytes data]729* TLSv1.3 (IN), TLS handshake, Newsession Ticket (4):730{ [265 bytes data]731732733734735736737738{ [5 bytes data]739100 20 100 20 0 0 653 0 0740* Connection #0 to host test.foo:443 left intact741client: (finished: waiting for success: curl -v https://test.foo, in 2.16 seconds)742client: must succeed: openssl s_client -connect test.foo:443 -servername test.foo </dev/null 2>/dev/null | openssl x509 -text -noout 1>&2743Certificate:744 Data:745 Version: 3 (0x2)746 Serial Number:747 4d:8f:41:b7:92:f8:36:11:1b:b3:88:8c:f2:f5:4d:ca748 Signature Algorithm: ecdsa-with-SHA256749 Issuer: CN=Clan Intermediate CA750 Validity751 Not Before: Sep 4 15:08:22 2026 GMT752 Not After : Dec 3 15:09:22 2026 GMT753 Subject: CN=test.foo754 Subject Public Key Info:755 Public Key Algorithm: id-ecPublicKey756 Public-Key: (256 bit)757 pub:758 04:e3:d9:61:ea:e1:bb:8d:3c:87:0e:f0:ad:5a:02:759 9a:88:75:9f:00:82:8b:f5:4e:d4:5a:2c:03:e0:64:760 20:cb:c9:dd:d1:8f:88:b1:00:e9:48:52:5d:fe:e9:761 4e:f9:2f:78:82:5d:37:59:f9:7f:fd:1b:f8:f4:b2:762 80:4a:8f:71:2a763 ASN1 OID: prime256v1764 NIST CURVE: P-256765 X509v3 extensions:766 X509v3 Key Usage: critical767 Digital Signature768 X509v3 Extended Key Usage: 769 TLS Web Server Authentication, TLS Web Client Authentication770 X509v3 Subject Key Identifier: 771 53:AF:BF:6A:4B:33:9E:14:3D:36:E3:AC:33:BE:B5:AF:CE:48:F3:40772 X509v3 Authority Key Identifier: 773 D1:6A:44:9D:41:07:2F:CC:CC:0A:2F:1D:D2:8F:E9:C1:DD:48:9D:12774 X509v3 Subject Alternative Name: 775 DNS:test.foo776 1.3.6.1.4.1.37476.9000.64.1: 777 0......acme..778 Signature Algorithm: ecdsa-with-SHA256779 Signature Value:780 30:44:02:20:5f:c9:7f:bc:00:34:31:66:ff:2e:05:cb:19:e0:781 67:8a:cd:96:84:10:c4:23:53:29:7f:4e:ac:c5:40:65:05:82:782 02:20:72:18:0e:64:9f:a5:72:73:07:e5:f6:23:2e:7e:94:95:783 72:11:75:f9:07:f1:38:af:f3:10:5f:6e:27:7e:36:d7784client: (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)785(finished: run the VM test script, in 12.14 seconds)786server # [27743.526171] server systemd[1]: Reloaded Nginx Web Server.787test script finished in 12.37s788cleanup789kill NspawnMachine (pid 52)790kill NspawnMachine (pid 55)791Container ca terminated by signal KILL.792kill NspawnMachine (pid 57)793Container client terminated by signal KILL.794Container server terminated by signal KILL.795(finished: cleanup, in 0.54 seconds)