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: machine, router, 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 machine: systemd-nspawn running (pid 52) router: systemd-nspawn running (pid 53) machine: Waiting for journal at /build/vm-state-machine/var/log/journal... router: Waiting for journal at /build/vm-state-router/var/log/journal... (finished: start all VMs, in 0.00 seconds) router: waiting for unit multi-user.target nixos-nspawn(machine): TAP vde-tap1 not found; container will be isolated from VDE nixos-nspawn(machine): 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(router): TAP vde-tap1 not found; container will be isolated from VDE nixos-nspawn(router): 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. ░ Spawning container machine on /build/vm-state-machine. 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 router on /build/vm-state-router. router # No journal files were found. router # No journal boot entry found for the specified boot (+0). machine # No journal files were found. machine # No journal boot entry found for the specified boot (+0). router # No journal files were found. router # No journal boot entry found for the specified boot (+0). machine # No journal files were found. machine # No journal boot entry found for the specified boot (+0). machine # [6727661.242271] machine systemd-journald[54]: Journal started machine # [6727661.242338] machine systemd-journald[54]: Runtime Journal (/run/log/journal/6ce4b9ed0d92424db4798fb3b692911a) is 8M, max 2.5G, 2.4G free. machine # [6727661.781805] machine systemd[1]: Finished Firewall. machine # [6727661.783556] machine systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine # [6727662.365941] machine systemd[1]: Reached target Preparation for Network. machine # [6727662.371152] machine systemd[1]: Starting Flush Journal to Persistent Storage... machine # [6727662.373401] machine systemd[1]: Starting Create Static Device Nodes in /dev... machine # [6727662.382732] machine systemd-journald[54]: Time spent on flushing to /var/log/journal/6ce4b9ed0d92424db4798fb3b692911a is 1.578ms for 7 entries. machine # [6727662.382732] machine systemd-journald[54]: System Journal (/var/log/journal/6ce4b9ed0d92424db4798fb3b692911a) is 8M, max 4G, 3.9G free. machine # [6727662.440316] machine systemd[1]: Finished Create Static Device Nodes in /dev. machine # [6727662.548019] machine systemd[1]: Reached target Preparation for Local File Systems. machine # [6727662.548216] machine systemd[1]: Reached target Local File Systems. machine # [6727662.549843] machine systemd[1]: Listening on Boot Loader Control Service Socket. machine # [6727662.549906] machine systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container machine # [6727662.625593] machine systemd[1]: Starting Save Transient machine-id to Disk... machine # [6727662.625669] machine systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys machine # [6727662.626635] machine systemd[1]: Finished Flush Journal to Persistent Storage. machine # [6727662.629876] machine systemd[1]: Starting Create System Files and Directories... machine # [6727662.654066] machine systemd-tmpfiles[197]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted machine # [6727662.654849] machine systemd-tmpfiles[197]: fchmod() of /var/log/journal failed: Operation not permitted machine # [6727662.654979] machine systemd-tmpfiles[197]: fchmod() of /var/log/journal/6ce4b9ed0d92424db4798fb3b692911a failed: Operation not permitted machine # [6727662.655164] machine systemd-tmpfiles[197]: fchmod() of /run/log/journal failed: Operation not permitted router # [6727661.252499] router systemd-journald[54]: Journal started router # [6727661.252584] router systemd-journald[54]: Runtime Journal (/run/log/journal/b23e32c80833449d90b254b5150278f8) is 8M, max 2.5G, 2.4G free. router # [6727661.776674] router systemd[1]: Finished Apply Kernel Variables. router # [6727662.392864] router systemd[1]: Finished Create Static Device Nodes in /dev gracefully. router # [6727662.522466] router systemd[1]: Starting Flush Journal to Persistent Storage... router # [6727662.524134] router systemd[1]: Starting Network Name Resolution... router # [6727662.525285] router systemd[1]: Starting Create Static Device Nodes in /dev... router # [6727662.630146] router systemd-journald[54]: Time spent on flushing to /var/log/journal/b23e32c80833449d90b254b5150278f8 is 1.219ms for 7 entries. router # [6727662.630146] router systemd-journald[54]: System Journal (/var/log/journal/b23e32c80833449d90b254b5150278f8) is 8M, max 4G, 3.9G free. router # [6727662.859977] router systemd[1]: Finished Flush Journal to Persistent Storage. router # [6727662.875775] router systemd[1]: Finished Create Static Device Nodes in /dev. machine # [6727662.828345] machine systemd[1]: Finished Create System Files and Directories. machine # [6727662.962803] machine systemd[1]: Starting Rebuild Journal Catalog... router # [6727663.116646] router systemd[1]: Reached target Preparation for Local File Systems. router # [6727663.116805] router systemd[1]: Reached target Local File Systems. router # [6727663.118357] router systemd[1]: Listening on Boot Loader Control Service Socket. router # [6727663.118423] router systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container router # [6727663.122179] router systemd[1]: Starting Save Transient machine-id to Disk... router # [6727663.126613] router systemd[1]: Starting Create System Files and Directories... router # [6727663.126697] router systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys router # [6727663.128476] router systemd[1]: Starting Network Management... machine # [6727662.964135] machine systemd[1]: Starting Record System Boot/Shutdown in UTMP... machine # [6727663.063698] machine systemd[1]: Finished Record System Boot/Shutdown in UTMP. machine # [6727663.068488] machine systemd[1]: Finished Rebuild Journal Catalog. machine # [6727663.070024] machine systemd[1]: Starting Update is Completed... machine # [6727663.083509] machine systemd[1]: Finished Update is Completed. machine # [6727663.084271] machine systemd[1]: Reached target System Initialization. machine # [6727663.084438] machine systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container machine # [6727663.084477] machine systemd[1]: Started Daily Cleanup of Temporary Directories. machine # [6727663.084499] machine systemd[1]: Reached target Timer Units. machine # [6727663.084646] machine systemd[1]: Listening on D-Bus System Message Bus Socket. machine # [6727663.084807] machine systemd[1]: Listening on Nix Daemon Socket. machine # [6727663.084926] machine systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. machine # [6727663.084950] machine systemd[1]: Reached target Socket Units. machine # [6727663.084987] machine systemd[1]: Reached target Basic System. machine # [6727663.086939] machine systemd[1]: Starting Import lastlog data into lastlog2 database... machine # [6727663.088176] machine systemd[1]: Starting Address configuration of eth1... machine # [6727663.089396] machine systemd[1]: Starting Extra networking commands.... machine # [6727663.090684] machine systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine # [6727663.091896] machine systemd[1]: Starting Setup wg0 dummy interface... machine # [6727663.107458] machine network-addresses-eth1-start[206]: adding address 192.168.1.1/24... done machine # [6727663.109608] machine systemd[1]: Finished Import lastlog data into lastlog2 database. machine # [6727663.110895] machine network-addresses-eth1-start[206]: adding address 2001:db8:1::1/64... done machine # [6727663.113343] machine systemd[1]: Finished Setup wg0 dummy interface. machine # [6727663.114839] machine systemd[1]: Finished Address configuration of eth1. machine # [6727663.174854] machine systemd[1]: Finished Extra networking commands.. machine # [6727663.175156] machine systemd[1]: nscd.service: Deactivated successfully. machine # [6727663.175571] machine systemd[1]: Stopped Name Service Cache Daemon (nsncd). machine # [6727663.178025] machine systemd[1]: Reached target Network. machine # [6727663.180181] machine systemd[1]: Starting Nginx Web Server... machine # [6727663.181597] machine systemd[1]: Starting Name Service Cache Daemon (nsncd)... router # [6727663.141861] router systemd-tmpfiles[68]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted router # [6727663.142062] router systemd-tmpfiles[68]: fchmod() of /var/log/journal failed: Operation not permitted router # [6727663.142183] router systemd-tmpfiles[68]: fchmod() of /var/log/journal/b23e32c80833449d90b254b5150278f8 failed: Operation not permitted router # [6727663.142360] router systemd-tmpfiles[68]: fchmod() of /run/log/journal failed: Operation not permitted router # [6727663.281024] router systemd[1]: Finished Create System Files and Directories. router # [6727663.283450] router systemd[1]: Starting Rebuild Journal Catalog... router # [6727663.284590] router systemd[1]: Starting Record System Boot/Shutdown in UTMP... router # [6727663.301376] router systemd[1]: Finished Record System Boot/Shutdown in UTMP. router # [6727663.307877] router systemd[1]: Finished Rebuild Journal Catalog. router # [6727663.310447] router systemd[1]: Starting Update is Completed... router # [6727663.323396] router systemd[1]: Finished Update is Completed. router # [6727663.918690] router systemd[1]: etc-machine\x2did.mount: Deactivated successfully. router # [6727663.944439] router systemd[1]: Finished Save Transient machine-id to Disk. machine # [6727663.913940] machine systemd[1]: etc-machine\x2did.mount: Deactivated successfully. machine # [6727663.944410] machine systemd[1]: Finished Save Transient machine-id to Disk. machine # [6727664.596033] machine nsncd[283]: Aug 25 20:11:30.647 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine # [6727664.594262] machine systemd[1]: Started Name Service Cache Daemon (nsncd). machine # [6727664.594360] machine systemd[1]: Reached target Host and Network Name Lookups. machine # [6727664.594421] machine systemd[1]: Reached target User and Group Name Lookups. machine # [6727664.622859] machine systemd[1]: Starting User Login Management... machine # [6727664.624199] machine systemd[1]: Starting Permit User Sessions... machine # [6727664.637352] machine systemd[1]: Finished Permit User Sessions. machine # [6727664.638581] machine systemd[1]: Started Console Getty. machine # [6727664.638635] machine systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0 machine # [6727664.638655] machine systemd[1]: Reached target Login Prompts. machine # [6727665.520260] machine systemd-logind[299]: New seat seat0. machine # [6727665.521272] machine systemd[1]: Starting D-Bus System Message Bus... machine # [6727665.521396] machine systemd[1]: Started User Login Management. machine # [6727665.522510] machine systemd[1]: Starting linger-users.service... machine # [6727665.533949] machine systemd[1]: linger-users.service: Deactivated successfully. machine # [6727665.534107] machine systemd[1]: Finished linger-users.service. machine # [6727665.544676] machine nginx-pre-start[312]: nginx: the configuration file /nix/store/5pcs84r3dp830lc3hlalvw13r6y9xas4-nginx.conf syntax is ok machine # [6727665.545023] machine nginx-pre-start[312]: nginx: configuration file /nix/store/5pcs84r3dp830lc3hlalvw13r6y9xas4-nginx.conf test is successful machine # [6727665.549212] machine systemd[1]: Started Nginx Web Server. machine # [6727665.549395] machine systemd[1]: Reached target Multi-User System. machine # [6727665.769119] machine dbus-broker-launch[309]: Looking up NSS user entry for 'systemd-timesync'... machine # [6727665.770234] machine dbus-broker-launch[309]: NSS returned no entry for 'systemd-timesync' machine # [6727665.770234] machine dbus-broker-launch[309]: Invalid user-name in /nix/store/p6vbdp9f12s33mjq914crsv0dyl62jlp-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" machine # [6727665.770703] machine systemd[1]: Started D-Bus System Message Bus. router # [6727665.439525] router systemd-networkd[69]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted router # [6727665.439618] router systemd-networkd[69]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted router # [6727665.446113] router systemd-networkd[69]: lo: Link UP router # [6727665.446120] router systemd-networkd[69]: lo: Gained carrier router # [6727665.446337] router systemd-networkd[69]: eth1: Configuring with /etc/systemd/network/40-eth1.network. router # [6727665.446752] router systemd[1]: Started Network Management. router # [6727665.446829] router systemd-networkd[69]: eth1: Link UP router # [6727665.447047] router systemd-networkd[69]: eth1: Gained carrier router # [6727665.447890] router systemd[1]: Starting Enable Persistent Storage in systemd-networkd... router # [6727665.485695] router systemd[1]: Finished Enable Persistent Storage in systemd-networkd. machine # [6727665.778145] machine dbus-broker-launch[309]: Ready machine # [6727665.778655] machine systemd[1]: Startup finished in 5.469s. router # [6727665.818724] router systemd-resolved[61]: Positive Trust Anchors: router # [6727665.818737] router systemd-resolved[61]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d router # [6727665.818739] router systemd-resolved[61]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 router # [6727665.818774] router systemd-resolved[61]: 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 router # [6727665.841750] router systemd-resolved[61]: Using system hostname 'router'. router # [6727665.843147] router systemd[1]: Started Network Name Resolution. router # [6727665.843247] router systemd[1]: Reached target Network. router # [6727665.843325] router systemd[1]: Reached target System Initialization. router # [6727665.843393] router systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container router # [6727665.843429] router systemd[1]: Started Daily Cleanup of Temporary Directories. router # [6727665.843448] router systemd[1]: Reached target Timer Units. router # [6727665.843611] router systemd[1]: Listening on D-Bus System Message Bus Socket. router # [6727665.843768] router systemd[1]: Listening on Nix Daemon Socket. router # [6727665.843906] router systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. router # [6727665.843939] router systemd[1]: Reached target Socket Units. router # [6727665.843992] router systemd[1]: Reached target Basic System. router # [6727665.845342] router systemd[1]: Starting Import lastlog data into lastlog2 database... router # [6727665.846307] router systemd[1]: Starting Nginx Web Server... router # [6727665.847199] router systemd[1]: Starting Name Service Cache Daemon (nsncd)... router # [6727665.848635] router systemd[1]: Starting D-Bus System Message Bus... router # [6727665.864870] router systemd[1]: Finished Import lastlog data into lastlog2 database. router # [6727665.965827] router nsncd[84]: Aug 25 20:11:32.018 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" router # [6727665.968371] router systemd[1]: Started Name Service Cache Daemon (nsncd). router # [6727665.968505] router systemd[1]: Reached target Host and Network Name Lookups. router # [6727665.968605] router systemd[1]: Reached target User and Group Name Lookups. router # [6727665.974421] router systemd[1]: Starting User Login Management... router # [6727665.980130] router systemd[1]: Starting Permit User Sessions... router # [6727666.029346] router systemd[1]: Finished Permit User Sessions. router # [6727666.031484] router systemd[1]: Started Console Getty. router # [6727666.031543] router systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0 router # [6727666.031563] router systemd[1]: Reached target Login Prompts. router # [6727666.759855] router dbus-broker-launch[85]: Looking up NSS user entry for 'systemd-timesync'... router # [6727666.760634] router dbus-broker-launch[85]: NSS returned no entry for 'systemd-timesync' router # [6727666.760634] router dbus-broker-launch[85]: Invalid user-name in /nix/store/gny6p0qbj5kanj00z99rj9lhgv2ih4q9-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" router # [6727666.761042] router systemd[1]: Started D-Bus System Message Bus. router # [6727666.768942] router dbus-broker-launch[85]: Ready router # [6727667.008158] router systemd-networkd[69]: eth1: Gained IPv6LL router: (finished: waiting for unit multi-user.target, in 7.67 seconds) router: waiting for unit nginx.service router: (finished: waiting for unit nginx.service, in 0.01 seconds) machine: waiting for unit multi-user.target machine: (finished: waiting for unit multi-user.target, in 0.01 seconds) machine: waiting for unit nginx.service machine: (finished: waiting for unit nginx.service, in 0.01 seconds) router: must succeed: ip -4 addr show eth1 | grep -oP '(?<=inet\s)\d+(\.\d+){3}' router: (finished: must succeed: ip -4 addr show eth1 | grep -oP '(?<=inet\s)\d+(\.\d+){3}', in 0.01 seconds) router: must succeed: ip -6 addr show eth1 | grep -oP '(?<=inet6\s)[0-9a-f:]+' | grep -v '^fe80' | head -1 router: (finished: must succeed: ip -6 addr show eth1 | grep -oP '(?<=inet6\s)[0-9a-f:]+' | grep -v '^fe80' | head -1, in 0.01 seconds) Router IPv4: 192.168.1.2 Router IPv6: 2001:db8:1::2 machine: must succeed: systemctl restart firewall router # [6727667.309594] router nginx-pre-start[114]: nginx: the configuration file /nix/store/ckdsz8m30l61fpv1pkpg4n8a9lpfdj27-nginx.conf syntax is ok router # [6727667.309983] router nginx-pre-start[114]: nginx: configuration file /nix/store/ckdsz8m30l61fpv1pkpg4n8a9lpfdj27-nginx.conf test is successful router # [6727667.315810] router systemd[1]: Started Nginx Web Server. router # [6727667.334534] router systemd-logind[103]: New seat seat0. router # [6727667.334727] router systemd[1]: Started User Login Management. router # [6727667.357112] router systemd[1]: Starting linger-users.service... router # [6727667.373107] router systemd[1]: linger-users.service: Deactivated successfully. router # [6727667.373273] router systemd[1]: Finished linger-users.service. router # [6727667.373701] router systemd[1]: Reached target Multi-User System. router # [6727667.373856] router systemd[1]: Startup finished in 6.980s. machine # [6727667.484569] machine systemd[1]: Stopping Firewall... machine # [6727668.017571] machine systemd[1]: firewall.service: Deactivated successfully. machine # [6727668.017757] machine systemd[1]: Stopped Firewall. machine # [6727668.019183] machine systemd[1]: Starting Firewall... machine: (finished: must succeed: systemctl restart firewall, in 2.16 seconds) machine: waiting for unit firewall.service machine: (finished: waiting for unit firewall.service, in 0.01 seconds) machine: must succeed: iptables -L user-firewall-output >&2 Chain user-firewall-output (1 references) target prot opt source destination RETURN all -- anywhere anywhere RETURN all -- anywhere anywhere RETURN all -- anywhere anywhere RETURN all -- anywhere anywhere RETURN all -- anywhere anywhere RETURN all -- anywhere anywhere RETURN all -- anywhere anywhere RETURN all -- anywhere anywhere RETURN all -- anywhere anywhere RETURN all -- anywhere anywhere RETURN all -- anywhere anywhere RETURN all -- anywhere anywhere RETURN all -- anywhere anywhere RETURN all -- anywhere anywhere RETURN all -- anywhere anywhere REJECT all -- anywhere anywhere owner UID match bob reject-with icmp-port-unreachable RETURN all -- anywhere anywhere machine: (finished: must succeed: iptables -L user-firewall-output >&2, in 0.01 seconds) machine: waiting for success: runuser -u alice -- curl -s http://127.0.0.1:8080 machine: (finished: waiting for success: runuser -u alice -- curl -s http://127.0.0.1:8080, in 0.04 seconds) machine: must succeed: runuser -u alice -- curl -s http://192.168.1.2 machine: (finished: must succeed: runuser -u alice -- curl -s http://192.168.1.2, in 0.05 seconds) machine: must succeed: runuser -u alice -- curl -s http://[2001:db8:1::2] machine: (finished: must succeed: runuser -u alice -- curl -s http://[2001:db8:1::2], in 0.03 seconds) machine: must succeed: runuser -u bob -- curl -s http://127.0.0.1:8080 machine: (finished: must succeed: runuser -u bob -- curl -s http://127.0.0.1:8080, in 0.03 seconds) machine: must succeed: runuser -u bob -- curl -s --connect-timeout 2 http://192.168.1.2 2>&1 || echo 'EXIT_CODE='$? machine: (finished: must succeed: runuser -u bob -- curl -s --connect-timeout 2 http://192.168.1.2 2>&1 || echo 'EXIT_CODE='$?, in 0.04 seconds) machine: must succeed: runuser -u bob -- curl -s --connect-timeout 2 http://[2001:db8:1::2] 2>&1 || echo 'EXIT_CODE='$? machine # [6727669.633082] machine systemd[1]: Finished Firewall. machine # [6727669.678428] machine runuser[472]: pam_unix(runuser:session): session opened for user alice(uid=1001) by (uid=0) machine # [6727669.695363] machine runuser[472]: pam_unix(runuser:session): session closed for user alice machine # [6727669.732543] machine runuser[474]: pam_unix(runuser:session): session opened for user alice(uid=1001) by (uid=0) machine # [6727669.744091] machine runuser[474]: pam_unix(runuser:session): session closed for user alice machine # [6727669.762168] machine runuser[476]: pam_unix(runuser:session): session opened for user alice(uid=1001) by (uid=0) machine # [6727669.774136] machine runuser[476]: pam_unix(runuser:session): session closed for user alice machine # [6727669.793312] machine runuser[478]: pam_unix(runuser:session): session opened for user bob(uid=1002) by (uid=0) machine # [6727669.804318] machine runuser[478]: pam_unix(runuser:session): session closed for user bob machine # [6727669.828790] machine runuser[481]: pam_unix(runuser:session): session opened for user bob(uid=1002) by (uid=0) machine # [6727669.840826] machine runuser[481]: pam_unix(runuser:session): session closed for user bob machine # [6727669.858235] machine runuser[484]: pam_unix(runuser:session): session opened for user bob(uid=1002) by (uid=0) machine: (finished: must succeed: runuser -u bob -- curl -s --connect-timeout 2 http://[2001:db8:1::2] 2>&1 || echo 'EXIT_CODE='$?, in 1.04 seconds) machine: must succeed: iptables -L user-firewall-output -n -v machine: (finished: must succeed: iptables -L user-firewall-output -n -v, in 0.01 seconds) machine: must succeed: ip6tables -L user-firewall-output -n -v machine: (finished: must succeed: ip6tables -L user-firewall-output -n -v, in 0.01 seconds) machine: waiting for unit setup-wg0-interface.service machine: (finished: waiting for unit setup-wg0-interface.service, in 0.01 seconds) machine: waiting for unit nginx.service machine: (finished: waiting for unit nginx.service, in 0.01 seconds) machine: waiting for TCP port 8081 on 10.100.0.2 Connection to 10.100.0.2 8081 port [tcp/sunproxyadmin] succeeded! machine: (finished: waiting for TCP port 8081 on 10.100.0.2, in 0.01 seconds) machine: must succeed: ip link show wg0 machine: (finished: must succeed: ip link show wg0, in 0.01 seconds) machine: must succeed: ip addr show wg0 machine: (finished: must succeed: ip addr show wg0, in 0.01 seconds) machine: must succeed: runuser -u alice -- curl -s --interface wg0 http://10.100.0.2:8081/ machine: (finished: must succeed: runuser -u alice -- curl -s --interface wg0 http://10.100.0.2:8081/, in 0.02 seconds) machine: must succeed: runuser -u alice -- curl -s --interface wg0 http://[fd00::2]:8081/ machine: (finished: must succeed: runuser -u alice -- curl -s --interface wg0 http://[fd00::2]:8081/, in 0.02 seconds) machine: must succeed: runuser -u bob -- curl -s --interface wg0 http://10.100.0.2:8081/ machine: (finished: must succeed: runuser -u bob -- curl -s --interface wg0 http://10.100.0.2:8081/, in 0.02 seconds) machine: must succeed: runuser -u bob -- curl -s --interface wg0 http://[fd00::2]:8081/ machine: (finished: must succeed: runuser -u bob -- curl -s --interface wg0 http://[fd00::2]:8081/, in 0.02 seconds) machine: must succeed: iptables -L user-firewall-output -n -v | grep -E 'wg0|wg\+' >&2 0 0 RETURN all -- * wg+ 0.0.0.0/0 0.0.0.0/0 machine: (finished: must succeed: iptables -L user-firewall-output -n -v | grep -E 'wg0|wg\+' >&2, in 0.01 seconds) (finished: run the VM test script, in 11.29 seconds) machine # [6727670.881678] machine runuser[484]: pam_unix(runuser:session): session closed for user bob machine # [6727670.954703] machine runuser[493]: pam_unix(runuser:session): session opened for user alice(uid=1001) by (uid=0) machine # [6727670.964758] machine runuser[493]: pam_unix(runuser:session): session closed for user alice machine # [6727670.975054] machine runuser[495]: pam_unix(runuser:session): session opened for user alice(uid=1001) by (uid=0) machine # [6727670.986802] machine runuser[495]: pam_unix(runuser:session): session closed for user alice machine # [6727670.996200] machine runuser[497]: pam_unix(runuser:session): session opened for user bob(uid=1002) by (uid=0) machine # [6727671.005969] machine runuser[497]: pam_unix(runuser:session): session closed for user bob machine # [6727671.016473] machine runuser[499]: pam_unix(runuser:session): session opened for user bob(uid=1002) by (uid=0) machine # [6727671.027344] machine runuser[499]: pam_unix(runuser:session): session closed for user bob test script finished in 19.08s cleanup kill NspawnMachine (pid 52) kill NspawnMachine (pid 53) Container machine terminated by signal KILL. Container router terminated by signal KILL. (finished: cleanup, in 0.43 seconds)