don't know how to build these paths: /nix/store/1g77rfpn0k06p7gv9f8yhawc617lzqdz-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/6k008szsxwnpz926fc5jk8376947pag7-test-script.drv' on nix-store.geninf.io:50051 test-script> web01: building 6k008szsxwnpz926fc5jk8376947pag7-test-script.drv building '/nix/store/mkyddzbqw8r6877s0cpc8ikjkw0mwx5r-X-Restart-Triggers-sshd.drv' on nix-store.geninf.io:50051 X-Restart-Triggers-sshd> web01: building mkyddzbqw8r6877s0cpc8ikjkw0mwx5r-X-Restart-Triggers-sshd.drv building '/nix/store/dgkc5dk9k4i3m0wk2cy39dlbkyhnjqwc-system-path.drv' on nix-store.geninf.io:50051 system-path> web01: building dgkc5dk9k4i3m0wk2cy39dlbkyhnjqwc-system-path.drv system-path> structuredAttrs is enabled system-path> created 1660 symlinks in user environment building '/nix/store/v8lf59xahp6x74f1yfszw34c2vncacrq-unit-sshd.service.drv' on nix-store.geninf.io:50051 unit-sshd.service> web01: building v8lf59xahp6x74f1yfszw34c2vncacrq-unit-sshd.service.drv unit-sshd.service> structuredAttrs is enabled building '/nix/store/wyjam4g7qr4r5n063j1vjqzaqyzflcw5-dbus-1.drv' on nix-store.geninf.io:50051 dbus-1> web01: building wyjam4g7qr4r5n063j1vjqzaqyzflcw5-dbus-1.drv building '/nix/store/c2dd6axsc7pdyv7vxlvjx2v4pc782njl-etc-ssh-ssh_config.drv' on nix-store.geninf.io:50051 etc-ssh-ssh_config> attached to the build already running on this worker etc-ssh-ssh_config> gi-build-x86-02: building c2dd6axsc7pdyv7vxlvjx2v4pc782njl-etc-ssh-ssh_config.drv building '/nix/store/jvfqrrfk28s0l38vvcvmil4xd233cj66-X-Restart-Triggers-dbus-broker.drv' on nix-store.geninf.io:50051 X-Restart-Triggers-dbus-broker> web01: building jvfqrrfk28s0l38vvcvmil4xd233cj66-X-Restart-Triggers-dbus-broker.drv building '/nix/store/pg6b96chm2v6ww3xzzji4fdrffkzd645-unit-dbus-broker.service.drv' on nix-store.geninf.io:50051 unit-dbus-broker.service> web01: building pg6b96chm2v6ww3xzzji4fdrffkzd645-unit-dbus-broker.service.drv building '/nix/store/2rgwzba4a6jbgadcsbl6wl8li6jlqryf-unit-dbus-broker.service.drv' on nix-store.geninf.io:50051 unit-dbus-broker.service> web01: building 2rgwzba4a6jbgadcsbl6wl8li6jlqryf-unit-dbus-broker.service.drv unit-dbus-broker.service> structuredAttrs is enabled unit-dbus-broker.service> structuredAttrs is enabled building '/nix/store/412cfqaz7dwvagm1f29fhp5f0l3ib92g-user-units.drv' on nix-store.geninf.io:50051 user-units> web01: building 412cfqaz7dwvagm1f29fhp5f0l3ib92g-user-units.drv building '/nix/store/4mkda25kp9ilfl2jafwvhbnaasnrs8g9-unit-resolvconf.service-disabled.drv' on nix-store.geninf.io:50051 unit-resolvconf.service-disabled> attached to the build already running on this worker unit-resolvconf.service-disabled> build-x86-01: building 4mkda25kp9ilfl2jafwvhbnaasnrs8g9-unit-resolvconf.service-disabled.drv cbcffs47vljqw7vagww4a83y8b2p9ia6-etc-os-release.drv: gi-build-x86-02: not scheduled here: cbcffs47vljqw7vagww4a83y8b2p9ia6-etc-os-release.drv, asking the scheduler again jchxv54cqhf5wab3bzqibr10k2x8z0cx-shutdown-ramfs-contents.json.drv: gi-build-x86-02: not scheduled here: jchxv54cqhf5wab3bzqibr10k2x8z0cx-shutdown-ramfs-contents.json.drv, asking the scheduler again wxx1dfcvr5ccr1mrb8vhvg6wi468abm2-nix.conf.drv: gi-build-x86-02: not scheduled here: wxx1dfcvr5ccr1mrb8vhvg6wi468abm2-nix.conf.drv, asking the scheduler again 3v0glwksc4v898hyvqamfy45j2y6iq73-unit-generate-shutdown-ramfs.service.drv: gi-build-x86-02: not scheduled here: 3v0glwksc4v898hyvqamfy45j2y6iq73-unit-generate-shutdown-ramfs.service.drv, asking the scheduler again xbz1rr1xjdhrq1d8rin3npj7qc1d537a-X-Restart-Triggers-nix-daemon.drv: gi-build-x86-02: not scheduled here: xbz1rr1xjdhrq1d8rin3npj7qc1d537a-X-Restart-Triggers-nix-daemon.drv, asking the scheduler again ll3klb635awaynsz3xnihvkyy9h4f43c-unit-nix-daemon.service.drv: gi-build-x86-02: not scheduled here: ll3klb635awaynsz3xnihvkyy9h4f43c-unit-nix-daemon.service.drv, asking the scheduler again building '/nix/store/lib6yin1y0rjmk9ls7d558zrv2cbym1v-system-units.drv' on nix-store.geninf.io:50051 system-units> gi-build-x86-02: building lib6yin1y0rjmk9ls7d558zrv2cbym1v-system-units.drv building '/nix/store/39xnbpwmn6lyqsc1x1vip92gnrz2f11q-etc.drv' on nix-store.geninf.io:50051 etc> gi-build-x86-02: building 39xnbpwmn6lyqsc1x1vip92gnrz2f11q-etc.drv building '/nix/store/1dzh1x9z0zyfzsq5nna85xwcc9m5p3fj-system-units.drv' on nix-store.geninf.io:50051 system-units> build-x86-01: building 1dzh1x9z0zyfzsq5nna85xwcc9m5p3fj-system-units.drv building '/nix/store/r65gq110hw7yvc1xsqmj28lfqf1avylh-activate.drv' on nix-store.geninf.io:50051 activate> gi-build-x86-02: building r65gq110hw7yvc1xsqmj28lfqf1avylh-activate.drv building '/nix/store/zzxhvw0xm5s7p6w9xsdnys3spm4hy8z7-nixos-system-machine1-test.drv' on nix-store.geninf.io:50051 nixos-system-machine1-test> gi-build-x86-02: building zzxhvw0xm5s7p6w9xsdnys3spm4hy8z7-nixos-system-machine1-test.drv nixos-system-machine1-test> structuredAttrs is enabled building '/nix/store/whc1qiyv1rav6485dg93rirj81s987jx-run-machine1-nspawn.drv' on nix-store.geninf.io:50051 run-machine1-nspawn> gi-build-x86-02: building whc1qiyv1rav6485dg93rirj81s987jx-run-machine1-nspawn.drv building '/nix/store/w3yjpsgawlibfab78wy5fshx6k0yyifh-etc.drv' on nix-store.geninf.io:50051 etc> build-x86-01: building w3yjpsgawlibfab78wy5fshx6k0yyifh-etc.drv building '/nix/store/zxzzsyjbfk75hk5s4vlm5rqdnbcb2743-activate.drv' on nix-store.geninf.io:50051 activate> build-x86-01: building zxzzsyjbfk75hk5s4vlm5rqdnbcb2743-activate.drv building '/nix/store/qjhn6cy4rlyzh4a1db6lcv9d1z544w87-nixos-system-machine2-test.drv' on nix-store.geninf.io:50051 nixos-system-machine2-test> gi-build-x86-02: building qjhn6cy4rlyzh4a1db6lcv9d1z544w87-nixos-system-machine2-test.drv nixos-system-machine2-test> structuredAttrs is enabled building '/nix/store/5jqd858vrvf0r76w114l7lcs9dym2liq-run-machine2-nspawn.drv' on nix-store.geninf.io:50051 run-machine2-nspawn> gi-build-x86-02: building 5jqd858vrvf0r76w114l7lcs9dym2liq-run-machine2-nspawn.drv building '/nix/store/kyr2ysplyqiswrm0rwapn1fmqnwrs325-driverConfiguration.json.drv' on nix-store.geninf.io:50051 driverConfiguration.json> gi-build-x86-02: building kyr2ysplyqiswrm0rwapn1fmqnwrs325-driverConfiguration.json.drv driverConfiguration.json> structuredAttrs is enabled 3kxf9053cc2zp6h4rhzdw2qiv26nvs04-nixos-test-driver-container.drv: gi-build-x86-02: not scheduled here: 3kxf9053cc2zp6h4rhzdw2qiv26nvs04-nixos-test-driver-container.drv, asking the scheduler again building '/nix/store/3kxf9053cc2zp6h4rhzdw2qiv26nvs04-nixos-test-driver-container.drv' on nix-store.geninf.io:50051 nixos-test-driver-container> build-x86-01: building 3kxf9053cc2zp6h4rhzdw2qiv26nvs04-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/1g77rfpn0k06p7gv9f8yhawc617lzqdz-container-test-run-container.drv' on nix-store.geninf.io:50051 container-test-run-container> build-x86-01: building 1g77rfpn0k06p7gv9f8yhawc617lzqdz-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 # [2956719.718150] machine1 systemd-journald[54]: Journal started container-test-run-container> machine1 # [2956719.718177] machine1 systemd-journald[54]: Runtime Journal (/run/log/journal/94ae69bbf25c44f08936e83e54316e48) is 8M, max 3.7G, 3.7G free. container-test-run-container> machine1 # [2956719.718682] machine1 systemd[1]: Finished Create Static Device Nodes in /dev gracefully. container-test-run-container> machine1 # [2956719.722685] machine1 systemd[1]: Starting Flush Journal to Persistent Storage... container-test-run-container> machine1 # [2956719.722950] machine1 systemd[1]: Starting Create Static Device Nodes in /dev... container-test-run-container> machine1 # [2956719.726860] machine1 systemd-journald[54]: Time spent on flushing to /var/log/journal/94ae69bbf25c44f08936e83e54316e48 is 1.010ms for 5 entries. container-test-run-container> machine1 # [2956719.726860] machine1 systemd-journald[54]: System Journal (/var/log/journal/94ae69bbf25c44f08936e83e54316e48) is 8M, max 4G, 3.9G free. container-test-run-container> machine1 # [2956719.730364] machine1 systemd[1]: Finished Create Static Device Nodes in /dev. container-test-run-container> machine1 # [2956719.730462] machine1 systemd[1]: Reached target Preparation for Local File Systems. container-test-run-container> machine1 # [2956719.730499] machine1 systemd[1]: Reached target Local File Systems. container-test-run-container> machine1 # [2956719.730882] machine1 systemd[1]: Listening on Boot Loader Control Service Socket. container-test-run-container> machine1 # [2956719.730905] machine1 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container container-test-run-container> machine1 # [2956719.731201] machine1 systemd[1]: Starting Save Transient machine-id to Disk... container-test-run-container> machine2 # [2956719.717585] machine2 systemd-journald[54]: Journal started container-test-run-container> machine1 # [2956719.731213] machine1 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys container-test-run-container> machine2 # [2956719.717613] machine2 systemd-journald[54]: Runtime Journal (/run/log/journal/c9e4cf232de24faf946dfa1827cae6b0) is 8M, max 3.7G, 3.7G free. container-test-run-container> machine1 # [2956719.734688] machine1 systemd[1]: Finished Flush Journal to Persistent Storage. container-test-run-container> machine2 # [2956719.721234] machine2 systemd[1]: Starting Flush Journal to Persistent Storage... container-test-run-container> machine1 # [2956719.735145] machine1 systemd[1]: Starting Create System Files and Directories... container-test-run-container> machine2 # [2956719.721508] machine2 systemd[1]: Starting Create Static Device Nodes in /dev... container-test-run-container> machine1 # [2956719.745109] machine1 systemd-tmpfiles[90]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted container-test-run-container> machine2 # [2956719.725460] machine2 systemd-journald[54]: Time spent on flushing to /var/log/journal/c9e4cf232de24faf946dfa1827cae6b0 is 859us for 4 entries. container-test-run-container> machine1 # [2956719.745244] machine1 systemd-tmpfiles[90]: fchmod() of /var/log/journal failed: Operation not permitted container-test-run-container> machine2 # [2956719.725460] machine2 systemd-journald[54]: System Journal (/var/log/journal/c9e4cf232de24faf946dfa1827cae6b0) is 8M, max 4G, 3.9G free. container-test-run-container> machine1 # [2956719.745338] machine1 systemd-tmpfiles[90]: fchmod() of /var/log/journal/94ae69bbf25c44f08936e83e54316e48 failed: Operation not permitted container-test-run-container> machine2 # [2956719.728868] machine2 systemd[1]: Finished Create Static Device Nodes in /dev. container-test-run-container> machine1 # [2956719.745478] machine1 systemd-tmpfiles[90]: fchmod() of /run/log/journal failed: Operation not permitted container-test-run-container> machine2 # [2956719.729191] machine2 systemd[1]: Reached target Preparation for Local File Systems. container-test-run-container> machine1 # [2956719.746136] machine1 systemd[1]: Finished Create System Files and Directories. container-test-run-container> machine2 # [2956719.729236] machine2 systemd[1]: Reached target Local File Systems. container-test-run-container> machine1 # [2956719.746650] machine1 systemd[1]: Starting Rebuild Journal Catalog... container-test-run-container> machine2 # [2956719.729606] machine2 systemd[1]: Listening on Boot Loader Control Service Socket. container-test-run-container> machine1 # [2956719.746972] machine1 systemd[1]: Starting Record System Boot/Shutdown in UTMP... container-test-run-container> machine2 # [2956719.729628] machine2 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container container-test-run-container> machine1 # [2956719.756716] machine1 systemd[1]: Finished Record System Boot/Shutdown in UTMP. container-test-run-container> machine2 # [2956719.729937] machine2 systemd[1]: Starting Save Transient machine-id to Disk... container-test-run-container> machine1 # [2956719.759414] machine1 systemd[1]: Finished Rebuild Journal Catalog. container-test-run-container> machine2 # [2956719.729950] machine2 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys container-test-run-container> machine1 # [2956719.759962] machine1 systemd[1]: Starting Update is Completed... container-test-run-container> machine2 # [2956719.734639] machine2 systemd[1]: Finished Flush Journal to Persistent Storage. container-test-run-container> machine1 # [2956719.760424] machine1 systemd[1]: Finished Save Transient machine-id to Disk. container-test-run-container> machine2 # [2956719.735133] machine2 systemd[1]: Starting Create System Files and Directories... container-test-run-container> machine1 # [2956719.765248] machine1 systemd[1]: Finished Update is Completed. container-test-run-container> machine2 # [2956719.745388] machine2 systemd-tmpfiles[91]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted container-test-run-container> machine1 # [2956719.765333] machine1 systemd[1]: Reached target System Initialization. container-test-run-container> machine2 # [2956719.745523] machine2 systemd-tmpfiles[91]: fchmod() of /var/log/journal failed: Operation not permitted container-test-run-container> machine1 # [2956719.765372] machine1 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container container-test-run-container> machine2 # [2956719.745617] machine2 systemd-tmpfiles[91]: fchmod() of /var/log/journal/c9e4cf232de24faf946dfa1827cae6b0 failed: Operation not permitted container-test-run-container> machine1 # [2956719.765388] machine1 systemd[1]: Started Daily Cleanup of Temporary Directories. container-test-run-container> machine2 # [2956719.745818] machine2 systemd-tmpfiles[91]: fchmod() of /run/log/journal failed: Operation not permitted container-test-run-container> machine1 # [2956719.765399] machine1 systemd[1]: Reached target Timer Units. container-test-run-container> machine2 # [2956719.746589] machine2 systemd[1]: Finished Create System Files and Directories. container-test-run-container> machine1 # [2956719.765458] machine1 systemd[1]: Listening on D-Bus System Message Bus Socket. container-test-run-container> machine2 # [2956719.746970] machine2 systemd[1]: Starting Rebuild Journal Catalog... container-test-run-container> machine1 # [2956719.765511] machine1 systemd[1]: Listening on Nix Daemon Socket. container-test-run-container> machine2 # [2956719.747245] machine2 systemd[1]: Starting Record System Boot/Shutdown in UTMP... container-test-run-container> machine1 # [2956719.766012] machine1 systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Export). container-test-run-container> machine2 # [2956719.752975] machine2 systemd[1]: Finished Record System Boot/Shutdown in UTMP. container-test-run-container> machine1 # [2956719.766434] machine1 systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). container-test-run-container> machine2 # [2956719.759785] machine2 systemd[1]: Finished Rebuild Journal Catalog. container-test-run-container> machine1 # [2956719.766492] machine1 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. container-test-run-container> machine2 # [2956719.760285] machine2 systemd[1]: Starting Update is Completed... container-test-run-container> machine1 # [2956719.766502] machine1 systemd[1]: Reached target Socket Units. container-test-run-container> machine2 # [2956719.760425] machine2 systemd[1]: Finished Save Transient machine-id to Disk. container-test-run-container> machine1 # [2956719.766520] machine1 systemd[1]: Reached target Basic System. container-test-run-container> machine2 # [2956719.765259] machine2 systemd[1]: Finished Update is Completed. container-test-run-container> machine1 # [2956719.766954] machine1 systemd[1]: Starting Import lastlog data into lastlog2 database... container-test-run-container> machine2 # [2956719.765336] machine2 systemd[1]: Reached target System Initialization. container-test-run-container> machine1 # [2956719.767310] machine1 systemd[1]: Starting Name Service Cache Daemon (nsncd)... container-test-run-container> machine2 # [2956719.765367] machine2 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container container-test-run-container> machine1 # [2956719.767638] machine1 systemd[1]: Starting SSH Host Keys Generation... container-test-run-container> machine2 # [2956719.765381] machine2 systemd[1]: Started Daily Cleanup of Temporary Directories. container-test-run-container> machine2 # [2956719.765391] machine2 systemd[1]: Reached target Timer Units. container-test-run-container> machine1 # [2956719.776591] machine1 systemd[1]: Finished Import lastlog data into lastlog2 database. container-test-run-container> machine2 # [2956719.765446] machine2 systemd[1]: Listening on D-Bus System Message Bus Socket. container-test-run-container> machine1 # [2956719.801275] machine1 systemd[1]: Finished Firewall. container-test-run-container> machine2 # [2956719.765494] machine2 systemd[1]: Listening on Nix Daemon Socket. container-test-run-container> machine1 # [2956719.801357] machine1 systemd[1]: Reached target Preparation for Network. container-test-run-container> machine2 # [2956719.765974] machine2 systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Export). container-test-run-container> machine1 # [2956719.801708] machine1 systemd[1]: Starting Address configuration of eth1... container-test-run-container> machine2 # [2956719.766414] machine2 systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). container-test-run-container> machine1 # [2956719.802027] machine1 systemd[1]: Starting Extra networking commands.... container-test-run-container> machine2 # [2956719.766472] machine2 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. container-test-run-container> machine1 # [2956719.810137] machine1 nsncd[131]: Oct 08 12:35:34.233 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" container-test-run-container> machine2 # [2956719.766481] machine2 systemd[1]: Reached target Socket Units. container-test-run-container> machine1 # [2956719.810165] machine1 systemd[1]: Started Name Service Cache Daemon (nsncd). container-test-run-container> machine2 # [2956719.766499] machine2 systemd[1]: Reached target Basic System. container-test-run-container> machine1 # [2956719.810190] machine1 systemd[1]: Reached target Host and Network Name Lookups. container-test-run-container> machine2 # [2956719.766921] machine2 systemd[1]: Starting Import lastlog data into lastlog2 database... container-test-run-container> machine1 # [2956719.810217] machine1 systemd[1]: Reached target User and Group Name Lookups. container-test-run-container> machine2 # [2956719.767314] machine2 systemd[1]: Starting Name Service Cache Daemon (nsncd)... container-test-run-container> machine1 # [2956719.810614] machine1 network-addresses-eth1-start[179]: adding address 192.168.1.1/24... done container-test-run-container> machine2 # [2956719.767628] machine2 systemd[1]: Starting SSH Host Keys Generation... container-test-run-container> machine1 # [2956719.810698] machine1 systemd[1]: Starting User Login Management... container-test-run-container> machine2 # [2956719.775955] machine2 systemd[1]: Finished Import lastlog data into lastlog2 database. container-test-run-container> machine1 # [2956719.811900] machine1 network-addresses-eth1-start[179]: adding address 2001:db8:1::1/64... done container-test-run-container> machine2 # [2956719.798840] machine2 systemd[1]: Finished Firewall. container-test-run-container> machine1 # [2956719.814251] machine1 systemd[1]: Finished Address configuration of eth1. container-test-run-container> machine1 # [2956719.829672] machine1 systemd[1]: Stopped target Host and Network Name Lookups. container-test-run-container> machine2 # [2956719.799250] machine2 systemd[1]: Reached target Preparation for Network. container-test-run-container> machine1 # [2956719.829699] machine1 systemd[1]: Stopping Host and Network Name Lookups... container-test-run-container> machine2 # [2956719.799611] machine2 systemd[1]: Starting Address configuration of eth1... container-test-run-container> machine1 # [2956719.829718] machine1 systemd[1]: Stopped target User and Group Name Lookups. container-test-run-container> machine2 # [2956719.799949] machine2 systemd[1]: Starting Extra networking commands.... container-test-run-container> machine1 # [2956719.829733] machine1 systemd[1]: Stopping User and Group Name Lookups... container-test-run-container> machine2 # [2956719.808282] machine2 network-addresses-eth1-start[179]: adding address 192.168.1.2/24... done container-test-run-container> machine1 # [2956719.829823] machine1 systemd[1]: Stopping Name Service Cache Daemon (nsncd)... container-test-run-container> machine2 # [2956719.809802] machine2 network-addresses-eth1-start[179]: adding address 2001:db8:1::2/64... done container-test-run-container> machine1 # [2956719.830762] machine1 systemd[1]: nscd.service: Deactivated successfully. container-test-run-container> machine2 # [2956719.810664] machine2 nsncd[131]: Oct 08 12:35:34.233 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" container-test-run-container> machine1 # [2956719.830842] machine1 systemd[1]: Stopped Name Service Cache Daemon (nsncd). container-test-run-container> machine2 # [2956719.810695] machine2 systemd[1]: Started Name Service Cache Daemon (nsncd). container-test-run-container> machine1 # [2956719.832408] machine1 systemd[1]: Starting Name Service Cache Daemon (nsncd)... container-test-run-container> machine2 # [2956719.810724] machine2 systemd[1]: Reached target Host and Network Name Lookups. container-test-run-container> machine1 # [2956719.837907] machine1 systemd[1]: Finished Extra networking commands.. container-test-run-container> machine2 # [2956719.810751] machine2 systemd[1]: Reached target User and Group Name Lookups. container-test-run-container> machine1 # [2956719.838075] machine1 systemd[1]: Reached target Network. container-test-run-container> machine2 # [2956719.811325] machine2 systemd[1]: Starting User Login Management... container-test-run-container> machine1 # [2956719.876777] machine1 nsncd[253]: Oct 08 12:35:34.300 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" container-test-run-container> machine2 # [2956719.812240] machine2 systemd[1]: Finished Address configuration of eth1. container-test-run-container> machine1 # [2956719.876807] machine1 systemd[1]: Started Name Service Cache Daemon (nsncd). container-test-run-container> machine2 # [2956719.828378] machine2 systemd[1]: Stopped target Host and Network Name Lookups. container-test-run-container> machine1 # [2956719.876829] machine1 systemd[1]: Reached target Host and Network Name Lookups. container-test-run-container> machine2 # [2956719.828402] machine2 systemd[1]: Stopping Host and Network Name Lookups... container-test-run-container> machine1 # [2956719.876857] machine1 systemd[1]: Reached target User and Group Name Lookups. container-test-run-container> machine2 # [2956719.828420] machine2 systemd[1]: Stopped target User and Group Name Lookups. container-test-run-container> machine1 # [2956719.877200] machine1 systemd[1]: Starting Permit User Sessions... container-test-run-container> machine2 # [2956719.828436] machine2 systemd[1]: Stopping User and Group Name Lookups... container-test-run-container> machine1 # [2956719.904080] machine1 systemd[1]: Finished Permit User Sessions. container-test-run-container> machine2 # [2956719.828526] machine2 systemd[1]: Stopping Name Service Cache Daemon (nsncd)... container-test-run-container> machine1 # [2956719.904493] machine1 systemd[1]: Started Console Getty. container-test-run-container> machine2 # [2956719.830131] machine2 systemd[1]: nscd.service: Deactivated successfully. container-test-run-container> machine1 # [2956719.904511] machine1 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0 container-test-run-container> machine2 # [2956719.830198] machine2 systemd[1]: Stopped Name Service Cache Daemon (nsncd). container-test-run-container> machine1 # [2956719.904521] machine1 systemd[1]: Reached target Login Prompts. container-test-run-container> machine2 # [2956719.831404] machine2 systemd[1]: Starting Name Service Cache Daemon (nsncd)... container-test-run-container> machine1 # [2956720.061762] machine1 systemd-logind[197]: New seat seat0. container-test-run-container> machine2 # [2956719.836778] machine2 systemd[1]: Finished Extra networking commands.. container-test-run-container> machine1 # [2956720.078155] machine1 systemd[1]: Starting D-Bus System Message Bus... container-test-run-container> machine2 # [2956719.836844] machine2 systemd[1]: Reached target Network. container-test-run-container> machine1 # [2956720.078192] machine1 systemd[1]: Started User Login Management. container-test-run-container> machine2 # [2956719.875751] machine2 nsncd[254]: Oct 08 12:35:34.299 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" container-test-run-container> machine1 # [2956720.078538] machine1 systemd[1]: Starting linger-users.service... container-test-run-container> machine2 # [2956719.875797] machine2 systemd[1]: Started Name Service Cache Daemon (nsncd). container-test-run-container> machine1 # [2956720.085090] machine1 systemd[1]: linger-users.service: Deactivated successfully. container-test-run-container> machine2 # [2956719.875821] machine2 systemd[1]: Reached target Host and Network Name Lookups. container-test-run-container> machine1 # [2956720.085154] machine1 systemd[1]: Finished linger-users.service. container-test-run-container> machine1 # [2956720.162960] machine1 dbus-broker-launch[276]: Looking up NSS user entry for 'systemd-timesync'... container-test-run-container> machine2 # [2956719.875848] machine2 systemd[1]: Reached target User and Group Name Lookups. container-test-run-container> machine1 # [2956720.163436] machine1 dbus-broker-launch[276]: NSS returned no entry for 'systemd-timesync' container-test-run-container> machine2 # [2956719.876208] machine2 systemd[1]: Starting Permit User Sessions... container-test-run-container> machine1 # [2956720.163436] machine1 dbus-broker-launch[276]: Invalid user-name in /nix/store/h4680s5xwcbiipb85ysxl98db14wrysp-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" container-test-run-container> machine2 # [2956719.904098] machine2 systemd[1]: Finished Permit User Sessions. container-test-run-container> machine1 # [2956720.163742] machine1 systemd[1]: Started D-Bus System Message Bus. container-test-run-container> machine2 # [2956719.904517] machine2 systemd[1]: Started Console Getty. container-test-run-container> machine1 # [2956720.167129] machine1 dbus-broker-launch[276]: Ready container-test-run-container> machine2 # [2956719.904535] machine2 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0 container-test-run-container> machine1 # [2956720.282255] machine1 sshd-keygen-start[151]: Generating public/private rsa key pair. container-test-run-container> machine2 # [2956719.904544] machine2 systemd[1]: Reached target Login Prompts. container-test-run-container> machine1 # [2956720.282255] machine1 sshd-keygen-start[151]: Your identification has been saved in /etc/ssh/ssh_host_rsa_key container-test-run-container> machine2 # [2956719.995617] machine2 sshd-keygen-start[152]: Generating public/private rsa key pair. container-test-run-container> machine1 # [2956720.282255] machine1 sshd-keygen-start[151]: Your public key has been saved in /etc/ssh/ssh_host_rsa_key.pub container-test-run-container> machine2 # [2956719.995617] machine2 sshd-keygen-start[152]: Your identification has been saved in /etc/ssh/ssh_host_rsa_key container-test-run-container> machine1 # [2956720.282255] machine1 sshd-keygen-start[151]: The key fingerprint is: container-test-run-container> machine2 # [2956719.995617] machine2 sshd-keygen-start[152]: Your public key has been saved in /etc/ssh/ssh_host_rsa_key.pub container-test-run-container> machine1 # [2956720.282255] machine1 sshd-keygen-start[151]: SHA256:ETYj2QXH4VVnWtS0r6BxR3FkB1aR6/iWFiBN3IerBac root@machine1 container-test-run-container> machine2 # [2956719.995617] machine2 sshd-keygen-start[152]: The key fingerprint is: container-test-run-container> machine1 # [2956720.282255] machine1 sshd-keygen-start[151]: The key's randomart image is: container-test-run-container> machine2 # [2956719.995617] machine2 sshd-keygen-start[152]: SHA256:TxLkcgdwBHqXeT0ale6PyWQJ4UH2UMy7m6j1A/PDWBI root@machine2 container-test-run-container> machine1 # [2956720.282255] machine1 sshd-keygen-start[151]: +---[RSA 4096]----+ container-test-run-container> machine2 # [2956719.995617] machine2 sshd-keygen-start[152]: The key's randomart image is: container-test-run-container> machine1 # [2956720.282255] machine1 sshd-keygen-start[151]: | .o*++.o.*O/| container-test-run-container> machine2 # [2956719.995617] machine2 sshd-keygen-start[152]: +---[RSA 4096]----+ container-test-run-container> machine1 # [2956720.282255] machine1 sshd-keygen-start[151]: | .o.*...+o@=| container-test-run-container> machine2 # [2956719.995617] machine2 sshd-keygen-start[152]: | o+=.++o. | container-test-run-container> machine1 # [2956720.282255] machine1 sshd-keygen-start[151]: | . . o+ooo| container-test-run-container> machine2 # [2956719.995617] machine2 sshd-keygen-start[152]: | . + =o=+ | container-test-run-container> machine1 # [2956720.282255] machine1 sshd-keygen-start[151]: | . .E+o..| container-test-run-container> machine2 # [2956719.995617] machine2 sshd-keygen-start[152]: | . o B.+++. | container-test-run-container> machine1 # [2956720.282255] machine1 sshd-keygen-start[151]: | S . +o= .| container-test-run-container> machine2 # [2956719.995617] machine2 sshd-keygen-start[152]: | . + +Eoo. | container-test-run-container> machine1 # [2956720.282255] machine1 sshd-keygen-start[151]: | +.+ + | container-test-run-container> machine2 # [2956719.995617] machine2 sshd-keygen-start[152]: | S o+ o | container-test-run-container> machine1 # [2956720.282255] machine1 sshd-keygen-start[151]: | . o o| container-test-run-container> machine2 # [2956719.995617] machine2 sshd-keygen-start[152]: | ++ B | container-test-run-container> machine1 # [2956720.282255] machine1 sshd-keygen-start[151]: | = | container-test-run-container> machine2 # [2956719.995617] machine2 sshd-keygen-start[152]: | o& * | container-test-run-container> machine1 # [2956720.282255] machine1 sshd-keygen-start[151]: | o | container-test-run-container> machine2 # [2956719.995617] machine2 sshd-keygen-start[152]: | .o.@ . | container-test-run-container> machine1 # [2956720.282255] machine1 sshd-keygen-start[151]: +----[SHA256]-----+ container-test-run-container> machine2 # [2956719.995617] machine2 sshd-keygen-start[152]: | .. .o | container-test-run-container> machine1 # [2956720.290018] machine1 sshd-keygen-start[286]: Generating public/private ed25519 key pair. container-test-run-container> machine2 # [2956719.995617] machine2 sshd-keygen-start[152]: +----[SHA256]-----+ container-test-run-container> machine1 # [2956720.290018] machine1 sshd-keygen-start[286]: Your identification has been saved in /etc/ssh/ssh_host_ed25519_key container-test-run-container> machine2 # [2956720.002599] machine2 sshd-keygen-start[281]: Generating public/private ed25519 key pair. container-test-run-container> machine1 # [2956720.290018] machine1 sshd-keygen-start[286]: Your public key has been saved in /etc/ssh/ssh_host_ed25519_key.pub container-test-run-container> machine2 # [2956720.002599] machine2 sshd-keygen-start[281]: Your identification has been saved in /etc/ssh/ssh_host_ed25519_key container-test-run-container> machine1 # [2956720.290018] machine1 sshd-keygen-start[286]: The key fingerprint is: container-test-run-container> machine2 # [2956720.002599] machine2 sshd-keygen-start[281]: Your public key has been saved in /etc/ssh/ssh_host_ed25519_key.pub container-test-run-container> machine1 # [2956720.290018] machine1 sshd-keygen-start[286]: SHA256:I9qJ8SLwMS04kwPKKkN24pLo1F80Onek2ZtJE4h7i4s root@machine1 container-test-run-container> machine2 # [2956720.002599] machine2 sshd-keygen-start[281]: The key fingerprint is: container-test-run-container> machine1 # [2956720.290018] machine1 sshd-keygen-start[286]: The key's randomart image is: container-test-run-container> machine2 # [2956720.002599] machine2 sshd-keygen-start[281]: SHA256:fcst2djCMhCLcj8THzJCbe6HhmSFqpZoLyAOAjllpH0 root@machine2 container-test-run-container> machine1 # [2956720.290018] machine1 sshd-keygen-start[286]: +--[ED25519 256]--+ container-test-run-container> machine2 # [2956720.002599] machine2 sshd-keygen-start[281]: The key's randomart image is: container-test-run-container> machine1 # [2956720.290018] machine1 sshd-keygen-start[286]: | | container-test-run-container> machine2 # [2956720.002599] machine2 sshd-keygen-start[281]: +--[ED25519 256]--+ container-test-run-container> machine1 # [2956720.290018] machine1 sshd-keygen-start[286]: | | container-test-run-container> machine2 # [2956720.002599] machine2 sshd-keygen-start[281]: | .. | container-test-run-container> machine1 # [2956720.290018] machine1 sshd-keygen-start[286]: |. | container-test-run-container> machine2 # [2956720.002599] machine2 sshd-keygen-start[281]: | oo o | container-test-run-container> machine1 # [2956720.290018] machine1 sshd-keygen-start[286]: |+o . . . | container-test-run-container> machine2 # [2956720.002599] machine2 sshd-keygen-start[281]: |.+. E o = | container-test-run-container> machine1 # [2956720.290018] machine1 sshd-keygen-start[286]: |O=+.o..+So | container-test-run-container> machine2 # [2956720.002599] machine2 sshd-keygen-start[281]: |+ . o = + | container-test-run-container> machine1 # [2956720.290018] machine1 sshd-keygen-start[286]: |*B++ *+o*.. | container-test-run-container> machine2 # [2956720.002599] machine2 sshd-keygen-start[281]: |.. o * S o . | container-test-run-container> machine1 # [2956720.290018] machine1 sshd-keygen-start[286]: |Boo.+++* = | container-test-run-container> machine2 # [2956720.002599] machine2 sshd-keygen-start[281]: |=. o = = B = B | container-test-run-container> machine1 # [2956720.290018] machine1 sshd-keygen-start[286]: |=. .o.* + = | container-test-run-container> machine2 # [2956720.002599] machine2 sshd-keygen-start[281]: |B.+ . B = O + | container-test-run-container> machine1 # [2956720.290018] machine1 sshd-keygen-start[286]: | . E +.. + | container-test-run-container> machine2 # [2956720.002599] machine2 sshd-keygen-start[281]: |.+. . + o o | container-test-run-container> machine1 # [2956720.290018] machine1 sshd-keygen-start[286]: +----[SHA256]-----+ container-test-run-container> machine2 # [2956720.002599] machine2 sshd-keygen-start[281]: | .. | container-test-run-container> machine1 # [2956720.290617] machine1 systemd[1]: sshd-keygen.service: Deactivated successfully. container-test-run-container> machine2 # [2956720.002599] machine2 sshd-keygen-start[281]: +----[SHA256]-----+ container-test-run-container> machine1 # [2956720.290662] machine1 systemd[1]: Finished SSH Host Keys Generation. container-test-run-container> machine2 # [2956720.003372] machine2 systemd[1]: sshd-keygen.service: Deactivated successfully. container-test-run-container> machine1 # [2956720.291552] machine1 systemd[1]: Starting SSH Daemon... container-test-run-container> machine2 # [2956720.003441] machine2 systemd[1]: Finished SSH Host Keys Generation. container-test-run-container> machine2 # [2956720.003850] machine2 systemd[1]: Starting SSH Daemon... container-test-run-container> machine2 # [2956720.019093] machine2 sshd[283]: Server listening on 0.0.0.0 port 22. container-test-run-container> machine2 # [2956720.019110] machine2 sshd[283]: Server listening on :: port 22. container-test-run-container> machine2 # [2956720.019147] machine2 systemd[1]: Started SSH Daemon. container-test-run-container> machine2 # [2956720.058855] machine2 systemd-logind[205]: New seat seat0. container-test-run-container> machine2 # [2956720.059158] machine2 systemd[1]: Starting D-Bus System Message Bus... container-test-run-container> machine2 # [2956720.059207] machine2 systemd[1]: Started User Login Management. container-test-run-container> machine2 # [2956720.059532] machine2 systemd[1]: Starting linger-users.service... container-test-run-container> machine2 # [2956720.082278] machine2 systemd[1]: linger-users.service: Deactivated successfully. container-test-run-container> machine2 # [2956720.082349] machine2 systemd[1]: Finished linger-users.service. container-test-run-container> machine2 # [2956720.082461] machine2 systemd[1]: Reached target Multi-User System. container-test-run-container> machine2 # [2956720.160216] machine2 dbus-broker-launch[284]: Looking up NSS user entry for 'systemd-timesync'... container-test-run-container> machine2 # [2956720.160668] machine2 dbus-broker-launch[284]: NSS returned no entry for 'systemd-timesync' container-test-run-container> machine2 # [2956720.160668] machine2 dbus-broker-launch[284]: Invalid user-name in /nix/store/h4680s5xwcbiipb85ysxl98db14wrysp-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" container-test-run-container> machine2 # [2956720.160986] machine2 systemd[1]: Started D-Bus System Message Bus. container-test-run-container> machine2 # [2956720.164517] machine2 dbus-broker-launch[284]: Ready container-test-run-container> machine2 # [2956720.164778] machine2 systemd[1]: Startup finished in 655ms. container-test-run-container> machine1: (finished: must succeed: systemctl status sshd, in 1.14 seconds) container-test-run-container> machine2: must succeed: systemctl status sshd container-test-run-container> machine2: (finished: must succeed: systemctl status sshd, in 0.01 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.01 seconds) container-test-run-container> (finished: run the VM test script, in 1.18 seconds) container-test-run-container> test script finished in 1.19s 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> Container machine2 terminated by signal KILL. container-test-run-container> (finished: cleanup, in 0.18 seconds)