nixbot

1these 43 derivations will be built:2  /nix/store/qqp4hhw6xpgyxssfxyzxac5yn55kdhzg-flakelet-relay.json.drv3  /nix/store/vln2mpqksri93wa8w5w1h7b242h6nxbq-flakelet-relay.service.drv4  /nix/store/k3dj4z3rj1a9ik2rm9vh17d0v3mgq86k-flakelet-flakelet-relay-units.drv5  /nix/store/006g8wgskgq50lzlsbjm117q113k0dd6-flakelet-flakelet-relay.drv6  /nix/store/x03fs0v5l0xxyahhmjr0m6adck5xvx1l-X-Restart-Triggers-flakelet-flakelet-agent.drv7  /nix/store/2870p40793vxwjjyc79cnjrz22h6czbc-unit-flakelet-flakelet-agent.service.drv8  /nix/store/2jlcpbb9y5fz6hijxdv6x78i85gxi1nh-flakelet-config.json.drv9  /nix/store/90lcf2a4d46vizsjnzq0wkxfh7c2wsdf-X-Restart-Triggers-flakelet-reconcile.drv10  /nix/store/7v95bjgiagpsjld7rixh8h6mla2wslwy-unit-flakelet-reconcile.service.drv11  /nix/store/nnrpxgg7pyifd293mn4h7v84yp0j8l8l-X-Restart-Triggers-flakelet-flakelet-relay.drv12  /nix/store/hva88l9j3pvimgzb44dv4sw3dj5czwnc-unit-flakelet-flakelet-relay.service.drv13  /nix/store/2lw666vh16106ik107rbjh7bpqpdx37g-system-units.drv14  /nix/store/ynbm2sd7w9gnh682zi4f095jcvhh824v-push.drv15  /nix/store/2rbhcr84lvanyr8llb67zkskk3pysm4a-system-path.drv16  /nix/store/3v22avfai9ls8irpdhnxbpbafchfb2xg-flakelet-agent.service.drv17  /nix/store/6n3gb7hxpccflp735f7bwdyax8q0kii2-flakelet-flakelet-agent-units.drv18  /nix/store/q5l7141zdgq2h8pvsg6kx55jbhzmpzd6-flakelet-flakelet-agent.drv19  /nix/store/zckd8gfnhimq763gqx185zcmf5qxsf4b-flakelet-config.json.drv20  /nix/store/8kldynx19s5yddk8z822gf8q19mc9agl-X-Restart-Triggers-flakelet-reconcile.drv21  /nix/store/hwnbdcdhlnk2sywscgwnirmmvilmjkim-unit-flakelet-reconcile.service.drv22  /nix/store/3s6ldxwx84a1a3qd3nsrnppbnv0raf0m-system-units.drv23  /nix/store/wvz6k2b0f3vlrsyczshccksdd89wpxd0-etc.drv24  /nix/store/mzjllrnkcl31il4ybq8gm0zlr6bc8c0r-activate.drv25  /nix/store/zns6802mfyzsfbvbvjyczkn0p67c531l-nixos-system-agent-test.drv26  /nix/store/493vgs5bbzq0ihr2zvnhpcayzpdfv96q-run-agent-nspawn.drv27  /nix/store/qigr2jrf4kxi7z7wimmpp8bs3gprccfh-etc.drv28  /nix/store/hy3awp7qns2xp1gdzkfzl3yzkxnbk9kh-activate.drv29  /nix/store/9y1yzazqppiadb9d44v83l3jn6vj3pbz-nixos-system-relay-test.drv30  /nix/store/4wqbiai64im3ml10dx1j8m1j0rbbkajc-run-relay-nspawn.drv31  /nix/store/wb2gs722qns3ij2kgzjqd4aw57y8fbd4-dbus-1.drv32  /nix/store/rg2qak4ii4wvnjkhaxsvvbldpwg3j9ci-X-Restart-Triggers-dbus-broker.drv33  /nix/store/iaiyh2pkscg798mbchly1c1yn8kimlh0-unit-dbus-broker.service.drv34  /nix/store/b1v9whx4ciykmhmj79hiy3zbk7fg3vcl-system-units.drv35  /nix/store/8hrflwjpkxdiid8ah15afj6261lwrzhh-unit-dbus-broker.service.drv36  /nix/store/mk656hxdf40f37qnxv3iwvrrqsy01wj9-user-units.drv37  /nix/store/z9p4rrh1z3wqzm6j91anf1jd2fyvym3b-etc.drv38  /nix/store/xbb7v66rcpjp6m5y1g1k7lf7im2pas9x-activate.drv39  /nix/store/v57z7dhglgc26a3w76krn05xw28rfnp0-nixos-system-client-test.drv40  /nix/store/9rxyi976zlaywm4zsmrknawdib5n449w-run-client-nspawn.drv41  /nix/store/hr9c52swyzb6ap39lm7avrpdqk2ixh0f-test-script.drv42  /nix/store/gw9dxj48811dfx5bqh2z8wynwmc8qgq3-driverConfiguration.json.drv43  /nix/store/xx56qak5d6s4dalrqcn14ypjcpagbdis-nixos-test-driver-flakelet-relay.drv44  /nix/store/3qbgp499vlfvi5qqwvh37126xl2j8vrk-container-test-run-flakelet-relay.drv45building '/nix/store/hr9c52swyzb6ap39lm7avrpdqk2ixh0f-test-script.drv'46building '/nix/store/ynbm2sd7w9gnh682zi4f095jcvhh824v-push.drv'47building '/nix/store/x03fs0v5l0xxyahhmjr0m6adck5xvx1l-X-Restart-Triggers-flakelet-flakelet-agent.drv'48building '/nix/store/nnrpxgg7pyifd293mn4h7v84yp0j8l8l-X-Restart-Triggers-flakelet-flakelet-relay.drv'49X-Restart-Triggers-flakelet-flakelet-agent> tribuchet: building on jamie50push> tribuchet: building on jamie51test-script> tribuchet: building on jamie52building '/nix/store/3v22avfai9ls8irpdhnxbpbafchfb2xg-flakelet-agent.service.drv'53X-Restart-Triggers-flakelet-flakelet-relay> tribuchet: building on jamie54building '/nix/store/qqp4hhw6xpgyxssfxyzxac5yn55kdhzg-flakelet-relay.json.drv'55flakelet-agent.service> tribuchet: building on jamie56flakelet-relay.json> tribuchet: building on jamie57flakelet-relay.json> check: oidc:mock:repo:github:example/app:ref:refs/heads/main -> agent/app (expect allow)58flakelet-relay.json> agent/app	allowed by ci59flakelet-relay.json> check: oidc:mock:repo:github:example/app:ref:refs/heads/main -> agent/other (expect deny)60flakelet-relay.json> agent/other	denied61building '/nix/store/2870p40793vxwjjyc79cnjrz22h6czbc-unit-flakelet-flakelet-agent.service.drv'62building '/nix/store/hva88l9j3pvimgzb44dv4sw3dj5czwnc-unit-flakelet-flakelet-relay.service.drv'63unit-flakelet-flakelet-agent.service> tribuchet: building on jamie64unit-flakelet-flakelet-relay.service> tribuchet: building on jamie65building '/nix/store/vln2mpqksri93wa8w5w1h7b242h6nxbq-flakelet-relay.service.drv'66building '/nix/store/6n3gb7hxpccflp735f7bwdyax8q0kii2-flakelet-flakelet-agent-units.drv'67building '/nix/store/2rbhcr84lvanyr8llb67zkskk3pysm4a-system-path.drv'68flakelet-flakelet-agent-units> tribuchet: building on jamie69flakelet-relay.service> tribuchet: building on jamie70system-path> tribuchet: building on jamie71unit-flakelet-flakelet-agent.service> structuredAttrs is enabled72unit-flakelet-flakelet-relay.service> structuredAttrs is enabled73system-path> structuredAttrs is enabled74system-path> created 6399 symlinks in user environment75building '/nix/store/k3dj4z3rj1a9ik2rm9vh17d0v3mgq86k-flakelet-flakelet-relay-units.drv'76flakelet-flakelet-relay-units> tribuchet: building on jamie77building '/nix/store/q5l7141zdgq2h8pvsg6kx55jbhzmpzd6-flakelet-flakelet-agent.drv'78flakelet-flakelet-agent> tribuchet: building on jamie79building '/nix/store/006g8wgskgq50lzlsbjm117q113k0dd6-flakelet-flakelet-relay.drv'80flakelet-flakelet-relay> tribuchet: building on jamie81building '/nix/store/zckd8gfnhimq763gqx185zcmf5qxsf4b-flakelet-config.json.drv'82flakelet-config.json> tribuchet: building on jamie83building '/nix/store/wb2gs722qns3ij2kgzjqd4aw57y8fbd4-dbus-1.drv'84flakelet-config.json> structuredAttrs is enabled85dbus-1> tribuchet: building on jamie86building '/nix/store/2jlcpbb9y5fz6hijxdv6x78i85gxi1nh-flakelet-config.json.drv'87flakelet-config.json> tribuchet: building on jamie88building '/nix/store/8kldynx19s5yddk8z822gf8q19mc9agl-X-Restart-Triggers-flakelet-reconcile.drv'89X-Restart-Triggers-flakelet-reconcile> tribuchet: building on jamie90flakelet-config.json> structuredAttrs is enabled91building '/nix/store/90lcf2a4d46vizsjnzq0wkxfh7c2wsdf-X-Restart-Triggers-flakelet-reconcile.drv'92X-Restart-Triggers-flakelet-reconcile> tribuchet: building on jamie93building '/nix/store/rg2qak4ii4wvnjkhaxsvvbldpwg3j9ci-X-Restart-Triggers-dbus-broker.drv'94building '/nix/store/hwnbdcdhlnk2sywscgwnirmmvilmjkim-unit-flakelet-reconcile.service.drv'95X-Restart-Triggers-dbus-broker> tribuchet: building on jamie96unit-flakelet-reconcile.service> tribuchet: building on jamie97unit-flakelet-reconcile.service> structuredAttrs is enabled98building '/nix/store/7v95bjgiagpsjld7rixh8h6mla2wslwy-unit-flakelet-reconcile.service.drv'99unit-flakelet-reconcile.service> tribuchet: building on jamie100building '/nix/store/3s6ldxwx84a1a3qd3nsrnppbnv0raf0m-system-units.drv'101building '/nix/store/8hrflwjpkxdiid8ah15afj6261lwrzhh-unit-dbus-broker.service.drv'102building '/nix/store/iaiyh2pkscg798mbchly1c1yn8kimlh0-unit-dbus-broker.service.drv'103system-units> tribuchet: building on jamie104unit-dbus-broker.service> tribuchet: building on jamie105unit-dbus-broker.service> tribuchet: building on jamie106unit-flakelet-reconcile.service> structuredAttrs is enabled107unit-dbus-broker.service> structuredAttrs is enabled108unit-dbus-broker.service> structuredAttrs is enabled109building '/nix/store/2lw666vh16106ik107rbjh7bpqpdx37g-system-units.drv'110system-units> tribuchet: building on jamie111building '/nix/store/b1v9whx4ciykmhmj79hiy3zbk7fg3vcl-system-units.drv'112building '/nix/store/mk656hxdf40f37qnxv3iwvrrqsy01wj9-user-units.drv'113system-units> tribuchet: building on jamie114user-units> tribuchet: building on jamie115building '/nix/store/wvz6k2b0f3vlrsyczshccksdd89wpxd0-etc.drv'116etc> tribuchet: building on jamie117building '/nix/store/z9p4rrh1z3wqzm6j91anf1jd2fyvym3b-etc.drv'118etc> tribuchet: building on jamie119building '/nix/store/qigr2jrf4kxi7z7wimmpp8bs3gprccfh-etc.drv'120etc> tribuchet: building on jamie121building '/nix/store/mzjllrnkcl31il4ybq8gm0zlr6bc8c0r-activate.drv'122activate> tribuchet: building on jamie123building '/nix/store/xbb7v66rcpjp6m5y1g1k7lf7im2pas9x-activate.drv'124activate> tribuchet: building on jamie125building '/nix/store/zns6802mfyzsfbvbvjyczkn0p67c531l-nixos-system-agent-test.drv'126nixos-system-agent-test> tribuchet: building on jamie127building '/nix/store/hy3awp7qns2xp1gdzkfzl3yzkxnbk9kh-activate.drv'128activate> tribuchet: building on jamie129nixos-system-agent-test> structuredAttrs is enabled130building '/nix/store/v57z7dhglgc26a3w76krn05xw28rfnp0-nixos-system-client-test.drv'131nixos-system-client-test> tribuchet: building on jamie132building '/nix/store/9y1yzazqppiadb9d44v83l3jn6vj3pbz-nixos-system-relay-test.drv'133nixos-system-relay-test> tribuchet: building on jamie134building '/nix/store/493vgs5bbzq0ihr2zvnhpcayzpdfv96q-run-agent-nspawn.drv'135run-agent-nspawn> tribuchet: building on jamie136nixos-system-client-test> structuredAttrs is enabled137nixos-system-relay-test> structuredAttrs is enabled138building '/nix/store/4wqbiai64im3ml10dx1j8m1j0rbbkajc-run-relay-nspawn.drv'139building '/nix/store/9rxyi976zlaywm4zsmrknawdib5n449w-run-client-nspawn.drv'140run-relay-nspawn> tribuchet: building on jamie141run-client-nspawn> tribuchet: building on jamie142building '/nix/store/gw9dxj48811dfx5bqh2z8wynwmc8qgq3-driverConfiguration.json.drv'143driverConfiguration.json> tribuchet: building on jamie144driverConfiguration.json> structuredAttrs is enabled145building '/nix/store/xx56qak5d6s4dalrqcn14ypjcpagbdis-nixos-test-driver-flakelet-relay.drv'146nixos-test-driver-flakelet-relay> tribuchet: building on jamie147nixos-test-driver-flakelet-relay> Running type check (enable/disable: config.skipTypeCheck)148nixos-test-driver-flakelet-relay> See https://nixos.org/manual/nixos/stable/#test-opt-skipTypeCheck149nixos-test-driver-flakelet-relay> All checks passed!150nixos-test-driver-flakelet-relay> Linting test script (enable/disable: config.skipLint)151nixos-test-driver-flakelet-relay> See https://nixos.org/manual/nixos/stable/#test-opt-skipLint152nixos-test-driver-flakelet-relay> All checks passed!153building '/nix/store/3qbgp499vlfvi5qqwvh37126xl2j8vrk-container-test-run-flakelet-relay.drv'154container-test-run-flakelet-relay> tribuchet: building on jamie155container-test-run-flakelet-relay> Machine state will be reset. To keep it, pass --keep-machine-state156container-test-run-flakelet-relay> start all VLans157container-test-run-flakelet-relay> (finished: start all VLans, in 0.00 seconds)158container-test-run-flakelet-relay> Initializing machine ID from random generator.159container-test-run-flakelet-relay> Test will time out and terminate in 3600.0 seconds160container-test-run-flakelet-relay> run the VM test script161container-test-run-flakelet-relay> start all VMs162container-test-run-flakelet-relay> agent: systemd-nspawn running (pid 52)163container-test-run-flakelet-relay> client: systemd-nspawn running (pid 53)164container-test-run-flakelet-relay> relay: systemd-nspawn running (pid 54)165container-test-run-flakelet-relay> agent: Waiting for journal at /build/vm-state-agent/var/log/journal...166container-test-run-flakelet-relay> client: Waiting for journal at /build/vm-state-client/var/log/journal...167container-test-run-flakelet-relay> relay: Waiting for journal at /build/vm-state-relay/var/log/journal...168container-test-run-flakelet-relay> (finished: start all VMs, in 0.00 seconds)169container-test-run-flakelet-relay> relay: waiting for unit flakelet-relay.service170container-test-run-flakelet-relay> nixos-nspawn(agent): TAP vde-tap1 not found; container will be isolated from VDE171container-test-run-flakelet-relay> nixos-nspawn(agent): A common reason for this is that /dev/net is not available in the Nix sandbox. Try adding /dev/net to extra-sandbox-paths.172container-test-run-flakelet-relay> nixos-nspawn(relay): TAP vde-tap1 not found; container will be isolated from VDE173container-test-run-flakelet-relay> nixos-nspawn(relay): A common reason for this is that /dev/net is not available in the Nix sandbox. Try adding /dev/net to extra-sandbox-paths.174container-test-run-flakelet-relay> nixos-nspawn(client): TAP vde-tap1 not found; container will be isolated from VDE175container-test-run-flakelet-relay> nixos-nspawn(client): A common reason for this is that /dev/net is not available in the Nix sandbox. Try adding /dev/net to extra-sandbox-paths.176container-test-run-flakelet-relay> 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.177container-test-run-flakelet-relay> 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.178container-test-run-flakelet-relay> ░ Spawning container relay on /build/vm-state-relay.179container-test-run-flakelet-relay> ░ Spawning container client on /build/vm-state-client.180container-test-run-flakelet-relay> 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.181container-test-run-flakelet-relay> ░ Spawning container agent on /build/vm-state-agent.182container-test-run-flakelet-relay> agent # No journal files were found.183container-test-run-flakelet-relay> agent # No journal boot entry found for the specified boot (+0).184container-test-run-flakelet-relay> client # No journal files were found.185container-test-run-flakelet-relay> client # No journal boot entry found for the specified boot (+0).186container-test-run-flakelet-relay> relay # No journal files were found.187container-test-run-flakelet-relay> relay # No journal boot entry found for the specified boot (+0).188container-test-run-flakelet-relay> client # [23412.795947] client systemd-journald[57]: Journal started189container-test-run-flakelet-relay> client # [23412.795990] client systemd-journald[57]: Runtime Journal (/run/log/journal/ca2f4864e74945a9b6da570cef0966cf) is 8M, max 4G, 3.9G free.190container-test-run-flakelet-relay> agent # [23412.796777] agent systemd-journald[57]: Journal started191container-test-run-flakelet-relay> client # [23412.807853] client systemd[1]: Finished Apply Kernel Variables.192container-test-run-flakelet-relay> agent # [23412.796818] agent systemd-journald[57]: Runtime Journal (/run/log/journal/8a35a01e4af443999b3fa8b64b468abd) is 8M, max 4G, 3.9G free.193container-test-run-flakelet-relay> client # [23412.820346] client systemd[1]: Finished Create Static Device Nodes in /dev gracefully.194container-test-run-flakelet-relay> agent # [23412.808177] agent systemd[1]: Finished Apply Kernel Variables.195container-test-run-flakelet-relay> client # [23412.834962] client systemd[1]: Starting Flush Journal to Persistent Storage...196container-test-run-flakelet-relay> agent # [23412.820604] agent systemd[1]: Finished Create Static Device Nodes in /dev gracefully.197container-test-run-flakelet-relay> client # [23412.835693] client systemd[1]: Starting Create Static Device Nodes in /dev...198container-test-run-flakelet-relay> agent # [23412.835142] agent systemd[1]: Starting Flush Journal to Persistent Storage...199container-test-run-flakelet-relay> client # [23412.873035] client systemd-journald[57]: Time spent on flushing to /var/log/journal/ca2f4864e74945a9b6da570cef0966cf is 1.489ms for 6 entries.200container-test-run-flakelet-relay> agent # [23412.835826] agent systemd[1]: Starting Create Static Device Nodes in /dev...201container-test-run-flakelet-relay> client # [23412.873035] client systemd-journald[57]: System Journal (/var/log/journal/ca2f4864e74945a9b6da570cef0966cf) is 512B, max 4G, 3.9G free.202container-test-run-flakelet-relay> agent # [23412.873533] agent systemd-journald[57]: Time spent on flushing to /var/log/journal/8a35a01e4af443999b3fa8b64b468abd is 1.533ms for 6 entries.203container-test-run-flakelet-relay> client # [23412.882103] client systemd[1]: Finished Create Static Device Nodes in /dev.204container-test-run-flakelet-relay> agent # [23412.873533] agent systemd-journald[57]: System Journal (/var/log/journal/8a35a01e4af443999b3fa8b64b468abd) is 512B, max 4G, 3.9G free.205container-test-run-flakelet-relay> client # [23412.882832] client systemd[1]: Reached target Preparation for Local File Systems.206container-test-run-flakelet-relay> agent # [23412.882030] agent systemd[1]: Finished Flush Journal to Persistent Storage.207container-test-run-flakelet-relay> client # [23412.882942] client systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys208container-test-run-flakelet-relay> agent # [23412.882341] agent systemd[1]: Finished Create Static Device Nodes in /dev.209container-test-run-flakelet-relay> client # [23412.898225] client systemd[1]: Finished Flush Journal to Persistent Storage.210container-test-run-flakelet-relay> agent # [23412.883575] agent systemd[1]: Reached target Preparation for Local File Systems.211container-test-run-flakelet-relay> client # [23412.956015] client systemd[1]: Finished Firewall.212container-test-run-flakelet-relay> agent # [23412.883699] agent systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys213container-test-run-flakelet-relay> client # [23413.814671] client systemd[1]: Mounting /run/wrappers...214container-test-run-flakelet-relay> agent # [23412.962248] agent systemd[1]: Finished Firewall.215container-test-run-flakelet-relay> client # [23413.845728] client systemd[1]: Mounted /run/wrappers.216container-test-run-flakelet-relay> agent # [23413.814662] agent systemd[1]: Mounting /run/wrappers...217container-test-run-flakelet-relay> client # [23413.846767] client systemd[1]: Reached target Local File Systems.218container-test-run-flakelet-relay> agent # [23413.845521] agent systemd[1]: Mounted /run/wrappers.219container-test-run-flakelet-relay> client # [23413.848106] client systemd[1]: Listening on Boot Loader Control Service Socket.220container-test-run-flakelet-relay> relay # [23412.796493] relay systemd-journald[57]: Journal started221container-test-run-flakelet-relay> agent # [23413.846523] agent systemd[1]: Reached target Local File Systems.222container-test-run-flakelet-relay> relay # [23412.796535] relay systemd-journald[57]: Runtime Journal (/run/log/journal/38663ab5ad3c4fc2bb41dcec3f73c24b) is 8M, max 4G, 3.9G free.223container-test-run-flakelet-relay> agent # [23413.847789] agent systemd[1]: Listening on Boot Loader Control Service Socket.224container-test-run-flakelet-relay> relay # [23412.808040] relay systemd[1]: Finished Apply Kernel Variables.225container-test-run-flakelet-relay> agent # [23413.849494] agent systemd[1]: Starting Create SUID/SGID Wrappers...226container-test-run-flakelet-relay> relay # [23412.820413] relay systemd[1]: Finished Create Static Device Nodes in /dev gracefully.227container-test-run-flakelet-relay> agent # [23413.849543] agent systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container228container-test-run-flakelet-relay> relay # [23412.835305] relay systemd[1]: Starting Flush Journal to Persistent Storage...229container-test-run-flakelet-relay> relay # [23412.835884] relay systemd[1]: Starting Create Static Device Nodes in /dev...230container-test-run-flakelet-relay> relay # [23412.873404] relay systemd-journald[57]: Time spent on flushing to /var/log/journal/38663ab5ad3c4fc2bb41dcec3f73c24b is 1.423ms for 6 entries.231container-test-run-flakelet-relay> relay # [23412.873404] relay systemd-journald[57]: System Journal (/var/log/journal/38663ab5ad3c4fc2bb41dcec3f73c24b) is 512B, max 4G, 3.9G free.232container-test-run-flakelet-relay> relay # [23412.882186] relay systemd[1]: Finished Create Static Device Nodes in /dev.233container-test-run-flakelet-relay> relay # [23412.882876] relay systemd[1]: Reached target Preparation for Local File Systems.234container-test-run-flakelet-relay> relay # [23412.883028] relay systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys235container-test-run-flakelet-relay> relay # [23412.896382] relay systemd[1]: Finished Flush Journal to Persistent Storage.236container-test-run-flakelet-relay> relay # [23412.966530] relay systemd[1]: Finished Firewall.237container-test-run-flakelet-relay> relay # [23413.795905] relay systemd[1]: Mounting /run/wrappers...238container-test-run-flakelet-relay> relay # [23413.845444] relay systemd[1]: Mounted /run/wrappers.239container-test-run-flakelet-relay> relay # [23413.846544] relay systemd[1]: Reached target Local File Systems.240container-test-run-flakelet-relay> relay # [23413.847781] relay systemd[1]: Listening on Boot Loader Control Service Socket.241container-test-run-flakelet-relay> relay # [23413.849167] relay systemd[1]: Starting Create SUID/SGID Wrappers...242container-test-run-flakelet-relay> relay # [23413.849213] relay systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container243container-test-run-flakelet-relay> relay # [23413.850682] relay systemd[1]: Starting Save Transient machine-id to Disk...244container-test-run-flakelet-relay> relay # [23413.851955] relay systemd[1]: Starting Create System Files and Directories...245container-test-run-flakelet-relay> agent # [23413.850715] agent systemd[1]: Starting Save Transient machine-id to Disk...246container-test-run-flakelet-relay> client # [23413.849638] client systemd[1]: Starting Create SUID/SGID Wrappers...247container-test-run-flakelet-relay> agent # [23413.852073] agent systemd[1]: Starting Create System Files and Directories...248container-test-run-flakelet-relay> client # [23413.849684] client systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container249container-test-run-flakelet-relay> relay # [23413.869615] relay systemd-tmpfiles[169]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted250container-test-run-flakelet-relay> client # [23413.850895] client systemd[1]: Starting Save Transient machine-id to Disk...251container-test-run-flakelet-relay> relay # [23413.870065] relay systemd-tmpfiles[169]: fchmod() of /var/log/journal failed: Operation not permitted252container-test-run-flakelet-relay> agent # [23413.870580] agent systemd-tmpfiles[161]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted253container-test-run-flakelet-relay> relay # [23413.870625] relay suid-sgid-wrappers-start[176]: chmod: changing permissions of '/run/wrappers/wrappers.wogDWL42B4/chsh': Operation not permitted254container-test-run-flakelet-relay> agent # [23413.870996] agent systemd-tmpfiles[161]: fchmod() of /var/log/journal failed: Operation not permitted255container-test-run-flakelet-relay> relay # [23413.870397] relay systemd-tmpfiles[169]: fchmod() of /var/log/journal/38663ab5ad3c4fc2bb41dcec3f73c24b failed: Operation not permitted256container-test-run-flakelet-relay> agent # [23413.871313] agent systemd-tmpfiles[161]: fchmod() of /var/log/journal/8a35a01e4af443999b3fa8b64b468abd failed: Operation not permitted257container-test-run-flakelet-relay> relay # [23413.870752] relay systemd-tmpfiles[169]: fchmod() of /run/log/journal failed: Operation not permitted258container-test-run-flakelet-relay> agent # [23413.871647] agent systemd-tmpfiles[161]: fchmod() of /run/log/journal failed: Operation not permitted259container-test-run-flakelet-relay> client # [23413.852106] client systemd[1]: Starting Create System Files and Directories...260container-test-run-flakelet-relay> client # [23413.870651] client systemd-tmpfiles[161]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted261container-test-run-flakelet-relay> client # [23413.871071] client systemd-tmpfiles[161]: fchmod() of /var/log/journal failed: Operation not permitted262container-test-run-flakelet-relay> client # [23413.871383] client systemd-tmpfiles[161]: fchmod() of /var/log/journal/ca2f4864e74945a9b6da570cef0966cf failed: Operation not permitted263container-test-run-flakelet-relay> client # [23413.871716] client systemd-tmpfiles[161]: fchmod() of /run/log/journal failed: Operation not permitted264container-test-run-flakelet-relay> agent # [23413.874403] agent suid-sgid-wrappers-start[168]: chmod: changing permissions of '/run/wrappers/wrappers.pUZonPYA6T/chsh': Operation not permitted265container-test-run-flakelet-relay> agent # [23413.876290] agent systemd[1]: suid-sgid-wrappers.service: Main process exited, code=exited, status=1/FAILURE266container-test-run-flakelet-relay> agent # [23413.876380] agent systemd[1]: suid-sgid-wrappers.service: Failed with result 'exit-code'.267container-test-run-flakelet-relay> agent # [23413.876585] agent systemd[1]: Failed to start Create SUID/SGID Wrappers.268container-test-run-flakelet-relay> agent # [23413.877035] agent systemd[1]: Finished Create System Files and Directories.269container-test-run-flakelet-relay> agent # [23413.892102] agent systemd[1]: Starting Rebuild Journal Catalog...270container-test-run-flakelet-relay> agent # [23413.893046] agent systemd[1]: Starting Record System Boot/Shutdown in UTMP...271container-test-run-flakelet-relay> agent # [23413.959495] agent systemd[1]: etc-machine\x2did.mount: Deactivated successfully.272container-test-run-flakelet-relay> agent # [23413.960118] agent systemd[1]: Finished Save Transient machine-id to Disk.273container-test-run-flakelet-relay> agent # [23413.960235] agent systemd[1]: Finished Rebuild Journal Catalog.274container-test-run-flakelet-relay> agent # [23413.963978] agent systemd[1]: Starting Update is Completed...275container-test-run-flakelet-relay> agent # [23413.964766] agent systemd[1]: Finished Record System Boot/Shutdown in UTMP.276container-test-run-flakelet-relay> agent # [23413.973204] agent systemd[1]: Finished Update is Completed.277container-test-run-flakelet-relay> agent # [23413.973333] agent systemd[1]: Reached target System Initialization.278container-test-run-flakelet-relay> agent # [23413.973390] agent systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container279container-test-run-flakelet-relay> agent # [23413.973411] agent systemd[1]: Started Daily Cleanup of Temporary Directories.280container-test-run-flakelet-relay> agent # [23413.973424] agent systemd[1]: Reached target Timer Units.281container-test-run-flakelet-relay> agent # [23413.973518] agent systemd[1]: Listening on D-Bus System Message Bus Socket.282container-test-run-flakelet-relay> agent # [23413.973677] agent systemd[1]: Listening on Nix Daemon Socket.283container-test-run-flakelet-relay> agent # [23413.973772] agent systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.284container-test-run-flakelet-relay> agent # [23413.973783] agent systemd[1]: Reached target Socket Units.285container-test-run-flakelet-relay> agent # [23413.973808] agent systemd[1]: Reached target Basic System.286container-test-run-flakelet-relay> agent # [23413.975224] agent systemd[1]: Starting Re-link flakelet services at boot...287container-test-run-flakelet-relay> agent # [23413.976153] agent systemd[1]: Starting Import lastlog data into lastlog2 database...288container-test-run-flakelet-relay> agent # [23413.977314] agent systemd[1]: Starting Name Service Cache Daemon (nsncd)...289container-test-run-flakelet-relay> agent # [23413.977999] agent systemd[1]: Starting resolvconf update...290container-test-run-flakelet-relay> agent # [23413.987199] agent systemd[1]: Finished Re-link flakelet services at boot.291container-test-run-flakelet-relay> agent # [23413.988372] agent systemd[1]: Starting Reconcile flakelet services with the host configuration...292container-test-run-flakelet-relay> agent # [23413.993980] agent systemd[1]: Finished Import lastlog data into lastlog2 database.293container-test-run-flakelet-relay> agent # [23413.999112] agent systemd[1]: Finished Reconcile flakelet services with the host configuration.294container-test-run-flakelet-relay> agent # [23414.066465] agent systemd[1]: nscd.service: Deactivated successfully.295container-test-run-flakelet-relay> relay # [23413.876485] relay systemd[1]: suid-sgid-wrappers.service: Main process exited, code=exited, status=1/FAILURE296container-test-run-flakelet-relay> relay # [23413.876598] relay systemd[1]: suid-sgid-wrappers.service: Failed with result 'exit-code'.297container-test-run-flakelet-relay> relay # [23413.876643] relay systemd[1]: Failed to start Create SUID/SGID Wrappers.298container-test-run-flakelet-relay> relay # [23413.877140] relay systemd[1]: Finished Create System Files and Directories.299container-test-run-flakelet-relay> relay # [23413.892249] relay systemd[1]: Starting Rebuild Journal Catalog...300container-test-run-flakelet-relay> relay # [23413.892918] relay systemd[1]: Starting Record System Boot/Shutdown in UTMP...301container-test-run-flakelet-relay> relay # [23413.955821] relay systemd[1]: etc-machine\x2did.mount: Deactivated successfully.302container-test-run-flakelet-relay> relay # [23413.956617] relay systemd[1]: Finished Save Transient machine-id to Disk.303container-test-run-flakelet-relay> relay # [23413.959565] relay systemd[1]: Finished Rebuild Journal Catalog.304container-test-run-flakelet-relay> relay # [23413.959768] relay systemd[1]: Finished Record System Boot/Shutdown in UTMP.305container-test-run-flakelet-relay> relay # [23413.961900] relay systemd[1]: Starting Update is Completed...306container-test-run-flakelet-relay> relay # [23413.971716] relay systemd[1]: Finished Update is Completed.307container-test-run-flakelet-relay> relay # [23413.971844] relay systemd[1]: Reached target System Initialization.308container-test-run-flakelet-relay> relay # [23413.971923] relay systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container309container-test-run-flakelet-relay> relay # [23413.971947] relay systemd[1]: Started Daily Cleanup of Temporary Directories.310container-test-run-flakelet-relay> relay # [23413.971960] relay systemd[1]: Reached target Timer Units.311container-test-run-flakelet-relay> relay # [23413.972077] relay systemd[1]: Listening on D-Bus System Message Bus Socket.312container-test-run-flakelet-relay> relay # [23413.972274] relay systemd[1]: Listening on Nix Daemon Socket.313container-test-run-flakelet-relay> relay # [23413.972387] relay systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.314container-test-run-flakelet-relay> relay # [23413.972404] relay systemd[1]: Reached target Socket Units.315container-test-run-flakelet-relay> relay # [23413.972437] relay systemd[1]: Reached target Basic System.316container-test-run-flakelet-relay> relay # [23413.973642] relay systemd[1]: Started device-mock.service.317container-test-run-flakelet-relay> relay # [23413.974663] relay systemd[1]: Starting Re-link flakelet services at boot...318container-test-run-flakelet-relay> relay # [23413.975641] relay systemd[1]: Starting Import lastlog data into lastlog2 database...319container-test-run-flakelet-relay> relay # [23413.976532] relay systemd[1]: Starting Name Service Cache Daemon (nsncd)...320container-test-run-flakelet-relay> relay # [23413.977211] relay systemd[1]: Starting resolvconf update...321container-test-run-flakelet-relay> relay # [23413.987176] relay systemd[1]: Finished Re-link flakelet services at boot.322container-test-run-flakelet-relay> relay # [23413.988446] relay systemd[1]: Starting Reconcile flakelet services with the host configuration...323container-test-run-flakelet-relay> relay # [23413.993200] relay systemd[1]: Finished Import lastlog data into lastlog2 database.324container-test-run-flakelet-relay> relay # [23413.999876] relay systemd[1]: Finished Reconcile flakelet services with the host configuration.325container-test-run-flakelet-relay> client # [23413.874191] client suid-sgid-wrappers-start[168]: chmod: changing permissions of '/run/wrappers/wrappers.XFxXZF9mni/chsh': Operation not permitted326container-test-run-flakelet-relay> client # [23413.876949] client systemd[1]: suid-sgid-wrappers.service: Main process exited, code=exited, status=1/FAILURE327container-test-run-flakelet-relay> client # [23413.876990] client systemd[1]: suid-sgid-wrappers.service: Failed with result 'exit-code'.328container-test-run-flakelet-relay> client # [23413.877067] client systemd[1]: Failed to start Create SUID/SGID Wrappers.329container-test-run-flakelet-relay> client # [23413.877733] client systemd[1]: Finished Create System Files and Directories.330container-test-run-flakelet-relay> client # [23413.892442] client systemd[1]: Starting Rebuild Journal Catalog...331container-test-run-flakelet-relay> client # [23413.893076] client systemd[1]: Starting Record System Boot/Shutdown in UTMP...332container-test-run-flakelet-relay> client # [23413.955621] client systemd[1]: etc-machine\x2did.mount: Deactivated successfully.333container-test-run-flakelet-relay> client # [23413.956546] client systemd[1]: Finished Save Transient machine-id to Disk.334container-test-run-flakelet-relay> client # [23413.958422] client systemd[1]: Finished Rebuild Journal Catalog.335container-test-run-flakelet-relay> client # [23413.960329] client systemd[1]: Starting Update is Completed...336container-test-run-flakelet-relay> client # [23413.960491] client systemd[1]: Finished Record System Boot/Shutdown in UTMP.337container-test-run-flakelet-relay> client # [23413.968545] client systemd[1]: Finished Update is Completed.338container-test-run-flakelet-relay> client # [23413.968612] client systemd[1]: Reached target System Initialization.339container-test-run-flakelet-relay> client # [23413.968670] client systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container340container-test-run-flakelet-relay> client # [23413.968691] client systemd[1]: Started Daily Cleanup of Temporary Directories.341container-test-run-flakelet-relay> client # [23413.968705] client systemd[1]: Reached target Timer Units.342container-test-run-flakelet-relay> client # [23413.968798] client systemd[1]: Listening on D-Bus System Message Bus Socket.343container-test-run-flakelet-relay> client # [23413.968962] client systemd[1]: Listening on Nix Daemon Socket.344container-test-run-flakelet-relay> client # [23413.969059] client systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.345container-test-run-flakelet-relay> client # [23413.969074] client systemd[1]: Reached target Socket Units.346container-test-run-flakelet-relay> client # [23413.969102] client systemd[1]: Reached target Basic System.347container-test-run-flakelet-relay> client # [23413.970141] client systemd[1]: Starting Import lastlog data into lastlog2 database...348container-test-run-flakelet-relay> client # [23413.971034] client systemd[1]: Starting Name Service Cache Daemon (nsncd)...349container-test-run-flakelet-relay> client # [23413.971801] client systemd[1]: Starting resolvconf update...350container-test-run-flakelet-relay> client # [23413.988391] client systemd[1]: Finished Import lastlog data into lastlog2 database.351container-test-run-flakelet-relay> client # [23414.042291] client systemd[1]: Finished resolvconf update.352container-test-run-flakelet-relay> client # [23414.042421] client systemd[1]: Reached target Preparation for Network.353container-test-run-flakelet-relay> client # [23414.043406] client systemd[1]: Starting Address configuration of eth1...354container-test-run-flakelet-relay> client # [23414.044261] client systemd[1]: Starting Extra networking commands....355container-test-run-flakelet-relay> client # [23414.082837] client systemd[1]: nscd.service: Deactivated successfully.356container-test-run-flakelet-relay> client # [23414.082929] client systemd[1]: Stopped Name Service Cache Daemon (nsncd).357container-test-run-flakelet-relay> client # [23414.085845] client systemd[1]: Starting Name Service Cache Daemon (nsncd)...358container-test-run-flakelet-relay> client # [23414.099494] client network-addresses-eth1-start[233]: adding address 192.168.1.2/24... done359container-test-run-flakelet-relay> client # [23414.102779] client network-addresses-eth1-start[233]: adding address 2001:db8:1::2/64... done360container-test-run-flakelet-relay> client # [23414.106743] client systemd[1]: Finished Address configuration of eth1.361container-test-run-flakelet-relay> client # [23414.150361] client systemd[1]: Finished Extra networking commands..362container-test-run-flakelet-relay> client # [23414.150492] client systemd[1]: Reached target Network.363container-test-run-flakelet-relay> client # [23414.190584] client systemd[1]: nscd.service: Deactivated successfully.364container-test-run-flakelet-relay> client # [23414.190733] client systemd[1]: Stopped Name Service Cache Daemon (nsncd).365container-test-run-flakelet-relay> client # [23414.193297] client systemd[1]: Starting Name Service Cache Daemon (nsncd)...366container-test-run-flakelet-relay> agent # [23414.082842] agent systemd[1]: Stopped Name Service Cache Daemon (nsncd).367container-test-run-flakelet-relay> agent # [23414.084531] agent systemd[1]: Finished resolvconf update.368container-test-run-flakelet-relay> agent # [23414.085302] agent systemd[1]: Reached target Preparation for Network.369container-test-run-flakelet-relay> agent # [23414.086315] agent systemd[1]: Starting Address configuration of eth1...370container-test-run-flakelet-relay> agent # [23414.086923] agent systemd[1]: Starting Extra networking commands....371container-test-run-flakelet-relay> agent # [23414.087720] agent systemd[1]: Starting Name Service Cache Daemon (nsncd)...372container-test-run-flakelet-relay> agent # [23414.106864] agent network-addresses-eth1-start[240]: adding address 192.168.1.1/24... done373container-test-run-flakelet-relay> agent # [23414.109806] agent network-addresses-eth1-start[240]: adding address 2001:db8:1::1/64... done374container-test-run-flakelet-relay> agent # [23414.114575] agent systemd[1]: Finished Address configuration of eth1.375container-test-run-flakelet-relay> agent # [23414.184237] agent systemd[1]: Finished Extra networking commands..376container-test-run-flakelet-relay> agent # [23414.184469] agent systemd[1]: nscd.service: Deactivated successfully.377container-test-run-flakelet-relay> agent # [23414.184647] agent systemd[1]: Stopped Name Service Cache Daemon (nsncd).378container-test-run-flakelet-relay> agent # [23414.186898] agent systemd[1]: Reached target Network.379container-test-run-flakelet-relay> agent # [23414.188021] agent systemd[1]: Starting Name Service Cache Daemon (nsncd)...380container-test-run-flakelet-relay> agent # [23414.188802] agent systemd[1]: Starting Update flakelet service app...381container-test-run-flakelet-relay> agent # [23414.189619] agent systemd[1]: Starting Update flakelet service flakelet-agent...382container-test-run-flakelet-relay> agent # [23414.190419] agent systemd[1]: Starting Update flakelet service other...383container-test-run-flakelet-relay> agent # [23414.200560] agent flakelet[308]: app: using prebuilt artifact /nix/store/hysydfr96ds875vr0jgw5rjxi4p39h2j-flakelet-app-v1384container-test-run-flakelet-relay> agent # [23414.200966] agent flakelet[309]: flakelet-agent: using prebuilt artifact /nix/store/5wdcda2phs8z3zc9gabz8187jkk7wyhk-flakelet-flakelet-agent385container-test-run-flakelet-relay> agent # [23414.201281] agent flakelet[308]: app: activating generation 1386container-test-run-flakelet-relay> agent # [23414.201538] agent flakelet[309]: flakelet-agent: activating generation 1387container-test-run-flakelet-relay> agent # [23414.201776] agent flakelet[310]: other: using prebuilt artifact /nix/store/hmr990n0b4v6rpzrcpyyw738r9xi7z95-flakelet-other-v1388container-test-run-flakelet-relay> agent # [23414.202297] agent flakelet[310]: other: activating generation 1389container-test-run-flakelet-relay> agent # [23414.206229] agent systemd[1]: Reload requested from client PID 313 ('systemctl') (unit flakelet-flakelet-agent.service)...390container-test-run-flakelet-relay> agent # [23414.206262] agent systemd[1]: Reloading...391container-test-run-flakelet-relay> relay # [23414.082820] relay systemd[1]: nscd.service: Deactivated successfully.392container-test-run-flakelet-relay> relay # [23414.082893] relay systemd[1]: Stopped Name Service Cache Daemon (nsncd).393container-test-run-flakelet-relay> relay # [23414.084541] relay systemd[1]: Finished resolvconf update.394container-test-run-flakelet-relay> relay # [23414.085371] relay systemd[1]: Reached target Preparation for Network.395container-test-run-flakelet-relay> relay # [23414.086527] relay systemd[1]: Starting Address configuration of eth1...396container-test-run-flakelet-relay> relay # [23414.087170] relay systemd[1]: Starting Extra networking commands....397container-test-run-flakelet-relay> relay # [23414.088029] relay systemd[1]: Starting Name Service Cache Daemon (nsncd)...398container-test-run-flakelet-relay> relay # [23414.107310] relay network-addresses-eth1-start[249]: adding address 192.168.1.3/24... done399container-test-run-flakelet-relay> relay # [23414.110507] relay network-addresses-eth1-start[249]: adding address 2001:db8:1::3/64... done400container-test-run-flakelet-relay> relay # [23414.114605] relay systemd[1]: Finished Address configuration of eth1.401container-test-run-flakelet-relay> relay # [23414.151017] relay systemd[1]: Finished Extra networking commands..402container-test-run-flakelet-relay> relay # [23414.151167] relay systemd[1]: Reached target Network.403container-test-run-flakelet-relay> relay # [23414.152467] relay systemd[1]: Starting Dnsmasq Daemon...404container-test-run-flakelet-relay> relay # [23414.153394] relay systemd[1]: Starting Nginx Web Server...405container-test-run-flakelet-relay> relay # [23414.154197] relay systemd[1]: Starting Update flakelet service flakelet-relay...406container-test-run-flakelet-relay> relay # [23414.192155] relay flakelet[311]: flakelet-relay: using prebuilt artifact /nix/store/zad6kx41hxqj8x6fsfsd6ydzw32z2fw7-flakelet-flakelet-relay407container-test-run-flakelet-relay> relay # [23414.192851] relay flakelet[311]: flakelet-relay: activating generation 1408container-test-run-flakelet-relay> relay # [23414.196721] relay systemd[1]: Reload requested from client PID 316 ('systemctl') (unit flakelet-flakelet-relay.service)...409container-test-run-flakelet-relay> relay # [23414.196762] relay systemd[1]: Reloading...410container-test-run-flakelet-relay> client # [23414.523789] client systemd[1]: Started Name Service Cache Daemon (nsncd).411container-test-run-flakelet-relay> client # [23414.524140] client nsncd[303]: Sep 06 13:34:52.485 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"412container-test-run-flakelet-relay> client # [23414.523894] client systemd[1]: Reached target Host and Network Name Lookups.413container-test-run-flakelet-relay> client # [23414.523995] client systemd[1]: Reached target User and Group Name Lookups.414container-test-run-flakelet-relay> client # [23414.526470] client systemd[1]: Starting User Login Management...415container-test-run-flakelet-relay> client # [23414.533991] client systemd[1]: Starting Permit User Sessions...416container-test-run-flakelet-relay> client # [23414.565096] client systemd[1]: Finished Permit User Sessions.417container-test-run-flakelet-relay> client # [23414.567247] client systemd[1]: Started Console Getty.418container-test-run-flakelet-relay> client # [23414.567313] client systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0419container-test-run-flakelet-relay> client # [23414.567340] client systemd[1]: Reached target Login Prompts.420container-test-run-flakelet-relay> relay # [23414.542379] relay nsncd[251]: Sep 06 13:34:52.504 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"421container-test-run-flakelet-relay> relay # [23414.618295] relay systemd[1]: Reloading finished in 421 ms.422container-test-run-flakelet-relay> relay # [23414.737327] relay systemd[1]: Started Name Service Cache Daemon (nsncd).423container-test-run-flakelet-relay> relay # [23414.738248] relay systemd[1]: Reached target Host and Network Name Lookups.424container-test-run-flakelet-relay> relay # [23414.738297] relay systemd[1]: Reached target User and Group Name Lookups.425container-test-run-flakelet-relay> relay # [23414.739366] relay systemd[1]: Starting User Login Management...426container-test-run-flakelet-relay> relay # [23414.740467] relay systemd[1]: Starting Permit User Sessions...427container-test-run-flakelet-relay> relay # [23414.746856] relay systemd[1]: Reload requested from client PID 357 ('systemctl') (unit flakelet-flakelet-relay.service)...428container-test-run-flakelet-relay> relay # [23414.746887] relay systemd[1]: Reloading...429container-test-run-flakelet-relay> agent # [23414.516295] agent nsncd[307]: Sep 06 13:34:52.478 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"430container-test-run-flakelet-relay> agent # [23414.610647] agent systemd[1]: Reloading finished in 404 ms.431container-test-run-flakelet-relay> agent # [23414.739975] agent systemd[1]: Started Name Service Cache Daemon (nsncd).432container-test-run-flakelet-relay> agent # [23414.740991] agent systemd[1]: Reload requested from client PID 312 ('systemctl') (unit flakelet-app.service)...433container-test-run-flakelet-relay> agent # [23414.741042] agent systemd[1]: Reloading...434container-test-run-flakelet-relay> relay # [23414.863921] relay dnsmasq-pre-start[376]: dnsmasq: syntax check OK.435container-test-run-flakelet-relay> agent # [23415.121351] agent systemd[1]: Reloading finished in 379 ms.436container-test-run-flakelet-relay> agent # [23415.241763] agent systemd[1]: Reload requested from client PID 314 ('systemctl') (unit flakelet-other.service)...437container-test-run-flakelet-relay> agent # [23415.241817] agent systemd[1]: Reloading...438container-test-run-flakelet-relay> relay # [23415.117915] relay systemd[1]: Reloading finished in 370 ms.439container-test-run-flakelet-relay> relay # [23415.238966] relay systemd[1]: Finished Permit User Sessions.440container-test-run-flakelet-relay> relay # [23415.269753] relay systemd[1]: Started Console Getty.441container-test-run-flakelet-relay> relay # [23415.269794] relay systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0442container-test-run-flakelet-relay> relay # [23415.269809] relay systemd[1]: Reached target Login Prompts.443container-test-run-flakelet-relay> relay # [23415.273172] relay systemd[1]: Starting flakelet-relay...444container-test-run-flakelet-relay> agent # [23415.600897] agent systemd[1]: Reloading finished in 358 ms.445container-test-run-flakelet-relay> agent # [23415.678471] agent systemd[1]: Reached target Host and Network Name Lookups.446container-test-run-flakelet-relay> agent # [23415.678610] agent systemd[1]: Reached target User and Group Name Lookups.447container-test-run-flakelet-relay> agent # [23415.680116] agent systemd[1]: Starting User Login Management...448container-test-run-flakelet-relay> agent # [23415.681277] agent systemd[1]: Starting Permit User Sessions...449container-test-run-flakelet-relay> agent # [23415.689037] agent systemd[1]: Reload requested from client PID 405 ('systemctl') (unit flakelet-flakelet-agent.service)...450container-test-run-flakelet-relay> agent # [23415.689066] agent systemd[1]: Reloading...451container-test-run-flakelet-relay> relay # [23415.717157] relay nginx-pre-start[402]: nginx: the configuration file /nix/store/01avjj26wcihp926xgasszcj5s0k7cbs-nginx.conf syntax is ok452container-test-run-flakelet-relay> relay # [23415.717923] relay nginx-pre-start[402]: nginx: configuration file /nix/store/01avjj26wcihp926xgasszcj5s0k7cbs-nginx.conf test is successful453container-test-run-flakelet-relay> relay # [23415.732881] relay systemd[1]: Started Nginx Web Server.454container-test-run-flakelet-relay> relay # [23415.909848] relay systemd[1]: Starting D-Bus System Message Bus...455container-test-run-flakelet-relay> client # [23415.874435] client systemd-logind[315]: New seat seat0.456container-test-run-flakelet-relay> client # [23415.874880] client systemd[1]: Started User Login Management.457container-test-run-flakelet-relay> client # [23415.877091] client systemd[1]: Starting D-Bus System Message Bus...458container-test-run-flakelet-relay> client # [23415.878561] client systemd[1]: Starting linger-users.service...459container-test-run-flakelet-relay> client # [23415.912240] client systemd[1]: linger-users.service: Deactivated successfully.460container-test-run-flakelet-relay> client # [23415.912573] client systemd[1]: Finished linger-users.service.461container-test-run-flakelet-relay> client # [23415.912914] client systemd[1]: Reached target Multi-User System.462container-test-run-flakelet-relay> agent # [23416.085670] agent systemd[1]: Reloading finished in 396 ms.463container-test-run-flakelet-relay> agent # [23416.171738] agent systemd[1]: Finished Permit User Sessions.464container-test-run-flakelet-relay> agent # [23416.177931] agent systemd[1]: Reload requested from client PID 408 ('systemctl') (unit flakelet-app.service)...465container-test-run-flakelet-relay> agent # [23416.177970] agent systemd[1]: Reloading...466container-test-run-flakelet-relay> relay # [23416.133096] relay systemd-logind[355]: New seat seat0.467container-test-run-flakelet-relay> relay # [23416.133477] relay systemd[1]: Started User Login Management.468container-test-run-flakelet-relay> relay # [23416.135731] relay systemd[1]: Starting linger-users.service...469container-test-run-flakelet-relay> relay # [23416.182250] relay systemd[1]: linger-users.service: Deactivated successfully.470container-test-run-flakelet-relay> relay # [23416.182549] relay systemd[1]: Finished linger-users.service.471container-test-run-flakelet-relay> client # [23416.408487] client dbus-broker-launch[325]: Looking up NSS user entry for 'systemd-timesync'...472container-test-run-flakelet-relay> client # [23416.410175] client dbus-broker-launch[325]: NSS returned no entry for 'systemd-timesync'473container-test-run-flakelet-relay> client # [23416.410175] client dbus-broker-launch[325]: Invalid user-name in /nix/store/5qxx3h6bg6pvpj92racx2s2lmks8rx12-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"474container-test-run-flakelet-relay> client # [23416.410967] client systemd[1]: Started D-Bus System Message Bus.475container-test-run-flakelet-relay> client # [23416.411503] client systemd[1]: Startup finished in 4.053s.476container-test-run-flakelet-relay> client # [23416.416732] client dbus-broker-launch[325]: Ready477container-test-run-flakelet-relay> agent # [23416.522628] agent systemd[1]: Reloading finished in 344 ms.478container-test-run-flakelet-relay> agent # [23416.601847] agent systemd[1]: Reload requested from client PID 443 ('systemctl') (unit flakelet-other.service)...479container-test-run-flakelet-relay> agent # [23416.601887] agent systemd[1]: Reloading...480container-test-run-flakelet-relay> relay # [23416.446788] relay dbus-broker-launch[408]: Looking up NSS user entry for 'systemd-timesync'...481container-test-run-flakelet-relay> relay # [23416.447591] relay dbus-broker-launch[408]: NSS returned no entry for 'systemd-timesync'482container-test-run-flakelet-relay> relay # [23416.447591] relay dbus-broker-launch[408]: Invalid user-name in /nix/store/jc4z11b21x1n6xiiply0x9mfc84afs4d-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"483container-test-run-flakelet-relay> relay # [23416.448308] relay systemd[1]: Started D-Bus System Message Bus.484container-test-run-flakelet-relay> relay # [23416.454317] relay dbus-broker-launch[408]: Ready485container-test-run-flakelet-relay> relay # [23416.457794] relay systemd[1]: Started Dnsmasq Daemon.486container-test-run-flakelet-relay> relay # [23416.460126] relay dnsmasq[394]: started, version 2.93 cachesize 150487container-test-run-flakelet-relay> relay # [23416.460143] relay dnsmasq[394]: compile time options: IPv6 GNU-getopt DBus no-UBus no-i18n IDN2 DHCP DHCPv6 no-Lua TFTP conntrack ipset nftset auth DNSSEC loop-detect inotify dumpfile488container-test-run-flakelet-relay> relay # [23416.460158] relay dnsmasq[394]: DBus support enabled: connected to system bus489container-test-run-flakelet-relay> relay # [23416.460174] relay dnsmasq[394]: no upstream servers configured - please set them from DBus490container-test-run-flakelet-relay> relay # [23416.460292] relay dnsmasq[394]: read /etc/hosts - 9 names491container-test-run-flakelet-relay> agent # [23416.938207] agent systemd[1]: Reloading finished in 335 ms.492container-test-run-flakelet-relay> agent # [23417.007549] agent systemd[1]: Started Console Getty.493container-test-run-flakelet-relay> agent # [23417.008653] agent systemd[1]: Starting flakelet-agent...494container-test-run-flakelet-relay> agent # [23417.008667] agent systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0495container-test-run-flakelet-relay> agent # [23417.008736] agent systemd[1]: Reached target Login Prompts.496container-test-run-flakelet-relay> agent # [23417.012092] agent systemd[1]: Starting app v1...497container-test-run-flakelet-relay> agent # [23417.013292] agent systemd[1]: Starting other v1...498container-test-run-flakelet-relay> agent # [23417.050942] agent systemd[1]: Started other v1.499container-test-run-flakelet-relay> agent # [23417.051166] agent systemd[1]: Started app v1.500container-test-run-flakelet-relay> agent # [23417.057660] agent flakelet[310]: other: updated to generation 1501container-test-run-flakelet-relay> agent # [23417.058218] agent flakelet[308]: app: updated to generation 1502container-test-run-flakelet-relay> agent # [23417.059730] agent systemd[1]: Finished Update flakelet service app.503container-test-run-flakelet-relay> agent # [23417.060052] agent systemd[1]: Finished Update flakelet service other.504container-test-run-flakelet-relay> agent # [23417.082777] agent flakelet-agent[502]: {"timestamp":"2026-09-06T13:34:55.044440Z","level":"INFO","message":"local change","flakelet":"app","generation":1,"by":"host activation","target":"flakelet_relay::agent::jobs"}505container-test-run-flakelet-relay> agent # [23417.087223] agent systemd-logind[406]: New seat seat0.506container-test-run-flakelet-relay> agent # [23417.088069] agent systemd[1]: Starting D-Bus System Message Bus...507container-test-run-flakelet-relay> agent # [23417.088173] agent systemd[1]: Started User Login Management.508container-test-run-flakelet-relay> agent # [23417.089200] agent systemd[1]: Starting linger-users.service...509container-test-run-flakelet-relay> agent # [23417.095227] agent flakelet-agent[502]: {"timestamp":"2026-09-06T13:34:55.057018Z","level":"INFO","message":"local change","flakelet":"other","generation":1,"by":"host activation","target":"flakelet_relay::agent::jobs"}510container-test-run-flakelet-relay> agent # [23417.095532] agent systemd[1]: Started flakelet-agent.511container-test-run-flakelet-relay> agent # [23417.098882] agent flakelet-agent[502]: {"timestamp":"2026-09-06T13:34:55.060688Z","level":"WARN","message":"connect failed: connect relay:7443: Connection refused (os error 111)","relay":"https://relay:7443","target":"flakelet_relay::agent::conn"}512container-test-run-flakelet-relay> agent # [23417.101521] agent flakelet[309]: flakelet-agent: updated to generation 1513container-test-run-flakelet-relay> agent # [23417.119247] agent systemd[1]: Finished Update flakelet service flakelet-agent.514container-test-run-flakelet-relay> agent # [23417.120649] agent systemd[1]: Reached target flakelet managed services.515container-test-run-flakelet-relay> agent # [23417.128685] agent systemd[1]: linger-users.service: Deactivated successfully.516container-test-run-flakelet-relay> agent # [23417.128857] agent systemd[1]: Finished linger-users.service.517container-test-run-flakelet-relay> agent # [23417.130230] agent systemd[1]: Reached target Multi-User System.518container-test-run-flakelet-relay> relay # [23417.272483] relay flakelet-relay[400]: {"timestamp":"2026-09-06T13:34:55.233776Z","level":"INFO","message":"listening on 127.0.0.1:7400","target":"flakelet_relay::relay::server"}519container-test-run-flakelet-relay> relay # [23417.272483] relay flakelet-relay[400]: {"timestamp":"2026-09-06T13:34:55.234269Z","level":"INFO","message":"listening on [::]:7443","target":"flakelet_relay::relay::server"}520container-test-run-flakelet-relay> relay # [23417.272656] relay systemd[1]: Started flakelet-relay.521container-test-run-flakelet-relay> relay # [23417.279567] relay systemd[1]: Starting health for flakelet-relay...522container-test-run-flakelet-relay> agent # [23417.522491] agent dbus-broker-launch[899]: Looking up NSS user entry for 'systemd-timesync'...523container-test-run-flakelet-relay> agent # [23417.523838] agent dbus-broker-launch[899]: NSS returned no entry for 'systemd-timesync'524container-test-run-flakelet-relay> agent # [23417.523838] agent dbus-broker-launch[899]: Invalid user-name in /nix/store/9jm4z4kmif1yf3786ghlinqlwra7q4q4-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"525container-test-run-flakelet-relay> agent # [23417.524350] agent systemd[1]: Started D-Bus System Message Bus.526container-test-run-flakelet-relay> agent # [23417.524663] agent systemd[1]: Startup finished in 5.164s.527container-test-run-flakelet-relay> agent # [23417.532838] agent dbus-broker-launch[899]: Ready528container-test-run-flakelet-relay> relay # [23417.540212] relay systemd[1]: flakelet-relay-health.service: Deactivated successfully.529container-test-run-flakelet-relay> relay # [23417.552149] relay systemd[1]: Finished health for flakelet-relay.530container-test-run-flakelet-relay> relay # [23417.557913] relay flakelet[311]: flakelet-relay: updated to generation 1531container-test-run-flakelet-relay> relay # [23417.559606] relay systemd[1]: Finished Update flakelet service flakelet-relay.532container-test-run-flakelet-relay> relay # [23417.559845] relay systemd[1]: Reached target flakelet managed services.533container-test-run-flakelet-relay> relay # [23417.559926] relay systemd[1]: Reached target Multi-User System.534container-test-run-flakelet-relay> relay # [23417.560059] relay systemd[1]: Startup finished in 5.200s.535container-test-run-flakelet-relay> relay: (finished: waiting for unit flakelet-relay.service, in 6.16 seconds)536container-test-run-flakelet-relay> relay: waiting for unit nginx.service537container-test-run-flakelet-relay> relay: (finished: waiting for unit nginx.service, in 0.01 seconds)538container-test-run-flakelet-relay> agent: waiting for unit flakelet.target539container-test-run-flakelet-relay> agent: (finished: waiting for unit flakelet.target, in 0.02 seconds)540container-test-run-flakelet-relay> agent: waiting for unit flakelet-agent.service541container-test-run-flakelet-relay> agent: (finished: waiting for unit flakelet-agent.service, in 0.02 seconds)542container-test-run-flakelet-relay> client: waiting for unit multi-user.target543container-test-run-flakelet-relay> client: (finished: waiting for unit multi-user.target, in 0.02 seconds)544container-test-run-flakelet-relay> relay: waiting for success: curl -sf http://127.0.0.1:7400/metrics | grep -q 'agent_up{host="agent"} 1'545container-test-run-flakelet-relay> agent # [23419.114756] agent flakelet-agent[502]: {"timestamp":"2026-09-06T13:34:57.076510Z","level":"INFO","message":"connected","relay":"https://relay:7443","relay_name":"relay","relay_version":"0.1.0","host":"agent","target":"flakelet_relay::agent::conn"}546container-test-run-flakelet-relay> relay # [23419.115343] relay flakelet-relay[400]: {"timestamp":"2026-09-06T13:34:57.077077Z","level":"INFO","message":"agent connected","host":"agent","version":"0.1.0","flakelets":"[Named { name: \"app\", generation: Some(1), revision: Some(\"prebuilt:app\") }, Named { name: \"other\", generation: Some(1), revision: Some(\"prebuilt:other\") }]","target":"flakelet_relay::relay::agent_conn"}547container-test-run-flakelet-relay> relay: (finished: waiting for success: curl -sf http://127.0.0.1:7400/metrics | grep -q 'agent_up{host="agent"} 1', in 2.06 seconds)548container-test-run-flakelet-relay> agent: must succeed: systemctl is-active app.service other.service549container-test-run-flakelet-relay> agent: (finished: must succeed: systemctl is-active app.service other.service, in 0.01 seconds)550container-test-run-flakelet-relay> subtest: deploy with client certificate, relay found via SRV551container-test-run-flakelet-relay> agent: must succeed: jq '.services.app.prebuilt="/nix/store/6ili1vxjlag9d7dy0bxrbxk6bpi5qh9p-flakelet-app-v2"' /etc/flakelet/config.json > /tmp/config.json && cp --remove-destination /tmp/config.json /etc/flakelet/config.json552container-test-run-flakelet-relay> agent: (finished: must succeed: jq '.services.app.prebuilt="/nix/store/6ili1vxjlag9d7dy0bxrbxk6bpi5qh9p-flakelet-app-v2"' /etc/flakelet/config.json > /tmp/config.json && cp --remove-destination /tmp/config.json /etc/flakelet/config.json, in 0.01 seconds)553container-test-run-flakelet-relay> client: must succeed: push --relay-srv test --cert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin.pem --key /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin-key.pem deploy agent/app 2>&1554container-test-run-flakelet-relay> agent # [23420.187919] agent flakelet-agent[502]: {"timestamp":"2026-09-06T13:34:58.149613Z","level":"INFO","message":"start","id":"29b1de543ee02742e3c47bb9ab2df81d","flakelet":"app","rule":"admins","relay":"relay","caller_name":"\"admin@example.org\"","target":"flakelet_relay::agent::conn"}555container-test-run-flakelet-relay> agent # [23420.188937] agent flakelet-agent[502]: {"timestamp":"2026-09-06T13:34:58.150623Z","level":"INFO","message":"update starting","flakelet":"app","ids":"[\"29b1de543ee02742e3c47bb9ab2df81d\"]","target":"flakelet_relay::agent::jobs"}556container-test-run-flakelet-relay> agent # [23420.232277] agent systemd[1]: Starting flakelet update app (via relay)...557container-test-run-flakelet-relay> agent # [23420.259979] agent systemd[1]: Started flakelet update app (via relay).558container-test-run-flakelet-relay> agent # [23420.264603] agent flakelet[937]: app: using prebuilt artifact /nix/store/6ili1vxjlag9d7dy0bxrbxk6bpi5qh9p-flakelet-app-v2559container-test-run-flakelet-relay> agent # [23420.265298] agent flakelet[937]: app: activating generation 2560container-test-run-flakelet-relay> agent # [23420.282592] agent systemd[1]: Stopping app v1...561container-test-run-flakelet-relay> agent # [23420.284264] agent systemd[1]: app.service: Deactivated successfully.562container-test-run-flakelet-relay> agent # [23420.284523] agent systemd[1]: Stopped app v1.563container-test-run-flakelet-relay> agent # [23420.301507] agent systemd[1]: Reload requested from client PID 944 ('systemctl') (unit flakelet-relay-job-app.service)...564container-test-run-flakelet-relay> agent # [23420.301574] agent systemd[1]: Reloading...565container-test-run-flakelet-relay> relay # [23420.186859] relay flakelet-relay[400]: {"timestamp":"2026-09-06T13:34:58.148605Z","level":"INFO","message":"deploy accepted","job":"e51225c096b924b191fd8bd4e6a6fe0c","caller":"admin@example.org","targets":"[\"agent/app\"]","target":"flakelet_relay::relay::api"}566container-test-run-flakelet-relay> agent # [23420.640375] agent systemd[1]: Reloading finished in 338 ms.567container-test-run-flakelet-relay> agent # [23420.705338] agent systemd[1]: Reload requested from client PID 974 ('systemctl') (unit flakelet-relay-job-app.service)...568container-test-run-flakelet-relay> agent # [23420.705375] agent systemd[1]: Reloading...569container-test-run-flakelet-relay> agent # [23421.031445] agent systemd[1]: Reloading finished in 325 ms.570container-test-run-flakelet-relay> agent # [23421.092760] agent systemd[1]: Reload requested from client PID 1003 ('systemctl') (unit flakelet-relay-job-app.service)...571container-test-run-flakelet-relay> agent # [23421.092796] agent systemd[1]: Reloading...572container-test-run-flakelet-relay> agent # [23421.417901] agent systemd[1]: Reloading finished in 324 ms.573container-test-run-flakelet-relay> agent # [23421.493202] agent systemd[1]: Reload requested from client PID 1037 ('systemctl') (unit flakelet-relay-job-app.service)...574container-test-run-flakelet-relay> agent # [23421.493233] agent systemd[1]: Reloading...575container-test-run-flakelet-relay> agent # [23421.818915] agent systemd[1]: Reloading finished in 325 ms.576container-test-run-flakelet-relay> agent # [23421.881267] agent systemd[1]: Starting app v2...577container-test-run-flakelet-relay> agent # [23421.909080] agent systemd[1]: Started app v2.578container-test-run-flakelet-relay> agent # [23421.914151] agent flakelet[937]: app: updated to generation 2579container-test-run-flakelet-relay> agent # [23421.916168] agent systemd[1]: flakelet-relay-job-app.service: Deactivated successfully.580container-test-run-flakelet-relay> client: (finished: must succeed: push --relay-srv test --cert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin.pem --key /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin-key.pem deploy agent/app 2>&1, in 2.87 seconds)581container-test-run-flakelet-relay> agent: must succeed: systemctl show app.service -p Description | grep -q 'app v2'582container-test-run-flakelet-relay> agent: (finished: must succeed: systemctl show app.service -p Description | grep -q 'app v2', in 0.02 seconds)583container-test-run-flakelet-relay> agent: must succeed: journalctl -u flakelet-relay-job-app.service | grep -q 'generation 2'584container-test-run-flakelet-relay> agent: (finished: must succeed: journalctl -u flakelet-relay-job-app.service | grep -q 'generation 2', in 0.01 seconds)585container-test-run-flakelet-relay> agent: must succeed: flakelet status --json app | jq -c '.[0].changed.by'586container-test-run-flakelet-relay> agent: (finished: must succeed: flakelet status --json app | jq -c '.[0].changed.by', in 0.03 seconds)587container-test-run-flakelet-relay> (finished: subtest: deploy with client certificate, relay found via SRV, in 2.94 seconds)588container-test-run-flakelet-relay> subtest: deploy with OIDC token, sub principal589container-test-run-flakelet-relay> agent: must succeed: jq '.services.app.prebuilt="/nix/store/hysydfr96ds875vr0jgw5rjxi4p39h2j-flakelet-app-v1"' /etc/flakelet/config.json > /tmp/config.json && cp --remove-destination /tmp/config.json /etc/flakelet/config.json590container-test-run-flakelet-relay> agent: (finished: must succeed: jq '.services.app.prebuilt="/nix/store/hysydfr96ds875vr0jgw5rjxi4p39h2j-flakelet-app-v1"' /etc/flakelet/config.json > /tmp/config.json && cp --remove-destination /tmp/config.json /etc/flakelet/config.json, in 0.01 seconds)591container-test-run-flakelet-relay> client: must succeed: echo '{"groups": []}' | step crypto jwt sign --key /nix/store/r7sclamg09v434w8zjlfnrcrn1w79wh6-flakelet-relay-test-issuer/jwk.json --iss https://relay:8443 --aud flakelet-relay --sub 'repo:github:example/app:ref:refs/heads/main' --exp $(( $(date +%s) + 300 )) --jti $(head -c8 /dev/urandom | base32)592container-test-run-flakelet-relay> client: (finished: must succeed: echo '{"groups": []}' | step crypto jwt sign --key /nix/store/r7sclamg09v434w8zjlfnrcrn1w79wh6-flakelet-relay-test-issuer/jwk.json --iss https://relay:8443 --aud flakelet-relay --sub 'repo:github:example/app:ref:refs/heads/main' --exp $(( $(date +%s) + 300 )) --jti $(head -c8 /dev/urandom | base32), in 0.05 seconds)593container-test-run-flakelet-relay> client: must succeed: FLAKELET_RELAY_TOKEN_COMMAND='echo eyJhbGciOiJFZERTQSIsImtpZCI6ImsxIiwidHlwIjoiSldUIn0.eyJhdWQiOiJmbGFrZWxldC1yZWxheSIsImV4cCI6MTc4ODcwMjAwMSwiZ3JvdXBzIjpbXSwiaWF0IjoxNzg4NzAxNzAxLCJpc3MiOiJodHRwczovL3JlbGF5Ojg0NDMiLCJqdGkiOiJDTVBPTUQ1VEFYUzVPPT09IiwibmJmIjoxNzg4NzAxNzAxLCJzdWIiOiJyZXBvOmdpdGh1YjpleGFtcGxlL2FwcDpyZWY6cmVmcy9oZWFkcy9tYWluIn0.nUZxq6Glhif5swDBDou0eXSJKzw49nIXRjivUuu48HoRfptp1yJF_bKxq5QDjo2Lu47mQV6-NKO6E6lYGkImDQ' push --relay https://relay:7443 deploy agent/app 2>&1594container-test-run-flakelet-relay> agent # [23423.028569] agent flakelet-agent[502]: {"timestamp":"2026-09-06T13:35:00.990330Z","level":"INFO","message":"update finished","flakelet":"app","status":"Updated","generation":"Some(2)","target":"flakelet_relay::agent::jobs"}595container-test-run-flakelet-relay> agent # [23423.189256] agent flakelet-agent[502]: {"timestamp":"2026-09-06T13:35:01.151045Z","level":"INFO","message":"start","id":"68e5f38b2d0277ca799885a69f57bc60","flakelet":"app","rule":"ci","relay":"relay","caller_name":"\"repo:github:example/app:ref:refs/heads/main\"","target":"flakelet_relay::agent::conn"}596container-test-run-flakelet-relay> agent # [23423.189649] agent flakelet-agent[502]: {"timestamp":"2026-09-06T13:35:01.151480Z","level":"INFO","message":"update starting","flakelet":"app","ids":"[\"68e5f38b2d0277ca799885a69f57bc60\"]","target":"flakelet_relay::agent::jobs"}597container-test-run-flakelet-relay> agent # [23423.224019] agent systemd[1]: Starting flakelet update app (via relay)...598container-test-run-flakelet-relay> agent # [23423.254061] agent systemd[1]: Started flakelet update app (via relay).599container-test-run-flakelet-relay> agent # [23423.259126] agent flakelet[1119]: app: using prebuilt artifact /nix/store/hysydfr96ds875vr0jgw5rjxi4p39h2j-flakelet-app-v1600container-test-run-flakelet-relay> agent # [23423.259762] agent flakelet[1119]: app: activating generation 3601container-test-run-flakelet-relay> agent # [23423.273226] agent systemd[1]: Stopping app v2...602container-test-run-flakelet-relay> agent # [23423.273708] agent systemd[1]: app.service: Deactivated successfully.603container-test-run-flakelet-relay> agent # [23423.273969] agent systemd[1]: Stopped app v2.604container-test-run-flakelet-relay> agent # [23423.290315] agent systemd[1]: Reload requested from client PID 1126 ('systemctl') (unit flakelet-relay-job-app.service)...605container-test-run-flakelet-relay> agent # [23423.290383] agent systemd[1]: Reloading...606container-test-run-flakelet-relay> relay # [23423.029890] relay flakelet-relay[400]: {"timestamp":"2026-09-06T13:35:00.991604Z","level":"INFO","message":"deploy finished","job":"e51225c096b924b191fd8bd4e6a6fe0c","ok":true,"target":"flakelet_relay::relay::api"}607container-test-run-flakelet-relay> relay # [23423.188951] relay flakelet-relay[400]: {"timestamp":"2026-09-06T13:35:01.150725Z","level":"INFO","message":"deploy accepted","job":"e57d8857e3e4cdd7796737ff038f4fb3","caller":"repo:github:example/app:ref:refs/heads/main","targets":"[\"agent/app\"]","target":"flakelet_relay::relay::api"}608container-test-run-flakelet-relay> agent # [23423.634748] agent systemd[1]: Reloading finished in 343 ms.609container-test-run-flakelet-relay> agent # [23423.700933] agent systemd[1]: Reload requested from client PID 1156 ('systemctl') (unit flakelet-relay-job-app.service)...610container-test-run-flakelet-relay> agent # [23423.700970] agent systemd[1]: Reloading...611container-test-run-flakelet-relay> agent # [23424.027625] agent systemd[1]: Reloading finished in 326 ms.612container-test-run-flakelet-relay> agent # [23424.094725] agent systemd[1]: Reload requested from client PID 1185 ('systemctl') (unit flakelet-relay-job-app.service)...613container-test-run-flakelet-relay> agent # [23424.094765] agent systemd[1]: Reloading...614container-test-run-flakelet-relay> agent # [23424.421127] agent systemd[1]: Reloading finished in 325 ms.615container-test-run-flakelet-relay> agent # [23424.485178] agent systemd[1]: Reload requested from client PID 1216 ('systemctl') (unit flakelet-relay-job-app.service)...616container-test-run-flakelet-relay> agent # [23424.485212] agent systemd[1]: Reloading...617container-test-run-flakelet-relay> agent # [23424.820371] agent systemd[1]: Reloading finished in 334 ms.618container-test-run-flakelet-relay> agent # [23424.882192] agent systemd[1]: Starting app v1...619container-test-run-flakelet-relay> agent # [23424.910923] agent systemd[1]: Started app v1.620container-test-run-flakelet-relay> agent # [23424.916752] agent flakelet[1119]: app: updated to generation 3621container-test-run-flakelet-relay> agent # [23424.918884] agent systemd[1]: flakelet-relay-job-app.service: Deactivated successfully.622container-test-run-flakelet-relay> client: (finished: must succeed: FLAKELET_RELAY_TOKEN_COMMAND='echo eyJhbGciOiJFZERTQSIsImtpZCI6ImsxIiwidHlwIjoiSldUIn0.eyJhdWQiOiJmbGFrZWxldC1yZWxheSIsImV4cCI6MTc4ODcwMjAwMSwiZ3JvdXBzIjpbXSwiaWF0IjoxNzg4NzAxNzAxLCJpc3MiOiJodHRwczovL3JlbGF5Ojg0NDMiLCJqdGkiOiJDTVBPTUQ1VEFYUzVPPT09IiwibmJmIjoxNzg4NzAxNzAxLCJzdWIiOiJyZXBvOmdpdGh1YjpleGFtcGxlL2FwcDpyZWY6cmVmcy9oZWFkcy9tYWluIn0.nUZxq6Glhif5swDBDou0eXSJKzw49nIXRjivUuu48HoRfptp1yJF_bKxq5QDjo2Lu47mQV6-NKO6E6lYGkImDQ' push --relay https://relay:7443 deploy agent/app 2>&1, in 2.87 seconds)623container-test-run-flakelet-relay> (finished: subtest: deploy with OIDC token, sub principal, in 2.93 seconds)624container-test-run-flakelet-relay> subtest: policy denies uncovered target625container-test-run-flakelet-relay> client: must fail: FLAKELET_RELAY_TOKEN_COMMAND='echo eyJhbGciOiJFZERTQSIsImtpZCI6ImsxIiwidHlwIjoiSldUIn0.eyJhdWQiOiJmbGFrZWxldC1yZWxheSIsImV4cCI6MTc4ODcwMjAwMSwiZ3JvdXBzIjpbXSwiaWF0IjoxNzg4NzAxNzAxLCJpc3MiOiJodHRwczovL3JlbGF5Ojg0NDMiLCJqdGkiOiJDTVBPTUQ1VEFYUzVPPT09IiwibmJmIjoxNzg4NzAxNzAxLCJzdWIiOiJyZXBvOmdpdGh1YjpleGFtcGxlL2FwcDpyZWY6cmVmcy9oZWFkcy9tYWluIn0.nUZxq6Glhif5swDBDou0eXSJKzw49nIXRjivUuu48HoRfptp1yJF_bKxq5QDjo2Lu47mQV6-NKO6E6lYGkImDQ' push --relay https://relay:7443 deploy agent/other 2>&1626container-test-run-flakelet-relay> client: (finished: must fail: FLAKELET_RELAY_TOKEN_COMMAND='echo eyJhbGciOiJFZERTQSIsImtpZCI6ImsxIiwidHlwIjoiSldUIn0.eyJhdWQiOiJmbGFrZWxldC1yZWxheSIsImV4cCI6MTc4ODcwMjAwMSwiZ3JvdXBzIjpbXSwiaWF0IjoxNzg4NzAxNzAxLCJpc3MiOiJodHRwczovL3JlbGF5Ojg0NDMiLCJqdGkiOiJDTVBPTUQ1VEFYUzVPPT09IiwibmJmIjoxNzg4NzAxNzAxLCJzdWIiOiJyZXBvOmdpdGh1YjpleGFtcGxlL2FwcDpyZWY6cmVmcy9oZWFkcy9tYWluIn0.nUZxq6Glhif5swDBDou0eXSJKzw49nIXRjivUuu48HoRfptp1yJF_bKxq5QDjo2Lu47mQV6-NKO6E6lYGkImDQ' push --relay https://relay:7443 deploy agent/other 2>&1, in 0.02 seconds)627container-test-run-flakelet-relay> (finished: subtest: policy denies uncovered target, in 0.02 seconds)628container-test-run-flakelet-relay> subtest: claim principal from groups, unchanged counts as success629container-test-run-flakelet-relay> client: must succeed: echo '{"groups": ["wheel"]}' | step crypto jwt sign --key /nix/store/r7sclamg09v434w8zjlfnrcrn1w79wh6-flakelet-relay-test-issuer/jwk.json --iss https://relay:8443 --aud flakelet-relay --sub 'someone' --exp $(( $(date +%s) + 300 )) --jti $(head -c8 /dev/urandom | base32)630container-test-run-flakelet-relay> client: (finished: must succeed: echo '{"groups": ["wheel"]}' | step crypto jwt sign --key /nix/store/r7sclamg09v434w8zjlfnrcrn1w79wh6-flakelet-relay-test-issuer/jwk.json --iss https://relay:8443 --aud flakelet-relay --sub 'someone' --exp $(( $(date +%s) + 300 )) --jti $(head -c8 /dev/urandom | base32), in 0.06 seconds)631container-test-run-flakelet-relay> client: must succeed: FLAKELET_RELAY_TOKEN_COMMAND='echo eyJhbGciOiJFZERTQSIsImtpZCI6ImsxIiwidHlwIjoiSldUIn0.eyJhdWQiOiJmbGFrZWxldC1yZWxheSIsImV4cCI6MTc4ODcwMjAwNCwiZ3JvdXBzIjpbIndoZWVsIl0sImlhdCI6MTc4ODcwMTcwNCwiaXNzIjoiaHR0cHM6Ly9yZWxheTo4NDQzIiwianRpIjoiWUdMWjQyTTRNRTdLVz09PSIsIm5iZiI6MTc4ODcwMTcwNCwic3ViIjoic29tZW9uZSJ9.m6Gj9M60xTfax9CFUb0ny5ANsgM5PyLsC7-x-TivUemqwzYvRL8d5__JTWmqJyWE1zzaujqN786TFyHZCeymAw' push --relay https://relay:7443 deploy agent/other 2>&1632container-test-run-flakelet-relay> agent # [23426.021576] agent flakelet-agent[502]: {"timestamp":"2026-09-06T13:35:03.983318Z","level":"INFO","message":"update finished","flakelet":"app","status":"Updated","generation":"Some(3)","target":"flakelet_relay::agent::jobs"}633container-test-run-flakelet-relay> agent # [23426.125327] agent flakelet-agent[502]: {"timestamp":"2026-09-06T13:35:04.087119Z","level":"INFO","message":"start","id":"09672544cda5505fb97342a2cdba90a3","flakelet":"other","rule":"admins","relay":"relay","caller_name":"\"someone\"","target":"flakelet_relay::agent::conn"}634container-test-run-flakelet-relay> agent # [23426.125737] agent flakelet-agent[502]: {"timestamp":"2026-09-06T13:35:04.087566Z","level":"INFO","message":"update starting","flakelet":"other","ids":"[\"09672544cda5505fb97342a2cdba90a3\"]","target":"flakelet_relay::agent::jobs"}635container-test-run-flakelet-relay> agent # [23426.162942] agent systemd[1]: Starting flakelet update other (via relay)...636container-test-run-flakelet-relay> agent # [23426.197972] agent systemd[1]: Started flakelet update other (via relay).637container-test-run-flakelet-relay> agent # [23426.202541] agent flakelet[1278]: other: using prebuilt artifact /nix/store/hmr990n0b4v6rpzrcpyyw738r9xi7z95-flakelet-other-v1638container-test-run-flakelet-relay> agent # [23426.203088] agent flakelet[1278]: other: up to date639container-test-run-flakelet-relay> agent # [23426.206021] agent systemd[1]: flakelet-relay-job-other.service: Deactivated successfully.640container-test-run-flakelet-relay> relay # [23426.023195] relay flakelet-relay[400]: {"timestamp":"2026-09-06T13:35:03.984922Z","level":"INFO","message":"deploy finished","job":"e57d8857e3e4cdd7796737ff038f4fb3","ok":true,"target":"flakelet_relay::relay::api"}641container-test-run-flakelet-relay> relay # [23426.124998] relay flakelet-relay[400]: {"timestamp":"2026-09-06T13:35:04.086789Z","level":"INFO","message":"deploy accepted","job":"9976a6f67a74ecf89cdb5295297afde8","caller":"someone","targets":"[\"agent/other\"]","target":"flakelet_relay::relay::api"}642container-test-run-flakelet-relay> client: (finished: must succeed: FLAKELET_RELAY_TOKEN_COMMAND='echo eyJhbGciOiJFZERTQSIsImtpZCI6ImsxIiwidHlwIjoiSldUIn0.eyJhdWQiOiJmbGFrZWxldC1yZWxheSIsImV4cCI6MTc4ODcwMjAwNCwiZ3JvdXBzIjpbIndoZWVsIl0sImlhdCI6MTc4ODcwMTcwNCwiaXNzIjoiaHR0cHM6Ly9yZWxheTo4NDQzIiwianRpIjoiWUdMWjQyTTRNRTdLVz09PSIsIm5iZiI6MTc4ODcwMTcwNCwic3ViIjoic29tZW9uZSJ9.m6Gj9M60xTfax9CFUb0ny5ANsgM5PyLsC7-x-TivUemqwzYvRL8d5__JTWmqJyWE1zzaujqN786TFyHZCeymAw' push --relay https://relay:7443 deploy agent/other 2>&1, in 0.64 seconds)643container-test-run-flakelet-relay> (finished: subtest: claim principal from groups, unchanged counts as success, in 0.70 seconds)644container-test-run-flakelet-relay> subtest: push login via device flow, cached token used afterwards645container-test-run-flakelet-relay> client: must succeed: push login --issuer https://relay:8443 --client-id flakelet-relay 2>&1646container-test-run-flakelet-relay> agent # [23426.748467] agent flakelet-agent[502]: {"timestamp":"2026-09-06T13:35:04.710193Z","level":"INFO","message":"update finished","flakelet":"other","status":"Unchanged","generation":"Some(1)","target":"flakelet_relay::agent::jobs"}647container-test-run-flakelet-relay> relay # [23426.749749] relay flakelet-relay[400]: {"timestamp":"2026-09-06T13:35:04.711467Z","level":"INFO","message":"deploy finished","job":"9976a6f67a74ecf89cdb5295297afde8","ok":true,"target":"flakelet_relay::relay::api"}648container-test-run-flakelet-relay> relay # [23426.770855] relay 93sn3sv24fj26ksccpppzwg6kbf10s4w-device-mock[186]: 127.0.0.1 - - [06/Sep/2026 13:35:04] "POST /device HTTP/1.1" 200 -649container-test-run-flakelet-relay> relay # [23427.775895] relay 93sn3sv24fj26ksccpppzwg6kbf10s4w-device-mock[186]: 127.0.0.1 - - [06/Sep/2026 13:35:05] "POST /token HTTP/1.1" 400 -650container-test-run-flakelet-relay> client: (finished: must succeed: push login --issuer https://relay:8443 --client-id flakelet-relay 2>&1, in 2.08 seconds)651container-test-run-flakelet-relay> client: must succeed: test "$(stat -c %a /root/.local/state/flakelet-push/token.json)" = 600652container-test-run-flakelet-relay> client: (finished: must succeed: test "$(stat -c %a /root/.local/state/flakelet-push/token.json)" = 600, in 0.01 seconds)653container-test-run-flakelet-relay> client: must succeed: push --relay https://relay:7443 deploy agent/other 2>&1654container-test-run-flakelet-relay> agent # [23428.857412] agent flakelet-agent[502]: {"timestamp":"2026-09-06T13:35:06.819168Z","level":"INFO","message":"start","id":"de2d2c04e74b2955ac6ad82a7164cecd","flakelet":"other","rule":"admins","relay":"relay","caller_name":"\"dev@example.org\"","target":"flakelet_relay::agent::conn"}655container-test-run-flakelet-relay> agent # [23428.858355] agent flakelet-agent[502]: {"timestamp":"2026-09-06T13:35:06.819809Z","level":"INFO","message":"update starting","flakelet":"other","ids":"[\"de2d2c04e74b2955ac6ad82a7164cecd\"]","target":"flakelet_relay::agent::jobs"}656container-test-run-flakelet-relay> agent # [23428.896398] agent systemd[1]: Starting flakelet update other (via relay)...657container-test-run-flakelet-relay> agent # [23428.930052] agent systemd[1]: Started flakelet update other (via relay).658container-test-run-flakelet-relay> agent # [23428.933896] agent flakelet[1310]: other: using prebuilt artifact /nix/store/hmr990n0b4v6rpzrcpyyw738r9xi7z95-flakelet-other-v1659container-test-run-flakelet-relay> agent # [23428.934455] agent flakelet[1310]: other: up to date660container-test-run-flakelet-relay> agent # [23428.937382] agent systemd[1]: flakelet-relay-job-other.service: Deactivated successfully.661container-test-run-flakelet-relay> relay # [23428.826508] relay 93sn3sv24fj26ksccpppzwg6kbf10s4w-device-mock[186]: 127.0.0.1 - - [06/Sep/2026 13:35:06] "POST /token HTTP/1.1" 200 -662container-test-run-flakelet-relay> relay # [23428.856997] relay flakelet-relay[400]: {"timestamp":"2026-09-06T13:35:06.818783Z","level":"INFO","message":"deploy accepted","job":"412cfb5bfecf470b1d56836a323c5cd7","caller":"dev@example.org","targets":"[\"agent/other\"]","target":"flakelet_relay::relay::api"}663container-test-run-flakelet-relay> client: (finished: must succeed: push --relay https://relay:7443 deploy agent/other 2>&1, in 0.64 seconds)664container-test-run-flakelet-relay> client: must succeed: push --relay https://relay:7443 deploy agent/other 2>&1665container-test-run-flakelet-relay> agent # [23429.477071] agent flakelet-agent[502]: {"timestamp":"2026-09-06T13:35:07.438802Z","level":"INFO","message":"update finished","flakelet":"other","status":"Unchanged","generation":"Some(1)","target":"flakelet_relay::agent::jobs"}666container-test-run-flakelet-relay> agent # [23429.499468] agent flakelet-agent[502]: {"timestamp":"2026-09-06T13:35:07.461263Z","level":"INFO","message":"start","id":"48df3cb01ecbafe1a45ac1af9bd04359","flakelet":"other","rule":"admins","relay":"relay","caller_name":"\"dev@example.org\"","target":"flakelet_relay::agent::conn"}667container-test-run-flakelet-relay> agent # [23429.499909] agent flakelet-agent[502]: {"timestamp":"2026-09-06T13:35:07.461728Z","level":"INFO","message":"update starting","flakelet":"other","ids":"[\"48df3cb01ecbafe1a45ac1af9bd04359\"]","target":"flakelet_relay::agent::jobs"}668container-test-run-flakelet-relay> agent # [23429.542807] agent systemd[1]: Starting flakelet update other (via relay)...669container-test-run-flakelet-relay> agent # [23429.570986] agent systemd[1]: Started flakelet update other (via relay).670container-test-run-flakelet-relay> agent # [23429.575423] agent flakelet[1342]: other: using prebuilt artifact /nix/store/hmr990n0b4v6rpzrcpyyw738r9xi7z95-flakelet-other-v1671container-test-run-flakelet-relay> agent # [23429.576051] agent flakelet[1342]: other: up to date672container-test-run-flakelet-relay> agent # [23429.578911] agent systemd[1]: flakelet-relay-job-other.service: Deactivated successfully.673container-test-run-flakelet-relay> relay # [23429.478431] relay flakelet-relay[400]: {"timestamp":"2026-09-06T13:35:07.440158Z","level":"INFO","message":"deploy finished","job":"412cfb5bfecf470b1d56836a323c5cd7","ok":true,"target":"flakelet_relay::relay::api"}674container-test-run-flakelet-relay> relay # [23429.499043] relay flakelet-relay[400]: {"timestamp":"2026-09-06T13:35:07.460813Z","level":"INFO","message":"deploy accepted","job":"5324ed8d22bbba1067e68b05a30d17ee","caller":"dev@example.org","targets":"[\"agent/other\"]","target":"flakelet_relay::relay::api"}675container-test-run-flakelet-relay> client: (finished: must succeed: push --relay https://relay:7443 deploy agent/other 2>&1, in 0.64 seconds)676container-test-run-flakelet-relay> (finished: subtest: push login via device flow, cached token used afterwards, in 3.37 seconds)677container-test-run-flakelet-relay> subtest: waves: failing first wave skips the second678container-test-run-flakelet-relay> agent: must succeed: jq '.services.app.prebuilt="/nix/store/8282rnbvyk847q4l4zby3zwcq3jpzrvw-flakelet-app-broken"' /etc/flakelet/config.json > /tmp/config.json && cp --remove-destination /tmp/config.json /etc/flakelet/config.json679container-test-run-flakelet-relay> agent: (finished: must succeed: jq '.services.app.prebuilt="/nix/store/8282rnbvyk847q4l4zby3zwcq3jpzrvw-flakelet-app-broken"' /etc/flakelet/config.json > /tmp/config.json && cp --remove-destination /tmp/config.json /etc/flakelet/config.json, in 0.01 seconds)680container-test-run-flakelet-relay> client: must fail: push --relay https://relay:7443 --cert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin.pem --key /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin-key.pem deploy agent/app --wave agent/other 2>&1681container-test-run-flakelet-relay> agent # [23430.120117] agent flakelet-agent[502]: {"timestamp":"2026-09-06T13:35:08.081816Z","level":"INFO","message":"update finished","flakelet":"other","status":"Unchanged","generation":"Some(1)","target":"flakelet_relay::agent::jobs"}682container-test-run-flakelet-relay> agent # [23430.157262] agent flakelet-agent[502]: {"timestamp":"2026-09-06T13:35:08.119018Z","level":"INFO","message":"start","id":"bb61cf0712a89bc84e9c8e21f8fd3e0a","flakelet":"app","rule":"admins","relay":"relay","caller_name":"\"admin@example.org\"","target":"flakelet_relay::agent::conn"}683container-test-run-flakelet-relay> agent # [23430.157646] agent flakelet-agent[502]: {"timestamp":"2026-09-06T13:35:08.119465Z","level":"INFO","message":"update starting","flakelet":"app","ids":"[\"bb61cf0712a89bc84e9c8e21f8fd3e0a\"]","target":"flakelet_relay::agent::jobs"}684container-test-run-flakelet-relay> agent # [23430.197069] agent systemd[1]: Starting flakelet update app (via relay)...685container-test-run-flakelet-relay> agent # [23430.225963] agent systemd[1]: Started flakelet update app (via relay).686container-test-run-flakelet-relay> agent # [23430.230337] agent flakelet[1369]: app: using prebuilt artifact /nix/store/8282rnbvyk847q4l4zby3zwcq3jpzrvw-flakelet-app-broken687container-test-run-flakelet-relay> agent # [23430.230978] agent flakelet[1369]: app: activating generation 4688container-test-run-flakelet-relay> agent # [23430.248670] agent systemd[1]: Stopping app v1...689container-test-run-flakelet-relay> agent # [23430.249167] agent systemd[1]: app.service: Deactivated successfully.690container-test-run-flakelet-relay> agent # [23430.249429] agent systemd[1]: Stopped app v1.691container-test-run-flakelet-relay> agent # [23430.266206] agent systemd[1]: Reload requested from client PID 1376 ('systemctl') (unit flakelet-relay-job-app.service)...692container-test-run-flakelet-relay> agent # [23430.266276] agent systemd[1]: Reloading...693container-test-run-flakelet-relay> relay # [23430.121727] relay flakelet-relay[400]: {"timestamp":"2026-09-06T13:35:08.083453Z","level":"INFO","message":"deploy finished","job":"5324ed8d22bbba1067e68b05a30d17ee","ok":true,"target":"flakelet_relay::relay::api"}694container-test-run-flakelet-relay> relay # [23430.156953] relay flakelet-relay[400]: {"timestamp":"2026-09-06T13:35:08.118704Z","level":"INFO","message":"deploy accepted","job":"5bf3160c9106280c89e43a9fe50de35b","caller":"admin@example.org","targets":"[\"agent/app\", \"agent/other\"]","target":"flakelet_relay::relay::api"}695container-test-run-flakelet-relay> agent # [23430.606602] agent systemd[1]: Reloading finished in 339 ms.696container-test-run-flakelet-relay> agent # [23430.675341] agent systemd[1]: Reload requested from client PID 1406 ('systemctl') (unit flakelet-relay-job-app.service)...697container-test-run-flakelet-relay> agent # [23430.675378] agent systemd[1]: Reloading...698container-test-run-flakelet-relay> agent # [23431.004281] agent systemd[1]: Reloading finished in 328 ms.699container-test-run-flakelet-relay> agent # [23431.067788] agent systemd[1]: Reload requested from client PID 1435 ('systemctl') (unit flakelet-relay-job-app.service)...700container-test-run-flakelet-relay> agent # [23431.067825] agent systemd[1]: Reloading...701container-test-run-flakelet-relay> agent # [23431.396411] agent systemd[1]: Reloading finished in 328 ms.702container-test-run-flakelet-relay> agent # [23431.462272] agent systemd[1]: Reload requested from client PID 1469 ('systemctl') (unit flakelet-relay-job-app.service)...703container-test-run-flakelet-relay> agent # [23431.462306] agent systemd[1]: Reloading...704container-test-run-flakelet-relay> agent # [23431.789805] agent systemd[1]: Reloading finished in 327 ms.705container-test-run-flakelet-relay> agent # [23431.859666] agent systemd[1]: Starting app broken...706container-test-run-flakelet-relay> agent # [23431.878265] agent (nonexistent)[1502]: app.service: Unable to locate executable '/nonexistent': No such file or directory707container-test-run-flakelet-relay> agent # [23431.878287] agent (nonexistent)[1502]: app.service: Failed at step EXEC spawning /nonexistent: No such file or directory708container-test-run-flakelet-relay> agent # [23431.880433] agent systemd[1]: app.service: Main process exited, code=exited, status=203/EXEC709container-test-run-flakelet-relay> agent # [23431.880540] agent systemd[1]: app.service: Failed with result 'exit-code'.710container-test-run-flakelet-relay> agent # [23431.880773] agent systemd[1]: Failed to start app broken.711container-test-run-flakelet-relay> agent # [23431.909762] agent systemd[1]: Reload requested from client PID 1510 ('systemctl') (unit flakelet-relay-job-app.service)...712container-test-run-flakelet-relay> agent # [23431.909802] agent systemd[1]: Reloading...713container-test-run-flakelet-relay> agent # [23432.242605] agent systemd[1]: Reloading finished in 332 ms.714container-test-run-flakelet-relay> agent # [23432.308300] agent systemd[1]: Reload requested from client PID 1540 ('systemctl') (unit flakelet-relay-job-app.service)...715container-test-run-flakelet-relay> agent # [23432.308338] agent systemd[1]: Reloading...716container-test-run-flakelet-relay> agent # [23432.637867] agent systemd[1]: Reloading finished in 329 ms.717container-test-run-flakelet-relay> agent # [23432.700974] agent systemd[1]: Reload requested from client PID 1570 ('systemctl') (unit flakelet-relay-job-app.service)...718container-test-run-flakelet-relay> agent # [23432.701021] agent systemd[1]: Reloading...719container-test-run-flakelet-relay> agent # [23433.030217] agent systemd[1]: Reloading finished in 328 ms.720container-test-run-flakelet-relay> agent # [23433.103328] agent systemd[1]: Reload requested from client PID 1601 ('systemctl') (unit flakelet-relay-job-app.service)...721container-test-run-flakelet-relay> agent # [23433.103369] agent systemd[1]: Reloading...722container-test-run-flakelet-relay> agent # [23433.430839] agent systemd[1]: Reloading finished in 327 ms.723container-test-run-flakelet-relay> agent # [23433.495189] agent systemd[1]: Starting app v1...724container-test-run-flakelet-relay> agent # [23433.520975] agent systemd[1]: Started app v1.725container-test-run-flakelet-relay> agent # [23433.522085] agent flakelet[1369]: app: deploy failed, rolled back: systemctl start app.service failed:726container-test-run-flakelet-relay> agent # [23433.522085] agent flakelet[1369]: failed units: app.service. See journalctl -u app.service727container-test-run-flakelet-relay> agent # [23433.523925] agent systemd[1]: flakelet-relay-job-app.service: Main process exited, code=exited, status=1/FAILURE728container-test-run-flakelet-relay> agent # [23433.524031] agent systemd[1]: flakelet-relay-job-app.service: Failed with result 'exit-code'.729container-test-run-flakelet-relay> client: (finished: must fail: push --relay https://relay:7443 --cert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin.pem --key /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin-key.pem deploy agent/app --wave agent/other 2>&1, in 4.10 seconds)730container-test-run-flakelet-relay> agent: must succeed: systemctl show app.service -p Description | grep -q 'app v1'731container-test-run-flakelet-relay> agent: (finished: must succeed: systemctl show app.service -p Description | grep -q 'app v1', in 0.02 seconds)732container-test-run-flakelet-relay> (finished: subtest: waves: failing first wave skips the second, in 4.13 seconds)733container-test-run-flakelet-relay> subtest: host patterns expand on the relay734container-test-run-flakelet-relay> agent: must succeed: jq '.services.app.prebuilt="/nix/store/hysydfr96ds875vr0jgw5rjxi4p39h2j-flakelet-app-v1"' /etc/flakelet/config.json > /tmp/config.json && cp --remove-destination /tmp/config.json /etc/flakelet/config.json735container-test-run-flakelet-relay> agent: (finished: must succeed: jq '.services.app.prebuilt="/nix/store/hysydfr96ds875vr0jgw5rjxi4p39h2j-flakelet-app-v1"' /etc/flakelet/config.json > /tmp/config.json && cp --remove-destination /tmp/config.json /etc/flakelet/config.json, in 0.01 seconds)736container-test-run-flakelet-relay> client: must succeed: push --relay https://relay:7443 --cert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin.pem --key /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin-key.pem deploy '@all/app' 2>&1737container-test-run-flakelet-relay> agent # [23434.234705] agent flakelet-agent[502]: {"timestamp":"2026-09-06T13:35:12.196445Z","level":"INFO","message":"update finished","flakelet":"app","status":"RolledBack","generation":"Some(3)","target":"flakelet_relay::agent::jobs"}738container-test-run-flakelet-relay> agent # [23434.292797] agent flakelet-agent[502]: {"timestamp":"2026-09-06T13:35:12.254538Z","level":"INFO","message":"start","id":"873ed410355944e81265f4e28c0cb259","flakelet":"app","rule":"admins","relay":"relay","caller_name":"\"admin@example.org\"","target":"flakelet_relay::agent::conn"}739container-test-run-flakelet-relay> agent # [23434.293294] agent flakelet-agent[502]: {"timestamp":"2026-09-06T13:35:12.255114Z","level":"INFO","message":"update starting","flakelet":"app","ids":"[\"873ed410355944e81265f4e28c0cb259\"]","target":"flakelet_relay::agent::jobs"}740container-test-run-flakelet-relay> agent # [23434.323260] agent systemd[1]: Starting flakelet update app (via relay)...741container-test-run-flakelet-relay> agent # [23434.355084] agent systemd[1]: Started flakelet update app (via relay).742container-test-run-flakelet-relay> agent # [23434.359556] agent flakelet[1668]: app: using prebuilt artifact /nix/store/hysydfr96ds875vr0jgw5rjxi4p39h2j-flakelet-app-v1743container-test-run-flakelet-relay> agent # [23434.360132] agent flakelet[1668]: app: up to date744container-test-run-flakelet-relay> agent # [23434.360443] agent systemd[1]: flakelet-relay-job-app.service: Deactivated successfully.745container-test-run-flakelet-relay> relay # [23434.236341] relay flakelet-relay[400]: {"timestamp":"2026-09-06T13:35:12.198072Z","level":"INFO","message":"deploy finished","job":"5bf3160c9106280c89e43a9fe50de35b","ok":false,"target":"flakelet_relay::relay::api"}746container-test-run-flakelet-relay> relay # [23434.292530] relay flakelet-relay[400]: {"timestamp":"2026-09-06T13:35:12.254284Z","level":"INFO","message":"deploy accepted","job":"a10ed2fb3e717c0e0266f770ddd65e05","caller":"admin@example.org","targets":"[\"agent/app\"]","target":"flakelet_relay::relay::api"}747container-test-run-flakelet-relay> client: (finished: must succeed: push --relay https://relay:7443 --cert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin.pem --key /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin-key.pem deploy '@all/app' 2>&1, in 0.63 seconds)748container-test-run-flakelet-relay> client: must fail: push --relay https://relay:7443 --cert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin.pem --key /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin-key.pem deploy '*/app' 2>&1749container-test-run-flakelet-relay> agent # [23434.898338] agent flakelet-agent[502]: {"timestamp":"2026-09-06T13:35:12.860127Z","level":"INFO","message":"update finished","flakelet":"app","status":"Unchanged","generation":"Some(3)","target":"flakelet_relay::agent::jobs"}750container-test-run-flakelet-relay> agent # [23434.921348] agent flakelet-agent[502]: {"timestamp":"2026-09-06T13:35:12.883158Z","level":"INFO","message":"start","id":"8d16bfced60b8e824d96d2326ff1b0be","flakelet":"app","rule":"admins","relay":"relay","caller_name":"\"admin@example.org\"","target":"flakelet_relay::agent::conn"}751container-test-run-flakelet-relay> agent # [23434.921742] agent flakelet-agent[502]: {"timestamp":"2026-09-06T13:35:12.883566Z","level":"INFO","message":"update starting","flakelet":"app","ids":"[\"8d16bfced60b8e824d96d2326ff1b0be\"]","target":"flakelet_relay::agent::jobs"}752container-test-run-flakelet-relay> agent # [23434.960821] agent systemd[1]: Starting flakelet update app (via relay)...753container-test-run-flakelet-relay> agent # [23434.999151] agent systemd[1]: Started flakelet update app (via relay).754container-test-run-flakelet-relay> agent # [23435.004785] agent flakelet[1692]: app: using prebuilt artifact /nix/store/hysydfr96ds875vr0jgw5rjxi4p39h2j-flakelet-app-v1755container-test-run-flakelet-relay> agent # [23435.005392] agent flakelet[1692]: app: up to date756container-test-run-flakelet-relay> agent # [23435.007018] agent systemd[1]: flakelet-relay-job-app.service: Deactivated successfully.757container-test-run-flakelet-relay> relay # [23434.900087] relay flakelet-relay[400]: {"timestamp":"2026-09-06T13:35:12.861746Z","level":"INFO","message":"deploy finished","job":"a10ed2fb3e717c0e0266f770ddd65e05","ok":true,"target":"flakelet_relay::relay::api"}758container-test-run-flakelet-relay> relay # [23434.921016] relay flakelet-relay[400]: {"timestamp":"2026-09-06T13:35:12.882814Z","level":"INFO","message":"deploy accepted","job":"167e87c32a4991adf9d79117e7267b66","caller":"admin@example.org","targets":"[\"agent/app\", \"ghost/app\"]","target":"flakelet_relay::relay::api"}759container-test-run-flakelet-relay> client: (finished: must fail: push --relay https://relay:7443 --cert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin.pem --key /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin-key.pem deploy '*/app' 2>&1, in 0.65 seconds)760container-test-run-flakelet-relay> client: must fail: push --relay https://relay:7443 --cert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin.pem --key /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin-key.pem deploy agent/app --wave '*/app' 2>&1761container-test-run-flakelet-relay> agent # [23435.546290] agent flakelet-agent[502]: {"timestamp":"2026-09-06T13:35:13.508005Z","level":"INFO","message":"update finished","flakelet":"app","status":"Unchanged","generation":"Some(3)","target":"flakelet_relay::agent::jobs"}762container-test-run-flakelet-relay> agent # [23435.568708] agent flakelet-agent[502]: {"timestamp":"2026-09-06T13:35:13.530506Z","level":"INFO","message":"start","id":"f077cca9a214454d00c1a8604e2963f7","flakelet":"app","rule":"admins","relay":"relay","caller_name":"\"admin@example.org\"","target":"flakelet_relay::agent::conn"}763container-test-run-flakelet-relay> agent # [23435.569137] agent flakelet-agent[502]: {"timestamp":"2026-09-06T13:35:13.530958Z","level":"INFO","message":"update starting","flakelet":"app","ids":"[\"f077cca9a214454d00c1a8604e2963f7\"]","target":"flakelet_relay::agent::jobs"}764container-test-run-flakelet-relay> agent # [23435.610059] agent systemd[1]: Starting flakelet update app (via relay)...765container-test-run-flakelet-relay> agent # [23435.643144] agent systemd[1]: Started flakelet update app (via relay).766container-test-run-flakelet-relay> agent # [23435.647793] agent flakelet[1724]: app: using prebuilt artifact /nix/store/hysydfr96ds875vr0jgw5rjxi4p39h2j-flakelet-app-v1767container-test-run-flakelet-relay> agent # [23435.648340] agent flakelet[1724]: app: up to date768container-test-run-flakelet-relay> agent # [23435.651482] agent systemd[1]: flakelet-relay-job-app.service: Deactivated successfully.769container-test-run-flakelet-relay> relay # [23435.547633] relay flakelet-relay[400]: {"timestamp":"2026-09-06T13:35:13.509386Z","level":"INFO","message":"deploy finished","job":"167e87c32a4991adf9d79117e7267b66","ok":false,"target":"flakelet_relay::relay::api"}770container-test-run-flakelet-relay> relay # [23435.568533] relay flakelet-relay[400]: {"timestamp":"2026-09-06T13:35:13.530344Z","level":"INFO","message":"deploy accepted","job":"a2eb9967bf7fd1fd14b027a272f6262f","caller":"admin@example.org","targets":"[\"agent/app\", \"ghost/app\"]","target":"flakelet_relay::relay::api"}771container-test-run-flakelet-relay> client: (finished: must fail: push --relay https://relay:7443 --cert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin.pem --key /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin-key.pem deploy agent/app --wave '*/app' 2>&1, in 0.65 seconds)772container-test-run-flakelet-relay> client: must fail: push --relay https://relay:7443 --cert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin.pem --key /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin-key.pem deploy '@all/nope' 2>&1773container-test-run-flakelet-relay> client: (finished: must fail: push --relay https://relay:7443 --cert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin.pem --key /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin-key.pem deploy '@all/nope' 2>&1, in 0.02 seconds)774container-test-run-flakelet-relay> (finished: subtest: host patterns expand on the relay, in 1.96 seconds)775container-test-run-flakelet-relay> subtest: detach returns once the job is accepted776container-test-run-flakelet-relay> agent: must succeed: jq '.services.app.prebuilt="/nix/store/ln47rsd5km8a04hd5zlkz4q1v352zqh3-flakelet-app-slow2"' /etc/flakelet/config.json > /tmp/config.json && cp --remove-destination /tmp/config.json /etc/flakelet/config.json777container-test-run-flakelet-relay> agent: (finished: must succeed: jq '.services.app.prebuilt="/nix/store/ln47rsd5km8a04hd5zlkz4q1v352zqh3-flakelet-app-slow2"' /etc/flakelet/config.json > /tmp/config.json && cp --remove-destination /tmp/config.json /etc/flakelet/config.json, in 0.01 seconds)778container-test-run-flakelet-relay> client: must succeed: timeout 20 push --relay https://relay:7443 --cert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin.pem --key /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin-key.pem deploy --detach agent/app 2>&1779container-test-run-flakelet-relay> client: (finished: must succeed: timeout 20 push --relay https://relay:7443 --cert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin.pem --key /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin-key.pem deploy --detach agent/app 2>&1, in 0.02 seconds)780container-test-run-flakelet-relay> agent: waiting for success: systemctl is-active flakelet-relay-job-app.service781container-test-run-flakelet-relay> agent # [23436.192370] agent flakelet-agent[502]: {"timestamp":"2026-09-06T13:35:14.154122Z","level":"INFO","message":"update finished","flakelet":"app","status":"Unchanged","generation":"Some(3)","target":"flakelet_relay::agent::jobs"}782container-test-run-flakelet-relay> agent # [23436.249748] agent flakelet-agent[502]: {"timestamp":"2026-09-06T13:35:14.211515Z","level":"INFO","message":"start","id":"c6a7c753ffe7354584d18ca9319066a5","flakelet":"app","rule":"admins","relay":"relay","caller_name":"\"admin@example.org\"","target":"flakelet_relay::agent::conn"}783container-test-run-flakelet-relay> agent # [23436.250074] agent flakelet-agent[502]: {"timestamp":"2026-09-06T13:35:14.211906Z","level":"INFO","message":"update starting","flakelet":"app","ids":"[\"c6a7c753ffe7354584d18ca9319066a5\"]","target":"flakelet_relay::agent::jobs"}784container-test-run-flakelet-relay> agent # [23436.287648] agent systemd[1]: Starting flakelet update app (via relay)...785container-test-run-flakelet-relay> agent # [23436.322202] agent systemd[1]: Started flakelet update app (via relay).786container-test-run-flakelet-relay> agent # [23436.327018] agent flakelet[1752]: app: using prebuilt artifact /nix/store/ln47rsd5km8a04hd5zlkz4q1v352zqh3-flakelet-app-slow2787container-test-run-flakelet-relay> agent # [23436.327673] agent flakelet[1752]: app: activating generation 4788container-test-run-flakelet-relay> agent # [23436.340553] agent systemd[1]: Stopping app v1...789container-test-run-flakelet-relay> agent # [23436.341095] agent systemd[1]: app.service: Deactivated successfully.790container-test-run-flakelet-relay> agent # [23436.341358] agent systemd[1]: Stopped app v1.791container-test-run-flakelet-relay> agent # [23436.359392] agent systemd[1]: Reload requested from client PID 1759 ('systemctl') (unit flakelet-relay-job-app.service)...792container-test-run-flakelet-relay> agent # [23436.359461] agent systemd[1]: Reloading...793container-test-run-flakelet-relay> relay # [23436.193984] relay flakelet-relay[400]: {"timestamp":"2026-09-06T13:35:14.155728Z","level":"INFO","message":"deploy finished","job":"a2eb9967bf7fd1fd14b027a272f6262f","ok":false,"target":"flakelet_relay::relay::api"}794container-test-run-flakelet-relay> relay # [23436.249454] relay flakelet-relay[400]: {"timestamp":"2026-09-06T13:35:14.211214Z","level":"INFO","message":"deploy accepted","job":"9d7d301ea5bc371d84417dcaa944784d","caller":"admin@example.org","targets":"[\"agent/app\"]","target":"flakelet_relay::relay::api"}795container-test-run-flakelet-relay> agent # [23436.702713] agent systemd[1]: Reloading finished in 342 ms.796container-test-run-flakelet-relay> agent # [23436.772108] agent systemd[1]: Reload requested from client PID 1789 ('systemctl') (unit flakelet-relay-job-app.service)...797container-test-run-flakelet-relay> agent # [23436.772148] agent systemd[1]: Reloading...798container-test-run-flakelet-relay> agent # [23437.098517] agent systemd[1]: Reloading finished in 325 ms.799container-test-run-flakelet-relay> agent # [23437.169472] agent systemd[1]: Reload requested from client PID 1821 ('systemctl') (unit flakelet-relay-job-app.service)...800container-test-run-flakelet-relay> agent # [23437.169510] agent systemd[1]: Reloading...801container-test-run-flakelet-relay> agent: (finished: waiting for success: systemctl is-active flakelet-relay-job-app.service, in 1.30 seconds)802container-test-run-flakelet-relay> ??? Warning (UserWarning): wait_until_fails(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.803container-test-run-flakelet-relay>     File "/nix/store/fv4yr9c0f9kyrfc00100i4rlshiiidbd-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39804container-test-run-flakelet-relay> agent: waiting for failure: systemctl is-active flakelet-relay-job-app.service805container-test-run-flakelet-relay> ??? Warning (UserWarning): execute(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.806container-test-run-flakelet-relay>     File "/nix/store/fv4yr9c0f9kyrfc00100i4rlshiiidbd-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39807container-test-run-flakelet-relay> agent # [23437.497607] agent systemd[1]: Reloading finished in 327 ms.808container-test-run-flakelet-relay> agent # [23437.563152] agent systemd[1]: Reload requested from client PID 1856 ('systemctl') (unit flakelet-relay-job-app.service)...809container-test-run-flakelet-relay> agent # [23437.563188] agent systemd[1]: Reloading...810container-test-run-flakelet-relay> agent # [23437.889410] agent systemd[1]: Reloading finished in 325 ms.811container-test-run-flakelet-relay> agent # [23437.952032] agent systemd[1]: Starting app slow2...812container-test-run-flakelet-relay> agent # [23444.022055] agent systemd[1]: Started app slow2.813container-test-run-flakelet-relay> agent # [23444.031838] agent flakelet[1752]: app: updated to generation 4814container-test-run-flakelet-relay> agent # [23444.035239] agent systemd[1]: flakelet-relay-job-app.service: Deactivated successfully.815container-test-run-flakelet-relay> agent: (finished: waiting for failure: systemctl is-active flakelet-relay-job-app.service, in 7.49 seconds)816container-test-run-flakelet-relay> (finished: subtest: detach returns once the job is accepted, in 8.83 seconds)817container-test-run-flakelet-relay> subtest: unknown host is final, missing agent is retried, agents listing818container-test-run-flakelet-relay> client: must fail: push --relay https://relay:7443 --cert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin.pem --key /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin-key.pem deploy nope/app 2>&1819container-test-run-flakelet-relay> client: (finished: must fail: push --relay https://relay:7443 --cert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin.pem --key /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin-key.pem deploy nope/app 2>&1, in 0.02 seconds)820container-test-run-flakelet-relay> agent: must succeed: systemctl stop flakelet-agent821container-test-run-flakelet-relay> agent: (finished: must succeed: systemctl stop flakelet-agent, in 0.08 seconds)822container-test-run-flakelet-relay> relay: waiting for success: curl -sf http://127.0.0.1:7400/metrics | grep -q 'agent_up{host="agent"} 0'823container-test-run-flakelet-relay> relay: (finished: waiting for success: curl -sf http://127.0.0.1:7400/metrics | grep -q 'agent_up{host="agent"} 0', in 0.02 seconds)824container-test-run-flakelet-relay> client: must succeed: push --relay https://relay:7443 --cert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin.pem --key /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin-key.pem deploy agent/other > /tmp/retry.out 2>&1 &825container-test-run-flakelet-relay> client: (finished: must succeed: push --relay https://relay:7443 --cert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin.pem --key /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin-key.pem deploy agent/other > /tmp/retry.out 2>&1 &, in 0.01 seconds)826container-test-run-flakelet-relay> client: waiting for success: grep -q 'agent_unavailable.*retrying' /tmp/retry.out827container-test-run-flakelet-relay> agent # [23445.083773] agent systemd[1]: Stopping flakelet-agent...828container-test-run-flakelet-relay> agent # [23445.116286] agent systemd[1]: flakelet-agent.service: Deactivated successfully.829container-test-run-flakelet-relay> agent # [23445.116677] agent systemd[1]: Stopped flakelet-agent.830container-test-run-flakelet-relay> agent # [23445.117135] agent systemd[1]: flakelet-agent.service: Consumed 2.629s CPU time over 28.107s wall clock time.831container-test-run-flakelet-relay> relay # [23445.083853] relay flakelet-relay[400]: {"timestamp":"2026-09-06T13:35:23.045626Z","level":"INFO","message":"agent disconnected: IO error: peer closed connection without sending TLS close_notify: https://docs.rs/rustls/latest/rustls/manual/_03_howto/index.html#unexpected-eof","host":"agent","target":"flakelet_relay::relay::agent_conn"}832container-test-run-flakelet-relay> client: (finished: waiting for success: grep -q 'agent_unavailable.*retrying' /tmp/retry.out, in 1.02 seconds)833container-test-run-flakelet-relay> agent: must succeed: systemctl start flakelet-agent834container-test-run-flakelet-relay> agent: (finished: must succeed: systemctl start flakelet-agent, in 0.10 seconds)835container-test-run-flakelet-relay> ??? Warning (UserWarning): wait_until_succeeds(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.836container-test-run-flakelet-relay>     File "/nix/store/fv4yr9c0f9kyrfc00100i4rlshiiidbd-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39837container-test-run-flakelet-relay> client: waiting for success: grep -q 'agent/other: unchanged' /tmp/retry.out838container-test-run-flakelet-relay> agent # [23446.207351] agent systemd[1]: Starting flakelet-agent...839container-test-run-flakelet-relay> agent # [23446.255923] agent flakelet-agent[1936]: {"timestamp":"2026-09-06T13:35:24.217712Z","level":"INFO","message":"reattaching to running unit","flakelet":"app","ids":"[\"c6a7c753ffe7354584d18ca9319066a5\"]","target":"flakelet_relay::agent::jobs"}840container-test-run-flakelet-relay> agent # [23446.296670] agent systemd[1]: Started flakelet-agent.841container-test-run-flakelet-relay> agent # [23446.307104] agent flakelet-agent[1936]: {"timestamp":"2026-09-06T13:35:24.268911Z","level":"INFO","message":"connected","relay":"https://relay:7443","relay_name":"relay","relay_version":"0.1.0","host":"agent","target":"flakelet_relay::agent::conn"}842container-test-run-flakelet-relay> relay # [23446.307192] relay flakelet-relay[400]: {"timestamp":"2026-09-06T13:35:24.268935Z","level":"INFO","message":"agent connected","host":"agent","version":"0.1.0","flakelets":"[Named { name: \"app\", generation: Some(4), revision: Some(\"prebuilt:app\") }, Named { name: \"other\", generation: Some(1), revision: Some(\"prebuilt:other\") }]","target":"flakelet_relay::relay::agent_conn"}843container-test-run-flakelet-relay> agent # [23446.798584] agent flakelet-agent[1936]: {"timestamp":"2026-09-06T13:35:24.760345Z","level":"INFO","message":"update finished","flakelet":"app","status":"Updated","generation":"Some(4)","target":"flakelet_relay::agent::jobs"}844container-test-run-flakelet-relay> agent # [23448.195835] agent flakelet-agent[1936]: {"timestamp":"2026-09-06T13:35:26.157547Z","level":"INFO","message":"start","id":"04919f8fd7c5eb26ccdf7cbd7a51d44f","flakelet":"other","rule":"admins","relay":"relay","caller_name":"\"admin@example.org\"","target":"flakelet_relay::agent::conn"}845container-test-run-flakelet-relay> agent # [23448.196791] agent flakelet-agent[1936]: {"timestamp":"2026-09-06T13:35:26.158312Z","level":"INFO","message":"update starting","flakelet":"other","ids":"[\"04919f8fd7c5eb26ccdf7cbd7a51d44f\"]","target":"flakelet_relay::agent::jobs"}846container-test-run-flakelet-relay> agent # [23448.238198] agent systemd[1]: Starting flakelet update other (via relay)...847container-test-run-flakelet-relay> agent # [23448.276107] agent systemd[1]: Started flakelet update other (via relay).848container-test-run-flakelet-relay> agent # [23448.280709] agent flakelet[2361]: other: using prebuilt artifact /nix/store/hmr990n0b4v6rpzrcpyyw738r9xi7z95-flakelet-other-v1849container-test-run-flakelet-relay> agent # [23448.281263] agent flakelet[2361]: other: up to date850container-test-run-flakelet-relay> agent # [23448.283047] agent systemd[1]: flakelet-relay-job-other.service: Deactivated successfully.851container-test-run-flakelet-relay> relay # [23448.195308] relay flakelet-relay[400]: {"timestamp":"2026-09-06T13:35:26.157042Z","level":"INFO","message":"deploy accepted","job":"a4c13627e1c74e5005e90c6bf19ba3c3","caller":"admin@example.org","targets":"[\"agent/other\"]","target":"flakelet_relay::relay::api"}852container-test-run-flakelet-relay> agent # [23448.821256] agent flakelet-agent[1936]: {"timestamp":"2026-09-06T13:35:26.782957Z","level":"INFO","message":"update finished","flakelet":"other","status":"Unchanged","generation":"Some(1)","target":"flakelet_relay::agent::jobs"}853container-test-run-flakelet-relay> relay # [23448.822523] relay flakelet-relay[400]: {"timestamp":"2026-09-06T13:35:26.784259Z","level":"INFO","message":"deploy finished","job":"a4c13627e1c74e5005e90c6bf19ba3c3","ok":true,"target":"flakelet_relay::relay::api"}854container-test-run-flakelet-relay> client: (finished: waiting for success: grep -q 'agent/other: unchanged' /tmp/retry.out, in 3.04 seconds)855container-test-run-flakelet-relay> client: must succeed: push --relay https://relay:7443 --cert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin.pem --key /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin-key.pem agents856container-test-run-flakelet-relay> client: (finished: must succeed: push --relay https://relay:7443 --cert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin.pem --key /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin-key.pem agents, in 0.02 seconds)857container-test-run-flakelet-relay> client: must succeed: echo '{"groups": []}' | step crypto jwt sign --key /nix/store/r7sclamg09v434w8zjlfnrcrn1w79wh6-flakelet-relay-test-issuer/jwk.json --iss https://relay:8443 --aud flakelet-relay --sub 'repo:github:example/app:ref:refs/heads/main' --exp $(( $(date +%s) + 300 )) --jti $(head -c8 /dev/urandom | base32)858container-test-run-flakelet-relay> client: (finished: must succeed: echo '{"groups": []}' | step crypto jwt sign --key /nix/store/r7sclamg09v434w8zjlfnrcrn1w79wh6-flakelet-relay-test-issuer/jwk.json --iss https://relay:8443 --aud flakelet-relay --sub 'repo:github:example/app:ref:refs/heads/main' --exp $(( $(date +%s) + 300 )) --jti $(head -c8 /dev/urandom | base32), in 0.06 seconds)859container-test-run-flakelet-relay> client: must succeed: FLAKELET_RELAY_TOKEN_COMMAND='echo eyJhbGciOiJFZERTQSIsImtpZCI6ImsxIiwidHlwIjoiSldUIn0.eyJhdWQiOiJmbGFrZWxldC1yZWxheSIsImV4cCI6MTc4ODcwMjAyNywiZ3JvdXBzIjpbXSwiaWF0IjoxNzg4NzAxNzI3LCJpc3MiOiJodHRwczovL3JlbGF5Ojg0NDMiLCJqdGkiOiI0NUJPQ1czRDRPVERDPT09IiwibmJmIjoxNzg4NzAxNzI3LCJzdWIiOiJyZXBvOmdpdGh1YjpleGFtcGxlL2FwcDpyZWY6cmVmcy9oZWFkcy9tYWluIn0.vpQVUTc5R4-hauc1Kk3znfgNlDRuazwNGyjuUYnzICOZedPIKB6gmUrOlmrIUVJ87s4tzqjoZzHqhFONoMs7Cg' push --relay https://relay:7443 agents860container-test-run-flakelet-relay> client: (finished: must succeed: FLAKELET_RELAY_TOKEN_COMMAND='echo eyJhbGciOiJFZERTQSIsImtpZCI6ImsxIiwidHlwIjoiSldUIn0.eyJhdWQiOiJmbGFrZWxldC1yZWxheSIsImV4cCI6MTc4ODcwMjAyNywiZ3JvdXBzIjpbXSwiaWF0IjoxNzg4NzAxNzI3LCJpc3MiOiJodHRwczovL3JlbGF5Ojg0NDMiLCJqdGkiOiI0NUJPQ1czRDRPVERDPT09IiwibmJmIjoxNzg4NzAxNzI3LCJzdWIiOiJyZXBvOmdpdGh1YjpleGFtcGxlL2FwcDpyZWY6cmVmcy9oZWFkcy9tYWluIn0.vpQVUTc5R4-hauc1Kk3znfgNlDRuazwNGyjuUYnzICOZedPIKB6gmUrOlmrIUVJ87s4tzqjoZzHqhFONoMs7Cg' push --relay https://relay:7443 agents, in 0.02 seconds)861container-test-run-flakelet-relay> (finished: subtest: unknown host is final, missing agent is retried, agents listing, in 4.39 seconds)862container-test-run-flakelet-relay> subtest: out-of-band flakelet update reaches the relay without reconnect863container-test-run-flakelet-relay> agent: must succeed: flakelet status --json app | jq '.[0].generation'864container-test-run-flakelet-relay> agent: (finished: must succeed: flakelet status --json app | jq '.[0].generation', in 0.03 seconds)865container-test-run-flakelet-relay> agent: must succeed: journalctl -u flakelet-agent -o cat | grep -c '"connected"' || true866container-test-run-flakelet-relay> agent: (finished: must succeed: journalctl -u flakelet-agent -o cat | grep -c '"connected"' || true, in 0.01 seconds)867container-test-run-flakelet-relay> agent: must succeed: jq '.services.app.prebuilt="/nix/store/6ili1vxjlag9d7dy0bxrbxk6bpi5qh9p-flakelet-app-v2"' /etc/flakelet/config.json > /tmp/config.json && cp --remove-destination /tmp/config.json /etc/flakelet/config.json868container-test-run-flakelet-relay> agent: (finished: must succeed: jq '.services.app.prebuilt="/nix/store/6ili1vxjlag9d7dy0bxrbxk6bpi5qh9p-flakelet-app-v2"' /etc/flakelet/config.json > /tmp/config.json && cp --remove-destination /tmp/config.json /etc/flakelet/config.json, in 0.01 seconds)869container-test-run-flakelet-relay> agent: must succeed: flakelet update app870container-test-run-flakelet-relay> app: using prebuilt artifact /nix/store/6ili1vxjlag9d7dy0bxrbxk6bpi5qh9p-flakelet-app-v2871container-test-run-flakelet-relay> app: activating generation 5872container-test-run-flakelet-relay> agent # [23449.519850] agent systemd[1]: Stopping app slow2...873container-test-run-flakelet-relay> agent # [23449.520344] agent systemd[1]: app.service: Deactivated successfully.874container-test-run-flakelet-relay> agent # [23449.520607] agent systemd[1]: Stopped app slow2.875container-test-run-flakelet-relay> agent # [23449.538260] agent systemd[1]: Reload requested from client PID 2403 ('systemctl')...876container-test-run-flakelet-relay> agent # [23449.538327] agent systemd[1]: Reloading...877container-test-run-flakelet-relay> agent # [23449.893806] agent systemd[1]: Reloading finished in 354 ms.878container-test-run-flakelet-relay> agent # [23449.962384] agent systemd[1]: Reload requested from client PID 2432 ('systemctl')...879container-test-run-flakelet-relay> agent # [23449.962425] agent systemd[1]: Reloading...880container-test-run-flakelet-relay> agent # [23450.299885] agent systemd[1]: Reloading finished in 337 ms.881container-test-run-flakelet-relay> agent # [23450.369625] agent systemd[1]: Reload requested from client PID 2462 ('systemctl')...882container-test-run-flakelet-relay> agent # [23450.369663] agent systemd[1]: Reloading...883container-test-run-flakelet-relay> agent # [23450.701683] agent systemd[1]: Reloading finished in 331 ms.884container-test-run-flakelet-relay> agent # [23450.769392] agent systemd[1]: Reload requested from client PID 2493 ('systemctl')...885container-test-run-flakelet-relay> agent # [23450.769429] agent systemd[1]: Reloading...886container-test-run-flakelet-relay> agent # [23451.101357] agent systemd[1]: Reloading finished in 331 ms.887container-test-run-flakelet-relay> agent: (finished: must succeed: flakelet update app, in 1.71 seconds)888container-test-run-flakelet-relay> client: waiting for success: push --relay https://relay:7443 --cert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin.pem --key /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin-key.pem agents | grep -q 'app@5'889container-test-run-flakelet-relay> agent # [23451.163630] agent systemd[1]: Starting app v2...890container-test-run-flakelet-relay> agent # [23451.199085] agent systemd[1]: Started app v2.891container-test-run-flakelet-relay> agent # [23452.324216] agent flakelet-agent[1936]: {"timestamp":"2026-09-06T13:35:30.285958Z","level":"INFO","message":"local change","flakelet":"app","generation":5,"by":"root (shell)","target":"flakelet_relay::agent::jobs"}892container-test-run-flakelet-relay> client: (finished: waiting for success: push --relay https://relay:7443 --cert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin.pem --key /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin-key.pem agents | grep -q 'app@5', in 2.07 seconds)893container-test-run-flakelet-relay> agent: must succeed: journalctl -u flakelet-agent -o cat | grep -c '"connected"' || true894container-test-run-flakelet-relay> agent: (finished: must succeed: journalctl -u flakelet-agent -o cat | grep -c '"connected"' || true, in 0.01 seconds)895container-test-run-flakelet-relay> client: waiting for success: push --relay https://relay:7443 --cert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin.pem --key /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin-key.pem jobs | grep local-app-5-896container-test-run-flakelet-relay> client: (finished: waiting for success: push --relay https://relay:7443 --cert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin.pem --key /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin-key.pem jobs | grep local-app-5-, in 0.02 seconds)897container-test-run-flakelet-relay> client: must succeed: push --relay https://relay:7443 --cert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin.pem --key /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin-key.pem jobs | grep local-app-898container-test-run-flakelet-relay> client: (finished: must succeed: push --relay https://relay:7443 --cert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin.pem --key /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin-key.pem jobs | grep local-app-, in 0.02 seconds)899container-test-run-flakelet-relay> agent: must succeed: jq '.services.app.prebuilt="/nix/store/hysydfr96ds875vr0jgw5rjxi4p39h2j-flakelet-app-v1"' /etc/flakelet/config.json > /tmp/config.json && cp --remove-destination /tmp/config.json /etc/flakelet/config.json900container-test-run-flakelet-relay> agent: (finished: must succeed: jq '.services.app.prebuilt="/nix/store/hysydfr96ds875vr0jgw5rjxi4p39h2j-flakelet-app-v1"' /etc/flakelet/config.json > /tmp/config.json && cp --remove-destination /tmp/config.json /etc/flakelet/config.json, in 0.01 seconds)901container-test-run-flakelet-relay> agent: must succeed: flakelet update app902container-test-run-flakelet-relay> app: using prebuilt artifact /nix/store/hysydfr96ds875vr0jgw5rjxi4p39h2j-flakelet-app-v1903container-test-run-flakelet-relay> app: activating generation 6904container-test-run-flakelet-relay> agent # [23453.378991] agent systemd[1]: Stopping app v2...905container-test-run-flakelet-relay> agent # [23453.379447] agent systemd[1]: app.service: Deactivated successfully.906container-test-run-flakelet-relay> agent # [23453.379716] agent systemd[1]: Stopped app v2.907container-test-run-flakelet-relay> agent # [23453.399370] agent systemd[1]: Reload requested from client PID 2547 ('systemctl')...908container-test-run-flakelet-relay> agent # [23453.399441] agent systemd[1]: Reloading...909container-test-run-flakelet-relay> agent # [23453.751595] agent systemd[1]: Reloading finished in 351 ms.910container-test-run-flakelet-relay> agent # [23453.819285] agent systemd[1]: Reload requested from client PID 2576 ('systemctl')...911container-test-run-flakelet-relay> agent # [23453.819320] agent systemd[1]: Reloading...