[INFO] fetching crate sodg 0.0.32...
[INFO] testing sodg-0.0.32 against try#2440211fe03bc45c89b6dc1a3df18382ce91e32b for pr-146098-1
[INFO] extracting crate sodg 0.0.32 into /workspace/builds/worker-3-tc2/source
[INFO] started tweaking crates.io crate sodg 0.0.32
[INFO] finished tweaking crates.io crate sodg 0.0.32
[INFO] tweaked toml for crates.io crate sodg 0.0.32 written to /workspace/builds/worker-3-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate sodg 0.0.32 on toolchain 2440211fe03bc45c89b6dc1a3df18382ce91e32b
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+2440211fe03bc45c89b6dc1a3df18382ce91e32b" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+2440211fe03bc45c89b6dc1a3df18382ce91e32b" "generate-lockfile" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]      Locking 107 packages to latest compatible versions
[INFO] [stderr]       Adding bincode v1.3.3 (available: v2.0.1)
[INFO] [stderr]       Adding ctor v0.1.26 (available: v0.5.0)
[INFO] [stderr]       Adding itertools v0.10.5 (available: v0.14.0)
[INFO] [stderr]       Adding rstest v0.16.0 (available: v0.26.1)
[INFO] [stderr]       Adding simple_logger v4.3.3 (available: v5.0.0)
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+2440211fe03bc45c89b6dc1a3df18382ce91e32b" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+2440211fe03bc45c89b6dc1a3df18382ce91e32b" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 278584b149372a5a5f0c16ea3d76be79478abd5cf460386bf3d8aa2370c50982
[INFO] running `Command { std: "docker" "start" "-a" "278584b149372a5a5f0c16ea3d76be79478abd5cf460386bf3d8aa2370c50982", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "278584b149372a5a5f0c16ea3d76be79478abd5cf460386bf3d8aa2370c50982", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "278584b149372a5a5f0c16ea3d76be79478abd5cf460386bf3d8aa2370c50982", kill_on_drop: false }`
[INFO] [stdout] 278584b149372a5a5f0c16ea3d76be79478abd5cf460386bf3d8aa2370c50982
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+2440211fe03bc45c89b6dc1a3df18382ce91e32b" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] b1afba84875c77472e450cd6902713ed76113eeb4783efed3d11c9e304cf0160
[INFO] running `Command { std: "docker" "start" "-a" "b1afba84875c77472e450cd6902713ed76113eeb4783efed3d11c9e304cf0160", kill_on_drop: false }`
[INFO] [stderr]    Compiling syn v1.0.109
[INFO] [stderr]    Compiling powerfmt v0.2.0
[INFO] [stderr]    Compiling num-conv v0.1.0
[INFO] [stderr]    Compiling time-core v0.1.6
[INFO] [stderr]    Compiling typed-arena v1.7.0
[INFO] [stderr]    Compiling cfg-if v1.0.3
[INFO] [stderr]    Compiling rstest_macros v0.16.0
[INFO] [stderr]    Compiling num_threads v0.1.7
[INFO] [stderr]    Compiling peresil v0.3.0
[INFO] [stderr]    Compiling colored v2.2.0
[INFO] [stderr]    Compiling futures-timer v3.0.3
[INFO] [stderr]    Compiling quick-error v1.2.3
[INFO] [stderr]    Compiling futures-macro v0.3.31
[INFO] [stderr]    Compiling xml-builder v0.5.4
[INFO] [stderr]    Compiling bincode v1.3.3
[INFO] [stderr]    Compiling sxd-document v0.3.2
[INFO] [stderr]    Compiling deranged v0.5.4
[INFO] [stderr]    Compiling time-macros v0.2.24
[INFO] [stderr]    Compiling futures-util v0.3.31
[INFO] [stderr]    Compiling sxd-xpath v0.4.2
[INFO] [stderr]    Compiling time v0.3.44
[INFO] [stderr]    Compiling simple_logger v4.3.3
[INFO] [stderr]    Compiling futures-executor v0.3.31
[INFO] [stderr]    Compiling ctor v0.1.26
[INFO] [stderr]    Compiling futures v0.3.31
[INFO] [stderr]    Compiling rstest v0.16.0
[INFO] [stderr]    Compiling sodg v0.0.32 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 14.64s
[INFO] running `Command { std: "docker" "inspect" "b1afba84875c77472e450cd6902713ed76113eeb4783efed3d11c9e304cf0160", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "b1afba84875c77472e450cd6902713ed76113eeb4783efed3d11c9e304cf0160", kill_on_drop: false }`
[INFO] [stdout] b1afba84875c77472e450cd6902713ed76113eeb4783efed3d11c9e304cf0160
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+2440211fe03bc45c89b6dc1a3df18382ce91e32b" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] b58b35da379ac1d7ff50ad6b0295e7ea426ab9b11807efe03b4b7afda7ccd4c7
[INFO] running `Command { std: "docker" "start" "-a" "b58b35da379ac1d7ff50ad6b0295e7ea426ab9b11807efe03b4b7afda7ccd4c7", kill_on_drop: false }`
[INFO] [stderr]    Compiling rstest_macros v0.16.0
[INFO] [stderr]    Compiling humantime v1.3.0
[INFO] [stderr]    Compiling float-cmp v0.10.0
[INFO] [stderr]    Compiling getrandom v0.3.3
[INFO] [stderr]    Compiling once_cell v1.21.3
[INFO] [stderr]    Compiling anstyle v1.0.13
[INFO] [stderr]    Compiling predicates v3.1.3
[INFO] [stderr]    Compiling tempfile v3.23.0
[INFO] [stderr]    Compiling env_logger v0.7.1
[INFO] [stderr]    Compiling fsutils v0.1.7
[INFO] [stderr]    Compiling rstest v0.16.0
[INFO] [stderr]    Compiling sodg v0.0.32 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 7.38s
[INFO] running `Command { std: "docker" "inspect" "b58b35da379ac1d7ff50ad6b0295e7ea426ab9b11807efe03b4b7afda7ccd4c7", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "b58b35da379ac1d7ff50ad6b0295e7ea426ab9b11807efe03b4b7afda7ccd4c7", kill_on_drop: false }`
[INFO] [stdout] b58b35da379ac1d7ff50ad6b0295e7ea426ab9b11807efe03b4b7afda7ccd4c7
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+2440211fe03bc45c89b6dc1a3df18382ce91e32b" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] e916b84d1d63ba39f3891eb212aef0981513d4c2beb6bf14a8d3c67be6dbb407
[INFO] running `Command { std: "docker" "start" "-a" "e916b84d1d63ba39f3891eb212aef0981513d4c2beb6bf14a8d3c67be6dbb407", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.13s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/sodg-595240d64c82764e)
[INFO] [stdout] 
[INFO] [stdout] running 86 tests
[INFO] [stdout] test alerts::dont_panic_when_alerts_disabled ... ok
[INFO] [stdout] test alerts::panic_on_complex_alert ... ok
[INFO] [stdout] test clone::makes_a_clone ... ok
[INFO] [stdout] test alerts::panic_on_simple_alert ... ok
[INFO] [stdout] test ctors::prohibits_empty_labels ... ok
[INFO] [stdout] test ctors::prohibits_labels_of_broken_format::case_1 ... ok
[INFO] [stdout] test ctors::prohibits_labels_of_broken_format::case_2 ... ok
[INFO] [stdout] test ctors::prohibits_labels_of_broken_format::case_3 ... ok
[INFO] [stdout] test ctors::prohibits_labels_with_dot ... ok
[INFO] [stdout] test ctors::prohibits_labels_with_empty_tail ... ok
[INFO] [stdout] test ctors::prohibits_loops ... ok
[INFO] [stdout] test ctors::prohibits_orphan_edges ... ok
[INFO] [stdout] test debug::displays_itself ... ok
[INFO] [stdout] test ctors::makes_an_empty_sodg ... ok
[INFO] [stdout] test debug::prints_itself ... ok
[INFO] [stdout] test dot::simple_graph_to_dot ... ok
[INFO] [stdout] test edge::makes_an_empty_edge ... ok
[INFO] [stdout] test find::closure_return_absolute_vertex ... ok
[INFO] [stdout] test find::finds_root ... ok
[INFO] [stdout] test find::finds_with_closure ... ok
[INFO] [stdout] test find::finds_with_locator ... ok
[INFO] [stdout] test find::relay_modifies_sodg_back ... ok
[INFO] [stdout] test hex::broken_float_from_small_data ... ok
[INFO] [stdout] test hex::broken_int_from_small_data ... ok
[INFO] [stdout] test hex::concat_test ... ok
[INFO] [stdout] test hex::compares_with_data ... ok
[INFO] [stdout] test hex::concatenates_from_hex_vec ... ok
[INFO] [stdout] test hex::correct_equality ... ok
[INFO] [stdout] test hex::creates_from_big_slice ... ok
[INFO] [stdout] test hex::concatenates_from_hex_str ... ok
[INFO] [stdout] test hex::empty_string ... ok
[INFO] [stdout] test hex::makes_string ... ok
[INFO] [stdout] test hex::measures_length ... ok
[INFO] [stdout] test hex::direct_access_to_vec ... ok
[INFO] [stdout] test hex::non_utf8_string ... ok
[INFO] [stdout] test hex::not_enough_data_for_float ... ok
[INFO] [stdout] test hex::not_enough_data_for_int ... ok
[INFO] [stdout] test hex::prints_bytes ... ok
[INFO] [stdout] test hex::prints_empty_bytes ... ok
[INFO] [stdout] test hex::simple_bool ... ok
[INFO] [stdout] test hex::simple_float ... ok
[INFO] [stdout] test hex::simple_int ... ok
[INFO] [stdout] test hex::takes_one_byte ... ok
[INFO] [stdout] test hex::takes_tail ... ok
[INFO] [stdout] test inspect::inspects_simple_object ... ok
[INFO] [stdout] test hex::too_much_data_for_int ... ok
[INFO] [stdout] test merge::keeps_existing_vertices_intact ... ok
[INFO] [stdout] test merge::avoids_simple_duplicates ... ok
[INFO] [stdout] test merge::merges_a_loop ... ok
[INFO] [stdout] test merge::finds_siblings ... ok
[INFO] [stdout] test merge::merges_data ... ok
[INFO] [stdout] test merge::merges_into_empty_graph ... ok
[INFO] [stdout] test merge::merges_large_loop ... ok
[INFO] [stdout] test merge::merges_simple_loop ... ok
[INFO] [stdout] test merge::merges_singletons ... ok
[INFO] [stdout] test merge::merges_two_non_trees ... ok
[INFO] [stdout] test merge::mixed_injection ... ok
[INFO] [stdout] test merge::merges_two_graphs ... ok
[INFO] [stdout] test misc::counts_vertices ... ok
[INFO] [stdout] test misc::collect_vertices ... ok
[INFO] [stdout] test merge::zero_to_zero ... ok
[INFO] [stdout] test merge::understands_same_name_kids ... ok
[INFO] [stdout] test misc::checks_for_emptiness ... ok
[INFO] [stdout] test next::calculates_next_id ... ok
[INFO] [stdout] test next::simple_next_id ... ok
[INFO] [stdout] test ops::adds_simple_vertex ... ok
[INFO] [stdout] test ops::adds_twice ... ok
[INFO] [stdout] test ops::binds_simple_vertices ... ok
[INFO] [stdout] test ops::binds_to_root ... ok
[INFO] [stdout] test merge::two_big_graphs ... ok
[INFO] [stdout] test ops::binds_two_names ... ok
[INFO] [stdout] test ops::builds_list_of_kids ... ok
[INFO] [stdout] test ops::finds_all_kids ... ok
[INFO] [stdout] test ops::gets_absent_kid ... ok
[INFO] [stdout] test ops::gets_data_from_empty_vertex ... ok
[INFO] [stdout] test ops::gets_kid_from_absent_vertex ... ok
[INFO] [stdout] test ops::overwrites_edge ... ok
[INFO] [stdout] test ops::pre_defined_ids ... ok
[INFO] [stdout] test ops::sets_simple_data ... ok
[INFO] [stdout] test vertex::makes_an_empty_vertex ... ok
[INFO] [stdout] test slice::makes_a_partial_slice ... ok
[INFO] [stdout] test slice::makes_a_slice ... ok
[INFO] [stdout] test xml::prints_simple_graph ... ok
[INFO] [stdout] test serialization::saves_and_loads ... ok
[INFO] [stdout] test script::simple_command ... ok
[INFO] [stdout] test slice::skips_some_vertices ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 86 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests sodg
[INFO] [stdout] 
[INFO] [stdout] running 42 tests
[INFO] [stdout] test src/hex.rs - hex::Hex::bytes (line 66) ... ok
[INFO] [stdout] test src/hex.rs - hex::Hex::from (line 398) ... ok
[INFO] [stdout] test src/alerts.rs - alerts::Sodg::alert_on (line 31) ... ok
[INFO] [stdout] test src/hex.rs - hex::Hex::from_slice (line 108) ... ok
[INFO] [stdout] test src/hex.rs - hex::Hex::byte_at (line 301) ... ok
[INFO] [stdout] test src/hex.rs - hex::Hex::from (line 367) ... ok
[INFO] [stdout] test src/hex.rs - hex::Hex::concat (line 331) ... ok
[INFO] [stdout] test src/hex.rs - hex::Hex::from_vec (line 135) ... ok
[INFO] [stdout] test src/find.rs - find::Sodg::find (line 94) ... ok
[INFO] [stdout] test src/hex.rs - hex::Hex::from_str_bytes (line 153) ... ok
[INFO] [stdout] test src/dot.rs - dot::Sodg::to_dot (line 29) ... ok
[INFO] [stdout] test src/hex.rs - hex::Hex::empty (line 51) ... ok
[INFO] [stdout] test src/hex.rs - hex::Hex::from_str (line 428) ... ok
[INFO] [stdout] test src/hex.rs - hex::Hex::from (line 382) ... ok
[INFO] [stdout] test src/hex.rs - hex::Hex::from_str (line 415) ... ok
[INFO] [stdout] test src/hex.rs - hex::Hex::is_empty (line 167) ... ok
[INFO] [stdout] test src/hex.rs - hex::Hex::len (line 91) ... ok
[INFO] [stdout] test src/hex.rs - hex::Hex::len (line 83) ... ok
[INFO] [stdout] test src/hex.rs - hex::Hex::print (line 273) ... ok
[INFO] [stdout] test src/hex.rs - hex::Hex::print (line 265) ... ok
[INFO] [stdout] test src/lib.rs - Sodg (line 147) ... ok
[INFO] [stdout] test src/hex.rs - hex::Hex::tail (line 317) ... ok
[INFO] [stdout] test src/hex.rs - hex::Hex::print (line 257) ... ok
[INFO] [stdout] test src/hex.rs - hex::Hex::to_i64 (line 195) ... ok
[INFO] [stdout] test src/hex.rs - hex::Hex::to_bool (line 181) ... ok
[INFO] [stdout] test src/hex.rs - hex::Hex::to_f64 (line 216) ... ok
[INFO] [stdout] test src/hex.rs - hex::Hex::to_utf8 (line 237) ... ok
[INFO] [stdout] test src/lib.rs - Hex (line 80) ... ok
[INFO] [stdout] test src/misc.rs - misc::Sodg::is_empty (line 42) ... ok
[INFO] [stdout] test src/lib.rs - (line 31) ... ok
[INFO] [stdout] test src/ops.rs - ops::Sodg::bind (line 60) ... ok
[INFO] [stdout] test src/xml.rs - xml::Sodg::to_xml (line 31) ... ok
[INFO] [stdout] test src/ops.rs - ops::Sodg::put (line 106) ... ok
[INFO] [stdout] test src/ops.rs - ops::Sodg::add (line 33) ... ok
[INFO] [stdout] test src/ops.rs - ops::Sodg::data (line 134) ... ok
[INFO] [stdout] test src/ops.rs - ops::Sodg::kid (line 215) ... ok
[INFO] [stdout] test src/lib.rs - Hex (line 88) ... ok
[INFO] [stdout] test src/ops.rs - ops::Sodg::kids (line 190) ... ok
[INFO] [stdout] test src/ops.rs - ops::Sodg::kids (line 177) ... ok
[INFO] [stdout] test src/ops.rs - ops::Sodg::data (line 148) ... ok
[INFO] [stdout] test src/vertex.rs - vertex::Vertex::empty (line 29) ... ok
[INFO] [stdout] test src/script.rs - script::Script::from_str (line 43) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 42 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 3.96s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "e916b84d1d63ba39f3891eb212aef0981513d4c2beb6bf14a8d3c67be6dbb407", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "e916b84d1d63ba39f3891eb212aef0981513d4c2beb6bf14a8d3c67be6dbb407", kill_on_drop: false }`
[INFO] [stdout] e916b84d1d63ba39f3891eb212aef0981513d4c2beb6bf14a8d3c67be6dbb407