[INFO] fetching crate sodg 0.0.32...
[INFO] testing sodg-0.0.32 against 1.95.0 for beta-1.96-2
[INFO] extracting crate sodg 0.0.32 into /workspace/builds/worker-4-tc1/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-4-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate sodg 0.0.32 on toolchain 1.95.0
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.95.0" "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" "+1.95.0" "generate-lockfile" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]      Locking 127 packages to latest compatible versions
[INFO] [stderr]       Adding bincode v1.3.3 (available: v3.0.0)
[INFO] [stderr]       Adding ctor v0.1.26 (available: v0.10.1)
[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.2.0)
[INFO] [stderr]       Adding xml-builder v0.5.4 (available: v0.6.0)
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.95.0" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded sxd-document v0.3.2
[INFO] [stderr]   Downloaded rstest_macros v0.16.0
[INFO] [stderr]   Downloaded rstest v0.16.0
[INFO] [stderr]   Downloaded fsutils v0.1.7
[INFO] [stderr]   Downloaded sxd-xpath v0.4.2
[INFO] [stderr]   Downloaded xml-builder v0.5.4
[INFO] [stderr]   Downloaded peresil v0.3.0
[INFO] [stderr]   Downloaded typed-arena v1.7.0
[INFO] [stderr]   Downloaded simple_logger v4.3.3
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+1.95.0" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 70443bd02c40aeb46a26b772c4ed0bb41267b6bb5683192974004bbbc7e3ec19
[INFO] running `Command { std: "docker" "start" "-a" "70443bd02c40aeb46a26b772c4ed0bb41267b6bb5683192974004bbbc7e3ec19", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "70443bd02c40aeb46a26b772c4ed0bb41267b6bb5683192974004bbbc7e3ec19", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "70443bd02c40aeb46a26b772c4ed0bb41267b6bb5683192974004bbbc7e3ec19", kill_on_drop: false }`
[INFO] [stdout] 70443bd02c40aeb46a26b772c4ed0bb41267b6bb5683192974004bbbc7e3ec19
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/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=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+1.95.0" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 974bed44590614b069718f9f7ba393bec0b4a546f515a24a808d2ce61e431c78
[INFO] running `Command { std: "docker" "start" "-a" "974bed44590614b069718f9f7ba393bec0b4a546f515a24a808d2ce61e431c78", kill_on_drop: false }`
[INFO] [stderr]    Compiling syn v1.0.109
[INFO] [stderr]    Compiling rstest_macros v0.16.0
[INFO] [stderr]    Compiling powerfmt v0.2.0
[INFO] [stderr]    Compiling time-core v0.1.8
[INFO] [stderr]    Compiling num-conv v0.2.1
[INFO] [stderr]    Compiling futures-macro v0.3.32
[INFO] [stderr]    Compiling cfg-if v1.0.4
[INFO] [stderr]    Compiling typed-arena v1.7.0
[INFO] [stderr]    Compiling peresil v0.3.0
[INFO] [stderr]    Compiling num_threads v0.1.7
[INFO] [stderr]    Compiling time-macros v0.2.27
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling sxd-document v0.3.2
[INFO] [stderr]    Compiling futures-timer v3.0.3
[INFO] [stderr]    Compiling deranged v0.5.8
[INFO] [stderr]    Compiling xml-builder v0.5.4
[INFO] [stderr]    Compiling futures-util v0.3.32
[INFO] [stderr]    Compiling bincode v1.3.3
[INFO] [stderr]    Compiling sxd-xpath v0.4.2
[INFO] [stderr]    Compiling time v0.3.47
[INFO] [stderr]    Compiling simple_logger v4.3.3
[INFO] [stderr]    Compiling futures-executor v0.3.32
[INFO] [stderr]    Compiling futures v0.3.32
[INFO] [stderr]    Compiling ctor v0.1.26
[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 22.67s
[INFO] running `Command { std: "docker" "inspect" "974bed44590614b069718f9f7ba393bec0b4a546f515a24a808d2ce61e431c78", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "974bed44590614b069718f9f7ba393bec0b4a546f515a24a808d2ce61e431c78", kill_on_drop: false }`
[INFO] [stdout] 974bed44590614b069718f9f7ba393bec0b4a546f515a24a808d2ce61e431c78
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/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=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+1.95.0" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 41e600518135f9a793a3a2c2a43b884b134a6398c6f2ffab028402f4610ae434
[INFO] running `Command { std: "docker" "start" "-a" "41e600518135f9a793a3a2c2a43b884b134a6398c6f2ffab028402f4610ae434", kill_on_drop: false }`
[INFO] [stderr]    Compiling rstest_macros v0.16.0
[INFO] [stderr]    Compiling float-cmp v0.10.0
[INFO] [stderr]    Compiling getrandom v0.4.2
[INFO] [stderr]    Compiling once_cell v1.21.4
[INFO] [stderr]    Compiling fastrand v2.4.1
[INFO] [stderr]    Compiling predicates-core v1.0.10
[INFO] [stderr]    Compiling fsutils v0.1.7
[INFO] [stderr]    Compiling predicates v3.1.4
[INFO] [stderr]    Compiling tempfile v3.27.0
[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 9.05s
[INFO] running `Command { std: "docker" "inspect" "41e600518135f9a793a3a2c2a43b884b134a6398c6f2ffab028402f4610ae434", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "41e600518135f9a793a3a2c2a43b884b134a6398c6f2ffab028402f4610ae434", kill_on_drop: false }`
[INFO] [stdout] 41e600518135f9a793a3a2c2a43b884b134a6398c6f2ffab028402f4610ae434
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/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=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+1.95.0" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 5960066e9925d54ce141c23e2fa4e0e2919ca756e4e44cf1f9a87bf625dcfff6
[INFO] running `Command { std: "docker" "start" "-a" "5960066e9925d54ce141c23e2fa4e0e2919ca756e4e44cf1f9a87bf625dcfff6", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.18s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/sodg-e52e1a53b967d4e3)
[INFO] [stdout] 
[INFO] [stdout] running 86 tests
[INFO] [stdout] test alerts::dont_panic_when_alerts_disabled ... ok
[INFO] [stdout] test alerts::panic_on_simple_alert ... ok
[INFO] [stdout] test alerts::panic_on_complex_alert ... ok
[INFO] [stdout] test clone::makes_a_clone ... ok
[INFO] [stdout] test ctors::makes_an_empty_sodg ... 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_empty_labels ... ok
[INFO] [stdout] test ctors::prohibits_labels_with_dot ... ok
[INFO] [stdout] test ctors::prohibits_labels_of_broken_format::case_3 ... ok
[INFO] [stdout] test ctors::prohibits_orphan_edges ... ok
[INFO] [stdout] test debug::prints_itself ... ok
[INFO] [stdout] test ctors::prohibits_loops ... ok
[INFO] [stdout] test ctors::prohibits_labels_with_empty_tail ... ok
[INFO] [stdout] test dot::simple_graph_to_dot ... ok
[INFO] [stdout] test find::closure_return_absolute_vertex ... ok
[INFO] [stdout] test find::finds_root ... ok
[INFO] [stdout] test edge::makes_an_empty_edge ... ok
[INFO] [stdout] test debug::displays_itself ... 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_int_from_small_data ... ok
[INFO] [stdout] test hex::concatenates_from_hex_str ... ok
[INFO] [stdout] test hex::compares_with_data ... ok
[INFO] [stdout] test hex::broken_float_from_small_data ... ok
[INFO] [stdout] test hex::concatenates_from_hex_vec ... ok
[INFO] [stdout] test hex::creates_from_big_slice ... ok
[INFO] [stdout] test hex::direct_access_to_vec ... ok
[INFO] [stdout] test hex::correct_equality ... ok
[INFO] [stdout] test hex::concat_test ... 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::not_enough_data_for_int ... ok
[INFO] [stdout] test hex::not_enough_data_for_float ... ok
[INFO] [stdout] test hex::prints_empty_bytes ... ok
[INFO] [stdout] test hex::non_utf8_string ... ok
[INFO] [stdout] test hex::simple_bool ... ok
[INFO] [stdout] test hex::simple_float ... ok
[INFO] [stdout] test hex::prints_bytes ... ok
[INFO] [stdout] test hex::takes_one_byte ... ok
[INFO] [stdout] test hex::simple_int ... ok
[INFO] [stdout] test hex::takes_tail ... ok
[INFO] [stdout] test hex::too_much_data_for_int ... ok
[INFO] [stdout] test inspect::inspects_simple_object ... ok
[INFO] [stdout] test merge::finds_siblings ... ok
[INFO] [stdout] test merge::keeps_existing_vertices_intact ... ok
[INFO] [stdout] test merge::merges_large_loop ... ok
[INFO] [stdout] test merge::merges_singletons ... ok
[INFO] [stdout] test merge::merges_a_loop ... ok
[INFO] [stdout] test merge::avoids_simple_duplicates ... ok
[INFO] [stdout] test merge::merges_simple_loop ... ok
[INFO] [stdout] test merge::merges_data ... ok
[INFO] [stdout] test merge::merges_two_graphs ... ok
[INFO] [stdout] test merge::merges_into_empty_graph ... ok
[INFO] [stdout] test merge::merges_two_non_trees ... ok
[INFO] [stdout] test merge::zero_to_zero ... ok
[INFO] [stdout] test misc::checks_for_emptiness ... ok
[INFO] [stdout] test next::calculates_next_id ... ok
[INFO] [stdout] test merge::mixed_injection ... ok
[INFO] [stdout] test misc::collect_vertices ... ok
[INFO] [stdout] test next::simple_next_id ... ok
[INFO] [stdout] test misc::counts_vertices ... ok
[INFO] [stdout] test ops::adds_simple_vertex ... ok
[INFO] [stdout] test merge::understands_same_name_kids ... ok
[INFO] [stdout] test ops::adds_twice ... ok
[INFO] [stdout] test ops::binds_simple_vertices ... ok
[INFO] [stdout] test ops::binds_two_names ... ok
[INFO] [stdout] test ops::builds_list_of_kids ... ok
[INFO] [stdout] test ops::binds_to_root ... ok
[INFO] [stdout] test ops::finds_all_kids ... ok
[INFO] [stdout] test ops::gets_absent_kid ... ok
[INFO] [stdout] test ops::overwrites_edge ... ok
[INFO] [stdout] test ops::sets_simple_data ... ok
[INFO] [stdout] test ops::gets_kid_from_absent_vertex ... ok
[INFO] [stdout] test ops::gets_data_from_empty_vertex ... ok
[INFO] [stdout] test slice::makes_a_partial_slice ... ok
[INFO] [stdout] test vertex::makes_an_empty_vertex ... ok
[INFO] [stdout] test slice::skips_some_vertices ... ok
[INFO] [stdout] test slice::makes_a_slice ... ok
[INFO] [stdout] test merge::two_big_graphs ... ok
[INFO] [stdout] test ops::pre_defined_ids ... ok
[INFO] [stdout] test xml::prints_simple_graph ... ok
[INFO] [stdout] test script::simple_command ... ok
[INFO] [stdout] test serialization::saves_and_loads ... 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::byte_at (line 301) ... ok
[INFO] [stdout] test src/hex.rs - hex::Hex::bytes (line 66) ... ok
[INFO] [stdout] test src/hex.rs - hex::Hex::from (line 367) ... ok
[INFO] [stdout] test src/hex.rs - hex::Hex::empty (line 51) ... ok
[INFO] [stdout] test src/dot.rs - dot::Sodg::to_dot (line 29) ... ok
[INFO] [stdout] test src/alerts.rs - alerts::Sodg::alert_on (line 31) ... ok
[INFO] [stdout] test src/find.rs - find::Sodg::find (line 94) ... ok
[INFO] [stdout] test src/hex.rs - hex::Hex::from (line 382) ... ok
[INFO] [stdout] test src/hex.rs - hex::Hex::from (line 398) ... ok
[INFO] [stdout] test src/hex.rs - hex::Hex::concat (line 331) ... ok
[INFO] [stdout] test src/hex.rs - hex::Hex::from_str_bytes (line 153) ... ok
[INFO] [stdout] test src/hex.rs - hex::Hex::from_vec (line 135) ... ok
[INFO] [stdout] test src/hex.rs - hex::Hex::from_str (line 415) ... ok
[INFO] [stdout] test src/hex.rs - hex::Hex::len (line 83) ... ok
[INFO] [stdout] test src/hex.rs - hex::Hex::from_str (line 428) ... ok
[INFO] [stdout] test src/hex.rs - hex::Hex::from_slice (line 108) ... 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::print (line 273) ... ok
[INFO] [stdout] test src/hex.rs - hex::Hex::to_bool (line 181) ... 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_f64 (line 216) ... ok
[INFO] [stdout] test src/hex.rs - hex::Hex::print (line 265) ... ok
[INFO] [stdout] test src/hex.rs - hex::Hex::to_utf8 (line 237) ... ok
[INFO] [stdout] test src/hex.rs - hex::Hex::to_i64 (line 195) ... ok
[INFO] [stdout] test src/lib.rs - Sodg (line 147) ... ok
[INFO] [stdout] test src/lib.rs - Hex (line 80) ... ok
[INFO] [stdout] test src/lib.rs - (line 31) ... ok
[INFO] [stdout] test src/misc.rs - misc::Sodg::is_empty (line 42) ... ok
[INFO] [stdout] test src/lib.rs - Hex (line 88) ... ok
[INFO] [stdout] test src/ops.rs - ops::Sodg::data (line 134) ... ok
[INFO] [stdout] test src/ops.rs - ops::Sodg::add (line 33) ... ok
[INFO] [stdout] test src/ops.rs - ops::Sodg::data (line 148) ... ok
[INFO] [stdout] test src/ops.rs - ops::Sodg::put (line 106) ... ok
[INFO] [stdout] test src/ops.rs - ops::Sodg::bind (line 60) ... ok
[INFO] [stdout] test src/ops.rs - ops::Sodg::kid (line 215) ... ok
[INFO] [stdout] test src/ops.rs - ops::Sodg::kids (line 177) ... ok
[INFO] [stdout] test src/xml.rs - xml::Sodg::to_xml (line 31) ... ok
[INFO] [stdout] test src/ops.rs - ops::Sodg::kids (line 190) ... ok
[INFO] [stdout] test src/script.rs - script::Script::from_str (line 43) ... ok
[INFO] [stdout] test src/vertex.rs - vertex::Vertex::empty (line 29) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 42 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 6.71s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "5960066e9925d54ce141c23e2fa4e0e2919ca756e4e44cf1f9a87bf625dcfff6", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "5960066e9925d54ce141c23e2fa4e0e2919ca756e4e44cf1f9a87bf625dcfff6", kill_on_drop: false }`
[INFO] [stdout] 5960066e9925d54ce141c23e2fa4e0e2919ca756e4e44cf1f9a87bf625dcfff6
