[INFO] fetching crate wingfoil 4.0.1...
[INFO] testing wingfoil-4.0.1 against try#dec9417b8611e34e787a3e4c37686b5131f9e5c5 for pr-154210-2
[INFO] extracting crate wingfoil 4.0.1 into /workspace/builds/worker-2-tc2/source
[INFO] started tweaking crates.io crate wingfoil 4.0.1
[INFO] removed 0 missing examples
[INFO] finished tweaking crates.io crate wingfoil 4.0.1
[INFO] tweaked toml for crates.io crate wingfoil 4.0.1 written to /workspace/builds/worker-2-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate wingfoil 4.0.1 on toolchain dec9417b8611e34e787a3e4c37686b5131f9e5c5
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate wingfoil 4.0.1 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" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] b2b548eb301decfc33cf8cbab106a6f14bb4b022232f1a5a92f071f0e79ed08b
[INFO] running `Command { std: "docker" "start" "-a" "b2b548eb301decfc33cf8cbab106a6f14bb4b022232f1a5a92f071f0e79ed08b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "b2b548eb301decfc33cf8cbab106a6f14bb4b022232f1a5a92f071f0e79ed08b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "b2b548eb301decfc33cf8cbab106a6f14bb4b022232f1a5a92f071f0e79ed08b", kill_on_drop: false }`
[INFO] [stdout] b2b548eb301decfc33cf8cbab106a6f14bb4b022232f1a5a92f071f0e79ed08b
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 6ffba9dcf7d664e73c1196d68e791699af40366c3aff973ccdbb1603ab70f30e
[INFO] running `Command { std: "docker" "start" "-a" "6ffba9dcf7d664e73c1196d68e791699af40366c3aff973ccdbb1603ab70f30e", kill_on_drop: false }`
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling zerocopy v0.8.48
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling aho-corasick v1.1.4
[INFO] [stderr]    Compiling syn v2.0.117
[INFO] [stderr]    Compiling anstyle v1.0.14
[INFO] [stderr]    Compiling regex-syntax v0.8.10
[INFO] [stderr]    Compiling cc v1.2.59
[INFO] [stderr]    Compiling futures-channel v0.3.32
[INFO] [stderr]    Compiling unicode-segmentation v1.13.2
[INFO] [stderr]    Compiling clap_lex v1.1.0
[INFO] [stderr]    Compiling getrandom v0.3.4
[INFO] [stderr]    Compiling serde_json v1.0.149
[INFO] [stderr]    Compiling ciborium-io v0.2.2
[INFO] [stderr]    Compiling plotters-backend v0.3.7
[INFO] [stderr]    Compiling itertools v0.13.0
[INFO] [stderr]    Compiling scopeguard v1.2.0
[INFO] [stderr]    Compiling clap_builder v4.6.0
[INFO] [stderr]    Compiling rand_core v0.9.5
[INFO] [stderr]    Compiling unicode-xid v0.2.6
[INFO] [stderr]    Compiling same-file v1.0.6
[INFO] [stderr]    Compiling colorchoice v1.0.5
[INFO] [stderr]    Compiling anstyle-query v1.1.5
[INFO] [stderr]    Compiling plotters-svg v0.3.7
[INFO] [stderr]    Compiling is_terminal_polyfill v1.70.2
[INFO] [stderr]    Compiling indexmap v2.13.1
[INFO] [stderr]    Compiling walkdir v2.5.0
[INFO] [stderr]    Compiling raw-cpuid v10.7.0
[INFO] [stderr]    Compiling anstream v1.0.0
[INFO] [stderr]    Compiling anyhow v1.0.102
[INFO] [stderr]    Compiling convert_case v0.10.0
[INFO] [stderr]    Compiling lock_api v0.4.14
[INFO] [stderr]    Compiling page_size v0.6.0
[INFO] [stderr]    Compiling crossbeam-channel v0.5.15
[INFO] [stderr]    Compiling crossbeam-queue v0.3.12
[INFO] [stderr]    Compiling nom v8.0.0
[INFO] [stderr]    Compiling jiff v0.2.23
[INFO] [stderr]    Compiling anes v0.1.6
[INFO] [stderr]    Compiling tinyvec v1.11.0
[INFO] [stderr]    Compiling kanal v0.1.1
[INFO] [stderr]    Compiling num-integer v0.1.46
[INFO] [stderr]    Compiling ordered-float v2.10.1
[INFO] [stderr]    Compiling chrono v0.4.44
[INFO] [stderr]    Compiling num-complex v0.4.6
[INFO] [stderr]    Compiling plotters v0.3.7
[INFO] [stderr]    Compiling regex-automata v0.4.14
[INFO] [stderr]    Compiling num-bigint v0.4.6
[INFO] [stderr]    Compiling num-iter v0.1.45
[INFO] [stderr]    Compiling quanta v0.9.3
[INFO] [stderr]    Compiling priority-queue v2.7.0
[INFO] [stderr]    Compiling ordered-float v4.6.0
[INFO] [stderr]    Compiling alloca v0.4.0
[INFO] [stderr]    Compiling criterion-plot v0.8.2
[INFO] [stderr]    Compiling crossbeam v0.8.4
[INFO] [stderr]    Compiling itertools v0.14.0
[INFO] [stderr]    Compiling tokio v1.51.0
[INFO] [stderr]    Compiling lobster v0.7.0
[INFO] [stderr]    Compiling formato v0.3.0
[INFO] [stderr]    Compiling clap v4.6.0
[INFO] [stderr]    Compiling num-rational v0.4.2
[INFO] [stderr]    Compiling num v0.4.3
[INFO] [stderr]    Compiling regex v1.12.3
[INFO] [stderr]    Compiling tynm v0.2.0
[INFO] [stderr]    Compiling env_filter v1.0.1
[INFO] [stderr]    Compiling zerocopy-derive v0.8.48
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling futures-macro v0.3.32
[INFO] [stderr]    Compiling thiserror-impl v2.0.18
[INFO] [stderr]    Compiling async-stream-impl v0.3.6
[INFO] [stderr]    Compiling strum_macros v0.27.2
[INFO] [stderr]    Compiling derive_more-impl v2.1.1
[INFO] [stderr]    Compiling wingfoil-derive v4.0.1
[INFO] [stderr]    Compiling derive-new v0.7.0
[INFO] [stderr]    Compiling env_logger v0.11.10
[INFO] [stderr]    Compiling async-stream v0.3.6
[INFO] [stderr]    Compiling futures-util v0.3.32
[INFO] [stderr]    Compiling thiserror v2.0.18
[INFO] [stderr]    Compiling strum v0.27.2
[INFO] [stderr]    Compiling futures-executor v0.3.32
[INFO] [stderr]    Compiling futures v0.3.32
[INFO] [stderr]    Compiling derive_more v2.1.1
[INFO] [stderr]    Compiling half v2.7.1
[INFO] [stderr]    Compiling ppv-lite86 v0.2.21
[INFO] [stderr]    Compiling ciborium-ll v0.2.2
[INFO] [stderr]    Compiling rand_chacha v0.9.0
[INFO] [stderr]    Compiling tinytemplate v1.2.1
[INFO] [stderr]    Compiling serde-value v0.7.0
[INFO] [stderr]    Compiling arrayvec v0.7.6
[INFO] [stderr]    Compiling ciborium v0.2.2
[INFO] [stderr]    Compiling rand v0.9.2
[INFO] [stderr]    Compiling serde-aux v4.7.0
[INFO] [stderr]    Compiling criterion v0.8.2
[INFO] [stderr]    Compiling wingfoil v4.0.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 10.77s
[INFO] running `Command { std: "docker" "inspect" "6ffba9dcf7d664e73c1196d68e791699af40366c3aff973ccdbb1603ab70f30e", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "6ffba9dcf7d664e73c1196d68e791699af40366c3aff973ccdbb1603ab70f30e", kill_on_drop: false }`
[INFO] [stdout] 6ffba9dcf7d664e73c1196d68e791699af40366c3aff973ccdbb1603ab70f30e
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 280d829bc9970126f3b473b83aed5351b17e0c3344a16d05e9ea6b189beb61ae
[INFO] running `Command { std: "docker" "start" "-a" "280d829bc9970126f3b473b83aed5351b17e0c3344a16d05e9ea6b189beb61ae", kill_on_drop: false }`
[INFO] [stderr]    Compiling semver v1.0.28
[INFO] [stderr]    Compiling regex-automata v0.4.14
[INFO] [stderr]    Compiling tokio-macros v2.7.0
[INFO] [stderr]    Compiling tracing-core v0.1.36
[INFO] [stderr]    Compiling cargo-husky v1.5.0
[INFO] [stderr]    Compiling sharded-slab v0.1.7
[INFO] [stderr]    Compiling thread_local v1.1.9
[INFO] [stderr]    Compiling nu-ansi-term v0.50.3
[INFO] [stderr]    Compiling rustc_version v0.4.1
[INFO] [stderr]    Compiling tracing-log v0.2.0
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling branches v0.4.4
[INFO] [stderr]    Compiling tokio v1.51.0
[INFO] [stderr]    Compiling rclite v0.4.1
[INFO] [stderr]    Compiling rxrust v1.0.0-rc.3
[INFO] [stderr]    Compiling regex v1.12.3
[INFO] [stderr]    Compiling matchers v0.2.0
[INFO] [stderr]    Compiling tracing-subscriber v0.3.23
[INFO] [stderr]    Compiling env_filter v1.0.1
[INFO] [stderr]    Compiling criterion v0.8.2
[INFO] [stderr]    Compiling env_logger v0.11.10
[INFO] [stderr]    Compiling wingfoil v4.0.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 8.49s
[INFO] running `Command { std: "docker" "inspect" "280d829bc9970126f3b473b83aed5351b17e0c3344a16d05e9ea6b189beb61ae", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "280d829bc9970126f3b473b83aed5351b17e0c3344a16d05e9ea6b189beb61ae", kill_on_drop: false }`
[INFO] [stdout] 280d829bc9970126f3b473b83aed5351b17e0c3344a16d05e9ea6b189beb61ae
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] cdd3c8a1275aa39f6355a3fc5670978a5722575d4573dabbc825a7ebdb2678c5
[INFO] running `Command { std: "docker" "start" "-a" "cdd3c8a1275aa39f6355a3fc5670978a5722575d4573dabbc825a7ebdb2678c5", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.18s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/wingfoil-6d1f8841b833be36)
[INFO] [stdout] 
[INFO] [stdout] running 189 tests
[INFO] [stdout] test bencher::tests::signal_from_u8_all_variants ... ok
[INFO] [stdout] test bencher::tests::bench_trigger_cycle_begin_transitions_to_running ... ok
[INFO] [stdout] test bencher::tests::bench_trigger_cycle_ready_returns_false ... ok
[INFO] [stdout] test channel::kanal_chan::tests::channel_pair_creates_working_channel ... ok
[INFO] [stdout] test bencher::tests::signal_into_u8_roundtrips ... ok
[INFO] [stdout] test channel::kanal_chan::tests::recv_returns_end_of_stream_when_sender_dropped ... ok
[INFO] [stdout] test channel::kanal_chan::tests::send_after_close_returns_error ... ok
[INFO] [stdout] test channel::kanal_chan::tests::send_checkpoint ... ok
[INFO] [stdout] test channel::kanal_chan::tests::close_sends_end_of_stream_and_idempotent ... ok
[INFO] [stdout] test channel::kanal_chan::tests::send_historical_batch ... ok
[INFO] [stdout] test channel::kanal_chan::tests::send_in_historical_mode_wraps_as_historical_value ... ok
[INFO] [stdout] test channel::kanal_chan::tests::send_in_realtime_mode_wraps_as_realtime_value ... ok
[INFO] [stdout] test channel::kanal_chan::tests::try_recv_returns_end_of_stream_when_closed ... ok
[INFO] [stdout] test channel::kanal_chan::tests::try_recv_returns_none_on_empty ... ok
[INFO] [stdout] test channel::message::tests::checkpoint_eq ... ok
[INFO] [stdout] test channel::message::tests::end_of_stream_eq ... ok
[INFO] [stdout] test channel::message::tests::historical_batch_eq ... ok
[INFO] [stdout] test channel::message::tests::historical_value_eq ... ok
[INFO] [stdout] test channel::message::tests::mismatching_variants_not_equal ... ok
[INFO] [stdout] test channel::message::tests::realtime_value_eq ... ok
[INFO] [stdout] test channel::message::tests::serde_roundtrip_checkpoint ... ok
[INFO] [stdout] test channel::message::tests::serde_roundtrip_end_of_stream ... ok
[INFO] [stdout] test channel::message::tests::serde_roundtrip_historical_batch ... ok
[INFO] [stdout] test channel::tests::channel_closed_display ... ok
[INFO] [stdout] test channel::tests::send_node_error_eq_and_copy ... ok
[INFO] [stdout] test channel::tests::send_node_error_is_error ... ok
[INFO] [stdout] test graph::tests::average_duration_normal_case ... ok
[INFO] [stdout] test graph::tests::average_duration_normal ... ok
[INFO] [stdout] test channel::message::tests::serde_roundtrip_historical_value ... ok
[INFO] [stdout] test channel::tests::notifier_closed_display ... ok
[INFO] [stdout] test channel::message::tests::serde_roundtrip_realtime_value ... ok
[INFO] [stdout] test graph::tests::average_duration_zero_n_returns_zero ... ok
[INFO] [stdout] test graph::tests::run_for_cycles_done_when_exceeded ... ok
[INFO] [stdout] test graph::tests::run_for_duration_done_when_elapsed_exceeds ... ok
[INFO] [stdout] test graph::tests::node_index_ticked_reflects_cycle ... ok
[INFO] [stdout] test graph::tests::graph_state_log_with_no_current_node_is_noop ... ok
[INFO] [stdout] test graph::tests::run_for_forever_never_done ... ok
[INFO] [stdout] test graph::tests::time_advances_with_duplicate_scheduled_time ... ok
[INFO] [stdout] test graph::tests::time_advances_with_past_scheduled_time ... ok
[INFO] [stdout] test graph::tests::ticked_on_unregistered_node_returns_false ... ok
[INFO] [stdout] test nodes::average::tests::average_of_sequence ... ok
[INFO] [stdout] test nodes::average::tests::average_of_constant_stream ... ok
[INFO] [stdout] test nodes::callback::tests::callback_stream_default_value_before_run ... ok
[INFO] [stdout] test nodes::bimap::tests::bimap_passive_does_not_trigger ... ok
[INFO] [stdout] test nodes::bimap::tests::bimap_both_active ... ok
[INFO] [stdout] test nodes::callback::tests::callback_stream_emits_pushed_values_in_order ... ok
[INFO] [stdout] test graph::tests::historical_mode_works ... ok
[INFO] [stdout] test nodes::callback::tests::callback_stream_emits_correct_timestamps ... ok
[INFO] [stdout] test nodes::consumer::tests::for_each_called_once_per_tick ... ok
[INFO] [stdout] test nodes::consumer::tests::try_for_each_success_path ... ok
[INFO] [stdout] test nodes::combine::tests::combine_works ... ok
[INFO] [stdout] test nodes::constant::tests::constant_value_works ... ok
[INFO] [stdout] test nodes::delay::tests::delay_works ... ok
[INFO] [stdout] test nodes::delay::tests::delay_initializes_to_first_value ... ok
[INFO] [stdout] test nodes::delay::tests::zero_delay_works ... ok
[INFO] [stdout] test nodes::delay::tests::long_delay_works ... ok
[INFO] [stdout] test graph::tests::graph_export_writes_gml_file ... ok
[INFO] [stdout] test nodes::delay_with_reset::tests::delay_with_reset_zero_delay_passes_through_immediately ... ok
[INFO] [stdout] test nodes::delay_with_reset::tests::delay_never_reset ... ok
[INFO] [stdout] test nodes::difference::tests::delta_for_non_unit_steps ... ok
[INFO] [stdout] test nodes::delay_with_reset::tests::delay_with_reset_snaps_on_trigger ... ok
[INFO] [stdout] test nodes::delay_with_reset::tests::delay_with_reset_snaps_on_trigger_2 ... ok
[INFO] [stdout] test nodes::difference::tests::delta_is_correct ... ok
[INFO] [stdout] test nodes::distinct::tests::suppresses_repeated_values ... ok
[INFO] [stdout] test nodes::feedback::tests::feedback_active_works ... ok
[INFO] [stdout] test nodes::difference::tests::first_tick_does_not_emit ... ok
[INFO] [stdout] test nodes::distinct::tests::emits_every_tick_when_all_unique ... ok
[INFO] [stdout] test nodes::feedback::tests::feedback_passive_works ... ok
[INFO] [stdout] test nodes::feedback::tests::feedback_sink_clone_works ... ok
[INFO] [stdout] test nodes::filter::tests::condition_stream_controls_emission ... ok
[INFO] [stdout] test nodes::filter::tests::suppresses_all_when_condition_always_false ... ok
[INFO] [stdout] test nodes::feedback::tests::feedback_works ... ok
[INFO] [stdout] test nodes::filter::tests::passes_values_when_condition_true ... ok
[INFO] [stdout] test nodes::finally::tests::finally_fires_with_default_if_never_ticked ... ok
[INFO] [stdout] test nodes::fold::tests::count ... ok
[INFO] [stdout] test nodes::finally::tests::finally_fires_after_graph_stops ... ok
[INFO] [stdout] test nodes::fold::tests::fold_collect_works ... ok
[INFO] [stdout] test nodes::fold::tests::fold_sum_works ... ok
[INFO] [stdout] test nodes::graph_state::tests::ticked_at_elapsed_emits_elapsed_time ... ok
[INFO] [stdout] test nodes::demux::tests::demux_works ... ok
[INFO] [stdout] test nodes::graph_state::tests::ticked_at_emits_graph_time ... ok
[INFO] [stdout] test nodes::inspect::tests::inspect_observes_and_passes_through ... ok
[INFO] [stdout] test nodes::iterator_stream::tests::iterator_stream_groups_same_timestamp_into_burst ... ok
[INFO] [stdout] test nodes::iterator_stream::tests::simple_iterator_emits_in_order ... ok
[INFO] [stdout] test nodes::limit::tests::emits_correct_values_up_to_limit ... ok
[INFO] [stdout] test nodes::limit::tests::stops_after_n_ticks_with_run_forever ... ok
[INFO] [stdout] test nodes::map::tests::map_stream_works ... ok
[INFO] [stdout] test nodes::map_filter::tests::emits_when_function_returns_true ... ok
[INFO] [stdout] test nodes::map_filter::tests::suppresses_when_function_returns_false ... ok
[INFO] [stdout] test nodes::merge::tests::merge_last_ticked_value_wins ... ok
[INFO] [stdout] test nodes::merge::tests::merge_emits_from_both_streams ... ok
[INFO] [stdout] test nodes::never::tests::never_does_not_trigger_downstream ... ok
[INFO] [stdout] test nodes::node_flow::tests::node_delay_shifts_ticks ... ok
[INFO] [stdout] test nodes::node_flow::tests::node_delay_zero_passes_immediately ... ok
[INFO] [stdout] test nodes::node_flow::tests::node_feedback_sends_signal ... ok
[INFO] [stdout] test nodes::node_flow::tests::node_filter_gates_ticks ... ok
[INFO] [stdout] test nodes::node_flow::tests::node_limit_caps_ticks ... ok
[INFO] [stdout] test nodes::node_flow::tests::node_throttle_suppresses_fast_ticks ... ok
[INFO] [stdout] test nodes::node_flow::tests::node_throttle_zero_interval_passes_all ... ok
[INFO] [stdout] test nodes::print::tests::print_passes_through_values ... ok
[INFO] [stdout] test nodes::producer::tests::produce_calls_closure_each_tick ... ok
[INFO] [stdout] test nodes::producer::tests::produce_emits_closure_return_value ... ok
[INFO] [stdout] test nodes::tests::collapse_skips_empty_iterator ... ok
[INFO] [stdout] test nodes::sample::tests::sample_works ... ok
[INFO] [stdout] test nodes::tests::node_count_via_dyn_node ... ok
[INFO] [stdout] test nodes::tests::demux_it_routes_items_from_iterable_stream ... ok
[INFO] [stdout] test nodes::tests::node_into_graph_via_dyn_node ... ok
[INFO] [stdout] test nodes::tests::node_produce_via_dyn_node ... ok
[INFO] [stdout] test nodes::tests::node_ticked_at_elapsed_via_dyn_node ... ok
[INFO] [stdout] test nodes::tests::node_ticked_at_via_dyn_node ... ok
[INFO] [stdout] test nodes::tests::not_inverts_bool_stream ... ok
[INFO] [stdout] test nodes::tests::split_decomposes_tuple_stream ... ok
[INFO] [stdout] test nodes::tests::stream_count_via_dyn_stream ... ok
[INFO] [stdout] test nodes::tests::stream_into_graph_via_dyn_stream ... ok
[INFO] [stdout] test nodes::tests::stream_produce_via_dyn_stream ... ok
[INFO] [stdout] test nodes::tests::stream_ticked_at_elapsed_via_dyn_stream ... ok
[INFO] [stdout] test nodes::tests::stream_ticked_at_via_dyn_stream ... ok
[INFO] [stdout] test nodes::throttle::tests::throttle_exact_interval_emits ... ok
[INFO] [stdout] test nodes::throttle::tests::throttle_suppresses_fast_ticks ... ok
[INFO] [stdout] test nodes::throttle::tests::throttle_zero_interval_passes_all ... ok
[INFO] [stdout] test nodes::timed::tests::timed_historical ... ok
[INFO] [stdout] test channel::message::tests::error_never_equals_itself ... ok
[INFO] [stdout] test bencher::tests::bench_trigger_cycle_kill_returns_error ... ok
[INFO] [stdout] test bencher::tests::signal_from_u8_invalid_panics - should panic ... ok
[INFO] [stdout] test channel::message::tests::serde_roundtrip_error_variant ... ok
[INFO] [stdout] test nodes::consumer::tests::try_for_each_error_propagates ... ok
[INFO] [stdout] test nodes::finally::tests::finally_error_propagates ... ok
[INFO] [stdout] test nodes::trimap::tests::trimap_all_active ... ok
[INFO] [stdout] test nodes::iterator_stream::tests::simple_iterator_errors_on_descending_timestamps ... ok
[INFO] [stdout] test nodes::trimap::tests::trimap_passive_does_not_trigger ... ok
[INFO] [stdout] test nodes::iterator_stream::tests::simple_iterator_errors_on_duplicate_timestamps ... ok
[INFO] [stdout] test nodes::try_bimap::tests::try_bimap_error ... ok
[INFO] [stdout] test nodes::try_bimap::tests::try_bimap_success ... ok
[INFO] [stdout] test nodes::try_bimap::tests::try_bimap_passive_does_not_trigger ... ok
[INFO] [stdout] test nodes::try_map::tests::try_map_success ... ok
[INFO] [stdout] test nodes::try_trimap::tests::try_trimap_success ... ok
[INFO] [stdout] test nodes::try_trimap::tests::try_trimap_passive_does_not_trigger ... ok
[INFO] [stdout] test nodes::window::tests::window_stream_works ... ok
[INFO] [stdout] test nodes::async_io::tests::produce_async_mid_stream_error ... ok
[INFO] [stdout] test queue::hash_by_ref::tests::clone_of_rc_is_equal ... ok
[INFO] [stdout] test queue::hash_by_ref::tests::different_rc_with_equal_content_are_not_equal ... ok
[INFO] [stdout] test nodes::with_time::tests::timestamps_match_graph_time ... ok
[INFO] [stdout] test queue::time_queue::tests::duplicate_value ... ok
[INFO] [stdout] test queue::time_queue::tests::duplicate_time ... ok
[INFO] [stdout] test queue::time_queue::tests::duplicates ... ok
[INFO] [stdout] test queue::time_queue::tests::pending ... ok
[INFO] [stdout] test queue::time_queue::tests::sorted ... ok
[INFO] [stdout] test queue::hash_by_ref::tests::same_rc_are_equal_and_same_hash ... ok
[INFO] [stdout] test queue::value_at::tests::default_is_zero_time_and_default_value ... ok
[INFO] [stdout] test queue::value_at::tests::different_time_not_equal ... ok
[INFO] [stdout] test queue::value_at::tests::different_value_not_equal ... ok
[INFO] [stdout] test queue::value_at::tests::equal_value_at_instances_are_equal ... ok
[INFO] [stdout] test time::tests::arithmetic_add_sub_mul ... ok
[INFO] [stdout] test time::tests::from_f64_truncates ... ok
[INFO] [stdout] test time::tests::from_i64_converts ... ok
[INFO] [stdout] test time::tests::from_u64_roundtrip ... ok
[INFO] [stdout] test time::tests::from_naive_datetime_roundtrips ... ok
[INFO] [stdout] test time::tests::into_f64_converts ... ok
[INFO] [stdout] test time::tests::kdb_epoch_offset_lands_on_year_2000 ... ok
[INFO] [stdout] test time::tests::kdb_max_converts_to_i64_max ... ok
[INFO] [stdout] test time::tests::kdb_timestamp_roundtrip ... ok
[INFO] [stdout] test time::tests::duration_roundtrip ... ok
[INFO] [stdout] test time::tests::from_u128_truncates_to_u64 ... ok
[INFO] [stdout] test time::tests::ordering ... ok
[INFO] [stdout] test time::tests::mul_i32_and_i64 ... ok
[INFO] [stdout] test types::tests::burst_empty ... ok
[INFO] [stdout] test time::tests::pretty_returns_formatted_string ... ok
[INFO] [stdout] test types::tests::burst_single ... ok
[INFO] [stdout] test types::tests::dep_active_is_active ... ok
[INFO] [stdout] test types::tests::burst_multiple ... ok
[INFO] [stdout] test types::tests::debug_for_dyn_stream_uses_type_name ... ok
[INFO] [stdout] test types::tests::dep_passive_is_not_active ... ok
[INFO] [stdout] test types::tests::into_node_creates_rc_dyn_node ... ok
[INFO] [stdout] test types::tests::into_stream_creates_rc_dyn_stream ... ok
[INFO] [stdout] test types::tests::graph_state_new_starts_at_zero ... ok
[INFO] [stdout] test types::tests::rc_stream_as_upstream_nodes ... ok
[INFO] [stdout] test types::tests::rc_node_as_upstream_nodes ... ok
[INFO] [stdout] test types::tests::upstreams_new_stores_vecs ... ok
[INFO] [stdout] test types::tests::vec_as_upstream_nodes ... ok
[INFO] [stdout] test types::tests::upstreams_none_is_empty ... ok
[INFO] [stdout] test graph::tests::error_context_shows_graph_structure ... ok
[INFO] [stdout] test nodes::try_map::tests::try_map_error ... ok
[INFO] [stdout] test nodes::try_trimap::tests::try_trimap_error ... ok
[INFO] [stdout] test nodes::timed::tests::timed_realtime ... ok
[INFO] [stdout] test nodes::async_io::tests::async_io_works ... ok
[INFO] [stdout] test nodes::tick::tests::tick_node_works_in_realtime ... ok
[INFO] [stdout] test nodes::graph_node::tests::graph_node_works ... ok
[INFO] [stdout] test nodes::always::tests::test_always_tick ... ok
[INFO] [stdout] test nodes::buffer::tests::buffer_stream_works ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 189 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 3.00s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests wingfoil
[INFO] [stdout] 
[INFO] [stdout] running 23 tests
[INFO] [stdout] test src/lib.rs - (line 226) ... ignored
[INFO] [stdout] test src/lib.rs - (line 257) ... ignored
[INFO] [stdout] test src/lib.rs - (line 289) ... ignored
[INFO] [stdout] test src/lib.rs - (line 314) ... ignored
[INFO] [stdout] test src/lib.rs - (line 326) ... ignored
[INFO] [stdout] test src/lib.rs - (line 345) ... ignored
[INFO] [stdout] test src/lib.rs - (line 380) ... ignored
[INFO] [stdout] test src/lib.rs - (line 550) ... ignored
[INFO] [stdout] test src/nodes/async_io.rs - nodes::async_io::produce_async (line 283) ... ignored
[INFO] [stdout] test src/lib.rs - (line 773) - compile ... ok
[INFO] [stdout] test src/lib.rs - (line 433) ... ok
[INFO] [stdout] test src/nodes/feedback.rs - nodes::feedback::feedback (line 98) ... ok
[INFO] [stdout] test src/nodes/mod.rs - nodes::NodeOperators::count (line 219) ... ok
[INFO] [stdout] test src/nodes/mod.rs - nodes::NodeOperators::produce (line 249) ... ok
[INFO] [stdout] test src/lib.rs - (line 653) ... ok
[INFO] [stdout] test src/nodes/mod.rs - nodes::NodeOperators::ticked_at (line 229) ... ok
[INFO] [stdout] test src/nodes/mod.rs - nodes::NodeOperators::ticked_at_elapsed (line 239) ... ok
[INFO] [stdout] test src/nodes/mod.rs - nodes::NodeOperators::run (line 259) ... ok
[INFO] [stdout] test src/nodes/mod.rs - nodes::StreamOperators::with_time (line 543) ... ok
[INFO] [stdout] test src/types.rs - types::burst (line 33) ... ok
[INFO] [stdout] test src/lib.rs - (line 605) ... ok
[INFO] [stdout] test src/lib.rs - (line 478) ... ok
[INFO] [stdout] test src/lib.rs - (line 202) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 14 passed; 0 failed; 9 ignored; 0 measured; 0 filtered out; finished in 3.00s
[INFO] [stdout] 
[INFO] [stdout] all doctests ran in 4.06s; merged doctests compilation took 1.04s
[INFO] running `Command { std: "docker" "inspect" "cdd3c8a1275aa39f6355a3fc5670978a5722575d4573dabbc825a7ebdb2678c5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "cdd3c8a1275aa39f6355a3fc5670978a5722575d4573dabbc825a7ebdb2678c5", kill_on_drop: false }`
[INFO] [stdout] cdd3c8a1275aa39f6355a3fc5670978a5722575d4573dabbc825a7ebdb2678c5