these 48 derivations will be built:
  /nix/store/0mycbmlf0718yzxrlp21rpqvrl0f30yl-etc-hello.drv
  /nix/store/im335z5wjb7miyhlhnac54b7jmf6pagg-nixos-generate-config.drv
  /nix/store/xd1cpawvbga7lld092d4xw42vnyn1iqi-greet-world.drv
  /nix/store/c7609106g2dc4g6vmj60f1s1pvynr1m6-system-path.drv
  /nix/store/nsnrknxmapwysvls9yksq39vgrgc6rg0-dbus-1.drv
  /nix/store/k3dhbl8wfx56jg64f7zllp73xldm046y-X-Restart-Triggers-dbus-broker.drv
  /nix/store/wrvyxfgbcfxa0xgh1w2pni13qlgrdmxl-unit-dbus-broker.service.drv
  /nix/store/1z6x1ipwlhfm7wga8n1jvwfgsq0pka28-user-units.drv
  /nix/store/2fxnb2950550avsggk0qd6ah9662v43n-etc-hello.drv
  /nix/store/4113cy3h1zn2pxikqj44bh2jglwwd29b-extra-hosts.drv
  /nix/store/nfsh9rgzb1d077ygj706g58d5c9hzh1d-string-hosts.drv
  /nix/store/4mg97arhv6villmd22qr70x9n0znl036-hosts.drv
  /nix/store/cgydqlk6x2wrh0dagw3iv7dk6w9anv8g-etc-hostname.drv
  /nix/store/54fksgp6mkibfl857115whw5dc4nrzsy-unit-nix-optimise.service.drv
  /nix/store/dxfpslk0h4gpl9n87pr26mhhh3jvpxc2-unit-dbus-broker.service.drv
  /nix/store/f7jxvzadmdfgs3hnj9ns8z63bfcxhphr-nix.conf.drv
  /nix/store/i40hwwb45207ff7wx4hi126zc02injm1-X-Restart-Triggers-nix-daemon.drv
  /nix/store/wgayqsakc24vl5nhzkk2yx7kzra6x51k-unit-nix-daemon.service.drv
  /nix/store/4xfr22gcizwrhk71z7vbqadhf6vw2kfm-unit-script-nix-gc-start.drv
  /nix/store/x2ri5bphzj7s2brwzpp0imgv64inz2bn-unit-nix-gc.service.drv
  /nix/store/xg44dmiffy5295dgbadssdgjryd5m86n-tmpfiles.d.drv
  /nix/store/bsln1zvgmzj87dylgk7b5ph1ka3fpkwd-X-Restart-Triggers-systemd-tmpfiles-resetup.drv
  /nix/store/zvhrvxgjg505cv8vg0mj77arbvx6jf0s-unit-systemd-tmpfiles-resetup.service.drv
  /nix/store/dl3hpz5aqlwj92vpmsaby6nmnmqbl4a8-system-units.drv
  /nix/store/gm98kck59ydsm6pi8a5s3psjvhfckqqj-etc.drv
  /nix/store/0y38qs9ifpc02hi656znyrw724javdkc-activate.drv
  /nix/store/5jd1wvwkm388y0g7j86s51pmah74b7kk-greet-world.drv
  /nix/store/6l06psv3pp7y1y85wq6rhbhyc72hj4l9-system-path.drv
  /nix/store/7f7rj9iph0nmyasmd1rkg7cjsnk3wyi7-dbus-1.drv
  /nix/store/vb1475lkiinvkqicc4xm09ygdkx16l5l-X-Restart-Triggers-dbus-broker.drv
  /nix/store/12ha8s511aqfdag035jgzq7rgnjcvdxn-unit-dbus-broker.service.drv
  /nix/store/214h447nbv2s2mrqr9sajvz58xr5xn5q-unit-dbus-broker.service.drv
  /nix/store/xlw2wlmr486h8zicyd2b5spncjdy5nqb-string-hosts.drv
  /nix/store/4345ay6cjb3fvgn35axis11aa5ckqzdg-hosts.drv
  /nix/store/f36p5fcjagbywa9n9jbg5c13k2n97gh8-system-units.drv
  /nix/store/nqx589nk6cpwp6v6smn99562077pir6v-user-units.drv
  /nix/store/mis8rms73qxrvq3h7abgg76ca1kv2p23-etc.drv
  /nix/store/r2x6dz51kglnd66562wbx38vh4pfg3a8-activate.drv
  /nix/store/xlmfm1l3vgqhriksl3wyyfz1wdgrff00-nixos-system-peer1-test.drv
  /nix/store/32gb011hc4yf225sybhnrjafdz1b8qqm-run-peer1-nspawn.drv
  /nix/store/6w1n91nmzssb9a23m26clsnj1zd2cnd5-nixos-system-peer2-test.drv
  /nix/store/8k98n01ib820xsl1jc0pz83advv4xp9j-run-peer2-nspawn.drv
  /nix/store/hgzqqcvzc04s3n3c8jvbynccsz4mgpc5-python3.14-nixos-test-lib-1.0.0.drv
  /nix/store/hqpqg72233dlgllawxgj9vh1rqmwkwlq-nixos-test-driver-1.1.drv
  /nix/store/pz5vb37rngjppfhi1d7aqzrwqsf9khn2-test-script.drv
  /nix/store/jl6p55v4fjmkqvw9bm899k3hh3xlrpvr-driverConfiguration.json.drv
  /nix/store/bf0aywkx04yvkzyfnndi151dsc032jv8-nixos-test-driver-hello-service.drv
  /nix/store/r5l0ipdpsj59im6w5lgfym9jyrfypg2y-container-test-run-hello-service.drv
