these 36 derivations will be built: /nix/store/ch08vf9rgrc610lbj205srqpjvb7a410-push.drv /nix/store/h5jw32qszyr6z4m3xfbiql7lp050k535-system-path.drv /nix/store/bdapr38ps55xv3sd73f4sh7q2pgl81xq-dbus-1.drv /nix/store/xvi47kmywwlcnmzzmvww6ngnqwxzf3gz-X-Restart-Triggers-dbus-broker.drv /nix/store/027b5ghhzp65j2d6vmdsd2yfvkv3bi33-unit-dbus-broker.service.drv /nix/store/4m8ciy18jk5h3h379csv8ij9y0pc923y-flakelet-relay-0.1.0.drv /nix/store/57ypshz1yp2f5yilyzlm4qfv102rf7c6-flakelet-relay.json.drv /nix/store/hvnp0rw4bcgcl13rbpwjyzscmq4c37y2-flakelet-relay.service.drv /nix/store/y9np13p9n9xr9i5ypjfxj4r736dwrlg6-flakelet-flakelet-relay-units.drv /nix/store/6fpxxzij8w7as8rshcln5yahd2alvxiv-flakelet-flakelet-relay.drv /nix/store/6fmxb40f7nm8drwc9hscln1knx8wpg4s-flakelet-config.json.drv /nix/store/xpljbk816rl6r924snz4adinq2fjg4s0-X-Restart-Triggers-flakelet-reconcile.drv /nix/store/59bdvrbrqz3wh5lkg275cpqpnkk770m9-unit-flakelet-reconcile.service.drv /nix/store/2sgf3mbi0kmxckywc7yh253qybkq5wgp-X-Restart-Triggers-flakelet-flakelet-relay.drv /nix/store/b12izj9vag0b7jb459j5lylkndylrl39-unit-flakelet-flakelet-relay.service.drv /nix/store/638p1l7641b92njlacmsrq174bcfb5vz-system-units.drv /nix/store/8s3ayw4kbw6ck7vk7zlfca2dlmkj1rbs-etc.drv /nix/store/42nk9122pd9gz3skbzx0mwl6b01fyxdl-activate.drv /nix/store/whdzvckxi60gqqi25ny0vr61asqndcv5-nixos-system-relay-test.drv /nix/store/cxp0lxdgkcj13gc4f82hnsm0m8dvjn1l-run-relay-nspawn.drv /nix/store/bnbkkhs8z7b9427kcj2c2r0bg1s2f408-user-units.drv /nix/store/lkkwjjcjiplyn4qw79jvl2d5sfzxy9gi-unit-dbus-broker.service.drv /nix/store/ijmrk8hni16gs594iq8nzrwcqkl2ymkf-system-units.drv /nix/store/6pd63ibphhjkl27gsz1pyvj79m612qpr-etc.drv /nix/store/k13x2n2iwdhlk4z8sh2dqvzs82w5m8ll-activate.drv /nix/store/xr981cg6k2d0bfkbpx3qgmjqcjc22pa7-nixos-system-client-test.drv /nix/store/gms124ppp1ai8igvigbhrdn3lq6c5nkz-run-client-nspawn.drv /nix/store/0f1f8k3fmzfbh5f2anzl92iqj2q7bczl-unit-flakelet-agent.service.drv /nix/store/a11n2dp1n30mpsifbipjfbgjzv7iyrcc-system-units.drv /nix/store/c3n54pydn9nd55cy9l46xq3v1ziznx1l-etc.drv /nix/store/238amcaccck6wv3xmi4rd1ml5nd1gack-activate.drv /nix/store/682azcsjvnfc7k8pwgy22d4ammba2wrr-nixos-system-agent-test.drv /nix/store/p5wnprn67kfaz37ald6k0n7agf66nki4-run-agent-nspawn.drv /nix/store/03midxxhc53mmjna3gahq7nwa710bqb7-driverConfiguration.json.drv /nix/store/1fzxi9aky8yszvl4rbmaz8a1485668wk-nixos-test-driver-flakelet-relay.drv /nix/store/1ic69a6r1i6vbhcpv5b41ivq6rhsvvr5-container-test-run-flakelet-relay.drv building '/nix/store/ch08vf9rgrc610lbj205srqpjvb7a410-push.drv' building '/nix/store/0f1f8k3fmzfbh5f2anzl92iqj2q7bczl-unit-flakelet-agent.service.drv' building '/nix/store/2sgf3mbi0kmxckywc7yh253qybkq5wgp-X-Restart-Triggers-flakelet-flakelet-relay.drv' push> tribuchet: building on jamie unit-flakelet-agent.service> tribuchet: building on jamie X-Restart-Triggers-flakelet-flakelet-relay> tribuchet: building on jamie building '/nix/store/4m8ciy18jk5h3h379csv8ij9y0pc923y-flakelet-relay-0.1.0.drv' flakelet-relay-0.1.0> tribuchet: building on jamie unit-flakelet-agent.service> structuredAttrs is enabled flakelet-relay-0.1.0> Running phase: unpackPhase flakelet-relay-0.1.0> unpacking source archive /nix/store/qscsghdyy85yjhhh59bc5446985z05rk-source flakelet-relay-0.1.0> source root is source flakelet-relay-0.1.0> Executing cargoSetupPostUnpackHook building '/nix/store/b12izj9vag0b7jb459j5lylkndylrl39-unit-flakelet-flakelet-relay.service.drv' unit-flakelet-flakelet-relay.service> tribuchet: building on jamie building '/nix/store/h5jw32qszyr6z4m3xfbiql7lp050k535-system-path.drv' unit-flakelet-flakelet-relay.service> structuredAttrs is enabled building '/nix/store/a11n2dp1n30mpsifbipjfbgjzv7iyrcc-system-units.drv' 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 system-path> tribuchet: building on jamie system-units> tribuchet: building on jamie 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 autocfg v1.5.1 flakelet-relay-0.1.0> Compiling memchr v2.8.3 flakelet-relay-0.1.0> Compiling itoa v1.0.18 flakelet-relay-0.1.0> Compiling shlex v2.0.1 flakelet-relay-0.1.0> Compiling find-msvc-tools v0.1.11 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 once_cell v1.21.4 flakelet-relay-0.1.0> Compiling num-conv v0.2.2 flakelet-relay-0.1.0> Compiling minimal-lexical v0.2.1 flakelet-relay-0.1.0> Compiling serde_core v1.0.229 flakelet-relay-0.1.0> Compiling thiserror v2.0.20 flakelet-relay-0.1.0> Compiling time-core v0.1.9 flakelet-relay-0.1.0> Compiling powerfmt v0.2.0 flakelet-relay-0.1.0> Compiling deranged v0.5.8 flakelet-relay-0.1.0> Compiling zeroize v1.9.0 flakelet-relay-0.1.0> Compiling version_check v0.9.5 flakelet-relay-0.1.0> Compiling log v0.4.34 flakelet-relay-0.1.0> Compiling untrusted v0.9.0 flakelet-relay-0.1.0> Compiling zmij v1.0.23 flakelet-relay-0.1.0> Compiling cc v1.4.4 flakelet-relay-0.1.0> Compiling futures-core v0.3.34 flakelet-relay-0.1.0> Compiling utf8parse v0.2.2 flakelet-relay-0.1.0> Compiling rustls-pki-types v1.15.1 flakelet-relay-0.1.0> Compiling tracing-core v0.1.36 flakelet-relay-0.1.0> Compiling time-macros v0.2.32 flakelet-relay-0.1.0> Compiling serde v1.0.229 flakelet-relay-0.1.0> Compiling httparse v1.10.1 flakelet-relay-0.1.0> Compiling oid-registry v0.8.1 flakelet-relay-0.1.0> Compiling anstyle-parse v1.0.0 flakelet-relay-0.1.0> Compiling anstyle v1.0.14 flakelet-relay-0.1.0> Compiling smallvec v1.16.0 flakelet-relay-0.1.0> Compiling serde_json v1.0.151 flakelet-relay-0.1.0> Compiling proc-macro2-diagnostics v0.10.1 flakelet-relay-0.1.0> Compiling anstyle-query v1.1.5 flakelet-relay-0.1.0> Compiling is_terminal_polyfill v1.70.2 flakelet-relay-0.1.0> Compiling regex-syntax v0.8.11 flakelet-relay-0.1.0> Compiling num-traits v0.2.19 flakelet-relay-0.1.0> Compiling rustls v0.23.43 flakelet-relay-0.1.0> Compiling colorchoice v1.0.5 flakelet-relay-0.1.0> Compiling try-lock v0.2.5 flakelet-relay-0.1.0> Compiling lazy_static v1.5.0 system-path> structuredAttrs is enabled 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 subtle v2.6.1 flakelet-relay-0.1.0> Compiling httpdate v1.0.3 flakelet-relay-0.1.0> Compiling nom v7.1.3 flakelet-relay-0.1.0> Compiling atomic-waker v1.1.2 flakelet-relay-0.1.0> Compiling clap_lex v1.1.0 flakelet-relay-0.1.0> Compiling strsim v0.11.1 flakelet-relay-0.1.0> Compiling heck v0.5.0 flakelet-relay-0.1.0> Compiling tracing-log v0.2.0 flakelet-relay-0.1.0> Compiling sharded-slab v0.1.7 flakelet-relay-0.1.0> Compiling http v1.5.0 flakelet-relay-0.1.0> Compiling thread_local v1.1.10 flakelet-relay-0.1.0> Compiling clap_builder v4.6.6 flakelet-relay-0.1.0> Compiling nu-ansi-term v0.50.3 flakelet-relay-0.1.0> Compiling data-encoding v2.11.1 system-path> created 6399 symlinks in user environment 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 errno v0.3.14 flakelet-relay-0.1.0> Compiling getrandom v0.2.17 flakelet-relay-0.1.0> Compiling socket2 v0.6.5 flakelet-relay-0.1.0> Compiling mio v1.2.2 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 time v0.3.55 flakelet-relay-0.1.0> Compiling http-body v1.1.0 flakelet-relay-0.1.0> Compiling http-body-util v0.1.5 flakelet-relay-0.1.0> Compiling regex-automata v0.4.18 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 rusticata-macros v4.1.0 building '/nix/store/c3n54pydn9nd55cy9l46xq3v1ziznx1l-etc.drv' etc> tribuchet: building on jamie building '/nix/store/bdapr38ps55xv3sd73f4sh7q2pgl81xq-dbus-1.drv' dbus-1> tribuchet: building on jamie 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 matchers v0.2.0 flakelet-relay-0.1.0> Compiling synstructure v0.13.2 building '/nix/store/238amcaccck6wv3xmi4rd1ml5nd1gack-activate.drv' flakelet-relay-0.1.0> Compiling tokio v1.53.1 activate> tribuchet: building on jamie 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 building '/nix/store/xvi47kmywwlcnmzzmvww6ngnqwxzf3gz-X-Restart-Triggers-dbus-broker.drv' X-Restart-Triggers-dbus-broker> tribuchet: building on jamie 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 building '/nix/store/682azcsjvnfc7k8pwgy22d4ammba2wrr-nixos-system-agent-test.drv' nixos-system-agent-test> tribuchet: building on jamie flakelet-relay-0.1.0> Compiling tracing v0.1.44 building '/nix/store/027b5ghhzp65j2d6vmdsd2yfvkv3bi33-unit-dbus-broker.service.drv' building '/nix/store/lkkwjjcjiplyn4qw79jvl2d5sfzxy9gi-unit-dbus-broker.service.drv' unit-dbus-broker.service> tribuchet: building on jamie unit-dbus-broker.service> tribuchet: building on jamie flakelet-relay-0.1.0> Compiling tracing-serde v0.2.0 flakelet-relay-0.1.0> Compiling tracing-subscriber v0.3.23 nixos-system-agent-test> structuredAttrs is enabled unit-dbus-broker.service> structuredAttrs is enabled unit-dbus-broker.service> structuredAttrs is enabled flakelet-relay-0.1.0> Compiling der-parser v10.0.0 building '/nix/store/p5wnprn67kfaz37ald6k0n7agf66nki4-run-agent-nspawn.drv' run-agent-nspawn> tribuchet: building on jamie flakelet-relay-0.1.0> Compiling x509-parser v0.18.1 building '/nix/store/bnbkkhs8z7b9427kcj2c2r0bg1s2f408-user-units.drv' building '/nix/store/ijmrk8hni16gs594iq8nzrwcqkl2ymkf-system-units.drv' user-units> tribuchet: building on jamie system-units> tribuchet: building on jamie flakelet-relay-0.1.0> Compiling hyper v1.11.1 flakelet-relay-0.1.0> Compiling rustls-webpki v0.103.15 flakelet-relay-0.1.0> Compiling hyper-util v0.1.20 building '/nix/store/6pd63ibphhjkl27gsz1pyvj79m612qpr-etc.drv' etc> tribuchet: building on jamie building '/nix/store/k13x2n2iwdhlk4z8sh2dqvzs82w5m8ll-activate.drv' activate> tribuchet: building on jamie flakelet-relay-0.1.0> Compiling tokio-rustls v0.26.4 flakelet-relay-0.1.0> Compiling flakelet-relay v0.1.0 (/build/source) building '/nix/store/xr981cg6k2d0bfkbpx3qgmjqcjc22pa7-nixos-system-client-test.drv' nixos-system-client-test> tribuchet: building on jamie nixos-system-client-test> structuredAttrs is enabled building '/nix/store/gms124ppp1ai8igvigbhrdn3lq6c5nkz-run-client-nspawn.drv' run-client-nspawn> tribuchet: building on jamie flakelet-relay-0.1.0> Finished `release` profile [optimized] target(s) in 27.54s flakelet-relay-0.1.0> Executing cargoInstallPostBuildHook flakelet-relay-0.1.0> Finished cargoInstallPostBuildHook flakelet-relay-0.1.0> Finished cargoBuildHook flakelet-relay-0.1.0> Running phase: checkPhase flakelet-relay-0.1.0> Executing cargoCheckHook flakelet-relay-0.1.0> cargoCheckHook flags: -j 24 --profile release --target x86_64-unknown-linux-gnu --offline -- flakelet-relay-0.1.0> Compiling getrandom v0.4.3 flakelet-relay-0.1.0> Compiling rustix v1.1.4 flakelet-relay-0.1.0> Compiling linux-raw-sys v0.12.1 flakelet-relay-0.1.0> Compiling bitflags v2.13.1 flakelet-relay-0.1.0> Compiling fastrand v2.5.0 flakelet-relay-0.1.0> Compiling tempfile v3.27.0 flakelet-relay-0.1.0> Compiling flakelet-relay v0.1.0 (/build/source) flakelet-relay-0.1.0> Finished `release` profile [optimized] target(s) in 10.21s flakelet-relay-0.1.0> Running unittests src/lib.rs (target/x86_64-unknown-linux-gnu/release/deps/flakelet_relay-a0eaee9b21fdfd23) flakelet-relay-0.1.0> flakelet-relay-0.1.0> running 20 tests flakelet-relay-0.1.0> test auth::policy::tests::globbing ... ok flakelet-relay-0.1.0> test auth::policy::tests::agent_identity ... ok flakelet-relay-0.1.0> test auth::policy::tests::deploy_rules ... ok flakelet-relay-0.1.0> test auth::policy::tests::validate_catches_unknown_group ... ok flakelet-relay-0.1.0> test oidc::tests::encodes_forms ... ok flakelet-relay-0.1.0> test client::tests::urls ... ok flakelet-relay-0.1.0> test relay::pages::tests::short_prefers_email_over_opaque_sub ... 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 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 proto::tests::target_split_rejects_malformed ... ok flakelet-relay-0.1.0> test ws::tests::rfc_example_accept_key ... ok flakelet-relay-0.1.0> test relay::session::tests::roundtrip_and_tamper ... ok flakelet-relay-0.1.0> test proto::tests::unknown_frame_type_is_tolerated ... ok flakelet-relay-0.1.0> test sse::tests::roundtrip_split_across_chunks ... ok flakelet-relay-0.1.0> test proto::tests::unknown_fields_and_status_are_tolerated ... ok flakelet-relay-0.1.0> test auth::jwt::tests::es256 ... ok flakelet-relay-0.1.0> test auth::jwt::tests::ed25519_happy_and_claim_checks ... ok flakelet-relay-0.1.0> test ws::tests::roundtrip_masked_large ... ok flakelet-relay-0.1.0> flakelet-relay-0.1.0> test result: ok. 20 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s flakelet-relay-0.1.0> flakelet-relay-0.1.0> Running unittests src/bin/flakelet-agent.rs (target/x86_64-unknown-linux-gnu/release/deps/flakelet_agent-1cb04c92098d9184) flakelet-relay-0.1.0> flakelet-relay-0.1.0> running 0 tests flakelet-relay-0.1.0> flakelet-relay-0.1.0> test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s flakelet-relay-0.1.0> flakelet-relay-0.1.0> Running unittests src/bin/flakelet-push.rs (target/x86_64-unknown-linux-gnu/release/deps/flakelet_push-e04fe5b96273a4a1) flakelet-relay-0.1.0> flakelet-relay-0.1.0> running 0 tests flakelet-relay-0.1.0> flakelet-relay-0.1.0> test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s flakelet-relay-0.1.0> flakelet-relay-0.1.0> Running unittests src/bin/flakelet-relay.rs (target/x86_64-unknown-linux-gnu/release/deps/flakelet_relay-2deef0a6284d0b44) flakelet-relay-0.1.0> flakelet-relay-0.1.0> running 0 tests flakelet-relay-0.1.0> flakelet-relay-0.1.0> test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s flakelet-relay-0.1.0> flakelet-relay-0.1.0> Doc-tests flakelet_relay flakelet-relay-0.1.0> flakelet-relay-0.1.0> running 0 tests flakelet-relay-0.1.0> flakelet-relay-0.1.0> test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s flakelet-relay-0.1.0> flakelet-relay-0.1.0> Finished cargoCheckHook flakelet-relay-0.1.0> Running phase: installPhase flakelet-relay-0.1.0> Executing cargoInstallHook flakelet-relay-0.1.0> Finished cargoInstallHook flakelet-relay-0.1.0> Running phase: fixupPhase flakelet-relay-0.1.0> shrinking RPATHs of ELF executables and libraries in /nix/store/q8sbl8x01r86v7b90ch8llya93wlc1d3-flakelet-relay-0.1.0 flakelet-relay-0.1.0> shrinking /nix/store/q8sbl8x01r86v7b90ch8llya93wlc1d3-flakelet-relay-0.1.0/bin/flakelet-agent flakelet-relay-0.1.0> shrinking /nix/store/q8sbl8x01r86v7b90ch8llya93wlc1d3-flakelet-relay-0.1.0/bin/flakelet-push flakelet-relay-0.1.0> shrinking /nix/store/q8sbl8x01r86v7b90ch8llya93wlc1d3-flakelet-relay-0.1.0/bin/flakelet-relay flakelet-relay-0.1.0> checking for references to /build/ in /nix/store/q8sbl8x01r86v7b90ch8llya93wlc1d3-flakelet-relay-0.1.0... flakelet-relay-0.1.0> patching script interpreter paths in /nix/store/q8sbl8x01r86v7b90ch8llya93wlc1d3-flakelet-relay-0.1.0 flakelet-relay-0.1.0> stripping (with command strip and flags -S -p) in /nix/store/q8sbl8x01r86v7b90ch8llya93wlc1d3-flakelet-relay-0.1.0/bin building '/nix/store/57ypshz1yp2f5yilyzlm4qfv102rf7c6-flakelet-relay.json.drv' flakelet-relay.json> tribuchet: building on jamie flakelet-relay.json> check: oidc:mock:repo:github:example/app:ref:refs/heads/main -> agent/app (expect allow) flakelet-relay.json> agent/app allowed by ci flakelet-relay.json> check: oidc:mock:repo:github:example/app:ref:refs/heads/main -> agent/other (expect deny) flakelet-relay.json> agent/other denied building '/nix/store/hvnp0rw4bcgcl13rbpwjyzscmq4c37y2-flakelet-relay.service.drv' flakelet-relay.service> tribuchet: building on jamie building '/nix/store/y9np13p9n9xr9i5ypjfxj4r736dwrlg6-flakelet-flakelet-relay-units.drv' flakelet-flakelet-relay-units> tribuchet: building on jamie building '/nix/store/6fpxxzij8w7as8rshcln5yahd2alvxiv-flakelet-flakelet-relay.drv' flakelet-flakelet-relay> tribuchet: building on jamie building '/nix/store/6fmxb40f7nm8drwc9hscln1knx8wpg4s-flakelet-config.json.drv' flakelet-config.json> tribuchet: building on jamie flakelet-config.json> structuredAttrs is enabled building '/nix/store/xpljbk816rl6r924snz4adinq2fjg4s0-X-Restart-Triggers-flakelet-reconcile.drv' X-Restart-Triggers-flakelet-reconcile> tribuchet: building on jamie building '/nix/store/59bdvrbrqz3wh5lkg275cpqpnkk770m9-unit-flakelet-reconcile.service.drv' unit-flakelet-reconcile.service> tribuchet: building on jamie unit-flakelet-reconcile.service> structuredAttrs is enabled building '/nix/store/638p1l7641b92njlacmsrq174bcfb5vz-system-units.drv' system-units> tribuchet: building on jamie building '/nix/store/8s3ayw4kbw6ck7vk7zlfca2dlmkj1rbs-etc.drv' etc> tribuchet: building on jamie building '/nix/store/42nk9122pd9gz3skbzx0mwl6b01fyxdl-activate.drv' activate> tribuchet: building on jamie building '/nix/store/whdzvckxi60gqqi25ny0vr61asqndcv5-nixos-system-relay-test.drv' nixos-system-relay-test> tribuchet: building on jamie nixos-system-relay-test> structuredAttrs is enabled building '/nix/store/cxp0lxdgkcj13gc4f82hnsm0m8dvjn1l-run-relay-nspawn.drv' run-relay-nspawn> tribuchet: building on jamie building '/nix/store/03midxxhc53mmjna3gahq7nwa710bqb7-driverConfiguration.json.drv' driverConfiguration.json> tribuchet: building on jamie driverConfiguration.json> structuredAttrs is enabled building '/nix/store/1fzxi9aky8yszvl4rbmaz8a1485668wk-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/1ic69a6r1i6vbhcpv5b41ivq6rhsvvr5-container-test-run-flakelet-relay.drv' container-test-run-flakelet-relay> tribuchet: building on jamie container-test-run-flakelet-relay> Machine state will be reset. To keep it, pass --keep-machine-state container-test-run-flakelet-relay> start all VLans container-test-run-flakelet-relay> (finished: start all VLans, in 0.00 seconds) container-test-run-flakelet-relay> Initializing machine ID from random generator. container-test-run-flakelet-relay> Test will time out and terminate in 3600.0 seconds container-test-run-flakelet-relay> run the VM test script container-test-run-flakelet-relay> start all VMs container-test-run-flakelet-relay> agent: systemd-nspawn running (pid 52) container-test-run-flakelet-relay> client: systemd-nspawn running (pid 53) container-test-run-flakelet-relay> relay: systemd-nspawn running (pid 54) container-test-run-flakelet-relay> agent: Waiting for journal at /build/vm-state-agent/var/log/journal... container-test-run-flakelet-relay> client: Waiting for journal at /build/vm-state-client/var/log/journal... container-test-run-flakelet-relay> relay: Waiting for journal at /build/vm-state-relay/var/log/journal... container-test-run-flakelet-relay> (finished: start all VMs, in 0.00 seconds) container-test-run-flakelet-relay> relay: waiting for unit flakelet-relay.service container-test-run-flakelet-relay> nixos-nspawn(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(agent): TAP vde-tap1 not found; container will be isolated from VDE container-test-run-flakelet-relay> nixos-nspawn(agent): A common reason for this is that /dev/net is not available in the Nix sandbox. Try adding /dev/net to extra-sandbox-paths. container-test-run-flakelet-relay> nixos-nspawn(relay): TAP vde-tap1 not found; container will be isolated from VDE container-test-run-flakelet-relay> nixos-nspawn(relay): A common reason for this is that /dev/net is not available in the Nix sandbox. Try adding /dev/net to extra-sandbox-paths. container-test-run-flakelet-relay> 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> Note: in a future version of systemd-nspawn the default set of permitted socket address families will be restricted to AF_INET, AF_INET6 and AF_UNIX. Use --restrict-address-families= to configure the set of permitted socket address families, or set RestrictAddressFamilies= in a .nspawn file. container-test-run-flakelet-relay> Note: in a future version of systemd-nspawn the default set of permitted socket address families will be restricted to AF_INET, AF_INET6 and AF_UNIX. Use --restrict-address-families= to configure the set of permitted socket address families, or set RestrictAddressFamilies= in a .nspawn file. container-test-run-flakelet-relay> ░ Spawning container relay on /build/vm-state-relay. container-test-run-flakelet-relay> ░ Spawning container 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> relay # No journal files were found. container-test-run-flakelet-relay> relay # No journal boot entry found for the specified boot (+0). container-test-run-flakelet-relay> client # 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> agent # [84900.952206] agent systemd-journald[57]: Journal started container-test-run-flakelet-relay> agent # [84900.952248] agent systemd-journald[57]: Runtime Journal (/run/log/journal/fdb647dac4674313a4b71cbec1ef960b) is 8M, max 4G, 3.9G free. container-test-run-flakelet-relay> agent # [84900.962708] agent systemd[1]: Finished Apply Kernel Variables. container-test-run-flakelet-relay> agent # [84900.974264] agent systemd[1]: Finished Create Static Device Nodes in /dev gracefully. container-test-run-flakelet-relay> agent # [84901.007506] agent systemd[1]: Starting Flush Journal to Persistent Storage... container-test-run-flakelet-relay> agent # [84901.008209] agent systemd[1]: Starting Create Static Device Nodes in /dev... container-test-run-flakelet-relay> agent # [84901.016401] agent systemd-journald[57]: Time spent on flushing to /var/log/journal/fdb647dac4674313a4b71cbec1ef960b is 1.744ms for 6 entries. container-test-run-flakelet-relay> agent # [84901.016401] agent systemd-journald[57]: System Journal (/var/log/journal/fdb647dac4674313a4b71cbec1ef960b) is 512B, max 4G, 3.9G free. container-test-run-flakelet-relay> agent # [84901.028938] agent systemd[1]: Finished Create Static Device Nodes in /dev. container-test-run-flakelet-relay> agent # [84901.029793] agent systemd[1]: Reached target Preparation for Local File Systems. container-test-run-flakelet-relay> agent # [84901.029925] agent systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys container-test-run-flakelet-relay> agent # [84901.039076] agent systemd[1]: Finished Flush Journal to Persistent Storage. container-test-run-flakelet-relay> agent # [84901.112361] agent systemd[1]: Finished Firewall. container-test-run-flakelet-relay> client # [84900.944399] client systemd-journald[57]: Journal started container-test-run-flakelet-relay> relay # [84900.953840] relay systemd-journald[57]: Journal started container-test-run-flakelet-relay> client # [84900.944440] client systemd-journald[57]: Runtime Journal (/run/log/journal/703e661a5c0b4c66b458fca3cfd9754f) is 8M, max 4G, 3.9G free. container-test-run-flakelet-relay> relay # [84900.953885] relay systemd-journald[57]: Runtime Journal (/run/log/journal/c570373fcc0240218ae60b94573eace1) is 8M, max 4G, 3.9G free. container-test-run-flakelet-relay> client # [84900.954034] client systemd[1]: Finished Apply Kernel Variables. container-test-run-flakelet-relay> relay # [84900.965393] relay systemd[1]: Finished Apply Kernel Variables. container-test-run-flakelet-relay> client # [84900.965821] client systemd[1]: Finished Create Static Device Nodes in /dev gracefully. container-test-run-flakelet-relay> relay # [84900.977497] relay systemd[1]: Finished Create Static Device Nodes in /dev gracefully. container-test-run-flakelet-relay> client # [84900.981403] client systemd[1]: Starting Flush Journal to Persistent Storage... container-test-run-flakelet-relay> relay # [84901.007557] relay systemd[1]: Starting Flush Journal to Persistent Storage... container-test-run-flakelet-relay> client # [84900.982113] client systemd[1]: Starting Create Static Device Nodes in /dev... container-test-run-flakelet-relay> relay # [84901.008390] relay systemd[1]: Starting Create Static Device Nodes in /dev... container-test-run-flakelet-relay> relay # [84901.017052] relay systemd-journald[57]: Time spent on flushing to /var/log/journal/c570373fcc0240218ae60b94573eace1 is 1.503ms for 6 entries. container-test-run-flakelet-relay> client # [84901.013810] client systemd-journald[57]: Time spent on flushing to /var/log/journal/703e661a5c0b4c66b458fca3cfd9754f is 1.563ms for 6 entries. container-test-run-flakelet-relay> relay # [84901.017052] relay systemd-journald[57]: System Journal (/var/log/journal/c570373fcc0240218ae60b94573eace1) is 512B, max 4G, 3.9G free. container-test-run-flakelet-relay> relay # [84901.028133] relay systemd[1]: Finished Create Static Device Nodes in /dev. container-test-run-flakelet-relay> relay # [84901.028893] relay systemd[1]: Reached target Preparation for Local File Systems. container-test-run-flakelet-relay> relay # [84901.029012] relay systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys container-test-run-flakelet-relay> client # [84901.013810] client systemd-journald[57]: System Journal (/var/log/journal/703e661a5c0b4c66b458fca3cfd9754f) is 512B, max 4G, 3.9G free. container-test-run-flakelet-relay> relay # [84901.041572] relay systemd[1]: Finished Flush Journal to Persistent Storage. container-test-run-flakelet-relay> relay # [84901.127751] relay systemd[1]: Finished Firewall. container-test-run-flakelet-relay> relay # [84901.954649] relay systemd[1]: Mounting /run/wrappers... container-test-run-flakelet-relay> client # [84901.022086] client systemd[1]: Finished Create Static Device Nodes in /dev. container-test-run-flakelet-relay> client # [84901.022777] client systemd[1]: Reached target Preparation for Local File Systems. container-test-run-flakelet-relay> client # [84901.022885] client systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys container-test-run-flakelet-relay> client # [84901.035034] client systemd[1]: Finished Flush Journal to Persistent Storage. container-test-run-flakelet-relay> client # [84901.102179] client systemd[1]: Finished Firewall. container-test-run-flakelet-relay> relay # [84902.003960] relay systemd[1]: Mounted /run/wrappers. container-test-run-flakelet-relay> relay # [84902.004777] relay systemd[1]: Reached target Local File Systems. container-test-run-flakelet-relay> relay # [84902.006176] relay systemd[1]: Listening on Boot Loader Control Service Socket. container-test-run-flakelet-relay> relay # [84902.007457] relay systemd[1]: Starting Create SUID/SGID Wrappers... container-test-run-flakelet-relay> relay # [84902.007505] relay systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container container-test-run-flakelet-relay> relay # [84902.008664] relay systemd[1]: Starting Save Transient machine-id to Disk... container-test-run-flakelet-relay> relay # [84902.009724] relay systemd[1]: Starting Create System Files and Directories... container-test-run-flakelet-relay> relay # [84902.030911] 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 # [84902.031367] relay systemd-tmpfiles[169]: fchmod() of /var/log/journal failed: Operation not permitted container-test-run-flakelet-relay> relay # [84902.031692] relay systemd-tmpfiles[169]: fchmod() of /var/log/journal/c570373fcc0240218ae60b94573eace1 failed: Operation not permitted container-test-run-flakelet-relay> relay # [84902.032072] relay systemd-tmpfiles[169]: fchmod() of /run/log/journal failed: Operation not permitted container-test-run-flakelet-relay> agent # [84901.986765] agent systemd[1]: Mounting /run/wrappers... container-test-run-flakelet-relay> agent # [84902.025667] agent systemd[1]: Mounted /run/wrappers. container-test-run-flakelet-relay> agent # [84902.026941] agent systemd[1]: Reached target Local File Systems. container-test-run-flakelet-relay> agent # [84902.028386] agent systemd[1]: Listening on Boot Loader Control Service Socket. container-test-run-flakelet-relay> agent # [84902.029769] agent systemd[1]: Starting Create SUID/SGID Wrappers... container-test-run-flakelet-relay> agent # [84902.029820] agent systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container container-test-run-flakelet-relay> agent # [84902.030728] agent systemd[1]: Starting Save Transient machine-id to Disk... container-test-run-flakelet-relay> agent # [84902.031667] agent systemd[1]: Starting Create System Files and Directories... container-test-run-flakelet-relay> agent # [84902.051168] 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 # [84902.051615] agent systemd-tmpfiles[161]: fchmod() of /var/log/journal failed: Operation not permitted container-test-run-flakelet-relay> agent # [84902.051967] agent systemd-tmpfiles[161]: fchmod() of /var/log/journal/fdb647dac4674313a4b71cbec1ef960b failed: Operation not permitted container-test-run-flakelet-relay> agent # [84902.052392] agent systemd-tmpfiles[161]: fchmod() of /run/log/journal failed: Operation not permitted container-test-run-flakelet-relay> client # [84901.986761] client systemd[1]: Mounting /run/wrappers... container-test-run-flakelet-relay> client # [84902.026241] client systemd[1]: Mounted /run/wrappers. container-test-run-flakelet-relay> client # [84902.027534] client systemd[1]: Reached target Local File Systems. container-test-run-flakelet-relay> client # [84902.028925] client systemd[1]: Listening on Boot Loader Control Service Socket. container-test-run-flakelet-relay> client # [84902.030199] client systemd[1]: Starting Create SUID/SGID Wrappers... container-test-run-flakelet-relay> client # [84902.030246] client systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container container-test-run-flakelet-relay> client # [84902.031225] client systemd[1]: Starting Save Transient machine-id to Disk... container-test-run-flakelet-relay> client # [84902.032111] client systemd[1]: Starting Create System Files and Directories... container-test-run-flakelet-relay> client # [84902.052766] 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 # [84902.053203] client systemd-tmpfiles[161]: fchmod() of /var/log/journal failed: Operation not permitted container-test-run-flakelet-relay> client # [84902.053544] client systemd-tmpfiles[161]: fchmod() of /var/log/journal/703e661a5c0b4c66b458fca3cfd9754f failed: Operation not permitted container-test-run-flakelet-relay> client # [84902.053913] client systemd-tmpfiles[161]: fchmod() of /run/log/journal failed: Operation not permitted container-test-run-flakelet-relay> relay # [84902.034229] relay systemd[1]: Finished Create System Files and Directories. container-test-run-flakelet-relay> relay # [84902.034367] relay suid-sgid-wrappers-start[176]: chmod: changing permissions of '/run/wrappers/wrappers.YIHsudkI03/chsh': Operation not permitted container-test-run-flakelet-relay> relay # [84902.045939] relay systemd[1]: suid-sgid-wrappers.service: Main process exited, code=exited, status=1/FAILURE container-test-run-flakelet-relay> relay # [84902.046183] relay systemd[1]: suid-sgid-wrappers.service: Failed with result 'exit-code'. container-test-run-flakelet-relay> relay # [84902.046321] relay systemd[1]: Failed to start Create SUID/SGID Wrappers. container-test-run-flakelet-relay> relay # [84902.048703] relay systemd[1]: Starting Rebuild Journal Catalog... container-test-run-flakelet-relay> relay # [84902.049365] relay systemd[1]: Starting Record System Boot/Shutdown in UTMP... container-test-run-flakelet-relay> relay # [84902.061047] relay systemd[1]: Finished Record System Boot/Shutdown in UTMP. container-test-run-flakelet-relay> relay # [84902.074782] relay systemd[1]: Finished Rebuild Journal Catalog. container-test-run-flakelet-relay> relay # [84902.075956] relay systemd[1]: Starting Update is Completed... container-test-run-flakelet-relay> relay # [84902.088539] relay systemd[1]: Finished Update is Completed. container-test-run-flakelet-relay> relay # [84902.088699] relay systemd[1]: Reached target System Initialization. container-test-run-flakelet-relay> relay # [84902.088772] relay systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container container-test-run-flakelet-relay> relay # [84902.088799] relay systemd[1]: Started Daily Cleanup of Temporary Directories. container-test-run-flakelet-relay> relay # [84902.088816] relay systemd[1]: Reached target Timer Units. container-test-run-flakelet-relay> relay # [84902.088938] relay systemd[1]: Listening on D-Bus System Message Bus Socket. container-test-run-flakelet-relay> relay # [84902.089184] relay systemd[1]: Listening on Nix Daemon Socket. container-test-run-flakelet-relay> relay # [84902.089297] relay systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. container-test-run-flakelet-relay> relay # [84902.089313] relay systemd[1]: Reached target Socket Units. container-test-run-flakelet-relay> relay # [84902.089346] relay systemd[1]: Reached target Basic System. container-test-run-flakelet-relay> relay # [84902.090654] relay systemd[1]: Started device-mock.service. container-test-run-flakelet-relay> relay # [84902.091366] relay systemd[1]: Starting Re-link flakelet services at boot... container-test-run-flakelet-relay> relay # [84902.092288] relay systemd[1]: Starting Import lastlog data into lastlog2 database... container-test-run-flakelet-relay> relay # [84902.093306] relay systemd[1]: Starting Name Service Cache Daemon (nsncd)... container-test-run-flakelet-relay> relay # [84902.094079] relay systemd[1]: Starting resolvconf update... container-test-run-flakelet-relay> relay # [84902.104362] relay systemd[1]: Finished Re-link flakelet services at boot. container-test-run-flakelet-relay> relay # [84902.105863] relay systemd[1]: Starting Reconcile flakelet services with the host configuration... container-test-run-flakelet-relay> relay # [84902.113523] relay systemd[1]: Finished Import lastlog data into lastlog2 database. container-test-run-flakelet-relay> relay # [84902.120549] relay systemd[1]: Finished Reconcile flakelet services with the host configuration. container-test-run-flakelet-relay> relay # [84902.173575] relay systemd[1]: Finished resolvconf update. container-test-run-flakelet-relay> relay # [84902.174246] relay systemd[1]: Reached target Preparation for Network. container-test-run-flakelet-relay> relay # [84902.175288] relay systemd[1]: Starting Address configuration of eth1... container-test-run-flakelet-relay> relay # [84902.176618] relay systemd[1]: Starting Extra networking commands.... container-test-run-flakelet-relay> relay # [84902.194547] relay network-addresses-eth1-start[245]: adding address 192.168.1.3/24... done container-test-run-flakelet-relay> relay # [84902.200077] relay network-addresses-eth1-start[245]: adding address 2001:db8:1::3/64... done container-test-run-flakelet-relay> client # [84902.055830] client suid-sgid-wrappers-start[168]: chmod: changing permissions of '/run/wrappers/wrappers.1RMV5ysm0P/chsh': Operation not permitted container-test-run-flakelet-relay> client # [84902.056752] client systemd[1]: Finished Create System Files and Directories. container-test-run-flakelet-relay> client # [84902.081514] client systemd[1]: suid-sgid-wrappers.service: Main process exited, code=exited, status=1/FAILURE container-test-run-flakelet-relay> agent # [84902.052675] agent suid-sgid-wrappers-start[168]: chmod: changing permissions of '/run/wrappers/wrappers.zTLlGtOMN2/chsh': Operation not permitted container-test-run-flakelet-relay> client # [84902.081557] client systemd[1]: suid-sgid-wrappers.service: Failed with result 'exit-code'. container-test-run-flakelet-relay> client # [84902.081605] client systemd[1]: Failed to start Create SUID/SGID Wrappers. container-test-run-flakelet-relay> agent # [84902.058081] agent systemd[1]: suid-sgid-wrappers.service: Main process exited, code=exited, status=1/FAILURE container-test-run-flakelet-relay> client # [84902.084637] client systemd[1]: Starting Rebuild Journal Catalog... container-test-run-flakelet-relay> agent # [84902.058124] agent systemd[1]: suid-sgid-wrappers.service: Failed with result 'exit-code'. container-test-run-flakelet-relay> client # [84902.085345] client systemd[1]: Starting Record System Boot/Shutdown in UTMP... container-test-run-flakelet-relay> client # [84902.097970] client systemd[1]: Finished Record System Boot/Shutdown in UTMP. container-test-run-flakelet-relay> agent # [84902.058176] agent systemd[1]: Failed to start Create SUID/SGID Wrappers. container-test-run-flakelet-relay> client # [84902.106146] client systemd[1]: Finished Rebuild Journal Catalog. container-test-run-flakelet-relay> agent # [84902.058653] agent systemd[1]: Finished Create System Files and Directories. container-test-run-flakelet-relay> client # [84902.107460] client systemd[1]: Starting Update is Completed... container-test-run-flakelet-relay> agent # [84902.085514] agent systemd[1]: Starting Rebuild Journal Catalog... container-test-run-flakelet-relay> client # [84902.116629] client systemd[1]: Finished Update is Completed. container-test-run-flakelet-relay> agent # [84902.086175] agent systemd[1]: Starting Record System Boot/Shutdown in UTMP... container-test-run-flakelet-relay> client # [84902.116726] client systemd[1]: Reached target System Initialization. container-test-run-flakelet-relay> agent # [84902.098560] agent systemd[1]: Finished Record System Boot/Shutdown in UTMP. container-test-run-flakelet-relay> client # [84902.116796] client systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container container-test-run-flakelet-relay> agent # [84902.107016] agent systemd[1]: Finished Rebuild Journal Catalog. container-test-run-flakelet-relay> client # [84902.116825] client systemd[1]: Started Daily Cleanup of Temporary Directories. container-test-run-flakelet-relay> agent # [84902.108189] agent systemd[1]: Starting Update is Completed... container-test-run-flakelet-relay> client # [84902.116844] client systemd[1]: Reached target Timer Units. container-test-run-flakelet-relay> agent # [84902.118610] agent systemd[1]: Finished Update is Completed. container-test-run-flakelet-relay> client # [84902.116957] client systemd[1]: Listening on D-Bus System Message Bus Socket. container-test-run-flakelet-relay> agent # [84902.119379] agent systemd[1]: Reached target System Initialization. container-test-run-flakelet-relay> client # [84902.117158] client systemd[1]: Listening on Nix Daemon Socket. container-test-run-flakelet-relay> agent # [84902.119541] agent systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container container-test-run-flakelet-relay> client # [84902.117272] client systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. container-test-run-flakelet-relay> agent # [84902.119615] agent systemd[1]: Started Daily Cleanup of Temporary Directories. container-test-run-flakelet-relay> client # [84902.117287] client systemd[1]: Reached target Socket Units. container-test-run-flakelet-relay> agent # [84902.119644] agent systemd[1]: Reached target Timer Units. container-test-run-flakelet-relay> client # [84902.117319] client systemd[1]: Reached target Basic System. container-test-run-flakelet-relay> agent # [84902.119863] agent systemd[1]: Listening on D-Bus System Message Bus Socket. container-test-run-flakelet-relay> client # [84902.118718] client systemd[1]: Starting Import lastlog data into lastlog2 database... container-test-run-flakelet-relay> agent # [84902.120135] agent systemd[1]: Listening on Nix Daemon Socket. container-test-run-flakelet-relay> client # [84902.119552] client systemd[1]: Starting Name Service Cache Daemon (nsncd)... container-test-run-flakelet-relay> agent # [84902.120335] agent systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. container-test-run-flakelet-relay> client # [84902.120211] client systemd[1]: Starting resolvconf update... container-test-run-flakelet-relay> agent # [84902.120361] agent systemd[1]: Reached target Socket Units. container-test-run-flakelet-relay> client # [84902.150415] client systemd[1]: Finished Import lastlog data into lastlog2 database. container-test-run-flakelet-relay> agent # [84902.120415] agent systemd[1]: Reached target Basic System. container-test-run-flakelet-relay> client # [84902.220978] client systemd[1]: etc-machine\x2did.mount: Deactivated successfully. container-test-run-flakelet-relay> agent # [84902.122255] agent systemd[1]: Starting Re-link flakelet services at boot... container-test-run-flakelet-relay> client # [84902.221997] client systemd[1]: Finished Save Transient machine-id to Disk. container-test-run-flakelet-relay> agent # [84902.123283] agent systemd[1]: Starting Import lastlog data into lastlog2 database... container-test-run-flakelet-relay> client # [84902.222218] client systemd[1]: nscd.service: Deactivated successfully. container-test-run-flakelet-relay> agent # [84902.124847] agent systemd[1]: Starting Name Service Cache Daemon (nsncd)... container-test-run-flakelet-relay> client # [84902.222370] client systemd[1]: Stopped Name Service Cache Daemon (nsncd). container-test-run-flakelet-relay> agent # [84902.125733] agent systemd[1]: Starting resolvconf update... container-test-run-flakelet-relay> client # [84902.224187] client systemd[1]: Finished resolvconf update. container-test-run-flakelet-relay> agent # [84902.138552] agent systemd[1]: Finished Re-link flakelet services at boot. container-test-run-flakelet-relay> client # [84902.226332] client systemd[1]: Reached target Preparation for Network. container-test-run-flakelet-relay> agent # [84902.141719] agent systemd[1]: Starting Reconcile flakelet services with the host configuration... container-test-run-flakelet-relay> client # [84902.227228] client systemd[1]: Starting Address configuration of eth1... container-test-run-flakelet-relay> agent # [84902.150652] agent systemd[1]: Finished Import lastlog data into lastlog2 database. container-test-run-flakelet-relay> client # [84902.227963] client systemd[1]: Starting Extra networking commands.... container-test-run-flakelet-relay> agent # [84902.155307] agent systemd[1]: Finished Reconcile flakelet services with the host configuration. container-test-run-flakelet-relay> client # [84902.233886] client systemd[1]: Starting Name Service Cache Daemon (nsncd)... container-test-run-flakelet-relay> agent # [84902.220873] agent systemd[1]: etc-machine\x2did.mount: Deactivated successfully. container-test-run-flakelet-relay> agent # [84902.221943] agent systemd[1]: Finished Save Transient machine-id to Disk. container-test-run-flakelet-relay> agent # [84902.222142] agent systemd[1]: nscd.service: Deactivated successfully. container-test-run-flakelet-relay> agent # [84902.222356] agent systemd[1]: Stopped Name Service Cache Daemon (nsncd). container-test-run-flakelet-relay> agent # [84902.224183] agent systemd[1]: Finished resolvconf update. container-test-run-flakelet-relay> agent # [84902.226385] agent systemd[1]: Reached target Preparation for Network. container-test-run-flakelet-relay> agent # [84902.227226] agent systemd[1]: Starting Address configuration of eth1... container-test-run-flakelet-relay> agent # [84902.227922] agent systemd[1]: Starting Extra networking commands.... container-test-run-flakelet-relay> agent # [84902.233888] agent systemd[1]: Starting Name Service Cache Daemon (nsncd)... container-test-run-flakelet-relay> client # [84902.283820] client network-addresses-eth1-start[236]: adding address 192.168.1.2/24... done container-test-run-flakelet-relay> client # [84902.287112] client network-addresses-eth1-start[236]: adding address 2001:db8:1::2/64... done container-test-run-flakelet-relay> client # [84902.290643] client systemd[1]: Finished Address configuration of eth1. container-test-run-flakelet-relay> client # [84902.330937] client systemd[1]: Finished Extra networking commands.. container-test-run-flakelet-relay> client # [84902.331092] client systemd[1]: Reached target Network. container-test-run-flakelet-relay> client # [84902.404299] client systemd[1]: nscd.service: Deactivated successfully. container-test-run-flakelet-relay> client # [84902.404490] client systemd[1]: Stopped Name Service Cache Daemon (nsncd). container-test-run-flakelet-relay> client # [84902.407278] client systemd[1]: Starting Name Service Cache Daemon (nsncd)... container-test-run-flakelet-relay> agent # [84902.286355] agent network-addresses-eth1-start[240]: adding address 192.168.1.1/24... done container-test-run-flakelet-relay> agent # [84902.289375] agent network-addresses-eth1-start[240]: adding address 2001:db8:1::1/64... done container-test-run-flakelet-relay> agent # [84902.294525] agent systemd[1]: Finished Address configuration of eth1. container-test-run-flakelet-relay> agent # [84902.336643] agent systemd[1]: Finished Extra networking commands.. container-test-run-flakelet-relay> agent # [84902.337353] agent systemd[1]: Reached target Network. container-test-run-flakelet-relay> agent # [84902.338585] agent systemd[1]: Starting flakelet-agent... container-test-run-flakelet-relay> agent # [84902.339253] agent systemd[1]: Starting Update flakelet service app... container-test-run-flakelet-relay> agent # [84902.339944] agent systemd[1]: Starting Update flakelet service other... container-test-run-flakelet-relay> agent # [84902.374631] agent systemd[1]: nscd.service: Deactivated successfully. container-test-run-flakelet-relay> agent # [84902.386384] agent systemd[1]: Stopped Name Service Cache Daemon (nsncd). container-test-run-flakelet-relay> agent # [84902.390551] agent systemd[1]: Starting Name Service Cache Daemon (nsncd)... container-test-run-flakelet-relay> agent # [84902.400292] agent flakelet[306]: other: using prebuilt artifact /nix/store/hmr990n0b4v6rpzrcpyyw738r9xi7z95-flakelet-other-v1 container-test-run-flakelet-relay> agent # [84902.401166] agent flakelet[306]: other: activating generation 1 container-test-run-flakelet-relay> agent # [84902.401303] agent flakelet[305]: app: using prebuilt artifact /nix/store/hysydfr96ds875vr0jgw5rjxi4p39h2j-flakelet-app-v1 container-test-run-flakelet-relay> agent # [84902.402021] agent flakelet[305]: app: activating generation 1 container-test-run-flakelet-relay> agent # [84902.409368] agent systemd[1]: Reload requested from client PID 313 ('systemctl') (unit flakelet-other.service)... container-test-run-flakelet-relay> agent # [84902.409461] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [84902.449106] agent flakelet-agent[304]: {"timestamp":"2026-09-06T06:14:23.578226Z","level":"WARN","message":"SRV _flakelet-relay._tcp.test: lookup failed","target":"flakelet_agent"} container-test-run-flakelet-relay> relay # [84902.227301] relay systemd[1]: etc-machine\x2did.mount: Deactivated successfully. container-test-run-flakelet-relay> relay # [84902.228357] relay systemd[1]: Finished Save Transient machine-id to Disk. container-test-run-flakelet-relay> relay # [84902.229012] relay systemd[1]: nscd.service: Deactivated successfully. container-test-run-flakelet-relay> relay # [84902.233919] relay systemd[1]: Stopped Name Service Cache Daemon (nsncd). container-test-run-flakelet-relay> relay # [84902.235598] relay systemd[1]: Finished Address configuration of eth1. container-test-run-flakelet-relay> relay # [84902.266677] relay systemd[1]: Starting Name Service Cache Daemon (nsncd)... container-test-run-flakelet-relay> relay # [84902.266930] relay systemd[1]: Finished Extra networking commands.. container-test-run-flakelet-relay> relay # [84902.267848] relay systemd[1]: Reached target Network. container-test-run-flakelet-relay> relay # [84902.269142] relay systemd[1]: Starting Dnsmasq Daemon... container-test-run-flakelet-relay> relay # [84902.270157] relay systemd[1]: Starting Nginx Web Server... container-test-run-flakelet-relay> relay # [84902.271043] relay systemd[1]: Starting Update flakelet service flakelet-relay... container-test-run-flakelet-relay> relay # [84902.282852] relay flakelet[310]: flakelet-relay: using prebuilt artifact /nix/store/7zwd9c7nvf99s9x33v3aakwppzscxpbq-flakelet-flakelet-relay container-test-run-flakelet-relay> relay # [84902.283579] relay flakelet[310]: flakelet-relay: activating generation 1 container-test-run-flakelet-relay> relay # [84902.287514] relay systemd[1]: Reload requested from client PID 314 ('systemctl') (unit flakelet-flakelet-relay.service)... container-test-run-flakelet-relay> relay # [84902.287563] relay systemd[1]: Reloading... container-test-run-flakelet-relay> relay # [84902.720396] relay nsncd[298]: Sep 06 06:14:23.849 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" container-test-run-flakelet-relay> relay # [84902.811931] relay systemd[1]: Reloading finished in 523 ms. container-test-run-flakelet-relay> relay # [84902.948058] relay systemd[1]: Started Name Service Cache Daemon (nsncd). container-test-run-flakelet-relay> relay # [84902.950456] relay systemd[1]: Reached target Host and Network Name Lookups. container-test-run-flakelet-relay> relay # [84902.950674] relay systemd[1]: Reached target User and Group Name Lookups. container-test-run-flakelet-relay> relay # [84902.953397] relay systemd[1]: Starting User Login Management... container-test-run-flakelet-relay> relay # [84902.954213] relay systemd[1]: Starting Permit User Sessions... container-test-run-flakelet-relay> relay # [84902.967861] relay systemd[1]: Reload requested from client PID 357 ('systemctl') (unit flakelet-flakelet-relay.service)... container-test-run-flakelet-relay> relay # [84902.967948] relay systemd[1]: Reloading... container-test-run-flakelet-relay> client # [84902.684079] client nsncd[303]: Sep 06 06:14:23.813 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" container-test-run-flakelet-relay> client # [84902.684043] client systemd[1]: Started Name Service Cache Daemon (nsncd). container-test-run-flakelet-relay> client # [84902.684126] client systemd[1]: Reached target Host and Network Name Lookups. container-test-run-flakelet-relay> client # [84902.684185] client systemd[1]: Reached target User and Group Name Lookups. container-test-run-flakelet-relay> client # [84902.686019] client systemd[1]: Starting User Login Management... container-test-run-flakelet-relay> client # [84902.686900] client systemd[1]: Starting Permit User Sessions... container-test-run-flakelet-relay> client # [84902.720484] client systemd[1]: Finished Permit User Sessions. container-test-run-flakelet-relay> client # [84902.722013] client systemd[1]: Started Console Getty. container-test-run-flakelet-relay> client # [84902.722043] client systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0 container-test-run-flakelet-relay> client # [84902.722060] client systemd[1]: Reached target Login Prompts. container-test-run-flakelet-relay> agent # [84902.712337] agent nsncd[311]: Sep 06 06:14:23.841 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" container-test-run-flakelet-relay> agent # [84902.818296] agent systemd[1]: Reloading finished in 407 ms. container-test-run-flakelet-relay> agent # [84902.952072] agent systemd[1]: Started flakelet-agent. container-test-run-flakelet-relay> agent # [84902.952233] agent systemd[1]: Started Name Service Cache Daemon (nsncd). container-test-run-flakelet-relay> agent # [84902.953895] agent systemd[1]: Reload requested from client PID 314 ('systemctl') (unit flakelet-app.service)... container-test-run-flakelet-relay> agent # [84902.953947] agent systemd[1]: Reloading... container-test-run-flakelet-relay> relay # [84902.972095] relay dnsmasq-pre-start[360]: dnsmasq: syntax check OK. container-test-run-flakelet-relay> relay # [84903.347541] relay systemd[1]: Reloading finished in 378 ms. container-test-run-flakelet-relay> agent # [84903.352784] agent systemd[1]: Reloading finished in 398 ms. container-test-run-flakelet-relay> agent # [84903.529252] agent systemd[1]: Reached target Host and Network Name Lookups. container-test-run-flakelet-relay> agent # [84903.529367] agent systemd[1]: Reached target User and Group Name Lookups. container-test-run-flakelet-relay> agent # [84903.530835] agent systemd[1]: Starting User Login Management... container-test-run-flakelet-relay> agent # [84903.531910] agent systemd[1]: Starting Permit User Sessions... container-test-run-flakelet-relay> agent # [84903.540986] agent systemd[1]: Reload requested from client PID 764 ('systemctl') (unit flakelet-other.service)... container-test-run-flakelet-relay> agent # [84903.541088] agent systemd[1]: Reloading... container-test-run-flakelet-relay> relay # [84903.528703] relay systemd[1]: Finished Permit User Sessions. container-test-run-flakelet-relay> relay # [84903.537690] relay systemd[1]: Started Console Getty. container-test-run-flakelet-relay> relay # [84903.537809] relay systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0 container-test-run-flakelet-relay> relay # [84903.537941] relay systemd[1]: Reached target Login Prompts. container-test-run-flakelet-relay> relay # [84903.540571] relay systemd[1]: Starting flakelet-relay... container-test-run-flakelet-relay> agent # [84903.929112] agent systemd[1]: Reloading finished in 387 ms. container-test-run-flakelet-relay> agent # [84903.997466] agent systemd[1]: Finished Permit User Sessions. container-test-run-flakelet-relay> agent # [84904.001989] agent systemd[1]: Started Console Getty. container-test-run-flakelet-relay> agent # [84904.002107] agent systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0 container-test-run-flakelet-relay> agent # [84904.002249] agent systemd[1]: Reached target Login Prompts. container-test-run-flakelet-relay> agent # [84904.002611] agent systemd[1]: Reload requested from client PID 767 ('systemctl') (unit flakelet-app.service)... container-test-run-flakelet-relay> agent # [84904.002639] agent systemd[1]: Reloading... container-test-run-flakelet-relay> relay # [84904.041258] relay nginx-pre-start[402]: nginx: the configuration file /nix/store/01avjj26wcihp926xgasszcj5s0k7cbs-nginx.conf syntax is ok container-test-run-flakelet-relay> relay # [84904.041940] relay nginx-pre-start[402]: nginx: configuration file /nix/store/01avjj26wcihp926xgasszcj5s0k7cbs-nginx.conf test is successful container-test-run-flakelet-relay> relay # [84904.056658] relay systemd[1]: Started Nginx Web Server. container-test-run-flakelet-relay> relay # [84904.217663] relay systemd[1]: Starting D-Bus System Message Bus... container-test-run-flakelet-relay> client # [84904.182866] client systemd-logind[315]: New seat seat0. container-test-run-flakelet-relay> client # [84904.183222] client systemd[1]: Started User Login Management. container-test-run-flakelet-relay> client # [84904.184804] client systemd[1]: Starting D-Bus System Message Bus... container-test-run-flakelet-relay> client # [84904.193291] client systemd[1]: Starting linger-users.service... container-test-run-flakelet-relay> client # [84904.227518] client systemd[1]: linger-users.service: Deactivated successfully. container-test-run-flakelet-relay> client # [84904.227814] client systemd[1]: Finished linger-users.service. container-test-run-flakelet-relay> client # [84904.228039] client systemd[1]: Reached target Multi-User System. container-test-run-flakelet-relay> agent # [84904.409122] agent systemd[1]: Reloading finished in 406 ms. container-test-run-flakelet-relay> agent # [84904.487084] agent systemd[1]: Starting other v1... container-test-run-flakelet-relay> agent # [84904.521360] agent systemd[1]: Starting app v1... container-test-run-flakelet-relay> agent # [84904.525247] agent systemd[1]: Started other v1. container-test-run-flakelet-relay> agent # [84904.531580] agent systemd[1]: Started app v1. container-test-run-flakelet-relay> agent # [84904.554734] agent flakelet[306]: other: updated to generation 1 container-test-run-flakelet-relay> agent # [84904.555780] agent systemd[1]: Finished Update flakelet service other. container-test-run-flakelet-relay> agent # [84904.559697] agent flakelet[305]: app: updated to generation 1 container-test-run-flakelet-relay> agent # [84904.561264] agent systemd[1]: Finished Update flakelet service app. container-test-run-flakelet-relay> agent # [84904.561403] agent systemd[1]: Reached target flakelet managed services. container-test-run-flakelet-relay> relay # [84904.514957] relay systemd-logind[354]: New seat seat0. container-test-run-flakelet-relay> relay # [84904.515210] relay systemd[1]: Started User Login Management. container-test-run-flakelet-relay> relay # [84904.521537] relay systemd[1]: Starting linger-users.service... container-test-run-flakelet-relay> relay # [84904.534674] relay systemd[1]: linger-users.service: Deactivated successfully. container-test-run-flakelet-relay> relay # [84904.534943] relay systemd[1]: Finished linger-users.service. container-test-run-flakelet-relay> relay # [84904.745565] relay dbus-broker-launch[408]: Looking up NSS user entry for 'systemd-timesync'... container-test-run-flakelet-relay> relay # [84904.746680] relay dbus-broker-launch[408]: NSS returned no entry for 'systemd-timesync' container-test-run-flakelet-relay> relay # [84904.746680] 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 # [84904.747560] relay systemd[1]: Started D-Bus System Message Bus. container-test-run-flakelet-relay> relay # [84904.757620] relay dbus-broker-launch[408]: Ready container-test-run-flakelet-relay> relay # [84904.761789] relay systemd[1]: Started Dnsmasq Daemon. container-test-run-flakelet-relay> relay # [84904.763723] relay dnsmasq[395]: started, version 2.93 cachesize 150 container-test-run-flakelet-relay> relay # [84904.763731] relay dnsmasq[395]: 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 # [84904.763736] relay dnsmasq[395]: DBus support enabled: connected to system bus container-test-run-flakelet-relay> relay # [84904.763744] relay dnsmasq[395]: no upstream servers configured - please set them from DBus container-test-run-flakelet-relay> relay # [84904.763829] relay dnsmasq[395]: read /etc/hosts - 9 names container-test-run-flakelet-relay> client # [84904.763765] client dbus-broker-launch[325]: Looking up NSS user entry for 'systemd-timesync'... container-test-run-flakelet-relay> client # [84904.765057] client dbus-broker-launch[325]: NSS returned no entry for 'systemd-timesync' container-test-run-flakelet-relay> client # [84904.765057] client dbus-broker-launch[325]: Invalid user-name in /nix/store/bja873g16bkdjc9pijmr630xwdjl32m3-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" container-test-run-flakelet-relay> client # [84904.766271] client systemd[1]: Started D-Bus System Message Bus. container-test-run-flakelet-relay> client # [84904.766783] client systemd[1]: Startup finished in 4.258s. container-test-run-flakelet-relay> client # [84904.772207] client dbus-broker-launch[325]: Ready container-test-run-flakelet-relay> agent # [84905.032411] agent systemd-logind[765]: New seat seat0. container-test-run-flakelet-relay> agent # [84905.032888] agent systemd[1]: Started User Login Management. container-test-run-flakelet-relay> agent # [84905.034447] agent systemd[1]: Starting D-Bus System Message Bus... container-test-run-flakelet-relay> agent # [84905.035538] agent systemd[1]: Starting linger-users.service... container-test-run-flakelet-relay> agent # [84905.078140] agent systemd[1]: linger-users.service: Deactivated successfully. container-test-run-flakelet-relay> agent # [84905.078584] agent systemd[1]: Finished linger-users.service. container-test-run-flakelet-relay> agent # [84905.079978] agent systemd[1]: Reached target Multi-User System. container-test-run-flakelet-relay> relay # [84905.575521] relay flakelet-relay[400]: {"timestamp":"2026-09-06T06:14:26.704654Z","level":"INFO","message":"listening on 127.0.0.1:7400","target":"flakelet_relay::relay::server"} container-test-run-flakelet-relay> relay # [84905.576149] relay flakelet-relay[400]: {"timestamp":"2026-09-06T06:14:26.705138Z","level":"INFO","message":"listening on [::]:7443","target":"flakelet_relay::relay::server"} container-test-run-flakelet-relay> relay # [84905.576078] relay systemd[1]: Started flakelet-relay. container-test-run-flakelet-relay> relay # [84905.594666] relay systemd[1]: Starting health for flakelet-relay... container-test-run-flakelet-relay> relay # [84905.826519] relay systemd[1]: flakelet-relay-health.service: Deactivated successfully. container-test-run-flakelet-relay> agent # [84905.456809] agent dbus-broker-launch[841]: Looking up NSS user entry for 'systemd-timesync'... container-test-run-flakelet-relay> agent # [84905.458515] agent dbus-broker-launch[841]: NSS returned no entry for 'systemd-timesync' container-test-run-flakelet-relay> agent # [84905.458515] agent dbus-broker-launch[841]: 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 # [84905.459262] agent systemd[1]: Started D-Bus System Message Bus. container-test-run-flakelet-relay> agent # [84905.459834] agent systemd[1]: Startup finished in 4.949s. container-test-run-flakelet-relay> agent # [84905.464541] agent dbus-broker-launch[841]: 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.01 seconds) container-test-run-flakelet-relay> agent: waiting for unit flakelet.target container-test-run-flakelet-relay> agent: (finished: waiting for unit flakelet.target, in 0.02 seconds) container-test-run-flakelet-relay> agent: waiting for unit flakelet-agent.service container-test-run-flakelet-relay> agent: (finished: waiting for unit flakelet-agent.service, in 0.02 seconds) container-test-run-flakelet-relay> client: waiting for unit multi-user.target container-test-run-flakelet-relay> client: (finished: waiting for unit multi-user.target, in 0.01 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 # [84905.827129] relay systemd[1]: Finished health for flakelet-relay. container-test-run-flakelet-relay> relay # [84905.875140] relay flakelet[310]: flakelet-relay: updated to generation 1 container-test-run-flakelet-relay> relay # [84905.876250] relay systemd[1]: Finished Update flakelet service flakelet-relay. container-test-run-flakelet-relay> relay # [84905.878209] relay systemd[1]: Reached target flakelet managed services. container-test-run-flakelet-relay> relay # [84905.878571] relay systemd[1]: Reached target Multi-User System. container-test-run-flakelet-relay> relay # [84905.878876] relay systemd[1]: Startup finished in 5.370s. container-test-run-flakelet-relay> agent # [84907.558538] agent flakelet-agent[304]: {"timestamp":"2026-09-06T06:14:28.687684Z","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 # [84907.558796] relay flakelet-relay[400]: {"timestamp":"2026-09-06T06:14:28.687968Z","level":"INFO","message":"agent connected","host":"agent","version":"0.1.0","flakelets":"[Named { name: \"app\", generation: Some(1), revision: Some(\"prebuilt:app\") }, Named { name: \"other\", generation: Some(1), revision: Some(\"prebuilt:other\") }]","target":"flakelet_relay::relay::agent_conn"} container-test-run-flakelet-relay> relay: (finished: waiting for success: curl -sf http://127.0.0.1:7400/metrics | grep -q 'agent_up{host="agent"} 1', in 2.05 seconds) container-test-run-flakelet-relay> agent: must succeed: systemctl is-active app.service other.service container-test-run-flakelet-relay> agent: (finished: must succeed: systemctl is-active app.service other.service, in 0.01 seconds) container-test-run-flakelet-relay> subtest: deploy with client certificate, relay found via SRV container-test-run-flakelet-relay> agent: must succeed: jq '.services.app.prebuilt="/nix/store/6ili1vxjlag9d7dy0bxrbxk6bpi5qh9p-flakelet-app-v2"' /etc/flakelet/config.json > /tmp/config.json && cp --remove-destination /tmp/config.json /etc/flakelet/config.json container-test-run-flakelet-relay> agent: (finished: must succeed: jq '.services.app.prebuilt="/nix/store/6ili1vxjlag9d7dy0bxrbxk6bpi5qh9p-flakelet-app-v2"' /etc/flakelet/config.json > /tmp/config.json && cp --remove-destination /tmp/config.json /etc/flakelet/config.json, in 0.01 seconds) container-test-run-flakelet-relay> client: must succeed: push --relay-srv test --cert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin.pem --key /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin-key.pem deploy agent/app 2>&1 container-test-run-flakelet-relay> agent # [84908.284278] agent flakelet-agent[304]: {"timestamp":"2026-09-06T06:14:29.413504Z","level":"INFO","message":"start","id":"dd05e7f5f3c9b9817dd220b61bba6d04","flakelet":"app","rule":"admins","relay":"relay","caller":"Some(\"x509:email:admin@example.org\")","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> agent # [84908.285090] agent flakelet-agent[304]: {"timestamp":"2026-09-06T06:14:29.414362Z","level":"INFO","message":"update starting","flakelet":"app","ids":"[\"dd05e7f5f3c9b9817dd220b61bba6d04\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [84908.341448] agent systemd[1]: Starting flakelet update app (via relay)... container-test-run-flakelet-relay> agent # [84908.373774] agent systemd[1]: Started flakelet update app (via relay). container-test-run-flakelet-relay> agent # [84908.379608] agent flakelet[870]: app: using prebuilt artifact /nix/store/6ili1vxjlag9d7dy0bxrbxk6bpi5qh9p-flakelet-app-v2 container-test-run-flakelet-relay> agent # [84908.380290] agent flakelet[870]: app: activating generation 2 container-test-run-flakelet-relay> agent # [84908.394238] agent systemd[1]: Stopping app v1... container-test-run-flakelet-relay> agent # [84908.394760] agent systemd[1]: app.service: Deactivated successfully. container-test-run-flakelet-relay> agent # [84908.395137] agent systemd[1]: Stopped app v1. container-test-run-flakelet-relay> agent # [84908.415097] agent systemd[1]: Reload requested from client PID 877 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [84908.415189] agent systemd[1]: Reloading... container-test-run-flakelet-relay> relay # [84908.283337] relay flakelet-relay[400]: {"timestamp":"2026-09-06T06:14:29.412557Z","level":"INFO","message":"deploy accepted","job":"7f630172135f1077f4a87a31e7dfb9d9","caller":"x509:email:admin@example.org","targets":"[\"agent/app\"]","target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> agent # [84908.777258] agent systemd[1]: Reloading finished in 361 ms. container-test-run-flakelet-relay> agent # [84908.860331] agent systemd[1]: Reload requested from client PID 907 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [84908.860380] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [84909.203307] agent systemd[1]: Reloading finished in 342 ms. container-test-run-flakelet-relay> agent # [84909.286851] agent systemd[1]: Reload requested from client PID 936 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [84909.286893] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [84909.642521] agent systemd[1]: Reloading finished in 355 ms. container-test-run-flakelet-relay> agent # [84909.727686] agent systemd[1]: Reload requested from client PID 967 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [84909.727774] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [84910.072244] agent systemd[1]: Reloading finished in 343 ms. container-test-run-flakelet-relay> agent # [84910.158246] agent systemd[1]: Starting app v2... container-test-run-flakelet-relay> agent # [84910.199686] agent systemd[1]: Started app v2. container-test-run-flakelet-relay> agent # [84910.221633] agent flakelet[870]: app: updated to generation 2 container-test-run-flakelet-relay> agent # [84910.224967] 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 3.02 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.01 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.06 seconds) container-test-run-flakelet-relay> subtest: deploy with OIDC token, sub principal container-test-run-flakelet-relay> agent: must succeed: jq '.services.app.prebuilt="/nix/store/hysydfr96ds875vr0jgw5rjxi4p39h2j-flakelet-app-v1"' /etc/flakelet/config.json > /tmp/config.json && cp --remove-destination /tmp/config.json /etc/flakelet/config.json container-test-run-flakelet-relay> agent: (finished: must succeed: jq '.services.app.prebuilt="/nix/store/hysydfr96ds875vr0jgw5rjxi4p39h2j-flakelet-app-v1"' /etc/flakelet/config.json > /tmp/config.json && cp --remove-destination /tmp/config.json /etc/flakelet/config.json, in 0.01 seconds) container-test-run-flakelet-relay> client: must succeed: echo '{"groups": []}' | step crypto jwt sign --key /nix/store/r7sclamg09v434w8zjlfnrcrn1w79wh6-flakelet-relay-test-issuer/jwk.json --iss https://relay:8443 --aud flakelet-relay --sub 'repo:github:example/app:ref:refs/heads/main' --exp $(( $(date +%s) + 300 )) --jti $(head -c8 /dev/urandom | base32) container-test-run-flakelet-relay> client: (finished: must succeed: echo '{"groups": []}' | step crypto jwt sign --key /nix/store/r7sclamg09v434w8zjlfnrcrn1w79wh6-flakelet-relay-test-issuer/jwk.json --iss https://relay:8443 --aud flakelet-relay --sub 'repo:github:example/app:ref:refs/heads/main' --exp $(( $(date +%s) + 300 )) --jti $(head -c8 /dev/urandom | base32), in 0.05 seconds) container-test-run-flakelet-relay> client: must succeed: FLAKELET_RELAY_TOKEN_COMMAND='echo eyJhbGciOiJFZERTQSIsImtpZCI6ImsxIiwidHlwIjoiSldUIn0.eyJhdWQiOiJmbGFrZWxldC1yZWxheSIsImV4cCI6MTc4ODY3NTU3MiwiZ3JvdXBzIjpbXSwiaWF0IjoxNzg4Njc1MjcyLCJpc3MiOiJodHRwczovL3JlbGF5Ojg0NDMiLCJqdGkiOiJTSUxFNllPN1A0VlBTPT09IiwibmJmIjoxNzg4Njc1MjcyLCJzdWIiOiJyZXBvOmdpdGh1YjpleGFtcGxlL2FwcDpyZWY6cmVmcy9oZWFkcy9tYWluIn0.HQuGntogsht3ERYbnBfLpdawDAKI5Bx7OiIn1ar3dHvgnYHILW_b5E9oz55vpuk2YVIcA5xurXIyqnkZSjQWDw' push --relay https://relay:7443 deploy agent/app 2>&1 container-test-run-flakelet-relay> agent # [84911.280814] agent flakelet-agent[304]: {"timestamp":"2026-09-06T06:14:32.410030Z","level":"INFO","message":"update finished","flakelet":"app","status":"Updated","generation":"Some(2)","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [84911.410247] agent flakelet-agent[304]: {"timestamp":"2026-09-06T06:14:32.539439Z","level":"INFO","message":"start","id":"f600d91a22cf57f15cb4e5781b592650","flakelet":"app","rule":"ci","relay":"relay","caller":"Some(\"oidc:mock:repo:github:example/app:ref:refs/heads/main\")","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> agent # [84911.410771] agent flakelet-agent[304]: {"timestamp":"2026-09-06T06:14:32.540065Z","level":"INFO","message":"update starting","flakelet":"app","ids":"[\"f600d91a22cf57f15cb4e5781b592650\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [84911.468051] agent systemd[1]: Starting flakelet update app (via relay)... container-test-run-flakelet-relay> agent # [84911.504525] agent systemd[1]: Started flakelet update app (via relay). container-test-run-flakelet-relay> agent # [84911.508834] agent flakelet[1025]: app: using prebuilt artifact /nix/store/hysydfr96ds875vr0jgw5rjxi4p39h2j-flakelet-app-v1 container-test-run-flakelet-relay> agent # [84911.509560] agent flakelet[1025]: app: activating generation 3 container-test-run-flakelet-relay> agent # [84911.527012] agent systemd[1]: Stopping app v2... container-test-run-flakelet-relay> agent # [84911.527289] agent systemd[1]: app.service: Deactivated successfully. container-test-run-flakelet-relay> agent # [84911.527496] agent systemd[1]: Stopped app v2. container-test-run-flakelet-relay> agent # [84911.542319] agent systemd[1]: Reload requested from client PID 1032 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [84911.542409] agent systemd[1]: Reloading... container-test-run-flakelet-relay> relay # [84911.281835] relay flakelet-relay[400]: {"timestamp":"2026-09-06T06:14:32.411036Z","level":"INFO","message":"deploy finished","job":"7f630172135f1077f4a87a31e7dfb9d9","ok":true,"target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> relay # [84911.409697] relay flakelet-relay[400]: {"timestamp":"2026-09-06T06:14:32.538953Z","level":"INFO","message":"deploy accepted","job":"79a6eb067ad5df00df61f597ee17baba","caller":"oidc:mock:repo:github:example/app:ref:refs/heads/main","targets":"[\"agent/app\"]","target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> agent # [84911.902999] agent systemd[1]: Reloading finished in 359 ms. container-test-run-flakelet-relay> agent # [84911.987962] agent systemd[1]: Reload requested from client PID 1062 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [84911.988033] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [84912.331060] agent systemd[1]: Reloading finished in 342 ms. container-test-run-flakelet-relay> agent # [84912.423041] agent systemd[1]: Reload requested from client PID 1091 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [84912.423100] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [84912.764568] agent systemd[1]: Reloading finished in 340 ms. container-test-run-flakelet-relay> agent # [84912.854114] agent systemd[1]: Reload requested from client PID 1122 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [84912.854165] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [84913.202480] agent systemd[1]: Reloading finished in 347 ms. container-test-run-flakelet-relay> agent # [84913.290951] agent systemd[1]: Starting app v1... container-test-run-flakelet-relay> agent # [84913.325353] agent systemd[1]: Started app v1. container-test-run-flakelet-relay> agent # [84913.351161] agent flakelet[1025]: app: updated to generation 3 container-test-run-flakelet-relay> agent # [84913.354581] 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.eyJhdWQiOiJmbGFrZWxldC1yZWxheSIsImV4cCI6MTc4ODY3NTU3MiwiZ3JvdXBzIjpbXSwiaWF0IjoxNzg4Njc1MjcyLCJpc3MiOiJodHRwczovL3JlbGF5Ojg0NDMiLCJqdGkiOiJTSUxFNllPN1A0VlBTPT09IiwibmJmIjoxNzg4Njc1MjcyLCJzdWIiOiJyZXBvOmdpdGh1YjpleGFtcGxlL2FwcDpyZWY6cmVmcy9oZWFkcy9tYWluIn0.HQuGntogsht3ERYbnBfLpdawDAKI5Bx7OiIn1ar3dHvgnYHILW_b5E9oz55vpuk2YVIcA5xurXIyqnkZSjQWDw' push --relay https://relay:7443 deploy agent/app 2>&1, in 3.04 seconds) container-test-run-flakelet-relay> (finished: subtest: deploy with OIDC token, sub principal, in 3.11 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.eyJhdWQiOiJmbGFrZWxldC1yZWxheSIsImV4cCI6MTc4ODY3NTU3MiwiZ3JvdXBzIjpbXSwiaWF0IjoxNzg4Njc1MjcyLCJpc3MiOiJodHRwczovL3JlbGF5Ojg0NDMiLCJqdGkiOiJTSUxFNllPN1A0VlBTPT09IiwibmJmIjoxNzg4Njc1MjcyLCJzdWIiOiJyZXBvOmdpdGh1YjpleGFtcGxlL2FwcDpyZWY6cmVmcy9oZWFkcy9tYWluIn0.HQuGntogsht3ERYbnBfLpdawDAKI5Bx7OiIn1ar3dHvgnYHILW_b5E9oz55vpuk2YVIcA5xurXIyqnkZSjQWDw' 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.eyJhdWQiOiJmbGFrZWxldC1yZWxheSIsImV4cCI6MTc4ODY3NTU3MiwiZ3JvdXBzIjpbXSwiaWF0IjoxNzg4Njc1MjcyLCJpc3MiOiJodHRwczovL3JlbGF5Ojg0NDMiLCJqdGkiOiJTSUxFNllPN1A0VlBTPT09IiwibmJmIjoxNzg4Njc1MjcyLCJzdWIiOiJyZXBvOmdpdGh1YjpleGFtcGxlL2FwcDpyZWY6cmVmcy9oZWFkcy9tYWluIn0.HQuGntogsht3ERYbnBfLpdawDAKI5Bx7OiIn1ar3dHvgnYHILW_b5E9oz55vpuk2YVIcA5xurXIyqnkZSjQWDw' push --relay https://relay:7443 deploy agent/other 2>&1, in 0.02 seconds) container-test-run-flakelet-relay> (finished: subtest: policy denies uncovered target, in 0.02 seconds) container-test-run-flakelet-relay> subtest: claim principal from groups, unchanged counts as success container-test-run-flakelet-relay> client: must succeed: echo '{"groups": ["wheel"]}' | step crypto jwt sign --key /nix/store/r7sclamg09v434w8zjlfnrcrn1w79wh6-flakelet-relay-test-issuer/jwk.json --iss https://relay:8443 --aud flakelet-relay --sub 'someone' --exp $(( $(date +%s) + 300 )) --jti $(head -c8 /dev/urandom | base32) container-test-run-flakelet-relay> client: (finished: must succeed: echo '{"groups": ["wheel"]}' | step crypto jwt sign --key /nix/store/r7sclamg09v434w8zjlfnrcrn1w79wh6-flakelet-relay-test-issuer/jwk.json --iss https://relay:8443 --aud flakelet-relay --sub 'someone' --exp $(( $(date +%s) + 300 )) --jti $(head -c8 /dev/urandom | base32), in 0.05 seconds) container-test-run-flakelet-relay> client: must succeed: FLAKELET_RELAY_TOKEN_COMMAND='echo eyJhbGciOiJFZERTQSIsImtpZCI6ImsxIiwidHlwIjoiSldUIn0.eyJhdWQiOiJmbGFrZWxldC1yZWxheSIsImV4cCI6MTc4ODY3NTU3NSwiZ3JvdXBzIjpbIndoZWVsIl0sImlhdCI6MTc4ODY3NTI3NSwiaXNzIjoiaHR0cHM6Ly9yZWxheTo4NDQzIiwianRpIjoiMzNIVENLRk5LVk5CMj09PSIsIm5iZiI6MTc4ODY3NTI3NSwic3ViIjoic29tZW9uZSJ9.XdcDm7E1XcvU6CnM0p2QN7ka7a67eiNkwDRcs5HJ4GTnccSYMeNWudMD-F7iq5mPIWffpHWXyXtSpm6yI99XCA' push --relay https://relay:7443 deploy agent/other 2>&1 container-test-run-flakelet-relay> relay # [84914.415425] relay flakelet-relay[400]: {"timestamp":"2026-09-06T06:14:35.544639Z","level":"INFO","message":"deploy finished","job":"79a6eb067ad5df00df61f597ee17baba","ok":true,"target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> relay # [84914.509882] relay flakelet-relay[400]: {"timestamp":"2026-09-06T06:14:35.639095Z","level":"INFO","message":"deploy accepted","job":"1e20c51c4f0451f1c80a79960289ba28","caller":"oidc:mock:groups:wheel\noidc:mock:someone","targets":"[\"agent/other\"]","target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> agent # [84914.414552] agent flakelet-agent[304]: {"timestamp":"2026-09-06T06:14:35.543764Z","level":"INFO","message":"update finished","flakelet":"app","status":"Updated","generation":"Some(3)","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [84914.510320] agent flakelet-agent[304]: {"timestamp":"2026-09-06T06:14:35.639522Z","level":"INFO","message":"start","id":"116f713ffd806c1abf7467b4e7bca3b5","flakelet":"other","rule":"admins","relay":"relay","caller":"Some(\"oidc:mock:groups:wheel\\noidc:mock:someone\")","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> agent # [84914.510701] agent flakelet-agent[304]: {"timestamp":"2026-09-06T06:14:35.639953Z","level":"INFO","message":"update starting","flakelet":"other","ids":"[\"116f713ffd806c1abf7467b4e7bca3b5\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [84914.569385] agent systemd[1]: Starting flakelet update other (via relay)... container-test-run-flakelet-relay> agent # [84914.618407] agent systemd[1]: Started flakelet update other (via relay). container-test-run-flakelet-relay> agent # [84914.623511] agent flakelet[1171]: other: using prebuilt artifact /nix/store/hmr990n0b4v6rpzrcpyyw738r9xi7z95-flakelet-other-v1 container-test-run-flakelet-relay> agent # [84914.624365] agent flakelet[1171]: other: up to date container-test-run-flakelet-relay> agent # [84914.626855] agent systemd[1]: flakelet-relay-job-other.service: Deactivated successfully. container-test-run-flakelet-relay> client: (finished: must succeed: FLAKELET_RELAY_TOKEN_COMMAND='echo eyJhbGciOiJFZERTQSIsImtpZCI6ImsxIiwidHlwIjoiSldUIn0.eyJhdWQiOiJmbGFrZWxldC1yZWxheSIsImV4cCI6MTc4ODY3NTU3NSwiZ3JvdXBzIjpbIndoZWVsIl0sImlhdCI6MTc4ODY3NTI3NSwiaXNzIjoiaHR0cHM6Ly9yZWxheTo4NDQzIiwianRpIjoiMzNIVENLRk5LVk5CMj09PSIsIm5iZiI6MTc4ODY3NTI3NSwic3ViIjoic29tZW9uZSJ9.XdcDm7E1XcvU6CnM0p2QN7ka7a67eiNkwDRcs5HJ4GTnccSYMeNWudMD-F7iq5mPIWffpHWXyXtSpm6yI99XCA' push --relay https://relay:7443 deploy agent/other 2>&1, in 0.69 seconds) container-test-run-flakelet-relay> (finished: subtest: claim principal from groups, unchanged counts as success, in 0.74 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> relay # [84915.174959] relay flakelet-relay[400]: {"timestamp":"2026-09-06T06:14:36.304215Z","level":"INFO","message":"deploy finished","job":"1e20c51c4f0451f1c80a79960289ba28","ok":true,"target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> relay # [84915.200262] relay 93sn3sv24fj26ksccpppzwg6kbf10s4w-device-mock[185]: 127.0.0.1 - - [06/Sep/2026 06:14:36] "POST /device HTTP/1.1" 200 - container-test-run-flakelet-relay> agent # [84915.174276] agent flakelet-agent[304]: {"timestamp":"2026-09-06T06:14:36.303492Z","level":"INFO","message":"update finished","flakelet":"other","status":"Unchanged","generation":"Some(1)","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> relay # [84916.205627] relay 93sn3sv24fj26ksccpppzwg6kbf10s4w-device-mock[185]: 127.0.0.1 - - [06/Sep/2026 06:14:37] "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 # [84917.278727] agent flakelet-agent[304]: {"timestamp":"2026-09-06T06:14:38.407937Z","level":"INFO","message":"start","id":"14457d368d0786df7ad554cb3c34c093","flakelet":"other","rule":"admins","relay":"relay","caller":"Some(\"oidc:mock:groups:wheel\\noidc:mock:someone\")","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> agent # [84917.279711] agent flakelet-agent[304]: {"timestamp":"2026-09-06T06:14:38.408518Z","level":"INFO","message":"update starting","flakelet":"other","ids":"[\"14457d368d0786df7ad554cb3c34c093\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [84917.312882] agent systemd[1]: Starting flakelet update other (via relay)... container-test-run-flakelet-relay> agent # [84917.346950] agent systemd[1]: Started flakelet update other (via relay). container-test-run-flakelet-relay> agent # [84917.352427] agent flakelet[1189]: other: using prebuilt artifact /nix/store/hmr990n0b4v6rpzrcpyyw738r9xi7z95-flakelet-other-v1 container-test-run-flakelet-relay> agent # [84917.352971] agent flakelet[1189]: other: up to date container-test-run-flakelet-relay> agent # [84917.355240] agent systemd[1]: flakelet-relay-job-other.service: Deactivated successfully. container-test-run-flakelet-relay> relay # [84917.251357] relay 93sn3sv24fj26ksccpppzwg6kbf10s4w-device-mock[185]: 127.0.0.1 - - [06/Sep/2026 06:14:38] "POST /token HTTP/1.1" 200 - container-test-run-flakelet-relay> relay # [84917.278263] relay flakelet-relay[400]: {"timestamp":"2026-09-06T06:14:38.407520Z","level":"INFO","message":"deploy accepted","job":"25218d3e6135daf23480ff25eb424e8b","caller":"oidc:mock:groups:wheel\noidc:mock:someone","targets":"[\"agent/other\"]","target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> client: (finished: must succeed: push --relay https://relay:7443 deploy agent/other 2>&1, in 0.64 seconds) container-test-run-flakelet-relay> client: must succeed: push --relay https://relay:7443 deploy agent/other 2>&1 container-test-run-flakelet-relay> relay # [84917.900836] relay flakelet-relay[400]: {"timestamp":"2026-09-06T06:14:39.030092Z","level":"INFO","message":"deploy finished","job":"25218d3e6135daf23480ff25eb424e8b","ok":true,"target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> relay # [84917.919255] relay flakelet-relay[400]: {"timestamp":"2026-09-06T06:14:39.048499Z","level":"INFO","message":"deploy accepted","job":"822444a1b1309653e979b866219cdf34","caller":"oidc:mock:groups:wheel\noidc:mock:someone","targets":"[\"agent/other\"]","target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> agent # [84917.900304] agent flakelet-agent[304]: {"timestamp":"2026-09-06T06:14:39.029552Z","level":"INFO","message":"update finished","flakelet":"other","status":"Unchanged","generation":"Some(1)","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [84917.919548] agent flakelet-agent[304]: {"timestamp":"2026-09-06T06:14:39.048786Z","level":"INFO","message":"start","id":"625552199fde351965a3f3909508eea7","flakelet":"other","rule":"admins","relay":"relay","caller":"Some(\"oidc:mock:groups:wheel\\noidc:mock:someone\")","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> agent # [84917.919970] agent flakelet-agent[304]: {"timestamp":"2026-09-06T06:14:39.049261Z","level":"INFO","message":"update starting","flakelet":"other","ids":"[\"625552199fde351965a3f3909508eea7\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [84917.965525] agent systemd[1]: Starting flakelet update other (via relay)... container-test-run-flakelet-relay> agent # [84918.001750] agent systemd[1]: Started flakelet update other (via relay). container-test-run-flakelet-relay> agent # [84918.006574] agent flakelet[1207]: other: using prebuilt artifact /nix/store/hmr990n0b4v6rpzrcpyyw738r9xi7z95-flakelet-other-v1 container-test-run-flakelet-relay> agent # [84918.007476] agent flakelet[1207]: other: up to date container-test-run-flakelet-relay> agent # [84918.010683] agent systemd[1]: flakelet-relay-job-other.service: Deactivated successfully. container-test-run-flakelet-relay> client: (finished: must succeed: push --relay https://relay:7443 deploy agent/other 2>&1, in 0.67 seconds) container-test-run-flakelet-relay> (finished: subtest: push login via device flow, cached token used afterwards, in 3.40 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 # [84918.569978] agent flakelet-agent[304]: {"timestamp":"2026-09-06T06:14:39.699182Z","level":"INFO","message":"update finished","flakelet":"other","status":"Unchanged","generation":"Some(1)","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [84918.606817] agent flakelet-agent[304]: {"timestamp":"2026-09-06T06:14:39.736094Z","level":"INFO","message":"start","id":"1f0c84abfe046ab751b69914e8ef2ec0","flakelet":"app","rule":"admins","relay":"relay","caller":"Some(\"x509:email:admin@example.org\")","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> agent # [84918.607065] agent flakelet-agent[304]: {"timestamp":"2026-09-06T06:14:39.736361Z","level":"INFO","message":"update starting","flakelet":"app","ids":"[\"1f0c84abfe046ab751b69914e8ef2ec0\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [84918.660825] agent systemd[1]: Starting flakelet update app (via relay)... container-test-run-flakelet-relay> agent # [84918.693881] agent systemd[1]: Started flakelet update app (via relay). container-test-run-flakelet-relay> agent # [84918.698415] agent flakelet[1228]: app: using prebuilt artifact /nix/store/8282rnbvyk847q4l4zby3zwcq3jpzrvw-flakelet-app-broken container-test-run-flakelet-relay> agent # [84918.699109] agent flakelet[1228]: app: activating generation 4 container-test-run-flakelet-relay> agent # [84918.713270] agent systemd[1]: Stopping app v1... container-test-run-flakelet-relay> agent # [84918.713779] agent systemd[1]: app.service: Deactivated successfully. container-test-run-flakelet-relay> agent # [84918.714159] agent systemd[1]: Stopped app v1. container-test-run-flakelet-relay> agent # [84918.731901] agent systemd[1]: Reload requested from client PID 1235 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [84918.731986] agent systemd[1]: Reloading... container-test-run-flakelet-relay> relay # [84918.570925] relay flakelet-relay[400]: {"timestamp":"2026-09-06T06:14:39.700142Z","level":"INFO","message":"deploy finished","job":"822444a1b1309653e979b866219cdf34","ok":true,"target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> relay # [84918.606601] relay flakelet-relay[400]: {"timestamp":"2026-09-06T06:14:39.735861Z","level":"INFO","message":"deploy accepted","job":"233d11327806c6ba4302b8da0bf6b8a9","caller":"x509:email:admin@example.org","targets":"[\"agent/app\", \"agent/other\"]","target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> agent # [84919.080377] agent systemd[1]: Reloading finished in 347 ms. container-test-run-flakelet-relay> agent # [84919.168878] agent systemd[1]: Reload requested from client PID 1265 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [84919.168936] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [84919.506261] agent systemd[1]: Reloading finished in 336 ms. container-test-run-flakelet-relay> agent # [84919.592834] agent systemd[1]: Reload requested from client PID 1294 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [84919.592933] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [84919.931432] agent systemd[1]: Reloading finished in 337 ms. container-test-run-flakelet-relay> agent # [84920.020634] agent systemd[1]: Reload requested from client PID 1325 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [84920.020686] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [84920.361111] agent systemd[1]: Reloading finished in 340 ms. container-test-run-flakelet-relay> agent # [84920.445405] agent systemd[1]: Starting app broken... container-test-run-flakelet-relay> agent # [84920.477538] agent (nonexistent)[1355]: app.service: Unable to locate executable '/nonexistent': No such file or directory container-test-run-flakelet-relay> agent # [84920.477564] agent (nonexistent)[1355]: app.service: Failed at step EXEC spawning /nonexistent: No such file or directory container-test-run-flakelet-relay> agent # [84920.478401] agent systemd[1]: app.service: Main process exited, code=exited, status=203/EXEC container-test-run-flakelet-relay> agent # [84920.478655] agent systemd[1]: app.service: Failed with result 'exit-code'. container-test-run-flakelet-relay> agent # [84920.479271] agent systemd[1]: Failed to start app broken. container-test-run-flakelet-relay> agent # [84920.519415] agent systemd[1]: Reload requested from client PID 1362 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [84920.519474] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [84920.860217] agent systemd[1]: Reloading finished in 340 ms. container-test-run-flakelet-relay> agent # [84920.948253] agent systemd[1]: Reload requested from client PID 1392 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [84920.948309] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [84921.286823] agent systemd[1]: Reloading finished in 338 ms. container-test-run-flakelet-relay> agent # [84921.373421] agent systemd[1]: Reload requested from client PID 1422 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [84921.373521] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [84921.715943] agent systemd[1]: Reloading finished in 341 ms. container-test-run-flakelet-relay> agent # [84921.793023] agent systemd[1]: Reload requested from client PID 1452 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [84921.793065] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [84922.128917] agent systemd[1]: Reloading finished in 335 ms. container-test-run-flakelet-relay> agent # [84922.216705] agent systemd[1]: Starting app v1... container-test-run-flakelet-relay> agent # [84922.246083] agent systemd[1]: Started app v1. container-test-run-flakelet-relay> agent # [84922.246853] agent flakelet[1228]: app: deploy failed, rolled back: systemctl start app.service failed: container-test-run-flakelet-relay> agent # [84922.246853] agent flakelet[1228]: failed units: app.service. See journalctl -u app.service container-test-run-flakelet-relay> agent # [84922.248970] agent systemd[1]: flakelet-relay-job-app.service: Main process exited, code=exited, status=1/FAILURE container-test-run-flakelet-relay> agent # [84922.249090] 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.36 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.38 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> relay # [84922.940893] relay flakelet-relay[400]: {"timestamp":"2026-09-06T06:14:44.070074Z","level":"INFO","message":"deploy finished","job":"233d11327806c6ba4302b8da0bf6b8a9","ok":false,"target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> relay # [84922.989791] relay flakelet-relay[400]: {"timestamp":"2026-09-06T06:14:44.119062Z","level":"INFO","message":"deploy accepted","job":"f0ef0f172dadfeb757f8028afc7ad84d","caller":"x509:email:admin@example.org","targets":"[\"agent/app\"]","target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> agent # [84922.940012] agent flakelet-agent[304]: {"timestamp":"2026-09-06T06:14:44.069199Z","level":"INFO","message":"update finished","flakelet":"app","status":"RolledBack","generation":"Some(3)","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [84922.990053] agent flakelet-agent[304]: {"timestamp":"2026-09-06T06:14:44.119251Z","level":"INFO","message":"start","id":"24727bc93796d2bbe431b12b80c8febe","flakelet":"app","rule":"admins","relay":"relay","caller":"Some(\"x509:email:admin@example.org\")","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> agent # [84922.990259] agent flakelet-agent[304]: {"timestamp":"2026-09-06T06:14:44.119559Z","level":"INFO","message":"update starting","flakelet":"app","ids":"[\"24727bc93796d2bbe431b12b80c8febe\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [84923.043345] agent systemd[1]: Starting flakelet update app (via relay)... container-test-run-flakelet-relay> agent # [84923.074349] agent systemd[1]: Started flakelet update app (via relay). container-test-run-flakelet-relay> agent # [84923.079249] agent flakelet[1507]: app: using prebuilt artifact /nix/store/ln47rsd5km8a04hd5zlkz4q1v352zqh3-flakelet-app-slow2 container-test-run-flakelet-relay> agent # [84923.079893] agent flakelet[1507]: app: activating generation 4 container-test-run-flakelet-relay> agent # [84923.095029] agent systemd[1]: Stopping app v1... container-test-run-flakelet-relay> agent # [84923.095541] agent systemd[1]: app.service: Deactivated successfully. container-test-run-flakelet-relay> agent # [84923.095899] agent systemd[1]: Stopped app v1. container-test-run-flakelet-relay> agent # [84923.112627] agent systemd[1]: Reload requested from client PID 1514 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [84923.112670] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [84923.454122] agent systemd[1]: Reloading finished in 341 ms. container-test-run-flakelet-relay> agent # [84923.542375] agent systemd[1]: Reload requested from client PID 1544 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [84923.542447] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [84923.880717] agent systemd[1]: Reloading finished in 337 ms. container-test-run-flakelet-relay> agent # [84923.972201] agent systemd[1]: Reload requested from client PID 1573 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [84923.972259] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [84924.313348] agent systemd[1]: Reloading finished in 340 ms. container-test-run-flakelet-relay> agent: (finished: waiting for success: systemctl is-active flakelet-relay-job-app.service, in 1.40 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 # [84924.394553] agent systemd[1]: Reload requested from client PID 1605 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [84924.394605] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [84924.732794] agent systemd[1]: Reloading finished in 337 ms. container-test-run-flakelet-relay> agent # [84924.824093] agent systemd[1]: Starting app slow2... container-test-run-flakelet-relay> agent # [84930.912763] agent systemd[1]: Started app slow2. container-test-run-flakelet-relay> agent # [84930.936839] agent flakelet[1507]: app: updated to generation 4 container-test-run-flakelet-relay> agent # [84930.940166] 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.95 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.03 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> relay # [84931.937088] relay flakelet-relay[400]: {"timestamp":"2026-09-06T06:14:53.066355Z","level":"INFO","message":"agent disconnected: websocket closed","host":"agent","target":"flakelet_relay::relay::agent_conn"} container-test-run-flakelet-relay> agent # [84931.937201] agent systemd[1]: Stopping flakelet-agent... container-test-run-flakelet-relay> agent # [84931.957139] agent systemd[1]: flakelet-agent.service: Deactivated successfully. container-test-run-flakelet-relay> agent # [84931.957804] agent systemd[1]: Stopped flakelet-agent. 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.07 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 # [84933.016695] agent systemd[1]: Starting flakelet-agent... container-test-run-flakelet-relay> agent # [84933.071795] agent flakelet-agent[1660]: {"timestamp":"2026-09-06T06:14:54.201056Z","level":"INFO","message":"reattaching to running unit","flakelet":"app","ids":"[\"24727bc93796d2bbe431b12b80c8febe\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [84933.071972] agent systemd[1]: Started flakelet-agent. container-test-run-flakelet-relay> agent # [84933.170393] agent flakelet-agent[1660]: {"timestamp":"2026-09-06T06:14:54.299626Z","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 # [84933.170461] relay flakelet-relay[400]: {"timestamp":"2026-09-06T06:14:54.299689Z","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 # [84933.633488] agent flakelet-agent[1660]: {"timestamp":"2026-09-06T06:14:54.762710Z","level":"INFO","message":"update finished","flakelet":"app","status":"Updated","generation":"Some(4)","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [84935.005305] agent flakelet-agent[1660]: {"timestamp":"2026-09-06T06:14:56.134515Z","level":"INFO","message":"start","id":"341a24f36eee22891cac419dfdd39181","flakelet":"other","rule":"admins","relay":"relay","caller":"Some(\"x509:email:admin@example.org\")","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> agent # [84935.006270] agent flakelet-agent[1660]: {"timestamp":"2026-09-06T06:14:56.135113Z","level":"INFO","message":"update starting","flakelet":"other","ids":"[\"341a24f36eee22891cac419dfdd39181\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [84935.065062] agent systemd[1]: Starting flakelet update other (via relay)... container-test-run-flakelet-relay> agent # [84935.100420] agent systemd[1]: Started flakelet update other (via relay). container-test-run-flakelet-relay> agent # [84935.105940] agent flakelet[2073]: other: using prebuilt artifact /nix/store/hmr990n0b4v6rpzrcpyyw738r9xi7z95-flakelet-other-v1 container-test-run-flakelet-relay> agent # [84935.106766] agent flakelet[2073]: other: up to date container-test-run-flakelet-relay> agent # [84935.109037] agent systemd[1]: flakelet-relay-job-other.service: Deactivated successfully. container-test-run-flakelet-relay> relay # [84935.005034] relay flakelet-relay[400]: {"timestamp":"2026-09-06T06:14:56.134248Z","level":"INFO","message":"deploy accepted","job":"37114be9c0062fbf49e54771d81fad12","caller":"x509:email:admin@example.org","targets":"[\"agent/other\"]","target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> relay # [84935.660993] relay flakelet-relay[400]: {"timestamp":"2026-09-06T06:14:56.790264Z","level":"INFO","message":"deploy finished","job":"37114be9c0062fbf49e54771d81fad12","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> agent # [84935.660276] agent flakelet-agent[1660]: {"timestamp":"2026-09-06T06:14:56.789527Z","level":"INFO","message":"update finished","flakelet":"other","status":"Unchanged","generation":"Some(1)","target":"flakelet_relay::agent::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 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.eyJhdWQiOiJmbGFrZWxldC1yZWxheSIsImV4cCI6MTc4ODY3NTU5NywiZ3JvdXBzIjpbXSwiaWF0IjoxNzg4Njc1Mjk3LCJpc3MiOiJodHRwczovL3JlbGF5Ojg0NDMiLCJqdGkiOiJBREJLVE03S0tLUFlNPT09IiwibmJmIjoxNzg4Njc1Mjk3LCJzdWIiOiJyZXBvOmdpdGh1YjpleGFtcGxlL2FwcDpyZWY6cmVmcy9oZWFkcy9tYWluIn0.zlGRHorh3UEfTilEPwHEbrVQVf__7AxnM_oH1QEKJ8Wq3-DbPQNsbeyynoj-qQemCL0NjJIb6drtxNzUOaSWCg' push --relay https://relay:7443 agents container-test-run-flakelet-relay> client: (finished: must succeed: FLAKELET_RELAY_TOKEN_COMMAND='echo eyJhbGciOiJFZERTQSIsImtpZCI6ImsxIiwidHlwIjoiSldUIn0.eyJhdWQiOiJmbGFrZWxldC1yZWxheSIsImV4cCI6MTc4ODY3NTU5NywiZ3JvdXBzIjpbXSwiaWF0IjoxNzg4Njc1Mjk3LCJpc3MiOiJodHRwczovL3JlbGF5Ojg0NDMiLCJqdGkiOiJBREJLVE03S0tLUFlNPT09IiwibmJmIjoxNzg4Njc1Mjk3LCJzdWIiOiJyZXBvOmdpdGh1YjpleGFtcGxlL2FwcDpyZWY6cmVmcy9oZWFkcy9tYWluIn0.zlGRHorh3UEfTilEPwHEbrVQVf__7AxnM_oH1QEKJ8Wq3-DbPQNsbeyynoj-qQemCL0NjJIb6drtxNzUOaSWCg' 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.30 seconds) container-test-run-flakelet-relay> subtest: certificate not listed under agents cannot connect as agent container-test-run-flakelet-relay> client: must succeed: curl -s -o /dev/null -w '%{http_code}' --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem --cert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/mallory/cert.pem --key /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/mallory/key.pem -H 'Upgrade: websocket' -H 'Connection: Upgrade' -H 'Sec-WebSocket-Version: 13' -H 'Sec-WebSocket-Key: dGhlIHNhbXBsZSBub25jZQ==' https://relay:7443/v1/agent container-test-run-flakelet-relay> client: (finished: must succeed: curl -s -o /dev/null -w '%{http_code}' --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem --cert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/mallory/cert.pem --key /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/mallory/key.pem -H 'Upgrade: websocket' -H 'Connection: Upgrade' -H 'Sec-WebSocket-Version: 13' -H 'Sec-WebSocket-Key: dGhlIHNhbXBsZSBub25jZQ==' https://relay:7443/v1/agent, in 0.02 seconds) container-test-run-flakelet-relay> (finished: subtest: certificate not listed under agents cannot connect as agent, in 0.02 seconds) container-test-run-flakelet-relay> subtest: metrics container-test-run-flakelet-relay> relay: must succeed: curl -sf http://127.0.0.1:7400/metrics container-test-run-flakelet-relay> relay: (finished: must succeed: curl -sf http://127.0.0.1:7400/metrics, in 0.02 seconds) container-test-run-flakelet-relay> (finished: subtest: metrics, in 0.02 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=@1788675297 | grep -q 'app-slow' container-test-run-flakelet-relay> agent # [84936.286568] agent flakelet-agent[1660]: {"timestamp":"2026-09-06T06:14:57.415808Z","level":"INFO","message":"start","id":"7a94d5587e6f543e3c1b2fcac4d90fd6","flakelet":"app","rule":"admins","relay":"relay","caller":"Some(\"x509:email:admin@example.org\")","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> agent # [84936.287157] agent flakelet-agent[1660]: {"timestamp":"2026-09-06T06:14:57.416394Z","level":"INFO","message":"update starting","flakelet":"app","ids":"[\"7a94d5587e6f543e3c1b2fcac4d90fd6\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [84936.349979] agent systemd[1]: Starting flakelet update app (via relay)... container-test-run-flakelet-relay> agent # [84936.384082] agent systemd[1]: Started flakelet update app (via relay). container-test-run-flakelet-relay> agent # [84936.389545] agent flakelet[2098]: app: using prebuilt artifact /nix/store/c67jj8kx3m50nyj874aqmdqf21w5iznd-flakelet-app-slow container-test-run-flakelet-relay> agent # [84936.390286] agent flakelet[2098]: app: activating generation 5 container-test-run-flakelet-relay> agent # [84936.406632] agent systemd[1]: Stopping app slow2... container-test-run-flakelet-relay> agent # [84936.407185] agent systemd[1]: app.service: Deactivated successfully. container-test-run-flakelet-relay> agent # [84936.407576] agent systemd[1]: Stopped app slow2. container-test-run-flakelet-relay> agent # [84936.430502] agent systemd[1]: Reload requested from client PID 2105 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [84936.430596] agent systemd[1]: Reloading... container-test-run-flakelet-relay> relay # [84936.226326] relay flakelet-relay[400]: {"timestamp":"2026-09-06T06:14:57.355592Z","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 # [84936.286179] relay flakelet-relay[400]: {"timestamp":"2026-09-06T06:14:57.415393Z","level":"INFO","message":"deploy accepted","job":"1ec5f3b6d0fcfa69c747402c5d67d6e5","caller":"x509:email:admin@example.org","targets":"[\"agent/app\"]","target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> agent # [84936.811995] agent systemd[1]: Reloading finished in 380 ms. container-test-run-flakelet-relay> agent # [84936.901052] agent systemd[1]: Reload requested from client PID 2135 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [84936.901100] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent: (finished: waiting for success: journalctl -u flakelet-relay-job-app.service --since=@1788675297 | 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: (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=@1788675297 | grep -q 'updated to generation' container-test-run-flakelet-relay> agent # [84937.268833] agent systemd[1]: Reloading finished in 367 ms. container-test-run-flakelet-relay> agent # [84937.351574] agent systemd[1]: Reload requested from client PID 2174 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [84937.351620] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [84937.397141] agent flakelet-agent[1660]: {"timestamp":"2026-09-06T06:14:58.526394Z","level":"INFO","message":"disconnected: Software caused connection abort (os error 103)","relay":"https://relay:7443","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> relay # [84937.366564] relay flakelet-relay[400]: {"timestamp":"2026-09-06T06:14:58.495771Z","level":"INFO","message":"agent disconnected: Software caused connection abort (os error 103)","host":"agent","target":"flakelet_relay::relay::agent_conn"} container-test-run-flakelet-relay> agent # [84937.697606] agent systemd[1]: Reloading finished in 345 ms. container-test-run-flakelet-relay> agent # [84937.787986] agent systemd[1]: Reload requested from client PID 2209 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [84937.788037] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [84938.131774] agent systemd[1]: Reloading finished in 343 ms. container-test-run-flakelet-relay> agent # [84938.224466] agent systemd[1]: Starting app slow... container-test-run-flakelet-relay> agent # [84938.490272] agent flakelet-agent[1660]: {"timestamp":"2026-09-06T06:14:59.619430Z","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 # [84940.568161] agent flakelet-agent[1660]: {"timestamp":"2026-09-06T06:15:01.697416Z","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: (finished: waiting for success: journalctl -u flakelet-relay-job-app.service --since=@1788675297 | grep -q 'updated to generation', in 7.09 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 # [84944.295555] agent systemd[1]: Started app slow. container-test-run-flakelet-relay> agent # [84944.311975] agent flakelet[2098]: app: updated to generation 5 container-test-run-flakelet-relay> agent # [84944.312934] agent systemd[1]: flakelet-relay-job-app.service: Deactivated successfully. container-test-run-flakelet-relay> relay # [84944.654166] relay flakelet-relay[400]: {"timestamp":"2026-09-06T06:15:05.783403Z","level":"INFO","message":"agent connected","host":"agent","version":"0.1.0","flakelets":"[Named { name: \"app\", generation: Some(5), 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 # [84944.654102] agent flakelet-agent[1660]: {"timestamp":"2026-09-06T06:15:05.783339Z","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 # [84944.655290] agent flakelet-agent[1660]: {"timestamp":"2026-09-06T06:15:05.784583Z","level":"INFO","message":"start","id":"7a94d5587e6f543e3c1b2fcac4d90fd6","flakelet":"app","rule":"admins","relay":"relay","caller":"Some(\"x509:email:admin@example.org\")","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> client: (finished: waiting for success: grep -q 'agent/app: updated' /tmp/slow.out, in 1.02 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 9.29 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> agent # [84945.386387] agent flakelet-agent[1660]: {"timestamp":"2026-09-06T06:15:06.515602Z","level":"INFO","message":"update finished","flakelet":"app","status":"Updated","generation":"Some(5)","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [84945.570399] agent flakelet-agent[1660]: {"timestamp":"2026-09-06T06:15:06.699611Z","level":"INFO","message":"start","id":"9d04c88ad4f512fa4f82c5100d18fc16","flakelet":"app","rule":"admins","relay":"relay","caller":"Some(\"x509:email:admin@example.org\")","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> agent # [84945.570977] agent flakelet-agent[1660]: {"timestamp":"2026-09-06T06:15:06.700246Z","level":"INFO","message":"update starting","flakelet":"app","ids":"[\"9d04c88ad4f512fa4f82c5100d18fc16\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [84945.633762] agent systemd[1]: Starting flakelet update app (via relay)... container-test-run-flakelet-relay> relay # [84945.387136] relay flakelet-relay[400]: {"timestamp":"2026-09-06T06:15:06.516378Z","level":"INFO","message":"deploy finished","job":"1ec5f3b6d0fcfa69c747402c5d67d6e5","ok":true,"target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> relay # [84945.569938] relay flakelet-relay[400]: {"timestamp":"2026-09-06T06:15:06.699197Z","level":"INFO","message":"deploy accepted","job":"9c34d306b5e457f2f406e05a8fbb7e1f","caller":"x509:email:admin@example.org","targets":"[\"agent/app\"]","target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> agent # [84945.641578] agent systemd[1]: Started flakelet update app (via relay). container-test-run-flakelet-relay> agent # [84945.645323] agent flakelet[2330]: app: using prebuilt artifact /nix/store/ln47rsd5km8a04hd5zlkz4q1v352zqh3-flakelet-app-slow2 container-test-run-flakelet-relay> agent # [84945.646246] agent flakelet[2330]: app: activating generation 6 container-test-run-flakelet-relay> agent # [84945.660062] agent systemd[1]: Stopping app slow... container-test-run-flakelet-relay> agent # [84945.661805] agent systemd[1]: app.service: Deactivated successfully. container-test-run-flakelet-relay> agent # [84945.662226] agent systemd[1]: Stopped app slow. container-test-run-flakelet-relay> agent # [84945.673782] agent systemd[1]: Reload requested from client PID 2337 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [84945.673823] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [84946.064258] agent systemd[1]: Reloading finished in 390 ms. container-test-run-flakelet-relay> agent # [84946.162236] agent systemd[1]: Reload requested from client PID 2367 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [84946.162337] 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.03 seconds) container-test-run-flakelet-relay> agent: must succeed: systemctl restart flakelet-agent container-test-run-flakelet-relay> agent # [84946.664344] agent systemd[1]: Reloading finished in 501 ms. container-test-run-flakelet-relay> agent # [84946.764537] agent systemd[1]: Reload requested from client PID 2401 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [84946.764585] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [84947.148375] agent systemd[1]: Reloading finished in 383 ms. container-test-run-flakelet-relay> agent # [84947.238071] agent systemd[1]: Stopping flakelet-agent... container-test-run-flakelet-relay> agent # [84947.247123] agent systemd[1]: Reload requested from client PID 2431 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [84947.247164] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [84947.602463] agent systemd[1]: Reloading finished in 354 ms. container-test-run-flakelet-relay> relay # [84947.238378] relay flakelet-relay[400]: {"timestamp":"2026-09-06T06:15:08.367580Z","level":"INFO","message":"agent disconnected: websocket closed","host":"agent","target":"flakelet_relay::relay::agent_conn"} container-test-run-flakelet-relay> agent: (finished: must succeed: systemctl restart flakelet-agent, in 1.17 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 # [84947.681340] agent systemd[1]: flakelet-agent.service: Deactivated successfully. container-test-run-flakelet-relay> agent # [84947.681610] agent systemd[1]: Stopped flakelet-agent. container-test-run-flakelet-relay> agent # [84947.687443] agent systemd[1]: Starting flakelet-agent... container-test-run-flakelet-relay> agent # [84947.688849] agent systemd[1]: Starting app slow2... container-test-run-flakelet-relay> agent # [84947.753711] agent flakelet-agent[2462]: {"timestamp":"2026-09-06T06:15:08.882920Z","level":"INFO","message":"reattaching to running unit","flakelet":"app","ids":"[\"9d04c88ad4f512fa4f82c5100d18fc16\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [84947.753772] agent systemd[1]: Started flakelet-agent. container-test-run-flakelet-relay> agent # [84947.836197] agent flakelet-agent[2462]: {"timestamp":"2026-09-06T06:15:08.965461Z","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 # [84947.836376] agent flakelet-agent[2462]: {"timestamp":"2026-09-06T06:15:08.965561Z","level":"INFO","message":"start","id":"9d04c88ad4f512fa4f82c5100d18fc16","flakelet":"app","rule":"admins","relay":"relay","caller":"Some(\"x509:email:admin@example.org\")","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> relay # [84947.836236] relay flakelet-relay[400]: {"timestamp":"2026-09-06T06:15:08.965494Z","level":"INFO","message":"agent connected","host":"agent","version":"0.1.0","flakelets":"[Named { name: \"app\", generation: Some(5), 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 # [84953.756111] agent systemd[1]: Started app slow2. container-test-run-flakelet-relay> agent # [84953.775326] agent flakelet[2330]: app: updated to generation 6 container-test-run-flakelet-relay> agent # [84953.777629] agent systemd[1]: flakelet-relay-job-app.service: Deactivated successfully. container-test-run-flakelet-relay> agent # [84954.342454] agent flakelet-agent[2462]: {"timestamp":"2026-09-06T06:15:15.471684Z","level":"INFO","message":"update finished","flakelet":"app","status":"Updated","generation":"Some(6)","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> relay # [84954.343329] relay flakelet-relay[400]: {"timestamp":"2026-09-06T06:15:15.472531Z","level":"INFO","message":"deploy finished","job":"9c34d306b5e457f2f406e05a8fbb7e1f","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.33 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=@1788675316 | grep -q 'app-slow' container-test-run-flakelet-relay> agent # [84954.914584] agent flakelet-agent[2462]: {"timestamp":"2026-09-06T06:15:16.043809Z","level":"INFO","message":"start","id":"6c5fb2032d298fc6f8a54a82f99273a2","flakelet":"app","rule":"admins","relay":"relay","caller":"Some(\"x509:email:admin@example.org\")","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> relay # [84954.914228] relay flakelet-relay[400]: {"timestamp":"2026-09-06T06:15:16.043389Z","level":"INFO","message":"deploy accepted","job":"c46a149e7623dfbfd5aecd8bb1d94a27","caller":"x509:email:admin@example.org","targets":"[\"agent/app\"]","target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> agent # [84954.915115] agent flakelet-agent[2462]: {"timestamp":"2026-09-06T06:15:16.044406Z","level":"INFO","message":"update starting","flakelet":"app","ids":"[\"6c5fb2032d298fc6f8a54a82f99273a2\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [84954.975782] agent systemd[1]: Starting flakelet update app (via relay)... container-test-run-flakelet-relay> agent # [84954.984070] agent systemd[1]: Started flakelet update app (via relay). container-test-run-flakelet-relay> agent # [84954.988817] agent flakelet[2897]: app: using prebuilt artifact /nix/store/c67jj8kx3m50nyj874aqmdqf21w5iznd-flakelet-app-slow container-test-run-flakelet-relay> agent # [84954.989520] agent flakelet[2897]: app: activating generation 7 container-test-run-flakelet-relay> agent # [84955.002783] agent systemd[1]: Stopping app slow2... container-test-run-flakelet-relay> agent # [84955.003700] agent systemd[1]: app.service: Deactivated successfully. container-test-run-flakelet-relay> agent # [84955.003892] agent systemd[1]: Stopped app slow2. container-test-run-flakelet-relay> agent # [84955.016477] agent systemd[1]: Reload requested from client PID 2904 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [84955.016569] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [84955.491591] agent systemd[1]: Reloading finished in 474 ms. container-test-run-flakelet-relay> agent # [84955.579613] agent systemd[1]: Reload requested from client PID 2934 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [84955.579669] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent: (finished: waiting for success: journalctl -u flakelet-relay-job-app.service --since=@1788675316 | 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> relay # [84955.933851] relay systemd[1]: Stopping flakelet-relay... container-test-run-flakelet-relay> relay # [84955.964925] relay systemd[1]: flakelet-relay.service: Deactivated successfully. container-test-run-flakelet-relay> relay # [84955.965334] relay systemd[1]: Stopped flakelet-relay. container-test-run-flakelet-relay> relay # [84955.965355] relay systemd[1]: flakelet-relay.service: Consumed 2.173s CPU time over 52.424s wall clock time. container-test-run-flakelet-relay> relay # [84955.984527] relay systemd[1]: Starting flakelet-relay... container-test-run-flakelet-relay> agent # [84955.934262] agent flakelet-agent[2462]: {"timestamp":"2026-09-06T06:15:17.063504Z","level":"INFO","message":"disconnected: websocket closed","relay":"https://relay:7443","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> agent # [84956.163696] agent systemd[1]: Reloading finished in 583 ms. container-test-run-flakelet-relay> agent # [84956.272418] agent systemd[1]: Reload requested from client PID 2967 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [84956.272462] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [84956.707168] agent systemd[1]: Reloading finished in 434 ms. container-test-run-flakelet-relay> agent # [84956.812105] agent systemd[1]: Reload requested from client PID 2997 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [84956.812146] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [84957.218486] agent systemd[1]: Reloading finished in 405 ms. container-test-run-flakelet-relay> agent # [84957.311143] agent systemd[1]: Starting app slow... container-test-run-flakelet-relay> agent # [84957.376183] agent flakelet-agent[2462]: {"timestamp":"2026-09-06T06:15:18.505432Z","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 2.53 seconds) container-test-run-flakelet-relay> client: waiting for success: grep -q '» ok' /tmp/resume.out container-test-run-flakelet-relay> relay # [84958.450241] relay flakelet-relay[933]: {"timestamp":"2026-09-06T06:15:19.579483Z","level":"INFO","message":"listening on 127.0.0.1:7400","target":"flakelet_relay::relay::server"} container-test-run-flakelet-relay> relay # [84958.451098] relay flakelet-relay[933]: {"timestamp":"2026-09-06T06:15:19.579896Z","level":"INFO","message":"listening on [::]:7443","target":"flakelet_relay::relay::server"} container-test-run-flakelet-relay> relay # [84958.450827] relay systemd[1]: Started flakelet-relay. container-test-run-flakelet-relay> agent # [84959.469222] agent flakelet-agent[2462]: {"timestamp":"2026-09-06T06:15:20.598467Z","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 # [84959.469298] relay flakelet-relay[933]: {"timestamp":"2026-09-06T06:15:20.598545Z","level":"INFO","message":"agent connected","host":"agent","version":"0.1.0","flakelets":"[Named { name: \"app\", generation: Some(6), 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 # [84962.953553] relay flakelet-relay[933]: {"timestamp":"2026-09-06T06:15:24.082804Z","level":"INFO","message":"job reattached","job":"c46a149e7623dfbfd5aecd8bb1d94a27","targets":"[\"agent/app\"]","target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> agent # [84963.354363] agent systemd[1]: Started app slow. container-test-run-flakelet-relay> agent # [84963.376430] agent flakelet[2897]: app: updated to generation 7 container-test-run-flakelet-relay> agent # [84963.380247] agent systemd[1]: flakelet-relay-job-app.service: Deactivated successfully. container-test-run-flakelet-relay> client: (finished: waiting for success: grep -q '» ok' /tmp/resume.out, in 6.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.66 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> relay # [84964.552031] relay flakelet-relay[933]: {"timestamp":"2026-09-06T06:15:25.681241Z","level":"INFO","message":"deploy accepted","job":"188b06c4636b65849b3ba8daac22dc7c","caller":"x509:email:admin@example.org","targets":"[\"agent/other\"]","target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> agent # [84964.418314] agent flakelet-agent[2462]: {"timestamp":"2026-09-06T06:15:25.547562Z","level":"INFO","message":"update finished","flakelet":"app","status":"Updated","generation":"Some(7)","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [84964.552481] agent flakelet-agent[2462]: {"timestamp":"2026-09-06T06:15:25.681728Z","level":"INFO","message":"start","id":"5be20c693e5c0b60e3f85779794dfb86","flakelet":"other","rule":"admins","relay":"relay","caller":"Some(\"x509:email:admin@example.org\")","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> agent # [84964.552937] agent flakelet-agent[2462]: {"timestamp":"2026-09-06T06:15:25.682210Z","level":"INFO","message":"update starting","flakelet":"other","ids":"[\"5be20c693e5c0b60e3f85779794dfb86\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [84964.613816] agent systemd[1]: Starting flakelet update other (via relay)... container-test-run-flakelet-relay> agent # [84964.621686] agent systemd[1]: Started flakelet update other (via relay). container-test-run-flakelet-relay> agent # [84964.625423] agent flakelet[3074]: other: using prebuilt artifact /nix/store/hmr990n0b4v6rpzrcpyyw738r9xi7z95-flakelet-other-v1 container-test-run-flakelet-relay> agent # [84964.626012] agent flakelet[3074]: other: up to date container-test-run-flakelet-relay> agent # [84964.630877] agent systemd[1]: flakelet-relay-job-other.service: Deactivated successfully. 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.66 seconds) container-test-run-flakelet-relay> relay: must succeed: systemctl restart flakelet-relay container-test-run-flakelet-relay> relay # [84965.188612] relay flakelet-relay[933]: {"timestamp":"2026-09-06T06:15:26.317835Z","level":"INFO","message":"deploy finished","job":"188b06c4636b65849b3ba8daac22dc7c","ok":true,"target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> relay # [84965.205103] relay systemd[1]: Stopping flakelet-relay... container-test-run-flakelet-relay> relay # [84965.254869] relay systemd[1]: flakelet-relay.service: Deactivated successfully. container-test-run-flakelet-relay> relay # [84965.255421] relay systemd[1]: Stopped flakelet-relay. container-test-run-flakelet-relay> relay # [84965.255466] relay systemd[1]: flakelet-relay.service: Consumed 2.064s CPU time over 9.270s wall clock time. container-test-run-flakelet-relay> relay # [84965.294043] relay systemd[1]: Starting flakelet-relay... container-test-run-flakelet-relay> agent # [84965.187790] agent flakelet-agent[2462]: {"timestamp":"2026-09-06T06:15:26.317006Z","level":"INFO","message":"update finished","flakelet":"other","status":"Unchanged","generation":"Some(1)","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [84965.205316] agent flakelet-agent[2462]: {"timestamp":"2026-09-06T06:15:26.334590Z","level":"INFO","message":"disconnected: websocket closed","relay":"https://relay:7443","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> agent # [84966.293588] agent flakelet-agent[2462]: {"timestamp":"2026-09-06T06:15:27.422846Z","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 2.62 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 # [84967.808445] relay flakelet-relay[1323]: {"timestamp":"2026-09-06T06:15:28.937654Z","level":"INFO","message":"listening on 127.0.0.1:7400","target":"flakelet_relay::relay::server"} container-test-run-flakelet-relay> relay # [84967.809325] relay flakelet-relay[1323]: {"timestamp":"2026-09-06T06:15:28.938060Z","level":"INFO","message":"listening on [::]:7443","target":"flakelet_relay::relay::server"} container-test-run-flakelet-relay> relay # [84967.808934] relay systemd[1]: Started flakelet-relay. container-test-run-flakelet-relay> relay # [84968.381317] relay flakelet-relay[1323]: {"timestamp":"2026-09-06T06:15:29.510573Z","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: (finished: waiting for success: curl -sf http://127.0.0.1:7400/metrics | grep -q 'agent_up{host="agent"} 1', in 1.04 seconds) container-test-run-flakelet-relay> client: must succeed: push --relay https://relay:7443 --cert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin.pem --key /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin-key.pem jobs container-test-run-flakelet-relay> client: (finished: must succeed: push --relay https://relay:7443 --cert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin.pem --key /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin-key.pem jobs, in 0.02 seconds) container-test-run-flakelet-relay> client: must succeed: echo '{"groups": []}' | step crypto jwt sign --key /nix/store/r7sclamg09v434w8zjlfnrcrn1w79wh6-flakelet-relay-test-issuer/jwk.json --iss https://relay:8443 --aud flakelet-relay --sub 'repo:github:example/app:ref:refs/heads/main' --exp $(( $(date +%s) + 300 )) --jti $(head -c8 /dev/urandom | base32) container-test-run-flakelet-relay> agent # [84968.381259] agent flakelet-agent[2462]: {"timestamp":"2026-09-06T06:15:29.510520Z","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> 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.eyJhdWQiOiJmbGFrZWxldC1yZWxheSIsImV4cCI6MTc4ODY3NTYzMCwiZ3JvdXBzIjpbXSwiaWF0IjoxNzg4Njc1MzMwLCJpc3MiOiJodHRwczovL3JlbGF5Ojg0NDMiLCJqdGkiOiJEN1ZKR0g3S0hSRlY2PT09IiwibmJmIjoxNzg4Njc1MzMwLCJzdWIiOiJyZXBvOmdpdGh1YjpleGFtcGxlL2FwcDpyZWY6cmVmcy9oZWFkcy9tYWluIn0.xyPeegCtYbiMow3k0t1GT0t8Z4U2uq2iYo4KAzM4dSlmb3-21ZmqG-qAc0iqztEXEejC9giHz_G3xz0MOmPCDw' push --relay https://relay:7443 jobs container-test-run-flakelet-relay> client: (finished: must succeed: FLAKELET_RELAY_TOKEN_COMMAND='echo eyJhbGciOiJFZERTQSIsImtpZCI6ImsxIiwidHlwIjoiSldUIn0.eyJhdWQiOiJmbGFrZWxldC1yZWxheSIsImV4cCI6MTc4ODY3NTYzMCwiZ3JvdXBzIjpbXSwiaWF0IjoxNzg4Njc1MzMwLCJpc3MiOiJodHRwczovL3JlbGF5Ojg0NDMiLCJqdGkiOiJEN1ZKR0g3S0hSRlY2PT09IiwibmJmIjoxNzg4Njc1MzMwLCJzdWIiOiJyZXBvOmdpdGh1YjpleGFtcGxlL2FwcDpyZWY6cmVmcy9oZWFkcy9tYWluIn0.xyPeegCtYbiMow3k0t1GT0t8Z4U2uq2iYo4KAzM4dSlmb3-21ZmqG-qAc0iqztEXEejC9giHz_G3xz0MOmPCDw' push --relay https://relay:7443 jobs, in 0.02 seconds) container-test-run-flakelet-relay> (finished: subtest: job history comes from the agents and survives a relay restart, in 4.41 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.02 seconds) container-test-run-flakelet-relay> client: must succeed: curl -sS --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem -b /tmp/jar -c /tmp/jar -f https://relay:7443/v1/jobs | grep -q hist1 container-test-run-flakelet-relay> client: (finished: must succeed: curl -sS --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem -b /tmp/jar -c /tmp/jar -f https://relay:7443/v1/jobs | grep -q hist1, in 0.02 seconds) container-test-run-flakelet-relay> client: must succeed: curl -sS --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem -b /tmp/jar -c /tmp/jar -N --max-time 10 https://relay:7443/ui/jobs/hist1/events || true container-test-run-flakelet-relay> client: (finished: must succeed: curl -sS --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem -b /tmp/jar -c /tmp/jar -N --max-time 10 https://relay:7443/ui/jobs/hist1/events || true, in 0.02 seconds) container-test-run-flakelet-relay> client: must succeed: curl -sS --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem -b /tmp/jar -c /tmp/jar -o /dev/null -w '%{http_code}' -X POST 'https://relay:7443/ui/deploy?arg=other' | grep -q 403 container-test-run-flakelet-relay> client: (finished: must succeed: curl -sS --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem -b /tmp/jar -c /tmp/jar -o /dev/null -w '%{http_code}' -X POST 'https://relay:7443/ui/deploy?arg=other' | grep -q 403, in 0.02 seconds) container-test-run-flakelet-relay> client: must succeed: curl -sS --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem -b /tmp/jar -c /tmp/jar -D - -o /dev/null -H 'HX-Request: true' -X POST 'https://relay:7443/ui/deploy?arg=other' container-test-run-flakelet-relay> client: (finished: must succeed: curl -sS --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem -b /tmp/jar -c /tmp/jar -D - -o /dev/null -H 'HX-Request: true' -X POST 'https://relay:7443/ui/deploy?arg=other', in 0.02 seconds) container-test-run-flakelet-relay> client: must succeed: curl -sS --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem -b /tmp/jar -c /tmp/jar -N --max-time 30 https://relay:7443/ui/jobs/e8ad9998b92795e99537ef719d2e5d3d/events || true container-test-run-flakelet-relay> relay # [84968.991790] relay 93sn3sv24fj26ksccpppzwg6kbf10s4w-device-mock[185]: 127.0.0.1 - - [06/Sep/2026 06:15:30] "GET /authorize?response_type=code&client_id=dashboard&redirect_uri=https%3A%2F%2Frelay%3A7443%2Fui%2Fcallback&scope=openid%20profile%20email%20groups&state=ryJccPXTE41y5X4MiTWpAyCLT7yE-Ygnwd4cr9j0uN4&code_challenge=I3lWc3H6RYYoUGETDTt3XQicCdOyRoAhaTh7L-wEd_A&code_challenge_method=S256 HTTP/1.1" 302 - container-test-run-flakelet-relay> relay # [84969.044908] relay 93sn3sv24fj26ksccpppzwg6kbf10s4w-device-mock[185]: 127.0.0.1 - - [06/Sep/2026 06:15:30] "POST /token HTTP/1.1" 200 - container-test-run-flakelet-relay> relay # [84969.045654] relay flakelet-relay[1323]: {"timestamp":"2026-09-06T06:15:30.174895Z","level":"INFO","message":"login","name":"someone","principals":"[\"oidc:mock:someone\", \"oidc:mock:groups:wheel\"]","target":"flakelet_relay::relay::login"} container-test-run-flakelet-relay> relay # [84969.108393] relay flakelet-relay[1323]: {"timestamp":"2026-09-06T06:15:30.237657Z","level":"INFO","message":"job reattached","job":"188b06c4636b65849b3ba8daac22dc7c","targets":"[\"agent/other\"]","target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> relay # [84969.149318] relay flakelet-relay[1323]: {"timestamp":"2026-09-06T06:15:30.278598Z","level":"INFO","message":"deploy accepted","job":"e8237b7e1d163f31bd30ffe5974f7bcd","caller":"oidc:mock:someone\noidc:mock:groups:wheel","targets":"[\"agent/other\"]","target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> relay # [84969.166995] relay flakelet-relay[1323]: {"timestamp":"2026-09-06T06:15:30.296287Z","level":"INFO","message":"job reattached","job":"e8237b7e1d163f31bd30ffe5974f7bcd","targets":"[\"agent/other\"]","target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> agent # [84969.149501] agent flakelet-agent[2462]: {"timestamp":"2026-09-06T06:15:30.278756Z","level":"INFO","message":"start","id":"176b615e057aa56f0dcd0f7febf10c20","flakelet":"other","rule":"admins","relay":"relay","caller":"Some(\"oidc:mock:someone\\noidc:mock:groups:wheel\")","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> agent # [84969.149781] agent flakelet-agent[2462]: {"timestamp":"2026-09-06T06:15:30.279083Z","level":"INFO","message":"update starting","flakelet":"other","ids":"[\"176b615e057aa56f0dcd0f7febf10c20\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [84969.215024] agent systemd[1]: Starting flakelet update other (via relay)... container-test-run-flakelet-relay> agent # [84969.249326] agent systemd[1]: Started flakelet update other (via relay). container-test-run-flakelet-relay> agent # [84969.253611] agent flakelet[3112]: other: using prebuilt artifact /nix/store/hmr990n0b4v6rpzrcpyyw738r9xi7z95-flakelet-other-v1 container-test-run-flakelet-relay> agent # [84969.254127] agent flakelet[3112]: other: up to date container-test-run-flakelet-relay> agent # [84969.256485] 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/e8ad9998b92795e99537ef719d2e5d3d/events || true, in 0.67 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/e8ad9998b92795e99537ef719d2e5d3d 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/e8ad9998b92795e99537ef719d2e5d3d, 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 -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=e8ad9998b92795e99537ef719d2e5d3d' | 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=e8ad9998b92795e99537ef719d2e5d3d' | 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 # [84969.814658] agent flakelet-agent[2462]: {"timestamp":"2026-09-06T06:15:30.943873Z","level":"INFO","message":"update finished","flakelet":"other","status":"Unchanged","generation":"Some(1)","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [84969.961991] agent flakelet-agent[2462]: {"timestamp":"2026-09-06T06:15:31.091186Z","level":"INFO","message":"start","id":"5e317fc57863c2a7c03ebe1bdacd1356","flakelet":"other","rule":"admins","relay":"relay","caller":"Some(\"x509:email:admin@example.org\")","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> agent # [84969.962592] agent flakelet-agent[2462]: {"timestamp":"2026-09-06T06:15:31.091877Z","level":"INFO","message":"update starting","flakelet":"other","ids":"[\"5e317fc57863c2a7c03ebe1bdacd1356\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [84970.032244] agent systemd[1]: Starting flakelet update other (via relay)... 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 # [84970.071178] agent systemd[1]: Started flakelet update other (via relay). container-test-run-flakelet-relay> agent # [84970.076867] agent flakelet[3130]: other: using prebuilt artifact /nix/store/hmr990n0b4v6rpzrcpyyw738r9xi7z95-flakelet-other-v1 container-test-run-flakelet-relay> agent # [84970.077375] agent flakelet[3130]: other: up to date container-test-run-flakelet-relay> relay # [84969.961466] relay flakelet-relay[1323]: {"timestamp":"2026-09-06T06:15:31.090734Z","level":"INFO","message":"deploy accepted","job":"38796a7ba508eadfe82a1cd5def000bc","caller":"x509:email:admin@example.org","targets":"[\"agent/other\"]","target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> agent # [84970.080524] 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> » 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 70.73 seconds) container-test-run-flakelet-relay> test script finished in 70.78s container-test-run-flakelet-relay> cleanup container-test-run-flakelet-relay> kill NspawnMachine (pid 52) container-test-run-flakelet-relay> kill NspawnMachine (pid 53) container-test-run-flakelet-relay> Container agent terminated by signal KILL. container-test-run-flakelet-relay> relay # [84970.636541] relay flakelet-relay[1323]: {"timestamp":"2026-09-06T06:15:31.765765Z","level":"INFO","message":"deploy finished","job":"38796a7ba508eadfe82a1cd5def000bc","ok":true,"target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> kill NspawnMachine (pid 54) 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.44 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 7f630172135f1077f4a87a31e7dfb9d9 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 233d11327806c6ba4302b8da0bf6b8a9 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 1ec5f3b6d0fcfa69c747402c5d67d6e5 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 5 container-test-run-flakelet-relay> agent/app | app: updated to generation 5 container-test-run-flakelet-relay> agent/app | flakelet-relay-job-app.service: Deactivated successfully. container-test-run-flakelet-relay> » agent/app: updated (generation 5) container-test-run-flakelet-relay> » ok container-test-run-flakelet-relay> container-test-run-flakelet-relay> » job 9c34d306b5e457f2f406e05a8fbb7e1f 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 6 container-test-run-flakelet-relay> agent/app | app: updated to generation 6 container-test-run-flakelet-relay> agent/app | flakelet-relay-job-app.service: Deactivated successfully. container-test-run-flakelet-relay> » agent/app: updated (generation 6) container-test-run-flakelet-relay> » ok container-test-run-flakelet-relay> container-test-run-flakelet-relay> » job c46a149e7623dfbfd5aecd8bb1d94a27 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> flakelet-push: stream: error reading a body from connection, resuming job f7683a0ce29721a893dd359ba17cba13 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 c46a149e7623dfbfd5aecd8bb1d94a27 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 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> 1788675325 hist1 x509:email:admin@example.org agent/other:unchanged container-test-run-flakelet-relay> 1788675316 f7683a0ce29721a893dd359ba17cba13 x509:email:admin@example.org agent/app:updated container-test-run-flakelet-relay> 1788675306 eb5c779a430282edcb4d478bee9f7140 x509:email:admin@example.org agent/app:updated container-test-run-flakelet-relay> 1788675297 d050d46ecd9126c68d4b97f049ef602e x509:email:admin@example.org agent/app:updated container-test-run-flakelet-relay> 1788675296 8ace4d79cbf786d8c9ea4c19127dfe18 x509:email:admin@example.org agent/other:unchanged container-test-run-flakelet-relay> 1788675284 62394e456da1426a48dd2e78304317f4 x509:email:admin@example.org agent/app:updated container-test-run-flakelet-relay> 1788675279 3bfbb9f9530087af5ec2c6b46ae89995 oidc:mock:groups:wheel agent/other:unchanged container-test-run-flakelet-relay> 1788675279 9bf48c7cda48d19e71679ef5253466d5 x509:email:admin@example.org agent/app:rolled-back container-test-run-flakelet-relay> 1788675278 d35781d1578a43dcce2bdef9fe8387f9 oidc:mock:groups:wheel agent/other:unchanged container-test-run-flakelet-relay> 1788675275 4398d84dff3af5c7a22b44068a165fe8 oidc:mock:groups:wheel agent/other:unchanged container-test-run-flakelet-relay> 1788675272 2ce159da99576c3c57adebdf891c528e oidc:mock:repo:github:example/app:ref:refs/heads/main agent/app:updated container-test-run-flakelet-relay> 1788675269 d87e7fa6c6fd40d99a781b8f1ed6bb6b x509:email: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