Dec 13 22:23:24.687 INFO testing danielwippermann/resol-vbus.rs against try#aa49d8ef14939ddec0e34b346b60174a5673d48f for pr-56550
Dec 13 22:23:24.687 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-0/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +aa49d8ef14939ddec0e34b346b60174a5673d48f-alt build --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 13 22:23:24.992 INFO [stdout] 065030565fbc9f48ca3a67f567ceeb78ebf3798eee326464f52fe809f16c5cf5
Dec 13 22:23:24.995 INFO running `"docker" "start" "-a" "065030565fbc9f48ca3a67f567ceeb78ebf3798eee326464f52fe809f16c5cf5"`
Dec 13 22:23:25.919 INFO [stderr] usermod: no changes
Dec 13 22:23:25.967 INFO [stderr]    Compiling resol-vbus v0.2.0 (/source)
Dec 13 22:23:28.982 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 3.05s
Dec 13 22:23:29.036 INFO [stderr] su: No module specific data is present
Dec 13 22:23:29.320 INFO running `"docker" "inspect" "065030565fbc9f48ca3a67f567ceeb78ebf3798eee326464f52fe809f16c5cf5"`
Dec 13 22:23:29.404 INFO running `"docker" "rm" "-f" "065030565fbc9f48ca3a67f567ceeb78ebf3798eee326464f52fe809f16c5cf5"`
Dec 13 22:23:29.468 INFO [stdout] 065030565fbc9f48ca3a67f567ceeb78ebf3798eee326464f52fe809f16c5cf5
Dec 13 22:23:29.472 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-0/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +aa49d8ef14939ddec0e34b346b60174a5673d48f-alt test --frozen --no-run" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 13 22:23:29.676 INFO [stdout] 7f4f56163392dc58230974e4168754eac837b67b488ac741ffeb726ab2af093f
Dec 13 22:23:29.677 INFO running `"docker" "start" "-a" "7f4f56163392dc58230974e4168754eac837b67b488ac741ffeb726ab2af093f"`
Dec 13 22:23:30.225 INFO [stderr] usermod: no changes
Dec 13 22:23:30.347 INFO [stderr]    Compiling resol-vbus v0.2.0 (/source)
Dec 13 22:23:39.622 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 9.37s
Dec 13 22:23:39.627 INFO [stderr] su: No module specific data is present
Dec 13 22:23:40.281 INFO running `"docker" "inspect" "7f4f56163392dc58230974e4168754eac837b67b488ac741ffeb726ab2af093f"`
Dec 13 22:23:40.523 INFO running `"docker" "rm" "-f" "7f4f56163392dc58230974e4168754eac837b67b488ac741ffeb726ab2af093f"`
Dec 13 22:23:40.735 INFO [stdout] 7f4f56163392dc58230974e4168754eac837b67b488ac741ffeb726ab2af093f
Dec 13 22:23:40.741 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-0/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +aa49d8ef14939ddec0e34b346b60174a5673d48f-alt test --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 13 22:23:41.207 INFO [stdout] 6035eba4ad69ae53a3667590eece1d5bf1ef1046c68414e27d1e71410d328a14
Dec 13 22:23:41.210 INFO running `"docker" "start" "-a" "6035eba4ad69ae53a3667590eece1d5bf1ef1046c68414e27d1e71410d328a14"`
Dec 13 22:23:42.473 INFO [stderr] usermod: no changes
Dec 13 22:23:42.559 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.06s
Dec 13 22:23:42.559 INFO [stderr]      Running /target/debug/deps/resol_vbus-9e70baab7fc9c851
Dec 13 22:23:42.564 INFO [stdout] 
Dec 13 22:23:42.564 INFO [stdout] running 83 tests
Dec 13 22:23:42.591 INFO [stdout] test blob_reader::tests::test_new ... ok
Dec 13 22:23:42.591 INFO [stdout] test blob_reader::tests::test_read_with_timeout ... ok
Dec 13 22:23:42.607 INFO [stdout] test blob_reader::tests::test_read ... ok
Dec 13 22:23:42.607 INFO [stdout] test blob_reader::tests::test_consume ... ok
Dec 13 22:23:42.607 INFO [stdout] test blob_reader::tests::test_as_bytes ... ok
Dec 13 22:23:42.607 INFO [stdout] test data::tests::test_into_datagram ... ok
Dec 13 22:23:42.607 INFO [stdout] test data::tests::test_id_hash ... ok
Dec 13 22:23:42.607 INFO [stdout] test data::tests::test_is_telegram ... ok
Dec 13 22:23:42.607 INFO [stdout] test data::tests::test_is_datagram ... ok
Dec 13 22:23:42.612 INFO [stdout] test data::tests::test_is_packet ... ok
Dec 13 22:23:42.612 INFO [stdout] test data::tests::test_into_telegram ... ok
Dec 13 22:23:42.612 INFO [stdout] test data::tests::test_as_header ... ok
Dec 13 22:23:42.619 INFO [stdout] test data::tests::test_into_packet ... ok
Dec 13 22:23:42.619 INFO [stdout] test data::tests::test_eq ... ok
Dec 13 22:23:42.619 INFO [stdout] test data_set::tests::test_clear_packets_older_than ... ok
Dec 13 22:23:42.619 INFO [stdout] test data_set::tests::test_id_hash ... ok
Dec 13 22:23:42.619 INFO [stdout] test data::tests::test_partial_cmp ... ok
Dec 13 22:23:42.619 INFO [stdout] test data_set::tests::test_add_data ... ok
Dec 13 22:23:42.619 INFO [stdout] test data_set::tests::test_add_data_set ... ok
Dec 13 22:23:42.619 INFO [stdout] test data_set::tests::test_remove_data_older_than ... ok
Dec 13 22:23:42.619 INFO [stdout] test live_data_encoder::tests::test_length_from_data ... ok
Dec 13 22:23:42.688 INFO [stdout] test header::tests::test_debug_fmt ... ok
Dec 13 22:23:42.688 INFO [stdout] test data_set::tests::test_sort ... ok
Dec 13 22:23:42.688 INFO [stdout] test live_data_decoder::tests::test_data_from_checked_bytes ... ok
Dec 13 22:23:42.688 INFO [stdout] test live_data_decoder::tests::test_length_from_bytes ... ok
Dec 13 22:23:42.688 INFO [stdout] test data_set::tests::test_sort_by ... ok
Dec 13 22:23:42.688 INFO [stdout] test live_data_reader::tests::test_read_data_with_timeout ... ok
Dec 13 22:23:42.688 INFO [stdout] test live_data_encoder::tests::test_bytes_from_data ... ok
Dec 13 22:23:42.688 INFO [stdout] test datagram::tests::test_debug_fmt ... ok
Dec 13 22:23:42.688 INFO [stdout] test live_data_stream::tests::test_begin_bulk_value_transaction ... ok
Dec 13 22:23:42.688 INFO [stdout] test live_data_stream::tests::test_commit_bulk_value_transaction ... ok
Dec 13 22:23:42.688 INFO [stdout] test live_data_stream::tests::test_get_caps1 ... ok
Dec 13 22:23:42.688 INFO [stdout] test live_data_reader::tests::test_read_data ... ok
Dec 13 22:23:42.688 INFO [stdout] test live_data_reader::tests::test_read_bytes_with_timeout ... ok
Dec 13 22:23:42.688 INFO [stdout] test live_data_recording_writer::tests::test_write_live_data ... ok
Dec 13 22:23:42.688 INFO [stdout] test live_data_stream::tests::test_get_value_id_hash_by_index ... ok
Dec 13 22:23:42.688 INFO [stdout] test live_data_recording_reader::tests::test_read_data ... ok
Dec 13 22:23:42.688 INFO [stdout] test live_data_stream::tests::test_get_value_index_by_id_hash ... ok
Dec 13 22:23:42.688 INFO [stdout] test live_data_reader::tests::test_read_bytes ... ok
Dec 13 22:23:42.688 INFO [stdout] test live_data_stream::tests::test_rollback_bulk_value_transaction ... ok
Dec 13 22:23:42.688 INFO [stdout] test live_data_stream::tests::test_new ... ok
Dec 13 22:23:42.688 INFO [stdout] test live_data_stream::tests::test_transmit ... ok
Dec 13 22:23:42.688 INFO [stdout] test live_data_stream::tests::test_wait_for_free_bus ... ok
Dec 13 22:23:42.688 INFO [stdout] test live_data_writer::tests::test_write_data ... ok
Dec 13 22:23:42.688 INFO [stdout] test live_data_recording_reader::tests::test_read_topology_data_set ... ok
Dec 13 22:23:42.688 INFO [stdout] test live_data_stream::tests::test_release_bus ... ok
Dec 13 22:23:42.688 INFO [stdout] test live_data_stream::tests::test_set_bulk_value_by_index ... ok
Dec 13 22:23:42.688 INFO [stdout] test live_data_stream::tests::test_transceive ... ok
Dec 13 22:23:42.688 INFO [stdout] test packet::tests::test_debug_fmt ... ok
Dec 13 22:23:42.689 INFO [stdout] test live_data_stream::tests::test_get_value_by_index ... ok
Dec 13 22:23:42.689 INFO [stdout] test packet::tests::test_str_to_packet_id ... ok
Dec 13 22:23:42.689 INFO [stdout] test recording_decoder::tests::test_data_from_bytes ... ok
Dec 13 22:23:42.689 INFO [stdout] test recording_encoder::tests::test_bytes_from_data ... ok
Dec 13 22:23:42.689 INFO [stdout] test recording_encoder::tests::test_bytes_from_record ... ok
Dec 13 22:23:42.689 INFO [stdout] test recording_encoder::tests::test_bytes_from_channel ... ok
Dec 13 22:23:42.689 INFO [stdout] test live_data_stream::tests::test_receive ... ok
Dec 13 22:23:42.689 INFO [stdout] test recording_encoder::tests::test_length_from_data ... ok
Dec 13 22:23:42.689 INFO [stdout] test recording_encoder::tests::test_bytes_from_timestamp ... ok
Dec 13 22:23:42.689 INFO [stdout] test recording_reader::tests::test_read_data_set ... ok
Dec 13 22:23:42.689 INFO [stdout] test recording_decoder::tests::test_data_from_checked_bytes ... ok
Dec 13 22:23:42.689 INFO [stdout] test recording_reader::tests::test_read_to_next_data_set_record ... ok
Dec 13 22:23:42.689 INFO [stdout] test recording_decoder::tests::test_timestamp_from_checked_bytes ... ok
Dec 13 22:23:42.689 INFO [stdout] test recording_decoder::tests::test_length_from_bytes ... ok
Dec 13 22:23:42.689 INFO [stdout] test specification::tests::test_fields_in_data_set ... ok
Dec 13 22:23:42.689 INFO [stdout] test live_data_stream::tests::test_set_value_by_index ... ok
Dec 13 22:23:42.689 INFO [stdout] test recording_writer::tests::test_write_data_set ... ok
Dec 13 22:23:42.689 INFO [stdout] test recording_reader::tests::test_read_record ... ok
Dec 13 22:23:42.689 INFO [stdout] test specification::tests::test_from_file ... ok
Dec 13 22:23:42.689 INFO [stdout] test specification::tests::test_power_of_ten_f64 ... ok
Dec 13 22:23:42.689 INFO [stdout] test specification::tests::test_fmt_raw_value ... ok
Dec 13 22:23:42.689 INFO [stdout] test specification::tests::test_power_of_ten_i64 ... ok
Dec 13 22:23:42.689 INFO [stdout] test specification::tests::test_raw_value_formatter ... ok
Dec 13 22:23:42.689 INFO [stdout] test specification::tests::test_get_packet_spec ... ok
Dec 13 22:23:42.689 INFO [stdout] test specification::tests::test_raw_value_f64 ... ok
Dec 13 22:23:42.691 INFO [stdout] test specification::tests::test_raw_value_i64 ... ok
Dec 13 22:23:42.691 INFO [stdout] test specification_file::tests::test_from_bytes ... ok
Dec 13 22:23:42.704 INFO [stdout] test specification::tests::test_get_field_spec ... ok
Dec 13 22:23:42.713 INFO [stdout] test test_utils::test_buffer ... ok
Dec 13 22:23:42.713 INFO [stdout] test test_utils::test_to_hex_string ... ok
Dec 13 22:23:42.713 INFO [stdout] test telegram::tests::test_debug_fmt ... ok
Dec 13 22:23:42.713 INFO [stdout] test specification::tests::test_get_device_spec ... ok
Dec 13 22:23:42.727 INFO [stdout] test tcp_connector::tests::test_connect ... ok
Dec 13 22:23:42.801 INFO [stdout] test specification_file::tests::test_new_default ... ok
Dec 13 22:23:42.801 INFO [stdout] 
Dec 13 22:23:42.801 INFO [stdout] test result: ok. 83 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 22:23:42.801 INFO [stdout] 
Dec 13 22:23:42.802 INFO [stderr]    Doc-tests resol-vbus
Dec 13 22:23:43.888 INFO [stdout] 
Dec 13 22:23:43.888 INFO [stdout] running 53 tests
Dec 13 22:23:48.628 INFO [stdout] test src/file_list_reader.rs - file_list_reader::FileListReader (line 13) ... ok
Dec 13 22:23:49.767 INFO [stdout] test src/blob_reader.rs - blob_reader::BlobReader (line 15) ... ok
Dec 13 22:23:50.499 INFO [stdout] test src/datagram.rs - datagram::Datagram::id_hash (line 113) ... ok
Dec 13 22:23:52.879 INFO [stdout] test src/data.rs - data::Data (line 15) ... ok
Dec 13 22:23:53.758 INFO [stdout] test src/data_set.rs - data_set::DataSet (line 16) ... ok
Dec 13 22:23:54.335 INFO [stdout] test src/header.rs - header::Header::id_string (line 67) ... ok
Dec 13 22:23:54.724 INFO [stdout] test src/datagram.rs - datagram::Datagram::id_string (line 54) ... ok
Dec 13 22:23:54.754 INFO [stdout] test src/header.rs - header::Header::id_hash (line 101) ... ok
Dec 13 22:23:58.531 INFO [stdout] test src/id_hash.rs - id_hash::id_hash (line 16) ... ok
Dec 13 22:23:58.804 INFO [stdout] test src/lib.rs -  (line 50) ... ok
Dec 13 22:24:00.131 INFO [stdout] test src/lib.rs -  (line 97) ... ok
Dec 13 22:24:02.843 INFO [stdout] test src/live_data_reader.rs - live_data_reader::LiveDataReader (line 17) ... ok
Dec 13 22:24:03.960 INFO [stdout] test src/packet.rs - packet::Packet::id_hash (line 435) ... ok
Dec 13 22:24:04.115 INFO [stdout] test src/live_data_recording_reader.rs - live_data_recording_reader::LiveDataRecordingReader (line 29) ... ok
Dec 13 22:24:05.260 INFO [stdout] test src/packet.rs - packet::Packet::packet_id (line 360) ... ok
Dec 13 22:24:05.283 INFO [stdout] test src/packet.rs - packet::Packet::id_string (line 395) ... ok
Dec 13 22:24:06.951 INFO [stdout] test src/packet.rs - packet::Packet::valid_frame_data (line 294) ... ok
Dec 13 22:24:07.127 INFO [stdout] test src/packet.rs - packet::Packet::valid_frame_data_len (line 267) ... ok
Dec 13 22:24:08.536 INFO [stdout] test src/packet.rs - packet::Packet::valid_frame_data_mut (line 323) ... ok
Dec 13 22:24:10.492 INFO [stdout] test src/packet.rs - packet::PacketFieldId<'a>::packet_field_id_string (line 165) ... ok
Dec 13 22:24:11.605 INFO [stdout] test src/packet.rs - packet::PacketId::packet_id_string (line 26) ... ok
Dec 13 22:24:12.879 INFO [stdout] test src/packet.rs - packet::PacketFieldId<'a>::packet_id_string (line 151) ... ok
Dec 13 22:24:13.107 INFO [stdout] test src/packet.rs - packet::str::to_packet_field_id (line 202) ... ok
Dec 13 22:24:13.634 INFO [stdout] test src/packet.rs - packet::str::to_packet_id (line 62) ... ok
Dec 13 22:24:14.276 INFO [stdout] test src/specification.rs - specification::DataSetPacketFieldIterator (line 213) ... ok
Dec 13 22:24:15.279 INFO [stdout] test src/recording_reader.rs - recording_reader::RecordingReader (line 16) ... ok
Dec 13 22:24:17.722 INFO [stdout] test src/specification.rs - specification::DeviceSpec (line 19) ... ok
Dec 13 22:24:18.107 INFO [stdout] test src/specification.rs - specification::Specification::get_device_spec (line 487) ... ok
Dec 13 22:24:18.623 INFO [stdout] test src/specification.rs - specification::PacketFieldSpec (line 104) ... ok
Dec 13 22:24:20.491 INFO [stdout] test src/specification.rs - specification::Specification::fields_in_data_set (line 557) ... ok
Dec 13 22:24:21.543 INFO [stdout] test src/specification.rs - specification::Specification (line 186) ... ok
Dec 13 22:24:22.127 INFO [stdout] test src/specification.rs - specification::Specification::fmt_timestamp (line 586) ... ok
Dec 13 22:24:22.248 INFO [stdout] test src/specification.rs - specification::PacketSpec (line 54) ... ok
Dec 13 22:24:22.967 INFO [stdout] test src/specification.rs - specification::Specification::from_file (line 463) ... ok
Dec 13 22:24:26.735 INFO [stdout] test src/tcp_connector.rs - tcp_connector::TcpConnector (line 10) ... ok
Dec 13 22:24:27.731 INFO [stdout] test src/specification.rs - specification::Specification::get_packet_spec (line 508) ... ok
Dec 13 22:24:27.968 INFO [stdout] test src/specification_file.rs - specification_file::Language (line 106) ... ok
Dec 13 22:24:28.871 INFO [stdout] test src/specification.rs - specification::Specification::get_packet_spec_by_id (line 533) ... ok
Dec 13 22:24:29.159 INFO [stdout] test src/telegram.rs - telegram::Telegram::frame_count (line 55) ... ok
Dec 13 22:24:31.627 INFO [stdout] test src/telegram.rs - telegram::Telegram::frame_count_from_command (line 39) ... ok
Dec 13 22:24:31.627 INFO [stdout] test src/telegram.rs - telegram::Telegram::id_hash (line 208) ... ok
Dec 13 22:24:31.647 INFO [stdout] test src/telegram.rs - telegram::Telegram::id_string (line 169) ... ok
Dec 13 22:24:34.527 INFO [stdout] test src/telegram.rs - telegram::Telegram::valid_frame_data (line 107) ... ok
Dec 13 22:24:35.607 INFO [stdout] test src/telegram.rs - telegram::Telegram::valid_frame_data_mut (line 134) ... ok
Dec 13 22:24:36.015 INFO [stdout] test src/telegram.rs - telegram::Telegram::valid_frame_data_len (line 81) ... ok
Dec 13 22:24:36.309 INFO [stdout] test src/utils.rs - utils::calc_and_compare_checksum_v0 (line 30) ... ok
Dec 13 22:24:38.187 INFO [stdout] test src/utils.rs - utils::calc_and_set_checksum_v0 (line 49) ... ok
Dec 13 22:24:38.499 INFO [stdout] test src/utils.rs - utils::calc_checksum_v0 (line 9) ... ok
Dec 13 22:24:39.190 INFO [stdout] test src/utils.rs - utils::calc_crc16 (line 190) ... ok
Dec 13 22:24:39.583 INFO [stdout] test src/utils.rs - utils::copy_bytes_extracting_septett (line 70) ... ok
Dec 13 22:24:41.033 INFO [stdout] test src/utils.rs - utils::copy_bytes_injecting_septett (line 105) ... ok
Dec 13 22:24:41.234 INFO [stdout] test src/utils.rs - utils::utc_timestamp (line 209) ... ok
Dec 13 22:24:41.359 INFO [stdout] test src/utils.rs - utils::has_msb_set (line 137) ... ok
Dec 13 22:24:41.359 INFO [stdout] 
Dec 13 22:24:41.360 INFO [stdout] test result: ok. 53 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 22:24:41.360 INFO [stdout] 
Dec 13 22:24:41.403 INFO [stderr] su: No module specific data is present
Dec 13 22:24:43.058 INFO running `"docker" "inspect" "6035eba4ad69ae53a3667590eece1d5bf1ef1046c68414e27d1e71410d328a14"`
Dec 13 22:24:43.391 INFO running `"docker" "rm" "-f" "6035eba4ad69ae53a3667590eece1d5bf1ef1046c68414e27d1e71410d328a14"`
Dec 13 22:24:43.687 INFO [stdout] 6035eba4ad69ae53a3667590eece1d5bf1ef1046c68414e27d1e71410d328a14