Machine state will be reset. To keep it, pass --keep-machine-state start all VLans (finished: start all VLans, in 0.00 seconds) Test will time out and terminate in 3600.0 seconds run the VM test script additionally exposed symbols: ca, client, server, vlan1, 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_ssh start all VMs server: systemd-nspawn running (pid 54) ca: systemd-nspawn running (pid 53) client: systemd-nspawn running (pid 55) server: Waiting for journal at /build/vm-state-server/var/log/journal... ca: Waiting for journal at /build/vm-state-ca/var/log/journal... client: Waiting for journal at /build/vm-state-client/var/log/journal... (finished: start all VMs, in 0.00 seconds) nixos-nspawn(server): TAP vde-tap1 not found; container will be isolated from VDE nixos-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. nixos-nspawn(client): TAP vde-tap1 not found; container will be isolated from VDE nixos-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. nixos-nspawn(ca): TAP vde-tap1 not found; container will be isolated from VDE nixos-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. Note: 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. Note: 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. Note: 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. ░ Spawning container server on /build/vm-state-server. ░ Spawning container ca on /build/vm-state-ca. ░ Spawning container client on /build/vm-state-client. server # No journal boot entry found for the specified boot (+0). client # No journal boot entry found for the specified boot (+0). ca # No journal boot entry found for the specified boot (+0). server # [6080382.545262] server systemd-journald[69]: Journal started ca # [6080382.557049] ca systemd-journald[78]: Journal started server # [6080382.545317] server systemd-journald[69]: Runtime Journal (/run/log/journal/2685418501c84214b452c2968e64de86) is 8M, max 2.5G, 2.4G free. ca # [6080382.557101] ca systemd-journald[78]: Runtime Journal (/run/log/journal/9633dc39b4bd4992aa24de7183a32b34) is 8M, max 2.5G, 2.4G free. client # [6080382.552625] client systemd-journald[69]: Journal started ca # [6080382.573051] ca systemd[1]: Finished Apply Kernel Variables. client # [6080382.552688] client systemd-journald[69]: Runtime Journal (/run/log/journal/41781df4e9dc4503877605d0254dbb65) is 8M, max 2.5G, 2.4G free. ca # [6080382.584639] ca systemd[1]: Finished Create Static Device Nodes in /dev gracefully. client # [6080382.575929] client systemd[1]: Finished Apply Kernel Variables. ca # [6080382.593379] ca systemd[1]: Starting Flush Journal to Persistent Storage... client # [6080382.583740] client systemd[1]: Finished Create Static Device Nodes in /dev gracefully. ca # [6080382.594154] ca systemd[1]: Starting Network Name Resolution... client # [6080382.592741] client systemd[1]: Starting Flush Journal to Persistent Storage... ca # [6080382.594785] ca systemd[1]: Starting Create Static Device Nodes in /dev... client # [6080382.593531] client systemd[1]: Starting Network Name Resolution... ca # [6080382.602002] ca systemd-journald[78]: Time spent on flushing to /var/log/journal/9633dc39b4bd4992aa24de7183a32b34 is 1.901ms for 7 entries. client # [6080382.594173] client systemd[1]: Starting Create Static Device Nodes in /dev... server # [6080382.566863] server systemd[1]: Finished Apply Kernel Variables. ca # [6080382.602002] ca systemd-journald[78]: System Journal (/var/log/journal/9633dc39b4bd4992aa24de7183a32b34) is 8M, max 4G, 3.9G free. server # [6080382.581331] server systemd[1]: Finished Create Static Device Nodes in /dev gracefully. client # [6080382.601557] client systemd-journald[69]: Time spent on flushing to /var/log/journal/41781df4e9dc4503877605d0254dbb65 is 1.720ms for 7 entries. ca # [6080382.617518] ca systemd[1]: Finished Create Static Device Nodes in /dev. client # [6080382.601557] client systemd-journald[69]: System Journal (/var/log/journal/41781df4e9dc4503877605d0254dbb65) is 8M, max 4G, 3.9G free. ca # [6080382.618229] ca systemd[1]: Reached target Preparation for Local File Systems. client # [6080382.617192] client systemd[1]: Finished Create Static Device Nodes in /dev. ca # [6080382.618337] ca systemd[1]: Reached target Local File Systems. client # [6080382.617929] client systemd[1]: Reached target Preparation for Local File Systems. server # [6080382.590291] server systemd[1]: Starting Flush Journal to Persistent Storage... client # [6080382.618054] client systemd[1]: Reached target Local File Systems. ca # [6080382.619169] ca systemd[1]: Listening on Boot Loader Control Service Socket. client # [6080382.618882] client systemd[1]: Listening on Boot Loader Control Service Socket. ca # [6080382.619223] ca systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container client # [6080382.618927] client systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container ca # [6080382.620296] ca systemd[1]: Starting Save Transient machine-id to Disk... server # [6080382.591149] server systemd[1]: Starting Network Name Resolution... client # [6080382.620014] client systemd[1]: Starting Save Transient machine-id to Disk... server # [6080382.591849] server systemd[1]: Starting Create Static Device Nodes in /dev... client # [6080382.620056] client systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys server # [6080382.602121] server systemd-journald[69]: Time spent on flushing to /var/log/journal/2685418501c84214b452c2968e64de86 is 1.702ms for 7 entries. client # [6080382.661131] client systemd[1]: Finished Flush Journal to Persistent Storage. ca # [6080382.620331] ca systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys server # [6080382.602121] server systemd-journald[69]: System Journal (/var/log/journal/2685418501c84214b452c2968e64de86) is 8M, max 4G, 3.9G free. ca # [6080382.663039] ca systemd[1]: Finished Flush Journal to Persistent Storage. client # [6080382.662899] client systemd[1]: Starting Create System Files and Directories... ca # [6080382.664058] ca systemd[1]: Starting Create System Files and Directories... client # [6080382.677292] client systemd-tmpfiles[162]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted server # [6080382.614030] server systemd[1]: Finished Create Static Device Nodes in /dev. ca # [6080382.680263] ca systemd-tmpfiles[164]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted client # [6080382.677470] client systemd-tmpfiles[162]: fchmod() of /var/log/journal failed: Operation not permitted server # [6080382.614736] server systemd[1]: Reached target Preparation for Local File Systems. ca # [6080382.680502] ca systemd-tmpfiles[164]: fchmod() of /var/log/journal failed: Operation not permitted client # [6080382.677596] client systemd-tmpfiles[162]: fchmod() of /var/log/journal/41781df4e9dc4503877605d0254dbb65 failed: Operation not permitted server # [6080382.614859] server systemd[1]: Reached target Local File Systems. ca # [6080382.680639] ca systemd-tmpfiles[164]: fchmod() of /var/log/journal/9633dc39b4bd4992aa24de7183a32b34 failed: Operation not permitted server # [6080382.615658] server systemd[1]: Listening on Boot Loader Control Service Socket. ca # [6080382.680853] ca systemd-tmpfiles[164]: fchmod() of /run/log/journal failed: Operation not permitted client # [6080382.677823] client systemd-tmpfiles[162]: fchmod() of /run/log/journal failed: Operation not permitted server # [6080382.615702] server systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container client # [6080382.680784] client systemd[1]: Finished Create System Files and Directories. server # [6080382.616600] server systemd[1]: Starting Save Transient machine-id to Disk... ca # [6080382.682634] ca systemd[1]: Finished Create System Files and Directories. server # [6080382.616632] server systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys ca # [6080382.683720] ca systemd[1]: Starting Rebuild Journal Catalog... server # [6080382.662618] server systemd[1]: Finished Flush Journal to Persistent Storage. ca # [6080382.684596] ca systemd[1]: Starting Record System Boot/Shutdown in UTMP... server # [6080382.663734] server systemd[1]: Starting Create System Files and Directories... client # [6080382.681991] client systemd[1]: Starting Rebuild Journal Catalog... ca # [6080382.698279] ca systemd[1]: Finished Record System Boot/Shutdown in UTMP. client # [6080382.682736] client systemd[1]: Starting Record System Boot/Shutdown in UTMP... server # [6080382.678550] server systemd-tmpfiles[161]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted client # [6080382.682970] client systemd[1]: Finished Firewall. ca # [6080382.701760] ca systemd[1]: Finished Firewall. server # [6080382.678737] server systemd-tmpfiles[161]: fchmod() of /var/log/journal failed: Operation not permitted server # [6080382.678856] server systemd-tmpfiles[161]: fchmod() of /var/log/journal/2685418501c84214b452c2968e64de86 failed: Operation not permitted client # [6080382.683489] client systemd[1]: Reached target Preparation for Network. ca # [6080382.702237] ca systemd[1]: Reached target Preparation for Network. server # [6080382.679040] server systemd-tmpfiles[161]: fchmod() of /run/log/journal failed: Operation not permitted server # [6080382.680992] server systemd[1]: Finished Create System Files and Directories. ca # [6080382.702524] ca systemd[1]: Listening on Network Management Resolve Hook Socket. client # [6080382.683804] client systemd[1]: Listening on Network Management Resolve Hook Socket. client # [6080382.684841] client systemd[1]: Starting Network Management... server # [6080382.682831] server systemd[1]: Starting Rebuild Journal Catalog... ca # [6080382.703566] ca systemd[1]: Starting Network Management... ca # [6080382.703796] ca systemd[1]: Finished Rebuild Journal Catalog. ca # [6080382.705367] ca systemd[1]: Starting Update is Completed... ca # [6080382.715183] ca systemd[1]: Finished Update is Completed. ca # [6080382.879041] ca systemd[1]: Finished Save Transient machine-id to Disk. ca # [6080383.552071] ca systemd[1]: etc-machine\x2did.mount: Deactivated successfully. client # [6080382.694057] client systemd[1]: Finished Record System Boot/Shutdown in UTMP. client # [6080382.702937] client systemd[1]: Finished Rebuild Journal Catalog. client # [6080382.704028] client systemd[1]: Starting Update is Completed... client # [6080382.714120] client systemd[1]: Finished Update is Completed. client # [6080382.880523] client systemd[1]: Finished Save Transient machine-id to Disk. client # [6080383.534471] client systemd[1]: etc-machine\x2did.mount: Deactivated successfully. server # [6080382.683637] server systemd[1]: Starting Record System Boot/Shutdown in UTMP... server # [6080382.691073] server systemd[1]: Finished Firewall. server # [6080382.691239] server systemd[1]: Reached target Preparation for Network. server # [6080382.691470] server systemd[1]: Listening on Network Management Resolve Hook Socket. server # [6080382.692532] server systemd[1]: Starting Network Management... server # [6080382.695136] server systemd[1]: Finished Record System Boot/Shutdown in UTMP. server # [6080382.702843] server systemd[1]: Finished Rebuild Journal Catalog. server # [6080382.704022] server systemd[1]: Starting Update is Completed... server # [6080382.714124] server systemd[1]: Finished Update is Completed. server # [6080382.882913] server systemd[1]: Finished Save Transient machine-id to Disk. server # [6080383.535290] server systemd[1]: etc-machine\x2did.mount: Deactivated successfully. server # [6080383.784478] server systemd-networkd[182]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted server # [6080383.784576] server systemd-networkd[182]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted server # [6080383.824336] server systemd-networkd[182]: /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. server # [6080383.824502] server systemd-networkd[182]: /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. server # [6080383.824660] server systemd-networkd[182]: lo: Link UP server # [6080383.824667] server systemd-networkd[182]: lo: Gained carrier server # [6080383.824868] server systemd-networkd[182]: eth1: Configuring with /etc/systemd/network/40-eth1.network. server # [6080383.825281] server systemd[1]: Started Network Management. server # [6080383.840423] server systemd-networkd[182]: eth1: Link UP server # [6080383.840765] server systemd-networkd[182]: eth1: Gained carrier server # [6080383.841245] server systemd[1]: Starting Enable Persistent Storage in systemd-networkd... server # [6080383.858989] server systemd[1]: Finished Enable Persistent Storage in systemd-networkd. client # [6080383.803797] client systemd-networkd[178]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted client # [6080383.803893] client systemd-networkd[178]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted client # [6080383.827571] client systemd-networkd[178]: /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. client # [6080383.827741] client systemd-networkd[178]: /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. client # [6080383.827914] client systemd-networkd[178]: lo: Link UP client # [6080383.827918] client systemd-networkd[178]: lo: Gained carrier client # [6080383.828138] client systemd-networkd[178]: eth1: Configuring with /etc/systemd/network/40-eth1.network. client # [6080383.828485] client systemd[1]: Started Network Management. client # [6080383.840731] client systemd-networkd[178]: eth1: Link UP client # [6080383.841065] client systemd-networkd[178]: eth1: Gained carrier client # [6080383.841836] client systemd[1]: Starting Enable Persistent Storage in systemd-networkd... client # [6080383.902008] client systemd[1]: Finished Enable Persistent Storage in systemd-networkd. ca # [6080383.811594] ca systemd-networkd[192]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted ca # [6080383.811687] ca systemd-networkd[192]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted ca # [6080383.827589] ca systemd-networkd[192]: /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. ca # [6080383.827760] ca systemd-networkd[192]: /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. ca # [6080383.827958] ca systemd-networkd[192]: lo: Link UP ca # [6080383.827962] ca systemd-networkd[192]: lo: Gained carrier ca # [6080383.828168] ca systemd-networkd[192]: eth1: Configuring with /etc/systemd/network/40-eth1.network. ca # [6080383.828533] ca systemd[1]: Started Network Management. ca # [6080383.840850] ca systemd-networkd[192]: eth1: Link UP ca # [6080383.841167] ca systemd-networkd[192]: eth1: Gained carrier ca # [6080383.841958] ca systemd[1]: Starting Enable Persistent Storage in systemd-networkd... ca # [6080383.902199] ca systemd[1]: Finished Enable Persistent Storage in systemd-networkd. server # [6080384.250208] server systemd-resolved[114]: Positive Trust Anchors: server # [6080384.250219] server systemd-resolved[114]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d server # [6080384.250222] server systemd-resolved[114]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 server # [6080384.250258] server systemd-resolved[114]: 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 test server # [6080384.272746] server systemd-resolved[114]: Using system hostname 'server'. server # [6080384.274137] server systemd[1]: Started Network Name Resolution. server # [6080384.274223] server systemd[1]: Reached target Network. server # [6080384.274281] server systemd[1]: Reached target Network is Online. server # [6080384.274326] server systemd[1]: Reached target System Initialization. server # [6080384.274776] server systemd[1]: Started Renew ACME Certificate for test.foo. server # [6080384.274812] server systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container server # [6080384.274836] server systemd[1]: Started Daily Cleanup of Temporary Directories. client # [6080384.257654] client systemd-resolved[117]: Positive Trust Anchors: server # [6080384.274855] server systemd[1]: Reached target Timer Units. client # [6080384.257666] client systemd-resolved[117]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d server # [6080384.275192] server systemd[1]: Listening on D-Bus System Message Bus Socket. client # [6080384.257670] client systemd-resolved[117]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 server # [6080384.275295] server systemd[1]: Listening on Nix Daemon Socket. client # [6080384.257706] client 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 test client # [6080384.280014] client systemd-resolved[117]: Using system hostname 'client'. server # [6080384.275506] server systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. client # [6080384.281360] client systemd[1]: Started Network Name Resolution. server # [6080384.275531] server systemd[1]: Reached target Socket Units. server # [6080384.275579] server systemd[1]: Reached target Basic System. client # [6080384.281459] client systemd[1]: Reached target Network. server # [6080384.277052] server systemd[1]: Starting Set up the ACME certificate renewal infrastructure... client # [6080384.281535] client systemd[1]: Reached target System Initialization. server # [6080384.277795] server systemd[1]: Starting Import lastlog data into lastlog2 database... client # [6080384.281603] client systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container server # [6080384.277832] server systemd[1]: nginx-config-reload.service skipped, unmet condition check ConditionPathExists=/var/lib/acme/test.foo/fullchain.pem client # [6080384.281637] client systemd[1]: Started Daily Cleanup of Temporary Directories. server # [6080384.278772] server systemd[1]: Starting Name Service Cache Daemon (nsncd)... client # [6080384.281658] client systemd[1]: Reached target Timer Units. server # [6080384.280072] server systemd[1]: Starting D-Bus System Message Bus... client # [6080384.281812] client systemd[1]: Listening on D-Bus System Message Bus Socket. server # [6080384.343312] server systemd[1]: Finished Import lastlog data into lastlog2 database. client # [6080384.281963] client systemd[1]: Listening on Nix Daemon Socket. server # [6080384.420755] server acme-setup-privileged[192]: + set -euo pipefail client # [6080384.282096] client systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. server # [6080384.420755] server acme-setup-privileged[192]: + cd /var/lib/acme server # [6080384.421158] server acme-setup-privileged[192]: + chmod -R u=rwX,g=,o= .lego/accounts client # [6080384.282123] client systemd[1]: Reached target Socket Units. server # [6080384.422257] server acme-setup-privileged[192]: + chown -R acme .lego/accounts client # [6080384.282169] client systemd[1]: Reached target Basic System. server # [6080384.423831] server acme-setup-privileged[192]: + for fixpath in test.foo .lego/test.foo client # [6080384.328493] client systemd[1]: Starting Import lastlog data into lastlog2 database... server # [6080384.423858] server acme-setup-privileged[192]: + '[' -d test.foo ']' client # [6080384.329618] client systemd[1]: Starting Name Service Cache Daemon (nsncd)... server # [6080384.423858] server acme-setup-privileged[192]: + for fixpath in test.foo .lego/test.foo server # [6080384.423900] server acme-setup-privileged[192]: + '[' -d .lego/test.foo ']' client # [6080384.331152] client systemd[1]: Starting D-Bus System Message Bus... server # [6080384.459273] server nsncd[194]: Aug 18 08:23:30.512 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" client # [6080384.346119] client systemd[1]: Finished Import lastlog data into lastlog2 database. server # [6080384.459337] server systemd[1]: Started Name Service Cache Daemon (nsncd). client # [6080384.446520] client nsncd[189]: Aug 18 08:23:30.499 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" server # [6080384.459405] server systemd[1]: Reached target Host and Network Name Lookups. client # [6080384.446717] client systemd[1]: Started Name Service Cache Daemon (nsncd). server # [6080384.459469] server systemd[1]: Reached target User and Group Name Lookups. client # [6080384.446784] client systemd[1]: Reached target Host and Network Name Lookups. server # [6080384.477022] server systemd[1]: Starting User Login Management... client # [6080384.446833] client systemd[1]: Reached target User and Group Name Lookups. server # [6080384.478013] server systemd[1]: Starting Permit User Sessions... client # [6080384.477048] client systemd[1]: Starting User Login Management... server # [6080384.487429] server systemd[1]: Finished Permit User Sessions. client # [6080384.478055] client systemd[1]: Starting Permit User Sessions... server # [6080384.488522] server systemd[1]: Started Console Getty. client # [6080384.487408] client systemd[1]: Finished Permit User Sessions. server # [6080384.488562] server systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0 client # [6080384.488485] client systemd[1]: Started Console Getty. server # [6080384.488580] server systemd[1]: Reached target Login Prompts. client # [6080384.488528] client systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0 server # [6080384.555651] server dbus-broker-launch[195]: Looking up NSS user entry for 'systemd-timesync'... server # [6080384.556361] server dbus-broker-launch[195]: NSS returned no entry for 'systemd-timesync' client # [6080384.488545] client systemd[1]: Reached target Login Prompts. server # [6080384.556361] server dbus-broker-launch[195]: Invalid user-name in /nix/store/lxnlg1wvz5bx4xfzc75k21l11ngxkyck-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" client # [6080384.555980] client dbus-broker-launch[190]: Looking up NSS user entry for 'systemd-timesync'... ca # [6080384.264934] ca systemd-resolved[119]: Positive Trust Anchors: ca # [6080384.264946] ca systemd-resolved[119]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d ca # [6080384.264950] ca systemd-resolved[119]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 ca # [6080384.264986] ca systemd-resolved[119]: 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 test ca # [6080384.287349] ca systemd-resolved[119]: Using system hostname 'ca'. ca # [6080384.288751] ca systemd[1]: Started Network Name Resolution. ca # [6080384.288838] ca systemd[1]: Reached target Network. ca # [6080384.288898] ca systemd[1]: Reached target Network is Online. ca # [6080384.288941] ca systemd[1]: Reached target System Initialization. ca # [6080384.289141] ca systemd[1]: Started Renew ACME Certificate for ca.foo. ca # [6080384.289171] ca systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container ca # [6080384.289194] ca systemd[1]: Started Daily Cleanup of Temporary Directories. ca # [6080384.289209] ca systemd[1]: Reached target Timer Units. ca # [6080384.289335] ca systemd[1]: Listening on D-Bus System Message Bus Socket. ca # [6080384.289431] ca systemd[1]: Listening on Nix Daemon Socket. ca # [6080384.289529] ca systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. ca # [6080384.289547] ca systemd[1]: Reached target Socket Units. ca # [6080384.289582] ca systemd[1]: Reached target Basic System. ca # [6080384.328607] ca systemd[1]: Starting Set up the ACME certificate renewal infrastructure... ca # [6080384.329480] ca systemd[1]: Starting Import lastlog data into lastlog2 database... ca # [6080384.329522] ca systemd[1]: nginx-config-reload.service skipped, unmet condition check ConditionPathExists=/var/lib/acme/ca.foo/fullchain.pem ca # [6080384.330479] ca systemd[1]: Starting Name Service Cache Daemon (nsncd)... ca # [6080384.331606] ca systemd[1]: Starting step-ca service... ca # [6080384.333028] ca systemd[1]: Starting D-Bus System Message Bus... ca # [6080384.346138] ca systemd[1]: Finished Import lastlog data into lastlog2 database. ca # [6080384.434785] ca acme-setup-privileged[201]: + set -euo pipefail ca # [6080384.434785] ca acme-setup-privileged[201]: + cd /var/lib/acme ca # [6080384.434785] ca acme-setup-privileged[201]: + chmod -R u=rwX,g=,o= .lego/accounts ca # [6080384.436263] ca acme-setup-privileged[201]: + chown -R acme .lego/accounts ca # [6080384.437714] ca acme-setup-privileged[201]: + for fixpath in ca.foo .lego/ca.foo ca # [6080384.437746] ca acme-setup-privileged[201]: + '[' -d ca.foo ']' ca # [6080384.437746] ca acme-setup-privileged[201]: + for fixpath in ca.foo .lego/ca.foo ca # [6080384.437746] ca acme-setup-privileged[201]: + '[' -d .lego/ca.foo ']' ca # [6080384.456515] ca nsncd[203]: Aug 18 08:23:30.509 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" ca # [6080384.476548] ca systemd[1]: Started Name Service Cache Daemon (nsncd). ca # [6080384.476694] ca systemd[1]: Reached target Host and Network Name Lookups. ca # [6080384.476817] ca systemd[1]: Reached target User and Group Name Lookups. ca # [6080384.477963] ca systemd[1]: Starting User Login Management... ca # [6080384.478886] ca systemd[1]: Starting Permit User Sessions... ca # [6080384.487387] ca systemd[1]: Finished Permit User Sessions. ca # [6080384.489157] ca systemd[1]: Started Console Getty. ca # [6080384.489194] ca systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0 ca # [6080384.489212] ca systemd[1]: Reached target Login Prompts. ca: must succeed: systemctl restart acme-order-renew-ca.foo.service client # [6080384.556638] client dbus-broker-launch[190]: NSS returned no entry for 'systemd-timesync' client # [6080384.556638] client dbus-broker-launch[190]: Invalid user-name in /nix/store/s0a40wv0lnwiz13r43fk318ri3wv536k-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" client # [6080384.557069] client systemd[1]: Started D-Bus System Message Bus. client # [6080384.564248] client dbus-broker-launch[190]: Ready ca # [6080384.567997] ca dbus-broker-launch[205]: Looking up NSS user entry for 'systemd-timesync'... server # [6080384.556780] server systemd[1]: Started D-Bus System Message Bus. ca # [6080384.568526] ca dbus-broker-launch[205]: NSS returned no entry for 'systemd-timesync' server # [6080384.564181] server dbus-broker-launch[195]: Ready ca # [6080384.568526] ca dbus-broker-launch[205]: Invalid user-name in /nix/store/mfvkn1zwby5692v4kx3ynjdz34b2lkq3-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" ca # [6080384.568895] ca systemd[1]: Started D-Bus System Message Bus. ca # [6080384.575777] ca dbus-broker-launch[205]: Ready server # [6080384.964118] server systemd-networkd[182]: eth1: Gained IPv6LL server # [6080385.034239] server systemd-logind[220]: New seat seat0. server # [6080385.034503] server systemd[1]: Started User Login Management. server # [6080385.035844] server systemd[1]: Starting linger-users.service... server # [6080385.091358] server acme-setup-start[208]: + set -euo pipefail server # [6080385.091358] server acme-setup-start[208]: + test -e ca/key.pem server # [6080385.091635] server acme-setup-start[208]: + minica --ca-key ca/key.pem --ca-cert ca/cert.pem --domains selfsigned.local server # [6080385.100388] server systemd[1]: linger-users.service: Deactivated successfully. server # [6080385.100548] server systemd[1]: Finished linger-users.service. server # [6080385.108469] server systemd[1]: Finished Set up the ACME certificate renewal infrastructure. server # [6080385.109940] server systemd[1]: Starting Ensure certificate for test.foo... ca # [6080385.042943] ca systemd-logind[230]: New seat seat0. ca # [6080385.043131] ca systemd[1]: Started User Login Management. client # [6080385.024168] client systemd-networkd[178]: eth1: Gained IPv6LL ca # [6080385.093167] ca systemd[1]: Starting linger-users.service... client # [6080385.034238] client systemd-logind[205]: New seat seat0. ca # [6080385.105188] ca systemd[1]: linger-users.service: Deactivated successfully. client # [6080385.034483] client systemd[1]: Started User Login Management. ca # [6080385.105258] ca systemd[1]: Finished linger-users.service. client # [6080385.035897] client systemd[1]: Starting linger-users.service... ca # [6080385.105415] ca acme-setup-start[219]: + set -euo pipefail client # [6080385.100414] client systemd[1]: linger-users.service: Deactivated successfully. ca # [6080385.105415] ca acme-setup-start[219]: + test -e ca/key.pem client # [6080385.100568] client systemd[1]: Finished linger-users.service. ca # [6080385.105415] ca acme-setup-start[219]: + minica --ca-key ca/key.pem --ca-cert ca/cert.pem --domains selfsigned.local ca # [6080385.121812] ca systemd[1]: Finished Set up the ACME certificate renewal infrastructure. ca # [6080385.123276] ca systemd[1]: Starting Ensure certificate for ca.foo... ca # [6080385.268879] ca step-ca[204]: badger 2026/08/18 08:23:31 INFO: All 0 tables opened in 0s ca # [6080385.272774] ca step-ca[204]: 2026/08/18 08:23:31 Building new tls configuration using step-ca x509 Signer Interface ca # [6080385.278790] ca step-ca[204]: 2026/08/18 08:23:31 Starting Smallstep CA/0.30.2 (linux/arm64) ca # [6080385.278790] ca step-ca[204]: 2026/08/18 08:23:31 Documentation: https://u.step.sm/docs/ca client # [6080385.101063] client systemd[1]: Reached target Multi-User System. ca # [6080385.278790] ca step-ca[204]: 2026/08/18 08:23:31 Community Discord: https://u.step.sm/discord ca # [6080385.278790] ca step-ca[204]: 2026/08/18 08:23:31 Config file: /etc/smallstep/ca.json client # [6080385.101319] client systemd[1]: Startup finished in 3.014s. ca # [6080385.278790] ca step-ca[204]: 2026/08/18 08:23:31 The primary server URL is https://ca.foo:1443 ca # [6080385.278790] ca step-ca[204]: 2026/08/18 08:23:31 Root certificates are available at https://ca.foo:1443/roots.pem ca # [6080385.278922] ca step-ca[204]: 2026/08/18 08:23:31 X.509 Root Fingerprint: e826ba1017ed9c6d1e80bbc83d56e54b4763fd0cf6f59c398a4f0b383416ea71 ca # [6080385.279358] ca systemd[1]: Started step-ca service. ca # [6080385.279651] ca step-ca[204]: 2026/08/18 08:23:31 Serving HTTPS on 0.0.0.0:1443 ... ca # [6080385.572179] ca systemd-networkd[192]: eth1: Gained IPv6LL ca # [6080385.725412] ca acme-ca.foo-start[256]: Waiting to acquire lock in /run/acme/ ca # [6080385.727911] ca acme-ca.foo-start[256]: + '[' -e out/acme-success ']' ca # [6080385.728034] ca acme-ca.foo-start[256]: + minica --ca-cert=ca/cert.pem --ca-key=ca/key.pem --domains=ca.foo --ip-addresses= ca # [6080385.743226] ca acme-ca.foo-start[294]: + cd ca.foo ca # [6080385.743778] ca acme-ca.foo-start[294]: + cp -vp cert.pem ../out/cert.pem ca # [6080385.744910] ca acme-ca.foo-start[295]: 'cert.pem' -> '../out/cert.pem' ca # [6080385.745135] ca acme-ca.foo-start[294]: + cp -vp key.pem ../out/key.pem ca # [6080385.746600] ca acme-ca.foo-start[294]: 'key.pem' -> '../out/key.pem' ca # [6080385.746812] ca acme-ca.foo-start[256]: + cat out/cert.pem ca/cert.pem ca # [6080385.748684] ca acme-ca.foo-start[256]: + cp ca/cert.pem out/chain.pem ca # [6080385.750432] ca acme-ca.foo-start[256]: + cat out/key.pem out/fullchain.pem ca # [6080385.751941] ca acme-ca.foo-start[256]: + for fixpath in out certificates ca # [6080385.751973] ca acme-ca.foo-start[256]: + '[' -d out ']' ca # [6080385.751973] ca acme-ca.foo-start[256]: + chmod -R u=rwX,g=rX,o= out ca # [6080385.753601] ca acme-ca.foo-start[256]: + chown -R acme:nginx out ca # [6080385.756142] ca acme-ca.foo-start[256]: + for fixpath in out certificates ca # [6080385.756142] ca acme-ca.foo-start[256]: + '[' -d certificates ']' ca # [6080385.780404] ca systemd[1]: Finished Ensure certificate for ca.foo. ca # [6080385.782967] ca systemd[1]: Starting Nginx Web Server... server # [6080385.700040] server acme-test.foo-start[245]: Waiting to acquire lock in /run/acme/ server # [6080385.703028] server acme-test.foo-start[245]: + '[' -e out/acme-success ']' server # [6080385.703111] server acme-test.foo-start[245]: + minica --ca-cert=ca/cert.pem --ca-key=ca/key.pem --domains=test.foo --ip-addresses= server # [6080385.718910] server acme-test.foo-start[255]: + cd test.foo server # [6080385.719483] server acme-test.foo-start[255]: + cp -vp cert.pem ../out/cert.pem server # [6080385.720395] server acme-test.foo-start[256]: 'cert.pem' -> '../out/cert.pem' server # [6080385.720724] server acme-test.foo-start[255]: + cp -vp key.pem ../out/key.pem server # [6080385.721749] server acme-test.foo-start[255]: 'key.pem' -> '../out/key.pem' server # [6080385.721983] server acme-test.foo-start[245]: + cat out/cert.pem ca/cert.pem server # [6080385.723623] server acme-test.foo-start[245]: + cp ca/cert.pem out/chain.pem server # [6080385.725390] server acme-test.foo-start[245]: + cat out/key.pem out/fullchain.pem server # [6080385.727223] server acme-test.foo-start[245]: + for fixpath in out certificates server # [6080385.727223] server acme-test.foo-start[245]: + '[' -d out ']' server # [6080385.727335] server acme-test.foo-start[245]: + chmod -R u=rwX,g=rX,o= out server # [6080385.729330] server acme-test.foo-start[245]: + chown -R acme:nginx out server # [6080385.732138] server acme-test.foo-start[245]: + for fixpath in out certificates server # [6080385.732138] server acme-test.foo-start[245]: + '[' -d certificates ']' server # [6080385.736469] server systemd[1]: Finished Ensure certificate for test.foo. server # [6080385.738993] server systemd[1]: Starting Nginx Web Server... server # [6080386.421306] server nginx-pre-start[267]: nginx: the configuration file /nix/store/cg1ianm6b7c0wkyi3akik85ix2jk9h6y-nginx.conf syntax is ok server # [6080386.421653] server nginx-pre-start[267]: nginx: configuration file /nix/store/cg1ianm6b7c0wkyi3akik85ix2jk9h6y-nginx.conf test is successful server # [6080386.425870] server systemd[1]: Started Nginx Web Server. server # [6080386.426268] server systemd[1]: Reached target Multi-User System. server # [6080386.427493] server systemd[1]: Starting Order (and renew) ACME certificate for test.foo... ca # [6080386.431297] ca nginx-pre-start[306]: nginx: the configuration file /nix/store/kijspw8hclw37aj8kl8y20jfn91c8ssx-nginx.conf syntax is ok ca # [6080386.431617] ca nginx-pre-start[306]: nginx: configuration file /nix/store/kijspw8hclw37aj8kl8y20jfn91c8ssx-nginx.conf test is successful ca # [6080386.476412] ca systemd[1]: Started Nginx Web Server. ca # [6080386.476939] ca systemd[1]: Reached target Multi-User System. ca # [6080386.478575] ca systemd[1]: Starting Order (and renew) ACME certificate for ca.foo... ca # [6080387.029530] ca acme-order-renew-ca.foo-start[309]: Waiting to acquire lock in /run/acme/ ca # [6080387.032817] ca acme-order-renew-ca.foo-start[309]: + set -euo pipefail ca # [6080387.032918] ca acme-order-renew-ca.foo-start[309]: + echo 88dc4fc401a6091a1bd9 ca # [6080387.033004] ca acme-order-renew-ca.foo-start[309]: + cmp -s domainhash.txt certificates/domainhash.txt ca # [6080387.034029] 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 run ca # [6080387.053357] ca acme-order-renew-ca.foo-start[320]: 2026/08/18 08:23:33 No key found for account none@none.tld. Generating a P256 key. ca # [6080387.053854] ca acme-order-renew-ca.foo-start[320]: 2026/08/18 08:23:33 Saved key to accounts/ca.foo_1443/none@none.tld/keys/none@none.tld.key ca # [6080387.084837] ca step-ca[204]: time="2026-08-18T08:23:33Z" level=info duration="92.761µs" duration-ns=92761 fields.time="2026-08-18T08:23:33Z" method=GET name=ca path=/acme/acme/directory protocol=HTTP/1.1 referer= remote-address="2001:db8:1::1" request-id=48483142-bff9-430b-9bbf-de004b80a8eb 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= ca # [6080387.085573] ca acme-order-renew-ca.foo-start[320]: 2026/08/18 08:23:33 [INFO] acme: Registering account for none@none.tld ca # [6080387.096453] ca step-ca[204]: time="2026-08-18T08:23:33Z" level=info duration=11.015994ms duration-ns=11015994 fields.time="2026-08-18T08:23:33Z" method=HEAD name=ca nonce=Uk5xNUtGZFA5WGZGd2N1c3pDTDRSeFpOYXhuYm92SlM path=/acme/acme/new-nonce protocol=HTTP/1.1 referer= remote-address="2001:db8:1::1" request-id=f758de90-921a-4a3c-9529-a7d5211f4292 size=0 status=200 user-agent="lego-cli/4.35.2 xenolf-acme/4.35.2 (release; linux; arm64)" user-id= ca # [6080387.098815] ca step-ca[204]: time="2026-08-18T08:23:33Z" level=info duration=1.521421ms duration-ns=1521421 fields.time="2026-08-18T08:23:33Z" method=POST name=ca nonce=dUFxdXg4M21FTGhsUURjeG1sNFk1bzVUZ0R3SkhvNlM path=/acme/acme/new-account protocol=HTTP/1.1 referer= remote-address="2001:db8:1::1" request-id=0902ccae-a9bc-43fb-b87f-5000bc9ddae7 response="{\"contact\":[\"mailto:none@none.tld\"],\"status\":\"valid\",\"orders\":\"https://ca.foo:1443/acme/acme/account/2rlPKAFiug3veow0TIy9Lb8pEFsSpoNq/orders\"}" size=143 status=201 user-agent="lego-cli/4.35.2 xenolf-acme/4.35.2 (release; linux; arm64)" user-id= ca # [6080387.099261] ca acme-order-renew-ca.foo-start[320]: !!!! HEADS UP !!!! ca # [6080387.099261] ca acme-order-renew-ca.foo-start[320]: Your account credentials have been saved in your ca # [6080387.099261] ca acme-order-renew-ca.foo-start[320]: configuration directory at "accounts". ca # [6080387.099261] ca acme-order-renew-ca.foo-start[320]: You should make a secure backup of this folder now. This ca # [6080387.099261] ca acme-order-renew-ca.foo-start[320]: configuration directory will also contain private keys ca # [6080387.099261] ca acme-order-renew-ca.foo-start[320]: generated by lego and certificates obtained from the ACME ca # [6080387.099261] ca acme-order-renew-ca.foo-start[320]: server. Making regular backups of this folder is ideal. ca # [6080387.099552] ca acme-order-renew-ca.foo-start[320]: 2026/08/18 08:23:33 [INFO] [ca.foo] acme: Obtaining bundled SAN certificate ca # [6080387.103890] ca step-ca[204]: time="2026-08-18T08:23:33Z" level=info duration=3.945775ms duration-ns=3945775 fields.time="2026-08-18T08:23:33Z" method=POST name=ca nonce=SE4zT2o3MjFWbEtkb3pxTE5ENjRHY3RYNmlBVks0RmI path=/acme/acme/new-order protocol=HTTP/1.1 referer= remote-address="2001:db8:1::1" request-id=85169033-97e9-4581-b427-b0fe61b9db54 response="{\"id\":\"Zi91IZGSMdZlw8fuJfzzJEIs1uyBd6Cq\",\"status\":\"pending\",\"expires\":\"2026-08-19T08:23:33Z\",\"identifiers\":[{\"type\":\"dns\",\"value\":\"ca.foo\"}],\"notBefore\":\"2026-08-18T08:22:33Z\",\"notAfter\":\"2026-11-16T08:23:33Z\",\"authorizations\":[\"https://ca.foo:1443/acme/acme/authz/ULRiTyRQWJLcNhn3ZV3i19ZE6KAKuvkt\"],\"finalize\":\"https://ca.foo:1443/acme/acme/order/Zi91IZGSMdZlw8fuJfzzJEIs1uyBd6Cq/finalize\"}" size=392 status=201 user-agent="lego-cli/4.35.2 xenolf-acme/4.35.2 (release; linux; arm64)" user-id= ca # [6080387.163291] ca step-ca[204]: time="2026-08-18T08:23:33Z" level=info duration=2.108949ms duration-ns=2108949 fields.time="2026-08-18T08:23:33Z" method=POST name=ca nonce=NXNRYWFGY0VPN2ZMUXliSGRRNHdFUm5wSVdqeWpOUVY path=/acme/acme/authz/ULRiTyRQWJLcNhn3ZV3i19ZE6KAKuvkt protocol=HTTP/1.1 referer= remote-address="2001:db8:1::1" request-id=db48806f-3547-4271-864a-50b937310221 response="{\"identifier\":{\"type\":\"dns\",\"value\":\"ca.foo\"},\"status\":\"pending\",\"challenges\":[{\"type\":\"dns-01\",\"status\":\"pending\",\"token\":\"Eallh6OoABzJbv6DHpSjVc7l7ZxEYhbj\",\"url\":\"https://ca.foo:1443/acme/acme/challenge/ULRiTyRQWJLcNhn3ZV3i19ZE6KAKuvkt/nyPcE2BF77Mn9OEcy6ecTVlilLo6gtAn\"},{\"type\":\"http-01\",\"status\":\"pending\",\"token\":\"Eallh6OoABzJbv6DHpSjVc7l7ZxEYhbj\",\"url\":\"https://ca.foo:1443/acme/acme/challenge/ULRiTyRQWJLcNhn3ZV3i19ZE6KAKuvkt/atALGN2XIUy2mMqvaiO932oWpC9TcH1O\"},{\"type\":\"tls-alpn-01\",\"status\":\"pending\",\"token\":\"Eallh6OoABzJbv6DHpSjVc7l7ZxEYhbj\",\"url\":\"https://ca.foo:1443/acme/acme/challenge/ULRiTyRQWJLcNhn3ZV3i19ZE6KAKuvkt/LDzxzTO3LcPH4pjq8dt3uiLQA4d5FihJ\"}],\"wildcard\":false,\"expires\":\"2026-08-19T08:23:33Z\"}" size=719 status=200 user-agent="lego-cli/4.35.2 xenolf-acme/4.35.2 (release; linux; arm64)" user-id= ca # [6080387.163728] ca acme-order-renew-ca.foo-start[320]: 2026/08/18 08:23:33 [INFO] [ca.foo] AuthURL: https://ca.foo:1443/acme/acme/authz/ULRiTyRQWJLcNhn3ZV3i19ZE6KAKuvkt ca # [6080387.163728] ca acme-order-renew-ca.foo-start[320]: 2026/08/18 08:23:33 [INFO] [ca.foo] acme: Could not find solver for: tls-alpn-01 ca # [6080387.163728] ca acme-order-renew-ca.foo-start[320]: 2026/08/18 08:23:33 [INFO] [ca.foo] acme: use http-01 solver ca # [6080387.163728] ca acme-order-renew-ca.foo-start[320]: 2026/08/18 08:23:33 [INFO] [ca.foo] acme: Trying to solve HTTP-01 ca # [6080387.169401] ca step-ca[204]: time="2026-08-18T08:23:33Z" level=info duration=4.838307ms duration-ns=4838307 fields.time="2026-08-18T08:23:33Z" method=POST name=ca nonce=MVNqV1p4ZVAwcEx0SXZuWjhrUGdWSHJrYnNDb1BxVEg path=/acme/acme/challenge/ULRiTyRQWJLcNhn3ZV3i19ZE6KAKuvkt/atALGN2XIUy2mMqvaiO932oWpC9TcH1O protocol=HTTP/1.1 referer= remote-address="2001:db8:1::1" request-id=02695b36-2a2f-404e-93b8-bf5d25cca107 response="{\"type\":\"http-01\",\"status\":\"valid\",\"token\":\"Eallh6OoABzJbv6DHpSjVc7l7ZxEYhbj\",\"validated\":\"2026-08-18T08:23:33Z\",\"url\":\"https://ca.foo:1443/acme/acme/challenge/ULRiTyRQWJLcNhn3ZV3i19ZE6KAKuvkt/atALGN2XIUy2mMqvaiO932oWpC9TcH1O\"}" size=228 status=200 user-agent="lego-cli/4.35.2 xenolf-acme/4.35.2 (release; linux; arm64)" user-id= ca # [6080387.169812] ca acme-order-renew-ca.foo-start[320]: 2026/08/18 08:23:33 [INFO] [ca.foo] The server validated our request ca # [6080387.169910] ca acme-order-renew-ca.foo-start[320]: 2026/08/18 08:23:33 [INFO] [ca.foo] acme: Validations succeeded; requesting certificates ca # [6080387.180785] ca step-ca[204]: time="2026-08-18T08:23:33Z" level=info duration=9.160808ms duration-ns=9160808 fields.time="2026-08-18T08:23:33Z" method=POST name=ca nonce=T0t3TnRqbE1Xd2lGckFsRUdVNkdwaFFhQjBFQ0tPWEg path=/acme/acme/order/Zi91IZGSMdZlw8fuJfzzJEIs1uyBd6Cq/finalize protocol=HTTP/1.1 referer= remote-address="2001:db8:1::1" request-id=897941de-5db6-4588-ba05-4bbcae6d89a6 response="{\"id\":\"Zi91IZGSMdZlw8fuJfzzJEIs1uyBd6Cq\",\"status\":\"valid\",\"expires\":\"2026-08-19T08:23:33Z\",\"identifiers\":[{\"type\":\"dns\",\"value\":\"ca.foo\"}],\"notBefore\":\"2026-08-18T08:22:33Z\",\"notAfter\":\"2026-11-16T08:23:33Z\",\"authorizations\":[\"https://ca.foo:1443/acme/acme/authz/ULRiTyRQWJLcNhn3ZV3i19ZE6KAKuvkt\"],\"finalize\":\"https://ca.foo:1443/acme/acme/order/Zi91IZGSMdZlw8fuJfzzJEIs1uyBd6Cq/finalize\",\"certificate\":\"https://ca.foo:1443/acme/acme/certificate/9xIVIgsCwOWzGxwdO5hFN6lVoIX33ZJE\"}" size=481 status=200 user-agent="lego-cli/4.35.2 xenolf-acme/4.35.2 (release; linux; arm64)" user-id= ca # [6080387.183785] ca step-ca[204]: time="2026-08-18T08:23:33Z" level=info certificate=MIIB0zCCAXmgAwIBAgIQGI7QTRetMf3XzfCKWir2MjAKBggqhkjOPQQDAjAfMR0wGwYDVQQDExRDbGFuIEludGVybWVkaWF0ZSBDQTAeFw0yNjA4MTgwODIyMzNaFw0yNjExMTYwODIzMzNaMBExDzANBgNVBAMTBmNhLmZvbzBZMBMGByqGSM49AgEGCCqGSM49AwEHA0IABD8ovfSg33/ACLpiwzbqvslaTZf4UHYXjGkzOIsaBeEqPuulOvnwHYA1CKaS+It114T3gsr/ZHaQCAd3Fr0qJnujgaQwgaEwDgYDVR0PAQH/BAQDAgeAMB0GA1UdJQQWMBQGCCsGAQUFBwMBBggrBgEFBQcDAjAdBgNVHQ4EFgQU+CltP37RsQNU+vryyDJ63wIIk4MwHwYDVR0jBBgwFoAUKF8nguOtO9anXpNHCp5FwGZblWwwEQYDVR0RBAowCIIGY2EuZm9vMB0GDCsGAQQBgqRkxihAAQQNMAsCAQYEBGFjbWUEADAKBggqhkjOPQQDAgNIADBFAiBhTKOqSbgtY+g4LMj+3Ye67llcoC/iQyP8YRvPrUHsOQIhAJ3As4Bu12RhcAdo5n0pgePUOT/nF77moge2qt8pu5Wf duration=2.047229ms duration-ns=2047229 fields.time="2026-08-18T08:23:33Z" issuer="Clan Intermediate CA" method=POST name=ca nonce=RWdpQmJRanN2MVhFTkkxUnhwMHB5OFhtSjZxV1pIRnY path=/acme/acme/certificate/9xIVIgsCwOWzGxwdO5hFN6lVoIX33ZJE protocol=HTTP/1.1 provisioner=acme public-key="ECDSA P-256" referer= remote-address="2001:db8:1::1" request-id=bf330ce0-0e54-4659-a53f-e15879765945 sans="map[dns:[ca.foo]]" serial=32643002901843535089963403911044462130 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-08-18T08:22:33Z" valid-to="2026-11-16T08:23:33Z" ca # [6080387.184202] ca acme-order-renew-ca.foo-start[320]: 2026/08/18 08:23:33 [INFO] [ca.foo] Server responded with a certificate. ca # [6080387.191098] ca acme-order-renew-ca.foo-start[309]: + mv domainhash.txt certificates/ ca # [6080387.192850] ca acme-order-renew-ca.foo-start[309]: + touch out/acme-success ca # [6080387.194673] ca acme-order-renew-ca.foo-start[309]: + cmp -s certificates/ca.foo.crt out/fullchain.pem ca # [6080387.196262] ca acme-order-renew-ca.foo-start[309]: + touch out/renewed ca # [6080387.197853] ca acme-order-renew-ca.foo-start[309]: + echo Installing new certificate ca # [6080387.197853] ca acme-order-renew-ca.foo-start[309]: Installing new certificate ca # [6080387.197853] ca acme-order-renew-ca.foo-start[309]: + cp -vp certificates/ca.foo.crt out/fullchain.pem ca # [6080387.199673] ca acme-order-renew-ca.foo-start[352]: 'certificates/ca.foo.crt' -> 'out/fullchain.pem' ca # [6080387.200053] ca acme-order-renew-ca.foo-start[309]: + cp -vp certificates/ca.foo.key out/key.pem ca # [6080387.201439] ca acme-order-renew-ca.foo-start[353]: 'certificates/ca.foo.key' -> 'out/key.pem' ca # [6080387.201754] ca acme-order-renew-ca.foo-start[309]: + cp -vp certificates/ca.foo.issuer.crt out/chain.pem ca # [6080387.202867] ca acme-order-renew-ca.foo-start[354]: 'certificates/ca.foo.issuer.crt' -> 'out/chain.pem' ca # [6080387.203161] ca acme-order-renew-ca.foo-start[309]: + ln -sf fullchain.pem out/cert.pem ca # [6080387.204880] ca acme-order-renew-ca.foo-start[309]: + cat out/key.pem out/fullchain.pem ca # [6080387.206619] ca acme-order-renew-ca.foo-start[309]: + for fixpath in out certificates ca # [6080387.206619] ca acme-order-renew-ca.foo-start[309]: + '[' -d out ']' ca # [6080387.206712] ca acme-order-renew-ca.foo-start[309]: + chmod -R u=rwX,g=rX,o= out ca # [6080387.208586] ca acme-order-renew-ca.foo-start[309]: + chown -R acme:nginx out ca # [6080387.212260] ca acme-order-renew-ca.foo-start[309]: + for fixpath in out certificates ca # [6080387.212260] ca acme-order-renew-ca.foo-start[309]: + '[' -d certificates ']' ca # [6080387.212413] ca acme-order-renew-ca.foo-start[309]: + chmod -R u=rwX,g=rX,o= certificates ca # [6080387.214035] ca acme-order-renew-ca.foo-start[309]: + chown -R acme:nginx certificates ca # [6080387.217822] ca acme-order-renew-ca.foo-start[309]: + chmod -R u=rwX,g=,o= accounts/. ca # [6080387.372084] ca systemd[1]: Reloading Nginx Web Server... server # [6080387.022053] server acme-order-renew-test.foo-start[270]: Waiting to acquire lock in /run/acme/ server # [6080387.025695] server acme-order-renew-test.foo-start[270]: + set -euo pipefail server # [6080387.025796] server acme-order-renew-test.foo-start[270]: + echo ad12aa6741ce4bd2c108 server # [6080387.025883] server acme-order-renew-test.foo-start[270]: + cmp -s domainhash.txt certificates/domainhash.txt server # [6080387.027002] server acme-order-renew-test.foo-start[270]: + 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 run server # [6080387.053345] server acme-order-renew-test.foo-start[281]: 2026/08/18 08:23:33 No key found for account none@none.tld. Generating a P256 key. server # [6080387.053667] server acme-order-renew-test.foo-start[281]: 2026/08/18 08:23:33 Saved key to accounts/ca.foo/none@none.tld/keys/none@none.tld.key server # [6080387.082298] server acme-order-renew-test.foo-start[281]: 2026/08/18 08:23:33 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 1 attempt(s): Get "https://ca.foo/acme/acme/directory": tls: failed to verify certificate: x509: certificate signed by unknown authority server # [6080387.082897] server acme-order-renew-test.foo-start[270]: + 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. server # [6080387.082897] server acme-order-renew-test.foo-start[270]: 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. server # [6080387.082897] server acme-order-renew-test.foo-start[270]: + exit 10 server # [6080387.085816] server systemd[1]: acme-order-renew-test.foo.service: Main process exited, code=exited, status=10/n/a server # [6080387.085904] server systemd[1]: acme-order-renew-test.foo.service: Failed with result 'exit-code'. server # [6080387.086273] server systemd[1]: Failed to start Order (and renew) ACME certificate for test.foo. server # [6080387.086612] server systemd[1]: Startup finished in 4.993s. ca # [6080387.376412] ca systemd[1]: acme-order-renew-ca.foo.service: Deactivated successfully. ca # [6080387.376598] ca systemd[1]: Finished Order (and renew) ACME certificate for ca.foo. ca # [6080387.964251] ca nginx[370]: nginx: the configuration file /nix/store/kijspw8hclw37aj8kl8y20jfn91c8ssx-nginx.conf syntax is ok ca # [6080387.964843] ca nginx[370]: nginx: configuration file /nix/store/kijspw8hclw37aj8kl8y20jfn91c8ssx-nginx.conf test is successful ca # [6080388.485489] ca systemd[1]: Reloaded Nginx Web Server. ca # [6080388.486168] ca systemd[1]: Startup finished in 6.375s. ca # [6080388.777789] ca systemd[1]: Starting Order (and renew) ACME certificate for ca.foo... ca # [6080389.325094] ca acme-order-renew-ca.foo-start[385]: Waiting to acquire lock in /run/acme/ ca # [6080389.328479] ca acme-order-renew-ca.foo-start[385]: + set -euo pipefail ca # [6080389.328561] ca acme-order-renew-ca.foo-start[385]: + echo 88dc4fc401a6091a1bd9 ca # [6080389.328666] ca acme-order-renew-ca.foo-start[385]: + cmp -s domainhash.txt certificates/domainhash.txt ca # [6080389.330233] ca acme-order-renew-ca.foo-start[385]: + '[' -e certificates/ca.foo.key ']' ca # [6080389.330233] ca acme-order-renew-ca.foo-start[385]: + '[' -e certificates/ca.foo.crt ']' ca # [6080389.330751] ca acme-order-renew-ca.foo-start[393]: ++ find accounts -name none@none.tld.key ca # [6080389.333978] ca acme-order-renew-ca.foo-start[385]: + '[' -n accounts/ca.foo_1443/none@none.tld/keys/none@none.tld.key ']' ca # [6080389.334055] ca acme-order-renew-ca.foo-start[385]: + 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 --dynamic ca # [6080389.380142] ca step-ca[204]: time="2026-08-18T08:23:35Z" level=info duration="51.6µs" duration-ns=51600 fields.time="2026-08-18T08:23:35Z" method=GET name=ca path=/acme/acme/directory protocol=HTTP/1.1 referer= remote-address="2001:db8:1::1" request-id=7d8e85cf-ce2b-4a97-8d85-e123d6c96a12 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= ca # [6080389.380636] ca acme-order-renew-ca.foo-start[394]: 2026/08/18 08:23:35 [WARN] [ca.foo] acme: renewalInfo[get/post]: server does not advertise a renewal info endpoint ca # [6080389.380636] ca acme-order-renew-ca.foo-start[394]: 2026/08/18 08:23:35 [INFO] [ca.foo] The certificate expires at 2026-11-16T08:23:33Z, the renewal can be performed in 1439h59m37.566326267s: no renewal. ca # [6080389.380949] ca acme-order-renew-ca.foo-start[385]: + mv domainhash.txt certificates/ ca # [6080389.383280] ca acme-order-renew-ca.foo-start[385]: + touch out/acme-success ca # [6080389.385082] ca acme-order-renew-ca.foo-start[385]: + cmp -s certificates/ca.foo.crt out/fullchain.pem ca # [6080389.387790] ca acme-order-renew-ca.foo-start[385]: + for fixpath in out certificates ca # [6080389.387790] ca acme-order-renew-ca.foo-start[385]: + '[' -d out ']' ca # [6080389.387790] ca acme-order-renew-ca.foo-start[385]: + chmod -R u=rwX,g=rX,o= out ca # [6080389.387941] ca acme-order-renew-ca.foo-start[385]: + chown -R acme:nginx out ca # [6080389.390777] ca acme-order-renew-ca.foo-start[385]: + for fixpath in out certificates ca # [6080389.390816] ca acme-order-renew-ca.foo-start[385]: + '[' -d certificates ']' ca # [6080389.390816] ca acme-order-renew-ca.foo-start[385]: + chmod -R u=rwX,g=rX,o= certificates ca # [6080389.392267] ca acme-order-renew-ca.foo-start[385]: + chown -R acme:nginx certificates ca # [6080389.394795] ca acme-order-renew-ca.foo-start[385]: + chmod -R u=rwX,g=,o= accounts/. ca: (finished: must succeed: systemctl restart acme-order-renew-ca.foo.service , in 5.00 seconds) ca # [6080389.617722] ca systemd[1]: acme-order-renew-ca.foo.service: Deactivated successfully. ca # [6080389.617912] ca systemd[1]: Finished Order (and renew) ACME certificate for ca.foo. server: must succeed: systemctl restart acme-test.foo.service server # [6080392.639770] server systemd[1]: acme-test.foo.service: Deactivated successfully. server # [6080392.639932] server systemd[1]: Stopped Ensure certificate for test.foo. server # [6080392.640773] server systemd[1]: Stopping Ensure certificate for test.foo... server # [6080392.682722] server systemd[1]: Starting Ensure certificate for test.foo... server: (finished: must succeed: systemctl restart acme-test.foo.service, in 0.79 seconds) client: waiting for success: curl -v https://test.foo * Host test.foo:443 was resolved. * IPv6: 2001:db8:1::3 * IPv4: 192.168.1.3 * Trying [2001:db8:1::3]:443... * ALPN: curl offers h2,http/1.1 } [5 bytes data] * TLSv1.3 (OUT), TLS handshake, Client hello (1): } [1552 bytes data] * SSL Trust Anchors: * OpenSSL default paths (fallback) { [5 bytes data] * TLSv1.3 (IN), TLS handshake, Server hello (2): { [1210 bytes data] * TLSv1.3 (IN), TLS change cipher, Change cipher spec (1): { [1 bytes data] * TLSv1.3 (IN), TLS handshake, Encrypted Extensions (8): { [19 bytes data] * TLSv1.3 (IN), TLS handshake, Certificate (11): { [1010 bytes data] * TLSv1.3 (IN), TLS handshake, CERT verify (15): { [111 bytes data] * TLSv1.3 (IN), TLS handshake, Finished (20): { [52 bytes data] * TLSv1.3 (OUT), TLS change cipher, Change cipher spec (1): } [1 bytes data] * TLSv1.3 (OUT), TLS handshake, Finished (20): } [52 bytes data] * SSL connection using TLSv1.3 / TLS_AES_256_GCM_SHA384 / X25519MLKEM768 / id-ecPublicKey * ALPN: server accepted h2 * Server certificate: * subject: CN=test.foo * start date: Aug 18 08:23:31 2026 GMT * expire date: Sep 17 08:23:31 2028 GMT * issuer: CN=minica root ca 142cc5 * Certificate level 0: Public key type EC/secp384r1 (384/192 Bits/secBits), signed using ecdsa-with-SHA384 * Certificate level 1: Public key type EC/secp384r1 (384/192 Bits/secBits), signed using ecdsa-with-SHA384 * subjectAltName: "test.foo" matches cert's "test.foo" * OpenSSL verify result: 13 * SSL certificate OpenSSL verify result: self-signed certificate in certificate chain (19) * closing connection #0 curl: (60) SSL certificate OpenSSL verify result: self-signed certificate in certificate chain (19) More details here: https://curl.se/docs/sslcerts.html curl failed to verify the legitimacy of the server and therefore could not establish a secure connection to it. To learn more about this situation and how to fix it, please visit the webpage mentioned above. server # [6080393.384775] server acme-test.foo-start[314]: Waiting to acquire lock in /run/acme/ server # [6080393.387182] server acme-test.foo-start[314]: + '[' -e out/acme-success ']' server # [6080393.387182] server acme-test.foo-start[314]: + minica --ca-cert=ca/cert.pem --ca-key=ca/key.pem --domains=test.foo --ip-addresses= server # [6080393.402418] server acme-test.foo-start[324]: + cd test.foo server # [6080393.403034] server acme-test.foo-start[324]: + cp -vp cert.pem ../out/cert.pem server # [6080393.403965] server acme-test.foo-start[325]: 'cert.pem' -> '../out/cert.pem' server # [6080393.404327] server acme-test.foo-start[324]: + cp -vp key.pem ../out/key.pem server # [6080393.405669] server acme-test.foo-start[324]: 'key.pem' -> '../out/key.pem' server # [6080393.405928] server acme-test.foo-start[314]: + cat out/cert.pem ca/cert.pem server # [6080393.408306] server acme-test.foo-start[314]: + cp ca/cert.pem out/chain.pem server # [6080393.411369] server acme-test.foo-start[314]: + cat out/key.pem out/fullchain.pem server # [6080393.413497] server acme-test.foo-start[314]: + for fixpath in out certificates server # [6080393.413497] server acme-test.foo-start[314]: + '[' -d out ']' server # [6080393.413595] server acme-test.foo-start[314]: + chmod -R u=rwX,g=rX,o= out server # [6080393.415501] server acme-test.foo-start[314]: + chown -R acme:nginx out server # [6080393.418636] server acme-test.foo-start[314]: + for fixpath in out certificates server # [6080393.418636] server acme-test.foo-start[314]: + '[' -d certificates ']' server # [6080393.422526] server systemd[1]: Finished Ensure certificate for test.foo. server # [6080393.425563] server systemd[1]: Starting Order (and renew) ACME certificate for test.foo... server # [6080394.078800] server acme-order-renew-test.foo-start[332]: Waiting to acquire lock in /run/acme/ server # [6080394.081636] server acme-order-renew-test.foo-start[332]: + set -euo pipefail server # [6080394.081716] server acme-order-renew-test.foo-start[332]: + echo ad12aa6741ce4bd2c108 server # [6080394.081827] server acme-order-renew-test.foo-start[332]: + cmp -s domainhash.txt certificates/domainhash.txt server # [6080394.083057] 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 run server # [6080394.125639] server acme-order-renew-test.foo-start[340]: 2026/08/18 08:23:40 [INFO] acme: Registering account for none@none.tld server # [6080394.143375] server acme-order-renew-test.foo-start[340]: !!!! HEADS UP !!!! server # [6080394.143375] server acme-order-renew-test.foo-start[340]: Your account credentials have been saved in your server # [6080394.143375] server acme-order-renew-test.foo-start[340]: configuration directory at "accounts". server # [6080394.143375] server acme-order-renew-test.foo-start[340]: You should make a secure backup of this folder now. This server # [6080394.143375] server acme-order-renew-test.foo-start[340]: configuration directory will also contain private keys server # [6080394.143375] server acme-order-renew-test.foo-start[340]: generated by lego and certificates obtained from the ACME server # [6080394.143375] server acme-order-renew-test.foo-start[340]: server. Making regular backups of this folder is ideal. server # [6080394.143720] server acme-order-renew-test.foo-start[340]: 2026/08/18 08:23:40 [INFO] [test.foo] acme: Obtaining bundled SAN certificate server # [6080394.215961] server acme-order-renew-test.foo-start[340]: 2026/08/18 08:23:40 [INFO] [test.foo] AuthURL: https://ca.foo/acme/acme/authz/3VUTu6QJ9CgLlHXWW97xVjADwqbLNvVo server # [6080394.215961] server acme-order-renew-test.foo-start[340]: 2026/08/18 08:23:40 [INFO] [test.foo] acme: Could not find solver for: tls-alpn-01 server # [6080394.215961] server acme-order-renew-test.foo-start[340]: 2026/08/18 08:23:40 [INFO] [test.foo] acme: use http-01 solver server # [6080394.215961] server acme-order-renew-test.foo-start[340]: 2026/08/18 08:23:40 [INFO] [test.foo] acme: Trying to solve HTTP-01 server # [6080394.224485] server acme-order-renew-test.foo-start[340]: 2026/08/18 08:23:40 [INFO] [test.foo] The server validated our request server # [6080394.224597] server acme-order-renew-test.foo-start[340]: 2026/08/18 08:23:40 [INFO] [test.foo] acme: Validations succeeded; requesting certificates server # [6080394.245271] server acme-order-renew-test.foo-start[340]: 2026/08/18 08:23:40 [INFO] [test.foo] Server responded with a certificate. server # [6080394.249778] server acme-order-renew-test.foo-start[332]: + mv domainhash.txt certificates/ server # [6080394.251933] server acme-order-renew-test.foo-start[332]: + touch out/acme-success server # [6080394.253508] server acme-order-renew-test.foo-start[332]: + cmp -s certificates/test.foo.crt out/fullchain.pem server # [6080394.254823] server acme-order-renew-test.foo-start[332]: + touch out/renewed server # [6080394.257215] server acme-order-renew-test.foo-start[332]: + echo Installing new certificate server # [6080394.257215] server acme-order-renew-test.foo-start[332]: Installing new certificate server # [6080394.257215] server acme-order-renew-test.foo-start[332]: + cp -vp certificates/test.foo.crt out/fullchain.pem server # [6080394.259066] server acme-order-renew-test.foo-start[371]: 'certificates/test.foo.crt' -> 'out/fullchain.pem' server # [6080394.259449] server acme-order-renew-test.foo-start[332]: + cp -vp certificates/test.foo.key out/key.pem server # [6080394.261155] server acme-order-renew-test.foo-start[372]: 'certificates/test.foo.key' -> 'out/key.pem' server # [6080394.261467] server acme-order-renew-test.foo-start[332]: + cp -vp certificates/test.foo.issuer.crt out/chain.pem server # [6080394.262893] server acme-order-renew-test.foo-start[373]: 'certificates/test.foo.issuer.crt' -> 'out/chain.pem' server # [6080394.263191] server acme-order-renew-test.foo-start[332]: + ln -sf fullchain.pem out/cert.pem server # [6080394.264720] server acme-order-renew-test.foo-start[332]: + cat out/key.pem out/fullchain.pem server # [6080394.266532] server acme-order-renew-test.foo-start[332]: + for fixpath in out certificates server # [6080394.266532] server acme-order-renew-test.foo-start[332]: + '[' -d out ']' server # [6080394.266628] server acme-order-renew-test.foo-start[332]: + chmod -R u=rwX,g=rX,o= out server # [6080394.268142] server acme-order-renew-test.foo-start[332]: + chown -R acme:nginx out server # [6080394.273766] server acme-order-renew-test.foo-start[332]: + for fixpath in out certificates server # [6080394.273766] server acme-order-renew-test.foo-start[332]: + '[' -d certificates ']' server # [6080394.273867] server acme-order-renew-test.foo-start[332]: + chmod -R u=rwX,g=rX,o= certificates server # [6080394.275406] server acme-order-renew-test.foo-start[332]: + chown -R acme:nginx certificates server # [6080394.278319] server acme-order-renew-test.foo-start[332]: + chmod -R u=rwX,g=,o= accounts/. ca # [6080394.124916] ca step-ca[204]: time="2026-08-18T08:23:40Z" level=info duration="48.12µs" duration-ns=48120 fields.time="2026-08-18T08:23:40Z" method=GET name=ca path=/acme/acme/directory protocol=HTTP/1.1 referer= remote-address="::1" request-id=a91c75bf-becc-482e-8123-35d4d9c72daa 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= ca # [6080394.136108] ca step-ca[204]: time="2026-08-18T08:23:40Z" level=info duration=6.374008ms duration-ns=6374008 fields.time="2026-08-18T08:23:40Z" method=HEAD name=ca nonce=Y3cwUXhVZ1NzZUhaMDVMbEd2ek5EOGs1c3F2RGduM2Q path=/acme/acme/new-nonce protocol=HTTP/1.1 referer= remote-address=127.0.0.1 request-id=14ca3766-c618-4085-803d-cffad28bca6b size=0 status=200 user-agent="lego-cli/4.35.2 xenolf-acme/4.35.2 (release; linux; arm64)" user-id= ca # [6080394.142775] ca step-ca[204]: time="2026-08-18T08:23:40Z" level=info duration=3.062202ms duration-ns=3062202 fields.time="2026-08-18T08:23:40Z" method=POST name=ca nonce=TjN0RDlDWERCYXVtQkZKVzJ1OWpDMGdBOEFFZ0JmcUI path=/acme/acme/new-account protocol=HTTP/1.1 referer= remote-address="::1" request-id=f95c570f-6482-4249-baee-5a3f4b82f4e7 response="{\"contact\":[\"mailto:none@none.tld\"],\"status\":\"valid\",\"orders\":\"https://ca.foo/acme/acme/account/z6NKd0yPbYjmhUm7fcFpk7Z8ik82UbI0/orders\"}" size=138 status=201 user-agent="lego-cli/4.35.2 xenolf-acme/4.35.2 (release; linux; arm64)" user-id= ca # [6080394.153254] ca step-ca[204]: time="2026-08-18T08:23:40Z" level=info duration=5.618438ms duration-ns=5618438 fields.time="2026-08-18T08:23:40Z" method=POST name=ca nonce=UnczcndiMW00d0RxNHh5UFhNZUZta3ZUaGx5dFpyVUU path=/acme/acme/new-order protocol=HTTP/1.1 referer= remote-address=127.0.0.1 request-id=8021078b-7f7a-4fd0-9b07-d6c21f57425f response="{\"id\":\"4Wtr7wHvgwcbG71IR1mJEvm8U8klc6Ob\",\"status\":\"pending\",\"expires\":\"2026-08-19T08:23:40Z\",\"identifiers\":[{\"type\":\"dns\",\"value\":\"test.foo\"}],\"notBefore\":\"2026-08-18T08:22:40Z\",\"notAfter\":\"2026-11-16T08:23:40Z\",\"authorizations\":[\"https://ca.foo/acme/acme/authz/3VUTu6QJ9CgLlHXWW97xVjADwqbLNvVo\"],\"finalize\":\"https://ca.foo/acme/acme/order/4Wtr7wHvgwcbG71IR1mJEvm8U8klc6Ob/finalize\"}" size=384 status=201 user-agent="lego-cli/4.35.2 xenolf-acme/4.35.2 (release; linux; arm64)" user-id= ca # [6080394.215323] ca step-ca[204]: time="2026-08-18T08:23:40Z" level=info duration=2.067109ms duration-ns=2067109 fields.time="2026-08-18T08:23:40Z" method=POST name=ca nonce=NTVYWE9ORXBqWGozZ1J3MjA4SGtEdTNRbkpFZExqQXQ path=/acme/acme/authz/3VUTu6QJ9CgLlHXWW97xVjADwqbLNvVo protocol=HTTP/1.1 referer= remote-address="::1" request-id=5b37a29e-d9ea-44e7-8ac4-16b3124900ce response="{\"identifier\":{\"type\":\"dns\",\"value\":\"test.foo\"},\"status\":\"pending\",\"challenges\":[{\"type\":\"dns-01\",\"status\":\"pending\",\"token\":\"Wty9twzwg3J2wLpX5I1EXmot2iOcYHWx\",\"url\":\"https://ca.foo/acme/acme/challenge/3VUTu6QJ9CgLlHXWW97xVjADwqbLNvVo/kKi6xxrKXm8xVObRFKWwEStIFFKAqqbP\"},{\"type\":\"http-01\",\"status\":\"pending\",\"token\":\"Wty9twzwg3J2wLpX5I1EXmot2iOcYHWx\",\"url\":\"https://ca.foo/acme/acme/challenge/3VUTu6QJ9CgLlHXWW97xVjADwqbLNvVo/IrKvkLWyTZH42up3fTudAy61HcfT7AyI\"},{\"type\":\"tls-alpn-01\",\"status\":\"pending\",\"token\":\"Wty9twzwg3J2wLpX5I1EXmot2iOcYHWx\",\"url\":\"https://ca.foo/acme/acme/challenge/3VUTu6QJ9CgLlHXWW97xVjADwqbLNvVo/YVAOYHBPLdb0FGaemAaUwrAydeiA8n0U\"}],\"wildcard\":false,\"expires\":\"2026-08-19T08:23:40Z\"}" size=706 status=200 user-agent="lego-cli/4.35.2 xenolf-acme/4.35.2 (release; linux; arm64)" user-id= ca # [6080394.223840] ca step-ca[204]: time="2026-08-18T08:23:40Z" level=info duration=4.404741ms duration-ns=4404741 fields.time="2026-08-18T08:23:40Z" method=POST name=ca nonce=V1l1TmpsNWxsaVVNZThLcWlSakJVdmhjQXlkWnJXWVI path=/acme/acme/challenge/3VUTu6QJ9CgLlHXWW97xVjADwqbLNvVo/IrKvkLWyTZH42up3fTudAy61HcfT7AyI protocol=HTTP/1.1 referer= remote-address=127.0.0.1 request-id=7922f2e5-428a-4ccc-8dd9-b4610e982478 response="{\"type\":\"http-01\",\"status\":\"valid\",\"token\":\"Wty9twzwg3J2wLpX5I1EXmot2iOcYHWx\",\"validated\":\"2026-08-18T08:23:40Z\",\"url\":\"https://ca.foo/acme/acme/challenge/3VUTu6QJ9CgLlHXWW97xVjADwqbLNvVo/IrKvkLWyTZH42up3fTudAy61HcfT7AyI\"}" size=223 status=200 user-agent="lego-cli/4.35.2 xenolf-acme/4.35.2 (release; linux; arm64)" user-id= ca # [6080394.237915] ca step-ca[204]: time="2026-08-18T08:23:40Z" level=info duration=8.401117ms duration-ns=8401117 fields.time="2026-08-18T08:23:40Z" method=POST name=ca nonce=cEVUM25wNnc2T3BnSTBvenFnSFdUcWxmQ2c0SGE3WFc path=/acme/acme/order/4Wtr7wHvgwcbG71IR1mJEvm8U8klc6Ob/finalize protocol=HTTP/1.1 referer= remote-address="::1" request-id=6d3f9665-57b2-4442-9f13-f2665928e5ea response="{\"id\":\"4Wtr7wHvgwcbG71IR1mJEvm8U8klc6Ob\",\"status\":\"valid\",\"expires\":\"2026-08-19T08:23:40Z\",\"identifiers\":[{\"type\":\"dns\",\"value\":\"test.foo\"}],\"notBefore\":\"2026-08-18T08:22:40Z\",\"notAfter\":\"2026-11-16T08:23:40Z\",\"authorizations\":[\"https://ca.foo/acme/acme/authz/3VUTu6QJ9CgLlHXWW97xVjADwqbLNvVo\"],\"finalize\":\"https://ca.foo/acme/acme/order/4Wtr7wHvgwcbG71IR1mJEvm8U8klc6Ob/finalize\",\"certificate\":\"https://ca.foo/acme/acme/certificate/8Bb8YeJs5w5rkKBs13XyIA9Ay2d4Ay2B\"}" size=468 status=200 user-agent="lego-cli/4.35.2 xenolf-acme/4.35.2 (release; linux; arm64)" user-id= ca # [6080394.244749] ca step-ca[204]: time="2026-08-18T08:23:40Z" level=info certificate="MIIB2DCCAX2gAwIBAgIQReqxhLb5x9m3r0ar00/LnTAKBggqhkjOPQQDAjAfMR0wGwYDVQQDExRDbGFuIEludGVybWVkaWF0ZSBDQTAeFw0yNjA4MTgwODIyNDBaFw0yNjExMTYwODIzNDBaMBMxETAPBgNVBAMTCHRlc3QuZm9vMFkwEwYHKoZIzj0CAQYIKoZIzj0DAQcDQgAEq3oXHQMseGMV3iKk7E2AJsN7n7QU+7BORvXYmFEvqq6Mv6nC7PrdshJ8NVEjc9R+Mn2CXsjK9vxKiRiSfwcMdaOBpjCBozAOBgNVHQ8BAf8EBAMCB4AwHQYDVR0lBBYwFAYIKwYBBQUHAwEGCCsGAQUFBwMCMB0GA1UdDgQWBBRRBVVulArskWSq8xDKCs/gHBiUQDAfBgNVHSMEGDAWgBQoXyeC46071qdek0cKnkXAZluVbDATBgNVHREEDDAKggh0ZXN0LmZvbzAdBgwrBgEEAYKkZMYoQAEEDTALAgEGBARhY21lBAAwCgYIKoZIzj0EAwIDSQAwRgIhAO3ct48XOjTYUy0ifFyphCWIsasADhkqO7Lip1r5sQdsAiEAqLb80N3BQh2IlaW+kcMq1Y0LwV5PoNX1cyJDQibquCw=" duration=2.023748ms duration-ns=2023748 fields.time="2026-08-18T08:23:40Z" issuer="Clan Intermediate CA" method=POST name=ca nonce=eXRSbmR5ZE1lSHYwR3gzakpOWUlwTFg1a2VNcUE4MzY path=/acme/acme/certificate/8Bb8YeJs5w5rkKBs13XyIA9Ay2d4Ay2B protocol=HTTP/1.1 provisioner=acme public-key="ECDSA P-256" referer= remote-address=127.0.0.1 request-id=73e87adc-637e-4a78-b0f4-62c83aff8e88 sans="map[dns:[test.foo]]" serial=92935329675301881207674700072106314653 size=1348 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-08-18T08:22:40Z" valid-to="2026-11-16T08:23:40Z" * Trying [2001:db8:1::3]:443... * Host test.foo:443 was resolved. * IPv6: 2001:db8:1::3 * IPv4: 192.168.1.3 * ALPN: curl offers h2,http/1.1 } [5 bytes data] * TLSv1.3 (OUT), TLS handshake, Client hello (1): } [1552 bytes data] * SSL Trust Anchors: * OpenSSL default paths (fallback) { [5 bytes data] * TLSv1.3 (IN), TLS handshake, Server hello (2): { [1210 bytes data] * TLSv1.3 (IN), TLS change cipher, Change cipher spec (1): { [1 bytes data] * TLSv1.3 (IN), TLS handshake, Encrypted Extensions (8): { [19 bytes data] * TLSv1.3 (IN), TLS handshake, Certificate (11): { [1010 bytes data] * TLSv1.3 (IN), TLS handshake, CERT verify (15): { [111 bytes data] * TLSv1.3 (IN), TLS handshake, Finished (20): { [52 bytes data] * TLSv1.3 (OUT), TLS change cipher, Change cipher spec (1): } [1 bytes data] * TLSv1.3 (OUT), TLS handshake, Finished (20): } [52 bytes data] * SSL connection using TLSv1.3 / TLS_AES_256_GCM_SHA384 / X25519MLKEM768 / id-ecPublicKey * ALPN: server accepted h2 * Server certificate: * subject: CN=test.foo * start date: Aug 18 08:23:31 2026 GMT * expire date: Sep 17 08:23:31 2028 GMT * issuer: CN=minica root ca 142cc5 * Certificate level 0: Public key type EC/secp384r1 (384/192 Bits/secBits), signed using ecdsa-with-SHA384 * Certificate level 1: Public key type EC/secp384r1 (384/192 Bits/secBits), signed using ecdsa-with-SHA384 * subjectAltName: "test.foo" matches cert's "test.foo" * OpenSSL verify result: 13 * SSL certificate OpenSSL verify result: self-signed certificate in certificate chain (19) * closing connection #0 curl: (60) SSL certificate OpenSSL verify result: self-signed certificate in certificate chain (19) More details here: https://curl.se/docs/sslcerts.html curl failed to verify the legitimacy of the server and therefore could not establish a secure connection to it. To learn more about this situation and how to fix it, please visit the webpage mentioned above. server # [6080394.464766] server systemd[1]: Reloading Nginx Web Server... server # [6080394.468690] server systemd[1]: acme-order-renew-test.foo.service: Deactivated successfully. server # [6080394.468908] server systemd[1]: Finished Order (and renew) ACME certificate for test.foo. server # [6080394.995785] server nginx[389]: nginx: the configuration file /nix/store/cg1ianm6b7c0wkyi3akik85ix2jk9h6y-nginx.conf syntax is ok server # [6080394.996400] server nginx[389]: nginx: configuration file /nix/store/cg1ianm6b7c0wkyi3akik85ix2jk9h6y-nginx.conf test is successful * Trying [2001:db8:1::3]:443... * Host test.foo:443 was resolved. * IPv6: 2001:db8:1::3 * IPv4: 192.168.1.3 * ALPN: curl offers h2,http/1.1 } [5 bytes data] * TLSv1.3 (OUT), TLS handshake, Client hello (1): } [1552 bytes data] * SSL Trust Anchors: * OpenSSL default paths (fallback) { [5 bytes data] * TLSv1.3 (IN), TLS handshake, Server hello (2): { [1210 bytes data] * TLSv1.3 (IN), TLS change cipher, Change cipher spec (1): { [1 bytes data] * TLSv1.3 (IN), TLS handshake, Encrypted Extensions (8): { [19 bytes data] * TLSv1.3 (IN), TLS handshake, Certificate (11): { [932 bytes data] * TLSv1.3 (IN), TLS handshake, CERT verify (15): { [80 bytes data] * TLSv1.3 (IN), TLS handshake, Finished (20): { [52 bytes data] * TLSv1.3 (OUT), TLS change cipher, Change cipher spec (1): } [1 bytes data] * TLSv1.3 (OUT), TLS handshake, Finished (20): } [52 bytes data] * SSL connection using TLSv1.3 / TLS_AES_256_GCM_SHA384 / X25519MLKEM768 / id-ecPublicKey * ALPN: server accepted h2 * Server certificate: * subject: CN=test.foo * start date: Aug 18 08:22:40 2026 GMT * expire date: Nov 16 08:23:40 2026 GMT * issuer: CN=Clan Intermediate CA * Certificate level 0: Public key type EC/prime256v1 (256/128 Bits/secBits), signed using ecdsa-with-SHA256 * Certificate level 1: Public key type EC/prime256v1 (256/128 Bits/secBits), signed using ecdsa-with-SHA256 * Certificate level 2: Public key type EC/prime256v1 (256/128 Bits/secBits), signed using ecdsa-with-SHA256 * subjectAltName: "test.foo" matches cert's "test.foo" * OpenSSL verify result: 0 * SSL certificate verified via OpenSSL. * Established connection to test.foo (2001:db8:1::3 port 443) from 2001:db8:1::2 port 55544 % Total % Received % Xferd Average Speed Time Time Time Current Dload Upload Total Spent Left Speed 0 0 0 0 0 0 0 0 0* using HTTP/2 * [HTTP/2] [1] OPENED stream for https://test.foo/ * [HTTP/2] [1] [:method: GET] * [HTTP/2] [1] [:scheme: https] * [HTTP/2] [1] [:authority: test.foo] * [HTTP/2] [1] [:path: /] * [HTTP/2] [1] [user-agent: curl/8.21.0] * [HTTP/2] [1] [accept: */*] } [5 bytes data] * Request completely sent off { [5 bytes data] * TLSv1.3 (IN), TLS handshake, Newsession Ticket (4): { [265 bytes data] * TLSv1.3 (IN), TLS handshake, Newsession Ticket (4): { [265 bytes data] { [5 bytes data] 100 20 100 20 0 0 667 0 0 * Connection #0 to host test.foo:443 left intact client: (finished: waiting for success: curl -v https://test.foo, in 2.17 seconds) client: must succeed: openssl s_client -connect test.foo:443 -servername test.foo /dev/null | openssl x509 -text -noout 1>&2 Certificate: Data: Version: 3 (0x2) Serial Number: 45:ea:b1:84:b6:f9:c7:d9:b7:af:46:ab:d3:4f:cb:9d Signature Algorithm: ecdsa-with-SHA256 Issuer: CN=Clan Intermediate CA Validity Not Before: Aug 18 08:22:40 2026 GMT Not After : Nov 16 08:23:40 2026 GMT Subject: CN=test.foo Subject Public Key Info: Public Key Algorithm: id-ecPublicKey Public-Key: (256 bit) pub: 04:ab:7a:17:1d:03:2c:78:63:15:de:22:a4:ec:4d: 80:26:c3:7b:9f:b4:14:fb:b0:4e:46:f5:d8:98:51: 2f:aa:ae:8c:bf:a9:c2:ec:fa:dd:b2:12:7c:35:51: 23:73:d4:7e:32:7d:82:5e:c8:ca:f6:fc:4a:89:18: 92:7f:07:0c:75 ASN1 OID: prime256v1 NIST CURVE: P-256 X509v3 extensions: X509v3 Key Usage: critical Digital Signature X509v3 Extended Key Usage: TLS Web Server Authentication, TLS Web Client Authentication X509v3 Subject Key Identifier: 51:05:55:6E:94:0A:EC:91:64:AA:F3:10:CA:0A:CF:E0:1C:18:94:40 X509v3 Authority Key Identifier: 28:5F:27:82:E3:AD:3B:D6:A7:5E:93:47:0A:9E:45:C0:66:5B:95:6C X509v3 Subject Alternative Name: DNS:test.foo 1.3.6.1.4.1.37476.9000.64.1: 0......acme.. Signature Algorithm: ecdsa-with-SHA256 Signature Value: 30:46:02:21:00:ed:dc:b7:8f:17:3a:34:d8:53:2d:22:7c:5c: a9:84:25:88:b1:ab:00:0e:19:2a:3b:b2:e2:a7:5a:f9:b1:07: 6c:02:21:00:a8:b6:fc:d0:dd:c1:42:1d:88:95:a5:be:91:c3: 2a:d5:8d:0b:c1:5e:4f:a0:d5:f5:73:22:43:42:26:ea:b8:2c client: (finished: must succeed: openssl s_client -connect test.foo:443 -servername test.foo /dev/null | openssl x509 -text -noout 1>&2, in 0.04 seconds) (finished: run the VM test script, in 14.02 seconds) test script finished in 14.08s cleanup kill NspawnMachine (pid 53) kill NspawnMachine (pid 55) kill NspawnMachine (pid 54) server # [6080395.541753] server systemd[1]: Reloaded Nginx Web Server. Container ca terminated by signal KILL. Container client terminated by signal KILL. (finished: cleanup, in 0.34 seconds) Container server terminated by signal KILL.