these 36 derivations will be built: /nix/store/4la77r7v1zb98prrm4pbvi7ki4a7ab4m-unit-flakelet-agent.service.drv /nix/store/mkz6cha39gc58186vyshlznyxm6y85i8-system-units.drv /nix/store/cqknnhv62l2mjmd7vngh79qawiy23jza-etc.drv /nix/store/lzfq8fri8j9x3c639grnn5zj4nsmw3nj-activate.drv /nix/store/0dz9fa99rh0ws06dv16fv9adq5wj2bjy-nixos-system-agent-test.drv /nix/store/z492vb4h90rgfnwv82ag6nv4a7mn3lfk-push.drv /nix/store/6phyyabv2xy1vzhzi69fivqcmx7w7r8k-system-path.drv /nix/store/akzjhf12xhc4zm85140qvyk02y6z4d5v-dbus-1.drv /nix/store/mm01zkmxyasz32sdff34z3mqd10mbi9m-X-Restart-Triggers-dbus-broker.drv /nix/store/29nm1iivy9zfswljzifslaw192pw4wvs-unit-dbus-broker.service.drv /nix/store/9y80m5i11s6fkxiswh2rbm4kckx7v865-system-units.drv /nix/store/fcznmivg6a6m2lz4vmbjfp5y3ypx042h-unit-dbus-broker.service.drv /nix/store/lg9m3qfg8v5x8xz4k0qrh5rgman0xibm-user-units.drv /nix/store/jq1h75jp4ndfhc6aja2zi5ggbkcy93gb-etc.drv /nix/store/dcc9hqmrj16ha19wdkf2xsg8bwn61gvj-activate.drv /nix/store/iin91f2hb7264q8rdifi2jbl6sh2l2ff-nixos-system-client-test.drv /nix/store/17jsdnv5jk3nk4ym373wf2zy71s8gdhc-run-client-nspawn.drv /nix/store/qvv68clyjll371nnjas3wbmbq67832r7-flakelet-relay-0.1.0.drv /nix/store/rad5dl8ww6x45icg4ikmvxxqpyv5p9b4-flakelet-relay.json.drv /nix/store/fansd5c798b73d5anvxhavmsp6h2kpp5-flakelet-relay.service.drv /nix/store/1amvcppnf2iy50g7kqfjgiigaihhgckm-flakelet-flakelet-relay-units.drv /nix/store/aal9q2yiasrxja8q20rqc89ah4pnxg0l-flakelet-flakelet-relay.drv /nix/store/d7v40i3jbai9fjdjzb74y5gvmkcg9z6z-flakelet-config.json.drv /nix/store/zjc0r8h84rvmcgbfg0s612lbljsfp6fp-X-Restart-Triggers-flakelet-flakelet-relay.drv /nix/store/3d6jxx9x28r6gipfhax7hp77bg5qys8m-unit-flakelet-flakelet-relay.service.drv /nix/store/ywcir72nd9ji9xhcmgl92qq7g3h1k2wc-X-Restart-Triggers-flakelet-reconcile.drv /nix/store/ybh1li2v16gw09zh2pwv46ardhzvdzsy-unit-flakelet-reconcile.service.drv /nix/store/dc256ghryh2739dqh23mgjka3qrqx9bp-system-units.drv /nix/store/3wc5ijym15zjbix2az62ncm03qpzd596-etc.drv /nix/store/f366s9yh3ngsr20x798ihkizyf38yai1-activate.drv /nix/store/1xb5lwpa2pxkqcwi440720ywwizb03dk-nixos-system-relay-test.drv /nix/store/2nm62a7pwwg6cwq5pr7icz1bj6i42adq-run-agent-nspawn.drv /nix/store/4g4prv8bsjawcq9kn5a63li63226l0qs-run-relay-nspawn.drv /nix/store/8fgam2phnfsg420rk28pwcd5jsk7s313-driverConfiguration.json.drv /nix/store/5m9jmvk1jsh67ns4r1i2820vi8py0k9s-nixos-test-driver-flakelet-relay.drv /nix/store/rdlnwn8q3vrln14m2ldjmgw4z0dd34dj-container-test-run-flakelet-relay.drv building '/nix/store/z492vb4h90rgfnwv82ag6nv4a7mn3lfk-push.drv' building '/nix/store/4la77r7v1zb98prrm4pbvi7ki4a7ab4m-unit-flakelet-agent.service.drv' building '/nix/store/zjc0r8h84rvmcgbfg0s612lbljsfp6fp-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/qvv68clyjll371nnjas3wbmbq67832r7-flakelet-relay-0.1.0.drv' flakelet-relay-0.1.0> tribuchet: building on jamie flakelet-relay-0.1.0> Running phase: unpackPhase flakelet-relay-0.1.0> unpacking source archive /nix/store/78nf5ba57fm10rqf3ph2gcz7f3lmqx30-source flakelet-relay-0.1.0> source root is source flakelet-relay-0.1.0> Executing cargoSetupPostUnpackHook building '/nix/store/3d6jxx9x28r6gipfhax7hp77bg5qys8m-unit-flakelet-flakelet-relay.service.drv' unit-flakelet-flakelet-relay.service> tribuchet: building on jamie unit-flakelet-agent.service> structuredAttrs is enabled unit-flakelet-flakelet-relay.service> structuredAttrs is enabled building '/nix/store/mkz6cha39gc58186vyshlznyxm6y85i8-system-units.drv' system-units> tribuchet: building on jamie 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 building '/nix/store/6phyyabv2xy1vzhzi69fivqcmx7w7r8k-system-path.drv' system-path> tribuchet: building on jamie flakelet-relay-0.1.0> Compiling proc-macro2 v1.0.107 flakelet-relay-0.1.0> Compiling quote v1.0.47 flakelet-relay-0.1.0> Compiling unicode-ident v1.0.24 flakelet-relay-0.1.0> Compiling libc v0.2.189 flakelet-relay-0.1.0> Compiling autocfg v1.5.1 flakelet-relay-0.1.0> Compiling itoa v1.0.18 flakelet-relay-0.1.0> Compiling find-msvc-tools v0.1.11 flakelet-relay-0.1.0> Compiling shlex v2.0.1 flakelet-relay-0.1.0> Compiling memchr v2.8.3 flakelet-relay-0.1.0> Compiling bytes v1.12.1 flakelet-relay-0.1.0> Compiling cfg-if v1.0.4 flakelet-relay-0.1.0> Compiling once_cell v1.21.4 flakelet-relay-0.1.0> Compiling pin-project-lite v0.2.17 flakelet-relay-0.1.0> Compiling minimal-lexical v0.2.1 flakelet-relay-0.1.0> Compiling time-core v0.1.9 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 num-conv v0.2.2 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 powerfmt v0.2.0 flakelet-relay-0.1.0> Compiling deranged v0.5.8 flakelet-relay-0.1.0> Compiling zmij v1.0.23 flakelet-relay-0.1.0> Compiling httparse v1.10.1 flakelet-relay-0.1.0> Compiling log v0.4.34 flakelet-relay-0.1.0> Compiling cc v1.4.4 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 time-macros v0.2.32 flakelet-relay-0.1.0> Compiling tracing-core v0.1.36 flakelet-relay-0.1.0> Compiling serde v1.0.229 flakelet-relay-0.1.0> Compiling untrusted v0.9.0 flakelet-relay-0.1.0> Compiling futures-core v0.3.34 flakelet-relay-0.1.0> Compiling anstyle-parse v1.0.0 flakelet-relay-0.1.0> Compiling serde_json v1.0.151 flakelet-relay-0.1.0> Compiling colorchoice v1.0.5 flakelet-relay-0.1.0> Compiling proc-macro2-diagnostics v0.10.1 flakelet-relay-0.1.0> Compiling oid-registry v0.8.1 flakelet-relay-0.1.0> Compiling rustls v0.23.43 flakelet-relay-0.1.0> Compiling regex-syntax v0.8.11 flakelet-relay-0.1.0> Compiling anstyle-query v1.1.5 flakelet-relay-0.1.0> Compiling smallvec v1.16.0 flakelet-relay-0.1.0> Compiling num-traits v0.2.19 flakelet-relay-0.1.0> Compiling lazy_static v1.5.0 system-path> structuredAttrs is enabled flakelet-relay-0.1.0> Compiling anstyle v1.0.14 flakelet-relay-0.1.0> Compiling is_terminal_polyfill v1.70.2 flakelet-relay-0.1.0> Compiling try-lock v0.2.5 flakelet-relay-0.1.0> Compiling futures-channel v0.3.34 flakelet-relay-0.1.0> Compiling nom v7.1.3 flakelet-relay-0.1.0> Compiling want v0.3.1 flakelet-relay-0.1.0> Compiling anstream v1.0.0 flakelet-relay-0.1.0> Compiling subtle v2.6.1 flakelet-relay-0.1.0> Compiling http v1.5.0 flakelet-relay-0.1.0> Compiling heck v0.5.0 flakelet-relay-0.1.0> Compiling clap_lex v1.1.0 flakelet-relay-0.1.0> Compiling httpdate v1.0.3 flakelet-relay-0.1.0> Compiling atomic-waker v1.1.2 flakelet-relay-0.1.0> Compiling strsim v0.11.1 flakelet-relay-0.1.0> Compiling tracing-log v0.2.0 flakelet-relay-0.1.0> Compiling sharded-slab v0.1.7 flakelet-relay-0.1.0> Compiling thread_local v1.1.10 system-path> created 6399 symlinks in user environment flakelet-relay-0.1.0> Compiling data-encoding v2.11.1 flakelet-relay-0.1.0> Compiling nu-ansi-term v0.50.3 flakelet-relay-0.1.0> Compiling clap_builder v4.6.6 flakelet-relay-0.1.0> Compiling webpki-roots v1.0.9 flakelet-relay-0.1.0> Compiling base64 v0.23.1 flakelet-relay-0.1.0> Compiling syn v3.0.4 flakelet-relay-0.1.0> Compiling syn v2.0.119 flakelet-relay-0.1.0> Compiling ring v0.17.14 flakelet-relay-0.1.0> Compiling http-body v1.1.0 flakelet-relay-0.1.0> Compiling errno v0.3.14 flakelet-relay-0.1.0> Compiling getrandom v0.2.17 flakelet-relay-0.1.0> Compiling mio v1.2.2 flakelet-relay-0.1.0> Compiling socket2 v0.6.5 flakelet-relay-0.1.0> Compiling sd-notify v0.5.0 flakelet-relay-0.1.0> Compiling time v0.3.55 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 signal-hook-registry v1.4.8 flakelet-relay-0.1.0> Compiling num-integer v0.1.47 flakelet-relay-0.1.0> Compiling num-bigint v0.4.8 building '/nix/store/cqknnhv62l2mjmd7vngh79qawiy23jza-etc.drv' flakelet-relay-0.1.0> Compiling rusticata-macros v4.1.0 etc> tribuchet: building on jamie building '/nix/store/akzjhf12xhc4zm85140qvyk02y6z4d5v-dbus-1.drv' dbus-1> tribuchet: building on jamie flakelet-relay-0.1.0> Compiling matchers v0.2.0 flakelet-relay-0.1.0> Compiling thiserror-impl v2.0.20 flakelet-relay-0.1.0> Compiling displaydoc v0.2.7 flakelet-relay-0.1.0> Compiling tokio-macros v2.7.2 building '/nix/store/lzfq8fri8j9x3c639grnn5zj4nsmw3nj-activate.drv' activate> tribuchet: building on jamie flakelet-relay-0.1.0> Compiling serde_derive v1.0.229 flakelet-relay-0.1.0> Compiling clap_derive v4.6.4 flakelet-relay-0.1.0> Compiling synstructure v0.13.2 building '/nix/store/mm01zkmxyasz32sdff34z3mqd10mbi9m-X-Restart-Triggers-dbus-broker.drv' X-Restart-Triggers-dbus-broker> tribuchet: building on jamie flakelet-relay-0.1.0> Compiling asn1-rs-derive v0.6.0 flakelet-relay-0.1.0> Compiling asn1-rs-impl v0.2.0 flakelet-relay-0.1.0> Compiling tracing-attributes v0.1.31 flakelet-relay-0.1.0> Compiling maud_macros v0.27.0 building '/nix/store/0dz9fa99rh0ws06dv16fv9adq5wj2bjy-nixos-system-agent-test.drv' nixos-system-agent-test> tribuchet: building on jamie flakelet-relay-0.1.0> Compiling tokio v1.53.1 building '/nix/store/29nm1iivy9zfswljzifslaw192pw4wvs-unit-dbus-broker.service.drv' building '/nix/store/fcznmivg6a6m2lz4vmbjfp5y3ypx042h-unit-dbus-broker.service.drv' nixos-system-agent-test> structuredAttrs is enabled unit-dbus-broker.service> tribuchet: building on jamie unit-dbus-broker.service> tribuchet: building on jamie unit-dbus-broker.service> structuredAttrs is enabled unit-dbus-broker.service> structuredAttrs is enabled building '/nix/store/2nm62a7pwwg6cwq5pr7icz1bj6i42adq-run-agent-nspawn.drv' run-agent-nspawn> tribuchet: building on jamie flakelet-relay-0.1.0> Compiling clap v4.6.6 flakelet-relay-0.1.0> Compiling maud v0.27.0 building '/nix/store/lg9m3qfg8v5x8xz4k0qrh5rgman0xibm-user-units.drv' building '/nix/store/9y80m5i11s6fkxiswh2rbm4kckx7v865-system-units.drv' user-units> tribuchet: building on jamie system-units> tribuchet: building on jamie flakelet-relay-0.1.0> Compiling asn1-rs v0.7.2 flakelet-relay-0.1.0> Compiling tracing v0.1.44 flakelet-relay-0.1.0> Compiling tracing-serde v0.2.0 flakelet-relay-0.1.0> Compiling tracing-subscriber v0.3.23 flakelet-relay-0.1.0> Compiling der-parser v10.0.0 flakelet-relay-0.1.0> Compiling x509-parser v0.18.1 flakelet-relay-0.1.0> Compiling rustls-webpki v0.103.15 building '/nix/store/jq1h75jp4ndfhc6aja2zi5ggbkcy93gb-etc.drv' etc> tribuchet: building on jamie flakelet-relay-0.1.0> Compiling hyper v1.11.1 flakelet-relay-0.1.0> Compiling hyper-util v0.1.20 building '/nix/store/dcc9hqmrj16ha19wdkf2xsg8bwn61gvj-activate.drv' activate> tribuchet: building on jamie building '/nix/store/iin91f2hb7264q8rdifi2jbl6sh2l2ff-nixos-system-client-test.drv' nixos-system-client-test> tribuchet: building on jamie nixos-system-client-test> structuredAttrs is enabled flakelet-relay-0.1.0> Compiling tokio-rustls v0.26.4 flakelet-relay-0.1.0> Compiling flakelet-relay v0.1.0 (/build/source) building '/nix/store/17jsdnv5jk3nk4ym373wf2zy71s8gdhc-run-client-nspawn.drv' run-client-nspawn> tribuchet: building on jamie flakelet-relay-0.1.0> Finished `release` profile [optimized] target(s) in 27.41s 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.05s 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 19 tests flakelet-relay-0.1.0> test auth::policy::tests::globbing ... ok flakelet-relay-0.1.0> test client::tests::urls ... ok flakelet-relay-0.1.0> test auth::policy::tests::validate_catches_unknown_group ... ok flakelet-relay-0.1.0> test auth::policy::tests::deploy_rules ... ok flakelet-relay-0.1.0> test auth::policy::tests::agent_identity ... ok flakelet-relay-0.1.0> test oidc::tests::encodes_forms ... ok flakelet-relay-0.1.0> test proto::tests::target_split_rejects_malformed ... ok flakelet-relay-0.1.0> test relay::pages::tests::short_rev_uses_rev_not_nar_hash ... ok flakelet-relay-0.1.0> test relay::pages::tests::filter_terms_words_and_toggle ... ok flakelet-relay-0.1.0> test proto::tests::unknown_frame_type_is_tolerated ... ok flakelet-relay-0.1.0> test relay::session::tests::roundtrip_and_tamper ... ok flakelet-relay-0.1.0> test srv::tests::parses_compressed_srv ... 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 ws::tests::rfc_example_accept_key ... ok flakelet-relay-0.1.0> test proto::tests::done_roundtrip_is_flat ... 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. 19 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/005jv7ixxs62bb6x4gw117q3zr4aw1rf-flakelet-relay-0.1.0 flakelet-relay-0.1.0> shrinking /nix/store/005jv7ixxs62bb6x4gw117q3zr4aw1rf-flakelet-relay-0.1.0/bin/flakelet-push flakelet-relay-0.1.0> shrinking /nix/store/005jv7ixxs62bb6x4gw117q3zr4aw1rf-flakelet-relay-0.1.0/bin/flakelet-agent flakelet-relay-0.1.0> shrinking /nix/store/005jv7ixxs62bb6x4gw117q3zr4aw1rf-flakelet-relay-0.1.0/bin/flakelet-relay flakelet-relay-0.1.0> checking for references to /build/ in /nix/store/005jv7ixxs62bb6x4gw117q3zr4aw1rf-flakelet-relay-0.1.0... flakelet-relay-0.1.0> patching script interpreter paths in /nix/store/005jv7ixxs62bb6x4gw117q3zr4aw1rf-flakelet-relay-0.1.0 flakelet-relay-0.1.0> stripping (with command strip and flags -S -p) in /nix/store/005jv7ixxs62bb6x4gw117q3zr4aw1rf-flakelet-relay-0.1.0/bin building '/nix/store/rad5dl8ww6x45icg4ikmvxxqpyv5p9b4-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/fansd5c798b73d5anvxhavmsp6h2kpp5-flakelet-relay.service.drv' flakelet-relay.service> tribuchet: building on jamie building '/nix/store/1amvcppnf2iy50g7kqfjgiigaihhgckm-flakelet-flakelet-relay-units.drv' flakelet-flakelet-relay-units> tribuchet: building on jamie building '/nix/store/aal9q2yiasrxja8q20rqc89ah4pnxg0l-flakelet-flakelet-relay.drv' flakelet-flakelet-relay> tribuchet: building on jamie building '/nix/store/d7v40i3jbai9fjdjzb74y5gvmkcg9z6z-flakelet-config.json.drv' flakelet-config.json> tribuchet: building on jamie flakelet-config.json> structuredAttrs is enabled building '/nix/store/ywcir72nd9ji9xhcmgl92qq7g3h1k2wc-X-Restart-Triggers-flakelet-reconcile.drv' X-Restart-Triggers-flakelet-reconcile> tribuchet: building on jamie building '/nix/store/ybh1li2v16gw09zh2pwv46ardhzvdzsy-unit-flakelet-reconcile.service.drv' unit-flakelet-reconcile.service> tribuchet: building on jamie unit-flakelet-reconcile.service> structuredAttrs is enabled building '/nix/store/dc256ghryh2739dqh23mgjka3qrqx9bp-system-units.drv' system-units> tribuchet: building on jamie building '/nix/store/3wc5ijym15zjbix2az62ncm03qpzd596-etc.drv' etc> tribuchet: building on jamie building '/nix/store/f366s9yh3ngsr20x798ihkizyf38yai1-activate.drv' activate> tribuchet: building on jamie building '/nix/store/1xb5lwpa2pxkqcwi440720ywwizb03dk-nixos-system-relay-test.drv' nixos-system-relay-test> tribuchet: building on jamie nixos-system-relay-test> structuredAttrs is enabled building '/nix/store/4g4prv8bsjawcq9kn5a63li63226l0qs-run-relay-nspawn.drv' run-relay-nspawn> tribuchet: building on jamie building '/nix/store/8fgam2phnfsg420rk28pwcd5jsk7s313-driverConfiguration.json.drv' driverConfiguration.json> tribuchet: building on jamie driverConfiguration.json> structuredAttrs is enabled building '/nix/store/5m9jmvk1jsh67ns4r1i2820vi8py0k9s-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/rdlnwn8q3vrln14m2ldjmgw4z0dd34dj-container-test-run-flakelet-relay.drv' container-test-run-flakelet-relay> tribuchet: building on jamie container-test-run-flakelet-relay> Machine state will be reset. To keep it, pass --keep-machine-state container-test-run-flakelet-relay> start all VLans container-test-run-flakelet-relay> (finished: start all VLans, in 0.00 seconds) container-test-run-flakelet-relay> Initializing machine ID from random generator. container-test-run-flakelet-relay> Test will time out and terminate in 3600.0 seconds container-test-run-flakelet-relay> run the VM test script container-test-run-flakelet-relay> start all VMs container-test-run-flakelet-relay> agent: systemd-nspawn running (pid 51) container-test-run-flakelet-relay> agent: Waiting for journal at /build/vm-state-agent/var/log/journal... container-test-run-flakelet-relay> client: systemd-nspawn running (pid 53) container-test-run-flakelet-relay> relay: systemd-nspawn running (pid 56) 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.01 seconds) container-test-run-flakelet-relay> relay: waiting for unit flakelet-relay.service container-test-run-flakelet-relay> nixos-nspawn(relay): TAP vde-tap1 not found; container will be isolated from VDE container-test-run-flakelet-relay> nixos-nspawn(relay): A common reason for this is that /dev/net is not available in the Nix sandbox. Try adding /dev/net to extra-sandbox-paths. container-test-run-flakelet-relay> nixos-nspawn(agent): TAP vde-tap1 not found; container will be isolated from VDE container-test-run-flakelet-relay> nixos-nspawn(agent): A common reason for this is that /dev/net is not available in the Nix sandbox. Try adding /dev/net to extra-sandbox-paths. container-test-run-flakelet-relay> nixos-nspawn(client): TAP vde-tap1 not found; container will be isolated from VDE container-test-run-flakelet-relay> nixos-nspawn(client): A common reason for this is that /dev/net is not available in the Nix sandbox. Try adding /dev/net to extra-sandbox-paths. container-test-run-flakelet-relay> Note: in a future version of systemd-nspawn the default set of permitted socket address families will be restricted to AF_INET, AF_INET6 and AF_UNIX. Use --restrict-address-families= to configure the set of permitted socket address families, or set RestrictAddressFamilies= in a .nspawn file. container-test-run-flakelet-relay> Note: in a future version of systemd-nspawn the default set of permitted socket address families will be restricted to AF_INET, AF_INET6 and AF_UNIX. Use --restrict-address-families= to configure the set of permitted socket address families, or set RestrictAddressFamilies= in a .nspawn file. container-test-run-flakelet-relay> Note: in a future version of systemd-nspawn the default set of permitted socket address families will be restricted to AF_INET, AF_INET6 and AF_UNIX. Use --restrict-address-families= to configure the set of permitted socket address families, or set RestrictAddressFamilies= in a .nspawn file. container-test-run-flakelet-relay> ░ Spawning container client on /build/vm-state-client. container-test-run-flakelet-relay> ░ Spawning container relay on /build/vm-state-relay. container-test-run-flakelet-relay> ░ Spawning container agent on /build/vm-state-agent. container-test-run-flakelet-relay> agent # No journal files were found. container-test-run-flakelet-relay> agent # No journal boot entry found for the specified boot (+0). container-test-run-flakelet-relay> client # No journal files were found. container-test-run-flakelet-relay> client # No journal boot entry found for the specified boot (+0). container-test-run-flakelet-relay> relay # No journal files were found. container-test-run-flakelet-relay> relay # No journal boot entry found for the specified boot (+0). container-test-run-flakelet-relay> agent # [ 2215.097228] agent systemd-journald[57]: Journal started container-test-run-flakelet-relay> agent # [ 2215.097274] agent systemd-journald[57]: Runtime Journal (/run/log/journal/abd373ab89a047b7b10dbda421974404) is 8M, max 4G, 3.9G free. container-test-run-flakelet-relay> agent # [ 2215.107096] agent systemd[1]: Finished Apply Kernel Variables. container-test-run-flakelet-relay> agent # [ 2215.119327] agent systemd[1]: Finished Create Static Device Nodes in /dev gracefully. container-test-run-flakelet-relay> agent # [ 2215.133471] agent systemd[1]: Starting Flush Journal to Persistent Storage... container-test-run-flakelet-relay> agent # [ 2215.134353] agent systemd[1]: Starting Create Static Device Nodes in /dev... container-test-run-flakelet-relay> agent # [ 2215.172144] agent systemd-journald[57]: Time spent on flushing to /var/log/journal/abd373ab89a047b7b10dbda421974404 is 1.527ms for 6 entries. container-test-run-flakelet-relay> agent # [ 2215.172144] agent systemd-journald[57]: System Journal (/var/log/journal/abd373ab89a047b7b10dbda421974404) is 512B, max 4G, 3.9G free. container-test-run-flakelet-relay> agent # [ 2215.187253] agent systemd[1]: Finished Create Static Device Nodes in /dev. container-test-run-flakelet-relay> agent # [ 2215.187439] agent systemd[1]: Reached target Preparation for Local File Systems. container-test-run-flakelet-relay> agent # [ 2215.187528] agent systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys container-test-run-flakelet-relay> agent # [ 2215.194660] agent systemd[1]: Finished Flush Journal to Persistent Storage. container-test-run-flakelet-relay> agent # [ 2215.275247] agent systemd[1]: Finished Firewall. container-test-run-flakelet-relay> agent # [ 2216.122779] agent systemd[1]: Mounting /run/wrappers... container-test-run-flakelet-relay> agent # [ 2216.153773] agent systemd[1]: Mounted /run/wrappers. container-test-run-flakelet-relay> agent # [ 2216.154781] agent systemd[1]: Reached target Local File Systems. container-test-run-flakelet-relay> agent # [ 2216.155986] agent systemd[1]: Listening on Boot Loader Control Service Socket. container-test-run-flakelet-relay> agent # [ 2216.157367] agent systemd[1]: Starting Create SUID/SGID Wrappers... container-test-run-flakelet-relay> agent # [ 2216.157415] agent systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container container-test-run-flakelet-relay> agent # [ 2216.158661] agent systemd[1]: Starting Save Transient machine-id to Disk... container-test-run-flakelet-relay> agent # [ 2216.159822] agent systemd[1]: Starting Create System Files and Directories... container-test-run-flakelet-relay> relay # [ 2215.097229] relay systemd-journald[57]: Journal started container-test-run-flakelet-relay> client # [ 2215.097182] client systemd-journald[57]: Journal started container-test-run-flakelet-relay> relay # [ 2215.097278] relay systemd-journald[57]: Runtime Journal (/run/log/journal/54d062c109234c548d59063a4908e468) is 8M, max 4G, 3.9G free. container-test-run-flakelet-relay> client # [ 2215.097275] client systemd-journald[57]: Runtime Journal (/run/log/journal/7ab7bbd09cb943bca1048377b982ca61) is 8M, max 4G, 3.9G free. container-test-run-flakelet-relay> relay # [ 2215.107220] relay systemd[1]: Finished Apply Kernel Variables. container-test-run-flakelet-relay> client # [ 2215.107895] client systemd[1]: Finished Apply Kernel Variables. container-test-run-flakelet-relay> relay # [ 2215.119545] relay systemd[1]: Finished Create Static Device Nodes in /dev gracefully. container-test-run-flakelet-relay> client # [ 2215.119992] client systemd[1]: Finished Create Static Device Nodes in /dev gracefully. container-test-run-flakelet-relay> relay # [ 2215.134092] relay systemd[1]: Starting Flush Journal to Persistent Storage... container-test-run-flakelet-relay> client # [ 2215.134346] client systemd[1]: Starting Flush Journal to Persistent Storage... container-test-run-flakelet-relay> relay # [ 2215.134652] relay systemd[1]: Starting Create Static Device Nodes in /dev... container-test-run-flakelet-relay> client # [ 2215.134873] client systemd[1]: Starting Create Static Device Nodes in /dev... container-test-run-flakelet-relay> relay # [ 2215.172151] relay systemd-journald[57]: Time spent on flushing to /var/log/journal/54d062c109234c548d59063a4908e468 is 1.467ms for 6 entries. container-test-run-flakelet-relay> client # [ 2215.172362] client systemd-journald[57]: Time spent on flushing to /var/log/journal/7ab7bbd09cb943bca1048377b982ca61 is 2.598ms for 6 entries. container-test-run-flakelet-relay> relay # [ 2215.172151] relay systemd-journald[57]: System Journal (/var/log/journal/54d062c109234c548d59063a4908e468) is 512B, max 4G, 3.9G free. container-test-run-flakelet-relay> client # [ 2215.172362] client systemd-journald[57]: System Journal (/var/log/journal/7ab7bbd09cb943bca1048377b982ca61) is 512B, max 4G, 3.9G free. container-test-run-flakelet-relay> client # [ 2215.180866] client systemd[1]: Finished Create Static Device Nodes in /dev. container-test-run-flakelet-relay> client # [ 2215.181540] client systemd[1]: Reached target Preparation for Local File Systems. container-test-run-flakelet-relay> relay # [ 2215.180945] relay systemd[1]: Finished Create Static Device Nodes in /dev. container-test-run-flakelet-relay> client # [ 2215.181692] client systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys container-test-run-flakelet-relay> client # [ 2215.200864] client systemd[1]: Finished Flush Journal to Persistent Storage. container-test-run-flakelet-relay> relay # [ 2215.181598] relay systemd[1]: Reached target Preparation for Local File Systems. container-test-run-flakelet-relay> client # [ 2215.275987] client systemd[1]: Finished Firewall. container-test-run-flakelet-relay> relay # [ 2215.181742] relay systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys container-test-run-flakelet-relay> client # [ 2216.122668] client systemd[1]: Mounting /run/wrappers... container-test-run-flakelet-relay> relay # [ 2215.198014] relay systemd[1]: Finished Flush Journal to Persistent Storage. container-test-run-flakelet-relay> relay # [ 2215.299254] relay systemd[1]: Finished Firewall. container-test-run-flakelet-relay> client # [ 2216.158422] client systemd[1]: Mounted /run/wrappers. container-test-run-flakelet-relay> client # [ 2216.159464] client systemd[1]: Reached target Local File Systems. container-test-run-flakelet-relay> client # [ 2216.160797] client systemd[1]: Listening on Boot Loader Control Service Socket. container-test-run-flakelet-relay> client # [ 2216.162160] client systemd[1]: Starting Create SUID/SGID Wrappers... container-test-run-flakelet-relay> client # [ 2216.162209] client systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container container-test-run-flakelet-relay> relay # [ 2216.095780] relay systemd[1]: Mounting /run/wrappers... container-test-run-flakelet-relay> client # [ 2216.163337] client systemd[1]: Starting Save Transient machine-id to Disk... container-test-run-flakelet-relay> relay # [ 2216.153204] relay systemd[1]: Mounted /run/wrappers. container-test-run-flakelet-relay> client # [ 2216.164276] client systemd[1]: Starting Create System Files and Directories... container-test-run-flakelet-relay> relay # [ 2216.154206] relay systemd[1]: Reached target Local File Systems. container-test-run-flakelet-relay> relay # [ 2216.155431] relay systemd[1]: Listening on Boot Loader Control Service Socket. container-test-run-flakelet-relay> relay # [ 2216.156962] relay systemd[1]: Starting Create SUID/SGID Wrappers... container-test-run-flakelet-relay> relay # [ 2216.157025] relay systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container container-test-run-flakelet-relay> relay # [ 2216.158444] relay systemd[1]: Starting Save Transient machine-id to Disk... container-test-run-flakelet-relay> relay # [ 2216.159645] relay systemd[1]: Starting Create System Files and Directories... container-test-run-flakelet-relay> agent # [ 2216.178047] 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 # [ 2216.178463] agent systemd-tmpfiles[161]: fchmod() of /var/log/journal failed: Operation not permitted container-test-run-flakelet-relay> agent # [ 2216.178777] agent systemd-tmpfiles[161]: fchmod() of /var/log/journal/abd373ab89a047b7b10dbda421974404 failed: Operation not permitted container-test-run-flakelet-relay> agent # [ 2216.179135] agent systemd-tmpfiles[161]: fchmod() of /run/log/journal failed: Operation not permitted container-test-run-flakelet-relay> relay # [ 2216.179178] relay suid-sgid-wrappers-start[176]: chmod: changing permissions of '/run/wrappers/wrappers.gteRDmCLYN/chsh': Operation not permitted container-test-run-flakelet-relay> relay # [ 2216.179319] 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 # [ 2216.179730] relay systemd-tmpfiles[169]: fchmod() of /var/log/journal failed: Operation not permitted container-test-run-flakelet-relay> relay # [ 2216.180050] relay systemd-tmpfiles[169]: fchmod() of /var/log/journal/54d062c109234c548d59063a4908e468 failed: Operation not permitted container-test-run-flakelet-relay> relay # [ 2216.180371] relay systemd-tmpfiles[169]: fchmod() of /run/log/journal failed: Operation not permitted container-test-run-flakelet-relay> client # [ 2216.183292] 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 # [ 2216.183695] client systemd-tmpfiles[161]: fchmod() of /var/log/journal failed: Operation not permitted container-test-run-flakelet-relay> client # [ 2216.183990] client systemd-tmpfiles[161]: fchmod() of /var/log/journal/7ab7bbd09cb943bca1048377b982ca61 failed: Operation not permitted container-test-run-flakelet-relay> client # [ 2216.184342] client systemd-tmpfiles[161]: fchmod() of /run/log/journal failed: Operation not permitted container-test-run-flakelet-relay> relay # [ 2216.244540] relay systemd[1]: etc-machine\x2did.mount: Deactivated successfully. container-test-run-flakelet-relay> relay # [ 2216.261200] relay systemd[1]: suid-sgid-wrappers.service: Main process exited, code=exited, status=1/FAILURE container-test-run-flakelet-relay> relay # [ 2216.261237] relay systemd[1]: suid-sgid-wrappers.service: Failed with result 'exit-code'. container-test-run-flakelet-relay> relay # [ 2216.261303] relay systemd[1]: Failed to start Create SUID/SGID Wrappers. container-test-run-flakelet-relay> relay # [ 2216.261447] relay systemd[1]: Finished Save Transient machine-id to Disk. container-test-run-flakelet-relay> relay # [ 2216.261943] relay systemd[1]: Finished Create System Files and Directories. container-test-run-flakelet-relay> relay # [ 2216.295762] relay systemd[1]: Starting Rebuild Journal Catalog... container-test-run-flakelet-relay> relay # [ 2216.296510] relay systemd[1]: Starting Record System Boot/Shutdown in UTMP... container-test-run-flakelet-relay> relay # [ 2216.308730] relay systemd[1]: Finished Record System Boot/Shutdown in UTMP. container-test-run-flakelet-relay> relay # [ 2216.315185] relay systemd[1]: Finished Rebuild Journal Catalog. container-test-run-flakelet-relay> relay # [ 2216.316448] relay systemd[1]: Starting Update is Completed... container-test-run-flakelet-relay> relay # [ 2216.324798] relay systemd[1]: Finished Update is Completed. container-test-run-flakelet-relay> relay # [ 2216.324920] relay systemd[1]: Reached target System Initialization. container-test-run-flakelet-relay> relay # [ 2216.325033] relay systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container container-test-run-flakelet-relay> relay # [ 2216.325076] relay systemd[1]: Started Daily Cleanup of Temporary Directories. container-test-run-flakelet-relay> relay # [ 2216.325104] relay systemd[1]: Reached target Timer Units. container-test-run-flakelet-relay> relay # [ 2216.325259] relay systemd[1]: Listening on D-Bus System Message Bus Socket. container-test-run-flakelet-relay> relay # [ 2216.325535] relay systemd[1]: Listening on Nix Daemon Socket. container-test-run-flakelet-relay> relay # [ 2216.325792] relay systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. container-test-run-flakelet-relay> relay # [ 2216.325820] relay systemd[1]: Reached target Socket Units. container-test-run-flakelet-relay> relay # [ 2216.325874] relay systemd[1]: Reached target Basic System. container-test-run-flakelet-relay> relay # [ 2216.327741] relay systemd[1]: Started device-mock.service. container-test-run-flakelet-relay> relay # [ 2216.328646] relay systemd[1]: Starting Re-link flakelet services at boot... container-test-run-flakelet-relay> relay # [ 2216.329531] relay systemd[1]: Starting Import lastlog data into lastlog2 database... container-test-run-flakelet-relay> relay # [ 2216.330756] relay systemd[1]: Starting Name Service Cache Daemon (nsncd)... container-test-run-flakelet-relay> relay # [ 2216.331793] relay systemd[1]: Starting resolvconf update... container-test-run-flakelet-relay> relay # [ 2216.341331] relay systemd[1]: Finished Re-link flakelet services at boot. container-test-run-flakelet-relay> relay # [ 2216.342739] relay systemd[1]: Starting Reconcile flakelet services with the host configuration... container-test-run-flakelet-relay> relay # [ 2216.346873] relay systemd[1]: Finished Import lastlog data into lastlog2 database. container-test-run-flakelet-relay> client # [ 2216.186928] client suid-sgid-wrappers-start[168]: chmod: changing permissions of '/run/wrappers/wrappers.19jnUl2KfA/chsh': Operation not permitted container-test-run-flakelet-relay> client # [ 2216.246168] client systemd[1]: etc-machine\x2did.mount: Deactivated successfully. container-test-run-flakelet-relay> client # [ 2216.259722] client systemd[1]: suid-sgid-wrappers.service: Main process exited, code=exited, status=1/FAILURE container-test-run-flakelet-relay> client # [ 2216.259756] client systemd[1]: suid-sgid-wrappers.service: Failed with result 'exit-code'. container-test-run-flakelet-relay> client # [ 2216.259817] client systemd[1]: Failed to start Create SUID/SGID Wrappers. container-test-run-flakelet-relay> client # [ 2216.259952] client systemd[1]: Finished Save Transient machine-id to Disk. container-test-run-flakelet-relay> client # [ 2216.260393] client systemd[1]: Finished Create System Files and Directories. container-test-run-flakelet-relay> client # [ 2216.295547] client systemd[1]: Starting Rebuild Journal Catalog... container-test-run-flakelet-relay> client # [ 2216.296356] client systemd[1]: Starting Record System Boot/Shutdown in UTMP... container-test-run-flakelet-relay> client # [ 2216.308767] client systemd[1]: Finished Record System Boot/Shutdown in UTMP. container-test-run-flakelet-relay> client # [ 2216.314334] client systemd[1]: Finished Rebuild Journal Catalog. container-test-run-flakelet-relay> client # [ 2216.315215] client systemd[1]: Starting Update is Completed... container-test-run-flakelet-relay> client # [ 2216.323358] client systemd[1]: Finished Update is Completed. container-test-run-flakelet-relay> client # [ 2216.323449] client systemd[1]: Reached target System Initialization. container-test-run-flakelet-relay> client # [ 2216.323523] client systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container container-test-run-flakelet-relay> client # [ 2216.323552] client systemd[1]: Started Daily Cleanup of Temporary Directories. container-test-run-flakelet-relay> client # [ 2216.323565] client systemd[1]: Reached target Timer Units. container-test-run-flakelet-relay> client # [ 2216.323659] client systemd[1]: Listening on D-Bus System Message Bus Socket. container-test-run-flakelet-relay> client # [ 2216.323882] client systemd[1]: Listening on Nix Daemon Socket. container-test-run-flakelet-relay> client # [ 2216.323976] client systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. container-test-run-flakelet-relay> client # [ 2216.323991] client systemd[1]: Reached target Socket Units. container-test-run-flakelet-relay> client # [ 2216.324028] client systemd[1]: Reached target Basic System. container-test-run-flakelet-relay> client # [ 2216.325415] client systemd[1]: Starting Import lastlog data into lastlog2 database... container-test-run-flakelet-relay> client # [ 2216.326344] client systemd[1]: Starting Name Service Cache Daemon (nsncd)... container-test-run-flakelet-relay> client # [ 2216.327589] client systemd[1]: Starting resolvconf update... container-test-run-flakelet-relay> client # [ 2216.342794] client systemd[1]: Finished Import lastlog data into lastlog2 database. container-test-run-flakelet-relay> agent # [ 2216.181697] agent suid-sgid-wrappers-start[168]: chmod: changing permissions of '/run/wrappers/wrappers.tvKjzVXsy7/chsh': Operation not permitted container-test-run-flakelet-relay> agent # [ 2216.239361] agent systemd[1]: etc-machine\x2did.mount: Deactivated successfully. container-test-run-flakelet-relay> agent # [ 2216.253890] agent systemd[1]: suid-sgid-wrappers.service: Main process exited, code=exited, status=1/FAILURE container-test-run-flakelet-relay> agent # [ 2216.253988] agent systemd[1]: suid-sgid-wrappers.service: Failed with result 'exit-code'. container-test-run-flakelet-relay> agent # [ 2216.254176] agent systemd[1]: Failed to start Create SUID/SGID Wrappers. container-test-run-flakelet-relay> agent # [ 2216.254409] agent systemd[1]: Finished Save Transient machine-id to Disk. container-test-run-flakelet-relay> agent # [ 2216.254805] agent systemd[1]: Finished Create System Files and Directories. container-test-run-flakelet-relay> agent # [ 2216.269098] agent systemd[1]: Starting Rebuild Journal Catalog... container-test-run-flakelet-relay> agent # [ 2216.269993] agent systemd[1]: Starting Record System Boot/Shutdown in UTMP... container-test-run-flakelet-relay> agent # [ 2216.304989] agent systemd[1]: Finished Record System Boot/Shutdown in UTMP. container-test-run-flakelet-relay> agent # [ 2216.310990] agent systemd[1]: Finished Rebuild Journal Catalog. container-test-run-flakelet-relay> agent # [ 2216.312278] agent systemd[1]: Starting Update is Completed... container-test-run-flakelet-relay> agent # [ 2216.322608] agent systemd[1]: Finished Update is Completed. container-test-run-flakelet-relay> agent # [ 2216.322675] agent systemd[1]: Reached target System Initialization. container-test-run-flakelet-relay> agent # [ 2216.322736] agent systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container container-test-run-flakelet-relay> agent # [ 2216.322786] agent systemd[1]: Started Daily Cleanup of Temporary Directories. container-test-run-flakelet-relay> agent # [ 2216.322801] agent systemd[1]: Reached target Timer Units. container-test-run-flakelet-relay> agent # [ 2216.322893] agent systemd[1]: Listening on D-Bus System Message Bus Socket. container-test-run-flakelet-relay> agent # [ 2216.323079] agent systemd[1]: Listening on Nix Daemon Socket. container-test-run-flakelet-relay> agent # [ 2216.323172] agent systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. container-test-run-flakelet-relay> agent # [ 2216.323189] agent systemd[1]: Reached target Socket Units. container-test-run-flakelet-relay> agent # [ 2216.323216] agent systemd[1]: Reached target Basic System. container-test-run-flakelet-relay> agent # [ 2216.324339] agent systemd[1]: Starting Re-link flakelet services at boot... container-test-run-flakelet-relay> agent # [ 2216.325564] agent systemd[1]: Starting Import lastlog data into lastlog2 database... container-test-run-flakelet-relay> agent # [ 2216.326294] agent systemd[1]: Starting Name Service Cache Daemon (nsncd)... container-test-run-flakelet-relay> agent # [ 2216.327486] agent systemd[1]: Starting resolvconf update... container-test-run-flakelet-relay> agent # [ 2216.341292] agent systemd[1]: Finished Re-link flakelet services at boot. container-test-run-flakelet-relay> agent # [ 2216.341592] agent systemd[1]: Finished Import lastlog data into lastlog2 database. container-test-run-flakelet-relay> agent # [ 2216.343198] agent systemd[1]: Starting Reconcile flakelet services with the host configuration... container-test-run-flakelet-relay> agent # [ 2216.354057] agent systemd[1]: Finished Reconcile flakelet services with the host configuration. container-test-run-flakelet-relay> relay # [ 2216.352465] relay systemd[1]: Finished Reconcile flakelet services with the host configuration. container-test-run-flakelet-relay> relay # [ 2216.422205] relay systemd[1]: Finished resolvconf update. container-test-run-flakelet-relay> relay # [ 2216.422875] relay systemd[1]: Reached target Preparation for Network. container-test-run-flakelet-relay> relay # [ 2216.428458] relay systemd[1]: Starting Address configuration of eth1... container-test-run-flakelet-relay> relay # [ 2216.429161] relay systemd[1]: Starting Extra networking commands.... container-test-run-flakelet-relay> relay # [ 2216.458222] relay systemd[1]: nscd.service: Deactivated successfully. container-test-run-flakelet-relay> relay # [ 2216.458416] relay systemd[1]: Stopped Name Service Cache Daemon (nsncd). container-test-run-flakelet-relay> relay # [ 2216.461047] relay systemd[1]: Starting Name Service Cache Daemon (nsncd)... container-test-run-flakelet-relay> relay # [ 2216.476838] relay network-addresses-eth1-start[246]: adding address 192.168.1.3/24... done container-test-run-flakelet-relay> relay # [ 2216.480144] relay network-addresses-eth1-start[246]: adding address 2001:db8:1::3/64... done container-test-run-flakelet-relay> relay # [ 2216.484364] relay systemd[1]: Finished Address configuration of eth1. container-test-run-flakelet-relay> relay # [ 2216.518205] relay systemd[1]: Finished Extra networking commands.. container-test-run-flakelet-relay> relay # [ 2216.518761] relay systemd[1]: Reached target Network. container-test-run-flakelet-relay> relay # [ 2216.519973] relay systemd[1]: Starting Dnsmasq Daemon... container-test-run-flakelet-relay> relay # [ 2216.521230] relay systemd[1]: Starting Nginx Web Server... container-test-run-flakelet-relay> relay # [ 2216.522024] relay systemd[1]: Starting Update flakelet service flakelet-relay... container-test-run-flakelet-relay> relay # [ 2216.532802] relay flakelet[311]: flakelet-relay: using prebuilt artifact /nix/store/1pi896bkzdlzk47gyahw6qz1jmh9nwyc-flakelet-flakelet-relay container-test-run-flakelet-relay> relay # [ 2216.533981] relay flakelet[311]: flakelet-relay: activating generation 1 container-test-run-flakelet-relay> relay # [ 2216.547660] relay systemd[1]: Reload requested from client PID 314 ('systemctl') (unit flakelet-flakelet-relay.service)... container-test-run-flakelet-relay> relay # [ 2216.547696] relay systemd[1]: Reloading... container-test-run-flakelet-relay> client # [ 2216.401147] client systemd[1]: Finished resolvconf update. container-test-run-flakelet-relay> client # [ 2216.401281] client systemd[1]: Reached target Preparation for Network. container-test-run-flakelet-relay> client # [ 2216.418124] client systemd[1]: Starting Address configuration of eth1... container-test-run-flakelet-relay> client # [ 2216.427927] client systemd[1]: Starting Extra networking commands.... container-test-run-flakelet-relay> client # [ 2216.428148] client systemd[1]: nscd.service: Deactivated successfully. container-test-run-flakelet-relay> client # [ 2216.428253] client systemd[1]: Stopped Name Service Cache Daemon (nsncd). container-test-run-flakelet-relay> client # [ 2216.458698] client systemd[1]: Starting Name Service Cache Daemon (nsncd)... container-test-run-flakelet-relay> client # [ 2216.475055] client network-addresses-eth1-start[233]: adding address 192.168.1.2/24... done container-test-run-flakelet-relay> client # [ 2216.478284] client network-addresses-eth1-start[233]: adding address 2001:db8:1::2/64... done container-test-run-flakelet-relay> client # [ 2216.483209] client systemd[1]: Finished Address configuration of eth1. container-test-run-flakelet-relay> client # [ 2216.528060] client systemd[1]: Finished Extra networking commands.. container-test-run-flakelet-relay> client # [ 2216.528127] client systemd[1]: Reached target Network. container-test-run-flakelet-relay> client # [ 2216.549903] client systemd[1]: nscd.service: Deactivated successfully. container-test-run-flakelet-relay> client # [ 2216.550122] client systemd[1]: Stopped Name Service Cache Daemon (nsncd). container-test-run-flakelet-relay> client # [ 2216.552623] client systemd[1]: Starting Name Service Cache Daemon (nsncd)... container-test-run-flakelet-relay> client # [ 2216.876964] client nsncd[303]: Sep 06 07:41:34.838 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" container-test-run-flakelet-relay> agent # [ 2216.412649] agent systemd[1]: Finished resolvconf update. container-test-run-flakelet-relay> agent # [ 2216.422244] agent systemd[1]: Reached target Preparation for Network. container-test-run-flakelet-relay> agent # [ 2216.427493] agent systemd[1]: Starting Address configuration of eth1... container-test-run-flakelet-relay> agent # [ 2216.428377] agent systemd[1]: Starting Extra networking commands.... container-test-run-flakelet-relay> agent # [ 2216.431641] agent systemd[1]: nscd.service: Deactivated successfully. container-test-run-flakelet-relay> agent # [ 2216.458208] agent systemd[1]: Stopped Name Service Cache Daemon (nsncd). container-test-run-flakelet-relay> agent # [ 2216.461048] agent systemd[1]: Starting Name Service Cache Daemon (nsncd)... container-test-run-flakelet-relay> agent # [ 2216.477931] agent network-addresses-eth1-start[237]: adding address 192.168.1.1/24... done container-test-run-flakelet-relay> agent # [ 2216.480948] agent network-addresses-eth1-start[237]: adding address 2001:db8:1::1/64... done container-test-run-flakelet-relay> agent # [ 2216.486567] agent systemd[1]: Finished Address configuration of eth1. container-test-run-flakelet-relay> agent # [ 2216.530962] agent systemd[1]: Finished Extra networking commands.. container-test-run-flakelet-relay> agent # [ 2216.531094] agent systemd[1]: Reached target Network. container-test-run-flakelet-relay> agent # [ 2216.532271] agent systemd[1]: Starting flakelet-agent... container-test-run-flakelet-relay> agent # [ 2216.533053] agent systemd[1]: Starting Update flakelet service app... container-test-run-flakelet-relay> agent # [ 2216.533800] agent systemd[1]: Starting Update flakelet service other... container-test-run-flakelet-relay> agent # [ 2216.544587] agent systemd[1]: nscd.service: Deactivated successfully. container-test-run-flakelet-relay> agent # [ 2216.544696] agent systemd[1]: Stopped Name Service Cache Daemon (nsncd). container-test-run-flakelet-relay> agent # [ 2216.547507] agent systemd[1]: Starting Name Service Cache Daemon (nsncd)... container-test-run-flakelet-relay> agent # [ 2216.553132] agent flakelet[305]: app: using prebuilt artifact /nix/store/hysydfr96ds875vr0jgw5rjxi4p39h2j-flakelet-app-v1 container-test-run-flakelet-relay> agent # [ 2216.553960] agent flakelet[305]: app: activating generation 1 container-test-run-flakelet-relay> agent # [ 2216.555307] agent flakelet[306]: other: using prebuilt artifact /nix/store/hmr990n0b4v6rpzrcpyyw738r9xi7z95-flakelet-other-v1 container-test-run-flakelet-relay> agent # [ 2216.557049] agent flakelet[306]: other: activating generation 1 container-test-run-flakelet-relay> agent # [ 2216.558745] agent systemd[1]: Reload requested from client PID 336 ('systemctl') (unit flakelet-app.service)... container-test-run-flakelet-relay> agent # [ 2216.558780] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [ 2216.578658] agent flakelet-agent[304]: {"timestamp":"2026-09-06T07:41:34.540358Z","level":"WARN","message":"SRV _flakelet-relay._tcp.test: lookup failed","target":"flakelet_agent"} container-test-run-flakelet-relay> client # [ 2216.876992] client systemd[1]: Started Name Service Cache Daemon (nsncd). container-test-run-flakelet-relay> client # [ 2216.877090] client systemd[1]: Reached target Host and Network Name Lookups. container-test-run-flakelet-relay> client # [ 2216.877153] client systemd[1]: Reached target User and Group Name Lookups. container-test-run-flakelet-relay> client # [ 2216.878630] client systemd[1]: Starting User Login Management... container-test-run-flakelet-relay> client # [ 2216.879584] client systemd[1]: Starting Permit User Sessions... container-test-run-flakelet-relay> client # [ 2216.918104] client systemd[1]: Finished Permit User Sessions. container-test-run-flakelet-relay> client # [ 2216.919824] client systemd[1]: Started Console Getty. container-test-run-flakelet-relay> client # [ 2216.919848] client systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0 container-test-run-flakelet-relay> client # [ 2216.919861] client systemd[1]: Reached target Login Prompts. container-test-run-flakelet-relay> agent # [ 2216.879500] agent nsncd[311]: Sep 06 07:41:34.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 # [ 2216.981483] agent systemd[1]: Reloading finished in 422 ms. container-test-run-flakelet-relay> agent # [ 2217.120743] agent systemd[1]: Started flakelet-agent. container-test-run-flakelet-relay> agent # [ 2217.120856] agent systemd[1]: Started Name Service Cache Daemon (nsncd). container-test-run-flakelet-relay> agent # [ 2217.122978] agent systemd[1]: Reached target Host and Network Name Lookups. container-test-run-flakelet-relay> agent # [ 2217.123105] agent systemd[1]: Reached target User and Group Name Lookups. container-test-run-flakelet-relay> agent # [ 2217.124766] agent systemd[1]: Starting User Login Management... container-test-run-flakelet-relay> agent # [ 2217.125569] agent systemd[1]: Starting Permit User Sessions... container-test-run-flakelet-relay> agent # [ 2217.125857] agent systemd[1]: Reload requested from client PID 396 ('systemctl') (unit flakelet-other.service)... container-test-run-flakelet-relay> agent # [ 2217.125887] agent systemd[1]: Reloading... container-test-run-flakelet-relay> relay # [ 2216.907035] relay nsncd[251]: Sep 06 07:41:34.868 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" container-test-run-flakelet-relay> relay # [ 2217.003328] relay systemd[1]: Reloading finished in 455 ms. container-test-run-flakelet-relay> relay # [ 2217.120681] relay systemd[1]: Started Name Service Cache Daemon (nsncd). container-test-run-flakelet-relay> relay # [ 2217.121152] relay systemd[1]: Reached target Host and Network Name Lookups. container-test-run-flakelet-relay> relay # [ 2217.121229] relay systemd[1]: Reached target User and Group Name Lookups. container-test-run-flakelet-relay> relay # [ 2217.122436] relay systemd[1]: Starting User Login Management... container-test-run-flakelet-relay> relay # [ 2217.123428] relay systemd[1]: Starting Permit User Sessions... container-test-run-flakelet-relay> relay # [ 2217.148625] relay systemd[1]: Reload requested from client PID 357 ('systemctl') (unit flakelet-flakelet-relay.service)... container-test-run-flakelet-relay> relay # [ 2217.148689] relay systemd[1]: Reloading... container-test-run-flakelet-relay> relay # [ 2217.171839] relay dnsmasq-pre-start[366]: dnsmasq: syntax check OK. container-test-run-flakelet-relay> relay # [ 2217.517884] relay systemd[1]: Reloading finished in 368 ms. container-test-run-flakelet-relay> relay # [ 2217.649923] relay systemd[1]: Finished Permit User Sessions. container-test-run-flakelet-relay> relay # [ 2217.653565] relay systemd[1]: Started Console Getty. container-test-run-flakelet-relay> relay # [ 2217.653632] relay systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0 container-test-run-flakelet-relay> relay # [ 2217.653694] relay systemd[1]: Reached target Login Prompts. container-test-run-flakelet-relay> relay # [ 2217.675274] relay systemd[1]: Starting flakelet-relay... container-test-run-flakelet-relay> agent # [ 2217.514702] agent systemd[1]: Reloading finished in 388 ms. container-test-run-flakelet-relay> agent # [ 2217.675153] agent systemd[1]: Finished Permit User Sessions. container-test-run-flakelet-relay> agent # [ 2217.688119] agent systemd[1]: Started Console Getty. container-test-run-flakelet-relay> agent # [ 2217.688235] agent systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0 container-test-run-flakelet-relay> agent # [ 2217.688357] agent systemd[1]: Reached target Login Prompts. container-test-run-flakelet-relay> agent # [ 2217.699789] agent systemd[1]: Reload requested from client PID 775 ('systemctl') (unit flakelet-app.service)... container-test-run-flakelet-relay> agent # [ 2217.699851] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [ 2218.034587] agent systemd[1]: Reloading finished in 334 ms. container-test-run-flakelet-relay> agent # [ 2218.105893] agent systemd[1]: Reload requested from client PID 774 ('systemctl') (unit flakelet-other.service)... container-test-run-flakelet-relay> agent # [ 2218.105944] agent systemd[1]: Reloading... container-test-run-flakelet-relay> relay # [ 2218.245546] relay nginx-pre-start[402]: nginx: the configuration file /nix/store/01avjj26wcihp926xgasszcj5s0k7cbs-nginx.conf syntax is ok container-test-run-flakelet-relay> relay # [ 2218.246380] relay nginx-pre-start[402]: nginx: configuration file /nix/store/01avjj26wcihp926xgasszcj5s0k7cbs-nginx.conf test is successful container-test-run-flakelet-relay> relay # [ 2218.264901] relay systemd[1]: Started Nginx Web Server. container-test-run-flakelet-relay> relay # [ 2218.286943] relay systemd[1]: Starting D-Bus System Message Bus... container-test-run-flakelet-relay> client # [ 2218.376818] client systemd-logind[315]: New seat seat0. container-test-run-flakelet-relay> client # [ 2218.378217] client systemd[1]: Starting D-Bus System Message Bus... container-test-run-flakelet-relay> client # [ 2218.378278] client systemd[1]: Started User Login Management. container-test-run-flakelet-relay> client # [ 2218.379214] client systemd[1]: Starting linger-users.service... container-test-run-flakelet-relay> client # [ 2218.416209] client systemd[1]: linger-users.service: Deactivated successfully. container-test-run-flakelet-relay> client # [ 2218.416420] client systemd[1]: Finished linger-users.service. container-test-run-flakelet-relay> client # [ 2218.416546] client systemd[1]: Reached target Multi-User System. container-test-run-flakelet-relay> agent # [ 2218.449392] agent systemd[1]: Reloading finished in 343 ms. container-test-run-flakelet-relay> agent # [ 2218.529668] agent systemd[1]: Starting app v1... container-test-run-flakelet-relay> agent # [ 2218.558046] agent systemd[1]: Starting other v1... container-test-run-flakelet-relay> agent # [ 2218.561093] agent systemd[1]: Started app v1. container-test-run-flakelet-relay> agent # [ 2218.566359] agent systemd[1]: Started other v1. container-test-run-flakelet-relay> agent # [ 2218.577702] agent flakelet[305]: app: updated to generation 1 container-test-run-flakelet-relay> agent # [ 2218.579040] agent systemd[1]: Finished Update flakelet service app. container-test-run-flakelet-relay> agent # [ 2218.582209] agent flakelet[306]: other: updated to generation 1 container-test-run-flakelet-relay> agent # [ 2218.583972] agent systemd[1]: Finished Update flakelet service other. container-test-run-flakelet-relay> agent # [ 2218.584099] agent systemd[1]: Reached target flakelet managed services. container-test-run-flakelet-relay> agent # [ 2218.605994] agent systemd-logind[736]: New seat seat0. container-test-run-flakelet-relay> agent # [ 2218.606969] agent systemd[1]: Starting D-Bus System Message Bus... container-test-run-flakelet-relay> agent # [ 2218.607217] agent systemd[1]: Started User Login Management. container-test-run-flakelet-relay> agent # [ 2218.608040] agent systemd[1]: Starting linger-users.service... container-test-run-flakelet-relay> agent # [ 2218.645488] agent systemd[1]: linger-users.service: Deactivated successfully. container-test-run-flakelet-relay> agent # [ 2218.645734] agent systemd[1]: Finished linger-users.service. container-test-run-flakelet-relay> agent # [ 2218.646802] agent systemd[1]: Reached target Multi-User System. container-test-run-flakelet-relay> relay # [ 2218.694805] relay systemd-logind[354]: New seat seat0. container-test-run-flakelet-relay> relay # [ 2218.695234] relay systemd[1]: Started User Login Management. container-test-run-flakelet-relay> relay # [ 2218.697787] relay systemd[1]: Starting linger-users.service... container-test-run-flakelet-relay> relay # [ 2218.736515] relay systemd[1]: linger-users.service: Deactivated successfully. container-test-run-flakelet-relay> relay # [ 2218.736853] relay systemd[1]: Finished linger-users.service. container-test-run-flakelet-relay> relay # [ 2218.862622] relay dbus-broker-launch[406]: Looking up NSS user entry for 'systemd-timesync'... container-test-run-flakelet-relay> relay # [ 2218.864123] relay dbus-broker-launch[406]: NSS returned no entry for 'systemd-timesync' container-test-run-flakelet-relay> relay # [ 2218.864123] relay dbus-broker-launch[406]: 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 # [ 2218.865888] relay systemd[1]: Started D-Bus System Message Bus. container-test-run-flakelet-relay> relay # [ 2218.873359] relay dbus-broker-launch[406]: Ready container-test-run-flakelet-relay> relay # [ 2218.876716] relay systemd[1]: Started Dnsmasq Daemon. container-test-run-flakelet-relay> relay # [ 2218.878974] relay dnsmasq[394]: started, version 2.93 cachesize 150 container-test-run-flakelet-relay> relay # [ 2218.878987] relay dnsmasq[394]: compile time options: IPv6 GNU-getopt DBus no-UBus no-i18n IDN2 DHCP DHCPv6 no-Lua TFTP conntrack ipset nftset auth DNSSEC loop-detect inotify dumpfile container-test-run-flakelet-relay> relay # [ 2218.878998] relay dnsmasq[394]: DBus support enabled: connected to system bus container-test-run-flakelet-relay> relay # [ 2218.879142] relay dnsmasq[394]: no upstream servers configured - please set them from DBus container-test-run-flakelet-relay> relay # [ 2218.879431] relay dnsmasq[394]: read /etc/hosts - 9 names container-test-run-flakelet-relay> client # [ 2218.907502] client dbus-broker-launch[325]: Looking up NSS user entry for 'systemd-timesync'... container-test-run-flakelet-relay> client # [ 2218.908756] client dbus-broker-launch[325]: NSS returned no entry for 'systemd-timesync' container-test-run-flakelet-relay> client # [ 2218.908756] client dbus-broker-launch[325]: Invalid user-name in /nix/store/s95yx4m4qalz791z1z94j1xd6qkrys9d-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" container-test-run-flakelet-relay> client # [ 2218.909433] client systemd[1]: Started D-Bus System Message Bus. container-test-run-flakelet-relay> client # [ 2218.909764] client systemd[1]: Startup finished in 4.240s. container-test-run-flakelet-relay> client # [ 2218.915220] client dbus-broker-launch[325]: Ready container-test-run-flakelet-relay> agent # [ 2219.064077] agent dbus-broker-launch[840]: Looking up NSS user entry for 'systemd-timesync'... container-test-run-flakelet-relay> agent # [ 2219.065749] agent dbus-broker-launch[840]: NSS returned no entry for 'systemd-timesync' container-test-run-flakelet-relay> agent # [ 2219.065749] agent dbus-broker-launch[840]: 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 # [ 2219.066965] agent systemd[1]: Started D-Bus System Message Bus. container-test-run-flakelet-relay> agent # [ 2219.067517] agent systemd[1]: Startup finished in 4.398s. container-test-run-flakelet-relay> agent # [ 2219.073883] agent dbus-broker-launch[840]: Ready container-test-run-flakelet-relay> relay # [ 2219.591952] relay flakelet-relay[400]: {"timestamp":"2026-09-06T07:41:37.553446Z","level":"INFO","message":"listening on 127.0.0.1:7400","target":"flakelet_relay::relay::server"} container-test-run-flakelet-relay> relay # [ 2219.592885] relay flakelet-relay[400]: {"timestamp":"2026-09-06T07:41:37.553964Z","level":"INFO","message":"listening on [::]:7443","target":"flakelet_relay::relay::server"} container-test-run-flakelet-relay> relay # [ 2219.592329] relay systemd[1]: Started flakelet-relay. container-test-run-flakelet-relay> relay # [ 2219.601791] relay systemd[1]: Starting health for flakelet-relay... container-test-run-flakelet-relay> relay # [ 2219.845849] relay systemd[1]: flakelet-relay-health.service: Deactivated successfully. container-test-run-flakelet-relay> relay # [ 2219.846230] relay systemd[1]: Finished health for flakelet-relay. container-test-run-flakelet-relay> relay # [ 2219.883651] relay flakelet[311]: flakelet-relay: updated to generation 1 container-test-run-flakelet-relay> relay # [ 2219.885474] relay systemd[1]: Finished Update flakelet service flakelet-relay. container-test-run-flakelet-relay> relay # [ 2219.885731] relay systemd[1]: Reached target flakelet managed services. container-test-run-flakelet-relay> relay # [ 2219.885814] relay systemd[1]: Reached target Multi-User System. container-test-run-flakelet-relay> relay # [ 2219.885978] relay systemd[1]: Startup finished in 5.210s. container-test-run-flakelet-relay> relay: (finished: waiting for unit flakelet-relay.service, in 6.16 seconds) container-test-run-flakelet-relay> relay: waiting for unit nginx.service container-test-run-flakelet-relay> relay: (finished: waiting for unit nginx.service, in 0.02 seconds) container-test-run-flakelet-relay> agent: waiting for unit flakelet.target container-test-run-flakelet-relay> agent: (finished: waiting for unit flakelet.target, in 0.02 seconds) container-test-run-flakelet-relay> agent: waiting for unit flakelet-agent.service container-test-run-flakelet-relay> agent: (finished: waiting for unit flakelet-agent.service, in 0.02 seconds) container-test-run-flakelet-relay> client: waiting for unit multi-user.target container-test-run-flakelet-relay> client: (finished: waiting for unit multi-user.target, in 0.02 seconds) container-test-run-flakelet-relay> relay: waiting for success: curl -sf http://127.0.0.1:7400/metrics | grep -q 'agent_up{host="agent"} 1' container-test-run-flakelet-relay> relay # [ 2221.677078] relay flakelet-relay[400]: {"timestamp":"2026-09-06T07:41:39.638721Z","level":"INFO","message":"agent connected","host":"agent","version":"0.1.0","flakelets":"[Named { name: \"app\", generation: Some(1), revision: Some(\"prebuilt:app\") }, Named { name: \"other\", generation: Some(1), revision: Some(\"prebuilt:other\") }]","target":"flakelet_relay::relay::agent_conn"} container-test-run-flakelet-relay> agent # [ 2221.676650] agent flakelet-agent[304]: {"timestamp":"2026-09-06T07:41:39.638387Z","level":"INFO","message":"connected","relay":"https://relay:7443","relay_name":"relay","relay_version":"0.1.0","host":"agent","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> relay: (finished: waiting for success: curl -sf http://127.0.0.1:7400/metrics | grep -q 'agent_up{host="agent"} 1', in 2.05 seconds) container-test-run-flakelet-relay> agent: must succeed: systemctl is-active app.service other.service container-test-run-flakelet-relay> agent: (finished: must succeed: systemctl is-active app.service other.service, in 0.01 seconds) container-test-run-flakelet-relay> subtest: deploy with client certificate, relay found via SRV container-test-run-flakelet-relay> agent: must succeed: jq '.services.app.prebuilt="/nix/store/6ili1vxjlag9d7dy0bxrbxk6bpi5qh9p-flakelet-app-v2"' /etc/flakelet/config.json > /tmp/config.json && cp --remove-destination /tmp/config.json /etc/flakelet/config.json container-test-run-flakelet-relay> agent: (finished: must succeed: jq '.services.app.prebuilt="/nix/store/6ili1vxjlag9d7dy0bxrbxk6bpi5qh9p-flakelet-app-v2"' /etc/flakelet/config.json > /tmp/config.json && cp --remove-destination /tmp/config.json /etc/flakelet/config.json, in 0.01 seconds) container-test-run-flakelet-relay> client: must succeed: push --relay-srv test --cert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin.pem --key /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin-key.pem deploy agent/app 2>&1 container-test-run-flakelet-relay> relay # [ 2222.504140] relay flakelet-relay[400]: {"timestamp":"2026-09-06T07:41:40.465896Z","level":"INFO","message":"deploy accepted","job":"99d8f4ffea21836a79f060ade55fa45a","caller":"x509:email:admin@example.org","targets":"[\"agent/app\"]","target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> agent # [ 2222.504756] agent flakelet-agent[304]: {"timestamp":"2026-09-06T07:41:40.466506Z","level":"INFO","message":"start","id":"c322a6860e62134e60d78f95813e6fbb","flakelet":"app","rule":"admins","relay":"relay","caller":"Some(\"x509:email:admin@example.org\")","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> agent # [ 2222.505238] agent flakelet-agent[304]: {"timestamp":"2026-09-06T07:41:40.467063Z","level":"INFO","message":"update starting","flakelet":"app","ids":"[\"c322a6860e62134e60d78f95813e6fbb\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [ 2222.558081] agent systemd[1]: Starting flakelet update app (via relay)... container-test-run-flakelet-relay> agent # [ 2222.590898] agent systemd[1]: Started flakelet update app (via relay). container-test-run-flakelet-relay> agent # [ 2222.595597] agent flakelet[869]: app: using prebuilt artifact /nix/store/6ili1vxjlag9d7dy0bxrbxk6bpi5qh9p-flakelet-app-v2 container-test-run-flakelet-relay> agent # [ 2222.596365] agent flakelet[869]: app: activating generation 2 container-test-run-flakelet-relay> agent # [ 2222.611037] agent systemd[1]: Stopping app v1... container-test-run-flakelet-relay> agent # [ 2222.611486] agent systemd[1]: app.service: Deactivated successfully. container-test-run-flakelet-relay> agent # [ 2222.611740] agent systemd[1]: Stopped app v1. container-test-run-flakelet-relay> agent # [ 2222.627810] agent systemd[1]: Reload requested from client PID 876 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [ 2222.627873] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [ 2222.953643] agent systemd[1]: Reloading finished in 325 ms. container-test-run-flakelet-relay> agent # [ 2223.016969] agent systemd[1]: Reload requested from client PID 906 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [ 2223.017008] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [ 2223.330011] agent systemd[1]: Reloading finished in 312 ms. container-test-run-flakelet-relay> agent # [ 2223.395373] agent systemd[1]: Reload requested from client PID 935 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [ 2223.395410] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [ 2223.706111] agent systemd[1]: Reloading finished in 310 ms. container-test-run-flakelet-relay> agent # [ 2223.784376] agent systemd[1]: Reload requested from client PID 966 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [ 2223.784408] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [ 2224.097388] agent systemd[1]: Reloading finished in 312 ms. container-test-run-flakelet-relay> agent # [ 2224.170251] agent systemd[1]: Starting app v2... container-test-run-flakelet-relay> agent # [ 2224.199903] agent systemd[1]: Started app v2. container-test-run-flakelet-relay> agent # [ 2224.214512] agent flakelet[869]: app: updated to generation 2 container-test-run-flakelet-relay> agent # [ 2224.216620] agent systemd[1]: flakelet-relay-job-app.service: Deactivated successfully. container-test-run-flakelet-relay> client: (finished: must succeed: push --relay-srv test --cert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin.pem --key /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin-key.pem deploy agent/app 2>&1, in 2.86 seconds) container-test-run-flakelet-relay> agent: must succeed: systemctl show app.service -p Description | grep -q 'app v2' container-test-run-flakelet-relay> agent: (finished: must succeed: systemctl show app.service -p Description | grep -q 'app v2', in 0.02 seconds) container-test-run-flakelet-relay> agent: must succeed: journalctl -u flakelet-relay-job-app.service | grep -q 'generation 2' container-test-run-flakelet-relay> agent: (finished: must succeed: journalctl -u flakelet-relay-job-app.service | grep -q 'generation 2', in 0.01 seconds) container-test-run-flakelet-relay> (finished: subtest: deploy with client certificate, relay found via SRV, in 2.91 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.14 seconds) container-test-run-flakelet-relay> client: must succeed: FLAKELET_RELAY_TOKEN_COMMAND='echo eyJhbGciOiJFZERTQSIsImtpZCI6ImsxIiwidHlwIjoiSldUIn0.eyJhdWQiOiJmbGFrZWxldC1yZWxheSIsImV4cCI6MTc4ODY4MDgwMywiZ3JvdXBzIjpbXSwiaWF0IjoxNzg4NjgwNTAzLCJpc3MiOiJodHRwczovL3JlbGF5Ojg0NDMiLCJqdGkiOiJUNFVRM0dFWlZNUUNBPT09IiwibmJmIjoxNzg4NjgwNTAzLCJzdWIiOiJyZXBvOmdpdGh1YjpleGFtcGxlL2FwcDpyZWY6cmVmcy9oZWFkcy9tYWluIn0.8HkE57RM2XafaudtZvvbAgJpwyQalrYl7Pn3WMlxy15Z-OVtSIbZjwyR-hSm71C2RNq0uJbNJMwF8NUQQ5sJAQ' push --relay https://relay:7443 deploy agent/app 2>&1 container-test-run-flakelet-relay> agent # [ 2225.336286] agent flakelet-agent[304]: {"timestamp":"2026-09-06T07:41:43.298033Z","level":"INFO","message":"update finished","flakelet":"app","status":"Updated","generation":"Some(2)","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [ 2225.555734] agent flakelet-agent[304]: {"timestamp":"2026-09-06T07:41:43.517455Z","level":"INFO","message":"start","id":"be79b72e3be500cfe4b869b4cf5411bc","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 # [ 2225.556285] agent flakelet-agent[304]: {"timestamp":"2026-09-06T07:41:43.518066Z","level":"INFO","message":"update starting","flakelet":"app","ids":"[\"be79b72e3be500cfe4b869b4cf5411bc\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> relay # [ 2225.337431] relay flakelet-relay[400]: {"timestamp":"2026-09-06T07:41:43.299187Z","level":"INFO","message":"deploy finished","job":"99d8f4ffea21836a79f060ade55fa45a","ok":true,"target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> relay # [ 2225.555404] relay flakelet-relay[400]: {"timestamp":"2026-09-06T07:41:43.517166Z","level":"INFO","message":"deploy accepted","job":"b550939a2b3fa9762e624be3d8635336","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 # [ 2225.612106] agent systemd[1]: Starting flakelet update app (via relay)... container-test-run-flakelet-relay> agent # [ 2225.645898] agent systemd[1]: Started flakelet update app (via relay). container-test-run-flakelet-relay> agent # [ 2225.649665] agent flakelet[1024]: app: using prebuilt artifact /nix/store/hysydfr96ds875vr0jgw5rjxi4p39h2j-flakelet-app-v1 container-test-run-flakelet-relay> agent # [ 2225.650241] agent flakelet[1024]: app: activating generation 3 container-test-run-flakelet-relay> agent # [ 2225.664506] agent systemd[1]: Stopping app v2... container-test-run-flakelet-relay> agent # [ 2225.664941] agent systemd[1]: app.service: Deactivated successfully. container-test-run-flakelet-relay> agent # [ 2225.665210] agent systemd[1]: Stopped app v2. container-test-run-flakelet-relay> agent # [ 2225.681147] agent systemd[1]: Reload requested from client PID 1031 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [ 2225.681210] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [ 2226.007873] agent systemd[1]: Reloading finished in 325 ms. container-test-run-flakelet-relay> agent # [ 2226.081734] agent systemd[1]: Reload requested from client PID 1061 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [ 2226.081768] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [ 2226.393818] agent systemd[1]: Reloading finished in 311 ms. container-test-run-flakelet-relay> agent # [ 2226.463514] agent systemd[1]: Reload requested from client PID 1090 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [ 2226.463549] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [ 2226.777828] agent systemd[1]: Reloading finished in 313 ms. container-test-run-flakelet-relay> agent # [ 2226.850916] agent systemd[1]: Reload requested from client PID 1121 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [ 2226.850949] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [ 2227.162121] agent systemd[1]: Reloading finished in 310 ms. container-test-run-flakelet-relay> agent # [ 2227.222563] agent systemd[1]: Starting app v1... container-test-run-flakelet-relay> agent # [ 2227.259902] agent systemd[1]: Started app v1. container-test-run-flakelet-relay> agent # [ 2227.277102] agent flakelet[1024]: app: updated to generation 3 container-test-run-flakelet-relay> agent # [ 2227.279316] 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.eyJhdWQiOiJmbGFrZWxldC1yZWxheSIsImV4cCI6MTc4ODY4MDgwMywiZ3JvdXBzIjpbXSwiaWF0IjoxNzg4NjgwNTAzLCJpc3MiOiJodHRwczovL3JlbGF5Ojg0NDMiLCJqdGkiOiJUNFVRM0dFWlZNUUNBPT09IiwibmJmIjoxNzg4NjgwNTAzLCJzdWIiOiJyZXBvOmdpdGh1YjpleGFtcGxlL2FwcDpyZWY6cmVmcy9oZWFkcy9tYWluIn0.8HkE57RM2XafaudtZvvbAgJpwyQalrYl7Pn3WMlxy15Z-OVtSIbZjwyR-hSm71C2RNq0uJbNJMwF8NUQQ5sJAQ' push --relay https://relay:7443 deploy agent/app 2>&1, in 2.88 seconds) container-test-run-flakelet-relay> (finished: subtest: deploy with OIDC token, sub principal, in 3.03 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.eyJhdWQiOiJmbGFrZWxldC1yZWxheSIsImV4cCI6MTc4ODY4MDgwMywiZ3JvdXBzIjpbXSwiaWF0IjoxNzg4NjgwNTAzLCJpc3MiOiJodHRwczovL3JlbGF5Ojg0NDMiLCJqdGkiOiJUNFVRM0dFWlZNUUNBPT09IiwibmJmIjoxNzg4NjgwNTAzLCJzdWIiOiJyZXBvOmdpdGh1YjpleGFtcGxlL2FwcDpyZWY6cmVmcy9oZWFkcy9tYWluIn0.8HkE57RM2XafaudtZvvbAgJpwyQalrYl7Pn3WMlxy15Z-OVtSIbZjwyR-hSm71C2RNq0uJbNJMwF8NUQQ5sJAQ' 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.eyJhdWQiOiJmbGFrZWxldC1yZWxheSIsImV4cCI6MTc4ODY4MDgwMywiZ3JvdXBzIjpbXSwiaWF0IjoxNzg4NjgwNTAzLCJpc3MiOiJodHRwczovL3JlbGF5Ojg0NDMiLCJqdGkiOiJUNFVRM0dFWlZNUUNBPT09IiwibmJmIjoxNzg4NjgwNTAzLCJzdWIiOiJyZXBvOmdpdGh1YjpleGFtcGxlL2FwcDpyZWY6cmVmcy9oZWFkcy9tYWluIn0.8HkE57RM2XafaudtZvvbAgJpwyQalrYl7Pn3WMlxy15Z-OVtSIbZjwyR-hSm71C2RNq0uJbNJMwF8NUQQ5sJAQ' 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.eyJhdWQiOiJmbGFrZWxldC1yZWxheSIsImV4cCI6MTc4ODY4MDgwNiwiZ3JvdXBzIjpbIndoZWVsIl0sImlhdCI6MTc4ODY4MDUwNiwiaXNzIjoiaHR0cHM6Ly9yZWxheTo4NDQzIiwianRpIjoiTzRIRENXNFpVM1hHMj09PSIsIm5iZiI6MTc4ODY4MDUwNiwic3ViIjoic29tZW9uZSJ9.KelE6eoLvqr5_-kZOd4l35etUPAwtwLhpEvihLe05Kk8M63goMgiMEgue5NlmnYAOMgRjeywdeYjVsXDP6cVCg' push --relay https://relay:7443 deploy agent/other 2>&1 container-test-run-flakelet-relay> relay # [ 2228.402585] relay flakelet-relay[400]: {"timestamp":"2026-09-06T07:41:46.364314Z","level":"INFO","message":"deploy finished","job":"b550939a2b3fa9762e624be3d8635336","ok":true,"target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> relay # [ 2228.501291] relay flakelet-relay[400]: {"timestamp":"2026-09-06T07:41:46.463070Z","level":"INFO","message":"deploy accepted","job":"36d606c530cb8fc629cf7fe13d8d24dd","caller":"oidc:mock:groups:wheel\noidc:mock:someone","targets":"[\"agent/other\"]","target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> agent # [ 2228.401667] agent flakelet-agent[304]: {"timestamp":"2026-09-06T07:41:46.363414Z","level":"INFO","message":"update finished","flakelet":"app","status":"Updated","generation":"Some(3)","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [ 2228.501708] agent flakelet-agent[304]: {"timestamp":"2026-09-06T07:41:46.463418Z","level":"INFO","message":"start","id":"f2a8814125ee2276262a4116d5bae179","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 # [ 2228.502254] agent flakelet-agent[304]: {"timestamp":"2026-09-06T07:41:46.464071Z","level":"INFO","message":"update starting","flakelet":"other","ids":"[\"f2a8814125ee2276262a4116d5bae179\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [ 2228.554962] agent systemd[1]: Starting flakelet update other (via relay)... container-test-run-flakelet-relay> agent # [ 2228.587978] agent systemd[1]: Started flakelet update other (via relay). container-test-run-flakelet-relay> agent # [ 2228.592740] agent flakelet[1170]: other: using prebuilt artifact /nix/store/hmr990n0b4v6rpzrcpyyw738r9xi7z95-flakelet-other-v1 container-test-run-flakelet-relay> agent # [ 2228.593242] agent flakelet[1170]: other: up to date container-test-run-flakelet-relay> agent # [ 2228.594979] 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.eyJhdWQiOiJmbGFrZWxldC1yZWxheSIsImV4cCI6MTc4ODY4MDgwNiwiZ3JvdXBzIjpbIndoZWVsIl0sImlhdCI6MTc4ODY4MDUwNiwiaXNzIjoiaHR0cHM6Ly9yZWxheTo4NDQzIiwianRpIjoiTzRIRENXNFpVM1hHMj09PSIsIm5iZiI6MTc4ODY4MDUwNiwic3ViIjoic29tZW9uZSJ9.KelE6eoLvqr5_-kZOd4l35etUPAwtwLhpEvihLe05Kk8M63goMgiMEgue5NlmnYAOMgRjeywdeYjVsXDP6cVCg' push --relay https://relay:7443 deploy agent/other 2>&1, in 0.67 seconds) container-test-run-flakelet-relay> (finished: subtest: claim principal from groups, unchanged counts as success, in 0.72 seconds) container-test-run-flakelet-relay> subtest: push login via device flow, cached token used afterwards container-test-run-flakelet-relay> client: must succeed: push login --issuer https://relay:8443 --client-id flakelet-relay 2>&1 container-test-run-flakelet-relay> agent # [ 2229.145190] agent flakelet-agent[304]: {"timestamp":"2026-09-06T07:41:47.106934Z","level":"INFO","message":"update finished","flakelet":"other","status":"Unchanged","generation":"Some(1)","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> relay # [ 2229.146169] relay flakelet-relay[400]: {"timestamp":"2026-09-06T07:41:47.107832Z","level":"INFO","message":"deploy finished","job":"36d606c530cb8fc629cf7fe13d8d24dd","ok":true,"target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> relay # [ 2229.171254] relay 93sn3sv24fj26ksccpppzwg6kbf10s4w-device-mock[186]: 127.0.0.1 - - [06/Sep/2026 07:41:47] "POST /device HTTP/1.1" 200 - container-test-run-flakelet-relay> relay # [ 2230.177523] relay 93sn3sv24fj26ksccpppzwg6kbf10s4w-device-mock[186]: 127.0.0.1 - - [06/Sep/2026 07:41:48] "POST /token HTTP/1.1" 400 - container-test-run-flakelet-relay> client: (finished: must succeed: push login --issuer https://relay:8443 --client-id flakelet-relay 2>&1, in 2.08 seconds) container-test-run-flakelet-relay> client: must succeed: test "$(stat -c %a /root/.local/state/flakelet-push/token.json)" = 600 container-test-run-flakelet-relay> client: (finished: must succeed: test "$(stat -c %a /root/.local/state/flakelet-push/token.json)" = 600, in 0.01 seconds) container-test-run-flakelet-relay> client: must succeed: push --relay https://relay:7443 deploy agent/other 2>&1 container-test-run-flakelet-relay> relay # [ 2231.228275] relay 93sn3sv24fj26ksccpppzwg6kbf10s4w-device-mock[186]: 127.0.0.1 - - [06/Sep/2026 07:41:49] "POST /token HTTP/1.1" 200 - container-test-run-flakelet-relay> relay # [ 2231.260271] relay flakelet-relay[400]: {"timestamp":"2026-09-06T07:41:49.222025Z","level":"INFO","message":"deploy accepted","job":"e2636799a1487e3381a2e7d71cd2e077","caller":"oidc:mock:groups:wheel\noidc:mock:someone","targets":"[\"agent/other\"]","target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> agent # [ 2231.260621] agent flakelet-agent[304]: {"timestamp":"2026-09-06T07:41:49.222352Z","level":"INFO","message":"start","id":"5ac2b65ba28fffd6c028547f1fd4d20b","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 # [ 2231.261512] agent flakelet-agent[304]: {"timestamp":"2026-09-06T07:41:49.222979Z","level":"INFO","message":"update starting","flakelet":"other","ids":"[\"5ac2b65ba28fffd6c028547f1fd4d20b\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [ 2231.298523] agent systemd[1]: Starting flakelet update other (via relay)... container-test-run-flakelet-relay> agent # [ 2231.324786] agent systemd[1]: Started flakelet update other (via relay). container-test-run-flakelet-relay> agent # [ 2231.328708] agent flakelet[1188]: other: using prebuilt artifact /nix/store/hmr990n0b4v6rpzrcpyyw738r9xi7z95-flakelet-other-v1 container-test-run-flakelet-relay> agent # [ 2231.329185] agent flakelet[1188]: other: up to date container-test-run-flakelet-relay> agent # [ 2231.331227] 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.63 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 # [ 2231.870162] agent flakelet-agent[304]: {"timestamp":"2026-09-06T07:41:49.831912Z","level":"INFO","message":"update finished","flakelet":"other","status":"Unchanged","generation":"Some(1)","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [ 2231.891735] agent flakelet-agent[304]: {"timestamp":"2026-09-06T07:41:49.853533Z","level":"INFO","message":"start","id":"7867c6cc7e8961ef2ef3fe0a3241857c","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 # [ 2231.892115] agent flakelet-agent[304]: {"timestamp":"2026-09-06T07:41:49.853936Z","level":"INFO","message":"update starting","flakelet":"other","ids":"[\"7867c6cc7e8961ef2ef3fe0a3241857c\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [ 2231.928659] agent systemd[1]: Starting flakelet update other (via relay)... container-test-run-flakelet-relay> agent # [ 2231.955769] agent systemd[1]: Started flakelet update other (via relay). container-test-run-flakelet-relay> agent # [ 2231.960614] agent flakelet[1206]: other: using prebuilt artifact /nix/store/hmr990n0b4v6rpzrcpyyw738r9xi7z95-flakelet-other-v1 container-test-run-flakelet-relay> agent # [ 2231.961148] agent flakelet[1206]: other: up to date container-test-run-flakelet-relay> agent # [ 2231.961455] agent systemd[1]: flakelet-relay-job-other.service: Deactivated successfully. container-test-run-flakelet-relay> relay # [ 2231.871083] relay flakelet-relay[400]: {"timestamp":"2026-09-06T07:41:49.832746Z","level":"INFO","message":"deploy finished","job":"e2636799a1487e3381a2e7d71cd2e077","ok":true,"target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> relay # [ 2231.891462] relay flakelet-relay[400]: {"timestamp":"2026-09-06T07:41:49.853268Z","level":"INFO","message":"deploy accepted","job":"d39c538b60456b892d25f6299141f35f","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> (finished: subtest: push login via device flow, cached token used afterwards, in 3.37 seconds) container-test-run-flakelet-relay> subtest: waves: failing first wave skips the second container-test-run-flakelet-relay> agent: must succeed: jq '.services.app.prebuilt="/nix/store/8282rnbvyk847q4l4zby3zwcq3jpzrvw-flakelet-app-broken"' /etc/flakelet/config.json > /tmp/config.json && cp --remove-destination /tmp/config.json /etc/flakelet/config.json container-test-run-flakelet-relay> agent: (finished: must succeed: jq '.services.app.prebuilt="/nix/store/8282rnbvyk847q4l4zby3zwcq3jpzrvw-flakelet-app-broken"' /etc/flakelet/config.json > /tmp/config.json && cp --remove-destination /tmp/config.json /etc/flakelet/config.json, in 0.01 seconds) container-test-run-flakelet-relay> client: must fail: push --relay https://relay:7443 --cert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin.pem --key /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin-key.pem deploy agent/app --wave agent/other 2>&1 container-test-run-flakelet-relay> relay # [ 2232.515338] relay flakelet-relay[400]: {"timestamp":"2026-09-06T07:41:50.477050Z","level":"INFO","message":"deploy finished","job":"d39c538b60456b892d25f6299141f35f","ok":true,"target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> relay # [ 2232.555083] relay flakelet-relay[400]: {"timestamp":"2026-09-06T07:41:50.516827Z","level":"INFO","message":"deploy accepted","job":"8e30ee8719b8e60ba6d476e79c5b976b","caller":"x509:email:admin@example.org","targets":"[\"agent/app\", \"agent/other\"]","target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> agent # [ 2232.514319] agent flakelet-agent[304]: {"timestamp":"2026-09-06T07:41:50.476064Z","level":"INFO","message":"update finished","flakelet":"other","status":"Unchanged","generation":"Some(1)","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [ 2232.555383] agent flakelet-agent[304]: {"timestamp":"2026-09-06T07:41:50.517147Z","level":"INFO","message":"start","id":"b54bdbb88d4285256bdf0c2fd528bd27","flakelet":"app","rule":"admins","relay":"relay","caller":"Some(\"x509:email:admin@example.org\")","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> agent # [ 2232.555720] agent flakelet-agent[304]: {"timestamp":"2026-09-06T07:41:50.517541Z","level":"INFO","message":"update starting","flakelet":"app","ids":"[\"b54bdbb88d4285256bdf0c2fd528bd27\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [ 2232.610619] agent systemd[1]: Starting flakelet update app (via relay)... container-test-run-flakelet-relay> agent # [ 2232.636907] agent systemd[1]: Started flakelet update app (via relay). container-test-run-flakelet-relay> agent # [ 2232.641410] agent flakelet[1227]: app: using prebuilt artifact /nix/store/8282rnbvyk847q4l4zby3zwcq3jpzrvw-flakelet-app-broken container-test-run-flakelet-relay> agent # [ 2232.642168] agent flakelet[1227]: app: activating generation 4 container-test-run-flakelet-relay> agent # [ 2232.654593] agent systemd[1]: Stopping app v1... container-test-run-flakelet-relay> agent # [ 2232.656315] agent systemd[1]: app.service: Deactivated successfully. container-test-run-flakelet-relay> agent # [ 2232.656630] agent systemd[1]: Stopped app v1. container-test-run-flakelet-relay> agent # [ 2232.672457] agent systemd[1]: Reload requested from client PID 1234 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [ 2232.672521] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [ 2232.995707] agent systemd[1]: Reloading finished in 322 ms. container-test-run-flakelet-relay> agent # [ 2233.066806] agent systemd[1]: Reload requested from client PID 1264 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [ 2233.066841] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [ 2233.378466] agent systemd[1]: Reloading finished in 311 ms. container-test-run-flakelet-relay> agent # [ 2233.440597] agent systemd[1]: Reload requested from client PID 1293 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [ 2233.440630] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [ 2233.752474] agent systemd[1]: Reloading finished in 311 ms. container-test-run-flakelet-relay> agent # [ 2233.821614] agent systemd[1]: Reload requested from client PID 1324 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [ 2233.821643] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [ 2234.132993] agent systemd[1]: Reloading finished in 311 ms. container-test-run-flakelet-relay> agent # [ 2234.201657] agent systemd[1]: Starting app broken... container-test-run-flakelet-relay> agent # [ 2234.229332] agent (nonexistent)[1354]: app.service: Unable to locate executable '/nonexistent': No such file or directory container-test-run-flakelet-relay> agent # [ 2234.229356] agent (nonexistent)[1354]: app.service: Failed at step EXEC spawning /nonexistent: No such file or directory container-test-run-flakelet-relay> agent # [ 2234.231542] agent systemd[1]: app.service: Main process exited, code=exited, status=203/EXEC container-test-run-flakelet-relay> agent # [ 2234.231653] agent systemd[1]: app.service: Failed with result 'exit-code'. container-test-run-flakelet-relay> agent # [ 2234.231985] agent systemd[1]: Failed to start app broken. container-test-run-flakelet-relay> agent # [ 2234.269995] agent systemd[1]: Reload requested from client PID 1361 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [ 2234.270039] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [ 2234.586192] agent systemd[1]: Reloading finished in 315 ms. container-test-run-flakelet-relay> agent # [ 2234.651649] agent systemd[1]: Reload requested from client PID 1391 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [ 2234.651684] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [ 2234.963527] agent systemd[1]: Reloading finished in 311 ms. container-test-run-flakelet-relay> agent # [ 2235.022327] agent systemd[1]: Reload requested from client PID 1421 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [ 2235.022360] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [ 2235.331901] agent systemd[1]: Reloading finished in 309 ms. container-test-run-flakelet-relay> agent # [ 2235.414030] agent systemd[1]: Reload requested from client PID 1451 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [ 2235.414066] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [ 2235.728719] agent systemd[1]: Reloading finished in 314 ms. container-test-run-flakelet-relay> agent # [ 2235.792855] agent systemd[1]: Starting app v1... container-test-run-flakelet-relay> agent # [ 2235.819840] agent systemd[1]: Started app v1. container-test-run-flakelet-relay> agent # [ 2235.821035] agent flakelet[1227]: app: deploy failed, rolled back: systemctl start app.service failed: container-test-run-flakelet-relay> agent # [ 2235.821035] agent flakelet[1227]: failed units: app.service. See journalctl -u app.service container-test-run-flakelet-relay> agent # [ 2235.823251] agent systemd[1]: flakelet-relay-job-app.service: Main process exited, code=exited, status=1/FAILURE container-test-run-flakelet-relay> agent # [ 2235.823350] 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.04 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.07 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 # [ 2236.571793] relay flakelet-relay[400]: {"timestamp":"2026-09-06T07:41:54.533534Z","level":"INFO","message":"deploy finished","job":"8e30ee8719b8e60ba6d476e79c5b976b","ok":false,"target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> relay # [ 2236.620980] relay flakelet-relay[400]: {"timestamp":"2026-09-06T07:41:54.582737Z","level":"INFO","message":"deploy accepted","job":"7fb2e6e865f8dd9d41d0b117fcb0d0ff","caller":"x509:email:admin@example.org","targets":"[\"agent/app\"]","target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> agent # [ 2236.570915] agent flakelet-agent[304]: {"timestamp":"2026-09-06T07:41:54.532665Z","level":"INFO","message":"update finished","flakelet":"app","status":"RolledBack","generation":"Some(3)","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [ 2236.621326] agent flakelet-agent[304]: {"timestamp":"2026-09-06T07:41:54.583083Z","level":"INFO","message":"start","id":"4e550bf92c48404eb3a8ef52a5e5fbbd","flakelet":"app","rule":"admins","relay":"relay","caller":"Some(\"x509:email:admin@example.org\")","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> agent # [ 2236.621707] agent flakelet-agent[304]: {"timestamp":"2026-09-06T07:41:54.583528Z","level":"INFO","message":"update starting","flakelet":"app","ids":"[\"4e550bf92c48404eb3a8ef52a5e5fbbd\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [ 2236.657214] agent systemd[1]: Starting flakelet update app (via relay)... container-test-run-flakelet-relay> agent # [ 2236.694892] agent systemd[1]: Started flakelet update app (via relay). container-test-run-flakelet-relay> agent # [ 2236.699044] agent flakelet[1506]: app: using prebuilt artifact /nix/store/ln47rsd5km8a04hd5zlkz4q1v352zqh3-flakelet-app-slow2 container-test-run-flakelet-relay> agent # [ 2236.699788] agent flakelet[1506]: app: activating generation 4 container-test-run-flakelet-relay> agent # [ 2236.711160] agent systemd[1]: Stopping app v1... container-test-run-flakelet-relay> agent # [ 2236.712111] agent systemd[1]: app.service: Deactivated successfully. container-test-run-flakelet-relay> agent # [ 2236.712294] agent systemd[1]: Stopped app v1. container-test-run-flakelet-relay> agent # [ 2236.724550] agent systemd[1]: Reload requested from client PID 1513 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [ 2236.724585] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [ 2237.039515] agent systemd[1]: Reloading finished in 314 ms. container-test-run-flakelet-relay> agent # [ 2237.113854] agent systemd[1]: Reload requested from client PID 1543 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [ 2237.113887] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [ 2237.424448] agent systemd[1]: Reloading finished in 310 ms. container-test-run-flakelet-relay> agent # [ 2237.489483] agent systemd[1]: Reload requested from client PID 1572 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [ 2237.489516] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent: (finished: waiting for success: systemctl is-active flakelet-relay-job-app.service, in 1.25 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 # [ 2237.800599] agent systemd[1]: Reloading finished in 310 ms. container-test-run-flakelet-relay> agent # [ 2237.873293] agent systemd[1]: Reload requested from client PID 1604 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [ 2237.873325] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [ 2238.184333] agent systemd[1]: Reloading finished in 310 ms. container-test-run-flakelet-relay> agent # [ 2238.247719] agent systemd[1]: Starting app slow2... container-test-run-flakelet-relay> agent # [ 2244.318912] agent systemd[1]: Started app slow2. container-test-run-flakelet-relay> agent # [ 2244.341412] agent flakelet[1506]: app: updated to generation 4 container-test-run-flakelet-relay> agent # [ 2244.344292] 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.46 seconds) container-test-run-flakelet-relay> (finished: subtest: detach returns once the job is accepted, in 8.74 seconds) container-test-run-flakelet-relay> subtest: unknown host is final, missing agent is retried, agents listing container-test-run-flakelet-relay> client: must fail: push --relay https://relay:7443 --cert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin.pem --key /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin-key.pem deploy nope/app 2>&1 container-test-run-flakelet-relay> client: (finished: must fail: push --relay https://relay:7443 --cert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin.pem --key /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin-key.pem deploy nope/app 2>&1, in 0.02 seconds) container-test-run-flakelet-relay> agent: must succeed: systemctl stop flakelet-agent container-test-run-flakelet-relay> agent: (finished: must succeed: systemctl stop flakelet-agent, in 0.04 seconds) container-test-run-flakelet-relay> relay: waiting for success: curl -sf http://127.0.0.1:7400/metrics | grep -q 'agent_up{host="agent"} 0' container-test-run-flakelet-relay> relay: (finished: waiting for success: curl -sf http://127.0.0.1:7400/metrics | grep -q 'agent_up{host="agent"} 0', in 0.02 seconds) container-test-run-flakelet-relay> client: must succeed: push --relay https://relay:7443 --cert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin.pem --key /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin-key.pem deploy agent/other > /tmp/retry.out 2>&1 & container-test-run-flakelet-relay> client: (finished: must succeed: push --relay https://relay:7443 --cert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin.pem --key /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin-key.pem deploy agent/other > /tmp/retry.out 2>&1 &, in 0.01 seconds) container-test-run-flakelet-relay> client: waiting for success: grep -q 'agent_unavailable.*retrying' /tmp/retry.out container-test-run-flakelet-relay> agent # [ 2245.366458] agent systemd[1]: Stopping flakelet-agent... container-test-run-flakelet-relay> agent # [ 2245.393084] agent systemd[1]: flakelet-agent.service: Deactivated successfully. container-test-run-flakelet-relay> agent # [ 2245.393358] agent systemd[1]: Stopped flakelet-agent. container-test-run-flakelet-relay> agent # [ 2245.393694] agent systemd[1]: flakelet-agent.service: Consumed 1.601s CPU time over 28.860s wall clock time. container-test-run-flakelet-relay> relay # [ 2245.366821] relay flakelet-relay[400]: {"timestamp":"2026-09-06T07:42:03.328552Z","level":"INFO","message":"agent disconnected: websocket closed","host":"agent","target":"flakelet_relay::relay::agent_conn"} container-test-run-flakelet-relay> client: (finished: waiting for success: grep -q 'agent_unavailable.*retrying' /tmp/retry.out, in 1.02 seconds) container-test-run-flakelet-relay> agent: must succeed: systemctl start flakelet-agent container-test-run-flakelet-relay> agent: (finished: must succeed: systemctl start flakelet-agent, in 0.08 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> relay # [ 2246.613859] relay flakelet-relay[400]: {"timestamp":"2026-09-06T07:42:04.575588Z","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 # [ 2246.452746] agent systemd[1]: Starting flakelet-agent... container-test-run-flakelet-relay> agent # [ 2246.517087] agent flakelet-agent[1659]: {"timestamp":"2026-09-06T07:42:04.478884Z","level":"INFO","message":"reattaching to running unit","flakelet":"app","ids":"[\"4e550bf92c48404eb3a8ef52a5e5fbbd\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [ 2246.517215] agent systemd[1]: Started flakelet-agent. container-test-run-flakelet-relay> agent # [ 2246.613733] agent flakelet-agent[1659]: {"timestamp":"2026-09-06T07:42:04.575528Z","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 # [ 2247.064646] agent flakelet-agent[1659]: {"timestamp":"2026-09-06T07:42:05.026454Z","level":"INFO","message":"update finished","flakelet":"app","status":"Updated","generation":"Some(4)","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> relay # [ 2248.436297] relay flakelet-relay[400]: {"timestamp":"2026-09-06T07:42:06.398033Z","level":"INFO","message":"deploy accepted","job":"caf1edda8adad8c3ab51cc748c3a318b","caller":"x509:email:admin@example.org","targets":"[\"agent/other\"]","target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> agent # [ 2248.436808] agent flakelet-agent[1659]: {"timestamp":"2026-09-06T07:42:06.398539Z","level":"INFO","message":"start","id":"59ccfec0e10b82383726a98002a914fa","flakelet":"other","rule":"admins","relay":"relay","caller":"Some(\"x509:email:admin@example.org\")","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> agent # [ 2248.437713] agent flakelet-agent[1659]: {"timestamp":"2026-09-06T07:42:06.399310Z","level":"INFO","message":"update starting","flakelet":"other","ids":"[\"59ccfec0e10b82383726a98002a914fa\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [ 2248.493564] agent systemd[1]: Starting flakelet update other (via relay)... container-test-run-flakelet-relay> agent # [ 2248.528949] agent systemd[1]: Started flakelet update other (via relay). container-test-run-flakelet-relay> agent # [ 2248.533740] agent flakelet[2072]: other: using prebuilt artifact /nix/store/hmr990n0b4v6rpzrcpyyw738r9xi7z95-flakelet-other-v1 container-test-run-flakelet-relay> agent # [ 2248.534244] agent flakelet[2072]: other: up to date container-test-run-flakelet-relay> agent # [ 2248.536875] agent systemd[1]: flakelet-relay-job-other.service: Deactivated successfully. container-test-run-flakelet-relay> relay # [ 2249.088385] relay flakelet-relay[400]: {"timestamp":"2026-09-06T07:42:07.050117Z","level":"INFO","message":"deploy finished","job":"caf1edda8adad8c3ab51cc748c3a318b","ok":true,"target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> agent # [ 2249.087603] agent flakelet-agent[1659]: {"timestamp":"2026-09-06T07:42:07.049397Z","level":"INFO","message":"update finished","flakelet":"other","status":"Unchanged","generation":"Some(1)","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> client: (finished: waiting for success: grep -q 'agent/other: unchanged' /tmp/retry.out, in 3.05 seconds) container-test-run-flakelet-relay> client: must succeed: push --relay https://relay:7443 --cert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin.pem --key /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin-key.pem agents container-test-run-flakelet-relay> client: (finished: must succeed: push --relay https://relay:7443 --cert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin.pem --key /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin-key.pem agents, in 0.02 seconds) container-test-run-flakelet-relay> client: must succeed: echo '{"groups": []}' | step crypto jwt sign --key /nix/store/r7sclamg09v434w8zjlfnrcrn1w79wh6-flakelet-relay-test-issuer/jwk.json --iss https://relay:8443 --aud flakelet-relay --sub 'repo:github:example/app:ref:refs/heads/main' --exp $(( $(date +%s) + 300 )) --jti $(head -c8 /dev/urandom | base32) container-test-run-flakelet-relay> client: (finished: must succeed: echo '{"groups": []}' | step crypto jwt sign --key /nix/store/r7sclamg09v434w8zjlfnrcrn1w79wh6-flakelet-relay-test-issuer/jwk.json --iss https://relay:8443 --aud flakelet-relay --sub 'repo:github:example/app:ref:refs/heads/main' --exp $(( $(date +%s) + 300 )) --jti $(head -c8 /dev/urandom | base32), in 0.05 seconds) container-test-run-flakelet-relay> client: must succeed: FLAKELET_RELAY_TOKEN_COMMAND='echo eyJhbGciOiJFZERTQSIsImtpZCI6ImsxIiwidHlwIjoiSldUIn0.eyJhdWQiOiJmbGFrZWxldC1yZWxheSIsImV4cCI6MTc4ODY4MDgyNywiZ3JvdXBzIjpbXSwiaWF0IjoxNzg4NjgwNTI3LCJpc3MiOiJodHRwczovL3JlbGF5Ojg0NDMiLCJqdGkiOiJFVkFESkk3Q0pBUUk2PT09IiwibmJmIjoxNzg4NjgwNTI3LCJzdWIiOiJyZXBvOmdpdGh1YjpleGFtcGxlL2FwcDpyZWY6cmVmcy9oZWFkcy9tYWluIn0.DS1iA5S4cdKRxB71qzmQlzn3Za3PY_XeORFA1lUImOQYO_B0l0UWY_394fxPfXj5QQoWLuhEVbooQncmKc8ZDw' push --relay https://relay:7443 agents container-test-run-flakelet-relay> client: (finished: must succeed: FLAKELET_RELAY_TOKEN_COMMAND='echo eyJhbGciOiJFZERTQSIsImtpZCI6ImsxIiwidHlwIjoiSldUIn0.eyJhdWQiOiJmbGFrZWxldC1yZWxheSIsImV4cCI6MTc4ODY4MDgyNywiZ3JvdXBzIjpbXSwiaWF0IjoxNzg4NjgwNTI3LCJpc3MiOiJodHRwczovL3JlbGF5Ojg0NDMiLCJqdGkiOiJFVkFESkk3Q0pBUUk2PT09IiwibmJmIjoxNzg4NjgwNTI3LCJzdWIiOiJyZXBvOmdpdGh1YjpleGFtcGxlL2FwcDpyZWY6cmVmcy9oZWFkcy9tYWluIn0.DS1iA5S4cdKRxB71qzmQlzn3Za3PY_XeORFA1lUImOQYO_B0l0UWY_394fxPfXj5QQoWLuhEVbooQncmKc8ZDw' 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.32 seconds) container-test-run-flakelet-relay> subtest: certificate not listed under agents cannot connect as agent container-test-run-flakelet-relay> client: must succeed: curl -s -o /dev/null -w '%{http_code}' --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem --cert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/mallory/cert.pem --key /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/mallory/key.pem -H 'Upgrade: websocket' -H 'Connection: Upgrade' -H 'Sec-WebSocket-Version: 13' -H 'Sec-WebSocket-Key: dGhlIHNhbXBsZSBub25jZQ==' https://relay:7443/v1/agent container-test-run-flakelet-relay> client: (finished: must succeed: curl -s -o /dev/null -w '%{http_code}' --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem --cert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/mallory/cert.pem --key /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/mallory/key.pem -H 'Upgrade: websocket' -H 'Connection: Upgrade' -H 'Sec-WebSocket-Version: 13' -H 'Sec-WebSocket-Key: dGhlIHNhbXBsZSBub25jZQ==' https://relay:7443/v1/agent, in 0.03 seconds) container-test-run-flakelet-relay> (finished: subtest: certificate not listed under agents cannot connect as agent, in 0.03 seconds) container-test-run-flakelet-relay> subtest: metrics container-test-run-flakelet-relay> relay: must succeed: curl -sf http://127.0.0.1:7400/metrics container-test-run-flakelet-relay> relay: (finished: must succeed: curl -sf http://127.0.0.1:7400/metrics, in 0.01 seconds) container-test-run-flakelet-relay> (finished: subtest: metrics, in 0.01 seconds) container-test-run-flakelet-relay> subtest: agent link drop mid-run replays missed lines once container-test-run-flakelet-relay> agent: must succeed: jq '.services.app.prebuilt="/nix/store/c67jj8kx3m50nyj874aqmdqf21w5iznd-flakelet-app-slow"' /etc/flakelet/config.json > /tmp/config.json && cp --remove-destination /tmp/config.json /etc/flakelet/config.json container-test-run-flakelet-relay> agent: (finished: must succeed: jq '.services.app.prebuilt="/nix/store/c67jj8kx3m50nyj874aqmdqf21w5iznd-flakelet-app-slow"' /etc/flakelet/config.json > /tmp/config.json && cp --remove-destination /tmp/config.json /etc/flakelet/config.json, in 0.01 seconds) container-test-run-flakelet-relay> agent: must succeed: date +%s container-test-run-flakelet-relay> agent: (finished: must succeed: date +%s, in 0.01 seconds) container-test-run-flakelet-relay> client: must succeed: push --relay https://relay:7443 --cert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin.pem --key /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin-key.pem deploy agent/app > /tmp/slow.out 2>&1 & container-test-run-flakelet-relay> client: (finished: must succeed: push --relay https://relay:7443 --cert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin.pem --key /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin-key.pem deploy agent/app > /tmp/slow.out 2>&1 &, in 0.01 seconds) container-test-run-flakelet-relay> agent: waiting for success: journalctl -u flakelet-relay-job-app.service --since=@1788680527 | grep -q 'app-slow' container-test-run-flakelet-relay> relay # [ 2249.680483] relay flakelet-relay[400]: {"timestamp":"2026-09-06T07:42:07.642236Z","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 # [ 2249.735680] relay flakelet-relay[400]: {"timestamp":"2026-09-06T07:42:07.697435Z","level":"INFO","message":"deploy accepted","job":"ec4f42d1b62c7dbcffeaccacdb9bd018","caller":"x509:email:admin@example.org","targets":"[\"agent/app\"]","target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> agent # [ 2249.735937] agent flakelet-agent[1659]: {"timestamp":"2026-09-06T07:42:07.697693Z","level":"INFO","message":"start","id":"2af1249cd8219f3eabc92d4fc4bc7ec4","flakelet":"app","rule":"admins","relay":"relay","caller":"Some(\"x509:email:admin@example.org\")","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> agent # [ 2249.736748] agent flakelet-agent[1659]: {"timestamp":"2026-09-06T07:42:07.698100Z","level":"INFO","message":"update starting","flakelet":"app","ids":"[\"2af1249cd8219f3eabc92d4fc4bc7ec4\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [ 2249.790421] agent systemd[1]: Starting flakelet update app (via relay)... container-test-run-flakelet-relay> agent # [ 2249.820792] agent systemd[1]: Started flakelet update app (via relay). container-test-run-flakelet-relay> agent # [ 2249.825779] agent flakelet[2097]: app: using prebuilt artifact /nix/store/c67jj8kx3m50nyj874aqmdqf21w5iznd-flakelet-app-slow container-test-run-flakelet-relay> agent # [ 2249.826534] agent flakelet[2097]: app: activating generation 5 container-test-run-flakelet-relay> agent # [ 2249.839945] agent systemd[1]: Stopping app slow2... container-test-run-flakelet-relay> agent # [ 2249.840404] agent systemd[1]: app.service: Deactivated successfully. container-test-run-flakelet-relay> agent # [ 2249.840667] agent systemd[1]: Stopped app slow2. container-test-run-flakelet-relay> agent # [ 2249.858609] agent systemd[1]: Reload requested from client PID 2104 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [ 2249.858670] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [ 2250.182840] agent systemd[1]: Reloading finished in 323 ms. container-test-run-flakelet-relay> agent # [ 2250.262747] agent systemd[1]: Reload requested from client PID 2134 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [ 2250.262781] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [ 2250.573841] agent systemd[1]: Reloading finished in 310 ms. container-test-run-flakelet-relay> agent: (finished: waiting for success: journalctl -u flakelet-relay-job-app.service --since=@1788680527 | grep -q 'app-slow', in 1.03 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.09 seconds) container-test-run-flakelet-relay> agent: must succeed: ss -K dport = 7443 container-test-run-flakelet-relay> agent # [ 2250.647530] agent systemd[1]: Reload requested from client PID 2163 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [ 2250.647565] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent: (finished: must succeed: ss -K dport = 7443, in 0.03 seconds) container-test-run-flakelet-relay> agent: waiting for success: journalctl -u flakelet-relay-job-app.service --since=@1788680527 | grep -q 'updated to generation' container-test-run-flakelet-relay> relay # [ 2250.854118] relay flakelet-relay[400]: {"timestamp":"2026-09-06T07:42:08.815838Z","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 # [ 2250.883820] agent flakelet-agent[1659]: {"timestamp":"2026-09-06T07:42:08.845460Z","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> agent # [ 2250.963442] agent systemd[1]: Reloading finished in 315 ms. container-test-run-flakelet-relay> agent # [ 2251.042642] agent systemd[1]: Reload requested from client PID 2208 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [ 2251.042679] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [ 2251.356274] agent systemd[1]: Reloading finished in 313 ms. container-test-run-flakelet-relay> agent # [ 2251.417344] agent systemd[1]: Starting app slow... container-test-run-flakelet-relay> agent # [ 2251.967186] agent flakelet-agent[1659]: {"timestamp":"2026-09-06T07:42:09.928935Z","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 # [ 2254.044722] agent flakelet-agent[1659]: {"timestamp":"2026-09-06T07:42:12.006491Z","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 # [ 2257.494948] agent systemd[1]: Started app slow. container-test-run-flakelet-relay> agent # [ 2257.514752] agent flakelet[2097]: app: updated to generation 5 container-test-run-flakelet-relay> agent # [ 2257.517606] agent systemd[1]: flakelet-relay-job-app.service: Deactivated successfully. container-test-run-flakelet-relay> agent: (finished: waiting for success: journalctl -u flakelet-relay-job-app.service --since=@1788680527 | grep -q 'updated to generation', in 7.10 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 # [ 2258.123517] agent flakelet-agent[1659]: {"timestamp":"2026-09-06T07:42:16.085269Z","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 # [ 2258.124682] agent flakelet-agent[1659]: {"timestamp":"2026-09-06T07:42:16.086500Z","level":"INFO","message":"start","id":"2af1249cd8219f3eabc92d4fc4bc7ec4","flakelet":"app","rule":"admins","relay":"relay","caller":"Some(\"x509:email:admin@example.org\")","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> relay # [ 2258.123596] relay flakelet-relay[400]: {"timestamp":"2026-09-06T07:42:16.085329Z","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 # [ 2258.641995] agent flakelet-agent[1659]: {"timestamp":"2026-09-06T07:42:16.603732Z","level":"INFO","message":"update finished","flakelet":"app","status":"Updated","generation":"Some(5)","target":"flakelet_relay::agent::jobs"} 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.35 seconds) container-test-run-flakelet-relay> subtest: agent restart mid-run reattaches to the unit container-test-run-flakelet-relay> agent: must succeed: jq '.services.app.prebuilt="/nix/store/ln47rsd5km8a04hd5zlkz4q1v352zqh3-flakelet-app-slow2"' /etc/flakelet/config.json > /tmp/config.json && cp --remove-destination /tmp/config.json /etc/flakelet/config.json container-test-run-flakelet-relay> agent: (finished: must succeed: jq '.services.app.prebuilt="/nix/store/ln47rsd5km8a04hd5zlkz4q1v352zqh3-flakelet-app-slow2"' /etc/flakelet/config.json > /tmp/config.json && cp --remove-destination /tmp/config.json /etc/flakelet/config.json, in 0.01 seconds) container-test-run-flakelet-relay> client: must succeed: push --relay https://relay:7443 --cert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin.pem --key /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin-key.pem deploy agent/app > /tmp/restart.out 2>&1 & container-test-run-flakelet-relay> client: (finished: must succeed: push --relay https://relay:7443 --cert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin.pem --key /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin-key.pem deploy agent/app > /tmp/restart.out 2>&1 &, in 0.01 seconds) container-test-run-flakelet-relay> agent: waiting for success: journalctl -u flakelet-relay-job-app.service --since -20s | grep -q 'app-slow2' container-test-run-flakelet-relay> relay # [ 2258.642961] relay flakelet-relay[400]: {"timestamp":"2026-09-06T07:42:16.604710Z","level":"INFO","message":"deploy finished","job":"ec4f42d1b62c7dbcffeaccacdb9bd018","ok":true,"target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> relay # [ 2259.074918] relay flakelet-relay[400]: {"timestamp":"2026-09-06T07:42:17.036574Z","level":"INFO","message":"deploy accepted","job":"96e73d09106181f1848873f54f77d16b","caller":"x509:email:admin@example.org","targets":"[\"agent/app\"]","target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> agent # [ 2259.075252] agent flakelet-agent[1659]: {"timestamp":"2026-09-06T07:42:17.036963Z","level":"INFO","message":"start","id":"4b036cfffd7f078b69601eb3ded6a7b5","flakelet":"app","rule":"admins","relay":"relay","caller":"Some(\"x509:email:admin@example.org\")","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> agent # [ 2259.075809] agent flakelet-agent[1659]: {"timestamp":"2026-09-06T07:42:17.037628Z","level":"INFO","message":"update starting","flakelet":"app","ids":"[\"4b036cfffd7f078b69601eb3ded6a7b5\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [ 2259.126638] agent systemd[1]: Starting flakelet update app (via relay)... container-test-run-flakelet-relay> agent # [ 2259.154822] agent systemd[1]: Started flakelet update app (via relay). container-test-run-flakelet-relay> agent # [ 2259.158996] agent flakelet[2329]: app: using prebuilt artifact /nix/store/ln47rsd5km8a04hd5zlkz4q1v352zqh3-flakelet-app-slow2 container-test-run-flakelet-relay> agent # [ 2259.159633] agent flakelet[2329]: app: activating generation 6 container-test-run-flakelet-relay> agent # [ 2259.173046] agent systemd[1]: Stopping app slow... container-test-run-flakelet-relay> agent # [ 2259.173497] agent systemd[1]: app.service: Deactivated successfully. container-test-run-flakelet-relay> agent # [ 2259.173760] agent systemd[1]: Stopped app slow. container-test-run-flakelet-relay> agent # [ 2259.192191] agent systemd[1]: Reload requested from client PID 2336 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [ 2259.192255] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [ 2259.517846] agent systemd[1]: Reloading finished in 324 ms. container-test-run-flakelet-relay> agent # [ 2259.581957] agent systemd[1]: Reload requested from client PID 2366 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [ 2259.581989] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [ 2259.894518] agent systemd[1]: Reloading finished in 312 ms. container-test-run-flakelet-relay> agent: (finished: waiting for success: journalctl -u flakelet-relay-job-app.service --since -20s | grep -q 'app-slow2', in 1.03 seconds) container-test-run-flakelet-relay> agent: must succeed: systemctl restart flakelet-agent container-test-run-flakelet-relay> agent # [ 2259.961909] agent systemd[1]: Reload requested from client PID 2395 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [ 2259.961943] agent systemd[1]: Reloading... container-test-run-flakelet-relay> relay # [ 2260.333778] relay flakelet-relay[400]: {"timestamp":"2026-09-06T07:42:18.295513Z","level":"INFO","message":"agent disconnected: websocket closed","host":"agent","target":"flakelet_relay::relay::agent_conn"} container-test-run-flakelet-relay> agent # [ 2260.273721] agent systemd[1]: Reloading finished in 311 ms. container-test-run-flakelet-relay> agent # [ 2260.333270] agent systemd[1]: Stopping flakelet-agent... container-test-run-flakelet-relay> agent # [ 2260.341368] agent systemd[1]: Reload requested from client PID 2430 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [ 2260.341402] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent: (finished: must succeed: systemctl restart flakelet-agent, in 0.69 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> relay # [ 2260.847689] relay flakelet-relay[400]: {"timestamp":"2026-09-06T07:42:18.809438Z","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 # [ 2260.658805] agent systemd[1]: Reloading finished in 317 ms. container-test-run-flakelet-relay> agent # [ 2260.717774] agent systemd[1]: flakelet-agent.service: Deactivated successfully. container-test-run-flakelet-relay> agent # [ 2260.717947] agent systemd[1]: Stopped flakelet-agent. container-test-run-flakelet-relay> agent # [ 2260.717959] agent systemd[1]: flakelet-agent.service: Consumed 1.074s CPU time over 14.265s wall clock time. container-test-run-flakelet-relay> agent # [ 2260.721711] agent systemd[1]: Starting flakelet-agent... container-test-run-flakelet-relay> agent # [ 2260.724533] agent systemd[1]: Starting app slow2... container-test-run-flakelet-relay> agent # [ 2260.778022] agent flakelet-agent[2461]: {"timestamp":"2026-09-06T07:42:18.739814Z","level":"INFO","message":"reattaching to running unit","flakelet":"app","ids":"[\"4b036cfffd7f078b69601eb3ded6a7b5\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [ 2260.778116] agent systemd[1]: Started flakelet-agent. container-test-run-flakelet-relay> agent # [ 2260.847642] agent flakelet-agent[2461]: {"timestamp":"2026-09-06T07:42:18.809381Z","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 # [ 2260.848791] agent flakelet-agent[2461]: {"timestamp":"2026-09-06T07:42:18.810609Z","level":"INFO","message":"start","id":"4b036cfffd7f078b69601eb3ded6a7b5","flakelet":"app","rule":"admins","relay":"relay","caller":"Some(\"x509:email:admin@example.org\")","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> agent # [ 2266.790886] agent systemd[1]: Started app slow2. container-test-run-flakelet-relay> agent # [ 2266.810795] agent flakelet[2329]: app: updated to generation 6 container-test-run-flakelet-relay> agent # [ 2266.813743] agent systemd[1]: flakelet-relay-job-app.service: Deactivated successfully. container-test-run-flakelet-relay> agent # [ 2267.377991] agent flakelet-agent[2461]: {"timestamp":"2026-09-06T07:42:25.339717Z","level":"INFO","message":"update finished","flakelet":"app","status":"Updated","generation":"Some(6)","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> relay # [ 2267.378912] relay flakelet-relay[400]: {"timestamp":"2026-09-06T07:42:25.340658Z","level":"INFO","message":"deploy finished","job":"96e73d09106181f1848873f54f77d16b","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 8.85 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=@1788680545 | grep -q 'app-slow' container-test-run-flakelet-relay> relay # [ 2267.936273] relay flakelet-relay[400]: {"timestamp":"2026-09-06T07:42:25.898042Z","level":"INFO","message":"deploy accepted","job":"fde20cb5964df9b6c5b9315af9b68c6e","caller":"x509:email:admin@example.org","targets":"[\"agent/app\"]","target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> agent # [ 2267.936680] agent flakelet-agent[2461]: {"timestamp":"2026-09-06T07:42:25.898423Z","level":"INFO","message":"start","id":"8f7437c78ce48ccd679873219f10d620","flakelet":"app","rule":"admins","relay":"relay","caller":"Some(\"x509:email:admin@example.org\")","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> agent # [ 2267.937283] agent flakelet-agent[2461]: {"timestamp":"2026-09-06T07:42:25.899101Z","level":"INFO","message":"update starting","flakelet":"app","ids":"[\"8f7437c78ce48ccd679873219f10d620\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [ 2267.987561] agent systemd[1]: Starting flakelet update app (via relay)... container-test-run-flakelet-relay> agent # [ 2268.021871] agent systemd[1]: Started flakelet update app (via relay). container-test-run-flakelet-relay> agent # [ 2268.025571] agent flakelet[2896]: app: using prebuilt artifact /nix/store/c67jj8kx3m50nyj874aqmdqf21w5iznd-flakelet-app-slow container-test-run-flakelet-relay> agent # [ 2268.026137] agent flakelet[2896]: app: activating generation 7 container-test-run-flakelet-relay> agent # [ 2268.040462] agent systemd[1]: Stopping app slow2... container-test-run-flakelet-relay> agent # [ 2268.042119] agent systemd[1]: app.service: Deactivated successfully. container-test-run-flakelet-relay> agent # [ 2268.042376] agent systemd[1]: Stopped app slow2. container-test-run-flakelet-relay> agent # [ 2268.058022] agent systemd[1]: Reload requested from client PID 2903 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [ 2268.058085] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [ 2268.383929] agent systemd[1]: Reloading finished in 325 ms. container-test-run-flakelet-relay> agent # [ 2268.455474] agent systemd[1]: Reload requested from client PID 2933 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [ 2268.455507] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent: (finished: waiting for success: journalctl -u flakelet-relay-job-app.service --since=@1788680545 | grep -q 'app-slow', in 1.02 seconds) container-test-run-flakelet-relay> relay: must succeed: systemctl restart flakelet-relay container-test-run-flakelet-relay> agent # [ 2268.766158] agent systemd[1]: Reloading finished in 310 ms. container-test-run-flakelet-relay> agent # [ 2268.834507] agent systemd[1]: Reload requested from client PID 2962 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [ 2268.834543] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [ 2268.958370] agent flakelet-agent[2461]: {"timestamp":"2026-09-06T07:42:26.920102Z","level":"INFO","message":"disconnected: websocket closed","relay":"https://relay:7443","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> relay # [ 2268.957672] relay systemd[1]: Stopping flakelet-relay... container-test-run-flakelet-relay> relay # [ 2269.006307] relay systemd[1]: flakelet-relay.service: Deactivated successfully. container-test-run-flakelet-relay> relay # [ 2269.006571] relay systemd[1]: Stopped flakelet-relay. container-test-run-flakelet-relay> relay # [ 2269.006612] relay systemd[1]: flakelet-relay.service: Consumed 2.371s CPU time over 51.331s wall clock time. container-test-run-flakelet-relay> relay # [ 2269.023116] relay systemd[1]: Starting flakelet-relay... container-test-run-flakelet-relay> agent # [ 2269.155720] agent systemd[1]: Reloading finished in 320 ms. container-test-run-flakelet-relay> agent # [ 2269.234030] agent systemd[1]: Reload requested from client PID 2996 ('systemctl') (unit flakelet-relay-job-app.service)... container-test-run-flakelet-relay> agent # [ 2269.234060] agent systemd[1]: Reloading... container-test-run-flakelet-relay> agent # [ 2269.550951] agent systemd[1]: Reloading finished in 316 ms. container-test-run-flakelet-relay> agent # [ 2269.633851] agent systemd[1]: Starting app slow... container-test-run-flakelet-relay> agent # [ 2270.043748] agent flakelet-agent[2461]: {"timestamp":"2026-09-06T07:42:28.005473Z","level":"WARN","message":"connect failed: connect relay:7443: Connection refused (os error 111)","relay":"https://relay:7443","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> relay: (finished: must succeed: systemctl restart flakelet-relay, in 1.48 seconds) container-test-run-flakelet-relay> client: waiting for success: grep -q '» ok' /tmp/resume.out container-test-run-flakelet-relay> relay # [ 2270.426943] relay flakelet-relay[934]: {"timestamp":"2026-09-06T07:42:28.388706Z","level":"INFO","message":"listening on 127.0.0.1:7400","target":"flakelet_relay::relay::server"} container-test-run-flakelet-relay> relay # [ 2270.427522] relay flakelet-relay[934]: {"timestamp":"2026-09-06T07:42:28.389213Z","level":"INFO","message":"listening on [::]:7443","target":"flakelet_relay::relay::server"} container-test-run-flakelet-relay> relay # [ 2270.427467] relay systemd[1]: Started flakelet-relay. container-test-run-flakelet-relay> relay # [ 2272.111826] relay flakelet-relay[934]: {"timestamp":"2026-09-06T07:42:30.073597Z","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> agent # [ 2272.111766] agent flakelet-agent[2461]: {"timestamp":"2026-09-06T07:42:30.073510Z","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 # [ 2275.700942] agent systemd[1]: Started app slow. container-test-run-flakelet-relay> agent # [ 2275.719047] agent flakelet[2896]: app: updated to generation 7 container-test-run-flakelet-relay> agent # [ 2275.722220] agent systemd[1]: flakelet-relay-job-app.service: Deactivated successfully. container-test-run-flakelet-relay> relay # [ 2275.979743] relay flakelet-relay[934]: {"timestamp":"2026-09-06T07:42:33.941492Z","level":"INFO","message":"job reattached","job":"fde20cb5964df9b6c5b9315af9b68c6e","targets":"[\"agent/app\"]","target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> agent # [ 2276.825434] agent flakelet-agent[2461]: {"timestamp":"2026-09-06T07:42:34.787178Z","level":"INFO","message":"update finished","flakelet":"app","status":"Updated","generation":"Some(7)","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> client: (finished: waiting for success: grep -q '» ok' /tmp/resume.out, in 7.08 seconds) container-test-run-flakelet-relay> client: must succeed: cat /tmp/resume.out container-test-run-flakelet-relay> client: (finished: must succeed: cat /tmp/resume.out, in 0.01 seconds) container-test-run-flakelet-relay> (finished: subtest: relay restart mid-run, push resumes via /v1/jobs, in 9.63 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 # [ 2277.538913] relay flakelet-relay[934]: {"timestamp":"2026-09-06T07:42:35.500653Z","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 # [ 2277.539482] agent flakelet-agent[2461]: {"timestamp":"2026-09-06T07:42:35.501201Z","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 # [ 2277.540156] agent flakelet-agent[2461]: {"timestamp":"2026-09-06T07:42:35.501972Z","level":"INFO","message":"update starting","flakelet":"other","ids":"[\"5be20c693e5c0b60e3f85779794dfb86\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [ 2277.596279] agent systemd[1]: Starting flakelet update other (via relay)... container-test-run-flakelet-relay> agent # [ 2277.630223] agent systemd[1]: Started flakelet update other (via relay). container-test-run-flakelet-relay> agent # [ 2277.635841] agent flakelet[3073]: other: using prebuilt artifact /nix/store/hmr990n0b4v6rpzrcpyyw738r9xi7z95-flakelet-other-v1 container-test-run-flakelet-relay> agent # [ 2277.636401] agent flakelet[3073]: other: up to date container-test-run-flakelet-relay> agent # [ 2277.637629] 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.67 seconds) container-test-run-flakelet-relay> relay: must succeed: systemctl restart flakelet-relay container-test-run-flakelet-relay> relay # [ 2278.188193] relay flakelet-relay[934]: {"timestamp":"2026-09-06T07:42:36.149927Z","level":"INFO","message":"deploy finished","job":"188b06c4636b65849b3ba8daac22dc7c","ok":true,"target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> relay # [ 2278.205320] relay systemd[1]: Stopping flakelet-relay... container-test-run-flakelet-relay> relay # [ 2278.240843] relay systemd[1]: flakelet-relay.service: Deactivated successfully. container-test-run-flakelet-relay> relay # [ 2278.241309] relay systemd[1]: Stopped flakelet-relay. container-test-run-flakelet-relay> relay # [ 2278.241351] relay systemd[1]: flakelet-relay.service: Consumed 2.651s CPU time over 9.217s wall clock time. container-test-run-flakelet-relay> relay # [ 2278.276811] relay systemd[1]: Starting flakelet-relay... container-test-run-flakelet-relay> agent # [ 2278.187451] agent flakelet-agent[2461]: {"timestamp":"2026-09-06T07:42:36.149200Z","level":"INFO","message":"update finished","flakelet":"other","status":"Unchanged","generation":"Some(1)","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [ 2278.205529] agent flakelet-agent[2461]: {"timestamp":"2026-09-06T07:42:36.167307Z","level":"INFO","message":"disconnected: websocket closed","relay":"https://relay:7443","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> agent # [ 2279.281895] agent flakelet-agent[2461]: {"timestamp":"2026-09-06T07:42:37.243608Z","level":"WARN","message":"connect failed: connect relay:7443: Connection refused (os error 111)","relay":"https://relay:7443","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> relay: (finished: must succeed: systemctl restart flakelet-relay, in 1.48 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 # [ 2279.664929] relay flakelet-relay[1324]: {"timestamp":"2026-09-06T07:42:37.626653Z","level":"INFO","message":"listening on 127.0.0.1:7400","target":"flakelet_relay::relay::server"} container-test-run-flakelet-relay> relay # [ 2279.665792] relay flakelet-relay[1324]: {"timestamp":"2026-09-06T07:42:37.627099Z","level":"INFO","message":"listening on [::]:7443","target":"flakelet_relay::relay::server"} container-test-run-flakelet-relay> relay # [ 2279.665457] relay systemd[1]: Started flakelet-relay. container-test-run-flakelet-relay> agent # [ 2281.372658] agent flakelet-agent[2461]: {"timestamp":"2026-09-06T07:42:39.334396Z","level":"INFO","message":"connected","relay":"https://relay:7443","relay_name":"relay","relay_version":"0.1.0","host":"agent","target":"flakelet_relay::agent::conn"} container-test-run-flakelet-relay> relay: (finished: waiting for success: curl -sf http://127.0.0.1:7400/metrics | grep -q 'agent_up{host="agent"} 1', in 2.06 seconds) container-test-run-flakelet-relay> client: must succeed: push --relay https://relay:7443 --cert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin.pem --key /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin-key.pem jobs container-test-run-flakelet-relay> client: (finished: must succeed: push --relay https://relay:7443 --cert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin.pem --key /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin-key.pem jobs, in 0.02 seconds) container-test-run-flakelet-relay> client: must succeed: echo '{"groups": []}' | step crypto jwt sign --key /nix/store/r7sclamg09v434w8zjlfnrcrn1w79wh6-flakelet-relay-test-issuer/jwk.json --iss https://relay:8443 --aud flakelet-relay --sub 'repo:github:example/app:ref:refs/heads/main' --exp $(( $(date +%s) + 300 )) --jti $(head -c8 /dev/urandom | base32) container-test-run-flakelet-relay> client: (finished: must succeed: echo '{"groups": []}' | step crypto jwt sign --key /nix/store/r7sclamg09v434w8zjlfnrcrn1w79wh6-flakelet-relay-test-issuer/jwk.json --iss https://relay:8443 --aud flakelet-relay --sub 'repo:github:example/app:ref:refs/heads/main' --exp $(( $(date +%s) + 300 )) --jti $(head -c8 /dev/urandom | base32), in 0.05 seconds) container-test-run-flakelet-relay> client: must succeed: FLAKELET_RELAY_TOKEN_COMMAND='echo eyJhbGciOiJFZERTQSIsImtpZCI6ImsxIiwidHlwIjoiSldUIn0.eyJhdWQiOiJmbGFrZWxldC1yZWxheSIsImV4cCI6MTc4ODY4MDg1OSwiZ3JvdXBzIjpbXSwiaWF0IjoxNzg4NjgwNTU5LCJpc3MiOiJodHRwczovL3JlbGF5Ojg0NDMiLCJqdGkiOiJVQkZKNVhSWlBFRko2PT09IiwibmJmIjoxNzg4NjgwNTU5LCJzdWIiOiJyZXBvOmdpdGh1YjpleGFtcGxlL2FwcDpyZWY6cmVmcy9oZWFkcy9tYWluIn0.4dDbj_TotTyJta01VYwXU7couwAAtAALA5ayYyUGYAm-Hc3c5A8a33HbwbzeYV2m2T_ndE9_e014J-vgdDznAw' push --relay https://relay:7443 jobs container-test-run-flakelet-relay> client: (finished: must succeed: FLAKELET_RELAY_TOKEN_COMMAND='echo eyJhbGciOiJFZERTQSIsImtpZCI6ImsxIiwidHlwIjoiSldUIn0.eyJhdWQiOiJmbGFrZWxldC1yZWxheSIsImV4cCI6MTc4ODY4MDg1OSwiZ3JvdXBzIjpbXSwiaWF0IjoxNzg4NjgwNTU5LCJpc3MiOiJodHRwczovL3JlbGF5Ojg0NDMiLCJqdGkiOiJVQkZKNVhSWlBFRko2PT09IiwibmJmIjoxNzg4NjgwNTU5LCJzdWIiOiJyZXBvOmdpdGh1YjpleGFtcGxlL2FwcDpyZWY6cmVmcy9oZWFkcy9tYWluIn0.4dDbj_TotTyJta01VYwXU7couwAAtAALA5ayYyUGYAm-Hc3c5A8a33HbwbzeYV2m2T_ndE9_e014J-vgdDznAw' 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.29 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> relay # [ 2281.372757] relay flakelet-relay[1324]: {"timestamp":"2026-09-06T07:42:39.334491Z","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> client: (finished: must succeed: curl -sS --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem -b /tmp/jar -c /tmp/jar -L https://relay:7443/ui/login, in 0.08 seconds) container-test-run-flakelet-relay> client: must succeed: curl -sS --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem -b /tmp/jar -c /tmp/jar https://relay:7443/ui/jobs container-test-run-flakelet-relay> client: (finished: must succeed: curl -sS --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem -b /tmp/jar -c /tmp/jar https://relay:7443/ui/jobs, in 0.02 seconds) container-test-run-flakelet-relay> client: must succeed: curl -sS --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem -b /tmp/jar -c /tmp/jar -f https://relay:7443/v1/jobs | grep -q hist1 container-test-run-flakelet-relay> client: (finished: must succeed: curl -sS --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem -b /tmp/jar -c /tmp/jar -f https://relay:7443/v1/jobs | grep -q hist1, in 0.02 seconds) container-test-run-flakelet-relay> client: must succeed: curl -sS --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem -b /tmp/jar -c /tmp/jar -N --max-time 10 https://relay:7443/ui/jobs/hist1/events || true container-test-run-flakelet-relay> client: (finished: must succeed: curl -sS --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem -b /tmp/jar -c /tmp/jar -N --max-time 10 https://relay:7443/ui/jobs/hist1/events || true, in 0.02 seconds) container-test-run-flakelet-relay> client: must succeed: curl -sS --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem -b /tmp/jar -c /tmp/jar -o /dev/null -w '%{http_code}' -X POST 'https://relay:7443/ui/deploy?arg=other' | grep -q 403 container-test-run-flakelet-relay> client: (finished: must succeed: curl -sS --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem -b /tmp/jar -c /tmp/jar -o /dev/null -w '%{http_code}' -X POST 'https://relay:7443/ui/deploy?arg=other' | grep -q 403, in 0.02 seconds) container-test-run-flakelet-relay> client: must succeed: curl -sS --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem -b /tmp/jar -c /tmp/jar -D - -o /dev/null -H 'HX-Request: true' -X POST 'https://relay:7443/ui/deploy?arg=other' container-test-run-flakelet-relay> client: (finished: must succeed: curl -sS --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem -b /tmp/jar -c /tmp/jar -D - -o /dev/null -H 'HX-Request: true' -X POST 'https://relay:7443/ui/deploy?arg=other', in 0.02 seconds) container-test-run-flakelet-relay> client: must succeed: curl -sS --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem -b /tmp/jar -c /tmp/jar -N --max-time 30 https://relay:7443/ui/jobs/cadc2281e7f810d8ad03fd0b96a0247d/events || true container-test-run-flakelet-relay> relay # [ 2281.866324] relay 93sn3sv24fj26ksccpppzwg6kbf10s4w-device-mock[186]: 127.0.0.1 - - [06/Sep/2026 07:42:39] "GET /authorize?response_type=code&client_id=dashboard&redirect_uri=https%3A%2F%2Frelay%3A7443%2Fui%2Fcallback&scope=openid%20profile%20email%20groups&state=lLS8oQzBR7vw5nZL9qNEmHTrfiz7b3oVRiyRNxohjfY&code_challenge=Nsz6NEgG0cWOV-zzZ1i2IpeKGt38BhSEbDvpe3BSaNo&code_challenge_method=S256 HTTP/1.1" 302 - container-test-run-flakelet-relay> relay # [ 2281.915674] relay 93sn3sv24fj26ksccpppzwg6kbf10s4w-device-mock[186]: 127.0.0.1 - - [06/Sep/2026 07:42:39] "POST /token HTTP/1.1" 200 - container-test-run-flakelet-relay> relay # [ 2281.916639] relay flakelet-relay[1324]: {"timestamp":"2026-09-06T07:42:39.878445Z","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 # [ 2281.979334] relay flakelet-relay[1324]: {"timestamp":"2026-09-06T07:42:39.941106Z","level":"INFO","message":"job reattached","job":"188b06c4636b65849b3ba8daac22dc7c","targets":"[\"agent/other\"]","target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> relay # [ 2282.020231] relay flakelet-relay[1324]: {"timestamp":"2026-09-06T07:42:39.982000Z","level":"INFO","message":"deploy accepted","job":"20713f949ca77b3929edf393830b56d6","caller":"oidc:mock:someone\noidc:mock:groups:wheel","targets":"[\"agent/other\"]","target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> relay # [ 2282.041162] relay flakelet-relay[1324]: {"timestamp":"2026-09-06T07:42:40.002967Z","level":"INFO","message":"job reattached","job":"20713f949ca77b3929edf393830b56d6","targets":"[\"agent/other\"]","target":"flakelet_relay::relay::api"} container-test-run-flakelet-relay> agent # [ 2282.020560] agent flakelet-agent[2461]: {"timestamp":"2026-09-06T07:42:39.982300Z","level":"INFO","message":"start","id":"cbc835294da48a4e99621caacc0c39c6","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 # [ 2282.020898] agent flakelet-agent[2461]: {"timestamp":"2026-09-06T07:42:39.982622Z","level":"INFO","message":"update starting","flakelet":"other","ids":"[\"cbc835294da48a4e99621caacc0c39c6\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [ 2282.072159] agent systemd[1]: Starting flakelet update other (via relay)... container-test-run-flakelet-relay> agent # [ 2282.109932] agent systemd[1]: Started flakelet update other (via relay). container-test-run-flakelet-relay> agent # [ 2282.114957] agent flakelet[3111]: other: using prebuilt artifact /nix/store/hmr990n0b4v6rpzrcpyyw738r9xi7z95-flakelet-other-v1 container-test-run-flakelet-relay> agent # [ 2282.115471] agent flakelet[3111]: other: up to date container-test-run-flakelet-relay> agent # [ 2282.117759] 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/cadc2281e7f810d8ad03fd0b96a0247d/events || true, in 0.65 seconds) container-test-run-flakelet-relay> client: must succeed: curl -sS --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem -b /tmp/jar -c /tmp/jar https://relay:7443/ui/jobs/cadc2281e7f810d8ad03fd0b96a0247d 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/cadc2281e7f810d8ad03fd0b96a0247d, in 0.02 seconds) container-test-run-flakelet-relay> client: must succeed: curl -sS --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem -b /tmp/jar -c /tmp/jar -sf -o /dev/null https://relay:7443/ui/static/htmx.min.js container-test-run-flakelet-relay> client: (finished: must succeed: curl -sS --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem -b /tmp/jar -c /tmp/jar -sf -o /dev/null https://relay:7443/ui/static/htmx.min.js, in 0.02 seconds) container-test-run-flakelet-relay> client: must succeed: curl -sS --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem -b /tmp/jar -c /tmp/jar 'https://relay:7443/ui/?q=status%3Ahealthy+oth' container-test-run-flakelet-relay> client: (finished: must succeed: curl -sS --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem -b /tmp/jar -c /tmp/jar 'https://relay:7443/ui/?q=status%3Ahealthy+oth', in 0.02 seconds) container-test-run-flakelet-relay> client: must succeed: curl -sS --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem -b /tmp/jar -c /tmp/jar https://relay:7443/ui/flakelets/other container-test-run-flakelet-relay> client: (finished: must succeed: curl -sS --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem -b /tmp/jar -c /tmp/jar https://relay:7443/ui/flakelets/other, in 0.02 seconds) container-test-run-flakelet-relay> client: must succeed: curl -sS --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem -b /tmp/jar -c /tmp/jar 'https://relay:7443/ui/hosts?q=status%3Adisconnected' container-test-run-flakelet-relay> client: (finished: must succeed: curl -sS --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem -b /tmp/jar -c /tmp/jar 'https://relay:7443/ui/hosts?q=status%3Adisconnected', in 0.02 seconds) container-test-run-flakelet-relay> client: must succeed: curl -sS --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem -b /tmp/jar -c /tmp/jar -o /dev/null -w '%{http_code}' -H 'HX-Request: true' -X POST 'https://relay:7443/ui/retry?arg=cadc2281e7f810d8ad03fd0b96a0247d' | 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=cadc2281e7f810d8ad03fd0b96a0247d' | grep -q 400, in 0.02 seconds) container-test-run-flakelet-relay> client: must succeed: curl -sS --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem -b /tmp/jar -c /tmp/jar -N --max-time 15 'https://relay:7443/ui/events?path=jobs' > /tmp/ev.out 2>&1 & container-test-run-flakelet-relay> client: (finished: must succeed: curl -sS --cacert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/ca.pem -b /tmp/jar -c /tmp/jar -N --max-time 15 'https://relay:7443/ui/events?path=jobs' > /tmp/ev.out 2>&1 &, in 0.01 seconds) container-test-run-flakelet-relay> client: must succeed: push --relay https://relay:7443 --cert /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin.pem --key /nix/store/ydql3jgw8j7khsad67k605wlnywsd36n-flakelet-relay-test-certs/admin-key.pem deploy --id live1 agent/other container-test-run-flakelet-relay> » job 38796a7ba508eadfe82a1cd5def000bc via relay 0.1.0 (agents: agent 0.1.0) container-test-run-flakelet-relay> » wave 0 container-test-run-flakelet-relay> agent/other | Starting flakelet update other (via relay)... container-test-run-flakelet-relay> agent/other | Started flakelet update other (via relay). container-test-run-flakelet-relay> agent/other | other: using prebuilt artifact /nix/store/hmr990n0b4v6rpzrcpyyw738r9xi7z95-flakelet-other-v1 container-test-run-flakelet-relay> agent/other | other: up to date container-test-run-flakelet-relay> relay # [ 2282.819250] relay flakelet-relay[1324]: {"timestamp":"2026-09-06T07:42:40.780990Z","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 # [ 2282.670034] agent flakelet-agent[2461]: {"timestamp":"2026-09-06T07:42:40.631778Z","level":"INFO","message":"update finished","flakelet":"other","status":"Unchanged","generation":"Some(1)","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [ 2282.819660] agent flakelet-agent[2461]: {"timestamp":"2026-09-06T07:42:40.781404Z","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 # [ 2282.820290] agent flakelet-agent[2461]: {"timestamp":"2026-09-06T07:42:40.782113Z","level":"INFO","message":"update starting","flakelet":"other","ids":"[\"5e317fc57863c2a7c03ebe1bdacd1356\"]","target":"flakelet_relay::agent::jobs"} container-test-run-flakelet-relay> agent # [ 2282.873476] agent systemd[1]: Starting flakelet update other (via relay)... container-test-run-flakelet-relay> agent # [ 2282.905018] agent systemd[1]: Started flakelet update other (via relay). container-test-run-flakelet-relay> agent # [ 2282.908735] agent flakelet[3129]: other: using prebuilt artifact /nix/store/hmr990n0b4v6rpzrcpyyw738r9xi7z95-flakelet-other-v1 container-test-run-flakelet-relay> agent # [ 2282.909320] agent flakelet[3129]: other: up to date container-test-run-flakelet-relay> agent # [ 2282.912180] 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.67 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.71 seconds) container-test-run-flakelet-relay> (finished: run the VM test script, in 69.37 seconds) container-test-run-flakelet-relay> test script finished in 69.41s container-test-run-flakelet-relay> cleanup container-test-run-flakelet-relay> kill NspawnMachine (pid 51) container-test-run-flakelet-relay> kill NspawnMachine (pid 53) container-test-run-flakelet-relay> kill NspawnMachine (pid 56) container-test-run-flakelet-relay> Container agent terminated by signal KILL. 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.34 seconds) container-test-run-flakelet-relay> additionally exposed symbols: container-test-run-flakelet-relay> agent, client, relay, container-test-run-flakelet-relay> vlan1, container-test-run-flakelet-relay> start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh container-test-run-flakelet-relay> » job 99d8f4ffea21836a79f060ade55fa45a 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 8e30ee8719b8e60ba6d476e79c5b976b 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 ec4f42d1b62c7dbcffeaccacdb9bd018 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 96e73d09106181f1848873f54f77d16b 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 fde20cb5964df9b6c5b9315af9b68c6e 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 7dc055ed2e10ca34554087a837410b8e 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 fde20cb5964df9b6c5b9315af9b68c6e 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> 1788680555 hist1 x509:email:admin@example.org agent/other:unchanged container-test-run-flakelet-relay> 1788680545 7dc055ed2e10ca34554087a837410b8e x509:email:admin@example.org agent/app:updated container-test-run-flakelet-relay> 1788680537 792f0a2d7f9a1c2ea672cdf708c91f94 x509:email:admin@example.org agent/app:updated container-test-run-flakelet-relay> 1788680527 70e9115f5b24ad004e82c2e70adcfed2 x509:email:admin@example.org agent/app:updated container-test-run-flakelet-relay> 1788680526 64edc7c65ba1366717230a63de7e3ecc x509:email:admin@example.org agent/other:unchanged container-test-run-flakelet-relay> 1788680514 813874768d8dd1d85de26041656d90ce x509:email:admin@example.org agent/app:updated container-test-run-flakelet-relay> 1788680510 3b6ffc23f2a074bf40510548140964a0 x509:email:admin@example.org agent/app:rolled-back container-test-run-flakelet-relay> 1788680509 4b37caa52b91db0cce33b54298956bd5 oidc:mock:groups:wheel agent/other:unchanged container-test-run-flakelet-relay> 1788680509 9c7a526ee72049f60a12c55b952b64f0 oidc:mock:groups:wheel agent/other:unchanged container-test-run-flakelet-relay> 1788680506 d76cdbfd18e8ba04acf92c4c87b2f046 oidc:mock:groups:wheel agent/other:unchanged container-test-run-flakelet-relay> 1788680503 834741d8b7fb18342f37ff284c6ee965 oidc:mock:repo:github:example/app:ref:refs/heads/main agent/app:updated container-test-run-flakelet-relay> 1788680500 8e2b89570da4f38e75505c778a49d06f 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