[INFO] fetching crate futuresdr 0.0.38... [INFO] testing futuresdr-0.0.38 against master#cdb45c87e2cd43495379f7e867e3cc15dcee9f93 for pr-145838-1 [INFO] extracting crate futuresdr 0.0.38 into /workspace/builds/worker-5-tc1/source [INFO] removed /workspace/builds/worker-5-tc1/source/.cargo/config.toml [INFO] started tweaking crates.io crate futuresdr 0.0.38 [INFO] removed 0 missing examples [INFO] removed 0 missing tests [INFO] finished tweaking crates.io crate futuresdr 0.0.38 [INFO] tweaked toml for crates.io crate futuresdr 0.0.38 written to /workspace/builds/worker-5-tc1/source/Cargo.toml [INFO] validating manifest of crates.io crate futuresdr 0.0.38 on toolchain cdb45c87e2cd43495379f7e867e3cc15dcee9f93 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+cdb45c87e2cd43495379f7e867e3cc15dcee9f93" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] crate crates.io crate futuresdr 0.0.38 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" "+cdb45c87e2cd43495379f7e867e3cc15dcee9f93" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Updating crates.io index [INFO] [stderr] Downloading crates ... [INFO] [stderr] Downloaded xilinx-dma v0.0.10 [INFO] [stderr] Downloaded vulkano-macros v0.34.0 [INFO] [stderr] Downloaded atomic_enum v0.3.0 [INFO] [stderr] Downloaded aaronia-rtsa-sys v0.0.4 [INFO] [stderr] Downloaded vk-parse v0.12.0 [INFO] [stderr] Downloaded futuresdr-macros v0.0.7 [INFO] [stderr] Downloaded easy-parallel v3.3.1 [INFO] [stderr] Downloaded android_logger v0.15.0 [INFO] [stderr] Downloaded core_affinity v0.8.3 [INFO] [stderr] Downloaded seify-rtlsdr v0.0.3 [INFO] [stderr] Downloaded nusb v0.1.13 [INFO] [stderr] Downloaded rusb v0.9.4 [INFO] [stderr] Downloaded soapysdr v0.4.1 [INFO] [stderr] Downloaded yaml-rust2 v0.10.1 [INFO] [stderr] Downloaded futuredsp v0.0.7 [INFO] [stderr] Downloaded seify v0.16.0 [INFO] [stderr] Downloaded vulkano-shaders v0.34.0 [INFO] [stderr] Downloaded futuresdr-types v0.0.13 [INFO] [stderr] Downloaded aaronia-rtsa v0.0.6 [INFO] [stderr] Downloaded seify-hackrfone v0.1.1 [INFO] [stderr] Downloaded webpki-roots v0.26.9 [INFO] [stderr] Downloaded vmcircbuffer v0.0.10 [INFO] [stderr] Downloaded libusb1-sys v0.7.0 [INFO] [stderr] Downloaded soapysdr-sys v0.7.5 [INFO] [stderr] Downloaded shaderc v0.8.3 [INFO] [stderr] Downloaded vulkano v0.34.1 [INFO] [stderr] Downloaded shaderc-sys v0.8.3 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-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:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+cdb45c87e2cd43495379f7e867e3cc15dcee9f93" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] db29eb744e2e42e6b6483e055e03d52bd3cc45cccfc553e659239764f99ddf68 [INFO] running `Command { std: "docker" "start" "-a" "db29eb744e2e42e6b6483e055e03d52bd3cc45cccfc553e659239764f99ddf68", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "db29eb744e2e42e6b6483e055e03d52bd3cc45cccfc553e659239764f99ddf68", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "db29eb744e2e42e6b6483e055e03d52bd3cc45cccfc553e659239764f99ddf68", kill_on_drop: false }` [INFO] [stdout] db29eb744e2e42e6b6483e055e03d52bd3cc45cccfc553e659239764f99ddf68 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-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:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+cdb45c87e2cd43495379f7e867e3cc15dcee9f93" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] b054b8650d1df17aca188664ead48f3c8b0c57eaac17a1894c7855902d559e1d [INFO] running `Command { std: "docker" "start" "-a" "b054b8650d1df17aca188664ead48f3c8b0c57eaac17a1894c7855902d559e1d", kill_on_drop: false }` [INFO] [stderr] Compiling libc v0.2.172 [INFO] [stderr] Compiling log v0.4.27 [INFO] [stderr] Compiling tracing-core v0.1.33 [INFO] [stderr] Compiling syn v2.0.101 [INFO] [stderr] Compiling event-listener v5.4.0 [INFO] [stderr] Compiling num-traits v0.2.19 [INFO] [stderr] Compiling crunchy v0.2.3 [INFO] [stderr] Compiling futures-lite v2.6.0 [INFO] [stderr] Compiling event-listener-strategy v0.5.4 [INFO] [stderr] Compiling zerocopy v0.8.25 [INFO] [stderr] Compiling rustix v0.38.44 [INFO] [stderr] Compiling hashbrown v0.15.2 [INFO] [stderr] Compiling tiny-keccak v2.0.2 [INFO] [stderr] Compiling linux-raw-sys v0.4.15 [INFO] [stderr] Compiling async-lock v3.4.0 [INFO] [stderr] Compiling getrandom v0.2.16 [INFO] [stderr] Compiling async-channel v2.3.1 [INFO] [stderr] Compiling mio v1.0.3 [INFO] [stderr] Compiling socket2 v0.5.9 [INFO] [stderr] Compiling const-random-macro v0.1.16 [INFO] [stderr] Compiling getrandom v0.3.2 [INFO] [stderr] Compiling const-random v0.1.18 [INFO] [stderr] Compiling rand_core v0.9.3 [INFO] [stderr] Compiling blocking v1.6.1 [INFO] [stderr] Compiling rustversion v1.0.20 [INFO] [stderr] Compiling smallvec v1.15.0 [INFO] [stderr] Compiling mime_guess v2.0.5 [INFO] [stderr] Compiling dlv-list v0.5.2 [INFO] [stderr] Compiling indexmap v2.9.0 [INFO] [stderr] Compiling num-integer v0.1.46 [INFO] [stderr] Compiling hashbrown v0.14.5 [INFO] [stderr] Compiling winnow v0.7.7 [INFO] [stderr] Compiling sha1 v0.10.6 [INFO] [stderr] Compiling hashlink v0.10.0 [INFO] [stderr] Compiling futuredsp v0.0.7 [INFO] [stderr] Compiling async-executor v1.13.2 [INFO] [stderr] Compiling rustfft v6.3.0 [INFO] [stderr] Compiling encoding_rs v0.8.35 [INFO] [stderr] Compiling anyhow v1.0.98 [INFO] [stderr] Compiling strength_reduce v0.2.4 [INFO] [stderr] Compiling ordered-multimap v0.7.3 [INFO] [stderr] Compiling dirs-sys v0.5.0 [INFO] [stderr] Compiling transpose v0.2.3 [INFO] [stderr] Compiling ppv-lite86 v0.2.21 [INFO] [stderr] Compiling primal-check v0.3.4 [INFO] [stderr] Compiling rust-ini v0.21.1 [INFO] [stderr] Compiling num_cpus v1.16.0 [INFO] [stderr] Compiling rand_chacha v0.9.0 [INFO] [stderr] Compiling futuresdr v0.0.38 (/opt/rustwide/workdir) [INFO] [stderr] Compiling rand v0.9.1 [INFO] [stderr] Compiling tracing-log v0.2.0 [INFO] [stderr] Compiling kv-log-macro v1.0.7 [INFO] [stderr] Compiling thread_local v1.1.8 [INFO] [stderr] Compiling http-range-header v0.4.2 [INFO] [stderr] Compiling core_affinity v0.8.3 [INFO] [stderr] Compiling dirs v6.0.0 [INFO] [stderr] Compiling spin v0.10.0 [INFO] [stderr] Compiling async-fs v2.1.2 [INFO] [stderr] Compiling yaml-rust2 v0.10.1 [INFO] [stderr] Compiling serde_derive v1.0.219 [INFO] [stderr] Compiling tracing-attributes v0.1.28 [INFO] [stderr] Compiling thiserror-impl v2.0.12 [INFO] [stderr] Compiling futures-macro v0.3.31 [INFO] [stderr] Compiling tokio-macros v2.5.0 [INFO] [stderr] Compiling thiserror-impl v1.0.69 [INFO] [stderr] Compiling async-trait v0.1.88 [INFO] [stderr] Compiling futuresdr-macros v0.0.7 [INFO] [stderr] Compiling tokio v1.44.2 [INFO] [stderr] Compiling futures-util v0.3.31 [INFO] [stderr] Compiling thiserror v1.0.69 [INFO] [stderr] Compiling tracing v0.1.41 [INFO] [stderr] Compiling thiserror v2.0.12 [INFO] [stderr] Compiling pest v2.8.0 [INFO] [stderr] Compiling tungstenite v0.26.2 [INFO] [stderr] Compiling axum-core v0.5.2 [INFO] [stderr] Compiling tracing-subscriber v0.3.19 [INFO] [stderr] Compiling pest_meta v2.8.0 [INFO] [stderr] Compiling serde v1.0.219 [INFO] [stderr] Compiling pest_generator v2.8.0 [INFO] [stderr] Compiling futures-executor v0.3.31 [INFO] [stderr] Compiling futures v0.3.31 [INFO] [stderr] Compiling async-tungstenite v0.29.1 [INFO] [stderr] Compiling vmcircbuffer v0.0.10 [INFO] [stderr] Compiling pest_derive v2.8.0 [INFO] [stderr] Compiling hyper v1.6.0 [INFO] [stderr] Compiling tokio-util v0.7.15 [INFO] [stderr] Compiling tower v0.5.2 [INFO] [stderr] Compiling hyper-util v0.1.11 [INFO] [stderr] Compiling bitflags v2.9.0 [INFO] [stderr] Compiling num-complex v0.4.6 [INFO] [stderr] Compiling serde_json v1.0.140 [INFO] [stderr] Compiling toml_datetime v0.6.9 [INFO] [stderr] Compiling serde_spanned v0.6.8 [INFO] [stderr] Compiling serde_urlencoded v0.7.1 [INFO] [stderr] Compiling serde_path_to_error v0.1.17 [INFO] [stderr] Compiling json5 v0.4.1 [INFO] [stderr] Compiling toml_edit v0.22.25 [INFO] [stderr] Compiling ron v0.8.1 [INFO] [stderr] Compiling tower-http v0.6.2 [INFO] [stderr] Compiling axum v0.8.3 [INFO] [stderr] Compiling futuresdr-types v0.0.13 [INFO] [stderr] Compiling toml v0.8.21 [INFO] [stderr] Compiling config v0.15.11 [INFO] [stderr] Compiling polling v3.7.4 [INFO] [stderr] Compiling async-io v2.4.0 [INFO] [stderr] Compiling async-global-executor v2.4.1 [INFO] [stderr] Compiling async-net v2.0.0 [INFO] [stderr] Compiling async-std v1.13.1 [INFO] [stdout] warning: hiding a lifetime that's elided elsewhere is confusing [INFO] [stdout] --> src/runtime/runtime.rs:237:9 [INFO] [stdout] | [INFO] [stdout] 237 | &self, [INFO] [stdout] | ^^^^^ the lifetime is elided here [INFO] [stdout] 238 | fg: Flowgraph, [INFO] [stdout] 239 | ) -> (TaskHandle>, FlowgraphHandle) { [INFO] [stdout] | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ the same lifetime is hidden here [INFO] [stdout] | [INFO] [stdout] = help: the same lifetime is referred to in inconsistent ways, making the signature confusing [INFO] [stdout] = note: `#[warn(mismatched_lifetime_syntaxes)]` on by default [INFO] [stdout] help: use `'_` for type paths [INFO] [stdout] | [INFO] [stdout] 239 | ) -> (TaskHandle<'_, Result>, FlowgraphHandle) { [INFO] [stdout] | +++ [INFO] [stdout] [INFO] [stdout] [INFO] [stderr] Finished `dev` profile [unoptimized + debuginfo] target(s) in 2m 31s [INFO] running `Command { std: "docker" "inspect" "b054b8650d1df17aca188664ead48f3c8b0c57eaac17a1894c7855902d559e1d", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "b054b8650d1df17aca188664ead48f3c8b0c57eaac17a1894c7855902d559e1d", kill_on_drop: false }` [INFO] [stdout] b054b8650d1df17aca188664ead48f3c8b0c57eaac17a1894c7855902d559e1d [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-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:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+cdb45c87e2cd43495379f7e867e3cc15dcee9f93" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 3f4d76a1aa986a01400e503d0507d1174c3ee12c4914303e2cd85ea3ab701b2b [INFO] running `Command { std: "docker" "start" "-a" "3f4d76a1aa986a01400e503d0507d1174c3ee12c4914303e2cd85ea3ab701b2b", kill_on_drop: false }` [INFO] [stderr] Compiling serde v1.0.219 [INFO] [stderr] Compiling half v2.6.0 [INFO] [stderr] Compiling anstyle v1.0.10 [INFO] [stderr] Compiling clap_lex v0.7.4 [INFO] [stderr] Compiling plotters v0.3.7 [INFO] [stderr] Compiling is-terminal v0.4.16 [INFO] [stderr] Compiling float-cmp v0.10.0 [INFO] [stderr] Compiling easy-parallel v3.3.1 [INFO] [stderr] Compiling clap_builder v4.5.37 [INFO] [stderr] Compiling ciborium-ll v0.2.2 [INFO] [stderr] Compiling clap v4.5.37 [INFO] [stderr] Compiling bitflags v2.9.0 [INFO] [stderr] Compiling serde_json v1.0.140 [INFO] [stderr] Compiling toml_datetime v0.6.9 [INFO] [stderr] Compiling serde_spanned v0.6.8 [INFO] [stderr] Compiling num-complex v0.4.6 [INFO] [stderr] Compiling serde_urlencoded v0.7.1 [INFO] [stderr] Compiling serde_path_to_error v0.1.17 [INFO] [stderr] Compiling json5 v0.4.1 [INFO] [stderr] Compiling rustix v0.38.44 [INFO] [stderr] Compiling toml_edit v0.22.25 [INFO] [stderr] Compiling ron v0.8.1 [INFO] [stderr] Compiling tower-http v0.6.2 [INFO] [stderr] Compiling ciborium v0.2.2 [INFO] [stderr] Compiling rustfft v6.3.0 [INFO] [stderr] Compiling axum v0.8.3 [INFO] [stderr] Compiling tinytemplate v1.2.1 [INFO] [stderr] Compiling futuresdr-types v0.0.13 [INFO] [stderr] Compiling futuredsp v0.0.7 [INFO] [stderr] Compiling criterion v0.5.1 [INFO] [stderr] Compiling toml v0.8.21 [INFO] [stderr] Compiling config v0.15.11 [INFO] [stderr] Compiling polling v3.7.4 [INFO] [stderr] Compiling async-io v2.4.0 [INFO] [stderr] Compiling async-global-executor v2.4.1 [INFO] [stderr] Compiling async-net v2.0.0 [INFO] [stderr] Compiling async-std v1.13.1 [INFO] [stderr] Compiling futuresdr v0.0.38 (/opt/rustwide/workdir) [INFO] [stdout] warning: hiding a lifetime that's elided elsewhere is confusing [INFO] [stdout] --> src/runtime/runtime.rs:237:9 [INFO] [stdout] | [INFO] [stdout] 237 | &self, [INFO] [stdout] | ^^^^^ the lifetime is elided here [INFO] [stdout] 238 | fg: Flowgraph, [INFO] [stdout] 239 | ) -> (TaskHandle>, FlowgraphHandle) { [INFO] [stdout] | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ the same lifetime is hidden here [INFO] [stdout] | [INFO] [stdout] = help: the same lifetime is referred to in inconsistent ways, making the signature confusing [INFO] [stdout] = note: `#[warn(mismatched_lifetime_syntaxes)]` on by default [INFO] [stdout] help: use `'_` for type paths [INFO] [stdout] | [INFO] [stdout] 239 | ) -> (TaskHandle<'_, Result>, FlowgraphHandle) { [INFO] [stdout] | +++ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: hiding a lifetime that's elided elsewhere is confusing [INFO] [stdout] --> src/runtime/runtime.rs:237:9 [INFO] [stdout] | [INFO] [stdout] 237 | &self, [INFO] [stdout] | ^^^^^ the lifetime is elided here [INFO] [stdout] 238 | fg: Flowgraph, [INFO] [stdout] 239 | ) -> (TaskHandle>, FlowgraphHandle) { [INFO] [stdout] | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ the same lifetime is hidden here [INFO] [stdout] | [INFO] [stdout] = help: the same lifetime is referred to in inconsistent ways, making the signature confusing [INFO] [stdout] = note: `#[warn(mismatched_lifetime_syntaxes)]` on by default [INFO] [stdout] help: use `'_` for type paths [INFO] [stdout] | [INFO] [stdout] 239 | ) -> (TaskHandle<'_, Result>, FlowgraphHandle) { [INFO] [stdout] | +++ [INFO] [stdout] [INFO] [stdout] [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 2m 33s [INFO] running `Command { std: "docker" "inspect" "3f4d76a1aa986a01400e503d0507d1174c3ee12c4914303e2cd85ea3ab701b2b", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "3f4d76a1aa986a01400e503d0507d1174c3ee12c4914303e2cd85ea3ab701b2b", kill_on_drop: false }` [INFO] [stdout] 3f4d76a1aa986a01400e503d0507d1174c3ee12c4914303e2cd85ea3ab701b2b [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-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:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+cdb45c87e2cd43495379f7e867e3cc15dcee9f93" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 1f11e357a7c6c021847fa15a41d7eaca9d5209e6b08f2fb9a5bf9fd69ec1d67f [INFO] running `Command { std: "docker" "start" "-a" "1f11e357a7c6c021847fa15a41d7eaca9d5209e6b08f2fb9a5bf9fd69ec1d67f", kill_on_drop: false }` [INFO] [stderr] warning: hiding a lifetime that's elided elsewhere is confusing [INFO] [stderr] --> src/runtime/runtime.rs:237:9 [INFO] [stderr] | [INFO] [stderr] 237 | &self, [INFO] [stderr] | ^^^^^ the lifetime is elided here [INFO] [stderr] 238 | fg: Flowgraph, [INFO] [stderr] 239 | ) -> (TaskHandle>, FlowgraphHandle) { [INFO] [stderr] | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ the same lifetime is hidden here [INFO] [stderr] | [INFO] [stderr] = help: the same lifetime is referred to in inconsistent ways, making the signature confusing [INFO] [stderr] = note: `#[warn(mismatched_lifetime_syntaxes)]` on by default [INFO] [stderr] help: use `'_` for type paths [INFO] [stderr] | [INFO] [stderr] 239 | ) -> (TaskHandle<'_, Result>, FlowgraphHandle) { [INFO] [stderr] | +++ [INFO] [stderr] [INFO] [stderr] warning: `futuresdr` (lib) generated 1 warning (run `cargo fix --lib -p futuresdr` to apply 1 suggestion) [INFO] [stderr] warning: `futuresdr` (lib test) generated 1 warning (1 duplicate) [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.53s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/futuresdr-a8725feac8eb65f5) [INFO] [stdout] [INFO] [stdout] running 2 tests [INFO] [stdout] test runtime::stream_io::tests::stream_connect ... ok [INFO] [stderr] Running tests/apply.rs (/opt/rustwide/target/debug/deps/apply-1d6d60a5da2393b1) [INFO] [stdout] test runtime::scheduler::smol::test::smol ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.03s [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] running 2 tests [INFO] [stdout] 2025-09-01T09:20:10.190162Z DEBUG apply_const_fn futuresdr::runtime::logging: logger already initialized [INFO] [stdout] 2025-09-01T09:20:10.206459Z DEBUG ThreadId(18) futuresdr::runtime::ctrl_port: Listening on 127.0.0.1:1337 [INFO] [stdout] 2025-09-01T09:20:10.206625Z  WARN ThreadId(22) futuresdr::runtime::ctrl_port: CtrlPort address 127.0.0.1:1337 already in use [INFO] [stdout] 2025-09-01T09:20:10.206849Z DEBUG smol-7 futuresdr::runtime::runtime: in run_flowgraph [INFO] [stdout] 2025-09-01T09:20:10.207150Z DEBUG smol-2 futuresdr::runtime::runtime: in run_flowgraph [INFO] [stdout] 2025-09-01T09:20:10.207480Z DEBUG smol-2 futuresdr::runtime::runtime: connect stream io [INFO] [stdout] 2025-09-01T09:20:10.207715Z DEBUG smol-7 futuresdr::runtime::runtime: connect stream io [INFO] [stdout] 2025-09-01T09:20:10.209204Z DEBUG smol-2 futuresdr::runtime::runtime: connect message io [INFO] [stdout] 2025-09-01T09:20:10.209232Z DEBUG smol-2 futuresdr::runtime::runtime: init blocks [INFO] [stdout] 2025-09-01T09:20:10.209266Z DEBUG smol-2 futuresdr::runtime::runtime: wait for blocks init [INFO] [stdout] 2025-09-01T09:20:10.209541Z DEBUG smol-7 futuresdr::runtime::runtime: connect message io [INFO] [stdout] 2025-09-01T09:20:10.209559Z DEBUG smol-7 futuresdr::runtime::runtime: init blocks [INFO] [stdout] 2025-09-01T09:20:10.209571Z DEBUG smol-7 futuresdr::runtime::runtime: wait for blocks init [INFO] [stdout] 2025-09-01T09:20:10.209628Z DEBUG smol-7 futuresdr::runtime::runtime: running blocks [INFO] [stdout] 2025-09-01T09:20:10.209821Z DEBUG smol-7 futuresdr::runtime::block: VectorSource-0 terminating [INFO] [stdout] 2025-09-01T09:20:10.209905Z DEBUG smol-7 futuresdr::runtime::block: Apply-1 terminating [INFO] [stdout] 2025-09-01T09:20:10.209952Z DEBUG smol-7 futuresdr::runtime::block: VectorSink-2 terminating [INFO] [stdout] 2025-09-01T09:20:10.210088Z DEBUG apply_const_fn futuresdr::runtime::runtime: Runtime dropped [INFO] [stdout] 2025-09-01T09:20:10.210140Z DEBUG smol-2 futuresdr::runtime::runtime: running blocks [INFO] [stdout] 2025-09-01T09:20:10.210318Z DEBUG smol-4 futuresdr::runtime::block: VectorSource-0 terminating [INFO] [stdout] 2025-09-01T09:20:10.210418Z DEBUG smol-4 futuresdr::runtime::block: Apply-1 terminating [INFO] [stdout] 2025-09-01T09:20:10.210478Z DEBUG smol-4 futuresdr::runtime::block: VectorSink-2 terminating [INFO] [stdout] 2025-09-01T09:20:10.210672Z DEBUG apply_mut_fn futuresdr::runtime::runtime: Runtime dropped [INFO] [stdout] test apply_mut_fn ... ok [INFO] [stdout] test apply_const_fn ... ok [INFO] [stderr] Running tests/applyintoiter.rs (/opt/rustwide/target/debug/deps/applyintoiter-0c3674836bc2e7b1) [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] [stdout] [INFO] [stdout] running 1 test [INFO] [stdout] 2025-09-01T09:20:10.235036Z DEBUG smol-5 futuresdr::runtime::runtime: in run_flowgraph [INFO] [stdout] 2025-09-01T09:20:10.235708Z DEBUG smol-5 futuresdr::runtime::runtime: connect stream io [INFO] [stdout] 2025-09-01T09:20:10.236035Z DEBUG smol-5 futuresdr::runtime::runtime: connect message io [INFO] [stdout] 2025-09-01T09:20:10.236054Z DEBUG smol-5 futuresdr::runtime::runtime: init blocks [INFO] [stdout] 2025-09-01T09:20:10.236075Z DEBUG smol-5 futuresdr::runtime::runtime: wait for blocks init [INFO] [stdout] 2025-09-01T09:20:10.236143Z DEBUG smol-5 futuresdr::runtime::runtime: running blocks [INFO] [stdout] 2025-09-01T09:20:10.236250Z DEBUG smol-5 futuresdr::runtime::block: VectorSource-0 terminating [INFO] [stdout] 2025-09-01T09:20:10.236462Z DEBUG ThreadId(12) futuresdr::runtime::ctrl_port: Listening on 127.0.0.1:1337 [INFO] [stdout] 2025-09-01T09:20:10.236840Z DEBUG smol-4 futuresdr::runtime::block: ApplyIntoIter-1 terminating [INFO] [stdout] 2025-09-01T09:20:10.237013Z DEBUG smol-4 futuresdr::runtime::block: VectorSink-2 terminating [INFO] [stdout] 2025-09-01T09:20:10.237218Z DEBUG repeat_3_buf3 futuresdr::runtime::runtime: Runtime dropped [INFO] [stdout] 2025-09-01T09:20:10.239091Z DEBUG repeat_3_buf3 futuresdr::runtime::logging: logger already initialized [INFO] [stdout] 2025-09-01T09:20:10.240191Z DEBUG smol-6 futuresdr::runtime::runtime: in run_flowgraph [INFO] [stdout] 2025-09-01T09:20:10.240268Z DEBUG smol-6 futuresdr::runtime::runtime: connect stream io [INFO] [stdout] 2025-09-01T09:20:10.240270Z DEBUG ThreadId(21) futuresdr::runtime::ctrl_port: Listening on 127.0.0.1:1337 [INFO] [stdout] 2025-09-01T09:20:10.240519Z DEBUG smol-6 futuresdr::runtime::runtime: connect message io [INFO] [stdout] 2025-09-01T09:20:10.240534Z DEBUG smol-6 futuresdr::runtime::runtime: init blocks [INFO] [stdout] 2025-09-01T09:20:10.240544Z DEBUG smol-6 futuresdr::runtime::runtime: wait for blocks init [INFO] [stdout] 2025-09-01T09:20:10.240588Z DEBUG smol-6 futuresdr::runtime::runtime: running blocks [INFO] [stdout] 2025-09-01T09:20:10.240674Z DEBUG smol-6 futuresdr::runtime::block: VectorSource-0 terminating [INFO] [stdout] 2025-09-01T09:20:10.240743Z DEBUG smol-6 futuresdr::runtime::block: ApplyIntoIter-1 terminating [INFO] [stdout] 2025-09-01T09:20:10.240790Z DEBUG smol-6 futuresdr::runtime::block: VectorSink-2 terminating [INFO] [stdout] 2025-09-01T09:20:10.240928Z DEBUG repeat_3_buf3 futuresdr::runtime::runtime: Runtime dropped [INFO] [stdout] 2025-09-01T09:20:10.242130Z DEBUG repeat_3_buf3 futuresdr::runtime::logging: logger already initialized [INFO] [stdout] 2025-09-01T09:20:10.243258Z DEBUG smol-5 futuresdr::runtime::runtime: in run_flowgraph [INFO] [stdout] 2025-09-01T09:20:10.243346Z DEBUG smol-5 futuresdr::runtime::runtime: connect stream io [INFO] [stdout] 2025-09-01T09:20:10.243348Z DEBUG ThreadId(30) futuresdr::runtime::ctrl_port: Listening on 127.0.0.1:1337 [INFO] [stdout] 2025-09-01T09:20:10.243701Z DEBUG smol-5 futuresdr::runtime::runtime: connect message io [INFO] [stdout] 2025-09-01T09:20:10.243741Z DEBUG smol-5 futuresdr::runtime::runtime: init blocks [INFO] [stdout] 2025-09-01T09:20:10.243756Z DEBUG smol-5 futuresdr::runtime::runtime: wait for blocks init [INFO] [stdout] 2025-09-01T09:20:10.243862Z DEBUG smol-1 futuresdr::runtime::runtime: running blocks [INFO] [stdout] 2025-09-01T09:20:10.243976Z DEBUG smol-1 futuresdr::runtime::block: VectorSource-0 terminating [INFO] [stdout] 2025-09-01T09:20:10.244183Z DEBUG smol-7 futuresdr::runtime::block: ApplyIntoIter-1 terminating [INFO] [stdout] 2025-09-01T09:20:10.244272Z DEBUG smol-7 futuresdr::runtime::block: VectorSink-2 terminating [INFO] [stdout] 2025-09-01T09:20:10.244515Z DEBUG repeat_3_buf3 futuresdr::runtime::runtime: Runtime dropped [INFO] [stdout] 2025-09-01T09:20:10.260893Z DEBUG repeat_3_buf3 futuresdr::runtime::logging: logger already initialized [INFO] [stdout] 2025-09-01T09:20:10.261912Z DEBUG smol-0 futuresdr::runtime::runtime: in run_flowgraph [INFO] [stdout] 2025-09-01T09:20:10.262002Z DEBUG smol-0 futuresdr::runtime::runtime: connect stream io [INFO] [stdout] 2025-09-01T09:20:10.262275Z DEBUG smol-0 futuresdr::runtime::runtime: connect message io [INFO] [stdout] 2025-09-01T09:20:10.262292Z DEBUG smol-0 futuresdr::runtime::runtime: init blocks [INFO] [stdout] 2025-09-01T09:20:10.262303Z DEBUG smol-0 futuresdr::runtime::runtime: wait for blocks init [INFO] [stdout] 2025-09-01T09:20:10.262369Z DEBUG smol-0 futuresdr::runtime::runtime: running blocks [INFO] [stdout] 2025-09-01T09:20:10.262474Z DEBUG smol-0 futuresdr::runtime::block: VectorSource-0 terminating [INFO] [stdout] 2025-09-01T09:20:10.262556Z DEBUG smol-0 futuresdr::runtime::block: ApplyIntoIter-1 terminating [INFO] [stdout] 2025-09-01T09:20:10.262622Z DEBUG smol-0 futuresdr::runtime::block: VectorSink-2 terminating [INFO] [stdout] 2025-09-01T09:20:10.262853Z DEBUG repeat_3_buf3 futuresdr::runtime::runtime: Runtime dropped [INFO] [stdout] test repeat_3_buf3 ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.06s [INFO] [stdout] [INFO] [stderr] Running tests/bad_block.rs (/opt/rustwide/target/debug/deps/bad_block-0b8b2aacbc4c1c19) [INFO] [stdout] [INFO] [stdout] running 8 tests [INFO] [stdout] test run_work_panic ... ignored [INFO] [stdout] test terminate_work_panic ... ignored [INFO] [stdout] 2025-09-01T09:20:10.325690Z DEBUG run_no_err futuresdr::runtime::logging: logger already initialized [INFO] [stdout] 2025-09-01T09:20:10.325716Z DEBUG terminate_work_err futuresdr::runtime::logging: logger already initialized [INFO] [stdout] 2025-09-01T09:20:10.325765Z DEBUG terminate_no_err futuresdr::runtime::logging: logger already initialized [INFO] [stdout] 2025-09-01T09:20:10.326302Z DEBUG terminate_drop_panic futuresdr::runtime::logging: logger already initialized [INFO] [stdout] 2025-09-01T09:20:10.326486Z DEBUG run_work_err futuresdr::runtime::logging: logger already initialized [INFO] [stdout] 2025-09-01T09:20:10.328396Z DEBUG ThreadId(26) futuresdr::runtime::ctrl_port: Listening on 127.0.0.1:1337 [INFO] [stdout] 2025-09-01T09:20:10.328466Z  WARN ThreadId(25) futuresdr::runtime::ctrl_port: CtrlPort address 127.0.0.1:1337 already in use [INFO] [stdout] 2025-09-01T09:20:10.328628Z DEBUG smol-7 futuresdr::runtime::runtime: in run_flowgraph [INFO] [stdout] 2025-09-01T09:20:10.330368Z  WARN ThreadId(40) futuresdr::runtime::ctrl_port: CtrlPort address 127.0.0.1:1337 already in use [INFO] [stdout] 2025-09-01T09:20:10.331035Z DEBUG smol-6 futuresdr::runtime::runtime: in run_flowgraph [INFO] [stdout] 2025-09-01T09:20:10.331147Z DEBUG smol-6 futuresdr::runtime::runtime: connect stream io [INFO] [stdout] 2025-09-01T09:20:10.332251Z  WARN ThreadId(44) futuresdr::runtime::ctrl_port: CtrlPort address 127.0.0.1:1337 already in use [INFO] [stdout] 2025-09-01T09:20:10.333436Z DEBUG smol-6 futuresdr::runtime::runtime: connect message io [INFO] [stdout] 2025-09-01T09:20:10.333457Z DEBUG smol-6 futuresdr::runtime::runtime: init blocks [INFO] [stdout] 2025-09-01T09:20:10.333481Z DEBUG smol-6 futuresdr::runtime::runtime: wait for blocks init [INFO] [stdout] 2025-09-01T09:20:10.333547Z DEBUG smol-6 futuresdr::runtime::runtime: running blocks [INFO] [stdout] 2025-09-01T09:20:10.334866Z DEBUG smol-7 futuresdr::runtime::runtime: connect stream io [INFO] [stdout] 2025-09-01T09:20:10.335119Z DEBUG smol-2 futuresdr::runtime::runtime: in run_flowgraph [INFO] [stdout] 2025-09-01T09:20:10.335230Z DEBUG smol-2 futuresdr::runtime::runtime: connect stream io [INFO] [stdout] 2025-09-01T09:20:10.335474Z DEBUG smol-7 futuresdr::runtime::runtime: connect message io [INFO] [stdout] 2025-09-01T09:20:10.335491Z DEBUG smol-7 futuresdr::runtime::runtime: init blocks [INFO] [stdout] 2025-09-01T09:20:10.335511Z DEBUG smol-7 futuresdr::runtime::runtime: wait for blocks init [INFO] [stdout] 2025-09-01T09:20:10.335543Z DEBUG smol-7 futuresdr::runtime::runtime: running blocks [INFO] [stdout] 2025-09-01T09:20:10.335713Z DEBUG smol-2 futuresdr::runtime::runtime: connect message io [INFO] [stdout] 2025-09-01T09:20:10.335750Z DEBUG smol-2 futuresdr::runtime::runtime: init blocks [INFO] [stdout] 2025-09-01T09:20:10.335772Z DEBUG smol-2 futuresdr::runtime::runtime: wait for blocks init [INFO] [stdout] 2025-09-01T09:20:10.335822Z DEBUG smol-5 futuresdr::runtime::block: NullSource-0 terminating [INFO] [stdout] 2025-09-01T09:20:10.335836Z DEBUG smol-2 futuresdr::runtime::runtime: running blocks [INFO] [stdout] 2025-09-01T09:20:10.335954Z DEBUG smol-6 futuresdr::runtime::block: BadBlock-3 terminating [INFO] [stdout] 2025-09-01T09:20:10.335976Z DEBUG smol-2 bad_block: BadBlock! Some("BadBlock-3") work(): Err [INFO] [stdout] 2025-09-01T09:20:10.336162Z DEBUG smol-6 futuresdr::runtime::block: NullSink-4 terminating [INFO] [stdout] 2025-09-01T09:20:10.336170Z DEBUG smol-7 futuresdr::runtime::runtime: in run_flowgraph [INFO] [stdout] 2025-09-01T09:20:10.336237Z DEBUG smol-6 futuresdr::runtime::block: Throttle-1 terminating [INFO] [stdout] 2025-09-01T09:20:10.336270Z DEBUG smol-7 futuresdr::runtime::runtime: connect stream io [INFO] [stdout] 2025-09-01T09:20:10.336298Z DEBUG smol-6 futuresdr::runtime::block: Head-2 terminating [INFO] [stdout] 2025-09-01T09:20:10.336451Z DEBUG terminate_drop_panic futuresdr::runtime::runtime: Runtime dropped [INFO] [stdout] 2025-09-01T09:20:10.336822Z DEBUG smol-7 futuresdr::runtime::runtime: connect message io [INFO] [stdout] 2025-09-01T09:20:10.336840Z DEBUG smol-7 futuresdr::runtime::runtime: init blocks [INFO] [stdout] 2025-09-01T09:20:10.336857Z DEBUG smol-7 futuresdr::runtime::runtime: wait for blocks init [INFO] [stdout] 2025-09-01T09:20:10.336917Z DEBUG smol-7 futuresdr::runtime::runtime: running blocks [INFO] [stdout] 2025-09-01T09:20:10.337126Z DEBUG smol-6 futuresdr::runtime::runtime: in run_flowgraph [INFO] [stdout] 2025-09-01T09:20:10.337212Z DEBUG smol-6 futuresdr::runtime::runtime: connect stream io [INFO] [stdout] 2025-09-01T09:20:10.337686Z DEBUG smol-6 futuresdr::runtime::runtime: connect message io [INFO] [stdout] 2025-09-01T09:20:10.337703Z DEBUG smol-6 futuresdr::runtime::runtime: init blocks [INFO] [stdout] 2025-09-01T09:20:10.337720Z DEBUG smol-6 futuresdr::runtime::runtime: wait for blocks init [INFO] [stdout] 2025-09-01T09:20:10.337802Z DEBUG smol-6 futuresdr::runtime::runtime: running blocks [INFO] [stdout] 2025-09-01T09:20:10.337927Z DEBUG smol-6 bad_block: BadBlock! Some("BadBlock-3") work(): Err [INFO] [stdout] 2025-09-01T09:20:10.339695Z  WARN ThreadId(53) futuresdr::runtime::ctrl_port: CtrlPort address 127.0.0.1:1337 already in use [INFO] [stdout] 2025-09-01T09:20:10.339934Z DEBUG smol-0 futuresdr::runtime::runtime: in run_flowgraph [INFO] [stdout] 2025-09-01T09:20:10.340026Z DEBUG smol-0 futuresdr::runtime::runtime: connect stream io [INFO] [stdout] 2025-09-01T09:20:10.340488Z DEBUG smol-0 futuresdr::runtime::runtime: connect message io [INFO] [stdout] 2025-09-01T09:20:10.340506Z DEBUG smol-0 futuresdr::runtime::runtime: init blocks [INFO] [stdout] 2025-09-01T09:20:10.340524Z DEBUG smol-0 futuresdr::runtime::runtime: wait for blocks init [INFO] [stdout] 2025-09-01T09:20:10.340590Z DEBUG smol-0 futuresdr::runtime::runtime: running blocks [INFO] [stdout] 2025-09-01T09:20:10.341649Z DEBUG smol-5 futuresdr::runtime::block: NullSource-0 terminating [INFO] [stdout] 2025-09-01T09:20:10.341740Z DEBUG smol-5 futuresdr::runtime::block: Throttle-1 terminating [INFO] [stdout] 2025-09-01T09:20:10.341786Z DEBUG smol-5 futuresdr::runtime::block: Head-2 terminating [INFO] [stdout] 2025-09-01T09:20:10.341820Z DEBUG smol-5 futuresdr::runtime::block: BadBlock-3 terminating [INFO] [stdout] 2025-09-01T09:20:10.341851Z DEBUG smol-5 futuresdr::runtime::block: NullSink-4 terminating [INFO] [stdout] 2025-09-01T09:20:10.341958Z DEBUG terminate_no_err futuresdr::runtime::runtime: Runtime dropped [INFO] [stdout] 2025-09-01T09:20:10.341921Z  WARN ThreadId(62) futuresdr::runtime::ctrl_port: CtrlPort address 127.0.0.1:1337 already in use [INFO] [stdout] 2025-09-01T09:20:10.343557Z DEBUG smol-7 futuresdr::runtime::block: NullSource-0 terminating [INFO] [stdout] 2025-09-01T09:20:10.343856Z DEBUG smol-5 futuresdr::runtime::block: Throttle-1 terminating [INFO] [stdout] 2025-09-01T09:20:10.343872Z DEBUG terminate_drop_panic bad_block: In BadBlock::drop() [INFO] [stdout] 2025-09-01T09:20:10.343893Z DEBUG terminate_drop_panic bad_block: BadBlock! drop(): panic [INFO] [stdout] 2025-09-01T09:20:10.343972Z DEBUG smol-7 futuresdr::runtime::block: Head-2 terminating [INFO] [stdout] 2025-09-01T09:20:10.344071Z DEBUG smol-0 futuresdr::runtime::block: NullSink-4 terminating [INFO] [stdout] 2025-09-01T09:20:10.360283Z DEBUG terminate_no_err bad_block: In BadBlock::drop() [INFO] [stdout] test terminate_no_err ... ok [INFO] [stdout] 2025-09-01T09:20:10.436125Z DEBUG smol-4 futuresdr::runtime::block: Head-2 terminating [INFO] [stdout] 2025-09-01T09:20:10.436266Z DEBUG smol-4 futuresdr::runtime::block: Throttle-1 terminating [INFO] [stdout] 2025-09-01T09:20:10.436379Z DEBUG smol-5 futuresdr::runtime::block: BadBlock-3 terminating [INFO] [stdout] 2025-09-01T09:20:10.436494Z DEBUG smol-5 futuresdr::runtime::block: NullSink-4 terminating [INFO] [stdout] 2025-09-01T09:20:10.436587Z DEBUG smol-4 futuresdr::runtime::block: NullSource-0 terminating [INFO] [stdout] 2025-09-01T09:20:10.438303Z DEBUG smol-7 futuresdr::runtime::block: Head-2 terminating [INFO] [stdout] 2025-09-01T09:20:10.438389Z DEBUG smol-7 futuresdr::runtime::block: Throttle-1 terminating [INFO] [stdout] 2025-09-01T09:20:10.438556Z DEBUG smol-4 futuresdr::runtime::block: NullSource-0 terminating [INFO] [stdout] 2025-09-01T09:20:10.441752Z DEBUG smol-6 futuresdr::runtime::block: Head-2 terminating [INFO] [stdout] 2025-09-01T09:20:10.441852Z DEBUG smol-7 futuresdr::runtime::block: Throttle-1 terminating [INFO] [stdout] 2025-09-01T09:20:10.441923Z DEBUG smol-7 futuresdr::runtime::block: NullSource-0 terminating [INFO] [stdout] 2025-09-01T09:20:10.442029Z DEBUG smol-6 futuresdr::runtime::block: BadBlock-3 terminating [INFO] [stdout] 2025-09-01T09:20:10.442095Z DEBUG smol-6 futuresdr::runtime::block: NullSink-4 terminating [INFO] [stdout] 2025-09-01T09:20:10.442280Z DEBUG run_no_err futuresdr::runtime::runtime: Runtime dropped [INFO] [stdout] 2025-09-01T09:20:10.443352Z DEBUG run_drop_panic futuresdr::runtime::runtime: Runtime dropped [INFO] [stdout] 2025-09-01T09:20:10.456158Z DEBUG run_no_err bad_block: In BadBlock::drop() [INFO] [stdout] test run_no_err ... ok [INFO] [stdout] 2025-09-01T09:20:10.457782Z DEBUG run_drop_panic bad_block: In BadBlock::drop() [INFO] [stdout] 2025-09-01T09:20:10.457913Z DEBUG run_drop_panic bad_block: BadBlock! drop(): panic [INFO] [stdout] 2025-09-01T09:20:10.341491Z ERROR smol-2 futuresdr::runtime::block: BadBlock-3: Error in work(). Terminating. (BadBlock! [INFO] [stdout] [INFO] [stdout] Stack backtrace: [INFO] [stdout] 0: anyhow::error::::msg [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/anyhow-1.0.98/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.98/src/lib.rs:694:13 [INFO] [stdout] 2: as futuresdr::runtime::block::Kernel>::work::{{closure}} [INFO] [stdout] at ./tests/bad_block.rs:70:17 [INFO] [stdout] 3: as core::future::future::Future>::poll [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/future/future.rs:133:9 [INFO] [stdout] 4: futuresdr::runtime::block::TypedBlock::run_impl::{{closure}} [INFO] [stdout] at ./src/runtime/block.rs:424:71 [INFO] [stdout] 5: as futuresdr::runtime::block::BlockT>::run::{{closure}} [INFO] [stdout] at ./src/runtime/block.rs:472:52 [INFO] [stdout] 6: as core::future::future::Future>::poll [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/future/future.rs:133:9 [INFO] [stdout] 7: futuresdr::runtime::block::Block::run::{{closure}} [INFO] [stdout] at ./src/runtime/block.rs:577:63 [INFO] [stdout] 8: as core::future::future::Future>::poll [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/async-executor-1.13.2/src/lib.rs:1179:31 [INFO] [stdout] 9: async_task::raw::RawTask::run::{{closure}} [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/async-task-4.7.1/src/raw.rs:550:21 [INFO] [stdout] 10: core::ops::function::FnOnce::call_once [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 11: as core::ops::function::FnOnce<()>>::call_once [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 12: std::panicking::catch_unwind::do_call [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:590:40 [INFO] [stdout] 13: __rust_try [INFO] [stdout] 14: std::panicking::catch_unwind [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:553:19 [INFO] [stdout] 15: std::panic::catch_unwind [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panic.rs:359:14 [INFO] [stdout] 16: async_task::raw::RawTask::run [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/async-task-4.7.1/src/raw.rs:549:23 [INFO] [stdout] 17: async_task::runnable::Runnable::run [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/async-task-4.7.1/src/runnable.rs:781:18 [INFO] [stdout] 18: async_executor::State::run::{{closure}}::{{closure}} [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/async-executor-1.13.2/src/lib.rs:745:30 [INFO] [stdout] 19: as core::future::future::Future>::poll [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/futures-lite-2.6.0/src/future.rs:457:46 [INFO] [stdout] 20: async_executor::State::run::{{closure}} [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/async-executor-1.13.2/src/lib.rs:752:32 [INFO] [stdout] 21: async_executor::Executor::run::{{closure}} [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/async-executor-1.13.2/src/lib.rs:343:34 [INFO] [stdout] 22: async_io::driver::block_on::{{closure}} [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/async-io-2.4.0/src/driver.rs:199:53 [INFO] [stdout] 23: std::thread::local::LocalKey::try_with [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/thread/local.rs:315:12 [INFO] [stdout] 24: std::thread::local::LocalKey::with [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/thread/local.rs:279:20 [INFO] [stdout] 25: async_io::driver::block_on [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/async-io-2.4.0/src/driver.rs:175:11 [INFO] [stdout] 26: futuresdr::runtime::scheduler::smol::SmolScheduler::new::{{closure}}::{{closure}} [INFO] [stdout] at ./src/runtime/scheduler/smol.rs:88:25 [INFO] [stdout] 27: as core::ops::function::FnOnce<()>>::call_once [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 28: std::panicking::catch_unwind::do_call [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:590:40 [INFO] [stdout] 29: __rust_try [INFO] [stdout] 30: std::panicking::catch_unwind [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:553:19 [INFO] [stdout] 31: std::panic::catch_unwind [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panic.rs:359:14 [INFO] [stdout] 32: futuresdr::runtime::scheduler::smol::SmolScheduler::new::{{closure}} [INFO] [stdout] at ./src/runtime/scheduler/smol.rs:87:34 [INFO] [stdout] 33: std::sys::backtrace::__rust_begin_short_backtrace [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/sys/backtrace.rs:158:18 [INFO] [stdout] 34: std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}} [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/thread/mod.rs:559:17 [INFO] [stdout] 35: as core::ops::function::FnOnce<()>>::call_once [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 36: std::panicking::catch_unwind::do_call [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:590:40 [INFO] [stdout] 37: __rust_try [INFO] [stdout] 38: std::panicking::catch_unwind [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:553:19 [INFO] [stdout] 39: std::panic::catch_unwind [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panic.rs:359:14 [INFO] [stdout] 40: std::thread::Builder::spawn_unchecked_::{{closure}} [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/thread/mod.rs:557:30 [INFO] [stdout] 41: core::ops::function::FnOnce::call_once{{vtable.shim}} [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 42: as core::ops::function::FnOnce>::call_once [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/alloc/src/boxed.rs:1985:9 [INFO] [stdout] 43: std::sys::pal::unix::thread::Thread::new::thread_start [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/sys/pal/unix/thread.rs:118:17 [INFO] [stdout] 44: [INFO] [stdout] 45: clone) [INFO] [stdout] 2025-09-01T09:20:10.730370Z ERROR smol-2 futuresdr::runtime::block: BadBlock-3: Error in Block.run() RuntimeError("BadBlock!") [INFO] [stdout] 2025-09-01T09:20:10.730641Z DEBUG smol-7 futuresdr::runtime::block: NullSink-4 terminating [INFO] [stdout] 2025-09-01T09:20:10.730167Z ERROR smol-6 futuresdr::runtime::block: BadBlock-3: Error in work(). Terminating. (BadBlock! [INFO] [stdout] [INFO] [stdout] Stack backtrace: [INFO] [stdout] 0: anyhow::error::::msg [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/anyhow-1.0.98/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.98/src/lib.rs:694:13 [INFO] [stdout] 2: as futuresdr::runtime::block::Kernel>::work::{{closure}} [INFO] [stdout] at ./tests/bad_block.rs:70:17 [INFO] [stdout] 3: as core::future::future::Future>::poll [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/future/future.rs:133:9 [INFO] [stdout] 4: futuresdr::runtime::block::TypedBlock::run_impl::{{closure}} [INFO] [stdout] at ./src/runtime/block.rs:424:71 [INFO] [stdout] 5: as futuresdr::runtime::block::BlockT>::run::{{closure}} [INFO] [stdout] at ./src/runtime/block.rs:472:52 [INFO] [stdout] 6: as core::future::future::Future>::poll [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/future/future.rs:133:9 [INFO] [stdout] 7: futuresdr::runtime::block::Block::run::{{closure}} [INFO] [stdout] at ./src/runtime/block.rs:577:63 [INFO] [stdout] 8: as core::future::future::Future>::poll [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/async-executor-1.13.2/src/lib.rs:1179:31 [INFO] [stdout] 9: async_task::raw::RawTask::run::{{closure}} [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/async-task-4.7.1/src/raw.rs:550:21 [INFO] [stdout] 10: core::ops::function::FnOnce::call_once [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 11: as core::ops::function::FnOnce<()>>::call_once [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 12: std::panicking::catch_unwind::do_call [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:590:40 [INFO] [stdout] 13: __rust_try [INFO] [stdout] 14: std::panicking::catch_unwind [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:553:19 [INFO] [stdout] 15: std::panic::catch_unwind [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panic.rs:359:14 [INFO] [stdout] 16: async_task::raw::RawTask::run [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/async-task-4.7.1/src/raw.rs:549:23 [INFO] [stdout] 17: async_task::runnable::Runnable::run [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/async-task-4.7.1/src/runnable.rs:781:18 [INFO] [stdout] 18: async_executor::State::run::{{closure}}::{{closure}} [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/async-executor-1.13.2/src/lib.rs:745:30 [INFO] [stdout] 19: as core::future::future::Future>::poll [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/futures-lite-2.6.0/src/future.rs:457:46 [INFO] [stdout] 20: async_executor::State::run::{{closure}} [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/async-executor-1.13.2/src/lib.rs:752:32 [INFO] [stdout] 21: async_executor::Executor::run::{{closure}} [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/async-executor-1.13.2/src/lib.rs:343:34 [INFO] [stdout] 22: async_io::driver::block_on::{{closure}} [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/async-io-2.4.0/src/driver.rs:199:53 [INFO] [stdout] 23: std::thread::local::LocalKey::try_with [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/thread/local.rs:315:12 [INFO] [stdout] 24: std::thread::local::LocalKey::with [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/thread/local.rs:279:20 [INFO] [stdout] 25: async_io::driver::block_on [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/async-io-2.4.0/src/driver.rs:175:11 [INFO] [stdout] 26: futuresdr::runtime::scheduler::smol::SmolScheduler::new::{{closure}}::{{closure}} [INFO] [stdout] at ./src/runtime/scheduler/smol.rs:88:25 [INFO] [stdout] 27: as core::ops::function::FnOnce<()>>::call_once [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 28: std::panicking::catch_unwind::do_call [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:590:40 [INFO] [stdout] 29: __rust_try [INFO] [stdout] 30: std::panicking::catch_unwind [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:553:19 [INFO] [stdout] 31: std::panic::catch_unwind [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panic.rs:359:14 [INFO] [stdout] 32: futuresdr::runtime::scheduler::smol::SmolScheduler::new::{{closure}} [INFO] [stdout] at ./src/runtime/scheduler/smol.rs:87:34 [INFO] [stdout] 33: std::sys::backtrace::__rust_begin_short_backtrace [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/sys/backtrace.rs:158:18 [INFO] [stdout] 34: std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}} [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/thread/mod.rs:559:17 [INFO] [stdout] 35: as core::ops::function::FnOnce<()>>::call_once [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 36: std::panicking::catch_unwind::do_call [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:590:40 [INFO] [stdout] 37: __rust_try [INFO] [stdout] 38: std::panicking::catch_unwind [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:553:19 [INFO] [stdout] 39: std::panic::catch_unwind [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panic.rs:359:14 [INFO] [stdout] 40: std::thread::Builder::spawn_unchecked_::{{closure}} [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/thread/mod.rs:557:30 [INFO] [stdout] 41: core::ops::function::FnOnce::call_once{{vtable.shim}} [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 42: as core::ops::function::FnOnce>::call_once [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/alloc/src/boxed.rs:1985:9 [INFO] [stdout] 43: std::sys::pal::unix::thread::Thread::new::thread_start [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/sys/pal/unix/thread.rs:118:17 [INFO] [stdout] 44: [INFO] [stdout] 45: clone) [INFO] [stdout] 2025-09-01T09:20:10.730970Z DEBUG smol-4 bad_block: In BadBlock::drop() [INFO] [stdout] 2025-09-01T09:20:10.730978Z ERROR smol-6 futuresdr::runtime::block: BadBlock-3: Error in Block.run() RuntimeError("BadBlock!") [INFO] [stdout] 2025-09-01T09:20:10.731066Z DEBUG run_work_err futuresdr::runtime::runtime: Runtime dropped [INFO] [stdout] 2025-09-01T09:20:10.731465Z DEBUG smol-6 bad_block: In BadBlock::drop() [INFO] [stdout] 2025-09-01T09:20:10.732871Z DEBUG terminate_work_err futuresdr::runtime::runtime: Runtime dropped [INFO] [stdout] 2025-09-01T09:20:10.734528Z DEBUG terminate_work_err bad_block: Error: Runtime error (A block raised an error) [INFO] [stdout] test terminate_work_err ... ok [INFO] [stdout] 2025-09-01T09:20:10.734685Z DEBUG run_work_err bad_block: Error: Runtime error (A block raised an error) [INFO] [stdout] test run_work_err ... ok [INFO] [stdout] test run_drop_panic - should panic ... ok [INFO] [stdout] test terminate_drop_panic - should panic ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 6 passed; 0 failed; 2 ignored; 0 measured; 0 filtered out; finished in 0.46s [INFO] [stdout] [INFO] [stderr] Running tests/channel_source.rs (/opt/rustwide/target/debug/deps/channel_source-58ff9520bcca7a95) [INFO] [stdout] [INFO] [stdout] running 3 tests [INFO] [stdout] 2025-09-01T09:20:10.769589Z DEBUG channel_source_min futuresdr::runtime::logging: logger already initialized [INFO] [stdout] 2025-09-01T09:20:10.772618Z DEBUG ThreadId(16) futuresdr::runtime::ctrl_port: Listening on 127.0.0.1:1337 [INFO] [stdout] 2025-09-01T09:20:10.772715Z DEBUG smol-4 futuresdr::runtime::runtime: in run_flowgraph [INFO] [stdout] 2025-09-01T09:20:10.772975Z  WARN ThreadId(23) futuresdr::runtime::ctrl_port: CtrlPort address 127.0.0.1:1337 already in use [INFO] [stdout] 2025-09-01T09:20:10.773052Z DEBUG smol-6 futuresdr::runtime::runtime: in run_flowgraph [INFO] [stdout] 2025-09-01T09:20:10.773227Z DEBUG smol-4 futuresdr::runtime::runtime: connect stream io [INFO] [stdout] 2025-09-01T09:20:10.773239Z DEBUG smol-6 futuresdr::runtime::runtime: connect stream io [INFO] [stdout] 2025-09-01T09:20:10.773679Z DEBUG smol-6 futuresdr::runtime::runtime: connect message io [INFO] [stdout] 2025-09-01T09:20:10.773708Z DEBUG smol-6 futuresdr::runtime::runtime: init blocks [INFO] [stdout] 2025-09-01T09:20:10.773739Z DEBUG smol-6 futuresdr::runtime::runtime: wait for blocks init [INFO] [stdout] 2025-09-01T09:20:10.773790Z DEBUG smol-6 futuresdr::runtime::runtime: running blocks [INFO] [stdout] 2025-09-01T09:20:10.774025Z DEBUG smol-4 futuresdr::runtime::runtime: connect message io [INFO] [stdout] 2025-09-01T09:20:10.774050Z DEBUG smol-4 futuresdr::runtime::runtime: init blocks [INFO] [stdout] 2025-09-01T09:20:10.774071Z DEBUG smol-4 futuresdr::runtime::runtime: wait for blocks init [INFO] [stdout] 2025-09-01T09:20:10.774107Z DEBUG smol-4 futuresdr::runtime::runtime: running blocks [INFO] [stdout] 2025-09-01T09:20:10.774211Z DEBUG smol-7 futuresdr::blocks::channel_source: received data chunk on channel [INFO] [stdout] 2025-09-01T09:20:10.775262Z DEBUG smol-7 futuresdr::blocks::channel_source: received data chunk on channel [INFO] [stdout] 2025-09-01T09:20:10.776124Z DEBUG smol-6 futuresdr::blocks::channel_source: sender-end of channel was closed [INFO] [stdout] 2025-09-01T09:20:10.776156Z DEBUG smol-6 futuresdr::runtime::block: ChannelSource-0 terminating [INFO] [stdout] 2025-09-01T09:20:10.776221Z DEBUG smol-6 futuresdr::runtime::block: VectorSink-1 terminating [INFO] [stdout] 2025-09-01T09:20:10.776382Z DEBUG smol-6 futuresdr::blocks::channel_source: received data chunk on channel [INFO] [stdout] 2025-09-01T09:20:10.776618Z DEBUG smol-4 futuresdr::blocks::channel_source: sender-end of channel was closed [INFO] [stdout] 2025-09-01T09:20:10.784846Z DEBUG smol-4 futuresdr::runtime::block: ChannelSource-0 terminating [INFO] [stdout] 2025-09-01T09:20:10.784987Z DEBUG smol-4 futuresdr::runtime::block: VectorSink-1 terminating [INFO] [stdout] 2025-09-01T09:20:10.785108Z DEBUG channel_source_min futuresdr::runtime::runtime: Runtime dropped [INFO] [stdout] 2025-09-01T09:20:10.785248Z DEBUG channel_source_big futuresdr::runtime::runtime: Runtime dropped [INFO] [stdout] test channel_source_min ... ok [INFO] [stdout] test channel_source_big ... ok [INFO] [stdout] 2025-09-01T09:20:10.789192Z DEBUG channel_source_small futuresdr::runtime::logging: logger already initialized [INFO] [stdout] 2025-09-01T09:20:10.797412Z DEBUG ThreadId(32) futuresdr::runtime::ctrl_port: Listening on 127.0.0.1:1337 [INFO] [stdout] 2025-09-01T09:20:10.797594Z DEBUG smol-7 futuresdr::runtime::runtime: in run_flowgraph [INFO] [stdout] 2025-09-01T09:20:10.797695Z DEBUG smol-7 futuresdr::runtime::runtime: connect stream io [INFO] [stdout] 2025-09-01T09:20:10.798064Z DEBUG smol-7 futuresdr::runtime::runtime: connect message io [INFO] [stdout] 2025-09-01T09:20:10.798083Z DEBUG smol-7 futuresdr::runtime::runtime: init blocks [INFO] [stdout] 2025-09-01T09:20:10.798094Z DEBUG smol-7 futuresdr::runtime::runtime: wait for blocks init [INFO] [stdout] 2025-09-01T09:20:10.798138Z DEBUG smol-7 futuresdr::runtime::runtime: running blocks [INFO] [stdout] 2025-09-01T09:20:10.798275Z DEBUG smol-4 futuresdr::blocks::channel_source: received data chunk on channel [INFO] [stdout] 2025-09-01T09:20:10.798362Z DEBUG smol-4 futuresdr::blocks::channel_source: received data chunk on channel [INFO] [stdout] 2025-09-01T09:20:10.798386Z DEBUG smol-4 futuresdr::blocks::channel_source: received data chunk on channel [INFO] [stdout] 2025-09-01T09:20:10.798401Z DEBUG smol-4 futuresdr::blocks::channel_source: received data chunk on channel [INFO] [stdout] 2025-09-01T09:20:10.798425Z DEBUG smol-4 futuresdr::blocks::channel_source: sender-end of channel was closed [INFO] [stdout] 2025-09-01T09:20:10.798440Z DEBUG smol-4 futuresdr::runtime::block: ChannelSource-0 terminating [INFO] [stdout] 2025-09-01T09:20:10.798501Z DEBUG smol-4 futuresdr::runtime::block: VectorSink-1 terminating [INFO] [stdout] 2025-09-01T09:20:10.798574Z DEBUG channel_source_small futuresdr::runtime::runtime: Runtime dropped [INFO] [stderr] Running tests/combine.rs (/opt/rustwide/target/debug/deps/combine-ca683b2b73574231) [INFO] [stdout] test channel_source_small ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.03s [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] running 3 tests [INFO] [stdout] 2025-09-01T09:20:10.848453Z DEBUG combine_const_fn_diff_len_first futuresdr::runtime::logging: logger already initialized [INFO] [stdout] 2025-09-01T09:20:10.848691Z DEBUG combine_const_fn futuresdr::runtime::logging: logger already initialized [INFO] [stdout] 2025-09-01T09:20:10.851062Z DEBUG ThreadId(19) futuresdr::runtime::ctrl_port: Listening on 127.0.0.1:1337 [INFO] [stdout] 2025-09-01T09:20:10.851368Z  WARN ThreadId(23) futuresdr::runtime::ctrl_port: CtrlPort address 127.0.0.1:1337 already in use [INFO] [stdout] 2025-09-01T09:20:10.851613Z DEBUG smol-7 futuresdr::runtime::runtime: in run_flowgraph [INFO] [stdout] 2025-09-01T09:20:10.851848Z DEBUG smol-6 futuresdr::runtime::runtime: in run_flowgraph [INFO] [stdout] 2025-09-01T09:20:10.852997Z DEBUG smol-6 futuresdr::runtime::runtime: connect stream io [INFO] [stdout] 2025-09-01T09:20:10.853275Z DEBUG smol-5 futuresdr::runtime::runtime: in run_flowgraph [INFO] [stdout] 2025-09-01T09:20:10.853354Z DEBUG smol-5 futuresdr::runtime::runtime: connect stream io [INFO] [stdout] 2025-09-01T09:20:10.853577Z DEBUG smol-6 futuresdr::runtime::runtime: connect message io [INFO] [stdout] 2025-09-01T09:20:10.853598Z DEBUG smol-6 futuresdr::runtime::runtime: init blocks [INFO] [stdout] 2025-09-01T09:20:10.853629Z DEBUG smol-6 futuresdr::runtime::runtime: wait for blocks init [INFO] [stdout] 2025-09-01T09:20:10.853044Z DEBUG smol-7 futuresdr::runtime::runtime: connect stream io [INFO] [stdout] 2025-09-01T09:20:10.853766Z DEBUG smol-5 futuresdr::runtime::runtime: connect message io [INFO] [stdout] 2025-09-01T09:20:10.853771Z DEBUG smol-3 futuresdr::runtime::runtime: running blocks [INFO] [stdout] 2025-09-01T09:20:10.853966Z  WARN ThreadId(32) futuresdr::runtime::ctrl_port: CtrlPort address 127.0.0.1:1337 already in use [INFO] [stdout] 2025-09-01T09:20:10.853785Z DEBUG smol-5 futuresdr::runtime::runtime: init blocks [INFO] [stdout] 2025-09-01T09:20:10.854125Z DEBUG smol-3 futuresdr::runtime::block: VectorSource-0 terminating [INFO] [stdout] 2025-09-01T09:20:10.854202Z DEBUG smol-3 futuresdr::runtime::block: VectorSource-1 terminating [INFO] [stdout] 2025-09-01T09:20:10.854258Z DEBUG smol-3 futuresdr::runtime::block: Combine-2 terminating [INFO] [stdout] 2025-09-01T09:20:10.854322Z DEBUG smol-3 futuresdr::runtime::block: VectorSink-3 terminating [INFO] [stdout] 2025-09-01T09:20:10.854330Z DEBUG smol-5 futuresdr::runtime::runtime: wait for blocks init [INFO] [stdout] 2025-09-01T09:20:10.854350Z DEBUG smol-5 futuresdr::runtime::runtime: running blocks [INFO] [stdout] 2025-09-01T09:20:10.854418Z DEBUG combine_const_fn_diff_len_second futuresdr::runtime::runtime: Runtime dropped [INFO] [stdout] 2025-09-01T09:20:10.854561Z DEBUG smol-0 futuresdr::runtime::block: VectorSource-0 terminating [INFO] [stdout] 2025-09-01T09:20:10.854633Z DEBUG smol-7 futuresdr::runtime::runtime: connect message io [INFO] [stdout] 2025-09-01T09:20:10.854651Z DEBUG smol-7 futuresdr::runtime::runtime: init blocks [INFO] [stdout] 2025-09-01T09:20:10.854665Z DEBUG smol-7 futuresdr::runtime::runtime: wait for blocks init [INFO] [stdout] 2025-09-01T09:20:10.854714Z DEBUG smol-7 futuresdr::runtime::runtime: running blocks [INFO] [stdout] 2025-09-01T09:20:10.854788Z DEBUG smol-6 futuresdr::runtime::block: VectorSource-1 terminating [INFO] [stdout] 2025-09-01T09:20:10.854861Z DEBUG smol-6 futuresdr::runtime::block: Combine-2 terminating [INFO] [stdout] 2025-09-01T09:20:10.854917Z DEBUG smol-6 futuresdr::runtime::block: VectorSink-3 terminating [INFO] [stdout] 2025-09-01T09:20:10.854977Z DEBUG smol-5 futuresdr::runtime::runtime: runtime wanted to start block that already terminated [INFO] [stdout] 2025-09-01T09:20:10.854992Z DEBUG smol-5 futuresdr::runtime::runtime: runtime wanted to start block that already terminated [INFO] [stdout] 2025-09-01T09:20:10.855078Z DEBUG combine_const_fn futuresdr::runtime::runtime: Runtime dropped [INFO] [stdout] 2025-09-01T09:20:10.855139Z DEBUG smol-6 futuresdr::runtime::block: VectorSource-0 terminating [INFO] [stdout] 2025-09-01T09:20:10.855192Z DEBUG smol-5 futuresdr::runtime::block: VectorSource-1 terminating [INFO] [stdout] 2025-09-01T09:20:10.855262Z DEBUG smol-6 futuresdr::runtime::block: Combine-2 terminating [INFO] [stdout] test combine_const_fn ... ok [INFO] [stdout] test combine_const_fn_diff_len_second ... ok [INFO] [stdout] 2025-09-01T09:20:10.857791Z DEBUG smol-3 futuresdr::runtime::block: VectorSink-3 terminating [INFO] [stderr] Running tests/connect_error.rs (/opt/rustwide/target/debug/deps/connect_error-69730e30e2f1eda9) [INFO] [stdout] 2025-09-01T09:20:10.858836Z DEBUG combine_const_fn_diff_len_first futuresdr::runtime::runtime: Runtime dropped [INFO] [stdout] test combine_const_fn_diff_len_first ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.04s [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] running 5 tests [INFO] [stdout] test stream_invalid_in_port ... ok [INFO] [stdout] test stream_invalid_out_port ... ok [INFO] [stdout] test message_invalid_out_port ... ok [INFO] [stdout] test message_invalid_in_port ... ok [INFO] [stdout] test connect_type_error ... 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] [stderr] Running tests/fail.rs (/opt/rustwide/target/debug/deps/fail-77f361cbd52f472f) [INFO] [stdout] [INFO] [stdout] running 3 tests [INFO] [stdout] 2025-09-01T09:20:10.957247Z DEBUG fail_init futuresdr::runtime::logging: logger already initialized [INFO] [stdout] 2025-09-01T09:20:10.957191Z DEBUG fail_work futuresdr::runtime::logging: logger already initialized [INFO] [stdout] 2025-09-01T09:20:10.959591Z DEBUG ThreadId(23) futuresdr::runtime::ctrl_port: Listening on 127.0.0.1:1337 [INFO] [stdout] 2025-09-01T09:20:10.959758Z  WARN ThreadId(18) futuresdr::runtime::ctrl_port: CtrlPort address 127.0.0.1:1337 already in use [INFO] [stdout] 2025-09-01T09:20:10.959938Z DEBUG smol-5 futuresdr::runtime::runtime: in run_flowgraph [INFO] [stdout] 2025-09-01T09:20:10.960143Z DEBUG smol-6 futuresdr::runtime::runtime: in run_flowgraph [INFO] [stdout] 2025-09-01T09:20:10.961445Z DEBUG smol-4 futuresdr::runtime::runtime: in run_flowgraph [INFO] [stdout] 2025-09-01T09:20:10.961523Z DEBUG smol-4 futuresdr::runtime::runtime: connect stream io [INFO] [stdout] 2025-09-01T09:20:10.961547Z DEBUG smol-4 futuresdr::runtime::runtime: connect message io [INFO] [stdout] 2025-09-01T09:20:10.961559Z DEBUG smol-4 futuresdr::runtime::runtime: init blocks [INFO] [stdout] 2025-09-01T09:20:10.961594Z DEBUG smol-4 futuresdr::runtime::runtime: wait for blocks init [INFO] [stdout] 2025-09-01T09:20:10.961659Z DEBUG smol-6 futuresdr::runtime::runtime: connect stream io [INFO] [stdout] 2025-09-01T09:20:10.961666Z  WARN ThreadId(32) futuresdr::runtime::ctrl_port: CtrlPort address 127.0.0.1:1337 already in use [INFO] [stdout] 2025-09-01T09:20:10.961685Z DEBUG smol-6 futuresdr::runtime::runtime: connect message io [INFO] [stdout] 2025-09-01T09:20:10.961695Z DEBUG smol-6 futuresdr::runtime::runtime: init blocks [INFO] [stdout] 2025-09-01T09:20:10.961710Z DEBUG smol-6 futuresdr::runtime::runtime: wait for blocks init [INFO] [stdout] 2025-09-01T09:20:10.962122Z DEBUG smol-6 futuresdr::runtime::runtime: running blocks [INFO] [stdout] 2025-09-01T09:20:10.962253Z DEBUG smol-5 futuresdr::runtime::block: FailDeinit-1 terminating [INFO] [stdout] 2025-09-01T09:20:10.963793Z DEBUG smol-5 futuresdr::runtime::runtime: connect stream io [INFO] [stdout] 2025-09-01T09:20:10.963823Z DEBUG smol-5 futuresdr::runtime::runtime: connect message io [INFO] [stdout] 2025-09-01T09:20:10.963833Z DEBUG smol-5 futuresdr::runtime::runtime: init blocks [INFO] [stdout] 2025-09-01T09:20:10.963850Z DEBUG smol-5 futuresdr::runtime::runtime: wait for blocks init [INFO] [stdout] 2025-09-01T09:20:10.963881Z DEBUG smol-5 futuresdr::runtime::runtime: running blocks [INFO] [stdout] 2025-09-01T09:20:10.965330Z ERROR smol-7 futuresdr::runtime::block: FailInit-1: Error during initialization. Terminating. [INFO] [stdout] 2025-09-01T09:20:10.965383Z ERROR smol-7 futuresdr::runtime::block: FailInit-1: Error in Block.run() RuntimeError("FailInit, failed init()") [INFO] [stdout] 2025-09-01T09:20:10.965474Z DEBUG smol-0 futuresdr::runtime::runtime: running blocks [INFO] [stdout] 2025-09-01T09:20:10.965537Z DEBUG smol-0 futuresdr::runtime::block: MessageSink-0 terminating [INFO] [stdout] 2025-09-01T09:20:10.965573Z DEBUG smol-0 futuresdr::blocks::message_sink: n_received: 0 [INFO] [stdout] 2025-09-01T09:20:10.967794Z DEBUG fail_init futuresdr::runtime::runtime: Runtime dropped [INFO] [stdout] test fail_init ... ok [INFO] [stdout] 2025-09-01T09:20:10.965485Z ERROR smol-5 futuresdr::runtime::block: FailDeinit-1: Error in deinit (). Terminating. (FailDeinit, failed deinit() [INFO] [stdout] [INFO] [stdout] Stack backtrace: [INFO] [stdout] 0: anyhow::error::::msg [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/anyhow-1.0.98/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.98/src/lib.rs:694:13 [INFO] [stdout] 2: ::deinit::{{closure}} [INFO] [stdout] at ./tests/fail.rs:103:9 [INFO] [stdout] 3: as core::future::future::Future>::poll [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/future/future.rs:133:9 [INFO] [stdout] 4: futuresdr::runtime::block::TypedBlock::run_impl::{{closure}} [INFO] [stdout] at ./src/runtime/block.rs:384:53 [INFO] [stdout] 5: as futuresdr::runtime::block::BlockT>::run::{{closure}} [INFO] [stdout] at ./src/runtime/block.rs:472:52 [INFO] [stdout] 6: as core::future::future::Future>::poll [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/future/future.rs:133:9 [INFO] [stdout] 7: futuresdr::runtime::block::Block::run::{{closure}} [INFO] [stdout] at ./src/runtime/block.rs:577:63 [INFO] [stdout] 8: as core::future::future::Future>::poll [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/async-executor-1.13.2/src/lib.rs:1179:31 [INFO] [stdout] 9: async_task::raw::RawTask::run::{{closure}} [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/async-task-4.7.1/src/raw.rs:550:21 [INFO] [stdout] 10: core::ops::function::FnOnce::call_once [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 11: as core::ops::function::FnOnce<()>>::call_once [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 12: std::panicking::catch_unwind::do_call [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:590:40 [INFO] [stdout] 13: __rust_try [INFO] [stdout] 14: std::panicking::catch_unwind [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:553:19 [INFO] [stdout] 15: std::panic::catch_unwind [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panic.rs:359:14 [INFO] [stdout] 16: async_task::raw::RawTask::run [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/async-task-4.7.1/src/raw.rs:549:23 [INFO] [stdout] 17: async_task::runnable::Runnable::run [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/async-task-4.7.1/src/runnable.rs:781:18 [INFO] [stdout] 18: async_executor::State::run::{{closure}}::{{closure}} [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/async-executor-1.13.2/src/lib.rs:745:30 [INFO] [stdout] 19: as core::future::future::Future>::poll [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/futures-lite-2.6.0/src/future.rs:457:46 [INFO] [stdout] 20: async_executor::State::run::{{closure}} [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/async-executor-1.13.2/src/lib.rs:752:32 [INFO] [stdout] 21: async_executor::Executor::run::{{closure}} [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/async-executor-1.13.2/src/lib.rs:343:34 [INFO] [stdout] 22: async_io::driver::block_on::{{closure}} [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/async-io-2.4.0/src/driver.rs:199:53 [INFO] [stdout] 23: std::thread::local::LocalKey::try_with [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/thread/local.rs:315:12 [INFO] [stdout] 24: std::thread::local::LocalKey::with [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/thread/local.rs:279:20 [INFO] [stdout] 25: async_io::driver::block_on [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/async-io-2.4.0/src/driver.rs:175:11 [INFO] [stdout] 26: futuresdr::runtime::scheduler::smol::SmolScheduler::new::{{closure}}::{{closure}} [INFO] [stdout] at ./src/runtime/scheduler/smol.rs:88:25 [INFO] [stdout] 27: as core::ops::function::FnOnce<()>>::call_once [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 28: std::panicking::catch_unwind::do_call [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:590:40 [INFO] [stdout] 29: __rust_try [INFO] [stdout] 30: std::panicking::catch_unwind [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:553:19 [INFO] [stdout] 31: std::panic::catch_unwind [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panic.rs:359:14 [INFO] [stdout] 32: futuresdr::runtime::scheduler::smol::SmolScheduler::new::{{closure}} [INFO] [stdout] at ./src/runtime/scheduler/smol.rs:87:34 [INFO] [stdout] 33: std::sys::backtrace::__rust_begin_short_backtrace [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/sys/backtrace.rs:158:18 [INFO] [stdout] 34: std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}} [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/thread/mod.rs:559:17 [INFO] [stdout] 35: as core::ops::function::FnOnce<()>>::call_once [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 36: std::panicking::catch_unwind::do_call [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:590:40 [INFO] [stdout] 37: __rust_try [INFO] [stdout] 38: std::panicking::catch_unwind [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:553:19 [INFO] [stdout] 39: std::panic::catch_unwind [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panic.rs:359:14 [INFO] [stdout] 40: std::thread::Builder::spawn_unchecked_::{{closure}} [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/thread/mod.rs:557:30 [INFO] [stdout] 41: core::ops::function::FnOnce::call_once{{vtable.shim}} [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 42: as core::ops::function::FnOnce>::call_once [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/alloc/src/boxed.rs:1985:9 [INFO] [stdout] 43: std::sys::pal::unix::thread::Thread::new::thread_start [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/sys/pal/unix/thread.rs:118:17 [INFO] [stdout] 44: [INFO] [stdout] 45: clone) [INFO] [stdout] 2025-09-01T09:20:11.408026Z ERROR smol-5 futuresdr::runtime::block: FailDeinit-1: Error in Block.run() RuntimeError("FailDeinit, failed deinit()") [INFO] [stdout] 2025-09-01T09:20:11.408242Z DEBUG smol-7 futuresdr::runtime::block: MessageSink-0 terminating [INFO] [stdout] 2025-09-01T09:20:11.408309Z DEBUG smol-7 futuresdr::blocks::message_sink: n_received: 0 [INFO] [stdout] 2025-09-01T09:20:11.408421Z DEBUG fail_deinit futuresdr::runtime::runtime: Runtime dropped [INFO] [stdout] 2025-09-01T09:20:11.407170Z ERROR smol-7 futuresdr::runtime::block: FailWork-1: Error in work(). Terminating. (FailWork, failed work() [INFO] [stdout] [INFO] [stdout] Stack backtrace: [INFO] [stdout] 0: anyhow::error::::msg [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/anyhow-1.0.98/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.98/src/lib.rs:694:13 [INFO] [stdout] 2: ::work::{{closure}} [INFO] [stdout] at ./tests/fail.rs:66:9 [INFO] [stdout] 3: as core::future::future::Future>::poll [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/future/future.rs:133:9 [INFO] [stdout] 4: futuresdr::runtime::block::TypedBlock::run_impl::{{closure}} [INFO] [stdout] at ./src/runtime/block.rs:424:71 [INFO] [stdout] 5: as futuresdr::runtime::block::BlockT>::run::{{closure}} [INFO] [stdout] at ./src/runtime/block.rs:472:52 [INFO] [stdout] 6: as core::future::future::Future>::poll [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/future/future.rs:133:9 [INFO] [stdout] 7: futuresdr::runtime::block::Block::run::{{closure}} [INFO] [stdout] at ./src/runtime/block.rs:577:63 [INFO] [stdout] 8: as core::future::future::Future>::poll [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/async-executor-1.13.2/src/lib.rs:1179:31 [INFO] [stdout] 9: async_task::raw::RawTask::run::{{closure}} [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/async-task-4.7.1/src/raw.rs:550:21 [INFO] [stdout] 10: core::ops::function::FnOnce::call_once [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 11: as core::ops::function::FnOnce<()>>::call_once [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 12: std::panicking::catch_unwind::do_call [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:590:40 [INFO] [stdout] 13: __rust_try [INFO] [stdout] 14: std::panicking::catch_unwind [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:553:19 [INFO] [stdout] 15: std::panic::catch_unwind [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panic.rs:359:14 [INFO] [stdout] 16: async_task::raw::RawTask::run [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/async-task-4.7.1/src/raw.rs:549:23 [INFO] [stdout] 17: async_task::runnable::Runnable::run [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/async-task-4.7.1/src/runnable.rs:781:18 [INFO] [stdout] 18: async_executor::State::run::{{closure}}::{{closure}} [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/async-executor-1.13.2/src/lib.rs:745:30 [INFO] [stdout] 19: as core::future::future::Future>::poll [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/futures-lite-2.6.0/src/future.rs:457:46 [INFO] [stdout] 20: async_executor::State::run::{{closure}} [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/async-executor-1.13.2/src/lib.rs:752:32 [INFO] [stdout] 21: async_executor::Executor::run::{{closure}} [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/async-executor-1.13.2/src/lib.rs:343:34 [INFO] [stdout] 22: async_io::driver::block_on::{{closure}} [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/async-io-2.4.0/src/driver.rs:199:53 [INFO] [stdout] 23: std::thread::local::LocalKey::try_with [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/thread/local.rs:315:12 [INFO] [stdout] 24: std::thread::local::LocalKey::with [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/thread/local.rs:279:20 [INFO] [stdout] 25: async_io::driver::block_on [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/async-io-2.4.0/src/driver.rs:175:11 [INFO] [stdout] 26: futuresdr::runtime::scheduler::smol::SmolScheduler::new::{{closure}}::{{closure}} [INFO] [stdout] at ./src/runtime/scheduler/smol.rs:88:25 [INFO] [stdout] 27: as core::ops::function::FnOnce<()>>::call_once [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 28: std::panicking::catch_unwind::do_call [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:590:40 [INFO] [stdout] 29: __rust_try [INFO] [stdout] 30: std::panicking::catch_unwind [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:553:19 [INFO] [stdout] 31: std::panic::catch_unwind [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panic.rs:359:14 [INFO] [stdout] 32: futuresdr::runtime::scheduler::smol::SmolScheduler::new::{{closure}} [INFO] [stdout] at ./src/runtime/scheduler/smol.rs:87:34 [INFO] [stdout] 33: std::sys::backtrace::__rust_begin_short_backtrace [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/sys/backtrace.rs:158:18 [INFO] [stdout] 34: std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}} [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/thread/mod.rs:559:17 [INFO] [stdout] 35: as core::ops::function::FnOnce<()>>::call_once [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 36: std::panicking::catch_unwind::do_call [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:590:40 [INFO] [stdout] 37: __rust_try [INFO] [stdout] 38: std::panicking::catch_unwind [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:553:19 [INFO] [stdout] 39: std::panic::catch_unwind [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panic.rs:359:14 [INFO] [stdout] 40: std::thread::Builder::spawn_unchecked_::{{closure}} [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/thread/mod.rs:557:30 [INFO] [stdout] 41: core::ops::function::FnOnce::call_once{{vtable.shim}} [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 42: as core::ops::function::FnOnce>::call_once [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/alloc/src/boxed.rs:1985:9 [INFO] [stdout] 43: std::sys::pal::unix::thread::Thread::new::thread_start [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/sys/pal/unix/thread.rs:118:17 [INFO] [stdout] 44: [INFO] [stdout] 45: clone) [INFO] [stdout] 2025-09-01T09:20:11.408677Z ERROR smol-7 futuresdr::runtime::block: FailWork-1: Error in Block.run() RuntimeError("FailWork, failed work()") [INFO] [stdout] 2025-09-01T09:20:11.408800Z DEBUG smol-7 futuresdr::runtime::block: MessageSink-0 terminating [INFO] [stdout] 2025-09-01T09:20:11.408857Z DEBUG smol-7 futuresdr::blocks::message_sink: n_received: 0 [INFO] [stdout] 2025-09-01T09:20:11.410761Z DEBUG fail_work futuresdr::runtime::runtime: Runtime dropped [INFO] [stdout] test fail_deinit ... ok [INFO] [stdout] test fail_work ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.48s [INFO] [stdout] [INFO] [stderr] Running tests/filter.rs (/opt/rustwide/target/debug/deps/filter-907413eb2b909ffb) [INFO] [stdout] [INFO] [stdout] running 2 tests [INFO] [stdout] 2025-09-01T09:20:11.425714Z DEBUG apply_const_fn futuresdr::runtime::logging: logger already initialized [INFO] [stdout] 2025-09-01T09:20:11.428218Z DEBUG ThreadId(18) futuresdr::runtime::ctrl_port: Listening on 127.0.0.1:1337 [INFO] [stdout] 2025-09-01T09:20:11.428611Z DEBUG smol-5 futuresdr::runtime::runtime: in run_flowgraph [INFO] [stdout] 2025-09-01T09:20:11.428719Z DEBUG smol-5 futuresdr::runtime::runtime: connect stream io [INFO] [stdout] 2025-09-01T09:20:11.429050Z DEBUG smol-5 futuresdr::runtime::runtime: connect message io [INFO] [stdout] 2025-09-01T09:20:11.429079Z DEBUG smol-5 futuresdr::runtime::runtime: init blocks [INFO] [stdout] 2025-09-01T09:20:11.429098Z DEBUG smol-5 futuresdr::runtime::runtime: wait for blocks init [INFO] [stdout] 2025-09-01T09:20:11.429171Z DEBUG smol-5 futuresdr::runtime::runtime: running blocks [INFO] [stdout] 2025-09-01T09:20:11.429331Z DEBUG smol-5 futuresdr::runtime::block: VectorSource-0 terminating [INFO] [stdout] 2025-09-01T09:20:11.429416Z DEBUG smol-5 futuresdr::runtime::block: Filter-1 terminating [INFO] [stdout] 2025-09-01T09:20:11.429478Z DEBUG smol-5 futuresdr::runtime::block: VectorSink-2 terminating [INFO] [stdout] 2025-09-01T09:20:11.429776Z DEBUG apply_const_fn futuresdr::runtime::runtime: Runtime dropped [INFO] [stdout] 2025-09-01T09:20:11.429780Z DEBUG smol-5 futuresdr::runtime::runtime: in run_flowgraph [INFO] [stdout] 2025-09-01T09:20:11.429848Z DEBUG smol-5 futuresdr::runtime::runtime: connect stream io [INFO] [stdout] 2025-09-01T09:20:11.430093Z DEBUG smol-5 futuresdr::runtime::runtime: connect message io [INFO] [stdout] 2025-09-01T09:20:11.430109Z DEBUG smol-5 futuresdr::runtime::runtime: init blocks [INFO] [stdout] 2025-09-01T09:20:11.430120Z DEBUG smol-5 futuresdr::runtime::runtime: wait for blocks init [INFO] [stdout] 2025-09-01T09:20:11.430166Z DEBUG smol-5 futuresdr::runtime::runtime: running blocks [INFO] [stdout] 2025-09-01T09:20:11.430289Z DEBUG smol-5 futuresdr::runtime::block: VectorSource-0 terminating [INFO] [stdout] 2025-09-01T09:20:11.430361Z DEBUG smol-5 futuresdr::runtime::block: Filter-1 terminating [INFO] [stdout] 2025-09-01T09:20:11.430423Z DEBUG smol-5 futuresdr::runtime::block: VectorSink-2 terminating [INFO] [stdout] 2025-09-01T09:20:11.430494Z DEBUG apply_mut_fn futuresdr::runtime::runtime: Runtime dropped [INFO] [stdout] test apply_mut_fn ... ok [INFO] [stderr] Running tests/finite_source.rs (/opt/rustwide/target/debug/deps/finite_source-637645137781e1be) [INFO] [stdout] test apply_const_fn ... 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 2 tests [INFO] [stdout] 2025-09-01T09:20:11.483831Z DEBUG finite_source_const_fn futuresdr::runtime::logging: logger already initialized [INFO] [stdout] 2025-09-01T09:20:11.491562Z DEBUG ThreadId(21) futuresdr::runtime::ctrl_port: Listening on 127.0.0.1:1337 [INFO] [stdout] 2025-09-01T09:20:11.491820Z DEBUG smol-1 futuresdr::runtime::runtime: in run_flowgraph [INFO] [stdout] 2025-09-01T09:20:11.491997Z DEBUG smol-7 futuresdr::runtime::runtime: in run_flowgraph [INFO] [stdout] 2025-09-01T09:20:11.492297Z DEBUG smol-7 futuresdr::runtime::runtime: connect stream io [INFO] [stdout] 2025-09-01T09:20:11.492634Z DEBUG smol-7 futuresdr::runtime::runtime: connect message io [INFO] [stdout] 2025-09-01T09:20:11.492664Z DEBUG smol-7 futuresdr::runtime::runtime: init blocks [INFO] [stdout] 2025-09-01T09:20:11.492682Z DEBUG smol-7 futuresdr::runtime::runtime: wait for blocks init [INFO] [stdout] 2025-09-01T09:20:11.492827Z DEBUG smol-1 futuresdr::runtime::runtime: connect stream io [INFO] [stdout] 2025-09-01T09:20:11.492981Z DEBUG smol-1 futuresdr::runtime::runtime: connect message io [INFO] [stdout] 2025-09-01T09:20:11.493008Z DEBUG smol-1 futuresdr::runtime::runtime: init blocks [INFO] [stdout] 2025-09-01T09:20:11.493022Z DEBUG smol-1 futuresdr::runtime::runtime: wait for blocks init [INFO] [stdout] 2025-09-01T09:20:11.493531Z DEBUG smol-6 futuresdr::runtime::runtime: running blocks [INFO] [stdout] 2025-09-01T09:20:11.493716Z DEBUG smol-1 futuresdr::runtime::runtime: running blocks [INFO] [stdout] 2025-09-01T09:20:11.493893Z DEBUG smol-1 futuresdr::runtime::block: FiniteSource-0 terminating [INFO] [stdout] 2025-09-01T09:20:11.493950Z  WARN ThreadId(22) futuresdr::runtime::ctrl_port: CtrlPort address 127.0.0.1:1337 already in use [INFO] [stdout] 2025-09-01T09:20:11.494003Z DEBUG smol-4 futuresdr::runtime::block: VectorSink-1 terminating [INFO] [stdout] 2025-09-01T09:20:11.494299Z DEBUG smol-7 futuresdr::runtime::block: Head-1 terminating [INFO] [stdout] 2025-09-01T09:20:11.494365Z DEBUG smol-7 futuresdr::runtime::block: FiniteSource-0 terminating [INFO] [stdout] 2025-09-01T09:20:11.494413Z DEBUG smol-7 futuresdr::runtime::block: VectorSink-2 terminating [INFO] [stdout] 2025-09-01T09:20:11.494485Z DEBUG finite_source_mut_fn futuresdr::runtime::runtime: Runtime dropped [INFO] [stdout] 2025-09-01T09:20:11.494522Z DEBUG finite_source_const_fn futuresdr::runtime::runtime: Runtime dropped [INFO] [stdout] test finite_source_const_fn ... ok [INFO] [stderr] Running tests/fir.rs (/opt/rustwide/target/debug/deps/fir-e59ec1a07026ecc6) [INFO] [stdout] test finite_source_mut_fn ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.05s [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] running 1 test [INFO] [stdout] 2025-09-01T09:20:11.567554Z DEBUG smol-6 futuresdr::runtime::runtime: in run_flowgraph [INFO] [stdout] 2025-09-01T09:20:11.567553Z DEBUG ThreadId(12) futuresdr::runtime::ctrl_port: Listening on 127.0.0.1:1337 [INFO] [stdout] 2025-09-01T09:20:11.568473Z DEBUG smol-6 futuresdr::runtime::runtime: connect stream io [INFO] [stdout] 2025-09-01T09:20:11.568853Z DEBUG smol-6 futuresdr::runtime::runtime: connect message io [INFO] [stdout] 2025-09-01T09:20:11.568875Z DEBUG smol-6 futuresdr::runtime::runtime: init blocks [INFO] [stdout] 2025-09-01T09:20:11.568889Z DEBUG smol-6 futuresdr::runtime::runtime: wait for blocks init [INFO] [stdout] 2025-09-01T09:20:11.569255Z DEBUG smol-6 futuresdr::runtime::runtime: running blocks [INFO] [stdout] 2025-09-01T09:20:11.569436Z DEBUG smol-6 futuresdr::runtime::block: VectorSource-0 terminating [INFO] [stdout] 2025-09-01T09:20:11.569523Z DEBUG smol-6 futuresdr::runtime::block: Fir-1 terminating [INFO] [stdout] 2025-09-01T09:20:11.569590Z DEBUG smol-6 futuresdr::runtime::block: VectorSink-2 terminating [INFO] [stdout] 2025-09-01T09:20:11.569670Z DEBUG fir_f32 futuresdr::runtime::runtime: Runtime dropped [INFO] [stdout] test fir_f32 ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.06s [INFO] [stdout] [INFO] [stderr] Running tests/message_handler.rs (/opt/rustwide/target/debug/deps/message_handler-8d95b65bddb78c0b) [INFO] [stdout] [INFO] [stdout] running 1 test [INFO] [stdout] test isolated_scope::message_handler_compiles ... ok [INFO] [stderr] Running tests/mocker.rs (/opt/rustwide/target/debug/deps/mocker-a4006c2c96b9a10b) [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] [stdout] [INFO] [stdout] running 3 tests [INFO] [stdout] test tags_through_mock ... ok [INFO] [stdout] test mock_pmts ... ok [INFO] [stdout] test multi_input_mock ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.42s [INFO] [stdout] [INFO] [stderr] Running tests/moving_avg.rs (/opt/rustwide/target/debug/deps/moving_avg-0624d475dcc38fdb) [INFO] [stdout] [INFO] [stdout] running 2 tests [INFO] [stdout] test moving_avg_correct_output ... ok [INFO] [stdout] test moving_avg_handles_non_finite_values ... 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] [stderr] Running tests/runtime.rs (/opt/rustwide/target/debug/deps/runtime-0dfd90a876217bdb) [INFO] [stdout] [INFO] [stdout] running 4 tests [INFO] [stdout] 2025-09-01T09:20:12.066191Z DEBUG fg_terminate futuresdr::runtime::logging: logger already initialized [INFO] [stdout] 2025-09-01T09:20:12.068051Z DEBUG ThreadId(19) futuresdr::runtime::ctrl_port: Listening on 127.0.0.1:1337 [INFO] [stdout] 2025-09-01T09:20:12.068863Z DEBUG smol-5 futuresdr::runtime::runtime: in run_flowgraph [INFO] [stdout] 2025-09-01T09:20:12.068899Z  WARN ThreadId(24) futuresdr::runtime::ctrl_port: CtrlPort address 127.0.0.1:1337 already in use [INFO] [stdout] 2025-09-01T09:20:12.069140Z DEBUG smol-7 futuresdr::runtime::runtime: in run_flowgraph [INFO] [stdout] 2025-09-01T09:20:12.071811Z DEBUG smol-5 futuresdr::runtime::runtime: connect stream io [INFO] [stdout] 2025-09-01T09:20:12.072360Z DEBUG smol-7 futuresdr::runtime::runtime: connect stream io [INFO] [stdout] 2025-09-01T09:20:12.072822Z DEBUG smol-5 futuresdr::runtime::runtime: connect message io [INFO] [stdout] 2025-09-01T09:20:12.072851Z DEBUG smol-5 futuresdr::runtime::runtime: init blocks [INFO] [stdout] 2025-09-01T09:20:12.072881Z DEBUG smol-5 futuresdr::runtime::runtime: wait for blocks init [INFO] [stdout] 2025-09-01T09:20:12.072916Z DEBUG smol-5 futuresdr::runtime::runtime: running blocks [INFO] [stdout] 2025-09-01T09:20:12.075114Z DEBUG smol-7 futuresdr::runtime::runtime: connect message io [INFO] [stdout] 2025-09-01T09:20:12.075142Z DEBUG smol-7 futuresdr::runtime::runtime: init blocks [INFO] [stdout] 2025-09-01T09:20:12.075157Z DEBUG smol-7 futuresdr::runtime::runtime: wait for blocks init [INFO] [stdout] 2025-09-01T09:20:12.075209Z DEBUG smol-5 futuresdr::runtime::runtime: running blocks [INFO] [stdout] 2025-09-01T09:20:12.083997Z DEBUG smol-6 futuresdr::runtime::block: Head-1 terminating [INFO] [stdout] 2025-09-01T09:20:12.084130Z DEBUG smol-6 futuresdr::runtime::block: NullSource-2 terminating [INFO] [stdout] 2025-09-01T09:20:12.084175Z DEBUG smol-6 futuresdr::runtime::block: Copy-0 terminating [INFO] [stdout] 2025-09-01T09:20:12.084230Z DEBUG smol-6 futuresdr::runtime::block: VectorSink-3 terminating [INFO] [stdout] 2025-09-01T09:20:12.084320Z DEBUG flowgraph futuresdr::runtime::runtime: Runtime dropped [INFO] [stdout] test flowgraph ... ok [INFO] [stdout] 2025-09-01T09:20:13.073253Z DEBUG smol-5 futuresdr::runtime::block: NullSource-0 terminating [INFO] [stdout] 2025-09-01T09:20:13.073383Z DEBUG smol-5 futuresdr::runtime::block: Throttle-1 terminating [INFO] [stdout] 2025-09-01T09:20:13.073450Z DEBUG smol-5 futuresdr::runtime::block: NullSink-2 terminating [INFO] [stdout] 2025-09-01T09:20:13.074927Z DEBUG fg_terminate futuresdr::runtime::runtime: Runtime dropped [INFO] [stdout] test fg_terminate ... ok [INFO] [stdout] 2025-09-01T09:20:13.328629Z DEBUG fg_rand_vec_multi_snk futuresdr::runtime::logging: logger already initialized [INFO] [stdout] 2025-09-01T09:20:13.334922Z DEBUG smol-6 futuresdr::runtime::runtime: in run_flowgraph [INFO] [stdout] 2025-09-01T09:20:13.334992Z DEBUG ThreadId(33) futuresdr::runtime::ctrl_port: Listening on 127.0.0.1:1337 [INFO] [stdout] 2025-09-01T09:20:13.335281Z DEBUG smol-6 futuresdr::runtime::runtime: connect stream io [INFO] [stdout] 2025-09-01T09:20:13.335720Z DEBUG smol-6 futuresdr::runtime::runtime: connect message io [INFO] [stdout] 2025-09-01T09:20:13.336000Z DEBUG smol-6 futuresdr::runtime::runtime: init blocks [INFO] [stdout] 2025-09-01T09:20:13.336051Z DEBUG smol-6 futuresdr::runtime::runtime: wait for blocks init [INFO] [stdout] 2025-09-01T09:20:13.336106Z DEBUG smol-6 futuresdr::runtime::runtime: running blocks [INFO] [stdout] 2025-09-01T09:20:13.425043Z DEBUG smol-2 futuresdr::runtime::block: VectorSource-0 terminating [INFO] [stdout] 2025-09-01T09:20:13.425300Z DEBUG smol-2 futuresdr::runtime::block: Copy-1 terminating [INFO] [stdout] 2025-09-01T09:20:13.425424Z DEBUG smol-2 futuresdr::runtime::block: VectorSink-2 terminating [INFO] [stdout] 2025-09-01T09:20:13.425471Z DEBUG smol-2 futuresdr::runtime::block: VectorSink-3 terminating [INFO] [stdout] 2025-09-01T09:20:13.425498Z DEBUG smol-2 futuresdr::runtime::block: VectorSink-4 terminating [INFO] [stdout] 2025-09-01T09:20:13.425523Z DEBUG smol-2 futuresdr::runtime::block: VectorSink-5 terminating [INFO] [stdout] 2025-09-01T09:20:13.425545Z DEBUG smol-2 futuresdr::runtime::block: VectorSink-6 terminating [INFO] [stdout] 2025-09-01T09:20:13.425571Z DEBUG smol-2 futuresdr::runtime::block: VectorSink-7 terminating [INFO] [stdout] 2025-09-01T09:20:13.425603Z DEBUG smol-2 futuresdr::runtime::block: VectorSink-8 terminating [INFO] [stdout] 2025-09-01T09:20:13.425629Z DEBUG smol-2 futuresdr::runtime::block: VectorSink-9 terminating [INFO] [stdout] 2025-09-01T09:20:13.425650Z DEBUG smol-2 futuresdr::runtime::block: VectorSink-10 terminating [INFO] [stdout] 2025-09-01T09:20:13.425673Z DEBUG smol-2 futuresdr::runtime::block: VectorSink-11 terminating [INFO] [stdout] 2025-09-01T09:20:13.425757Z DEBUG fg_rand_vec_multi_snk futuresdr::runtime::runtime: Runtime dropped [INFO] [stdout] test fg_rand_vec_multi_snk ... ok [INFO] [stdout] 2025-09-01T09:20:17.125667Z DEBUG fg_rand_vec futuresdr::runtime::logging: logger already initialized [INFO] [stdout] 2025-09-01T09:20:17.126873Z DEBUG smol-0 futuresdr::runtime::runtime: in run_flowgraph [INFO] [stdout] 2025-09-01T09:20:17.127006Z DEBUG smol-0 futuresdr::runtime::runtime: connect stream io [INFO] [stdout] 2025-09-01T09:20:17.127626Z DEBUG smol-0 futuresdr::runtime::runtime: connect message io [INFO] [stdout] 2025-09-01T09:20:17.128144Z DEBUG smol-0 futuresdr::runtime::runtime: init blocks [INFO] [stdout] 2025-09-01T09:20:17.128351Z DEBUG smol-0 futuresdr::runtime::runtime: wait for blocks init [INFO] [stdout] 2025-09-01T09:20:17.128399Z DEBUG smol-0 futuresdr::runtime::runtime: running blocks [INFO] [stdout] 2025-09-01T09:20:17.128094Z DEBUG ThreadId(42) futuresdr::runtime::ctrl_port: Listening on 127.0.0.1:1337 [INFO] [stdout] 2025-09-01T09:20:17.212562Z DEBUG smol-3 futuresdr::runtime::block: VectorSource-0 terminating [INFO] [stdout] 2025-09-01T09:20:17.212777Z DEBUG smol-3 futuresdr::runtime::block: Copy-1 terminating [INFO] [stdout] 2025-09-01T09:20:17.212890Z DEBUG smol-3 futuresdr::runtime::block: VectorSink-2 terminating [INFO] [stdout] 2025-09-01T09:20:17.213091Z DEBUG fg_rand_vec futuresdr::runtime::runtime: Runtime dropped [INFO] [stdout] test fg_rand_vec ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 5.82s [INFO] [stdout] [INFO] [stderr] Running tests/slab.rs (/opt/rustwide/target/debug/deps/slab-1cfb233efde4f5e5) [INFO] [stdout] [INFO] [stdout] running 2 tests [INFO] [stdout] 2025-09-01T09:20:17.900010Z DEBUG smol-2 futuresdr::runtime::runtime: in run_flowgraph [INFO] [stdout] 2025-09-01T09:20:17.900161Z DEBUG smol-2 futuresdr::runtime::runtime: connect stream io [INFO] [stdout] 2025-09-01T09:20:17.900305Z DEBUG smol-2 futuresdr::runtime::runtime: connect message io [INFO] [stdout] 2025-09-01T09:20:17.900322Z DEBUG smol-2 futuresdr::runtime::runtime: init blocks [INFO] [stdout] 2025-09-01T09:20:17.900345Z DEBUG smol-2 futuresdr::runtime::runtime: wait for blocks init [INFO] [stdout] 2025-09-01T09:20:17.900439Z DEBUG smol-2 futuresdr::runtime::runtime: running blocks [INFO] [stdout] 2025-09-01T09:20:17.905550Z DEBUG ThreadId(13) futuresdr::runtime::ctrl_port: Listening on 127.0.0.1:1337 [INFO] [stdout] 2025-09-01T09:20:17.909572Z DEBUG smol-5 futuresdr::runtime::block: Head-1 terminating [INFO] [stdout] 2025-09-01T09:20:17.909767Z DEBUG smol-6 futuresdr::runtime::block: NullSource-2 terminating [INFO] [stdout] 2025-09-01T09:20:17.909919Z DEBUG smol-2 futuresdr::runtime::block: Copy-0 terminating [INFO] [stdout] 2025-09-01T09:20:17.909983Z DEBUG smol-2 futuresdr::runtime::block: VectorSink-3 terminating [INFO] [stdout] 2025-09-01T09:20:17.910132Z DEBUG flowgraph futuresdr::runtime::runtime: Runtime dropped [INFO] [stdout] test flowgraph ... ok [INFO] [stdout] 2025-09-01T09:20:22.096264Z DEBUG fg_rand_vec futuresdr::runtime::logging: logger already initialized [INFO] [stdout] 2025-09-01T09:20:22.099169Z DEBUG smol-7 futuresdr::runtime::runtime: in run_flowgraph [INFO] [stdout] 2025-09-01T09:20:22.099780Z DEBUG ThreadId(22) futuresdr::runtime::ctrl_port: Listening on 127.0.0.1:1337 [INFO] [stdout] 2025-09-01T09:20:22.099936Z DEBUG smol-7 futuresdr::runtime::runtime: connect stream io [INFO] [stdout] 2025-09-01T09:20:22.100311Z DEBUG smol-7 futuresdr::runtime::runtime: connect message io [INFO] [stdout] 2025-09-01T09:20:22.100461Z DEBUG smol-7 futuresdr::runtime::runtime: init blocks [INFO] [stdout] 2025-09-01T09:20:22.100709Z DEBUG smol-7 futuresdr::runtime::runtime: wait for blocks init [INFO] [stdout] 2025-09-01T09:20:22.100842Z DEBUG smol-7 futuresdr::runtime::runtime: running blocks [INFO] [stdout] 2025-09-01T09:20:22.170180Z DEBUG smol-7 futuresdr::runtime::block: VectorSource-0 terminating [INFO] [stdout] 2025-09-01T09:20:22.170497Z DEBUG smol-5 futuresdr::runtime::block: Copy-1 terminating [INFO] [stdout] 2025-09-01T09:20:22.170610Z DEBUG smol-5 futuresdr::runtime::block: VectorSink-2 terminating [INFO] [stdout] 2025-09-01T09:20:22.170773Z DEBUG fg_rand_vec futuresdr::runtime::runtime: Runtime dropped [INFO] [stdout] test fg_rand_vec ... ok [INFO] [stderr] Running tests/source.rs (/opt/rustwide/target/debug/deps/source-a06a75623520e04c) [INFO] [stdout] [INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 4.94s [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] running 2 tests [INFO] [stdout] 2025-09-01T09:20:22.875191Z DEBUG source_const_fn futuresdr::runtime::logging: logger already initialized [INFO] [stdout] 2025-09-01T09:20:22.877456Z DEBUG ThreadId(15) futuresdr::runtime::ctrl_port: Listening on 127.0.0.1:1337 [INFO] [stdout] 2025-09-01T09:20:22.889948Z DEBUG smol-1 futuresdr::runtime::runtime: in run_flowgraph [INFO] [stdout] 2025-09-01T09:20:22.889948Z DEBUG smol-6 futuresdr::runtime::runtime: in run_flowgraph [INFO] [stdout] 2025-09-01T09:20:22.890444Z  WARN ThreadId(22) futuresdr::runtime::ctrl_port: CtrlPort address 127.0.0.1:1337 already in use [INFO] [stdout] 2025-09-01T09:20:22.890666Z DEBUG smol-1 futuresdr::runtime::runtime: connect stream io [INFO] [stdout] 2025-09-01T09:20:22.890770Z DEBUG smol-6 futuresdr::runtime::runtime: connect stream io [INFO] [stdout] 2025-09-01T09:20:22.891243Z DEBUG smol-6 futuresdr::runtime::runtime: connect message io [INFO] [stdout] 2025-09-01T09:20:22.891273Z DEBUG smol-6 futuresdr::runtime::runtime: init blocks [INFO] [stdout] 2025-09-01T09:20:22.891305Z DEBUG smol-6 futuresdr::runtime::runtime: wait for blocks init [INFO] [stdout] 2025-09-01T09:20:22.891502Z DEBUG smol-4 futuresdr::runtime::runtime: running blocks [INFO] [stdout] 2025-09-01T09:20:22.891878Z DEBUG smol-2 futuresdr::runtime::block: Head-1 terminating [INFO] [stdout] 2025-09-01T09:20:22.891989Z DEBUG smol-5 futuresdr::runtime::block: VectorSink-2 terminating [INFO] [stdout] 2025-09-01T09:20:22.892094Z DEBUG smol-4 futuresdr::runtime::block: Source-0 terminating [INFO] [stdout] 2025-09-01T09:20:22.892200Z DEBUG source_mut_fn futuresdr::runtime::runtime: Runtime dropped [INFO] [stdout] 2025-09-01T09:20:22.892653Z DEBUG smol-1 futuresdr::runtime::runtime: connect message io [INFO] [stdout] 2025-09-01T09:20:22.892675Z DEBUG smol-1 futuresdr::runtime::runtime: init blocks [INFO] [stdout] 2025-09-01T09:20:22.892690Z DEBUG smol-1 futuresdr::runtime::runtime: wait for blocks init [INFO] [stdout] 2025-09-01T09:20:22.892807Z DEBUG smol-7 futuresdr::runtime::runtime: running blocks [INFO] [stdout] 2025-09-01T09:20:22.893396Z DEBUG smol-1 futuresdr::runtime::block: Head-1 terminating [INFO] [stdout] 2025-09-01T09:20:22.893464Z DEBUG smol-1 futuresdr::runtime::block: Source-0 terminating [INFO] [stdout] 2025-09-01T09:20:22.893531Z DEBUG smol-1 futuresdr::runtime::block: VectorSink-2 terminating [INFO] [stdout] 2025-09-01T09:20:22.893639Z DEBUG source_const_fn futuresdr::runtime::runtime: Runtime dropped [INFO] [stdout] test source_mut_fn ... ok [INFO] [stdout] test source_const_fn ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.05s [INFO] [stdout] [INFO] [stderr] Running tests/split.rs (/opt/rustwide/target/debug/deps/split-688b4d59493805d3) [INFO] [stdout] [INFO] [stdout] running 1 test [INFO] [stdout] 2025-09-01T09:20:22.940027Z DEBUG smol-7 futuresdr::runtime::runtime: in run_flowgraph [INFO] [stdout] 2025-09-01T09:20:22.940162Z DEBUG ThreadId(12) futuresdr::runtime::ctrl_port: Listening on 127.0.0.1:1337 [INFO] [stdout] 2025-09-01T09:20:22.941300Z DEBUG smol-7 futuresdr::runtime::runtime: connect stream io [INFO] [stdout] 2025-09-01T09:20:22.942092Z DEBUG smol-7 futuresdr::runtime::runtime: connect message io [INFO] [stdout] 2025-09-01T09:20:22.942120Z DEBUG smol-7 futuresdr::runtime::runtime: init blocks [INFO] [stdout] 2025-09-01T09:20:22.942193Z DEBUG smol-7 futuresdr::runtime::runtime: wait for blocks init [INFO] [stdout] 2025-09-01T09:20:22.942310Z DEBUG smol-7 futuresdr::runtime::runtime: running blocks [INFO] [stdout] 2025-09-01T09:20:22.942527Z DEBUG smol-7 futuresdr::runtime::block: VectorSource-0 terminating [INFO] [stdout] 2025-09-01T09:20:22.942678Z DEBUG smol-6 futuresdr::runtime::block: Split-1 terminating [INFO] [stdout] 2025-09-01T09:20:22.942854Z DEBUG smol-6 futuresdr::runtime::block: VectorSink-3 terminating [INFO] [stdout] 2025-09-01T09:20:22.942850Z DEBUG smol-5 futuresdr::runtime::block: VectorSink-2 terminating [INFO] [stdout] 2025-09-01T09:20:22.943077Z DEBUG split_const_fn futuresdr::runtime::runtime: Runtime dropped [INFO] [stdout] test split_const_fn ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.04s [INFO] [stdout] [INFO] [stderr] Running tests/tag.rs (/opt/rustwide/target/debug/deps/tag-724b3d8269832b4d) [INFO] [stdout] [INFO] [stdout] running 1 test [INFO] [stdout] test tag_any_is ... 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/type_id.rs (/opt/rustwide/target/debug/deps/type_id-3a2180948e966234) [INFO] [stdout] [INFO] [stdout] running 1 test [INFO] [stdout] test type_id - should panic ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.62s [INFO] [stdout] [INFO] [stderr] Doc-tests futuresdr [INFO] [stdout] [INFO] [stdout] running 23 tests [INFO] [stdout] test src/blocks/file_sink.rs - blocks::file_sink::FileSink (line 32) - compile ... ok [INFO] [stdout] test src/blocks/file_source.rs - blocks::file_source::FileSource (line 29) - compile ... ok [INFO] [stdout] test src/blocks/fft.rs - blocks::fft::Fft (line 35) ... ok [INFO] [stdout] test src/blocks/channel_sink.rs - blocks::channel_sink::ChannelSink (line 20) ... ok [INFO] [stdout] test src/blocks/head.rs - blocks::head::Head (line 23) ... ok [INFO] [stdout] test src/blocks/combine.rs - blocks::combine::Combine (line 25) ... ok [INFO] [stdout] test src/blocks/channel_source.rs - blocks::channel_source::ChannelSource (line 22) ... ok [INFO] [stdout] test src/blocks/filter.rs - blocks::filter::Filter (line 23) ... ok [INFO] [stdout] test src/blocks/apply.rs - blocks::apply::Apply (line 23) ... ok [INFO] [stdout] test src/blocks/applynm.rs - blocks::applynm::ApplyNM (line 28) ... ok [INFO] [stdout] test src/blocks/fir.rs - blocks::fir::FirBuilder (line 191) ... ok [INFO] [stdout] test src/blocks/delay.rs - blocks::delay::Delay (line 31) ... ok [INFO] [stdout] test src/blocks/iir.rs - blocks::iir::IirBuilder (line 124) ... ok [INFO] [stdout] test src/blocks/null_sink.rs - blocks::null_sink::NullSink (line 23) ... ok [INFO] [stdout] test src/blocks/message_source.rs - blocks::message_source::MessageSourceBuilder (line 98) ... ok [INFO] [stdout] test src/blocks/null_source.rs - blocks::null_source::NullSource (line 23) ... ok [INFO] [stdout] test src/runtime/tag.rs - runtime::tag::copy_tag_propagation (line 80) - compile ... ok [INFO] [stdout] test src/blocks/tag_debug.rs - blocks::tag_debug::TagDebug (line 25) ... ok [INFO] [stdout] test src/blocks/xlating_fir.rs - blocks::xlating_fir::XlatingFirBuilder (line 110) ... ok [INFO] [stdout] test src/blocks/source.rs - blocks::source::Source (line 23) ... ok [INFO] [stdout] test src/blocks/sink.rs - blocks::sink::Sink (line 23) ... ok [INFO] [stdout] test src/blocks/throttle.rs - blocks::throttle::Throttle (line 25) ... ok [INFO] [stdout] test src/lib.rs - (line 14) ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 23 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.08s [INFO] [stdout] [INFO] running `Command { std: "docker" "inspect" "1f11e357a7c6c021847fa15a41d7eaca9d5209e6b08f2fb9a5bf9fd69ec1d67f", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "1f11e357a7c6c021847fa15a41d7eaca9d5209e6b08f2fb9a5bf9fd69ec1d67f", kill_on_drop: false }` [INFO] [stdout] 1f11e357a7c6c021847fa15a41d7eaca9d5209e6b08f2fb9a5bf9fd69ec1d67f