�[2mmachine # [  172.064687] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2�[0m
�[2mmachine # [  172.065196] cfg80211: failed to load regulatory.db�[0m
�[2mmachine # [  171.941737] nsncd[619]: Aug 18 16:15:56.509 INFO started, handoff_timeout: 3s, worker_count: 8, path: "/var/run/nscd/socket"�[0m
�[2mmachine # [  172.044823] systemd[1]: Started D-Bus System Message Bus.�[0m
�[2mmachine # [  172.053731] systemd[1]: Started Name Service Cache Daemon (nsncd).�[0m
�[2mmachine # [  172.058042] systemd[1]: Reached target Host and Network Name Lookups.�[0m
�[2mmachine # [  172.058977] systemd[1]: Reached target User and Group Name Lookups.�[0m
�[2mmachine # [  172.059954] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.�[0m
�[2mmachine # [  172.109094] systemd[1]: Starting User Login Management...�[0m
machine: Guest shell says: b'Spawning backdoor root shell...\n'
machine: connected to guest root shell
machine: (connecting took 175.53 seconds)
(finished: waiting for the VM to finish booting, in 175.53 seconds)
�[2mmachine # [  172.133648] systemd[1]: Stopped target Host and Network Name Lookups.�[0m
�[2mmachine # [  172.134474] systemd[1]: Stopping Host and Network Name Lookups...�[0m
�[2mmachine # [  172.135982] systemd[1]: Stopped target User and Group Name Lookups.�[0m
�[2mmachine # [  172.138231] systemd[1]: Stopping User and Group Name Lookups...�[0m
�[2mmachine # [  172.138880] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...�[0m
�[2mmachine # [  172.143723] systemd[1]: nscd.service: Deactivated successfully.�[0m
�[2mmachine # [  172.148584] systemd[1]: Stopped Name Service Cache Daemon (nsncd).�[0m
�[2mmachine # [  172.154339] systemd[1]: Starting Name Service Cache Daemon (nsncd)...�[0m
�[2mmachine # [  172.172694] systemd[1]: Started Name Service Cache Daemon (nsncd).�[0m
�[2mmachine # [  172.173320] systemd[1]: Reached target Host and Network Name Lookups.�[0m
�[2mmachine # [  172.173809] systemd[1]: Reached target User and Group Name Lookups.�[0m
�[2mmachine # [  172.175103] nsncd[754]: Aug 18 16:15:56.745 INFO started, handoff_timeout: 3s, worker_count: 8, path: "/var/run/nscd/socket"�[0m
�[2mmachine # [  172.221811] systemd-logind[749]: New seat seat0.�[0m
�[2mmachine # [  172.224652] systemd-logind[749]: Watching system buttons on /dev/input/event2 (Power Button)�[0m
�[2mmachine # [  172.228583] systemd-logind[749]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)�[0m
�[2mmachine # [  172.230795] systemd-logind[749]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)�[0m
�[2mmachine # [  172.232542] systemd[1]: Started User Login Management.�[0m
�[2mmachine # [  172.269671] systemd[1]: Finished resolvconf update.�[0m
�[2mmachine # [  172.270322] systemd[1]: Reached target Preparation for Network.�[0m
�[2mmachine # [  172.270898] systemd[1]: Reached target All Network Interfaces (deprecated).�[0m
�[2mmachine # [  172.275437] systemd[1]: Starting Address configuration of eth1...�[0m
(finished: must succeed: [ $(stat -c%U /run/secrets-rendered/test_template) = 'someuser' ], in 176.55 seconds)
machine: �[1m�[32mmust succeed: [ $(stat -c%G /run/secrets-rendered/test_template) = 'somegroup' ]�[0m
�[2mmachine # [  173.302314] 8021q: adding VLAN 0 to HW filter on device eth0�[0m
�[2mmachine # [  173.152557] dhcpcd[667]: eth0: waiting for carrier�[0m
�[2mmachine # [  173.155091] dhcpcd[667]: eth0: carrier acquired�[0m
(finished: must succeed: [ $(stat -c%G /run/secrets-rendered/test_template) = 'somegroup' ], in 0.05 seconds)
machine: �[1m�[32mmust succeed: [ $(stat -c%U /run/secrets-rendered/test_default) = 'root' ]�[0m
�[2mmachine # [  173.179277] dhcpcd[667]: DUID 00:01:00:01:2c:72:55:bd:52:54:00:12:34:56�[0m
�[2mmachine # [  173.180428] dhcpcd[667]: eth0: IAID 00:12:34:56�[0m
�[2mmachine # [  173.181953] dhcpcd[667]: eth0: adding address fe80::5054:ff:fe12:3456�[0m
(finished: must succeed: [ $(stat -c%U /run/secrets-rendered/test_default) = 'root' ], in 0.03 seconds)
machine: �[1m�[32mmust succeed: [ $(stat -c%G /run/secrets-rendered/test_default) = 'root' ]�[0m
(finished: must succeed: [ $(stat -c%G /run/secrets-rendered/test_default) = 'root' ], in 0.04 seconds)
machine: �[1m�[32mmust succeed: cat /run/secrets-rendered/test_template�[0m
(finished: must succeed: cat /run/secrets-rendered/test_template, in 0.03 seconds)
machine: �[1m�[32mmust succeed: cat /run/secrets-rendered/test_default�[0m
(finished: must succeed: cat /run/secrets-rendered/test_default, in 0.03 seconds)
(finished: run the VM test script, in 184.76 seconds)
�[2mmachine # [  173.552347] 8021q: adding VLAN 0 to HW filter on device eth1�[0m
�[2mmachine # [  173.405559] network-addresses-eth1-start[772]: adding address 192.168.1.1/24... done�[0m
�[2mmachine # [  173.420769] systemd[1]: Finished Address configuration of eth1.�[0m
�[2mmachine # [  173.426431] systemd[1]: Starting Networking Setup...�[0m
�[2mmachine # [  173.634599] dhcpcd[667]: eth0: soliciting an IPv6 router�[0m
�[2mmachine # [  173.837945] dhcpcd[667]: eth0: soliciting a DHCP lease�[0m
�[2mmachine # [  174.003332] NET: Registered PF_PACKET protocol family�[0m
�[2mmachine # [  173.855627] dhcpcd[667]: eth0: offered 10.0.2.15 from 10.0.2.2�[0m
�[2mmachine # [  173.856673] dhcpcd[667]: eth0: leased 10.0.2.15 for 86400 seconds�[0m
�[2mmachine # [  173.857274] dhcpcd[667]: eth0: adding route to 10.0.2.0/24�[0m
�[2mmachine # [  173.857709] dhcpcd[667]: eth0: adding default route via 10.0.2.2�[0m
�[2mmachine # [  173.899381] systemd[1]: Finished Networking Setup.�[0m
�[2mmachine # [  173.904083] systemd[1]: Starting Extra networking commands....�[0m
�[2mmachine # [  174.480614] systemd[1]: Finished Extra networking commands..�[0m
�[2mmachine # [  174.481160] systemd[1]: Reached target Network.�[0m
�[2mmachine # [  174.486425] systemd[1]: Starting Permit User Sessions...�[0m
�[2mmachine # [  174.822100] systemd[1]: Finished Permit User Sessions.�[0m
�[2mmachine # [  174.827295] systemd[1]: Started Getty on tty1.�[0m
�[2mmachine # [  174.829185] systemd[1]: Reached target Login Prompts.�[0m
�[2mmachine # [  177.583613] systemd[1]: Stopped target Host and Network Name Lookups.�[0m
�[2mmachine # [  177.584700] systemd[1]: Stopping Host and Network Name Lookups...�[0m
�[2mmachine # [  177.586052] systemd[1]: Stopped target User and Group Name Lookups.�[0m
�[2mmachine # [  177.587083] systemd[1]: Stopping User and Group Name Lookups...�[0m
�[2mmachine # [  177.590083] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...�[0m
�[2mmachine # [  177.593451] systemd[1]: nscd.service: Deactivated successfully.�[0m
�[2mmachine # [  177.597139] systemd[1]: Stopped Name Service Cache Daemon (nsncd).�[0m
�[2mmachine # [  177.615633] systemd[1]: Starting Name Service Cache Daemon (nsncd)...�[0m
�[2mmachine # [  177.639255] systemd[1]: Started Name Service Cache Daemon (nsncd).�[0m
�[2mmachine # [  177.639994] systemd[1]: Reached target Host and Network Name Lookups.�[0m
�[2mmachine # [  177.640808] systemd[1]: Reached target User and Group Name Lookups.�[0m
�[2mmachine # [  177.644980] nsncd[915]: Aug 18 16:16:02.214 INFO started, handoff_timeout: 3s, worker_count: 8, path: "/var/run/nscd/socket"�[0m
�[2mmachine # [  177.666661] dhcpcd[615]: Failed to reload-or-try-restart ntpd.service: Unit ntpd.service not found.�[0m
�[2mmachine # [  177.668656] dhcpcd[615]: Failed to reload-or-try-restart openntpd.service: Unit openntpd.service not found.�[0m
�[2mmachine # [  177.670825] dhcpcd[615]: Failed to reload-or-try-restart chronyd.service: Unit chronyd.service not found.�[0m
�[2mmachine # [  177.677380] systemd[1]: Started DHCP Client.�[0m
�[2mmachine # [  177.679542] systemd[1]: Reached target Network is Online.�[0m
�[2mmachine # [  177.684554] systemd[1]: Reached target Multi-User System.�[0m
�[2mmachine # [  177.686580] dhcpcd[667]: eth0: Router Advertisement from fe80::2�[0m
�[2mmachine # [  177.687680] systemd[1]: Startup finished in 2min 41.833s (kernel) + 15.849s (userspace) = 2min 57.683s.�[0m
�[2mmachine # [  177.688759] dhcpcd[667]: eth0: adding address fec0::5054:ff:fe12:3456/64�[0m
�[2mmachine # [  177.690500] dhcpcd[667]: eth0: adding route to fec0::/64�[0m
�[2mmachine # [  177.691350] dhcpcd[667]: eth0: adding default route via fe80::2�[0m
test script finished in 192.63s
�[1m�[32mcleanup�[0m
kill machine (pid 6)
�[2mmachine # qemu-kvm: terminating on signal 15 from pid 4 (/nix/store/2c7sgx69p6mmp76cvmi5j6c72dj76jj8-python3-3.10.12/bin/python3.10)�[0m
(finished: cleanup, in 0.82 seconds)
kill vlan (pid 5)