these 36 derivations will be built: /nix/store/bdcl8aisb4vzqqyc2x7pa3np1zrfnfwq-push.drv /nix/store/0qg4ckv93g142ykkydbm88l906j7mhrd-system-path.drv /nix/store/30djqwgxd21nvdsn79dkdzr9w56zv1rw-X-Restart-Triggers-flakelet-flakelet-relay.drv /nix/store/81nf5mnms5p3cip05dhc07wrphvy7mal-dbus-1.drv /nix/store/whdgcca7az49w9yn90psxn2cy40lw240-X-Restart-Triggers-dbus-broker.drv /nix/store/c20csrbbaj4031vp2hgr457yzjlf9lcb-unit-dbus-broker.service.drv /nix/store/ab9jzfdlzd3snblr11hn44hlx1yzdxfp-system-units.drv /nix/store/banx7lzn42rcgxwn2sgzr7v86jiwjw7j-unit-dbus-broker.service.drv /nix/store/mg4k9a7ixyzjcqrdsn718cfnxksar81z-user-units.drv /nix/store/4wrgj1alffkqhrch3fnzzgcfs8sbcsl5-etc.drv /nix/store/48k8gdsr39skpv6pnsmc871603mbc7iw-activate.drv /nix/store/4c13m3rm7w0r3888hylriwgrfw6fgs0d-nixos-system-client-test.drv /nix/store/4xicxvba6ns78pgq2xcmavgjhnf1fl1v-run-client-nspawn.drv /nix/store/5h64v4xjjh0h82y2if07k1rwdhiikpy1-unit-flakelet-agent.service.drv /nix/store/9y69d1f194r7pigah362lha2k8xg463f-flakelet-relay-0.1.0.drv /nix/store/c57q1hbdyd7dy208lq4xs6822jdgvlll-flakelet-relay.json.drv /nix/store/lmyvqv4mw4gy83pzzzy84wy6aqm3r8mg-flakelet-relay.service.drv /nix/store/xlznynrck0lrlzyvw1n1c9w2j9wn68kh-flakelet-flakelet-relay-units.drv /nix/store/s82aq513xgm33a90s8lscl741lkkzdr4-flakelet-flakelet-relay.drv /nix/store/7lkg3cj79gc21vzs5jsh5516bd9szbb7-flakelet-config.json.drv /nix/store/l0zpncpgfshznlg43aadg5c59ba42d63-unit-flakelet-flakelet-relay.service.drv /nix/store/m7f145pd83jnv28kcrmgngvl0h9zgd06-X-Restart-Triggers-flakelet-reconcile.drv /nix/store/lv1xbnrhqq6vbj05fcxp8i6cz1a74g5y-unit-flakelet-reconcile.service.drv /nix/store/cd9k0mqv38p1bv7nck9036v17cnh9pcc-system-units.drv /nix/store/fcy293b2qw7i4igpk3qfc0khqx7bj4dm-etc.drv /nix/store/c18bmsr71hvx7kisii68qny2r3ksz337-activate.drv /nix/store/cg3517hcswpb5ffs5llzgdy0q1fivv8v-nixos-system-relay-test.drv /nix/store/737is3xm22cawg2sb44q3k30mk397mps-run-relay-nspawn.drv /nix/store/ldczkdgb7g8wynfn14l2nm0nzlsax04j-system-units.drv /nix/store/ynna10v4bx5cayv9izsqf8vvnjvghs66-etc.drv /nix/store/9989biyncy0f8y0nn4frr29n2hpb6x1i-activate.drv /nix/store/syfyar7kgr53v863v3x2r8gkx2bcaa0f-nixos-system-agent-test.drv /nix/store/xnhhassn548ixv0j5xh3zsv1y838m80w-run-agent-nspawn.drv /nix/store/jqcxydms3qhwmizp4cjkyjjrv6ny4yj7-driverConfiguration.json.drv /nix/store/bh46xxvm8210hfg06lhppcpznr5q44qq-nixos-test-driver-flakelet-relay.drv /nix/store/rgjx3hydkq35iw8mlpjfgpqpjda3nvn4-container-test-run-flakelet-relay.drv building '/nix/store/bdcl8aisb4vzqqyc2x7pa3np1zrfnfwq-push.drv' building '/nix/store/5h64v4xjjh0h82y2if07k1rwdhiikpy1-unit-flakelet-agent.service.drv' building '/nix/store/30djqwgxd21nvdsn79dkdzr9w56zv1rw-X-Restart-Triggers-flakelet-flakelet-relay.drv' push> tribuchet: building on jamie unit-flakelet-agent.service> tribuchet: building on jamie X-Restart-Triggers-flakelet-flakelet-relay> tribuchet: building on jamie building '/nix/store/9y69d1f194r7pigah362lha2k8xg463f-flakelet-relay-0.1.0.drv' flakelet-relay-0.1.0> tribuchet: building on jamie unit-flakelet-agent.service> structuredAttrs is enabled flakelet-relay-0.1.0> Running phase: unpackPhase flakelet-relay-0.1.0> unpacking source archive /nix/store/la7y48xvzasnshd2ps60yaxb2ws52jr1-source flakelet-relay-0.1.0> source root is source flakelet-relay-0.1.0> Executing cargoSetupPostUnpackHook building '/nix/store/l0zpncpgfshznlg43aadg5c59ba42d63-unit-flakelet-flakelet-relay.service.drv' unit-flakelet-flakelet-relay.service> tribuchet: building on jamie building '/nix/store/0qg4ckv93g142ykkydbm88l906j7mhrd-system-path.drv' building '/nix/store/ldczkdgb7g8wynfn14l2nm0nzlsax04j-system-units.drv' system-path> tribuchet: building on jamie system-units> tribuchet: building on jamie unit-flakelet-flakelet-relay.service> structuredAttrs is enabled flakelet-relay-0.1.0> Finished cargoSetupPostUnpackHook flakelet-relay-0.1.0> Running phase: patchPhase flakelet-relay-0.1.0> Executing cargoSetupPostPatchHook flakelet-relay-0.1.0> Validating consistency between /build/source/Cargo.lock and /build/cargo-vendor-dir/Cargo.lock flakelet-relay-0.1.0> Finished cargoSetupPostPatchHook flakelet-relay-0.1.0> Running phase: updateAutotoolsGnuConfigScriptsPhase flakelet-relay-0.1.0> Running phase: configurePhase flakelet-relay-0.1.0> Running phase: buildPhase flakelet-relay-0.1.0> Executing cargoBuildHook flakelet-relay-0.1.0> cargoBuildHook flags: -j 24 --target x86_64-unknown-linux-gnu --offline --profile release flakelet-relay-0.1.0> Compiling proc-macro2 v1.0.107 flakelet-relay-0.1.0> Compiling quote v1.0.47 flakelet-relay-0.1.0> Compiling unicode-ident v1.0.24 flakelet-relay-0.1.0> Compiling libc v0.2.189 flakelet-relay-0.1.0> Compiling autocfg v1.5.1 flakelet-relay-0.1.0> Compiling itoa v1.0.18 flakelet-relay-0.1.0> Compiling find-msvc-tools v0.1.11 flakelet-relay-0.1.0> Compiling shlex v2.0.1 flakelet-relay-0.1.0> Compiling memchr v2.8.3 flakelet-relay-0.1.0> Compiling cfg-if v1.0.4 flakelet-relay-0.1.0> Compiling bytes v1.12.1 flakelet-relay-0.1.0> Compiling pin-project-lite v0.2.17 flakelet-relay-0.1.0> Compiling once_cell v1.21.4 flakelet-relay-0.1.0> Compiling serde_core v1.0.229 flakelet-relay-0.1.0> Compiling minimal-lexical v0.2.1 flakelet-relay-0.1.0> Compiling num-conv v0.2.2 flakelet-relay-0.1.0> Compiling thiserror v2.0.20 flakelet-relay-0.1.0> Compiling time-core v0.1.9 system-path> structuredAttrs is enabled flakelet-relay-0.1.0> Compiling version_check v0.9.5 flakelet-relay-0.1.0> Compiling deranged v0.5.8 flakelet-relay-0.1.0> Compiling zeroize v1.9.0 flakelet-relay-0.1.0> Compiling powerfmt v0.2.0 flakelet-relay-0.1.0> Compiling httparse v1.10.1 flakelet-relay-0.1.0> Compiling untrusted v0.9.0 flakelet-relay-0.1.0> Compiling futures-core v0.3.34 flakelet-relay-0.1.0> Compiling cc v1.4.4 flakelet-relay-0.1.0> Compiling rustls-pki-types v1.15.1 flakelet-relay-0.1.0> Compiling log v0.4.34 flakelet-relay-0.1.0> Compiling utf8parse v0.2.2 flakelet-relay-0.1.0> Compiling time-macros v0.2.32 flakelet-relay-0.1.0> Compiling tracing-core v0.1.36 flakelet-relay-0.1.0> Compiling serde v1.0.229 flakelet-relay-0.1.0> Compiling zmij v1.0.23 flakelet-relay-0.1.0> Compiling smallvec v1.16.0 flakelet-relay-0.1.0> Compiling regex-syntax v0.8.11 flakelet-relay-0.1.0> Compiling anstyle-parse v1.0.0 flakelet-relay-0.1.0> Compiling try-lock v0.2.5 flakelet-relay-0.1.0> Compiling proc-macro2-diagnostics v0.10.1 flakelet-relay-0.1.0> Compiling oid-registry v0.8.1 flakelet-relay-0.1.0> Compiling serde_json v1.0.151 flakelet-relay-0.1.0> Compiling colorchoice v1.0.5 flakelet-relay-0.1.0> Compiling num-traits v0.2.19 flakelet-relay-0.1.0> Compiling rustls v0.23.43 flakelet-relay-0.1.0> Compiling anstyle-query v1.1.5 flakelet-relay-0.1.0> Compiling lazy_static v1.5.0 flakelet-relay-0.1.0> Compiling anstyle v1.0.14 flakelet-relay-0.1.0> Compiling is_terminal_polyfill v1.70.2 flakelet-relay-0.1.0> Compiling want v0.3.1 system-path> created 6399 symlinks in user environment flakelet-relay-0.1.0> Compiling futures-channel v0.3.34 flakelet-relay-0.1.0> Compiling nom v7.1.3 flakelet-relay-0.1.0> Compiling anstream v1.0.0 flakelet-relay-0.1.0> Compiling http v1.5.0 flakelet-relay-0.1.0> Compiling atomic-waker v1.1.2 flakelet-relay-0.1.0> Compiling heck v0.5.0 flakelet-relay-0.1.0> Compiling httpdate v1.0.3 flakelet-relay-0.1.0> Compiling subtle v2.6.1 flakelet-relay-0.1.0> Compiling strsim v0.11.1 flakelet-relay-0.1.0> Compiling clap_lex v1.1.0 flakelet-relay-0.1.0> Compiling tracing-log v0.2.0 flakelet-relay-0.1.0> Compiling sharded-slab v0.1.7 flakelet-relay-0.1.0> Compiling thread_local v1.1.10 flakelet-relay-0.1.0> Compiling data-encoding v2.11.1 flakelet-relay-0.1.0> Compiling clap_builder v4.6.6 flakelet-relay-0.1.0> Compiling nu-ansi-term v0.50.3 flakelet-relay-0.1.0> Compiling webpki-roots v1.0.9 flakelet-relay-0.1.0> Compiling base64 v0.23.1 flakelet-relay-0.1.0> Compiling syn v3.0.4 flakelet-relay-0.1.0> Compiling syn v2.0.119 flakelet-relay-0.1.0> Compiling ring v0.17.14 flakelet-relay-0.1.0> Compiling http-body v1.1.0 flakelet-relay-0.1.0> Compiling regex-automata v0.4.18 flakelet-relay-0.1.0> Compiling errno v0.3.14 flakelet-relay-0.1.0> Compiling time v0.3.55 flakelet-relay-0.1.0> Compiling mio v1.2.2 flakelet-relay-0.1.0> Compiling getrandom v0.2.17 flakelet-relay-0.1.0> Compiling socket2 v0.6.5 flakelet-relay-0.1.0> Compiling http-body-util v0.1.5 flakelet-relay-0.1.0> Compiling sd-notify v0.5.0 flakelet-relay-0.1.0> Compiling signal-hook-registry v1.4.8 flakelet-relay-0.1.0> Compiling num-integer v0.1.47 building '/nix/store/ynna10v4bx5cayv9izsqf8vvnjvghs66-etc.drv' building '/nix/store/81nf5mnms5p3cip05dhc07wrphvy7mal-dbus-1.drv' etc> tribuchet: building on jamie flakelet-relay-0.1.0> Compiling rusticata-macros v4.1.0 flakelet-relay-0.1.0> Compiling num-bigint v0.4.8 dbus-1> tribuchet: building on jamie flakelet-relay-0.1.0> Compiling matchers v0.2.0 flakelet-relay-0.1.0> Compiling thiserror-impl v2.0.20 flakelet-relay-0.1.0> Compiling tokio-macros v2.7.2 flakelet-relay-0.1.0> Compiling displaydoc v0.2.7 flakelet-relay-0.1.0> Compiling serde_derive v1.0.229 flakelet-relay-0.1.0> Compiling clap_derive v4.6.4 building '/nix/store/9989biyncy0f8y0nn4frr29n2hpb6x1i-activate.drv' activate> tribuchet: building on jamie building '/nix/store/whdgcca7az49w9yn90psxn2cy40lw240-X-Restart-Triggers-dbus-broker.drv' X-Restart-Triggers-dbus-broker> tribuchet: building on jamie flakelet-relay-0.1.0> Compiling synstructure v0.13.2 building '/nix/store/syfyar7kgr53v863v3x2r8gkx2bcaa0f-nixos-system-agent-test.drv' flakelet-relay-0.1.0> Compiling asn1-rs-derive v0.6.0 flakelet-relay-0.1.0> Compiling asn1-rs-impl v0.2.0 flakelet-relay-0.1.0> Compiling tracing-attributes v0.1.31 flakelet-relay-0.1.0> Compiling maud_macros v0.27.0 nixos-system-agent-test> tribuchet: building on jamie flakelet-relay-0.1.0> Compiling tokio v1.53.1 building '/nix/store/banx7lzn42rcgxwn2sgzr7v86jiwjw7j-unit-dbus-broker.service.drv' building '/nix/store/c20csrbbaj4031vp2hgr457yzjlf9lcb-unit-dbus-broker.service.drv' unit-dbus-broker.service> tribuchet: building on jamie unit-dbus-broker.service> tribuchet: building on jamie nixos-system-agent-test> structuredAttrs is enabled unit-dbus-broker.service> structuredAttrs is enabled unit-dbus-broker.service> structuredAttrs is enabled building '/nix/store/xnhhassn548ixv0j5xh3zsv1y838m80w-run-agent-nspawn.drv' run-agent-nspawn> tribuchet: building on jamie flakelet-relay-0.1.0> Compiling clap v4.6.6 building '/nix/store/ab9jzfdlzd3snblr11hn44hlx1yzdxfp-system-units.drv' building '/nix/store/mg4k9a7ixyzjcqrdsn718cfnxksar81z-user-units.drv' system-units> tribuchet: building on jamie user-units> tribuchet: building on jamie flakelet-relay-0.1.0> Compiling maud v0.27.0 flakelet-relay-0.1.0> Compiling asn1-rs v0.7.2 flakelet-relay-0.1.0> Compiling tracing v0.1.44 flakelet-relay-0.1.0> Compiling tracing-serde v0.2.0 flakelet-relay-0.1.0> Compiling tracing-subscriber v0.3.23 flakelet-relay-0.1.0> Compiling der-parser v10.0.0 flakelet-relay-0.1.0> Compiling x509-parser v0.18.1 building '/nix/store/4wrgj1alffkqhrch3fnzzgcfs8sbcsl5-etc.drv' etc> tribuchet: building on jamie flakelet-relay-0.1.0> Compiling rustls-webpki v0.103.15 flakelet-relay-0.1.0> Compiling hyper v1.11.1 building '/nix/store/48k8gdsr39skpv6pnsmc871603mbc7iw-activate.drv' activate> tribuchet: building on jamie flakelet-relay-0.1.0> Compiling hyper-util v0.1.20 building '/nix/store/4c13m3rm7w0r3888hylriwgrfw6fgs0d-nixos-system-client-test.drv' nixos-system-client-test> tribuchet: building on jamie nixos-system-client-test> structuredAttrs is enabled flakelet-relay-0.1.0> Compiling tokio-rustls v0.26.4 building '/nix/store/4xicxvba6ns78pgq2xcmavgjhnf1fl1v-run-client-nspawn.drv' flakelet-relay-0.1.0> Compiling flakelet-relay v0.1.0 (/build/source) run-client-nspawn> tribuchet: building on jamie flakelet-relay-0.1.0> Finished `release` profile [optimized] target(s) in 28.06s flakelet-relay-0.1.0> Executing cargoInstallPostBuildHook flakelet-relay-0.1.0> Finished cargoInstallPostBuildHook flakelet-relay-0.1.0> Finished cargoBuildHook flakelet-relay-0.1.0> Running phase: checkPhase flakelet-relay-0.1.0> Executing cargoCheckHook flakelet-relay-0.1.0> cargoCheckHook flags: -j 24 --profile release --target x86_64-unknown-linux-gnu --offline -- flakelet-relay-0.1.0> Compiling rustix v1.1.4 flakelet-relay-0.1.0> Compiling getrandom v0.4.3 flakelet-relay-0.1.0> Compiling bitflags v2.13.1 flakelet-relay-0.1.0> Compiling linux-raw-sys v0.12.1 flakelet-relay-0.1.0> Compiling fastrand v2.5.0 flakelet-relay-0.1.0> Compiling tempfile v3.27.0 flakelet-relay-0.1.0> Compiling flakelet-relay v0.1.0 (/build/source) flakelet-relay-0.1.0> Finished `release` profile [optimized] target(s) in 10.24s flakelet-relay-0.1.0> Running unittests src/lib.rs (target/x86_64-unknown-linux-gnu/release/deps/flakelet_relay-a0eaee9b21fdfd23) flakelet-relay-0.1.0> flakelet-relay-0.1.0> running 20 tests flakelet-relay-0.1.0> test auth::policy::tests::globbing ... ok flakelet-relay-0.1.0> test client::tests::urls ... ok flakelet-relay-0.1.0> test oidc::tests::encodes_forms ... ok flakelet-relay-0.1.0> test auth::policy::tests::deploy_rules ... ok flakelet-relay-0.1.0> test auth::policy::tests::validate_catches_unknown_group ... ok flakelet-relay-0.1.0> test proto::tests::done_roundtrip_is_flat ... ok flakelet-relay-0.1.0> test auth::policy::tests::agent_identity ... ok flakelet-relay-0.1.0> test relay::pages::tests::filter_terms_words_and_toggle ... ok flakelet-relay-0.1.0> test relay::pages::tests::short_rev_uses_rev_not_nar_hash ... ok flakelet-relay-0.1.0> test proto::tests::unknown_frame_type_is_tolerated ... ok flakelet-relay-0.1.0> test proto::tests::target_split_rejects_malformed ... ok flakelet-relay-0.1.0> test srv::tests::parses_compressed_srv ... ok flakelet-relay-0.1.0> test relay::session::tests::roundtrip_and_tamper ... ok flakelet-relay-0.1.0> test ws::tests::rfc_example_accept_key ... ok flakelet-relay-0.1.0> test proto::tests::display_caller_prefers_email_over_opaque_sub ... ok flakelet-relay-0.1.0> test sse::tests::roundtrip_split_across_chunks ... ok flakelet-relay-0.1.0> test proto::tests::unknown_fields_and_status_are_tolerated ... ok flakelet-relay-0.1.0> test auth::jwt::tests::es256 ... ok flakelet-relay-0.1.0> test auth::jwt::tests::ed25519_happy_and_claim_checks ... ok flakelet-relay-0.1.0> test ws::tests::roundtrip_masked_large ... ok flakelet-relay-0.1.0> flakelet-relay-0.1.0> test result: ok. 20 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s flakelet-relay-0.1.0> flakelet-relay-0.1.0> Running unittests src/bin/flakelet-agent.rs (target/x86_64-unknown-linux-gnu/release/deps/flakelet_agent-1cb04c92098d9184) flakelet-relay-0.1.0> flakelet-relay-0.1.0> running 0 tests flakelet-relay-0.1.0> flakelet-relay-0.1.0> test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s flakelet-relay-0.1.0> flakelet-relay-0.1.0> Running unittests src/bin/flakelet-push.rs (target/x86_64-unknown-linux-gnu/release/deps/flakelet_push-e04fe5b96273a4a1) flakelet-relay-0.1.0> flakelet-relay-0.1.0> running 0 tests flakelet-relay-0.1.0> flakelet-relay-0.1.0> test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s flakelet-relay-0.1.0> flakelet-relay-0.1.0> Running unittests src/bin/flakelet-relay.rs (target/x86_64-unknown-linux-gnu/release/deps/flakelet_relay-2deef0a6284d0b44) flakelet-relay-0.1.0> flakelet-relay-0.1.0> running 0 tests flakelet-relay-0.1.0> flakelet-relay-0.1.0> test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s flakelet-relay-0.1.0> flakelet-relay-0.1.0> Doc-tests flakelet_relay flakelet-relay-0.1.0> flakelet-relay-0.1.0> running 0 tests flakelet-relay-0.1.0> flakelet-relay-0.1.0> test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s flakelet-relay-0.1.0> flakelet-relay-0.1.0> Finished cargoCheckHook flakelet-relay-0.1.0> Running phase: installPhase flakelet-relay-0.1.0> Executing cargoInstallHook flakelet-relay-0.1.0> Finished cargoInstallHook flakelet-relay-0.1.0> Running phase: fixupPhase flakelet-relay-0.1.0> shrinking RPATHs of ELF executables and libraries in /nix/store/bs1sal96kl6vr97mb0b80rif24gjbmjz-flakelet-relay-0.1.0 flakelet-relay-0.1.0> shrinking /nix/store/bs1sal96kl6vr97mb0b80rif24gjbmjz-flakelet-relay-0.1.0/bin/flakelet-push flakelet-relay-0.1.0> shrinking /nix/store/bs1sal96kl6vr97mb0b80rif24gjbmjz-flakelet-relay-0.1.0/bin/flakelet-agent flakelet-relay-0.1.0> shrinking /nix/store/bs1sal96kl6vr97mb0b80rif24gjbmjz-flakelet-relay-0.1.0/bin/flakelet-relay flakelet-relay-0.1.0> checking for references to /build/ in /nix/store/bs1sal96kl6vr97mb0b80rif24gjbmjz-flakelet-relay-0.1.0... flakelet-relay-0.1.0> patching script interpreter paths in /nix/store/bs1sal96kl6vr97mb0b80rif24gjbmjz-flakelet-relay-0.1.0 flakelet-relay-0.1.0> stripping (with command strip and flags -S -p) in /nix/store/bs1sal96kl6vr97mb0b80rif24gjbmjz-flakelet-relay-0.1.0/bin building '/nix/store/c57q1hbdyd7dy208lq4xs6822jdgvlll-flakelet-relay.json.drv' 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/lmyvqv4mw4gy83pzzzy84wy6aqm3r8mg-flakelet-relay.service.drv' flakelet-relay.service> tribuchet: building on jamie building '/nix/store/xlznynrck0lrlzyvw1n1c9w2j9wn68kh-flakelet-flakelet-relay-units.drv' flakelet-flakelet-relay-units> tribuchet: building on jamie building '/nix/store/s82aq513xgm33a90s8lscl741lkkzdr4-flakelet-flakelet-relay.drv' flakelet-flakelet-relay> tribuchet: building on jamie building '/nix/store/7lkg3cj79gc21vzs5jsh5516bd9szbb7-flakelet-config.json.drv' flakelet-config.json> tribuchet: building on jamie flakelet-config.json> structuredAttrs is enabled building '/nix/store/m7f145pd83jnv28kcrmgngvl0h9zgd06-X-Restart-Triggers-flakelet-reconcile.drv' X-Restart-Triggers-flakelet-reconcile> tribuchet: building on jamie building '/nix/store/lv1xbnrhqq6vbj05fcxp8i6cz1a74g5y-unit-flakelet-reconcile.service.drv' unit-flakelet-reconcile.service> tribuchet: building on jamie unit-flakelet-reconcile.service> structuredAttrs is enabled building '/nix/store/cd9k0mqv38p1bv7nck9036v17cnh9pcc-system-units.drv' system-units> tribuchet: building on jamie building '/nix/store/fcy293b2qw7i4igpk3qfc0khqx7bj4dm-etc.drv' etc> tribuchet: building on jamie building '/nix/store/c18bmsr71hvx7kisii68qny2r3ksz337-activate.drv' activate> tribuchet: building on jamie building '/nix/store/cg3517hcswpb5ffs5llzgdy0q1fivv8v-nixos-system-relay-test.drv' nixos-system-relay-test> tribuchet: building on jamie nixos-system-relay-test> structuredAttrs is enabled building '/nix/store/737is3xm22cawg2sb44q3k30mk397mps-run-relay-nspawn.drv' run-relay-nspawn> tribuchet: building on jamie building '/nix/store/jqcxydms3qhwmizp4cjkyjjrv6ny4yj7-driverConfiguration.json.drv' driverConfiguration.json> tribuchet: building on jamie driverConfiguration.json> structuredAttrs is enabled building '/nix/store/bh46xxvm8210hfg06lhppcpznr5q44qq-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/rgjx3hydkq35iw8mlpjfgpqpjda3nvn4-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 51) container-test-run-flakelet-relay> client: systemd-nspawn running (pid 53) container-test-run-flakelet-relay> agent: Waiting for journal at /build/vm-state-agent/var/log/journal... container-test-run-flakelet-relay> relay: systemd-nspawn running (pid 55) 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(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> 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(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> 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 client on /build/vm-state-client. container-test-run-flakelet-relay> ░ Spawning container relay on /build/vm-state-relay. 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> agent # [ 4267.426784] agent systemd-journald[57]: Journal started container-test-run-flakelet-relay> agent # [ 4267.426824] agent systemd-journald[57]: Runtime Journal (/run/log/journal/a8b5d2f187fb4bd1ab3bc06370b553db) is 8M, max 4G, 3.9G free. container-test-run-flakelet-relay> agent # [ 4267.437808] agent systemd[1]: Finished Apply Kernel Variables. container-test-run-flakelet-relay> agent # [ 4267.449695] agent systemd[1]: Finished Create Static Device Nodes in /dev gracefully. container-test-run-flakelet-relay> agent # [ 4267.463774] agent systemd[1]: Starting Flush Journal to Persistent Storage... container-test-run-flakelet-relay> agent # [ 4267.464491] agent systemd[1]: Starting Create Static Device Nodes in /dev... container-test-run-flakelet-relay> agent # [ 4267.494688] agent systemd-journald[57]: Time spent on flushing to /var/log/journal/a8b5d2f187fb4bd1ab3bc06370b553db is 1.631ms for 6 entries. container-test-run-flakelet-relay> agent # [ 4267.494688] agent systemd-journald[57]: System Journal (/var/log/journal/a8b5d2f187fb4bd1ab3bc06370b553db) is 512B, max 4G, 3.9G free. container-test-run-flakelet-relay> agent # [ 4267.504351] agent systemd[1]: Finished Create Static Device Nodes in /dev. container-test-run-flakelet-relay> agent # [ 4267.505031] agent systemd[1]: Reached target Preparation for Local File Systems. container-test-run-flakelet-relay> agent # [ 4267.505152] agent systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys container-test-run-flakelet-relay> agent # [ 4267.517663] agent systemd[1]: Finished Flush Journal to Persistent Storage. container-test-run-flakelet-relay> agent # [ 4267.579965] agent systemd[1]: Finished Firewall. container-test-run-flakelet-relay> client # [ 4267.427257] client systemd-journald[57]: Journal started container-test-run-flakelet-relay> client # [ 4267.427297] client systemd-journald[57]: Runtime Journal (/run/log/journal/feb4e24c6a0e4855b5669cee0f098a4e) is 8M, max 4G, 3.9G free. container-test-run-flakelet-relay> client # [ 4267.438687] client systemd[1]: Finished Apply Kernel Variables. container-test-run-flakelet-relay> client # [ 4267.450966] client systemd[1]: Finished Create Static Device Nodes in /dev gracefully. container-test-run-flakelet-relay> client # [ 4267.465224] client systemd[1]: Starting Flush Journal to Persistent Storage... container-test-run-flakelet-relay> client # [ 4267.465775] client systemd[1]: Starting Create Static Device Nodes in /dev... container-test-run-flakelet-relay> client # [ 4267.495249] client systemd-journald[57]: Time spent on flushing to /var/log/journal/feb4e24c6a0e4855b5669cee0f098a4e is 1.398ms for 6 entries. container-test-run-flakelet-relay> client # [ 4267.495249] client systemd-journald[57]: System Journal (/var/log/journal/feb4e24c6a0e4855b5669cee0f098a4e) is 512B, max 4G, 3.9G free. container-test-run-flakelet-relay> client # [ 4267.504152] client systemd[1]: Finished Create Static Device Nodes in /dev. container-test-run-flakelet-relay> client # [ 4267.504857] client systemd[1]: Reached target Preparation for Local File Systems. container-test-run-flakelet-relay> client # [ 4267.504979] client systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys container-test-run-flakelet-relay> client # [ 4267.519974] client systemd[1]: Finished Flush Journal to Persistent Storage. container-test-run-flakelet-relay> client # [ 4267.584362] client systemd[1]: Finished Firewall. container-test-run-flakelet-relay> relay # [ 4267.434405] relay systemd-journald[57]: Journal started container-test-run-flakelet-relay> relay # [ 4267.434447] relay systemd-journald[57]: Runtime Journal (/run/log/journal/b251195dba4843849f56bd16da8cc57a) is 8M, max 4G, 3.9G free. container-test-run-flakelet-relay> relay # [ 4267.443527] relay systemd[1]: Finished Apply Kernel Variables. container-test-run-flakelet-relay> relay # [ 4267.454811] relay systemd[1]: Finished Create Static Device Nodes in /dev gracefully. container-test-run-flakelet-relay> relay # [ 4267.489551] relay systemd[1]: Starting Flush Journal to Persistent Storage... container-test-run-flakelet-relay> relay # [ 4267.490184] relay systemd[1]: Starting Create Static Device Nodes in /dev... container-test-run-flakelet-relay> relay # [ 4267.498449] relay systemd-journald[57]: Time spent on flushing to /var/log/journal/b251195dba4843849f56bd16da8cc57a is 1.650ms for 6 entries. container-test-run-flakelet-relay> relay # [ 4267.498449] relay systemd-journald[57]: System Journal (/var/log/journal/b251195dba4843849f56bd16da8cc57a) is 512B, max 4G, 3.9G free. container-test-run-flakelet-relay> relay # [ 4267.507176] relay systemd[1]: Finished Create Static Device Nodes in /dev. container-test-run-flakelet-relay> relay # [ 4267.507847] relay systemd[1]: Reached target Preparation for Local File Systems. container-test-run-flakelet-relay> relay # [ 4267.507941] relay systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys container-test-run-flakelet-relay> relay # [ 4267.522923] relay systemd[1]: Finished Flush Journal to Persistent Storage. container-test-run-flakelet-relay> relay # [ 4267.602278] relay systemd[1]: Finished Firewall. container-test-run-flakelet-relay> relay # [ 4268.434409] relay systemd[1]: Mounting /run/wrappers... container-test-run-flakelet-relay> agent # [ 4268.437228] agent systemd[1]: Mounting /run/wrappers... container-test-run-flakelet-relay> agent # [ 4268.500815] agent systemd[1]: Mounted /run/wrappers. container-test-run-flakelet-relay> agent # [ 4268.501952] agent systemd[1]: Reached target Local File Systems. container-test-run-flakelet-relay> agent # [ 4268.503287] agent systemd[1]: Listening on Boot Loader Control Service Socket. container-test-run-flakelet-relay> agent # [ 4268.504710] agent systemd[1]: Starting Create SUID/SGID Wrappers... container-test-run-flakelet-relay> agent # [ 4268.504754] agent systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container container-test-run-flakelet-relay> agent # [ 4268.505697] agent systemd[1]: Starting Save Transient machine-id to Disk... container-test-run-flakelet-relay> agent # [ 4268.506836] agent systemd[1]: Starting Create System Files and Directories... container-test-run-flakelet-relay> agent # [ 4268.525239] agent systemd-tmpfiles[161]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted container-test-run-flakelet-relay> agent # [ 4268.525645] agent systemd-tmpfiles[161]: fchmod() of /var/log/journal failed: Operation not permitted container-test-run-flakelet-relay> agent # [ 4268.525970] agent systemd-tmpfiles[161]: fchmod() of /var/log/journal/a8b5d2f187fb4bd1ab3bc06370b553db failed: Operation not permitted container-test-run-flakelet-relay> agent # [ 4268.526330] agent systemd-tmpfiles[161]: fchmod() of /run/log/journal failed: Operation not permitted container-test-run-flakelet-relay> client # [ 4268.437699] client systemd[1]: Mounting /run/wrappers... container-test-run-flakelet-relay> client # [ 4268.500827] client systemd[1]: Mounted /run/wrappers. container-test-run-flakelet-relay> client # [ 4268.501883] client systemd[1]: Reached target Local File Systems. container-test-run-flakelet-relay> client # [ 4268.503191] client systemd[1]: Listening on Boot Loader Control Service Socket. container-test-run-flakelet-relay> client # [ 4268.504565] client systemd[1]: Starting Create SUID/SGID Wrappers... container-test-run-flakelet-relay> client # [ 4268.504635] client systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container container-test-run-flakelet-relay> client # [ 4268.506087] client systemd[1]: Starting Save Transient machine-id to Disk... container-test-run-flakelet-relay> client # [ 4268.507286] client systemd[1]: Starting Create System Files and Directories... container-test-run-flakelet-relay> client # [ 4268.526375] 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 # [ 4268.526769] client systemd-tmpfiles[161]: fchmod() of /var/log/journal failed: Operation not permitted container-test-run-flakelet-relay> client # [ 4268.527074] client systemd-tmpfiles[161]: fchmod() of /var/log/journal/feb4e24c6a0e4855b5669cee0f098a4e failed: Operation not permitted container-test-run-flakelet-relay> client # [ 4268.527182] client suid-sgid-wrappers-start[168]: chmod: changing permissions of '/run/wrappers/wrappers.gpk2Bu8h3K/chsh': Operation not permitted container-test-run-flakelet-relay> client # [ 4268.527404] client systemd-tmpfiles[161]: fchmod() of /run/log/journal failed: Operation not permitted container-test-run-flakelet-relay> relay # [ 4268.499730] relay systemd[1]: Mounted /run/wrappers. container-test-run-flakelet-relay> relay # [ 4268.500791] relay systemd[1]: Reached target Local File Systems. container-test-run-flakelet-relay> relay # [ 4268.502171] relay systemd[1]: Listening on Boot Loader Control Service Socket. container-test-run-flakelet-relay> relay # [ 4268.503763] relay systemd[1]: Starting Create SUID/SGID Wrappers... container-test-run-flakelet-relay> relay # [ 4268.503802] relay systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container container-test-run-flakelet-relay> relay # [ 4268.504908] relay systemd[1]: Starting Save Transient machine-id to Disk... container-test-run-flakelet-relay> relay # [ 4268.506101] relay systemd[1]: Starting Create System Files and Directories... container-test-run-flakelet-relay> relay # [ 4268.527793] relay systemd-tmpfiles[169]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted container-test-run-flakelet-relay> relay # [ 4268.528204] relay systemd-tmpfiles[169]: fchmod() of /var/log/journal failed: Operation not permitted container-test-run-flakelet-relay> relay # [ 4268.528511] relay systemd-tmpfiles[169]: fchmod() of /var/log/journal/b251195dba4843849f56bd16da8cc57a failed: Operation not permitted container-test-run-flakelet-relay> relay # [ 4268.528839] relay systemd-tmpfiles[169]: fchmod() of /run/log/journal failed: Operation not permitted container-test-run-flakelet-relay> relay # [ 4268.529962] relay suid-sgid-wrappers-start[176]: chmod: changing permissions of '/run/wrappers/wrappers.ZfGkMZzrPJ/chsh': Operation not permitted container-test-run-flakelet-relay> relay # [ 4268.584171] relay systemd[1]: etc-machine\x2did.mount: Deactivated successfully. container-test-run-flakelet-relay> relay # [ 4268.595663] relay systemd[1]: suid-sgid-wrappers.service: Main process exited, code=exited, status=1/FAILURE container-test-run-flakelet-relay> relay # [ 4268.595755] relay systemd[1]: suid-sgid-wrappers.service: Failed with result 'exit-code'. container-test-run-flakelet-relay> relay # [ 4268.595937] relay systemd[1]: Failed to start Create SUID/SGID Wrappers. container-test-run-flakelet-relay> relay # [ 4268.596184] relay systemd[1]: Finished Save Transient machine-id to Disk. container-test-run-flakelet-relay> relay # [ 4268.596726] relay systemd[1]: Finished Create System Files and Directories. container-test-run-flakelet-relay> relay # [ 4268.611588] relay systemd[1]: Starting Rebuild Journal Catalog... container-test-run-flakelet-relay> relay # [ 4268.612493] relay systemd[1]: Starting Record System Boot/Shutdown in UTMP... container-test-run-flakelet-relay> relay # [ 4268.645649] relay systemd[1]: Finished Record System Boot/Shutdown in UTMP. container-test-run-flakelet-relay> relay # [ 4268.651984] relay systemd[1]: Finished Rebuild Journal Catalog. container-test-run-flakelet-relay> relay # [ 4268.652956] relay systemd[1]: Starting Update is Completed... container-test-run-flakelet-relay> relay # [ 4268.660984] relay systemd[1]: Finished Update is Completed. container-test-run-flakelet-relay> relay # [ 4268.661074] relay systemd[1]: Reached target System Initialization. container-test-run-flakelet-relay> relay # [ 4268.661147] relay systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container container-test-run-flakelet-relay> relay # [ 4268.661167] relay systemd[1]: Started Daily Cleanup of Temporary Directories. container-test-run-flakelet-relay> relay # [ 4268.661179] relay systemd[1]: Reached target Timer Units. container-test-run-flakelet-relay> relay # [ 4268.661276] relay systemd[1]: Listening on D-Bus System Message Bus Socket. container-test-run-flakelet-relay> relay # [ 4268.661441] relay systemd[1]: Listening on Nix Daemon Socket. container-test-run-flakelet-relay> relay # [ 4268.661540] relay systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. container-test-run-flakelet-relay> relay # [ 4268.661556] relay systemd[1]: Reached target Socket Units. container-test-run-flakelet-relay> relay # [ 4268.661581] relay systemd[1]: Reached target Basic System. container-test-run-flakelet-relay> relay # [ 4268.663464] relay systemd[1]: Started device-mock.service. container-test-run-flakelet-relay> relay # [ 4268.664199] relay systemd[1]: Starting Re-link flakelet services at boot... container-test-run-flakelet-relay> relay # [ 4268.665082] relay systemd[1]: Starting Import lastlog data into lastlog2 database... container-test-run-flakelet-relay> relay # [ 4268.666060] relay systemd[1]: Starting Name Service Cache Daemon (nsncd)... container-test-run-flakelet-relay> relay # [ 4268.666678] relay systemd[1]: Starting resolvconf update... container-test-run-flakelet-relay> relay # [ 4268.680063] relay systemd[1]: Finished Re-link flakelet services at boot. container-test-run-flakelet-relay> relay # [ 4268.681037] relay systemd[1]: Starting Reconcile flakelet services with the host configuration... container-test-run-flakelet-relay> client # [ 4268.591159] client systemd[1]: etc-machine\x2did.mount: Deactivated successfully. container-test-run-flakelet-relay> agent # [ 4268.530051] agent suid-sgid-wrappers-start[168]: chmod: changing permissions of '/run/wrappers/wrappers.QoDBIPEiI7/chsh': Operation not permitted container-test-run-flakelet-relay> client # [ 4268.604736] client systemd[1]: suid-sgid-wrappers.service: Main process exited, code=exited, status=1/FAILURE container-test-run-flakelet-relay> agent # [ 4268.612009] agent systemd[1]: etc-machine\x2did.mount: Deactivated successfully. container-test-run-flakelet-relay> client # [ 4268.604771] client systemd[1]: suid-sgid-wrappers.service: Failed with result 'exit-code'. container-test-run-flakelet-relay> client # [ 4268.604824] client systemd[1]: Failed to start Create SUID/SGID Wrappers. container-test-run-flakelet-relay> agent # [ 4268.612650] agent systemd[1]: suid-sgid-wrappers.service: Main process exited, code=exited, status=1/FAILURE container-test-run-flakelet-relay> agent # [ 4268.612686] agent systemd[1]: suid-sgid-wrappers.service: Failed with result 'exit-code'. container-test-run-flakelet-relay> client # [ 4268.604969] client systemd[1]: Finished Save Transient machine-id to Disk. container-test-run-flakelet-relay> client # [ 4268.605453] client systemd[1]: Finished Create System Files and Directories. container-test-run-flakelet-relay> client # [ 4268.636528] client systemd[1]: Starting Rebuild Journal Catalog... container-test-run-flakelet-relay> client # [ 4268.637492] client systemd[1]: Starting Record System Boot/Shutdown in UTMP... container-test-run-flakelet-relay> client # [ 4268.650920] client systemd[1]: Finished Record System Boot/Shutdown in UTMP. container-test-run-flakelet-relay> client # [ 4268.656109] client systemd[1]: Finished Rebuild Journal Catalog. container-test-run-flakelet-relay> client # [ 4268.657468] client systemd[1]: Starting Update is Completed... container-test-run-flakelet-relay> client # [ 4268.665448] client systemd[1]: Finished Update is Completed. container-test-run-flakelet-relay> client # [ 4268.666264] client systemd[1]: Reached target System Initialization. container-test-run-flakelet-relay> client # [ 4268.666427] client systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container container-test-run-flakelet-relay> client # [ 4268.666493] client systemd[1]: Started Daily Cleanup of Temporary Directories. container-test-run-flakelet-relay> client # [ 4268.666520] client systemd[1]: Reached target Timer Units. container-test-run-flakelet-relay> client # [ 4268.666712] client systemd[1]: Listening on D-Bus System Message Bus Socket. container-test-run-flakelet-relay> agent # [ 4268.612740] agent systemd[1]: Failed to start Create SUID/SGID Wrappers. container-test-run-flakelet-relay> client # [ 4268.666995] client systemd[1]: Listening on Nix Daemon Socket. container-test-run-flakelet-relay> agent # [ 4268.612885] agent systemd[1]: Finished Save Transient machine-id to Disk. container-test-run-flakelet-relay> client # [ 4268.667184] client systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. container-test-run-flakelet-relay> agent # [ 4268.613381] agent systemd[1]: Finished Create System Files and Directories. container-test-run-flakelet-relay> client # [ 4268.667210] client systemd[1]: Reached target Socket Units. container-test-run-flakelet-relay> agent # [ 4268.636652] agent systemd[1]: Starting Rebuild Journal Catalog... container-test-run-flakelet-relay> client # [ 4268.667256] client systemd[1]: Reached target Basic System. container-test-run-flakelet-relay> agent # [ 4268.637533] agent systemd[1]: Starting Record System Boot/Shutdown in UTMP... container-test-run-flakelet-relay> client # [ 4268.668985] client systemd[1]: Starting Import lastlog data into lastlog2 database... container-test-run-flakelet-relay> agent # [ 4268.650171] agent systemd[1]: Finished Record System Boot/Shutdown in UTMP. container-test-run-flakelet-relay> client # [ 4268.670172] client systemd[1]: Starting Name Service Cache Daemon (nsncd)... container-test-run-flakelet-relay> agent # [ 4268.657723] agent systemd[1]: Finished Rebuild Journal Catalog. container-test-run-flakelet-relay> client # [ 4268.671276] client systemd[1]: Starting resolvconf update... container-test-run-flakelet-relay> agent # [ 4268.658618] agent systemd[1]: Starting Update is Completed... container-test-run-flakelet-relay> client # [ 4268.688178] client systemd[1]: Finished Import lastlog data into lastlog2 database. container-test-run-flakelet-relay> agent # [ 4268.667254] agent systemd[1]: Finished Update is Completed. container-test-run-flakelet-relay> agent # [ 4268.667326] agent systemd[1]: Reached target System Initialization. container-test-run-flakelet-relay> agent # [ 4268.667390] agent systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container container-test-run-flakelet-relay> agent # [ 4268.667412] agent systemd[1]: Started Daily Cleanup of Temporary Directories. container-test-run-flakelet-relay> agent # [ 4268.667424] agent systemd[1]: Reached target Timer Units. container-test-run-flakelet-relay> agent # [ 4268.667513] agent systemd[1]: Listening on D-Bus System Message Bus Socket. container-test-run-flakelet-relay> agent # [ 4268.667699] agent systemd[1]: Listening on Nix Daemon Socket. container-test-run-flakelet-relay> agent # [ 4268.667791] agent systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. container-test-run-flakelet-relay> agent # [ 4268.667803] agent systemd[1]: Reached target Socket Units. container-test-run-flakelet-relay> agent # [ 4268.667830] agent systemd[1]: Reached target Basic System. container-test-run-flakelet-relay> agent # [ 4268.668894] agent systemd[1]: Starting Re-link flakelet services at boot... container-test-run-flakelet-relay> agent # [ 4268.669606] agent systemd[1]: Starting Import lastlog data into lastlog2 database... container-test-run-flakelet-relay> agent # [ 4268.670315] agent systemd[1]: Starting Name Service Cache Daemon (nsncd)... container-test-run-flakelet-relay> agent # [ 4268.671010] agent systemd[1]: Starting resolvconf update... container-test-run-flakelet-relay> agent # [ 4268.683141] agent systemd[1]: Finished Re-link flakelet services at boot. container-test-run-flakelet-relay> agent # [ 4268.684645] agent systemd[1]: Starting Reconcile flakelet services with the host configuration... container-test-run-flakelet-relay> agent # [ 4268.688149] agent systemd[1]: Finished Import lastlog data into lastlog2 database. container-test-run-flakelet-relay> agent # [ 4268.697046] agent systemd[1]: Finished Reconcile flakelet services with the host configuration. container-test-run-flakelet-relay> agent # [ 4268.740898] agent systemd[1]: Finished resolvconf update. container-test-run-flakelet-relay> agent # [ 4268.741526] agent systemd[1]: Reached target Preparation for Network. container-test-run-flakelet-relay> agent # [ 4268.742497] agent systemd[1]: Starting Address configuration of eth1... container-test-run-flakelet-relay> agent # [ 4268.743099] agent systemd[1]: Starting Extra networking commands.... container-test-run-flakelet-relay> agent # [ 4268.781330] agent systemd[1]: nscd.service: Deactivated successfully. container-test-run-flakelet-relay> agent # [ 4268.781394] agent systemd[1]: Stopped Name Service Cache Daemon (nsncd). container-test-run-flakelet-relay> agent # [ 4268.784311] agent systemd[1]: Starting Name Service Cache Daemon (nsncd)... container-test-run-flakelet-relay> agent # [ 4268.798915] agent network-addresses-eth1-start[237]: adding address 192.168.1.1/24... done container-test-run-flakelet-relay> agent # [ 4268.802405] agent network-addresses-eth1-start[237]: adding address 2001:db8:1::1/64... done container-test-run-flakelet-relay> agent # [ 4268.806994] agent systemd[1]: Finished Address configuration of eth1. container-test-run-flakelet-relay> agent # [ 4268.850070] agent systemd[1]: Finished Extra networking commands.. container-test-run-flakelet-relay> agent # [ 4268.850196] agent systemd[1]: Reached target Network. container-test-run-flakelet-relay> agent # [ 4268.851248] agent systemd[1]: Starting flakelet-agent... container-test-run-flakelet-relay> agent # [ 4268.883801] agent systemd[1]: Starting Update flakelet service app... container-test-run-flakelet-relay> agent # [ 4268.885661] agent systemd[1]: Starting Update flakelet service other... container-test-run-flakelet-relay> agent # [ 4268.886283] agent systemd[1]: nscd.service: Deactivated successfully. container-test-run-flakelet-relay> agent # [ 4268.886501] agent systemd[1]: Stopped Name Service Cache Daemon (nsncd). container-test-run-flakelet-relay> agent # [ 4268.892100] agent systemd[1]: Starting Name Service Cache Daemon (nsncd)... container-test-run-flakelet-relay> agent # [ 4268.895500] agent flakelet[305]: app: using prebuilt artifact /nix/store/hysydfr96ds875vr0jgw5rjxi4p39h2j-flakelet-app-v1 container-test-run-flakelet-relay> agent # [ 4268.896057] agent flakelet[305]: app: activating generation 1 container-test-run-flakelet-relay> agent # [ 4268.896919] agent flakelet[307]: other: using prebuilt artifact /nix/store/hmr990n0b4v6rpzrcpyyw738r9xi7z95-flakelet-other-v1 container-test-run-flakelet-relay> agent # [ 4268.897437] agent flakelet[307]: other: activating generation 1 container-test-run-flakelet-relay> agent # [ 4268.899359] agent systemd[1]: Reload requested from client PID 391 ('systemctl') (unit flakelet-app.service)... container-test-run-flakelet-relay> agent # [ 4268.899399] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [ 4268.922173] agent flakelet-agent[304]: {"timestamp":"2026-09-06T08:15:46.883741Z","level":"WARN","message":"SRV _flakelet-relay._tcp.test: lookup failed","target":"flakelet_agent"} container-test-run-flakelet-relay> relay # [ 4268.686952] relay systemd[1]: Finished Import lastlog data into lastlog2 database. container-test-run-flakelet-relay> relay # [ 4268.692230] relay systemd[1]: Finished Reconcile flakelet services with the host configuration. container-test-run-flakelet-relay> relay # [ 4268.740225] relay systemd[1]: Finished resolvconf update. container-test-run-flakelet-relay> relay # [ 4268.740353] relay systemd[1]: Reached target Preparation for Network. container-test-run-flakelet-relay> relay # [ 4268.741371] relay systemd[1]: Starting Address configuration of eth1... container-test-run-flakelet-relay> relay # [ 4268.742306] relay systemd[1]: Starting Extra networking commands.... container-test-run-flakelet-relay> relay # [ 4268.768561] relay systemd[1]: nscd.service: Deactivated successfully. container-test-run-flakelet-relay> relay # [ 4268.781344] relay systemd[1]: Stopped Name Service Cache Daemon (nsncd). container-test-run-flakelet-relay> relay # [ 4268.784245] relay systemd[1]: Starting Name Service Cache Daemon (nsncd)... container-test-run-flakelet-relay> relay # [ 4268.797859] relay network-addresses-eth1-start[246]: adding address 192.168.1.3/24... done container-test-run-flakelet-relay> relay # [ 4268.802103] relay network-addresses-eth1-start[246]: adding address 2001:db8:1::3/64... done container-test-run-flakelet-relay> relay # [ 4268.806376] relay systemd[1]: Finished Address configuration of eth1. container-test-run-flakelet-relay> relay # [ 4268.846216] relay systemd[1]: Finished Extra networking commands.. container-test-run-flakelet-relay> relay # [ 4268.846313] relay systemd[1]: Reached target Network. container-test-run-flakelet-relay> relay # [ 4268.847709] relay systemd[1]: Starting Dnsmasq Daemon... container-test-run-flakelet-relay> relay # [ 4268.848642] relay systemd[1]: Starting Nginx Web Server... container-test-run-flakelet-relay> relay # [ 4268.849453] relay systemd[1]: Starting Update flakelet service flakelet-relay... container-test-run-flakelet-relay> relay # [ 4268.891988] relay flakelet[311]: flakelet-relay: using prebuilt artifact /nix/store/zhnlcnggdn7ymdlwkz9j9s4dkxd6v5g7-flakelet-flakelet-relay container-test-run-flakelet-relay> relay # [ 4268.892629] relay flakelet[311]: flakelet-relay: activating generation 1 container-test-run-flakelet-relay> relay # [ 4268.896804] relay systemd[1]: Reload requested from client PID 316 ('systemctl') (unit flakelet-flakelet-relay.service)... container-test-run-flakelet-relay> relay # [ 4268.896847] relay systemd[1]: Reloading... container-test-run-flakelet-relay> client # [ 4268.743989] client systemd[1]: Finished resolvconf update. container-test-run-flakelet-relay> client # [ 4268.744125] client systemd[1]: Reached target Preparation for Network. container-test-run-flakelet-relay> client # [ 4268.781627] client systemd[1]: Starting Address configuration of eth1... container-test-run-flakelet-relay> client # [ 4268.782532] client systemd[1]: Starting Extra networking commands.... container-test-run-flakelet-relay> client # [ 4268.782665] client systemd[1]: nscd.service: Deactivated successfully. container-test-run-flakelet-relay> client # [ 4268.782717] client systemd[1]: Stopped Name Service Cache Daemon (nsncd). container-test-run-flakelet-relay> client # [ 4268.786601] client systemd[1]: Starting Name Service Cache Daemon (nsncd)... container-test-run-flakelet-relay> client # [ 4268.803924] client network-addresses-eth1-start[233]: adding address 192.168.1.2/24... done container-test-run-flakelet-relay> client # [ 4268.807443] client network-addresses-eth1-start[233]: adding address 2001:db8:1::2/64... done container-test-run-flakelet-relay> client # [ 4268.812401] client systemd[1]: Finished Address configuration of eth1. container-test-run-flakelet-relay> client # [ 4268.883550] client systemd[1]: Finished Extra networking commands.. container-test-run-flakelet-relay> client # [ 4268.883960] client systemd[1]: nscd.service: Deactivated successfully. container-test-run-flakelet-relay> client # [ 4268.884199] client systemd[1]: Stopped Name Service Cache Daemon (nsncd). container-test-run-flakelet-relay> client # [ 4268.887767] client systemd[1]: Reached target Network. container-test-run-flakelet-relay> client # [ 4268.889604] client systemd[1]: Starting Name Service Cache Daemon (nsncd)... container-test-run-flakelet-relay> relay # [ 4269.219986] relay nsncd[251]: Sep 06 08:15:47.181 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" container-test-run-flakelet-relay> relay # [ 4269.314795] relay systemd[1]: Reloading finished in 417 ms. container-test-run-flakelet-relay> relay # [ 4269.441523] relay systemd[1]: Started Name Service Cache Daemon (nsncd). container-test-run-flakelet-relay> relay # [ 4269.442545] relay systemd[1]: Reached target Host and Network Name Lookups. container-test-run-flakelet-relay> relay # [ 4269.442631] relay systemd[1]: Reached target User and Group Name Lookups. container-test-run-flakelet-relay> relay # [ 4269.444015] relay systemd[1]: Starting User Login Management... container-test-run-flakelet-relay> relay # [ 4269.444896] relay systemd[1]: Starting Permit User Sessions... container-test-run-flakelet-relay> relay # [ 4269.459626] relay systemd[1]: Reload requested from client PID 357 ('systemctl') (unit flakelet-flakelet-relay.service)... container-test-run-flakelet-relay> relay # [ 4269.459660] relay systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [ 4269.230762] agent nsncd[311]: Sep 06 08:15:47.192 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" container-test-run-flakelet-relay> agent # [ 4269.307136] agent systemd[1]: Reloading finished in 407 ms. container-test-run-flakelet-relay> agent # [ 4269.440787] agent systemd[1]: Started flakelet-agent. container-test-run-flakelet-relay> agent # [ 4269.440901] agent systemd[1]: Started Name Service Cache Daemon (nsncd). container-test-run-flakelet-relay> agent # [ 4269.443033] agent systemd[1]: Reached target Host and Network Name Lookups. container-test-run-flakelet-relay> agent # [ 4269.443146] agent systemd[1]: Reached target User and Group Name Lookups. container-test-run-flakelet-relay> agent # [ 4269.444498] agent systemd[1]: Starting User Login Management... container-test-run-flakelet-relay> agent # [ 4269.445304] agent systemd[1]: Starting Permit User Sessions... container-test-run-flakelet-relay> agent # [ 4269.445587] agent systemd[1]: Reload requested from client PID 421 ('systemctl') (unit flakelet-other.service)... container-test-run-flakelet-relay> agent # [ 4269.445616] agent systemd[1]: Reloading... container-test-run-flakelet-relay> client # [ 4269.212236] client systemd[1]: Started Name Service Cache Daemon (nsncd). container-test-run-flakelet-relay> client # [ 4269.212530] client nsncd[303]: Sep 06 08:15:47.174 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" container-test-run-flakelet-relay> client # [ 4269.212332] client systemd[1]: Reached target Host and Network Name Lookups. container-test-run-flakelet-relay> client # [ 4269.212429] client systemd[1]: Reached target User and Group Name Lookups. container-test-run-flakelet-relay> client # [ 4269.214920] client systemd[1]: Starting User Login Management... container-test-run-flakelet-relay> client # [ 4269.219009] client systemd[1]: Starting Permit User Sessions... container-test-run-flakelet-relay> client # [ 4269.250106] client systemd[1]: Finished Permit User Sessions. container-test-run-flakelet-relay> client # [ 4269.251944] client systemd[1]: Started Console Getty. container-test-run-flakelet-relay> client # [ 4269.251997] client systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0 container-test-run-flakelet-relay> client # [ 4269.252044] client systemd[1]: Reached target Login Prompts. container-test-run-flakelet-relay> relay # [ 4269.503541] relay dnsmasq-pre-start[375]: dnsmasq: syntax check OK. container-test-run-flakelet-relay> agent # [ 4269.863561] agent systemd[1]: Reloading finished in 417 ms. container-test-run-flakelet-relay> agent # [ 4269.998197] agent systemd[1]: Finished Permit User Sessions. container-test-run-flakelet-relay> agent # [ 4270.004535] agent systemd[1]: Started Console Getty. container-test-run-flakelet-relay> agent # [ 4270.004561] agent systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0 container-test-run-flakelet-relay> agent # [ 4270.004572] agent systemd[1]: Reached target Login Prompts. container-test-run-flakelet-relay> agent # [ 4270.010471] agent systemd[1]: Reload requested from client PID 778 ('systemctl') (unit flakelet-app.service)... container-test-run-flakelet-relay> agent # [ 4270.010502] agent systemd[1]: Reloading... container-test-run-flakelet-relay> relay # [ 4269.856397] relay systemd[1]: Reloading finished in 396 ms. container-test-run-flakelet-relay> relay # [ 4270.001542] relay systemd[1]: Finished Permit User Sessions. container-test-run-flakelet-relay> relay # [ 4270.043653] relay systemd[1]: Started Console Getty. container-test-run-flakelet-relay> relay # [ 4270.043709] relay systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0 container-test-run-flakelet-relay> relay # [ 4270.043732] relay systemd[1]: Reached target Login Prompts. container-test-run-flakelet-relay> relay # [ 4270.048537] relay systemd[1]: Starting flakelet-relay... container-test-run-flakelet-relay> agent # [ 4270.390469] agent systemd[1]: Reloading finished in 379 ms. container-test-run-flakelet-relay> agent # [ 4270.468940] agent systemd[1]: Reload requested from client PID 779 ('systemctl') (unit flakelet-other.service)... container-test-run-flakelet-relay> agent # [ 4270.468980] agent systemd[1]: Reloading... container-test-run-flakelet-relay> relay # [ 4270.504057] relay nginx-pre-start[402]: nginx: the configuration file /nix/store/01avjj26wcihp926xgasszcj5s0k7cbs-nginx.conf syntax is ok container-test-run-flakelet-relay> relay # [ 4270.504748] relay nginx-pre-start[402]: nginx: configuration file /nix/store/01avjj26wcihp926xgasszcj5s0k7cbs-nginx.conf test is successful container-test-run-flakelet-relay> relay # [ 4270.521587] relay systemd[1]: Started Nginx Web Server. container-test-run-flakelet-relay> relay # [ 4270.665290] relay systemd[1]: Starting D-Bus System Message Bus... container-test-run-flakelet-relay> client # [ 4270.614986] client systemd-logind[315]: New seat seat0. container-test-run-flakelet-relay> client # [ 4270.615459] client systemd[1]: Started User Login Management. container-test-run-flakelet-relay> client # [ 4270.617646] client systemd[1]: Starting D-Bus System Message Bus... container-test-run-flakelet-relay> client # [ 4270.618920] client systemd[1]: Starting linger-users.service... container-test-run-flakelet-relay> client # [ 4270.650888] client systemd[1]: linger-users.service: Deactivated successfully. container-test-run-flakelet-relay> client # [ 4270.651230] client systemd[1]: Finished linger-users.service. container-test-run-flakelet-relay> client # [ 4270.651564] client systemd[1]: Reached target Multi-User System. container-test-run-flakelet-relay> agent # [ 4270.842883] agent systemd[1]: Reloading finished in 373 ms. container-test-run-flakelet-relay> agent # [ 4270.924266] agent systemd[1]: Starting other v1... container-test-run-flakelet-relay> agent # [ 4270.926902] agent systemd[1]: Starting app v1... container-test-run-flakelet-relay> agent # [ 4270.953858] agent systemd[1]: Started other v1. container-test-run-flakelet-relay> agent # [ 4270.954443] agent systemd[1]: Started app v1. container-test-run-flakelet-relay> agent # [ 4270.972094] agent flakelet[307]: other: updated to generation 1 container-test-run-flakelet-relay> agent # [ 4270.973040] agent systemd[1]: Finished Update flakelet service other. container-test-run-flakelet-relay> agent # [ 4270.974112] agent systemd-logind[740]: New seat seat0. container-test-run-flakelet-relay> agent # [ 4270.974906] agent systemd[1]: Started User Login Management. container-test-run-flakelet-relay> agent # [ 4270.975875] agent flakelet[305]: app: updated to generation 1 container-test-run-flakelet-relay> agent # [ 4270.976360] agent systemd[1]: Finished Update flakelet service app. container-test-run-flakelet-relay> agent # [ 4270.976981] agent systemd[1]: Reached target flakelet managed services. container-test-run-flakelet-relay> agent # [ 4270.978155] agent systemd[1]: Starting D-Bus System Message Bus... container-test-run-flakelet-relay> agent # [ 4270.979243] agent systemd[1]: Starting linger-users.service... container-test-run-flakelet-relay> agent # [ 4270.991741] agent systemd[1]: linger-users.service: Deactivated successfully. container-test-run-flakelet-relay> agent # [ 4270.991943] agent systemd[1]: Finished linger-users.service. container-test-run-flakelet-relay> agent # [ 4270.992088] agent systemd[1]: Reached target Multi-User System. container-test-run-flakelet-relay> relay # [ 4271.063373] relay systemd-logind[354]: New seat seat0. container-test-run-flakelet-relay> relay # [ 4271.063783] relay systemd[1]: Started User Login Management. container-test-run-flakelet-relay> relay # [ 4271.066123] relay systemd[1]: Starting linger-users.service... container-test-run-flakelet-relay> relay # [ 4271.105786] relay systemd[1]: linger-users.service: Deactivated successfully. container-test-run-flakelet-relay> relay # [ 4271.106165] relay systemd[1]: Finished linger-users.service. container-test-run-flakelet-relay> relay # [ 4271.184980] relay dbus-broker-launch[408]: Looking up NSS user entry for 'systemd-timesync'... container-test-run-flakelet-relay> relay # [ 4271.186417] relay dbus-broker-launch[408]: NSS returned no entry for 'systemd-timesync' container-test-run-flakelet-relay> relay # [ 4271.186417] relay dbus-broker-launch[408]: Invalid user-name in /nix/store/27h4g2jdw18vrabfh6p1l0n8ss5dhbp5-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" container-test-run-flakelet-relay> relay # [ 4271.187615] relay systemd[1]: Started D-Bus System Message Bus. container-test-run-flakelet-relay> relay # [ 4271.194700] relay dbus-broker-launch[408]: Ready container-test-run-flakelet-relay> relay # [ 4271.198113] relay systemd[1]: Started Dnsmasq Daemon. container-test-run-flakelet-relay> relay # [ 4271.199597] relay dnsmasq[394]: started, version 2.93 cachesize 150 container-test-run-flakelet-relay> relay # [ 4271.199614] 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 # [ 4271.199628] relay dnsmasq[394]: DBus support enabled: connected to system bus container-test-run-flakelet-relay> relay # [ 4271.199645] relay dnsmasq[394]: no upstream servers configured - please set them from DBus container-test-run-flakelet-relay> relay # [ 4271.199791] relay dnsmasq[394]: read /etc/hosts - 9 names container-test-run-flakelet-relay> client # [ 4271.156345] client dbus-broker-launch[325]: Looking up NSS user entry for 'systemd-timesync'... container-test-run-flakelet-relay> client # [ 4271.157806] client dbus-broker-launch[325]: NSS returned no entry for 'systemd-timesync' container-test-run-flakelet-relay> client # [ 4271.157806] client dbus-broker-launch[325]: Invalid user-name in /nix/store/kl0xf3n3ac6xk287l1j4isggl96cyhax-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" container-test-run-flakelet-relay> client # [ 4271.158757] client systemd[1]: Started D-Bus System Message Bus. container-test-run-flakelet-relay> client # [ 4271.159230] client systemd[1]: Startup finished in 4.201s. container-test-run-flakelet-relay> client # [ 4271.164362] client dbus-broker-launch[325]: Ready container-test-run-flakelet-relay> agent # [ 4271.399072] agent dbus-broker-launch[846]: Looking up NSS user entry for 'systemd-timesync'... container-test-run-flakelet-relay> agent # [ 4271.400682] agent dbus-broker-launch[846]: NSS returned no entry for 'systemd-timesync' container-test-run-flakelet-relay> agent # [ 4271.400682] agent dbus-broker-launch[846]: Invalid user-name in /nix/store/q3c2gxfm178wqgzv8m87na4aa72wxc04-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" container-test-run-flakelet-relay> agent # [ 4271.401672] agent systemd[1]: Started D-Bus System Message Bus. container-test-run-flakelet-relay> agent # [ 4271.402143] agent systemd[1]: Startup finished in 4.453s. container-test-run-flakelet-relay> agent # [ 4271.406731] agent dbus-broker-launch[846]: Ready container-test-run-flakelet-relay> relay # [ 4271.958749] relay flakelet-relay[400]: {"timestamp":"2026-09-06T08:15:49.920360Z","level":"INFO","message":"listening on 127.0.0.1:7400","target":"flakelet_relay::relay::server"} container-test-run-flakelet-relay> relay # [ 4271.959636] relay flakelet-relay[400]: {"timestamp":"2026-09-06T08:15:49.921027Z","level":"INFO","message":"listening on [::]:7443","target":"flakelet_relay::relay::server"} container-test-run-flakelet-relay> relay # [ 4271.959429] relay systemd[1]: Started flakelet-relay. container-test-run-flakelet-relay> relay # [ 4271.970174] relay systemd[1]: Starting health for flakelet-relay... container-test-run-flakelet-relay> relay # [ 4272.213411] relay systemd[1]: flakelet-relay-health.service: Deactivated successfully. container-test-run-flakelet-relay> relay # [ 4272.213758] relay systemd[1]: Finished health for flakelet-relay. container-test-run-flakelet-relay> relay # [ 4272.255128] relay flakelet[311]: flakelet-relay: updated to generation 1 container-test-run-flakelet-relay> relay # [ 4272.257487] relay systemd[1]: Finished Update flakelet service flakelet-relay. container-test-run-flakelet-relay> relay # [ 4272.258060] relay systemd[1]: Reached target flakelet managed services. container-test-run-flakelet-relay> relay # [ 4272.258234] relay systemd[1]: Reached target Multi-User System. container-test-run-flakelet-relay> relay # [ 4272.258491] relay systemd[1]: Startup finished in 5.311s. 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.02 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 # [ 4273.943557] agent flakelet-agent[304]: {"timestamp":"2026-09-06T08:15:51.905292Z","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 # [ 4273.943947] relay flakelet-relay[400]: {"timestamp":"2026-09-06T08:15:51.905666Z","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.05 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> relay # [ 4274.761704] relay flakelet-relay[400]: {"timestamp":"2026-09-06T08:15:52.723430Z","level":"INFO","message":"deploy accepted","job":"b08899e4f5d9448ad0e62beee8dfb372","caller":"x509:email:admin@example.org","targets":"[\"agent/app\"]","target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> agent # [ 4274.762409] agent flakelet-agent[304]: {"timestamp":"2026-09-06T08:15:52.724130Z","level":"INFO","message":"start","id":"6c8a2b22b9b638ee3bca1842049c9483","flakelet":"app","rule":"admins","relay":"relay","caller":"Some(\"x509:email:admin@example.org\")","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> agent # [ 4274.763355] agent flakelet-agent[304]: {"timestamp":"2026-09-06T08:15:52.725174Z","level":"INFO","message":"update starting","flakelet":"app","ids":"[\"6c8a2b22b9b638ee3bca1842049c9483\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [ 4274.817525] agent systemd[1]: Starting flakelet update app (via relay)... container-test-run-flakelet-relay> agent # [ 4274.847968] agent systemd[1]: Started flakelet update app (via relay). container-test-run-flakelet-relay> agent # [ 4274.852758] agent flakelet[875]: app: using prebuilt artifact /nix/store/6ili1vxjlag9d7dy0bxrbxk6bpi5qh9p-flakelet-app-v2 container-test-run-flakelet-relay> agent # [ 4274.853409] agent flakelet[875]: app: activating generation 2 container-test-run-flakelet-relay> agent # [ 4274.867213] agent systemd[1]: Stopping app v1... container-test-run-flakelet-relay> agent # [ 4274.867671] agent systemd[1]: app.service: Deactivated successfully. container-test-run-flakelet-relay> agent # [ 4274.867936] agent systemd[1]: Stopped app v1. container-test-run-flakelet-relay> agent # [ 4274.886902] agent systemd[1]: Reload requested from client PID 882 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [ 4274.886970] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [ 4275.229893] agent systemd[1]: Reloading finished in 342 ms. container-test-run-flakelet-relay> agent # [ 4275.320871] agent systemd[1]: Reload requested from client PID 915 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [ 4275.320906] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [ 4275.649235] agent systemd[1]: Reloading finished in 327 ms. container-test-run-flakelet-relay> agent # [ 4275.730516] agent systemd[1]: Reload requested from client PID 945 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [ 4275.730555] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [ 4276.059021] agent systemd[1]: Reloading finished in 328 ms. container-test-run-flakelet-relay> agent # [ 4276.140833] agent systemd[1]: Reload requested from client PID 978 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [ 4276.140874] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [ 4276.468935] agent systemd[1]: Reloading finished in 327 ms. container-test-run-flakelet-relay> agent # [ 4276.532855] agent systemd[1]: Starting app v2... container-test-run-flakelet-relay> agent # [ 4276.557961] agent systemd[1]: Started app v2. container-test-run-flakelet-relay> agent # [ 4276.570851] agent flakelet[875]: app: updated to generation 2 container-test-run-flakelet-relay> agent # [ 4276.573188] 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.96 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> (finished: subtest: deploy with client certificate, relay found via SRV, in 3.00 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.eyJhdWQiOiJmbGFrZWxldC1yZWxheSIsImV4cCI6MTc4ODY4Mjg1NSwiZ3JvdXBzIjpbXSwiaWF0IjoxNzg4NjgyNTU1LCJpc3MiOiJodHRwczovL3JlbGF5Ojg0NDMiLCJqdGkiOiJFUFozWjJXWUk0Q1RRPT09IiwibmJmIjoxNzg4NjgyNTU1LCJzdWIiOiJyZXBvOmdpdGh1YjpleGFtcGxlL2FwcDpyZWY6cmVmcy9oZWFkcy9tYWluIn0.eU6P3Q7FueynD5Ujut-lwWLcvfOIJ7WLsoHfdlY1DJ8ZnEpuHcEk9GYk8dvB8iwMb8QBnOQHOzX-JhlZTfYpBA' push --relay https://relay:7443 deploy agent/app 2>&1 container-test-run-flakelet-relay> agent # [ 4277.690429] agent flakelet-agent[304]: {"timestamp":"2026-09-06T08:15:55.652167Z","level":"INFO","message":"update finished","flakelet":"app","status":"Updated","generation":"Some(2)","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [ 4277.823543] agent flakelet-agent[304]: {"timestamp":"2026-09-06T08:15:55.785284Z","level":"INFO","message":"start","id":"1c39184043568d292bfd3e5893d98b5e","flakelet":"app","rule":"ci","relay":"relay","caller":"Some(\"oidc:mock:repo:github:example/app:ref:refs/heads/main\")","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> agent # [ 4277.823886] agent flakelet-agent[304]: {"timestamp":"2026-09-06T08:15:55.785659Z","level":"INFO","message":"update starting","flakelet":"app","ids":"[\"1c39184043568d292bfd3e5893d98b5e\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [ 4277.865259] agent systemd[1]: Starting flakelet update app (via relay)... container-test-run-flakelet-relay> agent # [ 4277.910109] agent systemd[1]: Started flakelet update app (via relay). container-test-run-flakelet-relay> agent # [ 4277.914334] agent flakelet[1060]: app: using prebuilt artifact /nix/store/hysydfr96ds875vr0jgw5rjxi4p39h2j-flakelet-app-v1 container-test-run-flakelet-relay> agent # [ 4277.914970] agent flakelet[1060]: app: activating generation 3 container-test-run-flakelet-relay> agent # [ 4277.929123] agent systemd[1]: Stopping app v2... container-test-run-flakelet-relay> agent # [ 4277.929608] agent systemd[1]: app.service: Deactivated successfully. container-test-run-flakelet-relay> agent # [ 4277.929875] agent systemd[1]: Stopped app v2. container-test-run-flakelet-relay> agent # [ 4277.948068] agent systemd[1]: Reload requested from client PID 1067 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [ 4277.948136] agent systemd[1]: Reloading... container-test-run-flakelet-relay> relay # [ 4277.691795] relay flakelet-relay[400]: {"timestamp":"2026-09-06T08:15:55.653552Z","level":"INFO","message":"deploy finished","job":"b08899e4f5d9448ad0e62beee8dfb372","ok":true,"target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> relay # [ 4277.823175] relay flakelet-relay[400]: {"timestamp":"2026-09-06T08:15:55.784948Z","level":"INFO","message":"deploy accepted","job":"4c9fe352143f106bd900e5e8de900986","caller":"oidc:mock:repo:github:example/app:ref:refs/heads/main","targets":"[\"agent/app\"]","target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> agent # [ 4278.286357] agent systemd[1]: Reloading finished in 337 ms. container-test-run-flakelet-relay> agent # [ 4278.350940] agent systemd[1]: Reload requested from client PID 1097 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [ 4278.350976] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [ 4278.676325] agent systemd[1]: Reloading finished in 324 ms. container-test-run-flakelet-relay> agent # [ 4278.748935] agent systemd[1]: Reload requested from client PID 1126 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [ 4278.748975] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [ 4279.074537] agent systemd[1]: Reloading finished in 325 ms. container-test-run-flakelet-relay> agent # [ 4279.144770] agent systemd[1]: Reload requested from client PID 1160 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [ 4279.144804] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [ 4279.467780] agent systemd[1]: Reloading finished in 322 ms. container-test-run-flakelet-relay> agent # [ 4279.536359] agent systemd[1]: Starting app v1... container-test-run-flakelet-relay> agent # [ 4279.578163] agent systemd[1]: Started app v1. container-test-run-flakelet-relay> agent # [ 4279.589462] agent flakelet[1060]: app: updated to generation 3 container-test-run-flakelet-relay> agent # [ 4279.591585] 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.eyJhdWQiOiJmbGFrZWxldC1yZWxheSIsImV4cCI6MTc4ODY4Mjg1NSwiZ3JvdXBzIjpbXSwiaWF0IjoxNzg4NjgyNTU1LCJpc3MiOiJodHRwczovL3JlbGF5Ojg0NDMiLCJqdGkiOiJFUFozWjJXWUk0Q1RRPT09IiwibmJmIjoxNzg4NjgyNTU1LCJzdWIiOiJyZXBvOmdpdGh1YjpleGFtcGxlL2FwcDpyZWY6cmVmcy9oZWFkcy9tYWluIn0.eU6P3Q7FueynD5Ujut-lwWLcvfOIJ7WLsoHfdlY1DJ8ZnEpuHcEk9GYk8dvB8iwMb8QBnOQHOzX-JhlZTfYpBA' push --relay https://relay:7443 deploy agent/app 2>&1, in 2.90 seconds) container-test-run-flakelet-relay> (finished: subtest: deploy with OIDC token, sub principal, in 2.97 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.eyJhdWQiOiJmbGFrZWxldC1yZWxheSIsImV4cCI6MTc4ODY4Mjg1NSwiZ3JvdXBzIjpbXSwiaWF0IjoxNzg4NjgyNTU1LCJpc3MiOiJodHRwczovL3JlbGF5Ojg0NDMiLCJqdGkiOiJFUFozWjJXWUk0Q1RRPT09IiwibmJmIjoxNzg4NjgyNTU1LCJzdWIiOiJyZXBvOmdpdGh1YjpleGFtcGxlL2FwcDpyZWY6cmVmcy9oZWFkcy9tYWluIn0.eU6P3Q7FueynD5Ujut-lwWLcvfOIJ7WLsoHfdlY1DJ8ZnEpuHcEk9GYk8dvB8iwMb8QBnOQHOzX-JhlZTfYpBA' 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.eyJhdWQiOiJmbGFrZWxldC1yZWxheSIsImV4cCI6MTc4ODY4Mjg1NSwiZ3JvdXBzIjpbXSwiaWF0IjoxNzg4NjgyNTU1LCJpc3MiOiJodHRwczovL3JlbGF5Ojg0NDMiLCJqdGkiOiJFUFozWjJXWUk0Q1RRPT09IiwibmJmIjoxNzg4NjgyNTU1LCJzdWIiOiJyZXBvOmdpdGh1YjpleGFtcGxlL2FwcDpyZWY6cmVmcy9oZWFkcy9tYWluIn0.eU6P3Q7FueynD5Ujut-lwWLcvfOIJ7WLsoHfdlY1DJ8ZnEpuHcEk9GYk8dvB8iwMb8QBnOQHOzX-JhlZTfYpBA' 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.05 seconds) container-test-run-flakelet-relay> client: must succeed: FLAKELET_RELAY_TOKEN_COMMAND='echo eyJhbGciOiJFZERTQSIsImtpZCI6ImsxIiwidHlwIjoiSldUIn0.eyJhdWQiOiJmbGFrZWxldC1yZWxheSIsImV4cCI6MTc4ODY4Mjg1OCwiZ3JvdXBzIjpbIndoZWVsIl0sImlhdCI6MTc4ODY4MjU1OCwiaXNzIjoiaHR0cHM6Ly9yZWxheTo4NDQzIiwianRpIjoiT1YyNllRRUdCUVNMUT09PSIsIm5iZiI6MTc4ODY4MjU1OCwic3ViIjoic29tZW9uZSJ9.i5FVG64l7WxjnSghL_f_U8B8MrvTWdGmI707t5p3ZKjGeQFuH1qPUnfYelGEBwPoGGDO12Qa4Ch5i9sf6EViCw' push --relay https://relay:7443 deploy agent/other 2>&1 container-test-run-flakelet-relay> agent # [ 4280.686107] agent flakelet-agent[304]: {"timestamp":"2026-09-06T08:15:58.647836Z","level":"INFO","message":"update finished","flakelet":"app","status":"Updated","generation":"Some(3)","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [ 4280.784962] agent flakelet-agent[304]: {"timestamp":"2026-09-06T08:15:58.746717Z","level":"INFO","message":"start","id":"57dfb8dca462d7707e252cbe917ec54c","flakelet":"other","rule":"admins","relay":"relay","caller":"Some(\"oidc:mock:groups:wheel\\noidc:mock:someone\")","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> agent # [ 4280.785309] agent flakelet-agent[304]: {"timestamp":"2026-09-06T08:15:58.747140Z","level":"INFO","message":"update starting","flakelet":"other","ids":"[\"57dfb8dca462d7707e252cbe917ec54c\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [ 4280.835065] agent systemd[1]: Starting flakelet update other (via relay)... container-test-run-flakelet-relay> agent # [ 4280.869914] agent systemd[1]: Started flakelet update other (via relay). container-test-run-flakelet-relay> agent # [ 4280.874258] agent flakelet[1226]: other: using prebuilt artifact /nix/store/hmr990n0b4v6rpzrcpyyw738r9xi7z95-flakelet-other-v1 container-test-run-flakelet-relay> agent # [ 4280.874766] agent flakelet[1226]: other: up to date container-test-run-flakelet-relay> agent # [ 4280.877998] agent systemd[1]: flakelet-relay-job-other.service: Deactivated successfully. container-test-run-flakelet-relay> relay # [ 4280.687217] relay flakelet-relay[400]: {"timestamp":"2026-09-06T08:15:58.648945Z","level":"INFO","message":"deploy finished","job":"4c9fe352143f106bd900e5e8de900986","ok":true,"target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> relay # [ 4280.784718] relay flakelet-relay[400]: {"timestamp":"2026-09-06T08:15:58.746482Z","level":"INFO","message":"deploy accepted","job":"c72b2526c5a009580f877a245aeaa6b7","caller":"oidc:mock:groups:wheel","targets":"[\"agent/other\"]","target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> client: (finished: must succeed: FLAKELET_RELAY_TOKEN_COMMAND='echo eyJhbGciOiJFZERTQSIsImtpZCI6ImsxIiwidHlwIjoiSldUIn0.eyJhdWQiOiJmbGFrZWxldC1yZWxheSIsImV4cCI6MTc4ODY4Mjg1OCwiZ3JvdXBzIjpbIndoZWVsIl0sImlhdCI6MTc4ODY4MjU1OCwiaXNzIjoiaHR0cHM6Ly9yZWxheTo4NDQzIiwianRpIjoiT1YyNllRRUdCUVNMUT09PSIsIm5iZiI6MTc4ODY4MjU1OCwic3ViIjoic29tZW9uZSJ9.i5FVG64l7WxjnSghL_f_U8B8MrvTWdGmI707t5p3ZKjGeQFuH1qPUnfYelGEBwPoGGDO12Qa4Ch5i9sf6EViCw' push --relay https://relay:7443 deploy agent/other 2>&1, in 0.67 seconds) container-test-run-flakelet-relay> (finished: subtest: claim principal from groups, unchanged counts as success, in 0.72 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 # [ 4281.430233] agent flakelet-agent[304]: {"timestamp":"2026-09-06T08:15:59.391960Z","level":"INFO","message":"update finished","flakelet":"other","status":"Unchanged","generation":"Some(1)","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> relay # [ 4281.431450] relay flakelet-relay[400]: {"timestamp":"2026-09-06T08:15:59.393201Z","level":"INFO","message":"deploy finished","job":"c72b2526c5a009580f877a245aeaa6b7","ok":true,"target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> relay # [ 4281.457803] relay 93sn3sv24fj26ksccpppzwg6kbf10s4w-device-mock[186]: 127.0.0.1 - - [06/Sep/2026 08:15:59] "POST /device HTTP/1.1" 200 - container-test-run-flakelet-relay> relay # [ 4282.463803] relay 93sn3sv24fj26ksccpppzwg6kbf10s4w-device-mock[186]: 127.0.0.1 - - [06/Sep/2026 08:16:00] "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> relay # [ 4283.513907] relay 93sn3sv24fj26ksccpppzwg6kbf10s4w-device-mock[186]: 127.0.0.1 - - [06/Sep/2026 08:16:01] "POST /token HTTP/1.1" 200 - container-test-run-flakelet-relay> relay # [ 4283.544224] relay flakelet-relay[400]: {"timestamp":"2026-09-06T08:16:01.506024Z","level":"INFO","message":"deploy accepted","job":"3846f68305ba7a8c5b66e0a9ffbe9e58","caller":"oidc:mock:groups:wheel","targets":"[\"agent/other\"]","target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> agent # [ 4283.544575] agent flakelet-agent[304]: {"timestamp":"2026-09-06T08:16:01.506319Z","level":"INFO","message":"start","id":"d0a18a8bb985692c8cb008a99a08b77c","flakelet":"other","rule":"admins","relay":"relay","caller":"Some(\"oidc:mock:groups:wheel\\noidc:mock:someone\")","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> agent # [ 4283.545302] agent flakelet-agent[304]: {"timestamp":"2026-09-06T08:16:01.507120Z","level":"INFO","message":"update starting","flakelet":"other","ids":"[\"d0a18a8bb985692c8cb008a99a08b77c\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [ 4283.596662] agent systemd[1]: Starting flakelet update other (via relay)... container-test-run-flakelet-relay> agent # [ 4283.628037] agent systemd[1]: Started flakelet update other (via relay). container-test-run-flakelet-relay> agent # [ 4283.632847] agent flakelet[1274]: other: using prebuilt artifact /nix/store/hmr990n0b4v6rpzrcpyyw738r9xi7z95-flakelet-other-v1 container-test-run-flakelet-relay> agent # [ 4283.633361] agent flakelet[1274]: other: up to date container-test-run-flakelet-relay> agent # [ 4283.636501] agent systemd[1]: flakelet-relay-job-other.service: Deactivated successfully. container-test-run-flakelet-relay> client: (finished: must succeed: push --relay https://relay:7443 deploy agent/other 2>&1, in 0.66 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 # [ 4284.187441] agent flakelet-agent[304]: {"timestamp":"2026-09-06T08:16:02.149182Z","level":"INFO","message":"update finished","flakelet":"other","status":"Unchanged","generation":"Some(1)","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [ 4284.209255] agent flakelet-agent[304]: {"timestamp":"2026-09-06T08:16:02.171068Z","level":"INFO","message":"start","id":"679f3221e08f81b35983e4943b6e1de4","flakelet":"other","rule":"admins","relay":"relay","caller":"Some(\"oidc:mock:groups:wheel\\noidc:mock:someone\")","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> agent # [ 4284.209470] agent flakelet-agent[304]: {"timestamp":"2026-09-06T08:16:02.171300Z","level":"INFO","message":"update starting","flakelet":"other","ids":"[\"679f3221e08f81b35983e4943b6e1de4\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [ 4284.268286] agent systemd[1]: Starting flakelet update other (via relay)... container-test-run-flakelet-relay> agent # [ 4284.303993] agent systemd[1]: Started flakelet update other (via relay). container-test-run-flakelet-relay> agent # [ 4284.308373] agent flakelet[1302]: other: using prebuilt artifact /nix/store/hmr990n0b4v6rpzrcpyyw738r9xi7z95-flakelet-other-v1 container-test-run-flakelet-relay> agent # [ 4284.308910] agent flakelet[1302]: other: up to date container-test-run-flakelet-relay> agent # [ 4284.310588] agent systemd[1]: flakelet-relay-job-other.service: Deactivated successfully. container-test-run-flakelet-relay> relay # [ 4284.188708] relay flakelet-relay[400]: {"timestamp":"2026-09-06T08:16:02.150456Z","level":"INFO","message":"deploy finished","job":"3846f68305ba7a8c5b66e0a9ffbe9e58","ok":true,"target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> relay # [ 4284.209066] relay flakelet-relay[400]: {"timestamp":"2026-09-06T08:16:02.170884Z","level":"INFO","message":"deploy accepted","job":"3fb621a90d1c4fe8d6c02115accb8284","caller":"oidc:mock:groups:wheel","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.67 seconds) container-test-run-flakelet-relay> (finished: subtest: push login via device flow, cached token used afterwards, in 3.43 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 # [ 4284.861167] agent flakelet-agent[304]: {"timestamp":"2026-09-06T08:16:02.822904Z","level":"INFO","message":"update finished","flakelet":"other","status":"Unchanged","generation":"Some(1)","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [ 4284.897668] agent flakelet-agent[304]: {"timestamp":"2026-09-06T08:16:02.859436Z","level":"INFO","message":"start","id":"60864decd9bfe1bb743beff185731eb3","flakelet":"app","rule":"admins","relay":"relay","caller":"Some(\"x509:email:admin@example.org\")","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> agent # [ 4284.897913] agent flakelet-agent[304]: {"timestamp":"2026-09-06T08:16:02.859690Z","level":"INFO","message":"update starting","flakelet":"app","ids":"[\"60864decd9bfe1bb743beff185731eb3\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [ 4284.957928] agent systemd[1]: Starting flakelet update app (via relay)... container-test-run-flakelet-relay> agent # [ 4285.004193] agent systemd[1]: Started flakelet update app (via relay). container-test-run-flakelet-relay> agent # [ 4285.008301] agent flakelet[1339]: app: using prebuilt artifact /nix/store/8282rnbvyk847q4l4zby3zwcq3jpzrvw-flakelet-app-broken container-test-run-flakelet-relay> agent # [ 4285.008956] agent flakelet[1339]: app: activating generation 4 container-test-run-flakelet-relay> agent # [ 4285.018688] agent systemd[1]: Stopping app v1... container-test-run-flakelet-relay> agent # [ 4285.019423] agent systemd[1]: app.service: Deactivated successfully. container-test-run-flakelet-relay> agent # [ 4285.019553] agent systemd[1]: Stopped app v1. container-test-run-flakelet-relay> agent # [ 4285.029235] agent systemd[1]: Reload requested from client PID 1350 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [ 4285.029270] agent systemd[1]: Reloading... container-test-run-flakelet-relay> relay # [ 4284.862234] relay flakelet-relay[400]: {"timestamp":"2026-09-06T08:16:02.824006Z","level":"INFO","message":"deploy finished","job":"3fb621a90d1c4fe8d6c02115accb8284","ok":true,"target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> relay # [ 4284.897491] relay flakelet-relay[400]: {"timestamp":"2026-09-06T08:16:02.859309Z","level":"INFO","message":"deploy accepted","job":"8419c3ef1200ac1a3b2918807efb1372","caller":"x509:email:admin@example.org","targets":"[\"agent/app\", \"agent/other\"]","target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> agent # [ 4285.355562] agent systemd[1]: Reloading finished in 325 ms. container-test-run-flakelet-relay> agent # [ 4285.428381] agent systemd[1]: Reload requested from client PID 1380 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [ 4285.428418] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [ 4285.753667] agent systemd[1]: Reloading finished in 324 ms. container-test-run-flakelet-relay> agent # [ 4285.821643] agent systemd[1]: Reload requested from client PID 1409 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [ 4285.821680] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [ 4286.146370] agent systemd[1]: Reloading finished in 324 ms. container-test-run-flakelet-relay> agent # [ 4286.210573] agent systemd[1]: Reload requested from client PID 1440 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [ 4286.210607] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [ 4286.537537] agent systemd[1]: Reloading finished in 326 ms. container-test-run-flakelet-relay> agent # [ 4286.599997] agent systemd[1]: Starting app broken... container-test-run-flakelet-relay> agent # [ 4286.635452] agent (nonexistent)[1470]: app.service: Unable to locate executable '/nonexistent': No such file or directory container-test-run-flakelet-relay> agent # [ 4286.635475] agent (nonexistent)[1470]: app.service: Failed at step EXEC spawning /nonexistent: No such file or directory container-test-run-flakelet-relay> agent # [ 4286.636856] agent systemd[1]: app.service: Main process exited, code=exited, status=203/EXEC container-test-run-flakelet-relay> agent # [ 4286.636962] agent systemd[1]: app.service: Failed with result 'exit-code'. container-test-run-flakelet-relay> agent # [ 4286.637332] agent systemd[1]: Failed to start app broken. container-test-run-flakelet-relay> agent # [ 4286.669082] agent systemd[1]: Reload requested from client PID 1477 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [ 4286.669119] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [ 4286.995429] agent systemd[1]: Reloading finished in 325 ms. container-test-run-flakelet-relay> agent # [ 4287.072509] agent systemd[1]: Reload requested from client PID 1510 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [ 4287.072541] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [ 4287.397221] agent systemd[1]: Reloading finished in 324 ms. container-test-run-flakelet-relay> agent # [ 4287.457311] agent systemd[1]: Reload requested from client PID 1541 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [ 4287.457342] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [ 4287.784621] agent systemd[1]: Reloading finished in 326 ms. container-test-run-flakelet-relay> agent # [ 4287.870345] agent systemd[1]: Reload requested from client PID 1573 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [ 4287.870381] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [ 4288.198166] agent systemd[1]: Reloading finished in 327 ms. container-test-run-flakelet-relay> agent # [ 4288.262729] agent systemd[1]: Starting app v1... container-test-run-flakelet-relay> agent # [ 4288.288954] agent systemd[1]: Started app v1. container-test-run-flakelet-relay> agent # [ 4288.290067] agent flakelet[1339]: app: deploy failed, rolled back: systemctl start app.service failed: container-test-run-flakelet-relay> agent # [ 4288.290067] agent flakelet[1339]: failed units: app.service. See journalctl -u app.service container-test-run-flakelet-relay> agent # [ 4288.292140] agent systemd[1]: flakelet-relay-job-app.service: Main process exited, code=exited, status=1/FAILURE container-test-run-flakelet-relay> agent # [ 4288.292233] 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.55 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.58 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 # [ 4289.421899] agent flakelet-agent[304]: {"timestamp":"2026-09-06T08:16:07.383644Z","level":"INFO","message":"update finished","flakelet":"app","status":"RolledBack","generation":"Some(3)","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [ 4289.478271] agent flakelet-agent[304]: {"timestamp":"2026-09-06T08:16:07.440025Z","level":"INFO","message":"start","id":"f1b32e63c63b348f6bcaa4dd85b44f1b","flakelet":"app","rule":"admins","relay":"relay","caller":"Some(\"x509:email:admin@example.org\")","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> agent # [ 4289.478711] agent flakelet-agent[304]: {"timestamp":"2026-09-06T08:16:07.440531Z","level":"INFO","message":"update starting","flakelet":"app","ids":"[\"f1b32e63c63b348f6bcaa4dd85b44f1b\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [ 4289.529511] agent systemd[1]: Starting flakelet update app (via relay)... container-test-run-flakelet-relay> agent # [ 4289.561021] agent systemd[1]: Started flakelet update app (via relay). container-test-run-flakelet-relay> agent # [ 4289.565340] agent flakelet[1651]: app: using prebuilt artifact /nix/store/hysydfr96ds875vr0jgw5rjxi4p39h2j-flakelet-app-v1 container-test-run-flakelet-relay> agent # [ 4289.565820] agent flakelet[1651]: app: up to date container-test-run-flakelet-relay> agent # [ 4289.568937] agent systemd[1]: flakelet-relay-job-app.service: Deactivated successfully. container-test-run-flakelet-relay> relay # [ 4289.423477] relay flakelet-relay[400]: {"timestamp":"2026-09-06T08:16:07.385244Z","level":"INFO","message":"deploy finished","job":"8419c3ef1200ac1a3b2918807efb1372","ok":false,"target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> relay # [ 4289.477893] relay flakelet-relay[400]: {"timestamp":"2026-09-06T08:16:07.439686Z","level":"INFO","message":"deploy accepted","job":"54e64c0c23a29bd77f9ed013b2ca24de","caller":"x509:email: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.67 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 # [ 4290.121774] agent flakelet-agent[304]: {"timestamp":"2026-09-06T08:16:08.083507Z","level":"INFO","message":"update finished","flakelet":"app","status":"Unchanged","generation":"Some(3)","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [ 4290.144718] agent flakelet-agent[304]: {"timestamp":"2026-09-06T08:16:08.106518Z","level":"INFO","message":"start","id":"59ff063568ac4f65946781e56e050ce3","flakelet":"app","rule":"admins","relay":"relay","caller":"Some(\"x509:email:admin@example.org\")","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> agent # [ 4290.145117] agent flakelet-agent[304]: {"timestamp":"2026-09-06T08:16:08.106937Z","level":"INFO","message":"update starting","flakelet":"app","ids":"[\"59ff063568ac4f65946781e56e050ce3\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [ 4290.202398] agent systemd[1]: Starting flakelet update app (via relay)... container-test-run-flakelet-relay> agent # [ 4290.235007] agent systemd[1]: Started flakelet update app (via relay). container-test-run-flakelet-relay> agent # [ 4290.239365] agent flakelet[1679]: app: using prebuilt artifact /nix/store/hysydfr96ds875vr0jgw5rjxi4p39h2j-flakelet-app-v1 container-test-run-flakelet-relay> agent # [ 4290.239923] agent flakelet[1679]: app: up to date container-test-run-flakelet-relay> agent # [ 4290.242509] agent systemd[1]: flakelet-relay-job-app.service: Deactivated successfully. container-test-run-flakelet-relay> relay # [ 4290.123259] relay flakelet-relay[400]: {"timestamp":"2026-09-06T08:16:08.085025Z","level":"INFO","message":"deploy finished","job":"54e64c0c23a29bd77f9ed013b2ca24de","ok":true,"target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> relay # [ 4290.144480] relay flakelet-relay[400]: {"timestamp":"2026-09-06T08:16:08.106300Z","level":"INFO","message":"deploy accepted","job":"beb907435b71cafa934c3fbf2377abc0","caller":"x509:email: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.67 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 # [ 4290.791811] agent flakelet-agent[304]: {"timestamp":"2026-09-06T08:16:08.753551Z","level":"INFO","message":"update finished","flakelet":"app","status":"Unchanged","generation":"Some(3)","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [ 4290.815252] agent flakelet-agent[304]: {"timestamp":"2026-09-06T08:16:08.777055Z","level":"INFO","message":"start","id":"a2fdbb9d6d5be999afc01028db739d51","flakelet":"app","rule":"admins","relay":"relay","caller":"Some(\"x509:email:admin@example.org\")","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> agent # [ 4290.815635] agent flakelet-agent[304]: {"timestamp":"2026-09-06T08:16:08.777456Z","level":"INFO","message":"update starting","flakelet":"app","ids":"[\"a2fdbb9d6d5be999afc01028db739d51\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [ 4290.876208] agent systemd[1]: Starting flakelet update app (via relay)... container-test-run-flakelet-relay> agent # [ 4290.906953] agent systemd[1]: Started flakelet update app (via relay). container-test-run-flakelet-relay> agent # [ 4290.911245] agent flakelet[1707]: app: using prebuilt artifact /nix/store/hysydfr96ds875vr0jgw5rjxi4p39h2j-flakelet-app-v1 container-test-run-flakelet-relay> agent # [ 4290.911734] agent flakelet[1707]: app: up to date container-test-run-flakelet-relay> agent # [ 4290.914181] agent systemd[1]: flakelet-relay-job-app.service: Deactivated successfully. container-test-run-flakelet-relay> relay # [ 4290.793271] relay flakelet-relay[400]: {"timestamp":"2026-09-06T08:16:08.755048Z","level":"INFO","message":"deploy finished","job":"beb907435b71cafa934c3fbf2377abc0","ok":false,"target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> relay # [ 4290.815041] relay flakelet-relay[400]: {"timestamp":"2026-09-06T08:16:08.776856Z","level":"INFO","message":"deploy accepted","job":"d4e529abb3e862d24c8b30e1f7400bc7","caller":"x509:email: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.67 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 2.04 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 # [ 4291.464705] agent flakelet-agent[304]: {"timestamp":"2026-09-06T08:16:09.426445Z","level":"INFO","message":"update finished","flakelet":"app","status":"Unchanged","generation":"Some(3)","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [ 4291.521924] agent flakelet-agent[304]: {"timestamp":"2026-09-06T08:16:09.483686Z","level":"INFO","message":"start","id":"cafe9eb7933849ac787c87a8e3aa3227","flakelet":"app","rule":"admins","relay":"relay","caller":"Some(\"x509:email:admin@example.org\")","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> agent # [ 4291.522385] agent flakelet-agent[304]: {"timestamp":"2026-09-06T08:16:09.484204Z","level":"INFO","message":"update starting","flakelet":"app","ids":"[\"cafe9eb7933849ac787c87a8e3aa3227\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [ 4291.571039] agent systemd[1]: Starting flakelet update app (via relay)... container-test-run-flakelet-relay> agent # [ 4291.599916] agent systemd[1]: Started flakelet update app (via relay). container-test-run-flakelet-relay> agent # [ 4291.604208] agent flakelet[1749]: app: using prebuilt artifact /nix/store/ln47rsd5km8a04hd5zlkz4q1v352zqh3-flakelet-app-slow2 container-test-run-flakelet-relay> agent # [ 4291.604864] agent flakelet[1749]: app: activating generation 4 container-test-run-flakelet-relay> agent # [ 4291.618338] agent systemd[1]: Stopping app v1... container-test-run-flakelet-relay> agent # [ 4291.618784] agent systemd[1]: app.service: Deactivated successfully. container-test-run-flakelet-relay> agent # [ 4291.619057] agent systemd[1]: Stopped app v1. container-test-run-flakelet-relay> agent # [ 4291.637189] agent systemd[1]: Reload requested from client PID 1756 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [ 4291.637257] agent systemd[1]: Reloading... container-test-run-flakelet-relay> relay # [ 4291.466311] relay flakelet-relay[400]: {"timestamp":"2026-09-06T08:16:09.428079Z","level":"INFO","message":"deploy finished","job":"d4e529abb3e862d24c8b30e1f7400bc7","ok":false,"target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> relay # [ 4291.521638] relay flakelet-relay[400]: {"timestamp":"2026-09-06T08:16:09.483434Z","level":"INFO","message":"deploy accepted","job":"54bf4db2326752f10b4c3da9a1be148e","caller":"x509:email:admin@example.org","targets":"[\"agent/app\"]","target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> agent # [ 4291.970935] agent systemd[1]: Reloading finished in 332 ms. container-test-run-flakelet-relay> agent # [ 4292.042489] agent systemd[1]: Reload requested from client PID 1786 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [ 4292.042526] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [ 4292.367840] agent systemd[1]: Reloading finished in 324 ms. container-test-run-flakelet-relay> agent # [ 4292.431553] agent systemd[1]: Reload requested from client PID 1815 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [ 4292.431591] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent: (finished: waiting for success: systemctl is-active flakelet-relay-job-app.service, in 1.29 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 # [ 4292.756463] agent systemd[1]: Reloading finished in 324 ms. container-test-run-flakelet-relay> agent # [ 4292.820893] agent systemd[1]: Reload requested from client PID 1848 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [ 4292.820928] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [ 4293.145495] agent systemd[1]: Reloading finished in 324 ms. container-test-run-flakelet-relay> agent # [ 4293.207787] agent systemd[1]: Starting app slow2... container-test-run-flakelet-relay> agent # [ 4299.285035] agent systemd[1]: Started app slow2. container-test-run-flakelet-relay> agent # [ 4299.302953] agent flakelet[1749]: app: updated to generation 4 container-test-run-flakelet-relay> agent # [ 4299.306097] 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.81 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.04 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 # [ 4300.332911] agent systemd[1]: Stopping flakelet-agent... container-test-run-flakelet-relay> agent # [ 4300.361332] agent systemd[1]: flakelet-agent.service: Deactivated successfully. container-test-run-flakelet-relay> agent # [ 4300.361777] agent systemd[1]: Stopped flakelet-agent. container-test-run-flakelet-relay> agent # [ 4300.362230] agent systemd[1]: flakelet-agent.service: Consumed 2.572s CPU time over 31.510s wall clock time. container-test-run-flakelet-relay> relay # [ 4300.332932] relay flakelet-relay[400]: {"timestamp":"2026-09-06T08:16:18.294715Z","level":"INFO","message":"agent disconnected: websocket closed","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.15 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 # [ 4301.423397] agent systemd[1]: Starting flakelet-agent... container-test-run-flakelet-relay> agent # [ 4301.475367] agent flakelet-agent[1942]: {"timestamp":"2026-09-06T08:16:19.437142Z","level":"INFO","message":"reattaching to running unit","flakelet":"app","ids":"[\"cafe9eb7933849ac787c87a8e3aa3227\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [ 4301.556960] agent systemd[1]: Started flakelet-agent. container-test-run-flakelet-relay> agent # [ 4301.570257] agent flakelet-agent[1942]: {"timestamp":"2026-09-06T08:16:19.532048Z","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 # [ 4301.570325] relay flakelet-relay[400]: {"timestamp":"2026-09-06T08:16:19.532090Z","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 # [ 4302.036750] agent flakelet-agent[1942]: {"timestamp":"2026-09-06T08:16:19.998470Z","level":"INFO","message":"update finished","flakelet":"app","status":"Updated","generation":"Some(4)","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [ 4303.406426] agent flakelet-agent[1942]: {"timestamp":"2026-09-06T08:16:21.368154Z","level":"INFO","message":"start","id":"9a950a093acee1c7eca217400075cae1","flakelet":"other","rule":"admins","relay":"relay","caller":"Some(\"x509:email:admin@example.org\")","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> agent # [ 4303.407376] agent flakelet-agent[1942]: {"timestamp":"2026-09-06T08:16:21.368812Z","level":"INFO","message":"update starting","flakelet":"other","ids":"[\"9a950a093acee1c7eca217400075cae1\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [ 4303.461664] agent systemd[1]: Starting flakelet update other (via relay)... container-test-run-flakelet-relay> agent # [ 4303.495045] agent systemd[1]: Started flakelet update other (via relay). container-test-run-flakelet-relay> agent # [ 4303.501049] agent flakelet[2375]: other: using prebuilt artifact /nix/store/hmr990n0b4v6rpzrcpyyw738r9xi7z95-flakelet-other-v1 container-test-run-flakelet-relay> agent # [ 4303.501594] agent flakelet[2375]: other: up to date container-test-run-flakelet-relay> agent # [ 4303.502320] agent systemd[1]: flakelet-relay-job-other.service: Deactivated successfully. container-test-run-flakelet-relay> relay # [ 4303.405965] relay flakelet-relay[400]: {"timestamp":"2026-09-06T08:16:21.367730Z","level":"INFO","message":"deploy accepted","job":"91cdeb537d902f2bbfda54be7f1ee2de","caller":"x509:email:admin@example.org","targets":"[\"agent/other\"]","target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> agent # [ 4304.055200] agent flakelet-agent[1942]: {"timestamp":"2026-09-06T08:16:22.016933Z","level":"INFO","message":"update finished","flakelet":"other","status":"Unchanged","generation":"Some(1)","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> relay # [ 4304.056677] relay flakelet-relay[400]: {"timestamp":"2026-09-06T08:16:22.018445Z","level":"INFO","message":"deploy finished","job":"91cdeb537d902f2bbfda54be7f1ee2de","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.05 seconds) container-test-run-flakelet-relay> client: must succeed: FLAKELET_RELAY_TOKEN_COMMAND='echo eyJhbGciOiJFZERTQSIsImtpZCI6ImsxIiwidHlwIjoiSldUIn0.eyJhdWQiOiJmbGFrZWxldC1yZWxheSIsImV4cCI6MTc4ODY4Mjg4MiwiZ3JvdXBzIjpbXSwiaWF0IjoxNzg4NjgyNTgyLCJpc3MiOiJodHRwczovL3JlbGF5Ojg0NDMiLCJqdGkiOiJSNTRGUFpWUUhNTlNHPT09IiwibmJmIjoxNzg4NjgyNTgyLCJzdWIiOiJyZXBvOmdpdGh1YjpleGFtcGxlL2FwcDpyZWY6cmVmcy9oZWFkcy9tYWluIn0.Iq3sKhHaBl7eLGLznpjPigr9cVJ0rexH0GsOepbDjdaEhadAE1AhgnFtlsKAIEW4uKWo867afXQc6ZpWPKlLDA' push --relay https://relay:7443 agents container-test-run-flakelet-relay> client: (finished: must succeed: FLAKELET_RELAY_TOKEN_COMMAND='echo eyJhbGciOiJFZERTQSIsImtpZCI6ImsxIiwidHlwIjoiSldUIn0.eyJhdWQiOiJmbGFrZWxldC1yZWxheSIsImV4cCI6MTc4ODY4Mjg4MiwiZ3JvdXBzIjpbXSwiaWF0IjoxNzg4NjgyNTgyLCJpc3MiOiJodHRwczovL3JlbGF5Ojg0NDMiLCJqdGkiOiJSNTRGUFpWUUhNTlNHPT09IiwibmJmIjoxNzg4NjgyNTgyLCJzdWIiOiJyZXBvOmdpdGh1YjpleGFtcGxlL2FwcDpyZWY6cmVmcy9oZWFkcy9tYWluIn0.Iq3sKhHaBl7eLGLznpjPigr9cVJ0rexH0GsOepbDjdaEhadAE1AhgnFtlsKAIEW4uKWo867afXQc6ZpWPKlLDA' 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.40 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.05 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 # [ 4304.792147] agent systemd[1]: Stopping app slow2... container-test-run-flakelet-relay> agent # [ 4304.792681] agent systemd[1]: app.service: Deactivated successfully. container-test-run-flakelet-relay> agent # [ 4304.792952] agent systemd[1]: Stopped app slow2. container-test-run-flakelet-relay> agent # [ 4304.810191] agent systemd[1]: Reload requested from client PID 2423 ('systemctl')... container-test-run-flakelet-relay> agent # [ 4304.810259] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [ 4305.157074] agent systemd[1]: Reloading finished in 346 ms. container-test-run-flakelet-relay> agent # [ 4305.244680] agent systemd[1]: Reload requested from client PID 2452 ('systemctl')... container-test-run-flakelet-relay> agent # [ 4305.244719] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [ 4305.572999] agent systemd[1]: Reloading finished in 327 ms. container-test-run-flakelet-relay> agent # [ 4305.672977] agent systemd[1]: Reload requested from client PID 2482 ('systemctl')... container-test-run-flakelet-relay> agent # [ 4305.673029] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [ 4305.997803] agent systemd[1]: Reloading finished in 324 ms. container-test-run-flakelet-relay> agent # [ 4306.075944] agent systemd[1]: Reload requested from client PID 2514 ('systemctl')... container-test-run-flakelet-relay> agent # [ 4306.075976] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [ 4306.402060] agent systemd[1]: Reloading finished in 325 ms. container-test-run-flakelet-relay> agent: (finished: must succeed: flakelet update app, in 1.77 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 # [ 4306.488699] agent systemd[1]: Starting app v2... container-test-run-flakelet-relay> agent # [ 4306.518999] agent systemd[1]: Started app v2. 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.06 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/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 # [ 4308.648174] agent systemd[1]: Stopping app v2... container-test-run-flakelet-relay> agent # [ 4308.648676] agent systemd[1]: app.service: Deactivated successfully. container-test-run-flakelet-relay> agent # [ 4308.648941] agent systemd[1]: Stopped app v2. container-test-run-flakelet-relay> agent # [ 4308.666170] agent systemd[1]: Reload requested from client PID 2572 ('systemctl')... container-test-run-flakelet-relay> agent # [ 4308.666238] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [ 4309.011072] agent systemd[1]: Reloading finished in 344 ms. container-test-run-flakelet-relay> agent # [ 4309.082523] agent systemd[1]: Reload requested from client PID 2601 ('systemctl')... container-test-run-flakelet-relay> agent # [ 4309.082560] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [ 4309.406690] agent systemd[1]: Reloading finished in 323 ms. container-test-run-flakelet-relay> agent # [ 4309.474463] agent systemd[1]: Reload requested from client PID 2629 ('systemctl')... container-test-run-flakelet-relay> agent # [ 4309.474501] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [ 4309.796919] agent systemd[1]: Reloading finished in 321 ms. container-test-run-flakelet-relay> agent # [ 4309.876130] agent systemd[1]: Reload requested from client PID 2661 ('systemctl')... container-test-run-flakelet-relay> agent # [ 4309.876168] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [ 4310.200994] agent systemd[1]: Reloading finished in 324 ms. container-test-run-flakelet-relay> agent: (finished: must succeed: flakelet update app, in 1.70 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@6' container-test-run-flakelet-relay> agent # [ 4310.272799] agent systemd[1]: Starting app v1... container-test-run-flakelet-relay> agent # [ 4310.311188] agent systemd[1]: Started app v1. 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@6', in 2.06 seconds) container-test-run-flakelet-relay> (finished: subtest: out-of-band flakelet update reaches the relay without reconnect, in 7.69 seconds) container-test-run-flakelet-relay> subtest: certificate not listed under agents cannot connect as agent container-test-run-flakelet-relay> client: must succeed: curl -s -o /dev/null -w '%{http_code}' --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem --cert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/mallory/cert.pem --key /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/mallory/key.pem -H 'Upgrade: websocket' -H 'Connection: Upgrade' -H 'Sec-WebSocket-Version: 13' -H 'Sec-WebSocket-Key: dGhlIHNhbXBsZSBub25jZQ==' https://relay:7443/v1/agent container-test-run-flakelet-relay> client: (finished: must succeed: curl -s -o /dev/null -w '%{http_code}' --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem --cert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/mallory/cert.pem --key /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/mallory/key.pem -H 'Upgrade: websocket' -H 'Connection: Upgrade' -H 'Sec-WebSocket-Version: 13' -H 'Sec-WebSocket-Key: dGhlIHNhbXBsZSBub25jZQ==' https://relay:7443/v1/agent, in 0.02 seconds) container-test-run-flakelet-relay> (finished: subtest: certificate not listed under agents cannot connect as agent, in 0.02 seconds) container-test-run-flakelet-relay> subtest: metrics container-test-run-flakelet-relay> relay: must succeed: curl -sf http://127.0.0.1:7400/metrics container-test-run-flakelet-relay> relay: (finished: must succeed: curl -sf http://127.0.0.1:7400/metrics, in 0.01 seconds) container-test-run-flakelet-relay> (finished: subtest: metrics, in 0.01 seconds) container-test-run-flakelet-relay> subtest: agent link drop mid-run replays missed lines once container-test-run-flakelet-relay> agent: must succeed: jq '.services.app.prebuilt="/nix/store/c67jj8kx3m50nyj874aqmdqf21w5iznd-flakelet-app-slow"' /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/c67jj8kx3m50nyj874aqmdqf21w5iznd-flakelet-app-slow"' /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: date +%s container-test-run-flakelet-relay> agent: (finished: must succeed: date +%s, 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 agent/app > /tmp/slow.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/app > /tmp/slow.out 2>&1 &, in 0.01 seconds) container-test-run-flakelet-relay> agent: waiting for success: journalctl -u flakelet-relay-job-app.service --since=@1788682590 | grep -q 'app-slow' container-test-run-flakelet-relay> agent # [ 4312.467100] agent flakelet-agent[1942]: {"timestamp":"2026-09-06T08:16:30.428813Z","level":"INFO","message":"start","id":"b21bacf6c84543d38b3547c2e8da39c8","flakelet":"app","rule":"admins","relay":"relay","caller":"Some(\"x509:email:admin@example.org\")","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> agent # [ 4312.467938] agent flakelet-agent[1942]: {"timestamp":"2026-09-06T08:16:30.429553Z","level":"INFO","message":"update starting","flakelet":"app","ids":"[\"b21bacf6c84543d38b3547c2e8da39c8\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [ 4312.523642] agent systemd[1]: Starting flakelet update app (via relay)... container-test-run-flakelet-relay> agent # [ 4312.656465] agent systemd[1]: Started flakelet update app (via relay). container-test-run-flakelet-relay> agent # [ 4312.661740] agent flakelet[2725]: app: using prebuilt artifact /nix/store/c67jj8kx3m50nyj874aqmdqf21w5iznd-flakelet-app-slow container-test-run-flakelet-relay> agent # [ 4312.662797] agent flakelet[2725]: app: activating generation 7 container-test-run-flakelet-relay> agent # [ 4312.676055] agent systemd[1]: Stopping app v1... container-test-run-flakelet-relay> agent # [ 4312.676509] agent systemd[1]: app.service: Deactivated successfully. container-test-run-flakelet-relay> agent # [ 4312.676774] agent systemd[1]: Stopped app v1. container-test-run-flakelet-relay> agent # [ 4312.693137] agent systemd[1]: Reload requested from client PID 2732 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [ 4312.693201] agent systemd[1]: Reloading... container-test-run-flakelet-relay> relay # [ 4312.409581] relay flakelet-relay[400]: {"timestamp":"2026-09-06T08:16:30.371345Z","level":"INFO","message":"agent rejected: no agents entry matches","principals":"[\"x509:dns:mallory\"]","target":"flakelet_relay::relay::agent_conn"} container-test-run-flakelet-relay> relay # [ 4312.466669] relay flakelet-relay[400]: {"timestamp":"2026-09-06T08:16:30.428477Z","level":"INFO","message":"deploy accepted","job":"f3469a74f8927189fefb099655270820","caller":"x509:email:admin@example.org","targets":"[\"agent/app\"]","target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> agent # [ 4313.033255] agent systemd[1]: Reloading finished in 339 ms. container-test-run-flakelet-relay> agent: (finished: waiting for success: journalctl -u flakelet-relay-job-app.service --since=@1788682590 | grep -q 'app-slow', in 1.02 seconds) container-test-run-flakelet-relay> agent: must succeed: for a in $(getent ahosts relay | cut -d' ' -f1 | sort -u); do ip route add unreachable $a; done container-test-run-flakelet-relay> agent: (finished: must succeed: for a in $(getent ahosts relay | cut -d' ' -f1 | sort -u); do ip route add unreachable $a; done, in 0.02 seconds) container-test-run-flakelet-relay> relay: must succeed: for a in $(getent ahosts agent | cut -d' ' -f1 | sort -u); do ss -K dst "[$a]"; done container-test-run-flakelet-relay> relay: (finished: must succeed: for a in $(getent ahosts agent | cut -d' ' -f1 | sort -u); do ss -K dst "[$a]"; done, in 0.05 seconds) container-test-run-flakelet-relay> agent: must succeed: ss -K dport = 7443 container-test-run-flakelet-relay> agent: (finished: must succeed: ss -K dport = 7443, in 0.03 seconds) container-test-run-flakelet-relay> agent: waiting for success: journalctl -u flakelet-relay-job-app.service --since=@1788682590 | grep -q 'updated to generation' container-test-run-flakelet-relay> agent # [ 4313.393152] agent systemd[1]: Reload requested from client PID 2762 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [ 4313.393194] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [ 4313.581895] agent flakelet-agent[1942]: {"timestamp":"2026-09-06T08:16:31.543621Z","level":"INFO","message":"disconnected: Software caused connection abort (os error 103)","relay":"https://relay:7443","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> relay # [ 4313.548842] relay flakelet-relay[400]: {"timestamp":"2026-09-06T08:16:31.510585Z","level":"INFO","message":"agent disconnected: Software caused connection abort (os error 103)","host":"agent","target":"flakelet_relay::relay::agent_conn"} container-test-run-flakelet-relay> agent # [ 4313.884646] agent systemd[1]: Reloading finished in 491 ms. container-test-run-flakelet-relay> agent # [ 4313.960977] agent systemd[1]: Reload requested from client PID 2808 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [ 4313.961035] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [ 4314.286509] agent systemd[1]: Reloading finished in 325 ms. container-test-run-flakelet-relay> agent # [ 4314.405909] agent systemd[1]: Reload requested from client PID 2841 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [ 4314.405945] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [ 4314.584905] agent flakelet-agent[1942]: {"timestamp":"2026-09-06T08:16:32.546475Z","level":"WARN","message":"connect failed: connect relay:7443: No route to host (os error 113)","relay":"https://relay:7443","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> agent # [ 4314.737292] agent systemd[1]: Reloading finished in 330 ms. container-test-run-flakelet-relay> agent # [ 4314.820191] agent systemd[1]: Starting app slow... container-test-run-flakelet-relay> agent # [ 4316.587400] agent flakelet-agent[1942]: {"timestamp":"2026-09-06T08:16:34.549117Z","level":"WARN","message":"connect failed: connect relay:7443: No route to host (os error 113)","relay":"https://relay:7443","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> agent # [ 4320.589391] agent flakelet-agent[1942]: {"timestamp":"2026-09-06T08:16:38.551036Z","level":"WARN","message":"connect failed: connect relay:7443: No route to host (os error 113)","relay":"https://relay:7443","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> agent # [ 4320.889861] agent systemd[1]: Started app slow. container-test-run-flakelet-relay> agent # [ 4320.907599] agent flakelet[2725]: app: updated to generation 7 container-test-run-flakelet-relay> agent # [ 4320.910667] agent systemd[1]: flakelet-relay-job-app.service: Deactivated successfully. container-test-run-flakelet-relay> agent: (finished: waiting for success: journalctl -u flakelet-relay-job-app.service --since=@1788682590 | grep -q 'updated to generation', in 8.12 seconds) container-test-run-flakelet-relay> agent: must succeed: for a in $(getent ahosts relay | cut -d' ' -f1 | sort -u); do ip route del unreachable $a; done container-test-run-flakelet-relay> agent: (finished: must succeed: for a in $(getent ahosts relay | cut -d' ' -f1 | sort -u); do ip route del unreachable $a; done, in 0.02 seconds) container-test-run-flakelet-relay> client: waiting for success: grep -q 'agent/app: updated' /tmp/slow.out container-test-run-flakelet-relay> agent # [ 4321.548254] agent flakelet-agent[1942]: {"timestamp":"2026-09-06T08:16:39.509966Z","level":"INFO","message":"update finished","flakelet":"app","status":"Updated","generation":"Some(7)","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> client: (finished: waiting for success: grep -q 'agent/app: updated' /tmp/slow.out, in 7.08 seconds) container-test-run-flakelet-relay> client: must succeed: cat /tmp/slow.out container-test-run-flakelet-relay> client: (finished: must succeed: cat /tmp/slow.out, in 0.01 seconds) container-test-run-flakelet-relay> (finished: subtest: agent link drop mid-run replays missed lines once, in 16.38 seconds) container-test-run-flakelet-relay> subtest: agent restart mid-run reattaches to the unit 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: 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 > /tmp/restart.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/app > /tmp/restart.out 2>&1 &, in 0.01 seconds) container-test-run-flakelet-relay> agent: waiting for success: journalctl -u flakelet-relay-job-app.service --since -20s | grep -q 'app-slow2' container-test-run-flakelet-relay> relay # [ 4328.595391] relay flakelet-relay[400]: {"timestamp":"2026-09-06T08:16:46.557075Z","level":"INFO","message":"agent connected","host":"agent","version":"0.1.0","flakelets":"[Named { name: \"app\", generation: Some(7), 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 # [ 4328.595281] agent flakelet-agent[1942]: {"timestamp":"2026-09-06T08:16:46.557000Z","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 # [ 4328.596802] relay flakelet-relay[400]: {"timestamp":"2026-09-06T08:16:46.558623Z","level":"INFO","message":"deploy finished","job":"f3469a74f8927189fefb099655270820","ok":true,"target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> agent # [ 4328.596434] agent flakelet-agent[1942]: {"timestamp":"2026-09-06T08:16:46.558251Z","level":"INFO","message":"start","id":"b21bacf6c84543d38b3547c2e8da39c8","flakelet":"app","rule":"admins","relay":"relay","caller":"Some(\"x509:email:admin@example.org\")","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> relay # [ 4328.846747] relay flakelet-relay[400]: {"timestamp":"2026-09-06T08:16:46.808343Z","level":"INFO","message":"deploy accepted","job":"0ddb9ea36fecb7d694381f79aeaf2b0e","caller":"x509:email:admin@example.org","targets":"[\"agent/app\"]","target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> agent # [ 4328.847131] agent flakelet-agent[1942]: {"timestamp":"2026-09-06T08:16:46.808668Z","level":"INFO","message":"start","id":"e5ae731e6705b9b81318c5da1e1a833c","flakelet":"app","rule":"admins","relay":"relay","caller":"Some(\"x509:email:admin@example.org\")","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> agent # [ 4328.847502] agent flakelet-agent[1942]: {"timestamp":"2026-09-06T08:16:46.809320Z","level":"INFO","message":"update starting","flakelet":"app","ids":"[\"e5ae731e6705b9b81318c5da1e1a833c\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [ 4328.902266] agent systemd[1]: Starting flakelet update app (via relay)... container-test-run-flakelet-relay> agent # [ 4328.935993] agent systemd[1]: Started flakelet update app (via relay). container-test-run-flakelet-relay> agent # [ 4328.941244] agent flakelet[3021]: app: using prebuilt artifact /nix/store/ln47rsd5km8a04hd5zlkz4q1v352zqh3-flakelet-app-slow2 container-test-run-flakelet-relay> agent # [ 4328.941873] agent flakelet[3021]: app: activating generation 8 container-test-run-flakelet-relay> agent # [ 4328.954753] agent systemd[1]: Stopping app slow... container-test-run-flakelet-relay> agent # [ 4328.956555] agent systemd[1]: app.service: Deactivated successfully. container-test-run-flakelet-relay> agent # [ 4328.956866] agent systemd[1]: Stopped app slow. container-test-run-flakelet-relay> agent # [ 4328.971824] agent systemd[1]: Reload requested from client PID 3028 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [ 4328.971890] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [ 4329.312712] agent systemd[1]: Reloading finished in 340 ms. container-test-run-flakelet-relay> agent # [ 4329.383755] agent systemd[1]: Reload requested from client PID 3058 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [ 4329.383791] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [ 4329.712869] agent systemd[1]: Reloading finished in 328 ms. container-test-run-flakelet-relay> agent: (finished: waiting for success: journalctl -u flakelet-relay-job-app.service --since -20s | grep -q 'app-slow2', in 1.03 seconds) container-test-run-flakelet-relay> agent: must succeed: systemctl restart flakelet-agent container-test-run-flakelet-relay> agent # [ 4329.778184] agent systemd[1]: Reload requested from client PID 3089 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [ 4329.778218] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [ 4330.112241] agent systemd[1]: Reloading finished in 333 ms. container-test-run-flakelet-relay> agent # [ 4330.189481] agent systemd[1]: Stopping flakelet-agent... container-test-run-flakelet-relay> agent # [ 4330.196049] agent systemd[1]: Reload requested from client PID 3126 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [ 4330.196091] agent systemd[1]: Reloading... container-test-run-flakelet-relay> relay # [ 4330.189811] relay flakelet-relay[400]: {"timestamp":"2026-09-06T08:16:48.151552Z","level":"INFO","message":"agent disconnected: websocket closed","host":"agent","target":"flakelet_relay::relay::agent_conn"} container-test-run-flakelet-relay> agent: (finished: must succeed: systemctl restart flakelet-agent, in 0.86 seconds) container-test-run-flakelet-relay> agent: must succeed: test -n "$(ls /var/lib/flakelet-agent/jobs)" container-test-run-flakelet-relay> agent: (finished: must succeed: test -n "$(ls /var/lib/flakelet-agent/jobs)", in 0.01 seconds) container-test-run-flakelet-relay> client: waiting for success: grep -q 'agent/app: updated' /tmp/restart.out container-test-run-flakelet-relay> agent # [ 4330.532183] agent systemd[1]: Reloading finished in 335 ms. container-test-run-flakelet-relay> agent # [ 4330.596410] agent systemd[1]: flakelet-agent.service: Deactivated successfully. container-test-run-flakelet-relay> agent # [ 4330.596588] agent systemd[1]: Stopped flakelet-agent. container-test-run-flakelet-relay> agent # [ 4330.596605] agent systemd[1]: flakelet-agent.service: Consumed 1.814s CPU time over 29.173s wall clock time. container-test-run-flakelet-relay> agent # [ 4330.600941] agent systemd[1]: Starting flakelet-agent... container-test-run-flakelet-relay> agent # [ 4330.603443] agent systemd[1]: Starting app slow2... container-test-run-flakelet-relay> agent # [ 4330.656259] agent flakelet-agent[3157]: {"timestamp":"2026-09-06T08:16:48.618046Z","level":"INFO","message":"reattaching to running unit","flakelet":"app","ids":"[\"e5ae731e6705b9b81318c5da1e1a833c\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [ 4330.722965] agent systemd[1]: Started flakelet-agent. container-test-run-flakelet-relay> agent # [ 4330.736828] agent flakelet-agent[3157]: {"timestamp":"2026-09-06T08:16:48.698620Z","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> agent # [ 4330.738101] agent flakelet-agent[3157]: {"timestamp":"2026-09-06T08:16:48.699918Z","level":"INFO","message":"start","id":"e5ae731e6705b9b81318c5da1e1a833c","flakelet":"app","rule":"admins","relay":"relay","caller":"Some(\"x509:email:admin@example.org\")","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> relay # [ 4330.736939] relay flakelet-relay[400]: {"timestamp":"2026-09-06T08:16:48.698690Z","level":"INFO","message":"agent connected","host":"agent","version":"0.1.0","flakelets":"[Named { name: \"app\", generation: Some(7), 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 # [ 4336.666944] agent systemd[1]: Started app slow2. container-test-run-flakelet-relay> agent # [ 4336.682207] agent flakelet[3021]: app: updated to generation 8 container-test-run-flakelet-relay> agent # [ 4336.684105] agent systemd[1]: flakelet-relay-job-app.service: Deactivated successfully. container-test-run-flakelet-relay> relay # [ 4337.262883] relay flakelet-relay[400]: {"timestamp":"2026-09-06T08:16:55.224631Z","level":"INFO","message":"deploy finished","job":"0ddb9ea36fecb7d694381f79aeaf2b0e","ok":true,"target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> agent # [ 4337.261305] agent flakelet-agent[3157]: {"timestamp":"2026-09-06T08:16:55.223014Z","level":"INFO","message":"update finished","flakelet":"app","status":"Updated","generation":"Some(8)","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> client: (finished: waiting for success: grep -q 'agent/app: updated' /tmp/restart.out, in 7.08 seconds) container-test-run-flakelet-relay> client: must succeed: cat /tmp/restart.out container-test-run-flakelet-relay> client: (finished: must succeed: cat /tmp/restart.out, in 0.01 seconds) container-test-run-flakelet-relay> agent: must succeed: systemctl is-active app.service container-test-run-flakelet-relay> agent: (finished: must succeed: systemctl is-active app.service, in 0.01 seconds) container-test-run-flakelet-relay> (finished: subtest: agent restart mid-run reattaches to the unit, in 9.03 seconds) container-test-run-flakelet-relay> subtest: relay restart mid-run, push resumes via /v1/jobs container-test-run-flakelet-relay> agent: must succeed: jq '.services.app.prebuilt="/nix/store/c67jj8kx3m50nyj874aqmdqf21w5iznd-flakelet-app-slow"' /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/c67jj8kx3m50nyj874aqmdqf21w5iznd-flakelet-app-slow"' /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: date +%s container-test-run-flakelet-relay> agent: (finished: must succeed: date +%s, 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 agent/app > /tmp/resume.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/app > /tmp/resume.out 2>&1 &, in 0.01 seconds) container-test-run-flakelet-relay> agent: waiting for success: journalctl -u flakelet-relay-job-app.service --since=@1788682615 | grep -q 'app-slow' container-test-run-flakelet-relay> agent # [ 4337.884309] agent flakelet-agent[3157]: {"timestamp":"2026-09-06T08:16:55.846047Z","level":"INFO","message":"start","id":"519f6601f6d4802b65e1d410940f29e2","flakelet":"app","rule":"admins","relay":"relay","caller":"Some(\"x509:email:admin@example.org\")","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> agent # [ 4337.884934] agent flakelet-agent[3157]: {"timestamp":"2026-09-06T08:16:55.846756Z","level":"INFO","message":"update starting","flakelet":"app","ids":"[\"519f6601f6d4802b65e1d410940f29e2\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [ 4337.936600] agent systemd[1]: Starting flakelet update app (via relay)... container-test-run-flakelet-relay> agent # [ 4337.968056] agent systemd[1]: Started flakelet update app (via relay). container-test-run-flakelet-relay> agent # [ 4337.972713] agent flakelet[3642]: app: using prebuilt artifact /nix/store/c67jj8kx3m50nyj874aqmdqf21w5iznd-flakelet-app-slow container-test-run-flakelet-relay> agent # [ 4337.973341] agent flakelet[3642]: app: activating generation 9 container-test-run-flakelet-relay> agent # [ 4337.986602] agent systemd[1]: Stopping app slow2... container-test-run-flakelet-relay> agent # [ 4337.987064] agent systemd[1]: app.service: Deactivated successfully. container-test-run-flakelet-relay> agent # [ 4337.987327] agent systemd[1]: Stopped app slow2. container-test-run-flakelet-relay> agent # [ 4338.006261] agent systemd[1]: Reload requested from client PID 3649 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [ 4338.006325] agent systemd[1]: Reloading... container-test-run-flakelet-relay> relay # [ 4337.883861] relay flakelet-relay[400]: {"timestamp":"2026-09-06T08:16:55.845613Z","level":"INFO","message":"deploy accepted","job":"8ab76c92a77a0c8f36dc064757064de6","caller":"x509:email:admin@example.org","targets":"[\"agent/app\"]","target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> agent # [ 4338.348044] agent systemd[1]: Reloading finished in 340 ms. container-test-run-flakelet-relay> agent # [ 4338.456095] agent systemd[1]: Reload requested from client PID 3679 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [ 4338.456132] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent: (finished: waiting for success: journalctl -u flakelet-relay-job-app.service --since=@1788682615 | grep -q 'app-slow', in 1.02 seconds) container-test-run-flakelet-relay> relay: must succeed: systemctl restart flakelet-relay container-test-run-flakelet-relay> agent # [ 4338.788286] agent systemd[1]: Reloading finished in 331 ms. container-test-run-flakelet-relay> agent # [ 4338.875545] agent systemd[1]: Reload requested from client PID 3710 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [ 4338.875577] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [ 4338.906740] agent flakelet-agent[3157]: {"timestamp":"2026-09-06T08:16:56.868524Z","level":"INFO","message":"disconnected: websocket closed","relay":"https://relay:7443","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> relay # [ 4338.906320] relay systemd[1]: Stopping flakelet-relay... container-test-run-flakelet-relay> relay # [ 4338.942125] relay systemd[1]: flakelet-relay.service: Deactivated successfully. container-test-run-flakelet-relay> relay # [ 4338.942603] relay systemd[1]: Stopped flakelet-relay. container-test-run-flakelet-relay> relay # [ 4338.942644] relay systemd[1]: flakelet-relay.service: Consumed 2.750s CPU time over 1min 8.893s wall clock time. container-test-run-flakelet-relay> relay # [ 4338.981339] relay systemd[1]: Starting flakelet-relay... container-test-run-flakelet-relay> agent # [ 4339.229836] agent systemd[1]: Reloading finished in 353 ms. container-test-run-flakelet-relay> agent # [ 4339.318674] agent systemd[1]: Reload requested from client PID 3746 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [ 4339.318714] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [ 4339.651864] agent systemd[1]: Reloading finished in 332 ms. container-test-run-flakelet-relay> agent # [ 4339.736248] agent systemd[1]: Starting app slow... container-test-run-flakelet-relay> agent # [ 4339.909881] agent flakelet-agent[3157]: {"timestamp":"2026-09-06T08:16:57.871524Z","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> relay: (finished: must succeed: systemctl restart flakelet-relay, in 1.53 seconds) container-test-run-flakelet-relay> client: waiting for success: grep -q '» ok' /tmp/resume.out container-test-run-flakelet-relay> relay # [ 4340.421999] relay flakelet-relay[934]: {"timestamp":"2026-09-06T08:16:58.383741Z","level":"INFO","message":"listening on 127.0.0.1:7400","target":"flakelet_relay::relay::server"} container-test-run-flakelet-relay> relay # [ 4340.422952] relay flakelet-relay[934]: {"timestamp":"2026-09-06T08:16:58.384141Z","level":"INFO","message":"listening on [::]:7443","target":"flakelet_relay::relay::server"} container-test-run-flakelet-relay> relay # [ 4340.422629] relay systemd[1]: Started flakelet-relay. container-test-run-flakelet-relay> agent # [ 4341.924082] agent flakelet-agent[3157]: {"timestamp":"2026-09-06T08:16:59.885791Z","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 # [ 4341.924266] relay flakelet-relay[934]: {"timestamp":"2026-09-06T08:16:59.886013Z","level":"INFO","message":"agent connected","host":"agent","version":"0.1.0","flakelets":"[Named { name: \"app\", generation: Some(8), 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 # [ 4341.926334] relay flakelet-relay[934]: {"timestamp":"2026-09-06T08:16:59.888146Z","level":"INFO","message":"job reattached","job":"8ab76c92a77a0c8f36dc064757064de6","targets":"[\"agent/app\"]","target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> agent # [ 4345.808957] agent systemd[1]: Started app slow. container-test-run-flakelet-relay> agent # [ 4345.826799] agent flakelet[3642]: app: updated to generation 9 container-test-run-flakelet-relay> agent # [ 4345.829471] agent systemd[1]: flakelet-relay-job-app.service: Deactivated successfully. container-test-run-flakelet-relay> agent # [ 4346.913189] agent flakelet-agent[3157]: {"timestamp":"2026-09-06T08:17:04.874927Z","level":"INFO","message":"update finished","flakelet":"app","status":"Updated","generation":"Some(9)","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> client: (finished: waiting for success: grep -q '» ok' /tmp/resume.out, in 7.08 seconds) container-test-run-flakelet-relay> client: must succeed: cat /tmp/resume.out container-test-run-flakelet-relay> client: (finished: must succeed: cat /tmp/resume.out, in 0.01 seconds) container-test-run-flakelet-relay> (finished: subtest: relay restart mid-run, push resumes via /v1/jobs, in 9.67 seconds) container-test-run-flakelet-relay> subtest: job history comes from the agents and survives a relay restart 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 --id hist1 agent/other 2>&1 container-test-run-flakelet-relay> agent # [ 4347.533732] agent flakelet-agent[3157]: {"timestamp":"2026-09-06T08:17:05.495468Z","level":"INFO","message":"start","id":"5be20c693e5c0b60e3f85779794dfb86","flakelet":"other","rule":"admins","relay":"relay","caller":"Some(\"x509:email:admin@example.org\")","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> agent # [ 4347.534375] agent flakelet-agent[3157]: {"timestamp":"2026-09-06T08:17:05.496192Z","level":"INFO","message":"update starting","flakelet":"other","ids":"[\"5be20c693e5c0b60e3f85779794dfb86\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [ 4347.586363] agent systemd[1]: Starting flakelet update other (via relay)... container-test-run-flakelet-relay> agent # [ 4347.620523] agent systemd[1]: Started flakelet update other (via relay). container-test-run-flakelet-relay> agent # [ 4347.625902] agent flakelet[3859]: other: using prebuilt artifact /nix/store/hmr990n0b4v6rpzrcpyyw738r9xi7z95-flakelet-other-v1 container-test-run-flakelet-relay> agent # [ 4347.626471] agent flakelet[3859]: other: up to date container-test-run-flakelet-relay> agent # [ 4347.627523] agent systemd[1]: flakelet-relay-job-other.service: Deactivated successfully. container-test-run-flakelet-relay> relay # [ 4347.533408] relay flakelet-relay[934]: {"timestamp":"2026-09-06T08:17:05.495083Z","level":"INFO","message":"deploy accepted","job":"188b06c4636b65849b3ba8daac22dc7c","caller":"x509:email:admin@example.org","targets":"[\"agent/other\"]","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 --id hist1 agent/other 2>&1, in 0.67 seconds) container-test-run-flakelet-relay> relay: must succeed: systemctl restart flakelet-relay container-test-run-flakelet-relay> agent # [ 4348.179049] agent flakelet-agent[3157]: {"timestamp":"2026-09-06T08:17:06.140782Z","level":"INFO","message":"update finished","flakelet":"other","status":"Unchanged","generation":"Some(1)","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [ 4348.194408] agent flakelet-agent[3157]: {"timestamp":"2026-09-06T08:17:06.156190Z","level":"INFO","message":"disconnected: websocket closed","relay":"https://relay:7443","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> relay # [ 4348.180392] relay flakelet-relay[934]: {"timestamp":"2026-09-06T08:17:06.142147Z","level":"INFO","message":"deploy finished","job":"188b06c4636b65849b3ba8daac22dc7c","ok":true,"target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> relay # [ 4348.193883] relay systemd[1]: Stopping flakelet-relay... container-test-run-flakelet-relay> relay # [ 4348.247150] relay systemd[1]: flakelet-relay.service: Deactivated successfully. container-test-run-flakelet-relay> relay # [ 4348.247627] relay systemd[1]: Stopped flakelet-relay. container-test-run-flakelet-relay> relay # [ 4348.247669] relay systemd[1]: flakelet-relay.service: Consumed 2.041s CPU time over 9.265s wall clock time. container-test-run-flakelet-relay> relay # [ 4348.284052] relay systemd[1]: Starting flakelet-relay... container-test-run-flakelet-relay> agent # [ 4349.197493] agent flakelet-agent[3157]: {"timestamp":"2026-09-06T08:17:07.159215Z","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> relay: (finished: must succeed: systemctl restart flakelet-relay, in 1.49 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> relay # [ 4349.670608] relay flakelet-relay[1324]: {"timestamp":"2026-09-06T08:17:07.632343Z","level":"INFO","message":"listening on 127.0.0.1:7400","target":"flakelet_relay::relay::server"} container-test-run-flakelet-relay> relay # [ 4349.671578] relay flakelet-relay[1324]: {"timestamp":"2026-09-06T08:17:07.632774Z","level":"INFO","message":"listening on [::]:7443","target":"flakelet_relay::relay::server"} container-test-run-flakelet-relay> relay # [ 4349.671194] relay systemd[1]: Started flakelet-relay. container-test-run-flakelet-relay> agent # [ 4351.207200] agent flakelet-agent[3157]: {"timestamp":"2026-09-06T08:17:09.168973Z","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 # [ 4351.207343] relay flakelet-relay[1324]: {"timestamp":"2026-09-06T08:17:09.169084Z","level":"INFO","message":"agent connected","host":"agent","version":"0.1.0","flakelets":"[Named { name: \"app\", generation: Some(9), 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.05 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 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, 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.eyJhdWQiOiJmbGFrZWxldC1yZWxheSIsImV4cCI6MTc4ODY4MjkyOSwiZ3JvdXBzIjpbXSwiaWF0IjoxNzg4NjgyNjI5LCJpc3MiOiJodHRwczovL3JlbGF5Ojg0NDMiLCJqdGkiOiJDWVlZNlNTWUtRTklVPT09IiwibmJmIjoxNzg4NjgyNjI5LCJzdWIiOiJyZXBvOmdpdGh1YjpleGFtcGxlL2FwcDpyZWY6cmVmcy9oZWFkcy9tYWluIn0.0owOCgV_QvciKpjEZKBBXoa003pIKLx7Zmn79daCm8V4sI_R3_IXjw7wXVY3O05mD-JR3OxrdWTroHcGVTKADg' push --relay https://relay:7443 jobs container-test-run-flakelet-relay> client: (finished: must succeed: FLAKELET_RELAY_TOKEN_COMMAND='echo eyJhbGciOiJFZERTQSIsImtpZCI6ImsxIiwidHlwIjoiSldUIn0.eyJhdWQiOiJmbGFrZWxldC1yZWxheSIsImV4cCI6MTc4ODY4MjkyOSwiZ3JvdXBzIjpbXSwiaWF0IjoxNzg4NjgyNjI5LCJpc3MiOiJodHRwczovL3JlbGF5Ojg0NDMiLCJqdGkiOiJDWVlZNlNTWUtRTklVPT09IiwibmJmIjoxNzg4NjgyNjI5LCJzdWIiOiJyZXBvOmdpdGh1YjpleGFtcGxlL2FwcDpyZWY6cmVmcy9oZWFkcy9tYWluIn0.0owOCgV_QvciKpjEZKBBXoa003pIKLx7Zmn79daCm8V4sI_R3_IXjw7wXVY3O05mD-JR3OxrdWTroHcGVTKADg' push --relay https://relay:7443 jobs, in 0.02 seconds) container-test-run-flakelet-relay> (finished: subtest: job history comes from the agents and survives a relay restart, in 4.31 seconds) container-test-run-flakelet-relay> subtest: dashboard login via authorization code flow container-test-run-flakelet-relay> client: must succeed: curl -sS --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem -b /tmp/jar -c /tmp/jar -o /dev/null -w '%{http_code} %{redirect_url}' https://relay:7443/ui/ | grep -q '303 https://relay:7443/ui/login' container-test-run-flakelet-relay> client: (finished: must succeed: curl -sS --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem -b /tmp/jar -c /tmp/jar -o /dev/null -w '%{http_code} %{redirect_url}' https://relay:7443/ui/ | grep -q '303 https://relay:7443/ui/login', in 0.02 seconds) container-test-run-flakelet-relay> client: must succeed: curl -sS --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem -b /tmp/jar -c /tmp/jar -L https://relay:7443/ui/login container-test-run-flakelet-relay> client: (finished: must succeed: curl -sS --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem -b /tmp/jar -c /tmp/jar -L https://relay:7443/ui/login, in 0.08 seconds) container-test-run-flakelet-relay> client: must succeed: curl -sS --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem -b /tmp/jar -c /tmp/jar https://relay:7443/ui/jobs container-test-run-flakelet-relay> client: (finished: must succeed: curl -sS --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem -b /tmp/jar -c /tmp/jar https://relay:7443/ui/jobs, in 0.02 seconds) container-test-run-flakelet-relay> client: must succeed: curl -sS --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem -b /tmp/jar -c /tmp/jar -f https://relay:7443/v1/jobs | grep -q hist1 container-test-run-flakelet-relay> client: (finished: must succeed: curl -sS --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem -b /tmp/jar -c /tmp/jar -f https://relay:7443/v1/jobs | grep -q hist1, in 0.02 seconds) container-test-run-flakelet-relay> client: must succeed: curl -sS --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem -b /tmp/jar -c /tmp/jar -N --max-time 10 https://relay:7443/ui/jobs/hist1/events || true container-test-run-flakelet-relay> client: (finished: must succeed: curl -sS --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem -b /tmp/jar -c /tmp/jar -N --max-time 10 https://relay:7443/ui/jobs/hist1/events || true, in 0.02 seconds) container-test-run-flakelet-relay> client: must succeed: curl -sS --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem -b /tmp/jar -c /tmp/jar -o /dev/null -w '%{http_code}' -X POST 'https://relay:7443/ui/deploy?arg=other' | grep -q 403 container-test-run-flakelet-relay> client: (finished: must succeed: curl -sS --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem -b /tmp/jar -c /tmp/jar -o /dev/null -w '%{http_code}' -X POST 'https://relay:7443/ui/deploy?arg=other' | grep -q 403, in 0.02 seconds) container-test-run-flakelet-relay> client: must succeed: curl -sS --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem -b /tmp/jar -c /tmp/jar -D - -o /dev/null -H 'HX-Request: true' -X POST 'https://relay:7443/ui/deploy?arg=other' container-test-run-flakelet-relay> client: (finished: must succeed: curl -sS --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem -b /tmp/jar -c /tmp/jar -D - -o /dev/null -H 'HX-Request: true' -X POST 'https://relay:7443/ui/deploy?arg=other', in 0.02 seconds) container-test-run-flakelet-relay> client: must succeed: curl -sS --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem -b /tmp/jar -c /tmp/jar -N --max-time 30 https://relay:7443/ui/jobs/2898b2a8a520ce1b90ca229229d36749/events || true container-test-run-flakelet-relay> relay # [ 4351.874698] relay 93sn3sv24fj26ksccpppzwg6kbf10s4w-device-mock[186]: 127.0.0.1 - - [06/Sep/2026 08:17:09] "GET /authorize?response_type=code&client_id=dashboard&redirect_uri=https%3A%2F%2Frelay%3A7443%2Fui%2Fcallback&scope=openid%20profile%20email%20groups&state=1KNzVBibdSVyboEPGKl-PdCIoQ7vehYyMPDFmNFtdyg&code_challenge=inwqMeyxARzYBCBF6Vy2xsmxYhCMss1bokJP7YYmnLs&code_challenge_method=S256 HTTP/1.1" 302 - container-test-run-flakelet-relay> relay # [ 4351.920465] relay 93sn3sv24fj26ksccpppzwg6kbf10s4w-device-mock[186]: 127.0.0.1 - - [06/Sep/2026 08:17:09] "POST /token HTTP/1.1" 200 - container-test-run-flakelet-relay> relay # [ 4351.921726] relay flakelet-relay[1324]: {"timestamp":"2026-09-06T08:17:09.883527Z","level":"INFO","message":"login","name":"someone","principals":"[\"oidc:mock:someone\", \"oidc:mock:groups:wheel\"]","target":"flakelet_relay::relay::login"} container-test-run-flakelet-relay> relay # [ 4351.985154] relay flakelet-relay[1324]: {"timestamp":"2026-09-06T08:17:09.946909Z","level":"INFO","message":"job reattached","job":"188b06c4636b65849b3ba8daac22dc7c","targets":"[\"agent/other\"]","target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> relay # [ 4352.027165] relay flakelet-relay[1324]: {"timestamp":"2026-09-06T08:17:09.988930Z","level":"INFO","message":"deploy accepted","job":"67e1fdd469ec58d1a761e0b3128acbe3","caller":"oidc:mock:someone","targets":"[\"agent/other\"]","target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> relay # [ 4352.046918] relay flakelet-relay[1324]: {"timestamp":"2026-09-06T08:17:10.008722Z","level":"INFO","message":"job reattached","job":"67e1fdd469ec58d1a761e0b3128acbe3","targets":"[\"agent/other\"]","target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> agent # [ 4352.027413] agent flakelet-agent[3157]: {"timestamp":"2026-09-06T08:17:09.989169Z","level":"INFO","message":"start","id":"ece355ae42cccaa7c0b1a99716e07372","flakelet":"other","rule":"admins","relay":"relay","caller":"Some(\"oidc:mock:someone\\noidc:mock:groups:wheel\")","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> agent # [ 4352.027856] agent flakelet-agent[3157]: {"timestamp":"2026-09-06T08:17:09.989674Z","level":"INFO","message":"update starting","flakelet":"other","ids":"[\"ece355ae42cccaa7c0b1a99716e07372\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [ 4352.079999] agent systemd[1]: Starting flakelet update other (via relay)... container-test-run-flakelet-relay> agent # [ 4352.112998] agent systemd[1]: Started flakelet update other (via relay). container-test-run-flakelet-relay> agent # [ 4352.118496] agent flakelet[3907]: other: using prebuilt artifact /nix/store/hmr990n0b4v6rpzrcpyyw738r9xi7z95-flakelet-other-v1 container-test-run-flakelet-relay> agent # [ 4352.118986] agent flakelet[3907]: other: up to date container-test-run-flakelet-relay> agent # [ 4352.121850] agent systemd[1]: flakelet-relay-job-other.service: Deactivated successfully. container-test-run-flakelet-relay> client: (finished: must succeed: curl -sS --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem -b /tmp/jar -c /tmp/jar -N --max-time 30 https://relay:7443/ui/jobs/2898b2a8a520ce1b90ca229229d36749/events || true, in 0.65 seconds) container-test-run-flakelet-relay> client: must succeed: curl -sS --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem -b /tmp/jar -c /tmp/jar https://relay:7443/ui/jobs/2898b2a8a520ce1b90ca229229d36749 container-test-run-flakelet-relay> client: (finished: must succeed: curl -sS --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem -b /tmp/jar -c /tmp/jar https://relay:7443/ui/jobs/2898b2a8a520ce1b90ca229229d36749, in 0.02 seconds) container-test-run-flakelet-relay> client: must succeed: curl -sS --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem -b /tmp/jar -c /tmp/jar -sf -o /dev/null https://relay:7443/ui/static/htmx.min.js container-test-run-flakelet-relay> client: (finished: must succeed: curl -sS --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem -b /tmp/jar -c /tmp/jar -sf -o /dev/null https://relay:7443/ui/static/htmx.min.js, in 0.02 seconds) container-test-run-flakelet-relay> client: must succeed: curl -sS --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem -b /tmp/jar -c /tmp/jar 'https://relay:7443/ui/?q=status%3Ahealthy+oth' container-test-run-flakelet-relay> client: (finished: must succeed: curl -sS --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem -b /tmp/jar -c /tmp/jar 'https://relay:7443/ui/?q=status%3Ahealthy+oth', in 0.02 seconds) container-test-run-flakelet-relay> client: must succeed: curl -sS --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem -b /tmp/jar -c /tmp/jar https://relay:7443/ui/flakelets/other container-test-run-flakelet-relay> client: (finished: must succeed: curl -sS --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem -b /tmp/jar -c /tmp/jar https://relay:7443/ui/flakelets/other, in 0.02 seconds) container-test-run-flakelet-relay> client: must succeed: curl -sS --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem -b /tmp/jar -c /tmp/jar 'https://relay:7443/ui/hosts?q=status%3Adisconnected' container-test-run-flakelet-relay> client: (finished: must succeed: curl -sS --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem -b /tmp/jar -c /tmp/jar 'https://relay:7443/ui/hosts?q=status%3Adisconnected', in 0.02 seconds) container-test-run-flakelet-relay> client: must succeed: curl -sS --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem -b /tmp/jar -c /tmp/jar -o /dev/null -w '%{http_code}' -H 'HX-Request: true' -X POST 'https://relay:7443/ui/retry?arg=2898b2a8a520ce1b90ca229229d36749' | grep -q 400 container-test-run-flakelet-relay> client: (finished: must succeed: curl -sS --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem -b /tmp/jar -c /tmp/jar -o /dev/null -w '%{http_code}' -H 'HX-Request: true' -X POST 'https://relay:7443/ui/retry?arg=2898b2a8a520ce1b90ca229229d36749' | grep -q 400, in 0.02 seconds) container-test-run-flakelet-relay> client: must succeed: curl -sS --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem -b /tmp/jar -c /tmp/jar -N --max-time 15 'https://relay:7443/ui/events?path=jobs' > /tmp/ev.out 2>&1 & container-test-run-flakelet-relay> client: (finished: must succeed: curl -sS --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem -b /tmp/jar -c /tmp/jar -N --max-time 15 'https://relay:7443/ui/events?path=jobs' > /tmp/ev.out 2>&1 &, 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 --id live1 agent/other container-test-run-flakelet-relay> » job 38796a7ba508eadfe82a1cd5def000bc via relay 0.1.0 (agents: agent 0.1.0) container-test-run-flakelet-relay> » wave 0 container-test-run-flakelet-relay> agent/other | Starting flakelet update other (via relay)... container-test-run-flakelet-relay> agent/other | Started flakelet update other (via relay). container-test-run-flakelet-relay> agent/other | other: using prebuilt artifact /nix/store/hmr990n0b4v6rpzrcpyyw738r9xi7z95-flakelet-other-v1 container-test-run-flakelet-relay> agent/other | other: up to date container-test-run-flakelet-relay> agent # [ 4352.672228] agent flakelet-agent[3157]: {"timestamp":"2026-09-06T08:17:10.633970Z","level":"INFO","message":"update finished","flakelet":"other","status":"Unchanged","generation":"Some(1)","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent/otheragent # [ 4352.821123] agent flakelet-agent[3157]: {"timestamp":"2026-09-06T08:17:10.782776Z","level":"INFO","message":"start","id":"5e317fc57863c2a7c03ebe1bdacd1356","flakelet":"other","rule":"admins","relay":"relay","caller":"Some(\"x509:email:admin@example.org\")","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> | agent # [ 4352.821345] agent flakelet-agent[3157]: {"timestamp":"2026-09-06T08:17:10.783148Z","level":"INFO","message":"update starting","flakelet":"other","ids":"[\"5e317fc57863c2a7c03ebe1bdacd1356\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> flakelet-relay-job-other.service: Deactivated successfully.agent # [ 4352.868511] agent systemd[1]: Starting flakelet update other (via relay)... container-test-run-flakelet-relay> container-test-run-flakelet-relay> agent # [ 4352.904098] agent systemd[1]: Started flakelet update other (via relay). container-test-run-flakelet-relay> agent # [ 4352.908518] agent flakelet[3945]: other: using prebuilt artifact /nix/store/hmr990n0b4v6rpzrcpyyw738r9xi7z95-flakelet-other-v1 container-test-run-flakelet-relay> agent # [ 4352.908977] agent flakelet[3945]: other: up to date container-test-run-flakelet-relay> agent # [ 4352.911172] agent systemd[1]: flakelet-relay-job-other.service: Deactivated successfully. container-test-run-flakelet-relay> relay # [ 4352.820688] relay flakelet-relay[1324]: {"timestamp":"2026-09-06T08:17:10.782440Z","level":"INFO","message":"deploy accepted","job":"38796a7ba508eadfe82a1cd5def000bc","caller":"x509:email:admin@example.org","targets":"[\"agent/other\"]","target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> » agent/other: unchanged (generation 1) container-test-run-flakelet-relay> » ok 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 --id live1 agent/other, in 0.66 seconds) container-test-run-flakelet-relay> client: waiting for success: grep -q live1 /tmp/ev.out container-test-run-flakelet-relay> client: (finished: waiting for success: grep -q live1 /tmp/ev.out, in 0.01 seconds) container-test-run-flakelet-relay> client: must succeed: curl -sS --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem -b /tmp/jar -c /tmp/jar -X POST -o /dev/null https://relay:7443/ui/logout container-test-run-flakelet-relay> client: (finished: must succeed: curl -sS --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem -b /tmp/jar -c /tmp/jar -X POST -o /dev/null https://relay:7443/ui/logout, in 0.02 seconds) container-test-run-flakelet-relay> client: must succeed: curl -sS --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem -b /tmp/jar -c /tmp/jar -o /dev/null -w '%{http_code}' https://relay:7443/ui/ | grep -q 303 container-test-run-flakelet-relay> client: (finished: must succeed: curl -sS --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem -b /tmp/jar -c /tmp/jar -o /dev/null -w '%{http_code}' https://relay:7443/ui/ | grep -q 303, in 0.02 seconds) container-test-run-flakelet-relay> (finished: subtest: dashboard login via authorization code flow, in 1.70 seconds) container-test-run-flakelet-relay> (finished: run the VM test script, in 87.10 seconds) container-test-run-flakelet-relay> test script finished in 87.14s container-test-run-flakelet-relay> cleanup container-test-run-flakelet-relay> kill NspawnMachine (pid 51) container-test-run-flakelet-relay> kill NspawnMachine (pid 53) container-test-run-flakelet-relay> Container agent terminated by signal KILL. container-test-run-flakelet-relay> kill NspawnMachine (pid 55) container-test-run-flakelet-relay> relay # [ 4353.465686] relay flakelet-relay[1324]: {"timestamp":"2026-09-06T08:17:11.427442Z","level":"INFO","message":"deploy finished","job":"38796a7ba508eadfe82a1cd5def000bc","ok":true,"target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> Container client terminated by signal KILL. container-test-run-flakelet-relay> (finished: cleanup, in 0.34 seconds) container-test-run-flakelet-relay> additionally exposed symbols: container-test-run-flakelet-relay> agent, client, relay, container-test-run-flakelet-relay> vlan1, container-test-run-flakelet-relay> start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh container-test-run-flakelet-relay> » job b08899e4f5d9448ad0e62beee8dfb372 via relay 0.1.0 (agents: agent 0.1.0) container-test-run-flakelet-relay> » wave 0 container-test-run-flakelet-relay> agent/app | Starting flakelet update app (via relay)... container-test-run-flakelet-relay> agent/app | Started flakelet update app (via relay). container-test-run-flakelet-relay> agent/app | app: using prebuilt artifact /nix/store/6ili1vxjlag9d7dy0bxrbxk6bpi5qh9p-flakelet-app-v2 container-test-run-flakelet-relay> agent/app | app: activating generation 2 container-test-run-flakelet-relay> agent/app | app: updated to generation 2 container-test-run-flakelet-relay> agent/app | flakelet-relay-job-app.service: Deactivated successfully. container-test-run-flakelet-relay> » agent/app: updated (generation 2) container-test-run-flakelet-relay> » ok container-test-run-flakelet-relay> container-test-run-flakelet-relay> » job 8419c3ef1200ac1a3b2918807efb1372 via relay 0.1.0 (agents: agent 0.1.0) container-test-run-flakelet-relay> » wave 0 container-test-run-flakelet-relay> agent/app | Starting flakelet update app (via relay)... container-test-run-flakelet-relay> agent/app | Started flakelet update app (via relay). container-test-run-flakelet-relay> agent/app | app: using prebuilt artifact /nix/store/8282rnbvyk847q4l4zby3zwcq3jpzrvw-flakelet-app-broken container-test-run-flakelet-relay> agent/app | app: activating generation 4 container-test-run-flakelet-relay> agent/app | app: deploy failed, rolled back: systemctl start app.service failed: container-test-run-flakelet-relay> agent/app | failed units: app.service. See journalctl -u app.service container-test-run-flakelet-relay> agent/app | flakelet-relay-job-app.service: Main process exited, code=exited, status=1/FAILURE container-test-run-flakelet-relay> agent/app | flakelet-relay-job-app.service: Failed with result 'exit-code'. container-test-run-flakelet-relay> » agent/app: rolled-back (generation 3) container-test-run-flakelet-relay> agent/app │ Starting app v1... container-test-run-flakelet-relay> agent/app │ Started app v1. container-test-run-flakelet-relay> agent/app │ Stopping app v1... container-test-run-flakelet-relay> agent/app │ app.service: Deactivated successfully. container-test-run-flakelet-relay> agent/app │ Stopped app v1. container-test-run-flakelet-relay> agent/app │ Starting app v2... container-test-run-flakelet-relay> agent/app │ Started app v2. container-test-run-flakelet-relay> agent/app │ Stopping app v2... container-test-run-flakelet-relay> agent/app │ app.service: Deactivated successfully. container-test-run-flakelet-relay> agent/app │ Stopped app v2. container-test-run-flakelet-relay> agent/app │ Starting app v1... container-test-run-flakelet-relay> agent/app │ Started app v1. container-test-run-flakelet-relay> agent/app │ Stopping app v1... container-test-run-flakelet-relay> agent/app │ app.service: Deactivated successfully. container-test-run-flakelet-relay> agent/app │ Stopped app v1. container-test-run-flakelet-relay> agent/app │ Starting app broken... container-test-run-flakelet-relay> agent/app │ app.service: Unable to locate executable '/nonexistent': No such file or directory container-test-run-flakelet-relay> agent/app │ app.service: Failed at step EXEC spawning /nonexistent: No such file or directory container-test-run-flakelet-relay> agent/app │ app.service: Main process exited, code=exited, status=203/EXEC container-test-run-flakelet-relay> agent/app │ app.service: Failed with result 'exit-code'. container-test-run-flakelet-relay> agent/app │ Failed to start app broken. container-test-run-flakelet-relay> agent/app │ Starting app v1... container-test-run-flakelet-relay> agent/app │ Started app v1. container-test-run-flakelet-relay> » skipped: agent/other container-test-run-flakelet-relay> » failed container-test-run-flakelet-relay> container-test-run-flakelet-relay> » job f3469a74f8927189fefb099655270820 via relay 0.1.0 (agents: agent 0.1.0) container-test-run-flakelet-relay> » wave 0 container-test-run-flakelet-relay> agent/app | Starting flakelet update app (via relay)... container-test-run-flakelet-relay> agent/app | Started flakelet update app (via relay). container-test-run-flakelet-relay> agent/app | app: using prebuilt artifact /nix/store/c67jj8kx3m50nyj874aqmdqf21w5iznd-flakelet-app-slow container-test-run-flakelet-relay> agent/app | app: activating generation 7 container-test-run-flakelet-relay> agent/app | app: updated to generation 7 container-test-run-flakelet-relay> agent/app | flakelet-relay-job-app.service: Deactivated successfully. container-test-run-flakelet-relay> » agent/app: updated (generation 7) container-test-run-flakelet-relay> » ok container-test-run-flakelet-relay> container-test-run-flakelet-relay> » job 0ddb9ea36fecb7d694381f79aeaf2b0e via relay 0.1.0 (agents: agent 0.1.0) container-test-run-flakelet-relay> » wave 0 container-test-run-flakelet-relay> agent/app | Starting flakelet update app (via relay)... container-test-run-flakelet-relay> agent/app | Started flakelet update app (via relay). container-test-run-flakelet-relay> agent/app | app: using prebuilt artifact /nix/store/ln47rsd5km8a04hd5zlkz4q1v352zqh3-flakelet-app-slow2 container-test-run-flakelet-relay> agent/app | app: activating generation 8 container-test-run-flakelet-relay> agent/app | app: updated to generation 8 container-test-run-flakelet-relay> agent/app | flakelet-relay-job-app.service: Deactivated successfully. container-test-run-flakelet-relay> » agent/app: updated (generation 8) container-test-run-flakelet-relay> » ok container-test-run-flakelet-relay> container-test-run-flakelet-relay> » job 8ab76c92a77a0c8f36dc064757064de6 via relay 0.1.0 (agents: agent 0.1.0) container-test-run-flakelet-relay> » wave 0 container-test-run-flakelet-relay> agent/app | Starting flakelet update app (via relay)... container-test-run-flakelet-relay> agent/app | Started flakelet update app (via relay). container-test-run-flakelet-relay> agent/app | app: using prebuilt artifact /nix/store/c67jj8kx3m50nyj874aqmdqf21w5iznd-flakelet-app-slow container-test-run-flakelet-relay> agent/app | app: activating generation 9 container-test-run-flakelet-relay> flakelet-push: stream: error reading a body from connection, resuming job 05d4231c307cb1cbf07c6a9bdf207b94 container-test-run-flakelet-relay> flakelet-push: https://relay:7443: connect relay:7443: Connection refused (os error 111), retrying in 1s container-test-run-flakelet-relay> flakelet-push: https://relay:7443: connect relay:7443: Connection refused (os error 111), retrying in 2s container-test-run-flakelet-relay> » job 8ab76c92a77a0c8f36dc064757064de6 via relay 0.1.0 (agents: agent 0.1.0) container-test-run-flakelet-relay> » wave 0 container-test-run-flakelet-relay> agent/app | app: updated to generation 9 container-test-run-flakelet-relay> agent/app | flakelet-relay-job-app.service: Deactivated successfully. container-test-run-flakelet-relay> » agent/app: updated (generation 9) container-test-run-flakelet-relay> » ok container-test-run-flakelet-relay> container-test-run-flakelet-relay> 1788682625 hist1 x509:email:admin@example.org agent/other:unchanged container-test-run-flakelet-relay> 1788682615 05d4231c307cb1cbf07c6a9bdf207b94 x509:email:admin@example.org agent/app:updated container-test-run-flakelet-relay> 1788682606 23da240189104d19234d432efbf0057c x509:email:admin@example.org agent/app:updated container-test-run-flakelet-relay> 1788682590 bf8c5b2d59a0eb357752b8716c11533b x509:email:admin@example.org agent/app:updated container-test-run-flakelet-relay> 1788682581 ed963f79555867c956c14f32ad09afb0 x509:email:admin@example.org agent/other:unchanged container-test-run-flakelet-relay> 1788682569 313fecae7846ea2661401f4ecb3ed8e4 x509:email:admin@example.org agent/app:updated container-test-run-flakelet-relay> 1788682568 8c41e2d174f8f86377f9484d2d4de768 x509:email:admin@example.org agent/app:unchanged container-test-run-flakelet-relay> 1788682568 f0dba552df68fb3bfa8da79a34b4b5ad x509:email:admin@example.org agent/app:unchanged container-test-run-flakelet-relay> 1788682567 88eb7e9f3367c33813834b7f2f43b527 x509:email:admin@example.org agent/app:unchanged container-test-run-flakelet-relay> 1788682562 2115e6266c59c8f29486fc6de24a7806 x509:email:admin@example.org agent/app:rolled-back container-test-run-flakelet-relay> 1788682562 a19f817a842695e2740b28dda6c7fe5f oidc:mock:groups:wheel agent/other:unchanged container-test-run-flakelet-relay> 1788682561 78f0ab79312d1634498aab8f76da8cf2 oidc:mock:groups:wheel agent/other:unchanged container-test-run-flakelet-relay> 1788682558 963c04c021b1f0489e22438454ce0598 oidc:mock:groups:wheel agent/other:unchanged container-test-run-flakelet-relay> 1788682555 2bf3cd2fd9539cc177c5e7413bc3abfd oidc:mock:repo:github:example/app:ref:refs/heads/main agent/app:updated container-test-run-flakelet-relay> 1788682552 cd6104c2a4dc8a780b83d81f54901226 x509:email:admin@example.org agent/app:updated container-test-run-flakelet-relay> container-test-run-flakelet-relay> Container relay terminated by signal KILL. post-build step Upload coverage to codecov: ok Skipping codecov: project=Mic92/flakelet-relay attr=checks.x86_64-linux.nixos-test