[INFO] cloning repository https://github.com/danielwippermann/resol-vbus.rs
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/danielwippermann/resol-vbus.rs" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fdanielwippermann%2Fresol-vbus.rs", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fdanielwippermann%2Fresol-vbus.rs'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 7cbc64169abfa05b9678969d811d5d731b56938d
[INFO] testing danielwippermann/resol-vbus.rs against master#61469b682c2b0bf9cebc4622f1859e2bb3b7ebca for pr-91743
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fdanielwippermann%2Fresol-vbus.rs" "/workspace/builds/worker-116/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-116/source'...
[INFO] [stderr] done.
[INFO] validating manifest of git repo https://github.com/danielwippermann/resol-vbus.rs on toolchain 61469b682c2b0bf9cebc4622f1859e2bb3b7ebca
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+61469b682c2b0bf9cebc4622f1859e2bb3b7ebca" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking git repo https://github.com/danielwippermann/resol-vbus.rs
[INFO] finished tweaking git repo https://github.com/danielwippermann/resol-vbus.rs
[INFO] tweaked toml for git repo https://github.com/danielwippermann/resol-vbus.rs written to /workspace/builds/worker-116/source/Cargo.toml
[INFO] crate git repo https://github.com/danielwippermann/resol-vbus.rs already has a lockfile, it will not be regenerated
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+61469b682c2b0bf9cebc4622f1859e2bb3b7ebca" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-116/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-116/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:d190cb871061d98bc6d0581d85cb2ecb09a0f8a142ba5463de30be9999fc3251" "/opt/rustwide/cargo-home/bin/cargo" "+61469b682c2b0bf9cebc4622f1859e2bb3b7ebca" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 4fb868f708944dc7e9ef77f72dec136c9380731ff1861f3ff30b4a18ebbd5615
[INFO] running `Command { std: "docker" "start" "-a" "4fb868f708944dc7e9ef77f72dec136c9380731ff1861f3ff30b4a18ebbd5615", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "4fb868f708944dc7e9ef77f72dec136c9380731ff1861f3ff30b4a18ebbd5615", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "4fb868f708944dc7e9ef77f72dec136c9380731ff1861f3ff30b4a18ebbd5615", kill_on_drop: false }`
[INFO] [stdout] 4fb868f708944dc7e9ef77f72dec136c9380731ff1861f3ff30b4a18ebbd5615
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-116/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-116/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" "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:d190cb871061d98bc6d0581d85cb2ecb09a0f8a142ba5463de30be9999fc3251" "/opt/rustwide/cargo-home/bin/cargo" "+61469b682c2b0bf9cebc4622f1859e2bb3b7ebca" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 68e1a3bf3f9f8c8baf88b2b011c3735b628ec00bdabb3f101d5bcb0477c69c92
[INFO] running `Command { std: "docker" "start" "-a" "68e1a3bf3f9f8c8baf88b2b011c3735b628ec00bdabb3f101d5bcb0477c69c92", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]    Compiling autocfg v1.0.1
[INFO] [stderr]    Compiling libc v0.2.101
[INFO] [stderr]    Compiling byteorder v1.4.3
[INFO] [stderr]    Compiling num-traits v0.2.14
[INFO] [stderr]    Compiling num-integer v0.1.44
[INFO] [stderr]    Compiling time v0.1.44
[INFO] [stderr]    Compiling chrono v0.4.19
[INFO] [stderr]    Compiling resol-vbus v0.2.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 5.64s
[INFO] running `Command { std: "docker" "inspect" "68e1a3bf3f9f8c8baf88b2b011c3735b628ec00bdabb3f101d5bcb0477c69c92", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "68e1a3bf3f9f8c8baf88b2b011c3735b628ec00bdabb3f101d5bcb0477c69c92", kill_on_drop: false }`
[INFO] [stdout] 68e1a3bf3f9f8c8baf88b2b011c3735b628ec00bdabb3f101d5bcb0477c69c92
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-116/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-116/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" "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:d190cb871061d98bc6d0581d85cb2ecb09a0f8a142ba5463de30be9999fc3251" "/opt/rustwide/cargo-home/bin/cargo" "+61469b682c2b0bf9cebc4622f1859e2bb3b7ebca" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 49e79d307a0745b88e2ab59466b9dd63f26658575e70b519248c69e749439935
[INFO] running `Command { std: "docker" "start" "-a" "49e79d307a0745b88e2ab59466b9dd63f26658575e70b519248c69e749439935", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]    Compiling resol-vbus v0.2.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 4.13s
[INFO] [stderr]   Executable unittests src/lib.rs (/opt/rustwide/target/debug/deps/resol_vbus-184f0d733f44f10f)
[INFO] running `Command { std: "docker" "inspect" "49e79d307a0745b88e2ab59466b9dd63f26658575e70b519248c69e749439935", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "49e79d307a0745b88e2ab59466b9dd63f26658575e70b519248c69e749439935", kill_on_drop: false }`
[INFO] [stdout] 49e79d307a0745b88e2ab59466b9dd63f26658575e70b519248c69e749439935
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-116/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-116/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" "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:d190cb871061d98bc6d0581d85cb2ecb09a0f8a142ba5463de30be9999fc3251" "/opt/rustwide/cargo-home/bin/cargo" "+61469b682c2b0bf9cebc4622f1859e2bb3b7ebca" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 244a5e7a80ec38a86c47c98079f3486269ffafcc78784a52970ed3651a9c33b7
[INFO] running `Command { std: "docker" "start" "-a" "244a5e7a80ec38a86c47c98079f3486269ffafcc78784a52970ed3651a9c33b7", kill_on_drop: false }`
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.08s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/resol_vbus-184f0d733f44f10f)
[INFO] [stdout] 
[INFO] [stdout] running 96 tests
[INFO] [stdout] test blob_reader::tests::test_into_inner ... ok
[INFO] [stdout] test blob_reader::tests::test_new ... ok
[INFO] [stdout] test blob_buffer::tests::test ... ok
[INFO] [stdout] test blob_reader::tests::test_consume ... ok
[INFO] [stdout] test blob_reader::tests::test_read ... ok
[INFO] [stdout] test data::tests::test_into_packet ... ok
[INFO] [stdout] test data::tests::test_as_packet ... ok
[INFO] [stdout] test data::tests::test_id_hash ... ok
[INFO] [stdout] test data::tests::test_as_datagram ... ok
[INFO] [stdout] test data::tests::test_as_header ... ok
[INFO] [stdout] test data::tests::test_as_telegram ... ok
[INFO] [stdout] test data::tests::test_into_datagram ... ok
[INFO] [stdout] test data::tests::test_eq ... ok
[INFO] [stdout] test data::tests::test_into_telegram ... ok
[INFO] [stdout] test data::tests::test_is_packet ... ok
[INFO] [stdout] test data::tests::test_is_datagram ... ok
[INFO] [stdout] test data::tests::test_is_telegram ... ok
[INFO] [stdout] test data::tests::test_partial_cmp ... ok
[INFO] [stdout] test datagram::tests::test_debug_fmt ... ok
[INFO] [stdout] test error::tests::test_display_fmt ... ok
[INFO] [stdout] test error::tests::test_from_cause ... ok
[INFO] [stdout] test error::tests::test_from_other_error ... ok
[INFO] [stdout] test error::tests::test_new ... ok
[INFO] [stdout] test file_list_reader::tests::test_new ... ok
[INFO] [stdout] test header::tests::test_debug_fmt ... ok
[INFO] [stdout] test live_data_buffer::tests::test_peek_length ... ok
[INFO] [stdout] test file_list_reader::tests::test_read ... ok
[INFO] [stdout] test live_data_buffer::tests::test_read_bytes ... ok
[INFO] [stdout] test data_set::tests::test_add_data ... ok
[INFO] [stdout] test data_set::tests::test_clear_packets_older_than ... ok
[INFO] [stdout] test data_set::tests::test_id_hash ... ok
[INFO] [stdout] test data_set::tests::test_add_data_set ... ok
[INFO] [stdout] test data_set::tests::test_remove_data_older_than ... ok
[INFO] [stdout] test live_data_buffer::tests::test_read_data ... ok
[INFO] [stdout] test data_set::tests::test_sort_by ... ok
[INFO] [stdout] test live_data_decoder::tests::test_length_from_bytes ... ok
[INFO] [stdout] test live_data_decoder::tests::test_data_from_checked_bytes ... ok
[INFO] [stdout] test live_data_encoder::tests::test_length_from_data ... ok
[INFO] [stdout] test data_set::tests::test_sort ... ok
[INFO] [stdout] test live_data_encoder::tests::test_bytes_from_data ... ok
[INFO] [stdout] test live_data_writer::tests::test_write_data ... ok
[INFO] [stdout] test live_data_recording_writer::tests::test_write_live_data ... ok
[INFO] [stdout] test live_data_recording_reader::tests::test_read_to_stats ... ok
[INFO] [stdout] test live_data_reader::tests::test_read_data ... ok
[INFO] [stdout] test packet::tests::test_packet_field_id_packet_field_id_string ... ok
[INFO] [stdout] test packet::tests::test_debug_fmt ... ok
[INFO] [stdout] test live_data_reader::tests::test_read_bytes ... ok
[INFO] [stdout] test live_data_recording_reader::tests::test_read_data ... ok
[INFO] [stdout] test packet::tests::test_packet_field_id_packet_id_string ... ok
[INFO] [stdout] test live_data_recording_reader::tests::test_read_topology_data_set ... ok
[INFO] [stdout] test packet::tests::test_packet_field_id_to_packet_field_id ... ok
[INFO] [stdout] test packet::tests::test_packet_id_string ... ok
[INFO] [stdout] test packet::tests::test_packet_id_to_packet_id ... ok
[INFO] [stdout] test packet::tests::test_str_to_packet_field_id ... ok
[INFO] [stdout] test packet::tests::test_str_to_packet_id ... ok
[INFO] [stdout] test recording_decoder::tests::test_length_from_bytes ... ok
[INFO] [stdout] test recording_decoder::tests::test_data_from_bytes ... ok
[INFO] [stdout] test recording_decoder::tests::test_data_from_checked_bytes ... ok
[INFO] [stdout] test recording_decoder::tests::test_timestamp_from_checked_bytes ... ok
[INFO] [stdout] test recording_encoder::tests::test_bytes_from_record ... ok
[INFO] [stdout] test recording_encoder::tests::test_bytes_from_data ... ok
[INFO] [stdout] test recording_encoder::tests::test_bytes_from_channel ... ok
[INFO] [stdout] test recording_encoder::tests::test_length_from_data ... ok
[INFO] [stdout] test recording_encoder::tests::test_bytes_from_timestamp ... ok
[INFO] [stdout] test recording_reader::tests::test_read_data_set ... ok
[INFO] [stdout] test recording_reader::tests::test_read_to_next_data_set_record ... ok
[INFO] [stdout] test recording_reader::tests::test_read_topology_data_set ... ok
[INFO] [stdout] test recording_writer::tests::test_write_data_set ... ok
[INFO] [stdout] test recording_reader::tests::test_read_record ... ok
[INFO] [stdout] test specification::tests::test_fmt_raw_value ... ok
[INFO] [stdout] test specification::tests::test_from_file ... ok
[INFO] [stdout] test specification::tests::test_get_device_spec ... ok
[INFO] [stdout] test specification::tests::test_fields_in_data_set ... ok
[INFO] [stdout] test specification::tests::test_power_of_ten_f64 ... ok
[INFO] [stdout] test specification::tests::test_power_of_ten_i64 ... ok
[INFO] [stdout] test specification::tests::test_raw_value_formatter ... ok
[INFO] [stdout] test specification::tests::test_get_field_spec ... ok
[INFO] [stdout] test specification::tests::test_get_packet_spec ... ok
[INFO] [stdout] test telegram::tests::test_frame_count ... ok
[INFO] [stdout] test telegram::tests::test_frame_count_from_command ... ok
[INFO] [stdout] test telegram::tests::test_debug_fmt ... ok
[INFO] [stdout] test specification::tests::test_raw_value_f64 ... ok
[INFO] [stdout] test specification::tests::test_raw_value_i64 ... ok
[INFO] [stdout] test specification_file::tests::test_from_bytes ... ok
[INFO] [stdout] test telegram::tests::test_valid_frame_data ... ok
[INFO] [stdout] test telegram::tests::test_valid_frame_data_len ... ok
[INFO] [stdout] test telegram::tests::test_valid_frame_data_mut ... ok
[INFO] [stdout] test test_utils::test_buffer ... ok
[INFO] [stdout] test test_utils::test_to_hex_string ... ok
[INFO] [stdout] test specification_file::tests::test_new_default ... ok
[INFO] [stdout] test data::tests::test_as_packet_panic - should panic ... ok
[INFO] [stdout] test data::tests::test_as_telegram_panic - should panic ... ok
[INFO] [stdout] test data::tests::test_as_datagram_panic - should panic ... ok
[INFO] [stdout] test data::tests::test_into_datagram_panic - should panic ... ok
[INFO] [stdout] test data::tests::test_into_telegram_panic - should panic ... ok
[INFO] [stdout] test data::tests::test_into_packet_panic - should panic ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 96 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.09s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests resol-vbus
[INFO] [stdout] 
[INFO] [stdout] running 53 tests
[INFO] [stdout] test src/blob_reader.rs - blob_reader::BlobReader (line 15) - compile ... ok
[INFO] [stderr] error: test failed, to rerun pass '--doc'
[INFO] [stderr] 
[INFO] [stderr] Caused by:
[INFO] [stderr]   process didn't exit successfully: `rustdoc --edition=2021 --crate-type lib --crate-name resol_vbus --test /opt/rustwide/workdir/src/lib.rs -L dependency=/opt/rustwide/target/debug/deps -L dependency=/opt/rustwide/target/debug/deps --extern byteorder=/opt/rustwide/target/debug/deps/libbyteorder-4d098c56eb4115f5.rlib --extern chrono=/opt/rustwide/target/debug/deps/libchrono-8a2dd79aa03fd491.rlib --extern resol_vbus=/opt/rustwide/target/debug/deps/libresol_vbus-6c8914972aa42d86.rlib -C embed-bitcode=no --error-format human` (signal: 9, SIGKILL: kill)
[INFO] running `Command { std: "docker" "inspect" "244a5e7a80ec38a86c47c98079f3486269ffafcc78784a52970ed3651a9c33b7", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "244a5e7a80ec38a86c47c98079f3486269ffafcc78784a52970ed3651a9c33b7", kill_on_drop: false }`
[INFO] [stdout] 244a5e7a80ec38a86c47c98079f3486269ffafcc78784a52970ed3651a9c33b7