[INFO] fetching crate rustledger-loader 0.24.0...
[INFO] testing rustledger-loader-0.24.0 against 1.100.0-beta.1 for beta-1.100-2
[INFO] extracting crate rustledger-loader 0.24.0 into /workspace/builds/worker-7-tc2/source
[INFO] started tweaking crates.io crate rustledger-loader 0.24.0
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate rustledger-loader 0.24.0
[INFO] tweaked toml for crates.io crate rustledger-loader 0.24.0 written to /workspace/builds/worker-7-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate rustledger-loader 0.24.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 rustledger-loader 0.24.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] 4ad170c76b5d85d6439ca435aa2f5098fa997f26fafc2fdc4d328aabe207bcce
[INFO] running `Command { std: "docker" "start" "4ad170c76b5d85d6439ca435aa2f5098fa997f26fafc2fdc4d328aabe207bcce", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "4ad170c76b5d85d6439ca435aa2f5098fa997f26fafc2fdc4d328aabe207bcce", 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" "4ad170c76b5d85d6439ca435aa2f5098fa997f26fafc2fdc4d328aabe207bcce" "/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" "4ad170c76b5d85d6439ca435aa2f5098fa997f26fafc2fdc4d328aabe207bcce", 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" "4ad170c76b5d85d6439ca435aa2f5098fa997f26fafc2fdc4d328aabe207bcce" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling serde_core v1.0.229
[INFO] [stderr]    Compiling simdutf8 v0.1.5
[INFO] [stderr]    Compiling rand_core v0.9.5
[INFO] [stderr]    Compiling rust_decimal v1.42.1
[INFO] [stderr]    Compiling bitmaps v3.2.1
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling aho-corasick v1.1.4
[INFO] [stderr]    Compiling safe_arch v0.7.4
[INFO] [stderr]    Compiling arrayvec v0.7.6
[INFO] [stderr]    Compiling jiff-core v0.1.0
[INFO] [stderr]    Compiling archery v1.2.2
[INFO] [stderr]    Compiling winnow v1.0.3
[INFO] [stderr]    Compiling syn v2.0.117
[INFO] [stderr]    Compiling syn v3.0.2
[INFO] [stderr]    Compiling indexmap v2.14.0
[INFO] [stderr]    Compiling rustc-hash v2.1.3
[INFO] [stderr]    Compiling rand_xoshiro v0.7.0
[INFO] [stderr]    Compiling toml_datetime v1.1.1+spec-1.1.0
[INFO] [stderr]    Compiling memoffset v0.9.1
[INFO] [stderr]    Compiling bigdecimal v0.4.10
[INFO] [stderr]    Compiling thiserror v2.0.20
[INFO] [stderr]    Compiling rustversion v1.0.22
[INFO] [stderr]    Compiling wide v0.7.33
[INFO] [stderr]    Compiling fnv v1.0.7
[INFO] [stderr]    Compiling imbl-sized-chunks v0.1.3
[INFO] [stderr]    Compiling cc v1.2.64
[INFO] [stderr]    Compiling text-size v1.1.1
[INFO] [stderr]    Compiling rustc-hash v1.1.0
[INFO] [stderr]    Compiling countme v3.0.1
[INFO] [stderr]    Compiling hashbrown v0.14.5
[INFO] [stderr]    Compiling cpufeatures v0.3.0
[INFO] [stderr]    Compiling constant_time_eq v0.4.2
[INFO] [stderr]    Compiling rustledger-plugin v0.24.0
[INFO] [stderr]    Compiling glob v0.3.4
[INFO] [stderr]    Compiling num-integer v0.1.46
[INFO] [stderr]    Compiling rmp v0.8.15
[INFO] [stderr]    Compiling toml_parser v1.1.2+spec-1.1.0
[INFO] [stderr]    Compiling rowan v0.17.0
[INFO] [stderr]    Compiling num-bigint v0.4.6
[INFO] [stderr]    Compiling blake3 v1.8.7
[INFO] [stderr]    Compiling regex-automata v0.4.16
[INFO] [stderr]    Compiling toml_edit v0.25.12+spec-1.1.0
[INFO] [stderr]    Compiling proc-macro-crate v3.5.0
[INFO] [stderr]    Compiling serde_derive v1.0.229
[INFO] [stderr]    Compiling rkyv_derive v0.8.18
[INFO] [stderr]    Compiling thiserror-impl v2.0.20
[INFO] [stderr]    Compiling imbl v7.0.1
[INFO] [stderr]    Compiling serde_json v1.0.151
[INFO] [stderr]    Compiling jiff v0.2.35
[INFO] [stderr]    Compiling regex v1.13.1
[INFO] [stderr]    Compiling ptr_meta_derive v0.3.1
[INFO] [stderr]    Compiling bytecheck_derive v0.8.2
[INFO] [stderr]    Compiling munge_macro v0.4.7
[INFO] [stderr]    Compiling rust_decimal_macros v1.40.0
[INFO] [stderr]    Compiling num_enum_derive v0.7.6
[INFO] [stderr]    Compiling ptr_meta v0.3.1
[INFO] [stderr]    Compiling rancor v0.1.1
[INFO] [stderr]    Compiling serde v1.0.229
[INFO] [stderr]    Compiling munge v0.4.7
[INFO] [stderr]    Compiling bytecheck v0.8.2
[INFO] [stderr]    Compiling logos-codegen v0.16.1
[INFO] [stderr]    Compiling rend v0.5.3
[INFO] [stderr]    Compiling num_enum v0.7.6
[INFO] [stderr]    Compiling rustledger-plugin-types v0.24.0
[INFO] [stderr]    Compiling rmp-serde v1.3.1
[INFO] [stderr]    Compiling logos-derive v0.16.1
[INFO] [stderr]    Compiling logos v0.16.1
[INFO] [stderr]    Compiling rkyv v0.8.18
[INFO] [stderr]    Compiling rustledger-core v0.24.0
[INFO] [stderr]    Compiling rustledger-booking v0.24.0
[INFO] [stderr]    Compiling rustledger-parser v0.24.0
[INFO] [stderr]    Compiling rustledger-ops v0.24.0
[INFO] [stderr]    Compiling rustledger-budget v0.24.0
[INFO] [stderr]    Compiling rustledger-validate v0.24.0
[INFO] [stderr]    Compiling rustledger-loader v0.24.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 53.51s
[INFO] [stderr] warning: the following packages contain code that will be rejected by a future version of Rust: bytecheck v0.8.2, rkyv v0.8.18
[INFO] [stderr] note: to see what the problems were, use the option `--future-incompat-report`, or run `cargo report future-incompatibilities --id 4`
[INFO] running `Command { std: "docker" "inspect" "4ad170c76b5d85d6439ca435aa2f5098fa997f26fafc2fdc4d328aabe207bcce", 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" "4ad170c76b5d85d6439ca435aa2f5098fa997f26fafc2fdc4d328aabe207bcce" "/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 libc v0.2.186
[INFO] [stderr]    Compiling bitflags v2.13.0
[INFO] [stderr]    Compiling rustix v1.1.4
[INFO] [stderr]    Compiling getrandom v0.4.2
[INFO] [stderr]    Compiling tempfile v3.27.0
[INFO] [stderr]    Compiling rustledger-loader v0.24.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 10.06s
[INFO] [stderr] warning: the following packages contain code that will be rejected by a future version of Rust: bytecheck v0.8.2, rkyv v0.8.18
[INFO] [stderr] note: to see what the problems were, use the option `--future-incompat-report`, or run `cargo report future-incompatibilities --id 4`
[INFO] running `Command { std: "docker" "inspect" "4ad170c76b5d85d6439ca435aa2f5098fa997f26fafc2fdc4d328aabe207bcce", 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" "4ad170c76b5d85d6439ca435aa2f5098fa997f26fafc2fdc4d328aabe207bcce" "/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.29s
[INFO] [stderr] warning: the following packages contain code that will be rejected by a future version of Rust: bytecheck v0.8.2, rkyv v0.8.18
[INFO] [stderr] note: to see what the problems were, use the option `--future-incompat-report`, or run `cargo report future-incompatibilities --id 4`
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/build/rustledger-loader/7529317a9bd4255a/out/rustledger_loader-7529317a9bd4255a)
[INFO] [stdout] 
[INFO] [stdout] running 106 tests
[INFO] [stdout] test cache::tests::bench_cache_performance ... ignored, manual benchmark - run with: cargo test -p rustledger-loader --release -- --ignored --nocapture
[INFO] [stdout] test cache::tests::a_nested_intern_scope_leaves_the_outer_one_interning ... ok
[INFO] [stdout] test cache::tests::test_load_cache_rejects_older_version ... ok
[INFO] [stdout] test cache::tests::test_invalidate_cache_removes_legacy_sidecar ... ok
[INFO] [stdout] test cache::tests::cache_hit_directives_share_one_arc_per_distinct_string ... ok
[INFO] [stdout] test cache::tests::test_legacy_cache_path ... ok
[INFO] [stdout] test cache::tests::test_cache_entry_file_paths ... ok
[INFO] [stdout] test cache::tests::test_cached_options_preserves_set_options_for_booking_method ... ok
[INFO] [stdout] test cache::tests::test_cache_header_roundtrip ... ok
[INFO] [stdout] test cache::tests::test_load_cache_missing_file ... ok
[INFO] [stdout] test cache::tests::cached_options_field_parity ... ok
[INFO] [stdout] test cache::tests::test_invalidate_cache ... ok
[INFO] [stdout] test cache::tests::test_load_cache_invalid_magic ... ok
[INFO] [stdout] test cache::tests::meta_value_archived_layout_hash_matches ... ok
[INFO] [stdout] test cache::tests::test_reintern_balance_directive ... ok
[INFO] [stdout] test cache::tests::test_resolve_cache_pattern_absolute ... ok
[INFO] [stdout] test cache::tests::test_resolve_cache_pattern_relative_with_substitution ... ok
[INFO] [stdout] test cache::tests::test_reintern_directives_deduplication ... ok
[INFO] [stdout] test cache::tests::test_resolve_cache_pattern_no_substitution ... ok
[INFO] [stdout] test cache::tests::test_serialize_deserialize_roundtrip ... ok
[INFO] [stdout] test cache::tests::test_compute_hash_deterministic ... ok
[INFO] [stdout] test options::tests::boolean_options_share_one_vocabulary ... ok
[INFO] [stdout] test discover::tests::name_priority_is_stable_and_directories_do_not_count ... ok
[INFO] [stdout] test cache::tests::test_save_load_cache_entry_roundtrip ... ok
[INFO] [stdout] test cache::tests::test_cached_options_roundtrip ... ok
[INFO] [stdout] test options::tests::test_deprecated_allow_pipe_separator ... ok
[INFO] [stdout] test options::tests::test_deprecated_plugin_option ... ok
[INFO] [stdout] test discover::tests::finds_a_root_beside_the_file_and_prefers_the_nearest ... ok
[INFO] [stdout] test options::tests::test_account_validation_options ... ok
[INFO] [stdout] test options::tests::test_display_precision_missing_colon_warns ... ok
[INFO] [stdout] test options::tests::test_display_precision_repeatable_per_currency ... ok
[INFO] [stdout] test options::tests::scoping_out_an_option_still_reports_its_deprecation ... ok
[INFO] [stdout] test options::tests::test_account_rounding_accepted_but_warns_noop ... ok
[INFO] [stdout] test options::tests::included_files_still_contribute_accumulating_options ... ok
[INFO] [stdout] test options::tests::included_files_do_not_govern_scoped_options ... ok
[INFO] [stdout] test options::tests::test_display_precision_zero_decimals ... ok
[INFO] [stdout] test options::tests::test_duplicate_option_warning ... ok
[INFO] [stdout] test options::tests::test_account_option_uses_canonical_rule ... ok
[INFO] [stdout] test discover::tests::returns_none_when_there_is_no_ledger_anywhere_above ... ok
[INFO] [stdout] test options::tests::test_invalid_booking_method ... ok
[INFO] [stdout] test options::tests::test_invalid_boolean_value ... ok
[INFO] [stdout] test options::tests::test_default_options ... ok
[INFO] [stdout] test options::tests::test_invalid_tolerance_value ... ok
[INFO] [stdout] test options::tests::test_custom_options ... ok
[INFO] [stdout] test options::tests::test_invalid_account_name_validation ... ok
[INFO] [stdout] test options::tests::test_inferred_tolerance_multiplier_deprecated ... ok
[INFO] [stdout] test options::tests::test_name_option_invalid_root_warns_e7008 ... ok
[INFO] [stdout] test options::tests::test_name_option_valid_roots_no_warning ... ok
[INFO] [stdout] test options::tests::test_display_precision_basic ... ok
[INFO] [stdout] test options::tests::test_display_precision_high_precision ... ok
[INFO] [stdout] test options::tests::test_tolerance_multiplier_new_name ... ok
[INFO] [stdout] test options::tests::test_unknown_option_warning ... ok
[INFO] [stdout] test options::tests::test_use_precise_interpolation_accepted ... ok
[INFO] [stdout] test options::tests::test_display_precision_invalid_example_warns ... ok
[INFO] [stdout] test options::tests::test_valid_account_name ... ok
[INFO] [stdout] test options::tests::test_valid_booking_methods ... ok
[INFO] [stdout] test phase::tests::directives_raw_can_be_constructed_from_parser_output ... ok
[INFO] [stdout] test phase::tests::finalized_into_inner_returns_the_vec ... ok
[INFO] [stdout] test process::module_ref_message_tests::message_falls_back_to_guidance_when_unresolved ... ok
[INFO] [stdout] test process::module_ref_message_tests::message_uses_resolved_path_when_known ... ok
[INFO] [stdout] test process::sanitize_tests::span_past_eof_is_reset_to_synthesized ... ok
[INFO] [stdout] test process::sanitize_tests::boundary_span_eq_source_len_is_valid ... ok
[INFO] [stdout] test process::sanitize_tests::non_transaction_directive_is_left_alone ... ok
[INFO] [stdout] test options::tests::test_inferred_tolerance_default ... ok
[INFO] [stdout] test process::sanitize_tests::start_after_end_is_reset_to_synthesized ... ok
[INFO] [stdout] test process::sanitize_tests::span_within_real_file_is_preserved ... ok
[INFO] [stdout] test process::sanitize_tests::synthesized_file_id_is_left_alone_but_span_normalized ... ok
[INFO] [stdout] test process::sanitize_tests::unknown_file_id_is_reset_to_synthesized ... ok
[INFO] [stdout] test process::validation_options_tests::maps_inferred_tolerance_default_and_account_types ... ok
[INFO] [stdout] test source_map::tests::test_line_col ... ok
[INFO] [stdout] test source_map::tests::test_line_start ... ok
[INFO] [stdout] test source_map::tests::test_get_line ... ok
[INFO] [stdout] test source_map::tests::test_source_map ... ok
[INFO] [stdout] test tests::file_id_to_u16_rejects_the_reserved_sentinel_not_panic ... ok
[INFO] [stdout] test options::tests::test_is_valid_account ... ok
[INFO] [stdout] test options::tests::test_plugin_processing_mode_validation ... ok
[INFO] [stdout] test options::tests::test_render_commas_with_numeric_values ... ok
[INFO] [stdout] test options::tests::test_readonly_option_warning ... ok
[INFO] [stdout] test tests::test_is_encrypted_file_plain_beancount ... ok
[INFO] [stdout] test tests::test_is_encrypted_file_asc_with_pgp_header ... ok
[INFO] [stdout] test tests::test_fresh_parse_deduplicates_internedstr_across_files ... ok
[INFO] [stdout] test tests::test_fresh_parse_deduplicates_transaction_fields_across_files ... ok
[INFO] [stdout] test tests::test_is_encrypted_file_asc_without_pgp_header ... ok
[INFO] [stdout] test tests::test_is_encrypted_file_gpg_extension ... ok
[INFO] [stdout] test options::tests::test_set_options ... ok
[INFO] [stdout] test tests::test_virtual_filesystem_glob_no_match ... ok
[INFO] [stdout] test tests::test_plugin_force_python_with_config ... ok
[INFO] [stdout] test tests::test_fresh_parse_deduplicates_metavalue_across_files ... ok
[INFO] [stdout] test tests::test_virtual_filesystem_missing_include ... ok
[INFO] [stdout] test tests::test_vfs_glob_include_within_root_not_flagged_as_traversal ... ok
[INFO] [stdout] test vfs::tests::test_normalize_vfs_path ... ok
[INFO] [stdout] test options::tests::test_repeatable_option_no_warning ... ok
[INFO] [stdout] test tests::test_virtual_filesystem_include_resolution ... ok
[INFO] [stdout] test tests::test_virtual_filesystem_glob_dot_slash_prefix ... ok
[INFO] [stdout] test tests::test_plugin_force_python_prefix ... ok
[INFO] [stdout] test tests::test_virtual_filesystem_nested_includes ... ok
[INFO] [stdout] test vfs::tests::test_virtual_filesystem_basic ... ok
[INFO] [stdout] test vfs::tests::test_virtual_filesystem_from_files ... ok
[INFO] [stdout] test tests::test_virtual_filesystem_glob_include ... ok
[INFO] [stdout] test vfs::tests::test_virtual_filesystem_not_encrypted ... ok
[INFO] [stdout] test vfs::tests::test_virtual_filesystem_path_normalization ... ok
[INFO] [stdout] test process::finalize_price_tests::finalize_normalizes_total_at_at_price_to_per_unit ... ok
[INFO] [stdout] test cache::tests::cost_number_archived_bytes_match_v8_fixtures ... ok
[INFO] [stdout] test cache::tests::test_reintern_open_close_directives ... ok
[INFO] [stdout] test cache::tests::the_intern_scope_stops_interning_once_the_guard_drops ... ok
[INFO] [stdout] test tests::test_decrypt_gpg_file_missing_gpg ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 105 passed; 0 failed; 1 ignored; 0 measured; 0 filtered out; finished in 0.07s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/balance_view_test.rs (/opt/rustwide/target/debug/build/rustledger-loader/755692beaf9489b8/out/balance_view_test-755692beaf9489b8)
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test balance_view_on_pad_free_ledger_is_equivalent_to_directives ... ok
[INFO] [stdout] test directives_field_keeps_pads_as_pads ... ok
[INFO] [stdout] test balance_view_does_not_mutate_source_directives ... ok
[INFO] [stdout] test balance_view_adds_synth_transaction_alongside_original_pad ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/cache_env_var_test.rs (/opt/rustwide/target/debug/build/rustledger-loader/6815d884a60cf37d/out/cache_env_var_test-6815d884a60cf37d)
[INFO] [stdout] 
[INFO] [stdout] running 7 tests
[INFO] [stdout] test cache_path_env_pattern_is_honored ... ok
[INFO] [stdout] test cache_path_empty_env_pattern_falls_back_to_default ... ok
[INFO] [stdout] test cache_path_relative_env_pattern_resolves_against_source_dir ... ok
[INFO] [stdout] test cache_path_default_is_hidden_dotfile ... ok
[INFO] [stdout] test save_creates_missing_parent_directory ... ok
[INFO] [stdout] test empty_disable_env_value_still_disables ... ok
[INFO] [stdout] test disable_env_makes_load_return_none_and_save_no_op ... ok
[INFO] [stdout] 
[INFO] [stderr]      Running tests/cache_roundtrip_properties.rs (/opt/rustwide/target/debug/build/rustledger-loader/39c1ae5d38e5fbf0/out/cache_roundtrip_properties-39c1ae5d38e5fbf0)
[INFO] [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test a_stale_version_is_refused_rather_than_reinterpreted ... ok
[INFO] [stdout] test cache_roundtrip_preserves_parsed_directives ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/cost_spec_unknowns.rs (/opt/rustwide/target/debug/build/rustledger-loader/00c9262b9f0cad88/out/cost_spec_unknowns-00c9262b9f0cad88)
[INFO] [stdout] 
[INFO] [stdout] running 5 tests
[INFO] [stdout] test a_malformed_cost_spec_does_not_produce_a_balance_error ... ok
[INFO] [stdout] test a_single_numberless_cost_spec_still_interpolates ... ok
[INFO] [stdout] test two_numberless_cost_specs_in_one_group_are_rejected ... ok
[INFO] [stdout] test hash_with_no_numbers_is_not_a_zero_cost ... ok
[INFO] [stdout] test a_well_formed_spec_that_does_not_balance_is_still_reported ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/cross_file_booking_order.rs (/opt/rustwide/target/debug/build/rustledger-loader/e3a18f2683617676/out/cross_file_booking_order-e3a18f2683617676)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test cross_file_same_date_directives_keep_include_order ... FAILED
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- cross_file_same_date_directives_keep_include_order stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'cross_file_same_date_directives_keep_include_order' (2006) panicked at tests/cross_file_booking_order.rs:43:42:
[INFO] [stdout] fixture loads: Io { path: "/opt/rustwide/workdir/../../tests/fixtures/cross-file-order/ledger.beancount", source: Os { code: 2, kind: NotFound, message: "No such file or directory" } }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5c26a22b3841 - std[617be2ca0a6fd877]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x5c26a22b3841 - std[617be2ca0a6fd877]::backtrace_rs::backtrace::trace_unsynchronized::<std[617be2ca0a6fd877]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x5c26a22b3841 - std[617be2ca0a6fd877]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x5c26a22b3841 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x5c26a22cb55a - <core[a373ad73a504971]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x5c26a22cb55a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    6:     0x5c26a22b7e4c - core[a373ad73a504971]::io::write::default_write_fmt::<alloc[2e1b2a31b91974c5]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/io/write.rs:402:11
[INFO] [stdout]    7:     0x5c26a22b7e4c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/io/write.rs:335:13
[INFO] [stdout]    8:     0x5c26a228e3f6 - <std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x5c26a228e3f6 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:289:27
[INFO] [stdout]   10:     0x5c26a22ab4e9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:313:9
[INFO] [stdout]   11:     0x5c26a1a245e0 - <alloc[2e1b2a31b91974c5]::boxed::Box<dyn for<'a, 'b> core[a373ad73a504971]::ops::function::Fn<(&'a std[617be2ca0a6fd877]::panic::PanicHookInfo<'b>,), Output = ()> + core[a373ad73a504971]::marker::Sync + core[a373ad73a504971]::marker::Send> as core[a373ad73a504971]::ops::function::Fn<(&std[617be2ca0a6fd877]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/alloc/src/boxed.rs:2328:9
[INFO] [stdout]   12:     0x5c26a1a245e0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:137:21
[INFO] [stdout]   13:     0x5c26a22ab812 - <alloc[2e1b2a31b91974c5]::boxed::Box<dyn for<'a, 'b> core[a373ad73a504971]::ops::function::Fn<(&'a std[617be2ca0a6fd877]::panic::PanicHookInfo<'b>,), Output = ()> + core[a373ad73a504971]::marker::Sync + core[a373ad73a504971]::marker::Send> as core[a373ad73a504971]::ops::function::Fn<(&std[617be2ca0a6fd877]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/alloc/src/boxed.rs:2328:9
[INFO] [stdout]   14:     0x5c26a22ab812 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:819:13
[INFO] [stdout]   15:     0x5c26a228e4a2 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:685:13
[INFO] [stdout]   16:     0x5c26a2286f59 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x5c26a228f0ad - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:676:5
[INFO] [stdout]   18:     0x5c26a22cbdfc - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x5c26a22cbb52 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/result.rs:1870:5
[INFO] [stdout]   20:     0x5c26a1a16e91 - <core[a373ad73a504971]::result::Result<rustledger_loader[5ed92dac866763e2]::LoadResult, rustledger_loader[5ed92dac866763e2]::LoadError>>::expect
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/result.rs:1184:23
[INFO] [stdout]   21:     0x5c26a1a167e6 - cross_file_booking_order[91e7f8020c6ad7b4]::realized_gains
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/cross_file_booking_order.rs:43:42
[INFO] [stdout]   22:     0x5c26a1a16d2c - cross_file_booking_order[91e7f8020c6ad7b4]::cross_file_same_date_directives_keep_include_order
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/cross_file_booking_order.rs:80:9
[INFO] [stdout]   23:     0x5c26a1a166c7 - cross_file_booking_order[91e7f8020c6ad7b4]::cross_file_same_date_directives_keep_include_order::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/cross_file_booking_order.rs:69:56
[INFO] [stdout]   24:     0x5c26a1a179d6 - <cross_file_booking_order[91e7f8020c6ad7b4]::cross_file_same_date_directives_keep_include_order::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   25:     0x5c26a1a17a2b - <fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String> as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   26:     0x5c26a1a17a2b - 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]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:701:18
[INFO] [stdout]   27:     0x5c26a1a24c05 - test[aada8e7e76ba119b]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:724:74
[INFO] [stdout]   28:     0x5c26a1a24c05 - <core[a373ad73a504971]::panic::unwind_safe::AssertUnwindSafe<test[aada8e7e76ba119b]::run_test_in_process::{closure#0}> as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   29:     0x5c26a1a24c05 - std[617be2ca0a6fd877]::panicking::catch_unwind::do_call::<core[a373ad73a504971]::panic::unwind_safe::AssertUnwindSafe<test[aada8e7e76ba119b]::run_test_in_process::{closure#0}>, core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:573:43
[INFO] [stdout]   30:     0x5c26a1a24c05 - std[617be2ca0a6fd877]::panicking::catch_unwind::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, core[a373ad73a504971]::panic::unwind_safe::AssertUnwindSafe<test[aada8e7e76ba119b]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:541:19
[INFO] [stdout]   31:     0x5c26a1a24c05 - std[617be2ca0a6fd877]::panic::catch_unwind::<core[a373ad73a504971]::panic::unwind_safe::AssertUnwindSafe<test[aada8e7e76ba119b]::run_test_in_process::{closure#0}>, core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panic.rs:359:14
[INFO] [stdout]   32:     0x5c26a1a24c05 - test[aada8e7e76ba119b]::run_test_in_process
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:724:27
[INFO] [stdout]   33:     0x5c26a1a24c05 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:645:43
[INFO] [stdout]   34:     0x5c26a1a1e4f4 - test[aada8e7e76ba119b]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:675:41
[INFO] [stdout]   35:     0x5c26a1a1e4f4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   36:     0x5c26a1a27c92 - std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   37:     0x5c26a1a27c92 - <core[a373ad73a504971]::panic::unwind_safe::AssertUnwindSafe<std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   38:     0x5c26a1a27c92 - std[617be2ca0a6fd877]::panicking::catch_unwind::do_call::<core[a373ad73a504971]::panic::unwind_safe::AssertUnwindSafe<std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:573:43
[INFO] [stdout]   39:     0x5c26a1a27c92 - std[617be2ca0a6fd877]::panicking::catch_unwind::<(), core[a373ad73a504971]::panic::unwind_safe::AssertUnwindSafe<std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:541:19
[INFO] [stdout]   40:     0x5c26a1a27c92 - std[617be2ca0a6fd877]::panic::catch_unwind::<core[a373ad73a504971]::panic::unwind_safe::AssertUnwindSafe<std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panic.rs:359:14
[INFO] [stdout]   41:     0x5c26a1a27c92 - std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   42:     0x5c26a1a27c92 - <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]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   43:     0x5c26a22b2d0f - <alloc[2e1b2a31b91974c5]::boxed::Box<dyn core[a373ad73a504971]::ops::function::FnOnce<(), Output = ()> + core[a373ad73a504971]::marker::Send> as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/alloc/src/boxed.rs:2314:9
[INFO] [stdout]   44:     0x5c26a22b2d0f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/sys/thread/unix.rs:127:17
[INFO] [stdout]   45:     0x7f9daba12dfa - <unknown>
[INFO] [stdout]   46:     0x7f9dabaa63d4 - clone
[INFO] [stdout]   47:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     cross_file_same_date_directives_keep_include_order
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 0 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.29s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--test cross_file_booking_order`
[INFO] running `Command { std: "docker" "inspect" "4ad170c76b5d85d6439ca435aa2f5098fa997f26fafc2fdc4d328aabe207bcce", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "4ad170c76b5d85d6439ca435aa2f5098fa997f26fafc2fdc4d328aabe207bcce", kill_on_drop: false }`
[INFO] [stdout] 4ad170c76b5d85d6439ca435aa2f5098fa997f26fafc2fdc4d328aabe207bcce
