these 27 derivations will be built: /nix/store/2bwq747y43397vy5yy81bjy44igxwb3r-flakelet-relay-0.1.0.drv /nix/store/q3sj2hzy80k47jrhdmy8vc744gcm94gl-flakelet-agent.service.drv /nix/store/0k1v6za62daw9730zxdj3bh3n68306sm-flakelet-flakelet-agent-units.drv /nix/store/qr07ki3dl1ah57zdw3jqprdq5kx2f6dy-flakelet-relay.json.drv /nix/store/bd5gwl52hhqdqhi5x5075m7fp92hnq8x-flakelet-relay.service.drv /nix/store/ygvx0f5053lir1pqsxwzp6qn59kzikas-flakelet-flakelet-relay-units.drv /nix/store/v1a5wl2hrg924iabn9qrfk38ab214mrj-flakelet-flakelet-relay.drv /nix/store/3xbpcmhwsl6ndi352d52q6v5ghmmx116-flakelet-config.json.drv /nix/store/shadnl0413v108ifad2hlr7icazndrwh-X-Restart-Triggers-flakelet-reconcile.drv /nix/store/34cchgxrrw9q9dgvv8z3vy24iadvbgyn-unit-flakelet-reconcile.service.drv /nix/store/wc4h2q51nzgynkyi98kq6bajh2biwq1k-system-units.drv /nix/store/kdf4sj0gb9z1qb0hhxhq6xpancqjq013-etc.drv /nix/store/0n772z25zazg9ggrx3gz4fxpik283j9x-activate.drv /nix/store/11ydpisdk07hyhkd93sdy0k2rcxaplb5-flakelet-flakelet-agent.drv /nix/store/3mgdq1vl64x3blkpsib6ddzficscmssl-nixos-system-relay-test.drv /nix/store/a6mxw2x82qqnbys14ap7hl2w24lih9k0-run-relay-nspawn.drv /nix/store/gkj4ypnr79vkya4ivlp8v0ig2dwb215p-flakelet-config.json.drv /nix/store/9vn1n2skc1lxkpg34k73pyc4gzz0x6nv-X-Restart-Triggers-flakelet-reconcile.drv /nix/store/rmrngkfzl04j5ns3658p1bgx49k54lyi-unit-flakelet-reconcile.service.drv /nix/store/walmk7rcqyfkb4ibp8yc4pgcip4m26k2-system-units.drv /nix/store/rs9maphd5pdkz3azls92n3r8lg2qvm51-etc.drv /nix/store/qsqpvw82130q88hsrc3qy891kq60lwx1-activate.drv /nix/store/s5bpvvqqci94v2kfjp591njj56lmm2qj-nixos-system-agent-test.drv /nix/store/nzl2hvp3ay4gghmaq80i92v8774k5wws-run-agent-nspawn.drv /nix/store/bi3j5vb0ip70j6lz5c43qmsrfn257k1c-driverConfiguration.json.drv /nix/store/szx762l1b8rjhj2fflhsh7ha36lwrcf2-nixos-test-driver-flakelet-relay.drv /nix/store/4s3caza21gvy102fwsshsycd4d6k84s9-container-test-run-flakelet-relay.drv building '/nix/store/2bwq747y43397vy5yy81bjy44igxwb3r-flakelet-relay-0.1.0.drv' flakelet-relay-0.1.0> tribuchet: building on jamie flakelet-relay-0.1.0> Running phase: unpackPhase flakelet-relay-0.1.0> unpacking source archive /nix/store/khmg14zw1wf05394930ij432lniagpy8-source flakelet-relay-0.1.0> source root is source flakelet-relay-0.1.0> Executing cargoSetupPostUnpackHook 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 unicode-ident v1.0.24 flakelet-relay-0.1.0> Compiling quote v1.0.47 flakelet-relay-0.1.0> Compiling libc v0.2.189 flakelet-relay-0.1.0> Compiling cfg-if v1.0.4 flakelet-relay-0.1.0> Compiling pin-project-lite v0.2.17 flakelet-relay-0.1.0> Compiling bytes v1.12.1 flakelet-relay-0.1.0> Compiling autocfg v1.5.1 flakelet-relay-0.1.0> Compiling find-msvc-tools v0.1.11 flakelet-relay-0.1.0> Compiling itoa v1.0.18 flakelet-relay-0.1.0> Compiling memchr v2.8.3 flakelet-relay-0.1.0> Compiling thiserror v2.0.20 flakelet-relay-0.1.0> Compiling shlex v2.0.1 flakelet-relay-0.1.0> Compiling once_cell v1.21.4 flakelet-relay-0.1.0> Compiling zerocopy v0.8.56 flakelet-relay-0.1.0> Compiling getrandom v0.3.4 flakelet-relay-0.1.0> Compiling time-core v0.1.9 flakelet-relay-0.1.0> Compiling num-conv v0.2.2 flakelet-relay-0.1.0> Compiling log v0.4.34 flakelet-relay-0.1.0> Compiling minimal-lexical v0.2.1 flakelet-relay-0.1.0> Compiling futures-core v0.3.34 flakelet-relay-0.1.0> Compiling serde_core v1.0.229 flakelet-relay-0.1.0> Compiling powerfmt v0.2.0 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 cc v1.4.4 flakelet-relay-0.1.0> Compiling zeroize v1.9.0 flakelet-relay-0.1.0> Compiling httparse v1.10.1 flakelet-relay-0.1.0> Compiling tracing-core v0.1.36 flakelet-relay-0.1.0> Compiling untrusted v0.9.0 flakelet-relay-0.1.0> Compiling time-macros v0.2.32 flakelet-relay-0.1.0> Compiling zmij v1.0.23 flakelet-relay-0.1.0> Compiling serde v1.0.229 flakelet-relay-0.1.0> Compiling utf8parse v0.2.2 flakelet-relay-0.1.0> Compiling regex-syntax v0.8.11 flakelet-relay-0.1.0> Compiling rustls-pki-types v1.15.1 flakelet-relay-0.1.0> Compiling lazy_static v1.5.0 flakelet-relay-0.1.0> Compiling anstyle-parse v1.0.0 flakelet-relay-0.1.0> Compiling proc-macro2-diagnostics v0.10.1 flakelet-relay-0.1.0> Compiling try-lock v0.2.5 flakelet-relay-0.1.0> Compiling num-traits v0.2.19 flakelet-relay-0.1.0> Compiling smallvec v1.16.0 flakelet-relay-0.1.0> Compiling is_terminal_polyfill v1.70.2 flakelet-relay-0.1.0> Compiling oid-registry v0.8.1 flakelet-relay-0.1.0> Compiling anstyle-query v1.1.5 flakelet-relay-0.1.0> Compiling rustls v0.23.43 flakelet-relay-0.1.0> Compiling anstyle v1.0.14 flakelet-relay-0.1.0> Compiling serde_json v1.0.151 flakelet-relay-0.1.0> Compiling nom v7.1.3 flakelet-relay-0.1.0> Compiling colorchoice v1.0.5 flakelet-relay-0.1.0> Compiling http v1.5.0 flakelet-relay-0.1.0> Compiling anstream v1.0.0 flakelet-relay-0.1.0> Compiling want v0.3.1 flakelet-relay-0.1.0> Compiling futures-channel v0.3.34 flakelet-relay-0.1.0> Compiling futures-task v0.3.34 flakelet-relay-0.1.0> Compiling slab v0.4.12 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 subtle v2.6.1 flakelet-relay-0.1.0> Compiling httpdate v1.0.3 flakelet-relay-0.1.0> Compiling utf-8 v0.7.6 flakelet-relay-0.1.0> Compiling futures-sink v0.3.34 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 futures-util v0.3.34 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 nu-ansi-term v0.50.3 flakelet-relay-0.1.0> Compiling clap_builder v4.6.6 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 socket2 v0.6.5 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 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 rand_core v0.9.5 flakelet-relay-0.1.0> Compiling time v0.3.55 flakelet-relay-0.1.0> Compiling rusticata-macros v4.1.0 flakelet-relay-0.1.0> Compiling num-integer v0.1.47 flakelet-relay-0.1.0> Compiling num-bigint v0.4.8 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 flakelet-relay-0.1.0> Compiling synstructure v0.13.2 flakelet-relay-0.1.0> Compiling ppv-lite86 v0.2.21 flakelet-relay-0.1.0> Compiling rand_chacha v0.9.0 flakelet-relay-0.1.0> Compiling rand v0.9.5 flakelet-relay-0.1.0> Compiling asn1-rs-impl v0.2.0 flakelet-relay-0.1.0> Compiling asn1-rs-derive v0.6.0 flakelet-relay-0.1.0> Compiling tracing-attributes v0.1.31 flakelet-relay-0.1.0> Compiling maud_macros v0.27.0 flakelet-relay-0.1.0> Compiling tokio v1.53.1 flakelet-relay-0.1.0> Compiling tungstenite v0.28.0 flakelet-relay-0.1.0> Compiling clap v4.6.6 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 flakelet-relay-0.1.0> Compiling rustls-webpki v0.103.15 flakelet-relay-0.1.0> Compiling hyper v1.11.1 flakelet-relay-0.1.0> Compiling tokio-tungstenite v0.28.0 flakelet-relay-0.1.0> Compiling hyper-util v0.1.20 flakelet-relay-0.1.0> Compiling tokio-rustls v0.26.4 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 32.18s 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> buildPhase completed in 32 seconds 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 getrandom v0.4.3 flakelet-relay-0.1.0> Compiling rustix v1.1.4 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 12.16s flakelet-relay-0.1.0> Running unittests src/lib.rs (target/x86_64-unknown-linux-gnu/release/deps/flakelet_relay-ddea05dd889c251d) 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 auth::policy::tests::deploy_rules ... ok flakelet-relay-0.1.0> test auth::policy::tests::agent_identity ... ok flakelet-relay-0.1.0> test auth::policy::tests::validate_catches_unknown_group ... ok flakelet-relay-0.1.0> test oidc::tests::encodes_forms ... ok flakelet-relay-0.1.0> test client::tests::urls ... 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::unknown_fields_and_status_are_tolerated ... ok flakelet-relay-0.1.0> test relay::ui::tests::sse_keeps_trailing_newline ... ok flakelet-relay-0.1.0> test relay::session::tests::roundtrip_and_tamper ... ok flakelet-relay-0.1.0> test auth::jwt::tests::es256 ... ok flakelet-relay-0.1.0> test srv::tests::parses_compressed_srv ... ok flakelet-relay-0.1.0> test proto::tests::done_roundtrip_is_flat ... ok flakelet-relay-0.1.0> test ws::tests::rfc_example_accept_key ... ok flakelet-relay-0.1.0> test sse::tests::roundtrip_split_across_chunks ... ok flakelet-relay-0.1.0> test proto::tests::target_split_rejects_malformed ... 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-6173f36b3e31ff05) 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-9147e1c7be34fa31) 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-e3f07f8167d4269d) 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/5r5p2m0wfz7956cb1yybs3xllbmyznx9-flakelet-relay-0.1.0 flakelet-relay-0.1.0> shrinking /nix/store/5r5p2m0wfz7956cb1yybs3xllbmyznx9-flakelet-relay-0.1.0/bin/flakelet-push flakelet-relay-0.1.0> shrinking /nix/store/5r5p2m0wfz7956cb1yybs3xllbmyznx9-flakelet-relay-0.1.0/bin/flakelet-agent flakelet-relay-0.1.0> shrinking /nix/store/5r5p2m0wfz7956cb1yybs3xllbmyznx9-flakelet-relay-0.1.0/bin/flakelet-relay flakelet-relay-0.1.0> checking for references to /build/ in /nix/store/5r5p2m0wfz7956cb1yybs3xllbmyznx9-flakelet-relay-0.1.0... flakelet-relay-0.1.0> patching script interpreter paths in /nix/store/5r5p2m0wfz7956cb1yybs3xllbmyznx9-flakelet-relay-0.1.0 flakelet-relay-0.1.0> stripping (with command strip and flags -S -p) in /nix/store/5r5p2m0wfz7956cb1yybs3xllbmyznx9-flakelet-relay-0.1.0/bin building '/nix/store/q3sj2hzy80k47jrhdmy8vc744gcm94gl-flakelet-agent.service.drv' building '/nix/store/qr07ki3dl1ah57zdw3jqprdq5kx2f6dy-flakelet-relay.json.drv' flakelet-agent.service> tribuchet: building on jamie flakelet-relay.json> tribuchet: building on jamie flakelet-relay.json> check: oidc:mock:repo:github:example/app:ref:refs/heads/main -> agent/app (expect allow) flakelet-relay.json> agent/app allowed by ci flakelet-relay.json> check: oidc:mock:repo:github:example/app:ref:refs/heads/main -> agent/other (expect deny) flakelet-relay.json> agent/other denied building '/nix/store/bd5gwl52hhqdqhi5x5075m7fp92hnq8x-flakelet-relay.service.drv' flakelet-relay.service> tribuchet: building on jamie building '/nix/store/0k1v6za62daw9730zxdj3bh3n68306sm-flakelet-flakelet-agent-units.drv' flakelet-flakelet-agent-units> tribuchet: building on jamie building '/nix/store/ygvx0f5053lir1pqsxwzp6qn59kzikas-flakelet-flakelet-relay-units.drv' flakelet-flakelet-relay-units> tribuchet: building on jamie building '/nix/store/11ydpisdk07hyhkd93sdy0k2rcxaplb5-flakelet-flakelet-agent.drv' flakelet-flakelet-agent> tribuchet: building on jamie building '/nix/store/v1a5wl2hrg924iabn9qrfk38ab214mrj-flakelet-flakelet-relay.drv' flakelet-flakelet-relay> tribuchet: building on jamie building '/nix/store/gkj4ypnr79vkya4ivlp8v0ig2dwb215p-flakelet-config.json.drv' building '/nix/store/3xbpcmhwsl6ndi352d52q6v5ghmmx116-flakelet-config.json.drv' flakelet-config.json> tribuchet: building on jamie flakelet-config.json> tribuchet: building on jamie flakelet-config.json> structuredAttrs is enabled flakelet-config.json> structuredAttrs is enabled building '/nix/store/9vn1n2skc1lxkpg34k73pyc4gzz0x6nv-X-Restart-Triggers-flakelet-reconcile.drv' X-Restart-Triggers-flakelet-reconcile> tribuchet: building on jamie building '/nix/store/shadnl0413v108ifad2hlr7icazndrwh-X-Restart-Triggers-flakelet-reconcile.drv' X-Restart-Triggers-flakelet-reconcile> tribuchet: building on jamie building '/nix/store/34cchgxrrw9q9dgvv8z3vy24iadvbgyn-unit-flakelet-reconcile.service.drv' building '/nix/store/rmrngkfzl04j5ns3658p1bgx49k54lyi-unit-flakelet-reconcile.service.drv' unit-flakelet-reconcile.service> tribuchet: building on jamie unit-flakelet-reconcile.service> tribuchet: building on jamie unit-flakelet-reconcile.service> structuredAttrs is enabled unit-flakelet-reconcile.service> structuredAttrs is enabled building '/nix/store/wc4h2q51nzgynkyi98kq6bajh2biwq1k-system-units.drv' building '/nix/store/walmk7rcqyfkb4ibp8yc4pgcip4m26k2-system-units.drv' system-units> tribuchet: building on jamie system-units> tribuchet: building on jamie building '/nix/store/kdf4sj0gb9z1qb0hhxhq6xpancqjq013-etc.drv' building '/nix/store/rs9maphd5pdkz3azls92n3r8lg2qvm51-etc.drv' etc> tribuchet: building on jamie etc> tribuchet: building on jamie building '/nix/store/qsqpvw82130q88hsrc3qy891kq60lwx1-activate.drv' building '/nix/store/0n772z25zazg9ggrx3gz4fxpik283j9x-activate.drv' activate> tribuchet: building on jamie activate> tribuchet: building on jamie building '/nix/store/s5bpvvqqci94v2kfjp591njj56lmm2qj-nixos-system-agent-test.drv' building '/nix/store/3mgdq1vl64x3blkpsib6ddzficscmssl-nixos-system-relay-test.drv' nixos-system-agent-test> tribuchet: building on jamie nixos-system-relay-test> tribuchet: building on jamie nixos-system-agent-test> structuredAttrs is enabled building '/nix/store/nzl2hvp3ay4gghmaq80i92v8774k5wws-run-agent-nspawn.drv' nixos-system-relay-test> structuredAttrs is enabled run-agent-nspawn> tribuchet: building on jamie building '/nix/store/a6mxw2x82qqnbys14ap7hl2w24lih9k0-run-relay-nspawn.drv' run-relay-nspawn> tribuchet: building on jamie building '/nix/store/bi3j5vb0ip70j6lz5c43qmsrfn257k1c-driverConfiguration.json.drv' driverConfiguration.json> tribuchet: building on jamie driverConfiguration.json> structuredAttrs is enabled building '/nix/store/szx762l1b8rjhj2fflhsh7ha36lwrcf2-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/4s3caza21gvy102fwsshsycd4d6k84s9-container-test-run-flakelet-relay.drv' container-test-run-flakelet-relay> tribuchet: building on jamie container-test-run-flakelet-relay> Machine state will be reset. To keep it, pass --keep-machine-state container-test-run-flakelet-relay> start all VLans container-test-run-flakelet-relay> (finished: start all VLans, in 0.00 seconds) container-test-run-flakelet-relay> Initializing machine ID from random generator. container-test-run-flakelet-relay> Test will time out and terminate in 3600.0 seconds container-test-run-flakelet-relay> run the VM test script container-test-run-flakelet-relay> start all VMs container-test-run-flakelet-relay> agent: systemd-nspawn running (pid 52) container-test-run-flakelet-relay> client: systemd-nspawn running (pid 54) container-test-run-flakelet-relay> agent: Waiting for journal at /build/vm-state-agent/var/log/journal... container-test-run-flakelet-relay> client: Waiting for journal at /build/vm-state-client/var/log/journal... container-test-run-flakelet-relay> relay: systemd-nspawn running (pid 56) 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 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 client on /build/vm-state-client. 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 # [13779.732380] agent systemd-journald[57]: Journal started container-test-run-flakelet-relay> agent # [13779.732423] agent systemd-journald[57]: Runtime Journal (/run/log/journal/0a227b56a4594bfca0c93f50f6110508) is 8M, max 4G, 3.9G free. container-test-run-flakelet-relay> agent # [13779.743181] agent systemd[1]: Finished Apply Kernel Variables. container-test-run-flakelet-relay> agent # [13779.756783] agent systemd[1]: Finished Create Static Device Nodes in /dev gracefully. container-test-run-flakelet-relay> agent # [13779.797571] agent systemd[1]: Starting Flush Journal to Persistent Storage... container-test-run-flakelet-relay> agent # [13779.798512] agent systemd[1]: Starting Create Static Device Nodes in /dev... container-test-run-flakelet-relay> agent # [13779.807556] agent systemd-journald[57]: Time spent on flushing to /var/log/journal/0a227b56a4594bfca0c93f50f6110508 is 2.505ms for 6 entries. container-test-run-flakelet-relay> agent # [13779.807556] agent systemd-journald[57]: System Journal (/var/log/journal/0a227b56a4594bfca0c93f50f6110508) is 512B, max 4G, 3.9G free. container-test-run-flakelet-relay> agent # [13779.816659] agent systemd[1]: Finished Create Static Device Nodes in /dev. container-test-run-flakelet-relay> agent # [13779.817520] agent systemd[1]: Reached target Preparation for Local File Systems. container-test-run-flakelet-relay> agent # [13779.817613] agent systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys container-test-run-flakelet-relay> agent # [13779.841621] agent systemd[1]: Finished Flush Journal to Persistent Storage. container-test-run-flakelet-relay> agent # [13779.912099] agent systemd[1]: Finished Firewall. container-test-run-flakelet-relay> agent # [13780.742140] agent systemd[1]: Mounting /run/wrappers... container-test-run-flakelet-relay> agent # [13780.783755] agent systemd[1]: Mounted /run/wrappers. container-test-run-flakelet-relay> agent # [13780.784875] agent systemd[1]: Reached target Local File Systems. container-test-run-flakelet-relay> agent # [13780.786218] agent systemd[1]: Listening on Boot Loader Control Service Socket. container-test-run-flakelet-relay> agent # [13780.787627] agent systemd[1]: Starting Create SUID/SGID Wrappers... container-test-run-flakelet-relay> agent # [13780.787674] agent systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container container-test-run-flakelet-relay> agent # [13780.788684] agent systemd[1]: Starting Save Transient machine-id to Disk... container-test-run-flakelet-relay> agent # [13780.789767] agent systemd[1]: Starting Create System Files and Directories... container-test-run-flakelet-relay> client # [13779.733014] client systemd-journald[57]: Journal started container-test-run-flakelet-relay> client # [13779.733058] client systemd-journald[57]: Runtime Journal (/run/log/journal/768415d70e1f4bbdb72c81cb9b697b8a) is 8M, max 4G, 3.9G free. container-test-run-flakelet-relay> client # [13779.743856] client systemd[1]: Finished Apply Kernel Variables. container-test-run-flakelet-relay> client # [13779.757357] client systemd[1]: Finished Create Static Device Nodes in /dev gracefully. container-test-run-flakelet-relay> client # [13779.797526] client systemd[1]: Starting Flush Journal to Persistent Storage... container-test-run-flakelet-relay> client # [13779.798463] client systemd[1]: Starting Create Static Device Nodes in /dev... container-test-run-flakelet-relay> client # [13779.806872] client systemd-journald[57]: Time spent on flushing to /var/log/journal/768415d70e1f4bbdb72c81cb9b697b8a is 3.002ms for 6 entries. container-test-run-flakelet-relay> client # [13779.806872] client systemd-journald[57]: System Journal (/var/log/journal/768415d70e1f4bbdb72c81cb9b697b8a) is 512B, max 4G, 3.9G free. container-test-run-flakelet-relay> client # [13779.817149] client systemd[1]: Finished Create Static Device Nodes in /dev. container-test-run-flakelet-relay> client # [13779.817837] client systemd[1]: Reached target Preparation for Local File Systems. container-test-run-flakelet-relay> client # [13779.817939] client systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys container-test-run-flakelet-relay> client # [13779.845422] client systemd[1]: Finished Flush Journal to Persistent Storage. container-test-run-flakelet-relay> client # [13779.907856] client systemd[1]: Finished Firewall. container-test-run-flakelet-relay> client # [13780.741785] client systemd[1]: Mounting /run/wrappers... container-test-run-flakelet-relay> client # [13780.783532] client systemd[1]: Mounted /run/wrappers. container-test-run-flakelet-relay> client # [13780.784584] client systemd[1]: Reached target Local File Systems. container-test-run-flakelet-relay> client # [13780.785822] client systemd[1]: Listening on Boot Loader Control Service Socket. container-test-run-flakelet-relay> client # [13780.787096] client systemd[1]: Starting Create SUID/SGID Wrappers... container-test-run-flakelet-relay> client # [13780.787145] client systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container container-test-run-flakelet-relay> client # [13780.788135] client systemd[1]: Starting Save Transient machine-id to Disk... container-test-run-flakelet-relay> client # [13780.789085] client systemd[1]: Starting Create System Files and Directories... container-test-run-flakelet-relay> relay # [13779.724433] relay systemd-journald[57]: Journal started container-test-run-flakelet-relay> relay # [13779.724474] relay systemd-journald[57]: Runtime Journal (/run/log/journal/c2e0cc80a76946869391671b60cc0c4f) is 8M, max 4G, 3.9G free. container-test-run-flakelet-relay> relay # [13779.734330] relay systemd[1]: Finished Apply Kernel Variables. container-test-run-flakelet-relay> relay # [13779.746334] relay systemd[1]: Finished Create Static Device Nodes in /dev gracefully. container-test-run-flakelet-relay> relay # [13779.760850] relay systemd[1]: Starting Flush Journal to Persistent Storage... container-test-run-flakelet-relay> relay # [13779.761913] relay systemd[1]: Starting Create Static Device Nodes in /dev... container-test-run-flakelet-relay> relay # [13779.803312] relay systemd-journald[57]: Time spent on flushing to /var/log/journal/c2e0cc80a76946869391671b60cc0c4f is 1.352ms for 6 entries. container-test-run-flakelet-relay> relay # [13779.803312] relay systemd-journald[57]: System Journal (/var/log/journal/c2e0cc80a76946869391671b60cc0c4f) is 512B, max 4G, 3.9G free. container-test-run-flakelet-relay> relay # [13779.813419] relay systemd[1]: Finished Create Static Device Nodes in /dev. container-test-run-flakelet-relay> relay # [13779.814218] relay systemd[1]: Reached target Preparation for Local File Systems. container-test-run-flakelet-relay> relay # [13779.814355] relay systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys container-test-run-flakelet-relay> relay # [13779.829617] relay systemd[1]: Finished Flush Journal to Persistent Storage. container-test-run-flakelet-relay> relay # [13779.923527] relay systemd[1]: Finished Firewall. container-test-run-flakelet-relay> relay # [13780.736314] relay systemd[1]: Mounting /run/wrappers... container-test-run-flakelet-relay> relay # [13780.766209] relay systemd[1]: Mounted /run/wrappers. container-test-run-flakelet-relay> relay # [13780.767015] relay systemd[1]: Reached target Local File Systems. container-test-run-flakelet-relay> relay # [13780.767813] relay systemd[1]: Listening on Boot Loader Control Service Socket. container-test-run-flakelet-relay> relay # [13780.768727] relay systemd[1]: Starting Create SUID/SGID Wrappers... container-test-run-flakelet-relay> relay # [13780.768754] relay systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container container-test-run-flakelet-relay> relay # [13780.769696] relay systemd[1]: Starting Save Transient machine-id to Disk... container-test-run-flakelet-relay> relay # [13780.770589] relay systemd[1]: Starting Create System Files and Directories... container-test-run-flakelet-relay> relay # [13780.789385] 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 # [13780.789789] relay systemd-tmpfiles[169]: fchmod() of /var/log/journal failed: Operation not permitted container-test-run-flakelet-relay> relay # [13780.790082] relay systemd-tmpfiles[169]: fchmod() of /var/log/journal/c2e0cc80a76946869391671b60cc0c4f failed: Operation not permitted container-test-run-flakelet-relay> relay # [13780.790414] relay systemd-tmpfiles[169]: fchmod() of /run/log/journal failed: Operation not permitted container-test-run-flakelet-relay> relay # [13780.796697] relay suid-sgid-wrappers-start[176]: chmod: changing permissions of '/run/wrappers/wrappers.XIevAPSPyS/chsh': Operation not permitted container-test-run-flakelet-relay> client # [13780.806497] 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 # [13780.806902] client systemd-tmpfiles[161]: fchmod() of /var/log/journal failed: Operation not permitted container-test-run-flakelet-relay> client # [13780.807301] client systemd-tmpfiles[161]: fchmod() of /var/log/journal/768415d70e1f4bbdb72c81cb9b697b8a failed: Operation not permitted container-test-run-flakelet-relay> client # [13780.807623] client systemd-tmpfiles[161]: fchmod() of /run/log/journal failed: Operation not permitted container-test-run-flakelet-relay> agent # [13780.811912] 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 # [13780.812345] agent systemd-tmpfiles[161]: fchmod() of /var/log/journal failed: Operation not permitted container-test-run-flakelet-relay> agent # [13780.812666] agent systemd-tmpfiles[161]: fchmod() of /var/log/journal/0a227b56a4594bfca0c93f50f6110508 failed: Operation not permitted container-test-run-flakelet-relay> agent # [13780.812998] agent systemd-tmpfiles[161]: fchmod() of /run/log/journal failed: Operation not permitted container-test-run-flakelet-relay> relay # [13780.799162] relay systemd[1]: suid-sgid-wrappers.service: Main process exited, code=exited, status=1/FAILURE container-test-run-flakelet-relay> relay # [13780.799409] relay systemd[1]: suid-sgid-wrappers.service: Failed with result 'exit-code'. container-test-run-flakelet-relay> relay # [13780.799595] relay systemd[1]: Failed to start Create SUID/SGID Wrappers. container-test-run-flakelet-relay> relay # [13780.799991] relay systemd[1]: Finished Create System Files and Directories. container-test-run-flakelet-relay> relay # [13780.819299] relay systemd[1]: Starting Rebuild Journal Catalog... container-test-run-flakelet-relay> relay # [13780.820163] relay systemd[1]: Starting Record System Boot/Shutdown in UTMP... container-test-run-flakelet-relay> relay # [13780.832318] relay systemd[1]: Finished Record System Boot/Shutdown in UTMP. container-test-run-flakelet-relay> relay # [13780.838868] relay systemd[1]: Finished Rebuild Journal Catalog. container-test-run-flakelet-relay> relay # [13780.840095] relay systemd[1]: Starting Update is Completed... container-test-run-flakelet-relay> relay # [13780.849417] relay systemd[1]: Finished Update is Completed. container-test-run-flakelet-relay> relay # [13780.849500] relay systemd[1]: Reached target System Initialization. container-test-run-flakelet-relay> relay # [13780.849561] relay systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container container-test-run-flakelet-relay> relay # [13780.849587] relay systemd[1]: Started Daily Cleanup of Temporary Directories. container-test-run-flakelet-relay> relay # [13780.849603] relay systemd[1]: Reached target Timer Units. container-test-run-flakelet-relay> relay # [13780.849704] relay systemd[1]: Listening on D-Bus System Message Bus Socket. container-test-run-flakelet-relay> relay # [13780.849873] relay systemd[1]: Listening on Nix Daemon Socket. container-test-run-flakelet-relay> relay # [13780.849963] relay systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. container-test-run-flakelet-relay> relay # [13780.849977] relay systemd[1]: Reached target Socket Units. container-test-run-flakelet-relay> relay # [13780.850011] relay systemd[1]: Reached target Basic System. container-test-run-flakelet-relay> relay # [13780.851198] relay systemd[1]: Started device-mock.service. container-test-run-flakelet-relay> relay # [13780.852157] relay systemd[1]: Starting Re-link flakelet services at boot... container-test-run-flakelet-relay> relay # [13780.854209] relay systemd[1]: Starting Import lastlog data into lastlog2 database... container-test-run-flakelet-relay> relay # [13780.855320] relay systemd[1]: Starting Name Service Cache Daemon (nsncd)... container-test-run-flakelet-relay> relay # [13780.856179] relay systemd[1]: Starting resolvconf update... container-test-run-flakelet-relay> relay # [13780.866693] relay systemd[1]: Finished Re-link flakelet services at boot. container-test-run-flakelet-relay> relay # [13780.868953] relay systemd[1]: Starting Reconcile flakelet services with the host configuration... container-test-run-flakelet-relay> relay # [13780.876016] relay systemd[1]: Finished Import lastlog data into lastlog2 database. container-test-run-flakelet-relay> relay # [13780.880745] relay systemd[1]: Finished Reconcile flakelet services with the host configuration. container-test-run-flakelet-relay> relay # [13780.943450] relay systemd[1]: nscd.service: Deactivated successfully. container-test-run-flakelet-relay> relay # [13780.943591] relay systemd[1]: Stopped Name Service Cache Daemon (nsncd). container-test-run-flakelet-relay> relay # [13780.950957] relay systemd[1]: Starting Name Service Cache Daemon (nsncd)... container-test-run-flakelet-relay> relay # [13780.992312] relay systemd[1]: Finished resolvconf update. container-test-run-flakelet-relay> client # [13780.814481] client suid-sgid-wrappers-start[168]: chmod: changing permissions of '/run/wrappers/wrappers.dHCPOKYvvx/chsh': Operation not permitted container-test-run-flakelet-relay> client # [13780.821514] client systemd[1]: suid-sgid-wrappers.service: Main process exited, code=exited, status=1/FAILURE container-test-run-flakelet-relay> client # [13780.821556] client systemd[1]: suid-sgid-wrappers.service: Failed with result 'exit-code'. container-test-run-flakelet-relay> client # [13780.821604] client systemd[1]: Failed to start Create SUID/SGID Wrappers. container-test-run-flakelet-relay> client # [13780.822080] client systemd[1]: Finished Create System Files and Directories. container-test-run-flakelet-relay> client # [13780.836517] client systemd[1]: Starting Rebuild Journal Catalog... container-test-run-flakelet-relay> client # [13780.837321] client systemd[1]: Starting Record System Boot/Shutdown in UTMP... container-test-run-flakelet-relay> client # [13780.850419] client systemd[1]: Finished Record System Boot/Shutdown in UTMP. container-test-run-flakelet-relay> client # [13780.855256] client systemd[1]: Finished Rebuild Journal Catalog. container-test-run-flakelet-relay> client # [13780.856579] client systemd[1]: Starting Update is Completed... container-test-run-flakelet-relay> client # [13780.867882] client systemd[1]: Finished Update is Completed. container-test-run-flakelet-relay> client # [13780.867971] client systemd[1]: Reached target System Initialization. container-test-run-flakelet-relay> client # [13780.868066] client systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container container-test-run-flakelet-relay> client # [13780.868116] client systemd[1]: Started Daily Cleanup of Temporary Directories. container-test-run-flakelet-relay> client # [13780.868135] client systemd[1]: Reached target Timer Units. container-test-run-flakelet-relay> client # [13780.868257] client systemd[1]: Listening on D-Bus System Message Bus Socket. container-test-run-flakelet-relay> client # [13780.868452] client systemd[1]: Listening on Nix Daemon Socket. container-test-run-flakelet-relay> client # [13780.868559] client systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. container-test-run-flakelet-relay> client # [13780.868580] client systemd[1]: Reached target Socket Units. container-test-run-flakelet-relay> client # [13780.868615] client systemd[1]: Reached target Basic System. container-test-run-flakelet-relay> client # [13780.869809] client systemd[1]: Starting Import lastlog data into lastlog2 database... container-test-run-flakelet-relay> client # [13780.870625] client systemd[1]: Starting Name Service Cache Daemon (nsncd)... container-test-run-flakelet-relay> client # [13780.871347] client systemd[1]: Starting resolvconf update... container-test-run-flakelet-relay> client # [13780.889157] client systemd[1]: Finished Import lastlog data into lastlog2 database. container-test-run-flakelet-relay> agent # [13780.813805] agent suid-sgid-wrappers-start[168]: chmod: changing permissions of '/run/wrappers/wrappers.Y0g9u8eZuO/chsh': Operation not permitted container-test-run-flakelet-relay> agent # [13780.824272] agent systemd[1]: suid-sgid-wrappers.service: Main process exited, code=exited, status=1/FAILURE container-test-run-flakelet-relay> agent # [13780.824311] agent systemd[1]: suid-sgid-wrappers.service: Failed with result 'exit-code'. container-test-run-flakelet-relay> agent # [13780.824367] agent systemd[1]: Failed to start Create SUID/SGID Wrappers. container-test-run-flakelet-relay> agent # [13780.824909] agent systemd[1]: Finished Create System Files and Directories. container-test-run-flakelet-relay> agent # [13780.841509] agent systemd[1]: Starting Rebuild Journal Catalog... container-test-run-flakelet-relay> agent # [13780.842395] agent systemd[1]: Starting Record System Boot/Shutdown in UTMP... container-test-run-flakelet-relay> agent # [13780.856033] agent systemd[1]: Finished Record System Boot/Shutdown in UTMP. container-test-run-flakelet-relay> agent # [13780.863048] agent systemd[1]: Finished Rebuild Journal Catalog. container-test-run-flakelet-relay> agent # [13780.864144] agent systemd[1]: Starting Update is Completed... container-test-run-flakelet-relay> agent # [13780.874962] agent systemd[1]: Finished Update is Completed. container-test-run-flakelet-relay> agent # [13780.875106] agent systemd[1]: Reached target System Initialization. container-test-run-flakelet-relay> agent # [13780.875168] agent systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container container-test-run-flakelet-relay> agent # [13780.875194] agent systemd[1]: Started Daily Cleanup of Temporary Directories. container-test-run-flakelet-relay> agent # [13780.875207] agent systemd[1]: Reached target Timer Units. container-test-run-flakelet-relay> agent # [13780.875301] agent systemd[1]: Listening on D-Bus System Message Bus Socket. container-test-run-flakelet-relay> agent # [13780.875481] agent systemd[1]: Listening on Nix Daemon Socket. container-test-run-flakelet-relay> agent # [13780.875578] agent systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. container-test-run-flakelet-relay> agent # [13780.875592] agent systemd[1]: Reached target Socket Units. container-test-run-flakelet-relay> agent # [13780.875714] agent systemd[1]: Reached target Basic System. container-test-run-flakelet-relay> agent # [13780.877034] agent systemd[1]: Starting Re-link flakelet services at boot... container-test-run-flakelet-relay> agent # [13780.877641] agent systemd[1]: Starting Import lastlog data into lastlog2 database... container-test-run-flakelet-relay> agent # [13780.878416] agent systemd[1]: Starting Name Service Cache Daemon (nsncd)... container-test-run-flakelet-relay> agent # [13780.879092] agent systemd[1]: Starting resolvconf update... container-test-run-flakelet-relay> agent # [13780.890339] agent systemd[1]: Finished Re-link flakelet services at boot. container-test-run-flakelet-relay> agent # [13780.891414] agent systemd[1]: Starting Reconcile flakelet services with the host configuration... container-test-run-flakelet-relay> agent # [13780.893267] agent systemd[1]: Finished Import lastlog data into lastlog2 database. container-test-run-flakelet-relay> agent # [13780.906424] agent systemd[1]: Finished Reconcile flakelet services with the host configuration. container-test-run-flakelet-relay> agent # [13780.999673] agent systemd[1]: nscd.service: Deactivated successfully. container-test-run-flakelet-relay> agent # [13780.999967] agent systemd[1]: Stopped Name Service Cache Daemon (nsncd). container-test-run-flakelet-relay> agent # [13781.002812] agent systemd[1]: Finished resolvconf update. container-test-run-flakelet-relay> agent # [13781.004245] agent systemd[1]: Reached target Preparation for Network. container-test-run-flakelet-relay> agent # [13781.005977] agent systemd[1]: Starting Address configuration of eth1... container-test-run-flakelet-relay> agent # [13781.007326] agent systemd[1]: Starting Extra networking commands.... container-test-run-flakelet-relay> agent # [13781.008968] agent systemd[1]: Starting Name Service Cache Daemon (nsncd)... container-test-run-flakelet-relay> agent # [13781.027938] agent network-addresses-eth1-start[239]: adding address 192.168.1.1/24... done container-test-run-flakelet-relay> agent # [13781.031246] agent network-addresses-eth1-start[239]: adding address 2001:db8:1::1/64... done container-test-run-flakelet-relay> agent # [13781.036937] agent systemd[1]: Finished Address configuration of eth1. container-test-run-flakelet-relay> relay # [13780.993669] relay systemd[1]: Reached target Preparation for Network. container-test-run-flakelet-relay> agent # [13781.091067] agent systemd[1]: nscd.service: Deactivated successfully. container-test-run-flakelet-relay> relay # [13780.995647] relay systemd[1]: Starting Address configuration of eth1... container-test-run-flakelet-relay> agent # [13781.091317] agent systemd[1]: Stopped Name Service Cache Daemon (nsncd). container-test-run-flakelet-relay> relay # [13780.997366] relay systemd[1]: Starting Extra networking commands.... container-test-run-flakelet-relay> agent # [13781.093705] agent systemd[1]: Finished Extra networking commands.. container-test-run-flakelet-relay> relay # [13781.018512] relay network-addresses-eth1-start[250]: adding address 192.168.1.3/24... done container-test-run-flakelet-relay> agent # [13781.094927] agent systemd[1]: Reached target Network. container-test-run-flakelet-relay> relay # [13781.022299] relay network-addresses-eth1-start[250]: adding address 2001:db8:1::3/64... done container-test-run-flakelet-relay> agent # [13781.096499] agent systemd[1]: Starting Name Service Cache Daemon (nsncd)... container-test-run-flakelet-relay> relay # [13781.029039] relay systemd[1]: Finished Address configuration of eth1. container-test-run-flakelet-relay> agent # [13781.097842] agent systemd[1]: Starting Update flakelet service app... container-test-run-flakelet-relay> relay # [13781.065435] relay systemd[1]: Finished Extra networking commands.. container-test-run-flakelet-relay> agent # [13781.099073] agent systemd[1]: Starting Update flakelet service flakelet-agent... container-test-run-flakelet-relay> relay # [13781.066103] relay systemd[1]: Reached target Network. container-test-run-flakelet-relay> agent # [13781.100259] agent systemd[1]: Starting Update flakelet service other... container-test-run-flakelet-relay> relay # [13781.067564] relay systemd[1]: Starting Dnsmasq Daemon... container-test-run-flakelet-relay> agent # [13781.110927] agent flakelet[308]: flakelet-agent: using prebuilt artifact /nix/store/8kqvay1p8vv145jvk6s1sp0j3q4w3drk-flakelet-flakelet-agent container-test-run-flakelet-relay> relay # [13781.068437] relay systemd[1]: Starting Nginx Web Server... container-test-run-flakelet-relay> agent # [13781.111574] agent flakelet[307]: app: using prebuilt artifact /nix/store/hysydfr96ds875vr0jgw5rjxi4p39h2j-flakelet-app-v1 container-test-run-flakelet-relay> relay # [13781.069373] relay systemd[1]: Starting Update flakelet service flakelet-relay... container-test-run-flakelet-relay> agent # [13781.112074] agent flakelet[307]: app: activating generation 1 container-test-run-flakelet-relay> relay # [13781.094302] relay flakelet[310]: flakelet-relay: using prebuilt artifact /nix/store/b2nmz15xs52x4jlbl3w1w7mjivxz0lxn-flakelet-flakelet-relay container-test-run-flakelet-relay> agent # [13781.112129] agent flakelet[308]: flakelet-agent: activating generation 1 container-test-run-flakelet-relay> relay # [13781.094983] relay flakelet[310]: flakelet-relay: activating generation 1 container-test-run-flakelet-relay> agent # [13781.113739] agent flakelet[309]: other: using prebuilt artifact /nix/store/hmr990n0b4v6rpzrcpyyw738r9xi7z95-flakelet-other-v1 container-test-run-flakelet-relay> relay # [13781.099035] relay systemd[1]: Reload requested from client PID 315 ('systemctl') (unit flakelet-flakelet-relay.service)... container-test-run-flakelet-relay> agent # [13781.114351] agent flakelet[309]: other: activating generation 1 container-test-run-flakelet-relay> relay # [13781.099074] relay systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [13781.119788] agent systemd[1]: Reload requested from client PID 312 ('systemctl') (unit flakelet-app.service)... container-test-run-flakelet-relay> agent # [13781.119853] agent systemd[1]: Reloading... container-test-run-flakelet-relay> client # [13780.994392] client systemd[1]: Finished resolvconf update. container-test-run-flakelet-relay> client # [13780.994657] client systemd[1]: Reached target Preparation for Network. container-test-run-flakelet-relay> client # [13780.995995] client systemd[1]: Starting Address configuration of eth1... container-test-run-flakelet-relay> client # [13780.997220] client systemd[1]: Starting Extra networking commands.... container-test-run-flakelet-relay> client # [13780.997549] client systemd[1]: nscd.service: Deactivated successfully. container-test-run-flakelet-relay> client # [13780.997729] client systemd[1]: Stopped Name Service Cache Daemon (nsncd). container-test-run-flakelet-relay> client # [13781.001666] client systemd[1]: Starting Name Service Cache Daemon (nsncd)... container-test-run-flakelet-relay> client # [13781.018470] client network-addresses-eth1-start[233]: adding address 192.168.1.2/24... done container-test-run-flakelet-relay> client # [13781.022601] client network-addresses-eth1-start[233]: adding address 2001:db8:1::2/64... done container-test-run-flakelet-relay> client # [13781.027612] client systemd[1]: Finished Address configuration of eth1. container-test-run-flakelet-relay> client # [13781.086714] client systemd[1]: Finished Extra networking commands.. container-test-run-flakelet-relay> client # [13781.090651] client systemd[1]: Reached target Network. container-test-run-flakelet-relay> client # [13781.092780] client systemd[1]: nscd.service: Deactivated successfully. container-test-run-flakelet-relay> client # [13781.092973] client systemd[1]: Stopped Name Service Cache Daemon (nsncd). container-test-run-flakelet-relay> client # [13781.096482] client systemd[1]: Starting Name Service Cache Daemon (nsncd)... container-test-run-flakelet-relay> relay # [13781.458029] relay nsncd[242]: Sep 06 10:54:19.419 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" container-test-run-flakelet-relay> relay # [13781.525019] relay systemd[1]: Reloading finished in 425 ms. container-test-run-flakelet-relay> relay # [13781.668391] relay systemd[1]: Started Name Service Cache Daemon (nsncd). container-test-run-flakelet-relay> relay # [13781.668886] relay systemd[1]: Reached target Host and Network Name Lookups. container-test-run-flakelet-relay> relay # [13781.668975] relay systemd[1]: Reached target User and Group Name Lookups. container-test-run-flakelet-relay> relay # [13781.670184] relay systemd[1]: Starting User Login Management... container-test-run-flakelet-relay> relay # [13781.671248] relay systemd[1]: Starting Permit User Sessions... container-test-run-flakelet-relay> relay # [13781.686839] relay systemd[1]: Reload requested from client PID 357 ('systemctl') (unit flakelet-flakelet-relay.service)... container-test-run-flakelet-relay> relay # [13781.686874] relay systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [13781.422665] agent nsncd[306]: Sep 06 10:54:19.384 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" container-test-run-flakelet-relay> agent # [13781.518358] agent systemd[1]: Reloading finished in 397 ms. container-test-run-flakelet-relay> agent # [13781.709979] agent systemd[1]: Started Name Service Cache Daemon (nsncd). container-test-run-flakelet-relay> client # [13781.428777] client nsncd[302]: Sep 06 10:54:19.390 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" container-test-run-flakelet-relay> client # [13781.428904] client systemd[1]: Started Name Service Cache Daemon (nsncd). container-test-run-flakelet-relay> client # [13781.428984] client systemd[1]: Reached target Host and Network Name Lookups. container-test-run-flakelet-relay> client # [13781.429159] client systemd[1]: Reached target User and Group Name Lookups. container-test-run-flakelet-relay> client # [13781.431710] client systemd[1]: Starting User Login Management... container-test-run-flakelet-relay> client # [13781.433222] client systemd[1]: Starting Permit User Sessions... container-test-run-flakelet-relay> client # [13781.472564] client systemd[1]: Finished Permit User Sessions. container-test-run-flakelet-relay> client # [13781.474806] client systemd[1]: Started Console Getty. container-test-run-flakelet-relay> client # [13781.474861] client systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0 container-test-run-flakelet-relay> client # [13781.474891] client systemd[1]: Reached target Login Prompts. container-test-run-flakelet-relay> agent # [13781.714601] agent systemd[1]: Reload requested from client PID 311 ('systemctl') (unit flakelet-flakelet-agent.service)... container-test-run-flakelet-relay> agent # [13781.714675] agent systemd[1]: Reloading... container-test-run-flakelet-relay> relay # [13781.729473] relay dnsmasq-pre-start[375]: dnsmasq: syntax check OK. container-test-run-flakelet-relay> client # [13781.887419] client systemd[1]: etc-machine\x2did.mount: Deactivated successfully. container-test-run-flakelet-relay> client # [13781.888451] client systemd[1]: Finished Save Transient machine-id to Disk. container-test-run-flakelet-relay> agent # [13782.146805] agent systemd[1]: etc-machine\x2did.mount: Deactivated successfully. container-test-run-flakelet-relay> agent # [13782.146991] agent systemd[1]: Reloading finished in 431 ms. container-test-run-flakelet-relay> agent # [13782.305636] agent systemd[1]: Finished Save Transient machine-id to Disk. container-test-run-flakelet-relay> agent # [13782.306339] agent systemd[1]: Reload requested from client PID 313 ('systemctl') (unit flakelet-other.service)... container-test-run-flakelet-relay> agent # [13782.306386] agent systemd[1]: Reloading... container-test-run-flakelet-relay> relay # [13782.157431] relay systemd[1]: etc-machine\x2did.mount: Deactivated successfully. container-test-run-flakelet-relay> relay # [13782.157806] relay systemd[1]: Reloading finished in 470 ms. container-test-run-flakelet-relay> relay # [13782.303914] relay systemd[1]: Finished Save Transient machine-id to Disk. container-test-run-flakelet-relay> relay # [13782.305304] relay systemd[1]: Finished Permit User Sessions. container-test-run-flakelet-relay> relay # [13782.337639] relay systemd[1]: Started Console Getty. container-test-run-flakelet-relay> relay # [13782.337724] relay systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0 container-test-run-flakelet-relay> relay # [13782.337796] relay systemd[1]: Reached target Login Prompts. container-test-run-flakelet-relay> relay # [13782.340089] relay systemd[1]: Starting flakelet-relay... container-test-run-flakelet-relay> agent # [13782.680890] agent systemd[1]: Reloading finished in 373 ms. container-test-run-flakelet-relay> agent # [13782.752679] agent systemd[1]: Reached target Host and Network Name Lookups. container-test-run-flakelet-relay> agent # [13782.752807] agent systemd[1]: Reached target User and Group Name Lookups. container-test-run-flakelet-relay> agent # [13782.754301] agent systemd[1]: Starting User Login Management... container-test-run-flakelet-relay> agent # [13782.755303] agent systemd[1]: Starting Permit User Sessions... container-test-run-flakelet-relay> agent # [13782.758984] agent systemd[1]: Reload requested from client PID 408 ('systemctl') (unit flakelet-app.service)... container-test-run-flakelet-relay> agent # [13782.759020] agent systemd[1]: Reloading... container-test-run-flakelet-relay> relay # [13782.883352] relay nginx-pre-start[404]: nginx: the configuration file /nix/store/01avjj26wcihp926xgasszcj5s0k7cbs-nginx.conf syntax is ok container-test-run-flakelet-relay> relay # [13782.883992] relay nginx-pre-start[404]: nginx: configuration file /nix/store/01avjj26wcihp926xgasszcj5s0k7cbs-nginx.conf test is successful container-test-run-flakelet-relay> relay # [13782.904155] relay systemd[1]: Started Nginx Web Server. container-test-run-flakelet-relay> relay # [13782.933133] relay systemd[1]: Starting D-Bus System Message Bus... container-test-run-flakelet-relay> client # [13783.013414] client systemd-logind[314]: New seat seat0. container-test-run-flakelet-relay> client # [13783.013856] client systemd[1]: Started User Login Management. container-test-run-flakelet-relay> client # [13783.015859] client systemd[1]: Starting D-Bus System Message Bus... container-test-run-flakelet-relay> client # [13783.017401] client systemd[1]: Starting linger-users.service... container-test-run-flakelet-relay> client # [13783.056228] client systemd[1]: linger-users.service: Deactivated successfully. container-test-run-flakelet-relay> client # [13783.056617] client systemd[1]: Finished linger-users.service. container-test-run-flakelet-relay> client # [13783.056974] client systemd[1]: Reached target Multi-User System. container-test-run-flakelet-relay> relay # [13783.229963] relay systemd-logind[354]: New seat seat0. container-test-run-flakelet-relay> relay # [13783.230370] relay systemd[1]: Started User Login Management. container-test-run-flakelet-relay> relay # [13783.232848] relay systemd[1]: Starting linger-users.service... container-test-run-flakelet-relay> relay # [13783.280830] relay systemd[1]: linger-users.service: Deactivated successfully. container-test-run-flakelet-relay> relay # [13783.281196] relay systemd[1]: Finished linger-users.service. container-test-run-flakelet-relay> agent # [13783.137411] agent systemd[1]: Reloading finished in 378 ms. container-test-run-flakelet-relay> agent # [13783.218743] agent systemd[1]: Finished Permit User Sessions. container-test-run-flakelet-relay> agent # [13783.224488] agent systemd[1]: Started Console Getty. container-test-run-flakelet-relay> agent # [13783.224579] agent systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0 container-test-run-flakelet-relay> agent # [13783.224689] agent systemd[1]: Reached target Login Prompts. container-test-run-flakelet-relay> agent # [13783.226799] agent systemd[1]: Reload requested from client PID 412 ('systemctl') (unit flakelet-flakelet-agent.service)... container-test-run-flakelet-relay> agent # [13783.226828] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [13783.561938] agent systemd[1]: Reloading finished in 334 ms. container-test-run-flakelet-relay> agent # [13783.647996] agent systemd[1]: Starting app v1... container-test-run-flakelet-relay> agent # [13783.649635] agent systemd[1]: Reload requested from client PID 448 ('systemctl') (unit flakelet-other.service)... container-test-run-flakelet-relay> agent # [13783.649675] agent systemd[1]: Reloading... container-test-run-flakelet-relay> relay # [13783.497850] relay dbus-broker-launch[408]: Looking up NSS user entry for 'systemd-timesync'... container-test-run-flakelet-relay> relay # [13783.498882] relay dbus-broker-launch[408]: NSS returned no entry for 'systemd-timesync' container-test-run-flakelet-relay> relay # [13783.498882] 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 # [13783.499741] relay systemd[1]: Started D-Bus System Message Bus. container-test-run-flakelet-relay> relay # [13783.505805] relay dbus-broker-launch[408]: Ready container-test-run-flakelet-relay> relay # [13783.509447] relay systemd[1]: Started Dnsmasq Daemon. container-test-run-flakelet-relay> relay # [13783.511458] relay dnsmasq[396]: started, version 2.93 cachesize 150 container-test-run-flakelet-relay> relay # [13783.511473] relay dnsmasq[396]: 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 # [13783.511486] relay dnsmasq[396]: DBus support enabled: connected to system bus container-test-run-flakelet-relay> relay # [13783.511497] relay dnsmasq[396]: no upstream servers configured - please set them from DBus container-test-run-flakelet-relay> relay # [13783.511625] relay dnsmasq[396]: read /etc/hosts - 9 names container-test-run-flakelet-relay> client # [13783.537175] client dbus-broker-launch[325]: Looking up NSS user entry for 'systemd-timesync'... container-test-run-flakelet-relay> client # [13783.538567] client dbus-broker-launch[325]: NSS returned no entry for 'systemd-timesync' container-test-run-flakelet-relay> client # [13783.538567] client dbus-broker-launch[325]: Invalid user-name in /nix/store/hxpsm3bnqlhm74a81cmd7dmp88rc8hak-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" container-test-run-flakelet-relay> client # [13783.539304] client systemd[1]: Started D-Bus System Message Bus. container-test-run-flakelet-relay> client # [13783.539562] client systemd[1]: Startup finished in 4.230s. container-test-run-flakelet-relay> client # [13783.545936] client dbus-broker-launch[325]: Ready container-test-run-flakelet-relay> agent # [13783.971718] agent systemd[1]: Reloading finished in 321 ms. container-test-run-flakelet-relay> agent # [13784.047764] agent systemd[1]: Started app v1. container-test-run-flakelet-relay> agent # [13784.051762] agent systemd[1]: Starting flakelet-agent... container-test-run-flakelet-relay> agent # [13784.094949] agent systemd-logind[409]: New seat seat0. container-test-run-flakelet-relay> agent # [13784.103935] agent systemd[1]: Starting other v1... container-test-run-flakelet-relay> agent # [13784.104131] agent systemd[1]: Started User Login Management. container-test-run-flakelet-relay> agent # [13784.119977] agent systemd[1]: Started other v1. container-test-run-flakelet-relay> agent # [13784.121938] agent systemd[1]: Starting D-Bus System Message Bus... container-test-run-flakelet-relay> agent # [13784.122898] agent systemd[1]: Starting linger-users.service... container-test-run-flakelet-relay> agent # [13784.127552] agent flakelet[307]: app: updated to generation 1 container-test-run-flakelet-relay> agent # [13784.129058] agent systemd[1]: Finished Update flakelet service app. container-test-run-flakelet-relay> agent # [13784.135609] agent flakelet[309]: other: updated to generation 1 container-test-run-flakelet-relay> agent # [13784.137141] agent systemd[1]: Finished Update flakelet service other. container-test-run-flakelet-relay> agent # [13784.137451] agent systemd[1]: linger-users.service: Deactivated successfully. container-test-run-flakelet-relay> agent # [13784.137572] agent systemd[1]: Finished linger-users.service. container-test-run-flakelet-relay> agent # [13784.193213] agent systemd[1]: Started flakelet-agent. container-test-run-flakelet-relay> agent # [13784.196368] agent flakelet-agent[507]: {"timestamp":"2026-09-06T10:54:22.157986Z","level":"WARN","message":"connect failed: connect relay:7443: Connection refused (os error 111)","relay":"https://relay:7443","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> agent # [13784.209023] agent flakelet[308]: flakelet-agent: updated to generation 1 container-test-run-flakelet-relay> agent # [13784.210172] agent systemd[1]: Finished Update flakelet service flakelet-agent. container-test-run-flakelet-relay> agent # [13784.212127] agent systemd[1]: Reached target flakelet managed services. container-test-run-flakelet-relay> agent # [13784.212170] agent systemd[1]: Reached target Multi-User System. container-test-run-flakelet-relay> relay # [13784.353723] relay flakelet-relay[402]: {"timestamp":"2026-09-06T10:54:22.315369Z","level":"INFO","message":"listening on 127.0.0.1:7400","target":"flakelet_relay::relay::server"} container-test-run-flakelet-relay> relay # [13784.354604] relay flakelet-relay[402]: {"timestamp":"2026-09-06T10:54:22.316088Z","level":"INFO","message":"listening on [::]:7443","target":"flakelet_relay::relay::server"} container-test-run-flakelet-relay> relay # [13784.354414] relay systemd[1]: Started flakelet-relay. container-test-run-flakelet-relay> relay # [13784.363964] relay systemd[1]: Starting health for flakelet-relay... container-test-run-flakelet-relay> agent # [13784.596364] agent dbus-broker-launch[670]: Looking up NSS user entry for 'systemd-timesync'... container-test-run-flakelet-relay> agent # [13784.597582] agent dbus-broker-launch[670]: NSS returned no entry for 'systemd-timesync' container-test-run-flakelet-relay> agent # [13784.597582] agent dbus-broker-launch[670]: 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 # [13784.598396] agent systemd[1]: Started D-Bus System Message Bus. container-test-run-flakelet-relay> agent # [13784.598702] agent systemd[1]: Startup finished in 5.291s. container-test-run-flakelet-relay> agent # [13784.604184] agent dbus-broker-launch[670]: Ready 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.01 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> relay # [13784.670075] relay systemd[1]: flakelet-relay-health.service: Deactivated successfully. container-test-run-flakelet-relay> relay # [13784.670457] relay systemd[1]: Finished health for flakelet-relay. container-test-run-flakelet-relay> relay # [13784.712756] relay flakelet[310]: flakelet-relay: updated to generation 1 container-test-run-flakelet-relay> relay # [13784.716158] relay systemd[1]: Finished Update flakelet service flakelet-relay. container-test-run-flakelet-relay> relay # [13784.716704] relay systemd[1]: Reached target flakelet managed services. container-test-run-flakelet-relay> relay # [13784.716887] relay systemd[1]: Reached target Multi-User System. container-test-run-flakelet-relay> relay # [13784.717161] relay systemd[1]: Startup finished in 5.408s. container-test-run-flakelet-relay> relay # [13786.216354] relay flakelet-relay[402]: {"timestamp":"2026-09-06T10:54:24.178118Z","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> agent # [13786.216108] agent flakelet-agent[507]: {"timestamp":"2026-09-06T10:54:24.177757Z","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: (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.02 seconds) container-test-run-flakelet-relay> client: must succeed: push --relay-srv test --cert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin.pem --key /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin-key.pem deploy agent/app 2>&1 container-test-run-flakelet-relay> agent # [13787.123692] agent flakelet-agent[507]: {"timestamp":"2026-09-06T10:54:25.085409Z","level":"INFO","message":"start","id":"227a864ccfa28d2ecb018f5f289bafcf","flakelet":"app","rule":"admins","relay":"relay","caller_name":"Some(\"admin@example.org\")","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> agent # [13787.124618] agent flakelet-agent[507]: {"timestamp":"2026-09-06T10:54:25.086419Z","level":"INFO","message":"update starting","flakelet":"app","ids":"[\"227a864ccfa28d2ecb018f5f289bafcf\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [13787.191895] agent systemd[1]: Starting flakelet update app (via relay)... container-test-run-flakelet-relay> agent # [13787.223026] agent systemd[1]: Started flakelet update app (via relay). container-test-run-flakelet-relay> agent # [13787.227748] agent flakelet[962]: app: using prebuilt artifact /nix/store/6ili1vxjlag9d7dy0bxrbxk6bpi5qh9p-flakelet-app-v2 container-test-run-flakelet-relay> agent # [13787.228446] agent flakelet[962]: app: activating generation 2 container-test-run-flakelet-relay> agent # [13787.241803] agent systemd[1]: Stopping app v1... container-test-run-flakelet-relay> agent # [13787.242307] agent systemd[1]: app.service: Deactivated successfully. container-test-run-flakelet-relay> agent # [13787.242588] agent systemd[1]: Stopped app v1. container-test-run-flakelet-relay> agent # [13787.259640] agent systemd[1]: Reload requested from client PID 969 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [13787.259708] agent systemd[1]: Reloading... container-test-run-flakelet-relay> relay # [13787.122844] relay flakelet-relay[402]: {"timestamp":"2026-09-06T10:54:25.084617Z","level":"INFO","message":"deploy accepted","job":"709c2bd7e751ca0e797473fae9dbe115","caller":"admin@example.org","targets":"[\"agent/app\"]","target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> agent # [13787.590257] agent systemd[1]: Reloading finished in 329 ms. container-test-run-flakelet-relay> agent # [13787.679496] agent systemd[1]: Reload requested from client PID 999 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [13787.679539] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [13788.001781] agent systemd[1]: Reloading finished in 321 ms. container-test-run-flakelet-relay> agent # [13788.085828] agent systemd[1]: Reload requested from client PID 1028 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [13788.085870] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [13788.407057] agent systemd[1]: Reloading finished in 320 ms. container-test-run-flakelet-relay> agent # [13788.498446] agent systemd[1]: Reload requested from client PID 1062 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [13788.498482] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [13788.816717] agent systemd[1]: Reloading finished in 317 ms. container-test-run-flakelet-relay> agent # [13788.895779] agent systemd[1]: Starting app v2... container-test-run-flakelet-relay> agent # [13788.926242] agent systemd[1]: Started app v2. container-test-run-flakelet-relay> agent # [13788.940746] agent flakelet[962]: app: updated to generation 2 container-test-run-flakelet-relay> agent # [13788.941864] 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.97 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.01 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.06 seconds) container-test-run-flakelet-relay> client: must succeed: FLAKELET_RELAY_TOKEN_COMMAND='echo eyJhbGciOiJFZERTQSIsImtpZCI6ImsxIiwidHlwIjoiSldUIn0.eyJhdWQiOiJmbGFrZWxldC1yZWxheSIsImV4cCI6MTc4ODY5MjM2OCwiZ3JvdXBzIjpbXSwiaWF0IjoxNzg4NjkyMDY4LCJpc3MiOiJodHRwczovL3JlbGF5Ojg0NDMiLCJqdGkiOiJSMlk0VjVMV1dMSE1PPT09IiwibmJmIjoxNzg4NjkyMDY4LCJzdWIiOiJyZXBvOmdpdGh1YjpleGFtcGxlL2FwcDpyZWY6cmVmcy9oZWFkcy9tYWluIn0.BjaQ7iUof7avjxG8XAigNhY3Ul7AFi-rv_Rh9y8bLiELSPEjQhGBVuzw9gCsO0dI33b0YWpMboh-ObHh77UyAQ' push --relay https://relay:7443 deploy agent/app 2>&1 container-test-run-flakelet-relay> agent # [13790.062456] agent flakelet-agent[507]: {"timestamp":"2026-09-06T10:54:28.024225Z","level":"INFO","message":"update finished","flakelet":"app","status":"Updated","generation":"Some(2)","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [13790.201069] agent flakelet-agent[507]: {"timestamp":"2026-09-06T10:54:28.162839Z","level":"INFO","message":"start","id":"2106b14872dd5cb43f025237819292bc","flakelet":"app","rule":"ci","relay":"relay","caller_name":"Some(\"repo:github:example/app:ref:refs/heads/main\")","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> agent # [13790.201517] agent flakelet-agent[507]: {"timestamp":"2026-09-06T10:54:28.163340Z","level":"INFO","message":"update starting","flakelet":"app","ids":"[\"2106b14872dd5cb43f025237819292bc\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [13790.277156] agent systemd[1]: Starting flakelet update app (via relay)... container-test-run-flakelet-relay> agent # [13790.310046] agent systemd[1]: Started flakelet update app (via relay). container-test-run-flakelet-relay> relay # [13790.064262] relay flakelet-relay[402]: {"timestamp":"2026-09-06T10:54:28.025952Z","level":"INFO","message":"deploy finished","job":"709c2bd7e751ca0e797473fae9dbe115","ok":true,"target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> relay # [13790.200847] relay flakelet-relay[402]: {"timestamp":"2026-09-06T10:54:28.162609Z","level":"INFO","message":"deploy accepted","job":"a51a7dd2ea78427e565f708e35e88100","caller":"repo:github:example/app:ref:refs/heads/main","targets":"[\"agent/app\"]","target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> agent # [13790.314694] agent flakelet[1160]: app: using prebuilt artifact /nix/store/hysydfr96ds875vr0jgw5rjxi4p39h2j-flakelet-app-v1 container-test-run-flakelet-relay> agent # [13790.315404] agent flakelet[1160]: app: activating generation 3 container-test-run-flakelet-relay> agent # [13790.323649] agent systemd[1]: Stopping app v2... container-test-run-flakelet-relay> agent # [13790.324357] agent systemd[1]: app.service: Deactivated successfully. container-test-run-flakelet-relay> agent # [13790.324484] agent systemd[1]: Stopped app v2. container-test-run-flakelet-relay> agent # [13790.334890] agent systemd[1]: Reload requested from client PID 1170 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [13790.334926] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [13790.657605] agent systemd[1]: Reloading finished in 322 ms. container-test-run-flakelet-relay> agent # [13790.741879] agent systemd[1]: Reload requested from client PID 1200 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [13790.741918] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [13791.064143] agent systemd[1]: Reloading finished in 321 ms. container-test-run-flakelet-relay> agent # [13791.158401] agent systemd[1]: Reload requested from client PID 1229 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [13791.158442] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [13791.482651] agent systemd[1]: Reloading finished in 323 ms. container-test-run-flakelet-relay> agent # [13791.566889] agent systemd[1]: Reload requested from client PID 1260 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [13791.566927] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [13791.892484] agent systemd[1]: Reloading finished in 325 ms. container-test-run-flakelet-relay> agent # [13791.977765] agent systemd[1]: Starting app v1... container-test-run-flakelet-relay> agent # [13792.010323] agent systemd[1]: Started app v1. container-test-run-flakelet-relay> agent # [13792.023028] agent flakelet[1160]: app: updated to generation 3 container-test-run-flakelet-relay> agent # [13792.025277] 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.eyJhdWQiOiJmbGFrZWxldC1yZWxheSIsImV4cCI6MTc4ODY5MjM2OCwiZ3JvdXBzIjpbXSwiaWF0IjoxNzg4NjkyMDY4LCJpc3MiOiJodHRwczovL3JlbGF5Ojg0NDMiLCJqdGkiOiJSMlk0VjVMV1dMSE1PPT09IiwibmJmIjoxNzg4NjkyMDY4LCJzdWIiOiJyZXBvOmdpdGh1YjpleGFtcGxlL2FwcDpyZWY6cmVmcy9oZWFkcy9tYWluIn0.BjaQ7iUof7avjxG8XAigNhY3Ul7AFi-rv_Rh9y8bLiELSPEjQhGBVuzw9gCsO0dI33b0YWpMboh-ObHh77UyAQ' push --relay https://relay:7443 deploy agent/app 2>&1, in 2.97 seconds) container-test-run-flakelet-relay> (finished: subtest: deploy with OIDC token, sub principal, in 3.04 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.eyJhdWQiOiJmbGFrZWxldC1yZWxheSIsImV4cCI6MTc4ODY5MjM2OCwiZ3JvdXBzIjpbXSwiaWF0IjoxNzg4NjkyMDY4LCJpc3MiOiJodHRwczovL3JlbGF5Ojg0NDMiLCJqdGkiOiJSMlk0VjVMV1dMSE1PPT09IiwibmJmIjoxNzg4NjkyMDY4LCJzdWIiOiJyZXBvOmdpdGh1YjpleGFtcGxlL2FwcDpyZWY6cmVmcy9oZWFkcy9tYWluIn0.BjaQ7iUof7avjxG8XAigNhY3Ul7AFi-rv_Rh9y8bLiELSPEjQhGBVuzw9gCsO0dI33b0YWpMboh-ObHh77UyAQ' 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.eyJhdWQiOiJmbGFrZWxldC1yZWxheSIsImV4cCI6MTc4ODY5MjM2OCwiZ3JvdXBzIjpbXSwiaWF0IjoxNzg4NjkyMDY4LCJpc3MiOiJodHRwczovL3JlbGF5Ojg0NDMiLCJqdGkiOiJSMlk0VjVMV1dMSE1PPT09IiwibmJmIjoxNzg4NjkyMDY4LCJzdWIiOiJyZXBvOmdpdGh1YjpleGFtcGxlL2FwcDpyZWY6cmVmcy9oZWFkcy9tYWluIn0.BjaQ7iUof7avjxG8XAigNhY3Ul7AFi-rv_Rh9y8bLiELSPEjQhGBVuzw9gCsO0dI33b0YWpMboh-ObHh77UyAQ' push --relay https://relay:7443 deploy agent/other 2>&1, in 0.03 seconds) container-test-run-flakelet-relay> (finished: subtest: policy denies uncovered target, in 0.03 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.eyJhdWQiOiJmbGFrZWxldC1yZWxheSIsImV4cCI6MTc4ODY5MjM3MSwiZ3JvdXBzIjpbIndoZWVsIl0sImlhdCI6MTc4ODY5MjA3MSwiaXNzIjoiaHR0cHM6Ly9yZWxheTo4NDQzIiwianRpIjoiVlhLTlZFWjQySkdKVT09PSIsIm5iZiI6MTc4ODY5MjA3MSwic3ViIjoic29tZW9uZSJ9.lmpZcAGqeprecUDfDQgvFmkasyuuW1qqFyhEb30-sifrZrxxBi6FETL3v-kJaJ6gZTFEqOV0x6TTEf52m2ScDQ' push --relay https://relay:7443 deploy agent/other 2>&1 container-test-run-flakelet-relay> agent # [13793.130758] agent flakelet-agent[507]: {"timestamp":"2026-09-06T10:54:31.092546Z","level":"INFO","message":"update finished","flakelet":"app","status":"Updated","generation":"Some(3)","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [13793.234451] agent flakelet-agent[507]: {"timestamp":"2026-09-06T10:54:31.196212Z","level":"INFO","message":"start","id":"c0e2243eb434b6fb48c8d767962385c6","flakelet":"other","rule":"admins","relay":"relay","caller_name":"Some(\"someone\")","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> agent # [13793.234783] agent flakelet-agent[507]: {"timestamp":"2026-09-06T10:54:31.196612Z","level":"INFO","message":"update starting","flakelet":"other","ids":"[\"c0e2243eb434b6fb48c8d767962385c6\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [13793.302961] agent systemd[1]: Starting flakelet update other (via relay)... container-test-run-flakelet-relay> agent # [13793.329979] agent systemd[1]: Started flakelet update other (via relay). container-test-run-flakelet-relay> agent # [13793.334473] agent flakelet[1341]: other: using prebuilt artifact /nix/store/hmr990n0b4v6rpzrcpyyw738r9xi7z95-flakelet-other-v1 container-test-run-flakelet-relay> agent # [13793.335046] agent flakelet[1341]: other: up to date container-test-run-flakelet-relay> agent # [13793.337745] agent systemd[1]: flakelet-relay-job-other.service: Deactivated successfully. container-test-run-flakelet-relay> relay # [13793.132028] relay flakelet-relay[402]: {"timestamp":"2026-09-06T10:54:31.093771Z","level":"INFO","message":"deploy finished","job":"a51a7dd2ea78427e565f708e35e88100","ok":true,"target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> relay # [13793.234191] relay flakelet-relay[402]: {"timestamp":"2026-09-06T10:54:31.195947Z","level":"INFO","message":"deploy accepted","job":"efef3df319f84b27f9c75bed85956ee8","caller":"someone","targets":"[\"agent/other\"]","target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> client: (finished: must succeed: FLAKELET_RELAY_TOKEN_COMMAND='echo eyJhbGciOiJFZERTQSIsImtpZCI6ImsxIiwidHlwIjoiSldUIn0.eyJhdWQiOiJmbGFrZWxldC1yZWxheSIsImV4cCI6MTc4ODY5MjM3MSwiZ3JvdXBzIjpbIndoZWVsIl0sImlhdCI6MTc4ODY5MjA3MSwiaXNzIjoiaHR0cHM6Ly9yZWxheTo4NDQzIiwianRpIjoiVlhLTlZFWjQySkdKVT09PSIsIm5iZiI6MTc4ODY5MjA3MSwic3ViIjoic29tZW9uZSJ9.lmpZcAGqeprecUDfDQgvFmkasyuuW1qqFyhEb30-sifrZrxxBi6FETL3v-kJaJ6gZTFEqOV0x6TTEf52m2ScDQ' push --relay https://relay:7443 deploy agent/other 2>&1, in 0.68 seconds) container-test-run-flakelet-relay> (finished: subtest: claim principal from groups, unchanged counts as success, in 0.73 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 # [13793.888268] agent flakelet-agent[507]: {"timestamp":"2026-09-06T10:54:31.850014Z","level":"INFO","message":"update finished","flakelet":"other","status":"Unchanged","generation":"Some(1)","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> relay # [13793.889671] relay flakelet-relay[402]: {"timestamp":"2026-09-06T10:54:31.851430Z","level":"INFO","message":"deploy finished","job":"efef3df319f84b27f9c75bed85956ee8","ok":true,"target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> relay # [13793.912301] relay 93sn3sv24fj26ksccpppzwg6kbf10s4w-device-mock[185]: 127.0.0.1 - - [06/Sep/2026 10:54:31] "POST /device HTTP/1.1" 200 - container-test-run-flakelet-relay> relay # [13794.917901] relay 93sn3sv24fj26ksccpppzwg6kbf10s4w-device-mock[185]: 127.0.0.1 - - [06/Sep/2026 10:54:32] "POST /token HTTP/1.1" 400 - container-test-run-flakelet-relay> client: (finished: must succeed: push login --issuer https://relay:8443 --client-id flakelet-relay 2>&1, in 2.08 seconds) container-test-run-flakelet-relay> client: must succeed: test "$(stat -c %a /root/.local/state/flakelet-push/token.json)" = 600 container-test-run-flakelet-relay> client: (finished: must succeed: test "$(stat -c %a /root/.local/state/flakelet-push/token.json)" = 600, in 0.01 seconds) container-test-run-flakelet-relay> client: must succeed: push --relay https://relay:7443 deploy agent/other 2>&1 container-test-run-flakelet-relay> agent # [13795.997687] agent flakelet-agent[507]: {"timestamp":"2026-09-06T10:54:33.959441Z","level":"INFO","message":"start","id":"6fc83c9167596291f2712efb4f0ce21e","flakelet":"other","rule":"admins","relay":"relay","caller_name":"Some(\"dev@example.org\")","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> agent # [13795.998566] agent flakelet-agent[507]: {"timestamp":"2026-09-06T10:54:33.960211Z","level":"INFO","message":"update starting","flakelet":"other","ids":"[\"6fc83c9167596291f2712efb4f0ce21e\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [13796.068606] agent systemd[1]: Starting flakelet update other (via relay)... container-test-run-flakelet-relay> agent # [13796.098940] agent systemd[1]: Started flakelet update other (via relay). container-test-run-flakelet-relay> agent # [13796.103754] agent flakelet[1391]: other: using prebuilt artifact /nix/store/hmr990n0b4v6rpzrcpyyw738r9xi7z95-flakelet-other-v1 container-test-run-flakelet-relay> agent # [13796.104323] agent flakelet[1391]: other: up to date container-test-run-flakelet-relay> agent # [13796.107095] agent systemd[1]: flakelet-relay-job-other.service: Deactivated successfully. container-test-run-flakelet-relay> relay # [13795.966171] relay 93sn3sv24fj26ksccpppzwg6kbf10s4w-device-mock[185]: 127.0.0.1 - - [06/Sep/2026 10:54:33] "POST /token HTTP/1.1" 200 - container-test-run-flakelet-relay> relay # [13795.997386] relay flakelet-relay[402]: {"timestamp":"2026-09-06T10:54:33.959158Z","level":"INFO","message":"deploy accepted","job":"0e086139e747de660a3aa9240843a874","caller":"dev@example.org","targets":"[\"agent/other\"]","target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> client: (finished: must succeed: push --relay https://relay:7443 deploy agent/other 2>&1, in 0.69 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 # [13796.667654] agent flakelet-agent[507]: {"timestamp":"2026-09-06T10:54:34.629376Z","level":"INFO","message":"update finished","flakelet":"other","status":"Unchanged","generation":"Some(1)","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [13796.691761] agent flakelet-agent[507]: {"timestamp":"2026-09-06T10:54:34.653567Z","level":"INFO","message":"start","id":"dee8aad028d72a7ae0916e45620bf1ae","flakelet":"other","rule":"admins","relay":"relay","caller_name":"Some(\"dev@example.org\")","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> agent # [13796.692191] agent flakelet-agent[507]: {"timestamp":"2026-09-06T10:54:34.654010Z","level":"INFO","message":"update starting","flakelet":"other","ids":"[\"dee8aad028d72a7ae0916e45620bf1ae\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [13796.749325] agent systemd[1]: Starting flakelet update other (via relay)... container-test-run-flakelet-relay> agent # [13796.781963] agent systemd[1]: Started flakelet update other (via relay). container-test-run-flakelet-relay> agent # [13796.787496] agent flakelet[1441]: other: using prebuilt artifact /nix/store/hmr990n0b4v6rpzrcpyyw738r9xi7z95-flakelet-other-v1 container-test-run-flakelet-relay> agent # [13796.788217] agent flakelet[1441]: other: up to date container-test-run-flakelet-relay> agent # [13796.788747] agent systemd[1]: flakelet-relay-job-other.service: Deactivated successfully. container-test-run-flakelet-relay> relay # [13796.669333] relay flakelet-relay[402]: {"timestamp":"2026-09-06T10:54:34.631067Z","level":"INFO","message":"deploy finished","job":"0e086139e747de660a3aa9240843a874","ok":true,"target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> relay # [13796.691519] relay flakelet-relay[402]: {"timestamp":"2026-09-06T10:54:34.653326Z","level":"INFO","message":"deploy accepted","job":"fbf92ca58b2c04c4998aa284fe8200f2","caller":"dev@example.org","targets":"[\"agent/other\"]","target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> client: (finished: must succeed: push --relay https://relay:7443 deploy agent/other 2>&1, in 1.68 seconds) container-test-run-flakelet-relay> (finished: subtest: push login via device flow, cached token used afterwards, in 4.46 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 # [13798.344774] agent flakelet-agent[507]: {"timestamp":"2026-09-06T10:54:36.306555Z","level":"INFO","message":"update finished","flakelet":"other","status":"Unchanged","generation":"Some(1)","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [13798.382439] agent flakelet-agent[507]: {"timestamp":"2026-09-06T10:54:36.344236Z","level":"INFO","message":"start","id":"9735ea118ceae5b70f4ad0f9d783d5cb","flakelet":"app","rule":"admins","relay":"relay","caller_name":"Some(\"admin@example.org\")","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> agent # [13798.382865] agent flakelet-agent[507]: {"timestamp":"2026-09-06T10:54:36.344684Z","level":"INFO","message":"update starting","flakelet":"app","ids":"[\"9735ea118ceae5b70f4ad0f9d783d5cb\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [13798.430370] agent systemd[1]: Starting flakelet update app (via relay)... container-test-run-flakelet-relay> agent # [13798.463264] agent systemd[1]: Started flakelet update app (via relay). container-test-run-flakelet-relay> agent # [13798.467641] agent flakelet[1495]: app: using prebuilt artifact /nix/store/8282rnbvyk847q4l4zby3zwcq3jpzrvw-flakelet-app-broken container-test-run-flakelet-relay> agent # [13798.468322] agent flakelet[1495]: app: activating generation 4 container-test-run-flakelet-relay> agent # [13798.479076] agent systemd[1]: Stopping app v1... container-test-run-flakelet-relay> agent # [13798.479461] agent systemd[1]: app.service: Deactivated successfully. container-test-run-flakelet-relay> agent # [13798.479684] agent systemd[1]: Stopped app v1. container-test-run-flakelet-relay> agent # [13798.494166] agent systemd[1]: Reload requested from client PID 1502 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [13798.494223] agent systemd[1]: Reloading... container-test-run-flakelet-relay> relay # [13798.346242] relay flakelet-relay[402]: {"timestamp":"2026-09-06T10:54:36.307998Z","level":"INFO","message":"deploy finished","job":"fbf92ca58b2c04c4998aa284fe8200f2","ok":true,"target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> relay # [13798.382177] relay flakelet-relay[402]: {"timestamp":"2026-09-06T10:54:36.343977Z","level":"INFO","message":"deploy accepted","job":"a6b2e57da7350a73b57cc2ec347ac673","caller":"admin@example.org","targets":"[\"agent/app\", \"agent/other\"]","target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> agent # [13798.814739] agent systemd[1]: Reloading finished in 319 ms. container-test-run-flakelet-relay> agent # [13798.903884] agent systemd[1]: Reload requested from client PID 1532 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [13798.903927] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [13799.215247] agent systemd[1]: Reloading finished in 310 ms. container-test-run-flakelet-relay> agent # [13799.299907] agent systemd[1]: Reload requested from client PID 1561 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [13799.299948] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [13799.615088] agent systemd[1]: Reloading finished in 314 ms. container-test-run-flakelet-relay> agent # [13799.690466] agent systemd[1]: Reload requested from client PID 1592 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [13799.690499] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [13800.004888] agent systemd[1]: Reloading finished in 314 ms. container-test-run-flakelet-relay> agent # [13800.075524] agent systemd[1]: Starting app broken... container-test-run-flakelet-relay> agent # [13800.104381] agent (nonexistent)[1622]: app.service: Unable to locate executable '/nonexistent': No such file or directory container-test-run-flakelet-relay> agent # [13800.104405] agent (nonexistent)[1622]: app.service: Failed at step EXEC spawning /nonexistent: No such file or directory container-test-run-flakelet-relay> agent # [13800.105750] agent systemd[1]: app.service: Main process exited, code=exited, status=203/EXEC container-test-run-flakelet-relay> agent # [13800.105864] agent systemd[1]: app.service: Failed with result 'exit-code'. container-test-run-flakelet-relay> agent # [13800.106208] agent systemd[1]: Failed to start app broken. container-test-run-flakelet-relay> agent # [13800.135969] agent systemd[1]: Reload requested from client PID 1629 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [13800.136017] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [13800.453294] agent systemd[1]: Reloading finished in 316 ms. container-test-run-flakelet-relay> agent # [13800.543268] agent systemd[1]: Reload requested from client PID 1662 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [13800.543305] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [13800.863389] agent systemd[1]: Reloading finished in 319 ms. container-test-run-flakelet-relay> agent # [13800.932986] agent systemd[1]: Reload requested from client PID 1693 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [13800.933030] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [13801.242861] agent systemd[1]: Reloading finished in 309 ms. container-test-run-flakelet-relay> agent # [13801.318698] agent systemd[1]: Reload requested from client PID 1725 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [13801.318737] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [13801.631892] agent systemd[1]: Reloading finished in 312 ms. container-test-run-flakelet-relay> agent # [13801.700017] agent systemd[1]: Starting app v1... container-test-run-flakelet-relay> agent # [13801.729974] agent systemd[1]: Started app v1. container-test-run-flakelet-relay> agent # [13801.731197] agent flakelet[1495]: app: deploy failed, rolled back: systemctl start app.service failed: container-test-run-flakelet-relay> agent # [13801.731197] agent flakelet[1495]: failed units: app.service. See journalctl -u app.service container-test-run-flakelet-relay> agent # [13801.731737] agent systemd[1]: flakelet-relay-job-app.service: Main process exited, code=exited, status=1/FAILURE container-test-run-flakelet-relay> agent # [13801.731826] 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.13 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.01 seconds) container-test-run-flakelet-relay> (finished: subtest: waves: failing first wave skips the second, in 4.16 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 # [13802.489204] agent flakelet-agent[507]: {"timestamp":"2026-09-06T10:54:40.450946Z","level":"INFO","message":"update finished","flakelet":"app","status":"RolledBack","generation":"Some(3)","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [13802.538898] agent flakelet-agent[507]: {"timestamp":"2026-09-06T10:54:40.500648Z","level":"INFO","message":"start","id":"ab7fe2d1210fa65d378328137413e9a0","flakelet":"app","rule":"admins","relay":"relay","caller_name":"Some(\"admin@example.org\")","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> agent # [13802.539340] agent flakelet-agent[507]: {"timestamp":"2026-09-06T10:54:40.501159Z","level":"INFO","message":"update starting","flakelet":"app","ids":"[\"ab7fe2d1210fa65d378328137413e9a0\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [13802.588169] agent systemd[1]: Starting flakelet update app (via relay)... container-test-run-flakelet-relay> agent # [13802.595387] agent systemd[1]: Started flakelet update app (via relay). container-test-run-flakelet-relay> agent # [13802.599535] agent flakelet[1819]: app: using prebuilt artifact /nix/store/hysydfr96ds875vr0jgw5rjxi4p39h2j-flakelet-app-v1 container-test-run-flakelet-relay> agent # [13802.600080] agent flakelet[1819]: app: up to date container-test-run-flakelet-relay> agent # [13802.602059] agent systemd[1]: flakelet-relay-job-app.service: Deactivated successfully. container-test-run-flakelet-relay> relay # [13802.490539] relay flakelet-relay[402]: {"timestamp":"2026-09-06T10:54:40.452278Z","level":"INFO","message":"deploy finished","job":"a6b2e57da7350a73b57cc2ec347ac673","ok":false,"target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> relay # [13802.538646] relay flakelet-relay[402]: {"timestamp":"2026-09-06T10:54:40.500424Z","level":"INFO","message":"deploy accepted","job":"72555c88214a681d769521bd20966e93","caller":"admin@example.org","targets":"[\"agent/app\"]","target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> client: (finished: must succeed: push --relay https://relay:7443 --cert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin.pem --key /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin-key.pem deploy '@all/app' 2>&1, in 0.65 seconds) container-test-run-flakelet-relay> client: must fail: push --relay https://relay:7443 --cert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin.pem --key /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin-key.pem deploy '*/app' 2>&1 container-test-run-flakelet-relay> agent # [13803.168409] agent flakelet-agent[507]: {"timestamp":"2026-09-06T10:54:41.130158Z","level":"INFO","message":"update finished","flakelet":"app","status":"Unchanged","generation":"Some(3)","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [13803.196352] agent flakelet-agent[507]: {"timestamp":"2026-09-06T10:54:41.158136Z","level":"INFO","message":"start","id":"343eebcb69e76104b72681c31a13c3eb","flakelet":"app","rule":"admins","relay":"relay","caller_name":"Some(\"admin@example.org\")","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> agent # [13803.196709] agent flakelet-agent[507]: {"timestamp":"2026-09-06T10:54:41.158533Z","level":"INFO","message":"update starting","flakelet":"app","ids":"[\"343eebcb69e76104b72681c31a13c3eb\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [13803.259694] agent systemd[1]: Starting flakelet update app (via relay)... container-test-run-flakelet-relay> agent # [13803.288984] agent systemd[1]: Started flakelet update app (via relay). container-test-run-flakelet-relay> relay # [13803.169635] relay flakelet-relay[402]: {"timestamp":"2026-09-06T10:54:41.131388Z","level":"INFO","message":"deploy finished","job":"72555c88214a681d769521bd20966e93","ok":true,"target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> agent # [13803.293608] agent flakelet[1853]: app: using prebuilt artifact /nix/store/hysydfr96ds875vr0jgw5rjxi4p39h2j-flakelet-app-v1 container-test-run-flakelet-relay> relay # [13803.196097] relay flakelet-relay[402]: {"timestamp":"2026-09-06T10:54:41.157900Z","level":"INFO","message":"deploy accepted","job":"67ace821fadda5103842a3578999b5ce","caller":"admin@example.org","targets":"[\"agent/app\", \"ghost/app\"]","target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> agent # [13803.294184] agent flakelet[1853]: app: up to date container-test-run-flakelet-relay> agent # [13803.294613] agent systemd[1]: flakelet-relay-job-app.service: Deactivated successfully. 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 1.70 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 # [13804.872390] agent flakelet-agent[507]: {"timestamp":"2026-09-06T10:54:42.834122Z","level":"INFO","message":"update finished","flakelet":"app","status":"Unchanged","generation":"Some(3)","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [13804.898613] agent flakelet-agent[507]: {"timestamp":"2026-09-06T10:54:42.860424Z","level":"INFO","message":"start","id":"5023b90d89ea3e6edfd43c861dff56ba","flakelet":"app","rule":"admins","relay":"relay","caller_name":"Some(\"admin@example.org\")","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> agent # [13804.898810] agent flakelet-agent[507]: {"timestamp":"2026-09-06T10:54:42.860638Z","level":"INFO","message":"update starting","flakelet":"app","ids":"[\"5023b90d89ea3e6edfd43c861dff56ba\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [13804.972028] agent systemd[1]: Starting flakelet update app (via relay)... container-test-run-flakelet-relay> agent # [13805.011047] agent systemd[1]: Started flakelet update app (via relay). container-test-run-flakelet-relay> agent # [13805.015553] agent flakelet[1905]: app: using prebuilt artifact /nix/store/hysydfr96ds875vr0jgw5rjxi4p39h2j-flakelet-app-v1 container-test-run-flakelet-relay> agent # [13805.016141] agent flakelet[1905]: app: up to date container-test-run-flakelet-relay> agent # [13805.017428] agent systemd[1]: flakelet-relay-job-app.service: Deactivated successfully. container-test-run-flakelet-relay> relay # [13804.873658] relay flakelet-relay[402]: {"timestamp":"2026-09-06T10:54:42.835404Z","level":"INFO","message":"deploy finished","job":"67ace821fadda5103842a3578999b5ce","ok":false,"target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> relay # [13804.898389] relay flakelet-relay[402]: {"timestamp":"2026-09-06T10:54:42.860188Z","level":"INFO","message":"deploy accepted","job":"4d5bbe94d61b8466be65daa7066e1923","caller":"admin@example.org","targets":"[\"agent/app\", \"ghost/app\"]","target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> client: (finished: must fail: push --relay https://relay:7443 --cert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin.pem --key /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin-key.pem deploy agent/app --wave '*/app' 2>&1, in 0.70 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 3.09 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 # [13805.574761] agent flakelet-agent[507]: {"timestamp":"2026-09-06T10:54:43.536545Z","level":"INFO","message":"update finished","flakelet":"app","status":"Unchanged","generation":"Some(3)","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [13805.632848] agent flakelet-agent[507]: {"timestamp":"2026-09-06T10:54:43.594619Z","level":"INFO","message":"start","id":"51856e597527c83ebc4e3192cdb556f2","flakelet":"app","rule":"admins","relay":"relay","caller_name":"Some(\"admin@example.org\")","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> agent # [13805.633115] agent flakelet-agent[507]: {"timestamp":"2026-09-06T10:54:43.594942Z","level":"INFO","message":"update starting","flakelet":"app","ids":"[\"51856e597527c83ebc4e3192cdb556f2\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [13805.680196] agent systemd[1]: Starting flakelet update app (via relay)... container-test-run-flakelet-relay> agent # [13805.712164] agent systemd[1]: Started flakelet update app (via relay). container-test-run-flakelet-relay> relay # [13805.575969] relay flakelet-relay[402]: {"timestamp":"2026-09-06T10:54:43.537717Z","level":"INFO","message":"deploy finished","job":"4d5bbe94d61b8466be65daa7066e1923","ok":false,"target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> agent # [13805.716331] agent flakelet[1946]: app: using prebuilt artifact /nix/store/ln47rsd5km8a04hd5zlkz4q1v352zqh3-flakelet-app-slow2 container-test-run-flakelet-relay> relay # [13805.632529] relay flakelet-relay[402]: {"timestamp":"2026-09-06T10:54:43.594336Z","level":"INFO","message":"deploy accepted","job":"3ea15e35cb7a7e7226366cf8fbf16862","caller":"admin@example.org","targets":"[\"agent/app\"]","target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> agent # [13805.717080] agent flakelet[1946]: app: activating generation 4 container-test-run-flakelet-relay> agent # [13805.727104] agent systemd[1]: Stopping app v1... container-test-run-flakelet-relay> agent # [13805.727802] agent systemd[1]: app.service: Deactivated successfully. container-test-run-flakelet-relay> agent # [13805.727933] agent systemd[1]: Stopped app v1. container-test-run-flakelet-relay> agent # [13805.738930] agent systemd[1]: Reload requested from client PID 1953 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [13805.738965] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [13806.077436] agent systemd[1]: Reloading finished in 338 ms. container-test-run-flakelet-relay> agent # [13806.171166] agent systemd[1]: Reload requested from client PID 1983 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [13806.171204] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [13806.493418] agent systemd[1]: Reloading finished in 321 ms. container-test-run-flakelet-relay> agent # [13806.587028] agent systemd[1]: Reload requested from client PID 2014 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [13806.587063] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent: (finished: waiting for success: systemctl is-active flakelet-relay-job-app.service, in 1.34 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 # [13806.898111] agent systemd[1]: Reloading finished in 310 ms. container-test-run-flakelet-relay> agent # [13806.982360] agent systemd[1]: Reload requested from client PID 2048 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [13806.982397] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [13807.311407] agent systemd[1]: Reloading finished in 328 ms. container-test-run-flakelet-relay> agent # [13807.395187] agent systemd[1]: Starting app slow2... container-test-run-flakelet-relay> agent # [13813.466361] agent systemd[1]: Started app slow2. container-test-run-flakelet-relay> agent # [13813.486514] agent flakelet[1946]: app: updated to generation 4 container-test-run-flakelet-relay> agent # [13813.489497] 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.52 seconds) container-test-run-flakelet-relay> (finished: subtest: detach returns once the job is accepted, in 8.90 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.07 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 # [13814.535856] agent systemd[1]: Stopping flakelet-agent... container-test-run-flakelet-relay> agent # [13814.567317] agent systemd[1]: flakelet-agent.service: Deactivated successfully. container-test-run-flakelet-relay> agent # [13814.567690] agent systemd[1]: Stopped flakelet-agent. container-test-run-flakelet-relay> agent # [13814.568033] agent systemd[1]: flakelet-agent.service: Consumed 2.800s CPU time over 30.515s wall clock time. container-test-run-flakelet-relay> relay # [13814.536288] relay flakelet-relay[402]: {"timestamp":"2026-09-06T10:54:52.498006Z","level":"INFO","message":"agent disconnected: IO error: peer closed connection without sending TLS close_notify: https://docs.rs/rustls/latest/rustls/manual/_03_howto/index.html#unexpected-eof","host":"agent","target":"flakelet_relay::relay::agent_conn"} container-test-run-flakelet-relay> client: (finished: waiting for success: grep -q 'agent_unavailable.*retrying' /tmp/retry.out, in 1.02 seconds) container-test-run-flakelet-relay> agent: must succeed: systemctl start flakelet-agent container-test-run-flakelet-relay> agent: (finished: must succeed: systemctl start flakelet-agent, in 0.20 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 # [13815.660994] agent systemd[1]: Starting flakelet-agent... container-test-run-flakelet-relay> agent # [13815.725278] agent flakelet-agent[2169]: {"timestamp":"2026-09-06T10:54:53.687062Z","level":"INFO","message":"reattaching to running unit","flakelet":"app","ids":"[\"51856e597527c83ebc4e3192cdb556f2\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [13815.845097] agent systemd[1]: Started flakelet-agent. container-test-run-flakelet-relay> agent # [13815.855532] agent flakelet-agent[2169]: {"timestamp":"2026-09-06T10:54:53.817338Z","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 # [13815.855692] relay flakelet-relay[402]: {"timestamp":"2026-09-06T10:54:53.817440Z","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 # [13816.304603] agent flakelet-agent[2169]: {"timestamp":"2026-09-06T10:54:54.266339Z","level":"INFO","message":"update finished","flakelet":"app","status":"Updated","generation":"Some(4)","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [13817.643162] agent flakelet-agent[2169]: {"timestamp":"2026-09-06T10:54:55.604831Z","level":"INFO","message":"start","id":"3c9fe0ed6281853c89a060b792a7f9d2","flakelet":"other","rule":"admins","relay":"relay","caller_name":"Some(\"admin@example.org\")","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> agent # [13817.643998] agent flakelet-agent[2169]: {"timestamp":"2026-09-06T10:54:55.605596Z","level":"INFO","message":"update starting","flakelet":"other","ids":"[\"3c9fe0ed6281853c89a060b792a7f9d2\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [13817.745821] agent systemd[1]: Starting flakelet update other (via relay)... container-test-run-flakelet-relay> agent # [13817.753756] agent systemd[1]: Started flakelet update other (via relay). container-test-run-flakelet-relay> agent # [13817.757277] agent flakelet[2618]: other: using prebuilt artifact /nix/store/hmr990n0b4v6rpzrcpyyw738r9xi7z95-flakelet-other-v1 container-test-run-flakelet-relay> agent # [13817.757819] agent flakelet[2618]: other: up to date container-test-run-flakelet-relay> relay # [13817.642727] relay flakelet-relay[402]: {"timestamp":"2026-09-06T10:54:55.604499Z","level":"INFO","message":"deploy accepted","job":"77b2be0b0dd71a45113ed727bb0e46b9","caller":"admin@example.org","targets":"[\"agent/other\"]","target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> agent # [13817.760613] agent systemd[1]: flakelet-relay-job-other.service: Deactivated successfully. container-test-run-flakelet-relay> agent # [13818.332532] agent flakelet-agent[2169]: {"timestamp":"2026-09-06T10:54:56.294271Z","level":"INFO","message":"update finished","flakelet":"other","status":"Unchanged","generation":"Some(1)","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> relay # [13818.334206] relay flakelet-relay[402]: {"timestamp":"2026-09-06T10:54:56.295987Z","level":"INFO","message":"deploy finished","job":"77b2be0b0dd71a45113ed727bb0e46b9","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.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 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.eyJhdWQiOiJmbGFrZWxldC1yZWxheSIsImV4cCI6MTc4ODY5MjM5NiwiZ3JvdXBzIjpbXSwiaWF0IjoxNzg4NjkyMDk2LCJpc3MiOiJodHRwczovL3JlbGF5Ojg0NDMiLCJqdGkiOiJDMkFSUVZFUTJQWkNFPT09IiwibmJmIjoxNzg4NjkyMDk2LCJzdWIiOiJyZXBvOmdpdGh1YjpleGFtcGxlL2FwcDpyZWY6cmVmcy9oZWFkcy9tYWluIn0.ALRDP0Ao_KjtrjzsFskoQzFcfMKvbMV16fSpdSKjQF-uerQ2qey_crBtT-stPBlTib_tcmfxe5NHKEF8Hz41CQ' push --relay https://relay:7443 agents container-test-run-flakelet-relay> client: (finished: must succeed: FLAKELET_RELAY_TOKEN_COMMAND='echo eyJhbGciOiJFZERTQSIsImtpZCI6ImsxIiwidHlwIjoiSldUIn0.eyJhdWQiOiJmbGFrZWxldC1yZWxheSIsImV4cCI6MTc4ODY5MjM5NiwiZ3JvdXBzIjpbXSwiaWF0IjoxNzg4NjkyMDk2LCJpc3MiOiJodHRwczovL3JlbGF5Ojg0NDMiLCJqdGkiOiJDMkFSUVZFUTJQWkNFPT09IiwibmJmIjoxNzg4NjkyMDk2LCJzdWIiOiJyZXBvOmdpdGh1YjpleGFtcGxlL2FwcDpyZWY6cmVmcy9oZWFkcy9tYWluIn0.ALRDP0Ao_KjtrjzsFskoQzFcfMKvbMV16fSpdSKjQF-uerQ2qey_crBtT-stPBlTib_tcmfxe5NHKEF8Hz41CQ' 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.49 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 # [13819.082247] agent systemd[1]: Stopping app slow2... container-test-run-flakelet-relay> agent # [13819.082720] agent systemd[1]: app.service: Deactivated successfully. container-test-run-flakelet-relay> agent # [13819.082949] agent systemd[1]: Stopped app slow2. container-test-run-flakelet-relay> agent # [13819.097992] agent systemd[1]: Reload requested from client PID 2678 ('systemctl')... container-test-run-flakelet-relay> agent # [13819.098085] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [13819.448099] agent systemd[1]: Reloading finished in 349 ms. container-test-run-flakelet-relay> agent # [13819.549639] agent systemd[1]: Reload requested from client PID 2707 ('systemctl')... container-test-run-flakelet-relay> agent # [13819.549681] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [13819.889007] agent systemd[1]: Reloading finished in 338 ms. container-test-run-flakelet-relay> agent # [13819.989763] agent systemd[1]: Reload requested from client PID 2737 ('systemctl')... container-test-run-flakelet-relay> agent # [13819.989806] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [13820.303632] agent systemd[1]: Reloading finished in 313 ms. container-test-run-flakelet-relay> agent # [13820.399772] agent systemd[1]: Reload requested from client PID 2767 ('systemctl')... container-test-run-flakelet-relay> agent # [13820.399807] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent: (finished: must succeed: flakelet update app, in 1.81 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 # [13820.729543] agent systemd[1]: Reloading finished in 329 ms. container-test-run-flakelet-relay> agent # [13820.825857] agent systemd[1]: Starting app v2... container-test-run-flakelet-relay> agent # [13820.859224] 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.09 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 # [13823.011193] agent systemd[1]: Stopping app v2... container-test-run-flakelet-relay> agent # [13823.011649] agent systemd[1]: app.service: Deactivated successfully. container-test-run-flakelet-relay> agent # [13823.019179] agent systemd[1]: Stopped app v2. container-test-run-flakelet-relay> agent # [13823.044482] agent systemd[1]: Reload requested from client PID 2835 ('systemctl')... container-test-run-flakelet-relay> agent # [13823.044552] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [13823.393683] agent systemd[1]: Reloading finished in 348 ms. container-test-run-flakelet-relay> agent # [13823.470930] agent systemd[1]: Reload requested from client PID 2864 ('systemctl')... container-test-run-flakelet-relay> agent # [13823.470969] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [13823.785138] agent systemd[1]: Reloading finished in 313 ms. container-test-run-flakelet-relay> agent # [13823.913589] agent systemd[1]: Reload requested from client PID 2894 ('systemctl')... container-test-run-flakelet-relay> agent # [13823.913628] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [13824.236316] agent systemd[1]: Reloading finished in 322 ms. container-test-run-flakelet-relay> agent # [13824.334532] agent systemd[1]: Reload requested from client PID 2925 ('systemctl')... container-test-run-flakelet-relay> agent # [13824.334571] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent: (finished: must succeed: flakelet update app, in 1.80 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 # [13824.668849] agent systemd[1]: Reloading finished in 333 ms. container-test-run-flakelet-relay> agent # [13824.761451] agent systemd[1]: Starting app v1... container-test-run-flakelet-relay> agent # [13824.769911] 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.07 seconds) container-test-run-flakelet-relay> (finished: subtest: out-of-band flakelet update reaches the relay without reconnect, in 7.86 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.03 seconds) container-test-run-flakelet-relay> (finished: subtest: certificate not listed under agents cannot connect as agent, in 0.03 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=@1788692104 | grep -q 'app-slow' container-test-run-flakelet-relay> relay # [13826.874022] relay flakelet-relay[402]: {"timestamp":"2026-09-06T10:55:04.835639Z","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 # [13826.930083] relay flakelet-relay[402]: {"timestamp":"2026-09-06T10:55:04.891882Z","level":"INFO","message":"deploy accepted","job":"ccfd2b06ce8ff9b6ab7cdc543fb73b2f","caller":"admin@example.org","targets":"[\"agent/app\"]","target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> agent # [13826.930458] agent flakelet-agent[2169]: {"timestamp":"2026-09-06T10:55:04.892229Z","level":"INFO","message":"start","id":"65a2f0668530130017d2b93c589a2596","flakelet":"app","rule":"admins","relay":"relay","caller_name":"Some(\"admin@example.org\")","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> agent # [13826.931110] agent flakelet-agent[2169]: {"timestamp":"2026-09-06T10:55:04.892931Z","level":"INFO","message":"update starting","flakelet":"app","ids":"[\"65a2f0668530130017d2b93c589a2596\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [13827.000827] agent systemd[1]: Starting flakelet update app (via relay)... container-test-run-flakelet-relay> agent # [13827.007596] agent systemd[1]: Started flakelet update app (via relay). container-test-run-flakelet-relay> agent # [13827.011656] agent flakelet[2998]: app: using prebuilt artifact /nix/store/c67jj8kx3m50nyj874aqmdqf21w5iznd-flakelet-app-slow container-test-run-flakelet-relay> agent # [13827.012370] agent flakelet[2998]: app: activating generation 7 container-test-run-flakelet-relay> agent # [13827.026215] agent systemd[1]: Stopping app v1... container-test-run-flakelet-relay> agent # [13827.026660] agent systemd[1]: app.service: Deactivated successfully. container-test-run-flakelet-relay> agent # [13827.026914] agent systemd[1]: Stopped app v1. container-test-run-flakelet-relay> agent # [13827.043325] agent systemd[1]: Reload requested from client PID 3005 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [13827.043393] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [13827.369417] agent systemd[1]: Reloading finished in 325 ms. container-test-run-flakelet-relay> agent # [13827.451496] agent systemd[1]: Reload requested from client PID 3035 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [13827.451537] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent: (finished: waiting for success: journalctl -u flakelet-relay-job-app.service --since=@1788692104 | 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.06 seconds) container-test-run-flakelet-relay> agent: must succeed: ss -K dport = 7443 container-test-run-flakelet-relay> agent # [13827.767801] agent systemd[1]: Reloading finished in 315 ms. container-test-run-flakelet-relay> agent # [13827.851165] agent systemd[1]: Reload requested from client PID 3064 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [13827.851205] agent systemd[1]: Reloading... 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=@1788692104 | grep -q 'updated to generation' container-test-run-flakelet-relay> agent # [13828.044502] agent flakelet-agent[2169]: {"timestamp":"2026-09-06T10:55:06.006059Z","level":"INFO","message":"disconnected: IO error: Software caused connection abort (os error 103)","relay":"https://relay:7443","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> agent # [13828.168640] agent systemd[1]: Reloading finished in 317 ms. container-test-run-flakelet-relay> agent # [13828.247824] agent systemd[1]: Reload requested from client PID 3112 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [13828.247859] agent systemd[1]: Reloading... container-test-run-flakelet-relay> relay # [13828.014508] relay flakelet-relay[402]: {"timestamp":"2026-09-06T10:55:05.976251Z","level":"INFO","message":"agent disconnected: IO error: Software caused connection abort (os error 103)","host":"agent","target":"flakelet_relay::relay::agent_conn"} container-test-run-flakelet-relay> agent # [13828.570053] agent systemd[1]: Reloading finished in 321 ms. container-test-run-flakelet-relay> agent # [13828.670927] agent systemd[1]: Starting app slow... container-test-run-flakelet-relay> agent # [13829.046539] agent flakelet-agent[2169]: {"timestamp":"2026-09-06T10:55:07.008288Z","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 # [13831.048427] agent flakelet-agent[2169]: {"timestamp":"2026-09-06T10:55:09.010138Z","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 # [13834.715177] agent systemd[1]: Started app slow. container-test-run-flakelet-relay> agent # [13834.735062] agent flakelet[2998]: app: updated to generation 7 container-test-run-flakelet-relay> agent # [13834.737700] 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=@1788692104 | grep -q 'updated to generation', in 7.11 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 # [13835.050657] agent flakelet-agent[2169]: {"timestamp":"2026-09-06T10:55:13.012247Z","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 # [13835.863605] agent flakelet-agent[2169]: {"timestamp":"2026-09-06T10:55:13.825360Z","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 8.09 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.39 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 # [13843.055334] relay flakelet-relay[402]: {"timestamp":"2026-09-06T10:55:21.017081Z","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> relay # [13843.056802] relay flakelet-relay[402]: {"timestamp":"2026-09-06T10:55:21.018613Z","level":"INFO","message":"deploy finished","job":"ccfd2b06ce8ff9b6ab7cdc543fb73b2f","ok":true,"target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> agent # [13843.055305] agent flakelet-agent[2169]: {"timestamp":"2026-09-06T10:55:21.017010Z","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 # [13843.056523] agent flakelet-agent[2169]: {"timestamp":"2026-09-06T10:55:21.018270Z","level":"INFO","message":"start","id":"65a2f0668530130017d2b93c589a2596","flakelet":"app","rule":"admins","relay":"relay","caller_name":"Some(\"admin@example.org\")","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> agent # [13843.317445] agent flakelet-agent[2169]: {"timestamp":"2026-09-06T10:55:21.279071Z","level":"INFO","message":"start","id":"a2fffa1dd335346c8c84e9a88b52fe6c","flakelet":"app","rule":"admins","relay":"relay","caller_name":"Some(\"admin@example.org\")","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> agent # [13843.317883] agent flakelet-agent[2169]: {"timestamp":"2026-09-06T10:55:21.279704Z","level":"INFO","message":"update starting","flakelet":"app","ids":"[\"a2fffa1dd335346c8c84e9a88b52fe6c\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [13843.390410] agent systemd[1]: Starting flakelet update app (via relay)... container-test-run-flakelet-relay> agent # [13843.415044] agent systemd[1]: Started flakelet update app (via relay). container-test-run-flakelet-relay> agent # [13843.419433] agent flakelet[3331]: app: using prebuilt artifact /nix/store/ln47rsd5km8a04hd5zlkz4q1v352zqh3-flakelet-app-slow2 container-test-run-flakelet-relay> agent # [13843.420136] agent flakelet[3331]: app: activating generation 8 container-test-run-flakelet-relay> agent # [13843.431031] agent systemd[1]: Stopping app slow... container-test-run-flakelet-relay> agent # [13843.431758] agent systemd[1]: app.service: Deactivated successfully. container-test-run-flakelet-relay> agent # [13843.431890] agent systemd[1]: Stopped app slow. container-test-run-flakelet-relay> agent # [13843.441742] agent systemd[1]: Reload requested from client PID 3338 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [13843.441780] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [13843.776501] agent systemd[1]: Reloading finished in 334 ms. container-test-run-flakelet-relay> agent # [13843.889817] agent systemd[1]: Reload requested from client PID 3371 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [13843.889852] agent systemd[1]: Reloading... 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.02 seconds) container-test-run-flakelet-relay> agent: must succeed: systemctl restart flakelet-agent container-test-run-flakelet-relay> agent # [13844.208945] agent systemd[1]: Reloading finished in 318 ms. container-test-run-flakelet-relay> agent # [13844.295661] agent systemd[1]: Reload requested from client PID 3402 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [13844.295696] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [13844.613560] agent systemd[1]: Reloading finished in 317 ms. container-test-run-flakelet-relay> agent # [13844.699687] agent systemd[1]: Stopping flakelet-agent... container-test-run-flakelet-relay> agent # [13844.703668] agent systemd[1]: Reload requested from client PID 3439 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [13844.703706] agent systemd[1]: Reloading... container-test-run-flakelet-relay> relay # [13843.317138] relay flakelet-relay[402]: {"timestamp":"2026-09-06T10:55:21.278711Z","level":"INFO","message":"deploy accepted","job":"38ae229ab76e201521bb6f4046c6addd","caller":"admin@example.org","targets":"[\"agent/app\"]","target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> relay # [13844.699809] relay flakelet-relay[402]: {"timestamp":"2026-09-06T10:55:22.661562Z","level":"INFO","message":"agent disconnected: IO error: peer closed connection without sending TLS close_notify: https://docs.rs/rustls/latest/rustls/manual/_03_howto/index.html#unexpected-eof","host":"agent","target":"flakelet_relay::relay::agent_conn"} container-test-run-flakelet-relay> agent: (finished: must succeed: systemctl restart flakelet-agent, in 0.92 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 # [13845.038294] agent systemd[1]: Reloading finished in 334 ms. container-test-run-flakelet-relay> agent # [13845.097598] agent systemd[1]: flakelet-agent.service: Deactivated successfully. container-test-run-flakelet-relay> agent # [13845.097777] agent systemd[1]: Stopped flakelet-agent. container-test-run-flakelet-relay> agent # [13845.097792] agent systemd[1]: flakelet-agent.service: Consumed 2.187s CPU time over 29.436s wall clock time. container-test-run-flakelet-relay> agent # [13845.116203] agent systemd[1]: Starting app slow2... container-test-run-flakelet-relay> agent # [13845.117319] agent systemd[1]: Starting flakelet-agent... container-test-run-flakelet-relay> agent # [13845.167572] agent flakelet-agent[3471]: {"timestamp":"2026-09-06T10:55:23.129368Z","level":"INFO","message":"reattaching to running unit","flakelet":"app","ids":"[\"a2fffa1dd335346c8c84e9a88b52fe6c\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [13845.249934] agent systemd[1]: Started flakelet-agent. container-test-run-flakelet-relay> agent # [13845.263920] agent flakelet-agent[3471]: {"timestamp":"2026-09-06T10:55:23.225724Z","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 # [13845.265194] agent flakelet-agent[3471]: {"timestamp":"2026-09-06T10:55:23.227016Z","level":"INFO","message":"start","id":"a2fffa1dd335346c8c84e9a88b52fe6c","flakelet":"app","rule":"admins","relay":"relay","caller_name":"Some(\"admin@example.org\")","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> relay # [13845.264067] relay flakelet-relay[402]: {"timestamp":"2026-09-06T10:55:23.225809Z","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 # [13851.186132] agent systemd[1]: Started app slow2. container-test-run-flakelet-relay> agent # [13851.207163] agent flakelet[3331]: app: updated to generation 8 container-test-run-flakelet-relay> agent # [13851.209954] agent systemd[1]: flakelet-relay-job-app.service: Deactivated successfully. container-test-run-flakelet-relay> agent # [13851.781159] agent flakelet-agent[3471]: {"timestamp":"2026-09-06T10:55:29.742940Z","level":"INFO","message":"update finished","flakelet":"app","status":"Updated","generation":"Some(8)","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> relay # [13851.782221] relay flakelet-relay[402]: {"timestamp":"2026-09-06T10:55:29.744003Z","level":"INFO","message":"deploy finished","job":"38ae229ab76e201521bb6f4046c6addd","ok":true,"target":"flakelet_relay::relay::api"} 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.09 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=@1788692130 | grep -q 'app-slow' container-test-run-flakelet-relay> agent # [13852.415278] agent flakelet-agent[3471]: {"timestamp":"2026-09-06T10:55:30.377014Z","level":"INFO","message":"start","id":"a9a4a742d504cfbe5806e69307de7fe5","flakelet":"app","rule":"admins","relay":"relay","caller_name":"Some(\"admin@example.org\")","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> agent # [13852.415808] agent flakelet-agent[3471]: {"timestamp":"2026-09-06T10:55:30.377625Z","level":"INFO","message":"update starting","flakelet":"app","ids":"[\"a9a4a742d504cfbe5806e69307de7fe5\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [13852.497589] agent systemd[1]: Starting flakelet update app (via relay)... container-test-run-flakelet-relay> agent # [13852.504227] agent systemd[1]: Started flakelet update app (via relay). container-test-run-flakelet-relay> agent # [13852.508809] agent flakelet[3983]: app: using prebuilt artifact /nix/store/c67jj8kx3m50nyj874aqmdqf21w5iznd-flakelet-app-slow container-test-run-flakelet-relay> agent # [13852.509530] agent flakelet[3983]: app: activating generation 9 container-test-run-flakelet-relay> agent # [13852.523095] agent systemd[1]: Stopping app slow2... container-test-run-flakelet-relay> agent # [13852.524792] agent systemd[1]: app.service: Deactivated successfully. container-test-run-flakelet-relay> agent # [13852.525124] agent systemd[1]: Stopped app slow2. container-test-run-flakelet-relay> agent # [13852.540455] agent systemd[1]: Reload requested from client PID 3990 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [13852.540520] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [13852.867719] agent systemd[1]: Reloading finished in 326 ms. container-test-run-flakelet-relay> relay # [13852.414979] relay flakelet-relay[402]: {"timestamp":"2026-09-06T10:55:30.376734Z","level":"INFO","message":"deploy accepted","job":"e2d0a825b695f0f0a63f82f59f24ccf7","caller":"admin@example.org","targets":"[\"agent/app\"]","target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> agent # [13852.953248] agent systemd[1]: Reload requested from client PID 4020 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [13852.953290] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent: (finished: waiting for success: journalctl -u flakelet-relay-job-app.service --since=@1788692130 | 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 # [13853.270159] agent systemd[1]: Reloading finished in 316 ms. container-test-run-flakelet-relay> agent # [13853.367160] agent systemd[1]: Reload requested from client PID 4051 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [13853.367202] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [13853.437902] agent flakelet-agent[3471]: {"timestamp":"2026-09-06T10:55:31.399692Z","level":"INFO","message":"disconnected: IO error: peer closed connection without sending TLS close_notify: https://docs.rs/rustls/latest/rustls/manual/_03_howto/index.html#unexpected-eof","relay":"https://relay:7443","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> relay # [13853.437530] relay systemd[1]: Stopping flakelet-relay... container-test-run-flakelet-relay> relay # [13853.472966] relay systemd[1]: flakelet-relay.service: Deactivated successfully. container-test-run-flakelet-relay> relay # [13853.473252] relay systemd[1]: Stopped flakelet-relay. container-test-run-flakelet-relay> relay # [13853.473275] relay systemd[1]: flakelet-relay.service: Consumed 2.814s CPU time over 1min 11.132s wall clock time. container-test-run-flakelet-relay> relay # [13853.490565] relay systemd[1]: Starting flakelet-relay... container-test-run-flakelet-relay> agent # [13853.686725] agent systemd[1]: Reloading finished in 319 ms. container-test-run-flakelet-relay> agent # [13853.786437] agent systemd[1]: Reload requested from client PID 4086 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [13853.786473] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [13854.110294] agent systemd[1]: Reloading finished in 323 ms. container-test-run-flakelet-relay> agent # [13854.191181] agent systemd[1]: Starting app slow... container-test-run-flakelet-relay> agent # [13854.441476] agent flakelet-agent[3471]: {"timestamp":"2026-09-06T10:55:32.403230Z","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.70 seconds) container-test-run-flakelet-relay> client: waiting for success: grep -q '» ok' /tmp/resume.out container-test-run-flakelet-relay> relay # [13855.120048] relay flakelet-relay[935]: {"timestamp":"2026-09-06T10:55:33.081783Z","level":"INFO","message":"listening on 127.0.0.1:7400","target":"flakelet_relay::relay::server"} container-test-run-flakelet-relay> relay # [13855.120783] relay flakelet-relay[935]: {"timestamp":"2026-09-06T10:55:33.082290Z","level":"INFO","message":"listening on [::]:7443","target":"flakelet_relay::relay::server"} container-test-run-flakelet-relay> relay # [13855.120594] relay systemd[1]: Started flakelet-relay. container-test-run-flakelet-relay> agent # [13856.454904] agent flakelet-agent[3471]: {"timestamp":"2026-09-06T10:55:34.416647Z","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 # [13856.457452] relay flakelet-relay[935]: {"timestamp":"2026-09-06T10:55:34.419220Z","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> agent # [13860.257977] agent systemd[1]: Started app slow. container-test-run-flakelet-relay> agent # [13860.277306] agent flakelet[3983]: app: updated to generation 9 container-test-run-flakelet-relay> agent # [13860.280104] agent systemd[1]: flakelet-relay-job-app.service: Deactivated successfully. container-test-run-flakelet-relay> relay # [13860.462105] relay flakelet-relay[935]: {"timestamp":"2026-09-06T10:55:38.423738Z","level":"INFO","message":"job reattached","job":"e2d0a825b695f0f0a63f82f59f24ccf7","targets":"[\"agent/app\"]","target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> agent # [13861.397650] agent flakelet-agent[3471]: {"timestamp":"2026-09-06T10:55:39.359405Z","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.07 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.84 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 # [13862.225891] agent flakelet-agent[3471]: {"timestamp":"2026-09-06T10:55:40.187678Z","level":"INFO","message":"start","id":"5be20c693e5c0b60e3f85779794dfb86","flakelet":"other","rule":"admins","relay":"relay","caller_name":"Some(\"admin@example.org\")","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> agent # [13862.226370] agent flakelet-agent[3471]: {"timestamp":"2026-09-06T10:55:40.188198Z","level":"INFO","message":"update starting","flakelet":"other","ids":"[\"5be20c693e5c0b60e3f85779794dfb86\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [13862.305705] agent systemd[1]: Starting flakelet update other (via relay)... container-test-run-flakelet-relay> agent # [13862.312485] agent systemd[1]: Started flakelet update other (via relay). container-test-run-flakelet-relay> agent # [13862.317572] agent flakelet[4228]: other: using prebuilt artifact /nix/store/hmr990n0b4v6rpzrcpyyw738r9xi7z95-flakelet-other-v1 container-test-run-flakelet-relay> agent # [13862.318248] agent flakelet[4228]: other: up to date container-test-run-flakelet-relay> agent # [13862.320441] agent systemd[1]: flakelet-relay-job-other.service: Deactivated successfully. container-test-run-flakelet-relay> relay # [13862.225547] relay flakelet-relay[935]: {"timestamp":"2026-09-06T10:55:40.187253Z","level":"INFO","message":"deploy accepted","job":"188b06c4636b65849b3ba8daac22dc7c","caller":"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.69 seconds) container-test-run-flakelet-relay> relay: must succeed: systemctl restart flakelet-relay container-test-run-flakelet-relay> agent # [13862.889871] agent flakelet-agent[3471]: {"timestamp":"2026-09-06T10:55:40.851660Z","level":"INFO","message":"update finished","flakelet":"other","status":"Unchanged","generation":"Some(1)","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [13862.907291] agent flakelet-agent[3471]: {"timestamp":"2026-09-06T10:55:40.869105Z","level":"INFO","message":"disconnected: IO error: peer closed connection without sending TLS close_notify: https://docs.rs/rustls/latest/rustls/manual/_03_howto/index.html#unexpected-eof","relay":"https://relay:7443","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> relay # [13862.891372] relay flakelet-relay[935]: {"timestamp":"2026-09-06T10:55:40.853123Z","level":"INFO","message":"deploy finished","job":"188b06c4636b65849b3ba8daac22dc7c","ok":true,"target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> relay # [13862.907045] relay systemd[1]: Stopping flakelet-relay... container-test-run-flakelet-relay> relay # [13862.946718] relay systemd[1]: flakelet-relay.service: Deactivated successfully. container-test-run-flakelet-relay> relay # [13862.947189] relay systemd[1]: Stopped flakelet-relay. container-test-run-flakelet-relay> relay # [13862.947228] relay systemd[1]: flakelet-relay.service: Consumed 2.413s CPU time over 9.456s wall clock time. container-test-run-flakelet-relay> relay # [13862.983215] relay systemd[1]: Starting flakelet-relay... container-test-run-flakelet-relay> agent # [13863.910223] agent flakelet-agent[3471]: {"timestamp":"2026-09-06T10:55:41.871970Z","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.55 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 # [13864.445876] relay flakelet-relay[1325]: {"timestamp":"2026-09-06T10:55:42.407663Z","level":"INFO","message":"listening on 127.0.0.1:7400","target":"flakelet_relay::relay::server"} container-test-run-flakelet-relay> relay # [13864.446424] relay flakelet-relay[1325]: {"timestamp":"2026-09-06T10:55:42.408194Z","level":"INFO","message":"listening on [::]:7443","target":"flakelet_relay::relay::server"} container-test-run-flakelet-relay> relay # [13864.446581] relay systemd[1]: Started flakelet-relay. container-test-run-flakelet-relay> agent # [13865.924911] agent flakelet-agent[3471]: {"timestamp":"2026-09-06T10:55:43.886654Z","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 # [13865.925056] relay flakelet-relay[1325]: {"timestamp":"2026-09-06T10:55:43.886781Z","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": [], "repository": "example/app"}' | 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": [], "repository": "example/app"}' | 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.eyJhdWQiOiJmbGFrZWxldC1yZWxheSIsImV4cCI6MTc4ODY5MjQ0NCwiZ3JvdXBzIjpbXSwiaWF0IjoxNzg4NjkyMTQ0LCJpc3MiOiJodHRwczovL3JlbGF5Ojg0NDMiLCJqdGkiOiJXSFZRQVg1NzNSSlIyPT09IiwibmJmIjoxNzg4NjkyMTQ0LCJyZXBvc2l0b3J5IjoiZXhhbXBsZS9hcHAiLCJzdWIiOiJyZXBvOmdpdGh1YjpleGFtcGxlL2FwcDpyZWY6cmVmcy9oZWFkcy9tYWluIn0.HhKbBMYUCf-fwPTUPQzpuyV7fbYFNVM0pLtyDwdISzM-dwLOUk432ZNAvz1wrxj7AgWw3H3iG8wWbjhbSlW-DQ' push --relay https://relay:7443 deploy --id hist2 agent/app container-test-run-flakelet-relay> » job 6a239dbf87cfec796e49404c351d787f 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: up to date container-test-run-flakelet-relay> agent # [13866.597499] agent flakelet-agent[3471]: {"timestamp":"2026-09-06T10:55:44.559245Z","level":"INFO","message":"start","id":"dce6faec8455f85321e72ee634888126","flakelet":"app","rule":"ci","relay":"relay","caller_name":"Some(\"example/app\")","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> agent # [13866.598165] agent flakelet-agent[3471]: {"timestamp":"2026-09-06T10:55:44.559981Z","level":"INFO","message":"update starting","flakelet":"app","ids":"[\"dce6faec8455f85321e72ee634888126\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [13866.666442] agent systemd[1]: Starting flakelet update app (via relay)... container-test-run-flakelet-relay> agent/appagent # [13866.697165] agent systemd[1]: Started flakelet update app (via relay). container-test-run-flakelet-relay> | agent # [13866.701194] agent flakelet[4292]: app: using prebuilt artifact /nix/store/c67jj8kx3m50nyj874aqmdqf21w5iznd-flakelet-app-slow container-test-run-flakelet-relay> flakelet-relay-job-app.service: Deactivated successfully.agent # [13866.701683] agent flakelet[4292]: app: up to date container-test-run-flakelet-relay> container-test-run-flakelet-relay> agent # [13866.704331] agent systemd[1]: flakelet-relay-job-app.service: Deactivated successfully. container-test-run-flakelet-relay> relay # [13866.596983] relay flakelet-relay[1325]: {"timestamp":"2026-09-06T10:55:44.558753Z","level":"INFO","message":"deploy accepted","job":"6a239dbf87cfec796e49404c351d787f","caller":"example/app","targets":"[\"agent/app\"]","target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> » agent/app: unchanged (generation 9) container-test-run-flakelet-relay> » ok container-test-run-flakelet-relay> client: (finished: must succeed: FLAKELET_RELAY_TOKEN_COMMAND='echo eyJhbGciOiJFZERTQSIsImtpZCI6ImsxIiwidHlwIjoiSldUIn0.eyJhdWQiOiJmbGFrZWxldC1yZWxheSIsImV4cCI6MTc4ODY5MjQ0NCwiZ3JvdXBzIjpbXSwiaWF0IjoxNzg4NjkyMTQ0LCJpc3MiOiJodHRwczovL3JlbGF5Ojg0NDMiLCJqdGkiOiJXSFZRQVg1NzNSSlIyPT09IiwibmJmIjoxNzg4NjkyMTQ0LCJyZXBvc2l0b3J5IjoiZXhhbXBsZS9hcHAiLCJzdWIiOiJyZXBvOmdpdGh1YjpleGFtcGxlL2FwcDpyZWY6cmVmcy9oZWFkcy9tYWluIn0.HhKbBMYUCf-fwPTUPQzpuyV7fbYFNVM0pLtyDwdISzM-dwLOUk432ZNAvz1wrxj7AgWw3H3iG8wWbjhbSlW-DQ' push --relay https://relay:7443 deploy --id hist2 agent/app, in 0.70 seconds) container-test-run-flakelet-relay> client: must succeed: FLAKELET_RELAY_TOKEN_COMMAND='echo eyJhbGciOiJFZERTQSIsImtpZCI6ImsxIiwidHlwIjoiSldUIn0.eyJhdWQiOiJmbGFrZWxldC1yZWxheSIsImV4cCI6MTc4ODY5MjQ0NCwiZ3JvdXBzIjpbXSwiaWF0IjoxNzg4NjkyMTQ0LCJpc3MiOiJodHRwczovL3JlbGF5Ojg0NDMiLCJqdGkiOiJXSFZRQVg1NzNSSlIyPT09IiwibmJmIjoxNzg4NjkyMTQ0LCJyZXBvc2l0b3J5IjoiZXhhbXBsZS9hcHAiLCJzdWIiOiJyZXBvOmdpdGh1YjpleGFtcGxlL2FwcDpyZWY6cmVmcy9oZWFkcy9tYWluIn0.HhKbBMYUCf-fwPTUPQzpuyV7fbYFNVM0pLtyDwdISzM-dwLOUk432ZNAvz1wrxj7AgWw3H3iG8wWbjhbSlW-DQ' push --relay https://relay:7443 jobs container-test-run-flakelet-relay> client: (finished: must succeed: FLAKELET_RELAY_TOKEN_COMMAND='echo eyJhbGciOiJFZERTQSIsImtpZCI6ImsxIiwidHlwIjoiSldUIn0.eyJhdWQiOiJmbGFrZWxldC1yZWxheSIsImV4cCI6MTc4ODY5MjQ0NCwiZ3JvdXBzIjpbXSwiaWF0IjoxNzg4NjkyMTQ0LCJpc3MiOiJodHRwczovL3JlbGF5Ojg0NDMiLCJqdGkiOiJXSFZRQVg1NzNSSlIyPT09IiwibmJmIjoxNzg4NjkyMTQ0LCJyZXBvc2l0b3J5IjoiZXhhbXBsZS9hcHAiLCJzdWIiOiJyZXBvOmdpdGh1YjpleGFtcGxlL2FwcDpyZWY6cmVmcy9oZWFkcy9tYWluIn0.HhKbBMYUCf-fwPTUPQzpuyV7fbYFNVM0pLtyDwdISzM-dwLOUk432ZNAvz1wrxj7AgWw3H3iG8wWbjhbSlW-DQ' 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 5.09 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.09 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.03 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/bf07407f1598eaa93f702d9a2acdbe89/events || true container-test-run-flakelet-relay> relay # [13867.277283] relay flakelet-relay[1325]: {"timestamp":"2026-09-06T10:55:45.239026Z","level":"INFO","message":"deploy finished","job":"6a239dbf87cfec796e49404c351d787f","ok":true,"target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> relay # [13867.355017] relay 93sn3sv24fj26ksccpppzwg6kbf10s4w-device-mock[185]: 127.0.0.1 - - [06/Sep/2026 10:55:45] "GET /authorize?response_type=code&client_id=dashboard&redirect_uri=https%3A%2F%2Frelay%3A7443%2Fui%2Fcallback&scope=openid%20profile%20email%20groups&state=kF1gQ5hCUaQvi1-QkC72TWNy2GLVXWxDQH9BYotosPo&code_challenge=UJBXRVmqS_ceSuD65G-bmw3J660uAwr7AVfVr8PeMzM&code_challenge_method=S256 HTTP/1.1" 302 - container-test-run-flakelet-relay> relay # [13867.405980] relay 93sn3sv24fj26ksccpppzwg6kbf10s4w-device-mock[185]: 127.0.0.1 - - [06/Sep/2026 10:55:45] "POST /token HTTP/1.1" 200 - container-test-run-flakelet-relay> relay # [13867.407485] relay flakelet-relay[1325]: {"timestamp":"2026-09-06T10:55:45.369286Z","level":"INFO","message":"login","name":"dev@example.org","principals":"[\"oidc:mock:someone\", \"oidc:mock:groups:wheel\"]","target":"flakelet_relay::relay::login"} container-test-run-flakelet-relay> relay # [13867.479364] relay flakelet-relay[1325]: {"timestamp":"2026-09-06T10:55:45.441155Z","level":"INFO","message":"job reattached","job":"188b06c4636b65849b3ba8daac22dc7c","targets":"[\"agent/other\"]","target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> relay # [13867.520764] relay flakelet-relay[1325]: {"timestamp":"2026-09-06T10:55:45.482549Z","level":"INFO","message":"deploy accepted","job":"dffaf53aeeba14d4889b0c9f277f030d","caller":"dev@example.org","targets":"[\"agent/other\"]","target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> relay # [13867.541139] relay flakelet-relay[1325]: {"timestamp":"2026-09-06T10:55:45.502870Z","level":"INFO","message":"job reattached","job":"dffaf53aeeba14d4889b0c9f277f030d","targets":"[\"agent/other\"]","target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> agent # [13867.276293] agent flakelet-agent[3471]: {"timestamp":"2026-09-06T10:55:45.238077Z","level":"INFO","message":"update finished","flakelet":"app","status":"Unchanged","generation":"Some(9)","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [13867.521037] agent flakelet-agent[3471]: {"timestamp":"2026-09-06T10:55:45.482818Z","level":"INFO","message":"start","id":"69101171e8c290f0efba69759f5c8a1f","flakelet":"other","rule":"admins","relay":"relay","caller_name":"Some(\"dev@example.org\")","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> agent # [13867.521475] agent flakelet-agent[3471]: {"timestamp":"2026-09-06T10:55:45.483301Z","level":"INFO","message":"update starting","flakelet":"other","ids":"[\"69101171e8c290f0efba69759f5c8a1f\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [13867.567528] agent systemd[1]: Starting flakelet update other (via relay)... container-test-run-flakelet-relay> agent # [13867.604204] agent systemd[1]: Started flakelet update other (via relay). container-test-run-flakelet-relay> agent # [13867.608912] agent flakelet[4342]: other: using prebuilt artifact /nix/store/hmr990n0b4v6rpzrcpyyw738r9xi7z95-flakelet-other-v1 container-test-run-flakelet-relay> agent # [13867.609548] agent flakelet[4342]: other: up to date container-test-run-flakelet-relay> agent # [13867.609916] 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/bf07407f1598eaa93f702d9a2acdbe89/events || true, in 0.66 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/bf07407f1598eaa93f702d9a2acdbe89 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/bf07407f1598eaa93f702d9a2acdbe89, 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=bf07407f1598eaa93f702d9a2acdbe89' | 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=bf07407f1598eaa93f702d9a2acdbe89' | 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 # [13868.179552] agent flakelet-agent[3471]: {"timestamp":"2026-09-06T10:55:46.141306Z","level":"INFO","message":"update finished","flakelet":"other","status":"Unchanged","generation":"Some(1)","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [13868.323423] agent flakelet-agent[3471]: {"timestamp":"2026-09-06T10:55:46.285182Z","level":"INFO","message":"start","id":"5e317fc57863c2a7c03ebe1bdacd1356","flakelet":"other","rule":"admins","relay":"relay","caller_name":"Some(\"admin@example.org\")","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> agent # [13868.323904] agent flakelet-agent[3471]: {"timestamp":"2026-09-06T10:55:46.285588Z","level":"INFO","message":"update starting","flakelet":"other","ids":"[\"5e317fc57863c2a7c03ebe1bdacd1356\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [13868.391443] agent systemd[1]: Starting flakelet update other (via relay)... container-test-run-flakelet-relay> agent # [13868.421120] agent systemd[1]: Started flakelet update other (via relay). container-test-run-flakelet-relay> agent # [13868.425548] agent flakelet[4378]: other: using prebuilt artifact /nix/store/hmr990n0b4v6rpzrcpyyw738r9xi7z95-flakelet-other-v1 container-test-run-flakelet-relay> agent # [13868.426125] agent flakelet[4378]: other: up to date container-test-run-flakelet-relay> agent # [13868.428261] agent systemd[1]: flakelet-relay-job-other.service: Deactivated successfully. container-test-run-flakelet-relay> agent/other | flakelet-relay-job-other.service: Deactivated successfully. container-test-run-flakelet-relay> relay # [13868.323117] relay flakelet-relay[1325]: {"timestamp":"2026-09-06T10:55:46.284869Z","level":"INFO","message":"deploy accepted","job":"38796a7ba508eadfe82a1cd5def000bc","caller":"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.69 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.75 seconds) container-test-run-flakelet-relay> (finished: run the VM test script, in 90.27 seconds) container-test-run-flakelet-relay> agent # [13868.993388] agent flakelet-agent[3471]: {"timestamp":"2026-09-06T10:55:46.955137Z","level":"INFO","message":"update finished","flakelet":"other","status":"Unchanged","generation":"Some(1)","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> relay # [13868.994565] relay flakelet-relay[1325]: {"timestamp":"2026-09-06T10:55:46.956313Z","level":"INFO","message":"deploy finished","job":"38796a7ba508eadfe82a1cd5def000bc","ok":true,"target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> test script finished in 90.96s container-test-run-flakelet-relay> cleanup container-test-run-flakelet-relay> kill NspawnMachine (pid 52) container-test-run-flakelet-relay> kill NspawnMachine (pid 54) container-test-run-flakelet-relay> Container agent terminated by signal KILL. container-test-run-flakelet-relay> kill NspawnMachine (pid 56) container-test-run-flakelet-relay> Container client terminated by signal KILL. container-test-run-flakelet-relay> Container relay terminated by signal KILL. container-test-run-flakelet-relay> (finished: cleanup, in 0.39 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 709c2bd7e751ca0e797473fae9dbe115 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 a6b2e57da7350a73b57cc2ec347ac673 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 ccfd2b06ce8ff9b6ab7cdc543fb73b2f 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 38ae229ab76e201521bb6f4046c6addd 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 e2d0a825b695f0f0a63f82f59f24ccf7 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 c2c287850fdb7de11828404ab13e6a48 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> flakelet-push: https://relay:7443: 404 Not Found: unknown_job: no connected agent knows this job, retrying in 4s container-test-run-flakelet-relay> » job e2d0a825b695f0f0a63f82f59f24ccf7 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> 1788692140 hist1 admin@example.org agent/other:unchanged container-test-run-flakelet-relay> 1788692130 c2c287850fdb7de11828404ab13e6a48 admin@example.org agent/app:updated container-test-run-flakelet-relay> 1788692121 4c296c1443262f1e88dbe00726be407a admin@example.org agent/app:updated container-test-run-flakelet-relay> 1788692104 6b316ad33958d7fbf29ad747232e19e3 admin@example.org agent/app:updated container-test-run-flakelet-relay> 1788692095 9d07645779b22e56606718e0e6b55e12 admin@example.org agent/other:unchanged container-test-run-flakelet-relay> 1788692083 06424286e0d538bf7f4e4758dd27ae22 admin@example.org agent/app:updated container-test-run-flakelet-relay> 1788692082 653799fff305669e311074d03779dcd0 admin@example.org agent/app:unchanged container-test-run-flakelet-relay> 1788692081 3a3d09aa1a05fad0265c6670a721ade8 admin@example.org agent/app:unchanged container-test-run-flakelet-relay> 1788692080 d02f77bef7741cd426dceb5d33fa62f9 admin@example.org agent/app:unchanged container-test-run-flakelet-relay> 1788692076 a04cceb60083fcca1a93dd4d757088e1 admin@example.org agent/app:rolled-back container-test-run-flakelet-relay> 1788692074 72285518dced8768c8bd732098788ba4 dev@example.org agent/other:unchanged container-test-run-flakelet-relay> 1788692073 40be619d7555a6175d5b5a39bc007711 dev@example.org agent/other:unchanged container-test-run-flakelet-relay> 1788692071 0d3f26da8060adc440b15e84effa27ce someone agent/other:unchanged container-test-run-flakelet-relay> 1788692068 c40e1e483ad49d8ac264044519b2ef17 repo:github:example/app:ref:refs/heads/main agent/app:updated container-test-run-flakelet-relay> 1788692065 9ab879d72e64275b03c417f2785e8f31 admin@example.org agent/app:updated container-test-run-flakelet-relay> post-build step Upload coverage to codecov: ok Skipping codecov: project=Mic92/flakelet-relay attr=checks.x86_64-linux.nixos-test