these 25 derivations will be built: /nix/store/r5353xlbpqhdb9vwbk42nf4kij210zzx-extra-hosts.drv /nix/store/sy7a04afnn25bn97xxcnlq3b5dfkg72j-string-hosts.drv /nix/store/03j9dc68m42jl1kc7qrb59rd3y9zd2vw-hosts.drv /nix/store/2fhk5dyyyskay8j2hm0b0zjaj6jgwmgn-system-path.drv /nix/store/0xdnp7v88i22l6xx5hgi3nwns5r89yjc-dbus-1.drv /nix/store/0zznajq4nc28vyq9qxz91vlw7m54zxfv-container-test-run-container.drv /nix/store/6nlnr4640l8v9y99q8rn5mhhblrq7kg1-unit-script-network-addresses-eth1-start.drv /nix/store/a9qv3hghirv5adc8y451karjhq19cvsy-unit-network-addresses-eth1.service.drv /nix/store/m93fjaxqyl970p7kzcc4d20rhj2lpv38-unit-dbus-broker.service.drv /nix/store/35sawcw1ibrknkwg54y29fnmf0ks0a03-system-units.drv /nix/store/df96826r2g9fbciird7n1c3m3rnfv68l-unit-dbus-broker.service.drv /nix/store/qz0xwnqg9a5qzlfl22qnhd8qhf9k185x-user-units.drv /nix/store/223nrzpiy1y6kdp4vh64pkr06fh7h2am-etc.drv /nix/store/48iy1ym2f22zkbj6qnk1425rv5b31y4i-activate.drv /nix/store/6965czv01v3h5f72khbycr7xsjwqhjvy-run-machine1-nspawn.drv /nix/store/7apwgk1i7hxvsfkgwvg16zr9l8zh0n1h-driverConfiguration.json.drv /nix/store/7awqaav0vrx35wrsargli3fx9yci6yrs-test-script.drv /nix/store/81fahl1khy0np924n589vd5dcm2v6irq-nixos-system-machine2-test.drv /nix/store/rn2wmzj8dfpxdv1il1vyhcdgm7mbpyh3-system-units.drv /nix/store/bkfzlyim6d72v3b7rasa7hjp1phc63n6-etc.drv /nix/store/fdck56v2yw9zn029cv0pxrvd13ay32ya-run-machine2-nspawn.drv /nix/store/hddxl9n5w6v6nml107szwava83cpdr7k-X-Restart-Triggers-dbus-broker.drv /nix/store/jcrcy64n5yn7sm0bs1k6mci6lf1kbxl5-nixos-system-machine1-test.drv /nix/store/p40xhcsp2hz7rfsbxmj87nmxhqwqpwqg-activate.drv /nix/store/rfz76qqlixad1y5jf4pj7g4r0xj5sr77-nixos-test-driver-container.drv this path will be fetched (0.0 KiB download, 634.2 KiB unpacked): /nix/store/ygjnmscxbjxvpg98wpgsl0fmyp82kaqm-nixos-test-driver-1.1 warning: ignoring the client-specified setting 'store', because it is a restricted setting and you are not a trusted user building '/nix/store/2fhk5dyyyskay8j2hm0b0zjaj6jgwmgn-system-path.drv' on nix-store.geninf.io:50051 system-path> gi-build-x86-02: building 2fhk5dyyyskay8j2hm0b0zjaj6jgwmgn-system-path.drv system-path> structuredAttrs is enabled system-path> created 1659 symlinks in user environment building '/nix/store/0xdnp7v88i22l6xx5hgi3nwns5r89yjc-dbus-1.drv' on nix-store.geninf.io:50051 dbus-1> gi-build-x86-02: building 0xdnp7v88i22l6xx5hgi3nwns5r89yjc-dbus-1.drv building '/nix/store/hddxl9n5w6v6nml107szwava83cpdr7k-X-Restart-Triggers-dbus-broker.drv' on nix-store.geninf.io:50051 X-Restart-Triggers-dbus-broker> gi-build-x86-02: building hddxl9n5w6v6nml107szwava83cpdr7k-X-Restart-Triggers-dbus-broker.drv X-Restart-Triggers-dbus-broker> structuredAttrs is enabled building '/nix/store/m93fjaxqyl970p7kzcc4d20rhj2lpv38-unit-dbus-broker.service.drv' on nix-store.geninf.io:50051 unit-dbus-broker.service> gi-build-x86-02: building m93fjaxqyl970p7kzcc4d20rhj2lpv38-unit-dbus-broker.service.drv unit-dbus-broker.service> structuredAttrs is enabled building '/nix/store/df96826r2g9fbciird7n1c3m3rnfv68l-unit-dbus-broker.service.drv' on nix-store.geninf.io:50051 unit-dbus-broker.service> gi-build-x86-02: building df96826r2g9fbciird7n1c3m3rnfv68l-unit-dbus-broker.service.drv unit-dbus-broker.service> structuredAttrs is enabled building '/nix/store/35sawcw1ibrknkwg54y29fnmf0ks0a03-system-units.drv' on nix-store.geninf.io:50051 system-units> gi-build-x86-02: building 35sawcw1ibrknkwg54y29fnmf0ks0a03-system-units.drv building '/nix/store/qz0xwnqg9a5qzlfl22qnhd8qhf9k185x-user-units.drv' on nix-store.geninf.io:50051 user-units> gi-build-x86-02: building qz0xwnqg9a5qzlfl22qnhd8qhf9k185x-user-units.drv building '/nix/store/rn2wmzj8dfpxdv1il1vyhcdgm7mbpyh3-system-units.drv' on nix-store.geninf.io:50051 system-units> gi-build-x86-02: building rn2wmzj8dfpxdv1il1vyhcdgm7mbpyh3-system-units.drv building '/nix/store/223nrzpiy1y6kdp4vh64pkr06fh7h2am-etc.drv' on nix-store.geninf.io:50051 etc> gi-build-x86-02: building 223nrzpiy1y6kdp4vh64pkr06fh7h2am-etc.drv building '/nix/store/bkfzlyim6d72v3b7rasa7hjp1phc63n6-etc.drv' on nix-store.geninf.io:50051 etc> gi-build-x86-02: building bkfzlyim6d72v3b7rasa7hjp1phc63n6-etc.drv building '/nix/store/p40xhcsp2hz7rfsbxmj87nmxhqwqpwqg-activate.drv' on nix-store.geninf.io:50051 activate> gi-build-x86-02: building p40xhcsp2hz7rfsbxmj87nmxhqwqpwqg-activate.drv activate> structuredAttrs is enabled building '/nix/store/48iy1ym2f22zkbj6qnk1425rv5b31y4i-activate.drv' on nix-store.geninf.io:50051 activate> gi-build-x86-02: building 48iy1ym2f22zkbj6qnk1425rv5b31y4i-activate.drv activate> structuredAttrs is enabled building '/nix/store/81fahl1khy0np924n589vd5dcm2v6irq-nixos-system-machine2-test.drv' on nix-store.geninf.io:50051 nixos-system-machine2-test> gi-build-x86-02: building 81fahl1khy0np924n589vd5dcm2v6irq-nixos-system-machine2-test.drv nixos-system-machine2-test> structuredAttrs is enabled building '/nix/store/jcrcy64n5yn7sm0bs1k6mci6lf1kbxl5-nixos-system-machine1-test.drv' on nix-store.geninf.io:50051 nixos-system-machine1-test> gi-build-x86-02: building jcrcy64n5yn7sm0bs1k6mci6lf1kbxl5-nixos-system-machine1-test.drv nixos-system-machine1-test> structuredAttrs is enabled building '/nix/store/6965czv01v3h5f72khbycr7xsjwqhjvy-run-machine1-nspawn.drv' on nix-store.geninf.io:50051 run-machine1-nspawn> gi-build-x86-02: building 6965czv01v3h5f72khbycr7xsjwqhjvy-run-machine1-nspawn.drv building '/nix/store/fdck56v2yw9zn029cv0pxrvd13ay32ya-run-machine2-nspawn.drv' on nix-store.geninf.io:50051 run-machine2-nspawn> build-x86-01: building fdck56v2yw9zn029cv0pxrvd13ay32ya-run-machine2-nspawn.drv building '/nix/store/7apwgk1i7hxvsfkgwvg16zr9l8zh0n1h-driverConfiguration.json.drv' on nix-store.geninf.io:50051 driverConfiguration.json> build-x86-01: building 7apwgk1i7hxvsfkgwvg16zr9l8zh0n1h-driverConfiguration.json.drv driverConfiguration.json> structuredAttrs is enabled building '/nix/store/rfz76qqlixad1y5jf4pj7g4r0xj5sr77-nixos-test-driver-container.drv' on nix-store.geninf.io:50051 nixos-test-driver-container> build-x86-01: building rfz76qqlixad1y5jf4pj7g4r0xj5sr77-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/0zznajq4nc28vyq9qxz91vlw7m54zxfv-container-test-run-container.drv' on nix-store.geninf.io:50051 container-test-run-container> build-x86-01: building 0zznajq4nc28vyq9qxz91vlw7m54zxfv-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(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> 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> 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 machine1 on /build/vm-state-machine1. 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> machine2 # [2465447.714381] machine2 systemd-journald[57]: Journal started container-test-run-container> machine2 # [2465447.714408] machine2 systemd-journald[57]: Runtime Journal (/run/log/journal/4a479fedd20640d9a770c0180e3fac79) is 8M, max 3.7G, 3.7G free. container-test-run-container> machine2 # [2465447.717514] machine2 systemd[1]: Finished Apply Kernel Variables. container-test-run-container> machine2 # [2465447.723357] machine2 systemd[1]: Finished Create Static Device Nodes in /dev gracefully. container-test-run-container> machine2 # [2465447.729972] machine2 systemd[1]: Starting Flush Journal to Persistent Storage... container-test-run-container> machine2 # [2465447.730257] machine2 systemd[1]: Starting Create Static Device Nodes in /dev... container-test-run-container> machine2 # [2465447.734286] machine2 systemd-journald[57]: Time spent on flushing to /var/log/journal/4a479fedd20640d9a770c0180e3fac79 is 926us for 6 entries. container-test-run-container> machine2 # [2465447.734286] machine2 systemd-journald[57]: System Journal (/var/log/journal/4a479fedd20640d9a770c0180e3fac79) is 8M, max 4G, 3.9G free. container-test-run-container> machine2 # [2465447.738494] machine2 systemd[1]: Finished Create Static Device Nodes in /dev. container-test-run-container> machine2 # [2465447.738777] machine2 systemd[1]: Reached target Preparation for Local File Systems. container-test-run-container> machine2 # [2465447.738826] machine2 systemd[1]: Reached target Local File Systems. container-test-run-container> machine2 # [2465447.739238] machine2 systemd[1]: Listening on Boot Loader Control Service Socket. container-test-run-container> machine2 # [2465447.739264] machine2 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container container-test-run-container> machine2 # [2465447.739582] machine2 systemd[1]: Starting Save Transient machine-id to Disk... container-test-run-container> machine2 # [2465447.739597] machine2 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys container-test-run-container> machine2 # [2465447.739776] machine2 systemd[1]: Finished Flush Journal to Persistent Storage. container-test-run-container> machine1 # [2465447.713801] machine1 systemd-journald[57]: Journal started container-test-run-container> machine2 # [2465447.740398] machine2 systemd[1]: Starting Create System Files and Directories... container-test-run-container> machine1 # [2465447.713832] machine1 systemd-journald[57]: Runtime Journal (/run/log/journal/fe8060ecd58e4ec19b001658a6d9bf64) is 8M, max 3.7G, 3.7G free. container-test-run-container> machine1 # [2465447.718696] machine1 systemd[1]: Finished Apply Kernel Variables. container-test-run-container> machine1 # [2465447.724446] machine1 systemd[1]: Finished Create Static Device Nodes in /dev gracefully. container-test-run-container> machine2 # [2465447.750686] machine2 systemd-tmpfiles[106]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted container-test-run-container> machine1 # [2465447.731185] machine1 systemd[1]: Starting Flush Journal to Persistent Storage... container-test-run-container> machine2 # [2465447.750878] machine2 systemd-tmpfiles[106]: fchmod() of /var/log/journal failed: Operation not permitted container-test-run-container> machine1 # [2465447.731471] machine1 systemd[1]: Starting Create Static Device Nodes in /dev... container-test-run-container> machine2 # [2465447.751008] machine2 systemd-tmpfiles[106]: fchmod() of /var/log/journal/4a479fedd20640d9a770c0180e3fac79 failed: Operation not permitted container-test-run-container> machine1 # [2465447.735390] machine1 systemd-journald[57]: Time spent on flushing to /var/log/journal/fe8060ecd58e4ec19b001658a6d9bf64 is 893us for 6 entries. container-test-run-container> machine2 # [2465447.751174] machine2 systemd-tmpfiles[106]: fchmod() of /run/log/journal failed: Operation not permitted container-test-run-container> machine1 # [2465447.735390] machine1 systemd-journald[57]: System Journal (/var/log/journal/fe8060ecd58e4ec19b001658a6d9bf64) is 8M, max 4G, 3.9G free. container-test-run-container> machine2 # [2465447.752122] machine2 systemd[1]: Finished Create System Files and Directories. container-test-run-container> machine1 # [2465447.741925] machine1 systemd[1]: Finished Flush Journal to Persistent Storage. container-test-run-container> machine2 # [2465447.752666] machine2 systemd[1]: Starting Rebuild Journal Catalog... container-test-run-container> machine1 # [2465447.742125] machine1 systemd[1]: Finished Create Static Device Nodes in /dev. container-test-run-container> machine2 # [2465447.752957] machine2 systemd[1]: Starting Record System Boot/Shutdown in UTMP... container-test-run-container> machine1 # [2465447.743022] machine1 systemd[1]: Reached target Preparation for Local File Systems. container-test-run-container> machine2 # [2465447.760672] machine2 systemd[1]: Finished Record System Boot/Shutdown in UTMP. container-test-run-container> machine1 # [2465447.743074] machine1 systemd[1]: Reached target Local File Systems. container-test-run-container> machine2 # [2465447.770550] machine2 systemd[1]: Finished Rebuild Journal Catalog. container-test-run-container> machine1 # [2465447.743488] machine1 systemd[1]: Listening on Boot Loader Control Service Socket. container-test-run-container> machine2 # [2465447.771307] machine2 systemd[1]: Starting Update is Completed... container-test-run-container> machine1 # [2465447.743514] machine1 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container container-test-run-container> machine1 # [2465447.744133] machine1 systemd[1]: Starting Save Transient machine-id to Disk... container-test-run-container> machine2 # [2465447.781524] machine2 systemd[1]: Finished Update is Completed. container-test-run-container> machine1 # [2465447.744636] machine1 systemd[1]: Starting Create System Files and Directories... container-test-run-container> machine2 # [2465447.781622] machine2 systemd[1]: Reached target System Initialization. container-test-run-container> machine1 # [2465447.744656] machine1 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys container-test-run-container> machine2 # [2465447.781668] machine2 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container container-test-run-container> machine1 # [2465447.754307] machine1 systemd-tmpfiles[111]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted container-test-run-container> machine2 # [2465447.781690] machine2 systemd[1]: Started Daily Cleanup of Temporary Directories. container-test-run-container> machine1 # [2465447.754454] machine1 systemd-tmpfiles[111]: fchmod() of /var/log/journal failed: Operation not permitted container-test-run-container> machine2 # [2465447.781703] machine2 systemd[1]: Reached target Timer Units. container-test-run-container> machine1 # [2465447.754560] machine1 systemd-tmpfiles[111]: fchmod() of /var/log/journal/fe8060ecd58e4ec19b001658a6d9bf64 failed: Operation not permitted container-test-run-container> machine2 # [2465447.781780] machine2 systemd[1]: Listening on D-Bus System Message Bus Socket. container-test-run-container> machine1 # [2465447.754709] machine1 systemd-tmpfiles[111]: fchmod() of /run/log/journal failed: Operation not permitted container-test-run-container> machine2 # [2465447.781841] machine2 systemd[1]: Listening on Nix Daemon Socket. container-test-run-container> machine1 # [2465447.755663] machine1 systemd[1]: Finished Create System Files and Directories. container-test-run-container> machine2 # [2465447.782395] machine2 systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Export). container-test-run-container> machine1 # [2465447.756204] machine1 systemd[1]: Starting Rebuild Journal Catalog... container-test-run-container> machine2 # [2465447.782848] machine2 systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). container-test-run-container> machine1 # [2465447.756518] machine1 systemd[1]: Starting Record System Boot/Shutdown in UTMP... container-test-run-container> machine2 # [2465447.782917] machine2 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. container-test-run-container> machine1 # [2465447.762961] machine1 systemd[1]: Finished Record System Boot/Shutdown in UTMP. container-test-run-container> machine2 # [2465447.782927] machine2 systemd[1]: Reached target Socket Units. container-test-run-container> machine1 # [2465447.769315] machine1 systemd[1]: Finished Rebuild Journal Catalog. container-test-run-container> machine2 # [2465447.782949] machine2 systemd[1]: Reached target Basic System. container-test-run-container> machine1 # [2465447.769770] machine1 systemd[1]: Starting Update is Completed... container-test-run-container> machine2 # [2465447.783531] machine2 systemd[1]: Starting Import lastlog data into lastlog2 database... container-test-run-container> machine2 # [2465447.783972] machine2 systemd[1]: Starting Name Service Cache Daemon (nsncd)... container-test-run-container> machine2 # [2465447.784324] machine2 systemd[1]: Starting SSH Host Keys Generation... container-test-run-container> machine1 # [2465447.775413] machine1 systemd[1]: Finished Update is Completed. container-test-run-container> machine1 # [2465447.775491] machine1 systemd[1]: Reached target System Initialization. container-test-run-container> machine2 # [2465447.789281] machine2 systemd[1]: lastlog2-import.service: Failed to spawn executor: No such file or directory container-test-run-container> machine1 # [2465447.775543] machine1 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container container-test-run-container> machine2 # [2465447.789293] machine2 systemd[1]: lastlog2-import.service: Failed to spawn 'start-post' task: No such file or directory container-test-run-container> machine1 # [2465447.775564] machine1 systemd[1]: Started Daily Cleanup of Temporary Directories. container-test-run-container> machine2 # [2465447.789320] machine2 systemd[1]: lastlog2-import.service: Failed with result 'resources'. container-test-run-container> machine1 # [2465447.775577] machine1 systemd[1]: Reached target Timer Units. container-test-run-container> machine2 # [2465447.789355] machine2 systemd[1]: Failed to start Import lastlog data into lastlog2 database. container-test-run-container> machine1 # [2465447.775656] machine1 systemd[1]: Listening on D-Bus System Message Bus Socket. container-test-run-container> machine2 # [2465447.797262] machine2 systemd[1]: Finished Save Transient machine-id to Disk. container-test-run-container> machine1 # [2465447.775728] machine1 systemd[1]: Listening on Nix Daemon Socket. container-test-run-container> machine2 # [2465447.801388] machine2 systemd[1]: Finished Firewall. container-test-run-container> machine1 # [2465447.776384] machine1 systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Export). container-test-run-container> machine2 # [2465447.801457] machine2 systemd[1]: Reached target Preparation for Network. container-test-run-container> machine1 # [2465447.776980] machine1 systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). container-test-run-container> machine2 # [2465447.801887] machine2 systemd[1]: Starting Address configuration of eth1... container-test-run-container> machine1 # [2465447.777048] machine1 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. container-test-run-container> machine2 # [2465447.802240] machine2 systemd[1]: Starting Extra networking commands.... container-test-run-container> machine1 # [2465447.777058] machine1 systemd[1]: Reached target Socket Units. container-test-run-container> machine2 # [2465447.811628] machine2 network-addresses-eth1-start[180]: adding address 192.168.1.2/24... done container-test-run-container> machine1 # [2465447.777078] machine1 systemd[1]: Reached target Basic System. container-test-run-container> machine2 # [2465447.813106] machine2 network-addresses-eth1-start[180]: adding address 2001:db8:1::2/64... done container-test-run-container> machine1 # [2465447.777535] machine1 systemd[1]: Starting Import lastlog data into lastlog2 database... container-test-run-container> machine2 # [2465447.815657] machine2 systemd[1]: Finished Address configuration of eth1. container-test-run-container> machine1 # [2465447.781081] machine1 systemd[1]: Starting Name Service Cache Daemon (nsncd)... container-test-run-container> machine2 # [2465447.833522] machine2 systemd[1]: nscd.service: Deactivated successfully. container-test-run-container> machine2 # [2465447.833637] machine2 systemd[1]: Stopped Name Service Cache Daemon (nsncd). container-test-run-container> machine1 # [2465447.781426] machine1 systemd[1]: Starting SSH Host Keys Generation... container-test-run-container> machine2 # [2465447.834841] machine2 systemd[1]: Starting Name Service Cache Daemon (nsncd)... container-test-run-container> machine1 # [2465447.788773] machine1 systemd[1]: Finished Import lastlog data into lastlog2 database. container-test-run-container> machine2 # [2465447.838833] machine2 systemd[1]: Finished Extra networking commands.. container-test-run-container> machine1 # [2465447.797631] machine1 systemd[1]: Finished Save Transient machine-id to Disk. container-test-run-container> machine1 # [2465447.799013] machine1 systemd[1]: Finished Firewall. container-test-run-container> machine2 # [2465447.838912] machine2 systemd[1]: Reached target Network. container-test-run-container> machine2 # [2465447.912798] machine2 nsncd[239]: Oct 02 20:07:42.336 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" container-test-run-container> machine1 # [2465447.799114] machine1 systemd[1]: Reached target Preparation for Network. container-test-run-container> machine2 # [2465447.912829] machine2 systemd[1]: Started Name Service Cache Daemon (nsncd). container-test-run-container> machine1 # [2465447.799691] machine1 systemd[1]: Starting Address configuration of eth1... container-test-run-container> machine1 # [2465447.800165] machine1 systemd[1]: Starting Extra networking commands.... container-test-run-container> machine2 # [2465447.912867] machine2 systemd[1]: Reached target Host and Network Name Lookups. container-test-run-container> machine1 # [2465447.809522] machine1 network-addresses-eth1-start[182]: adding address 192.168.1.1/24... done container-test-run-container> machine2 # [2465447.912898] machine2 systemd[1]: Reached target User and Group Name Lookups. container-test-run-container> machine1 # [2465447.811237] machine1 network-addresses-eth1-start[182]: adding address 2001:db8:1::1/64... done container-test-run-container> machine2 # [2465447.913525] machine2 systemd[1]: Starting User Login Management... container-test-run-container> machine1 # [2465447.813736] machine1 systemd[1]: Finished Address configuration of eth1. container-test-run-container> machine2 # [2465447.913899] machine2 systemd[1]: Starting Permit User Sessions... container-test-run-container> machine1 # [2465447.832669] machine1 systemd[1]: nscd.service: Deactivated successfully. container-test-run-container> machine2 # [2465447.940497] machine2 systemd[1]: Finished Permit User Sessions. container-test-run-container> machine1 # [2465447.832763] machine1 systemd[1]: Stopped Name Service Cache Daemon (nsncd). container-test-run-container> machine2 # [2465447.940967] machine2 systemd[1]: Started Console Getty. container-test-run-container> machine1 # [2465447.834210] machine1 systemd[1]: nscd.service: Failed to spawn executor: No such file or directory container-test-run-container> machine2 # [2465447.940986] machine2 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0 container-test-run-container> machine1 # [2465447.834226] machine1 systemd[1]: nscd.service: Failed to spawn 'start' task: No such file or directory container-test-run-container> machine2 # [2465447.940995] machine2 systemd[1]: Reached target Login Prompts. container-test-run-container> machine1 # [2465447.834255] machine1 systemd[1]: nscd.service: Failed with result 'resources'. container-test-run-container> machine1 # [2465447.834299] machine1 systemd[1]: Failed to start Name Service Cache Daemon (nsncd). container-test-run-container> machine1 # [2465447.834306] machine1 systemd[1]: Dependency failed for User and Group Name Lookups. container-test-run-container> machine1 # [2465447.834314] machine1 systemd[1]: nss-user-lookup.target: Job nss-user-lookup.target/start failed with result 'dependency'. container-test-run-container> machine1 # [2465447.834322] machine1 systemd[1]: Dependency failed for Host and Network Name Lookups. container-test-run-container> machine1 # [2465447.834325] machine1 systemd[1]: nss-lookup.target: Job nss-lookup.target/start failed with result 'dependency'. container-test-run-container> machine1 # [2465447.835669] machine1 systemd[1]: nscd.service: Scheduled restart job immediately on client request, restart counter is at 1. container-test-run-container> machine1 # [2465447.836158] machine1 systemd[1]: Starting Name Service Cache Daemon (nsncd)... container-test-run-container> machine1 # [2465447.839039] machine1 systemd[1]: Finished Extra networking commands.. container-test-run-container> machine1 # [2465447.839112] machine1 systemd[1]: Reached target Network. container-test-run-container> machine1 # [2465447.912348] machine1 nsncd[246]: Oct 02 20:07:42.335 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" container-test-run-container> machine1 # [2465447.912411] machine1 systemd[1]: Started Name Service Cache Daemon (nsncd). container-test-run-container> machine1 # [2465447.912454] machine1 systemd[1]: Reached target Host and Network Name Lookups. container-test-run-container> machine1 # [2465447.912490] machine1 systemd[1]: Reached target User and Group Name Lookups. container-test-run-container> machine1 # [2465447.913139] machine1 systemd[1]: Starting User Login Management... container-test-run-container> machine1 # [2465447.913452] machine1 systemd[1]: Starting Permit User Sessions... container-test-run-container> machine1 # [2465447.939639] machine1 systemd[1]: Finished Permit User Sessions. container-test-run-container> machine1 # [2465447.940087] machine1 systemd[1]: Started Console Getty. container-test-run-container> machine1 # [2465447.940108] machine1 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0 container-test-run-container> machine1 # [2465447.940119] machine1 systemd[1]: Reached target Login Prompts. container-test-run-container> machine1 # [2465448.042288] machine1 sshd-keygen-start[174]: Generating public/private rsa key pair. container-test-run-container> machine1 # [2465448.042288] machine1 sshd-keygen-start[174]: Your identification has been saved in /etc/ssh/ssh_host_rsa_key container-test-run-container> machine1 # [2465448.042288] machine1 sshd-keygen-start[174]: Your public key has been saved in /etc/ssh/ssh_host_rsa_key.pub container-test-run-container> machine1 # [2465448.042288] machine1 sshd-keygen-start[174]: The key fingerprint is: container-test-run-container> machine1 # [2465448.042288] machine1 sshd-keygen-start[174]: SHA256:CeEoJTFZS4uPMy3a9qZCx+hINdG+S44ysfcXgJRYHxE root@machine1 container-test-run-container> machine1 # [2465448.042288] machine1 sshd-keygen-start[174]: The key's randomart image is: container-test-run-container> machine1 # [2465448.042288] machine1 sshd-keygen-start[174]: +---[RSA 4096]----+ container-test-run-container> machine1 # [2465448.042288] machine1 sshd-keygen-start[174]: | *=*E+ | container-test-run-container> machine1 # [2465448.042288] machine1 sshd-keygen-start[174]: | ..X.*.. | container-test-run-container> machine1 # [2465448.042288] machine1 sshd-keygen-start[174]: | + B.o | container-test-run-container> machine1 # [2465448.042288] machine1 sshd-keygen-start[174]: | O o . . | container-test-run-container> machine1 # [2465448.042288] machine1 sshd-keygen-start[174]: | O + o S | container-test-run-container> machine1 # [2465448.042288] machine1 sshd-keygen-start[174]: | B * o . | container-test-run-container> machine1 # [2465448.042288] machine1 sshd-keygen-start[174]: |* * + . . | container-test-run-container> machine1 # [2465448.042288] machine1 sshd-keygen-start[174]: |o* +.o . | container-test-run-container> machine1 # [2465448.042288] machine1 sshd-keygen-start[174]: | .=oo.. | container-test-run-container> machine1 # [2465448.042288] machine1 sshd-keygen-start[174]: +----[SHA256]-----+ container-test-run-container> machine1 # [2465448.051406] machine1 sshd-keygen-start[276]: Generating public/private ed25519 key pair. container-test-run-container> machine1 # [2465448.051406] machine1 sshd-keygen-start[276]: Your identification has been saved in /etc/ssh/ssh_host_ed25519_key container-test-run-container> machine1 # [2465448.051406] machine1 sshd-keygen-start[276]: Your public key has been saved in /etc/ssh/ssh_host_ed25519_key.pub container-test-run-container> machine1 # [2465448.051406] machine1 sshd-keygen-start[276]: The key fingerprint is: container-test-run-container> machine1 # [2465448.051406] machine1 sshd-keygen-start[276]: SHA256:6jp39l7OC7VnO9KhYIJgtAEz4MlKGwGZRdbRkiVynzA root@machine1 container-test-run-container> machine1 # [2465448.051406] machine1 sshd-keygen-start[276]: The key's randomart image is: container-test-run-container> machine1 # [2465448.051406] machine1 sshd-keygen-start[276]: +--[ED25519 256]--+ container-test-run-container> machine1 # [2465448.051406] machine1 sshd-keygen-start[276]: |+BOoE=. | container-test-run-container> machine1 # [2465448.051406] machine1 sshd-keygen-start[276]: |=.o=*=.. | container-test-run-container> machine1 # [2465448.051406] machine1 sshd-keygen-start[276]: | * . +o | container-test-run-container> machine1 # [2465448.051406] machine1 sshd-keygen-start[276]: |o o + | container-test-run-container> machine1 # [2465448.051406] machine1 sshd-keygen-start[276]: |.. . . .S . | container-test-run-container> machine1 # [2465448.051406] machine1 sshd-keygen-start[276]: | ... o. .. | container-test-run-container> machine1 # [2465448.051406] machine1 sshd-keygen-start[276]: | . o..ooo. | container-test-run-container> machine1 # [2465448.051406] machine1 sshd-keygen-start[276]: | ... o =ooo. | container-test-run-container> machine1 # [2465448.051406] machine1 sshd-keygen-start[276]: | .+.o oo +o.. | container-test-run-container> machine1 # [2465448.051406] machine1 sshd-keygen-start[276]: +----[SHA256]-----+ container-test-run-container> machine1 # [2465448.052341] machine1 systemd[1]: sshd-keygen.service: Deactivated successfully. container-test-run-container> machine1 # [2465448.052416] machine1 systemd[1]: Finished SSH Host Keys Generation. container-test-run-container> machine1 # [2465448.052868] machine1 systemd[1]: Starting SSH Daemon... container-test-run-container> machine1 # [2465448.060355] machine1 sshd[278]: Server listening on 0.0.0.0 port 22. container-test-run-container> machine1 # [2465448.060373] machine1 sshd[278]: Server listening on :: port 22. container-test-run-container> machine1 # [2465448.060423] machine1 systemd[1]: Started SSH Daemon. container-test-run-container> machine2 # [2465448.416106] machine2 systemd-logind[257]: New seat seat0. container-test-run-container> machine1 # [2465448.411230] machine1 systemd-logind[261]: New seat seat0. container-test-run-container> machine2 # [2465448.430192] machine2 systemd[1]: Starting D-Bus System Message Bus... container-test-run-container> machine1 # [2465448.411964] machine1 systemd[1]: Starting D-Bus System Message Bus... container-test-run-container> machine2 # [2465448.430229] machine2 systemd[1]: Started User Login Management. container-test-run-container> machine1 # [2465448.412035] machine1 systemd[1]: Started User Login Management. container-test-run-container> machine2 # [2465448.430539] machine2 systemd[1]: Starting linger-users.service... container-test-run-container> machine2 # [2465448.437548] machine2 systemd[1]: linger-users.service: Deactivated successfully. container-test-run-container> machine1 # [2465448.412377] machine1 systemd[1]: Starting linger-users.service... container-test-run-container> machine2 # [2465448.437590] machine2 systemd[1]: Finished linger-users.service. container-test-run-container> machine1 # [2465448.434087] machine1 systemd[1]: linger-users.service: Deactivated successfully. container-test-run-container> machine1 # [2465448.434174] machine1 systemd[1]: Finished linger-users.service. container-test-run-container> machine2 # [2465448.590784] machine2 dbus-broker-launch[267]: Looking up NSS user entry for 'systemd-timesync'... container-test-run-container> machine1 # [2465448.434297] machine1 systemd[1]: Reached target Multi-User System. container-test-run-container> machine2 # [2465448.591201] machine2 dbus-broker-launch[267]: NSS returned no entry for 'systemd-timesync' container-test-run-container> machine1 # [2465448.589740] machine1 dbus-broker-launch[279]: Looking up NSS user entry for 'systemd-timesync'... container-test-run-container> machine2 # [2465448.591201] machine2 dbus-broker-launch[267]: Invalid user-name in /nix/store/6js9nlpqy5hsbrzmy36iid5fvw62k2ax-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" container-test-run-container> machine2 # [2465448.591398] machine2 systemd[1]: Started D-Bus System Message Bus. container-test-run-container> machine2 # [2465448.594786] machine2 dbus-broker-launch[267]: Ready container-test-run-container> machine1 # [2465448.590290] machine1 dbus-broker-launch[279]: NSS returned no entry for 'systemd-timesync' container-test-run-container> machine1 # [2465448.590290] machine1 dbus-broker-launch[279]: Invalid user-name in /nix/store/6js9nlpqy5hsbrzmy36iid5fvw62k2ax-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" container-test-run-container> machine1 # [2465448.590655] machine1 systemd[1]: Started D-Bus System Message Bus. container-test-run-container> machine1 # [2465448.590834] machine1 systemd[1]: Startup finished in 1.094s. container-test-run-container> machine1 # [2465448.594218] machine1 dbus-broker-launch[279]: Ready container-test-run-container> machine1: (finished: must succeed: systemctl status sshd, in 1.64 seconds) container-test-run-container> machine2: must succeed: systemctl status sshd container-test-run-container> machine1 # [2465448.711742] machine1 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. container-test-run-container> machine2 # [2465448.711787] machine2 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. container-test-run-container> machine2: (finished: must succeed: systemctl status sshd, in 0.32 seconds) container-test-run-container> machine1: waiting for unit sshd container-test-run-container> machine1: (finished: waiting for unit sshd, in 0.01 seconds) container-test-run-container> machine2: waiting for unit sshd container-test-run-container> machine2: (finished: waiting for unit sshd, in 0.01 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.00 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.00 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.00 seconds) container-test-run-container> (finished: run the VM test script, in 1.98 seconds) container-test-run-container> test script finished in 1.99s 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> Container machine1 terminated by signal KILL. container-test-run-container> machine2 # [2465449.080057] machine2 sshd-keygen-start[171]: Generating public/private rsa key pair. container-test-run-container> machine2 # [2465449.080057] machine2 sshd-keygen-start[171]: Your identification has been saved in /etc/ssh/ssh_host_rsa_key container-test-run-container> machine2 # [2465449.080057] machine2 sshd-keygen-start[171]: Your public key has been saved in /etc/ssh/ssh_host_rsa_key.pub container-test-run-container> machine2 # [2465449.080057] machine2 sshd-keygen-start[171]: The key fingerprint is: container-test-run-container> machine2 # [2465449.080057] machine2 sshd-keygen-start[171]: SHA256:xfGAhDFgaFgBiYIyIbSVKbUXXQ+EaCYU4PCvLADkbws root@machine2 container-test-run-container> machine2 # [2465449.080057] machine2 sshd-keygen-start[171]: The key's randomart image is: container-test-run-container> machine2 # [2465449.080057] machine2 sshd-keygen-start[171]: +---[RSA 4096]----+ container-test-run-container> machine2 # [2465449.080057] machine2 sshd-keygen-start[171]: |OX*B*o=+==o | container-test-run-container> machine2 # [2465449.080057] machine2 sshd-keygen-start[171]: |#o==.+o+..o+ | container-test-run-container> machine2 # [2465449.080057] machine2 sshd-keygen-start[171]: |+*o.+. o.. | container-test-run-container> machine2 # [2465449.080057] machine2 sshd-keygen-start[171]: |. ... . | container-test-run-container> machine2 # [2465449.080057] machine2 sshd-keygen-start[171]: |.E o. S | container-test-run-container> machine2 # [2465449.080057] machine2 sshd-keygen-start[171]: |..o.. | container-test-run-container> machine2 # [2465449.080057] machine2 sshd-keygen-start[171]: |. o. | container-test-run-container> machine2 # [2465449.080057] machine2 sshd-keygen-start[171]: | . | container-test-run-container> machine2 # [2465449.080057] machine2 sshd-keygen-start[171]: | | container-test-run-container> machine2 # [2465449.080057] machine2 sshd-keygen-start[171]: +----[SHA256]-----+ container-test-run-container> machine2 # [2465449.088413] machine2 sshd-keygen-start[277]: Generating public/private ed25519 key pair. container-test-run-container> machine2 # [2465449.088413] machine2 sshd-keygen-start[277]: Your identification has been saved in /etc/ssh/ssh_host_ed25519_key container-test-run-container> machine2 # [2465449.088413] machine2 sshd-keygen-start[277]: Your public key has been saved in /etc/ssh/ssh_host_ed25519_key.pub container-test-run-container> machine2 # [2465449.088413] machine2 sshd-keygen-start[277]: The key fingerprint is: container-test-run-container> machine2 # [2465449.088413] machine2 sshd-keygen-start[277]: SHA256:mmsIlI6Y3Cdq8ANo/Smr/yNd2jdqY+68Q6sIRe7nhNs root@machine2 container-test-run-container> machine2 # [2465449.088413] machine2 sshd-keygen-start[277]: The key's randomart image is: container-test-run-container> machine2 # [2465449.088413] machine2 sshd-keygen-start[277]: +--[ED25519 256]--+ container-test-run-container> machine2 # [2465449.088413] machine2 sshd-keygen-start[277]: | | container-test-run-container> machine2 # [2465449.088413] machine2 sshd-keygen-start[277]: | | container-test-run-container> machine2 # [2465449.088413] machine2 sshd-keygen-start[277]: | o | container-test-run-container> machine2 # [2465449.088413] machine2 sshd-keygen-start[277]: | = | container-test-run-container> machine2 # [2465449.088413] machine2 sshd-keygen-start[277]: |+=oo S | container-test-run-container> machine2 # [2465449.088413] machine2 sshd-keygen-start[277]: |Bo=+.. = | container-test-run-container> machine2 # [2465449.088413] machine2 sshd-keygen-start[277]: |o+.+=+O . | container-test-run-container> machine2 # [2465449.088413] machine2 sshd-keygen-start[277]: | o+oO*oO o | container-test-run-container> machine2 # [2465449.088413] machine2 sshd-keygen-start[277]: |..o*=EO** . | container-test-run-container> machine2 # [2465449.088413] machine2 sshd-keygen-start[277]: +----[SHA256]-----+ container-test-run-container> machine2 # [2465449.089080] machine2 systemd[1]: sshd-keygen.service: Deactivated successfully. container-test-run-container> machine2 # [2465449.089135] machine2 systemd[1]: Finished SSH Host Keys Generation. container-test-run-container> machine2 # [2465449.090370] machine2 systemd[1]: Starting SSH Daemon... container-test-run-container> machine2 # [2465449.119819] machine2 sshd[279]: Server listening on 0.0.0.0 port 22. container-test-run-container> machine2 # [2465449.119846] machine2 sshd[279]: Server listening on :: port 22. container-test-run-container> machine2 # [2465449.119898] machine2 systemd[1]: Started SSH Daemon. container-test-run-container> machine2 # [2465449.120005] machine2 systemd[1]: Reached target Multi-User System. container-test-run-container> machine2 # [2465449.120088] machine2 systemd[1]: Startup finished in 1.625s. container-test-run-container> Container machine2 terminated by signal KILL. container-test-run-container> (finished: cleanup, in 0.18 seconds)