build01: building h2cjldlx4r4cdpwqmskw5f90zn7zlpfy-container-test-run-test-extra-python-packages.drv 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, 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 51) machine: Waiting for journal at /build/vm-state-machine/var/log/journal... (finished: start all VMs, in 0.00 seconds) machine: 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. 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. machine # [2465768.714364] machine systemd-journald[57]: Journal started machine # [2465768.714425] machine systemd-journald[57]: Runtime Journal (/run/log/journal/f200ea5434c34cfab409869492b1915f) is 8M, max 2.5G, 2.4G free. machine # [2465768.725177] machine systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine # [2465768.736464] machine systemd[1]: Starting Flush Journal to Persistent Storage... machine # [2465768.737190] machine systemd[1]: Starting Create Static Device Nodes in /dev... machine # [2465768.747819] machine systemd-journald[57]: Time spent on flushing to /var/log/journal/f200ea5434c34cfab409869492b1915f is 2.091ms for 5 entries. machine # [2465768.747819] machine systemd-journald[57]: System Journal (/var/log/journal/f200ea5434c34cfab409869492b1915f) is 8M, max 4G, 3.9G free. machine # [2465768.753171] machine systemd[1]: Finished Create Static Device Nodes in /dev. machine # [2465768.753705] machine systemd[1]: Reached target Preparation for Local File Systems. machine # [2465768.753818] machine systemd[1]: Reached target Local File Systems. machine # [2465768.754649] machine systemd[1]: Listening on Boot Loader Control Service Socket. machine # [2465768.754696] machine systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container machine # [2465768.755619] machine systemd[1]: Starting Save Transient machine-id to Disk... machine # [2465768.755652] machine systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys machine # [2465768.763698] machine systemd[1]: Finished Flush Journal to Persistent Storage. machine # [2465768.764940] machine systemd[1]: Starting Create System Files and Directories... machine # [2465768.786416] machine systemd-tmpfiles[104]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted machine # [2465768.786627] machine systemd-tmpfiles[104]: fchmod() of /var/log/journal failed: Operation not permitted machine # [2465768.786774] machine systemd-tmpfiles[104]: fchmod() of /var/log/journal/f200ea5434c34cfab409869492b1915f failed: Operation not permitted machine # [2465768.786994] machine systemd-tmpfiles[104]: fchmod() of /run/log/journal failed: Operation not permitted machine # [2465768.789490] machine systemd[1]: Finished Create System Files and Directories. machine # [2465768.790763] machine systemd[1]: Starting Rebuild Journal Catalog... machine # [2465768.791632] machine systemd[1]: Starting Record System Boot/Shutdown in UTMP... machine # [2465768.793369] machine systemd[1]: Finished Save Transient machine-id to Disk. machine # [2465768.806251] machine systemd[1]: Finished Record System Boot/Shutdown in UTMP. machine # [2465768.814780] machine systemd[1]: Finished Rebuild Journal Catalog. machine # [2465768.815992] machine systemd[1]: Starting Update is Completed... machine # [2465768.826338] machine systemd[1]: Finished Update is Completed. machine # [2465768.826519] machine systemd[1]: Reached target System Initialization. machine # [2465768.826610] machine systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container machine # [2465768.826646] machine systemd[1]: Started Daily Cleanup of Temporary Directories. machine # [2465768.826668] machine systemd[1]: Reached target Timer Units. machine # [2465768.826789] machine systemd[1]: Listening on D-Bus System Message Bus Socket. machine # [2465768.826909] machine systemd[1]: Listening on Nix Daemon Socket. machine # [2465768.827027] machine systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. machine # [2465768.827056] machine systemd[1]: Reached target Socket Units. machine # [2465768.827091] machine systemd[1]: Reached target Basic System. machine # [2465768.828192] machine systemd[1]: Starting Import lastlog data into lastlog2 database... machine # [2465768.829232] machine systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine # [2465768.849327] machine systemd[1]: Finished Import lastlog data into lastlog2 database. machine # [2465768.877465] machine systemd[1]: Finished Firewall. machine # [2465768.877653] machine systemd[1]: Reached target Preparation for Network. machine # [2465768.878768] machine systemd[1]: Starting Address configuration of eth1... machine # [2465768.879727] machine systemd[1]: Starting Extra networking commands.... machine # [2465768.899258] machine network-addresses-eth1-start[173]: adding address 192.168.1.1/24... done machine # [2465768.902120] machine network-addresses-eth1-start[173]: adding address 2001:db8:1::1/64... done machine # [2465768.907609] machine systemd[1]: Finished Address configuration of eth1. machine # [2465768.932347] machine nsncd[145]: Oct 02 20:23:08.918 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine # [2465768.932321] machine systemd[1]: Started Name Service Cache Daemon (nsncd). machine # [2465768.932394] machine systemd[1]: Reached target Host and Network Name Lookups. machine # [2465768.932455] machine systemd[1]: Reached target User and Group Name Lookups. machine # [2465768.934057] machine systemd[1]: Starting User Login Management... machine # [2465768.950072] machine systemd[1]: Stopped target Host and Network Name Lookups. machine # [2465768.950175] machine systemd[1]: Stopping Host and Network Name Lookups... machine # [2465768.950279] machine systemd[1]: Stopped target User and Group Name Lookups. machine # [2465768.950346] machine systemd[1]: Stopping User and Group Name Lookups... machine # [2465768.950669] machine systemd[1]: Stopping Name Service Cache Daemon (nsncd)... machine # [2465768.956364] machine systemd[1]: nscd.service: Deactivated successfully. machine # [2465768.956675] machine systemd[1]: Stopped Name Service Cache Daemon (nsncd). machine # [2465768.960197] machine systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine # [2465768.965911] machine systemd[1]: Finished Extra networking commands.. machine # [2465768.966078] machine systemd[1]: Reached target Network. machine # [2465769.064463] machine nsncd[251]: Oct 02 20:23:09.050 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine # [2465769.064573] machine systemd[1]: Started Name Service Cache Daemon (nsncd). machine # [2465769.064674] machine systemd[1]: Reached target Host and Network Name Lookups. machine # [2465769.064774] machine systemd[1]: Reached target User and Group Name Lookups. machine # [2465769.066363] machine systemd[1]: Starting Permit User Sessions... machine # [2465769.111124] machine systemd[1]: Finished Permit User Sessions. machine # [2465769.112836] machine systemd[1]: Started Console Getty. machine # [2465769.112907] machine systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0 machine # [2465769.112944] machine systemd[1]: Reached target Login Prompts. machine # [2465769.571498] machine systemd-logind[226]: New seat seat0. machine # [2465769.571828] machine systemd[1]: Started User Login Management. machine # [2465769.573573] machine systemd[1]: Starting D-Bus System Message Bus... machine # [2465769.574883] machine systemd[1]: Starting linger-users.service... machine # [2465769.614222] machine systemd[1]: linger-users.service: Deactivated successfully. machine # [2465769.614544] machine systemd[1]: Finished linger-users.service. machine # [2465769.614886] machine systemd[1]: Reached target Multi-User System. machine # [2465769.720799] machine systemd[1]: etc-machine\x2did.mount: Deactivated successfully. machine: (finished: waiting for unit multi-user.target, in 2.15 seconds) Numpy array: [1 2 3] (finished: run the VM test script, in 2.22 seconds) test script finished in 2.24s cleanup kill NspawnMachine (pid 51) Container machine terminated by signal KILL. (finished: cleanup, in 0.21 seconds)