this path will be fetched (0.0 KiB download, 0.1 KiB unpacked):
  /nix/store/qs6iq64ksavnr3cqi7jdk4v2x2dl0h8h-vars-check-hello-service
building '/nix/store/0mycbmlf0718yzxrlp21rpqvrl0f30yl-etc-hello.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/2fxnb2950550avsggk0qd6ah9662v43n-etc-hello.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/0mycbmlf0718yzxrlp21rpqvrl0f30yl-etc-hello.drv'
etc-hello> structuredAttrs is enabled
building '/nix/store/2fxnb2950550avsggk0qd6ah9662v43n-etc-hello.drv'
etc-hello> structuredAttrs is enabled
building '/nix/store/4113cy3h1zn2pxikqj44bh2jglwwd29b-extra-hosts.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/5jd1wvwkm388y0g7j86s51pmah74b7kk-greet-world.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/xd1cpawvbga7lld092d4xw42vnyn1iqi-greet-world.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/pz5vb37rngjppfhi1d7aqzrwqsf9khn2-test-script.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/4113cy3h1zn2pxikqj44bh2jglwwd29b-extra-hosts.drv'
extra-hosts> structuredAttrs is enabled
building '/nix/store/4345ay6cjb3fvgn35axis11aa5ckqzdg-hosts.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/5jd1wvwkm388y0g7j86s51pmah74b7kk-greet-world.drv'
greet-world> structuredAttrs is enabled
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/6l06psv3pp7y1y85wq6rhbhyc72hj4l9-system-path.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/pz5vb37rngjppfhi1d7aqzrwqsf9khn2-test-script.drv'
test-script> structuredAttrs is enabled
building '/nix/store/xd1cpawvbga7lld092d4xw42vnyn1iqi-greet-world.drv'
greet-world> structuredAttrs is enabled
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/c7609106g2dc4g6vmj60f1s1pvynr1m6-system-path.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/4345ay6cjb3fvgn35axis11aa5ckqzdg-hosts.drv'
building '/nix/store/6l06psv3pp7y1y85wq6rhbhyc72hj4l9-system-path.drv'
system-path> structuredAttrs is enabled
system-path> created 1717 symlinks in user environment
building '/nix/store/7f7rj9iph0nmyasmd1rkg7cjsnk3wyi7-dbus-1.drv' on 'ssh-ng://builder@build01.clan.lol'
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/4mg97arhv6villmd22qr70x9n0znl036-hosts.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/c7609106g2dc4g6vmj60f1s1pvynr1m6-system-path.drv'
system-path> structuredAttrs is enabled
system-path> created 1717 symlinks in user environment
building '/nix/store/nsnrknxmapwysvls9yksq39vgrgc6rg0-dbus-1.drv' on 'ssh-ng://builder@build01.clan.lol'
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/4mg97arhv6villmd22qr70x9n0znl036-hosts.drv'
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/nsnrknxmapwysvls9yksq39vgrgc6rg0-dbus-1.drv'
building '/nix/store/7f7rj9iph0nmyasmd1rkg7cjsnk3wyi7-dbus-1.drv'
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/k3dhbl8wfx56jg64f7zllp73xldm046y-X-Restart-Triggers-dbus-broker.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/vb1475lkiinvkqicc4xm09ygdkx16l5l-X-Restart-Triggers-dbus-broker.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/k3dhbl8wfx56jg64f7zllp73xldm046y-X-Restart-Triggers-dbus-broker.drv'
X-Restart-Triggers-dbus-broker> structuredAttrs is enabled
building '/nix/store/dxfpslk0h4gpl9n87pr26mhhh3jvpxc2-unit-dbus-broker.service.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/vb1475lkiinvkqicc4xm09ygdkx16l5l-X-Restart-Triggers-dbus-broker.drv'
X-Restart-Triggers-dbus-broker> structuredAttrs is enabled
building '/nix/store/12ha8s511aqfdag035jgzq7rgnjcvdxn-unit-dbus-broker.service.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/214h447nbv2s2mrqr9sajvz58xr5xn5q-unit-dbus-broker.service.drv' on 'ssh-ng://builder@build01.clan.lol'
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/wrvyxfgbcfxa0xgh1w2pni13qlgrdmxl-unit-dbus-broker.service.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/dxfpslk0h4gpl9n87pr26mhhh3jvpxc2-unit-dbus-broker.service.drv'
unit-dbus-broker.service> structuredAttrs is enabled
building '/nix/store/12ha8s511aqfdag035jgzq7rgnjcvdxn-unit-dbus-broker.service.drv'
unit-dbus-broker.service> structuredAttrs is enabled
building '/nix/store/nqx589nk6cpwp6v6smn99562077pir6v-user-units.drv' on 'ssh-ng://builder@build01.clan.lol'
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/214h447nbv2s2mrqr9sajvz58xr5xn5q-unit-dbus-broker.service.drv'
unit-dbus-broker.service> structuredAttrs is enabled
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/f36p5fcjagbywa9n9jbg5c13k2n97gh8-system-units.drv' on 'ssh-ng://builder@build01.clan.lol'
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/wrvyxfgbcfxa0xgh1w2pni13qlgrdmxl-unit-dbus-broker.service.drv'
unit-dbus-broker.service> structuredAttrs is enabled
building '/nix/store/1z6x1ipwlhfm7wga8n1jvwfgsq0pka28-user-units.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/nqx589nk6cpwp6v6smn99562077pir6v-user-units.drv'
building '/nix/store/dl3hpz5aqlwj92vpmsaby6nmnmqbl4a8-system-units.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/1z6x1ipwlhfm7wga8n1jvwfgsq0pka28-user-units.drv'
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/f36p5fcjagbywa9n9jbg5c13k2n97gh8-system-units.drv'
building '/nix/store/mis8rms73qxrvq3h7abgg76ca1kv2p23-etc.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/dl3hpz5aqlwj92vpmsaby6nmnmqbl4a8-system-units.drv'
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/gm98kck59ydsm6pi8a5s3psjvhfckqqj-etc.drv' on 'ssh-ng://builder@build01.clan.lol'
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/mis8rms73qxrvq3h7abgg76ca1kv2p23-etc.drv'
building '/nix/store/r2x6dz51kglnd66562wbx38vh4pfg3a8-activate.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/gm98kck59ydsm6pi8a5s3psjvhfckqqj-etc.drv'
building '/nix/store/0y38qs9ifpc02hi656znyrw724javdkc-activate.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/r2x6dz51kglnd66562wbx38vh4pfg3a8-activate.drv'
activate> structuredAttrs is enabled
building '/nix/store/xlmfm1l3vgqhriksl3wyyfz1wdgrff00-nixos-system-peer1-test.drv' on 'ssh-ng://builder@build01.clan.lol'
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/0y38qs9ifpc02hi656znyrw724javdkc-activate.drv'
activate> structuredAttrs is enabled
building '/nix/store/6w1n91nmzssb9a23m26clsnj1zd2cnd5-nixos-system-peer2-test.drv' on 'ssh-ng://builder@build01.clan.lol'
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/xlmfm1l3vgqhriksl3wyyfz1wdgrff00-nixos-system-peer1-test.drv'
nixos-system-peer1-test> structuredAttrs is enabled
building '/nix/store/32gb011hc4yf225sybhnrjafdz1b8qqm-run-peer1-nspawn.drv' on 'ssh-ng://builder@build01.clan.lol'
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/6w1n91nmzssb9a23m26clsnj1zd2cnd5-nixos-system-peer2-test.drv'
nixos-system-peer2-test> structuredAttrs is enabled
building '/nix/store/8k98n01ib820xsl1jc0pz83advv4xp9j-run-peer2-nspawn.drv' on 'ssh-ng://builder@build01.clan.lol'
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/32gb011hc4yf225sybhnrjafdz1b8qqm-run-peer1-nspawn.drv'
building '/nix/store/8k98n01ib820xsl1jc0pz83advv4xp9j-run-peer2-nspawn.drv'
building '/nix/store/jl6p55v4fjmkqvw9bm899k3hh3xlrpvr-driverConfiguration.json.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/jl6p55v4fjmkqvw9bm899k3hh3xlrpvr-driverConfiguration.json.drv'
driverConfiguration.json> structuredAttrs is enabled
building '/nix/store/bf0aywkx04yvkzyfnndi151dsc032jv8-nixos-test-driver-hello-service.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/bf0aywkx04yvkzyfnndi151dsc032jv8-nixos-test-driver-hello-service.drv'
nixos-test-driver-hello-service> Running type check (enable/disable: config.skipTypeCheck)
nixos-test-driver-hello-service> See https://nixos.org/manual/nixos/stable/#test-opt-skipTypeCheck
nixos-test-driver-hello-service> All checks passed!
nixos-test-driver-hello-service> Linting test script (enable/disable: config.skipLint)
nixos-test-driver-hello-service> See https://nixos.org/manual/nixos/stable/#test-opt-skipLint
nixos-test-driver-hello-service> All checks passed!
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/r5l0ipdpsj59im6w5lgfym9jyrfypg2y-container-test-run-hello-service.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/r5l0ipdpsj59im6w5lgfym9jyrfypg2y-container-test-run-hello-service.drv'
container-test-run-hello-service> Machine state will be reset. To keep it, pass --keep-machine-state
container-test-run-hello-service> start all VLans
container-test-run-hello-service> (finished: start all VLans, in 0.00 seconds)
container-test-run-hello-service> 
container-test-run-hello-service> Test will time out and terminate in 3600.0 seconds
container-test-run-hello-service> run the VM test script
container-test-run-hello-service> additionally exposed symbols:
container-test-run-hello-service>     peer1, peer2,
container-test-run-hello-service>     vlan1,
container-test-run-hello-service>     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-hello-service> start all VMs
container-test-run-hello-service> peer1: systemd-nspawn running (pid 52)
container-test-run-hello-service> peer2: systemd-nspawn running (pid 53)
container-test-run-hello-service> peer1: Waiting for journal at /build/vm-state-peer1/var/log/journal...
container-test-run-hello-service> peer2: Waiting for journal at /build/vm-state-peer2/var/log/journal...
container-test-run-hello-service> (finished: start all VMs, in 0.00 seconds)
container-test-run-hello-service> peer1: must succeed: greet-world
container-test-run-hello-service> nixos-nspawn(peer2): TAP vde-tap1 not found; container will be isolated from VDE
container-test-run-hello-service> nixos-nspawn(peer2): 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-hello-service> nixos-nspawn(peer1): TAP vde-tap1 not found; container will be isolated from VDE
container-test-run-hello-service> nixos-nspawn(peer1): 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-hello-service> 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-hello-service> 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-hello-service> ░ Spawning container peer2 on /build/vm-state-peer2.
container-test-run-hello-service> ░ Spawning container peer1 on /build/vm-state-peer1.
container-test-run-hello-service> peer2 # [1201423.038515] peer2 systemd-journald[72]: Journal started
container-test-run-hello-service> peer2 # [1201423.038573] peer2 systemd-journald[72]: Runtime Journal (/run/log/journal/16451d2ec2f849c4a0ec8af17096879e) is 8M, max 2.5G, 2.4G free.
container-test-run-hello-service> peer2 # [1201423.047676] peer2 systemd[1]: Finished Apply Kernel Variables.
container-test-run-hello-service> peer2 # [1201423.061246] peer2 systemd[1]: Finished Create Static Device Nodes in /dev gracefully.
container-test-run-hello-service> peer2 # [1201423.079308] peer2 systemd[1]: Starting Flush Journal to Persistent Storage...
container-test-run-hello-service> peer2 # [1201423.080251] peer2 systemd[1]: Starting Network Name Resolution...
container-test-run-hello-service> peer2 # [1201423.080931] peer2 systemd[1]: Starting Create Static Device Nodes in /dev...
container-test-run-hello-service> peer2 # [1201423.094692] peer2 systemd-journald[72]: Time spent on flushing to /var/log/journal/16451d2ec2f849c4a0ec8af17096879e is 2.123ms for 7 entries.
container-test-run-hello-service> peer1 # [1201423.041129] peer1 systemd-journald[72]: Journal started
container-test-run-hello-service> peer1 # [1201423.041176] peer1 systemd-journald[72]: Runtime Journal (/run/log/journal/ae5a9d76779c47ceb70a8682e37410c7) is 8M, max 2.5G, 2.4G free.
container-test-run-hello-service> peer1 # [1201423.051596] peer1 systemd[1]: Finished Apply Kernel Variables.
container-test-run-hello-service> peer1 # [1201423.063316] peer1 systemd[1]: Finished Create Static Device Nodes in /dev gracefully.
container-test-run-hello-service> peer1 # [1201423.076377] peer1 systemd[1]: Starting Flush Journal to Persistent Storage...
container-test-run-hello-service> peer1 # [1201423.077607] peer1 systemd[1]: Starting Network Name Resolution...
container-test-run-hello-service> peer1 # [1201423.079264] peer1 systemd[1]: Starting Create Static Device Nodes in /dev...
container-test-run-hello-service> peer1 # [1201423.087483] peer1 systemd-journald[72]: Time spent on flushing to /var/log/journal/ae5a9d76779c47ceb70a8682e37410c7 is 1.553ms for 7 entries.
container-test-run-hello-service> peer1 # [1201423.087483] peer1 systemd-journald[72]: System Journal (/var/log/journal/ae5a9d76779c47ceb70a8682e37410c7) is 8M, max 4G, 3.9G free.
container-test-run-hello-service> peer1 # [1201423.107579] peer1 systemd[1]: Finished Create Static Device Nodes in /dev.
container-test-run-hello-service> peer1 # [1201423.108318] peer1 systemd[1]: Reached target Preparation for Local File Systems.
container-test-run-hello-service> peer1 # [1201423.108763] peer1 systemd[1]: Reached target Local File Systems.
container-test-run-hello-service> peer1 # [1201423.109611] peer1 systemd[1]: Listening on Boot Loader Control Service Socket.
container-test-run-hello-service> peer1 # [1201423.109658] peer1 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container
container-test-run-hello-service> peer1 # [1201423.110559] peer1 systemd[1]: Starting Save Transient machine-id to Disk...
container-test-run-hello-service> peer1 # [1201423.110601] peer1 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys
container-test-run-hello-service> peer1 # [1201423.111054] peer1 systemd[1]: Finished Flush Journal to Persistent Storage.
container-test-run-hello-service> peer1 # [1201423.112453] peer1 systemd[1]: Starting Create System Files and Directories...
container-test-run-hello-service> peer1 # [1201423.127313] peer1 systemd-tmpfiles[129]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted
container-test-run-hello-service> peer1 # [1201423.127514] peer1 systemd-tmpfiles[129]: fchmod() of /var/log/journal failed: Operation not permitted
container-test-run-hello-service> peer1 # [1201423.127652] peer1 systemd-tmpfiles[129]: fchmod() of /var/log/journal/ae5a9d76779c47ceb70a8682e37410c7 failed: Operation not permitted
container-test-run-hello-service> peer1 # [1201423.127855] peer1 systemd-tmpfiles[129]: fchmod() of /run/log/journal failed: Operation not permitted
container-test-run-hello-service> peer2 # [1201423.094692] peer2 systemd-journald[72]: System Journal (/var/log/journal/16451d2ec2f849c4a0ec8af17096879e) is 8M, max 4G, 3.9G free.
container-test-run-hello-service> peer2 # [1201423.108862] peer2 systemd[1]: Finished Create Static Device Nodes in /dev.
container-test-run-hello-service> peer2 # [1201423.109589] peer2 systemd[1]: Reached target Preparation for Local File Systems.
container-test-run-hello-service> peer2 # [1201423.109722] peer2 systemd[1]: Reached target Local File Systems.
container-test-run-hello-service> peer2 # [1201423.110600] peer2 systemd[1]: Listening on Boot Loader Control Service Socket.
container-test-run-hello-service> peer2 # [1201423.110646] peer2 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container
container-test-run-hello-service> peer2 # [1201423.111493] peer2 systemd[1]: Starting Save Transient machine-id to Disk...
container-test-run-hello-service> peer2 # [1201423.111532] peer2 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys
container-test-run-hello-service> peer2 # [1201423.120024] peer2 systemd[1]: Finished Flush Journal to Persistent Storage.
container-test-run-hello-service> peer2 # [1201423.121318] peer2 systemd[1]: Starting Create System Files and Directories...
container-test-run-hello-service> peer2 # [1201423.137527] peer2 systemd-tmpfiles[136]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted
container-test-run-hello-service> peer2 # [1201423.137742] peer2 systemd-tmpfiles[136]: fchmod() of /var/log/journal failed: Operation not permitted
container-test-run-hello-service> peer2 # [1201423.137891] peer2 systemd-tmpfiles[136]: fchmod() of /var/log/journal/16451d2ec2f849c4a0ec8af17096879e failed: Operation not permitted
container-test-run-hello-service> peer2 # [1201423.138109] peer2 systemd-tmpfiles[136]: fchmod() of /run/log/journal failed: Operation not permitted
container-test-run-hello-service> peer2 # [1201423.139662] peer2 systemd[1]: Finished Create System Files and Directories.
container-test-run-hello-service> peer2 # [1201423.140745] peer2 systemd[1]: Starting Rebuild Journal Catalog...
container-test-run-hello-service> peer2 # [1201423.141434] peer2 systemd[1]: Starting Record System Boot/Shutdown in UTMP...
container-test-run-hello-service> peer2 # [1201423.154167] peer2 systemd[1]: Finished Record System Boot/Shutdown in UTMP.
container-test-run-hello-service> peer2 # [1201423.164470] peer2 systemd[1]: Finished Rebuild Journal Catalog.
container-test-run-hello-service> peer2 # [1201423.165631] peer2 systemd[1]: Starting Update is Completed...
container-test-run-hello-service> peer2 # [1201423.177406] peer2 systemd[1]: Finished Update is Completed.
container-test-run-hello-service> peer2 # [1201423.200880] peer2 systemd[1]: Finished Firewall.
container-test-run-hello-service> peer2 # [1201423.201530] peer2 systemd[1]: Reached target Preparation for Network.
container-test-run-hello-service> peer2 # [1201423.201878] peer2 systemd[1]: Listening on Network Management Resolve Hook Socket.
container-test-run-hello-service> peer2 # [1201423.203293] peer2 systemd[1]: Starting Network Management...
container-test-run-hello-service> peer1 # [1201423.129091] peer1 systemd[1]: Finished Create System Files and Directories.
container-test-run-hello-service> peer1 # [1201423.130725] peer1 systemd[1]: Starting Rebuild Journal Catalog...
container-test-run-hello-service> peer1 # [1201423.131471] peer1 systemd[1]: Starting Record System Boot/Shutdown in UTMP...
container-test-run-hello-service> peer1 # [1201423.145475] peer1 systemd[1]: Finished Record System Boot/Shutdown in UTMP.
container-test-run-hello-service> peer1 # [1201423.154480] peer1 systemd[1]: Finished Rebuild Journal Catalog.
container-test-run-hello-service> peer1 # [1201423.155760] peer1 systemd[1]: Starting Update is Completed...
container-test-run-hello-service> peer1 # [1201423.168414] peer1 systemd[1]: Finished Update is Completed.
container-test-run-hello-service> peer1 # [1201423.213534] peer1 systemd[1]: Finished Firewall.
container-test-run-hello-service> peer1 # [1201423.213694] peer1 systemd[1]: Reached target Preparation for Network.
container-test-run-hello-service> peer1 # [1201423.213933] peer1 systemd[1]: Listening on Network Management Resolve Hook Socket.
container-test-run-hello-service> peer1 # [1201423.214938] peer1 systemd[1]: Starting Network Management...
container-test-run-hello-service> peer1 # [1201423.564851] peer1 systemd[1]: Finished Save Transient machine-id to Disk.
container-test-run-hello-service> peer2 # [1201423.564781] peer2 systemd[1]: Finished Save Transient machine-id to Disk.
container-test-run-hello-service> peer1 # [1201423.905981] peer1 systemd-networkd[185]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted
container-test-run-hello-service> peer2 # [1201423.913930] peer2 systemd-networkd[185]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted
container-test-run-hello-service> peer1 # [1201423.906073] peer1 systemd-networkd[185]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted
container-test-run-hello-service> peer2 # [1201423.914017] peer2 systemd-networkd[185]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted
container-test-run-hello-service> peer1 # [1201423.915234] peer1 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-hello-service> peer1 # [1201423.915400] peer1 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-hello-service> peer1 # [1201423.915554] peer1 systemd-networkd[185]: lo: Link UP
container-test-run-hello-service> peer1 # [1201423.915559] peer1 systemd-networkd[185]: lo: Gained carrier
container-test-run-hello-service> peer1 # [1201423.915753] peer1 systemd-networkd[185]: eth1: Configuring with /etc/systemd/network/40-eth1.network.
container-test-run-hello-service> peer1 # [1201423.916202] peer1 systemd[1]: Started Network Management.
container-test-run-hello-service> peer1 # [1201423.916218] peer1 systemd-networkd[185]: eth1: Link UP
container-test-run-hello-service> peer1 # [1201423.916480] peer1 systemd-networkd[185]: eth1: Gained carrier
container-test-run-hello-service> peer1 # [1201423.917911] peer1 systemd[1]: Starting Enable Persistent Storage in systemd-networkd...
container-test-run-hello-service> peer1 # [1201423.961519] peer1 systemd[1]: Finished Enable Persistent Storage in systemd-networkd.
container-test-run-hello-service> peer1 # [1201424.039802] peer1 systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
container-test-run-hello-service> peer2 # [1201423.923650] peer2 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-hello-service> peer2 # [1201423.923822] peer2 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-hello-service> peer2 # [1201423.923977] peer2 systemd-networkd[185]: lo: Link UP
container-test-run-hello-service> peer2 # [1201423.923981] peer2 systemd-networkd[185]: lo: Gained carrier
container-test-run-hello-service> peer2 # [1201423.924212] peer2 systemd-networkd[185]: eth1: Configuring with /etc/systemd/network/40-eth1.network.
container-test-run-hello-service> peer2 # [1201423.924577] peer2 systemd[1]: Started Network Management.
container-test-run-hello-service> peer2 # [1201423.924676] peer2 systemd-networkd[185]: eth1: Link UP
container-test-run-hello-service> peer2 # [1201423.924895] peer2 systemd-networkd[185]: eth1: Gained carrier
container-test-run-hello-service> peer2 # [1201423.925567] peer2 systemd[1]: Starting Enable Persistent Storage in systemd-networkd...
container-test-run-hello-service> peer2 # [1201423.978121] peer2 systemd[1]: Finished Enable Persistent Storage in systemd-networkd.
container-test-run-hello-service> peer2 # [1201424.032149] peer2 systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
container-test-run-hello-service> peer1 # [1201424.287663] peer1 systemd-resolved[104]: Positive Trust Anchors:
container-test-run-hello-service> peer1 # [1201424.287674] peer1 systemd-resolved[104]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
container-test-run-hello-service> peer1 # [1201424.287677] peer1 systemd-resolved[104]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16
container-test-run-hello-service> peer1 # [1201424.287713] peer1 systemd-resolved[104]: 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-hello-service> peer1 # [1201424.309814] peer1 systemd-resolved[104]: Using system hostname 'peer1'.
container-test-run-hello-service> peer1 # [1201424.311253] peer1 systemd[1]: Started Network Name Resolution.
container-test-run-hello-service> peer1 # [1201424.311381] peer1 systemd[1]: Reached target Network.
container-test-run-hello-service> peer1 # [1201424.311500] peer1 systemd[1]: Reached target System Initialization.
container-test-run-hello-service> peer1 # [1201424.311598] peer1 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container
container-test-run-hello-service> peer1 # [1201424.311654] peer1 systemd[1]: Started Daily Cleanup of Temporary Directories.
container-test-run-hello-service> peer1 # [1201424.311691] peer1 systemd[1]: Reached target Timer Units.
container-test-run-hello-service> peer1 # [1201424.311928] peer1 systemd[1]: Listening on D-Bus System Message Bus Socket.
container-test-run-hello-service> peer1 # [1201424.312181] peer1 systemd[1]: Listening on Nix Daemon Socket.
container-test-run-hello-service> peer1 # [1201424.312415] peer1 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.
container-test-run-hello-service> peer1 # [1201424.312469] peer1 systemd[1]: Reached target Socket Units.
container-test-run-hello-service> peer1 # [1201424.312545] peer1 systemd[1]: Reached target Basic System.
container-test-run-hello-service> peer1 # [1201424.329126] peer1 systemd[1]: Starting Import lastlog data into lastlog2 database...
container-test-run-hello-service> peer1 # [1201424.330599] peer1 systemd[1]: Starting Name Service Cache Daemon (nsncd)...
container-test-run-hello-service> peer1 # [1201424.332945] peer1 systemd[1]: Starting D-Bus System Message Bus...
container-test-run-hello-service> peer1 # [1201424.351977] peer1 systemd[1]: Finished Import lastlog data into lastlog2 database.
container-test-run-hello-service> peer1 # [1201424.534010] peer1 nsncd[192]: Sep 18 05:10:44.519 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"
container-test-run-hello-service> peer1 # [1201424.534075] peer1 systemd[1]: Started Name Service Cache Daemon (nsncd).
container-test-run-hello-service> peer1 # [1201424.534141] peer1 systemd[1]: Reached target Host and Network Name Lookups.
container-test-run-hello-service> peer1 # [1201424.534206] peer1 systemd[1]: Reached target User and Group Name Lookups.
container-test-run-hello-service> peer1 # [1201424.535402] peer1 systemd[1]: Starting User Login Management...
container-test-run-hello-service> peer1 # [1201424.536486] peer1 systemd[1]: Starting Permit User Sessions...
container-test-run-hello-service> peer2 # [1201424.295142] peer2 systemd-resolved[109]: Positive Trust Anchors:
container-test-run-hello-service> peer2 # [1201424.295154] peer2 systemd-resolved[109]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
container-test-run-hello-service> peer2 # [1201424.295159] peer2 systemd-resolved[109]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16
container-test-run-hello-service> peer2 # [1201424.295193] peer2 systemd-resolved[109]: 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-hello-service> peer2 # [1201424.317744] peer2 systemd-resolved[109]: Using system hostname 'peer2'.
container-test-run-hello-service> peer2 # [1201424.319074] peer2 systemd[1]: Started Network Name Resolution.
container-test-run-hello-service> peer2 # [1201424.319144] peer2 systemd[1]: Reached target Network.
container-test-run-hello-service> peer2 # [1201424.319212] peer2 systemd[1]: Reached target System Initialization.
container-test-run-hello-service> peer2 # [1201424.319259] peer2 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container
container-test-run-hello-service> peer2 # [1201424.319287] peer2 systemd[1]: Started Daily Cleanup of Temporary Directories.
container-test-run-hello-service> peer2 # [1201424.319305] peer2 systemd[1]: Reached target Timer Units.
container-test-run-hello-service> peer2 # [1201424.319417] peer2 systemd[1]: Listening on D-Bus System Message Bus Socket.
container-test-run-hello-service> peer2 # [1201424.319525] peer2 systemd[1]: Listening on Nix Daemon Socket.
container-test-run-hello-service> peer2 # [1201424.319629] peer2 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.
container-test-run-hello-service> peer2 # [1201424.319650] peer2 systemd[1]: Reached target Socket Units.
container-test-run-hello-service> peer2 # [1201424.319686] peer2 systemd[1]: Reached target Basic System.
container-test-run-hello-service> peer2 # [1201424.328861] peer2 systemd[1]: Starting Import lastlog data into lastlog2 database...
container-test-run-hello-service> peer2 # [1201424.329732] peer2 systemd[1]: Starting Name Service Cache Daemon (nsncd)...
container-test-run-hello-service> peer2 # [1201424.330925] peer2 systemd[1]: Starting D-Bus System Message Bus...
container-test-run-hello-service> peer2 # [1201424.352026] peer2 systemd[1]: Finished Import lastlog data into lastlog2 database.
container-test-run-hello-service> peer2 # [1201424.581523] peer2 nsncd[192]: Sep 18 05:10:44.567 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"
container-test-run-hello-service> peer2 # [1201424.581404] peer2 systemd[1]: Started Name Service Cache Daemon (nsncd).
container-test-run-hello-service> peer2 # [1201424.581467] peer2 systemd[1]: Reached target Host and Network Name Lookups.
container-test-run-hello-service> peer2 # [1201424.581530] peer2 systemd[1]: Reached target User and Group Name Lookups.
container-test-run-hello-service> peer2 # [1201424.582678] peer2 systemd[1]: Starting User Login Management...
container-test-run-hello-service> peer2 # [1201424.583452] peer2 systemd[1]: Starting Permit User Sessions...
container-test-run-hello-service> peer2 # [1201424.593448] peer2 systemd[1]: Finished Permit User Sessions.
container-test-run-hello-service> peer2 # [1201424.595426] peer2 systemd[1]: Started Console Getty.
container-test-run-hello-service> peer2 # [1201424.595475] peer2 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0
container-test-run-hello-service> peer2 # [1201424.595491] peer2 systemd[1]: Reached target Login Prompts.
container-test-run-hello-service> peer2 # [1201424.774531] peer2 dbus-broker-launch[193]: Looking up NSS user entry for 'systemd-timesync'...
container-test-run-hello-service> peer2 # [1201424.775842] peer2 dbus-broker-launch[193]: NSS returned no entry for 'systemd-timesync'
container-test-run-hello-service> peer2 # [1201424.775842] peer2 dbus-broker-launch[193]: Invalid user-name in /nix/store/ikn9zj7mqprkymkklp277s7il1ag23b4-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"
container-test-run-hello-service> peer2 # [1201424.776289] peer2 systemd[1]: Started D-Bus System Message Bus.
container-test-run-hello-service> peer2 # [1201424.783299] peer2 dbus-broker-launch[193]: Ready
container-test-run-hello-service> peer1 # [1201424.548643] peer1 systemd[1]: Finished Permit User Sessions.
container-test-run-hello-service> peer1 # [1201424.549682] peer1 systemd[1]: Started Console Getty.
container-test-run-hello-service> peer1 # [1201424.549729] peer1 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0
container-test-run-hello-service> peer1 # [1201424.549746] peer1 systemd[1]: Reached target Login Prompts.
container-test-run-hello-service> peer1 # [1201424.774600] peer1 dbus-broker-launch[193]: Looking up NSS user entry for 'systemd-timesync'...
container-test-run-hello-service> peer1 # [1201424.776100] peer1 dbus-broker-launch[193]: NSS returned no entry for 'systemd-timesync'
container-test-run-hello-service> peer1 # [1201424.776100] peer1 dbus-broker-launch[193]: Invalid user-name in /nix/store/js66x40fqbxaljpp9mmrhmgnp3wcw001-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"
container-test-run-hello-service> peer1 # [1201424.776510] peer1 systemd[1]: Started D-Bus System Message Bus.
container-test-run-hello-service> peer1 # [1201424.783354] peer1 dbus-broker-launch[193]: Ready
container-test-run-hello-service> peer1 # [1201424.960153] peer1 systemd-networkd[185]: eth1: Gained IPv6LL
container-test-run-hello-service> peer2 # [1201425.316194] peer2 systemd-networkd[185]: eth1: Gained IPv6LL
container-test-run-hello-service> peer2 # [1201425.475445] peer2 systemd-logind[208]: New seat seat0.
container-test-run-hello-service> peer2 # [1201425.475664] peer2 systemd[1]: Started User Login Management.
container-test-run-hello-service> peer2 # [1201425.476887] peer2 systemd[1]: Starting linger-users.service...
container-test-run-hello-service> peer2 # [1201425.488373] peer2 systemd[1]: linger-users.service: Deactivated successfully.
container-test-run-hello-service> peer2 # [1201425.488557] peer2 systemd[1]: Finished linger-users.service.
container-test-run-hello-service> peer2 # [1201425.488946] peer2 systemd[1]: Reached target Multi-User System.
container-test-run-hello-service> peer2 # [1201425.489182] peer2 systemd[1]: Startup finished in 2.916s.
container-test-run-hello-service> peer1: (finished: must succeed: greet-world, in 3.65 seconds)
container-test-run-hello-service> peer2: must succeed: greet-world
container-test-run-hello-service> peer2: (finished: must succeed: greet-world, in 0.02 seconds)
container-test-run-hello-service> (finished: run the VM test script, in 3.67 seconds)
container-test-run-hello-service> test script finished in 3.72s
container-test-run-hello-service> cleanup
container-test-run-hello-service> kill NspawnMachine (pid 52)
container-test-run-hello-service> peer1 # [1201425.459092] peer1 systemd-logind[208]: New seat seat0.
container-test-run-hello-service> peer1 # [1201425.459294] peer1 systemd[1]: Started User Login Management.
container-test-run-hello-service> peer1 # [1201425.460411] peer1 systemd[1]: Starting linger-users.service...
container-test-run-hello-service> peer1 # [1201425.472805] peer1 systemd[1]: linger-users.service: Deactivated successfully.
container-test-run-hello-service> peer1 # [1201425.473073] peer1 systemd[1]: Finished linger-users.service.
container-test-run-hello-service> peer1 # [1201425.473623] peer1 systemd[1]: Reached target Multi-User System.
container-test-run-hello-service> peer1 # [1201425.473901] peer1 systemd[1]: Startup finished in 2.911s.
container-test-run-hello-service> peer1 # [1201425.838686] peer1 systemd-networkd[185]: eth1: Link DOWN
container-test-run-hello-service> kill NspawnMachine (pid 53)
container-test-run-hello-service> Container peer1 terminated by signal KILL.
container-test-run-hello-service> Container peer2 terminated by signal KILL.
container-test-run-hello-service> (finished: cleanup, in 0.38 seconds)
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy

upload niks3: ok