�[2mmachine # [   13.523571] systemd[1]: Starting Rebuild Journal Catalog...�[0m
�[2mmachine # [   13.614167] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...�[0m
�[2mmachine # [   13.668640] systemd[1]: Starting Record System Boot/Shutdown in UTMP...�[0m
�[2mmachine # [   13.973637] systemd[1]: Finished Record System Boot/Shutdown in UTMP.�[0m
�[2mmachine # [   14.064646] systemd[1]: Finished Rebuild Journal Catalog.�[0m
�[2mmachine # [   14.098170] systemd[1]: Starting Update is Completed...�[0m
�[2mmachine # [   14.232122] systemd[1]: Finished Update is Completed.�[0m
�[2mmachine # [   14.435965] systemd-oomd[525]: No swap; memory pressure usage will be degraded�[0m
�[2mmachine # [   14.449139] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.�[0m
�[2mmachine # [   14.556315] systemd[1]: Found device /dev/ttyS0.�[0m
�[2mmachine # [   14.558786] systemd[1]: Found device /dev/hvc0.�[0m
�[2mmachine # [   14.823861] l23mzyv7r9n305cws2ca3j4bcs7wi9m0-mount-pstore.sh[476]: Persistent Storage backend was not registered in time.�[0m
�[2mmachine # [   14.861177] systemd[1]: Finished mount-pstore.service.�[0m
�[2mmachine # [   14.880491] systemd[1]: Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).�[0m
�[2mmachine # [   14.920650] systemd[1]: Reached target System Initialization.�[0m
�[2mmachine # [   14.937429] systemd[1]: Started logrotate.timer.�[0m
�[2mmachine # [   14.948595] systemd[1]: Started Daily Cleanup of Temporary Directories.�[0m
�[2mmachine # [   14.970823] systemd[1]: Reached target Timer Units.�[0m
�[2mmachine # [   14.987750] systemd[1]: Listening on D-Bus System Message Bus Socket.�[0m
�[2mmachine # [   15.014642] systemd[1]: Listening on Nix Daemon Socket.�[0m
�[2mmachine # [   15.031447] systemd[1]: Reached target Socket Units.�[0m
�[2mmachine # [   15.056609] systemd[1]: Reached target Basic System.�[0m
�[2mmachine # connecting to host...�[0m
�[2mmachine # [   15.115059] dhcpcd[573]: dhcpcd-9.4.1 starting�[0m
�[2mmachine # [   15.153834] logrotate[574]: warning: logrotate in debug mode does nothing except printing debug messages!  Consider using verbose mode (-v) instead if this is not what you want.�[0m
�[2mmachine # [   15.190680] logrotate[574]: reading config file /nix/store/2my9cdxg2pmzj3q7vy76s1malpys36g5-logrotate.conf�[0m
�[2mmachine # [   15.215562] logrotate[574]: note: 'monthly' overrides previously specified 'weekly'�[0m
�[2mmachine # [   15.239845] logrotate[574]: Reading state from file: /var/lib/logrotate.status�[0m
�[2mmachine # [   15.262518] logrotate[574]: state file /var/lib/logrotate.status does not exist�[0m
�[2mmachine # [   15.281766] logrotate[574]: Allocating hash table for state file, size 64 entries�[0m
�[2mmachine # [   15.305651] logrotate[574]: Handling 2 logs[   15.515056] 8021q: 802.1Q VLAN Support v1.8�[0m
�[2mmachine # �[0m
�[2mmachine # [   15.322603] logrotate[574]: rotating pattern: "/var/log/btmp"  monthly (1 rotations)�[0m
�[2mmachine # [   15.340605] logrotate[574]: empty log files are not rotated, only log files >= 1048576 bytes are rotated, old logs are removed�[0m
�[2mmachine # [   15.395150] logrotate[574]: considering log /var/log/btmp�[0m
�[2mmachine # [   15.415318] logrotate[574]: Creating new state�[0m
�[2mmachine # [   15.429512] logrotate[574]:   Now: 2023-08-15 10:32�[0m
�[2mmachine # [   15.454717] logrotate[574]:   Last rotated at 2023-08-15 10:00�[0m
machine: Guest shell says: b'Spawning backdoor root shell...\n'
�[2mmachine # [   15.474813] logrotate[574]:   log does not need rotating (log has already been rotated)�[0m
�[2mmachine # [   15.496724] logrotate[574]: rotating pattern: "/var/log/wtmp"  monthly (1 rotations)�[0m
machine: connected to guest root shell
machine: (connecting took 18.01 seconds)
(finished: waiting for the VM to finish booting, in 18.01 seconds)
�[2mmachine # [   15.507286] logrotate[574]: empty log files are not rotated, only log files >= 1048576 bytes are rotated, old logs are removed�[0m
�[2mmachine # [   15.556511] logrotate[574]: considering log /var/log/wtmp�[0m
�[2mmachine # [   15.563564] logrotate[574]: Creating new state�[0m
�[2mmachine # [   15.604225] logrotate[574]:   Now: 2023-08-15 10:32�[0m
�[2mmachine # [   15.615607] logrotate[574]:   Last rotated at 2023-08-15 10:00�[0m
�[2mmachine # [   15.632762] logrotate[574]:   log does not need rotating (log has already been rotated)�[0m
�[2mmachine # [   15.677331] systemd[1]: Starting Kernel Auditing...�[0m
�[2mmachine # [   15.705739] r6g4nwns9wh2kqi008c1nc100yza4syf-audit-disable[576]: No rules�[0m
�[2mmachine # [   15.762839] dhcpcd[580]: dev: loaded udev�[0m
�[2mmachine # [   15.785191] systemd[1]: Started backdoor.service.�[0m
�[2mmachine # [   15.802987] nsncd[577]: Aug 15 10:32:34.680 INFO started, handoff_timeout: 3s, worker_count: 8, path: "/var/run/nscd/socket"�[0m
�[2mmachine # [   15.834554] systemd[1]: Starting DHCP Client...�[0m
�[2mmachine # [   15.855656] systemd[1]: Starting Logrotate configuration check...�[0m
�[2mmachine # [   15.876512] dbus-daemon[594]: dbus[594]: Unknown username "systemd-timesync" in message bus configuration file�[0m
�[2mmachine # [   15.911492] (udev-worker)[557]: Network interface NamePolicy= disabled on kernel command line.�[0m
�[2mmachine # [   15.940684] systemd[1]: Starting Name Service Cache Daemon (nsncd)...�[0m
�[2mmachine # [   15.961663] systemd[1]: Started Reset console on configuration changes.�[0m
�[2mmachine # [   15.979514] systemd[1]: Starting resolvconf update...�[0m
�[2mmachine # [   15.994463] systemd[1]: Finished Logrotate configuration check.�[0m
(finished: must succeed: cat /run/secrets/test_key | grep -q test_value, in 18.52 seconds)
(finished: run the VM test script, in 58.57 seconds)
�[2mmachine # [   16.052379] systemd[1]: Finished Kernel Auditing.�[0m
�[2mmachine # [   16.065788] (udev-worker)[512]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.�[0m
�[2mmachine # [   16.109284] (udev-worker)[512]: Network interface NamePolicy= disabled on kernel command line.�[0m
�[2mmachine # [   16.112974] systemd[1]: Starting D-Bus System Message Bus...�[0m
�[2mmachine # [   16.135235] systemd[1]: Started Name Service Cache Daemon (nsncd).�[0m
�[2mmachine # [   16.153645] systemd[1]: Reached target Host and Network Name Lookups.�[0m
�[2mmachine # [   16.203321] systemd[1]: Reached target User and Group Name Lookups.�[0m
�[2mmachine # [   16.426811] cfg80211: Loading compiled-in X.509 certificates for regulatory database�[0m
�[2mmachine # [   16.225527] systemd[1]: Starting User Login Management...�[0m
�[2mmachine # [   16.244273] systemd[1]: Started D-Bus System Message Bus.�[0m
�[2mmachine # [   16.262281] systemd-logind[599]: New seat seat0.�[0m
�[2mmachine # [   16.273741] systemd[1]: Started User Login Management.�[0m
�[2mmachine # [   16.291577] systemd[1]: Stopped target Host and Network Name Lookups.�[0m
�[2mmachine # [   16.304835] systemd[1]: Stopping Host and Network Name Lookups...�[0m
�[2mmachine # [   16.333303] systemd[1]: Stopped target User and Group Name Lookups.�[0m
�[2mmachine # [   16.358314] systemd[1]: Stopping User and Group Name Lookups...�[0m
�[2mmachine # [   16.367813] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...�[0m
�[2mmachine # [   16.383897] nsncd[668]: Aug 15 10:32:35.641 INFO started, handoff_timeout: 3s, worker_count: 8, path: "/var/run/nscd/socket"�[0m
�[2mmachine # [   16.404216] systemd[1]: nscd.service: Deactivated successfully.�[0m
�[2mmachine # [   16.411646] systemd[1]: Stopped Name Service Cache Daemon (nsncd).�[0m
�[2mmachine # [   16.422145] systemd[1]: Starting Name Service Cache Daemon (nsncd)...�[0m
�[2mmachine # [   16.430354] systemd[1]: Found device Virtio network device.�[0m
�[2mmachine # [   16.437389] systemd[1]: Started Name Service Cache Daemon (nsncd).[   16.645704] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'�[0m
�[2mmachine # �[0m
�[2mmachine # [   16.449778] systemd[1]: Reached target Host and Network Name Lookups.[   16.660510] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2�[0m
�[2mmachine # �[0m
�[2mmachine # [   16.461748] systemd[1]: Reached target User and Group Name Lookups.[   16.672785] cfg80211: failed to load regulatory.db�[0m
�[2mmachine # �[0m
�[2mmachine # [   16.478884] systemd[1]: Finished resolvconf update.�[0m
test script finished in 59.55s
�[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.13 seconds)
kill vlan (pid 5)