don't know how to build these paths: /nix/store/4xngibh010584bc4cvdbzf693p8z6p4w-container-test-run-admin.drv warning: ignoring the client-specified setting 'store', because it is a restricted setting and you are not a trusted user building '/nix/store/gsbywyvs258hrirhcr46bdzaf8n1sy03-system-units.drv' on nix-store.geninf.io:50051 system-units> build-x86-01: building gsbywyvs258hrirhcr46bdzaf8n1sy03-system-units.drv building '/nix/store/80l2sfyya9mr3qks9c0s3ygmiir21g9d-system-path.drv' on nix-store.geninf.io:50051 system-path> build-x86-01: building 80l2sfyya9mr3qks9c0s3ygmiir21g9d-system-path.drv system-path> structuredAttrs is enabled system-path> created 1719 symlinks in user environment building '/nix/store/p4pnnjmjqvgsnjih4l5s88ycc4k28h6f-nixos-test-driver-1.1.drv' on nix-store.geninf.io:50051 nixos-test-driver-1.1> gi-build-x86-02: building p4pnnjmjqvgsnjih4l5s88ycc4k28h6f-nixos-test-driver-1.1.drv nixos-test-driver-1.1> Sourcing python-remove-tests-dir-hook nixos-test-driver-1.1> Sourcing python-catch-conflicts-hook.sh nixos-test-driver-1.1> Sourcing python-remove-bin-bytecode-hook.sh nixos-test-driver-1.1> Sourcing pypa-build-hook nixos-test-driver-1.1> Using pypaBuildPhase nixos-test-driver-1.1> Sourcing python-runtime-deps-check-hook nixos-test-driver-1.1> Using pythonRuntimeDepsCheckHook nixos-test-driver-1.1> Sourcing pypa-install-hook nixos-test-driver-1.1> Using pypaInstallPhase nixos-test-driver-1.1> Sourcing python-imports-check-hook.sh nixos-test-driver-1.1> Using pythonImportsCheckPhase nixos-test-driver-1.1> Sourcing python-namespaces-hook nixos-test-driver-1.1> Sourcing python-catch-conflicts-hook.sh nixos-test-driver-1.1> Running phase: unpackPhase nixos-test-driver-1.1> unpacking source archive /nix/store/77bky4iifk87n5xbwf870ac3kk2li2qz-src nixos-test-driver-1.1> source root is src nixos-test-driver-1.1> setting SOURCE_DATE_EPOCH to timestamp 315619200 of file "src/test_driver/vlan.py" nixos-test-driver-1.1> Running phase: patchPhase nixos-test-driver-1.1> Running phase: updateAutotoolsGnuConfigScriptsPhase nixos-test-driver-1.1> Running phase: configurePhase nixos-test-driver-1.1> no configure script, doing nothing nixos-test-driver-1.1> Running phase: buildPhase nixos-test-driver-1.1> Executing pypaBuildPhase nixos-test-driver-1.1> Creating a wheel... nixos-test-driver-1.1> pypa build flags: --no-isolation --outdir dist/ --wheel nixos-test-driver-1.1> * Getting build dependencies for wheel... nixos-test-driver-1.1> running egg_info nixos-test-driver-1.1> creating nixos_test_driver.egg-info nixos-test-driver-1.1> writing nixos_test_driver.egg-info/PKG-INFO nixos-test-driver-1.1> writing dependency_links to nixos_test_driver.egg-info/dependency_links.txt nixos-test-driver-1.1> writing entry points to nixos_test_driver.egg-info/entry_points.txt nixos-test-driver-1.1> writing top-level names to nixos_test_driver.egg-info/top_level.txt nixos-test-driver-1.1> writing manifest file 'nixos_test_driver.egg-info/SOURCES.txt' nixos-test-driver-1.1> reading manifest file 'nixos_test_driver.egg-info/SOURCES.txt' nixos-test-driver-1.1> writing manifest file 'nixos_test_driver.egg-info/SOURCES.txt' nixos-test-driver-1.1> * Building wheel... nixos-test-driver-1.1> running bdist_wheel nixos-test-driver-1.1> running build nixos-test-driver-1.1> running build_py nixos-test-driver-1.1> creating build/lib/test_driver nixos-test-driver-1.1> copying test_driver/duration.py -> build/lib/test_driver nixos-test-driver-1.1> copying test_driver/efi.py -> build/lib/test_driver nixos-test-driver-1.1> copying test_driver/polling_condition.py -> build/lib/test_driver nixos-test-driver-1.1> copying test_driver/logger.py -> build/lib/test_driver nixos-test-driver-1.1> copying test_driver/errors.py -> build/lib/test_driver nixos-test-driver-1.1> copying test_driver/debug.py -> build/lib/test_driver nixos-test-driver-1.1> copying test_driver/vlan.py -> build/lib/test_driver nixos-test-driver-1.1> copying test_driver/__init__.py -> build/lib/test_driver nixos-test-driver-1.1> copying test_driver/driver.py -> build/lib/test_driver nixos-test-driver-1.1> creating build/lib/test_driver/machine nixos-test-driver-1.1> copying test_driver/machine/ocr.py -> build/lib/test_driver/machine nixos-test-driver-1.1> copying test_driver/machine/qmp.py -> build/lib/test_driver/machine nixos-test-driver-1.1> copying test_driver/machine/__init__.py -> build/lib/test_driver/machine nixos-test-driver-1.1> running egg_info nixos-test-driver-1.1> writing nixos_test_driver.egg-info/PKG-INFO nixos-test-driver-1.1> writing dependency_links to nixos_test_driver.egg-info/dependency_links.txt nixos-test-driver-1.1> writing entry points to nixos_test_driver.egg-info/entry_points.txt nixos-test-driver-1.1> writing top-level names to nixos_test_driver.egg-info/top_level.txt nixos-test-driver-1.1> reading manifest file 'nixos_test_driver.egg-info/SOURCES.txt' nixos-test-driver-1.1> writing manifest file 'nixos_test_driver.egg-info/SOURCES.txt' nixos-test-driver-1.1> copying test_driver/py.typed -> build/lib/test_driver nixos-test-driver-1.1> installing to build/bdist.linux-x86_64/wheel nixos-test-driver-1.1> running install nixos-test-driver-1.1> running install_lib nixos-test-driver-1.1> creating build/bdist.linux-x86_64/wheel nixos-test-driver-1.1> creating build/bdist.linux-x86_64/wheel/test_driver nixos-test-driver-1.1> copying build/lib/test_driver/debug.py -> build/bdist.linux-x86_64/wheel/./test_driver nixos-test-driver-1.1> copying build/lib/test_driver/vlan.py -> build/bdist.linux-x86_64/wheel/./test_driver nixos-test-driver-1.1> copying build/lib/test_driver/logger.py -> build/bdist.linux-x86_64/wheel/./test_driver nixos-test-driver-1.1> copying build/lib/test_driver/errors.py -> build/bdist.linux-x86_64/wheel/./test_driver nixos-test-driver-1.1> copying build/lib/test_driver/duration.py -> build/bdist.linux-x86_64/wheel/./test_driver nixos-test-driver-1.1> copying build/lib/test_driver/py.typed -> build/bdist.linux-x86_64/wheel/./test_driver nixos-test-driver-1.1> copying build/lib/test_driver/__init__.py -> build/bdist.linux-x86_64/wheel/./test_driver nixos-test-driver-1.1> creating build/bdist.linux-x86_64/wheel/test_driver/machine nixos-test-driver-1.1> copying build/lib/test_driver/machine/qmp.py -> build/bdist.linux-x86_64/wheel/./test_driver/machine nixos-test-driver-1.1> copying build/lib/test_driver/machine/ocr.py -> build/bdist.linux-x86_64/wheel/./test_driver/machine nixos-test-driver-1.1> copying build/lib/test_driver/machine/__init__.py -> build/bdist.linux-x86_64/wheel/./test_driver/machine nixos-test-driver-1.1> copying build/lib/test_driver/efi.py -> build/bdist.linux-x86_64/wheel/./test_driver nixos-test-driver-1.1> copying build/lib/test_driver/driver.py -> build/bdist.linux-x86_64/wheel/./test_driver nixos-test-driver-1.1> copying build/lib/test_driver/polling_condition.py -> build/bdist.linux-x86_64/wheel/./test_driver nixos-test-driver-1.1> running install_egg_info nixos-test-driver-1.1> Copying nixos_test_driver.egg-info to build/bdist.linux-x86_64/wheel/./nixos_test_driver-0.0.0-py3.14.egg-info building '/nix/store/fxd7b36lvshg8v4jskx2zrf51f59mish-etc.drv' on nix-store.geninf.io:50051 etc> build-x86-01: building fxd7b36lvshg8v4jskx2zrf51f59mish-etc.drv nixos-test-driver-1.1> running install_scripts nixos-test-driver-1.1> creating build/bdist.linux-x86_64/wheel/nixos_test_driver-0.0.0.dist-info/WHEEL nixos-test-driver-1.1> creating '/build/src/dist/.tmp-oojic9un/nixos_test_driver-0.0.0-py3-none-any.whl' and adding 'build/bdist.linux-x86_64/wheel' to it nixos-test-driver-1.1> adding 'test_driver/__init__.py' nixos-test-driver-1.1> adding 'test_driver/debug.py' nixos-test-driver-1.1> adding 'test_driver/driver.py' nixos-test-driver-1.1> adding 'test_driver/duration.py' nixos-test-driver-1.1> adding 'test_driver/efi.py' nixos-test-driver-1.1> adding 'test_driver/errors.py' nixos-test-driver-1.1> adding 'test_driver/logger.py' nixos-test-driver-1.1> adding 'test_driver/polling_condition.py' nixos-test-driver-1.1> adding 'test_driver/py.typed' nixos-test-driver-1.1> adding 'test_driver/vlan.py' nixos-test-driver-1.1> adding 'test_driver/machine/__init__.py' nixos-test-driver-1.1> adding 'test_driver/machine/ocr.py' nixos-test-driver-1.1> adding 'test_driver/machine/qmp.py' nixos-test-driver-1.1> adding 'nixos_test_driver-0.0.0.dist-info/METADATA' nixos-test-driver-1.1> adding 'nixos_test_driver-0.0.0.dist-info/WHEEL' nixos-test-driver-1.1> adding 'nixos_test_driver-0.0.0.dist-info/entry_points.txt' nixos-test-driver-1.1> adding 'nixos_test_driver-0.0.0.dist-info/top_level.txt' nixos-test-driver-1.1> adding 'nixos_test_driver-0.0.0.dist-info/RECORD' nixos-test-driver-1.1> removing build/bdist.linux-x86_64/wheel nixos-test-driver-1.1> Successfully built nixos_test_driver-0.0.0-py3-none-any.whl nixos-test-driver-1.1> Finished creating a wheel... nixos-test-driver-1.1> Finished executing pypaBuildPhase nixos-test-driver-1.1> Running phase: pythonRuntimeDepsCheckHook nixos-test-driver-1.1> Executing pythonRuntimeDepsCheck nixos-test-driver-1.1> Checking runtime dependencies for nixos_test_driver-0.0.0-py3-none-any.whl building '/nix/store/q2ygd6rs4p84bpn01rii0fwdl6ha7abg-dbus-1.drv' on nix-store.geninf.io:50051 dbus-1> gi-build-x86-02: building q2ygd6rs4p84bpn01rii0fwdl6ha7abg-dbus-1.drv nixos-test-driver-1.1> Finished executing pythonRuntimeDepsCheck nixos-test-driver-1.1> Running phase: installPhase nixos-test-driver-1.1> Executing pypaInstallPhase nixos-test-driver-1.1> Successfully installed nixos_test_driver-0.0.0-py3-none-any.whl nixos-test-driver-1.1> Finished executing pypaInstallPhase nixos-test-driver-1.1> Running phase: pythonOutputDistPhase nixos-test-driver-1.1> Executing pythonOutputDistPhase nixos-test-driver-1.1> Finished executing pythonOutputDistPhase nixos-test-driver-1.1> Running phase: fixupPhase nixos-test-driver-1.1> shrinking RPATHs of ELF executables and libraries in /nix/store/2dz8bfsj1j9rgxlyrgm856siav96qnip-nixos-test-driver-1.1 nixos-test-driver-1.1> checking for references to /build/ in /nix/store/2dz8bfsj1j9rgxlyrgm856siav96qnip-nixos-test-driver-1.1... nixos-test-driver-1.1> patching script interpreter paths in /nix/store/2dz8bfsj1j9rgxlyrgm856siav96qnip-nixos-test-driver-1.1 nixos-test-driver-1.1> stripping (with command strip and flags -S -p) in /nix/store/2dz8bfsj1j9rgxlyrgm856siav96qnip-nixos-test-driver-1.1/lib /nix/store/2dz8bfsj1j9rgxlyrgm856siav96qnip-nixos-test-driver-1.1/bin nixos-test-driver-1.1> shrinking RPATHs of ELF executables and libraries in /nix/store/vfivykbdy09h99qvw2fbimnadxq6ghv5-nixos-test-driver-1.1-dist nixos-test-driver-1.1> checking for references to /build/ in /nix/store/vfivykbdy09h99qvw2fbimnadxq6ghv5-nixos-test-driver-1.1-dist... nixos-test-driver-1.1> patching script interpreter paths in /nix/store/vfivykbdy09h99qvw2fbimnadxq6ghv5-nixos-test-driver-1.1-dist nixos-test-driver-1.1> Rewriting #!/nix/store/lb41b0anx1f98y9y5s9mdv97gjgsq740-python3-3.14.7/bin/python3.14 to #!/nix/store/lb41b0anx1f98y9y5s9mdv97gjgsq740-python3-3.14.7 nixos-test-driver-1.1> wrapping `/nix/store/2dz8bfsj1j9rgxlyrgm856siav96qnip-nixos-test-driver-1.1/bin/nixos-test-driver'... nixos-test-driver-1.1> Executing pythonRemoveTestsDir nixos-test-driver-1.1> Finished executing pythonRemoveTestsDir nixos-test-driver-1.1> Running phase: installCheckPhase nixos-test-driver-1.1> ## run ty nixos-test-driver-1.1> All checks passed! nixos-test-driver-1.1> ## run ruff check nixos-test-driver-1.1> All checks passed! nixos-test-driver-1.1> ## run ruff format nixos-test-driver-1.1> 25 files already formatted nixos-test-driver-1.1> Running phase: pythonCatchConflictsPhase nixos-test-driver-1.1> Running phase: pythonRemoveBinBytecodePhase nixos-test-driver-1.1> Running phase: pythonImportsCheckPhase nixos-test-driver-1.1> Executing pythonImportsCheckPhase building '/nix/store/m729np1rgw8h997s76q8795gysd67g5r-X-Restart-Triggers-dbus-broker.drv' on nix-store.geninf.io:50051 X-Restart-Triggers-dbus-broker> gi-build-x86-02: building m729np1rgw8h997s76q8795gysd67g5r-X-Restart-Triggers-dbus-broker.drv X-Restart-Triggers-dbus-broker> structuredAttrs is enabled building '/nix/store/pxp81pichrrq13jw7lzn5rmb1mmdy0gc-activate.drv' on nix-store.geninf.io:50051 activate> build-x86-01: building pxp81pichrrq13jw7lzn5rmb1mmdy0gc-activate.drv activate> structuredAttrs is enabled building '/nix/store/5drlanyx9d6580hwnqcd64kq66y0rxzb-nixos-system-client-test.drv' on nix-store.geninf.io:50051 nixos-system-client-test> build-x86-01: building 5drlanyx9d6580hwnqcd64kq66y0rxzb-nixos-system-client-test.drv nixos-system-client-test> structuredAttrs is enabled building '/nix/store/ax8vcm0mjpwb8abl2g6zafxvymlhv6p2-unit-dbus-broker.service.drv' on nix-store.geninf.io:50051 unit-dbus-broker.service> gi-build-x86-02: building ax8vcm0mjpwb8abl2g6zafxvymlhv6p2-unit-dbus-broker.service.drv building '/nix/store/50qslkn1hfy4dc593595qq6hbi2vhq3v-unit-dbus-broker.service.drv' on nix-store.geninf.io:50051 unit-dbus-broker.service> gi-build-x86-02: building 50qslkn1hfy4dc593595qq6hbi2vhq3v-unit-dbus-broker.service.drv unit-dbus-broker.service> structuredAttrs is enabled unit-dbus-broker.service> structuredAttrs is enabled building '/nix/store/4vw2xkxhqwx11lkyhirwkrybvr9kdirr-run-client-nspawn.drv' on nix-store.geninf.io:50051 run-client-nspawn> build-x86-01: building 4vw2xkxhqwx11lkyhirwkrybvr9kdirr-run-client-nspawn.drv building '/nix/store/rw7vzfnfwdkpadg3vvdn33xj90yjvhm9-user-units.drv' on nix-store.geninf.io:50051 user-units> gi-build-x86-02: building rw7vzfnfwdkpadg3vvdn33xj90yjvhm9-user-units.drv building '/nix/store/784pwcywhgwjjgq6gf91mna6fcy0d5bg-system-units.drv' on nix-store.geninf.io:50051 system-units> gi-build-x86-02: building 784pwcywhgwjjgq6gf91mna6fcy0d5bg-system-units.drv building '/nix/store/l2dkr4yrrbmdds4wfjrrc8yrbx8bznxy-etc.drv' on nix-store.geninf.io:50051 etc> gi-build-x86-02: building l2dkr4yrrbmdds4wfjrrc8yrbx8bznxy-etc.drv building '/nix/store/iw8kdn6647ajlkzs6xb4xi3hr3c4qv6c-activate.drv' on nix-store.geninf.io:50051 activate> gi-build-x86-02: building iw8kdn6647ajlkzs6xb4xi3hr3c4qv6c-activate.drv activate> structuredAttrs is enabled building '/nix/store/6db4zrgnxzxj2bxgpxwb5ll39dm8709z-nixos-system-server-test.drv' on nix-store.geninf.io:50051 nixos-system-server-test> build-x86-01: building 6db4zrgnxzxj2bxgpxwb5ll39dm8709z-nixos-system-server-test.drv nixos-system-server-test> structuredAttrs is enabled building '/nix/store/8r2y91f8ipf1k1yr4s93qiqx43j9y3j2-run-server-nspawn.drv' on nix-store.geninf.io:50051 run-server-nspawn> build-x86-01: building 8r2y91f8ipf1k1yr4s93qiqx43j9y3j2-run-server-nspawn.drv building '/nix/store/5qzfqlzg64si5kird1b5yvk3b8mj9w08-driverConfiguration.json.drv' on nix-store.geninf.io:50051 driverConfiguration.json> build-x86-01: building 5qzfqlzg64si5kird1b5yvk3b8mj9w08-driverConfiguration.json.drv driverConfiguration.json> structuredAttrs is enabled building '/nix/store/grgdraw65p162nvx1b27zsszlfdjwrwc-nixos-test-driver-admin.drv' on nix-store.geninf.io:50051 nixos-test-driver-admin> build-x86-01: building grgdraw65p162nvx1b27zsszlfdjwrwc-nixos-test-driver-admin.drv nixos-test-driver-admin> Running type check (enable/disable: config.skipTypeCheck) nixos-test-driver-admin> See https://nixos.org/manual/nixos/stable/#test-opt-skipTypeCheck nixos-test-driver-admin> All checks passed! nixos-test-driver-admin> Linting test script (enable/disable: config.skipLint) nixos-test-driver-admin> See https://nixos.org/manual/nixos/stable/#test-opt-skipLint nixos-test-driver-admin> All checks passed! building '/nix/store/4xngibh010584bc4cvdbzf693p8z6p4w-container-test-run-admin.drv' on nix-store.geninf.io:50051 container-test-run-admin> build-x86-01: building 4xngibh010584bc4cvdbzf693p8z6p4w-container-test-run-admin.drv container-test-run-admin> Machine state will be reset. To keep it, pass --keep-machine-state container-test-run-admin> start all VLans container-test-run-admin> (finished: start all VLans, in 0.00 seconds) container-test-run-admin> container-test-run-admin> Test will time out and terminate in 3600.0 seconds container-test-run-admin> run the VM test script container-test-run-admin> additionally exposed symbols: container-test-run-admin> client, server, container-test-run-admin> vlan1, container-test-run-admin> 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-admin> start all VMs container-test-run-admin> client: systemd-nspawn running (pid 52) container-test-run-admin> server: systemd-nspawn running (pid 53) container-test-run-admin> client: Waiting for journal at /build/vm-state-client/var/log/journal... container-test-run-admin> server: Waiting for journal at /build/vm-state-server/var/log/journal... container-test-run-admin> (finished: start all VMs, in 0.00 seconds) container-test-run-admin> nixos-nspawn(client): TAP vde-tap1 not found; container will be isolated from VDE container-test-run-admin> nixos-nspawn(client): 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-admin> nixos-nspawn(server): TAP vde-tap1 not found; container will be isolated from VDE container-test-run-admin> nixos-nspawn(server): 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-admin> 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-admin> ░ Spawning container client on /build/vm-state-client. container-test-run-admin> 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-admin> ░ Spawning container server on /build/vm-state-server. container-test-run-admin> client # [2688513.697931] client systemd-journald[72]: Journal started container-test-run-admin> client # [2688513.697962] client systemd-journald[72]: Runtime Journal (/run/log/journal/adb4e662cad94e2b874ddc4e431a365f) is 8M, max 3.7G, 3.7G free. container-test-run-admin> client # [2688513.701988] client systemd[1]: Finished Apply Kernel Variables. container-test-run-admin> client # [2688513.708144] client systemd[1]: Finished Create Static Device Nodes in /dev gracefully. container-test-run-admin> client # [2688513.715127] client systemd[1]: Starting Flush Journal to Persistent Storage... container-test-run-admin> client # [2688513.715470] client systemd[1]: Starting Network Name Resolution... container-test-run-admin> client # [2688513.715759] client systemd[1]: Starting Create Static Device Nodes in /dev... container-test-run-admin> client # [2688513.719853] client systemd-journald[72]: Time spent on flushing to /var/log/journal/adb4e662cad94e2b874ddc4e431a365f is 983us for 7 entries. container-test-run-admin> client # [2688513.719853] client systemd-journald[72]: System Journal (/var/log/journal/adb4e662cad94e2b874ddc4e431a365f) is 8M, max 4G, 3.9G free. container-test-run-admin> client # [2688513.729153] client systemd[1]: Finished Flush Journal to Persistent Storage. container-test-run-admin> client # [2688513.729366] client systemd[1]: Finished Create Static Device Nodes in /dev. container-test-run-admin> client # [2688513.729973] client systemd[1]: Reached target Preparation for Local File Systems. container-test-run-admin> client # [2688513.730019] client systemd[1]: Reached target Local File Systems. container-test-run-admin> client # [2688513.730464] client systemd[1]: Listening on Boot Loader Control Service Socket. container-test-run-admin> client # [2688513.730491] client systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container container-test-run-admin> client # [2688513.730803] client systemd[1]: Starting Save Transient machine-id to Disk... container-test-run-admin> client # [2688513.731122] client systemd[1]: Starting Create System Files and Directories... container-test-run-admin> client # [2688513.731140] client systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys container-test-run-admin> client # [2688513.740465] client systemd-tmpfiles[127]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted container-test-run-admin> client # [2688513.740656] client systemd-tmpfiles[127]: fchmod() of /var/log/journal failed: Operation not permitted container-test-run-admin> client # [2688513.740781] client systemd-tmpfiles[127]: fchmod() of /var/log/journal/adb4e662cad94e2b874ddc4e431a365f failed: Operation not permitted container-test-run-admin> client # [2688513.740941] client systemd-tmpfiles[127]: fchmod() of /run/log/journal failed: Operation not permitted container-test-run-admin> client # [2688513.741872] client systemd[1]: Finished Create System Files and Directories. container-test-run-admin> client # [2688513.742393] client systemd[1]: Starting Rebuild Journal Catalog... container-test-run-admin> client # [2688513.742725] client systemd[1]: Starting Record System Boot/Shutdown in UTMP... container-test-run-admin> client # [2688513.748876] client systemd[1]: Finished Record System Boot/Shutdown in UTMP. container-test-run-admin> client # [2688513.754071] client systemd[1]: Finished Rebuild Journal Catalog. container-test-run-admin> client # [2688513.754565] client systemd[1]: Starting Update is Completed... container-test-run-admin> client # [2688513.759578] client systemd[1]: Finished Update is Completed. container-test-run-admin> client # [2688513.785721] client systemd[1]: Finished Firewall. container-test-run-admin> client # [2688513.785800] client systemd[1]: Reached target Preparation for Network. container-test-run-admin> client # [2688513.785960] client systemd[1]: Listening on Network Management Resolve Hook Socket. container-test-run-admin> client # [2688513.786666] client systemd[1]: Starting Network Management... container-test-run-admin> client # [2688513.828450] client systemd[1]: Finished Save Transient machine-id to Disk. container-test-run-admin> client # [2688514.221954] client systemd-networkd[185]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted container-test-run-admin> client # [2688514.222033] client systemd-networkd[185]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted container-test-run-admin> server # [2688513.703310] server systemd-journald[101]: Journal started container-test-run-admin> server # [2688513.703338] server systemd-journald[101]: Runtime Journal (/run/log/journal/6d6c227c1a8540bd9cbde47de3e84277) is 8M, max 3.7G, 3.7G free. container-test-run-admin> server # [2688513.706989] server systemd[1]: Finished Apply Kernel Variables. container-test-run-admin> server # [2688513.713261] server systemd[1]: Finished Create Static Device Nodes in /dev gracefully. container-test-run-admin> server # [2688513.720299] server systemd[1]: Starting Flush Journal to Persistent Storage... container-test-run-admin> server # [2688513.720642] server systemd[1]: Starting Network Name Resolution... container-test-run-admin> server # [2688513.720920] server systemd[1]: Starting Create Static Device Nodes in /dev... container-test-run-admin> server # [2688513.724742] server systemd-journald[101]: Time spent on flushing to /var/log/journal/6d6c227c1a8540bd9cbde47de3e84277 is 1.320ms for 7 entries. container-test-run-admin> server # [2688513.724742] server systemd-journald[101]: System Journal (/var/log/journal/6d6c227c1a8540bd9cbde47de3e84277) is 8M, max 4G, 3.9G free. container-test-run-admin> server # [2688513.733113] server systemd[1]: Finished Flush Journal to Persistent Storage. container-test-run-admin> server # [2688513.733264] server systemd[1]: Finished Create Static Device Nodes in /dev. container-test-run-admin> server # [2688513.734102] server systemd[1]: Reached target Preparation for Local File Systems. container-test-run-admin> server # [2688513.734146] server systemd[1]: Reached target Local File Systems. container-test-run-admin> server # [2688513.734555] server systemd[1]: Listening on Boot Loader Control Service Socket. container-test-run-admin> server # [2688513.734580] server systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container container-test-run-admin> server # [2688513.734913] server systemd[1]: Starting Save Transient machine-id to Disk... container-test-run-admin> server # [2688513.735211] server systemd[1]: Starting Create System Files and Directories... container-test-run-admin> server # [2688513.735225] server systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys container-test-run-admin> server # [2688513.744213] server systemd-tmpfiles[155]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted container-test-run-admin> server # [2688513.744354] server systemd-tmpfiles[155]: fchmod() of /var/log/journal failed: Operation not permitted container-test-run-admin> server # [2688513.744452] server systemd-tmpfiles[155]: fchmod() of /var/log/journal/6d6c227c1a8540bd9cbde47de3e84277 failed: Operation not permitted container-test-run-admin> server # [2688513.744596] server systemd-tmpfiles[155]: fchmod() of /run/log/journal failed: Operation not permitted container-test-run-admin> server # [2688513.745628] server systemd[1]: Finished Create System Files and Directories. container-test-run-admin> server # [2688513.746171] server systemd[1]: Starting Rebuild Journal Catalog... container-test-run-admin> server # [2688513.746459] server systemd[1]: Starting Record System Boot/Shutdown in UTMP... container-test-run-admin> server # [2688513.752571] server systemd[1]: Finished Record System Boot/Shutdown in UTMP. container-test-run-admin> server # [2688513.761968] server systemd[1]: Finished Rebuild Journal Catalog. container-test-run-admin> server # [2688513.762446] server systemd[1]: Starting Update is Completed... container-test-run-admin> server # [2688513.767097] server systemd[1]: Finished Update is Completed. container-test-run-admin> server # [2688513.787887] server systemd[1]: Finished Firewall. container-test-run-admin> server # [2688513.787979] server systemd[1]: Reached target Preparation for Network. container-test-run-admin> server # [2688513.788122] server systemd[1]: Listening on Network Management Resolve Hook Socket. container-test-run-admin> server # [2688513.788629] server systemd[1]: Starting Network Management... container-test-run-admin> server # [2688513.828707] server systemd[1]: Finished Save Transient machine-id to Disk. container-test-run-admin> client # [2688514.229311] client systemd-networkd[185]: /etc/systemd/network/99-ethernet-default-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section. container-test-run-admin> client # [2688514.229451] client systemd-networkd[185]: /etc/systemd/network/99-wireless-client-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section. container-test-run-admin> client # [2688514.229528] client systemd-networkd[185]: lo: Link UP container-test-run-admin> client # [2688514.229532] client systemd-networkd[185]: lo: Gained carrier container-test-run-admin> client # [2688514.229653] client systemd-networkd[185]: eth1: Configuring with /etc/systemd/network/40-eth1.network. container-test-run-admin> client # [2688514.229895] client systemd[1]: Started Network Management. container-test-run-admin> client # [2688514.229958] client systemd-networkd[185]: eth1: Link UP container-test-run-admin> client # [2688514.230108] client systemd-networkd[185]: eth1: Gained carrier container-test-run-admin> client # [2688514.230480] client systemd[1]: Starting Enable Persistent Storage in systemd-networkd... container-test-run-admin> client # [2688514.255621] client systemd[1]: Finished Enable Persistent Storage in systemd-networkd. container-test-run-admin> server # [2688514.228775] server systemd-networkd[216]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted container-test-run-admin> server # [2688514.228847] server systemd-networkd[216]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted container-test-run-admin> server # [2688514.235604] server systemd-networkd[216]: /etc/systemd/network/99-ethernet-default-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section. container-test-run-admin> server # [2688514.235745] server systemd-networkd[216]: /etc/systemd/network/99-wireless-client-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section. container-test-run-admin> server # [2688514.235796] server systemd-networkd[216]: lo: Link UP container-test-run-admin> server # [2688514.235798] server systemd-networkd[216]: lo: Gained carrier container-test-run-admin> server # [2688514.235914] server systemd-networkd[216]: eth1: Configuring with /etc/systemd/network/40-eth1.network. container-test-run-admin> server # [2688514.236147] server systemd[1]: Started Network Management. container-test-run-admin> server # [2688514.252109] server systemd-networkd[216]: eth1: Link UP container-test-run-admin> server # [2688514.252150] server systemd[1]: Starting Enable Persistent Storage in systemd-networkd... container-test-run-admin> server # [2688514.252274] server systemd-networkd[216]: eth1: Gained carrier container-test-run-admin> server # [2688514.272068] server systemd[1]: Finished Enable Persistent Storage in systemd-networkd. container-test-run-admin> server # [2688514.536133] server systemd-resolved[134]: Positive Trust Anchors: container-test-run-admin> server # [2688514.536139] server systemd-resolved[134]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d container-test-run-admin> server # [2688514.536142] server systemd-resolved[134]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 container-test-run-admin> server # [2688514.536158] server systemd-resolved[134]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test container-test-run-admin> server # [2688514.545823] server systemd-resolved[134]: Using system hostname 'server'. container-test-run-admin> server # [2688514.546823] server systemd[1]: Started Network Name Resolution. container-test-run-admin> server # [2688514.546868] server systemd[1]: Reached target Network. container-test-run-admin> server # [2688514.546903] server systemd[1]: Reached target System Initialization. container-test-run-admin> server # [2688514.546935] server systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container container-test-run-admin> server # [2688514.546952] server systemd[1]: Started Daily Cleanup of Temporary Directories. container-test-run-admin> server # [2688514.546961] server systemd[1]: Reached target Timer Units. container-test-run-admin> server # [2688514.547041] server systemd[1]: Listening on D-Bus System Message Bus Socket. container-test-run-admin> server # [2688514.547106] server systemd[1]: Listening on Nix Daemon Socket. container-test-run-admin> server # [2688514.547654] server systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Export). container-test-run-admin> server # [2688514.548132] server systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). container-test-run-admin> server # [2688514.548444] server systemd[1]: Listening on SSH Socket. container-test-run-admin> server # [2688514.548510] server systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. container-test-run-admin> server # [2688514.548518] server systemd[1]: Reached target Socket Units. container-test-run-admin> server # [2688514.548537] server systemd[1]: Reached target Basic System. container-test-run-admin> server # [2688514.558189] server systemd[1]: Starting Import lastlog data into lastlog2 database... container-test-run-admin> server # [2688514.558529] server systemd[1]: Starting Name Service Cache Daemon (nsncd)... container-test-run-admin> server # [2688514.558542] server systemd[1]: SSH Host Keys Generation skipped, no trigger condition checks were met. container-test-run-admin> server # [2688514.559169] server systemd[1]: Starting D-Bus System Message Bus... container-test-run-admin> server # [2688514.568158] server systemd[1]: Finished Import lastlog data into lastlog2 database. container-test-run-admin> server # [2688514.665385] server nsncd[223]: Oct 05 10:05:29.088 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" container-test-run-admin> server # [2688514.665389] server systemd[1]: Started Name Service Cache Daemon (nsncd). container-test-run-admin> server # [2688514.665447] server systemd[1]: Reached target Host and Network Name Lookups. container-test-run-admin> server # [2688514.665531] server systemd[1]: Reached target User and Group Name Lookups. container-test-run-admin> server # [2688514.666142] server systemd[1]: Starting User Login Management... container-test-run-admin> server # [2688514.666440] server systemd[1]: Starting Permit User Sessions... container-test-run-admin> server # [2688514.690188] server systemd[1]: Finished Permit User Sessions. container-test-run-admin> server # [2688514.690617] server systemd[1]: Started Console Getty. container-test-run-admin> server # [2688514.690640] server systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0 container-test-run-admin> server # [2688514.690650] server systemd[1]: Reached target Login Prompts. container-test-run-admin> server # [2688514.703024] server systemd[1]: etc-machine\x2did.mount: Deactivated successfully. container-test-run-admin> server # [2688514.764322] server dbus-broker-launch[224]: Looking up NSS user entry for 'systemd-timesync'... container-test-run-admin> server # [2688514.764803] server dbus-broker-launch[224]: NSS returned no entry for 'systemd-timesync' container-test-run-admin> server # [2688514.764803] server dbus-broker-launch[224]: Invalid user-name in /nix/store/cqm89h4qmaf9n6hhdm0sdy1i7g5nh228-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" container-test-run-admin> server # [2688514.765044] server systemd[1]: Started D-Bus System Message Bus. container-test-run-admin> server # [2688514.768281] server dbus-broker-launch[224]: Ready container-test-run-admin> client # [2688514.527116] client systemd-resolved[105]: Positive Trust Anchors: container-test-run-admin> client # [2688514.527123] client systemd-resolved[105]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d container-test-run-admin> client # [2688514.527126] client systemd-resolved[105]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 container-test-run-admin> client # [2688514.527142] client systemd-resolved[105]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test container-test-run-admin> client # [2688514.537010] client systemd-resolved[105]: Using system hostname 'client'. container-test-run-admin> client # [2688514.537927] client systemd[1]: Started Network Name Resolution. container-test-run-admin> client # [2688514.537977] client systemd[1]: Reached target Network. container-test-run-admin> client # [2688514.538035] client systemd[1]: Reached target System Initialization. container-test-run-admin> client # [2688514.538075] client systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container container-test-run-admin> client # [2688514.538095] client systemd[1]: Started Daily Cleanup of Temporary Directories. container-test-run-admin> client # [2688514.538107] client systemd[1]: Reached target Timer Units. container-test-run-admin> client # [2688514.538183] client systemd[1]: Listening on D-Bus System Message Bus Socket. container-test-run-admin> client # [2688514.538257] client systemd[1]: Listening on Nix Daemon Socket. container-test-run-admin> client # [2688514.538322] client systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. container-test-run-admin> client # [2688514.538341] client systemd[1]: Reached target Socket Units. container-test-run-admin> client # [2688514.538365] client systemd[1]: Reached target Basic System. container-test-run-admin> client # [2688514.538984] client systemd[1]: Starting Import lastlog data into lastlog2 database... container-test-run-admin> client # [2688514.539434] client systemd[1]: Starting Name Service Cache Daemon (nsncd)... container-test-run-admin> client # [2688514.540034] client systemd[1]: Starting D-Bus System Message Bus... container-test-run-admin> client # [2688514.567112] client systemd[1]: Finished Import lastlog data into lastlog2 database. container-test-run-admin> client # [2688514.665239] client nsncd[192]: Oct 05 10:05:29.088 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" container-test-run-admin> client # [2688514.665253] client systemd[1]: Started Name Service Cache Daemon (nsncd). container-test-run-admin> client # [2688514.665291] client systemd[1]: Reached target Host and Network Name Lookups. container-test-run-admin> client # [2688514.665325] client systemd[1]: Reached target User and Group Name Lookups. container-test-run-admin> client # [2688514.665995] client systemd[1]: Starting User Login Management... container-test-run-admin> client # [2688514.666318] client systemd[1]: Starting Permit User Sessions... container-test-run-admin> client # [2688514.701923] client systemd[1]: etc-machine\x2did.mount: Deactivated successfully. container-test-run-admin> client # [2688514.702229] client systemd[1]: Finished Permit User Sessions. container-test-run-admin> client # [2688514.702939] client systemd[1]: Started Console Getty. container-test-run-admin> client # [2688514.702958] client systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0 container-test-run-admin> client # [2688514.702967] client systemd[1]: Reached target Login Prompts. container-test-run-admin> client # [2688514.765571] client dbus-broker-launch[193]: Looking up NSS user entry for 'systemd-timesync'... container-test-run-admin> client # [2688514.765921] client dbus-broker-launch[193]: NSS returned no entry for 'systemd-timesync' container-test-run-admin> client # [2688514.765921] client dbus-broker-launch[193]: Invalid user-name in /nix/store/0sd6l8g8jgy7p8a1c1x3c34kqhl2hg1a-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" container-test-run-admin> client # [2688514.766133] client systemd[1]: Started D-Bus System Message Bus. container-test-run-admin> client # [2688514.769323] client dbus-broker-launch[193]: Ready container-test-run-admin> client: waiting for unit network-online.target container-test-run-admin> client: (finished: waiting for unit network-online.target, in 0.01 seconds) container-test-run-admin> server: waiting for unit network-online.target container-test-run-admin> server: (finished: waiting for unit network-online.target, in 0.01 seconds) container-test-run-admin> client: must succeed: ssh -F /dev/null -i /etc/private-test-key -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o BatchMode=yes root@server true &>/dev/null container-test-run-admin> server # [2688515.186625] server systemd-logind[239]: New seat seat0. container-test-run-admin> server # [2688515.186759] server systemd[1]: Started User Login Management. container-test-run-admin> server # [2688515.187473] server systemd[1]: Starting linger-users.service... container-test-run-admin> server # [2688515.205911] server systemd[1]: linger-users.service: Deactivated successfully. container-test-run-admin> server # [2688515.206027] server systemd[1]: Finished linger-users.service. container-test-run-admin> server # [2688515.206232] server systemd[1]: Reached target Multi-User System. container-test-run-admin> server # [2688515.206334] server systemd[1]: Startup finished in 1.701s. container-test-run-admin> server # [2688515.362117] server systemd[1]: Reached target Network is Online. container-test-run-admin> server # [2688515.385065] server systemd[1]: Created slice Slice /system/sshd. container-test-run-admin> server # [2688515.385115] server systemd[1]: SSH Host Keys Generation skipped, no trigger condition checks were met. container-test-run-admin> server # [2688515.385662] server systemd[1]: Started SSH per-connection Daemon (192.168.1.1:37764). container-test-run-admin> client: (finished: must succeed: ssh -F /dev/null -i /etc/private-test-key -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o BatchMode=yes root@server true &>/dev/null, in 0.12 seconds) container-test-run-admin> (finished: run the VM test script, in 2.28 seconds) container-test-run-admin> test script finished in 2.29s container-test-run-admin> cleanup container-test-run-admin> kill NspawnMachine (pid 52) container-test-run-admin> client # [2688515.188072] client systemd-logind[208]: New seat seat0. container-test-run-admin> client # [2688515.188179] client systemd[1]: Started User Login Management. container-test-run-admin> client # [2688515.188806] client systemd[1]: Starting linger-users.service... container-test-run-admin> client # [2688515.206163] client systemd[1]: linger-users.service: Deactivated successfully. container-test-run-admin> client # [2688515.206217] client systemd[1]: Finished linger-users.service. container-test-run-admin> client # [2688515.206474] client systemd[1]: Reached target Multi-User System. container-test-run-admin> client # [2688515.206584] client systemd[1]: Startup finished in 1.734s. container-test-run-admin> client # [2688515.356277] client systemd[1]: Reached target Network is Online. container-test-run-admin> kill NspawnMachine (pid 53) container-test-run-admin> Container client terminated by signal KILL. container-test-run-admin> Container server terminated by signal KILL. container-test-run-admin> (finished: cleanup, in 0.18 seconds)