don't know how to build these paths:
  /nix/store/39srs8y041gbzzrxjg0pca3db22n1y4n-container-test-run-container.drv
warning: ignoring the client-specified setting 'store', because it is a restricted setting and you are not a trusted user
building '/nix/store/ck830cnm3mxiihq87i0iiqq7a4i06iav-nix.conf.drv' on nix-store.geninf.io:50051
nix.conf> attached to the build already running on this worker
nix.conf> build01: building ck830cnm3mxiihq87i0iiqq7a4i06iav-nix.conf.drv
nix.conf> structuredAttrs is enabled
nix.conf> Validating generated nix.conf
building '/nix/store/zv4cy4az8n9kgss4m7hali1p94x7f7hg-X-Restart-Triggers-nix-daemon.drv' on nix-store.geninf.io:50051
X-Restart-Triggers-nix-daemon> attached to the build already running on this worker
X-Restart-Triggers-nix-daemon> build01: building zv4cy4az8n9kgss4m7hali1p94x7f7hg-X-Restart-Triggers-nix-daemon.drv
X-Restart-Triggers-nix-daemon> structuredAttrs is enabled
building '/nix/store/j35l8207vx1hmd3fk8rk5cwqzpbx75bw-unit-nix-daemon.service.drv' on nix-store.geninf.io:50051
unit-nix-daemon.service> attached to the build already running on this worker
unit-nix-daemon.service> build01: building j35l8207vx1hmd3fk8rk5cwqzpbx75bw-unit-nix-daemon.service.drv
unit-nix-daemon.service> structuredAttrs is enabled
m9dnq8fbs16p1v8qbbjdrfpj4pmcjih4-system-units.drv: build01: not scheduled here: m9dnq8fbs16p1v8qbbjdrfpj4pmcjih4-system-units.drv, asking the scheduler again
building '/nix/store/m9dnq8fbs16p1v8qbbjdrfpj4pmcjih4-system-units.drv' on nix-store.geninf.io:50051
system-units> build01: building m9dnq8fbs16p1v8qbbjdrfpj4pmcjih4-system-units.drv
c717kibf4jrfsi5drbzfd04vz6np6f0m-system-units.drv: build01: not scheduled here: c717kibf4jrfsi5drbzfd04vz6np6f0m-system-units.drv, asking the scheduler again
building '/nix/store/c717kibf4jrfsi5drbzfd04vz6np6f0m-system-units.drv' on nix-store.geninf.io:50051
system-units> build01: building c717kibf4jrfsi5drbzfd04vz6np6f0m-system-units.drv
zw43j3pai4kx4bbw5c29z7w566v070bc-etc.drv: build01: not scheduled here: zw43j3pai4kx4bbw5c29z7w566v070bc-etc.drv, asking the scheduler again
building '/nix/store/zw43j3pai4kx4bbw5c29z7w566v070bc-etc.drv' on nix-store.geninf.io:50051
etc> build01: building zw43j3pai4kx4bbw5c29z7w566v070bc-etc.drv
csfkjz43kz35b4c173g81y6ib7z7jqg2-etc.drv: build01: not scheduled here: csfkjz43kz35b4c173g81y6ib7z7jqg2-etc.drv, asking the scheduler again
building '/nix/store/csfkjz43kz35b4c173g81y6ib7z7jqg2-etc.drv' on nix-store.geninf.io:50051
etc> build01: building csfkjz43kz35b4c173g81y6ib7z7jqg2-etc.drv
ff8jnf9gakm3hpcw7x5m11bip31k0jay-activate.drv: build01: not scheduled here: ff8jnf9gakm3hpcw7x5m11bip31k0jay-activate.drv, asking the scheduler again
gvp93r0d68s0m5xbg5yz23znr0nivmz1-activate.drv: build01: not scheduled here: gvp93r0d68s0m5xbg5yz23znr0nivmz1-activate.drv, asking the scheduler again
building '/nix/store/ff8jnf9gakm3hpcw7x5m11bip31k0jay-activate.drv' on nix-store.geninf.io:50051
activate> build01: building ff8jnf9gakm3hpcw7x5m11bip31k0jay-activate.drv
activate> structuredAttrs is enabled
building '/nix/store/gvp93r0d68s0m5xbg5yz23znr0nivmz1-activate.drv' on nix-store.geninf.io:50051
activate> build01: building gvp93r0d68s0m5xbg5yz23znr0nivmz1-activate.drv
activate> structuredAttrs is enabled
6jksiqmr91n0ppq16l127z3wsk021wkc-nixos-system-machine1-test.drv: build01: not scheduled here: 6jksiqmr91n0ppq16l127z3wsk021wkc-nixos-system-machine1-test.drv, asking the scheduler again
c8hyjracvpd9w950z0cif619zzxnnpdh-nixos-system-machine2-test.drv: build01: not scheduled here: c8hyjracvpd9w950z0cif619zzxnnpdh-nixos-system-machine2-test.drv, asking the scheduler again
building '/nix/store/6jksiqmr91n0ppq16l127z3wsk021wkc-nixos-system-machine1-test.drv' on nix-store.geninf.io:50051
nixos-system-machine1-test> build01: building 6jksiqmr91n0ppq16l127z3wsk021wkc-nixos-system-machine1-test.drv
nixos-system-machine1-test> structuredAttrs is enabled
building '/nix/store/c8hyjracvpd9w950z0cif619zzxnnpdh-nixos-system-machine2-test.drv' on nix-store.geninf.io:50051
nixos-system-machine2-test> build01: building c8hyjracvpd9w950z0cif619zzxnnpdh-nixos-system-machine2-test.drv
nixos-system-machine2-test> structuredAttrs is enabled
3qi91vmgmbc5liy5gd5w6i59xpnn6pwd-run-machine1-nspawn.drv: build01: not scheduled here: 3qi91vmgmbc5liy5gd5w6i59xpnn6pwd-run-machine1-nspawn.drv, asking the scheduler again
m7mqf5bw6nnjxgds37ck0908aby5d6my-run-machine2-nspawn.drv: build01: not scheduled here: m7mqf5bw6nnjxgds37ck0908aby5d6my-run-machine2-nspawn.drv, asking the scheduler again
building '/nix/store/3qi91vmgmbc5liy5gd5w6i59xpnn6pwd-run-machine1-nspawn.drv' on nix-store.geninf.io:50051
run-machine1-nspawn> build01: building 3qi91vmgmbc5liy5gd5w6i59xpnn6pwd-run-machine1-nspawn.drv
building '/nix/store/m7mqf5bw6nnjxgds37ck0908aby5d6my-run-machine2-nspawn.drv' on nix-store.geninf.io:50051
run-machine2-nspawn> build01: building m7mqf5bw6nnjxgds37ck0908aby5d6my-run-machine2-nspawn.drv
ic0bvrpc2c8rc8admp126kz1hizlywyd-driverConfiguration.json.drv: build01: not scheduled here: ic0bvrpc2c8rc8admp126kz1hizlywyd-driverConfiguration.json.drv, asking the scheduler again
building '/nix/store/ic0bvrpc2c8rc8admp126kz1hizlywyd-driverConfiguration.json.drv' on nix-store.geninf.io:50051
driverConfiguration.json> build01: building ic0bvrpc2c8rc8admp126kz1hizlywyd-driverConfiguration.json.drv
driverConfiguration.json> structuredAttrs is enabled
building '/nix/store/q5q5pygsjaxzx3bcvih76wbjdnws0lqk-nixos-test-driver-container.drv' on nix-store.geninf.io:50051
nixos-test-driver-container> build01: building q5q5pygsjaxzx3bcvih76wbjdnws0lqk-nixos-test-driver-container.drv
nixos-test-driver-container> Running type check (enable/disable: config.skipTypeCheck)
nixos-test-driver-container> See https://nixos.org/manual/nixos/stable/#test-opt-skipTypeCheck
nixos-test-driver-container> All checks passed!
nixos-test-driver-container> Linting test script (enable/disable: config.skipLint)
nixos-test-driver-container> See https://nixos.org/manual/nixos/stable/#test-opt-skipLint
nixos-test-driver-container> All checks passed!
building '/nix/store/39srs8y041gbzzrxjg0pca3db22n1y4n-container-test-run-container.drv' on nix-store.geninf.io:50051
container-test-run-container> build01: building 39srs8y041gbzzrxjg0pca3db22n1y4n-container-test-run-container.drv
container-test-run-container> Machine state will be reset. To keep it, pass --keep-machine-state
container-test-run-container> start all VLans
container-test-run-container> (finished: start all VLans, in 0.00 seconds)
container-test-run-container> 
container-test-run-container> Test will time out and terminate in 3600.0 seconds
container-test-run-container> run the VM test script
container-test-run-container> additionally exposed symbols:
container-test-run-container>     machine1, machine2,
container-test-run-container>     vlan1,
container-test-run-container>     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
container-test-run-container> start all VMs
container-test-run-container> machine1: systemd-nspawn running (pid 52)
container-test-run-container> machine2: systemd-nspawn running (pid 53)
container-test-run-container> machine1: Waiting for journal at /build/vm-state-machine1/var/log/journal...
container-test-run-container> machine2: Waiting for journal at /build/vm-state-machine2/var/log/journal...
container-test-run-container> (finished: start all VMs, in 0.00 seconds)
container-test-run-container> machine1: must succeed: systemctl status sshd
container-test-run-container> nixos-nspawn(machine2): TAP vde-tap1 not found; container will be isolated from VDE
container-test-run-container> nixos-nspawn(machine2): A common reason for this is that /dev/net is not available in the Nix sandbox. Try adding /dev/net to extra-sandbox-paths.
container-test-run-container> nixos-nspawn(machine1): TAP vde-tap1 not found; container will be isolated from VDE
container-test-run-container> nixos-nspawn(machine1): A common reason for this is that /dev/net is not available in the Nix sandbox. Try adding /dev/net to extra-sandbox-paths.
container-test-run-container> 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.
container-test-run-container> 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.
container-test-run-container> ░ Spawning container machine2 on /build/vm-state-machine2.
container-test-run-container> ░ Spawning container machine1 on /build/vm-state-machine1.
container-test-run-container> machine1 # [2101945.305630] machine1 systemd-journald[57]: Journal started
container-test-run-container> machine1 # [2101945.305689] machine1 systemd-journald[57]: Runtime Journal (/run/log/journal/55501677726d470eb60107ec8880fbd0) is 8M, max 2.5G, 2.4G free.
container-test-run-container> machine1 # [2101945.316962] machine1 systemd[1]: Finished Apply Kernel Variables.
container-test-run-container> machine1 # [2101945.330002] machine1 systemd[1]: Finished Create Static Device Nodes in /dev gracefully.
container-test-run-container> machine1 # [2101945.388336] machine1 systemd[1]: Starting Flush Journal to Persistent Storage...
container-test-run-container> machine1 # [2101945.389321] machine1 systemd[1]: Starting Create Static Device Nodes in /dev...
container-test-run-container> machine1 # [2101945.399310] machine1 systemd-journald[57]: Time spent on flushing to /var/log/journal/55501677726d470eb60107ec8880fbd0 is 1.224ms for 6 entries.
container-test-run-container> machine1 # [2101945.399310] machine1 systemd-journald[57]: System Journal (/var/log/journal/55501677726d470eb60107ec8880fbd0) is 8M, max 4G, 3.9G free.
container-test-run-container> machine1 # [2101945.405589] machine1 systemd[1]: Finished Create Static Device Nodes in /dev.
container-test-run-container> machine1 # [2101945.406229] machine1 systemd[1]: Reached target Preparation for Local File Systems.
container-test-run-container> machine1 # [2101945.406348] machine1 systemd[1]: Reached target Local File Systems.
container-test-run-container> machine1 # [2101945.407182] machine1 systemd[1]: Listening on Boot Loader Control Service Socket.
container-test-run-container> machine1 # [2101945.407229] machine1 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container
container-test-run-container> machine1 # [2101945.408187] machine1 systemd[1]: Starting Save Transient machine-id to Disk...
container-test-run-container> machine1 # [2101945.408220] machine1 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys
container-test-run-container> machine1 # [2101945.411861] machine1 systemd[1]: Finished Flush Journal to Persistent Storage.
container-test-run-container> machine1 # [2101945.412756] machine1 systemd[1]: Starting Create System Files and Directories...
container-test-run-container> machine1 # [2101945.428556] machine1 systemd-tmpfiles[128]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted
container-test-run-container> machine2 # [2101945.314085] machine2 systemd-journald[57]: Journal started
container-test-run-container> machine2 # [2101945.314141] machine2 systemd-journald[57]: Runtime Journal (/run/log/journal/5b1ab0219f3b494a8ab751ddb2db52d1) is 8M, max 2.5G, 2.4G free.
container-test-run-container> machine2 # [2101945.316708] machine2 systemd[1]: Finished Apply Kernel Variables.
container-test-run-container> machine2 # [2101945.327403] machine2 systemd[1]: Finished Create Static Device Nodes in /dev gracefully.
container-test-run-container> machine2 # [2101945.340175] machine2 systemd[1]: Starting Flush Journal to Persistent Storage...
container-test-run-container> machine2 # [2101945.340845] machine2 systemd[1]: Starting Create Static Device Nodes in /dev...
container-test-run-container> machine2 # [2101945.394975] machine2 systemd-journald[57]: Time spent on flushing to /var/log/journal/5b1ab0219f3b494a8ab751ddb2db52d1 is 1.625ms for 6 entries.
container-test-run-container> machine2 # [2101945.394975] machine2 systemd-journald[57]: System Journal (/var/log/journal/5b1ab0219f3b494a8ab751ddb2db52d1) is 8M, max 4G, 3.9G free.
container-test-run-container> machine2 # [2101945.401096] machine2 systemd[1]: Finished Create Static Device Nodes in /dev.
container-test-run-container> machine2 # [2101945.401729] machine2 systemd[1]: Reached target Preparation for Local File Systems.
container-test-run-container> machine2 # [2101945.401856] machine2 systemd[1]: Reached target Local File Systems.
container-test-run-container> machine2 # [2101945.402681] machine2 systemd[1]: Listening on Boot Loader Control Service Socket.
container-test-run-container> machine2 # [2101945.402729] machine2 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container
container-test-run-container> machine2 # [2101945.403519] machine2 systemd[1]: Starting Save Transient machine-id to Disk...
container-test-run-container> machine2 # [2101945.403552] machine2 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys
container-test-run-container> machine2 # [2101945.423756] machine2 systemd[1]: Finished Flush Journal to Persistent Storage.
container-test-run-container> machine2 # [2101945.425319] machine2 systemd[1]: Starting Create System Files and Directories...
container-test-run-container> machine2 # [2101945.444319] machine2 systemd-tmpfiles[130]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted
container-test-run-container> machine2 # [2101945.444597] machine2 systemd-tmpfiles[130]: fchmod() of /var/log/journal failed: Operation not permitted
container-test-run-container> machine2 # [2101945.444790] machine2 systemd-tmpfiles[130]: fchmod() of /var/log/journal/5b1ab0219f3b494a8ab751ddb2db52d1 failed: Operation not permitted
container-test-run-container> machine2 # [2101945.445054] machine2 systemd-tmpfiles[130]: fchmod() of /run/log/journal failed: Operation not permitted
container-test-run-container> machine1 # [2101945.428761] machine1 systemd-tmpfiles[128]: fchmod() of /var/log/journal failed: Operation not permitted
container-test-run-container> machine1 # [2101945.428914] machine1 systemd-tmpfiles[128]: fchmod() of /var/log/journal/55501677726d470eb60107ec8880fbd0 failed: Operation not permitted
container-test-run-container> machine1 # [2101945.429115] machine1 systemd-tmpfiles[128]: fchmod() of /run/log/journal failed: Operation not permitted
container-test-run-container> machine1 # [2101945.430880] machine1 systemd[1]: Finished Create System Files and Directories.
container-test-run-container> machine1 # [2101945.431992] machine1 systemd[1]: Starting Rebuild Journal Catalog...
container-test-run-container> machine1 # [2101945.432778] machine1 systemd[1]: Starting Record System Boot/Shutdown in UTMP...
container-test-run-container> machine1 # [2101945.445706] machine1 systemd[1]: Finished Record System Boot/Shutdown in UTMP.
container-test-run-container> machine1 # [2101945.446695] machine1 systemd[1]: Finished Save Transient machine-id to Disk.
container-test-run-container> machine1 # [2101945.456395] machine1 systemd[1]: Finished Rebuild Journal Catalog.
container-test-run-container> machine1 # [2101945.457348] machine1 systemd[1]: Starting Update is Completed...
container-test-run-container> machine1 # [2101945.467989] machine1 systemd[1]: Finished Update is Completed.
container-test-run-container> machine1 # [2101945.468202] machine1 systemd[1]: Reached target System Initialization.
container-test-run-container> machine1 # [2101945.468302] machine1 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container
container-test-run-container> machine1 # [2101945.468346] machine1 systemd[1]: Started Daily Cleanup of Temporary Directories.
container-test-run-container> machine1 # [2101945.468378] machine1 systemd[1]: Reached target Timer Units.
container-test-run-container> machine1 # [2101945.468514] machine1 systemd[1]: Listening on D-Bus System Message Bus Socket.
container-test-run-container> machine1 # [2101945.468659] machine1 systemd[1]: Listening on Nix Daemon Socket.
container-test-run-container> machine1 # [2101945.470063] machine1 systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Export).
container-test-run-container> machine1 # [2101945.471272] machine1 systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local).
container-test-run-container> machine1 # [2101945.471390] machine1 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.
container-test-run-container> machine1 # [2101945.471417] machine1 systemd[1]: Reached target Socket Units.
container-test-run-container> machine1 # [2101945.471472] machine1 systemd[1]: Reached target Basic System.
container-test-run-container> machine1 # [2101945.472650] machine1 systemd[1]: Starting Import lastlog data into lastlog2 database...
container-test-run-container> machine1 # [2101945.473621] machine1 systemd[1]: Starting Name Service Cache Daemon (nsncd)...
container-test-run-container> machine1 # [2101945.474440] machine1 systemd[1]: Starting SSH Host Keys Generation...
container-test-run-container> machine1 # [2101945.476665] machine1 systemd[1]: Finished Firewall.
container-test-run-container> machine1 # [2101945.476926] machine1 systemd[1]: Reached target Preparation for Network.
container-test-run-container> machine1 # [2101945.477835] machine1 systemd[1]: Starting Address configuration of eth1...
container-test-run-container> machine1 # [2101945.478766] machine1 systemd[1]: Starting Extra networking commands....
container-test-run-container> machine1 # [2101945.492046] machine1 systemd[1]: Finished Import lastlog data into lastlog2 database.
container-test-run-container> machine1 # [2101945.500813] machine1 network-addresses-eth1-start[172]: adding address 192.168.1.1/24... done
container-test-run-container> machine1 # [2101945.503613] machine1 network-addresses-eth1-start[172]: adding address 2001:db8:1::1/64... done
container-test-run-container> machine1 # [2101945.508662] machine1 systemd[1]: Finished Address configuration of eth1.
container-test-run-container> machine1 # [2101945.548174] machine1 systemd[1]: nscd.service: Deactivated successfully.
container-test-run-container> machine1 # [2101945.548299] machine1 systemd[1]: Stopped Name Service Cache Daemon (nsncd).
container-test-run-container> machine1 # [2101945.550306] machine1 systemd[1]: Starting Name Service Cache Daemon (nsncd)...
container-test-run-container> machine1 # [2101945.557822] machine1 systemd[1]: Finished Extra networking commands..
container-test-run-container> machine1 # [2101945.558136] machine1 systemd[1]: Reached target Network.
container-test-run-container> machine1 # [2101945.670736] machine1 nsncd[242]: Sep 28 15:19:25.656 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"
container-test-run-container> machine1 # [2101945.670878] machine1 systemd[1]: Started Name Service Cache Daemon (nsncd).
container-test-run-container> machine2 # [2101945.446308] machine2 systemd[1]: Finished Create System Files and Directories.
container-test-run-container> machine2 # [2101945.446614] machine2 systemd[1]: Finished Save Transient machine-id to Disk.
container-test-run-container> machine2 # [2101945.448239] machine2 systemd[1]: Starting Rebuild Journal Catalog...
container-test-run-container> machine2 # [2101945.448930] machine2 systemd[1]: Starting Record System Boot/Shutdown in UTMP...
container-test-run-container> machine2 # [2101945.461563] machine2 systemd[1]: Finished Record System Boot/Shutdown in UTMP.
container-test-run-container> machine2 # [2101945.471351] machine2 systemd[1]: Finished Rebuild Journal Catalog.
container-test-run-container> machine2 # [2101945.472370] machine2 systemd[1]: Starting Update is Completed...
container-test-run-container> machine2 # [2101945.483450] machine2 systemd[1]: Finished Firewall.
container-test-run-container> machine2 # [2101945.483877] machine2 systemd[1]: Finished Update is Completed.
container-test-run-container> machine2 # [2101945.485655] machine2 systemd[1]: Reached target Preparation for Network.
container-test-run-container> machine2 # [2101945.485908] machine2 systemd[1]: Reached target System Initialization.
container-test-run-container> machine2 # [2101945.486282] machine2 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container
container-test-run-container> machine2 # [2101945.486722] machine2 systemd[1]: Started Daily Cleanup of Temporary Directories.
container-test-run-container> machine2 # [2101945.486751] machine2 systemd[1]: Reached target Timer Units.
container-test-run-container> machine2 # [2101945.486933] machine2 systemd[1]: Listening on D-Bus System Message Bus Socket.
container-test-run-container> machine2 # [2101945.487133] machine2 systemd[1]: Listening on Nix Daemon Socket.
container-test-run-container> machine2 # [2101945.489074] machine2 systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Export).
container-test-run-container> machine2 # [2101945.490446] machine2 systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local).
container-test-run-container> machine2 # [2101945.490583] machine2 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.
container-test-run-container> machine2 # [2101945.490615] machine2 systemd[1]: Reached target Socket Units.
container-test-run-container> machine2 # [2101945.490676] machine2 systemd[1]: Reached target Basic System.
container-test-run-container> machine2 # [2101945.491831] machine2 systemd[1]: Starting Import lastlog data into lastlog2 database...
container-test-run-container> machine2 # [2101945.492651] machine2 systemd[1]: Starting Address configuration of eth1...
container-test-run-container> machine2 # [2101945.493772] machine2 systemd[1]: Starting Extra networking commands....
container-test-run-container> machine2 # [2101945.494808] machine2 systemd[1]: Starting Name Service Cache Daemon (nsncd)...
container-test-run-container> machine2 # [2101945.495637] machine2 systemd[1]: Starting SSH Host Keys Generation...
container-test-run-container> machine2 # [2101945.510750] machine2 systemd[1]: Finished Import lastlog data into lastlog2 database.
container-test-run-container> machine2 # [2101945.511716] machine2 network-addresses-eth1-start[170]: adding address 192.168.1.2/24... done
container-test-run-container> machine2 # [2101945.514508] machine2 network-addresses-eth1-start[170]: adding address 2001:db8:1::2/64... done
container-test-run-container> machine2 # [2101945.518746] machine2 systemd[1]: Finished Address configuration of eth1.
container-test-run-container> machine2 # [2101945.562582] machine2 systemd[1]: nscd.service: Deactivated successfully.
container-test-run-container> machine2 # [2101945.562823] machine2 systemd[1]: Stopped Name Service Cache Daemon (nsncd).
container-test-run-container> machine2 # [2101945.566185] machine2 systemd[1]: Starting Name Service Cache Daemon (nsncd)...
container-test-run-container> machine2 # [2101945.571284] machine2 systemd[1]: Finished Extra networking commands..
container-test-run-container> machine2 # [2101945.571485] machine2 systemd[1]: Reached target Network.
container-test-run-container> machine2 # [2101945.680616] machine2 systemd[1]: Started Name Service Cache Daemon (nsncd).
container-test-run-container> machine2 # [2101945.680692] machine2 systemd[1]: Reached target Host and Network Name Lookups.
container-test-run-container> machine2 # [2101945.680752] machine2 systemd[1]: Reached target User and Group Name Lookups.
container-test-run-container> machine1 # [2101945.670979] machine1 systemd[1]: Reached target Host and Network Name Lookups.
container-test-run-container> machine1 # [2101945.671085] machine1 systemd[1]: Reached target User and Group Name Lookups.
container-test-run-container> machine1 # [2101945.673265] machine1 systemd[1]: Starting User Login Management...
container-test-run-container> machine1 # [2101945.674526] machine1 systemd[1]: Starting Permit User Sessions...
container-test-run-container> machine1 # [2101945.728291] machine1 systemd[1]: Finished Permit User Sessions.
container-test-run-container> machine1 # [2101945.730053] machine1 systemd[1]: Started Console Getty.
container-test-run-container> machine1 # [2101945.730136] machine1 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0
container-test-run-container> machine1 # [2101945.730176] machine1 systemd[1]: Reached target Login Prompts.
container-test-run-container> machine2 # [2101945.681314] machine2 nsncd[244]: Sep 28 15:19:25.667 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"
container-test-run-container> machine2 # [2101945.720415] machine2 systemd[1]: Starting User Login Management...
container-test-run-container> machine2 # [2101945.721433] machine2 systemd[1]: Starting Permit User Sessions...
container-test-run-container> machine2 # [2101945.733101] machine2 systemd[1]: Finished Permit User Sessions.
container-test-run-container> machine2 # [2101945.734785] machine2 systemd[1]: Started Console Getty.
container-test-run-container> machine2 # [2101945.734852] machine2 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0
container-test-run-container> machine2 # [2101945.734901] machine2 systemd[1]: Reached target Login Prompts.
container-test-run-container> machine1 # [2101946.316887] machine1 systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
container-test-run-container> machine1 # [2101946.400070] machine1 systemd-logind[259]: New seat seat0.
container-test-run-container> machine1 # [2101946.401513] machine1 systemd[1]: Starting D-Bus System Message Bus...
container-test-run-container> machine1 # [2101946.401633] machine1 systemd[1]: Started User Login Management.
container-test-run-container> machine1 # [2101946.402603] machine1 systemd[1]: Starting linger-users.service...
container-test-run-container> machine1 # [2101946.445731] machine1 systemd[1]: linger-users.service: Deactivated successfully.
container-test-run-container> machine1 # [2101946.445998] machine1 systemd[1]: Finished linger-users.service.
container-test-run-container> machine2 # [2101946.277393] machine2 sshd-keygen-start[195]: Generating public/private rsa key pair.
container-test-run-container> machine2 # [2101946.277393] machine2 sshd-keygen-start[195]: Your identification has been saved in /etc/ssh/ssh_host_rsa_key
container-test-run-container> machine2 # [2101946.277393] machine2 sshd-keygen-start[195]: Your public key has been saved in /etc/ssh/ssh_host_rsa_key.pub
container-test-run-container> machine2 # [2101946.277393] machine2 sshd-keygen-start[195]: The key fingerprint is:
container-test-run-container> machine2 # [2101946.277393] machine2 sshd-keygen-start[195]: SHA256:b8Lna4pbfC95mO3gGd3kIrdZFUUZyA/CCW9eAkTrQOw root@machine2
container-test-run-container> machine2 # [2101946.277393] machine2 sshd-keygen-start[195]: The key's randomart image is:
container-test-run-container> machine2 # [2101946.277393] machine2 sshd-keygen-start[195]: +---[RSA 4096]----+
container-test-run-container> machine2 # [2101946.277393] machine2 sshd-keygen-start[195]: |      ..o=o o .o=|
container-test-run-container> machine2 # [2101946.277393] machine2 sshd-keygen-start[195]: |      ..  ++ + ..|
container-test-run-container> machine2 # [2101946.277393] machine2 sshd-keygen-start[195]: |      .. . +..o. |
container-test-run-container> machine2 # [2101946.277393] machine2 sshd-keygen-start[195]: |       Eo o o  ..|
container-test-run-container> machine2 # [2101946.277393] machine2 sshd-keygen-start[195]: |        S. .  . .|
container-test-run-container> machine2 # [2101946.277393] machine2 sshd-keygen-start[195]: |       o . . + . |
container-test-run-container> machine2 # [2101946.277393] machine2 sshd-keygen-start[195]: |        = O=+ +  |
container-test-run-container> machine2 # [2101946.277393] machine2 sshd-keygen-start[195]: |       o B*Bo=   |
container-test-run-container> machine2 # [2101946.277393] machine2 sshd-keygen-start[195]: |      o..o===    |
container-test-run-container> machine2 # [2101946.277393] machine2 sshd-keygen-start[195]: +----[SHA256]-----+
container-test-run-container> machine2 # [2101946.296019] machine2 sshd-keygen-start[274]: Generating public/private ed25519 key pair.
container-test-run-container> machine2 # [2101946.296019] machine2 sshd-keygen-start[274]: Your identification has been saved in /etc/ssh/ssh_host_ed25519_key
container-test-run-container> machine2 # [2101946.296019] machine2 sshd-keygen-start[274]: Your public key has been saved in /etc/ssh/ssh_host_ed25519_key.pub
container-test-run-container> machine2 # [2101946.296019] machine2 sshd-keygen-start[274]: The key fingerprint is:
container-test-run-container> machine2 # [2101946.296019] machine2 sshd-keygen-start[274]: SHA256:vFZBB7xVyBaoiR9iHjVXDwPKrcy7vygsf/Mc34J69DE root@machine2
container-test-run-container> machine2 # [2101946.296019] machine2 sshd-keygen-start[274]: The key's randomart image is:
container-test-run-container> machine2 # [2101946.296019] machine2 sshd-keygen-start[274]: +--[ED25519 256]--+
container-test-run-container> machine2 # [2101946.296019] machine2 sshd-keygen-start[274]: |         .+**+.  |
container-test-run-container> machine2 # [2101946.296019] machine2 sshd-keygen-start[274]: |       .o++.=+   |
container-test-run-container> machine2 # [2101946.296019] machine2 sshd-keygen-start[274]: |       oo=o+  .  |
container-test-run-container> machine2 # [2101946.296019] machine2 sshd-keygen-start[274]: |      =++...     |
container-test-run-container> machine2 # [2101946.296019] machine2 sshd-keygen-start[274]: |     o +S..      |
container-test-run-container> machine2 # [2101946.296019] machine2 sshd-keygen-start[274]: |      . .+. E    |
container-test-run-container> machine2 # [2101946.296019] machine2 sshd-keygen-start[274]: |     .  +..o o   |
container-test-run-container> machine2 # [2101946.296019] machine2 sshd-keygen-start[274]: |    . o.o+ooo.   |
container-test-run-container> machine2 # [2101946.296019] machine2 sshd-keygen-start[274]: |     o.o+*=....  |
container-test-run-container> machine2 # [2101946.296019] machine2 sshd-keygen-start[274]: +----[SHA256]-----+
container-test-run-container> machine2 # [2101946.325742] machine2 systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
container-test-run-container> machine2 # [2101946.327664] machine2 systemd[1]: sshd-keygen.service: Deactivated successfully.
container-test-run-container> machine2 # [2101946.327962] machine2 systemd[1]: Finished SSH Host Keys Generation.
container-test-run-container> machine2 # [2101946.328106] machine2 systemd[1]: sshd-keygen.service: Consumed 1.650s CPU time over 832ms wall clock time.
container-test-run-container> machine2 # [2101946.330517] machine2 systemd[1]: Starting SSH Daemon...
container-test-run-container> machine2 # [2101946.345224] machine2 sshd[276]: Server listening on 0.0.0.0 port 22.
container-test-run-container> machine2 # [2101946.345254] machine2 sshd[276]: Server listening on :: port 22.
container-test-run-container> machine2 # [2101946.345507] machine2 systemd[1]: Started SSH Daemon.
container-test-run-container> machine2 # [2101946.409156] machine2 systemd-logind[259]: New seat seat0.
container-test-run-container> machine2 # [2101946.409597] machine2 systemd[1]: Started User Login Management.
container-test-run-container> machine2 # [2101946.436611] machine2 systemd[1]: Starting D-Bus System Message Bus...
container-test-run-container> machine2 # [2101946.437909] machine2 systemd[1]: Starting linger-users.service...
container-test-run-container> machine2 # [2101946.456595] machine2 systemd[1]: linger-users.service: Deactivated successfully.
container-test-run-container> machine2 # [2101946.456692] machine2 systemd[1]: Finished linger-users.service.
container-test-run-container> machine2 # [2101946.456875] machine2 systemd[1]: Reached target Multi-User System.
container-test-run-container> machine2 # [2101946.676491] machine2 dbus-broker-launch[277]: Looking up NSS user entry for 'systemd-timesync'...
container-test-run-container> machine2 # [2101946.678235] machine2 dbus-broker-launch[277]: NSS returned no entry for 'systemd-timesync'
container-test-run-container> machine2 # [2101946.678235] machine2 dbus-broker-launch[277]: Invalid user-name in /nix/store/q2j5z2r3vaff2mz4g5abjanb05bzg12a-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"
container-test-run-container> machine2 # [2101946.678717] machine2 systemd[1]: Started D-Bus System Message Bus.
container-test-run-container> machine2 # [2101946.679194] machine2 systemd[1]: Startup finished in 1.768s.
container-test-run-container> machine2 # [2101946.687364] machine2 dbus-broker-launch[277]: Ready
container-test-run-container> machine1 # [2101946.701262] machine1 dbus-broker-launch[269]: Looking up NSS user entry for 'systemd-timesync'...
container-test-run-container> machine1 # [2101946.702734] machine1 dbus-broker-launch[269]: NSS returned no entry for 'systemd-timesync'
container-test-run-container> machine1 # [2101946.702734] machine1 dbus-broker-launch[269]: Invalid user-name in /nix/store/q2j5z2r3vaff2mz4g5abjanb05bzg12a-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"
container-test-run-container> machine1 # [2101946.703194] machine1 systemd[1]: Started D-Bus System Message Bus.
container-test-run-container> machine1 # [2101946.710292] machine1 dbus-broker-launch[269]: Ready
container-test-run-container> machine1: (finished: must succeed: systemctl status sshd, in 6.66 seconds)
container-test-run-container> machine2: must succeed: systemctl status sshd
container-test-run-container> machine2: (finished: must succeed: systemctl status sshd, in 0.02 seconds)
container-test-run-container> machine1: waiting for unit sshd
container-test-run-container> machine1: (finished: waiting for unit sshd, in 0.02 seconds)
container-test-run-container> machine2: waiting for unit sshd
container-test-run-container> machine2: (finished: waiting for unit sshd, in 0.02 seconds)
container-test-run-container> machine1: must succeed: ip addr show eth1
container-test-run-container> machine1: (finished: must succeed: ip addr show eth1, in 0.01 seconds)
container-test-run-container> machine2: must succeed: ip addr show eth1
container-test-run-container> machine2: (finished: must succeed: ip addr show eth1, in 0.01 seconds)
container-test-run-container> machine1: must succeed: ping -c 1 machine2
container-test-run-container> machine1: (finished: must succeed: ping -c 1 machine2, in 0.01 seconds)
container-test-run-container> (finished: run the VM test script, in 6.75 seconds)
container-test-run-container> test script finished in 6.79s
container-test-run-container> cleanup
container-test-run-container> kill NspawnMachine (pid 52)
container-test-run-container> kill NspawnMachine (pid 53)
container-test-run-container> machine1 # [2101950.914569] machine1 sshd-keygen-start[187]: Generating public/private rsa key pair.
container-test-run-container> Container machine1 terminated by signal KILL.
container-test-run-container> (finished: cleanup, in 0.33 seconds)
container-test-run-container> Container machine2 terminated by signal KILL.