these 43 derivations will be built: /nix/store/qqp4hhw6xpgyxssfxyzxac5yn55kdhzg-flakelet-relay.json.drv /nix/store/vln2mpqksri93wa8w5w1h7b242h6nxbq-flakelet-relay.service.drv /nix/store/k3dj4z3rj1a9ik2rm9vh17d0v3mgq86k-flakelet-flakelet-relay-units.drv /nix/store/006g8wgskgq50lzlsbjm117q113k0dd6-flakelet-flakelet-relay.drv /nix/store/x03fs0v5l0xxyahhmjr0m6adck5xvx1l-X-Restart-Triggers-flakelet-flakelet-agent.drv /nix/store/2870p40793vxwjjyc79cnjrz22h6czbc-unit-flakelet-flakelet-agent.service.drv /nix/store/2jlcpbb9y5fz6hijxdv6x78i85gxi1nh-flakelet-config.json.drv /nix/store/90lcf2a4d46vizsjnzq0wkxfh7c2wsdf-X-Restart-Triggers-flakelet-reconcile.drv /nix/store/7v95bjgiagpsjld7rixh8h6mla2wslwy-unit-flakelet-reconcile.service.drv /nix/store/nnrpxgg7pyifd293mn4h7v84yp0j8l8l-X-Restart-Triggers-flakelet-flakelet-relay.drv /nix/store/hva88l9j3pvimgzb44dv4sw3dj5czwnc-unit-flakelet-flakelet-relay.service.drv /nix/store/2lw666vh16106ik107rbjh7bpqpdx37g-system-units.drv /nix/store/ynbm2sd7w9gnh682zi4f095jcvhh824v-push.drv /nix/store/2rbhcr84lvanyr8llb67zkskk3pysm4a-system-path.drv /nix/store/3v22avfai9ls8irpdhnxbpbafchfb2xg-flakelet-agent.service.drv /nix/store/6n3gb7hxpccflp735f7bwdyax8q0kii2-flakelet-flakelet-agent-units.drv /nix/store/q5l7141zdgq2h8pvsg6kx55jbhzmpzd6-flakelet-flakelet-agent.drv /nix/store/zckd8gfnhimq763gqx185zcmf5qxsf4b-flakelet-config.json.drv /nix/store/8kldynx19s5yddk8z822gf8q19mc9agl-X-Restart-Triggers-flakelet-reconcile.drv /nix/store/hwnbdcdhlnk2sywscgwnirmmvilmjkim-unit-flakelet-reconcile.service.drv /nix/store/3s6ldxwx84a1a3qd3nsrnppbnv0raf0m-system-units.drv /nix/store/wvz6k2b0f3vlrsyczshccksdd89wpxd0-etc.drv /nix/store/mzjllrnkcl31il4ybq8gm0zlr6bc8c0r-activate.drv /nix/store/zns6802mfyzsfbvbvjyczkn0p67c531l-nixos-system-agent-test.drv /nix/store/493vgs5bbzq0ihr2zvnhpcayzpdfv96q-run-agent-nspawn.drv /nix/store/qigr2jrf4kxi7z7wimmpp8bs3gprccfh-etc.drv /nix/store/hy3awp7qns2xp1gdzkfzl3yzkxnbk9kh-activate.drv /nix/store/9y1yzazqppiadb9d44v83l3jn6vj3pbz-nixos-system-relay-test.drv /nix/store/4wqbiai64im3ml10dx1j8m1j0rbbkajc-run-relay-nspawn.drv /nix/store/wb2gs722qns3ij2kgzjqd4aw57y8fbd4-dbus-1.drv /nix/store/rg2qak4ii4wvnjkhaxsvvbldpwg3j9ci-X-Restart-Triggers-dbus-broker.drv /nix/store/iaiyh2pkscg798mbchly1c1yn8kimlh0-unit-dbus-broker.service.drv /nix/store/b1v9whx4ciykmhmj79hiy3zbk7fg3vcl-system-units.drv /nix/store/8hrflwjpkxdiid8ah15afj6261lwrzhh-unit-dbus-broker.service.drv /nix/store/mk656hxdf40f37qnxv3iwvrrqsy01wj9-user-units.drv /nix/store/z9p4rrh1z3wqzm6j91anf1jd2fyvym3b-etc.drv /nix/store/xbb7v66rcpjp6m5y1g1k7lf7im2pas9x-activate.drv /nix/store/v57z7dhglgc26a3w76krn05xw28rfnp0-nixos-system-client-test.drv /nix/store/9rxyi976zlaywm4zsmrknawdib5n449w-run-client-nspawn.drv /nix/store/hr9c52swyzb6ap39lm7avrpdqk2ixh0f-test-script.drv /nix/store/gw9dxj48811dfx5bqh2z8wynwmc8qgq3-driverConfiguration.json.drv /nix/store/xx56qak5d6s4dalrqcn14ypjcpagbdis-nixos-test-driver-flakelet-relay.drv /nix/store/3qbgp499vlfvi5qqwvh37126xl2j8vrk-container-test-run-flakelet-relay.drv building '/nix/store/hr9c52swyzb6ap39lm7avrpdqk2ixh0f-test-script.drv' building '/nix/store/ynbm2sd7w9gnh682zi4f095jcvhh824v-push.drv' building '/nix/store/x03fs0v5l0xxyahhmjr0m6adck5xvx1l-X-Restart-Triggers-flakelet-flakelet-agent.drv' building '/nix/store/nnrpxgg7pyifd293mn4h7v84yp0j8l8l-X-Restart-Triggers-flakelet-flakelet-relay.drv' X-Restart-Triggers-flakelet-flakelet-agent> tribuchet: building on jamie push> tribuchet: building on jamie test-script> tribuchet: building on jamie building '/nix/store/3v22avfai9ls8irpdhnxbpbafchfb2xg-flakelet-agent.service.drv' X-Restart-Triggers-flakelet-flakelet-relay> tribuchet: building on jamie building '/nix/store/qqp4hhw6xpgyxssfxyzxac5yn55kdhzg-flakelet-relay.json.drv' flakelet-agent.service> tribuchet: building on jamie flakelet-relay.json> tribuchet: building on jamie flakelet-relay.json> check: oidc:mock:repo:github:example/app:ref:refs/heads/main -> agent/app (expect allow) flakelet-relay.json> agent/app allowed by ci flakelet-relay.json> check: oidc:mock:repo:github:example/app:ref:refs/heads/main -> agent/other (expect deny) flakelet-relay.json> agent/other denied building '/nix/store/2870p40793vxwjjyc79cnjrz22h6czbc-unit-flakelet-flakelet-agent.service.drv' building '/nix/store/hva88l9j3pvimgzb44dv4sw3dj5czwnc-unit-flakelet-flakelet-relay.service.drv' unit-flakelet-flakelet-agent.service> tribuchet: building on jamie unit-flakelet-flakelet-relay.service> tribuchet: building on jamie building '/nix/store/vln2mpqksri93wa8w5w1h7b242h6nxbq-flakelet-relay.service.drv' building '/nix/store/6n3gb7hxpccflp735f7bwdyax8q0kii2-flakelet-flakelet-agent-units.drv' building '/nix/store/2rbhcr84lvanyr8llb67zkskk3pysm4a-system-path.drv' flakelet-flakelet-agent-units> tribuchet: building on jamie flakelet-relay.service> tribuchet: building on jamie system-path> tribuchet: building on jamie unit-flakelet-flakelet-agent.service> structuredAttrs is enabled unit-flakelet-flakelet-relay.service> structuredAttrs is enabled system-path> structuredAttrs is enabled system-path> created 6399 symlinks in user environment building '/nix/store/k3dj4z3rj1a9ik2rm9vh17d0v3mgq86k-flakelet-flakelet-relay-units.drv' flakelet-flakelet-relay-units> tribuchet: building on jamie building '/nix/store/q5l7141zdgq2h8pvsg6kx55jbhzmpzd6-flakelet-flakelet-agent.drv' flakelet-flakelet-agent> tribuchet: building on jamie building '/nix/store/006g8wgskgq50lzlsbjm117q113k0dd6-flakelet-flakelet-relay.drv' flakelet-flakelet-relay> tribuchet: building on jamie building '/nix/store/zckd8gfnhimq763gqx185zcmf5qxsf4b-flakelet-config.json.drv' flakelet-config.json> tribuchet: building on jamie building '/nix/store/wb2gs722qns3ij2kgzjqd4aw57y8fbd4-dbus-1.drv' flakelet-config.json> structuredAttrs is enabled dbus-1> tribuchet: building on jamie building '/nix/store/2jlcpbb9y5fz6hijxdv6x78i85gxi1nh-flakelet-config.json.drv' flakelet-config.json> tribuchet: building on jamie building '/nix/store/8kldynx19s5yddk8z822gf8q19mc9agl-X-Restart-Triggers-flakelet-reconcile.drv' X-Restart-Triggers-flakelet-reconcile> tribuchet: building on jamie flakelet-config.json> structuredAttrs is enabled building '/nix/store/90lcf2a4d46vizsjnzq0wkxfh7c2wsdf-X-Restart-Triggers-flakelet-reconcile.drv' X-Restart-Triggers-flakelet-reconcile> tribuchet: building on jamie building '/nix/store/rg2qak4ii4wvnjkhaxsvvbldpwg3j9ci-X-Restart-Triggers-dbus-broker.drv' building '/nix/store/hwnbdcdhlnk2sywscgwnirmmvilmjkim-unit-flakelet-reconcile.service.drv' X-Restart-Triggers-dbus-broker> tribuchet: building on jamie unit-flakelet-reconcile.service> tribuchet: building on jamie unit-flakelet-reconcile.service> structuredAttrs is enabled building '/nix/store/7v95bjgiagpsjld7rixh8h6mla2wslwy-unit-flakelet-reconcile.service.drv' unit-flakelet-reconcile.service> tribuchet: building on jamie building '/nix/store/3s6ldxwx84a1a3qd3nsrnppbnv0raf0m-system-units.drv' building '/nix/store/8hrflwjpkxdiid8ah15afj6261lwrzhh-unit-dbus-broker.service.drv' building '/nix/store/iaiyh2pkscg798mbchly1c1yn8kimlh0-unit-dbus-broker.service.drv' system-units> tribuchet: building on jamie unit-dbus-broker.service> tribuchet: building on jamie unit-dbus-broker.service> tribuchet: building on jamie unit-flakelet-reconcile.service> structuredAttrs is enabled unit-dbus-broker.service> structuredAttrs is enabled unit-dbus-broker.service> structuredAttrs is enabled building '/nix/store/2lw666vh16106ik107rbjh7bpqpdx37g-system-units.drv' system-units> tribuchet: building on jamie building '/nix/store/b1v9whx4ciykmhmj79hiy3zbk7fg3vcl-system-units.drv' building '/nix/store/mk656hxdf40f37qnxv3iwvrrqsy01wj9-user-units.drv' system-units> tribuchet: building on jamie user-units> tribuchet: building on jamie building '/nix/store/wvz6k2b0f3vlrsyczshccksdd89wpxd0-etc.drv' etc> tribuchet: building on jamie building '/nix/store/z9p4rrh1z3wqzm6j91anf1jd2fyvym3b-etc.drv' etc> tribuchet: building on jamie building '/nix/store/qigr2jrf4kxi7z7wimmpp8bs3gprccfh-etc.drv' etc> tribuchet: building on jamie building '/nix/store/mzjllrnkcl31il4ybq8gm0zlr6bc8c0r-activate.drv' activate> tribuchet: building on jamie building '/nix/store/xbb7v66rcpjp6m5y1g1k7lf7im2pas9x-activate.drv' activate> tribuchet: building on jamie building '/nix/store/zns6802mfyzsfbvbvjyczkn0p67c531l-nixos-system-agent-test.drv' nixos-system-agent-test> tribuchet: building on jamie building '/nix/store/hy3awp7qns2xp1gdzkfzl3yzkxnbk9kh-activate.drv' activate> tribuchet: building on jamie nixos-system-agent-test> structuredAttrs is enabled building '/nix/store/v57z7dhglgc26a3w76krn05xw28rfnp0-nixos-system-client-test.drv' nixos-system-client-test> tribuchet: building on jamie building '/nix/store/9y1yzazqppiadb9d44v83l3jn6vj3pbz-nixos-system-relay-test.drv' nixos-system-relay-test> tribuchet: building on jamie building '/nix/store/493vgs5bbzq0ihr2zvnhpcayzpdfv96q-run-agent-nspawn.drv' run-agent-nspawn> tribuchet: building on jamie nixos-system-client-test> structuredAttrs is enabled nixos-system-relay-test> structuredAttrs is enabled building '/nix/store/4wqbiai64im3ml10dx1j8m1j0rbbkajc-run-relay-nspawn.drv' building '/nix/store/9rxyi976zlaywm4zsmrknawdib5n449w-run-client-nspawn.drv' run-relay-nspawn> tribuchet: building on jamie run-client-nspawn> tribuchet: building on jamie building '/nix/store/gw9dxj48811dfx5bqh2z8wynwmc8qgq3-driverConfiguration.json.drv' driverConfiguration.json> tribuchet: building on jamie driverConfiguration.json> structuredAttrs is enabled building '/nix/store/xx56qak5d6s4dalrqcn14ypjcpagbdis-nixos-test-driver-flakelet-relay.drv' nixos-test-driver-flakelet-relay> tribuchet: building on jamie nixos-test-driver-flakelet-relay> Running type check (enable/disable: config.skipTypeCheck) nixos-test-driver-flakelet-relay> See https://nixos.org/manual/nixos/stable/#test-opt-skipTypeCheck nixos-test-driver-flakelet-relay> All checks passed! nixos-test-driver-flakelet-relay> Linting test script (enable/disable: config.skipLint) nixos-test-driver-flakelet-relay> See https://nixos.org/manual/nixos/stable/#test-opt-skipLint nixos-test-driver-flakelet-relay> All checks passed! building '/nix/store/3qbgp499vlfvi5qqwvh37126xl2j8vrk-container-test-run-flakelet-relay.drv' container-test-run-flakelet-relay> tribuchet: building on jamie container-test-run-flakelet-relay> Machine state will be reset. To keep it, pass --keep-machine-state container-test-run-flakelet-relay> start all VLans container-test-run-flakelet-relay> (finished: start all VLans, in 0.00 seconds) container-test-run-flakelet-relay> Initializing machine ID from random generator. container-test-run-flakelet-relay> Test will time out and terminate in 3600.0 seconds container-test-run-flakelet-relay> run the VM test script container-test-run-flakelet-relay> start all VMs container-test-run-flakelet-relay> agent: systemd-nspawn running (pid 52) container-test-run-flakelet-relay> client: systemd-nspawn running (pid 53) container-test-run-flakelet-relay> relay: systemd-nspawn running (pid 54) container-test-run-flakelet-relay> agent: Waiting for journal at /build/vm-state-agent/var/log/journal... container-test-run-flakelet-relay> client: Waiting for journal at /build/vm-state-client/var/log/journal... container-test-run-flakelet-relay> relay: Waiting for journal at /build/vm-state-relay/var/log/journal... container-test-run-flakelet-relay> (finished: start all VMs, in 0.00 seconds) container-test-run-flakelet-relay> relay: waiting for unit flakelet-relay.service container-test-run-flakelet-relay> nixos-nspawn(agent): TAP vde-tap1 not found; container will be isolated from VDE container-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. container-test-run-flakelet-relay> nixos-nspawn(relay): TAP vde-tap1 not found; container will be isolated from VDE container-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. container-test-run-flakelet-relay> nixos-nspawn(client): TAP vde-tap1 not found; container will be isolated from VDE container-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. container-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. container-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. container-test-run-flakelet-relay> ░ Spawning container relay on /build/vm-state-relay. container-test-run-flakelet-relay> ░ Spawning container client on /build/vm-state-client. container-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. container-test-run-flakelet-relay> ░ Spawning container agent on /build/vm-state-agent. container-test-run-flakelet-relay> agent # No journal files were found. container-test-run-flakelet-relay> agent # No journal boot entry found for the specified boot (+0). container-test-run-flakelet-relay> client # No journal files were found. container-test-run-flakelet-relay> client # No journal boot entry found for the specified boot (+0). container-test-run-flakelet-relay> relay # No journal files were found. container-test-run-flakelet-relay> relay # No journal boot entry found for the specified boot (+0). container-test-run-flakelet-relay> client # [23412.795947] client systemd-journald[57]: Journal started container-test-run-flakelet-relay> client # [23412.795990] client systemd-journald[57]: Runtime Journal (/run/log/journal/ca2f4864e74945a9b6da570cef0966cf) is 8M, max 4G, 3.9G free. container-test-run-flakelet-relay> agent # [23412.796777] agent systemd-journald[57]: Journal started container-test-run-flakelet-relay> client # [23412.807853] client systemd[1]: Finished Apply Kernel Variables. container-test-run-flakelet-relay> agent # [23412.796818] agent systemd-journald[57]: Runtime Journal (/run/log/journal/8a35a01e4af443999b3fa8b64b468abd) is 8M, max 4G, 3.9G free. container-test-run-flakelet-relay> client # [23412.820346] client systemd[1]: Finished Create Static Device Nodes in /dev gracefully. container-test-run-flakelet-relay> agent # [23412.808177] agent systemd[1]: Finished Apply Kernel Variables. container-test-run-flakelet-relay> client # [23412.834962] client systemd[1]: Starting Flush Journal to Persistent Storage... container-test-run-flakelet-relay> agent # [23412.820604] agent systemd[1]: Finished Create Static Device Nodes in /dev gracefully. container-test-run-flakelet-relay> client # [23412.835693] client systemd[1]: Starting Create Static Device Nodes in /dev... container-test-run-flakelet-relay> agent # [23412.835142] agent systemd[1]: Starting Flush Journal to Persistent Storage... container-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. container-test-run-flakelet-relay> agent # [23412.835826] agent systemd[1]: Starting Create Static Device Nodes in /dev... container-test-run-flakelet-relay> client # [23412.873035] client systemd-journald[57]: System Journal (/var/log/journal/ca2f4864e74945a9b6da570cef0966cf) is 512B, max 4G, 3.9G free. container-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. container-test-run-flakelet-relay> client # [23412.882103] client systemd[1]: Finished Create Static Device Nodes in /dev. container-test-run-flakelet-relay> agent # [23412.873533] agent systemd-journald[57]: System Journal (/var/log/journal/8a35a01e4af443999b3fa8b64b468abd) is 512B, max 4G, 3.9G free. container-test-run-flakelet-relay> client # [23412.882832] client systemd[1]: Reached target Preparation for Local File Systems. container-test-run-flakelet-relay> agent # [23412.882030] agent systemd[1]: Finished Flush Journal to Persistent Storage. container-test-run-flakelet-relay> client # [23412.882942] client systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys container-test-run-flakelet-relay> agent # [23412.882341] agent systemd[1]: Finished Create Static Device Nodes in /dev. container-test-run-flakelet-relay> client # [23412.898225] client systemd[1]: Finished Flush Journal to Persistent Storage. container-test-run-flakelet-relay> agent # [23412.883575] agent systemd[1]: Reached target Preparation for Local File Systems. container-test-run-flakelet-relay> client # [23412.956015] client systemd[1]: Finished Firewall. container-test-run-flakelet-relay> agent # [23412.883699] agent systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys container-test-run-flakelet-relay> client # [23413.814671] client systemd[1]: Mounting /run/wrappers... container-test-run-flakelet-relay> agent # [23412.962248] agent systemd[1]: Finished Firewall. container-test-run-flakelet-relay> client # [23413.845728] client systemd[1]: Mounted /run/wrappers. container-test-run-flakelet-relay> agent # [23413.814662] agent systemd[1]: Mounting /run/wrappers... container-test-run-flakelet-relay> client # [23413.846767] client systemd[1]: Reached target Local File Systems. container-test-run-flakelet-relay> agent # [23413.845521] agent systemd[1]: Mounted /run/wrappers. container-test-run-flakelet-relay> client # [23413.848106] client systemd[1]: Listening on Boot Loader Control Service Socket. container-test-run-flakelet-relay> relay # [23412.796493] relay systemd-journald[57]: Journal started container-test-run-flakelet-relay> agent # [23413.846523] agent systemd[1]: Reached target Local File Systems. container-test-run-flakelet-relay> relay # [23412.796535] relay systemd-journald[57]: Runtime Journal (/run/log/journal/38663ab5ad3c4fc2bb41dcec3f73c24b) is 8M, max 4G, 3.9G free. container-test-run-flakelet-relay> agent # [23413.847789] agent systemd[1]: Listening on Boot Loader Control Service Socket. container-test-run-flakelet-relay> relay # [23412.808040] relay systemd[1]: Finished Apply Kernel Variables. container-test-run-flakelet-relay> agent # [23413.849494] agent systemd[1]: Starting Create SUID/SGID Wrappers... container-test-run-flakelet-relay> relay # [23412.820413] relay systemd[1]: Finished Create Static Device Nodes in /dev gracefully. container-test-run-flakelet-relay> agent # [23413.849543] agent systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container container-test-run-flakelet-relay> relay # [23412.835305] relay systemd[1]: Starting Flush Journal to Persistent Storage... container-test-run-flakelet-relay> relay # [23412.835884] relay systemd[1]: Starting Create Static Device Nodes in /dev... container-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. container-test-run-flakelet-relay> relay # [23412.873404] relay systemd-journald[57]: System Journal (/var/log/journal/38663ab5ad3c4fc2bb41dcec3f73c24b) is 512B, max 4G, 3.9G free. container-test-run-flakelet-relay> relay # [23412.882186] relay systemd[1]: Finished Create Static Device Nodes in /dev. container-test-run-flakelet-relay> relay # [23412.882876] relay systemd[1]: Reached target Preparation for Local File Systems. container-test-run-flakelet-relay> relay # [23412.883028] relay systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys container-test-run-flakelet-relay> relay # [23412.896382] relay systemd[1]: Finished Flush Journal to Persistent Storage. container-test-run-flakelet-relay> relay # [23412.966530] relay systemd[1]: Finished Firewall. container-test-run-flakelet-relay> relay # [23413.795905] relay systemd[1]: Mounting /run/wrappers... container-test-run-flakelet-relay> relay # [23413.845444] relay systemd[1]: Mounted /run/wrappers. container-test-run-flakelet-relay> relay # [23413.846544] relay systemd[1]: Reached target Local File Systems. container-test-run-flakelet-relay> relay # [23413.847781] relay systemd[1]: Listening on Boot Loader Control Service Socket. container-test-run-flakelet-relay> relay # [23413.849167] relay systemd[1]: Starting Create SUID/SGID Wrappers... container-test-run-flakelet-relay> relay # [23413.849213] relay systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container container-test-run-flakelet-relay> relay # [23413.850682] relay systemd[1]: Starting Save Transient machine-id to Disk... container-test-run-flakelet-relay> relay # [23413.851955] relay systemd[1]: Starting Create System Files and Directories... container-test-run-flakelet-relay> agent # [23413.850715] agent systemd[1]: Starting Save Transient machine-id to Disk... container-test-run-flakelet-relay> client # [23413.849638] client systemd[1]: Starting Create SUID/SGID Wrappers... container-test-run-flakelet-relay> agent # [23413.852073] agent systemd[1]: Starting Create System Files and Directories... container-test-run-flakelet-relay> client # [23413.849684] client systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container container-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 permitted container-test-run-flakelet-relay> client # [23413.850895] client systemd[1]: Starting Save Transient machine-id to Disk... container-test-run-flakelet-relay> relay # [23413.870065] relay systemd-tmpfiles[169]: fchmod() of /var/log/journal failed: Operation not permitted container-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 permitted container-test-run-flakelet-relay> relay # [23413.870625] relay suid-sgid-wrappers-start[176]: chmod: changing permissions of '/run/wrappers/wrappers.wogDWL42B4/chsh': Operation not permitted container-test-run-flakelet-relay> agent # [23413.870996] agent systemd-tmpfiles[161]: fchmod() of /var/log/journal failed: Operation not permitted container-test-run-flakelet-relay> relay # [23413.870397] relay systemd-tmpfiles[169]: fchmod() of /var/log/journal/38663ab5ad3c4fc2bb41dcec3f73c24b failed: Operation not permitted container-test-run-flakelet-relay> agent # [23413.871313] agent systemd-tmpfiles[161]: fchmod() of /var/log/journal/8a35a01e4af443999b3fa8b64b468abd failed: Operation not permitted container-test-run-flakelet-relay> relay # [23413.870752] relay systemd-tmpfiles[169]: fchmod() of /run/log/journal failed: Operation not permitted container-test-run-flakelet-relay> agent # [23413.871647] agent systemd-tmpfiles[161]: fchmod() of /run/log/journal failed: Operation not permitted container-test-run-flakelet-relay> client # [23413.852106] client systemd[1]: Starting Create System Files and Directories... container-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 permitted container-test-run-flakelet-relay> client # [23413.871071] client systemd-tmpfiles[161]: fchmod() of /var/log/journal failed: Operation not permitted container-test-run-flakelet-relay> client # [23413.871383] client systemd-tmpfiles[161]: fchmod() of /var/log/journal/ca2f4864e74945a9b6da570cef0966cf failed: Operation not permitted container-test-run-flakelet-relay> client # [23413.871716] client systemd-tmpfiles[161]: fchmod() of /run/log/journal failed: Operation not permitted container-test-run-flakelet-relay> agent # [23413.874403] agent suid-sgid-wrappers-start[168]: chmod: changing permissions of '/run/wrappers/wrappers.pUZonPYA6T/chsh': Operation not permitted container-test-run-flakelet-relay> agent # [23413.876290] agent systemd[1]: suid-sgid-wrappers.service: Main process exited, code=exited, status=1/FAILURE container-test-run-flakelet-relay> agent # [23413.876380] agent systemd[1]: suid-sgid-wrappers.service: Failed with result 'exit-code'. container-test-run-flakelet-relay> agent # [23413.876585] agent systemd[1]: Failed to start Create SUID/SGID Wrappers. container-test-run-flakelet-relay> agent # [23413.877035] agent systemd[1]: Finished Create System Files and Directories. container-test-run-flakelet-relay> agent # [23413.892102] agent systemd[1]: Starting Rebuild Journal Catalog... container-test-run-flakelet-relay> agent # [23413.893046] agent systemd[1]: Starting Record System Boot/Shutdown in UTMP... container-test-run-flakelet-relay> agent # [23413.959495] agent systemd[1]: etc-machine\x2did.mount: Deactivated successfully. container-test-run-flakelet-relay> agent # [23413.960118] agent systemd[1]: Finished Save Transient machine-id to Disk. container-test-run-flakelet-relay> agent # [23413.960235] agent systemd[1]: Finished Rebuild Journal Catalog. container-test-run-flakelet-relay> agent # [23413.963978] agent systemd[1]: Starting Update is Completed... container-test-run-flakelet-relay> agent # [23413.964766] agent systemd[1]: Finished Record System Boot/Shutdown in UTMP. container-test-run-flakelet-relay> agent # [23413.973204] agent systemd[1]: Finished Update is Completed. container-test-run-flakelet-relay> agent # [23413.973333] agent systemd[1]: Reached target System Initialization. container-test-run-flakelet-relay> agent # [23413.973390] agent systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container container-test-run-flakelet-relay> agent # [23413.973411] agent systemd[1]: Started Daily Cleanup of Temporary Directories. container-test-run-flakelet-relay> agent # [23413.973424] agent systemd[1]: Reached target Timer Units. container-test-run-flakelet-relay> agent # [23413.973518] agent systemd[1]: Listening on D-Bus System Message Bus Socket. container-test-run-flakelet-relay> agent # [23413.973677] agent systemd[1]: Listening on Nix Daemon Socket. container-test-run-flakelet-relay> agent # [23413.973772] agent systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. container-test-run-flakelet-relay> agent # [23413.973783] agent systemd[1]: Reached target Socket Units. container-test-run-flakelet-relay> agent # [23413.973808] agent systemd[1]: Reached target Basic System. container-test-run-flakelet-relay> agent # [23413.975224] agent systemd[1]: Starting Re-link flakelet services at boot... container-test-run-flakelet-relay> agent # [23413.976153] agent systemd[1]: Starting Import lastlog data into lastlog2 database... container-test-run-flakelet-relay> agent # [23413.977314] agent systemd[1]: Starting Name Service Cache Daemon (nsncd)... container-test-run-flakelet-relay> agent # [23413.977999] agent systemd[1]: Starting resolvconf update... container-test-run-flakelet-relay> agent # [23413.987199] agent systemd[1]: Finished Re-link flakelet services at boot. container-test-run-flakelet-relay> agent # [23413.988372] agent systemd[1]: Starting Reconcile flakelet services with the host configuration... container-test-run-flakelet-relay> agent # [23413.993980] agent systemd[1]: Finished Import lastlog data into lastlog2 database. container-test-run-flakelet-relay> agent # [23413.999112] agent systemd[1]: Finished Reconcile flakelet services with the host configuration. container-test-run-flakelet-relay> agent # [23414.066465] agent systemd[1]: nscd.service: Deactivated successfully. container-test-run-flakelet-relay> relay # [23413.876485] relay systemd[1]: suid-sgid-wrappers.service: Main process exited, code=exited, status=1/FAILURE container-test-run-flakelet-relay> relay # [23413.876598] relay systemd[1]: suid-sgid-wrappers.service: Failed with result 'exit-code'. container-test-run-flakelet-relay> relay # [23413.876643] relay systemd[1]: Failed to start Create SUID/SGID Wrappers. container-test-run-flakelet-relay> relay # [23413.877140] relay systemd[1]: Finished Create System Files and Directories. container-test-run-flakelet-relay> relay # [23413.892249] relay systemd[1]: Starting Rebuild Journal Catalog... container-test-run-flakelet-relay> relay # [23413.892918] relay systemd[1]: Starting Record System Boot/Shutdown in UTMP... container-test-run-flakelet-relay> relay # [23413.955821] relay systemd[1]: etc-machine\x2did.mount: Deactivated successfully. container-test-run-flakelet-relay> relay # [23413.956617] relay systemd[1]: Finished Save Transient machine-id to Disk. container-test-run-flakelet-relay> relay # [23413.959565] relay systemd[1]: Finished Rebuild Journal Catalog. container-test-run-flakelet-relay> relay # [23413.959768] relay systemd[1]: Finished Record System Boot/Shutdown in UTMP. container-test-run-flakelet-relay> relay # [23413.961900] relay systemd[1]: Starting Update is Completed... container-test-run-flakelet-relay> relay # [23413.971716] relay systemd[1]: Finished Update is Completed. container-test-run-flakelet-relay> relay # [23413.971844] relay systemd[1]: Reached target System Initialization. container-test-run-flakelet-relay> relay # [23413.971923] relay systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container container-test-run-flakelet-relay> relay # [23413.971947] relay systemd[1]: Started Daily Cleanup of Temporary Directories. container-test-run-flakelet-relay> relay # [23413.971960] relay systemd[1]: Reached target Timer Units. container-test-run-flakelet-relay> relay # [23413.972077] relay systemd[1]: Listening on D-Bus System Message Bus Socket. container-test-run-flakelet-relay> relay # [23413.972274] relay systemd[1]: Listening on Nix Daemon Socket. container-test-run-flakelet-relay> relay # [23413.972387] relay systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. container-test-run-flakelet-relay> relay # [23413.972404] relay systemd[1]: Reached target Socket Units. container-test-run-flakelet-relay> relay # [23413.972437] relay systemd[1]: Reached target Basic System. container-test-run-flakelet-relay> relay # [23413.973642] relay systemd[1]: Started device-mock.service. container-test-run-flakelet-relay> relay # [23413.974663] relay systemd[1]: Starting Re-link flakelet services at boot... container-test-run-flakelet-relay> relay # [23413.975641] relay systemd[1]: Starting Import lastlog data into lastlog2 database... container-test-run-flakelet-relay> relay # [23413.976532] relay systemd[1]: Starting Name Service Cache Daemon (nsncd)... container-test-run-flakelet-relay> relay # [23413.977211] relay systemd[1]: Starting resolvconf update... container-test-run-flakelet-relay> relay # [23413.987176] relay systemd[1]: Finished Re-link flakelet services at boot. container-test-run-flakelet-relay> relay # [23413.988446] relay systemd[1]: Starting Reconcile flakelet services with the host configuration... container-test-run-flakelet-relay> relay # [23413.993200] relay systemd[1]: Finished Import lastlog data into lastlog2 database. container-test-run-flakelet-relay> relay # [23413.999876] relay systemd[1]: Finished Reconcile flakelet services with the host configuration. container-test-run-flakelet-relay> client # [23413.874191] client suid-sgid-wrappers-start[168]: chmod: changing permissions of '/run/wrappers/wrappers.XFxXZF9mni/chsh': Operation not permitted container-test-run-flakelet-relay> client # [23413.876949] client systemd[1]: suid-sgid-wrappers.service: Main process exited, code=exited, status=1/FAILURE container-test-run-flakelet-relay> client # [23413.876990] client systemd[1]: suid-sgid-wrappers.service: Failed with result 'exit-code'. container-test-run-flakelet-relay> client # [23413.877067] client systemd[1]: Failed to start Create SUID/SGID Wrappers. container-test-run-flakelet-relay> client # [23413.877733] client systemd[1]: Finished Create System Files and Directories. container-test-run-flakelet-relay> client # [23413.892442] client systemd[1]: Starting Rebuild Journal Catalog... container-test-run-flakelet-relay> client # [23413.893076] client systemd[1]: Starting Record System Boot/Shutdown in UTMP... container-test-run-flakelet-relay> client # [23413.955621] client systemd[1]: etc-machine\x2did.mount: Deactivated successfully. container-test-run-flakelet-relay> client # [23413.956546] client systemd[1]: Finished Save Transient machine-id to Disk. container-test-run-flakelet-relay> client # [23413.958422] client systemd[1]: Finished Rebuild Journal Catalog. container-test-run-flakelet-relay> client # [23413.960329] client systemd[1]: Starting Update is Completed... container-test-run-flakelet-relay> client # [23413.960491] client systemd[1]: Finished Record System Boot/Shutdown in UTMP. container-test-run-flakelet-relay> client # [23413.968545] client systemd[1]: Finished Update is Completed. container-test-run-flakelet-relay> client # [23413.968612] client systemd[1]: Reached target System Initialization. container-test-run-flakelet-relay> client # [23413.968670] client systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container container-test-run-flakelet-relay> client # [23413.968691] client systemd[1]: Started Daily Cleanup of Temporary Directories. container-test-run-flakelet-relay> client # [23413.968705] client systemd[1]: Reached target Timer Units. container-test-run-flakelet-relay> client # [23413.968798] client systemd[1]: Listening on D-Bus System Message Bus Socket. container-test-run-flakelet-relay> client # [23413.968962] client systemd[1]: Listening on Nix Daemon Socket. container-test-run-flakelet-relay> client # [23413.969059] client systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. container-test-run-flakelet-relay> client # [23413.969074] client systemd[1]: Reached target Socket Units. container-test-run-flakelet-relay> client # [23413.969102] client systemd[1]: Reached target Basic System. container-test-run-flakelet-relay> client # [23413.970141] client systemd[1]: Starting Import lastlog data into lastlog2 database... container-test-run-flakelet-relay> client # [23413.971034] client systemd[1]: Starting Name Service Cache Daemon (nsncd)... container-test-run-flakelet-relay> client # [23413.971801] client systemd[1]: Starting resolvconf update... container-test-run-flakelet-relay> client # [23413.988391] client systemd[1]: Finished Import lastlog data into lastlog2 database. container-test-run-flakelet-relay> client # [23414.042291] client systemd[1]: Finished resolvconf update. container-test-run-flakelet-relay> client # [23414.042421] client systemd[1]: Reached target Preparation for Network. container-test-run-flakelet-relay> client # [23414.043406] client systemd[1]: Starting Address configuration of eth1... container-test-run-flakelet-relay> client # [23414.044261] client systemd[1]: Starting Extra networking commands.... container-test-run-flakelet-relay> client # [23414.082837] client systemd[1]: nscd.service: Deactivated successfully. container-test-run-flakelet-relay> client # [23414.082929] client systemd[1]: Stopped Name Service Cache Daemon (nsncd). container-test-run-flakelet-relay> client # [23414.085845] client systemd[1]: Starting Name Service Cache Daemon (nsncd)... container-test-run-flakelet-relay> client # [23414.099494] client network-addresses-eth1-start[233]: adding address 192.168.1.2/24... done container-test-run-flakelet-relay> client # [23414.102779] client network-addresses-eth1-start[233]: adding address 2001:db8:1::2/64... done container-test-run-flakelet-relay> client # [23414.106743] client systemd[1]: Finished Address configuration of eth1. container-test-run-flakelet-relay> client # [23414.150361] client systemd[1]: Finished Extra networking commands.. container-test-run-flakelet-relay> client # [23414.150492] client systemd[1]: Reached target Network. container-test-run-flakelet-relay> client # [23414.190584] client systemd[1]: nscd.service: Deactivated successfully. container-test-run-flakelet-relay> client # [23414.190733] client systemd[1]: Stopped Name Service Cache Daemon (nsncd). container-test-run-flakelet-relay> client # [23414.193297] client systemd[1]: Starting Name Service Cache Daemon (nsncd)... container-test-run-flakelet-relay> agent # [23414.082842] agent systemd[1]: Stopped Name Service Cache Daemon (nsncd). container-test-run-flakelet-relay> agent # [23414.084531] agent systemd[1]: Finished resolvconf update. container-test-run-flakelet-relay> agent # [23414.085302] agent systemd[1]: Reached target Preparation for Network. container-test-run-flakelet-relay> agent # [23414.086315] agent systemd[1]: Starting Address configuration of eth1... container-test-run-flakelet-relay> agent # [23414.086923] agent systemd[1]: Starting Extra networking commands.... container-test-run-flakelet-relay> agent # [23414.087720] agent systemd[1]: Starting Name Service Cache Daemon (nsncd)... container-test-run-flakelet-relay> agent # [23414.106864] agent network-addresses-eth1-start[240]: adding address 192.168.1.1/24... done container-test-run-flakelet-relay> agent # [23414.109806] agent network-addresses-eth1-start[240]: adding address 2001:db8:1::1/64... done container-test-run-flakelet-relay> agent # [23414.114575] agent systemd[1]: Finished Address configuration of eth1. container-test-run-flakelet-relay> agent # [23414.184237] agent systemd[1]: Finished Extra networking commands.. container-test-run-flakelet-relay> agent # [23414.184469] agent systemd[1]: nscd.service: Deactivated successfully. container-test-run-flakelet-relay> agent # [23414.184647] agent systemd[1]: Stopped Name Service Cache Daemon (nsncd). container-test-run-flakelet-relay> agent # [23414.186898] agent systemd[1]: Reached target Network. container-test-run-flakelet-relay> agent # [23414.188021] agent systemd[1]: Starting Name Service Cache Daemon (nsncd)... container-test-run-flakelet-relay> agent # [23414.188802] agent systemd[1]: Starting Update flakelet service app... container-test-run-flakelet-relay> agent # [23414.189619] agent systemd[1]: Starting Update flakelet service flakelet-agent... container-test-run-flakelet-relay> agent # [23414.190419] agent systemd[1]: Starting Update flakelet service other... container-test-run-flakelet-relay> agent # [23414.200560] agent flakelet[308]: app: using prebuilt artifact /nix/store/hysydfr96ds875vr0jgw5rjxi4p39h2j-flakelet-app-v1 container-test-run-flakelet-relay> agent # [23414.200966] agent flakelet[309]: flakelet-agent: using prebuilt artifact /nix/store/5wdcda2phs8z3zc9gabz8187jkk7wyhk-flakelet-flakelet-agent container-test-run-flakelet-relay> agent # [23414.201281] agent flakelet[308]: app: activating generation 1 container-test-run-flakelet-relay> agent # [23414.201538] agent flakelet[309]: flakelet-agent: activating generation 1 container-test-run-flakelet-relay> agent # [23414.201776] agent flakelet[310]: other: using prebuilt artifact /nix/store/hmr990n0b4v6rpzrcpyyw738r9xi7z95-flakelet-other-v1 container-test-run-flakelet-relay> agent # [23414.202297] agent flakelet[310]: other: activating generation 1 container-test-run-flakelet-relay> agent # [23414.206229] agent systemd[1]: Reload requested from client PID 313 ('systemctl') (unit flakelet-flakelet-agent.service)... container-test-run-flakelet-relay> agent # [23414.206262] agent systemd[1]: Reloading... container-test-run-flakelet-relay> relay # [23414.082820] relay systemd[1]: nscd.service: Deactivated successfully. container-test-run-flakelet-relay> relay # [23414.082893] relay systemd[1]: Stopped Name Service Cache Daemon (nsncd). container-test-run-flakelet-relay> relay # [23414.084541] relay systemd[1]: Finished resolvconf update. container-test-run-flakelet-relay> relay # [23414.085371] relay systemd[1]: Reached target Preparation for Network. container-test-run-flakelet-relay> relay # [23414.086527] relay systemd[1]: Starting Address configuration of eth1... container-test-run-flakelet-relay> relay # [23414.087170] relay systemd[1]: Starting Extra networking commands.... container-test-run-flakelet-relay> relay # [23414.088029] relay systemd[1]: Starting Name Service Cache Daemon (nsncd)... container-test-run-flakelet-relay> relay # [23414.107310] relay network-addresses-eth1-start[249]: adding address 192.168.1.3/24... done container-test-run-flakelet-relay> relay # [23414.110507] relay network-addresses-eth1-start[249]: adding address 2001:db8:1::3/64... done container-test-run-flakelet-relay> relay # [23414.114605] relay systemd[1]: Finished Address configuration of eth1. container-test-run-flakelet-relay> relay # [23414.151017] relay systemd[1]: Finished Extra networking commands.. container-test-run-flakelet-relay> relay # [23414.151167] relay systemd[1]: Reached target Network. container-test-run-flakelet-relay> relay # [23414.152467] relay systemd[1]: Starting Dnsmasq Daemon... container-test-run-flakelet-relay> relay # [23414.153394] relay systemd[1]: Starting Nginx Web Server... container-test-run-flakelet-relay> relay # [23414.154197] relay systemd[1]: Starting Update flakelet service flakelet-relay... container-test-run-flakelet-relay> relay # [23414.192155] relay flakelet[311]: flakelet-relay: using prebuilt artifact /nix/store/zad6kx41hxqj8x6fsfsd6ydzw32z2fw7-flakelet-flakelet-relay container-test-run-flakelet-relay> relay # [23414.192851] relay flakelet[311]: flakelet-relay: activating generation 1 container-test-run-flakelet-relay> relay # [23414.196721] relay systemd[1]: Reload requested from client PID 316 ('systemctl') (unit flakelet-flakelet-relay.service)... container-test-run-flakelet-relay> relay # [23414.196762] relay systemd[1]: Reloading... container-test-run-flakelet-relay> client # [23414.523789] client systemd[1]: Started Name Service Cache Daemon (nsncd). container-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" container-test-run-flakelet-relay> client # [23414.523894] client systemd[1]: Reached target Host and Network Name Lookups. container-test-run-flakelet-relay> client # [23414.523995] client systemd[1]: Reached target User and Group Name Lookups. container-test-run-flakelet-relay> client # [23414.526470] client systemd[1]: Starting User Login Management... container-test-run-flakelet-relay> client # [23414.533991] client systemd[1]: Starting Permit User Sessions... container-test-run-flakelet-relay> client # [23414.565096] client systemd[1]: Finished Permit User Sessions. container-test-run-flakelet-relay> client # [23414.567247] client systemd[1]: Started Console Getty. container-test-run-flakelet-relay> client # [23414.567313] client systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0 container-test-run-flakelet-relay> client # [23414.567340] client systemd[1]: Reached target Login Prompts. container-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" container-test-run-flakelet-relay> relay # [23414.618295] relay systemd[1]: Reloading finished in 421 ms. container-test-run-flakelet-relay> relay # [23414.737327] relay systemd[1]: Started Name Service Cache Daemon (nsncd). container-test-run-flakelet-relay> relay # [23414.738248] relay systemd[1]: Reached target Host and Network Name Lookups. container-test-run-flakelet-relay> relay # [23414.738297] relay systemd[1]: Reached target User and Group Name Lookups. container-test-run-flakelet-relay> relay # [23414.739366] relay systemd[1]: Starting User Login Management... container-test-run-flakelet-relay> relay # [23414.740467] relay systemd[1]: Starting Permit User Sessions... container-test-run-flakelet-relay> relay # [23414.746856] relay systemd[1]: Reload requested from client PID 357 ('systemctl') (unit flakelet-flakelet-relay.service)... container-test-run-flakelet-relay> relay # [23414.746887] relay systemd[1]: Reloading... container-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" container-test-run-flakelet-relay> agent # [23414.610647] agent systemd[1]: Reloading finished in 404 ms. container-test-run-flakelet-relay> agent # [23414.739975] agent systemd[1]: Started Name Service Cache Daemon (nsncd). container-test-run-flakelet-relay> agent # [23414.740991] agent systemd[1]: Reload requested from client PID 312 ('systemctl') (unit flakelet-app.service)... container-test-run-flakelet-relay> agent # [23414.741042] agent systemd[1]: Reloading... container-test-run-flakelet-relay> relay # [23414.863921] relay dnsmasq-pre-start[376]: dnsmasq: syntax check OK. container-test-run-flakelet-relay> agent # [23415.121351] agent systemd[1]: Reloading finished in 379 ms. container-test-run-flakelet-relay> agent # [23415.241763] agent systemd[1]: Reload requested from client PID 314 ('systemctl') (unit flakelet-other.service)... container-test-run-flakelet-relay> agent # [23415.241817] agent systemd[1]: Reloading... container-test-run-flakelet-relay> relay # [23415.117915] relay systemd[1]: Reloading finished in 370 ms. container-test-run-flakelet-relay> relay # [23415.238966] relay systemd[1]: Finished Permit User Sessions. container-test-run-flakelet-relay> relay # [23415.269753] relay systemd[1]: Started Console Getty. container-test-run-flakelet-relay> relay # [23415.269794] relay systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0 container-test-run-flakelet-relay> relay # [23415.269809] relay systemd[1]: Reached target Login Prompts. container-test-run-flakelet-relay> relay # [23415.273172] relay systemd[1]: Starting flakelet-relay... container-test-run-flakelet-relay> agent # [23415.600897] agent systemd[1]: Reloading finished in 358 ms. container-test-run-flakelet-relay> agent # [23415.678471] agent systemd[1]: Reached target Host and Network Name Lookups. container-test-run-flakelet-relay> agent # [23415.678610] agent systemd[1]: Reached target User and Group Name Lookups. container-test-run-flakelet-relay> agent # [23415.680116] agent systemd[1]: Starting User Login Management... container-test-run-flakelet-relay> agent # [23415.681277] agent systemd[1]: Starting Permit User Sessions... container-test-run-flakelet-relay> agent # [23415.689037] agent systemd[1]: Reload requested from client PID 405 ('systemctl') (unit flakelet-flakelet-agent.service)... container-test-run-flakelet-relay> agent # [23415.689066] agent systemd[1]: Reloading... container-test-run-flakelet-relay> relay # [23415.717157] relay nginx-pre-start[402]: nginx: the configuration file /nix/store/01avjj26wcihp926xgasszcj5s0k7cbs-nginx.conf syntax is ok container-test-run-flakelet-relay> relay # [23415.717923] relay nginx-pre-start[402]: nginx: configuration file /nix/store/01avjj26wcihp926xgasszcj5s0k7cbs-nginx.conf test is successful container-test-run-flakelet-relay> relay # [23415.732881] relay systemd[1]: Started Nginx Web Server. container-test-run-flakelet-relay> relay # [23415.909848] relay systemd[1]: Starting D-Bus System Message Bus... container-test-run-flakelet-relay> client # [23415.874435] client systemd-logind[315]: New seat seat0. container-test-run-flakelet-relay> client # [23415.874880] client systemd[1]: Started User Login Management. container-test-run-flakelet-relay> client # [23415.877091] client systemd[1]: Starting D-Bus System Message Bus... container-test-run-flakelet-relay> client # [23415.878561] client systemd[1]: Starting linger-users.service... container-test-run-flakelet-relay> client # [23415.912240] client systemd[1]: linger-users.service: Deactivated successfully. container-test-run-flakelet-relay> client # [23415.912573] client systemd[1]: Finished linger-users.service. container-test-run-flakelet-relay> client # [23415.912914] client systemd[1]: Reached target Multi-User System. container-test-run-flakelet-relay> agent # [23416.085670] agent systemd[1]: Reloading finished in 396 ms. container-test-run-flakelet-relay> agent # [23416.171738] agent systemd[1]: Finished Permit User Sessions. container-test-run-flakelet-relay> agent # [23416.177931] agent systemd[1]: Reload requested from client PID 408 ('systemctl') (unit flakelet-app.service)... container-test-run-flakelet-relay> agent # [23416.177970] agent systemd[1]: Reloading... container-test-run-flakelet-relay> relay # [23416.133096] relay systemd-logind[355]: New seat seat0. container-test-run-flakelet-relay> relay # [23416.133477] relay systemd[1]: Started User Login Management. container-test-run-flakelet-relay> relay # [23416.135731] relay systemd[1]: Starting linger-users.service... container-test-run-flakelet-relay> relay # [23416.182250] relay systemd[1]: linger-users.service: Deactivated successfully. container-test-run-flakelet-relay> relay # [23416.182549] relay systemd[1]: Finished linger-users.service. container-test-run-flakelet-relay> client # [23416.408487] client dbus-broker-launch[325]: Looking up NSS user entry for 'systemd-timesync'... container-test-run-flakelet-relay> client # [23416.410175] client dbus-broker-launch[325]: NSS returned no entry for 'systemd-timesync' container-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" container-test-run-flakelet-relay> client # [23416.410967] client systemd[1]: Started D-Bus System Message Bus. container-test-run-flakelet-relay> client # [23416.411503] client systemd[1]: Startup finished in 4.053s. container-test-run-flakelet-relay> client # [23416.416732] client dbus-broker-launch[325]: Ready container-test-run-flakelet-relay> agent # [23416.522628] agent systemd[1]: Reloading finished in 344 ms. container-test-run-flakelet-relay> agent # [23416.601847] agent systemd[1]: Reload requested from client PID 443 ('systemctl') (unit flakelet-other.service)... container-test-run-flakelet-relay> agent # [23416.601887] agent systemd[1]: Reloading... container-test-run-flakelet-relay> relay # [23416.446788] relay dbus-broker-launch[408]: Looking up NSS user entry for 'systemd-timesync'... container-test-run-flakelet-relay> relay # [23416.447591] relay dbus-broker-launch[408]: NSS returned no entry for 'systemd-timesync' container-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" container-test-run-flakelet-relay> relay # [23416.448308] relay systemd[1]: Started D-Bus System Message Bus. container-test-run-flakelet-relay> relay # [23416.454317] relay dbus-broker-launch[408]: Ready container-test-run-flakelet-relay> relay # [23416.457794] relay systemd[1]: Started Dnsmasq Daemon. container-test-run-flakelet-relay> relay # [23416.460126] relay dnsmasq[394]: started, version 2.93 cachesize 150 container-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 dumpfile container-test-run-flakelet-relay> relay # [23416.460158] relay dnsmasq[394]: DBus support enabled: connected to system bus container-test-run-flakelet-relay> relay # [23416.460174] relay dnsmasq[394]: no upstream servers configured - please set them from DBus container-test-run-flakelet-relay> relay # [23416.460292] relay dnsmasq[394]: read /etc/hosts - 9 names container-test-run-flakelet-relay> agent # [23416.938207] agent systemd[1]: Reloading finished in 335 ms. container-test-run-flakelet-relay> agent # [23417.007549] agent systemd[1]: Started Console Getty. container-test-run-flakelet-relay> agent # [23417.008653] agent systemd[1]: Starting flakelet-agent... container-test-run-flakelet-relay> agent # [23417.008667] agent systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0 container-test-run-flakelet-relay> agent # [23417.008736] agent systemd[1]: Reached target Login Prompts. container-test-run-flakelet-relay> agent # [23417.012092] agent systemd[1]: Starting app v1... container-test-run-flakelet-relay> agent # [23417.013292] agent systemd[1]: Starting other v1... container-test-run-flakelet-relay> agent # [23417.050942] agent systemd[1]: Started other v1. container-test-run-flakelet-relay> agent # [23417.051166] agent systemd[1]: Started app v1. container-test-run-flakelet-relay> agent # [23417.057660] agent flakelet[310]: other: updated to generation 1 container-test-run-flakelet-relay> agent # [23417.058218] agent flakelet[308]: app: updated to generation 1 container-test-run-flakelet-relay> agent # [23417.059730] agent systemd[1]: Finished Update flakelet service app. container-test-run-flakelet-relay> agent # [23417.060052] agent systemd[1]: Finished Update flakelet service other. container-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"} container-test-run-flakelet-relay> agent # [23417.087223] agent systemd-logind[406]: New seat seat0. container-test-run-flakelet-relay> agent # [23417.088069] agent systemd[1]: Starting D-Bus System Message Bus... container-test-run-flakelet-relay> agent # [23417.088173] agent systemd[1]: Started User Login Management. container-test-run-flakelet-relay> agent # [23417.089200] agent systemd[1]: Starting linger-users.service... container-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"} container-test-run-flakelet-relay> agent # [23417.095532] agent systemd[1]: Started flakelet-agent. container-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"} container-test-run-flakelet-relay> agent # [23417.101521] agent flakelet[309]: flakelet-agent: updated to generation 1 container-test-run-flakelet-relay> agent # [23417.119247] agent systemd[1]: Finished Update flakelet service flakelet-agent. container-test-run-flakelet-relay> agent # [23417.120649] agent systemd[1]: Reached target flakelet managed services. container-test-run-flakelet-relay> agent # [23417.128685] agent systemd[1]: linger-users.service: Deactivated successfully. container-test-run-flakelet-relay> agent # [23417.128857] agent systemd[1]: Finished linger-users.service. container-test-run-flakelet-relay> agent # [23417.130230] agent systemd[1]: Reached target Multi-User System. container-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"} container-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"} container-test-run-flakelet-relay> relay # [23417.272656] relay systemd[1]: Started flakelet-relay. container-test-run-flakelet-relay> relay # [23417.279567] relay systemd[1]: Starting health for flakelet-relay... container-test-run-flakelet-relay> agent # [23417.522491] agent dbus-broker-launch[899]: Looking up NSS user entry for 'systemd-timesync'... container-test-run-flakelet-relay> agent # [23417.523838] agent dbus-broker-launch[899]: NSS returned no entry for 'systemd-timesync' container-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" container-test-run-flakelet-relay> agent # [23417.524350] agent systemd[1]: Started D-Bus System Message Bus. container-test-run-flakelet-relay> agent # [23417.524663] agent systemd[1]: Startup finished in 5.164s. container-test-run-flakelet-relay> agent # [23417.532838] agent dbus-broker-launch[899]: Ready container-test-run-flakelet-relay> relay # [23417.540212] relay systemd[1]: flakelet-relay-health.service: Deactivated successfully. container-test-run-flakelet-relay> relay # [23417.552149] relay systemd[1]: Finished health for flakelet-relay. container-test-run-flakelet-relay> relay # [23417.557913] relay flakelet[311]: flakelet-relay: updated to generation 1 container-test-run-flakelet-relay> relay # [23417.559606] relay systemd[1]: Finished Update flakelet service flakelet-relay. container-test-run-flakelet-relay> relay # [23417.559845] relay systemd[1]: Reached target flakelet managed services. container-test-run-flakelet-relay> relay # [23417.559926] relay systemd[1]: Reached target Multi-User System. container-test-run-flakelet-relay> relay # [23417.560059] relay systemd[1]: Startup finished in 5.200s. container-test-run-flakelet-relay> relay: (finished: waiting for unit flakelet-relay.service, in 6.16 seconds) container-test-run-flakelet-relay> relay: waiting for unit nginx.service container-test-run-flakelet-relay> relay: (finished: waiting for unit nginx.service, in 0.01 seconds) container-test-run-flakelet-relay> agent: waiting for unit flakelet.target container-test-run-flakelet-relay> agent: (finished: waiting for unit flakelet.target, in 0.02 seconds) container-test-run-flakelet-relay> agent: waiting for unit flakelet-agent.service container-test-run-flakelet-relay> agent: (finished: waiting for unit flakelet-agent.service, in 0.02 seconds) container-test-run-flakelet-relay> client: waiting for unit multi-user.target container-test-run-flakelet-relay> client: (finished: waiting for unit multi-user.target, in 0.02 seconds) container-test-run-flakelet-relay> relay: waiting for success: curl -sf http://127.0.0.1:7400/metrics | grep -q 'agent_up{host="agent"} 1' container-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"} container-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"} container-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) container-test-run-flakelet-relay> agent: must succeed: systemctl is-active app.service other.service container-test-run-flakelet-relay> agent: (finished: must succeed: systemctl is-active app.service other.service, in 0.01 seconds) container-test-run-flakelet-relay> subtest: deploy with client certificate, relay found via SRV container-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.json container-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) container-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>&1 container-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"} container-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"} container-test-run-flakelet-relay> agent # [23420.232277] agent systemd[1]: Starting flakelet update app (via relay)... container-test-run-flakelet-relay> agent # [23420.259979] agent systemd[1]: Started flakelet update app (via relay). container-test-run-flakelet-relay> agent # [23420.264603] agent flakelet[937]: app: using prebuilt artifact /nix/store/6ili1vxjlag9d7dy0bxrbxk6bpi5qh9p-flakelet-app-v2 container-test-run-flakelet-relay> agent # [23420.265298] agent flakelet[937]: app: activating generation 2 container-test-run-flakelet-relay> agent # [23420.282592] agent systemd[1]: Stopping app v1... container-test-run-flakelet-relay> agent # [23420.284264] agent systemd[1]: app.service: Deactivated successfully. container-test-run-flakelet-relay> agent # [23420.284523] agent systemd[1]: Stopped app v1. container-test-run-flakelet-relay> agent # [23420.301507] agent systemd[1]: Reload requested from client PID 944 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [23420.301574] agent systemd[1]: Reloading... container-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"} container-test-run-flakelet-relay> agent # [23420.640375] agent systemd[1]: Reloading finished in 338 ms. container-test-run-flakelet-relay> agent # [23420.705338] agent systemd[1]: Reload requested from client PID 974 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [23420.705375] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [23421.031445] agent systemd[1]: Reloading finished in 325 ms. container-test-run-flakelet-relay> agent # [23421.092760] agent systemd[1]: Reload requested from client PID 1003 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [23421.092796] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [23421.417901] agent systemd[1]: Reloading finished in 324 ms. container-test-run-flakelet-relay> agent # [23421.493202] agent systemd[1]: Reload requested from client PID 1037 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [23421.493233] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [23421.818915] agent systemd[1]: Reloading finished in 325 ms. container-test-run-flakelet-relay> agent # [23421.881267] agent systemd[1]: Starting app v2... container-test-run-flakelet-relay> agent # [23421.909080] agent systemd[1]: Started app v2. container-test-run-flakelet-relay> agent # [23421.914151] agent flakelet[937]: app: updated to generation 2 container-test-run-flakelet-relay> agent # [23421.916168] agent systemd[1]: flakelet-relay-job-app.service: Deactivated successfully. container-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) container-test-run-flakelet-relay> agent: must succeed: systemctl show app.service -p Description | grep -q 'app v2' container-test-run-flakelet-relay> agent: (finished: must succeed: systemctl show app.service -p Description | grep -q 'app v2', in 0.02 seconds) container-test-run-flakelet-relay> agent: must succeed: journalctl -u flakelet-relay-job-app.service | grep -q 'generation 2' container-test-run-flakelet-relay> agent: (finished: must succeed: journalctl -u flakelet-relay-job-app.service | grep -q 'generation 2', in 0.01 seconds) container-test-run-flakelet-relay> agent: must succeed: flakelet status --json app | jq -c '.[0].changed.by' container-test-run-flakelet-relay> agent: (finished: must succeed: flakelet status --json app | jq -c '.[0].changed.by', in 0.03 seconds) container-test-run-flakelet-relay> (finished: subtest: deploy with client certificate, relay found via SRV, in 2.94 seconds) container-test-run-flakelet-relay> subtest: deploy with OIDC token, sub principal container-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.json container-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) container-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) container-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) container-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>&1 container-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"} container-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"} container-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"} container-test-run-flakelet-relay> agent # [23423.224019] agent systemd[1]: Starting flakelet update app (via relay)... container-test-run-flakelet-relay> agent # [23423.254061] agent systemd[1]: Started flakelet update app (via relay). container-test-run-flakelet-relay> agent # [23423.259126] agent flakelet[1119]: app: using prebuilt artifact /nix/store/hysydfr96ds875vr0jgw5rjxi4p39h2j-flakelet-app-v1 container-test-run-flakelet-relay> agent # [23423.259762] agent flakelet[1119]: app: activating generation 3 container-test-run-flakelet-relay> agent # [23423.273226] agent systemd[1]: Stopping app v2... container-test-run-flakelet-relay> agent # [23423.273708] agent systemd[1]: app.service: Deactivated successfully. container-test-run-flakelet-relay> agent # [23423.273969] agent systemd[1]: Stopped app v2. container-test-run-flakelet-relay> agent # [23423.290315] agent systemd[1]: Reload requested from client PID 1126 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [23423.290383] agent systemd[1]: Reloading... container-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"} container-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"} container-test-run-flakelet-relay> agent # [23423.634748] agent systemd[1]: Reloading finished in 343 ms. container-test-run-flakelet-relay> agent # [23423.700933] agent systemd[1]: Reload requested from client PID 1156 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [23423.700970] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [23424.027625] agent systemd[1]: Reloading finished in 326 ms. container-test-run-flakelet-relay> agent # [23424.094725] agent systemd[1]: Reload requested from client PID 1185 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [23424.094765] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [23424.421127] agent systemd[1]: Reloading finished in 325 ms. container-test-run-flakelet-relay> agent # [23424.485178] agent systemd[1]: Reload requested from client PID 1216 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [23424.485212] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [23424.820371] agent systemd[1]: Reloading finished in 334 ms. container-test-run-flakelet-relay> agent # [23424.882192] agent systemd[1]: Starting app v1... container-test-run-flakelet-relay> agent # [23424.910923] agent systemd[1]: Started app v1. container-test-run-flakelet-relay> agent # [23424.916752] agent flakelet[1119]: app: updated to generation 3 container-test-run-flakelet-relay> agent # [23424.918884] agent systemd[1]: flakelet-relay-job-app.service: Deactivated successfully. container-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) container-test-run-flakelet-relay> (finished: subtest: deploy with OIDC token, sub principal, in 2.93 seconds) container-test-run-flakelet-relay> subtest: policy denies uncovered target container-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>&1 container-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) container-test-run-flakelet-relay> (finished: subtest: policy denies uncovered target, in 0.02 seconds) container-test-run-flakelet-relay> subtest: claim principal from groups, unchanged counts as success container-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) container-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) container-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>&1 container-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"} container-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"} container-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"} container-test-run-flakelet-relay> agent # [23426.162942] agent systemd[1]: Starting flakelet update other (via relay)... container-test-run-flakelet-relay> agent # [23426.197972] agent systemd[1]: Started flakelet update other (via relay). container-test-run-flakelet-relay> agent # [23426.202541] agent flakelet[1278]: other: using prebuilt artifact /nix/store/hmr990n0b4v6rpzrcpyyw738r9xi7z95-flakelet-other-v1 container-test-run-flakelet-relay> agent # [23426.203088] agent flakelet[1278]: other: up to date container-test-run-flakelet-relay> agent # [23426.206021] agent systemd[1]: flakelet-relay-job-other.service: Deactivated successfully. container-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"} container-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"} container-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) container-test-run-flakelet-relay> (finished: subtest: claim principal from groups, unchanged counts as success, in 0.70 seconds) container-test-run-flakelet-relay> subtest: push login via device flow, cached token used afterwards container-test-run-flakelet-relay> client: must succeed: push login --issuer https://relay:8443 --client-id flakelet-relay 2>&1 container-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"} container-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"} container-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 - container-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 - container-test-run-flakelet-relay> client: (finished: must succeed: push login --issuer https://relay:8443 --client-id flakelet-relay 2>&1, in 2.08 seconds) container-test-run-flakelet-relay> client: must succeed: test "$(stat -c %a /root/.local/state/flakelet-push/token.json)" = 600 container-test-run-flakelet-relay> client: (finished: must succeed: test "$(stat -c %a /root/.local/state/flakelet-push/token.json)" = 600, in 0.01 seconds) container-test-run-flakelet-relay> client: must succeed: push --relay https://relay:7443 deploy agent/other 2>&1 container-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"} container-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"} container-test-run-flakelet-relay> agent # [23428.896398] agent systemd[1]: Starting flakelet update other (via relay)... container-test-run-flakelet-relay> agent # [23428.930052] agent systemd[1]: Started flakelet update other (via relay). container-test-run-flakelet-relay> agent # [23428.933896] agent flakelet[1310]: other: using prebuilt artifact /nix/store/hmr990n0b4v6rpzrcpyyw738r9xi7z95-flakelet-other-v1 container-test-run-flakelet-relay> agent # [23428.934455] agent flakelet[1310]: other: up to date container-test-run-flakelet-relay> agent # [23428.937382] agent systemd[1]: flakelet-relay-job-other.service: Deactivated successfully. container-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 - container-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"} container-test-run-flakelet-relay> client: (finished: must succeed: push --relay https://relay:7443 deploy agent/other 2>&1, in 0.64 seconds) container-test-run-flakelet-relay> client: must succeed: push --relay https://relay:7443 deploy agent/other 2>&1 container-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"} container-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"} container-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"} container-test-run-flakelet-relay> agent # [23429.542807] agent systemd[1]: Starting flakelet update other (via relay)... container-test-run-flakelet-relay> agent # [23429.570986] agent systemd[1]: Started flakelet update other (via relay). container-test-run-flakelet-relay> agent # [23429.575423] agent flakelet[1342]: other: using prebuilt artifact /nix/store/hmr990n0b4v6rpzrcpyyw738r9xi7z95-flakelet-other-v1 container-test-run-flakelet-relay> agent # [23429.576051] agent flakelet[1342]: other: up to date container-test-run-flakelet-relay> agent # [23429.578911] agent systemd[1]: flakelet-relay-job-other.service: Deactivated successfully. container-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"} container-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"} container-test-run-flakelet-relay> client: (finished: must succeed: push --relay https://relay:7443 deploy agent/other 2>&1, in 0.64 seconds) container-test-run-flakelet-relay> (finished: subtest: push login via device flow, cached token used afterwards, in 3.37 seconds) container-test-run-flakelet-relay> subtest: waves: failing first wave skips the second container-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.json container-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) container-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>&1 container-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"} container-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"} container-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"} container-test-run-flakelet-relay> agent # [23430.197069] agent systemd[1]: Starting flakelet update app (via relay)... container-test-run-flakelet-relay> agent # [23430.225963] agent systemd[1]: Started flakelet update app (via relay). container-test-run-flakelet-relay> agent # [23430.230337] agent flakelet[1369]: app: using prebuilt artifact /nix/store/8282rnbvyk847q4l4zby3zwcq3jpzrvw-flakelet-app-broken container-test-run-flakelet-relay> agent # [23430.230978] agent flakelet[1369]: app: activating generation 4 container-test-run-flakelet-relay> agent # [23430.248670] agent systemd[1]: Stopping app v1... container-test-run-flakelet-relay> agent # [23430.249167] agent systemd[1]: app.service: Deactivated successfully. container-test-run-flakelet-relay> agent # [23430.249429] agent systemd[1]: Stopped app v1. container-test-run-flakelet-relay> agent # [23430.266206] agent systemd[1]: Reload requested from client PID 1376 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [23430.266276] agent systemd[1]: Reloading... container-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"} container-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"} container-test-run-flakelet-relay> agent # [23430.606602] agent systemd[1]: Reloading finished in 339 ms. container-test-run-flakelet-relay> agent # [23430.675341] agent systemd[1]: Reload requested from client PID 1406 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [23430.675378] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [23431.004281] agent systemd[1]: Reloading finished in 328 ms. container-test-run-flakelet-relay> agent # [23431.067788] agent systemd[1]: Reload requested from client PID 1435 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [23431.067825] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [23431.396411] agent systemd[1]: Reloading finished in 328 ms. container-test-run-flakelet-relay> agent # [23431.462272] agent systemd[1]: Reload requested from client PID 1469 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [23431.462306] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [23431.789805] agent systemd[1]: Reloading finished in 327 ms. container-test-run-flakelet-relay> agent # [23431.859666] agent systemd[1]: Starting app broken... container-test-run-flakelet-relay> agent # [23431.878265] agent (nonexistent)[1502]: app.service: Unable to locate executable '/nonexistent': No such file or directory container-test-run-flakelet-relay> agent # [23431.878287] agent (nonexistent)[1502]: app.service: Failed at step EXEC spawning /nonexistent: No such file or directory container-test-run-flakelet-relay> agent # [23431.880433] agent systemd[1]: app.service: Main process exited, code=exited, status=203/EXEC container-test-run-flakelet-relay> agent # [23431.880540] agent systemd[1]: app.service: Failed with result 'exit-code'. container-test-run-flakelet-relay> agent # [23431.880773] agent systemd[1]: Failed to start app broken. container-test-run-flakelet-relay> agent # [23431.909762] agent systemd[1]: Reload requested from client PID 1510 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [23431.909802] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [23432.242605] agent systemd[1]: Reloading finished in 332 ms. container-test-run-flakelet-relay> agent # [23432.308300] agent systemd[1]: Reload requested from client PID 1540 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [23432.308338] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [23432.637867] agent systemd[1]: Reloading finished in 329 ms. container-test-run-flakelet-relay> agent # [23432.700974] agent systemd[1]: Reload requested from client PID 1570 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [23432.701021] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [23433.030217] agent systemd[1]: Reloading finished in 328 ms. container-test-run-flakelet-relay> agent # [23433.103328] agent systemd[1]: Reload requested from client PID 1601 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [23433.103369] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [23433.430839] agent systemd[1]: Reloading finished in 327 ms. container-test-run-flakelet-relay> agent # [23433.495189] agent systemd[1]: Starting app v1... container-test-run-flakelet-relay> agent # [23433.520975] agent systemd[1]: Started app v1. container-test-run-flakelet-relay> agent # [23433.522085] agent flakelet[1369]: app: deploy failed, rolled back: systemctl start app.service failed: container-test-run-flakelet-relay> agent # [23433.522085] agent flakelet[1369]: failed units: app.service. See journalctl -u app.service container-test-run-flakelet-relay> agent # [23433.523925] agent systemd[1]: flakelet-relay-job-app.service: Main process exited, code=exited, status=1/FAILURE container-test-run-flakelet-relay> agent # [23433.524031] agent systemd[1]: flakelet-relay-job-app.service: Failed with result 'exit-code'. container-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) container-test-run-flakelet-relay> agent: must succeed: systemctl show app.service -p Description | grep -q 'app v1' container-test-run-flakelet-relay> agent: (finished: must succeed: systemctl show app.service -p Description | grep -q 'app v1', in 0.02 seconds) container-test-run-flakelet-relay> (finished: subtest: waves: failing first wave skips the second, in 4.13 seconds) container-test-run-flakelet-relay> subtest: host patterns expand on the relay container-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.json container-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) container-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>&1 container-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"} container-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"} container-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"} container-test-run-flakelet-relay> agent # [23434.323260] agent systemd[1]: Starting flakelet update app (via relay)... container-test-run-flakelet-relay> agent # [23434.355084] agent systemd[1]: Started flakelet update app (via relay). container-test-run-flakelet-relay> agent # [23434.359556] agent flakelet[1668]: app: using prebuilt artifact /nix/store/hysydfr96ds875vr0jgw5rjxi4p39h2j-flakelet-app-v1 container-test-run-flakelet-relay> agent # [23434.360132] agent flakelet[1668]: app: up to date container-test-run-flakelet-relay> agent # [23434.360443] agent systemd[1]: flakelet-relay-job-app.service: Deactivated successfully. container-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"} container-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"} container-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) container-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>&1 container-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"} container-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"} container-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"} container-test-run-flakelet-relay> agent # [23434.960821] agent systemd[1]: Starting flakelet update app (via relay)... container-test-run-flakelet-relay> agent # [23434.999151] agent systemd[1]: Started flakelet update app (via relay). container-test-run-flakelet-relay> agent # [23435.004785] agent flakelet[1692]: app: using prebuilt artifact /nix/store/hysydfr96ds875vr0jgw5rjxi4p39h2j-flakelet-app-v1 container-test-run-flakelet-relay> agent # [23435.005392] agent flakelet[1692]: app: up to date container-test-run-flakelet-relay> agent # [23435.007018] agent systemd[1]: flakelet-relay-job-app.service: Deactivated successfully. container-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"} container-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"} container-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) container-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>&1 container-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"} container-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"} container-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"} container-test-run-flakelet-relay> agent # [23435.610059] agent systemd[1]: Starting flakelet update app (via relay)... container-test-run-flakelet-relay> agent # [23435.643144] agent systemd[1]: Started flakelet update app (via relay). container-test-run-flakelet-relay> agent # [23435.647793] agent flakelet[1724]: app: using prebuilt artifact /nix/store/hysydfr96ds875vr0jgw5rjxi4p39h2j-flakelet-app-v1 container-test-run-flakelet-relay> agent # [23435.648340] agent flakelet[1724]: app: up to date container-test-run-flakelet-relay> agent # [23435.651482] agent systemd[1]: flakelet-relay-job-app.service: Deactivated successfully. container-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"} container-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"} container-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) container-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>&1 container-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) container-test-run-flakelet-relay> (finished: subtest: host patterns expand on the relay, in 1.96 seconds) container-test-run-flakelet-relay> subtest: detach returns once the job is accepted container-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.json container-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) container-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>&1 container-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) container-test-run-flakelet-relay> agent: waiting for success: systemctl is-active flakelet-relay-job-app.service container-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"} container-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"} container-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"} container-test-run-flakelet-relay> agent # [23436.287648] agent systemd[1]: Starting flakelet update app (via relay)... container-test-run-flakelet-relay> agent # [23436.322202] agent systemd[1]: Started flakelet update app (via relay). container-test-run-flakelet-relay> agent # [23436.327018] agent flakelet[1752]: app: using prebuilt artifact /nix/store/ln47rsd5km8a04hd5zlkz4q1v352zqh3-flakelet-app-slow2 container-test-run-flakelet-relay> agent # [23436.327673] agent flakelet[1752]: app: activating generation 4 container-test-run-flakelet-relay> agent # [23436.340553] agent systemd[1]: Stopping app v1... container-test-run-flakelet-relay> agent # [23436.341095] agent systemd[1]: app.service: Deactivated successfully. container-test-run-flakelet-relay> agent # [23436.341358] agent systemd[1]: Stopped app v1. container-test-run-flakelet-relay> agent # [23436.359392] agent systemd[1]: Reload requested from client PID 1759 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [23436.359461] agent systemd[1]: Reloading... container-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"} container-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"} container-test-run-flakelet-relay> agent # [23436.702713] agent systemd[1]: Reloading finished in 342 ms. container-test-run-flakelet-relay> agent # [23436.772108] agent systemd[1]: Reload requested from client PID 1789 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [23436.772148] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [23437.098517] agent systemd[1]: Reloading finished in 325 ms. container-test-run-flakelet-relay> agent # [23437.169472] agent systemd[1]: Reload requested from client PID 1821 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [23437.169510] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent: (finished: waiting for success: systemctl is-active flakelet-relay-job-app.service, in 1.30 seconds) container-test-run-flakelet-relay> ??? Warning (UserWarning): wait_until_fails(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead. container-test-run-flakelet-relay> File "/nix/store/fv4yr9c0f9kyrfc00100i4rlshiiidbd-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39 container-test-run-flakelet-relay> agent: waiting for failure: systemctl is-active flakelet-relay-job-app.service container-test-run-flakelet-relay> ??? Warning (UserWarning): execute(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead. container-test-run-flakelet-relay> File "/nix/store/fv4yr9c0f9kyrfc00100i4rlshiiidbd-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39 container-test-run-flakelet-relay> agent # [23437.497607] agent systemd[1]: Reloading finished in 327 ms. container-test-run-flakelet-relay> agent # [23437.563152] agent systemd[1]: Reload requested from client PID 1856 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [23437.563188] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [23437.889410] agent systemd[1]: Reloading finished in 325 ms. container-test-run-flakelet-relay> agent # [23437.952032] agent systemd[1]: Starting app slow2... container-test-run-flakelet-relay> agent # [23444.022055] agent systemd[1]: Started app slow2. container-test-run-flakelet-relay> agent # [23444.031838] agent flakelet[1752]: app: updated to generation 4 container-test-run-flakelet-relay> agent # [23444.035239] agent systemd[1]: flakelet-relay-job-app.service: Deactivated successfully. container-test-run-flakelet-relay> agent: (finished: waiting for failure: systemctl is-active flakelet-relay-job-app.service, in 7.49 seconds) container-test-run-flakelet-relay> (finished: subtest: detach returns once the job is accepted, in 8.83 seconds) container-test-run-flakelet-relay> subtest: unknown host is final, missing agent is retried, agents listing container-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>&1 container-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) container-test-run-flakelet-relay> agent: must succeed: systemctl stop flakelet-agent container-test-run-flakelet-relay> agent: (finished: must succeed: systemctl stop flakelet-agent, in 0.08 seconds) container-test-run-flakelet-relay> relay: waiting for success: curl -sf http://127.0.0.1:7400/metrics | grep -q 'agent_up{host="agent"} 0' container-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) container-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 & container-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) container-test-run-flakelet-relay> client: waiting for success: grep -q 'agent_unavailable.*retrying' /tmp/retry.out container-test-run-flakelet-relay> agent # [23445.083773] agent systemd[1]: Stopping flakelet-agent... container-test-run-flakelet-relay> agent # [23445.116286] agent systemd[1]: flakelet-agent.service: Deactivated successfully. container-test-run-flakelet-relay> agent # [23445.116677] agent systemd[1]: Stopped flakelet-agent. container-test-run-flakelet-relay> agent # [23445.117135] agent systemd[1]: flakelet-agent.service: Consumed 2.629s CPU time over 28.107s wall clock time. container-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"} container-test-run-flakelet-relay> client: (finished: waiting for success: grep -q 'agent_unavailable.*retrying' /tmp/retry.out, in 1.02 seconds) container-test-run-flakelet-relay> agent: must succeed: systemctl start flakelet-agent container-test-run-flakelet-relay> agent: (finished: must succeed: systemctl start flakelet-agent, in 0.10 seconds) container-test-run-flakelet-relay> ??? Warning (UserWarning): wait_until_succeeds(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead. container-test-run-flakelet-relay> File "/nix/store/fv4yr9c0f9kyrfc00100i4rlshiiidbd-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39 container-test-run-flakelet-relay> client: waiting for success: grep -q 'agent/other: unchanged' /tmp/retry.out container-test-run-flakelet-relay> agent # [23446.207351] agent systemd[1]: Starting flakelet-agent... container-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"} container-test-run-flakelet-relay> agent # [23446.296670] agent systemd[1]: Started flakelet-agent. container-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"} container-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"} container-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"} container-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"} container-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"} container-test-run-flakelet-relay> agent # [23448.238198] agent systemd[1]: Starting flakelet update other (via relay)... container-test-run-flakelet-relay> agent # [23448.276107] agent systemd[1]: Started flakelet update other (via relay). container-test-run-flakelet-relay> agent # [23448.280709] agent flakelet[2361]: other: using prebuilt artifact /nix/store/hmr990n0b4v6rpzrcpyyw738r9xi7z95-flakelet-other-v1 container-test-run-flakelet-relay> agent # [23448.281263] agent flakelet[2361]: other: up to date container-test-run-flakelet-relay> agent # [23448.283047] agent systemd[1]: flakelet-relay-job-other.service: Deactivated successfully. container-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"} container-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"} container-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"} container-test-run-flakelet-relay> client: (finished: waiting for success: grep -q 'agent/other: unchanged' /tmp/retry.out, in 3.04 seconds) container-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 agents container-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) container-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) container-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) container-test-run-flakelet-relay> client: must succeed: FLAKELET_RELAY_TOKEN_COMMAND='echo eyJhbGciOiJFZERTQSIsImtpZCI6ImsxIiwidHlwIjoiSldUIn0.eyJhdWQiOiJmbGFrZWxldC1yZWxheSIsImV4cCI6MTc4ODcwMjAyNywiZ3JvdXBzIjpbXSwiaWF0IjoxNzg4NzAxNzI3LCJpc3MiOiJodHRwczovL3JlbGF5Ojg0NDMiLCJqdGkiOiI0NUJPQ1czRDRPVERDPT09IiwibmJmIjoxNzg4NzAxNzI3LCJzdWIiOiJyZXBvOmdpdGh1YjpleGFtcGxlL2FwcDpyZWY6cmVmcy9oZWFkcy9tYWluIn0.vpQVUTc5R4-hauc1Kk3znfgNlDRuazwNGyjuUYnzICOZedPIKB6gmUrOlmrIUVJ87s4tzqjoZzHqhFONoMs7Cg' push --relay https://relay:7443 agents container-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) container-test-run-flakelet-relay> (finished: subtest: unknown host is final, missing agent is retried, agents listing, in 4.39 seconds) container-test-run-flakelet-relay> subtest: out-of-band flakelet update reaches the relay without reconnect container-test-run-flakelet-relay> agent: must succeed: flakelet status --json app | jq '.[0].generation' container-test-run-flakelet-relay> agent: (finished: must succeed: flakelet status --json app | jq '.[0].generation', in 0.03 seconds) container-test-run-flakelet-relay> agent: must succeed: journalctl -u flakelet-agent -o cat | grep -c '"connected"' || true container-test-run-flakelet-relay> agent: (finished: must succeed: journalctl -u flakelet-agent -o cat | grep -c '"connected"' || true, in 0.01 seconds) container-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.json container-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) container-test-run-flakelet-relay> agent: must succeed: flakelet update app container-test-run-flakelet-relay> app: using prebuilt artifact /nix/store/6ili1vxjlag9d7dy0bxrbxk6bpi5qh9p-flakelet-app-v2 container-test-run-flakelet-relay> app: activating generation 5 container-test-run-flakelet-relay> agent # [23449.519850] agent systemd[1]: Stopping app slow2... container-test-run-flakelet-relay> agent # [23449.520344] agent systemd[1]: app.service: Deactivated successfully. container-test-run-flakelet-relay> agent # [23449.520607] agent systemd[1]: Stopped app slow2. container-test-run-flakelet-relay> agent # [23449.538260] agent systemd[1]: Reload requested from client PID 2403 ('systemctl')... container-test-run-flakelet-relay> agent # [23449.538327] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [23449.893806] agent systemd[1]: Reloading finished in 354 ms. container-test-run-flakelet-relay> agent # [23449.962384] agent systemd[1]: Reload requested from client PID 2432 ('systemctl')... container-test-run-flakelet-relay> agent # [23449.962425] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [23450.299885] agent systemd[1]: Reloading finished in 337 ms. container-test-run-flakelet-relay> agent # [23450.369625] agent systemd[1]: Reload requested from client PID 2462 ('systemctl')... container-test-run-flakelet-relay> agent # [23450.369663] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [23450.701683] agent systemd[1]: Reloading finished in 331 ms. container-test-run-flakelet-relay> agent # [23450.769392] agent systemd[1]: Reload requested from client PID 2493 ('systemctl')... container-test-run-flakelet-relay> agent # [23450.769429] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [23451.101357] agent systemd[1]: Reloading finished in 331 ms. container-test-run-flakelet-relay> agent: (finished: must succeed: flakelet update app, in 1.71 seconds) container-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' container-test-run-flakelet-relay> agent # [23451.163630] agent systemd[1]: Starting app v2... container-test-run-flakelet-relay> agent # [23451.199085] agent systemd[1]: Started app v2. container-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"} container-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) container-test-run-flakelet-relay> agent: must succeed: journalctl -u flakelet-agent -o cat | grep -c '"connected"' || true container-test-run-flakelet-relay> agent: (finished: must succeed: journalctl -u flakelet-agent -o cat | grep -c '"connected"' || true, in 0.01 seconds) container-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- container-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) container-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- container-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) container-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.json container-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) container-test-run-flakelet-relay> agent: must succeed: flakelet update app container-test-run-flakelet-relay> app: using prebuilt artifact /nix/store/hysydfr96ds875vr0jgw5rjxi4p39h2j-flakelet-app-v1 container-test-run-flakelet-relay> app: activating generation 6 container-test-run-flakelet-relay> agent # [23453.378991] agent systemd[1]: Stopping app v2... container-test-run-flakelet-relay> agent # [23453.379447] agent systemd[1]: app.service: Deactivated successfully. container-test-run-flakelet-relay> agent # [23453.379716] agent systemd[1]: Stopped app v2. container-test-run-flakelet-relay> agent # [23453.399370] agent systemd[1]: Reload requested from client PID 2547 ('systemctl')... container-test-run-flakelet-relay> agent # [23453.399441] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [23453.751595] agent systemd[1]: Reloading finished in 351 ms. container-test-run-flakelet-relay> agent # [23453.819285] agent systemd[1]: Reload requested from client PID 2576 ('systemctl')... container-test-run-flakelet-relay> agent # [23453.819320] agent systemd[1]: Reloading...