don't know how to build these paths: /nix/store/j9dfzwqmcrqaxm9xnpkyk0klpk407mlr-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/qm4f6wpq4ws8kk7zlcgy55kv5irwb618-system-path.drv' on nix-store.geninf.io:50051 system-path> build01: building qm4f6wpq4ws8kk7zlcgy55kv5irwb618-system-path.drv system-path> structuredAttrs is enabled system-path> created 1654 symlinks in user environment 0f7szaj2g4cmv41a6syrk92w1ianl5lq-dbus-1.drv: build01: not scheduled here: 0f7szaj2g4cmv41a6syrk92w1ianl5lq-dbus-1.drv, asking the scheduler again building '/nix/store/0f7szaj2g4cmv41a6syrk92w1ianl5lq-dbus-1.drv' on nix-store.geninf.io:50051 dbus-1> build01: building 0f7szaj2g4cmv41a6syrk92w1ianl5lq-dbus-1.drv building '/nix/store/0l1jlzhkyxn7fw095m0k4jk7y3nr0dil-X-Restart-Triggers-dbus-broker.drv' on nix-store.geninf.io:50051 X-Restart-Triggers-dbus-broker> build01: building 0l1jlzhkyxn7fw095m0k4jk7y3nr0dil-X-Restart-Triggers-dbus-broker.drv X-Restart-Triggers-dbus-broker> structuredAttrs is enabled building '/nix/store/1dv6vwdx6alb7jjvz128kgfpy0vbk4s5-unit-dbus-broker.service.drv' on nix-store.geninf.io:50051 unit-dbus-broker.service> build01: building 1dv6vwdx6alb7jjvz128kgfpy0vbk4s5-unit-dbus-broker.service.drv unit-dbus-broker.service> structuredAttrs is enabled 4vsk0dg5apffimznf86c2xpkrywq7xfp-unit-dbus-broker.service.drv: build01: not scheduled here: 4vsk0dg5apffimznf86c2xpkrywq7xfp-unit-dbus-broker.service.drv, asking the scheduler again building '/nix/store/4vsk0dg5apffimznf86c2xpkrywq7xfp-unit-dbus-broker.service.drv' on nix-store.geninf.io:50051 unit-dbus-broker.service> build01: building 4vsk0dg5apffimznf86c2xpkrywq7xfp-unit-dbus-broker.service.drv unit-dbus-broker.service> structuredAttrs is enabled building '/nix/store/7lvh9rq0l2gy8j6q4fkcpvm1l460gcyd-user-units.drv' on nix-store.geninf.io:50051 user-units> build01: building 7lvh9rq0l2gy8j6q4fkcpvm1l460gcyd-user-units.drv gvk2wpbr1jcsn9iawfanx077l5zz4hb8-system-units.drv: build01: not scheduled here: gvk2wpbr1jcsn9iawfanx077l5zz4hb8-system-units.drv, asking the scheduler again building '/nix/store/gvk2wpbr1jcsn9iawfanx077l5zz4hb8-system-units.drv' on nix-store.geninf.io:50051 system-units> build01: building gvk2wpbr1jcsn9iawfanx077l5zz4hb8-system-units.drv 60pyh8cqf7rdfy3b00bdnkzvl3b22rpn-system-units.drv: build01: not scheduled here: 60pyh8cqf7rdfy3b00bdnkzvl3b22rpn-system-units.drv, asking the scheduler again building '/nix/store/60pyh8cqf7rdfy3b00bdnkzvl3b22rpn-system-units.drv' on nix-store.geninf.io:50051 system-units> build01: building 60pyh8cqf7rdfy3b00bdnkzvl3b22rpn-system-units.drv i17q3dgnpjyjkbjcw7hy70n9jlg4pjn4-etc.drv: build01: not scheduled here: i17q3dgnpjyjkbjcw7hy70n9jlg4pjn4-etc.drv, asking the scheduler again jyqpn0r4qfwzrcn8ygaf659swx1ic435-etc.drv: build01: not scheduled here: jyqpn0r4qfwzrcn8ygaf659swx1ic435-etc.drv, asking the scheduler again building '/nix/store/i17q3dgnpjyjkbjcw7hy70n9jlg4pjn4-etc.drv' on nix-store.geninf.io:50051 etc> build01: building i17q3dgnpjyjkbjcw7hy70n9jlg4pjn4-etc.drv building '/nix/store/jyqpn0r4qfwzrcn8ygaf659swx1ic435-etc.drv' on nix-store.geninf.io:50051 etc> build01: building jyqpn0r4qfwzrcn8ygaf659swx1ic435-etc.drv 34ci7ysv1iv82m23sy0l9s6bxvmxshvd-activate.drv: build01: not scheduled here: 34ci7ysv1iv82m23sy0l9s6bxvmxshvd-activate.drv, asking the scheduler again y2dfc4p0y4rnylnjyvgnn6xlwlgq8cic-activate.drv: build01: not scheduled here: y2dfc4p0y4rnylnjyvgnn6xlwlgq8cic-activate.drv, asking the scheduler again building '/nix/store/34ci7ysv1iv82m23sy0l9s6bxvmxshvd-activate.drv' on nix-store.geninf.io:50051 activate> build01: building 34ci7ysv1iv82m23sy0l9s6bxvmxshvd-activate.drv building '/nix/store/y2dfc4p0y4rnylnjyvgnn6xlwlgq8cic-activate.drv' on nix-store.geninf.io:50051 activate> build01: building y2dfc4p0y4rnylnjyvgnn6xlwlgq8cic-activate.drv activate> structuredAttrs is enabled activate> structuredAttrs is enabled 304az3a325bmhz3v6j8fdah6nbd65pyz-nixos-system-machine1-test.drv: build01: not scheduled here: 304az3a325bmhz3v6j8fdah6nbd65pyz-nixos-system-machine1-test.drv, asking the scheduler again building '/nix/store/304az3a325bmhz3v6j8fdah6nbd65pyz-nixos-system-machine1-test.drv' on nix-store.geninf.io:50051 nixos-system-machine1-test> build01: building 304az3a325bmhz3v6j8fdah6nbd65pyz-nixos-system-machine1-test.drv nixos-system-machine1-test> structuredAttrs is enabled ssh7hny6dcfg1x87k2ripq7xj57irfjl-nixos-system-machine2-test.drv: build01: not scheduled here: ssh7hny6dcfg1x87k2ripq7xj57irfjl-nixos-system-machine2-test.drv, asking the scheduler again building '/nix/store/ssh7hny6dcfg1x87k2ripq7xj57irfjl-nixos-system-machine2-test.drv' on nix-store.geninf.io:50051 nixos-system-machine2-test> build01: building ssh7hny6dcfg1x87k2ripq7xj57irfjl-nixos-system-machine2-test.drv nixos-system-machine2-test> structuredAttrs is enabled dhn8y0z385cfsmwsjr1kkkyxnxp3xzpm-run-machine1-nspawn.drv: build01: not scheduled here: dhn8y0z385cfsmwsjr1kkkyxnxp3xzpm-run-machine1-nspawn.drv, asking the scheduler again building '/nix/store/dhn8y0z385cfsmwsjr1kkkyxnxp3xzpm-run-machine1-nspawn.drv' on nix-store.geninf.io:50051 run-machine1-nspawn> build01: building dhn8y0z385cfsmwsjr1kkkyxnxp3xzpm-run-machine1-nspawn.drv 0li22xwmla926qg1rj21ndsx2vs4q828-run-machine2-nspawn.drv: build01: not scheduled here: 0li22xwmla926qg1rj21ndsx2vs4q828-run-machine2-nspawn.drv, asking the scheduler again building '/nix/store/0li22xwmla926qg1rj21ndsx2vs4q828-run-machine2-nspawn.drv' on nix-store.geninf.io:50051 run-machine2-nspawn> build01: building 0li22xwmla926qg1rj21ndsx2vs4q828-run-machine2-nspawn.drv l1vi62cs2bcx9l2g3psaqs3fwk5pgsba-driverConfiguration.json.drv: build01: not scheduled here: l1vi62cs2bcx9l2g3psaqs3fwk5pgsba-driverConfiguration.json.drv, asking the scheduler again building '/nix/store/l1vi62cs2bcx9l2g3psaqs3fwk5pgsba-driverConfiguration.json.drv' on nix-store.geninf.io:50051 driverConfiguration.json> build01: building l1vi62cs2bcx9l2g3psaqs3fwk5pgsba-driverConfiguration.json.drv driverConfiguration.json> structuredAttrs is enabled 7kfx3n8ar5a1w6f4ymvpr720qw6imx2d-nixos-test-driver-container.drv: build01: not scheduled here: 7kfx3n8ar5a1w6f4ymvpr720qw6imx2d-nixos-test-driver-container.drv, asking the scheduler again building '/nix/store/7kfx3n8ar5a1w6f4ymvpr720qw6imx2d-nixos-test-driver-container.drv' on nix-store.geninf.io:50051 nixos-test-driver-container> build01: building 7kfx3n8ar5a1w6f4ymvpr720qw6imx2d-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/j9dfzwqmcrqaxm9xnpkyk0klpk407mlr-container-test-run-container.drv' on nix-store.geninf.io:50051 container-test-run-container> build01: building j9dfzwqmcrqaxm9xnpkyk0klpk407mlr-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> 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> ░ Spawning container machine2 on /build/vm-state-machine2. container-test-run-container> machine2 # No journal files were found. container-test-run-container> machine1 # No journal files were found. container-test-run-container> machine2 # No journal boot entry found for the specified boot (+0). container-test-run-container> machine1 # No journal boot entry found for the specified boot (+0). container-test-run-container> machine2 # [2620535.362438] machine2 systemd-journald[57]: Journal started container-test-run-container> machine2 # [2620535.362499] machine2 systemd-journald[57]: Runtime Journal (/run/log/journal/37f5673161c64955a0ffaf08a73274a8) is 8M, max 2.5G, 2.4G free. container-test-run-container> machine1 # [2620535.354292] machine1 systemd-journald[57]: Journal started container-test-run-container> machine1 # [2620535.354356] machine1 systemd-journald[57]: Runtime Journal (/run/log/journal/f32eb7e7ed3b4f83beae730abfcb7c14) is 8M, max 2.5G, 2.4G free. container-test-run-container> machine1 # [2620535.364345] machine1 systemd[1]: Finished Apply Kernel Variables. container-test-run-container> machine2 # [2620535.368920] machine2 systemd[1]: Finished Apply Kernel Variables. container-test-run-container> machine1 # [2620535.377277] machine1 systemd[1]: Finished Create Static Device Nodes in /dev gracefully. container-test-run-container> machine2 # [2620535.380826] machine2 systemd[1]: Finished Create Static Device Nodes in /dev gracefully. container-test-run-container> machine1 # [2620535.390675] machine1 systemd[1]: Starting Flush Journal to Persistent Storage... container-test-run-container> machine2 # [2620535.394578] machine2 systemd[1]: Starting Flush Journal to Persistent Storage... container-test-run-container> machine1 # [2620535.391621] machine1 systemd[1]: Starting Create Static Device Nodes in /dev... container-test-run-container> machine2 # [2620535.444378] machine2 systemd[1]: Starting Create Static Device Nodes in /dev... container-test-run-container> machine1 # [2620535.450753] machine1 systemd-journald[57]: Time spent on flushing to /var/log/journal/f32eb7e7ed3b4f83beae730abfcb7c14 is 3.209ms for 6 entries. container-test-run-container> machine2 # [2620535.451603] machine2 systemd-journald[57]: Time spent on flushing to /var/log/journal/37f5673161c64955a0ffaf08a73274a8 is 2.269ms for 6 entries. container-test-run-container> machine1 # [2620535.450753] machine1 systemd-journald[57]: System Journal (/var/log/journal/f32eb7e7ed3b4f83beae730abfcb7c14) is 8M, max 4G, 3.9G free. container-test-run-container> machine2 # [2620535.451603] machine2 systemd-journald[57]: System Journal (/var/log/journal/37f5673161c64955a0ffaf08a73274a8) is 8M, max 4G, 3.9G free. container-test-run-container> machine1 # [2620535.457747] machine1 systemd[1]: Finished Create Static Device Nodes in /dev. container-test-run-container> machine2 # [2620535.460835] machine2 systemd[1]: Finished Create Static Device Nodes in /dev. container-test-run-container> machine1 # [2620535.458361] machine1 systemd[1]: Reached target Preparation for Local File Systems. container-test-run-container> machine2 # [2620535.461416] machine2 systemd[1]: Reached target Preparation for Local File Systems. container-test-run-container> machine1 # [2620535.458477] machine1 systemd[1]: Reached target Local File Systems. container-test-run-container> machine2 # [2620535.461526] machine2 systemd[1]: Reached target Local File Systems. container-test-run-container> machine1 # [2620535.459322] machine1 systemd[1]: Listening on Boot Loader Control Service Socket. container-test-run-container> machine2 # [2620535.462358] machine2 systemd[1]: Listening on Boot Loader Control Service Socket. container-test-run-container> machine1 # [2620535.459370] machine1 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container container-test-run-container> machine2 # [2620535.462410] machine2 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container container-test-run-container> machine1 # [2620535.460213] machine1 systemd[1]: Starting Save Transient machine-id to Disk... container-test-run-container> machine2 # [2620535.463141] machine2 systemd[1]: Starting Save Transient machine-id to Disk... container-test-run-container> machine2 # [2620535.463181] machine2 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys container-test-run-container> machine2 # [2620535.464862] machine2 systemd[1]: Finished Flush Journal to Persistent Storage. container-test-run-container> machine2 # [2620535.465721] machine2 systemd[1]: Starting Create System Files and Directories... container-test-run-container> machine2 # [2620535.480227] machine2 systemd-tmpfiles[133]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted container-test-run-container> machine2 # [2620535.480400] machine2 systemd-tmpfiles[133]: fchmod() of /var/log/journal failed: Operation not permitted container-test-run-container> machine2 # [2620535.480518] machine2 systemd-tmpfiles[133]: fchmod() of /var/log/journal/37f5673161c64955a0ffaf08a73274a8 failed: Operation not permitted container-test-run-container> machine2 # [2620535.480693] machine2 systemd-tmpfiles[133]: fchmod() of /run/log/journal failed: Operation not permitted container-test-run-container> machine2 # [2620535.481899] machine2 systemd[1]: Finished Create System Files and Directories. container-test-run-container> machine2 # [2620535.483034] machine2 systemd[1]: Starting Rebuild Journal Catalog... container-test-run-container> machine2 # [2620535.483904] machine2 systemd[1]: Starting Record System Boot/Shutdown in UTMP... container-test-run-container> machine2 # [2620535.498964] machine2 systemd[1]: Finished Record System Boot/Shutdown in UTMP. container-test-run-container> machine2 # [2620535.508370] machine2 systemd[1]: Finished Rebuild Journal Catalog. container-test-run-container> machine2 # [2620535.509905] machine2 systemd[1]: Starting Update is Completed... container-test-run-container> machine2 # [2620535.515678] machine2 systemd[1]: Finished Firewall. container-test-run-container> machine2 # [2620535.515926] machine2 systemd[1]: Reached target Preparation for Network. container-test-run-container> machine2 # [2620535.521236] machine2 systemd[1]: Finished Update is Completed. container-test-run-container> machine2 # [2620535.521464] machine2 systemd[1]: Reached target System Initialization. container-test-run-container> machine2 # [2620535.521575] machine2 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container container-test-run-container> machine2 # [2620535.521623] machine2 systemd[1]: Started Daily Cleanup of Temporary Directories. container-test-run-container> machine2 # [2620535.521651] machine2 systemd[1]: Reached target Timer Units. container-test-run-container> machine2 # [2620535.521819] machine2 systemd[1]: Listening on D-Bus System Message Bus Socket. container-test-run-container> machine2 # [2620535.521986] machine2 systemd[1]: Listening on Nix Daemon Socket. container-test-run-container> machine2 # [2620535.523919] machine2 systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Export). container-test-run-container> machine2 # [2620535.525660] machine2 systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). container-test-run-container> machine2 # [2620535.525816] machine2 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. container-test-run-container> machine2 # [2620535.525854] machine2 systemd[1]: Reached target Socket Units. container-test-run-container> machine2 # [2620535.525920] machine2 systemd[1]: Reached target Basic System. container-test-run-container> machine2 # [2620535.527295] machine2 systemd[1]: Starting Import lastlog data into lastlog2 database... container-test-run-container> machine2 # [2620535.528270] machine2 systemd[1]: Starting Address configuration of eth1... container-test-run-container> machine2 # [2620535.529319] machine2 systemd[1]: Starting Extra networking commands.... container-test-run-container> machine2 # [2620535.530470] machine2 systemd[1]: Starting Name Service Cache Daemon (nsncd)... container-test-run-container> machine2 # [2620535.531456] machine2 systemd[1]: Starting SSH Host Keys Generation... container-test-run-container> machine2 # [2620535.546523] machine2 network-addresses-eth1-start[169]: adding address 192.168.1.2/24... done container-test-run-container> machine2 # [2620535.547497] machine2 systemd[1]: Finished Import lastlog data into lastlog2 database. container-test-run-container> machine2 # [2620535.549821] machine2 network-addresses-eth1-start[169]: adding address 2001:db8:1::2/64... done container-test-run-container> machine2 # [2620535.555011] machine2 systemd[1]: Finished Address configuration of eth1. container-test-run-container> machine2 # [2620535.596458] machine2 systemd[1]: nscd.service: Deactivated successfully. container-test-run-container> machine2 # [2620535.596640] machine2 systemd[1]: Stopped Name Service Cache Daemon (nsncd). container-test-run-container> machine2 # [2620535.598992] machine2 systemd[1]: Starting Name Service Cache Daemon (nsncd)... container-test-run-container> machine2 # [2620535.599272] machine2 systemd[1]: Finished Save Transient machine-id to Disk. container-test-run-container> machine2 # [2620535.606380] machine2 systemd[1]: Finished Extra networking commands.. container-test-run-container> machine2 # [2620535.606591] machine2 systemd[1]: Reached target Network. container-test-run-container> machine2 # [2620535.719526] machine2 systemd[1]: Started Name Service Cache Daemon (nsncd). container-test-run-container> machine2 # [2620535.720212] machine2 nsncd[241]: Oct 04 15:22:35.705 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" container-test-run-container> machine2 # [2620535.719650] machine2 systemd[1]: Reached target Host and Network Name Lookups. container-test-run-container> machine2 # [2620535.719755] machine2 systemd[1]: Reached target User and Group Name Lookups. container-test-run-container> machine2 # [2620535.721856] machine2 systemd[1]: Starting User Login Management... container-test-run-container> machine2 # [2620535.723252] machine2 systemd[1]: Starting Permit User Sessions... container-test-run-container> machine2 # [2620535.784068] machine2 systemd[1]: Finished Permit User Sessions. container-test-run-container> machine2 # [2620535.785772] machine2 systemd[1]: Started Console Getty. container-test-run-container> machine2 # [2620535.785848] machine2 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0 container-test-run-container> machine2 # [2620535.785888] machine2 systemd[1]: Reached target Login Prompts. container-test-run-container> machine2 # [2620536.370013] machine2 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. container-test-run-container> machine1 # [2620535.460253] machine1 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys container-test-run-container> machine1 # [2620535.473892] machine1 systemd[1]: Finished Flush Journal to Persistent Storage. container-test-run-container> machine1 # [2620535.474837] machine1 systemd[1]: Starting Create System Files and Directories... container-test-run-container> machine1 # [2620535.492540] machine1 systemd-tmpfiles[143]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted container-test-run-container> machine1 # [2620535.492739] machine1 systemd-tmpfiles[143]: fchmod() of /var/log/journal failed: Operation not permitted container-test-run-container> machine1 # [2620535.492877] machine1 systemd-tmpfiles[143]: fchmod() of /var/log/journal/f32eb7e7ed3b4f83beae730abfcb7c14 failed: Operation not permitted container-test-run-container> machine1 # [2620535.493078] machine1 systemd-tmpfiles[143]: fchmod() of /run/log/journal failed: Operation not permitted container-test-run-container> machine1 # [2620535.494614] machine1 systemd[1]: Finished Create System Files and Directories. container-test-run-container> machine1 # [2620535.495838] machine1 systemd[1]: Starting Rebuild Journal Catalog... container-test-run-container> machine1 # [2620535.496711] machine1 systemd[1]: Starting Record System Boot/Shutdown in UTMP... container-test-run-container> machine1 # [2620535.507165] machine1 systemd[1]: Finished Firewall. container-test-run-container> machine1 # [2620535.507419] machine1 systemd[1]: Reached target Preparation for Network. container-test-run-container> machine1 # [2620535.515194] machine1 systemd[1]: Finished Rebuild Journal Catalog. container-test-run-container> machine1 # [2620535.515598] machine1 systemd[1]: Finished Record System Boot/Shutdown in UTMP. container-test-run-container> machine1 # [2620535.518689] machine1 systemd[1]: Starting Update is Completed... container-test-run-container> machine1 # [2620535.529819] machine1 systemd[1]: Finished Update is Completed. container-test-run-container> machine1 # [2620535.530050] machine1 systemd[1]: Reached target System Initialization. container-test-run-container> machine1 # [2620535.530146] machine1 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container container-test-run-container> machine1 # [2620535.530186] machine1 systemd[1]: Started Daily Cleanup of Temporary Directories. container-test-run-container> machine1 # [2620535.530207] machine1 systemd[1]: Reached target Timer Units. container-test-run-container> machine1 # [2620535.530335] machine1 systemd[1]: Listening on D-Bus System Message Bus Socket. container-test-run-container> machine1 # [2620535.530479] machine1 systemd[1]: Listening on Nix Daemon Socket. container-test-run-container> machine1 # [2620535.531891] machine1 systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Export). container-test-run-container> machine1 # [2620535.533124] machine1 systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). container-test-run-container> machine1 # [2620535.533256] machine1 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. container-test-run-container> machine1 # [2620535.533283] machine1 systemd[1]: Reached target Socket Units. container-test-run-container> machine1 # [2620535.533333] machine1 systemd[1]: Reached target Basic System. container-test-run-container> machine1 # [2620535.534490] machine1 systemd[1]: Starting Import lastlog data into lastlog2 database... container-test-run-container> machine1 # [2620535.535242] machine1 systemd[1]: Starting Address configuration of eth1... container-test-run-container> machine1 # [2620535.536041] machine1 systemd[1]: Starting Extra networking commands.... container-test-run-container> machine1 # [2620535.536938] machine1 systemd[1]: Starting Name Service Cache Daemon (nsncd)... container-test-run-container> machine1 # [2620535.537754] machine1 systemd[1]: Starting SSH Host Keys Generation... container-test-run-container> machine1 # [2620535.554737] machine1 network-addresses-eth1-start[169]: adding address 192.168.1.1/24... done container-test-run-container> machine1 # [2620535.557838] machine1 systemd[1]: Finished Import lastlog data into lastlog2 database. container-test-run-container> machine1 # [2620535.557980] machine1 network-addresses-eth1-start[169]: adding address 2001:db8:1::1/64... done container-test-run-container> machine1 # [2620535.562805] machine1 systemd[1]: Finished Address configuration of eth1. container-test-run-container> machine1 # [2620535.599762] machine1 systemd[1]: Finished Save Transient machine-id to Disk. container-test-run-container> machine1 # [2620535.608668] machine1 systemd[1]: nscd.service: Deactivated successfully. container-test-run-container> machine1 # [2620535.608793] machine1 systemd[1]: Stopped Name Service Cache Daemon (nsncd). container-test-run-container> machine1 # [2620535.610784] machine1 systemd[1]: Starting Name Service Cache Daemon (nsncd)... container-test-run-container> machine1 # [2620535.616403] machine1 systemd[1]: Finished Extra networking commands.. container-test-run-container> machine1 # [2620535.616600] machine1 systemd[1]: Reached target Network. container-test-run-container> machine1 # [2620535.719589] machine1 systemd[1]: Started Name Service Cache Daemon (nsncd). container-test-run-container> machine1 # [2620535.719658] machine1 systemd[1]: Reached target Host and Network Name Lookups. container-test-run-container> machine1 # [2620535.719729] machine1 systemd[1]: Reached target User and Group Name Lookups. container-test-run-container> machine1 # [2620535.720462] machine1 nsncd[244]: Oct 04 15:22:35.706 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" container-test-run-container> machine1 # [2620535.721334] machine1 systemd[1]: Starting User Login Management... container-test-run-container> machine1 # [2620535.722173] machine1 systemd[1]: Starting Permit User Sessions... container-test-run-container> machine1 # [2620535.783996] machine1 systemd[1]: Finished Permit User Sessions. container-test-run-container> machine1 # [2620535.785041] machine1 systemd[1]: Started Console Getty. container-test-run-container> machine1 # [2620535.785086] machine1 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0 container-test-run-container> machine1 # [2620535.785106] machine1 systemd[1]: Reached target Login Prompts. container-test-run-container> machine1 # [2620536.361388] machine1 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. container-test-run-container> machine2 # [2620536.455483] machine2 systemd-logind[259]: New seat seat0. container-test-run-container> machine2 # [2620536.455760] machine2 systemd[1]: Started User Login Management. container-test-run-container> machine2 # [2620536.457406] machine2 systemd[1]: Starting D-Bus System Message Bus... container-test-run-container> machine2 # [2620536.458870] machine2 systemd[1]: Starting linger-users.service... container-test-run-container> machine2 # [2620536.503639] machine2 systemd[1]: linger-users.service: Deactivated successfully. container-test-run-container> machine2 # [2620536.503785] machine2 systemd[1]: Finished linger-users.service. container-test-run-container> machine1 # [2620536.458516] machine1 systemd-logind[258]: New seat seat0. container-test-run-container> machine1 # [2620536.458812] machine1 systemd[1]: Started User Login Management. container-test-run-container> machine1 # [2620536.460317] machine1 systemd[1]: Starting D-Bus System Message Bus... container-test-run-container> machine1 # [2620536.461533] machine1 systemd[1]: Starting linger-users.service... container-test-run-container> machine1 # [2620536.502652] machine1 systemd[1]: linger-users.service: Deactivated successfully. container-test-run-container> machine1 # [2620536.503001] machine1 systemd[1]: Finished linger-users.service. container-test-run-container> machine2 # [2620536.778860] machine2 dbus-broker-launch[269]: Looking up NSS user entry for 'systemd-timesync'... container-test-run-container> machine2 # [2620536.780324] machine2 dbus-broker-launch[269]: NSS returned no entry for 'systemd-timesync' container-test-run-container> machine2 # [2620536.780324] machine2 dbus-broker-launch[269]: Invalid user-name in /nix/store/1fnjsjv1lh8z41nxmk5cxx9wrq76lly5-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" container-test-run-container> machine2 # [2620536.780993] machine2 systemd[1]: Started D-Bus System Message Bus. container-test-run-container> machine2 # [2620536.788788] machine2 dbus-broker-launch[269]: Ready container-test-run-container> machine1 # [2620536.778622] machine1 dbus-broker-launch[269]: Looking up NSS user entry for 'systemd-timesync'... container-test-run-container> machine1 # [2620536.779809] machine1 dbus-broker-launch[269]: NSS returned no entry for 'systemd-timesync' container-test-run-container> machine1 # [2620536.779809] machine1 dbus-broker-launch[269]: Invalid user-name in /nix/store/1fnjsjv1lh8z41nxmk5cxx9wrq76lly5-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" container-test-run-container> machine1 # [2620536.780286] machine1 systemd[1]: Started D-Bus System Message Bus. container-test-run-container> machine1 # [2620536.789242] machine1 dbus-broker-launch[269]: Ready container-test-run-container> machine2 # [2620537.626735] machine2 sshd-keygen-start[197]: Generating public/private rsa key pair. container-test-run-container> machine2 # [2620537.626735] machine2 sshd-keygen-start[197]: Your identification has been saved in /etc/ssh/ssh_host_rsa_key container-test-run-container> machine2 # [2620537.626735] machine2 sshd-keygen-start[197]: Your public key has been saved in /etc/ssh/ssh_host_rsa_key.pub container-test-run-container> machine2 # [2620537.626735] machine2 sshd-keygen-start[197]: The key fingerprint is: container-test-run-container> machine2 # [2620537.626735] machine2 sshd-keygen-start[197]: SHA256:lvgJobHDEOWM5bvTU2MciWEY7cCGulpW5KwegoAlfn4 root@machine2 container-test-run-container> machine2 # [2620537.626735] machine2 sshd-keygen-start[197]: The key's randomart image is: container-test-run-container> machine2 # [2620537.626735] machine2 sshd-keygen-start[197]: +---[RSA 4096]----+ container-test-run-container> machine2 # [2620537.626735] machine2 sshd-keygen-start[197]: | .+++o | container-test-run-container> machine2 # [2620537.626735] machine2 sshd-keygen-start[197]: |. oB*..o . | container-test-run-container> machine2 # [2620537.626735] machine2 sshd-keygen-start[197]: |o+o==oo o | container-test-run-container> machine2 # [2620537.626735] machine2 sshd-keygen-start[197]: |+. ++=.+ o | container-test-run-container> machine2 # [2620537.626735] machine2 sshd-keygen-start[197]: |o.oo* o S | container-test-run-container> machine2 # [2620537.626735] machine2 sshd-keygen-start[197]: |+ =. E * o | container-test-run-container> machine2 # [2620537.626735] machine2 sshd-keygen-start[197]: |.= .+ o o | container-test-run-container> machine2 # [2620537.626735] machine2 sshd-keygen-start[197]: |. . . . | container-test-run-container> machine2 # [2620537.626735] machine2 sshd-keygen-start[197]: | | container-test-run-container> machine2 # [2620537.626735] machine2 sshd-keygen-start[197]: +----[SHA256]-----+ container-test-run-container> machine2 # [2620537.644707] machine2 sshd-keygen-start[279]: Generating public/private ed25519 key pair. container-test-run-container> machine2 # [2620537.644707] machine2 sshd-keygen-start[279]: Your identification has been saved in /etc/ssh/ssh_host_ed25519_key container-test-run-container> machine2 # [2620537.644707] machine2 sshd-keygen-start[279]: Your public key has been saved in /etc/ssh/ssh_host_ed25519_key.pub container-test-run-container> machine2 # [2620537.644707] machine2 sshd-keygen-start[279]: The key fingerprint is: container-test-run-container> machine2 # [2620537.644707] machine2 sshd-keygen-start[279]: SHA256:VkC8HOOcYRbSpapOeaDqGLyrTCykfilaoJOdwNJZEVE root@machine2 container-test-run-container> machine2 # [2620537.644707] machine2 sshd-keygen-start[279]: The key's randomart image is: container-test-run-container> machine2 # [2620537.644707] machine2 sshd-keygen-start[279]: +--[ED25519 256]--+ container-test-run-container> machine2 # [2620537.644707] machine2 sshd-keygen-start[279]: | ++E++o. | container-test-run-container> machine2 # [2620537.644707] machine2 sshd-keygen-start[279]: | . .Oo | container-test-run-container> machine2 # [2620537.644707] machine2 sshd-keygen-start[279]: | . *.*. | container-test-run-container> machine2 # [2620537.644707] machine2 sshd-keygen-start[279]: |.. o .*. | container-test-run-container> machine2 # [2620537.644707] machine2 sshd-keygen-start[279]: |+oo . . S | container-test-run-container> machine2 # [2620537.644707] machine2 sshd-keygen-start[279]: |B= o + . | container-test-run-container> machine2 # [2620537.644707] machine2 sshd-keygen-start[279]: |B+= = . | container-test-run-container> machine2 # [2620537.644707] machine2 sshd-keygen-start[279]: |**.= . | container-test-run-container> machine2 # [2620537.644707] machine2 sshd-keygen-start[279]: |O*+ . | container-test-run-container> machine2 # [2620537.644707] machine2 sshd-keygen-start[279]: +----[SHA256]-----+ container-test-run-container> machine2 # [2620537.646951] machine2 systemd[1]: sshd-keygen.service: Deactivated successfully. container-test-run-container> machine2 # [2620537.647285] machine2 systemd[1]: Finished SSH Host Keys Generation. container-test-run-container> machine2 # [2620537.647666] machine2 systemd[1]: sshd-keygen.service: Consumed 4.214s CPU time over 2.115s wall clock time. container-test-run-container> machine2 # [2620537.649730] machine2 systemd[1]: Starting SSH Daemon... container-test-run-container> machine2 # [2620537.715701] machine2 sshd[281]: Server listening on 0.0.0.0 port 22. container-test-run-container> machine2 # [2620537.715739] machine2 sshd[281]: Server listening on :: port 22. container-test-run-container> machine2 # [2620537.715907] machine2 systemd[1]: Started SSH Daemon. container-test-run-container> machine2 # [2620537.716301] machine2 systemd[1]: Reached target Multi-User System. container-test-run-container> machine2 # [2620537.716630] machine2 systemd[1]: Startup finished in 2.862s. container-test-run-container> machine1 # [2620538.050175] machine1 sshd-keygen-start[193]: Generating public/private rsa key pair. container-test-run-container> machine1 # [2620538.050175] machine1 sshd-keygen-start[193]: Your identification has been saved in /etc/ssh/ssh_host_rsa_key container-test-run-container> machine1 # [2620538.050175] machine1 sshd-keygen-start[193]: Your public key has been saved in /etc/ssh/ssh_host_rsa_key.pub container-test-run-container> machine1 # [2620538.050175] machine1 sshd-keygen-start[193]: The key fingerprint is: container-test-run-container> machine1 # [2620538.050175] machine1 sshd-keygen-start[193]: SHA256:jJ6a6GwEz02MhlH0HbvanHwe2vFXwpQ4WJjRzrCwqcI root@machine1 container-test-run-container> machine1 # [2620538.050175] machine1 sshd-keygen-start[193]: The key's randomart image is: container-test-run-container> machine1 # [2620538.050175] machine1 sshd-keygen-start[193]: +---[RSA 4096]----+ container-test-run-container> machine1 # [2620538.050175] machine1 sshd-keygen-start[193]: | oo . .= | container-test-run-container> machine1 # [2620538.050175] machine1 sshd-keygen-start[193]: |. . ..o+ o | container-test-run-container> machine1 # [2620538.050175] machine1 sshd-keygen-start[193]: | o o. o+ B . . | container-test-run-container> machine1 # [2620538.050175] machine1 sshd-keygen-start[193]: |o o o o+o = o | container-test-run-container> machine1 # [2620538.050175] machine1 sshd-keygen-start[193]: | * o .o S + | container-test-run-container> machine1 # [2620538.050175] machine1 sshd-keygen-start[193]: | E o* o o . | container-test-run-container> machine1 # [2620538.050175] machine1 sshd-keygen-start[193]: | . .. B + o | container-test-run-container> machine1 # [2620538.050175] machine1 sshd-keygen-start[193]: | ... o = + . | container-test-run-container> machine1 # [2620538.050175] machine1 sshd-keygen-start[193]: | o+ o . o .. | container-test-run-container> machine1 # [2620538.050175] machine1 sshd-keygen-start[193]: +----[SHA256]-----+ container-test-run-container> machine1 # [2620538.068980] machine1 sshd-keygen-start[279]: Generating public/private ed25519 key pair. container-test-run-container> machine1 # [2620538.068980] machine1 sshd-keygen-start[279]: Your identification has been saved in /etc/ssh/ssh_host_ed25519_key container-test-run-container> machine1 # [2620538.068980] machine1 sshd-keygen-start[279]: Your public key has been saved in /etc/ssh/ssh_host_ed25519_key.pub container-test-run-container> machine1 # [2620538.068980] machine1 sshd-keygen-start[279]: The key fingerprint is: container-test-run-container> machine1 # [2620538.068980] machine1 sshd-keygen-start[279]: SHA256:t2WUcIBYjUtf/TaNHsLFq72/2xB1Jcc1+oVtQODl1z0 root@machine1 container-test-run-container> machine1 # [2620538.068980] machine1 sshd-keygen-start[279]: The key's randomart image is: container-test-run-container> machine1 # [2620538.068980] machine1 sshd-keygen-start[279]: +--[ED25519 256]--+ container-test-run-container> machine1 # [2620538.068980] machine1 sshd-keygen-start[279]: | o.+o.o==o=| container-test-run-container> machine1 # [2620538.068980] machine1 sshd-keygen-start[279]: | . + .+.+=**| container-test-run-container> machine1 # [2620538.068980] machine1 sshd-keygen-start[279]: | . o o+ooE@| container-test-run-container> machine1 # [2620538.068980] machine1 sshd-keygen-start[279]: | . ..o ==B| container-test-run-container> machine1 # [2620538.068980] machine1 sshd-keygen-start[279]: | S . o=.+.| container-test-run-container> machine1 # [2620538.068980] machine1 sshd-keygen-start[279]: | . +. o. | container-test-run-container> machine1 # [2620538.068980] machine1 sshd-keygen-start[279]: | . .. | container-test-run-container> machine1 # [2620538.068980] machine1 sshd-keygen-start[279]: | ...| container-test-run-container> machine1 # [2620538.068980] machine1 sshd-keygen-start[279]: | +=| container-test-run-container> machine1 # [2620538.068980] machine1 sshd-keygen-start[279]: +----[SHA256]-----+ container-test-run-container> machine1 # [2620538.071087] machine1 systemd[1]: sshd-keygen.service: Deactivated successfully. container-test-run-container> machine1 # [2620538.071252] machine1 systemd[1]: Finished SSH Host Keys Generation. container-test-run-container> machine1 # [2620538.073395] machine1 systemd[1]: Starting SSH Daemon... container-test-run-container> machine1 # [2620538.139638] machine1 sshd[281]: Server listening on 0.0.0.0 port 22. container-test-run-container> machine1 # [2620538.139661] machine1 sshd[281]: Server listening on :: port 22. container-test-run-container> machine1 # [2620538.139817] machine1 systemd[1]: Started SSH Daemon. container-test-run-container> machine1 # [2620538.140048] machine1 systemd[1]: Reached target Multi-User System. container-test-run-container> machine1 # [2620538.140265] machine1 systemd[1]: Startup finished in 3.282s. container-test-run-container> machine1: (finished: must succeed: systemctl status sshd, in 4.17 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.01 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 4.25 seconds) container-test-run-container> test script finished in 4.35s 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.33 seconds)