[INFO] cloning repository https://github.com/foxtive/foxtive
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/foxtive/foxtive" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Ffoxtive%2Ffoxtive", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Ffoxtive%2Ffoxtive'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] bb657875e7a4f61f28ce78d5d309ecac08d87d43
[INFO] testing foxtive/foxtive/bb657875e7a4f61f28ce78d5d309ecac08d87d43 against master#f9988fefd3add01f414f52b414308e7872622fee for pr-155114
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Ffoxtive%2Ffoxtive" "/workspace/builds/worker-4-tc1/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-4-tc1/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/foxtive/foxtive
[INFO] finished tweaking git repo https://github.com/foxtive/foxtive
[INFO] tweaked toml for git repo https://github.com/foxtive/foxtive written to /workspace/builds/worker-4-tc1/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/foxtive/foxtive on toolchain f9988fefd3add01f414f52b414308e7872622fee
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+f9988fefd3add01f414f52b414308e7872622fee" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/foxtive/foxtive already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+f9988fefd3add01f414f52b414308e7872622fee" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded tracing-serde v0.2.0
[INFO] [stderr]   Downloaded rand v0.10.1
[INFO] [stderr]   Downloaded rand_core v0.10.1
[INFO] [stderr]   Downloaded sha1_smol v1.0.1
[INFO] [stderr]   Downloaded openssl-sys v0.9.114
[INFO] [stderr]   Downloaded arcstr v1.2.0
[INFO] [stderr]   Downloaded cron v0.16.0
[INFO] [stderr]   Downloaded openssl v0.10.78
[INFO] [stderr]   Downloaded slug v0.1.6
[INFO] [stderr]   Downloaded globwalk v0.9.1
[INFO] [stderr]   Downloaded chrono-tz-build v0.3.0
[INFO] [stderr]   Downloaded block-buffer v0.12.0
[INFO] [stderr]   Downloaded cmov v0.5.3
[INFO] [stderr]   Downloaded crypto-common v0.2.1
[INFO] [stderr]   Downloaded digest v0.11.2
[INFO] [stderr]   Downloaded rust-argon2 v3.0.0
[INFO] [stderr]   Downloaded redis v1.2.0
[INFO] [stderr]   Downloaded blake2b_simd v1.0.4
[INFO] [stderr]   Downloaded sha2 v0.11.0
[INFO] [stderr]   Downloaded hybrid-array v0.4.10
[INFO] [stderr]   Downloaded utoipa v5.4.0
[INFO] [stderr]   Downloaded tera v1.20.1
[INFO] [stderr]   Downloaded ctutils v0.4.2
[INFO] [stderr]   Downloaded const-oid v0.10.2
[INFO] [stderr]   Downloaded utoipa-gen v5.4.0
[INFO] [stderr]   Downloaded deunicode v1.6.2
[INFO] [stderr]   Downloaded reactor-trait v3.1.1
[INFO] [stderr]   Downloaded async-reactor-trait v3.1.1
[INFO] [stderr]   Downloaded async-global-executor v3.1.0
[INFO] [stderr]   Downloaded reactor-trait v2.8.0
[INFO] [stderr]   Downloaded executor-trait v2.1.2
[INFO] [stderr]   Downloaded async-global-executor-trait v3.1.0
[INFO] [stderr]   Downloaded async-global-executor-trait v2.2.0
[INFO] [stderr]   Downloaded executor-trait v3.1.0
[INFO] [stderr]   Downloaded cookie-factory v0.3.3
[INFO] [stderr]   Downloaded amq-protocol-uri v8.3.1
[INFO] [stderr]   Downloaded amq-protocol-types v8.3.1
[INFO] [stderr]   Downloaded rustls-connector v0.21.11
[INFO] [stderr]   Downloaded futures-rustls v0.26.0
[INFO] [stderr]   Downloaded asn1-rs-impl v0.2.0
[INFO] [stderr]   Downloaded flagset v0.4.7
[INFO] [stderr]   Downloaded tcp-stream v0.30.9
[INFO] [stderr]   Downloaded asn1-rs-derive v0.6.0
[INFO] [stderr]   Downloaded rc2 v0.8.1
[INFO] [stderr]   Downloaded amq-protocol-tcp v8.3.1
[INFO] [stderr]   Downloaded oid-registry v0.8.1
[INFO] [stderr]   Downloaded amq-protocol v8.3.1
[INFO] [stderr]   Downloaded der_derive v0.7.3
[INFO] [stderr]   Downloaded simple_asn1 v0.6.4
[INFO] [stderr]   Downloaded dsl_auto_type v0.2.0
[INFO] [stderr]   Downloaded deadpool-runtime v0.3.1
[INFO] [stderr]   Downloaded tokio-executor-trait v2.4.0
[INFO] [stderr]   Downloaded p12-keystore v0.2.1
[INFO] [stderr]   Downloaded pkcs5 v0.7.1
[INFO] [stderr]   Downloaded des v0.8.1
[INFO] [stderr]   Downloaded primeorder v0.13.6
[INFO] [stderr]   Downloaded scheduled-thread-pool v0.2.7
[INFO] [stderr]   Downloaded diesel_table_macro_syntax v0.3.0
[INFO] [stderr]   Downloaded tokio-reactor-trait v3.9.0
[INFO] [stderr]   Downloaded r2d2 v0.8.10
[INFO] [stderr]   Downloaded deadpool-lapin v0.13.1
[INFO] [stderr]   Downloaded der-parser v10.0.0
[INFO] [stderr]   Downloaded pkcs12 v0.1.0
[INFO] [stderr]   Downloaded p256 v0.13.2
[INFO] [stderr]   Downloaded pq-sys v0.7.5
[INFO] [stderr]   Downloaded deadpool-redis v0.23.0
[INFO] [stderr]   Downloaded deadpool v0.13.0
[INFO] [stderr]   Downloaded x509-cert v0.2.5
[INFO] [stderr]   Downloaded rsa v0.9.10
[INFO] [stderr]   Downloaded ammonia v4.1.2
[INFO] [stderr]   Downloaded hmac v0.13.0
[INFO] [stderr]   Downloaded fancy-regex v0.17.0
[INFO] [stderr]   Downloaded asn1-rs v0.7.1
[INFO] [stderr]   Downloaded diesel_derives v2.3.7
[INFO] [stderr]   Downloaded lapin v3.7.2
[INFO] [stderr]   Downloaded x509-parser v0.18.1
[INFO] [stderr]   Downloaded num-bigint-dig v0.8.6
[INFO] [stderr]   Downloaded libc v0.2.185
[INFO] [stderr]   Downloaded chrono-tz v0.9.0
[INFO] [stderr]   Downloaded cms v0.2.3
[INFO] [stderr]   Downloaded rustls v0.23.38
[INFO] [stderr]   Downloaded p384 v0.13.1
[INFO] [stderr]   Downloaded diesel v2.3.7
[INFO] [stderr]   Downloaded cssparser v0.35.0
[INFO] [stderr]   Downloaded jsonwebtoken v10.3.0
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+f9988fefd3add01f414f52b414308e7872622fee" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 333b55cfb4e5918f6cb2ca2fbd6f2d60f69c8ccc6b0fc5482bdcf4226f6539a4
[INFO] running `Command { std: "docker" "start" "-a" "333b55cfb4e5918f6cb2ca2fbd6f2d60f69c8ccc6b0fc5482bdcf4226f6539a4", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "333b55cfb4e5918f6cb2ca2fbd6f2d60f69c8ccc6b0fc5482bdcf4226f6539a4", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "333b55cfb4e5918f6cb2ca2fbd6f2d60f69c8ccc6b0fc5482bdcf4226f6539a4", kill_on_drop: false }`
[INFO] [stdout] 333b55cfb4e5918f6cb2ca2fbd6f2d60f69c8ccc6b0fc5482bdcf4226f6539a4
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+f9988fefd3add01f414f52b414308e7872622fee" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 8b5f315676b70f083b6f21719979732b9e005bba6c9c381a0094ea5c3f466700
[INFO] running `Command { std: "docker" "start" "-a" "8b5f315676b70f083b6f21719979732b9e005bba6c9c381a0094ea5c3f466700", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.185
[INFO] [stderr]    Compiling pin-project-lite v0.2.17
[INFO] [stderr]    Compiling serde_core v1.0.228
[INFO] [stderr]    Compiling futures-core v0.3.32
[INFO] [stderr]    Compiling itoa v1.0.18
[INFO] [stderr]    Compiling bytes v1.11.1
[INFO] [stderr]    Compiling stable_deref_trait v1.2.1
[INFO] [stderr]    Compiling parking_lot_core v0.9.12
[INFO] [stderr]    Compiling futures-sink v0.3.32
[INFO] [stderr]    Compiling syn v2.0.117
[INFO] [stderr]    Compiling lock_api v0.4.14
[INFO] [stderr]    Compiling once_cell v1.21.4
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling futures-io v0.3.32
[INFO] [stderr]    Compiling slab v0.4.12
[INFO] [stderr]    Compiling futures-task v0.3.32
[INFO] [stderr]    Compiling siphasher v1.0.2
[INFO] [stderr]    Compiling writeable v0.6.3
[INFO] [stderr]    Compiling futures-channel v0.3.32
[INFO] [stderr]    Compiling tracing-core v0.1.36
[INFO] [stderr]    Compiling litemap v0.8.2
[INFO] [stderr]    Compiling log v0.4.29
[INFO] [stderr]    Compiling zmij v1.0.21
[INFO] [stderr]    Compiling icu_properties_data v2.2.0
[INFO] [stderr]    Compiling icu_normalizer_data v2.2.0
[INFO] [stderr]    Compiling getrandom v0.4.2
[INFO] [stderr]    Compiling serde_json v1.0.149
[INFO] [stderr]    Compiling rand_core v0.10.1
[INFO] [stderr]    Compiling rand v0.8.6
[INFO] [stderr]    Compiling phf_shared v0.11.3
[INFO] [stderr]    Compiling iana-time-zone v0.1.65
[INFO] [stderr]    Compiling cpufeatures v0.3.0
[INFO] [stderr]    Compiling percent-encoding v2.3.2
[INFO] [stderr]    Compiling concurrent-queue v2.5.0
[INFO] [stderr]    Compiling thiserror v2.0.18
[INFO] [stderr]    Compiling anyhow v1.0.102
[INFO] [stderr]    Compiling chacha20 v0.10.0
[INFO] [stderr]    Compiling phf_shared v0.12.1
[INFO] [stderr]    Compiling form_urlencoded v1.2.2
[INFO] [stderr]    Compiling event-listener v5.4.1
[INFO] [stderr]    Compiling chrono-tz v0.10.4
[INFO] [stderr]    Compiling ryu v1.0.23
[INFO] [stderr]    Compiling regex-syntax v0.8.10
[INFO] [stderr]    Compiling phf v0.12.1
[INFO] [stderr]    Compiling winnow v0.7.15
[INFO] [stderr]    Compiling phf_generator v0.11.3
[INFO] [stderr]    Compiling event-listener-strategy v0.5.4
[INFO] [stderr]    Compiling tracing-log v0.2.0
[INFO] [stderr]    Compiling thread_local v1.1.9
[INFO] [stderr]    Compiling xxhash-rust v0.8.15
[INFO] [stderr]    Compiling nu-ansi-term v0.50.3
[INFO] [stderr]    Compiling sha1_smol v1.0.1
[INFO] [stderr]    Compiling arcstr v1.2.0
[INFO] [stderr]    Compiling async-lock v3.4.2
[INFO] [stderr]    Compiling http v1.4.0
[INFO] [stderr]    Compiling tower-service v0.3.3
[INFO] [stderr]    Compiling tower-layer v0.3.3
[INFO] [stderr]    Compiling httpdate v1.0.3
[INFO] [stderr]    Compiling sync_wrapper v1.0.2
[INFO] [stderr]    Compiling mime v0.3.17
[INFO] [stderr]    Compiling matchit v0.8.4
[INFO] [stderr]    Compiling strum v0.28.0
[INFO] [stderr]    Compiling dotenv v0.15.0
[INFO] [stderr]    Compiling num-integer v0.1.46
[INFO] [stderr]    Compiling errno v0.3.14
[INFO] [stderr]    Compiling mio v1.2.0
[INFO] [stderr]    Compiling socket2 v0.6.3
[INFO] [stderr]    Compiling signal-hook-registry v1.4.8
[INFO] [stderr]    Compiling http-body v1.0.1
[INFO] [stderr]    Compiling rand v0.10.1
[INFO] [stderr]    Compiling parking_lot v0.12.5
[INFO] [stderr]    Compiling num-bigint v0.4.6
[INFO] [stderr]    Compiling http-body-util v0.1.3
[INFO] [stderr]    Compiling regex-automata v0.4.14
[INFO] [stderr]    Compiling uuid v1.23.1
[INFO] [stderr]    Compiling serde_path_to_error v0.1.20
[INFO] [stderr]    Compiling matchers v0.2.0
[INFO] [stderr]    Compiling synstructure v0.13.2
[INFO] [stderr]    Compiling zerovec-derive v0.11.3
[INFO] [stderr]    Compiling displaydoc v0.2.5
[INFO] [stderr]    Compiling tokio-macros v2.7.0
[INFO] [stderr]    Compiling futures-macro v0.3.32
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling tracing-attributes v0.1.31
[INFO] [stderr]    Compiling phf_macros v0.11.3
[INFO] [stderr]    Compiling thiserror-impl v2.0.18
[INFO] [stderr]    Compiling async-trait v0.1.89
[INFO] [stderr]    Compiling strum_macros v0.28.0
[INFO] [stderr]    Compiling zerofrom-derive v0.1.7
[INFO] [stderr]    Compiling yoke-derive v0.8.2
[INFO] [stderr]    Compiling tokio v1.52.1
[INFO] [stderr]    Compiling phf v0.11.3
[INFO] [stderr]    Compiling futures-util v0.3.32
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling zerofrom v0.1.7
[INFO] [stderr]    Compiling axum-core v0.5.6
[INFO] [stderr]    Compiling yoke v0.8.2
[INFO] [stderr]    Compiling zerovec v0.11.6
[INFO] [stderr]    Compiling zerotrie v0.2.4
[INFO] [stderr]    Compiling tinystr v0.8.3
[INFO] [stderr]    Compiling potential_utf v0.1.5
[INFO] [stderr]    Compiling icu_collections v2.2.0
[INFO] [stderr]    Compiling foxtive-macros v0.4.4 (/opt/rustwide/workdir/foxtive-macros)
[INFO] [stderr]    Compiling icu_locale_core v2.2.0
[INFO] [stderr]    Compiling icu_provider v2.2.0
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling icu_normalizer v2.2.0
[INFO] [stderr]    Compiling icu_properties v2.2.0
[INFO] [stderr]    Compiling chrono v0.4.44
[INFO] [stderr]    Compiling tracing-serde v0.2.0
[INFO] [stderr]    Compiling serde_urlencoded v0.7.1
[INFO] [stderr]    Compiling tracing-subscriber v0.3.23
[INFO] [stderr]    Compiling futures-executor v0.3.32
[INFO] [stderr]    Compiling futures v0.3.32
[INFO] [stderr]    Compiling idna_adapter v1.2.1
[INFO] [stderr]    Compiling idna v1.1.0
[INFO] [stderr]    Compiling url v2.5.8
[INFO] [stderr]    Compiling cron v0.16.0
[INFO] [stderr]    Compiling tokio-util v0.7.18
[INFO] [stderr]    Compiling hyper v1.9.0
[INFO] [stderr]    Compiling tower v0.5.3
[INFO] [stderr]    Compiling foxtive v0.25.6 (/opt/rustwide/workdir/foxtive)
[INFO] [stderr]    Compiling combine v4.6.7
[INFO] [stderr]    Compiling hyper-util v0.1.20
[INFO] [stderr]    Compiling axum v0.8.9
[INFO] [stderr]    Compiling foxtive-cron v0.5.0 (/opt/rustwide/workdir/foxtive-cron)
[INFO] [stderr]    Compiling redis v1.2.0
[INFO] [stderr]    Compiling foxtive-supervisor v0.3.3 (/opt/rustwide/workdir/foxtive-supervisor)
[INFO] [stderr]    Compiling circuit-breaker-example v0.1.0 (/opt/rustwide/workdir/foxtive-supervisor/examples/circuit-breaker)
[INFO] [stderr]    Compiling distributed-coordination-example v0.1.0 (/opt/rustwide/workdir/foxtive-supervisor/examples/distributed-coordination)
[INFO] [stderr]    Compiling cron-scheduling v0.1.0 (/opt/rustwide/workdir/foxtive-supervisor/examples/cron-scheduling)
[INFO] [stderr]    Compiling db-message-consumer v0.1.0 (/opt/rustwide/workdir/foxtive-supervisor/examples/db-message-consumer)
[INFO] [stderr]    Compiling tracing-example v0.1.0 (/opt/rustwide/workdir/foxtive-supervisor/examples/tracing)
[INFO] [stderr]    Compiling task-pools v0.1.0 (/opt/rustwide/workdir/foxtive-supervisor/examples/task-pools)
[INFO] [stderr]    Compiling supervisor-hierarchies v0.1.0 (/opt/rustwide/workdir/foxtive-supervisor/examples/supervisor-hierarchies)
[INFO] [stderr]    Compiling microservice-orchestration v0.1.0 (/opt/rustwide/workdir/foxtive-supervisor/examples/microservice-orchestration)
[INFO] [stderr]    Compiling basic v0.1.0 (/opt/rustwide/workdir/foxtive-supervisor/examples/basic)
[INFO] [stderr]    Compiling graceful-shutdown v0.1.0 (/opt/rustwide/workdir/foxtive-supervisor/examples/graceful-shutdown)
[INFO] [stderr]    Compiling persistence v0.1.0 (/opt/rustwide/workdir/foxtive-supervisor/examples/persistence)
[INFO] [stderr]    Compiling task-groups v0.1.0 (/opt/rustwide/workdir/foxtive-supervisor/examples/task-groups)
[INFO] [stderr]    Compiling axum-cron v0.1.0 (/opt/rustwide/workdir/foxtive-supervisor/examples/axum-cron)
[INFO] [stderr]    Compiling panic-cathcer v0.1.0 (/opt/rustwide/workdir/foxtive-supervisor/examples/panic-catcher)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 1m 34s
[INFO] running `Command { std: "docker" "inspect" "8b5f315676b70f083b6f21719979732b9e005bba6c9c381a0094ea5c3f466700", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "8b5f315676b70f083b6f21719979732b9e005bba6c9c381a0094ea5c3f466700", kill_on_drop: false }`
[INFO] [stdout] 8b5f315676b70f083b6f21719979732b9e005bba6c9c381a0094ea5c3f466700
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+f9988fefd3add01f414f52b414308e7872622fee" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] da4be3fb218877519eaf8ada2affcb18f6125b937dbb4eeeaad18d5b82a96cac
[INFO] running `Command { std: "docker" "start" "-a" "da4be3fb218877519eaf8ada2affcb18f6125b937dbb4eeeaad18d5b82a96cac", kill_on_drop: false }`
[INFO] [stderr]    Compiling tokio v1.52.1
[INFO] [stderr]    Compiling bitflags v2.11.1
[INFO] [stderr]    Compiling fastrand v2.4.1
[INFO] [stderr]    Compiling foxtive-macros v0.4.4 (/opt/rustwide/workdir/foxtive-macros)
[INFO] [stderr]    Compiling rustix v1.1.4
[INFO] [stderr]    Compiling tempfile v3.27.0
[INFO] [stderr]    Compiling tokio-util v0.7.18
[INFO] [stderr]    Compiling hyper v1.9.0
[INFO] [stderr]    Compiling tower v0.5.3
[INFO] [stderr]    Compiling foxtive v0.25.6 (/opt/rustwide/workdir/foxtive)
[INFO] [stderr]    Compiling hyper-util v0.1.20
[INFO] [stderr]    Compiling foxtive-cron v0.5.0 (/opt/rustwide/workdir/foxtive-cron)
[INFO] [stderr]    Compiling combine v4.6.7
[INFO] [stderr]    Compiling axum v0.8.9
[INFO] [stderr]    Compiling redis v1.2.0
[INFO] [stderr]    Compiling foxtive-supervisor v0.3.3 (/opt/rustwide/workdir/foxtive-supervisor)
[INFO] [stderr]    Compiling basic v0.1.0 (/opt/rustwide/workdir/foxtive-supervisor/examples/basic)
[INFO] [stderr]    Compiling tracing-example v0.1.0 (/opt/rustwide/workdir/foxtive-supervisor/examples/tracing)
[INFO] [stderr]    Compiling supervisor-hierarchies v0.1.0 (/opt/rustwide/workdir/foxtive-supervisor/examples/supervisor-hierarchies)
[INFO] [stderr]    Compiling panic-cathcer v0.1.0 (/opt/rustwide/workdir/foxtive-supervisor/examples/panic-catcher)
[INFO] [stderr]    Compiling distributed-coordination-example v0.1.0 (/opt/rustwide/workdir/foxtive-supervisor/examples/distributed-coordination)
[INFO] [stderr]    Compiling axum-cron v0.1.0 (/opt/rustwide/workdir/foxtive-supervisor/examples/axum-cron)
[INFO] [stderr]    Compiling persistence v0.1.0 (/opt/rustwide/workdir/foxtive-supervisor/examples/persistence)
[INFO] [stderr]    Compiling graceful-shutdown v0.1.0 (/opt/rustwide/workdir/foxtive-supervisor/examples/graceful-shutdown)
[INFO] [stderr]    Compiling circuit-breaker-example v0.1.0 (/opt/rustwide/workdir/foxtive-supervisor/examples/circuit-breaker)
[INFO] [stderr]    Compiling db-message-consumer v0.1.0 (/opt/rustwide/workdir/foxtive-supervisor/examples/db-message-consumer)
[INFO] [stderr]    Compiling task-pools v0.1.0 (/opt/rustwide/workdir/foxtive-supervisor/examples/task-pools)
[INFO] [stderr]    Compiling task-groups v0.1.0 (/opt/rustwide/workdir/foxtive-supervisor/examples/task-groups)
[INFO] [stderr]    Compiling cron-scheduling v0.1.0 (/opt/rustwide/workdir/foxtive-supervisor/examples/cron-scheduling)
[INFO] [stderr]    Compiling microservice-orchestration v0.1.0 (/opt/rustwide/workdir/foxtive-supervisor/examples/microservice-orchestration)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 1m 15s
[INFO] running `Command { std: "docker" "inspect" "da4be3fb218877519eaf8ada2affcb18f6125b937dbb4eeeaad18d5b82a96cac", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "da4be3fb218877519eaf8ada2affcb18f6125b937dbb4eeeaad18d5b82a96cac", kill_on_drop: false }`
[INFO] [stdout] da4be3fb218877519eaf8ada2affcb18f6125b937dbb4eeeaad18d5b82a96cac
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+f9988fefd3add01f414f52b414308e7872622fee" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 9457ed4671fb8f157536fb5f50547fbdac1f85f37141cee7db815716c3f70069
[INFO] running `Command { std: "docker" "start" "-a" "9457ed4671fb8f157536fb5f50547fbdac1f85f37141cee7db815716c3f70069", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.53s
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/axum_cron-a003c115b4f82403)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/basic-27cf39e4b0b79622)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/circuit_breaker_example-ba07114ebc07ae79)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/cron_scheduling-caa3c07e997f64dc)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/db_message_consumer-133c28e31af183bf)
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/distributed_coordination_example-67dadc553a1afca1)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/foxtive-20f1a32c070a2555)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 154 tests
[INFO] [stdout] test enums::app_message::tests::test_kind_name_coverage ... ok
[INFO] [stdout] test enums::app_message::tests::test_conflict ... ok
[INFO] [stdout] test enums::app_message::tests::test_unprocessable_entity ... ok
[INFO] [stdout] test enums::app_message::tests::test_not_found ... ok
[INFO] [stdout] test enums::app_message::tests::test_invalid ... ok
[INFO] [stdout] test enums::app_message::tests::test_internal_server_error ... ok
[INFO] [stdout] test enums::app_message::tests::test_unauthorized ... ok
[INFO] [stdout] test enums::app_message::tests::test_error_message_explicit_status ... ok
[INFO] [stdout] test enums::app_message::tests::test_redirect_is_not_an_error ... ok
[INFO] [stdout] test enums::app_message::tests::test_validation_errors_none_for_other_variants ... ok
[INFO] [stdout] test env::tests::test_all ... ok
[INFO] [stdout] test env::tests::test_as_short_str ... ok
[INFO] [stdout] test env::tests::test_default ... ok
[INFO] [stdout] test enums::app_message::tests::test_into_result ... ok
[INFO] [stdout] test env::tests::test_is_dev_like ... ok
[INFO] [stdout] test enums::app_message::tests::test_into_anyhow ... ok
[INFO] [stdout] test env::tests::test_is_production ... ok
[INFO] [stdout] test enums::app_message::tests::test_success ... ok
[INFO] [stdout] test ext::result::tests::test_msg ... ok
[INFO] [stdout] test env::tests::test_from_str ... ok
[INFO] [stdout] test enums::app_message::tests::test_forbidden ... ok
[INFO] [stdout] test enums::app_message::tests::test_missing_environment_variable ... ok
[INFO] [stdout] test helpers::file_ext::tests::test_custom_extension ... ok
[INFO] [stdout] test ext::result::tests::test_recover_from_result ... ok
[INFO] [stdout] test helpers::file_ext::tests::test_edge_cases ... ok
[INFO] [stdout] test ext::result::tests::test_recover_from_error ... ok
[INFO] [stdout] test enums::app_message::tests::test_validation_error ... ok
[INFO] [stdout] test helpers::file_ext::tests::test_split_filename ... ok
[INFO] [stdout] test helpers::file_ext::tests::test_single_level_extensions ... ok
[INFO] [stdout] test env::tests::test_display ... ok
[INFO] [stdout] test helpers::file_ext::tests::test_known_extensions ... ok
[INFO] [stdout] test helpers::file_ext::tests::test_hidden_files ... ok
[INFO] [stdout] test ext::result::tests::test_recover_from_async_result ... ok
[INFO] [stdout] test ext::result::tests::test_recover_from_async_error ... ok
[INFO] [stdout] test env::tests::test_as_str ... ok
[INFO] [stdout] test helpers::file_size::tests::test_basic_formatting ... ok
[INFO] [stdout] test helpers::file_size::tests::test_edge_cases ... ok
[INFO] [stdout] test helpers::file_size::tests::test_custom_separator ... ok
[INFO] [stdout] test helpers::file_size::tests::test_precision ... ok
[INFO] [stdout] test helpers::file_size::tests::test_unit_constraints ... ok
[INFO] [stdout] test helpers::file_size::tests::test_verbose_formatting ... ok
[INFO] [stdout] test helpers::number::tests::test_from_cents ... ok
[INFO] [stdout] test helpers::file_size::tests::test_decimal_prefixes ... ok
[INFO] [stdout] test helpers::number::tests::test_to_cents ... ok
[INFO] [stdout] test helpers::serde_json::tests::test_i64_nonzero ... ok
[INFO] [stdout] test helpers::serde_json::tests::test_i64_null ... ok
[INFO] [stdout] test helpers::serde_json::tests::test_i64_with_default_missing ... ok
[INFO] [stdout] test helpers::serde_json::tests::test_deserialize_i64_from_any ... ok
[INFO] [stdout] test helpers::serde_json::tests::test_bool_from_string ... ok
[INFO] [stdout] test helpers::serde_json::tests::test_bool_from_number ... ok
[INFO] [stdout] test helpers::serde_json::tests::test_bool_from_boolean ... ok
[INFO] [stdout] test helpers::serde_json::tests::test_deserialize_string_from_any ... ok
[INFO] [stdout] test helpers::serde_json::tests::test_deserialize_f64_from_any ... ok
[INFO] [stdout] test helpers::serde_json::tests::test_deserialize_optional_string_from_any ... ok
[INFO] [stdout] test helpers::serde_json::tests::test_bool_invalid ... ok
[INFO] [stdout] test helpers::serde_json::tests::test_i64_with_default_null ... ok
[INFO] [stdout] test helpers::serde_json::tests::test_i64_with_default_number ... ok
[INFO] [stdout] test helpers::number::tests::test_format_integer ... ok
[INFO] [stdout] test helpers::number::tests::test_format_currency ... ok
[INFO] [stdout] test helpers::serde_json::tests::test_i64_zero_as_none ... ok
[INFO] [stdout] test helpers::serde_json::tests::test_i64_with_default_string ... ok
[INFO] [stdout] test helpers::file_ext::tests::test_no_extension ... ok
[INFO] [stdout] test helpers::file_size::tests::test_large_sizes ... ok
[INFO] [stdout] test helpers::serde_json::tests::test_optional_bool_null ... ok
[INFO] [stdout] test helpers::serde_json::tests::test_optional_f64 ... ok
[INFO] [stdout] test helpers::serde_json::tests::test_optional_vec_null ... ok
[INFO] [stdout] test helpers::serde_json::tests::test_optional_vec_string ... ok
[INFO] [stdout] test helpers::serde_json::tests::test_percentage_as_number ... ok
[INFO] [stdout] test helpers::serde_json::tests::test_percentage_as_decimal ... ok
[INFO] [stdout] test helpers::serde_json::tests::test_percentage_as_string ... ok
[INFO] [stdout] test helpers::serde_json::tests::test_optional_bool_values ... ok
[INFO] [stdout] test helpers::serde_json::tests::test_timestamp_from_number ... ok
[INFO] [stdout] test helpers::serde_json::tests::test_string_null ... ok
[INFO] [stdout] test helpers::serde_json::tests::test_optional_i64 ... ok
[INFO] [stdout] test helpers::serde_json::tests::test_i64_zero_string ... ok
[INFO] [stdout] test helpers::serde_json::tests::test_percentage_invalid ... ok
[INFO] [stdout] test helpers::serde_json::tests::test_percentage_edge_cases ... ok
[INFO] [stdout] test helpers::serde_json::tests::test_string_empty_as_none ... ok
[INFO] [stdout] test helpers::serde_json::tests::test_string_nonempty ... ok
[INFO] [stdout] test helpers::serde_json::tests::test_timestamp_invalid ... ok
[INFO] [stdout] test helpers::serde_json::tests::test_timestamp_from_string ... ok
[INFO] [stdout] test helpers::serde_json::tests::test_vec_from_array ... ok
[INFO] [stdout] test helpers::serde_json::tests::test_optional_vec_array ... ok
[INFO] [stdout] test helpers::serde_json::tests::test_vec_from_array_with_numbers ... ok
[INFO] [stdout] test helpers::serde_json::tests::test_vec_from_comma_separated_string ... ok
[INFO] [stdout] test helpers::serde_json::tests::test_vec_from_string_with_spaces ... ok
[INFO] [stdout] test helpers::string::ext_tests::test_count_occurrences_ext ... ok
[INFO] [stdout] test helpers::serde_json::tests::test_vec_from_single_string ... ok
[INFO] [stdout] test helpers::string::ext_tests::test_camel_case_ext ... ok
[INFO] [stdout] test helpers::string::ext_tests::test_remove_whitespace_ext ... ok
[INFO] [stdout] test helpers::string::ext_tests::test_reverse_ext ... ok
[INFO] [stdout] test helpers::string::ext_tests::test_is_alphabetic_ext ... ok
[INFO] [stdout] test helpers::string::ext_tests::test_pad_left_ext ... ok
[INFO] [stdout] test helpers::string::ext_tests::test_is_numeric_ext ... ok
[INFO] [stdout] test helpers::string::tests::test_is_numeric ... ok
[INFO] [stdout] test helpers::serde_json::tests::test_timestamp_null ... ok
[INFO] [stdout] test helpers::string::ext_tests::test_truncate_ext ... ok
[INFO] [stdout] test helpers::string::tests::test_reverse ... ok
[INFO] [stdout] test helpers::string::tests::test_to_camel_case ... ok
[INFO] [stdout] test helpers::string::tests::test_uc_first ... ok
[INFO] [stdout] test helpers::string::tests::test_truncate ... ok
[INFO] [stdout] test helpers::string::tests::test_remove_whitespace ... ok
[INFO] [stdout] test helpers::string::tests::test_pad_left ... ok
[INFO] [stdout] test helpers::string::ext_tests::test_uc_first_ext ... ok
[INFO] [stdout] test helpers::string::ext_tests::test_uc_words_ext ... ok
[INFO] [stdout] test helpers::string::tests::test_is_alphabetic ... ok
[INFO] [stdout] test helpers::string::tests::test_count_occurrences ... ok
[INFO] [stdout] test helpers::string::tests::test_uc_words ... ok
[INFO] [stdout] test helpers::tokio::tests::test_block_multiple_calls ... ok
[INFO] [stdout] test helpers::tokio::tests::test_block_captures_variables ... ok
[INFO] [stdout] test helpers::tokio::tests::test_blk_with_result_type ... ok
[INFO] [stdout] test helpers::tokio::tests::test_block_with_computation ... ok
[INFO] [stdout] test helpers::tokio::tests::test_block_concurrent_execution ... ok
[INFO] [stdout] test helpers::tokio::tests::test_blk_captures_variables ... ok
[INFO] [stdout] test helpers::tokio::tests::test_block_with_result_type ... ok
[INFO] [stdout] test helpers::tokio::tests::test_blk_with_computation ... ok
[INFO] [stdout] test helpers::tokio::tests::test_blk_with_string ... ok
[INFO] [stdout] test helpers::tokio::tests::test_blk_with_shared_state ... ok
[INFO] [stdout] test helpers::tokio::tests::test_blk_concurrent_execution ... ok
[INFO] [stdout] test helpers::tokio::tests::test_blk_returns_value ... ok
[INFO] [stdout] test helpers::tokio::tests::test_blk_multiple_tasks ... ok
[INFO] [stdout] test helpers::tokio::tests::test_block_nested_calls_without_runtime ... ok
[INFO] [stdout] test helpers::tokio::tests::test_block_with_shared_state ... ok
[INFO] [stdout] test helpers::tokio::tests::test_block_without_runtime ... ok
[INFO] [stdout] test helpers::tokio::tests::test_run_async_nested_calls ... ok
[INFO] [stdout] test helpers::tokio::tests::test_run_async_returns_string ... ok
[INFO] [stdout] test helpers::tokio::tests::test_run_async_returns_value ... ok
[INFO] [stdout] test helpers::tokio::tests::test_run_async_with_existing_runtime ... ok
[INFO] [stdout] test helpers::tokio::tests::test_run_async_with_computation ... ok
[INFO] [stdout] test helpers::tokio::tests::test_run_async_with_result_type ... ok
[INFO] [stdout] test helpers::tokio::tests::test_block_with_string ... ok
[INFO] [stdout] test helpers::string::tests::test_uuid ... ok
[INFO] [stdout] test helpers::tokio::tests::test_block_with_runtime ... ok
[INFO] [stdout] test helpers::tokio::tests::test_block_with_nested_spawn ... ok
[INFO] [stdout] test setup::trace::tests::test_hide_location_info ... ok
[INFO] [stdout] test setup::trace::tests::test_log_level ... ok
[INFO] [stdout] test helpers::tokio::tests::test_block_creates_runtime_when_needed ... ok
[INFO] [stdout] test setup::trace::tests::test_minimal_config ... ok
[INFO] [stdout] test setup::trace::tests::test_show_location_info ... ok
[INFO] [stdout] test setup::trace::tests::test_verbose_config ... ok
[INFO] [stdout] test helpers::tokio::tests::test_blk_with_sleep ... ok
[INFO] [stdout] test helpers::tokio::tests::test_block_with_sleep ... ok
[INFO] [stdout] test helpers::tokio::tests::test_run_async_with_await ... ok
[INFO] [stdout] test helpers::tokio::tests::test_run_async_and_blk_integration ... ok
[INFO] [stdout] test helpers::tokio::tests::test_block_integration_with_async ... ok
[INFO] [stdout]   2026-05-04T18:19:17.888006Z  INFO foxtive::setup::trace::tests: This is an info message
[INFO] [stdout]     at foxtive/src/setup/trace.rs:482 on setup::trace::tests::test_event_callback
[INFO] [stdout] 
[INFO] [stdout]   2026-05-04T18:19:17.888134Z  WARN foxtive::setup::trace::tests: This is a warning message
[INFO] [stdout]     at foxtive/src/setup/trace.rs:483 on setup::trace::tests::test_event_callback
[INFO] [stdout] 
[INFO] [stdout]   2026-05-04T18:19:17.889344Z ERROR foxtive::setup::trace::tests: This is an error message
[INFO] [stdout]     at foxtive/src/setup/trace.rs:484 on setup::trace::tests::test_event_callback
[INFO] [stdout] 
[INFO] [stdout] test setup::trace::tests::test_event_callback ... ok
[INFO] [stdout] test macros::error::tests::test_basic_macros ... ok
[INFO] [stdout] test macros::error::tests::test_ensure_found ... ok
[INFO] [stdout] test helpers::tokio::tests::test_block_with_panic_recovery ... ok
[INFO] [stdout] test macros::error::tests::test_validation_error_inline ... ok
[INFO] [stdout] test macros::error::tests::test_ensure_passes_and_fails ... ok
[INFO] [stdout] test macros::error::tests::test_validation_error_prebuilt_map ... ok
[INFO] [stdout] test macros::error::tests::test_bad_request_and_invalid_are_equivalent ... ok
[INFO] [stdout] test helpers::tokio::tests::test_blk_with_panic_recovery ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 154 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.33s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/foxtive_cron-c6d6e8761664913b)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/builder_and_runtime_tests.rs (/opt/rustwide/target/debug/deps/builder_and_runtime_tests-3842edf9a8977945)
[INFO] [stdout] 
[INFO] [stdout] running 19 tests
[INFO] [stdout] test cron_builder::new_creates_empty_builder ... ok
[INFO] [stdout] test set_metrics_exporter_runtime::metrics_exporter_can_be_replaced ... ok
[INFO] [stdout] test set_job_store_runtime::job_store_can_be_replaced ... ok
[INFO] [stdout] test add_listener_runtime::add_listener_increases_listener_count ... ok
[INFO] [stdout] test cron_builder::with_listener_adds_listener ... ok
[INFO] [stdout] test add_listener_runtime::multiple_listeners_can_be_added ... ok
[INFO] [stdout] test cron_builder::with_job_store_sets_store ... ok
[INFO] [stdout] test set_metrics_exporter_runtime::set_metrics_exporter_sets_exporter ... ok
[INFO] [stdout] test cron_builder::with_metrics_exporter_sets_exporter ... ok
[INFO] [stdout] test cron_builder::builder_with_all_options ... ok
[INFO] [stdout] test cron_builder::builder_chaining_is_fluent ... ok
[INFO] [stdout] test cron_builder::with_global_concurrency_limit_sets_limit ... ok
[INFO] [stdout] test set_job_store_runtime::set_job_store_sets_store ... ok
[INFO] [stdout] test trigger_job_edge_cases::trigger_job_executes_removed_job ... ok
[INFO] [stdout] test trigger_job_edge_cases::trigger_job_on_nonexistent_job_returns_error ... ok
[INFO] [stdout] test shutdown_behavior::trigger_job_during_shutdown_fails ... ok
[INFO] [stdout] test shutdown_behavior::empty_scheduler_shutdown_is_immediate ... ok
[INFO] [stdout] test trigger_job_edge_cases::multiple_triggers_of_same_job ... ok
[INFO] [stdout] test shutdown_behavior::shutdown_waits_for_running_jobs ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 19 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.12s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/builder_comprehensive_tests.rs (/opt/rustwide/target/debug/deps/builder_comprehensive_tests-a42f713f2b926a42)
[INFO] [stdout] 
[INFO] [stdout] running 69 tests
[INFO] [stdout] test builder_composition_tests::quarterly_review_schedule ... ok
[INFO] [stdout] test builder_composition_tests::monthly_report_schedule ... ok
[INFO] [stdout] test builder_composition_tests::maintenance_window_schedule ... ok
[INFO] [stdout] test builder_composition_tests::complex_business_hours_schedule ... ok
[INFO] [stdout] test builder_composition_tests::override_preset_values ... ok
[INFO] [stdout] test builder_composition_tests::end_of_month_schedule ... ok
[INFO] [stdout] test builder_composition_tests::mixed_intervals_and_lists ... ok
[INFO] [stdout] test real_world_expression_verification::analytics_aggregation_hourly ... ok
[INFO] [stdout] test real_world_expression_verification::api_rate_limit_reset ... ok
[INFO] [stdout] test real_world_expression_verification::archive_old_records_monthly ... ok
[INFO] [stdout] test real_world_expression_verification::audit_log_rotation ... ok
[INFO] [stdout] test real_world_expression_verification::backup_retention_cleanup ... ok
[INFO] [stdout] test real_world_expression_verification::batch_processing_off_peak ... ok
[INFO] [stdout] test real_world_expression_verification::cache_cleanup_every_6_hours ... ok
[INFO] [stdout] test blackout_dates_tests::consecutive_blackout_dates_dont_cause_infinite_loop ... ok
[INFO] [stdout] test real_world_expression_verification::business_hours_weekdays ... ok
[INFO] [stdout] test blackout_dates_tests::blackout_dates_with_weekday_schedule ... ok
[INFO] [stdout] test jitter_tests::zero_jitter_produces_consistent_results ... ok
[INFO] [stdout] test real_world_expression_verification::data_sync_every_10_minutes ... ok
[INFO] [stdout] test real_world_expression_verification::certificate_renewal_check ... ok
[INFO] [stdout] test real_world_expression_verification::database_backup_daily_2am ... ok
[INFO] [stdout] test blackout_dates_tests::single_blackout_date_is_skipped ... ok
[INFO] [stdout] test real_world_expression_verification::email_digest_twice_daily ... ok
[INFO] [stdout] test real_world_expression_verification::etl_pipeline_daily ... ok
[INFO] [stdout] test real_world_expression_verification::compliance_report_weekly_friday ... ok
[INFO] [stdout] test blackout_dates_tests::multiple_blackout_dates_are_skipped ... ok
[INFO] [stdout] test jitter_tests::jitter_adds_random_offset ... ok
[INFO] [stdout] test jitter_tests::jitter_respects_maximum_bound ... ok
[INFO] [stdout] test real_world_expression_verification::database_vacuum_weekly ... ok
[INFO] [stdout] test real_world_expression_verification::invoice_generation_monthly ... ok
[INFO] [stdout] test real_world_expression_verification::load_balancer_health_check ... ok
[INFO] [stdout] test real_world_expression_verification::log_rotation_hourly ... ok
[INFO] [stdout] test real_world_expression_verification::metrics_collection_every_5_minutes ... ok
[INFO] [stdout] test real_world_expression_verification::monthly_report_first_day ... ok
[INFO] [stdout] test real_world_expression_verification::monitoring_alert_check ... ok
[INFO] [stdout] test real_world_expression_verification::health_check_every_30_seconds ... ok
[INFO] [stdout] test real_world_expression_verification::index_rebuild_weekend ... ok
[INFO] [stdout] test real_world_expression_verification::payment_processing_batch ... ok
[INFO] [stdout] test jitter_tests::jitter_with_timezone_applies_correctly ... ok
[INFO] [stdout] test real_world_expression_verification::payroll_processing_biweekly ... ok
[INFO] [stdout] test real_world_expression_verification::quarterly_audit ... ok
[INFO] [stdout] test real_world_expression_verification::notification_digest_hourly ... ok
[INFO] [stdout] test real_world_expression_verification::ssl_certificate_check ... ok
[INFO] [stdout] test real_world_expression_verification::session_cleanup_every_hour ... ok
[INFO] [stdout] test real_world_expression_verification::stock_market_hours ... ok
[INFO] [stdout] test real_world_expression_verification::system_reboot_first_sunday ... ok
[INFO] [stdout] test real_world_expression_verification::weekly_maintenance_sunday_midnight ... ok
[INFO] [stdout] test real_world_expression_verification::temp_file_cleanup_daily ... ok
[INFO] [stdout] test real_world_expression_verification::security_scan_nightly ... ok
[INFO] [stdout] test real_world_expression_verification::user_activity_summary ... ok
[INFO] [stdout] test schedule_trait_integration::schedule_with_all_features_combined ... ok
[INFO] [stdout] test validation_edge_cases::invalid_day_of_month_rejected ... ok
[INFO] [stdout] test timezone_advanced_tests::timezone_with_specific_days ... ok
[INFO] [stdout] test validation_edge_cases::invalid_month_rejected ... ok
[INFO] [stdout] test schedule_trait_integration::multiple_next_calls_advance_correctly ... ok
[INFO] [stdout] test serialization_tests::serialize_with_timezone ... ok
[INFO] [stdout] test serialization_tests::serialize_with_blackout_dates ... ok
[INFO] [stdout] test timezone_advanced_tests::cross_midnight_timezone_conversion ... ok
[INFO] [stdout] test serialization_tests::serialize_complex_expression ... ok
[INFO] [stdout] test timezone_advanced_tests::dst_transition_spring_forward ... ok
[INFO] [stdout] test real_world_expression_verification::webhook_retry_every_minute ... ok
[INFO] [stdout] test serialization_tests::serialize_deserialize_round_trip ... ok
[INFO] [stdout] test validation_edge_cases::maximum_valid_values_accepted ... ok
[INFO] [stdout] test validation_edge_cases::negative_values_rejected ... ok
[INFO] [stdout] test validation_edge_cases::weekday_enum_values_are_valid ... ok
[INFO] [stdout] test schedule_trait_integration::cron_expression_implements_schedule_trait ... ok
[INFO] [stdout] test serialization_tests::serialize_with_jitter ... ok
[INFO] [stdout] test timezone_advanced_tests::dst_transition_fall_back ... ok
[INFO] [stdout] test blackout_dates_tests::blackout_dates_with_jitter ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 69 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.15s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/builder_convenience_methods_tests.rs (/opt/rustwide/target/debug/deps/builder_convenience_methods_tests-9af0adb8491ba5fd)
[INFO] [stdout] 
[INFO] [stdout] running 34 tests
[INFO] [stdout] test method_combinations::sundays_with_timezone ... ok
[INFO] [stdout] test method_combinations::fridays_with_jitter ... ok
[INFO] [stdout] test method_combinations::saturdays_morning ... ok
[INFO] [stdout] test method_combinations::mondays_business_hours ... ok
[INFO] [stdout] test method_combinations::quarterly_with_blackout_dates ... ok
[INFO] [stdout] test method_combinations::wednesdays_midday ... ok
[INFO] [stdout] test quarterly_method::quarterly_with_custom_hour ... ok
[INFO] [stdout] test quarterly_method::quarterly_produces_correct_expression ... ok
[INFO] [stdout] test quarterly_method::quarterly_with_custom_time ... ok
[INFO] [stdout] test quarterly_method::quarterly_executes_in_july ... ok
[INFO] [stdout] test quarterly_method::quarterly_executes_in_october ... ok
[INFO] [stdout] test quarterly_method::quarterly_executes_in_april ... ok
[INFO] [stdout] test quarterly_method::quarterly_advances_to_next_year ... ok
[INFO] [stdout] test quarterly_method::quarterly_executes_in_january ... ok
[INFO] [stdout] test quarterly_method::quarterly_multiple_executions ... ok
[INFO] [stdout] test weekday_convenience_methods::mondays_only_produces_correct_expression ... ok
[INFO] [stdout] test weekday_convenience_methods::saturdays_only_produces_correct_expression ... ok
[INFO] [stdout] test weekday_convenience_methods::saturday_schedule_executes_on_saturday ... ok
[INFO] [stdout] test weekday_convenience_methods::sunday_schedule_executes_on_sunday ... ok
[INFO] [stdout] test weekday_convenience_methods::weekends_only_still_works ... ok
[INFO] [stdout] test weekday_convenience_methods::sundays_only_produces_correct_expression ... ok
[INFO] [stdout] test weekday_convenience_methods::thursdays_only_produces_correct_expression ... ok
[INFO] [stdout] test weekday_convenience_methods::tuesdays_only_produces_correct_expression ... ok
[INFO] [stdout] test weekday_convenience_methods::wednesdays_only_produces_correct_expression ... ok
[INFO] [stdout] test validation_and_edge_cases::all_weekday_methods_parse_successfully ... ok
[INFO] [stdout] test validation_and_edge_cases::quarterly_can_be_overridden ... ok
[INFO] [stdout] test validation_and_edge_cases::quarterly_contains_all_four_months ... ok
[INFO] [stdout] test validation_and_edge_cases::quarterly_parses_successfully ... ok
[INFO] [stdout] test validation_and_edge_cases::sunday_is_one_in_cron_format ... ok
[INFO] [stdout] test validation_and_edge_cases::weekday_methods_override_each_other ... ok
[INFO] [stdout] test weekday_convenience_methods::friday_schedule_executes_on_friday ... ok
[INFO] [stdout] test weekday_convenience_methods::fridays_only_produces_correct_expression ... ok
[INFO] [stdout] test weekday_convenience_methods::monday_schedule_executes_on_monday ... ok
[INFO] [stdout] test weekday_convenience_methods::weekdays_only_still_works ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 34 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.07s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/builder_real_world_tests.rs (/opt/rustwide/target/debug/deps/builder_real_world_tests-607ab6d67fbab745)
[INFO] [stdout] 
[INFO] [stdout] running 24 tests
[INFO] [stdout] test edge_cases_and_boundaries::multiple_times_same_day ... ok
[INFO] [stdout] test real_world_expressions::daily_at_midnight ... ok
[INFO] [stdout] test real_world_use_cases::cache_cleanup ... ok
[INFO] [stdout] test real_world_expressions::every_minute_standard ... ok
[INFO] [stdout] test real_world_expressions::every_five_minutes ... ok
[INFO] [stdout] test performance_tests::long_term_scheduling ... ok
[INFO] [stdout] test real_world_use_cases::health_check_interval ... ok
[INFO] [stdout] test real_world_use_cases::api_rate_limit_reset ... ok
[INFO] [stdout] test real_world_use_cases::database_backup_schedule ... ok
[INFO] [stdout] test real_world_expressions::business_hours_every_30_min ... ok
[INFO] [stdout] test real_world_expressions::every_15_seconds ... ok
[INFO] [stdout] test real_world_use_cases::invoice_generation ... ok
[INFO] [stdout] test edge_cases_and_boundaries::leap_year_feb_29 ... ok
[INFO] [stdout] test real_world_expressions::daily_at_specific_time ... ok
[INFO] [stdout] test performance_tests::rapid_succession_calls ... ok
[INFO] [stdout] test timezone_real_world::stock_market_open_ny ... ok
[INFO] [stdout] test timezone_real_world::london_business_hours ... ok
[INFO] [stdout] test timezone_real_world::tokyo_midnight_maintenance ... ok
[INFO] [stdout] test validation_tests::invalid_minute_value ... ok
[INFO] [stdout] test validation_tests::valid_range_expression ... ok
[INFO] [stdout] test validation_tests::invalid_hour_value ... ok
[INFO] [stdout] test validation_tests::malformed_expression ... ok
[INFO] [stdout] test validation_tests::valid_step_expression ... ok
[INFO] [stdout] test real_world_expressions::first_day_of_month ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 24 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.05s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/builder_tests.rs (/opt/rustwide/target/debug/deps/builder_tests-48e893620cf3591b)
[INFO] [stdout] 
[INFO] [stdout] running 9 tests
[INFO] [stdout] test test_basic_presets ... ok
[INFO] [stdout] test test_custom_time_daily ... ok
[INFO] [stdout] test test_validation_errors ... ok
[INFO] [stdout] test test_enums ... ok
[INFO] [stdout] test test_complex_intervals ... ok
[INFO] [stdout] test test_ranges ... ok
[INFO] [stdout] test test_lists ... ok
[INFO] [stdout] test test_leap_year_february ... ok
[INFO] [stdout] test test_schedule_trait_logic ... ok
[INFO] [stdout] 
[INFO] [stderr]      Running tests/concurrency_tests.rs (/opt/rustwide/target/debug/deps/concurrency_tests-c544f02f3d938df2)
[INFO] [stdout] test result: ok. 9 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test concurrency::global_concurrency_limit_is_enforced ... ok
[INFO] [stdout] test concurrency::per_job_concurrency_limit_is_enforced ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 2.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/cron.rs (/opt/rustwide/target/debug/deps/cron-20c733e5c4f0037f)
[INFO] [stdout] 
[INFO] [stdout] running 45 tests
[INFO] [stdout] test cron_scheduler::empty_scheduler_exits_promptly ... ok
[INFO] [stdout] test cron_scheduler::add_job_fn_rejects_invalid_schedule ... ok
[INFO] [stdout] test cron_scheduler::add_blocking_job_fn_rejects_invalid_schedule ... ok
[INFO] [stdout] test cron_scheduler::priority_is_respected ... ok
[INFO] [stdout] test cron_scheduler::remove_job_removes_from_registry ... ok
[INFO] [stdout] test cron_scheduler::add_job_fn_accepts_valid_schedule ... ok
[INFO] [stdout] test cron_scheduler::list_job_ids_returns_correct_ids ... ok
[INFO] [stdout] test cron_scheduler::add_blocking_job_fn_accepts_valid_schedule ... ok
[INFO] [stdout] test cron_scheduler::multiple_jobs_can_be_registered ... ok
[INFO] [stdout] test cron_scheduler::add_job_accepts_arc_job_contract ... ok
[INFO] [stdout] test cron_scheduler::new_creates_empty_scheduler ... ok
[INFO] [stdout] test fn_job::description_defaults_to_none ... ok
[INFO] [stdout] test fn_job::id_returns_correct_value ... ok
[INFO] [stdout] test fn_job::name_returns_correct_value ... ok
[INFO] [stdout] test fn_job::new_blocking_returns_err_with_invalid_schedule ... ok
[INFO] [stdout] test fn_job::new_blocking_returns_ok_with_valid_schedule ... ok
[INFO] [stdout] test fn_job::new_returns_err_with_invalid_schedule ... ok
[INFO] [stdout] test fn_job::new_returns_ok_with_valid_schedule ... ok
[INFO] [stdout] test fn_job::run_propagates_async_closure_error ... ok
[INFO] [stdout] test fn_job::run_executes_async_closure ... ok
[INFO] [stdout] test lifecycle_hooks::all_hooks_fire_in_correct_sequence ... ok
[INFO] [stdout] test fn_job::run_executes_blocking_closure ... ok
[INFO] [stdout] test lifecycle_hooks::on_complete_called_on_success ... ok
[INFO] [stdout] test lifecycle_hooks::on_complete_not_called_on_failure ... ok
[INFO] [stdout] test fn_job::run_propagates_blocking_closure_error ... ok
[INFO] [stdout] test lifecycle_hooks::on_error_called_on_failure ... ok
[INFO] [stdout] test validated_schedule::accepts_every_second_expression ... ok
[INFO] [stdout] test lifecycle_hooks::on_error_not_called_on_success ... ok
[INFO] [stdout] test lifecycle_hooks::on_start_called_before_run ... ok
[INFO] [stdout] test validated_schedule::accepts_specific_time_expression ... ok
[INFO] [stdout] test validated_schedule::accepts_valid_seven_field_expression ... ok
[INFO] [stdout] test validated_schedule::rejects_nonsense_expression ... ok
[INFO] [stdout] test validated_schedule::rejects_empty_expression ... ok
[INFO] [stdout] test validated_schedule::error_message_includes_original_expression ... ok
[INFO] [stdout] test validated_schedule::rejects_out_of_range_field ... ok
[INFO] [stdout] test cron_scheduler::fixed_retry_policy_is_enforced ... ok
[INFO] [stdout] test cron_scheduler::exponential_retry_policy_is_enforced ... ok
[INFO] [stdout] test cron_scheduler::trigger_job_executes_immediately ... ok
[INFO] [stdout] test cron_scheduler::failing_job_does_not_crash_scheduler ... ok
[INFO] [stdout] test cron_scheduler::scheduler_executes_multiple_jobs_concurrently ... ok
[INFO] [stdout] test cron_scheduler::scheduler_executes_registered_job ... ok
[INFO] [stdout] test cron_scheduler::job_timeout_is_enforced ... ok
[INFO] [stdout] test cron_scheduler::per_job_concurrency_limit_is_enforced ... ok
[INFO] [stdout] test cron_scheduler::global_concurrency_limit_is_enforced ... ok
[INFO] [stdout] test cron_scheduler::removed_job_stops_running ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 45 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 3.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/cron_scheduler_tests.rs (/opt/rustwide/target/debug/deps/cron_scheduler_tests-1c8311d0828032b9)
[INFO] [stdout] 
[INFO] [stdout] running 13 tests
[INFO] [stdout] test cron_scheduler::new_creates_empty_scheduler ... ok
[INFO] [stdout] test cron_scheduler::empty_scheduler_exits_promptly ... ok
[INFO] [stdout] test cron_scheduler::add_job_fn_rejects_invalid_schedule ... ok
[INFO] [stdout] test cron_scheduler::add_blocking_job_fn_accepts_valid_schedule ... ok
[INFO] [stdout] test cron_scheduler::remove_job_removes_from_registry ... ok
[INFO] [stdout] test cron_scheduler::add_job_fn_accepts_valid_schedule ... ok
[INFO] [stdout] test cron_scheduler::multiple_jobs_can_be_registered ... ok
[INFO] [stdout] test cron_scheduler::list_job_ids_returns_correct_ids ... ok
[INFO] [stdout] test cron_scheduler::add_job_accepts_arc_job_contract ... ok
[INFO] [stdout] test cron_scheduler::trigger_job_executes_immediately ... ok
[INFO] [stdout] test cron_scheduler::add_blocking_job_fn_rejects_invalid_schedule ... ok
[INFO] [stdout] test cron_scheduler::scheduler_executes_registered_job ... ok
[INFO] [stdout] test cron_scheduler::removed_job_stops_running ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 13 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 3.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/debug_weekday.rs (/opt/rustwide/target/debug/deps/debug_weekday-b08c647f3f355e7f)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test debug_sunday_schedule ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/event_and_metrics_tests.rs (/opt/rustwide/target/debug/deps/event_and_metrics_tests-e89a10899c9d6c70)
[INFO] [stdout] 
[INFO] [stdout] running 14 tests
[INFO] [stdout] test job_event::misfired_variant_contains_scheduled_time ... ok
[INFO] [stdout] test job_event::event_is_cloneable ... ok
[INFO] [stdout] test job_event::retrying_variant_contains_attempt_and_delay ... ok
[INFO] [stdout] test job_event::started_variant_contains_id_and_name ... ok
[INFO] [stdout] test mock_metrics_exporter::exporter_records_failure ... ok
[INFO] [stdout] test mock_metrics_exporter::exporter_tracks_multiple_metrics ... ok
[INFO] [stdout] test mock_metrics_exporter::exporter_records_retry ... ok
[INFO] [stdout] test mock_metrics_exporter::exporter_records_completion_with_duration ... ok
[INFO] [stdout] test mock_metrics_exporter::exporter_records_start ... ok
[INFO] [stdout] test mock_metrics_exporter::exporter_records_misfire ... ok
[INFO] [stdout] test job_event::completed_variant_contains_duration ... ok
[INFO] [stdout] test job_event::failed_variant_contains_error_message ... ok
[INFO] [stdout] test mock_event_listener::listener_tracks_multiple_events ... ok
[INFO] [stdout] test mock_event_listener::listener_receives_started_event ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 14 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/fn_job_tests.rs (/opt/rustwide/target/debug/deps/fn_job_tests-dc51ebb0f4d7b79e)
[INFO] [stdout] 
[INFO] [stdout] running 11 tests
[INFO] [stdout] test fn_job::new_returns_err_with_invalid_schedule ... ok
[INFO] [stdout] test fn_job::new_returns_ok_with_valid_schedule ... ok
[INFO] [stdout] test fn_job::description_defaults_to_none ... ok
[INFO] [stderr]      Running tests/job_features_tests.rs (/opt/rustwide/target/debug/deps/job_features_tests-cef6ba35181adc23)
[INFO] [stdout] test fn_job::run_executes_async_closure ... ok
[INFO] [stdout] test fn_job::new_blocking_returns_err_with_invalid_schedule ... ok
[INFO] [stdout] test fn_job::new_blocking_returns_ok_with_valid_schedule ... ok
[INFO] [stdout] test fn_job::id_returns_correct_value ... ok
[INFO] [stdout] test fn_job::name_returns_correct_value ... ok
[INFO] [stdout] test fn_job::run_executes_blocking_closure ... ok
[INFO] [stdout] test fn_job::run_propagates_async_closure_error ... ok
[INFO] [stdout] test fn_job::run_propagates_blocking_closure_error ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 11 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 12 tests
[INFO] [stdout] test description::description_defaults_to_none ... ok
[INFO] [stdout] test start_after::start_after_returns_specified_time ... ok
[INFO] [stdout] test description::custom_description_is_returned ... ok
[INFO] [stdout] test misfire_policy::skip_policy_skips_missed_runs ... ok
[INFO] [stdout] test misfire_policy::default_misfire_policy_is_skip ... ok
[INFO] [stdout] test job_type::once_job_type_is_correct ... ok
[INFO] [stdout] test misfire_policy::fire_once_policy_executes_once_when_missed ... ok
[INFO] [stdout] test misfire_policy::fire_all_policy_executes_all_missed_runs ... ok
[INFO] [stdout] test job_type::recurring_job_type_is_default ... ok
[INFO] [stdout] test job_type::once_job_with_future_run_at_is_scheduled ... ok
[INFO] [stdout] test job_type::once_job_with_past_run_at_is_not_scheduled ... ok
[INFO] [stdout] test start_after::start_after_defaults_to_none ... ok
[INFO] [stdout] 
[INFO] [stderr]      Running tests/job_store_tests.rs (/opt/rustwide/target/debug/deps/job_store_tests-62212c93159dc4f9)
[INFO] [stdout] test result: ok. 12 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.03s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 7 tests
[INFO] [stdout] test in_memory_job_store::job_state_tracks_all_fields ... ok
[INFO] [stdout] test in_memory_job_store::get_state_returns_none_for_missing_job ... ok
[INFO] [stdout] test in_memory_job_store::multiple_jobs_can_be_stored_independently ... ok
[INFO] [stdout] test in_memory_job_store::save_state_stores_job_state ... ok
[INFO] [stdout] test in_memory_job_store::new_creates_empty_store ... ok
[INFO] [stdout] test in_memory_job_store::concurrent_access_is_safe ... ok
[INFO] [stdout] test in_memory_job_store::save_state_updates_existing_state ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/lifecycle_hooks_tests.rs (/opt/rustwide/target/debug/deps/lifecycle_hooks_tests-65666c702b242225)
[INFO] [stdout] 
[INFO] [stdout] running 6 tests
[INFO] [stdout] test lifecycle_hooks::on_complete_called_on_success ... ok
[INFO] [stdout] test lifecycle_hooks::on_error_not_called_on_success ... ok
[INFO] [stdout] test lifecycle_hooks::on_start_called_before_run ... ok
[INFO] [stdout] test lifecycle_hooks::all_hooks_fire_in_correct_sequence ... ok
[INFO] [stdout] test lifecycle_hooks::on_error_called_on_failure ... ok
[INFO] [stdout] test lifecycle_hooks::on_complete_not_called_on_failure ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/priority_tests.rs (/opt/rustwide/target/debug/deps/priority_tests-3f5f4d18d1b0054f)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test priority_tests::priority_is_respected ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/retry_tests.rs (/opt/rustwide/target/debug/deps/retry_tests-af845b7e404d0076)
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test retries::fixed_retry_policy_is_enforced ... ok
[INFO] [stdout] test retries::exponential_retry_policy_is_enforced ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.04s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/timeout_tests.rs (/opt/rustwide/target/debug/deps/timeout_tests-78da251048a24eed)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stderr]      Running tests/timezone_and_schedule_tests.rs (/opt/rustwide/target/debug/deps/timezone_and_schedule_tests-81363ac5dbf01575)
[INFO] [stdout] test timeouts::job_timeout_is_enforced ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.15s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 14 tests
[INFO] [stdout] test validated_schedule_tests::parse_validates_standard_six_field_expression ... ok
[INFO] [stdout] test validated_schedule_tests::parse_validates_seven_field_expression ... ok
[INFO] [stdout] test timezone::default_timezone_is_utc ... ok
[INFO] [stdout] test validated_schedule_tests::parse_rejects_out_of_range_values ... ok
[INFO] [stdout] test timezone::custom_timezone_is_returned ... ok
[INFO] [stdout] test validated_schedule_tests::parse_accepts_special_characters ... ok
[INFO] [stdout] test validated_schedule_tests::parse_rejects_invalid_expression ... ok
[INFO] [stdout] test validated_schedule_tests::schedule_clone_works ... ok
[INFO] [stdout] test validated_schedule_tests::next_after_with_different_timezones ... ok
[INFO] [stdout] test validated_schedule_tests::next_after_returns_future_time ... ok
[INFO] [stdout] test timezone::next_run_time_respects_timezone ... ok
[INFO] [stdout] test timezone::job_with_timezone_can_be_added_to_cron ... ok
[INFO] [stdout] test timezone::various_timezones_are_supported ... ok
[INFO] [stdout] test retry_policy_none::no_retry_policy_fails_immediately ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 14 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/validated_schedule_tests.rs (/opt/rustwide/target/debug/deps/validated_schedule_tests-59cfde3faf3705fa)
[INFO] [stdout] 
[INFO] [stdout] running 7 tests
[INFO] [stdout] test accepts_valid_seven_field_expression ... ok
[INFO] [stdout] test error_message_includes_original_expression ... ok
[INFO] [stdout] test accepts_every_second_expression ... ok
[INFO] [stdout] test rejects_out_of_range_field ... ok
[INFO] [stdout] test rejects_nonsense_expression ... ok
[INFO] [stdout] test rejects_empty_expression ... ok
[INFO] [stdout] test accepts_specific_time_expression ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/foxtive_macros-04c4cfcd617cea09)
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/foxtive_supervisor-921961a83cded202)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 14 tests
[INFO] [stdout] test enums::tests::test_custom_backoff ... ok
[INFO] [stdout] test enums::tests::test_linear_backoff ... ok
[INFO] [stdout] test enums::tests::test_fibonacci_backoff ... ok
[INFO] [stdout] test enums::tests::test_backoff_debug ... ok
[INFO] [stdout] test enums::tests::test_fixed_backoff ... ok
[INFO] [stdout] test enums::tests::test_health_status ... ok
[INFO] [stdout] test enums::tests::test_backoff_clone ... ok
[INFO] [stdout] test enums::tests::test_exponential_backoff ... ok
[INFO] [stdout] test task_pool::tests::test_pool_info ... ok
[INFO] [stdout] test task_pool::tests::test_random_distribution ... ok
[INFO] [stdout] test task_pool::tests::test_round_robin_distribution ... ok
[INFO] [stdout] test task_pool::tests::test_pool_builder ... ok
[INFO] [stdout] test hierarchy::tests::test_create_hierarchy ... ok
[INFO] [stdout] test runtime::tests::test_basic_supervision ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 14 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 2.14s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/cascading_shutdown_tests.rs (/opt/rustwide/target/debug/deps/cascading_shutdown_tests-95343da2f9ece70b)
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test test_hierarchy_with_nested_structure ... ok
[INFO] [stdout] test test_cascading_shutdown_hierarchy ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.10s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/circuit_breaker_tests.rs (/opt/rustwide/target/debug/deps/circuit_breaker_tests-5d4ca6b175f1e13d)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test test_circuit_breaker_trips_and_resets ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 2.31s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/complex_dependency_tests.rs (/opt/rustwide/target/debug/deps/complex_dependency_tests-946b908c526f9a0e)
[INFO] [stdout] 
[INFO] [stdout] running 6 tests
[INFO] [stdout] test test_diamond_dependency_graph ... ok
[INFO] [stdout] test test_dependency_with_groups ... ok
[INFO] [stdout] test test_multiple_independent_chains ... ok
[INFO] [stdout] test test_fan_out_fan_in_pattern ... ok
[INFO] [stdout] test test_deep_linear_chain ... ok
[INFO] [stderr]      Running tests/concurrency_tests.rs (/opt/rustwide/target/debug/deps/concurrency_tests-a1f6bde1489276f8)
[INFO] [stdout] test test_complex_dag_with_conditional_deps ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.34s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test test_priority_scheduling ... ok
[INFO] [stdout] test test_global_concurrency_limit ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.30s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/conditional_deps_tests.rs (/opt/rustwide/target/debug/deps/conditional_deps_tests-8356665cda2ae323)
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test test_conditional_dependencies_disabled ... ok
[INFO] [stderr]      Running tests/cron_tests.rs (/opt/rustwide/target/debug/deps/cron_tests-878cefebe88ed409)
[INFO] [stdout] test test_conditional_dependencies_enabled ... ok
[INFO] [stdout] test test_active_dependencies_combination ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.11s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 12 tests
[INFO] [stdout] test test_cron_with_invalid_expression ... ok
[INFO] [stdout] test test_initial_delay_zero_duration ... ok
[INFO] [stdout] test test_time_window_configuration ... ok
[INFO] [stdout] test test_combined_initial_delay_and_restart_backoff ... ok
[INFO] [stdout] test test_initial_delay ... ok
[INFO] [stdout] test test_initial_delay_with_jitter ... ok
[INFO] [stdout] test test_cron_task_with_very_frequent_schedule ... ok
[INFO] [stdout] test test_cron_task_can_be_stopped ... ok
[INFO] [stdout] test test_cron_with_immediate_execution ... ok
[INFO] [stdout] test test_cron_scheduled_task ... ok
[INFO] [stdout] test test_rate_limiting_restart_interval ... ok
[INFO] [stdout] test test_multiple_cron_tasks_different_schedules ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 12 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 3.51s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/dependency_race_condition_tests.rs (/opt/rustwide/target/debug/deps/dependency_race_condition_tests-bf01ef74736d57ff)
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test test_multiple_dependents_race_condition ... ok
[INFO] [stderr]      Running tests/distributed_coordination_tests.rs (/opt/rustwide/target/debug/deps/distributed_coordination_tests-24e49f3013d9c1ff)
[INFO] [stdout] test test_dependency_race_condition_fix ... ok
[INFO] [stdout] test test_dependency_chain_no_hang ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.51s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 9 tests
[INFO] [stdout] test tests::test_coordination_manager ... ignored
[INFO] [stdout] test tests::test_distributed_lock_acquire_and_release ... ignored
[INFO] [stdout] test tests::test_distributed_lock_prevents_duplicate ... ignored
[INFO] [stdout] test tests::test_heartbeat_expiration ... ignored
[INFO] [stdout] test tests::test_heartbeat_mechanism ... ignored
[INFO] [stdout] test tests::test_leader_election_single_instance ... ignored
[INFO] [stdout] test tests::test_leader_election_with_competition ... ignored
[INFO] [stdout] test tests::test_leader_lease_expiration ... ignored
[INFO] [stdout] test tests::test_multiple_instances_coordination ... ignored
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 9 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/distributed_unit_tests.rs (/opt/rustwide/target/debug/deps/distributed_unit_tests-8d69aab99e657509)
[INFO] [stdout] 
[INFO] [stdout] running 6 tests
[INFO] [stdout] test tests::test_mock_heartbeat ... ok
[INFO] [stdout] test tests::test_mock_leader_election ... ok
[INFO] [stdout] test tests::test_mock_multiple_locks ... ok
[INFO] [stdout] test tests::test_coordination_config_builder ... ok
[INFO] [stdout] test tests::test_mock_lock_acquire_release ... ok
[INFO] [stdout] test tests::test_mock_leader_transition ... ok
[INFO] [stderr]      Running tests/dynamic_management_tests.rs (/opt/rustwide/target/debug/deps/dynamic_management_tests-7ea3f277db6ec8ac)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.11s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test test_add_task_at_runtime ... ok
[INFO] [stdout] test test_remove_task_at_runtime ... ok
[INFO] [stdout] test test_restart_task_manually ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.32s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/edge_cases_tests.rs (/opt/rustwide/target/debug/deps/edge_cases_tests-1846b676f82d31d7)
[INFO] [stdout] 
[INFO] [stdout] running 14 tests
[INFO] [stdout] test test_pool_zero_size_handled ... ok
[INFO] [stdout] test test_pool_size_one ... ok
[INFO] [stdout] test test_pool_large_size ... ok
[INFO] [stdout] test test_conditional_dep_nonexistent_when_true ... ok
[INFO] [stdout] test test_group_health_empty_group ... ok
[INFO] [stdout] test test_group_health_all_healthy ... ok
[INFO] [stdout] test test_group_health_mixed_statuses ... ok
[INFO] [stdout] test test_multiple_groups_overlap ... ok
[INFO] [stdout] test test_empty_group_operations ... ok
[INFO] [stdout] test test_group_with_conditional_deps ... ok
[INFO] [stdout] test test_group_with_single_task ... ok
[INFO] [stdout] test test_conditional_dep_always_false ... ok
[INFO] [stdout] test test_many_conditional_dependencies ... ok
[INFO] [stdout] test test_rapid_group_stop_start ... ok
[INFO] [stderr]      Running tests/error_handling_tests.rs (/opt/rustwide/target/debug/deps/error_handling_tests-906e38343ec198bb)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 14 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.21s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test test_dependency_failure_prevents_startup ... ok
[INFO] [stdout] test test_restart_prevented_by_should_restart ... ok
[INFO] [stdout] test test_max_attempts_exceeded ... ok
[INFO] [stderr]      Running tests/event_tests.rs (/opt/rustwide/target/debug/deps/event_tests-cbb50fc78e81c88f)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.14s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test test_supervisor_events ... ok
[INFO] [stdout] test test_shutdown_events ... ok
[INFO] [stderr]      Running tests/group_tests.rs (/opt/rustwide/target/debug/deps/group_tests-badcb6d0662ff17f)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.07s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test test_list_group_tasks ... ok
[INFO] [stdout] test test_group_health_aggregation ... ok
[INFO] [stdout] test test_task_groups_start_stop ... ok
[INFO] [stdout] test test_restart_group ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.22s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/hierarchy_integration_tests.rs (/opt/rustwide/target/debug/deps/hierarchy_integration_tests-e407312b4457a980)
[INFO] [stdout] 
[INFO] [stdout] running 6 tests
[INFO] [stdout] test test_empty_hierarchy ... ok
[INFO] [stdout] test test_hierarchy_shutdown_order ... ok
[INFO] [stdout] test test_nested_hierarchy ... ok
[INFO] [stdout] test test_deep_nested_hierarchy ... ok
[INFO] [stdout] test test_hierarchy_starts_and_stops ... ok
[INFO] [stdout] test test_hierarchy_with_failing_task ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 2.10s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/hot_reload_tests.rs (/opt/rustwide/target/debug/deps/hot_reload_tests-1e4bf2542d5354e8)
[INFO] [stdout] 
[INFO] [stdout] running 13 tests
[INFO] [stdout] 2026-05-04T18:19:41.927518Z  INFO foxtive_supervisor::runtime::core: Updated restart policy task_id=event-test old_policy=MaxAttempts(5) new_policy=Always
[INFO] [stdout] 2026-05-04T18:19:41.927697Z  INFO foxtive_supervisor::runtime::core: Updated backoff strategy task_id=event-test old_strategy=Fixed(10ms) new_strategy=Exponential { initial: 10ms, max: 1s }
[INFO] [stdout] 2026-05-04T18:19:41.927702Z  INFO foxtive_supervisor::runtime::core: Updated restart policy task_id=persistence-test old_policy=MaxAttempts(5) new_policy=MaxAttempts(10)
[INFO] [stdout] 2026-05-04T18:19:41.927812Z  INFO foxtive_supervisor::runtime::core: Updated backoff strategy task_id=persistence-test old_strategy=Fixed(10ms) new_strategy=Linear { initial: 10ms, increment: 5ms, max: 1s }
[INFO] [stdout] 2026-05-04T18:19:41.928189Z  INFO foxtive_supervisor::runtime::core: Updated restart policy task_id=rapid-test old_policy=MaxAttempts(5) new_policy=Always
[INFO] [stdout] 2026-05-04T18:19:41.928263Z  INFO foxtive_supervisor::runtime::core: Updated restart policy task_id=rapid-test old_policy=Always new_policy=Never
[INFO] [stdout] 2026-05-04T18:19:41.928289Z  INFO foxtive_supervisor::runtime::core: Updated restart policy task_id=rapid-test old_policy=Never new_policy=Always
[INFO] [stdout] 2026-05-04T18:19:41.928309Z  INFO foxtive_supervisor::runtime::core: Updated restart policy task_id=rapid-test old_policy=Always new_policy=Never
[INFO] [stdout] 2026-05-04T18:19:41.928337Z  INFO foxtive_supervisor::runtime::core: Updated restart policy task_id=rapid-test old_policy=Never new_policy=Always
[INFO] [stdout] 2026-05-04T18:19:41.928359Z  INFO foxtive_supervisor::runtime::core: Updated restart policy task_id=rapid-test old_policy=Always new_policy=Never
[INFO] [stdout] 2026-05-04T18:19:41.928360Z  INFO foxtive_supervisor::runtime::core: [Supervisor] Starting 1 supervised tasks...
[INFO] [stdout] 2026-05-04T18:19:41.928378Z  INFO foxtive_supervisor::runtime::core: Updated restart policy task_id=rapid-test old_policy=Never new_policy=Always
[INFO] [stdout] 2026-05-04T18:19:41.928405Z  INFO foxtive_supervisor::runtime::core: Updated restart policy task_id=rapid-test old_policy=Always new_policy=Never
[INFO] [stdout] 2026-05-04T18:19:41.928426Z  INFO foxtive_supervisor::runtime::core: Updated restart policy task_id=rapid-test old_policy=Never new_policy=Always
[INFO] [stdout] 2026-05-04T18:19:41.928459Z  INFO foxtive_supervisor::runtime::core: Updated restart policy task_id=rapid-test old_policy=Always new_policy=Never
[INFO] [stdout] 2026-05-04T18:19:41.928480Z  INFO foxtive_supervisor::runtime::core: Updated restart policy task_id=rapid-test old_policy=Never new_policy=Always
[INFO] [stdout] 2026-05-04T18:19:41.928478Z  INFO foxtive_supervisor::runtime::core: [Supervisor] Starting 1 supervised tasks...
[INFO] [stdout] 2026-05-04T18:19:41.928501Z  INFO foxtive_supervisor::runtime::core: Updated restart policy task_id=rapid-test old_policy=Always new_policy=Never
[INFO] [stdout] 2026-05-04T18:19:41.928514Z  INFO foxtive_supervisor::runtime::core: [Supervisor] All tasks started
[INFO] [stdout] 2026-05-04T18:19:41.928523Z  INFO foxtive_supervisor::runtime::core: Updated restart policy task_id=rapid-test old_policy=Never new_policy=Always
[INFO] [stdout] 2026-05-04T18:19:41.928545Z  INFO foxtive_supervisor::runtime::core: Updated restart policy task_id=rapid-test old_policy=Always new_policy=Never
[INFO] [stdout] 2026-05-04T18:19:41.928567Z  INFO foxtive_supervisor::runtime::core: [Supervisor] All tasks started
[INFO] [stdout] 2026-05-04T18:19:41.928567Z  INFO foxtive_supervisor::runtime::core: Updated restart policy task_id=rapid-test old_policy=Never new_policy=Always
[INFO] [stdout] 2026-05-04T18:19:41.928590Z  INFO foxtive_supervisor::runtime::core: Updated restart policy task_id=rapid-test old_policy=Always new_policy=Never
[INFO] [stdout] 2026-05-04T18:19:41.928614Z  INFO foxtive_supervisor::runtime::core: Updated restart policy task_id=rapid-test old_policy=Never new_policy=Always
[INFO] [stdout] 2026-05-04T18:19:41.928619Z  INFO foxtive_supervisor::runtime::core: Updated restart policy task_id=task-0 old_policy=MaxAttempts(5) new_policy=Always
[INFO] [stdout] 2026-05-04T18:19:41.928636Z  INFO foxtive_supervisor::runtime::core: Updated restart policy task_id=rapid-test old_policy=Always new_policy=Never
[INFO] [stdout] 2026-05-04T18:19:41.928622Z  INFO supervision{task_id="during-exec-test" task_name=during-exec-test}:task_setup: foxtive_supervisor::runtime::supervision: Running setup phase
[INFO] [stdout] 2026-05-04T18:19:41.928635Z  INFO supervision{task_id="disabled-test" task_name=disabled-test}:task_setup: foxtive_supervisor::runtime::supervision: Running setup phase
[INFO] [stdout] 2026-05-04T18:19:41.928659Z  INFO foxtive_supervisor::runtime::core: Updated restart policy task_id=rapid-test old_policy=Never new_policy=Always
[INFO] [stdout] 2026-05-04T18:19:41.928660Z  INFO foxtive_supervisor::runtime::core: Updated backoff strategy task_id=task-0 old_strategy=Fixed(10ms) new_strategy=Fixed(20ms)
[INFO] [stdout] 2026-05-04T18:19:41.928682Z  INFO foxtive_supervisor::runtime::core: Updated restart policy task_id=rapid-test old_policy=Always new_policy=Never
[INFO] [stdout] 2026-05-04T18:19:41.928680Z  INFO foxtive_supervisor::runtime::core: [Supervisor] Starting 1 supervised tasks...
[INFO] [stdout] 2026-05-04T18:19:41.928687Z  INFO foxtive_supervisor::runtime::core: Updated restart policy task_id=task-1 old_policy=MaxAttempts(5) new_policy=Always
[INFO] [stdout] 2026-05-04T18:19:41.928689Z  INFO supervision{task_id="during-exec-test" task_name=during-exec-test}: foxtive_supervisor::runtime::supervision: Setup complete, signalled dependents
[INFO] [stdout] 2026-05-04T18:19:41.928691Z  INFO supervision{task_id="disabled-test" task_name=disabled-test}: foxtive_supervisor::runtime::supervision: Setup complete, signalled dependents
[INFO] [stdout] 2026-05-04T18:19:41.928711Z  INFO foxtive_supervisor::runtime::core: Updated backoff strategy task_id=task-1 old_strategy=Fixed(10ms) new_strategy=Fixed(20ms)
[INFO] [stdout] 2026-05-04T18:19:41.928734Z  INFO foxtive_supervisor::runtime::core: Updated restart policy task_id=task-2 old_policy=MaxAttempts(5) new_policy=Always
[INFO] [stdout] 2026-05-04T18:19:41.928761Z  INFO foxtive_supervisor::runtime::core: [Supervisor] All tasks started
[INFO] [stdout] 2026-05-04T18:19:41.928757Z  INFO foxtive_supervisor::runtime::core: Updated backoff strategy task_id=task-2 old_strategy=Fixed(10ms) new_strategy=Fixed(20ms)
[INFO] [stdout] 2026-05-04T18:19:41.928786Z  INFO foxtive_supervisor::runtime::core: Updated restart policy task_id=task-3 old_policy=MaxAttempts(5) new_policy=Always
[INFO] [stdout] 2026-05-04T18:19:41.928793Z  INFO foxtive_supervisor::runtime::core: Updated restart policy task_id=concurrent-test old_policy=MaxAttempts(5) new_policy=Always
[INFO] [stdout] 2026-05-04T18:19:41.928812Z  INFO foxtive_supervisor::runtime::core: Updated backoff strategy task_id=task-3 old_strategy=Fixed(10ms) new_strategy=Fixed(20ms)
[INFO] [stdout] 2026-05-04T18:19:41.928819Z  INFO supervision{task_id="backoff-test" task_name=backoff-test}:task_setup: foxtive_supervisor::runtime::supervision: Running setup phase
[INFO] [stdout] 2026-05-04T18:19:41.928837Z  INFO foxtive_supervisor::runtime::core: Updated backoff strategy task_id=concurrent-test old_strategy=Fixed(10ms) new_strategy=Fixed(50ms)
[INFO] [stdout] 2026-05-04T18:19:41.928835Z  INFO foxtive_supervisor::runtime::core: Updated restart policy task_id=task-4 old_policy=MaxAttempts(5) new_policy=Always
[INFO] [stdout] 2026-05-04T18:19:41.928876Z  INFO foxtive_supervisor::runtime::core: Updated task enabled status task_id=concurrent-test old_enabled=true new_enabled=false
[INFO] [stdout] 2026-05-04T18:19:41.928876Z  INFO foxtive_supervisor::runtime::core: Updated backoff strategy task_id=task-4 old_strategy=Fixed(10ms) new_strategy=Fixed(20ms)
[INFO] [stdout] 2026-05-04T18:19:41.928886Z  INFO supervision{task_id="backoff-test" task_name=backoff-test}: foxtive_supervisor::runtime::supervision: Setup complete, signalled dependents
[INFO] [stdout] 2026-05-04T18:19:41.929027Z  INFO foxtive_supervisor::runtime::core: [Supervisor] Starting 1 supervised tasks...
[INFO] [stdout] 2026-05-04T18:19:41.929126Z  INFO foxtive_supervisor::runtime::core: [Supervisor] All tasks started
[INFO] [stdout] 2026-05-04T18:19:41.929288Z  INFO supervision{task_id="disabled-test" task_name=disabled-test}: foxtive_supervisor::runtime::supervision: Task completed successfully
[INFO] [stdout] 2026-05-04T18:19:41.929413Z  INFO supervision{task_id="restart-policy-test" task_name=restart-policy-test}:task_setup: foxtive_supervisor::runtime::supervision: Running setup phase
[INFO] [stdout] 2026-05-04T18:19:41.929466Z  INFO supervision{task_id="restart-policy-test" task_name=restart-policy-test}: foxtive_supervisor::runtime::supervision: Setup complete, signalled dependents
[INFO] [stdout] test test_update_unknown_task_returns_error ... ok
[INFO] [stdout] test test_rapid_config_changes ... ok
[INFO] [stdout] 2026-05-04T18:19:41.930498Z  INFO foxtive_supervisor::runtime::core: Updated task enabled status task_id=enable-disable-test old_enabled=true new_enabled=false
[INFO] [stdout] 2026-05-04T18:19:41.930580Z  INFO foxtive_supervisor::runtime::core: Updated task enabled status task_id=enable-disable-test old_enabled=false new_enabled=true
[INFO] [stdout] 2026-05-04T18:19:41.931280Z  INFO foxtive_supervisor::runtime::core: Updated task enabled status task_id=reenable-test old_enabled=true new_enabled=false
[INFO] [stdout] 2026-05-04T18:19:41.931322Z  INFO foxtive_supervisor::runtime::core: Updated task enabled status task_id=reenable-test old_enabled=false new_enabled=true
[INFO] [stdout] test test_concurrent_config_updates ... ok
[INFO] [stdout] test test_hot_reload_multiple_tasks ... ok
[INFO] [stdout] test test_config_change_events ... ok
[INFO] [stdout] test test_reenable_disabled_task ... ok
[INFO] [stdout] test test_validation_rejects_invalid_strategies ... ok
[INFO] [stdout] test test_hot_reload_enable_disable ... ok
[INFO] [stdout] 2026-05-04T18:19:41.979576Z  INFO foxtive_supervisor::runtime::core: Updated task enabled status task_id=disabled-test old_enabled=true new_enabled=false
[INFO] [stdout] test test_config_persistence_across_reads ... ok
[INFO] [stdout] 2026-05-04T18:19:42.031304Z  INFO foxtive_supervisor::runtime::core: Updated restart policy task_id=during-exec-test old_policy=Always new_policy=Never
[INFO] [stdout] 2026-05-04T18:19:42.034313Z ERROR supervision{task_id="backoff-test" task_name=backoff-test}: foxtive_supervisor::runtime::supervision: Task execution failed error=Intentional failure #1
[INFO] [stdout] 
[INFO] [stdout] Stack backtrace:
[INFO] [stdout]    0: <anyhow::Error>::msg::<alloc::string::String>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/anyhow-1.0.102/src/backtrace.rs:10:14
[INFO] [stdout]    1: <hot_reload_tests::FailingTask as foxtive_supervisor::contracts::SupervisedTask>::run::{closure#0}
[INFO] [stdout]              at ./tests/hot_reload_tests.rs:36:24
[INFO] [stdout]    2: <core::pin::Pin<alloc::boxed::Box<dyn core::future::future::Future<Output = core::result::Result<(), anyhow::Error>> + core::marker::Send>> as core::future::future::Future>::poll
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/future/future.rs:133:9
[INFO] [stdout]    3: foxtive_supervisor::runtime::supervision::supervise::{closure#0}::{closure#3}
[INFO] [stdout]              at ./src/runtime/supervision.rs:324:47
[INFO] [stdout]    4: <tracing::instrument::Instrumented<foxtive_supervisor::runtime::supervision::supervise::{closure#0}::{closure#3}> as core::future::future::Future>::poll
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tracing-0.1.44/src/instrument.rs:321:15
[INFO] [stdout]    5: <tokio::runtime::task::core::Core<tracing::instrument::Instrumented<foxtive_supervisor::runtime::supervision::supervise::{closure#0}::{closure#3}>, alloc::sync::Arc<tokio::runtime::scheduler::current_thread::Handle>>>::poll::{closure#0}
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/task/core.rs:375:24
[INFO] [stdout]    6: <tokio::loom::std::unsafe_cell::UnsafeCell<tokio::runtime::task::core::Stage<tracing::instrument::Instrumented<foxtive_supervisor::runtime::supervision::supervise::{closure#0}::{closure#3}>>>>::with_mut::<core::task::poll::Poll<core::result::Result<(), anyhow::Error>>, <tokio::runtime::task::core::Core<tracing::instrument::Instrumented<foxtive_supervisor::runtime::supervision::supervise::{closure#0}::{closure#3}>, alloc::sync::Arc<tokio::runtime::scheduler::current_thread::Handle>>>::poll::{closure#0}>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/loom/std/unsafe_cell.rs:16:9
[INFO] [stdout]    7: <tokio::runtime::task::core::Core<tracing::instrument::Instrumented<foxtive_supervisor::runtime::supervision::supervise::{closure#0}::{closure#3}>, alloc::sync::Arc<tokio::runtime::scheduler::current_thread::Handle>>>::poll
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/task/core.rs:364:30
[INFO] [stdout]    8: tokio::runtime::task::harness::poll_future::<tracing::instrument::Instrumented<foxtive_supervisor::runtime::supervision::supervise::{closure#0}::{closure#3}>, alloc::sync::Arc<tokio::runtime::scheduler::current_thread::Handle>>::{closure#0}
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/task/harness.rs:535:30
[INFO] [stdout]    9: <core::panic::unwind_safe::AssertUnwindSafe<tokio::runtime::task::harness::poll_future<tracing::instrument::Instrumented<foxtive_supervisor::runtime::supervision::supervise::{closure#0}::{closure#3}>, alloc::sync::Arc<tokio::runtime::scheduler::current_thread::Handle>>::{closure#0}> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   10: std::panicking::catch_unwind::do_call::<core::panic::unwind_safe::AssertUnwindSafe<tokio::runtime::task::harness::poll_future<tracing::instrument::Instrumented<foxtive_supervisor::runtime::supervision::supervise::{closure#0}::{closure#3}>, alloc::sync::Arc<tokio::runtime::scheduler::current_thread::Handle>>::{closure#0}>, core::task::poll::Poll<core::result::Result<(), anyhow::Error>>>
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:581:40
[INFO] [stdout]   11: __rust_try
[INFO] [stdout]   12: std::panicking::catch_unwind::<core::task::poll::Poll<core::result::Result<(), anyhow::Error>>, core::panic::unwind_safe::AssertUnwindSafe<tokio::runtime::task::harness::poll_future<tracing::instrument::Instrumented<foxtive_supervisor::runtime::supervision::supervise::{closure#0}::{closure#3}>, alloc::sync::Arc<tokio::runtime::scheduler::current_thread::Handle>>::{closure#0}>>
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:544:19
[INFO] [stdout]   13: std::panic::catch_unwind::<core::panic::unwind_safe::AssertUnwindSafe<tokio::runtime::task::harness::poll_future<tracing::instrument::Instrumented<foxtive_supervisor::runtime::supervision::supervise::{closure#0}::{closure#3}>, alloc::sync::Arc<tokio::runtime::scheduler::current_thread::Handle>>::{closure#0}>, core::task::poll::Poll<core::result::Result<(), anyhow::Error>>>
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panic.rs:359:14
[INFO] [stdout]   14: tokio::runtime::task::harness::poll_future::<tracing::instrument::Instrumented<foxtive_supervisor::runtime::supervision::supervise::{closure#0}::{closure#3}>, alloc::sync::Arc<tokio::runtime::scheduler::current_thread::Handle>>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/task/harness.rs:523:18
[INFO] [stdout]   15: <tokio::runtime::task::harness::Harness<tracing::instrument::Instrumented<foxtive_supervisor::runtime::supervision::supervise::{closure#0}::{closure#3}>, alloc::sync::Arc<tokio::runtime::scheduler::current_thread::Handle>>>::poll_inner
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/task/harness.rs:210:27
[INFO] [stdout]   16: <tokio::runtime::task::harness::Harness<tracing::instrument::Instrumented<foxtive_supervisor::runtime::supervision::supervise::{closure#0}::{closure#3}>, alloc::sync::Arc<tokio::runtime::scheduler::current_thread::Handle>>>::poll
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/task/harness.rs:155:20
[INFO] [stdout]   17: tokio::runtime::task::raw::poll::<tracing::instrument::Instrumented<foxtive_supervisor::runtime::supervision::supervise::{closure#0}::{closure#3}>, alloc::sync::Arc<tokio::runtime::scheduler::current_thread::Handle>>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/task/raw.rs:337:13
[INFO] [stdout]   18: <tokio::runtime::task::raw::RawTask>::poll
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/task/raw.rs:267:18
[INFO] [stdout]   19: <tokio::runtime::task::LocalNotified<alloc::sync::Arc<tokio::runtime::scheduler::current_thread::Handle>>>::run
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/task/mod.rs:515:13
[INFO] [stdout]   20: <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on::<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}::{closure#1}
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/scheduler/current_thread/mod.rs:825:30
[INFO] [stdout]   21: tokio::task::coop::with_budget::<(), <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}::{closure#1}>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/task/coop/mod.rs:167:5
[INFO] [stdout]   22: tokio::task::coop::budget::<(), <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}::{closure#1}>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/task/coop/mod.rs:133:5
[INFO] [stdout]   23: <tokio::runtime::scheduler::current_thread::Context>::run_task::<(), <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}::{closure#1}>::{closure#0}
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/scheduler/current_thread/mod.rs:373:43
[INFO] [stdout]   24: <tokio::runtime::scheduler::current_thread::Context>::enter::<(), <tokio::runtime::scheduler::current_thread::Context>::run_task<(), <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}::{closure#1}>::{closure#0}>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/scheduler/current_thread/mod.rs:451:19
[INFO] [stdout]   25: <tokio::runtime::scheduler::current_thread::Context>::run_task::<(), <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}::{closure#1}>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/scheduler/current_thread/mod.rs:373:28
[INFO] [stdout]   26: <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on::<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/scheduler/current_thread/mod.rs:821:43
[INFO] [stdout]   27: <tokio::runtime::scheduler::current_thread::CoreGuard>::enter::<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>::{closure#0}
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/scheduler/current_thread/mod.rs:865:68
[INFO] [stdout]   28: <tokio::runtime::context::scoped::Scoped<tokio::runtime::scheduler::Context>>::set::<<tokio::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>::{closure#0}, (alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>)>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/context/scoped.rs:40:9
[INFO] [stdout]   29: tokio::runtime::context::set_scheduler::<(alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>), <tokio::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>::{closure#0}>::{closure#0}
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/context.rs:181:38
[INFO] [stdout]   30: <std::thread::local::LocalKey<tokio::runtime::context::Context>>::try_with::<tokio::runtime::context::set_scheduler<(alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>), <tokio::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>::{closure#0}>::{closure#0}, (alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>)>
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/thread/local.rs:462:12
[INFO] [stdout]   31: <std::thread::local::LocalKey<tokio::runtime::context::Context>>::with::<tokio::runtime::context::set_scheduler<(alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>), <tokio::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>::{closure#0}>::{closure#0}, (alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>)>
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/thread/local.rs:426:20
[INFO] [stdout]   32: tokio::runtime::context::set_scheduler::<(alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>), <tokio::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>::{closure#0}>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/context.rs:181:17
[INFO] [stdout]   33: <tokio::runtime::scheduler::current_thread::CoreGuard>::enter::<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/scheduler/current_thread/mod.rs:865:27
[INFO] [stdout]   34: <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on::<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/scheduler/current_thread/mod.rs:765:24
[INFO] [stdout]   35: <tokio::runtime::scheduler::current_thread::CurrentThread>::block_on::<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>::{closure#0}
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/scheduler/current_thread/mod.rs:205:33
[INFO] [stdout]   36: tokio::runtime::context::runtime::enter_runtime::<<tokio::runtime::scheduler::current_thread::CurrentThread>::block_on<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/context/runtime.rs:65:16
[INFO] [stdout]   37: <tokio::runtime::scheduler::current_thread::CurrentThread>::block_on::<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/scheduler/current_thread/mod.rs:193:9
[INFO] [stdout]   38: <tokio::runtime::runtime::Runtime>::block_on_inner::<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/runtime.rs:371:52
[INFO] [stdout]   39: <tokio::runtime::runtime::Runtime>::block_on::<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/runtime.rs:345:18
[INFO] [stdout]   40: hot_reload_tests::test_hot_reload_backoff_strategy
[INFO] [stdout]              at ./tests/hot_reload_tests.rs:131:60
[INFO] [stdout]   41: hot_reload_tests::test_hot_reload_backoff_strategy::{closure#0}
[INFO] [stdout]              at ./tests/hot_reload_tests.rs:92:44
[INFO] [stdout]   42: <hot_reload_tests::test_hot_reload_backoff_strategy::{closure#0} as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   43: <fn() -> core::result::Result<(), alloc::string::String> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   44: test::__rust_begin_short_backtrace::<core::result::Result<(), alloc::string::String>, fn() -> core::result::Result<(), alloc::string::String>>
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/test/src/lib.rs:724:18
[INFO] [stdout]   45: test::run_test_in_process::{closure#0}
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/test/src/lib.rs:747:74
[INFO] [stdout]   46: <core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   47: std::panicking::catch_unwind::do_call::<core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}>, core::result::Result<(), alloc::string::String>>
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:581:40
[INFO] [stdout]   48: std::panicking::catch_unwind::<core::result::Result<(), alloc::string::String>, core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}>>
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:544:19
[INFO] [stdout]   49: std::panic::catch_unwind::<core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}>, core::result::Result<(), alloc::string::String>>
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panic.rs:359:14
[INFO] [stdout]   50: test::run_test_in_process
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/test/src/lib.rs:747:27
[INFO] [stdout]   51: test::run_test::{closure#0}
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/test/src/lib.rs:668:43
[INFO] [stdout]   52: test::run_test::{closure#1}
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/test/src/lib.rs:698:41
[INFO] [stdout]   53: std::sys::backtrace::__rust_begin_short_backtrace::<test::run_test::{closure#1}, ()>
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   54: std::thread::lifecycle::spawn_unchecked::<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   55: <core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   56: std::panicking::catch_unwind::do_call::<core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:581:40
[INFO] [stdout]   57: std::panicking::catch_unwind::<(), core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:544:19
[INFO] [stdout]   58: std::panic::catch_unwind::<core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panic.rs:359:14
[INFO] [stdout]   59: std::thread::lifecycle::spawn_unchecked::<test::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   60: <std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1} as core::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   61: <alloc::boxed::Box<dyn core::ops::function::FnOnce<(), Output = ()> + core::marker::Send> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/alloc/src/boxed.rs:2271:9
[INFO] [stdout]   62: <std::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   63: <unknown>
[INFO] [stdout]   64: clone
[INFO] [stdout] 2026-05-04T18:19:42.034504Z  WARN supervision{task_id="backoff-test" task_name=backoff-test}: foxtive_supervisor::runtime::supervision: Scheduling restart after backoff delay_ms=10 attempt=1
[INFO] [stdout] 2026-05-04T18:19:42.034678Z  INFO foxtive_supervisor::runtime::core: Updated backoff strategy task_id=backoff-test old_strategy=Fixed(10ms) new_strategy=Fixed(100ms)
[INFO] [stdout] 2026-05-04T18:19:42.034870Z ERROR supervision{task_id="restart-policy-test" task_name=restart-policy-test}: foxtive_supervisor::runtime::supervision: Task execution failed error=Intentional failure #1
[INFO] [stdout] 
[INFO] [stdout] Stack backtrace:
[INFO] [stdout]    0: <anyhow::Error>::msg::<alloc::string::String>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/anyhow-1.0.102/src/backtrace.rs:10:14
[INFO] [stdout]    1: <hot_reload_tests::FailingTask as foxtive_supervisor::contracts::SupervisedTask>::run::{closure#0}
[INFO] [stdout]              at ./tests/hot_reload_tests.rs:36:24
[INFO] [stdout]    2: <core::pin::Pin<alloc::boxed::Box<dyn core::future::future::Future<Output = core::result::Result<(), anyhow::Error>> + core::marker::Send>> as core::future::future::Future>::poll
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/future/future.rs:133:9
[INFO] [stdout]    3: foxtive_supervisor::runtime::supervision::supervise::{closure#0}::{closure#3}
[INFO] [stdout]              at ./src/runtime/supervision.rs:324:47
[INFO] [stdout]    4: <tracing::instrument::Instrumented<foxtive_supervisor::runtime::supervision::supervise::{closure#0}::{closure#3}> as core::future::future::Future>::poll
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tracing-0.1.44/src/instrument.rs:321:15
[INFO] [stdout]    5: <tokio::runtime::task::core::Core<tracing::instrument::Instrumented<foxtive_supervisor::runtime::supervision::supervise::{closure#0}::{closure#3}>, alloc::sync::Arc<tokio::runtime::scheduler::current_thread::Handle>>>::poll::{closure#0}
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/task/core.rs:375:24
[INFO] [stdout]    6: <tokio::loom::std::unsafe_cell::UnsafeCell<tokio::runtime::task::core::Stage<tracing::instrument::Instrumented<foxtive_supervisor::runtime::supervision::supervise::{closure#0}::{closure#3}>>>>::with_mut::<core::task::poll::Poll<core::result::Result<(), anyhow::Error>>, <tokio::runtime::task::core::Core<tracing::instrument::Instrumented<foxtive_supervisor::runtime::supervision::supervise::{closure#0}::{closure#3}>, alloc::sync::Arc<tokio::runtime::scheduler::current_thread::Handle>>>::poll::{closure#0}>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/loom/std/unsafe_cell.rs:16:9
[INFO] [stdout]    7: <tokio::runtime::task::core::Core<tracing::instrument::Instrumented<foxtive_supervisor::runtime::supervision::supervise::{closure#0}::{closure#3}>, alloc::sync::Arc<tokio::runtime::scheduler::current_thread::Handle>>>::poll
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/task/core.rs:364:30
[INFO] [stdout]    8: tokio::runtime::task::harness::poll_future::<tracing::instrument::Instrumented<foxtive_supervisor::runtime::supervision::supervise::{closure#0}::{closure#3}>, alloc::sync::Arc<tokio::runtime::scheduler::current_thread::Handle>>::{closure#0}
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/task/harness.rs:535:30
[INFO] [stdout]    9: <core::panic::unwind_safe::AssertUnwindSafe<tokio::runtime::task::harness::poll_future<tracing::instrument::Instrumented<foxtive_supervisor::runtime::supervision::supervise::{closure#0}::{closure#3}>, alloc::sync::Arc<tokio::runtime::scheduler::current_thread::Handle>>::{closure#0}> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   10: std::panicking::catch_unwind::do_call::<core::panic::unwind_safe::AssertUnwindSafe<tokio::runtime::task::harness::poll_future<tracing::instrument::Instrumented<foxtive_supervisor::runtime::supervision::supervise::{closure#0}::{closure#3}>, alloc::sync::Arc<tokio::runtime::scheduler::current_thread::Handle>>::{closure#0}>, core::task::poll::Poll<core::result::Result<(), anyhow::Error>>>
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:581:40
[INFO] [stdout]   11: __rust_try
[INFO] [stdout]   12: std::panicking::catch_unwind::<core::task::poll::Poll<core::result::Result<(), anyhow::Error>>, core::panic::unwind_safe::AssertUnwindSafe<tokio::runtime::task::harness::poll_future<tracing::instrument::Instrumented<foxtive_supervisor::runtime::supervision::supervise::{closure#0}::{closure#3}>, alloc::sync::Arc<tokio::runtime::scheduler::current_thread::Handle>>::{closure#0}>>
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:544:19
[INFO] [stdout]   13: std::panic::catch_unwind::<core::panic::unwind_safe::AssertUnwindSafe<tokio::runtime::task::harness::poll_future<tracing::instrument::Instrumented<foxtive_supervisor::runtime::supervision::supervise::{closure#0}::{closure#3}>, alloc::sync::Arc<tokio::runtime::scheduler::current_thread::Handle>>::{closure#0}>, core::task::poll::Poll<core::result::Result<(), anyhow::Error>>>
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panic.rs:359:14
[INFO] [stdout]   14: tokio::runtime::task::harness::poll_future::<tracing::instrument::Instrumented<foxtive_supervisor::runtime::supervision::supervise::{closure#0}::{closure#3}>, alloc::sync::Arc<tokio::runtime::scheduler::current_thread::Handle>>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/task/harness.rs:523:18
[INFO] [stdout]   15: <tokio::runtime::task::harness::Harness<tracing::instrument::Instrumented<foxtive_supervisor::runtime::supervision::supervise::{closure#0}::{closure#3}>, alloc::sync::Arc<tokio::runtime::scheduler::current_thread::Handle>>>::poll_inner
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/task/harness.rs:210:27
[INFO] [stdout]   16: <tokio::runtime::task::harness::Harness<tracing::instrument::Instrumented<foxtive_supervisor::runtime::supervision::supervise::{closure#0}::{closure#3}>, alloc::sync::Arc<tokio::runtime::scheduler::current_thread::Handle>>>::poll
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/task/harness.rs:155:20
[INFO] [stdout]   17: tokio::runtime::task::raw::poll::<tracing::instrument::Instrumented<foxtive_supervisor::runtime::supervision::supervise::{closure#0}::{closure#3}>, alloc::sync::Arc<tokio::runtime::scheduler::current_thread::Handle>>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/task/raw.rs:337:13
[INFO] [stdout]   18: <tokio::runtime::task::raw::RawTask>::poll
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/task/raw.rs:267:18
[INFO] [stdout]   19: <tokio::runtime::task::LocalNotified<alloc::sync::Arc<tokio::runtime::scheduler::current_thread::Handle>>>::run
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/task/mod.rs:515:13
[INFO] [stdout]   20: <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on::<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}::{closure#1}
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/scheduler/current_thread/mod.rs:825:30
[INFO] [stdout]   21: tokio::task::coop::with_budget::<(), <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}::{closure#1}>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/task/coop/mod.rs:167:5
[INFO] [stdout]   22: tokio::task::coop::budget::<(), <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}::{closure#1}>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/task/coop/mod.rs:133:5
[INFO] [stdout]   23: <tokio::runtime::scheduler::current_thread::Context>::run_task::<(), <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}::{closure#1}>::{closure#0}
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/scheduler/current_thread/mod.rs:373:43
[INFO] [stdout]   24: <tokio::runtime::scheduler::current_thread::Context>::enter::<(), <tokio::runtime::scheduler::current_thread::Context>::run_task<(), <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}::{closure#1}>::{closure#0}>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/scheduler/current_thread/mod.rs:451:19
[INFO] [stdout]   25: <tokio::runtime::scheduler::current_thread::Context>::run_task::<(), <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}::{closure#1}>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/scheduler/current_thread/mod.rs:373:28
[INFO] [stdout]   26: <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on::<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/scheduler/current_thread/mod.rs:821:43
[INFO] [stdout]   27: <tokio::runtime::scheduler::current_thread::CoreGuard>::enter::<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>::{closure#0}
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/scheduler/current_thread/mod.rs:865:68
[INFO] [stdout]   28: <tokio::runtime::context::scoped::Scoped<tokio::runtime::scheduler::Context>>::set::<<tokio::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>::{closure#0}, (alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>)>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/context/scoped.rs:40:9
[INFO] [stdout]   29: tokio::runtime::context::set_scheduler::<(alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>), <tokio::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>::{closure#0}>::{closure#0}
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/context.rs:181:38
[INFO] [stdout]   30: <std::thread::local::LocalKey<tokio::runtime::context::Context>>::try_with::<tokio::runtime::context::set_scheduler<(alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>), <tokio::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>::{closure#0}>::{closure#0}, (alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>)>
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/thread/local.rs:462:12
[INFO] [stdout]   31: <std::thread::local::LocalKey<tokio::runtime::context::Context>>::with::<tokio::runtime::context::set_scheduler<(alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>), <tokio::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>::{closure#0}>::{closure#0}, (alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>)>
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/thread/local.rs:426:20
[INFO] [stdout]   32: tokio::runtime::context::set_scheduler::<(alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>), <tokio::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>::{closure#0}>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/context.rs:181:17
[INFO] [stdout]   33: <tokio::runtime::scheduler::current_thread::CoreGuard>::enter::<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/scheduler/current_thread/mod.rs:865:27
[INFO] [stdout]   34: <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on::<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/scheduler/current_thread/mod.rs:765:24
[INFO] [stdout]   35: <tokio::runtime::scheduler::current_thread::CurrentThread>::block_on::<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>::{closure#0}
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/scheduler/current_thread/mod.rs:205:33
[INFO] [stdout]   36: tokio::runtime::context::runtime::enter_runtime::<<tokio::runtime::scheduler::current_thread::CurrentThread>::block_on<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/context/runtime.rs:65:16
[INFO] [stdout]   37: <tokio::runtime::scheduler::current_thread::CurrentThread>::block_on::<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/scheduler/current_thread/mod.rs:193:9
[INFO] [stdout]   38: <tokio::runtime::runtime::Runtime>::block_on_inner::<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/runtime.rs:371:52
[INFO] [stdout]   39: <tokio::runtime::runtime::Runtime>::block_on::<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/runtime.rs:345:18
[INFO] [stdout]   40: hot_reload_tests::test_hot_reload_restart_policy
[INFO] [stdout]              at ./tests/hot_reload_tests.rs:87:58
[INFO] [stdout]   41: hot_reload_tests::test_hot_reload_restart_policy::{closure#0}
[INFO] [stdout]              at ./tests/hot_reload_tests.rs:52:42
[INFO] [stdout]   42: <hot_reload_tests::test_hot_reload_restart_policy::{closure#0} as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   43: <fn() -> core::result::Result<(), alloc::string::String> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   44: test::__rust_begin_short_backtrace::<core::result::Result<(), alloc::string::String>, fn() -> core::result::Result<(), alloc::string::String>>
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/test/src/lib.rs:724:18
[INFO] [stdout]   45: test::run_test_in_process::{closure#0}
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/test/src/lib.rs:747:74
[INFO] [stdout]   46: <core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   47: std::panicking::catch_unwind::do_call::<core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}>, core::result::Result<(), alloc::string::String>>
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:581:40
[INFO] [stdout]   48: std::panicking::catch_unwind::<core::result::Result<(), alloc::string::String>, core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}>>
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:544:19
[INFO] [stdout]   49: std::panic::catch_unwind::<core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}>, core::result::Result<(), alloc::string::String>>
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panic.rs:359:14
[INFO] [stdout]   50: test::run_test_in_process
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/test/src/lib.rs:747:27
[INFO] [stdout]   51: test::run_test::{closure#0}
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/test/src/lib.rs:668:43
[INFO] [stdout]   52: test::run_test::{closure#1}
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/test/src/lib.rs:698:41
[INFO] [stdout]   53: std::sys::backtrace::__rust_begin_short_backtrace::<test::run_test::{closure#1}, ()>
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   54: std::thread::lifecycle::spawn_unchecked::<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   55: <core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   56: std::panicking::catch_unwind::do_call::<core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:581:40
[INFO] [stdout]   57: std::panicking::catch_unwind::<(), core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:544:19
[INFO] [stdout]   58: std::panic::catch_unwind::<core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panic.rs:359:14
[INFO] [stdout]   59: std::thread::lifecycle::spawn_unchecked::<test::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   60: <std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1} as core::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   61: <alloc::boxed::Box<dyn core::ops::function::FnOnce<(), Output = ()> + core::marker::Send> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/alloc/src/boxed.rs:2271:9
[INFO] [stdout]   62: <std::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   63: <unknown>
[INFO] [stdout]   64: clone
[INFO] [stdout] 2026-05-04T18:19:42.034990Z  WARN supervision{task_id="restart-policy-test" task_name=restart-policy-test}: foxtive_supervisor::runtime::supervision: Scheduling restart after backoff delay_ms=10 attempt=1
[INFO] [stdout] 2026-05-04T18:19:42.035113Z  INFO foxtive_supervisor::runtime::core: Updated restart policy task_id=restart-policy-test old_policy=MaxAttempts(5) new_policy=Never
[INFO] [stdout] 2026-05-04T18:19:42.035181Z  INFO foxtive_supervisor::runtime::core: [Supervisor] Shutting down 1 tasks...
[INFO] [stdout] 2026-05-04T18:19:42.035232Z  INFO foxtive_supervisor::runtime::core: Signalling task 'restart-policy-test' to stop... task_id=restart-policy-test
[INFO] [stdout] 2026-05-04T18:19:42.047233Z  INFO supervision{task_id="restart-policy-test" task_name=restart-policy-test}: foxtive_supervisor::runtime::supervision: Received Stop command during backoff
[INFO] [stdout] 2026-05-04T18:19:42.047394Z  INFO foxtive_supervisor::runtime::core: Task 'restart-policy-test' supervision completed. task_id=restart-policy-test
[INFO] [stdout] 2026-05-04T18:19:42.047433Z  INFO foxtive_supervisor::runtime::core: Task 'restart-policy-test' on_shutdown completed. task_id=restart-policy-test
[INFO] [stdout] 2026-05-04T18:19:42.047464Z  INFO foxtive_supervisor::runtime::core: [Supervisor] All tasks shut down
[INFO] [stdout] 2026-05-04T18:19:42.047770Z ERROR supervision{task_id="backoff-test" task_name=backoff-test}: foxtive_supervisor::runtime::supervision: Task execution failed error=Intentional failure #2
[INFO] [stdout] 
[INFO] [stdout] Stack backtrace:
[INFO] [stdout]    0: <anyhow::Error>::msg::<alloc::string::String>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/anyhow-1.0.102/src/backtrace.rs:10:14
[INFO] [stdout]    1: <hot_reload_tests::FailingTask as foxtive_supervisor::contracts::SupervisedTask>::run::{closure#0}
[INFO] [stdout]              at ./tests/hot_reload_tests.rs:36:24
[INFO] [stdout]    2: <core::pin::Pin<alloc::boxed::Box<dyn core::future::future::Future<Output = core::result::Result<(), anyhow::Error>> + core::marker::Send>> as core::future::future::Future>::poll
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/future/future.rs:133:9
[INFO] [stdout]    3: foxtive_supervisor::runtime::supervision::supervise::{closure#0}::{closure#3}
[INFO] [stdout]              at ./src/runtime/supervision.rs:324:47
[INFO] [stdout]    4: <tracing::instrument::Instrumented<foxtive_supervisor::runtime::supervision::supervise::{closure#0}::{closure#3}> as core::future::future::Future>::poll
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tracing-0.1.44/src/instrument.rs:321:15
[INFO] [stdout]    5: <tokio::runtime::task::core::Core<tracing::instrument::Instrumented<foxtive_supervisor::runtime::supervision::supervise::{closure#0}::{closure#3}>, alloc::sync::Arc<tokio::runtime::scheduler::current_thread::Handle>>>::poll::{closure#0}
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/task/core.rs:375:24
[INFO] [stdout]    6: <tokio::loom::std::unsafe_cell::UnsafeCell<tokio::runtime::task::core::Stage<tracing::instrument::Instrumented<foxtive_supervisor::runtime::supervision::supervise::{closure#0}::{closure#3}>>>>::with_mut::<core::task::poll::Poll<core::result::Result<(), anyhow::Error>>, <tokio::runtime::task::core::Core<tracing::instrument::Instrumented<foxtive_supervisor::runtime::supervision::supervise::{closure#0}::{closure#3}>, alloc::sync::Arc<tokio::runtime::scheduler::current_thread::Handle>>>::poll::{closure#0}>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/loom/std/unsafe_cell.rs:16:9
[INFO] [stdout]    7: <tokio::runtime::task::core::Core<tracing::instrument::Instrumented<foxtive_supervisor::runtime::supervision::supervise::{closure#0}::{closure#3}>, alloc::sync::Arc<tokio::runtime::scheduler::current_thread::Handle>>>::poll
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/task/core.rs:364:30
[INFO] [stdout]    8: tokio::runtime::task::harness::poll_future::<tracing::instrument::Instrumented<foxtive_supervisor::runtime::supervision::supervise::{closure#0}::{closure#3}>, alloc::sync::Arc<tokio::runtime::scheduler::current_thread::Handle>>::{closure#0}
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/task/harness.rs:535:30
[INFO] [stdout]    9: <core::panic::unwind_safe::AssertUnwindSafe<tokio::runtime::task::harness::poll_future<tracing::instrument::Instrumented<foxtive_supervisor::runtime::supervision::supervise::{closure#0}::{closure#3}>, alloc::sync::Arc<tokio::runtime::scheduler::current_thread::Handle>>::{closure#0}> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   10: std::panicking::catch_unwind::do_call::<core::panic::unwind_safe::AssertUnwindSafe<tokio::runtime::task::harness::poll_future<tracing::instrument::Instrumented<foxtive_supervisor::runtime::supervision::supervise::{closure#0}::{closure#3}>, alloc::sync::Arc<tokio::runtime::scheduler::current_thread::Handle>>::{closure#0}>, core::task::poll::Poll<core::result::Result<(), anyhow::Error>>>
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:581:40
[INFO] [stdout]   11: __rust_try
[INFO] [stdout]   12: std::panicking::catch_unwind::<core::task::poll::Poll<core::result::Result<(), anyhow::Error>>, core::panic::unwind_safe::AssertUnwindSafe<tokio::runtime::task::harness::poll_future<tracing::instrument::Instrumented<foxtive_supervisor::runtime::supervision::supervise::{closure#0}::{closure#3}>, alloc::sync::Arc<tokio::runtime::scheduler::current_thread::Handle>>::{closure#0}>>
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:544:19
[INFO] [stdout]   13: std::panic::catch_unwind::<core::panic::unwind_safe::AssertUnwindSafe<tokio::runtime::task::harness::poll_future<tracing::instrument::Instrumented<foxtive_supervisor::runtime::supervision::supervise::{closure#0}::{closure#3}>, alloc::sync::Arc<tokio::runtime::scheduler::current_thread::Handle>>::{closure#0}>, core::task::poll::Poll<core::result::Result<(), anyhow::Error>>>
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panic.rs:359:14
[INFO] [stdout]   14: tokio::runtime::task::harness::poll_future::<tracing::instrument::Instrumented<foxtive_supervisor::runtime::supervision::supervise::{closure#0}::{closure#3}>, alloc::sync::Arc<tokio::runtime::scheduler::current_thread::Handle>>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/task/harness.rs:523:18
[INFO] [stdout]   15: <tokio::runtime::task::harness::Harness<tracing::instrument::Instrumented<foxtive_supervisor::runtime::supervision::supervise::{closure#0}::{closure#3}>, alloc::sync::Arc<tokio::runtime::scheduler::current_thread::Handle>>>::poll_inner
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/task/harness.rs:210:27
[INFO] [stdout]   16: <tokio::runtime::task::harness::Harness<tracing::instrument::Instrumented<foxtive_supervisor::runtime::supervision::supervise::{closure#0}::{closure#3}>, alloc::sync::Arc<tokio::runtime::scheduler::current_thread::Handle>>>::poll
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/task/harness.rs:155:20
[INFO] [stdout]   17: tokio::runtime::task::raw::poll::<tracing::instrument::Instrumented<foxtive_supervisor::runtime::supervision::supervise::{closure#0}::{closure#3}>, alloc::sync::Arc<tokio::runtime::scheduler::current_thread::Handle>>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/task/raw.rs:337:13
[INFO] [stdout]   18: <tokio::runtime::task::raw::RawTask>::poll
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/task/raw.rs:267:18
[INFO] [stdout]   19: <tokio::runtime::task::LocalNotified<alloc::sync::Arc<tokio::runtime::scheduler::current_thread::Handle>>>::run
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/task/mod.rs:515:13
[INFO] [stdout]   20: <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on::<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}::{closure#1}
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/scheduler/current_thread/mod.rs:825:30
[INFO] [stdout]   21: tokio::task::coop::with_budget::<(), <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}::{closure#1}>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/task/coop/mod.rs:167:5
[INFO] [stdout]   22: tokio::task::coop::budget::<(), <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}::{closure#1}>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/task/coop/mod.rs:133:5
[INFO] [stdout]   23: <tokio::runtime::scheduler::current_thread::Context>::run_task::<(), <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}::{closure#1}>::{closure#0}
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/scheduler/current_thread/mod.rs:373:43
[INFO] [stdout]   24: <tokio::runtime::scheduler::current_thread::Context>::enter::<(), <tokio::runtime::scheduler::current_thread::Context>::run_task<(), <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}::{closure#1}>::{closure#0}>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/scheduler/current_thread/mod.rs:451:19
[INFO] [stdout]   25: <tokio::runtime::scheduler::current_thread::Context>::run_task::<(), <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}::{closure#1}>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/scheduler/current_thread/mod.rs:373:28
[INFO] [stdout]   26: <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on::<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/scheduler/current_thread/mod.rs:821:43
[INFO] [stdout]   27: <tokio::runtime::scheduler::current_thread::CoreGuard>::enter::<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>::{closure#0}
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/scheduler/current_thread/mod.rs:865:68
[INFO] [stdout]   28: <tokio::runtime::context::scoped::Scoped<tokio::runtime::scheduler::Context>>::set::<<tokio::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>::{closure#0}, (alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>)>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/context/scoped.rs:40:9
[INFO] [stdout]   29: tokio::runtime::context::set_scheduler::<(alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>), <tokio::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>::{closure#0}>::{closure#0}
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/context.rs:181:38
[INFO] [stdout]   30: <std::thread::local::LocalKey<tokio::runtime::context::Context>>::try_with::<tokio::runtime::context::set_scheduler<(alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>), <tokio::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>::{closure#0}>::{closure#0}, (alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>)>
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/thread/local.rs:462:12
[INFO] [stdout]   31: <std::thread::local::LocalKey<tokio::runtime::context::Context>>::with::<tokio::runtime::context::set_scheduler<(alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>), <tokio::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>::{closure#0}>::{closure#0}, (alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>)>
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/thread/local.rs:426:20
[INFO] [stdout]   32: tokio::runtime::context::set_scheduler::<(alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>), <tokio::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>::{closure#0}>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/context.rs:181:17
[INFO] [stdout]   33: <tokio::runtime::scheduler::current_thread::CoreGuard>::enter::<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/scheduler/current_thread/mod.rs:865:27
[INFO] [stdout]   34: <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on::<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/scheduler/current_thread/mod.rs:765:24
[INFO] [stdout]   35: <tokio::runtime::scheduler::current_thread::CurrentThread>::block_on::<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>::{closure#0}
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/scheduler/current_thread/mod.rs:205:33
[INFO] [stdout]   36: tokio::runtime::context::runtime::enter_runtime::<<tokio::runtime::scheduler::current_thread::CurrentThread>::block_on<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/context/runtime.rs:65:16
[INFO] [stdout]   37: <tokio::runtime::scheduler::current_thread::CurrentThread>::block_on::<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/scheduler/current_thread/mod.rs:193:9
[INFO] [stdout]   38: <tokio::runtime::runtime::Runtime>::block_on_inner::<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/runtime.rs:371:52
[INFO] [stdout]   39: <tokio::runtime::runtime::Runtime>::block_on::<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/runtime.rs:345:18
[INFO] [stdout]   40: hot_reload_tests::test_hot_reload_backoff_strategy
[INFO] [stdout]              at ./tests/hot_reload_tests.rs:131:60
[INFO] [stdout]   41: hot_reload_tests::test_hot_reload_backoff_strategy::{closure#0}
[INFO] [stdout]              at ./tests/hot_reload_tests.rs:92:44
[INFO] [stdout]   42: <hot_reload_tests::test_hot_reload_backoff_strategy::{closure#0} as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   43: <fn() -> core::result::Result<(), alloc::string::String> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   44: test::__rust_begin_short_backtrace::<core::result::Result<(), alloc::string::String>, fn() -> core::result::Result<(), alloc::string::String>>
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/test/src/lib.rs:724:18
[INFO] [stdout]   45: test::run_test_in_process::{closure#0}
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/test/src/lib.rs:747:74
[INFO] [stdout]   46: <core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   47: std::panicking::catch_unwind::do_call::<core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}>, core::result::Result<(), alloc::string::String>>
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:581:40
[INFO] [stdout]   48: std::panicking::catch_unwind::<core::result::Result<(), alloc::string::String>, core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}>>
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:544:19
[INFO] [stdout]   49: std::panic::catch_unwind::<core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}>, core::result::Result<(), alloc::string::String>>
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panic.rs:359:14
[INFO] [stdout]   50: test::run_test_in_process
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/test/src/lib.rs:747:27
[INFO] [stdout]   51: test::run_test::{closure#0}
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/test/src/lib.rs:668:43
[INFO] [stdout]   52: test::run_test::{closure#1}
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/test/src/lib.rs:698:41
[INFO] [stdout]   53: std::sys::backtrace::__rust_begin_short_backtrace::<test::run_test::{closure#1}, ()>
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   54: std::thread::lifecycle::spawn_unchecked::<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   55: <core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   56: std::panicking::catch_unwind::do_call::<core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:581:40
[INFO] [stdout]   57: std::panicking::catch_unwind::<(), core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:544:19
[INFO] [stdout]   58: std::panic::catch_unwind::<core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panic.rs:359:14
[INFO] [stdout]   59: std::thread::lifecycle::spawn_unchecked::<test::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   60: <std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1} as core::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   61: <alloc::boxed::Box<dyn core::ops::function::FnOnce<(), Output = ()> + core::marker::Send> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/alloc/src/boxed.rs:2271:9
[INFO] [stdout]   62: <std::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]              at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   63: <unknown>
[INFO] [stdout]   64: clone
[INFO] [stdout] 2026-05-04T18:19:42.047936Z  WARN supervision{task_id="backoff-test" task_name=backoff-test}: foxtive_supervisor::runtime::supervision: Scheduling restart after backoff delay_ms=100 attempt=2
[INFO] [stdout] test test_hot_reload_restart_policy ... ok
[INFO] [stdout] 2026-05-04T18:19:42.149611Z  INFO supervision{task_id="backoff-test" task_name=backoff-test}: foxtive_supervisor::runtime::supervision: Task completed successfully
[INFO] [stdout] 2026-05-04T18:19:42.181084Z  INFO foxtive_supervisor::runtime::core: [Supervisor] Shutting down 1 tasks...
[INFO] [stdout] 2026-05-04T18:19:42.181215Z  INFO foxtive_supervisor::runtime::core: Signalling task 'disabled-test' to stop... task_id=disabled-test
[INFO] [stdout] 2026-05-04T18:19:42.181282Z  INFO foxtive_supervisor::runtime::core: Task 'disabled-test' supervision completed. task_id=disabled-test
[INFO] [stdout] 2026-05-04T18:19:42.181317Z  INFO foxtive_supervisor::runtime::core: Task 'disabled-test' on_shutdown completed. task_id=disabled-test
[INFO] [stdout] 2026-05-04T18:19:42.181341Z  INFO foxtive_supervisor::runtime::core: [Supervisor] All tasks shut down
[INFO] [stdout] test test_disabled_task_no_restart ... ok
[INFO] [stdout] 2026-05-04T18:19:42.439441Z  INFO supervision{task_id="during-exec-test" task_name=during-exec-test}: foxtive_supervisor::runtime::supervision: Task completed successfully
[INFO] [stdout] 2026-05-04T18:19:42.535362Z  INFO foxtive_supervisor::runtime::core: [Supervisor] Shutting down 1 tasks...
[INFO] [stdout] 2026-05-04T18:19:42.535475Z  INFO foxtive_supervisor::runtime::core: Signalling task 'backoff-test' to stop... task_id=backoff-test
[INFO] [stdout] 2026-05-04T18:19:42.535532Z  INFO foxtive_supervisor::runtime::core: Task 'backoff-test' supervision completed. task_id=backoff-test
[INFO] [stdout] 2026-05-04T18:19:42.535563Z  INFO foxtive_supervisor::runtime::core: Task 'backoff-test' on_shutdown completed. task_id=backoff-test
[INFO] [stdout] 2026-05-04T18:19:42.535583Z  INFO foxtive_supervisor::runtime::core: [Supervisor] All tasks shut down
[INFO] [stdout] test test_hot_reload_backoff_strategy ... ok
[INFO] [stdout] 2026-05-04T18:19:42.834361Z  INFO foxtive_supervisor::runtime::core: [Supervisor] Shutting down 1 tasks...
[INFO] [stdout] 2026-05-04T18:19:42.834469Z  INFO foxtive_supervisor::runtime::core: Signalling task 'during-exec-test' to stop... task_id=during-exec-test
[INFO] [stdout] 2026-05-04T18:19:42.834513Z  INFO foxtive_supervisor::runtime::core: Task 'during-exec-test' supervision completed. task_id=during-exec-test
[INFO] [stdout] 2026-05-04T18:19:42.834532Z  INFO foxtive_supervisor::runtime::core: Task 'during-exec-test' on_shutdown completed. task_id=during-exec-test
[INFO] [stdout] 2026-05-04T18:19:42.834552Z  INFO foxtive_supervisor::runtime::core: [Supervisor] All tasks shut down
[INFO] [stdout] test test_update_during_execution ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 13 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.91s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/lifecycle_tests.rs (/opt/rustwide/target/debug/deps/lifecycle_tests-1d7b59f9b2405946)
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test test_successful_task_lifecycle ... ok
[INFO] [stdout] test test_task_restart_on_failure ... ok
[INFO] [stdout] test test_task_panic_recovery ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 6.10s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/persistence_benchmarks.rs (/opt/rustwide/target/debug/deps/persistence_benchmarks-5b8f3d8039e22ccb)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stderr]      Running tests/persistence_tests.rs (/opt/rustwide/target/debug/deps/persistence_tests-1b923cb0548b64b0)
[INFO] [stdout] test benchmark_persistence_impact ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 2.28s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test test_in_memory_state_store ... ok
[INFO] [stderr]      Running tests/prerequisite_tests.rs (/opt/rustwide/target/debug/deps/prerequisite_tests-7f6209dd02c4ecc0)
[INFO] [stdout] test test_fs_state_store ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test test_multiple_prerequisites ... ok
[INFO] [stdout] test test_prerequisite_satisfaction ... ok
[INFO] [stdout] test test_prerequisite_failure_prevents_startup ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.06s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/real_world_examples.rs (/opt/rustwide/target/debug/deps/real_world_examples-504fcb0d6924947a)
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test test_microservice_architecture ... ok
[INFO] [stdout] test test_message_processing_pipeline ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.51s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/shutdown_tests.rs (/opt/rustwide/target/debug/deps/shutdown_tests-19ab75de505d9719)
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test test_shutdown_without_tasks ... ok
[INFO] [stdout] test test_shutdown_with_mock_task ... ok
[INFO] [stdout] test test_shutdown_order_respects_dependencies ... ok
[INFO] [stdout] test test_shutdown_timeout_forces_termination ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.16s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/task_pool_tests.rs (/opt/rustwide/target/debug/deps/task_pool_tests-55ee2f0ad9d85f24)
[INFO] [stdout] 
[INFO] [stdout] running 8 tests
[INFO] [stdout] test test_pool_with_different_strategies ... ok
[INFO] [stdout] test test_pool_integration_with_groups ... ok
[INFO] [stdout] test test_task_pool_creation ... ok
[INFO] [stdout] test test_pool_dynamic_scaling_simulation ... ok
[INFO] [stdout] test test_pool_concurrent_start_stop ... ok
[INFO] [stdout] test test_pool_load_distribution ... ok
[INFO] [stdout] test test_large_pool_stress ... ok
[INFO] [stderr]      Running tests/testing_util_tests.rs (/opt/rustwide/target/debug/deps/testing_util_tests-90cfdf249c62b128)
[INFO] [stdout] test test_pool_with_failing_workers ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 2.06s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test test_fake_time_backoff ... ok
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/graceful_shutdown-d3a565eefa3100c2)
[INFO] [stdout] test test_harness_and_assertions ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.06s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/microservice_orchestration-d4a0312f5fd79309)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/panic_cathcer-7e2cdeb65e781f91)
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/persistence-e66227eda85fe1fd)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/supervisor_hierarchies-78496700076a474f)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/task_groups-1b681484cb9e7e22)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/task_pools-5fcfd7c6b3a1d284)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/tracing_example-bf709387d35fdc8d)
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests foxtive
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 42 tests
[INFO] [stdout] test foxtive/src/helpers/number.rs - helpers::number::to_cents (line 11) ... ok
[INFO] [stdout] test foxtive/src/helpers/mod.rs - helpers (line 51) ... ok
[INFO] [stdout] test foxtive/src/helpers/serde_json.rs - helpers::serde_json::deserialize_f64_from_any (line 170) ... ok
[INFO] [stdout] test foxtive/src/helpers/serde_json.rs - helpers::serde_json::deserialize_i64_with_default (line 571) ... ok
[INFO] [stdout] test foxtive/src/helpers/serde_json.rs - helpers::serde_json::deserialize_optional_f64_from_any (line 211) ... ok
[INFO] [stdout] test foxtive/src/helpers/serde_json.rs - helpers::serde_json::deserialize_optional_string_from_any (line 13) ... ok
[INFO] [stdout] test foxtive/src/helpers/mod.rs - helpers (line 58) ... ok
[INFO] [stdout] test foxtive/src/helpers/number.rs - helpers::number::format_currency (line 44) ... ok
[INFO] [stdout] test foxtive/src/helpers/tokio.rs - helpers::tokio::blk (line 35) - compile ... ok
[INFO] [stdout] test foxtive/src/helpers/tokio.rs - helpers::tokio::block (line 103) - compile ... ok
[INFO] [stdout] test foxtive/src/helpers/serde_json.rs - helpers::serde_json::deserialize_optional_i64_from_any (line 122) ... ok
[INFO] [stdout] test foxtive/src/helpers/number.rs - helpers::number::format_integer (line 96) ... ok
[INFO] [stdout] test foxtive/src/helpers/tokio.rs - helpers::tokio::run_async (line 183) ... ok
[INFO] [stdout] test foxtive/src/macros/error.rs - macros::error::bad_request (line 54) - compile ... ok
[INFO] [stdout] test foxtive/src/macros/error.rs - macros::error::conflict (line 88) - compile ... ok
[INFO] [stdout] test foxtive/src/macros/error.rs - macros::error::ensure (line 177) - compile ... ok
[INFO] [stdout] test foxtive/src/macros/error.rs - macros::error::ensure_found (line 197) - compile ... ok
[INFO] [stdout] test foxtive/src/macros/error.rs - macros::error::forbidden (line 37) - compile ... ok
[INFO] [stdout] test foxtive/src/helpers/tokio.rs - helpers::tokio::run_async (line 193) ... ok
[INFO] [stdout] test foxtive/src/macros/error.rs - macros::error::invalid (line 71) - compile ... ok
[INFO] [stdout] test foxtive/src/macros/error.rs - macros::error::not_found (line 3) - compile ... ok
[INFO] [stdout] test foxtive/src/macros/error.rs - macros::error::unauthorized (line 20) - compile ... ok
[INFO] [stdout] test foxtive/src/macros/error.rs - macros::error::unprocessable_entity (line 105) - compile ... ok
[INFO] [stdout] test foxtive/src/macros/error.rs - macros::error::validation_error (line 142) - compile ... ok
[INFO] [stdout] test foxtive/src/setup/trace.rs - setup::trace::init_tracing (line 134) ... ok
[INFO] [stdout] test foxtive/src/tokio.rs - tokio::Tokio::tick (line 69) ... ok
[INFO] [stdout] test foxtive/src/tokio.rs - tokio::Tokio::timeout (line 34) ... ok
[INFO] [stdout] test foxtive/src/helpers/serde_json.rs - helpers::serde_json::deserialize_optional_bool_from_any (line 293) ... ok
[INFO] [stdout] test foxtive/src/helpers/serde_json.rs - helpers::serde_json::deserialize_bool_from_any (line 257) ... ok
[INFO] [stdout] test foxtive/src/enums/app_message.rs - enums::app_message::AppMessage::validation_error (line 82) ... ok
[INFO] [stdout] test foxtive/src/helpers/tokio.rs - helpers::tokio::blk (line 50) - compile ... ok
[INFO] [stdout] test foxtive/src/helpers/tokio.rs - helpers::tokio::block (line 121) ... ok
[INFO] [stdout] test foxtive/src/helpers/serde_json.rs - helpers::serde_json::deserialize_i64_from_any (line 83) ... ok
[INFO] [stdout] test foxtive/src/helpers/number.rs - helpers::number::from_cents (line 26) ... ok
[INFO] [stdout] test foxtive/src/helpers/serde_json.rs - helpers::serde_json::deserialize_percentage_to_decimal (line 525) ... ok
[INFO] [stdout] test foxtive/src/helpers/serde_json.rs - helpers::serde_json::deserialize_string_from_any (line 47) ... ok
[INFO] [stdout] test foxtive/src/helpers/serde_json.rs - helpers::serde_json::deserialize_optional_i64_zero_as_none (line 455) ... ok
[INFO] [stdout] test foxtive/src/helpers/serde_json.rs - helpers::serde_json::deserialize_optional_vec_from_string_or_array (line 413) ... ok
[INFO] [stdout] test foxtive/src/helpers/serde_json.rs - helpers::serde_json::deserialize_optional_string_empty_as_none (line 494) ... ok
[INFO] [stdout] test foxtive/src/helpers/serde_json.rs - helpers::serde_json::deserialize_vec_from_string_or_array (line 379) ... ok
[INFO] [stdout] test foxtive/src/helpers/serde_json.rs - helpers::serde_json::deserialize_optional_timestamp (line 336) ... ok
[INFO] [stdout] test foxtive/src/macros/error.rs - macros::error::internal_server_error (line 122) - compile ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 42 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.14s
[INFO] [stdout] 
[INFO] [stdout] all doctests ran in 1.96s; merged doctests compilation took 1.80s
[INFO] [stderr]    Doc-tests foxtive_cron
[INFO] [stdout] 
[INFO] [stdout] running 5 tests
[INFO] [stdout] test foxtive-cron/src/fn_job.rs - fn_job::FnJob::new_blocking (line 133) ... ok
[INFO] [stdout] test foxtive-cron/src/contracts.rs - contracts::ValidatedSchedule::parse (line 156) ... ok
[INFO] [stdout] test foxtive-cron/src/fn_job.rs - fn_job::FnJob::new (line 92) ... ok
[INFO] [stdout] test foxtive-cron/src/fn_job.rs - fn_job::FnJob (line 33) ... ok
[INFO] [stdout] test foxtive-cron/src/fn_job.rs - fn_job::FnJob (line 22) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] all doctests ran in 0.97s; merged doctests compilation took 0.96s
[INFO] [stderr]    Doc-tests foxtive_macros
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests foxtive_supervisor
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test foxtive-supervisor/src/contracts.rs - contracts::SupervisedTask::conditional_dependencies (line 136) ... ignored
[INFO] [stdout] test foxtive-supervisor/src/contracts.rs - contracts::SupervisedTask::on_shutdown (line 249) ... ignored
[INFO] [stdout] test foxtive-supervisor/src/lib.rs - Supervisor (line 41) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 2 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] all doctests ran in 1.70s; merged doctests compilation took 1.69s
[INFO] running `Command { std: "docker" "inspect" "9457ed4671fb8f157536fb5f50547fbdac1f85f37141cee7db815716c3f70069", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "9457ed4671fb8f157536fb5f50547fbdac1f85f37141cee7db815716c3f70069", kill_on_drop: false }`
[INFO] [stdout] 9457ed4671fb8f157536fb5f50547fbdac1f85f37141cee7db815716c3f70069