[INFO] fetching crate nocontrol 0.0.3...
[INFO] testing nocontrol-0.0.3 against master#562dee4820c458d823175268e41601d4c060588a for pr-154210-2
[INFO] extracting crate nocontrol 0.0.3 into /workspace/builds/worker-0-tc1/source
[INFO] started tweaking crates.io crate nocontrol 0.0.3
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate nocontrol 0.0.3
[INFO] tweaked toml for crates.io crate nocontrol 0.0.3 written to /workspace/builds/worker-0-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate nocontrol 0.0.3 on toolchain 562dee4820c458d823175268e41601d4c060588a
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate nocontrol 0.0.3 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" "+562dee4820c458d823175268e41601d4c060588a" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded tracing-test v0.2.5
[INFO] [stderr]   Downloaded tracing-test-macro v0.2.5
[INFO] [stderr]   Downloaded zmij v1.0.11
[INFO] [stderr]   Downloaded jiff-static v0.2.17
[INFO] [stderr]   Downloaded insta v1.46.0
[INFO] [stderr]   Downloaded tracing-subscriber v0.3.22
[INFO] [stderr]   Downloaded syn v2.0.113
[INFO] [stderr]   Downloaded libc v0.2.179
[INFO] [stderr]   Downloaded jiff v0.2.17
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-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" "+562dee4820c458d823175268e41601d4c060588a" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] bb40838e5b0270671a27948011c3f0711581147555ba2ce1334f5f6e656c4ecf
[INFO] running `Command { std: "docker" "start" "-a" "bb40838e5b0270671a27948011c3f0711581147555ba2ce1334f5f6e656c4ecf", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "bb40838e5b0270671a27948011c3f0711581147555ba2ce1334f5f6e656c4ecf", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "bb40838e5b0270671a27948011c3f0711581147555ba2ce1334f5f6e656c4ecf", kill_on_drop: false }`
[INFO] [stdout] bb40838e5b0270671a27948011c3f0711581147555ba2ce1334f5f6e656c4ecf
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-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" "+562dee4820c458d823175268e41601d4c060588a" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 48236568746d325bafe75c945aabdc832cf1533cd84cab40870629012d4cd6b6
[INFO] running `Command { std: "docker" "start" "-a" "48236568746d325bafe75c945aabdc832cf1533cd84cab40870629012d4cd6b6", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.179
[INFO] [stderr]    Compiling proc-macro2 v1.0.104
[INFO] [stderr]    Compiling unicode-ident v1.0.22
[INFO] [stderr]    Compiling quote v1.0.42
[INFO] [stderr]    Compiling generic-array v0.14.7
[INFO] [stderr]    Compiling zerocopy v0.8.31
[INFO] [stderr]    Compiling smallvec v1.15.1
[INFO] [stderr]    Compiling zmij v1.0.11
[INFO] [stderr]    Compiling bytes v1.11.0
[INFO] [stderr]    Compiling serde_json v1.0.148
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling tracing-core v0.1.36
[INFO] [stderr]    Compiling itoa v1.0.17
[INFO] [stderr]    Compiling memchr v2.7.6
[INFO] [stderr]    Compiling log v0.4.29
[INFO] [stderr]    Compiling crypto-common v0.1.7
[INFO] [stderr]    Compiling block-buffer v0.10.4
[INFO] [stderr]    Compiling jiff v0.2.17
[INFO] [stderr]    Compiling digest v0.10.7
[INFO] [stderr]    Compiling hex v0.4.3
[INFO] [stderr]    Compiling syn v2.0.113
[INFO] [stderr]    Compiling sha2 v0.10.9
[INFO] [stderr]    Compiling getrandom v0.3.4
[INFO] [stderr]    Compiling parking_lot_core v0.9.12
[INFO] [stderr]    Compiling errno v0.3.14
[INFO] [stderr]    Compiling socket2 v0.6.1
[INFO] [stderr]    Compiling signal-hook-registry v1.4.8
[INFO] [stderr]    Compiling rand_core v0.9.3
[INFO] [stderr]    Compiling parking_lot v0.12.5
[INFO] [stderr]    Compiling mio v1.1.1
[INFO] [stderr]    Compiling uuid v1.19.0
[INFO] [stderr]    Compiling ppv-lite86 v0.2.21
[INFO] [stderr]    Compiling rand_chacha v0.9.0
[INFO] [stderr]    Compiling rand v0.9.2
[INFO] [stderr]    Compiling tokio-macros v2.6.0
[INFO] [stderr]    Compiling tracing-attributes v0.1.31
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling async-trait v0.1.89
[INFO] [stderr]    Compiling tokio v1.49.0
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling tokio-util v0.7.18
[INFO] [stderr]    Compiling nocontrol v0.0.3 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 50.59s
[INFO] running `Command { std: "docker" "inspect" "48236568746d325bafe75c945aabdc832cf1533cd84cab40870629012d4cd6b6", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "48236568746d325bafe75c945aabdc832cf1533cd84cab40870629012d4cd6b6", kill_on_drop: false }`
[INFO] [stdout] 48236568746d325bafe75c945aabdc832cf1533cd84cab40870629012d4cd6b6
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-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" "+562dee4820c458d823175268e41601d4c060588a" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 6aaa6ca482a557fd4163be6ced3768baa755daed92338c648a4c586abc685a93
[INFO] running `Command { std: "docker" "start" "-a" "6aaa6ca482a557fd4163be6ced3768baa755daed92338c648a4c586abc685a93", kill_on_drop: false }`
[INFO] [stderr]    Compiling tracing-core v0.1.36
[INFO] [stderr]    Compiling log v0.4.29
[INFO] [stderr]    Compiling regex-syntax v0.8.8
[INFO] [stderr]    Compiling rustix v1.1.3
[INFO] [stderr]    Compiling linux-raw-sys v0.11.0
[INFO] [stderr]    Compiling bitflags v2.10.0
[INFO] [stderr]    Compiling sharded-slab v0.1.7
[INFO] [stderr]    Compiling thread_local v1.1.9
[INFO] [stderr]    Compiling nu-ansi-term v0.50.3
[INFO] [stderr]    Compiling fastrand v2.3.0
[INFO] [stderr]    Compiling tracing-test-macro v0.2.5
[INFO] [stderr]    Compiling console v0.15.11
[INFO] [stderr]    Compiling similar v2.7.0
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling tracing-log v0.2.0
[INFO] [stderr]    Compiling nocontrol v0.0.3 (/opt/rustwide/workdir)
[INFO] [stderr]    Compiling regex-automata v0.4.13
[INFO] [stderr]    Compiling tempfile v3.24.0
[INFO] [stderr]    Compiling insta v1.46.0
[INFO] [stderr]    Compiling matchers v0.2.0
[INFO] [stderr]    Compiling tracing-subscriber v0.3.22
[INFO] [stderr]    Compiling tracing-test v0.2.5
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 28.96s
[INFO] running `Command { std: "docker" "inspect" "6aaa6ca482a557fd4163be6ced3768baa755daed92338c648a4c586abc685a93", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "6aaa6ca482a557fd4163be6ced3768baa755daed92338c648a4c586abc685a93", kill_on_drop: false }`
[INFO] [stdout] 6aaa6ca482a557fd4163be6ced3768baa755daed92338c648a4c586abc685a93
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-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" "+562dee4820c458d823175268e41601d4c060588a" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 6e45b58becf9e78a65fe137c79c3378ab2b06421d88a30a0fdd2bda7c12dfcc8
[INFO] running `Command { std: "docker" "start" "-a" "6e45b58becf9e78a65fe137c79c3378ab2b06421d88a30a0fdd2bda7c12dfcc8", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.23s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/nocontrol-9f4ce37c625245d1)
[INFO] [stdout] 
[INFO] [stdout] running 6 tests
[INFO] [stdout] test reconcile_queue::tests::test_enqueue_and_take ... ok
[INFO] [stdout] test reconcile_queue::tests::test_enqueue_earlier_updates ... ok
[INFO] [stdout] test reconcile_queue::tests::test_clone_shares_state ... ok
[INFO] [stdout] test reconcile_queue::tests::test_scheduled_order ... ok
[INFO] [stdout] test reconcile_queue::tests::test_idempotent_enqueue ... ok
[INFO] [stdout] test reconcile_queue::tests::test_enqueue_later_no_op ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/mod.rs (/opt/rustwide/target/debug/deps/mod-4c581819a9f3242c)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test test_can_run_reconciler ... FAILED
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- test_can_run_reconciler stdout ----
[INFO] [stdout] 2026-04-19T21:30:51.093998Z  INFO test_can_run_reconciler: nocontrol::control_plane: starting control plane worker_id=019da7a7-4755-7272-81eb-8ceee658020d
[INFO] [stdout] 2026-04-19T21:30:51.094094Z DEBUG test_can_run_reconciler: nocontrol::control_plane::reconciler: starting reconciler self.worker_id=019da7a7-4755-7272-81eb-8ceee658020d now=2026-04-19T21:30:51.094064941Z
[INFO] [stdout] 2026-04-19T21:30:51.094175Z DEBUG test_can_run_reconciler: nocontrol::control_plane::reconciler: starting periodic resync self.worker_id=019da7a7-4755-7272-81eb-8ceee658020d resync_interval=300s
[INFO] [stdout] 2026-04-19T21:30:51.094238Z TRACE test_can_run_reconciler: nocontrol::control_plane::reconciler: syncing manifests self.worker_id=019da7a7-4755-7272-81eb-8ceee658020d now=2026-04-19T21:30:51.094229911Z
[INFO] [stdout] 2026-04-19T21:30:51.094347Z  INFO test_can_run_reconciler: nocontrol::control_plane: adding manifest manifest.name="some-manifest"
[INFO] [stdout] 2026-04-19T21:30:51.094384Z DEBUG test_can_run_reconciler: nocontrol::control_plane::backing_store: adding manifest
[INFO] [stdout] 2026-04-19T21:30:51.596777Z TRACE test_can_run_reconciler: nocontrol::control_plane::reconciler: syncing manifests self.worker_id=019da7a7-4755-7272-81eb-8ceee658020d now=2026-04-19T21:30:51.596752372Z
[INFO] [stdout] 2026-04-19T21:30:51.596960Z TRACE test_can_run_reconciler: nocontrol::control_plane::backing_store: acquiring lease manifest_state.manifest.name="some-manifest"
[INFO] [stdout] 2026-04-19T21:30:51.597028Z TRACE test_can_run_reconciler: nocontrol::control_plane::reconciler: processing manifest from queue self.worker_id=019da7a7-4755-7272-81eb-8ceee658020d manifest_name=some-manifest
[INFO] [stdout] 2026-04-19T21:30:51.597069Z  INFO test_can_run_reconciler: r#mod: reconciliation was called for name = some-manifest, value = some-name
[INFO] [stdout] 2026-04-19T21:30:51.597122Z TRACE test_can_run_reconciler: nocontrol::control_plane::reconciler: requeuing manifest manifest_name=some-manifest delay=1s scheduled_at=2026-04-19T21:30:52.597093912Z
[INFO] [stdout] 2026-04-19T21:30:52.095930Z  INFO test_can_run_reconciler: nocontrol::control_plane: adding manifest manifest.name="some-manifest"
[INFO] [stdout] 2026-04-19T21:30:52.096039Z DEBUG test_can_run_reconciler: nocontrol::control_plane::backing_store: updating manifest
[INFO] [stdout] 2026-04-19T21:30:52.098197Z TRACE test_can_run_reconciler: nocontrol::control_plane::reconciler: syncing manifests self.worker_id=019da7a7-4755-7272-81eb-8ceee658020d now=2026-04-19T21:30:52.098189943Z
[INFO] [stdout] 2026-04-19T21:30:52.598949Z TRACE test_can_run_reconciler: nocontrol::control_plane::reconciler: processing manifest from queue self.worker_id=019da7a7-4755-7272-81eb-8ceee658020d manifest_name=some-manifest
[INFO] [stdout] 2026-04-19T21:30:52.599056Z  INFO test_can_run_reconciler: r#mod: reconciliation was called for name = some-manifest, value = some-changed-name
[INFO] [stdout] 2026-04-19T21:30:52.599130Z TRACE test_can_run_reconciler: nocontrol::control_plane::reconciler: requeuing manifest manifest_name=some-manifest delay=1s scheduled_at=2026-04-19T21:30:53.599086614Z
[INFO] [stdout] 2026-04-19T21:30:52.599305Z TRACE test_can_run_reconciler: nocontrol::control_plane::reconciler: syncing manifests self.worker_id=019da7a7-4755-7272-81eb-8ceee658020d now=2026-04-19T21:30:52.599301594Z
[INFO] [stdout] 2026-04-19T21:30:52.599372Z TRACE test_can_run_reconciler: nocontrol::control_plane::backing_store: updating lease manifest_state.manifest.name="some-manifest"
[INFO] [stdout] 2026-04-19T21:30:53.100947Z TRACE test_can_run_reconciler: nocontrol::control_plane::reconciler: syncing manifests self.worker_id=019da7a7-4755-7272-81eb-8ceee658020d now=2026-04-19T21:30:53.100915325Z
[INFO] [stdout] 2026-04-19T21:30:53.600936Z TRACE test_can_run_reconciler: nocontrol::control_plane::reconciler: processing manifest from queue self.worker_id=019da7a7-4755-7272-81eb-8ceee658020d manifest_name=some-manifest
[INFO] [stdout] 2026-04-19T21:30:53.601046Z  INFO test_can_run_reconciler: r#mod: reconciliation was called for name = some-manifest, value = some-changed-name
[INFO] [stdout] 2026-04-19T21:30:53.601121Z TRACE test_can_run_reconciler: nocontrol::control_plane::reconciler: requeuing manifest manifest_name=some-manifest delay=1s scheduled_at=2026-04-19T21:30:54.601076076Z
[INFO] [stdout] 2026-04-19T21:30:53.602379Z TRACE test_can_run_reconciler: nocontrol::control_plane::reconciler: syncing manifests self.worker_id=019da7a7-4755-7272-81eb-8ceee658020d now=2026-04-19T21:30:53.602371956Z
[INFO] [stdout] 2026-04-19T21:30:53.602446Z TRACE test_can_run_reconciler: nocontrol::control_plane::backing_store: updating lease manifest_state.manifest.name="some-manifest"
[INFO] [stdout] 2026-04-19T21:30:54.095996Z DEBUG test_can_run_reconciler: nocontrol::control_plane::reconciler: reconciler shutting down
[INFO] [stdout] Error: fail
[INFO] [stdout] 
[INFO] [stdout] Stack backtrace:
[INFO] [stdout]    0: <anyhow::Error>::msg::<&str>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/anyhow-1.0.100/src/backtrace.rs:27:14
[INFO] [stdout]    1: anyhow::__private::format_err
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/anyhow-1.0.100/src/lib.rs:695:13
[INFO] [stdout]    2: mod::test_can_run_reconciler::{closure#1}
[INFO] [stdout]              at ./tests/mod.rs:46:9
[INFO] [stdout]    3: <core::pin::Pin<&mut dyn core::future::future::Future<Output = core::result::Result<(), anyhow::Error>>> as core::future::future::Future>::poll
[INFO] [stdout]              at /rustc/562dee4820c458d823175268e41601d4c060588a/library/core/src/future/future.rs:133:9
[INFO] [stdout]    4: <core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = core::result::Result<(), anyhow::Error>>>> as core::future::future::Future>::poll
[INFO] [stdout]              at /rustc/562dee4820c458d823175268e41601d4c060588a/library/core/src/future/future.rs:133:9
[INFO] [stdout]    5: <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on::<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = core::result::Result<(), anyhow::Error>>>>>::{closure#0}::{closure#0}::{closure#0}
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/scheduler/current_thread/mod.rs:753:70
[INFO] [stdout]    6: tokio::task::coop::with_budget::<core::task::poll::Poll<core::result::Result<(), anyhow::Error>>, <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = core::result::Result<(), anyhow::Error>>>>>::{closure#0}::{closure#0}::{closure#0}>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/task/coop/mod.rs:167:5
[INFO] [stdout]    7: tokio::task::coop::budget::<core::task::poll::Poll<core::result::Result<(), anyhow::Error>>, <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = core::result::Result<(), anyhow::Error>>>>>::{closure#0}::{closure#0}::{closure#0}>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/task/coop/mod.rs:133:5
[INFO] [stdout]    8: <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on::<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = core::result::Result<(), anyhow::Error>>>>>::{closure#0}::{closure#0}
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/scheduler/current_thread/mod.rs:753:25
[INFO] [stdout]    9: <tokio::runtime::scheduler::current_thread::Context>::enter::<core::task::poll::Poll<core::result::Result<(), anyhow::Error>>, <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = core::result::Result<(), anyhow::Error>>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/scheduler/current_thread/mod.rs:442:19
[INFO] [stdout]   10: <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on::<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = core::result::Result<(), anyhow::Error>>>>>::{closure#0}
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/scheduler/current_thread/mod.rs:752:44
[INFO] [stdout]   11: <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 = core::result::Result<(), anyhow::Error>>>>>::{closure#0}, core::option::Option<core::result::Result<(), anyhow::Error>>>::{closure#0}
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/scheduler/current_thread/mod.rs:840:68
[INFO] [stdout]   12: <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 = core::result::Result<(), anyhow::Error>>>>>::{closure#0}, core::option::Option<core::result::Result<(), anyhow::Error>>>::{closure#0}, (alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<core::result::Result<(), anyhow::Error>>)>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/context/scoped.rs:40:9
[INFO] [stdout]   13: tokio::runtime::context::set_scheduler::<(alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<core::result::Result<(), anyhow::Error>>), <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 = core::result::Result<(), anyhow::Error>>>>>::{closure#0}, core::option::Option<core::result::Result<(), anyhow::Error>>>::{closure#0}>::{closure#0}
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/context.rs:176:38
[INFO] [stdout]   14: <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<core::result::Result<(), anyhow::Error>>), <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 = core::result::Result<(), anyhow::Error>>>>>::{closure#0}, core::option::Option<core::result::Result<(), anyhow::Error>>>::{closure#0}>::{closure#0}, (alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<core::result::Result<(), anyhow::Error>>)>
[INFO] [stdout]              at /rustc/562dee4820c458d823175268e41601d4c060588a/library/std/src/thread/local.rs:462:12
[INFO] [stdout]   15: <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<core::result::Result<(), anyhow::Error>>), <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 = core::result::Result<(), anyhow::Error>>>>>::{closure#0}, core::option::Option<core::result::Result<(), anyhow::Error>>>::{closure#0}>::{closure#0}, (alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<core::result::Result<(), anyhow::Error>>)>
[INFO] [stdout]              at /rustc/562dee4820c458d823175268e41601d4c060588a/library/std/src/thread/local.rs:426:20
[INFO] [stdout]   16: tokio::runtime::context::set_scheduler::<(alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<core::result::Result<(), anyhow::Error>>), <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 = core::result::Result<(), anyhow::Error>>>>>::{closure#0}, core::option::Option<core::result::Result<(), anyhow::Error>>>::{closure#0}>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/context.rs:176:17
[INFO] [stdout]   17: <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 = core::result::Result<(), anyhow::Error>>>>>::{closure#0}, core::option::Option<core::result::Result<(), anyhow::Error>>>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/scheduler/current_thread/mod.rs:840:27
[INFO] [stdout]   18: <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on::<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = core::result::Result<(), anyhow::Error>>>>>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/scheduler/current_thread/mod.rs:740:24
[INFO] [stdout]   19: <tokio::runtime::scheduler::current_thread::CurrentThread>::block_on::<core::pin::Pin<&mut dyn core::future::future::Future<Output = core::result::Result<(), anyhow::Error>>>>::{closure#0}
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/scheduler/current_thread/mod.rs:200:33
[INFO] [stdout]   20: tokio::runtime::context::runtime::enter_runtime::<<tokio::runtime::scheduler::current_thread::CurrentThread>::block_on<core::pin::Pin<&mut dyn core::future::future::Future<Output = core::result::Result<(), anyhow::Error>>>>::{closure#0}, core::result::Result<(), anyhow::Error>>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/context/runtime.rs:65:16
[INFO] [stdout]   21: <tokio::runtime::scheduler::current_thread::CurrentThread>::block_on::<core::pin::Pin<&mut dyn core::future::future::Future<Output = core::result::Result<(), anyhow::Error>>>>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/scheduler/current_thread/mod.rs:188:9
[INFO] [stdout]   22: <tokio::runtime::runtime::Runtime>::block_on_inner::<core::pin::Pin<&mut dyn core::future::future::Future<Output = core::result::Result<(), anyhow::Error>>>>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/runtime.rs:368:52
[INFO] [stdout]   23: <tokio::runtime::runtime::Runtime>::block_on::<core::pin::Pin<&mut dyn core::future::future::Future<Output = core::result::Result<(), anyhow::Error>>>>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/runtime.rs:342:18
[INFO] [stdout]   24: mod::test_can_run_reconciler
[INFO] [stdout]              at ./tests/mod.rs:46:8
[INFO] [stdout]   25: mod::test_can_run_reconciler::{closure#0}
[INFO] [stdout]              at ./tests/mod.rs:10:39
[INFO] [stdout]   26: <mod::test_can_run_reconciler::{closure#0} as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/562dee4820c458d823175268e41601d4c060588a/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   27: <fn() -> core::result::Result<(), alloc::string::String> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/562dee4820c458d823175268e41601d4c060588a/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   28: test::__rust_begin_short_backtrace::<core::result::Result<(), alloc::string::String>, fn() -> core::result::Result<(), alloc::string::String>>
[INFO] [stdout]              at /rustc/562dee4820c458d823175268e41601d4c060588a/library/test/src/lib.rs:663:18
[INFO] [stdout]   29: test::run_test_in_process::{closure#0}
[INFO] [stdout]              at /rustc/562dee4820c458d823175268e41601d4c060588a/library/test/src/lib.rs:686:74
[INFO] [stdout]   30: <core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/562dee4820c458d823175268e41601d4c060588a/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   31: 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/562dee4820c458d823175268e41601d4c060588a/library/std/src/panicking.rs:581:40
[INFO] [stdout]   32: 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/562dee4820c458d823175268e41601d4c060588a/library/std/src/panicking.rs:544:19
[INFO] [stdout]   33: 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/562dee4820c458d823175268e41601d4c060588a/library/std/src/panic.rs:359:14
[INFO] [stdout]   34: test::run_test_in_process
[INFO] [stdout]              at /rustc/562dee4820c458d823175268e41601d4c060588a/library/test/src/lib.rs:686:27
[INFO] [stdout]   35: test::run_test::{closure#0}
[INFO] [stdout]              at /rustc/562dee4820c458d823175268e41601d4c060588a/library/test/src/lib.rs:607:43
[INFO] [stdout]   36: test::run_test::{closure#1}
[INFO] [stdout]              at /rustc/562dee4820c458d823175268e41601d4c060588a/library/test/src/lib.rs:637:41
[INFO] [stdout]   37: std::sys::backtrace::__rust_begin_short_backtrace::<test::run_test::{closure#1}, ()>
[INFO] [stdout]              at /rustc/562dee4820c458d823175268e41601d4c060588a/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   38: std::thread::lifecycle::spawn_unchecked::<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]              at /rustc/562dee4820c458d823175268e41601d4c060588a/library/std/src/thread/lifecycle.rs:91:13
[INFO] [stdout]   39: <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/562dee4820c458d823175268e41601d4c060588a/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   40: 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/562dee4820c458d823175268e41601d4c060588a/library/std/src/panicking.rs:581:40
[INFO] [stdout]   41: 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/562dee4820c458d823175268e41601d4c060588a/library/std/src/panicking.rs:544:19
[INFO] [stdout]   42: 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/562dee4820c458d823175268e41601d4c060588a/library/std/src/panic.rs:359:14
[INFO] [stdout]   43: std::thread::lifecycle::spawn_unchecked::<test::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]              at /rustc/562dee4820c458d823175268e41601d4c060588a/library/std/src/thread/lifecycle.rs:89:26
[INFO] [stdout]   44: <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/562dee4820c458d823175268e41601d4c060588a/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   45: <alloc::boxed::Box<dyn core::ops::function::FnOnce<(), Output = ()> + core::marker::Send> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/562dee4820c458d823175268e41601d4c060588a/library/alloc/src/boxed.rs:2240:9
[INFO] [stdout]   46: <std::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]              at /rustc/562dee4820c458d823175268e41601d4c060588a/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   47: <unknown>
[INFO] [stdout]   48: clone
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     test_can_run_reconciler
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 0 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 3.05s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--test mod`
[INFO] running `Command { std: "docker" "inspect" "6e45b58becf9e78a65fe137c79c3378ab2b06421d88a30a0fdd2bda7c12dfcc8", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "6e45b58becf9e78a65fe137c79c3378ab2b06421d88a30a0fdd2bda7c12dfcc8", kill_on_drop: false }`
[INFO] [stdout] 6e45b58becf9e78a65fe137c79c3378ab2b06421d88a30a0fdd2bda7c12dfcc8