[INFO] fetching crate tracefold-log 0.1.2...
[INFO] testing tracefold-log-0.1.2 against 1.99.0-beta.8 for beta-1.100-2
[INFO] extracting crate tracefold-log 0.1.2 into /workspace/builds/worker-6-tc1/source
[INFO] started tweaking crates.io crate tracefold-log 0.1.2
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate tracefold-log 0.1.2
[INFO] tweaked toml for crates.io crate tracefold-log 0.1.2 written to /workspace/builds/worker-6-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate tracefold-log 0.1.2 on toolchain 1.99.0-beta.8
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.99.0-beta.8" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate tracefold-log 0.1.2 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.99.0-beta.8" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded serde_json_canonicalizer v0.3.2
[INFO] [stderr]   Downloaded ryu-js v1.0.3
[INFO] [stderr]   Downloaded match-lookup v0.1.2
[INFO] [stderr]   Downloaded multibase v0.9.3
[INFO] [stderr]   Downloaded base45 v3.2.0
[INFO] [stderr]   Downloaded serde_ipld_dagcbor v0.7.0
[INFO] [stderr]   Downloaded base256emoji v1.0.2
[INFO] [stderr]   Downloaded tracefold-canon v0.1.2
[INFO] [stderr]   Downloaded ipld-core v0.4.3
[INFO] [stderr]   Downloaded const-str v0.4.3
[INFO] [stderr]   Downloaded cid v0.11.3
[INFO] [stderr]   Downloaded cbor4ii v1.2.2
[INFO] [stderr]   Downloaded tracefold-core v0.1.2
[INFO] [stderr]   Downloaded blake3 v1.8.6
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/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] eeb01170798bc534e93589d7823426e98baac0c1f149f18f7df0c6d297caab61
[INFO] running `Command { std: "docker" "start" "eeb01170798bc534e93589d7823426e98baac0c1f149f18f7df0c6d297caab61", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "eeb01170798bc534e93589d7823426e98baac0c1f149f18f7df0c6d297caab61", 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" "eeb01170798bc534e93589d7823426e98baac0c1f149f18f7df0c6d297caab61" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "eeb01170798bc534e93589d7823426e98baac0c1f149f18f7df0c6d297caab61", 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" "eeb01170798bc534e93589d7823426e98baac0c1f149f18f7df0c6d297caab61" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling proc-macro2 v1.0.107
[INFO] [stderr]    Compiling quote v1.0.47
[INFO] [stderr]    Compiling serde_core v1.0.229
[INFO] [stderr]    Compiling serde v1.0.229
[INFO] [stderr]    Compiling data-encoding v2.11.1
[INFO] [stderr]    Compiling const-str v0.4.3
[INFO] [stderr]    Compiling unsigned-varint v0.8.0
[INFO] [stderr]    Compiling shlex v2.0.1
[INFO] [stderr]    Compiling base45 v3.2.0
[INFO] [stderr]    Compiling base-x v0.2.11
[INFO] [stderr]    Compiling serde_json v1.0.151
[INFO] [stderr]    Compiling thiserror v2.0.19
[INFO] [stderr]    Compiling cbor4ii v1.2.2
[INFO] [stderr]    Compiling ryu-js v1.0.3
[INFO] [stderr]    Compiling arrayvec v0.7.8
[INFO] [stderr]    Compiling cc v1.4.0
[INFO] [stderr]    Compiling syn v3.0.3
[INFO] [stderr]    Compiling syn v2.0.119
[INFO] [stderr]    Compiling blake3 v1.8.6
[INFO] [stderr]    Compiling serde_bytes v0.11.19
[INFO] [stderr]    Compiling serde_derive v1.0.229
[INFO] [stderr]    Compiling data-encoding-macro-internal v0.1.19
[INFO] [stderr]    Compiling thiserror-impl v2.0.19
[INFO] [stderr]    Compiling data-encoding-macro v0.1.21
[INFO] [stderr]    Compiling match-lookup v0.1.2
[INFO] [stderr]    Compiling base256emoji v1.0.2
[INFO] [stderr]    Compiling multibase v0.9.3
[INFO] [stderr]    Compiling multihash v0.19.5
[INFO] [stderr]    Compiling tracefold-core v0.1.2
[INFO] [stderr]    Compiling serde_json_canonicalizer v0.3.2
[INFO] [stderr]    Compiling cid v0.11.3
[INFO] [stderr]    Compiling ipld-core v0.4.3
[INFO] [stderr]    Compiling serde_ipld_dagcbor v0.7.0
[INFO] [stderr]    Compiling tracefold-canon v0.1.2
[INFO] [stderr]    Compiling tracefold-log v0.1.2 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 19.29s
[INFO] running `Command { std: "docker" "inspect" "eeb01170798bc534e93589d7823426e98baac0c1f149f18f7df0c6d297caab61", 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" "eeb01170798bc534e93589d7823426e98baac0c1f149f18f7df0c6d297caab61" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.189
[INFO] [stderr]    Compiling getrandom v0.4.3
[INFO] [stderr]    Compiling zerocopy v0.8.56
[INFO] [stderr]    Compiling bitflags v2.13.1
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling fastrand v2.5.0
[INFO] [stderr]    Compiling once_cell v1.21.4
[INFO] [stderr]    Compiling bit-vec v0.8.0
[INFO] [stderr]    Compiling quick-error v1.2.3
[INFO] [stderr]    Compiling unarray v0.1.4
[INFO] [stderr]    Compiling regex-syntax v0.8.11
[INFO] [stderr]    Compiling rustix v1.1.4
[INFO] [stderr]    Compiling bit-set v0.8.0
[INFO] [stderr]    Compiling getrandom v0.3.4
[INFO] [stderr]    Compiling wait-timeout v0.2.1
[INFO] [stderr]    Compiling rand_core v0.9.5
[INFO] [stderr]    Compiling tempfile v3.27.0
[INFO] [stderr]    Compiling rand_xorshift v0.4.0
[INFO] [stderr]    Compiling rand v0.9.5
[INFO] [stderr]    Compiling rusty-fork v0.3.1
[INFO] [stderr]    Compiling ppv-lite86 v0.2.21
[INFO] [stderr]    Compiling rand_chacha v0.9.0
[INFO] [stderr]    Compiling proptest v1.11.0
[INFO] [stderr]    Compiling tracefold-log v0.1.2 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 21.47s
[INFO] running `Command { std: "docker" "inspect" "eeb01170798bc534e93589d7823426e98baac0c1f149f18f7df0c6d297caab61", 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" "eeb01170798bc534e93589d7823426e98baac0c1f149f18f7df0c6d297caab61" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "test" "--frozen", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.11s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/gx_log-62d824caacdede95)
[INFO] [stdout] 
[INFO] [stdout] running 19 tests
[INFO] [stdout] test head::tests::hex_round_trips ... ok
[INFO] [stdout] test proof::tests::an_empty_log_has_no_head ... ok
[INFO] [stdout] test head::tests::a_project_at_its_published_head_is_not_rolled_back ... ok
[INFO] [stdout] test proof::tests::the_split_is_strictly_below_the_size ... ok
[INFO] [stdout] test tile::tests::a_leaf_outside_the_tree_has_no_length ... ok
[INFO] [stdout] test tile::tests::a_perfect_tree_has_one_path_length ... ok
[INFO] [stdout] test tile::tests::a_ragged_tree_has_two_path_lengths ... ok
[INFO] [stdout] test proof::tests::appending_does_not_move_an_earlier_leaf ... ok
[INFO] [stdout] test proof::tests::a_prefix_root_is_the_root_of_the_prefix ... ok
[INFO] [stdout] test proof::tests::the_root_of_three_leaves_is_the_written_out_one ... ok
[INFO] [stdout] test head::tests::a_shorter_tree_is_named_as_one ... ok
[INFO] [stdout] test tile::tests::the_u64_split_point_agrees_with_the_usize_one ... ok
[INFO] [stdout] test tile::tests::an_unaligned_range_is_folded_from_the_leaves ... ok
[INFO] [stdout] test store::tests::creating_a_ledger_pushes_its_directory_entry_to_the_device ... ok
[INFO] [stdout] test tile::tests::only_completed_tiles_are_cached ... ok
[INFO] [stdout] test store::tests::an_append_answers_only_after_a_barrier_it_completed_itself ... ok
[INFO] [stdout] test tile::tests::the_lawful_length_is_the_generated_length ... ok
[INFO] [stdout] test tile::tests::the_cached_fold_is_the_recursive_fold ... ok
[INFO] [stdout] test tile::tests::the_cached_audit_path_is_the_recursive_path ... ok
[INFO] [stderr]      Running tests/ac_021.rs (/opt/rustwide/target/debug/deps/ac_021-1c89d35b7f1791db)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 19 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 6.67s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 5 tests
[INFO] [stdout] test ac_021_no_merkle_library_is_declared ... ok
[INFO] [stdout] test ac_021_the_public_surface_is_the_declared_one ... ok
[INFO] [stdout] test ac_021_the_scan_actually_reads_a_surface ... ok
[INFO] [stdout] test ac_021_every_public_function_with_a_mutable_receiver_appends ... ok
[INFO] [stdout] test ac_021_no_public_function_is_named_for_a_mutation ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.03s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/ac_022.rs (/opt/rustwide/target/debug/deps/ac_022-5d1981cb129c905c)
[INFO] [stdout] 
[INFO] [stdout] running 9 tests
[INFO] [stdout] test ac_022_the_single_leaf_tree_verifies_with_an_empty_path ... ok
[INFO] [stdout] test ac_022_a_tampered_audit_path_does_not_verify ... ok
[INFO] [stdout] test ac_022_seq_42_of_a_hundred_entries_verifies ... ok
[INFO] [stdout] test ac_022_a_proof_does_not_verify_against_a_truncated_tree ... ok
[INFO] [stdout] test ac_022_a_tampered_entry_does_not_verify ... ok
[INFO] [stdout] test ac_022_an_index_past_the_end_has_no_proof ... ok
[INFO] [stdout] test ac_022_a_wrong_root_rejects ... ok
[INFO] [stdout] test ac_022_every_leaf_of_every_size_verifies ... ok
[INFO] [stdout] test ac_022_a_proof_is_about_the_leaf_it_names ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 9 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.24s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/ac_023.rs (/opt/rustwide/target/debug/deps/ac_023-ea8ca4d52e6e7857)
[INFO] [stdout] 
[INFO] [stdout] running 10 tests
[INFO] [stdout] test ac_023_a_tree_is_consistent_with_itself ... ok
[INFO] [stdout] test ac_023_a_tampered_path_rejects ... ok
[INFO] [stdout] test ac_023_a_rewritten_prefix_cannot_be_made_consistent ... ok
[INFO] [stdout] test ac_023_impossible_sizes_are_refused ... ok
[INFO] [stdout] test ac_023_a_truncated_tree_is_not_a_successor ... ok
[INFO] [stdout] test ac_023_fifty_grown_to_a_hundred_is_consistent ... ok
[INFO] [stdout] test ac_023_a_modified_root_rejects ... ok
[INFO] [stdout] test ac_023_a_proof_is_about_the_two_trees_it_names ... ok
[INFO] [stdout] test ac_023_any_edit_to_the_path_breaks_it ... ok
[INFO] [stdout] test ac_023_every_prefix_is_consistent_with_every_later_size ... ok
[INFO] [stderr]      Running tests/ac_024.rs (/opt/rustwide/target/debug/deps/ac_024-5c59a2a9c96bcc0a)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 10 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.18s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 8 tests
[INFO] [stdout] test ac_024_an_internal_level_holds_only_complete_subtrees ... ok
[INFO] [stdout] test ac_024_level_zero_is_the_leaf_layer ... ok
[INFO] [stdout] test ac_024_the_hash_algorithm_is_a_declared_difference ... ok
[INFO] [stdout] test ac_024_tile_height_matches_the_reference ... ok
[INFO] [stdout] test ac_024_the_digest_length_matches ... ok
[INFO] [stdout] test ac_024_structural_correspondence_is_not_interoperability ... ok
[INFO] [stdout] test ac_024_a_generated_tile_has_the_reference_shape ... ok
[INFO] [stdout] test ac_024_the_domain_separation_bytes_match_the_reference ... ok
[INFO] [stderr]      Running tests/ac_069.rs (/opt/rustwide/target/debug/deps/ac_069-57682d61a13344eb)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 13 tests
[INFO] [stdout] test ac_069_the_entry_becomes_visible_only_after_the_barrier ... ok
[INFO] [stdout] test ac_069_the_record_ceiling_is_consulted_before_the_buffer_is_allocated ... ok
[INFO] [stdout] test ac_069_the_non_unix_directory_sync_is_a_declared_gap_and_still_says_so ... ok
[INFO] [stdout] test ac_069_the_durability_barrier_has_exactly_one_call_site ... ok
[INFO] [stdout] test ac_069_an_absent_file_opens_as_an_empty_ledger ... ok
[INFO] [stdout] test ac_069_a_length_header_over_the_ceiling_is_a_torn_tail ... ok
[INFO] [stdout] test ac_069_a_record_whose_digest_no_longer_matches_is_not_replayed ... ok
[INFO] [stdout] test ac_069_damage_in_the_middle_truncates_from_there ... ok
[INFO] [stdout] test ac_069_appending_resumes_after_a_torn_tail ... ok
[INFO] [stdout] test ac_069_every_entry_a_successful_append_returned_is_there_after_a_reopen ... ok
[INFO] [stdout] test ac_069_the_recovered_log_has_the_root_it_had ... ok
[INFO] [stdout] test ac_069_a_torn_tail_is_discarded_and_the_ledger_still_opens ... ok
[INFO] [stdout] test ac_069_a_reopened_ledger_still_proves_inclusion ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 13 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.08s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/append_idempotence.rs (/opt/rustwide/target/debug/deps/append_idempotence-bae68d5169a5b49f)
[INFO] [stdout] 
[INFO] [stdout] running 5 tests
[INFO] [stdout] test the_same_key_with_a_different_digest_is_rejected ... ok
[INFO] [stdout] test a_repeat_of_the_same_append_is_a_no_op ... ok
[INFO] [stdout] test a_run_of_repeats_leaves_the_bytes_alone ... ok
[INFO] [stdout] test idempotence_survives_a_reopen ... ok
[INFO] [stdout] test distinct_transformations_append_normally ... 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/audit_path_length.rs (/opt/rustwide/target/debug/deps/audit_path_length-3dcdda49f2997a29)
[INFO] [stdout] 
[INFO] [stdout] running 5 tests
[INFO] [stdout] test a_proof_outside_its_own_tree_is_refused ... ok
[INFO] [stdout] test the_length_gate_precedes_the_first_hash ... ok
[INFO] [stdout] test an_oversized_path_is_refused ... ok
[INFO] [stdout] test a_path_of_the_wrong_length_is_refused ... ok
[INFO] [stdout] test a_generated_path_has_the_length_the_two_indices_fix ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.14s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/checkpoint_core.rs (/opt/rustwide/target/debug/deps/checkpoint_core-8796c16d4f626f36)
[INFO] [stdout] 
[INFO] [stdout] running 11 tests
[INFO] [stdout] test a_head_of_a_grown_tree_signs_different_bytes ... ok
[INFO] [stdout] test the_checkpoint_encodes_five_keys_three_covered_and_two_declared_out ... ok
[INFO] [stdout] test the_head_signs_the_bytes_the_core_function_produces ... ok
[INFO] [stdout] test an_unsigned_checkpoint_states_the_tree_it_was_taken_from ... ok
[INFO] [stdout] test an_unsigned_checkpoint_carries_no_signature ... ok
[INFO] [stdout] test the_signed_bytes_are_deterministic ... ok
[INFO] [stdout] test the_signed_bytes_do_not_contain_the_signature ... ok
[INFO] [stdout] test every_field_of_the_core_is_covered ... ok
[INFO] [stdout] test the_signed_bytes_are_canonical ... ok
[INFO] [stdout] test the_signed_bytes_do_not_move_when_the_clock_does ... ok
[INFO] [stdout] test an_empty_log_has_no_head_to_publish ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 11 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/dr529_residual_cells.rs (/opt/rustwide/target/debug/deps/dr529_residual_cells-4af2663ca0fee36d)
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test dr529_log_missing_field_is_treated_as_a_torn_tail_not_silently_accepted ... ok
[INFO] [stderr]      Running tests/incremental_inclusion.rs (/opt/rustwide/target/debug/deps/incremental_inclusion-aee7a678d0762657)
[INFO] [stdout] test dr529_log_order_swap_is_caught_by_index_monotonicity_not_silently_accepted ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.03s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 5 tests
[INFO] [stdout] test an_append_leaves_an_issued_proof_standing ... ok
[INFO] [stdout] test the_proof_carries_three_fields_and_the_cache_is_not_one_of_them ... ok
[INFO] [stdout] test the_completed_tiles_are_cached_and_the_partial_tail_is_not ... ok
[INFO] [stdout] test a_proof_against_an_older_tree_size_is_the_same_proof ... ok
[INFO] [stdout] test every_proof_equals_the_transcribed_rfc_6962_path ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 2.16s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/log_error_vocabulary.rs (/opt/rustwide/target/debug/deps/log_error_vocabulary-9c5be5acc795785b)
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test the_table_holds_five_distinct_words ... ok
[INFO] [stdout] test the_error_vocabulary_is_the_error_enum ... FAILED
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- the_error_vocabulary_is_the_error_enum stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'the_error_vocabulary_is_the_error_enum' (1728) panicked at tests/log_error_vocabulary.rs:33:59:
[INFO] [stdout] cannot read crates/gx-log/src/lib.rs: No such file or directory (os error 2)
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5d64fe506961 - std[70759c8f55707aa4]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x5d64fe506961 - std[70759c8f55707aa4]::backtrace_rs::backtrace::trace_unsynchronized::<std[70759c8f55707aa4]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x5d64fe506961 - std[70759c8f55707aa4]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x5d64fe506961 - <<std[70759c8f55707aa4]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[df12db4294e9bfd3]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x5d64fe51ac3a - <core[df12db4294e9bfd3]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x5d64fe51ac3a - core[df12db4294e9bfd3]::fmt::write
[INFO] [stdout]    6:     0x5d64fe50adfc - core[df12db4294e9bfd3]::io::write::default_write_fmt::<alloc[2182bb758b4b3781]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/core/src/io/write.rs:402:11
[INFO] [stdout]    7:     0x5d64fe50adfc - <alloc[2182bb758b4b3781]::vec::Vec<u8> as core[df12db4294e9bfd3]::io::write::Write>::write_fmt
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/core/src/io/write.rs:335:13
[INFO] [stdout]    8:     0x5d64fe4e2956 - <std[70759c8f55707aa4]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x5d64fe4e2956 - std[70759c8f55707aa4]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x5d64fe4fed59 - std[70759c8f55707aa4]::panicking::default_hook
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x5d64fe4951f0 - <alloc[2182bb758b4b3781]::boxed::Box<dyn for<'a, 'b> core[df12db4294e9bfd3]::ops::function::Fn<(&'a std[70759c8f55707aa4]::panic::PanicHookInfo<'b>,), Output = ()> + core[df12db4294e9bfd3]::marker::Send + core[df12db4294e9bfd3]::marker::Sync> as core[df12db4294e9bfd3]::ops::function::Fn<(&std[70759c8f55707aa4]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/alloc/src/boxed.rs:2334:9
[INFO] [stdout]   12:     0x5d64fe4951f0 - test[9d35eded1c95d3be]::test_main_inner::<test[9d35eded1c95d3be]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x5d64fe4fef12 - <alloc[2182bb758b4b3781]::boxed::Box<dyn for<'a, 'b> core[df12db4294e9bfd3]::ops::function::Fn<(&'a std[70759c8f55707aa4]::panic::PanicHookInfo<'b>,), Output = ()> + core[df12db4294e9bfd3]::marker::Send + core[df12db4294e9bfd3]::marker::Sync> as core[df12db4294e9bfd3]::ops::function::Fn<(&std[70759c8f55707aa4]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/alloc/src/boxed.rs:2334:9
[INFO] [stdout]   14:     0x5d64fe4fef12 - std[70759c8f55707aa4]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/std/src/panicking.rs:823:13
[INFO] [stdout]   15:     0x5d64fe4e2a02 - std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/std/src/panicking.rs:688:13
[INFO] [stdout]   16:     0x5d64fe4db5f9 - std[70759c8f55707aa4]::sys::backtrace::__rust_end_short_backtrace::<std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x5d64fe4e35fd - __rustc[8fa7c3cbc660c2b3]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/std/src/panicking.rs:679:5
[INFO] [stdout]   18:     0x5d64fe51b36c - core[df12db4294e9bfd3]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x5d64fe483863 - log_error_vocabulary[561e69f52642c285]::variants_in_source::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/log_error_vocabulary.rs:33:59
[INFO] [stdout]   20:     0x5d64fe47f061 - <core[df12db4294e9bfd3]::result::Result<alloc[2182bb758b4b3781]::string::String, core[df12db4294e9bfd3]::io::error::Error>>::unwrap_or_else::<log_error_vocabulary[561e69f52642c285]::variants_in_source::{closure#0}>
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/core/src/result.rs:1621:23
[INFO] [stdout]   21:     0x5d64fe483b78 - log_error_vocabulary[561e69f52642c285]::variants_in_source
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/log_error_vocabulary.rs:33:40
[INFO] [stdout]   22:     0x5d64fe4842f2 - log_error_vocabulary[561e69f52642c285]::the_error_vocabulary_is_the_error_enum
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/log_error_vocabulary.rs:67:20
[INFO] [stdout]   23:     0x5d64fe483937 - log_error_vocabulary[561e69f52642c285]::the_error_vocabulary_is_the_error_enum::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/log_error_vocabulary.rs:66:44
[INFO] [stdout]   24:     0x5d64fe488316 - <log_error_vocabulary[561e69f52642c285]::the_error_vocabulary_is_the_error_enum::{closure#0} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   25:     0x5d64fe4884db - <fn() -> core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String> as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   26:     0x5d64fe4884db - test[9d35eded1c95d3be]::__rust_begin_short_backtrace::<core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>, fn() -> core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>>
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/test/src/lib.rs:733:18
[INFO] [stdout]   27:     0x5d64fe495b45 - test[9d35eded1c95d3be]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/test/src/lib.rs:756:74
[INFO] [stdout]   28:     0x5d64fe495b45 - <core[df12db4294e9bfd3]::panic::unwind_safe::AssertUnwindSafe<test[9d35eded1c95d3be]::run_test_in_process::{closure#0}> as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   29:     0x5d64fe495b45 - std[70759c8f55707aa4]::panicking::catch_unwind::do_call::<core[df12db4294e9bfd3]::panic::unwind_safe::AssertUnwindSafe<test[9d35eded1c95d3be]::run_test_in_process::{closure#0}>, core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>>
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/std/src/panicking.rs:576:43
[INFO] [stdout]   30:     0x5d64fe495b45 - std[70759c8f55707aa4]::panicking::catch_unwind::<core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>, core[df12db4294e9bfd3]::panic::unwind_safe::AssertUnwindSafe<test[9d35eded1c95d3be]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/std/src/panicking.rs:544:19
[INFO] [stdout]   31:     0x5d64fe495b45 - std[70759c8f55707aa4]::panic::catch_unwind::<core[df12db4294e9bfd3]::panic::unwind_safe::AssertUnwindSafe<test[9d35eded1c95d3be]::run_test_in_process::{closure#0}>, core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>>
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/std/src/panic.rs:359:14
[INFO] [stdout]   32:     0x5d64fe495b45 - test[9d35eded1c95d3be]::run_test_in_process
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/test/src/lib.rs:756:27
[INFO] [stdout]   33:     0x5d64fe495b45 - test[9d35eded1c95d3be]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/test/src/lib.rs:677:43
[INFO] [stdout]   34:     0x5d64fe48f404 - test[9d35eded1c95d3be]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/test/src/lib.rs:707:41
[INFO] [stdout]   35:     0x5d64fe48f404 - std[70759c8f55707aa4]::sys::backtrace::__rust_begin_short_backtrace::<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   36:     0x5d64fe498ca2 - std[70759c8f55707aa4]::thread::lifecycle::spawn_unchecked::<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   37:     0x5d64fe498ca2 - <core[df12db4294e9bfd3]::panic::unwind_safe::AssertUnwindSafe<std[70759c8f55707aa4]::thread::lifecycle::spawn_unchecked<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   38:     0x5d64fe498ca2 - std[70759c8f55707aa4]::panicking::catch_unwind::do_call::<core[df12db4294e9bfd3]::panic::unwind_safe::AssertUnwindSafe<std[70759c8f55707aa4]::thread::lifecycle::spawn_unchecked<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/std/src/panicking.rs:576:43
[INFO] [stdout]   39:     0x5d64fe498ca2 - std[70759c8f55707aa4]::panicking::catch_unwind::<(), core[df12db4294e9bfd3]::panic::unwind_safe::AssertUnwindSafe<std[70759c8f55707aa4]::thread::lifecycle::spawn_unchecked<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/std/src/panicking.rs:544:19
[INFO] [stdout]   40:     0x5d64fe498ca2 - std[70759c8f55707aa4]::panic::catch_unwind::<core[df12db4294e9bfd3]::panic::unwind_safe::AssertUnwindSafe<std[70759c8f55707aa4]::thread::lifecycle::spawn_unchecked<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/std/src/panic.rs:359:14
[INFO] [stdout]   41:     0x5d64fe498ca2 - std[70759c8f55707aa4]::thread::lifecycle::spawn_unchecked::<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   42:     0x5d64fe498ca2 - <std[70759c8f55707aa4]::thread::lifecycle::spawn_unchecked<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>::{closure#1} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   43:     0x5d64fe505ec9 - <alloc[2182bb758b4b3781]::boxed::Box<dyn core[df12db4294e9bfd3]::ops::function::FnOnce<(), Output = ()> + core[df12db4294e9bfd3]::marker::Send> as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/alloc/src/boxed.rs:2320:9
[INFO] [stdout]   44:     0x5d64fe505ec9 - <std[70759c8f55707aa4]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/std/src/sys/thread/unix.rs:123:17
[INFO] [stdout]   45:     0x7a166559cdfa - <unknown>
[INFO] [stdout]   46:     0x7a16656303d4 - clone
[INFO] [stdout]   47:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     the_error_vocabulary_is_the_error_enum
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 1 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.05s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--test log_error_vocabulary`
[INFO] running `Command { std: "docker" "inspect" "eeb01170798bc534e93589d7823426e98baac0c1f149f18f7df0c6d297caab61", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "eeb01170798bc534e93589d7823426e98baac0c1f149f18f7df0c6d297caab61", kill_on_drop: false }`
[INFO] [stdout] eeb01170798bc534e93589d7823426e98baac0c1f149f18f7df0c6d297caab61
