don't know how to build these paths: /nix/store/lfwy9b3rcy65ss4j0i0zjq5plw98bh7z-container-test-run-dm-wireguard-star.drv warning: ignoring the client-specified setting 'store', because it is a restricted setting and you are not a trusted user building '/nix/store/yzsbrn99ap0al6dlv1ql8gsbcl4dcym0-unit-40-wg-star.netdev.drv' on nix-store.geninf.io:50051 unit-40-wg-star.netdev> web01: building yzsbrn99ap0al6dlv1ql8gsbcl4dcym0-unit-40-wg-star.netdev.drv unit-40-wg-star.netdev> structuredAttrs is enabled building '/nix/store/rh6md5g9q4ba796m252zgbsrqin4nr3h-X-Restart-Triggers-systemd-networkd.drv' on nix-store.geninf.io:50051 X-Restart-Triggers-systemd-networkd> web01: building rh6md5g9q4ba796m252zgbsrqin4nr3h-X-Restart-Triggers-systemd-networkd.drv X-Restart-Triggers-systemd-networkd> structuredAttrs is enabled building '/nix/store/grpggfa0iwmqznhkbaxdnkr5jb3p3njl-firewall-start.drv' on nix-store.geninf.io:50051 firewall-start> build-x86-01: building grpggfa0iwmqznhkbaxdnkr5jb3p3njl-firewall-start.drv firewall-start> structuredAttrs is enabled building '/nix/store/1lqp6fxk8h3wiw2nwzllpg10ccd4cqxc-unit-40-wg-star.netdev.drv' on nix-store.geninf.io:50051 unit-40-wg-star.netdev> build-x86-01: building 1lqp6fxk8h3wiw2nwzllpg10ccd4cqxc-unit-40-wg-star.netdev.drv building '/nix/store/wcjcy0jp73sb64zp2f23kmicsw5x1ppl-unit-40-wg-star.network.drv' on nix-store.geninf.io:50051 unit-40-wg-star.network> build-x86-01: building wcjcy0jp73sb64zp2f23kmicsw5x1ppl-unit-40-wg-star.network.drv unit-40-wg-star.netdev> structuredAttrs is enabled unit-40-wg-star.network> structuredAttrs is enabled building '/nix/store/k1wjxsckqkr1aj00n8vbw9267082fl1l-unit-dm-wg-star-wg-star-reconfig.path.drv' on nix-store.geninf.io:50051 unit-dm-wg-star-wg-star-reconfig.path> build-x86-01: building k1wjxsckqkr1aj00n8vbw9267082fl1l-unit-dm-wg-star-wg-star-reconfig.path.drv unit-dm-wg-star-wg-star-reconfig.path> structuredAttrs is enabled building '/nix/store/cah24mass60j0s0qh2h743dhp78ddahn-extra-hosts.drv' on nix-store.geninf.io:50051 extra-hosts> build-x86-01: building cah24mass60j0s0qh2h743dhp78ddahn-extra-hosts.drv building '/nix/store/pnvjfd6dd6jlx1mphjfy86ag2yh5f297-unit-script-dm-wg-star-wg-star-reconfig-start.drv' on nix-store.geninf.io:50051 unit-script-dm-wg-star-wg-star-reconfig-start> build-x86-01: building pnvjfd6dd6jlx1mphjfy86ag2yh5f297-unit-script-dm-wg-star-wg-star-reconfig-start.drv building '/nix/store/y5l4l76zzfalypxf9klr56r0xjsgxl4h-unit-dm-wg-star-wg-star-publish.timer.drv' on nix-store.geninf.io:50051 unit-dm-wg-star-wg-star-publish.timer> build-x86-01: building y5l4l76zzfalypxf9klr56r0xjsgxl4h-unit-dm-wg-star-wg-star-publish.timer.drv building '/nix/store/35f2739all4j36843rhdar8vfysf46xp-unit-40-wg-star.network.drv' on nix-store.geninf.io:50051 unit-40-wg-star.network> build-x86-01: building 35f2739all4j36843rhdar8vfysf46xp-unit-40-wg-star.network.drv extra-hosts> structuredAttrs is enabled unit-script-dm-wg-star-wg-star-reconfig-start> structuredAttrs is enabled unit-dm-wg-star-wg-star-publish.timer> structuredAttrs is enabled unit-40-wg-star.network> structuredAttrs is enabled building '/nix/store/zdx62linvrsv0xgiqxdw4277sisgdhr0-test-script.drv' on nix-store.geninf.io:50051 test-script> build-x86-01: building zdx62linvrsv0xgiqxdw4277sisgdhr0-test-script.drv building '/nix/store/wyqf136l8wcxsq96z4s9lxrlk5iy7w1r-unit-script-dm-wg-star-wg-star-publish-start.drv' on nix-store.geninf.io:50051 unit-script-dm-wg-star-wg-star-publish-start> build-x86-01: building wyqf136l8wcxsq96z4s9lxrlk5iy7w1r-unit-script-dm-wg-star-wg-star-publish-start.drv test-script> structuredAttrs is enabled unit-script-dm-wg-star-wg-star-publish-start> structuredAttrs is enabled building '/nix/store/0ih7wgbagxl5d1cajw0j1ji103nhb2ab-X-Restart-Triggers-systemd-networkd.drv' on nix-store.geninf.io:50051 X-Restart-Triggers-systemd-networkd> build-x86-01: building 0ih7wgbagxl5d1cajw0j1ji103nhb2ab-X-Restart-Triggers-systemd-networkd.drv X-Restart-Triggers-systemd-networkd> structuredAttrs is enabled building '/nix/store/6qnsbcfalh512byqqc1rx5yhnlfs9pm6-firewall-reload.drv' on nix-store.geninf.io:50051 firewall-reload> build-x86-01: building 6qnsbcfalh512byqqc1rx5yhnlfs9pm6-firewall-reload.drv firewall-reload> structuredAttrs is enabled building '/nix/store/2982565bviiqjbwp6i1bvn7f0hpyzdy6-vars-check-dm-wireguard-star.drv' on nix-store.geninf.io:50051 vars-check-dm-wireguard-star> gi-build-x86-02: building 2982565bviiqjbwp6i1bvn7f0hpyzdy6-vars-check-dm-wireguard-star.drv building '/nix/store/p5504llsbgv4ab28g5kisdnfq6rmz45r-unit-script-dm-wg-star-wg-star-reconfig-start.drv' on nix-store.geninf.io:50051 unit-script-dm-wg-star-wg-star-reconfig-start> gi-build-x86-02: building p5504llsbgv4ab28g5kisdnfq6rmz45r-unit-script-dm-wg-star-wg-star-reconfig-start.drv building '/nix/store/iy2swr7mk61pgzbjx4z2hzfa2b8p2pr7-unit-dm-wg-star-wg-star-reconfig.path.drv' on nix-store.geninf.io:50051 unit-dm-wg-star-wg-star-reconfig.path> gi-build-x86-02: building iy2swr7mk61pgzbjx4z2hzfa2b8p2pr7-unit-dm-wg-star-wg-star-reconfig.path.drv unit-script-dm-wg-star-wg-star-reconfig-start> structuredAttrs is enabled unit-dm-wg-star-wg-star-reconfig.path> structuredAttrs is enabled vars-check-dm-wireguard-star> Running vars check using Nix-based executor... vars-check-dm-wireguard-star> ✓ All vars checks completed successfully building '/nix/store/aby149g5bq0amyigcr1sdrv9kzfi3mvd-X-Reload-Triggers-systemd-networkd.drv' on nix-store.geninf.io:50051 X-Reload-Triggers-systemd-networkd> build-x86-01: building aby149g5bq0amyigcr1sdrv9kzfi3mvd-X-Reload-Triggers-systemd-networkd.drv X-Reload-Triggers-systemd-networkd> structuredAttrs is enabled building '/nix/store/qpf8raz6blvy717rd4vq961689zq7gmm-dm.toml.drv' on nix-store.geninf.io:50051 dm.toml> gi-build-x86-02: building qpf8raz6blvy717rd4vq961689zq7gmm-dm.toml.drv dm.toml> structuredAttrs is enabled building '/nix/store/vz37aclfjk6lfa417jrni4n84j3mydky-unit-script-dm-wg-star-wg-star-publish-start.drv' on nix-store.geninf.io:50051 unit-script-dm-wg-star-wg-star-publish-start> gi-build-x86-02: building vz37aclfjk6lfa417jrni4n84j3mydky-unit-script-dm-wg-star-wg-star-publish-start.drv building '/nix/store/wk1f460y6ipx99yfjj947rk8xg70y88v-dm.toml.drv' on nix-store.geninf.io:50051 dm.toml> gi-build-x86-02: building wk1f460y6ipx99yfjj947rk8xg70y88v-dm.toml.drv building '/nix/store/9gnv36vfa95qmqz29645kf788bsf9n4k-unit-40-wg-star.network.drv' on nix-store.geninf.io:50051 unit-40-wg-star.network> gi-build-x86-02: building 9gnv36vfa95qmqz29645kf788bsf9n4k-unit-40-wg-star.network.drv building '/nix/store/xfvc9s96l6vhghvcvcbpvddi08kk4ki2-unit-script-dm-wg-star-wg-star-publish-start.drv' on nix-store.geninf.io:50051 unit-script-dm-wg-star-wg-star-publish-start> gi-build-x86-02: building xfvc9s96l6vhghvcvcbpvddi08kk4ki2-unit-script-dm-wg-star-wg-star-publish-start.drv unit-script-dm-wg-star-wg-star-publish-start> structuredAttrs is enabled unit-40-wg-star.network> structuredAttrs is enabled unit-script-dm-wg-star-wg-star-publish-start> structuredAttrs is enabled building '/nix/store/4y03cm11cqk2zvr68idms4j4xn80qzx9-hosts.drv' on nix-store.geninf.io:50051 hosts> build-x86-01: building 4y03cm11cqk2zvr68idms4j4xn80qzx9-hosts.drv building '/nix/store/k4a6ajvq6fbfg112my4cc1dl8pflsdzv-X-Reload-Triggers-systemd-networkd.drv' on nix-store.geninf.io:50051 X-Reload-Triggers-systemd-networkd> build-x86-01: building k4a6ajvq6fbfg112my4cc1dl8pflsdzv-X-Reload-Triggers-systemd-networkd.drv X-Reload-Triggers-systemd-networkd> structuredAttrs is enabled building '/nix/store/yciymvmjyd7y1d9lm3xbskgwiwkijvrc-hosts.drv' on nix-store.geninf.io:50051 hosts> build-x86-01: building yciymvmjyd7y1d9lm3xbskgwiwkijvrc-hosts.drv dm.toml> structuredAttrs is enabled building '/nix/store/fa5pvy2swa71cj157z6p6p97lrplm6l5-dm.toml.drv' on nix-store.geninf.io:50051 dm.toml> gi-build-x86-02: building fa5pvy2swa71cj157z6p6p97lrplm6l5-dm.toml.drv dm.toml> structuredAttrs is enabled building '/nix/store/mln92a993ayw6fckdnpz5rjj7lhgs8lr-hosts.drv' on nix-store.geninf.io:50051 hosts> build-x86-01: building mln92a993ayw6fckdnpz5rjj7lhgs8lr-hosts.drv building '/nix/store/v71yxl8pzdz2jr41i698d8yqywfj2b1v-system-path.drv' on nix-store.geninf.io:50051 system-path> build-x86-01: building v71yxl8pzdz2jr41i698d8yqywfj2b1v-system-path.drv system-path> structuredAttrs is enabled system-path> created 1729 symlinks in user environment building '/nix/store/q08y2yk1aackjymaz62ws59h9j5ignpl-unit-dm-wg-star-wg-star-reconfig.service.drv' on nix-store.geninf.io:50051 unit-dm-wg-star-wg-star-reconfig.service> build-x86-01: building q08y2yk1aackjymaz62ws59h9j5ignpl-unit-dm-wg-star-wg-star-reconfig.service.drv unit-dm-wg-star-wg-star-reconfig.service> structuredAttrs is enabled building '/nix/store/kmnxwqhwg7qcljyhi072p1m6vg2c58vb-decrypt-age-secrets.drv' on nix-store.geninf.io:50051 decrypt-age-secrets> build-x86-01: building kmnxwqhwg7qcljyhi072p1m6vg2c58vb-decrypt-age-secrets.drv building '/nix/store/klwaba22a99fbichi0cyw5i7s1iih4ws-decrypt-age-secrets.drv' on nix-store.geninf.io:50051 decrypt-age-secrets> gi-build-x86-02: building klwaba22a99fbichi0cyw5i7s1iih4ws-decrypt-age-secrets.drv decrypt-age-secrets> structuredAttrs is enabled building '/nix/store/74b0map95f09dqrj3997kgxdvlciyfdi-unit-dm-wg-star-wg-star-publish.service.drv' on nix-store.geninf.io:50051 unit-dm-wg-star-wg-star-publish.service> build-x86-01: building 74b0map95f09dqrj3997kgxdvlciyfdi-unit-dm-wg-star-wg-star-publish.service.drv unit-dm-wg-star-wg-star-publish.service> structuredAttrs is enabled decrypt-age-secrets> structuredAttrs is enabled building '/nix/store/amhbbvrphhbgjc8d8dga892l8nygm1nl-decrypt-age-secrets.drv' on nix-store.geninf.io:50051 decrypt-age-secrets> gi-build-x86-02: building amhbbvrphhbgjc8d8dga892l8nygm1nl-decrypt-age-secrets.drv decrypt-age-secrets> structuredAttrs is enabled building '/nix/store/8wh3rm2rn8jv8ylvr1claqj3m59p8rbj-unit-firewall.service.drv' on nix-store.geninf.io:50051 unit-firewall.service> build-x86-01: building 8wh3rm2rn8jv8ylvr1claqj3m59p8rbj-unit-firewall.service.drv unit-firewall.service> structuredAttrs is enabled building '/nix/store/qimcdy6zn7jxhdxhafgd3pmkzsiz8z9m-unit-dm-wg-star-wg-star-reconfig.service.drv' on nix-store.geninf.io:50051 unit-dm-wg-star-wg-star-reconfig.service> gi-build-x86-02: building qimcdy6zn7jxhdxhafgd3pmkzsiz8z9m-unit-dm-wg-star-wg-star-reconfig.service.drv building '/nix/store/7q27b7f6wfhqdqs6c9wdg3h2fci4alwh-X-Restart-Triggers-data-mesher.drv' on nix-store.geninf.io:50051 X-Restart-Triggers-data-mesher> gi-build-x86-02: building 7q27b7f6wfhqdqs6c9wdg3h2fci4alwh-X-Restart-Triggers-data-mesher.drv unit-dm-wg-star-wg-star-reconfig.service> structuredAttrs is enabled X-Restart-Triggers-data-mesher> structuredAttrs is enabled building '/nix/store/ipswq6d20a0d8npd8g6v8xcakkzn6bf1-unit-systemd-networkd.service.drv' on nix-store.geninf.io:50051 unit-systemd-networkd.service> build-x86-01: building ipswq6d20a0d8npd8g6v8xcakkzn6bf1-unit-systemd-networkd.service.drv unit-systemd-networkd.service> structuredAttrs is enabled building '/nix/store/gimva3fhh0c8dz8aspniaarc2xv0ykrc-X-Reload-Triggers-systemd-networkd.drv' on nix-store.geninf.io:50051 X-Reload-Triggers-systemd-networkd> gi-build-x86-02: building gimva3fhh0c8dz8aspniaarc2xv0ykrc-X-Reload-Triggers-systemd-networkd.drv X-Reload-Triggers-systemd-networkd> structuredAttrs is enabled building '/nix/store/apca6m8bs4djhm9bin3jm77vhl1xl4qg-unit-dm-wg-star-wg-star-publish.service.drv' on nix-store.geninf.io:50051 unit-dm-wg-star-wg-star-publish.service> gi-build-x86-02: building apca6m8bs4djhm9bin3jm77vhl1xl4qg-unit-dm-wg-star-wg-star-publish.service.drv building '/nix/store/ybinyjr63f57xqnfk6yjn0dbmkmcq7n9-unit-systemd-networkd.service.drv' on nix-store.geninf.io:50051 unit-systemd-networkd.service> build-x86-01: building ybinyjr63f57xqnfk6yjn0dbmkmcq7n9-unit-systemd-networkd.service.drv unit-systemd-networkd.service> structuredAttrs is enabled unit-dm-wg-star-wg-star-publish.service> structuredAttrs is enabled building '/nix/store/7c5x0pls9s9kvk32chrwkppd584lk5y5-X-Restart-Triggers-data-mesher.drv' on nix-store.geninf.io:50051 X-Restart-Triggers-data-mesher> gi-build-x86-02: building 7c5x0pls9s9kvk32chrwkppd584lk5y5-X-Restart-Triggers-data-mesher.drv X-Restart-Triggers-data-mesher> structuredAttrs is enabled building '/nix/store/kgpjrl23vzciihn7ryp814xn625f0r82-unit-dm-wg-star-wg-star-publish.service.drv' on nix-store.geninf.io:50051 unit-dm-wg-star-wg-star-publish.service> gi-build-x86-02: building kgpjrl23vzciihn7ryp814xn625f0r82-unit-dm-wg-star-wg-star-publish.service.drv building '/nix/store/z1vmp6ld5sz66qy3ygafgk5vkzyah5ij-X-Restart-Triggers-data-mesher.drv' on nix-store.geninf.io:50051 X-Restart-Triggers-data-mesher> build-x86-01: building z1vmp6ld5sz66qy3ygafgk5vkzyah5ij-X-Restart-Triggers-data-mesher.drv X-Restart-Triggers-data-mesher> structuredAttrs is enabled unit-dm-wg-star-wg-star-publish.service> structuredAttrs is enabled building '/nix/store/c7pl1c2yy74qi3g0nd25mr53i5am979f-dbus-1.drv' on nix-store.geninf.io:50051 dbus-1> build-x86-01: building c7pl1c2yy74qi3g0nd25mr53i5am979f-dbus-1.drv building '/nix/store/jvwr243fa3xs3gi3zvwa7hr3ladpq3rh-dry-activate.drv' on nix-store.geninf.io:50051 dry-activate> build-x86-01: building jvwr243fa3xs3gi3zvwa7hr3ladpq3rh-dry-activate.drv dry-activate> structuredAttrs is enabled building '/nix/store/i7a3sw5anyg7ij735ysxsl9skrh2ygjk-dry-activate.drv' on nix-store.geninf.io:50051 dry-activate> build-x86-01: building i7a3sw5anyg7ij735ysxsl9skrh2ygjk-dry-activate.drv dry-activate> structuredAttrs is enabled building '/nix/store/2jik4m3dr1xbf6nylgnkq629ds8rfs6z-dry-activate.drv' on nix-store.geninf.io:50051 dry-activate> gi-build-x86-02: building 2jik4m3dr1xbf6nylgnkq629ds8rfs6z-dry-activate.drv dry-activate> structuredAttrs is enabled building '/nix/store/b09qqyqaln6i5i13rf6yh797f1yksmng-unit-data-mesher.service.drv' on nix-store.geninf.io:50051 unit-data-mesher.service> gi-build-x86-02: building b09qqyqaln6i5i13rf6yh797f1yksmng-unit-data-mesher.service.drv unit-data-mesher.service> structuredAttrs is enabled building '/nix/store/p7123k2vd1jf6f3zkbh1mm086b3lrh24-unit-systemd-networkd.service.drv' on nix-store.geninf.io:50051 unit-systemd-networkd.service> gi-build-x86-02: building p7123k2vd1jf6f3zkbh1mm086b3lrh24-unit-systemd-networkd.service.drv unit-systemd-networkd.service> structuredAttrs is enabled building '/nix/store/x5kha98ahvlppwmn2g9znj68sl7mz30x-unit-data-mesher.service.drv' on nix-store.geninf.io:50051 unit-data-mesher.service> gi-build-x86-02: building x5kha98ahvlppwmn2g9znj68sl7mz30x-unit-data-mesher.service.drv unit-data-mesher.service> structuredAttrs is enabled building '/nix/store/n5a9z3dhhgsnw01qdhm6g84sqyxx63cp-unit-data-mesher.service.drv' on nix-store.geninf.io:50051 unit-data-mesher.service> gi-build-x86-02: building n5a9z3dhhgsnw01qdhm6g84sqyxx63cp-unit-data-mesher.service.drv unit-data-mesher.service> structuredAttrs is enabled building '/nix/store/jvhjqgdwq219yi2y37ibnp47lalbarl8-X-Restart-Triggers-dbus-broker.drv' on nix-store.geninf.io:50051 X-Restart-Triggers-dbus-broker> build-x86-01: building jvhjqgdwq219yi2y37ibnp47lalbarl8-X-Restart-Triggers-dbus-broker.drv X-Restart-Triggers-dbus-broker> structuredAttrs is enabled building '/nix/store/j4lp7zsx1srlqpaw0q2pc915p35iqf7m-unit-dbus-broker.service.drv' on nix-store.geninf.io:50051 unit-dbus-broker.service> build-x86-01: building j4lp7zsx1srlqpaw0q2pc915p35iqf7m-unit-dbus-broker.service.drv unit-dbus-broker.service> structuredAttrs is enabled building '/nix/store/6qlx86n8n0wnac0y5v440ii4gkk1h8ch-unit-dbus-broker.service.drv' on nix-store.geninf.io:50051 unit-dbus-broker.service> build-x86-01: building 6qlx86n8n0wnac0y5v440ii4gkk1h8ch-unit-dbus-broker.service.drv unit-dbus-broker.service> structuredAttrs is enabled building '/nix/store/aikvgs8k6b7b5zfv37hyxgisvhb42z2c-user-units.drv' on nix-store.geninf.io:50051 user-units> gi-build-x86-02: building aikvgs8k6b7b5zfv37hyxgisvhb42z2c-user-units.drv building '/nix/store/vq3yl4651247sl9ck3smihi53hmiyb13-system-units.drv' on nix-store.geninf.io:50051 system-units> build-x86-01: building vq3yl4651247sl9ck3smihi53hmiyb13-system-units.drv building '/nix/store/ykbcwipwfk1y4w9hrsl63dnlj2kmysxh-system-units.drv' on nix-store.geninf.io:50051 system-units> gi-build-x86-02: building ykbcwipwfk1y4w9hrsl63dnlj2kmysxh-system-units.drv building '/nix/store/x0ikrsi9g5xyglgjlgdwffnrqvimaivz-system-units.drv' on nix-store.geninf.io:50051 system-units> build-x86-01: building x0ikrsi9g5xyglgjlgdwffnrqvimaivz-system-units.drv building '/nix/store/7qbc3x99qz0n1b859ysjwrmnk551qxrd-etc.drv' on nix-store.geninf.io:50051 etc> gi-build-x86-02: building 7qbc3x99qz0n1b859ysjwrmnk551qxrd-etc.drv building '/nix/store/b8rxcq3pv13igadc9r6fdicb4sp6fas8-etc.drv' on nix-store.geninf.io:50051 etc> build-x86-01: building b8rxcq3pv13igadc9r6fdicb4sp6fas8-etc.drv building '/nix/store/nv2xisqplydv625ylg3j9d25h143j9m8-etc.drv' on nix-store.geninf.io:50051 etc> gi-build-x86-02: building nv2xisqplydv625ylg3j9d25h143j9m8-etc.drv building '/nix/store/h1y3xnk92p4imfp33ddq4pljb6banm9c-activate.drv' on nix-store.geninf.io:50051 activate> gi-build-x86-02: building h1y3xnk92p4imfp33ddq4pljb6banm9c-activate.drv activate> structuredAttrs is enabled building '/nix/store/3r1kwlbrmkz69lw0k84lm55dj4x5xfqb-activate.drv' on nix-store.geninf.io:50051 activate> gi-build-x86-02: building 3r1kwlbrmkz69lw0k84lm55dj4x5xfqb-activate.drv activate> structuredAttrs is enabled building '/nix/store/irmwli5qbhjbv37baayy9jxwqfdawv36-activate.drv' on nix-store.geninf.io:50051 activate> build-x86-01: building irmwli5qbhjbv37baayy9jxwqfdawv36-activate.drv activate> structuredAttrs is enabled building '/nix/store/yv9f1mp1gs428xji0kyvxyk8imcyv1kq-nixos-system-peer2-test.drv' on nix-store.geninf.io:50051 nixos-system-peer2-test> gi-build-x86-02: building yv9f1mp1gs428xji0kyvxyk8imcyv1kq-nixos-system-peer2-test.drv nixos-system-peer2-test> structuredAttrs is enabled building '/nix/store/fwsril4d7rsw7xv0va1l6jsn9f2j9h59-nixos-system-controller-test.drv' on nix-store.geninf.io:50051 nixos-system-controller-test> gi-build-x86-02: building fwsril4d7rsw7xv0va1l6jsn9f2j9h59-nixos-system-controller-test.drv nixos-system-controller-test> structuredAttrs is enabled building '/nix/store/jnnv8cm6aqjmzi68k9rscy7kyjiz2k2i-nixos-system-peer1-test.drv' on nix-store.geninf.io:50051 nixos-system-peer1-test> build-x86-01: building jnnv8cm6aqjmzi68k9rscy7kyjiz2k2i-nixos-system-peer1-test.drv nixos-system-peer1-test> structuredAttrs is enabled building '/nix/store/9n6rym4hvb3kb054qvjc4akzg9m2gzc0-run-peer2-nspawn.drv' on nix-store.geninf.io:50051 run-peer2-nspawn> gi-build-x86-02: building 9n6rym4hvb3kb054qvjc4akzg9m2gzc0-run-peer2-nspawn.drv building '/nix/store/iaxj410phi28b312bzmwzr8y9bfdm1pl-run-peer1-nspawn.drv' on nix-store.geninf.io:50051 run-peer1-nspawn> build-x86-01: building iaxj410phi28b312bzmwzr8y9bfdm1pl-run-peer1-nspawn.drv building '/nix/store/2mpd5skr7665g9hwvf2sfw38b75v1kcs-run-controller-nspawn.drv' on nix-store.geninf.io:50051 run-controller-nspawn> gi-build-x86-02: building 2mpd5skr7665g9hwvf2sfw38b75v1kcs-run-controller-nspawn.drv building '/nix/store/67s7qybpia86yyf3f9b4yawna8b9b8y5-driverConfiguration.json.drv' on nix-store.geninf.io:50051 driverConfiguration.json> build-x86-01: building 67s7qybpia86yyf3f9b4yawna8b9b8y5-driverConfiguration.json.drv driverConfiguration.json> structuredAttrs is enabled building '/nix/store/jbavbh19pnn2bmal67yp152ip8ipnlr9-nixos-test-driver-dm-wireguard-star.drv' on nix-store.geninf.io:50051 nixos-test-driver-dm-wireguard-star> build-x86-01: building jbavbh19pnn2bmal67yp152ip8ipnlr9-nixos-test-driver-dm-wireguard-star.drv nixos-test-driver-dm-wireguard-star> Running type check (enable/disable: config.skipTypeCheck) nixos-test-driver-dm-wireguard-star> See https://nixos.org/manual/nixos/stable/#test-opt-skipTypeCheck nixos-test-driver-dm-wireguard-star> All checks passed! nixos-test-driver-dm-wireguard-star> Linting test script (enable/disable: config.skipLint) nixos-test-driver-dm-wireguard-star> See https://nixos.org/manual/nixos/stable/#test-opt-skipLint nixos-test-driver-dm-wireguard-star> All checks passed! building '/nix/store/lfwy9b3rcy65ss4j0i0zjq5plw98bh7z-container-test-run-dm-wireguard-star.drv' on nix-store.geninf.io:50051 container-test-run-dm-wireguard-star> build-x86-01: building lfwy9b3rcy65ss4j0i0zjq5plw98bh7z-container-test-run-dm-wireguard-star.drv container-test-run-dm-wireguard-star> Machine state will be reset. To keep it, pass --keep-machine-state container-test-run-dm-wireguard-star> start all VLans container-test-run-dm-wireguard-star> (finished: start all VLans, in 0.00 seconds) container-test-run-dm-wireguard-star> container-test-run-dm-wireguard-star> Test will time out and terminate in 3600.0 seconds container-test-run-dm-wireguard-star> run the VM test script container-test-run-dm-wireguard-star> additionally exposed symbols: container-test-run-dm-wireguard-star> controller, peer1, peer2, container-test-run-dm-wireguard-star> vlan1, container-test-run-dm-wireguard-star> 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-dm-wireguard-star> controller: systemd-nspawn running (pid 50) container-test-run-dm-wireguard-star> controller: Waiting for journal at /build/vm-state-controller/var/log/journal... container-test-run-dm-wireguard-star> peer1: systemd-nspawn running (pid 53) container-test-run-dm-wireguard-star> peer1: Waiting for journal at /build/vm-state-peer1/var/log/journal... container-test-run-dm-wireguard-star> controller: waiting for unit data-mesher.service container-test-run-dm-wireguard-star> nixos-nspawn(controller): TAP vde-tap1 not found; container will be isolated from VDE container-test-run-dm-wireguard-star> nixos-nspawn(controller): 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-dm-wireguard-star> nixos-nspawn(peer1): TAP vde-tap1 not found; container will be isolated from VDE container-test-run-dm-wireguard-star> 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-dm-wireguard-star> 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-dm-wireguard-star> 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-dm-wireguard-star> ░ Spawning container controller on /build/vm-state-controller. container-test-run-dm-wireguard-star> ░ Spawning container peer1 on /build/vm-state-peer1. container-test-run-dm-wireguard-star> controller # [2274435.775425] controller systemd-journald[108]: Journal started container-test-run-dm-wireguard-star> controller # [2274435.775451] controller systemd-journald[108]: Runtime Journal (/run/log/journal/129e75dfd9db43259cfd09a8284a2e80) is 8M, max 3.7G, 3.7G free. container-test-run-dm-wireguard-star> peer1 # [2274435.775418] peer1 systemd-journald[99]: Journal started container-test-run-dm-wireguard-star> peer1 # [2274435.775445] peer1 systemd-journald[99]: Runtime Journal (/run/log/journal/6c8c57b385c24ece82fd81b8cdc67687) is 8M, max 3.7G, 3.7G free. container-test-run-dm-wireguard-star> controller # [2274435.780165] controller systemd[1]: Finished Apply Kernel Variables. container-test-run-dm-wireguard-star> controller # [2274435.788201] controller systemd[1]: Finished Create Static Device Nodes in /dev gracefully. container-test-run-dm-wireguard-star> controller # [2274435.813153] controller systemd[1]: Starting Flush Journal to Persistent Storage... container-test-run-dm-wireguard-star> peer1 # [2274435.780153] peer1 systemd[1]: Finished Apply Kernel Variables. container-test-run-dm-wireguard-star> controller # [2274435.813546] controller systemd[1]: Starting Network Name Resolution... container-test-run-dm-wireguard-star> peer1 # [2274435.786656] peer1 systemd[1]: Finished Create Static Device Nodes in /dev gracefully. container-test-run-dm-wireguard-star> controller # [2274435.813809] controller systemd[1]: Starting Create Static Device Nodes in /dev... container-test-run-dm-wireguard-star> peer1 # [2274435.794402] peer1 systemd[1]: Starting Flush Journal to Persistent Storage... container-test-run-dm-wireguard-star> peer1 # [2274435.794753] peer1 systemd[1]: Starting Network Name Resolution... container-test-run-dm-wireguard-star> peer1 # [2274435.795037] peer1 systemd[1]: Starting Create Static Device Nodes in /dev... container-test-run-dm-wireguard-star> peer1 # [2274435.816414] peer1 systemd-journald[99]: Time spent on flushing to /var/log/journal/6c8c57b385c24ece82fd81b8cdc67687 is 918us for 7 entries. container-test-run-dm-wireguard-star> peer1 # [2274435.816414] peer1 systemd-journald[99]: System Journal (/var/log/journal/6c8c57b385c24ece82fd81b8cdc67687) is 8M, max 4G, 3.9G free. container-test-run-dm-wireguard-star> controller # [2274435.818284] controller systemd-journald[108]: Time spent on flushing to /var/log/journal/129e75dfd9db43259cfd09a8284a2e80 is 908us for 7 entries. container-test-run-dm-wireguard-star> controller # [2274435.818284] controller systemd-journald[108]: System Journal (/var/log/journal/129e75dfd9db43259cfd09a8284a2e80) is 8M, max 4G, 3.9G free. container-test-run-dm-wireguard-star> peer1 # [2274435.830512] peer1 systemd[1]: Finished Flush Journal to Persistent Storage. container-test-run-dm-wireguard-star> controller # [2274435.832321] controller systemd[1]: Finished Flush Journal to Persistent Storage. container-test-run-dm-wireguard-star> peer1 # [2274435.830707] peer1 systemd[1]: Finished Create Static Device Nodes in /dev. container-test-run-dm-wireguard-star> controller # [2274435.832462] controller systemd[1]: Finished Create Static Device Nodes in /dev. container-test-run-dm-wireguard-star> peer1 # [2274435.831251] peer1 systemd[1]: Reached target Preparation for Local File Systems. container-test-run-dm-wireguard-star> peer1 # [2274435.831293] peer1 systemd[1]: Reached target Local File Systems. container-test-run-dm-wireguard-star> controller # [2274435.833161] controller systemd[1]: Reached target Preparation for Local File Systems. container-test-run-dm-wireguard-star> peer1 # [2274435.831686] peer1 systemd[1]: Listening on Boot Loader Control Service Socket. container-test-run-dm-wireguard-star> peer1 # [2274435.831714] peer1 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container container-test-run-dm-wireguard-star> controller # [2274435.833207] controller systemd[1]: Reached target Local File Systems. container-test-run-dm-wireguard-star> peer1 # [2274435.832038] peer1 systemd[1]: Starting Save Transient machine-id to Disk... container-test-run-dm-wireguard-star> controller # [2274435.833620] controller systemd[1]: Listening on Boot Loader Control Service Socket. container-test-run-dm-wireguard-star> peer1 # [2274435.832325] peer1 systemd[1]: Starting Create System Files and Directories... container-test-run-dm-wireguard-star> controller # [2274435.833645] controller systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container container-test-run-dm-wireguard-star> peer1 # [2274435.832341] peer1 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys container-test-run-dm-wireguard-star> controller # [2274435.834035] controller systemd[1]: Starting Save Transient machine-id to Disk... container-test-run-dm-wireguard-star> controller # [2274435.834324] controller systemd[1]: Starting Create System Files and Directories... container-test-run-dm-wireguard-star> controller # [2274435.834337] controller systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys container-test-run-dm-wireguard-star> peer1 # [2274435.845680] peer1 systemd-tmpfiles[175]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted container-test-run-dm-wireguard-star> controller # [2274435.845929] controller systemd-tmpfiles[187]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted container-test-run-dm-wireguard-star> peer1 # [2274435.845832] peer1 systemd-tmpfiles[175]: fchmod() of /var/log/journal failed: Operation not permitted container-test-run-dm-wireguard-star> controller # [2274435.846076] controller systemd-tmpfiles[187]: fchmod() of /var/log/journal failed: Operation not permitted container-test-run-dm-wireguard-star> peer1 # [2274435.845932] peer1 systemd-tmpfiles[175]: fchmod() of /var/log/journal/6c8c57b385c24ece82fd81b8cdc67687 failed: Operation not permitted container-test-run-dm-wireguard-star> peer1 # [2274435.846090] peer1 systemd-tmpfiles[175]: fchmod() of /run/log/journal failed: Operation not permitted container-test-run-dm-wireguard-star> controller # [2274435.846172] controller systemd-tmpfiles[187]: fchmod() of /var/log/journal/129e75dfd9db43259cfd09a8284a2e80 failed: Operation not permitted container-test-run-dm-wireguard-star> peer1 # [2274435.848164] peer1 systemd[1]: Finished Create System Files and Directories. container-test-run-dm-wireguard-star> controller # [2274435.846324] controller systemd-tmpfiles[187]: fchmod() of /run/log/journal failed: Operation not permitted container-test-run-dm-wireguard-star> peer1 # [2274435.848758] peer1 systemd[1]: Starting Rebuild Journal Catalog... container-test-run-dm-wireguard-star> controller # [2274435.848203] controller systemd[1]: Finished Create System Files and Directories. container-test-run-dm-wireguard-star> peer1 # [2274435.849319] peer1 systemd[1]: Starting Record System Boot/Shutdown in UTMP... container-test-run-dm-wireguard-star> controller # [2274435.849080] controller systemd[1]: Starting Rebuild Journal Catalog... container-test-run-dm-wireguard-star> peer1 # [2274435.850408] peer1 systemd[1]: Finished Save Transient machine-id to Disk. container-test-run-dm-wireguard-star> controller # [2274435.849549] controller systemd[1]: Starting Record System Boot/Shutdown in UTMP... container-test-run-dm-wireguard-star> peer1 # [2274435.856773] peer1 systemd[1]: Finished Record System Boot/Shutdown in UTMP. container-test-run-dm-wireguard-star> controller # [2274435.851256] controller systemd[1]: Finished Save Transient machine-id to Disk. container-test-run-dm-wireguard-star> peer1 # [2274435.865232] peer1 systemd[1]: Finished Rebuild Journal Catalog. container-test-run-dm-wireguard-star> controller # [2274435.856736] controller systemd[1]: Finished Record System Boot/Shutdown in UTMP. container-test-run-dm-wireguard-star> peer1 # [2274435.865906] peer1 systemd[1]: Starting Update is Completed... container-test-run-dm-wireguard-star> controller # [2274435.865605] controller systemd[1]: Finished Rebuild Journal Catalog. container-test-run-dm-wireguard-star> peer1 # [2274435.866991] peer1 systemd[1]: Finished Firewall. container-test-run-dm-wireguard-star> controller # [2274435.866085] controller systemd[1]: Starting Update is Completed... container-test-run-dm-wireguard-star> peer1 # [2274435.867063] peer1 systemd[1]: Reached target Preparation for Network. container-test-run-dm-wireguard-star> peer1 # [2274435.867201] peer1 systemd[1]: Listening on Network Management Resolve Hook Socket. container-test-run-dm-wireguard-star> controller # [2274435.868529] controller systemd[1]: Finished Firewall. container-test-run-dm-wireguard-star> controller # [2274435.868600] controller systemd[1]: Reached target Preparation for Network. container-test-run-dm-wireguard-star> peer1 # [2274435.867663] peer1 systemd[1]: Starting Network Management... container-test-run-dm-wireguard-star> controller # [2274435.868741] controller systemd[1]: Listening on Network Management Resolve Hook Socket. container-test-run-dm-wireguard-star> peer1 # [2274435.870791] peer1 systemd[1]: Finished Update is Completed. container-test-run-dm-wireguard-star> controller # [2274435.869227] controller systemd[1]: Starting Network Management... container-test-run-dm-wireguard-star> controller # [2274435.871919] controller systemd[1]: Finished Update is Completed. container-test-run-dm-wireguard-star> peer1 # [2274436.320006] peer1 systemd-networkd[216]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted container-test-run-dm-wireguard-star> peer1 # [2274436.320075] peer1 systemd-networkd[216]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted container-test-run-dm-wireguard-star> peer1 # [2274436.327942] peer1 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-dm-wireguard-star> peer1 # [2274436.328093] peer1 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-dm-wireguard-star> peer1 # [2274436.328175] peer1 systemd-networkd[216]: lo: Link UP container-test-run-dm-wireguard-star> peer1 # [2274436.328178] peer1 systemd-networkd[216]: lo: Gained carrier container-test-run-dm-wireguard-star> peer1 # [2274436.328311] peer1 systemd-networkd[216]: eth1: Configuring with /etc/systemd/network/40-eth1.network. container-test-run-dm-wireguard-star> peer1 # [2274436.328560] peer1 systemd[1]: Started Network Management. container-test-run-dm-wireguard-star> peer1 # [2274436.329390] peer1 systemd[1]: Starting Enable Persistent Storage in systemd-networkd... container-test-run-dm-wireguard-star> peer1 # [2274436.330348] peer1 systemd-networkd[216]: wg-star: Configuring with /etc/systemd/network/40-wg-star.network. container-test-run-dm-wireguard-star> peer1 # [2274436.330549] peer1 systemd-networkd[216]: wg-star: netdev ready container-test-run-dm-wireguard-star> peer1 # [2274436.330692] peer1 systemd-networkd[216]: eth1: Link UP container-test-run-dm-wireguard-star> peer1 # [2274436.330828] peer1 systemd-networkd[216]: eth1: Gained carrier container-test-run-dm-wireguard-star> peer1 # [2274436.349351] peer1 systemd-networkd[216]: wg-star: Link UP container-test-run-dm-wireguard-star> peer1 # [2274436.349354] peer1 systemd-networkd[216]: wg-star: Gained carrier container-test-run-dm-wireguard-star> peer1 # [2274436.352158] peer1 systemd[1]: Finished Enable Persistent Storage in systemd-networkd. container-test-run-dm-wireguard-star> controller # [2274436.323841] controller systemd-networkd[227]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted container-test-run-dm-wireguard-star> controller # [2274436.323902] controller systemd-networkd[227]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted container-test-run-dm-wireguard-star> controller # [2274436.332116] controller systemd-networkd[227]: /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-dm-wireguard-star> controller # [2274436.332255] controller systemd-networkd[227]: /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-dm-wireguard-star> controller # [2274436.332311] controller systemd-networkd[227]: lo: Link UP container-test-run-dm-wireguard-star> controller # [2274436.332314] controller systemd-networkd[227]: lo: Gained carrier container-test-run-dm-wireguard-star> controller # [2274436.332421] controller systemd-networkd[227]: eth1: Configuring with /etc/systemd/network/40-eth1.network. container-test-run-dm-wireguard-star> controller # [2274436.332653] controller systemd[1]: Started Network Management. container-test-run-dm-wireguard-star> controller # [2274436.347180] controller systemd[1]: Starting Enable Persistent Storage in systemd-networkd... container-test-run-dm-wireguard-star> controller # [2274436.347393] controller systemd-networkd[227]: wg-star: Configuring with /etc/systemd/network/40-wg-star.network. container-test-run-dm-wireguard-star> controller # [2274436.347550] controller systemd-networkd[227]: wg-star: netdev ready container-test-run-dm-wireguard-star> controller # [2274436.347642] controller systemd-networkd[227]: eth1: Link UP container-test-run-dm-wireguard-star> controller # [2274436.347743] controller systemd-networkd[227]: eth1: Gained carrier container-test-run-dm-wireguard-star> controller # [2274436.364589] controller systemd-networkd[227]: wg-star: Link UP container-test-run-dm-wireguard-star> controller # [2274436.364593] controller systemd-networkd[227]: wg-star: Gained carrier container-test-run-dm-wireguard-star> controller # [2274436.365350] controller systemd[1]: Finished Enable Persistent Storage in systemd-networkd. container-test-run-dm-wireguard-star> controller # [2274436.668492] controller systemd-resolved[160]: Positive Trust Anchors: container-test-run-dm-wireguard-star> controller # [2274436.668501] controller systemd-resolved[160]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d container-test-run-dm-wireguard-star> controller # [2274436.668503] controller systemd-resolved[160]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 container-test-run-dm-wireguard-star> controller # [2274436.668519] controller systemd-resolved[160]: 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-dm-wireguard-star> controller # [2274436.678663] controller systemd-resolved[160]: Using system hostname 'controller'. container-test-run-dm-wireguard-star> controller # [2274436.679732] controller systemd[1]: Started Network Name Resolution. container-test-run-dm-wireguard-star> controller # [2274436.679788] controller systemd[1]: Reached target Network. container-test-run-dm-wireguard-star> controller # [2274436.679831] controller systemd[1]: Reached target System Initialization. container-test-run-dm-wireguard-star> controller # [2274436.679894] controller systemd[1]: Started Watch for WireGuard peer changes from data-mesher. container-test-run-dm-wireguard-star> controller # [2274436.679910] controller systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container container-test-run-dm-wireguard-star> controller # [2274436.679926] controller systemd[1]: Started Daily Cleanup of Temporary Directories. container-test-run-dm-wireguard-star> controller # [2274436.679937] controller systemd[1]: Reached target Path Units. container-test-run-dm-wireguard-star> controller # [2274436.679960] controller systemd[1]: Reached target Timer Units. container-test-run-dm-wireguard-star> controller # [2274436.680055] controller systemd[1]: Listening on D-Bus System Message Bus Socket. container-test-run-dm-wireguard-star> controller # [2274436.680129] controller systemd[1]: Listening on Nix Daemon Socket. container-test-run-dm-wireguard-star> controller # [2274436.680197] controller systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. container-test-run-dm-wireguard-star> controller # [2274436.680216] controller systemd[1]: Reached target Socket Units. container-test-run-dm-wireguard-star> controller # [2274436.680239] controller systemd[1]: Reached target Basic System. container-test-run-dm-wireguard-star> controller # [2274436.681007] controller systemd[1]: Starting data mesher daemon... container-test-run-dm-wireguard-star> controller # [2274436.681363] controller systemd[1]: Starting Import lastlog data into lastlog2 database... container-test-run-dm-wireguard-star> controller # [2274436.681743] controller systemd[1]: Starting Name Service Cache Daemon (nsncd)... container-test-run-dm-wireguard-star> controller # [2274436.682336] controller systemd[1]: Starting D-Bus System Message Bus... container-test-run-dm-wireguard-star> controller # [2274436.709619] controller systemd[1]: Finished Import lastlog data into lastlog2 database. container-test-run-dm-wireguard-star> controller # [2274436.776685] controller systemd[1]: etc-machine\x2did.mount: Deactivated successfully. container-test-run-dm-wireguard-star> controller # [2274436.828378] controller nsncd[235]: Sep 30 15:04:11.251 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" container-test-run-dm-wireguard-star> controller # [2274436.828389] controller systemd[1]: Started Name Service Cache Daemon (nsncd). container-test-run-dm-wireguard-star> controller # [2274436.828421] controller systemd[1]: Reached target Host and Network Name Lookups. container-test-run-dm-wireguard-star> controller # [2274436.828449] controller systemd[1]: Reached target User and Group Name Lookups. container-test-run-dm-wireguard-star> controller # [2274436.838122] controller systemd[1]: Starting User Login Management... container-test-run-dm-wireguard-star> controller # [2274436.838429] controller systemd[1]: Starting Permit User Sessions... container-test-run-dm-wireguard-star> controller # [2274436.845310] controller systemd[1]: Finished Permit User Sessions. container-test-run-dm-wireguard-star> controller # [2274436.845805] controller systemd[1]: Started Console Getty. container-test-run-dm-wireguard-star> controller # [2274436.845826] controller systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0 container-test-run-dm-wireguard-star> controller # [2274436.845837] controller systemd[1]: Reached target Login Prompts. container-test-run-dm-wireguard-star> controller # [2274436.941967] controller dbus-broker-launch[236]: Looking up NSS user entry for 'systemd-timesync'... container-test-run-dm-wireguard-star> controller # [2274436.942444] controller dbus-broker-launch[236]: NSS returned no entry for 'systemd-timesync' container-test-run-dm-wireguard-star> controller # [2274436.942444] controller dbus-broker-launch[236]: Invalid user-name in /nix/store/889k1i369sbi9l688fx6rdg2z6zcv8xa-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" container-test-run-dm-wireguard-star> peer1 # [2274436.671914] peer1 systemd-resolved[133]: Positive Trust Anchors: container-test-run-dm-wireguard-star> peer1 # [2274436.671919] peer1 systemd-resolved[133]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d container-test-run-dm-wireguard-star> peer1 # [2274436.671923] peer1 systemd-resolved[133]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 container-test-run-dm-wireguard-star> peer1 # [2274436.671938] peer1 systemd-resolved[133]: 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-dm-wireguard-star> peer1 # [2274436.682442] peer1 systemd-resolved[133]: Using system hostname 'peer1'. container-test-run-dm-wireguard-star> peer1 # [2274436.683458] peer1 systemd[1]: Started Network Name Resolution. container-test-run-dm-wireguard-star> peer1 # [2274436.683513] peer1 systemd[1]: Reached target Network. container-test-run-dm-wireguard-star> peer1 # [2274436.683552] peer1 systemd[1]: Reached target System Initialization. container-test-run-dm-wireguard-star> peer1 # [2274436.683617] peer1 systemd[1]: Started Watch for WireGuard controller info from data-mesher. container-test-run-dm-wireguard-star> peer1 # [2274436.683645] peer1 systemd[1]: Started Periodic heartbeat re-publish for dm-wireguard-star peer. container-test-run-dm-wireguard-star> peer1 # [2274436.683659] peer1 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container container-test-run-dm-wireguard-star> peer1 # [2274436.683675] peer1 systemd[1]: Started Daily Cleanup of Temporary Directories. container-test-run-dm-wireguard-star> peer1 # [2274436.683688] peer1 systemd[1]: Reached target Path Units. container-test-run-dm-wireguard-star> peer1 # [2274436.683714] peer1 systemd[1]: Reached target Timer Units. container-test-run-dm-wireguard-star> peer1 # [2274436.683794] peer1 systemd[1]: Listening on D-Bus System Message Bus Socket. container-test-run-dm-wireguard-star> peer1 # [2274436.683869] peer1 systemd[1]: Listening on Nix Daemon Socket. container-test-run-dm-wireguard-star> peer1 # [2274436.683954] peer1 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. container-test-run-dm-wireguard-star> peer1 # [2274436.683967] peer1 systemd[1]: Reached target Socket Units. container-test-run-dm-wireguard-star> peer1 # [2274436.683995] peer1 systemd[1]: Reached target Basic System. container-test-run-dm-wireguard-star> peer1 # [2274436.701180] peer1 systemd[1]: Starting data mesher daemon... container-test-run-dm-wireguard-star> peer1 # [2274436.701627] peer1 systemd[1]: Starting Import lastlog data into lastlog2 database... container-test-run-dm-wireguard-star> peer1 # [2274436.701974] peer1 systemd[1]: Starting Name Service Cache Daemon (nsncd)... container-test-run-dm-wireguard-star> peer1 # [2274436.702526] peer1 systemd[1]: Starting D-Bus System Message Bus... container-test-run-dm-wireguard-star> peer1 # [2274436.710977] peer1 systemd[1]: Finished Import lastlog data into lastlog2 database. container-test-run-dm-wireguard-star> peer1 # [2274436.776043] peer1 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. container-test-run-dm-wireguard-star> peer1 # [2274436.817363] peer1 systemd[1]: Started Name Service Cache Daemon (nsncd). container-test-run-dm-wireguard-star> peer1 # [2274436.817420] peer1 nsncd[224]: Sep 30 15:04:11.240 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" container-test-run-dm-wireguard-star> peer1 # [2274436.817390] peer1 systemd[1]: Reached target Host and Network Name Lookups. container-test-run-dm-wireguard-star> peer1 # [2274436.817420] peer1 systemd[1]: Reached target User and Group Name Lookups. container-test-run-dm-wireguard-star> peer1 # [2274436.817982] peer1 systemd[1]: Starting User Login Management... container-test-run-dm-wireguard-star> peer1 # [2274436.818312] peer1 systemd[1]: Starting Permit User Sessions... container-test-run-dm-wireguard-star> peer1 # [2274436.841304] peer1 systemd[1]: Finished Permit User Sessions. container-test-run-dm-wireguard-star> peer1 # [2274436.841733] peer1 systemd[1]: Started Console Getty. container-test-run-dm-wireguard-star> peer1 # [2274436.841753] peer1 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0 container-test-run-dm-wireguard-star> peer1 # [2274436.841763] peer1 systemd[1]: Reached target Login Prompts. container-test-run-dm-wireguard-star> peer1 # [2274436.943535] peer1 dbus-broker-launch[225]: Looking up NSS user entry for 'systemd-timesync'... container-test-run-dm-wireguard-star> peer1 # [2274436.943923] peer1 dbus-broker-launch[225]: NSS returned no entry for 'systemd-timesync' container-test-run-dm-wireguard-star> peer1 # [2274436.943923] peer1 dbus-broker-launch[225]: Invalid user-name in /nix/store/889k1i369sbi9l688fx6rdg2z6zcv8xa-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" container-test-run-dm-wireguard-star> controller # [2274436.942795] controller systemd[1]: Started D-Bus System Message Bus. container-test-run-dm-wireguard-star> controller # [2274436.947313] controller dbus-broker-launch[236]: Ready container-test-run-dm-wireguard-star> peer1 # [2274436.944154] peer1 systemd[1]: Started D-Bus System Message Bus. container-test-run-dm-wireguard-star> peer1 # [2274436.947492] peer1 dbus-broker-launch[225]: Ready container-test-run-dm-wireguard-star> controller # [2274437.243591] controller data-mesher[233]: time=2026-09-30T15:04:11.666Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo] container-test-run-dm-wireguard-star> controller # [2274437.244690] controller data-mesher[233]: time=2026-09-30T15:04:11.667Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWQBtx3u69HNUY42USobYSHfK83SZnpB37GBZtjkLXFfhc: [/dns/controller.clan/tcp/7946]} {12D3KooWASkyH3DmocSayyYPsbCKdhNH9PSyAi42SM6VB8kVSuQK: [/dns/peer1.clan/tcp/7946]} {12D3KooWMPWs9aKgHJhbes9uwANoEH35yZsQ4fw37Xg3qBnXPT3j: [/dns/peer2.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWQBtx3u69HNUY42USobYSHfK83SZnpB37GBZtjkLXFfhc container-test-run-dm-wireguard-star> controller # [2274437.244690] controller data-mesher[233]: time=2026-09-30T15:04:11.668Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml container-test-run-dm-wireguard-star> controller # [2274437.245518] controller data-mesher[233]: time=2026-09-30T15:04:11.668Z level=INFO msg="checking file integrity" container-test-run-dm-wireguard-star> controller # [2274437.245589] controller data-mesher[233]: time=2026-09-30T15:04:11.668Z level=INFO msg="file integrity check complete" container-test-run-dm-wireguard-star> controller # [2274437.247668] controller data-mesher[233]: time=2026-09-30T15:04:11.670Z level=INFO msg="libp2p host created" peer_id=12D3KooWQBtx3u69HNUY42USobYSHfK83SZnpB37GBZtjkLXFfhc addresses="[/ip4/127.0.0.1/tcp/7946 /ip4/192.168.1.1/tcp/7946 /ip6/::1/tcp/7946 /ip6/2001:db8:1::1/tcp/7946 /ip6/fda1:5c8::375:3ab0:8930:c2db/tcp/7946]" container-test-run-dm-wireguard-star> controller # [2274437.247713] controller data-mesher[233]: time=2026-09-30T15:04:11.671Z level=INFO msg="registered HTTP route" method=GET path=/files container-test-run-dm-wireguard-star> controller # [2274437.247713] controller data-mesher[233]: time=2026-09-30T15:04:11.671Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name container-test-run-dm-wireguard-star> controller # [2274437.247713] controller data-mesher[233]: time=2026-09-30T15:04:11.671Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name container-test-run-dm-wireguard-star> controller # [2274437.247713] controller data-mesher[233]: time=2026-09-30T15:04:11.671Z level=INFO msg="starting server" container-test-run-dm-wireguard-star> controller # [2274437.247772] controller data-mesher[233]: time=2026-09-30T15:04:11.671Z level=INFO msg="waiting for DHT to populate" delay=10s container-test-run-dm-wireguard-star> controller # [2274437.247796] controller data-mesher[233]: time=2026-09-30T15:04:11.671Z level=INFO msg="HTTP server listening" address=[::1]:7331 container-test-run-dm-wireguard-star> controller # [2274437.247821] controller data-mesher[233]: time=2026-09-30T15:04:11.671Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331 container-test-run-dm-wireguard-star> controller # [2274437.390641] controller systemd-logind[253]: New seat seat0. container-test-run-dm-wireguard-star> controller # [2274437.390735] controller systemd[1]: Started User Login Management. container-test-run-dm-wireguard-star> controller # [2274437.391513] controller systemd[1]: Starting linger-users.service... container-test-run-dm-wireguard-star> controller # [2274437.418276] controller systemd[1]: linger-users.service: Deactivated successfully. container-test-run-dm-wireguard-star> controller # [2274437.418386] controller systemd[1]: Finished linger-users.service. container-test-run-dm-wireguard-star> peer1 # [2274437.245881] peer1 data-mesher[222]: time=2026-09-30T15:04:11.669Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo] container-test-run-dm-wireguard-star> peer1 # [2274437.246983] peer1 data-mesher[222]: time=2026-09-30T15:04:11.670Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWQBtx3u69HNUY42USobYSHfK83SZnpB37GBZtjkLXFfhc: [/dns/controller.clan/tcp/7946]} {12D3KooWASkyH3DmocSayyYPsbCKdhNH9PSyAi42SM6VB8kVSuQK: [/dns/peer1.clan/tcp/7946]} {12D3KooWMPWs9aKgHJhbes9uwANoEH35yZsQ4fw37Xg3qBnXPT3j: [/dns/peer2.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWASkyH3DmocSayyYPsbCKdhNH9PSyAi42SM6VB8kVSuQK container-test-run-dm-wireguard-star> peer1 # [2274437.246983] peer1 data-mesher[222]: time=2026-09-30T15:04:11.670Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml container-test-run-dm-wireguard-star> peer1 # [2274437.247515] peer1 data-mesher[222]: time=2026-09-30T15:04:11.670Z level=INFO msg="checking file integrity" container-test-run-dm-wireguard-star> peer1 # [2274437.247586] peer1 data-mesher[222]: time=2026-09-30T15:04:11.670Z level=INFO msg="file integrity check complete" container-test-run-dm-wireguard-star> peer1 # [2274437.249429] peer1 data-mesher[222]: time=2026-09-30T15:04:11.672Z level=INFO msg="libp2p host created" peer_id=12D3KooWASkyH3DmocSayyYPsbCKdhNH9PSyAi42SM6VB8kVSuQK addresses="[/ip4/127.0.0.1/tcp/7946 /ip4/192.168.1.2/tcp/7946 /ip6/::1/tcp/7946 /ip6/2001:db8:1::2/tcp/7946 /ip6/fda1:5c8::c36d:c4c9:f95c:eff8/tcp/7946]" container-test-run-dm-wireguard-star> peer1 # [2274437.249460] peer1 data-mesher[222]: time=2026-09-30T15:04:11.672Z level=INFO msg="registered HTTP route" method=GET path=/files container-test-run-dm-wireguard-star> peer1 # [2274437.249460] peer1 data-mesher[222]: time=2026-09-30T15:04:11.672Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name container-test-run-dm-wireguard-star> peer1 # [2274437.249460] peer1 data-mesher[222]: time=2026-09-30T15:04:11.672Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name container-test-run-dm-wireguard-star> peer1 # [2274437.249460] peer1 data-mesher[222]: time=2026-09-30T15:04:11.672Z level=INFO msg="starting server" container-test-run-dm-wireguard-star> peer1 # [2274437.249518] peer1 data-mesher[222]: time=2026-09-30T15:04:11.672Z level=INFO msg="waiting for DHT to populate" delay=10s container-test-run-dm-wireguard-star> peer1 # [2274437.249576] peer1 data-mesher[222]: time=2026-09-30T15:04:11.672Z level=INFO msg="HTTP server listening" address=[::1]:7331 container-test-run-dm-wireguard-star> peer1 # [2274437.249623] peer1 data-mesher[222]: time=2026-09-30T15:04:11.672Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331 container-test-run-dm-wireguard-star> peer1 # [2274437.392374] peer1 systemd-logind[242]: New seat seat0. container-test-run-dm-wireguard-star> peer1 # [2274437.392478] peer1 systemd[1]: Started User Login Management. container-test-run-dm-wireguard-star> peer1 # [2274437.393205] peer1 systemd[1]: Starting linger-users.service... container-test-run-dm-wireguard-star> peer1 # [2274437.418432] peer1 systemd[1]: linger-users.service: Deactivated successfully. container-test-run-dm-wireguard-star> peer1 # [2274437.418473] peer1 systemd[1]: Finished linger-users.service. container-test-run-dm-wireguard-star> controller # [2274437.568132] controller systemd-networkd[227]: eth1: Gained IPv6LL container-test-run-dm-wireguard-star> peer1 # [2274437.953111] peer1 systemd-networkd[216]: eth1: Gained IPv6LL container-test-run-dm-wireguard-star> peer1 # [2274442.251801] peer1 data-mesher[222]: time=2026-09-30T15:04:16.675Z level=INFO msg="peer connected" peer_id=12D3KooWQBtx3u69HNUY42USobYSHfK83SZnpB37GBZtjkLXFfhc remote_addr=/ip4/192.168.1.1/tcp/7946 container-test-run-dm-wireguard-star> peer1 # [2274442.252062] peer1 data-mesher[222]: time=2026-09-30T15:04:16.675Z level=INFO msg="peer connected" peer_id=12D3KooWQBtx3u69HNUY42USobYSHfK83SZnpB37GBZtjkLXFfhc remote_addr=/ip4/192.168.1.1/tcp/7946 container-test-run-dm-wireguard-star> controller # [2274442.251476] controller data-mesher[233]: time=2026-09-30T15:04:16.674Z level=INFO msg="peer connected" peer_id=12D3KooWASkyH3DmocSayyYPsbCKdhNH9PSyAi42SM6VB8kVSuQK remote_addr=/ip4/192.168.1.2/tcp/7946 container-test-run-dm-wireguard-star> controller # [2274442.251975] controller data-mesher[233]: time=2026-09-30T15:04:16.675Z level=INFO msg="peer connected" peer_id=12D3KooWASkyH3DmocSayyYPsbCKdhNH9PSyAi42SM6VB8kVSuQK remote_addr=/ip4/192.168.1.2/tcp/55098 container-test-run-dm-wireguard-star> controller: still waiting for container 'controller' to reach ready state... container-test-run-dm-wireguard-star> controller: (finished: waiting for unit data-mesher.service, in 12.14 seconds) container-test-run-dm-wireguard-star> peer1: waiting for unit data-mesher.service container-test-run-dm-wireguard-star> peer1: (finished: waiting for unit data-mesher.service, in 0.02 seconds) container-test-run-dm-wireguard-star> ??? Warning (UserWarning): wait_until_succeeds(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead. container-test-run-dm-wireguard-star> File "/nix/store/g8gdxw5226n5cx2gy3ljqfhkb5w6qm8i-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39 container-test-run-dm-wireguard-star> controller: waiting for success: test -f /var/lib/data-mesher/files/home/dm_wg_star_wg_star/controller container-test-run-dm-wireguard-star> ??? Warning (UserWarning): execute(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead. container-test-run-dm-wireguard-star> File "/nix/store/g8gdxw5226n5cx2gy3ljqfhkb5w6qm8i-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39 container-test-run-dm-wireguard-star> controller: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/dm_wg_star_wg_star/controller, in 0.00 seconds) container-test-run-dm-wireguard-star> peer1: waiting for success: test -f /var/lib/data-mesher/files/home/dm_wg_star_wg_star/controller container-test-run-dm-wireguard-star> peer1 # [2274447.248954] peer1 data-mesher[222]: time=2026-09-30T15:04:21.672Z level=INFO msg="received state sync from peer" peer=12D3KooWQBtx3u69HNUY42USobYSHfK83SZnpB37GBZtjkLXFfhc container-test-run-dm-wireguard-star> peer1 # [2274447.248954] peer1 data-mesher[222]: time=2026-09-30T15:04:21.672Z level=INFO msg="merging remote state" peer=12D3KooWQBtx3u69HNUY42USobYSHfK83SZnpB37GBZtjkLXFfhc container-test-run-dm-wireguard-star> peer1 # [2274447.250211] peer1 data-mesher[222]: time=2026-09-30T15:04:21.673Z level=INFO msg="performing state exchange with peers on join" count=1 container-test-run-dm-wireguard-star> peer1 # [2274447.250211] peer1 data-mesher[222]: time=2026-09-30T15:04:21.673Z level=DEBUG msg="initiating state exchange" peer=12D3KooWQBtx3u69HNUY42USobYSHfK83SZnpB37GBZtjkLXFfhc timeout=5s container-test-run-dm-wireguard-star> peer1 # [2274447.250428] peer1 data-mesher[222]: time=2026-09-30T15:04:21.673Z level=INFO msg="merging remote state" peer=12D3KooWQBtx3u69HNUY42USobYSHfK83SZnpB37GBZtjkLXFfhc container-test-run-dm-wireguard-star> peer1 # [2274447.250428] peer1 data-mesher[222]: time=2026-09-30T15:04:21.673Z level=INFO msg="state exchange complete" peer=12D3KooWQBtx3u69HNUY42USobYSHfK83SZnpB37GBZtjkLXFfhc timeout=5s container-test-run-dm-wireguard-star> peer1 # [2274447.250464] peer1 data-mesher[222]: time=2026-09-30T15:04:21.673Z level=INFO msg="server started" container-test-run-dm-wireguard-star> peer1 # [2274447.250508] peer1 data-mesher[222]: time=2026-09-30T15:04:21.673Z level=INFO msg="starting expired-file sweeper" interval=1m0s container-test-run-dm-wireguard-star> peer1 # [2274447.250560] peer1 systemd[1]: Started data mesher daemon. container-test-run-dm-wireguard-star> peer1 # [2274447.251188] peer1 systemd[1]: Starting Publish WireGuard peer info to data-mesher... container-test-run-dm-wireguard-star> peer1 # [2274447.329128] peer1 data-mesher[222]: time=2026-09-30T15:04:21.752Z level=INFO msg=http_request uri=/files/dm_wg_star_wg_star/CVKxFydc87xaCEOsUkbAsrmE68I5l8mqQFzeapEkKOA status=204 container-test-run-dm-wireguard-star> peer1 # [2274447.329229] peer1 dm-wg-star-publish[277]: Status: 204 No Content container-test-run-dm-wireguard-star> peer1 # [2274447.330181] peer1 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher... container-test-run-dm-wireguard-star> peer1 # [2274447.330811] peer1 systemd[1]: dm-wg-star-wg-star-publish.service: Deactivated successfully. container-test-run-dm-wireguard-star> peer1 # [2274447.330909] peer1 systemd[1]: Finished Publish WireGuard peer info to data-mesher. container-test-run-dm-wireguard-star> peer1 # [2274447.331164] peer1 systemd[1]: Reached target Multi-User System. container-test-run-dm-wireguard-star> peer1 # [2274447.418831] peer1 dm-wg-star-reconfig[285]: No controller data available yet, skipping container-test-run-dm-wireguard-star> peer1 # [2274447.419727] peer1 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully. container-test-run-dm-wireguard-star> peer1 # [2274447.419808] peer1 systemd[1]: Finished Reconfigure WireGuard with controller from data-mesher. container-test-run-dm-wireguard-star> peer1 # [2274447.420093] peer1 systemd[1]: Startup finished in 11.874s. container-test-run-dm-wireguard-star> controller # [2274447.248554] controller data-mesher[233]: time=2026-09-30T15:04:21.671Z level=INFO msg="performing state exchange with peers on join" count=1 container-test-run-dm-wireguard-star> controller # [2274447.248554] controller data-mesher[233]: time=2026-09-30T15:04:21.671Z level=DEBUG msg="initiating state exchange" peer=12D3KooWASkyH3DmocSayyYPsbCKdhNH9PSyAi42SM6VB8kVSuQK timeout=5s container-test-run-dm-wireguard-star> controller # [2274447.249033] controller data-mesher[233]: time=2026-09-30T15:04:21.672Z level=INFO msg="merging remote state" peer=12D3KooWASkyH3DmocSayyYPsbCKdhNH9PSyAi42SM6VB8kVSuQK container-test-run-dm-wireguard-star> controller # [2274447.249033] controller data-mesher[233]: time=2026-09-30T15:04:21.672Z level=INFO msg="state exchange complete" peer=12D3KooWASkyH3DmocSayyYPsbCKdhNH9PSyAi42SM6VB8kVSuQK timeout=5s container-test-run-dm-wireguard-star> controller # [2274447.249097] controller data-mesher[233]: time=2026-09-30T15:04:21.672Z level=INFO msg="server started" container-test-run-dm-wireguard-star> controller # [2274447.249123] controller data-mesher[233]: time=2026-09-30T15:04:21.672Z level=INFO msg="starting expired-file sweeper" interval=1m0s container-test-run-dm-wireguard-star> controller # [2274447.249205] controller systemd[1]: Started data mesher daemon. container-test-run-dm-wireguard-star> controller # [2274447.249934] controller systemd[1]: Starting Publish WireGuard controller info to data-mesher... container-test-run-dm-wireguard-star> controller # [2274447.250360] controller data-mesher[233]: time=2026-09-30T15:04:21.673Z level=INFO msg="received state sync from peer" peer=12D3KooWASkyH3DmocSayyYPsbCKdhNH9PSyAi42SM6VB8kVSuQK container-test-run-dm-wireguard-star> controller # [2274447.250360] controller data-mesher[233]: time=2026-09-30T15:04:21.673Z level=INFO msg="merging remote state" peer=12D3KooWASkyH3DmocSayyYPsbCKdhNH9PSyAi42SM6VB8kVSuQK container-test-run-dm-wireguard-star> controller # [2274447.292098] controller systemd[1]: Starting Reconfigure WireGuard peers from data-mesher... container-test-run-dm-wireguard-star> controller # [2274447.292281] controller data-mesher[233]: time=2026-09-30T15:04:21.715Z level=INFO msg=http_request uri=/files/dm_wg_star_wg_star/controller status=204 container-test-run-dm-wireguard-star> controller # [2274447.292394] controller dm-wg-star-publish[288]: Status: 204 No Content container-test-run-dm-wireguard-star> controller # [2274447.294208] controller systemd[1]: Finished Publish WireGuard controller info to data-mesher. container-test-run-dm-wireguard-star> controller # [2274447.294397] controller systemd[1]: Reached target Multi-User System. container-test-run-dm-wireguard-star> controller # [2274447.335626] controller dm-wg-star-reconfig[295]: No peer data available yet, skipping container-test-run-dm-wireguard-star> controller # [2274447.336446] controller systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully. container-test-run-dm-wireguard-star> controller # [2274447.354141] controller systemd[1]: Finished Reconfigure WireGuard peers from data-mesher. container-test-run-dm-wireguard-star> controller # [2274447.354423] controller systemd[1]: Startup finished in 11.800s. container-test-run-dm-wireguard-star> peer1: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/dm_wg_star_wg_star/controller, in 5.02 seconds) container-test-run-dm-wireguard-star> controller: waiting for success: ls /var/lib/data-mesher/files/home/dm_wg_star_wg_star/ | grep -v controller | grep -q . container-test-run-dm-wireguard-star> controller: (finished: waiting for success: ls /var/lib/data-mesher/files/home/dm_wg_star_wg_star/ | grep -v controller | grep -q ., in 0.00 seconds) container-test-run-dm-wireguard-star> controller: waiting for success: wg show wg-star peers | grep -q . container-test-run-dm-wireguard-star> controller: (finished: waiting for success: wg show wg-star peers | grep -q ., in 0.01 seconds) container-test-run-dm-wireguard-star> peer1: waiting for success: wg show wg-star peers | grep -q . container-test-run-dm-wireguard-star> peer1: (finished: waiting for success: wg show wg-star peers | grep -q ., in 0.01 seconds) container-test-run-dm-wireguard-star> peer1: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::0375:3ab0:8930:c2db container-test-run-dm-wireguard-star> peer1: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::0375:3ab0:8930:c2db, in 0.00 seconds) container-test-run-dm-wireguard-star> controller: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::c36d:c4c9:f95c:eff8 container-test-run-dm-wireguard-star> controller: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::c36d:c4c9:f95c:eff8, in 0.00 seconds) container-test-run-dm-wireguard-star> controller: must succeed: wg show wg-star peers | wc -l container-test-run-dm-wireguard-star> controller: (finished: must succeed: wg show wg-star peers | wc -l, in 0.01 seconds) container-test-run-dm-wireguard-star> peer2: systemd-nspawn running (pid 725) container-test-run-dm-wireguard-star> peer2: Waiting for journal at /build/vm-state-peer2/var/log/journal... container-test-run-dm-wireguard-star> peer2: waiting for unit data-mesher.service container-test-run-dm-wireguard-star> nixos-nspawn(peer2): TAP vde-tap1 not found; container will be isolated from VDE container-test-run-dm-wireguard-star> 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-dm-wireguard-star> 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-dm-wireguard-star> ░ Spawning container peer2 on /build/vm-state-peer2. container-test-run-dm-wireguard-star> peer1 # [2274452.251687] peer1 data-mesher[222]: time=2026-09-30T15:04:26.674Z level=DEBUG msg="attempting push/pull" peer_count=1 container-test-run-dm-wireguard-star> peer1 # [2274452.251687] peer1 data-mesher[222]: time=2026-09-30T15:04:26.675Z level=DEBUG msg="initiating state exchange" peer=12D3KooWQBtx3u69HNUY42USobYSHfK83SZnpB37GBZtjkLXFfhc timeout=5s container-test-run-dm-wireguard-star> peer1 # [2274452.252021] peer1 data-mesher[222]: time=2026-09-30T15:04:26.675Z level=INFO msg="received state sync from peer" peer=12D3KooWQBtx3u69HNUY42USobYSHfK83SZnpB37GBZtjkLXFfhc container-test-run-dm-wireguard-star> peer1 # [2274452.252021] peer1 data-mesher[222]: time=2026-09-30T15:04:26.675Z level=INFO msg="merging remote state" peer=12D3KooWQBtx3u69HNUY42USobYSHfK83SZnpB37GBZtjkLXFfhc container-test-run-dm-wireguard-star> controller # [2274452.251459] controller data-mesher[233]: time=2026-09-30T15:04:26.674Z level=DEBUG msg="attempting push/pull" peer_count=1 container-test-run-dm-wireguard-star> peer1 # [2274452.252021] peer1 data-mesher[222]: time=2026-09-30T15:04:26.675Z level=DEBUG msg="new file detected" peer=12D3KooWQBtx3u69HNUY42USobYSHfK83SZnpB37GBZtjkLXFfhc name=dm_wg_star_wg_star/controller name=dm_wg_star_wg_star/controller container-test-run-dm-wireguard-star> controller # [2274452.251459] controller data-mesher[233]: time=2026-09-30T15:04:26.674Z level=DEBUG msg="initiating state exchange" peer=12D3KooWASkyH3DmocSayyYPsbCKdhNH9PSyAi42SM6VB8kVSuQK timeout=5s container-test-run-dm-wireguard-star> peer1 # [2274452.252021] peer1 data-mesher[222]: time=2026-09-30T15:04:26.675Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/controller container-test-run-dm-wireguard-star> controller # [2274452.252027] controller data-mesher[233]: time=2026-09-30T15:04:26.675Z level=INFO msg="received state sync from peer" peer=12D3KooWASkyH3DmocSayyYPsbCKdhNH9PSyAi42SM6VB8kVSuQK container-test-run-dm-wireguard-star> peer1 # [2274452.252021] peer1 data-mesher[222]: time=2026-09-30T15:04:26.675Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/controller signed_at="2026-09-30 15:04:21.712 +0000 UTC" signed_by="KmX9zzP3TaSrMuIkZ9jDp1fzDbAoW+5Oj2fK6XZVi2c=" peer=12D3KooWQBtx3u69HNUY42USobYSHfK83SZnpB37GBZtjkLXFfhc container-test-run-dm-wireguard-star> controller # [2274452.252027] controller data-mesher[233]: time=2026-09-30T15:04:26.675Z level=INFO msg="merging remote state" peer=12D3KooWASkyH3DmocSayyYPsbCKdhNH9PSyAi42SM6VB8kVSuQK container-test-run-dm-wireguard-star> peer1 # [2274452.252253] peer1 data-mesher[222]: time=2026-09-30T15:04:26.675Z level=INFO msg="merging remote state" peer=12D3KooWQBtx3u69HNUY42USobYSHfK83SZnpB37GBZtjkLXFfhc container-test-run-dm-wireguard-star> controller # [2274452.252027] controller data-mesher[233]: time=2026-09-30T15:04:26.675Z level=INFO msg="merging remote state" peer=12D3KooWASkyH3DmocSayyYPsbCKdhNH9PSyAi42SM6VB8kVSuQK container-test-run-dm-wireguard-star> peer1 # [2274452.252291] peer1 data-mesher[222]: time=2026-09-30T15:04:26.675Z level=DEBUG msg="new file detected" peer=12D3KooWQBtx3u69HNUY42USobYSHfK83SZnpB37GBZtjkLXFfhc name=dm_wg_star_wg_star/controller name=dm_wg_star_wg_star/controller container-test-run-dm-wireguard-star> controller # [2274452.252208] controller data-mesher[233]: time=2026-09-30T15:04:26.675Z level=DEBUG msg="new file detected" peer=12D3KooWASkyH3DmocSayyYPsbCKdhNH9PSyAi42SM6VB8kVSuQK name=dm_wg_star_wg_star/CVKxFydc87xaCEOsUkbAsrmE68I5l8mqQFzeapEkKOA name=dm_wg_star_wg_star/CVKxFydc87xaCEOsUkbAsrmE68I5l8mqQFzeapEkKOA container-test-run-dm-wireguard-star> peer1 # [2274452.252291] peer1 data-mesher[222]: time=2026-09-30T15:04:26.675Z level=INFO msg="state exchange complete" peer=12D3KooWQBtx3u69HNUY42USobYSHfK83SZnpB37GBZtjkLXFfhc timeout=5s container-test-run-dm-wireguard-star> controller # [2274452.252208] controller data-mesher[233]: time=2026-09-30T15:04:26.675Z level=DEBUG msg="new file detected" peer=12D3KooWASkyH3DmocSayyYPsbCKdhNH9PSyAi42SM6VB8kVSuQK name=dm_wg_star_wg_star/CVKxFydc87xaCEOsUkbAsrmE68I5l8mqQFzeapEkKOA name=dm_wg_star_wg_star/CVKxFydc87xaCEOsUkbAsrmE68I5l8mqQFzeapEkKOA container-test-run-dm-wireguard-star> peer1 # [2274452.252291] peer1 data-mesher[222]: time=2026-09-30T15:04:26.675Z level=DEBUG msg="push/pull successful" interval=5s container-test-run-dm-wireguard-star> controller # [2274452.252208] controller data-mesher[233]: time=2026-09-30T15:04:26.675Z level=INFO msg="state exchange complete" peer=12D3KooWASkyH3DmocSayyYPsbCKdhNH9PSyAi42SM6VB8kVSuQK timeout=5s container-test-run-dm-wireguard-star> peer1 # [2274452.252400] peer1 data-mesher[222]: time=2026-09-30T15:04:26.675Z level=INFO msg="received file request" peer=12D3KooWQBtx3u69HNUY42USobYSHfK83SZnpB37GBZtjkLXFfhc network="DTueDPD8Vynf7+UtkJ6sKBsVBu0/EcN0Z5Y5Tf1HIfM=" name=dm_wg_star_wg_star/CVKxFydc87xaCEOsUkbAsrmE68I5l8mqQFzeapEkKOA container-test-run-dm-wireguard-star> controller # [2274452.252208] controller data-mesher[233]: time=2026-09-30T15:04:26.675Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/CVKxFydc87xaCEOsUkbAsrmE68I5l8mqQFzeapEkKOA container-test-run-dm-wireguard-star> peer1 # [2274452.253281] peer1 data-mesher[222]: time=2026-09-30T15:04:26.676Z level=INFO msg="file transfer complete" peer=12D3KooWQBtx3u69HNUY42USobYSHfK83SZnpB37GBZtjkLXFfhc network="DTueDPD8Vynf7+UtkJ6sKBsVBu0/EcN0Z5Y5Tf1HIfM=" name=dm_wg_star_wg_star/CVKxFydc87xaCEOsUkbAsrmE68I5l8mqQFzeapEkKOA container-test-run-dm-wireguard-star> controller # [2274452.252208] controller data-mesher[233]: time=2026-09-30T15:04:26.675Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/CVKxFydc87xaCEOsUkbAsrmE68I5l8mqQFzeapEkKOA signed_at="2026-09-30 15:04:21.751 +0000 UTC" signed_by="CVKxFydc87xaCEOsUkbAsrmE68I5l8mqQFzeapEkKOA=" peer=12D3KooWASkyH3DmocSayyYPsbCKdhNH9PSyAi42SM6VB8kVSuQK container-test-run-dm-wireguard-star> peer1 # [2274452.255663] peer1 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher... container-test-run-dm-wireguard-star> controller # [2274452.252208] controller data-mesher[233]: time=2026-09-30T15:04:26.675Z level=INFO msg="received file request" peer=12D3KooWASkyH3DmocSayyYPsbCKdhNH9PSyAi42SM6VB8kVSuQK network="DTueDPD8Vynf7+UtkJ6sKBsVBu0/EcN0Z5Y5Tf1HIfM=" name=dm_wg_star_wg_star/controller container-test-run-dm-wireguard-star> peer1 # [2274452.256382] peer1 data-mesher[222]: time=2026-09-30T15:04:26.679Z level=INFO msg="download complete" name=dm_wg_star_wg_star/controller signed_at="2026-09-30 15:04:21.712 +0000 UTC" signed_by="KmX9zzP3TaSrMuIkZ9jDp1fzDbAoW+5Oj2fK6XZVi2c=" peer=12D3KooWQBtx3u69HNUY42USobYSHfK83SZnpB37GBZtjkLXFfhc written=true elapsed=4.393664ms container-test-run-dm-wireguard-star> controller # [2274452.252324] controller data-mesher[233]: time=2026-09-30T15:04:26.675Z level=DEBUG msg="push/pull successful" interval=5s container-test-run-dm-wireguard-star> peer1 # [2274452.331622] peer1 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully. container-test-run-dm-wireguard-star> controller # [2274452.253332] controller data-mesher[233]: time=2026-09-30T15:04:26.676Z level=INFO msg="file transfer complete" peer=12D3KooWASkyH3DmocSayyYPsbCKdhNH9PSyAi42SM6VB8kVSuQK network="DTueDPD8Vynf7+UtkJ6sKBsVBu0/EcN0Z5Y5Tf1HIfM=" name=dm_wg_star_wg_star/controller container-test-run-dm-wireguard-star> controller # [2274452.255235] controller systemd[1]: Starting Reconfigure WireGuard peers from data-mesher... container-test-run-dm-wireguard-star> peer1 # [2274452.331667] peer1 systemd[1]: Finished Reconfigure WireGuard with controller from data-mesher. container-test-run-dm-wireguard-star> controller # [2274452.256331] controller data-mesher[233]: time=2026-09-30T15:04:26.679Z level=INFO msg="download complete" name=dm_wg_star_wg_star/CVKxFydc87xaCEOsUkbAsrmE68I5l8mqQFzeapEkKOA signed_at="2026-09-30 15:04:21.751 +0000 UTC" signed_by="CVKxFydc87xaCEOsUkbAsrmE68I5l8mqQFzeapEkKOA=" peer=12D3KooWASkyH3DmocSayyYPsbCKdhNH9PSyAi42SM6VB8kVSuQK written=true elapsed=4.139265ms container-test-run-dm-wireguard-star> controller # [2274452.328944] controller systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully. container-test-run-dm-wireguard-star> controller # [2274452.329076] controller systemd[1]: Finished Reconfigure WireGuard peers from data-mesher. container-test-run-dm-wireguard-star> peer2 # [2274452.997961] peer2 systemd-journald[99]: Journal started container-test-run-dm-wireguard-star> peer2 # [2274452.997992] peer2 systemd-journald[99]: Runtime Journal (/run/log/journal/abfdf36750be4515b6e6d92dd81d55fb) is 8M, max 3.7G, 3.7G free. container-test-run-dm-wireguard-star> peer2 # [2274453.007819] peer2 systemd[1]: Finished Apply Kernel Variables. container-test-run-dm-wireguard-star> peer2 # [2274453.015127] peer2 systemd[1]: Finished Create Static Device Nodes in /dev gracefully. container-test-run-dm-wireguard-star> peer2 # [2274453.023259] peer2 systemd[1]: Starting Flush Journal to Persistent Storage... container-test-run-dm-wireguard-star> peer2 # [2274453.023821] peer2 systemd[1]: Starting Network Name Resolution... container-test-run-dm-wireguard-star> peer2 # [2274453.024364] peer2 systemd[1]: Starting Create Static Device Nodes in /dev... container-test-run-dm-wireguard-star> peer2 # [2274453.048918] peer2 systemd-journald[99]: Time spent on flushing to /var/log/journal/abfdf36750be4515b6e6d92dd81d55fb is 1.242ms for 7 entries. container-test-run-dm-wireguard-star> peer2 # [2274453.048918] peer2 systemd-journald[99]: System Journal (/var/log/journal/abfdf36750be4515b6e6d92dd81d55fb) is 8M, max 4G, 3.9G free. container-test-run-dm-wireguard-star> peer2 # [2274453.065941] peer2 systemd[1]: Finished Flush Journal to Persistent Storage. container-test-run-dm-wireguard-star> peer2 # [2274453.066285] peer2 systemd[1]: Finished Create Static Device Nodes in /dev. container-test-run-dm-wireguard-star> peer2 # [2274453.067323] peer2 systemd[1]: Reached target Preparation for Local File Systems. container-test-run-dm-wireguard-star> peer2 # [2274453.067378] peer2 systemd[1]: Reached target Local File Systems. container-test-run-dm-wireguard-star> peer2 # [2274453.067851] peer2 systemd[1]: Listening on Boot Loader Control Service Socket. container-test-run-dm-wireguard-star> peer2 # [2274453.067882] peer2 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container container-test-run-dm-wireguard-star> peer2 # [2274453.068651] peer2 systemd[1]: Starting Save Transient machine-id to Disk... container-test-run-dm-wireguard-star> peer2 # [2274453.069624] peer2 systemd[1]: Starting Create System Files and Directories... container-test-run-dm-wireguard-star> peer2 # [2274453.069643] peer2 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys container-test-run-dm-wireguard-star> peer2 # [2274453.091289] peer2 systemd[1]: Finished Firewall. container-test-run-dm-wireguard-star> peer2 # [2274453.091393] peer2 systemd[1]: Reached target Preparation for Network. container-test-run-dm-wireguard-star> peer2 # [2274453.091557] peer2 systemd[1]: Listening on Network Management Resolve Hook Socket. container-test-run-dm-wireguard-star> peer2 # [2274453.092313] peer2 systemd[1]: Starting Network Management... container-test-run-dm-wireguard-star> peer2 # [2274453.094175] peer2 systemd-tmpfiles[186]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted container-test-run-dm-wireguard-star> peer2 # [2274453.094326] peer2 systemd-tmpfiles[186]: fchmod() of /var/log/journal failed: Operation not permitted container-test-run-dm-wireguard-star> peer2 # [2274453.094425] peer2 systemd-tmpfiles[186]: fchmod() of /var/log/journal/abfdf36750be4515b6e6d92dd81d55fb failed: Operation not permitted container-test-run-dm-wireguard-star> peer2 # [2274453.094574] peer2 systemd-tmpfiles[186]: fchmod() of /run/log/journal failed: Operation not permitted container-test-run-dm-wireguard-star> peer2 # [2274453.095631] peer2 systemd[1]: Finished Create System Files and Directories. container-test-run-dm-wireguard-star> peer2 # [2274453.096311] peer2 systemd[1]: Starting Rebuild Journal Catalog... container-test-run-dm-wireguard-star> peer2 # [2274453.096736] peer2 systemd[1]: Starting Record System Boot/Shutdown in UTMP... container-test-run-dm-wireguard-star> peer2 # [2274453.104621] peer2 systemd[1]: Finished Record System Boot/Shutdown in UTMP. container-test-run-dm-wireguard-star> peer2 # [2274453.112203] peer2 systemd[1]: Finished Rebuild Journal Catalog. container-test-run-dm-wireguard-star> peer2 # [2274453.112876] peer2 systemd[1]: Starting Update is Completed... container-test-run-dm-wireguard-star> peer2 # [2274453.119872] peer2 systemd[1]: Finished Update is Completed. container-test-run-dm-wireguard-star> peer2 # [2274453.135470] peer2 systemd[1]: Finished Save Transient machine-id to Disk. container-test-run-dm-wireguard-star> peer2 # [2274453.589889] peer2 systemd-networkd[209]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted container-test-run-dm-wireguard-star> peer2 # [2274453.589969] peer2 systemd-networkd[209]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted container-test-run-dm-wireguard-star> peer2 # [2274453.597415] peer2 systemd-networkd[209]: /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-dm-wireguard-star> peer2 # [2274453.597568] peer2 systemd-networkd[209]: /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-dm-wireguard-star> peer2 # [2274453.597646] peer2 systemd-networkd[209]: lo: Link UP container-test-run-dm-wireguard-star> peer2 # [2274453.597648] peer2 systemd-networkd[209]: lo: Gained carrier container-test-run-dm-wireguard-star> peer2 # [2274453.597788] peer2 systemd-networkd[209]: eth1: Configuring with /etc/systemd/network/40-eth1.network. container-test-run-dm-wireguard-star> peer2 # [2274453.598036] peer2 systemd[1]: Started Network Management. container-test-run-dm-wireguard-star> peer2 # [2274453.598460] peer2 systemd-networkd[209]: wg-star: Configuring with /etc/systemd/network/40-wg-star.network. container-test-run-dm-wireguard-star> peer2 # [2274453.598627] peer2 systemd-networkd[209]: wg-star: netdev ready container-test-run-dm-wireguard-star> peer2 # [2274453.598714] peer2 systemd[1]: Starting Enable Persistent Storage in systemd-networkd... container-test-run-dm-wireguard-star> peer2 # [2274453.598733] peer2 systemd-networkd[209]: eth1: Link UP container-test-run-dm-wireguard-star> peer2 # [2274453.598839] peer2 systemd-networkd[209]: eth1: Gained carrier container-test-run-dm-wireguard-star> peer2 # [2274453.621454] peer2 systemd-networkd[209]: wg-star: Link UP container-test-run-dm-wireguard-star> peer2 # [2274453.621461] peer2 systemd-networkd[209]: wg-star: Gained carrier container-test-run-dm-wireguard-star> peer2 # [2274453.625319] peer2 systemd[1]: Finished Enable Persistent Storage in systemd-networkd. container-test-run-dm-wireguard-star> peer2 # [2274453.953744] peer2 systemd-resolved[140]: Positive Trust Anchors: container-test-run-dm-wireguard-star> peer2 # [2274453.953754] peer2 systemd-resolved[140]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d container-test-run-dm-wireguard-star> peer2 # [2274453.953757] peer2 systemd-resolved[140]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 container-test-run-dm-wireguard-star> peer2 # [2274453.953772] peer2 systemd-resolved[140]: 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-dm-wireguard-star> peer2 # [2274453.964377] peer2 systemd-resolved[140]: Using system hostname 'peer2'. container-test-run-dm-wireguard-star> peer2 # [2274453.965472] peer2 systemd[1]: Started Network Name Resolution. container-test-run-dm-wireguard-star> peer2 # [2274453.965529] peer2 systemd[1]: Reached target Network. container-test-run-dm-wireguard-star> peer2 # [2274453.965570] peer2 systemd[1]: Reached target System Initialization. container-test-run-dm-wireguard-star> peer2 # [2274453.965622] peer2 systemd[1]: Started Watch for WireGuard controller info from data-mesher. container-test-run-dm-wireguard-star> peer2 # [2274453.965644] peer2 systemd[1]: Started Periodic heartbeat re-publish for dm-wireguard-star peer. container-test-run-dm-wireguard-star> peer2 # [2274453.965660] peer2 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container container-test-run-dm-wireguard-star> peer2 # [2274453.965671] peer2 systemd[1]: Started Daily Cleanup of Temporary Directories. container-test-run-dm-wireguard-star> peer2 # [2274453.965682] peer2 systemd[1]: Reached target Path Units. container-test-run-dm-wireguard-star> peer2 # [2274453.965700] peer2 systemd[1]: Reached target Timer Units. container-test-run-dm-wireguard-star> peer2 # [2274453.965779] peer2 systemd[1]: Listening on D-Bus System Message Bus Socket. container-test-run-dm-wireguard-star> peer2 # [2274453.965882] peer2 systemd[1]: Listening on Nix Daemon Socket. container-test-run-dm-wireguard-star> peer2 # [2274453.965948] peer2 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. container-test-run-dm-wireguard-star> peer2 # [2274453.965959] peer2 systemd[1]: Reached target Socket Units. container-test-run-dm-wireguard-star> peer2 # [2274453.965979] peer2 systemd[1]: Reached target Basic System. container-test-run-dm-wireguard-star> peer2 # [2274453.966688] peer2 systemd[1]: Starting data mesher daemon... container-test-run-dm-wireguard-star> peer2 # [2274453.967046] peer2 systemd[1]: Starting Import lastlog data into lastlog2 database... container-test-run-dm-wireguard-star> peer2 # [2274453.967424] peer2 systemd[1]: Starting Name Service Cache Daemon (nsncd)... container-test-run-dm-wireguard-star> peer2 # [2274453.967985] peer2 systemd[1]: Starting D-Bus System Message Bus... container-test-run-dm-wireguard-star> peer2 # [2274453.995626] peer2 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. container-test-run-dm-wireguard-star> peer2 # [2274454.007274] peer2 systemd[1]: Finished Import lastlog data into lastlog2 database. container-test-run-dm-wireguard-star> peer2 # [2274454.102535] peer2 systemd[1]: Started Name Service Cache Daemon (nsncd). container-test-run-dm-wireguard-star> peer2 # [2274454.102632] peer2 nsncd[224]: Sep 30 15:04:28.525 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" container-test-run-dm-wireguard-star> peer2 # [2274454.102561] peer2 systemd[1]: Reached target Host and Network Name Lookups. container-test-run-dm-wireguard-star> peer2 # [2274454.102590] peer2 systemd[1]: Reached target User and Group Name Lookups. container-test-run-dm-wireguard-star> peer2 # [2274454.103223] peer2 systemd[1]: Starting User Login Management... container-test-run-dm-wireguard-star> peer2 # [2274454.103530] peer2 systemd[1]: Starting Permit User Sessions... container-test-run-dm-wireguard-star> peer2 # [2274454.136926] peer2 systemd[1]: Finished Permit User Sessions. container-test-run-dm-wireguard-star> peer2 # [2274454.137511] peer2 systemd[1]: Started Console Getty. container-test-run-dm-wireguard-star> peer2 # [2274454.137539] peer2 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0 container-test-run-dm-wireguard-star> peer2 # [2274454.137550] peer2 systemd[1]: Reached target Login Prompts. container-test-run-dm-wireguard-star> peer2 # [2274454.205279] peer2 dbus-broker-launch[225]: Looking up NSS user entry for 'systemd-timesync'... container-test-run-dm-wireguard-star> peer2 # [2274454.205671] peer2 dbus-broker-launch[225]: NSS returned no entry for 'systemd-timesync' container-test-run-dm-wireguard-star> peer2 # [2274454.205671] peer2 dbus-broker-launch[225]: Invalid user-name in /nix/store/889k1i369sbi9l688fx6rdg2z6zcv8xa-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" container-test-run-dm-wireguard-star> peer2 # [2274454.205943] peer2 systemd[1]: Started D-Bus System Message Bus. container-test-run-dm-wireguard-star> peer2 # [2274454.209526] peer2 dbus-broker-launch[225]: Ready container-test-run-dm-wireguard-star> peer1 # [2274454.519903] peer1 data-mesher[222]: time=2026-09-30T15:04:28.943Z level=INFO msg="peer connected" peer_id=12D3KooWMPWs9aKgHJhbes9uwANoEH35yZsQ4fw37Xg3qBnXPT3j remote_addr=/ip4/192.168.1.3/tcp/7946 container-test-run-dm-wireguard-star> peer2 # [2274454.512736] peer2 data-mesher[222]: time=2026-09-30T15:04:28.935Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo] container-test-run-dm-wireguard-star> peer2 # [2274454.513056] peer2 data-mesher[222]: time=2026-09-30T15:04:28.936Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWQBtx3u69HNUY42USobYSHfK83SZnpB37GBZtjkLXFfhc: [/dns/controller.clan/tcp/7946]} {12D3KooWASkyH3DmocSayyYPsbCKdhNH9PSyAi42SM6VB8kVSuQK: [/dns/peer1.clan/tcp/7946]} {12D3KooWMPWs9aKgHJhbes9uwANoEH35yZsQ4fw37Xg3qBnXPT3j: [/dns/peer2.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWMPWs9aKgHJhbes9uwANoEH35yZsQ4fw37Xg3qBnXPT3j container-test-run-dm-wireguard-star> peer2 # [2274454.513056] peer2 data-mesher[222]: time=2026-09-30T15:04:28.936Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml container-test-run-dm-wireguard-star> peer2 # [2274454.514548] peer2 data-mesher[222]: time=2026-09-30T15:04:28.937Z level=INFO msg="checking file integrity" container-test-run-dm-wireguard-star> peer2 # [2274454.514608] peer2 data-mesher[222]: time=2026-09-30T15:04:28.937Z level=INFO msg="file integrity check complete" container-test-run-dm-wireguard-star> peer2 # [2274454.517692] peer2 data-mesher[222]: time=2026-09-30T15:04:28.941Z level=INFO msg="libp2p host created" peer_id=12D3KooWMPWs9aKgHJhbes9uwANoEH35yZsQ4fw37Xg3qBnXPT3j addresses="[/ip4/127.0.0.1/tcp/7946 /ip4/192.168.1.3/tcp/7946 /ip6/::1/tcp/7946 /ip6/2001:db8:1::3/tcp/7946 /ip6/fda1:5c8::9c98:dff:ff3b:7791/tcp/7946]" container-test-run-dm-wireguard-star> peer2 # [2274454.517727] peer2 data-mesher[222]: time=2026-09-30T15:04:28.941Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name container-test-run-dm-wireguard-star> peer2 # [2274454.517727] peer2 data-mesher[222]: time=2026-09-30T15:04:28.941Z level=INFO msg="registered HTTP route" method=GET path=/files container-test-run-dm-wireguard-star> peer2 # [2274454.517727] peer2 data-mesher[222]: time=2026-09-30T15:04:28.941Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name container-test-run-dm-wireguard-star> peer2 # [2274454.517727] peer2 data-mesher[222]: time=2026-09-30T15:04:28.941Z level=INFO msg="starting server" container-test-run-dm-wireguard-star> peer2 # [2274454.517806] peer2 data-mesher[222]: time=2026-09-30T15:04:28.941Z level=INFO msg="waiting for DHT to populate" delay=10s container-test-run-dm-wireguard-star> peer2 # [2274454.517847] peer2 data-mesher[222]: time=2026-09-30T15:04:28.941Z level=INFO msg="HTTP server listening" address=[::1]:7331 container-test-run-dm-wireguard-star> peer2 # [2274454.517900] peer2 data-mesher[222]: time=2026-09-30T15:04:28.941Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331 container-test-run-dm-wireguard-star> peer2 # [2274454.519730] peer2 data-mesher[222]: time=2026-09-30T15:04:28.943Z level=INFO msg="peer connected" peer_id=12D3KooWASkyH3DmocSayyYPsbCKdhNH9PSyAi42SM6VB8kVSuQK remote_addr=/ip4/192.168.1.2/tcp/7946 container-test-run-dm-wireguard-star> peer2 # [2274454.521793] peer2 data-mesher[222]: time=2026-09-30T15:04:28.945Z level=INFO msg="peer connected" peer_id=12D3KooWQBtx3u69HNUY42USobYSHfK83SZnpB37GBZtjkLXFfhc remote_addr=/ip4/192.168.1.1/tcp/7946 container-test-run-dm-wireguard-star> peer2 # [2274454.679991] peer2 systemd-logind[242]: New seat seat0. container-test-run-dm-wireguard-star> peer2 # [2274454.680191] peer2 systemd[1]: Started User Login Management. container-test-run-dm-wireguard-star> peer2 # [2274454.681090] peer2 systemd[1]: Starting linger-users.service... container-test-run-dm-wireguard-star> peer2 # [2274454.707783] peer2 systemd[1]: linger-users.service: Deactivated successfully. container-test-run-dm-wireguard-star> peer2 # [2274454.707894] peer2 systemd[1]: Finished linger-users.service. container-test-run-dm-wireguard-star> controller # [2274454.521982] controller data-mesher[233]: time=2026-09-30T15:04:28.945Z level=INFO msg="peer connected" peer_id=12D3KooWMPWs9aKgHJhbes9uwANoEH35yZsQ4fw37Xg3qBnXPT3j remote_addr=/ip4/192.168.1.3/tcp/7946 container-test-run-dm-wireguard-star> peer2 # [2274454.912121] peer2 systemd-networkd[209]: eth1: Gained IPv6LL container-test-run-dm-wireguard-star> peer1 # [2274457.252904] peer1 data-mesher[222]: time=2026-09-30T15:04:31.676Z level=DEBUG msg="attempting push/pull" peer_count=1 container-test-run-dm-wireguard-star> peer1 # [2274457.252904] peer1 data-mesher[222]: time=2026-09-30T15:04:31.676Z level=DEBUG msg="initiating state exchange" peer=12D3KooWQBtx3u69HNUY42USobYSHfK83SZnpB37GBZtjkLXFfhc timeout=5s container-test-run-dm-wireguard-star> peer1 # [2274457.253316] peer1 data-mesher[222]: time=2026-09-30T15:04:31.676Z level=INFO msg="received state sync from peer" peer=12D3KooWQBtx3u69HNUY42USobYSHfK83SZnpB37GBZtjkLXFfhc container-test-run-dm-wireguard-star> peer1 # [2274457.253316] peer1 data-mesher[222]: time=2026-09-30T15:04:31.676Z level=INFO msg="merging remote state" peer=12D3KooWQBtx3u69HNUY42USobYSHfK83SZnpB37GBZtjkLXFfhc container-test-run-dm-wireguard-star> peer1 # [2274457.253360] peer1 data-mesher[222]: time=2026-09-30T15:04:31.676Z level=INFO msg="merging remote state" peer=12D3KooWQBtx3u69HNUY42USobYSHfK83SZnpB37GBZtjkLXFfhc container-test-run-dm-wireguard-star> peer1 # [2274457.253388] peer1 data-mesher[222]: time=2026-09-30T15:04:31.676Z level=INFO msg="state exchange complete" peer=12D3KooWQBtx3u69HNUY42USobYSHfK83SZnpB37GBZtjkLXFfhc timeout=5s container-test-run-dm-wireguard-star> peer1 # [2274457.253406] peer1 data-mesher[222]: time=2026-09-30T15:04:31.676Z level=DEBUG msg="push/pull successful" interval=5s container-test-run-dm-wireguard-star> controller # [2274457.252904] controller data-mesher[233]: time=2026-09-30T15:04:31.676Z level=DEBUG msg="attempting push/pull" peer_count=1 container-test-run-dm-wireguard-star> controller # [2274457.252904] controller data-mesher[233]: time=2026-09-30T15:04:31.676Z level=DEBUG msg="initiating state exchange" peer=12D3KooWASkyH3DmocSayyYPsbCKdhNH9PSyAi42SM6VB8kVSuQK timeout=5s container-test-run-dm-wireguard-star> controller # [2274457.253302] controller data-mesher[233]: time=2026-09-30T15:04:31.676Z level=INFO msg="received state sync from peer" peer=12D3KooWASkyH3DmocSayyYPsbCKdhNH9PSyAi42SM6VB8kVSuQK container-test-run-dm-wireguard-star> controller # [2274457.253302] controller data-mesher[233]: time=2026-09-30T15:04:31.676Z level=INFO msg="merging remote state" peer=12D3KooWASkyH3DmocSayyYPsbCKdhNH9PSyAi42SM6VB8kVSuQK container-test-run-dm-wireguard-star> controller # [2274457.253414] controller data-mesher[233]: time=2026-09-30T15:04:31.676Z level=INFO msg="merging remote state" peer=12D3KooWASkyH3DmocSayyYPsbCKdhNH9PSyAi42SM6VB8kVSuQK container-test-run-dm-wireguard-star> controller # [2274457.253504] controller data-mesher[233]: time=2026-09-30T15:04:31.676Z level=INFO msg="state exchange complete" peer=12D3KooWASkyH3DmocSayyYPsbCKdhNH9PSyAi42SM6VB8kVSuQK timeout=5s container-test-run-dm-wireguard-star> controller # [2274457.253530] controller data-mesher[233]: time=2026-09-30T15:04:31.676Z level=DEBUG msg="push/pull successful" interval=5s container-test-run-dm-wireguard-star> peer2: still waiting for container 'peer2' to reach ready state... container-test-run-dm-wireguard-star> peer1 # [2274462.253612] peer1 data-mesher[222]: time=2026-09-30T15:04:36.676Z level=DEBUG msg="attempting push/pull" peer_count=1 container-test-run-dm-wireguard-star> peer1 # [2274462.253962] peer1 data-mesher[222]: time=2026-09-30T15:04:36.676Z level=DEBUG msg="initiating state exchange" peer=12D3KooWMPWs9aKgHJhbes9uwANoEH35yZsQ4fw37Xg3qBnXPT3j timeout=5s container-test-run-dm-wireguard-star> peer1 # [2274462.254253] peer1 data-mesher[222]: time=2026-09-30T15:04:36.677Z level=INFO msg="merging remote state" peer=12D3KooWMPWs9aKgHJhbes9uwANoEH35yZsQ4fw37Xg3qBnXPT3j container-test-run-dm-wireguard-star> peer1 # [2274462.254253] peer1 data-mesher[222]: time=2026-09-30T15:04:36.677Z level=INFO msg="state exchange complete" peer=12D3KooWMPWs9aKgHJhbes9uwANoEH35yZsQ4fw37Xg3qBnXPT3j timeout=5s container-test-run-dm-wireguard-star> peer1 # [2274462.254253] peer1 data-mesher[222]: time=2026-09-30T15:04:36.677Z level=DEBUG msg="push/pull successful" interval=5s container-test-run-dm-wireguard-star> peer1 # [2274462.254508] peer1 data-mesher[222]: time=2026-09-30T15:04:36.677Z level=INFO msg="received file request" peer=12D3KooWMPWs9aKgHJhbes9uwANoEH35yZsQ4fw37Xg3qBnXPT3j network="DTueDPD8Vynf7+UtkJ6sKBsVBu0/EcN0Z5Y5Tf1HIfM=" name=dm_wg_star_wg_star/controller container-test-run-dm-wireguard-star> peer1 # [2274462.254508] peer1 data-mesher[222]: time=2026-09-30T15:04:36.677Z level=INFO msg="received file request" peer=12D3KooWMPWs9aKgHJhbes9uwANoEH35yZsQ4fw37Xg3qBnXPT3j network="DTueDPD8Vynf7+UtkJ6sKBsVBu0/EcN0Z5Y5Tf1HIfM=" name=dm_wg_star_wg_star/CVKxFydc87xaCEOsUkbAsrmE68I5l8mqQFzeapEkKOA container-test-run-dm-wireguard-star> peer1 # [2274462.254634] peer1 data-mesher[222]: time=2026-09-30T15:04:36.677Z level=INFO msg="received state sync from peer" peer=12D3KooWQBtx3u69HNUY42USobYSHfK83SZnpB37GBZtjkLXFfhc container-test-run-dm-wireguard-star> peer1 # [2274462.254634] peer1 data-mesher[222]: time=2026-09-30T15:04:36.677Z level=INFO msg="merging remote state" peer=12D3KooWQBtx3u69HNUY42USobYSHfK83SZnpB37GBZtjkLXFfhc container-test-run-dm-wireguard-star> peer1 # [2274462.255377] peer1 data-mesher[222]: time=2026-09-30T15:04:36.678Z level=INFO msg="file transfer complete" peer=12D3KooWMPWs9aKgHJhbes9uwANoEH35yZsQ4fw37Xg3qBnXPT3j network="DTueDPD8Vynf7+UtkJ6sKBsVBu0/EcN0Z5Y5Tf1HIfM=" name=dm_wg_star_wg_star/controller container-test-run-dm-wireguard-star> peer1 # [2274462.255417] peer1 data-mesher[222]: time=2026-09-30T15:04:36.678Z level=INFO msg="file transfer complete" peer=12D3KooWMPWs9aKgHJhbes9uwANoEH35yZsQ4fw37Xg3qBnXPT3j network="DTueDPD8Vynf7+UtkJ6sKBsVBu0/EcN0Z5Y5Tf1HIfM=" name=dm_wg_star_wg_star/CVKxFydc87xaCEOsUkbAsrmE68I5l8mqQFzeapEkKOA container-test-run-dm-wireguard-star> peer2 # [2274462.254061] peer2 data-mesher[222]: time=2026-09-30T15:04:36.677Z level=INFO msg="received state sync from peer" peer=12D3KooWASkyH3DmocSayyYPsbCKdhNH9PSyAi42SM6VB8kVSuQK container-test-run-dm-wireguard-star> peer2 # [2274462.254061] peer2 data-mesher[222]: time=2026-09-30T15:04:36.677Z level=INFO msg="merging remote state" peer=12D3KooWASkyH3DmocSayyYPsbCKdhNH9PSyAi42SM6VB8kVSuQK container-test-run-dm-wireguard-star> peer2 # [2274462.254562] peer2 data-mesher[222]: time=2026-09-30T15:04:36.677Z level=DEBUG msg="new file detected" peer=12D3KooWASkyH3DmocSayyYPsbCKdhNH9PSyAi42SM6VB8kVSuQK name=dm_wg_star_wg_star/CVKxFydc87xaCEOsUkbAsrmE68I5l8mqQFzeapEkKOA name=dm_wg_star_wg_star/CVKxFydc87xaCEOsUkbAsrmE68I5l8mqQFzeapEkKOA container-test-run-dm-wireguard-star> peer2 # [2274462.254562] peer2 data-mesher[222]: time=2026-09-30T15:04:36.677Z level=DEBUG msg="new file detected" peer=12D3KooWASkyH3DmocSayyYPsbCKdhNH9PSyAi42SM6VB8kVSuQK name=dm_wg_star_wg_star/CVKxFydc87xaCEOsUkbAsrmE68I5l8mqQFzeapEkKOA name=dm_wg_star_wg_star/controller name=dm_wg_star_wg_star/controller container-test-run-dm-wireguard-star> peer2 # [2274462.254562] peer2 data-mesher[222]: time=2026-09-30T15:04:36.677Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/CVKxFydc87xaCEOsUkbAsrmE68I5l8mqQFzeapEkKOA container-test-run-dm-wireguard-star> peer2 # [2274462.254562] peer2 data-mesher[222]: time=2026-09-30T15:04:36.677Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/controller container-test-run-dm-wireguard-star> peer2 # [2274462.254562] peer2 data-mesher[222]: time=2026-09-30T15:04:36.677Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/controller signed_at="2026-09-30 15:04:21.712 +0000 UTC" signed_by="KmX9zzP3TaSrMuIkZ9jDp1fzDbAoW+5Oj2fK6XZVi2c=" peer=12D3KooWASkyH3DmocSayyYPsbCKdhNH9PSyAi42SM6VB8kVSuQK container-test-run-dm-wireguard-star> peer2 # [2274462.254562] peer2 data-mesher[222]: time=2026-09-30T15:04:36.677Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/CVKxFydc87xaCEOsUkbAsrmE68I5l8mqQFzeapEkKOA signed_at="2026-09-30 15:04:21.751 +0000 UTC" signed_by="CVKxFydc87xaCEOsUkbAsrmE68I5l8mqQFzeapEkKOA=" peer=12D3KooWASkyH3DmocSayyYPsbCKdhNH9PSyAi42SM6VB8kVSuQK container-test-run-dm-wireguard-star> peer2 # [2274462.257448] peer2 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher... container-test-run-dm-wireguard-star> peer2 # [2274462.259055] peer2 data-mesher[222]: time=2026-09-30T15:04:36.682Z level=INFO msg="download complete" name=dm_wg_star_wg_star/CVKxFydc87xaCEOsUkbAsrmE68I5l8mqQFzeapEkKOA signed_at="2026-09-30 15:04:21.751 +0000 UTC" signed_by="CVKxFydc87xaCEOsUkbAsrmE68I5l8mqQFzeapEkKOA=" peer=12D3KooWASkyH3DmocSayyYPsbCKdhNH9PSyAi42SM6VB8kVSuQK written=true elapsed=4.845515ms container-test-run-dm-wireguard-star> peer2 # [2274462.259338] peer2 data-mesher[222]: time=2026-09-30T15:04:36.682Z level=INFO msg="download complete" name=dm_wg_star_wg_star/controller signed_at="2026-09-30 15:04:21.712 +0000 UTC" signed_by="KmX9zzP3TaSrMuIkZ9jDp1fzDbAoW+5Oj2fK6XZVi2c=" peer=12D3KooWASkyH3DmocSayyYPsbCKdhNH9PSyAi42SM6VB8kVSuQK written=true elapsed=5.131764ms container-test-run-dm-wireguard-star> peer2 # [2274462.330230] peer2 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully. container-test-run-dm-wireguard-star> peer2 # [2274462.330330] peer2 systemd[1]: Finished Reconfigure WireGuard with controller from data-mesher. container-test-run-dm-wireguard-star> controller # [2274462.254375] controller data-mesher[233]: time=2026-09-30T15:04:36.677Z level=DEBUG msg="attempting push/pull" peer_count=1 container-test-run-dm-wireguard-star> controller # [2274462.254375] controller data-mesher[233]: time=2026-09-30T15:04:36.677Z level=DEBUG msg="initiating state exchange" peer=12D3KooWASkyH3DmocSayyYPsbCKdhNH9PSyAi42SM6VB8kVSuQK timeout=5s container-test-run-dm-wireguard-star> controller # [2274462.255243] controller data-mesher[233]: time=2026-09-30T15:04:36.678Z level=INFO msg="merging remote state" peer=12D3KooWASkyH3DmocSayyYPsbCKdhNH9PSyAi42SM6VB8kVSuQK container-test-run-dm-wireguard-star> controller # [2274462.255301] controller data-mesher[233]: time=2026-09-30T15:04:36.678Z level=INFO msg="state exchange complete" peer=12D3KooWASkyH3DmocSayyYPsbCKdhNH9PSyAi42SM6VB8kVSuQK timeout=5s container-test-run-dm-wireguard-star> controller # [2274462.255324] controller data-mesher[233]: time=2026-09-30T15:04:36.678Z level=DEBUG msg="push/pull successful" interval=5s container-test-run-dm-wireguard-star> peer2 # [2274464.518467] peer2 data-mesher[222]: time=2026-09-30T15:04:38.941Z level=INFO msg="performing state exchange with peers on join" count=1 container-test-run-dm-wireguard-star> peer2 # [2274464.518667] peer2 data-mesher[222]: time=2026-09-30T15:04:38.941Z level=DEBUG msg="initiating state exchange" peer=12D3KooWQBtx3u69HNUY42USobYSHfK83SZnpB37GBZtjkLXFfhc timeout=5s container-test-run-dm-wireguard-star> peer2 # [2274464.518950] peer2 data-mesher[222]: time=2026-09-30T15:04:38.942Z level=INFO msg="merging remote state" peer=12D3KooWQBtx3u69HNUY42USobYSHfK83SZnpB37GBZtjkLXFfhc container-test-run-dm-wireguard-star> peer2 # [2274464.519028] peer2 data-mesher[222]: time=2026-09-30T15:04:38.942Z level=INFO msg="state exchange complete" peer=12D3KooWQBtx3u69HNUY42USobYSHfK83SZnpB37GBZtjkLXFfhc timeout=5s container-test-run-dm-wireguard-star> peer2 # [2274464.519050] peer2 data-mesher[222]: time=2026-09-30T15:04:38.942Z level=INFO msg="server started" container-test-run-dm-wireguard-star> peer2 # [2274464.519090] peer2 data-mesher[222]: time=2026-09-30T15:04:38.942Z level=INFO msg="starting expired-file sweeper" interval=1m0s container-test-run-dm-wireguard-star> peer2 # [2274464.519125] peer2 systemd[1]: Started data mesher daemon. container-test-run-dm-wireguard-star> peer2 # [2274464.519701] peer2 systemd[1]: Starting Publish WireGuard peer info to data-mesher... container-test-run-dm-wireguard-star> peer2 # [2274464.599355] peer2 data-mesher[222]: time=2026-09-30T15:04:39.022Z level=INFO msg=http_request uri=/files/dm_wg_star_wg_star/q-6OYrWSyzCBgTA3if13Kp6r7w1q4E64GXXTmPPO4TY status=204 container-test-run-dm-wireguard-star> peer2 # [2274464.599871] peer2 dm-wg-star-publish[287]: Status: 204 No Content container-test-run-dm-wireguard-star> peer2 # [2274464.600095] peer2 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher... container-test-run-dm-wireguard-star> peer2 # [2274464.601499] peer2 systemd[1]: dm-wg-star-wg-star-publish.service: Deactivated successfully. container-test-run-dm-wireguard-star> peer2 # [2274464.601583] peer2 systemd[1]: Finished Publish WireGuard peer info to data-mesher. container-test-run-dm-wireguard-star> peer2 # [2274464.601811] peer2 systemd[1]: Reached target Multi-User System. container-test-run-dm-wireguard-star> peer2 # [2274464.671017] peer2 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully. container-test-run-dm-wireguard-star> peer2 # [2274464.671130] peer2 systemd[1]: Finished Reconfigure WireGuard with controller from data-mesher. container-test-run-dm-wireguard-star> peer2 # [2274464.671334] peer2 systemd[1]: Startup finished in 11.915s. container-test-run-dm-wireguard-star> controller # [2274464.518729] controller data-mesher[233]: time=2026-09-30T15:04:38.942Z level=INFO msg="received state sync from peer" peer=12D3KooWMPWs9aKgHJhbes9uwANoEH35yZsQ4fw37Xg3qBnXPT3j container-test-run-dm-wireguard-star> controller # [2274464.518729] controller data-mesher[233]: time=2026-09-30T15:04:38.942Z level=INFO msg="merging remote state" peer=12D3KooWMPWs9aKgHJhbes9uwANoEH35yZsQ4fw37Xg3qBnXPT3j container-test-run-dm-wireguard-star> peer2: (finished: waiting for unit data-mesher.service, in 12.64 seconds) container-test-run-dm-wireguard-star> ??? Warning (UserWarning): wait_until_succeeds(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead. container-test-run-dm-wireguard-star> File "/nix/store/g8gdxw5226n5cx2gy3ljqfhkb5w6qm8i-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39 container-test-run-dm-wireguard-star> controller: waiting for success: test $(ls /var/lib/data-mesher/files/home/dm_wg_star_wg_star/ | grep -v controller | wc -l) -gt 1 container-test-run-dm-wireguard-star> ??? Warning (UserWarning): execute(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead. container-test-run-dm-wireguard-star> File "/nix/store/g8gdxw5226n5cx2gy3ljqfhkb5w6qm8i-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39 container-test-run-dm-wireguard-star> peer1 # [2274467.254887] peer1 data-mesher[222]: time=2026-09-30T15:04:41.678Z level=DEBUG msg="attempting push/pull" peer_count=1 container-test-run-dm-wireguard-star> peer1 # [2274467.254887] peer1 data-mesher[222]: time=2026-09-30T15:04:41.678Z level=DEBUG msg="initiating state exchange" peer=12D3KooWMPWs9aKgHJhbes9uwANoEH35yZsQ4fw37Xg3qBnXPT3j timeout=5s container-test-run-dm-wireguard-star> peer1 # [2274467.255346] peer1 data-mesher[222]: time=2026-09-30T15:04:41.678Z level=INFO msg="merging remote state" peer=12D3KooWMPWs9aKgHJhbes9uwANoEH35yZsQ4fw37Xg3qBnXPT3j container-test-run-dm-wireguard-star> peer1 # [2274467.255463] peer1 data-mesher[222]: time=2026-09-30T15:04:41.678Z level=DEBUG msg="new file detected" peer=12D3KooWMPWs9aKgHJhbes9uwANoEH35yZsQ4fw37Xg3qBnXPT3j name=dm_wg_star_wg_star/CVKxFydc87xaCEOsUkbAsrmE68I5l8mqQFzeapEkKOA name=dm_wg_star_wg_star/controller name=dm_wg_star_wg_star/q-6OYrWSyzCBgTA3if13Kp6r7w1q4E64GXXTmPPO4TY name=dm_wg_star_wg_star/q-6OYrWSyzCBgTA3if13Kp6r7w1q4E64GXXTmPPO4TY container-test-run-dm-wireguard-star> peer1 # [2274467.255463] peer1 data-mesher[222]: time=2026-09-30T15:04:41.678Z level=INFO msg="state exchange complete" peer=12D3KooWMPWs9aKgHJhbes9uwANoEH35yZsQ4fw37Xg3qBnXPT3j timeout=5s container-test-run-dm-wireguard-star> peer1 # [2274467.255520] peer1 data-mesher[222]: time=2026-09-30T15:04:41.678Z level=DEBUG msg="push/pull successful" interval=5s container-test-run-dm-wireguard-star> peer1 # [2274467.255520] peer1 data-mesher[222]: time=2026-09-30T15:04:41.678Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/q-6OYrWSyzCBgTA3if13Kp6r7w1q4E64GXXTmPPO4TY container-test-run-dm-wireguard-star> peer1 # [2274467.255520] peer1 data-mesher[222]: time=2026-09-30T15:04:41.678Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/q-6OYrWSyzCBgTA3if13Kp6r7w1q4E64GXXTmPPO4TY signed_at="2026-09-30 15:04:39.021 +0000 UTC" signed_by="q+6OYrWSyzCBgTA3if13Kp6r7w1q4E64GXXTmPPO4TY=" peer=12D3KooWMPWs9aKgHJhbes9uwANoEH35yZsQ4fw37Xg3qBnXPT3j container-test-run-dm-wireguard-star> peer1 # [2274467.257286] peer1 data-mesher[222]: time=2026-09-30T15:04:41.680Z level=INFO msg="download complete" name=dm_wg_star_wg_star/q-6OYrWSyzCBgTA3if13Kp6r7w1q4E64GXXTmPPO4TY signed_at="2026-09-30 15:04:39.021 +0000 UTC" signed_by="q+6OYrWSyzCBgTA3if13Kp6r7w1q4E64GXXTmPPO4TY=" peer=12D3KooWMPWs9aKgHJhbes9uwANoEH35yZsQ4fw37Xg3qBnXPT3j written=true elapsed=1.777548ms container-test-run-dm-wireguard-star> peer1 # [2274467.258343] peer1 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher... container-test-run-dm-wireguard-star> peer1 # [2274467.335860] peer1 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully. container-test-run-dm-wireguard-star> peer1 # [2274467.335946] peer1 systemd[1]: Finished Reconfigure WireGuard with controller from data-mesher. container-test-run-dm-wireguard-star> peer2 # [2274467.255139] peer2 data-mesher[222]: time=2026-09-30T15:04:41.678Z level=INFO msg="received state sync from peer" peer=12D3KooWASkyH3DmocSayyYPsbCKdhNH9PSyAi42SM6VB8kVSuQK container-test-run-dm-wireguard-star> peer2 # [2274467.255139] peer2 data-mesher[222]: time=2026-09-30T15:04:41.678Z level=INFO msg="merging remote state" peer=12D3KooWASkyH3DmocSayyYPsbCKdhNH9PSyAi42SM6VB8kVSuQK container-test-run-dm-wireguard-star> peer2 # [2274467.255746] peer2 data-mesher[222]: time=2026-09-30T15:04:41.679Z level=INFO msg="received file request" peer=12D3KooWASkyH3DmocSayyYPsbCKdhNH9PSyAi42SM6VB8kVSuQK network="DTueDPD8Vynf7+UtkJ6sKBsVBu0/EcN0Z5Y5Tf1HIfM=" name=dm_wg_star_wg_star/q-6OYrWSyzCBgTA3if13Kp6r7w1q4E64GXXTmPPO4TY container-test-run-dm-wireguard-star> peer2 # [2274467.256504] peer2 data-mesher[222]: time=2026-09-30T15:04:41.679Z level=INFO msg="file transfer complete" peer=12D3KooWASkyH3DmocSayyYPsbCKdhNH9PSyAi42SM6VB8kVSuQK network="DTueDPD8Vynf7+UtkJ6sKBsVBu0/EcN0Z5Y5Tf1HIfM=" name=dm_wg_star_wg_star/q-6OYrWSyzCBgTA3if13Kp6r7w1q4E64GXXTmPPO4TY container-test-run-dm-wireguard-star> peer2 # [2274467.256544] peer2 data-mesher[222]: time=2026-09-30T15:04:41.679Z level=INFO msg="received state sync from peer" peer=12D3KooWQBtx3u69HNUY42USobYSHfK83SZnpB37GBZtjkLXFfhc container-test-run-dm-wireguard-star> peer2 # [2274467.256544] peer2 data-mesher[222]: time=2026-09-30T15:04:41.679Z level=INFO msg="merging remote state" peer=12D3KooWQBtx3u69HNUY42USobYSHfK83SZnpB37GBZtjkLXFfhc container-test-run-dm-wireguard-star> peer2 # [2274467.256947] peer2 data-mesher[222]: time=2026-09-30T15:04:41.680Z level=INFO msg="received file request" peer=12D3KooWQBtx3u69HNUY42USobYSHfK83SZnpB37GBZtjkLXFfhc network="DTueDPD8Vynf7+UtkJ6sKBsVBu0/EcN0Z5Y5Tf1HIfM=" name=dm_wg_star_wg_star/q-6OYrWSyzCBgTA3if13Kp6r7w1q4E64GXXTmPPO4TY container-test-run-dm-wireguard-star> peer2 # [2274467.257070] peer2 data-mesher[222]: time=2026-09-30T15:04:41.680Z level=INFO msg="file transfer complete" peer=12D3KooWQBtx3u69HNUY42USobYSHfK83SZnpB37GBZtjkLXFfhc network="DTueDPD8Vynf7+UtkJ6sKBsVBu0/EcN0Z5Y5Tf1HIfM=" name=dm_wg_star_wg_star/q-6OYrWSyzCBgTA3if13Kp6r7w1q4E64GXXTmPPO4TY container-test-run-dm-wireguard-star> controller # [2274467.255836] controller data-mesher[233]: time=2026-09-30T15:04:41.679Z level=DEBUG msg="attempting push/pull" peer_count=1 container-test-run-dm-wireguard-star> controller # [2274467.255836] controller data-mesher[233]: time=2026-09-30T15:04:41.679Z level=DEBUG msg="initiating state exchange" peer=12D3KooWMPWs9aKgHJhbes9uwANoEH35yZsQ4fw37Xg3qBnXPT3j timeout=5s container-test-run-dm-wireguard-star> controller # [2274467.256691] controller data-mesher[233]: time=2026-09-30T15:04:41.680Z level=INFO msg="merging remote state" peer=12D3KooWMPWs9aKgHJhbes9uwANoEH35yZsQ4fw37Xg3qBnXPT3j container-test-run-dm-wireguard-star> controller # [2274467.256795] controller data-mesher[233]: time=2026-09-30T15:04:41.680Z level=DEBUG msg="new file detected" peer=12D3KooWMPWs9aKgHJhbes9uwANoEH35yZsQ4fw37Xg3qBnXPT3j name=dm_wg_star_wg_star/CVKxFydc87xaCEOsUkbAsrmE68I5l8mqQFzeapEkKOA name=dm_wg_star_wg_star/controller name=dm_wg_star_wg_star/q-6OYrWSyzCBgTA3if13Kp6r7w1q4E64GXXTmPPO4TY name=dm_wg_star_wg_star/q-6OYrWSyzCBgTA3if13Kp6r7w1q4E64GXXTmPPO4TY container-test-run-dm-wireguard-star> controller # [2274467.256795] controller data-mesher[233]: time=2026-09-30T15:04:41.680Z level=INFO msg="state exchange complete" peer=12D3KooWMPWs9aKgHJhbes9uwANoEH35yZsQ4fw37Xg3qBnXPT3j timeout=5s container-test-run-dm-wireguard-star> controller # [2274467.256849] controller data-mesher[233]: time=2026-09-30T15:04:41.680Z level=DEBUG msg="push/pull successful" interval=5s container-test-run-dm-wireguard-star> controller # [2274467.256849] controller data-mesher[233]: time=2026-09-30T15:04:41.680Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/q-6OYrWSyzCBgTA3if13Kp6r7w1q4E64GXXTmPPO4TY container-test-run-dm-wireguard-star> controller # [2274467.256849] controller data-mesher[233]: time=2026-09-30T15:04:41.680Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/q-6OYrWSyzCBgTA3if13Kp6r7w1q4E64GXXTmPPO4TY signed_at="2026-09-30 15:04:39.021 +0000 UTC" signed_by="q+6OYrWSyzCBgTA3if13Kp6r7w1q4E64GXXTmPPO4TY=" peer=12D3KooWMPWs9aKgHJhbes9uwANoEH35yZsQ4fw37Xg3qBnXPT3j container-test-run-dm-wireguard-star> controller # [2274467.257750] controller data-mesher[233]: time=2026-09-30T15:04:41.681Z level=INFO msg="download complete" name=dm_wg_star_wg_star/q-6OYrWSyzCBgTA3if13Kp6r7w1q4E64GXXTmPPO4TY signed_at="2026-09-30 15:04:39.021 +0000 UTC" signed_by="q+6OYrWSyzCBgTA3if13Kp6r7w1q4E64GXXTmPPO4TY=" peer=12D3KooWMPWs9aKgHJhbes9uwANoEH35yZsQ4fw37Xg3qBnXPT3j written=true elapsed=925.162µs container-test-run-dm-wireguard-star> controller # [2274467.258825] controller systemd[1]: Starting Reconfigure WireGuard peers from data-mesher... container-test-run-dm-wireguard-star> controller # [2274467.337228] controller systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully. container-test-run-dm-wireguard-star> controller # [2274467.337306] controller systemd[1]: Finished Reconfigure WireGuard peers from data-mesher. container-test-run-dm-wireguard-star> controller: (finished: waiting for success: test $(ls /var/lib/data-mesher/files/home/dm_wg_star_wg_star/ | grep -v controller | wc -l) -gt 1, in 3.02 seconds) container-test-run-dm-wireguard-star> controller: waiting for success: test $(wg show wg-star peers | wc -l) -gt 1 container-test-run-dm-wireguard-star> controller: (finished: waiting for success: test $(wg show wg-star peers | wc -l) -gt 1, in 0.01 seconds) container-test-run-dm-wireguard-star> peer2: waiting for success: wg show wg-star peers | grep -q . container-test-run-dm-wireguard-star> peer2: (finished: waiting for success: wg show wg-star peers | grep -q ., in 0.01 seconds) container-test-run-dm-wireguard-star> peer2: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::0375:3ab0:8930:c2db container-test-run-dm-wireguard-star> peer2: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::0375:3ab0:8930:c2db, in 0.00 seconds) container-test-run-dm-wireguard-star> controller: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::9c98:0dff:ff3b:7791 container-test-run-dm-wireguard-star> controller: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::9c98:0dff:ff3b:7791, in 0.00 seconds) container-test-run-dm-wireguard-star> peer1: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::9c98:0dff:ff3b:7791 container-test-run-dm-wireguard-star> peer1: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::9c98:0dff:ff3b:7791, in 0.00 seconds) container-test-run-dm-wireguard-star> peer2: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::c36d:c4c9:f95c:eff8 container-test-run-dm-wireguard-star> peer2: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::c36d:c4c9:f95c:eff8, in 0.00 seconds) container-test-run-dm-wireguard-star> (finished: run the VM test script, in 32.91 seconds) container-test-run-dm-wireguard-star> test script finished in 32.95s container-test-run-dm-wireguard-star> cleanup container-test-run-dm-wireguard-star> kill NspawnMachine (pid 50) container-test-run-dm-wireguard-star> kill NspawnMachine (pid 53) container-test-run-dm-wireguard-star> Container controller terminated by signal KILL. container-test-run-dm-wireguard-star> kill NspawnMachine (pid 725) container-test-run-dm-wireguard-star> Container peer1 terminated by signal KILL. container-test-run-dm-wireguard-star> Container peer2 terminated by signal KILL. container-test-run-dm-wireguard-star> (finished: cleanup, in 0.29 seconds)