[INFO] fetching crate siftr 0.3.1...
[INFO] testing siftr-0.3.1 against 1.100.0-beta.1+cargoflags=--release for beta-release-1.100-2
[INFO] extracting crate siftr 0.3.1 into /workspace/builds/worker-5-tc2/source
[INFO] started tweaking crates.io crate siftr 0.3.1
[INFO] finished tweaking crates.io crate siftr 0.3.1
[INFO] tweaked toml for crates.io crate siftr 0.3.1 written to /workspace/builds/worker-5-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate siftr 0.3.1 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 siftr 0.3.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" "+1.100.0-beta.1" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr] warning: `package.homepage` is redundant with `package.repository`
[INFO] [stderr]   --> Cargo.toml:56:12
[INFO] [stderr]    |
[INFO] [stderr] 56 | homepage = "https://github.com/dpep/siftr"
[INFO] [stderr]    |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] ...
[INFO] [stderr] 62 | repository = "https://github.com/dpep/siftr"
[INFO] [stderr]    |              -------------------------------
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::redundant_homepage` is set to `warn` by default
[INFO] [stderr] help: consider removing `package.homepage`
[INFO] [stderr] warning: `siftr` (manifest) generated 1 warning
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-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:77db811e55d90add9212f6832c23229e3de5f1c1f9905e8cb07319ea15df73ac" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] 6fc040bcdeae4809b10a8a55d2c201a0e9750f0657ca9950feb6aa67a817bbfc
[INFO] running `Command { std: "docker" "start" "6fc040bcdeae4809b10a8a55d2c201a0e9750f0657ca9950feb6aa67a817bbfc", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "6fc040bcdeae4809b10a8a55d2c201a0e9750f0657ca9950feb6aa67a817bbfc", 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" "6fc040bcdeae4809b10a8a55d2c201a0e9750f0657ca9950feb6aa67a817bbfc" "/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" "6fc040bcdeae4809b10a8a55d2c201a0e9750f0657ca9950feb6aa67a817bbfc", 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" "6fc040bcdeae4809b10a8a55d2c201a0e9750f0657ca9950feb6aa67a817bbfc" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "build" "--frozen" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr] warning: `package.homepage` is redundant with `package.repository`
[INFO] [stderr]   --> Cargo.toml:56:12
[INFO] [stderr]    |
[INFO] [stderr] 56 | homepage = "https://github.com/dpep/siftr"
[INFO] [stderr]    |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] ...
[INFO] [stderr] 62 | repository = "https://github.com/dpep/siftr"
[INFO] [stderr]    |              -------------------------------
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::redundant_homepage` is set to `warn` by default
[INFO] [stderr] help: consider removing `package.homepage`
[INFO] [stderr] warning: `siftr` (manifest) generated 1 warning
[INFO] [stderr]    Compiling find-msvc-tools v0.1.12
[INFO] [stderr]    Compiling rustix v1.1.5
[INFO] [stderr]    Compiling errno v0.3.14
[INFO] [stderr]    Compiling linux-raw-sys v0.12.1
[INFO] [stderr]    Compiling syn v3.0.5
[INFO] [stderr]    Compiling getrandom v0.4.3
[INFO] [stderr]    Compiling hashlink v0.12.2
[INFO] [stderr]    Compiling toml_parser v1.1.3+spec-1.1.0
[INFO] [stderr]    Compiling toml_datetime v1.1.1+spec-1.1.0
[INFO] [stderr]    Compiling serde_spanned v1.1.1
[INFO] [stderr]    Compiling clap_builder v4.6.7
[INFO] [stderr]    Compiling fallible-streaming-iterator v0.1.9
[INFO] [stderr]    Compiling fallible-iterator v0.3.0
[INFO] [stderr]    Compiling smallvec v1.16.1
[INFO] [stderr]    Compiling serde_json v1.0.151
[INFO] [stderr]    Compiling signal-hook-registry v1.4.8
[INFO] [stderr]    Compiling cc v1.4.6
[INFO] [stderr]    Compiling signal-hook v0.3.18
[INFO] [stderr]    Compiling toml v1.1.6+spec-1.1.0
[INFO] [stderr]    Compiling libsqlite3-sys v0.38.2
[INFO] [stderr]    Compiling clap_derive v4.6.7
[INFO] [stderr]    Compiling serde_derive v1.0.229
[INFO] [stderr]    Compiling tempfile v3.27.0
[INFO] [stderr]    Compiling clap v4.6.7
[INFO] [stderr]    Compiling serde v1.0.229
[INFO] [stderr]    Compiling rusqlite v0.40.2
[INFO] [stderr]    Compiling siftr v0.3.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 2m 07s
[INFO] running `Command { std: "docker" "inspect" "6fc040bcdeae4809b10a8a55d2c201a0e9750f0657ca9950feb6aa67a817bbfc", 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" "6fc040bcdeae4809b10a8a55d2c201a0e9750f0657ca9950feb6aa67a817bbfc" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen" "--no-run" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr] warning: `package.homepage` is redundant with `package.repository`
[INFO] [stderr]   --> Cargo.toml:56:12
[INFO] [stderr]    |
[INFO] [stderr] 56 | homepage = "https://github.com/dpep/siftr"
[INFO] [stderr]    |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] ...
[INFO] [stderr] 62 | repository = "https://github.com/dpep/siftr"
[INFO] [stderr]    |              -------------------------------
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::redundant_homepage` is set to `warn` by default
[INFO] [stderr] help: consider removing `package.homepage`
[INFO] [stderr] warning: `siftr` (manifest) generated 1 warning
[INFO] [stderr]    Compiling siftr v0.3.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 19.28s
[INFO] running `Command { std: "docker" "inspect" "6fc040bcdeae4809b10a8a55d2c201a0e9750f0657ca9950feb6aa67a817bbfc", 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" "6fc040bcdeae4809b10a8a55d2c201a0e9750f0657ca9950feb6aa67a817bbfc" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen" "--release", kill_on_drop: false }`
[INFO] [stderr] warning: `package.homepage` is redundant with `package.repository`
[INFO] [stderr]   --> Cargo.toml:56:12
[INFO] [stderr]    |
[INFO] [stderr] 56 | homepage = "https://github.com/dpep/siftr"
[INFO] [stderr]    |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] ...
[INFO] [stderr] 62 | repository = "https://github.com/dpep/siftr"
[INFO] [stderr]    |              -------------------------------
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::redundant_homepage` is set to `warn` by default
[INFO] [stderr] help: consider removing `package.homepage`
[INFO] [stderr] warning: `siftr` (manifest) generated 1 warning
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 0.13s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/release/build/siftr/a8451bb17aab8389/out/siftr-a8451bb17aab8389)
[INFO] [stdout] 
[INFO] [stdout] running 111 tests
[INFO] [stdout] test aggregate::tests::exemplars_are_bounded_and_start_with_the_first_occurrences ... ok
[INFO] [stdout] test aggregate::tests::attributes_occurrences_and_measures_to_scopes ... ok
[INFO] [stdout] test aggregate::tests::exemplar_text_is_capped_on_a_char_boundary ... ok
[INFO] [stdout] test aggregate::histogram::tests::buckets_contain_their_values ... ok
[INFO] [stdout] test aggregate::tests::phases_round_trip_through_scope_ids ... ok
[INFO] [stdout] test analyze::tests::lines_that_differ_only_in_values_are_one_behavior ... ok
[INFO] [stdout] test baseline::tests::evidence_grows_with_quiet_runs ... ok
[INFO] [stdout] test context::tests::quotes_only_what_the_shell_would_split ... ok
[INFO] [stdout] test interpret::cargo::tests::a_summary_that_isnt_libtests_shape_is_left_alone ... ok
[INFO] [stdout] test behavior::tests::ids_and_kinds_round_trip_through_text ... ok
[INFO] [stdout] test baseline::tests::errors_outside_examples_that_persist_do_not_skip_every_run ... ok
[INFO] [stdout] test aggregate::tests::summarizes_durations ... ok
[INFO] [stdout] test interpret::cargo::tests::a_tests_identity_is_its_name_and_its_verdict_is_an_outcome ... ok
[INFO] [stdout] test baseline::tests::the_current_run_is_incomplete_only_for_examples_it_skipped ... ok
[INFO] [stdout] test interpret::cargo::tests::cargos_notice_that_a_target_failed_is_dropped ... ok
[INFO] [stdout] test interpret::cargo::tests::stderr_is_cargos_not_libtests ... ok
[INFO] [stdout] test baseline::tests::a_recent_run_is_skipped_only_for_examples_it_skipped_that_this_run_ran ... ok
[INFO] [stdout] test baseline::tests::a_run_without_a_test_summary_compares_with_every_recent_run ... ok
[INFO] [stdout] test interpret::cargo::tests::only_libtests_own_lines_are_claimed ... ok
[INFO] [stdout] test interpret::cargo::tests::every_test_binary_counts_into_one_summary ... ok
[INFO] [stdout] test interpret::generic::tests::a_log_show_column_header_is_not_a_behavior ... ok
[INFO] [stdout] test interpret::rails::tests::a_blocks_lines_scope_to_its_endpoint_when_no_example_encloses_them ... ok
[INFO] [stdout] test interpret::rails::tests::a_completed_line_without_its_request_is_a_log ... ok
[INFO] [stdout] test interpret::generic::tests::detects_error_levels_only_as_words_near_the_start ... ok
[INFO] [stdout] test interpret::rails::tests::a_request_block_is_one_behavior_carrying_the_reported_query_count ... ok
[INFO] [stdout] test interpret::generic::tests::takes_the_duration_from_the_first_duration_slot ... ok
[INFO] [stdout] test interpret::rails::tests::without_a_reported_count_a_request_counts_its_queries_but_not_transactions ... ok
[INFO] [stdout] test behavior::tests::behavior_ids_are_pinned ... ok
[INFO] [stdout] test interpret::rails::tests::query_lines_key_on_name_and_statement_without_binds ... ok
[INFO] [stdout] test context::tests::a_credential_in_the_command_is_masked_and_still_a_stable_key ... ok
[INFO] [stdout] test interpret::resources::tests::one_run_level_behavior_carries_every_measure_once ... ok
[INFO] [stdout] test interpret::resources::tests::the_behavior_is_the_same_however_much_the_run_burned ... ok
[INFO] [stdout] test interpret::resources::tests::the_evidence_line_reads_without_a_unit_lookup ... ok
[INFO] [stdout] test interpret::resources::tests::what_was_recorded_reads_back_unchanged ... ok
[INFO] [stdout] test interpret::resources::tests::typical_usage_is_the_median_of_each_field ... ok
[INFO] [stdout] test behavior::tests::kind_and_template_both_distinguish ... ok
[INFO] [stdout] test interpret::rspec::tests::an_unnamed_examples_identity_survives_what_rspec_writes_for_it ... ok
[INFO] [stdout] test interpret::rspec::tests::an_error_outside_examples_is_an_unscoped_failed_exception ... ok
[INFO] [stdout] test interpret::fixtures::a_failure_carries_its_exception_class_scoped_to_the_example ... FAILED
[INFO] [stdout] test analyze::tests::interpreters_claim_in_order_and_flush_at_finish ... ok
[INFO] [stdout] test interpret::fixtures::listener_offsets_land_on_log_line_starts ... FAILED
[INFO] [stdout] test baseline::tests::runs_that_skipped_the_same_examples_compare ... ok
[INFO] [stdout] test baseline::tests::ignores_runs_beyond_the_cap ... ok
[INFO] [stdout] test interpret::rspec::tests::lines_not_in_the_listener_shape_are_kept_as_logs ... ok
[INFO] [stdout] test interpret::rspec::tests::a_load_error_is_named_by_its_file_class_and_cause ... ok
[INFO] [stdout] test interpret::cargo::tests::a_failure_with_no_test_behind_it_is_left_alone ... ok
[INFO] [stdout] test interpret::rspec::tests::examples_sharing_a_file_and_description_are_one_behavior ... ok
[INFO] [stdout] test interpret::rspec::tests::inserting_an_example_above_another_keeps_both_ids ... ok
[INFO] [stdout] test interpret::fixtures::clean_runs_produce_identical_behaviors_scopes_and_counts ... FAILED
[INFO] [stdout] test interpret::rspec::tests::a_named_example_keeps_the_description_its_author_wrote ... ok
[INFO] [stdout] test normalize::hash::tests::fnv1a64_matches_reference_vectors ... ok
[INFO] [stdout] test normalize::recognize::tests::emails ... ok
[INFO] [stdout] test normalize::recognize::tests::epoch_seconds_stay_int ... ok
[INFO] [stdout] test normalize::recognize::tests::identifiers ... ok
[INFO] [stdout] test normalize::recognize::tests::network ... ok
[INFO] [stdout] test interpret::rspec::tests::log_lines_take_the_scope_of_the_example_whose_range_holds_their_first_byte ... ok
[INFO] [stdout] test interpret::rspec::tests::the_summary_carries_counts_and_fails_on_errors_outside_examples ... ok
[INFO] [stdout] test interpret::fixtures::n_plus_one_changes_only_the_show_example ... FAILED
[INFO] [stdout] test normalize::path::tests::package_names_drop_versions_platforms_and_revisions ... ok
[INFO] [stdout] test normalize::path::tests::roles_round_trip_through_text ... ok
[INFO] [stdout] test interpret::fixtures::only_log_lines_before_the_first_example_are_unscoped ... FAILED
[INFO] [stdout] test normalize::recognize::tests::numbers_and_units ... ok
[INFO] [stdout] test normalize::syslog::tests::spans ... ok
[INFO] [stdout] test normalize::syslog::tests::rejects_near_misses ... ok
[INFO] [stdout] test normalize::recognize::tests::timestamps ... ok
[INFO] [stdout] test normalize::recognize::tests::versions ... ok
[INFO] [stdout] test num::tests::rounds_floats_to_significant_figures ... ok
[INFO] [stdout] test observation::tests::stream_names_round_trip ... ok
[INFO] [stdout] test signal::rules::tests::error_vectors ... ok
[INFO] [stdout] test signal::rules::tests::frequency_vectors ... ok
[INFO] [stdout] test signal::tests::a_count_that_tracks_the_suite_size_is_demoted_below_the_examples_that_explain_it ... ok
[INFO] [stdout] test signal::tests::a_recurring_behavior_is_vetoed_only_when_its_window_moved_without_it ... ok
[INFO] [stdout] test signal::tests::a_request_blocks_signals_are_one_group_headed_by_the_request ... ok
[INFO] [stdout] test signal::tests::a_request_that_slowed_groups_with_the_count_that_moved_inside_it ... ok
[INFO] [stdout] test observation::tests::joins_lines_across_chunks_and_numbers_them ... ok
[INFO] [stdout] test signal::tests::a_new_error_outside_examples_that_skipped_nothing_is_the_change ... ok
[INFO] [stdout] test signal::tests::a_slow_example_is_latency_unless_its_neighbour_slowed_too ... ok
[INFO] [stdout] test num::tests::medians_and_deviations ... ok
[INFO] [stdout] test signal::tests::a_slow_example_is_vetoed_once_enough_distant_examples_slowed_with_it ... ok
[INFO] [stdout] test signal::tests::changes_after_the_last_example_are_teardown_ranked_with_setup ... ok
[INFO] [stdout] test signal::tests::changes_before_the_first_example_form_one_setup_group_ranked_last ... ok
[INFO] [stdout] test signal::tests::examples_added_in_one_file_are_one_group ... ok
[INFO] [stdout] test signal::tests::examples_added_in_two_files_stay_two_groups ... ok
[INFO] [stdout] test signal::tests::a_clean_run_has_no_signals ... ok
[INFO] [stdout] test signal::rules::tests::presence_vectors ... ok
[INFO] [stdout] test signal::tests::examples_renamed_within_one_file_stay_two_groups ... ok
[INFO] [stdout] test signal::tests::a_truncated_run_does_not_turn_its_absences_into_changes ... ok
[INFO] [stdout] test num::tests::rounds_integers_to_significant_figures ... ok
[INFO] [stdout] test signal::tests::a_known_flaky_failure_is_not_an_error_but_a_new_exception_is ... ok
[INFO] [stdout] test signal::tests::an_incomplete_run_keeps_what_ran_and_names_what_did_not ... ok
[INFO] [stdout] test signal::tests::an_n_plus_one_is_one_group_headed_by_the_request ... ok
[INFO] [stdout] test signal::tests::the_suite_size_demotion_spares_a_moved_rate_and_a_change_one_example_owns ... ok
[INFO] [stdout] test signal::tests::a_slow_request_is_latency_with_no_example_in_sight ... ok
[INFO] [stdout] test signal::tests::unattributed_occurrences_leave_a_signal_ungrouped ... ok
[INFO] [stdout] test signal::tests::examples_that_disappeared_from_one_file_are_one_group_below_a_regression ... ok
[INFO] [stdout] test signal::tests::one_incomplete_run_in_the_baseline_does_not_blind_the_rest ... ok
[INFO] [stdout] test observation::tests::truncates_overlong_lines_without_renumbering_or_losing_their_length ... ok
[INFO] [stdout] test interpret::rspec::tests::an_event_without_the_declared_description_keeps_its_full_description ... ok
[INFO] [stdout] test signal::rules::tests::latency_vectors ... ok
[INFO] [stdout] test interpret::rspec::tests::lines_outside_examples_are_setup_between_or_teardown ... ok
[INFO] [stdout] test signal::tests::runs_recorded_before_test_results_were_read_are_no_baseline_for_a_test_run ... ok
[INFO] [stdout] test signal::tests::stderr_messages_group_by_prefix_and_stdout_only_counts_without_a_reporter ... ok
[INFO] [stdout] test interpret::rspec::tests::crlf_and_overlong_lines_keep_later_lines_in_their_own_example ... ok
[INFO] [stdout] test store::capture::tests::a_file_stream_keeps_its_own_extension ... ok
[INFO] [stdout] test store::retention::tests::settings_come_from_the_environment_within_their_floors ... ok
[INFO] [stdout] test store::ids::tests::ids_parse_with_or_without_prefix ... ok
[INFO] [stdout] test aggregate::tests::distinct_behaviors_are_capped_into_one_overflow_behavior ... ok
[INFO] [stdout] test store::capture::tests::a_capture_recorded_under_the_old_name_is_still_found ... ok
[INFO] [stdout] test store::schema::tests::first_occurrence_is_backfilled_from_the_first_exemplar ... ok
[INFO] [stdout] test store::schema::tests::credentials_an_older_siftr_stored_are_redacted_in_place_and_its_captures_removed ... ok
[INFO] [stdout] test store::schema::tests::runs_an_old_build_recorded_as_killed_by_a_signal_leave_baselines ... ok
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- interpret::fixtures::a_failure_carries_its_exception_class_scoped_to_the_example stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'interpret::fixtures::a_failure_carries_its_exception_class_scoped_to_the_example' (826) panicked at src/interpret/fixtures.rs:79:49:
[INFO] [stdout] reading /opt/rustwide/workdir/fixtures/rails_demo/fail/rspec.ndjson: No such file or directory (os error 2)
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x654a1955a311 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x654a195707da - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x654a1955ed1c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x654a19533c86 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x654a19551669 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x654a19342330 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x654a19551822 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x654a19533d32 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x654a1952c169 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x654a19534a4d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x654a19570f3c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x654a192992ca - siftr[aece6f2142be8982]::interpret::fixtures::read::{closure#0}
[INFO] [stdout]   12:     0x654a192997b4 - siftr[aece6f2142be8982]::interpret::fixtures::read
[INFO] [stdout]   13:     0x654a19299c87 - siftr[aece6f2142be8982]::interpret::fixtures::capture
[INFO] [stdout]   14:     0x654a1929f56a - <siftr[aece6f2142be8982]::interpret::fixtures::a_failure_carries_its_exception_class_scoped_to_the_example::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   15:     0x654a1933577b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   16:     0x654a19342955 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   17:     0x654a1933c244 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   18:     0x654a193459e2 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   19:     0x654a1955931f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   20:     0x743cf15d0dfa - <unknown>
[INFO] [stdout]   21:     0x743cf16643d4 - clone
[INFO] [stdout]   22:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- interpret::fixtures::listener_offsets_land_on_log_line_starts stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'interpret::fixtures::listener_offsets_land_on_log_line_starts' (828) panicked at src/interpret/fixtures.rs:79:49:
[INFO] [stdout] reading /opt/rustwide/workdir/fixtures/rails_demo/baseline/test.log: No such file or directory (os error 2)
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x654a1955a311 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x654a195707da - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x654a1955ed1c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x654a19533c86 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x654a19551669 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x654a19342330 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x654a19551822 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x654a19533d32 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x654a1952c169 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x654a19534a4d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x654a19570f3c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x654a192992ca - siftr[aece6f2142be8982]::interpret::fixtures::read::{closure#0}
[INFO] [stdout]   12:     0x654a192997b4 - siftr[aece6f2142be8982]::interpret::fixtures::read
[INFO] [stdout]   13:     0x654a1929d23e - <siftr[aece6f2142be8982]::interpret::fixtures::listener_offsets_land_on_log_line_starts::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   14:     0x654a1933577b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   15:     0x654a19342955 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   16:     0x654a1933c244 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   17:     0x654a193459e2 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   18:     0x654a1955931f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   19:     0x743cf15d0dfa - <unknown>
[INFO] [stdout]   20:     0x743cf16643d4 - clone
[INFO] [stdout]   21:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- interpret::fixtures::clean_runs_produce_identical_behaviors_scopes_and_counts stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'interpret::fixtures::clean_runs_produce_identical_behaviors_scopes_and_counts' (827) panicked at src/interpret/fixtures.rs:79:49:
[INFO] [stdout] reading /opt/rustwide/workdir/fixtures/rails_demo/baseline/rspec.ndjson: No such file or directory (os error 2)
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x654a1955a311 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x654a195707da - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x654a1955ed1c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x654a19533c86 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x654a19551669 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x654a19342330 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x654a19551822 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x654a19533d32 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x654a1952c169 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x654a19534a4d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x654a19570f3c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x654a192992ca - siftr[aece6f2142be8982]::interpret::fixtures::read::{closure#0}
[INFO] [stdout]   12:     0x654a192997b4 - siftr[aece6f2142be8982]::interpret::fixtures::read
[INFO] [stdout]   13:     0x654a19299c87 - siftr[aece6f2142be8982]::interpret::fixtures::capture
[INFO] [stdout]   14:     0x654a1929e6b2 - <siftr[aece6f2142be8982]::interpret::fixtures::clean_runs_produce_identical_behaviors_scopes_and_counts::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   15:     0x654a1933577b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   16:     0x654a19342955 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   17:     0x654a1933c244 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   18:     0x654a193459e2 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   19:     0x654a1955931f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   20:     0x743cf15d0dfa - <unknown>
[INFO] [stdout]   21:     0x743cf16643d4 - clone
[INFO] [stdout]   22:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- interpret::fixtures::n_plus_one_changes_only_the_show_example stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'interpret::fixtures::n_plus_one_changes_only_the_show_example' (829) panicked at src/interpret/fixtures.rs:79:49:
[INFO] [stdout] reading /opt/rustwide/workdir/fixtures/rails_demo/baseline/rspec.ndjson: No such file or directory (os error 2)
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x654a1955a311 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x654a195707da - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x654a1955ed1c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x654a19533c86 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x654a19551669 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x654a19342330 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x654a19551822 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x654a19533d32 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x654a1952c169 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x654a19534a4d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x654a19570f3c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x654a192992ca - siftr[aece6f2142be8982]::interpret::fixtures::read::{closure#0}
[INFO] [stdout]   12:     0x654a192997b4 - siftr[aece6f2142be8982]::interpret::fixtures::read
[INFO] [stdout]   13:     0x654a19299c87 - siftr[aece6f2142be8982]::interpret::fixtures::capture
[INFO] [stdout]   14:     0x654a1929d77d - <siftr[aece6f2142be8982]::interpret::fixtures::n_plus_one_changes_only_the_show_example::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   15:     0x654a1933577b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   16:     0x654a19342955 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   17:     0x654a1933c244 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   18:     0x654a193459e2 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   19:     0x654a1955931f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   20:     0x743cf15d0dfa - <unknown>
[INFO] [stdout]   21:     0x743cf16643d4 - clone
[INFO] [stdout]   22:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- interpret::fixtures::only_log_lines_before_the_first_example_are_unscoped stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'interpret::fixtures::only_log_lines_before_the_first_example_are_unscoped' (830) panicked at src/interpret/fixtures.rs:79:49:
[INFO] [stdout] reading /opt/rustwide/workdir/fixtures/rails_demo/baseline/rspec.ndjson: No such file or directory (os error 2)
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x654a1955a311 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x654a195707da - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x654a1955ed1c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x654a19533c86 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x654a19551669 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x654a19342330 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x654a19551822 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x654a19533d32 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x654a1952c169 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x654a19534a4d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x654a19570f3c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x654a192992ca - siftr[aece6f2142be8982]::interpret::fixtures::read::{closure#0}
[INFO] [stdout]   12:     0x654a192997b4 - siftr[aece6f2142be8982]::interpret::fixtures::read
[INFO] [stdout]   13:     0x654a19299c87 - siftr[aece6f2142be8982]::interpret::fixtures::capture
[INFO] [stdout]   14:     0x654a1929e54e - <siftr[aece6f2142be8982]::interpret::fixtures::only_log_lines_before_the_first_example_are_unscoped::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   15:     0x654a1933577b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   16:     0x654a19342955 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   17:     0x654a1933c244 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   18:     0x654a193459e2 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   19:     0x654a1955931f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   20:     0x743cf15d0dfa - <unknown>
[INFO] [stdout]   21:     0x743cf16643d4 - clone
[INFO] [stdout]   22:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     interpret::fixtures::a_failure_carries_its_exception_class_scoped_to_the_example
[INFO] [stdout]     interpret::fixtures::clean_runs_produce_identical_behaviors_scopes_and_counts
[INFO] [stdout]     interpret::fixtures::listener_offsets_land_on_log_line_starts
[INFO] [stdout]     interpret::fixtures::n_plus_one_changes_only_the_show_example
[INFO] [stdout]     interpret::fixtures::only_log_lines_before_the_first_example_are_unscoped
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 106 passed; 5 failed; 0 ignored; 0 measured; 0 filtered out; finished in 6.34s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--lib`
[INFO] running `Command { std: "docker" "inspect" "6fc040bcdeae4809b10a8a55d2c201a0e9750f0657ca9950feb6aa67a817bbfc", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "6fc040bcdeae4809b10a8a55d2c201a0e9750f0657ca9950feb6aa67a817bbfc", kill_on_drop: false }`
[INFO] [stdout] 6fc040bcdeae4809b10a8a55d2c201a0e9750f0657ca9950feb6aa67a817bbfc
