[INFO] fetching crate wingfoil-python 9.0.0...
[INFO] testing wingfoil-python-9.0.0 against 1.100.0-beta.1 for beta-1.100-2
[INFO] extracting crate wingfoil-python 9.0.0 into /workspace/builds/worker-7-tc2/source
[INFO] started tweaking crates.io crate wingfoil-python 9.0.0
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate wingfoil-python 9.0.0
[INFO] tweaked toml for crates.io crate wingfoil-python 9.0.0 written to /workspace/builds/worker-7-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate wingfoil-python 9.0.0 on toolchain 1.100.0-beta.1
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.100.0-beta.1" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate wingfoil-python 9.0.0 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" "+1.100.0-beta.1" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/target:/opt/rustwide/target:rw,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" "-m" "1610612736" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:3111399a4047eeb3a02b7a90e478d715f38a8c6669b5c4b49d30a17385265909" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] fe7d079ee53c02d6c5da9e069b2fb357264098bb516e3131c10bf6e0ffc92113
[INFO] running `Command { std: "docker" "start" "fe7d079ee53c02d6c5da9e069b2fb357264098bb516e3131c10bf6e0ffc92113", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "fe7d079ee53c02d6c5da9e069b2fb357264098bb516e3131c10bf6e0ffc92113", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-w" "/opt/rustwide/workdir" "--user" "0:0" "fe7d079ee53c02d6c5da9e069b2fb357264098bb516e3131c10bf6e0ffc92113" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "fe7d079ee53c02d6c5da9e069b2fb357264098bb516e3131c10bf6e0ffc92113", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-w" "/opt/rustwide/workdir" "--user" "0:0" "fe7d079ee53c02d6c5da9e069b2fb357264098bb516e3131c10bf6e0ffc92113" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling target-lexicon v0.13.5
[INFO] [stderr]    Compiling crossbeam-epoch v0.9.20
[INFO] [stderr]    Compiling unicode-segmentation v1.13.2
[INFO] [stderr]    Compiling raw-cpuid v10.7.0
[INFO] [stderr]    Compiling formato v0.3.0
[INFO] [stderr]    Compiling tinyvec v1.12.0
[INFO] [stderr]    Compiling syn v2.0.117
[INFO] [stderr]    Compiling syn v3.0.3
[INFO] [stderr]    Compiling crossbeam-deque v0.8.6
[INFO] [stderr]    Compiling convert_case v0.10.0
[INFO] [stderr]    Compiling crossbeam v0.8.4
[INFO] [stderr]    Compiling pyo3-build-config v0.29.1
[INFO] [stderr]    Compiling quanta v0.9.3
[INFO] [stderr]    Compiling pyo3-ffi v0.29.1
[INFO] [stderr]    Compiling pyo3 v0.29.1
[INFO] [stderr]    Compiling serde_derive v1.0.229
[INFO] [stderr]    Compiling pyo3-macros-backend v0.29.1
[INFO] [stderr]    Compiling serde v1.0.229
[INFO] [stderr]    Compiling derive_more-impl v2.1.1
[INFO] [stderr]    Compiling wingfoil-derive v9.0.0
[INFO] [stderr]    Compiling derive-new v0.7.0
[INFO] [stderr]    Compiling wingfoil-python-derive v9.0.0
[INFO] [stderr]    Compiling pyo3-macros v0.29.1
[INFO] [stderr]    Compiling derive_more v2.1.1
[INFO] [stderr]    Compiling wingfoil v9.0.0
[INFO] [stderr]    Compiling wingfoil-python v9.0.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 10.21s
[INFO] running `Command { std: "docker" "inspect" "fe7d079ee53c02d6c5da9e069b2fb357264098bb516e3131c10bf6e0ffc92113", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-w" "/opt/rustwide/workdir" "--user" "0:0" "fe7d079ee53c02d6c5da9e069b2fb357264098bb516e3131c10bf6e0ffc92113" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling pyo3 v0.29.1
[INFO] [stderr]    Compiling wingfoil-python v9.0.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 5.38s
[INFO] running `Command { std: "docker" "inspect" "fe7d079ee53c02d6c5da9e069b2fb357264098bb516e3131c10bf6e0ffc92113", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-w" "/opt/rustwide/workdir" "--user" "0:0" "fe7d079ee53c02d6c5da9e069b2fb357264098bb516e3131c10bf6e0ffc92113" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.12s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/build/wingfoil-python/9ab0dc483a42ce85/out/wingfoil_python-9ab0dc483a42ce85)
[INFO] [stdout] 
[INFO] [stdout] running 86 tests
[INFO] [stdout] test element::tests::default_is_empty ... ok
[INFO] [stdout] test element::tests::an_empty_element_hands_back_none_rather_than_panicking ... ok
[INFO] [stdout] test element::tests::option_propagates_a_wrong_typed_inner ... ok
[INFO] [stdout] test element::tests::f32_round_trips ... ok
[INFO] [stdout] test element::tests::arithmetic_delegates_to_python ... ok
[INFO] [stdout] test element::tests::a_negative_value_does_not_silently_become_unsigned ... ok
[INFO] [stdout] test element::tests::extract_wrong_type_errors ... ok
[INFO] [stdout] test element::tests::option_maps_none_to_python_none ... ok
[INFO] [stdout] test element::tests::bytes_erase_to_python_bytes_not_a_list ... ok
[INFO] [stdout] test graph::tests::build_dataframe_joins_synchronous_columns ... ok
[INFO] [stdout] test element::tests::scalar_round_trips ... ok
[INFO] [stdout] test element::tests::empty_bytes_round_trip ... ok
[INFO] [stdout] test graph::tests::build_dataframe_skips_columns_that_produced_nothing ... ok
[INFO] [stdout] test graph::tests::build_dataframe_with_nothing_to_join_is_empty ... ok
[INFO] [stdout] test element::tests::equality_uses_python_semantics ... ok
[INFO] [stdout] test element::tests::flows_through_next_graph ... ok
[INFO] [stdout] test graph::tests::drop_small_change_non_bool_return_aborts_run ... ok
[INFO] [stdout] test graph::tests::bimap_exception_aborts_run ... ok
[INFO] [stdout] test graph::tests::counter_source_ticks_and_reads_final ... ok
[INFO] [stdout] test graph::tests::buffer_flushes_a_list_at_capacity ... ok
[INFO] [stdout] test graph::tests::bimap_combines_two_inputs ... ok
[INFO] [stdout] test graph::tests::constant_maps_via_python_callable ... ok
[INFO] [stdout] test graph::tests::drop_small_change_predicate_error_aborts_run ... ok
[INFO] [stdout] test element::tests::integer_widths_round_trip ... ok
[INFO] [stdout] test graph::tests::build_dataframe_outer_joins_asynchronous_columns ... ok
[INFO] [stdout] test graph::tests::difference_of_counter_is_one ... ok
[INFO] [stdout] test graph::tests::count_ignores_values ... ok
[INFO] [stdout] test graph::tests::accumulate_grows_a_list ... ok
[INFO] [stdout] test graph::tests::dataframe_builds_on_last_cycle ... ok
[INFO] [stdout] test graph::tests::inspect_exception_aborts_run ... ok
[INFO] [stdout] test graph::tests::map_callable_error_aborts_run ... ok
[INFO] [stdout] test graph::tests::drop_small_change_compares_against_last_emitted ... ok
[INFO] [stdout] test graph::tests::filter_map_keeps_non_none ... ok
[INFO] [stdout] test graph::tests::inspect_taps_and_passes_through ... ok
[INFO] [stdout] test graph::tests::collect_gathers_time_value_tuples ... ok
[INFO] [stdout] test graph::tests::fold_exception_aborts_run ... ok
[INFO] [stdout] test graph::tests::neg_of_a_bool_is_an_int_not_a_logical_negation ... ok
[INFO] [stdout] test graph::tests::graph_reruns_and_resets ... ok
[INFO] [stdout] test graph::tests::python_custom_node_error_aborts_run ... ok
[INFO] [stdout] test graph::tests::filter_by_python_predicate ... ok
[INFO] [stdout] test graph::tests::limit_caps_ticks ... ok
[INFO] [stdout] test graph::tests::neg_arithmetically_negates_an_integer ... ok
[INFO] [stdout] test graph::tests::neg_of_a_float_negates_where_the_rust_op_would_not_compile ... ok
[INFO] [stdout] test graph::tests::filter_none_drops_python_none ... ok
[INFO] [stdout] test graph::tests::distinct_suppresses_consecutive_duplicates ... ok
[INFO] [stdout] test graph::tests::sum_of_non_numeric_aborts_run ... ok
[INFO] [stdout] test graph::tests::mean_is_cumulative ... ok
[INFO] [stdout] test graph::tests::fold_accumulates_and_resets_on_rerun ... ok
[INFO] [stdout] test graph::tests::skip_suppresses_the_first_n ... ok
[INFO] [stdout] test latency::tests::a_record_addresses_its_stamps_by_name ... ok
[INFO] [stdout] test graph::tests::filter_value_keeps_on_predicate ... ok
[INFO] [stdout] test latency::tests::a_record_with_the_wrong_stage_count_is_rejected ... ok
[INFO] [stdout] test graph::tests::python_custom_node_sums_upstreams ... ok
[INFO] [stdout] test latency::tests::a_same_cycle_hop_is_tallied_rather_than_measured_as_zero ... ok
[INFO] [stdout] test latency::tests::a_short_header_leaves_the_missing_stamps_unset ... ok
[INFO] [stdout] test latency::tests::an_unstamped_hop_says_so_rather_than_reporting_a_zero ... ok
[INFO] [stdout] test graph::tests::python_custom_node_can_stay_quiet ... ok
[INFO] [stdout] test latency::tests::an_unusable_stage_list_is_rejected ... ok
[INFO] [stdout] test graph::tests::pairwise_is_quiet_until_a_previous_string_exists ... ok
[INFO] [stdout] test latency::tests::clearing_drops_the_previous_runs_samples ... ok
[INFO] [stdout] test graph::tests::reduce_runs_from_first_value ... ok
[INFO] [stdout] test latency::tests::a_report_over_an_unstamped_stream_aborts_the_run ... ok
[INFO] [stdout] test latency::tests::stamping_an_unknown_stage_aborts_the_run ... ok
[INFO] [stdout] test latency::tests::stamps_round_trip_through_the_wire_header ... ok
[INFO] [stdout] test latency::tests::the_report_aggregates_the_hop_between_stages ... ok
[INFO] [stdout] test latency::tests::the_report_renders_one_row_per_hop_plus_the_total ... ok
[INFO] [stdout] test graph::tests::step_by_emits_the_first_then_every_nth_value ... ok
[INFO] [stdout] test graph::tests::split_decomposes_tuples ... ok
[INFO] [stdout] test graph::tests::with_time_pairs_nanos_and_value ... ok
[INFO] [stdout] test graph::tests::values_source_feeds_downstream_ops ... ok
[INFO] [stdout] test graph::tests::skip_while_latches_with_exact_tick_times ... ok
[INFO] [stdout] test graph::tests::take_while_latches_after_the_first_rejection ... ok
[INFO] [stdout] test graph::tests::values_replays_a_sequence ... ok
[INFO] [stdout] test latency::tests::stamping_a_non_traced_value_aborts_the_run ... ok
[INFO] [stdout] test graph::tests::enumerate_splits_indices_from_string_values ... ok
[INFO] [stdout] test latency::tests::the_precise_clock_separates_stages_within_one_cycle ... ok
[INFO] [stdout] test graph::tests::merge_all_earliest_supplied_wins_ties ... ok
[INFO] [stdout] test graph::tests::sum_is_cumulative ... ok
[INFO] [stdout] test latency::tests::a_report_over_a_graph_measures_the_stamped_hop ... ok
[INFO] [stdout] test latency::tests::stamping_writes_the_wall_clock_in_stage_order ... ok
[INFO] [stdout] test statistics::tests::non_numeric_input_names_the_operator ... ok
[INFO] [stdout] test latency::tests::every_member_of_a_burst_is_stamped ... ok
[INFO] [stdout] test statistics::tests::ewma_per_tick_matches_the_closed_form ... ok
[INFO] [stdout] test statistics::tests::median_dispatch_reaches_the_windowed_engine_op ... ok
[INFO] [stdout] test statistics::tests::weighting_dispatch_selects_the_time_weighted_twin ... ok
[INFO] [stdout] test statistics::tests::window_dispatch_covers_count_time_and_unbounded ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 86 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.04s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/plugin_seam.rs (/opt/rustwide/target/debug/build/wingfoil-python/8e09c3d08890dc88/out/plugin_seam-8e09c3d08890dc88)
[INFO] [stdout] 
[INFO] [stdout] running 23 tests
[INFO] [stdout] test free_fn_pyadapter_generates_source_and_sink_functions ... ok
[INFO] [stdout] test pyadapter_sink_macro_compiles_in_an_external_crate ... ok
[INFO] [stdout] test fallible_pyadapter_generates_pyresult_functions ... ok
[INFO] [stdout] test pygraph_shapes_expand_in_an_external_crate ... ok
[INFO] [stdout] test pyop_four_input_expands_in_an_external_crate ... ok
[INFO] [stdout] test pyop_generates_a_function_in_an_external_crate ... ok
[INFO] [stdout] test pyop_three_input_and_tuple_cfg_expand_in_an_external_crate ... ok
[INFO] [stdout] test a_user_struct_round_trips_across_the_boundary ... ok
[INFO] [stdout] test custom_op_error_aborts_run ... ok
[INFO] [stdout] test a_malformed_user_struct_aborts_the_run_with_context ... ok
[INFO] [stdout] test free_fn_pyadapter_wires_end_to_end ... ok
[INFO] [stdout] test pyadapter_sink_seam_from_an_external_crate ... ok
[INFO] [stdout] test pygraph_exposes_a_subgraph_from_an_external_crate ... ok
[INFO] [stdout] test pyop_supports_two_input_ops_in_an_external_crate ... ok
[INFO] [stdout] test external_crate_can_wire_a_custom_op ... ok
[INFO] [stdout] test pyadapter_burst_source_from_an_external_crate ... ok
[INFO] [stdout] test pyadapter_source_from_an_external_crate ... ok
[INFO] [stdout] test pyop_supports_stateful_ops_in_an_external_crate ... ok
[INFO] [stdout] test a_user_struct_flows_through_a_typed_op ... ok
[INFO] [stdout] test fallible_pyadapter_wiring_succeeds_and_fails_as_written ... ok
[INFO] [stdout] test wire_op4_seam_runs_four_inputs_from_an_external_crate ... ok
[INFO] [stdout] test wire_op3_seam_runs_three_inputs_from_an_external_crate ... ok
[INFO] [stdout] test pygraph_multi_output_erases_each_element ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 23 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests wingfoil_python
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test src/macros.rs - macros (line 16) ... ignored
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 1 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] all doctests ran in 0.12s; merged doctests compilation took 0.11s
[INFO] running `Command { std: "docker" "inspect" "fe7d079ee53c02d6c5da9e069b2fb357264098bb516e3131c10bf6e0ffc92113", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "fe7d079ee53c02d6c5da9e069b2fb357264098bb516e3131c10bf6e0ffc92113", kill_on_drop: false }`
[INFO] [stdout] fe7d079ee53c02d6c5da9e069b2fb357264098bb516e3131c10bf6e0ffc92113
