Dec 13 22:23:25.420 INFO testing danielwippermann/resol-vbus.rs against master#bd47d6825bf4090517549d33cfef10d3300b4a75 for pr-56550
Dec 13 22:23:25.420 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-4/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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:25.763 INFO [stdout] 4523cff614d87184940baf38d977664303a6e93cbf48d872493c99a9db61ade9
Dec 13 22:23:25.765 INFO running `"docker" "start" "-a" "4523cff614d87184940baf38d977664303a6e93cbf48d872493c99a9db61ade9"`
Dec 13 22:23:26.519 INFO [stderr] usermod: no changes
Dec 13 22:23:26.551 INFO [stderr]    Compiling chrono v0.3.0
Dec 13 22:23:28.986 INFO [stderr]    Compiling resol-vbus v0.2.0 (/source)
Dec 13 22:23:32.026 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 5.51s
Dec 13 22:23:32.036 INFO [stderr] su: No module specific data is present
Dec 13 22:23:32.575 INFO running `"docker" "inspect" "4523cff614d87184940baf38d977664303a6e93cbf48d872493c99a9db61ade9"`
Dec 13 22:23:32.804 INFO running `"docker" "rm" "-f" "4523cff614d87184940baf38d977664303a6e93cbf48d872493c99a9db61ade9"`
Dec 13 22:23:33.091 INFO [stdout] 4523cff614d87184940baf38d977664303a6e93cbf48d872493c99a9db61ade9
Dec 13 22:23:33.095 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-4/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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:33.591 INFO [stdout] 7f86629fad4b2a2aa4155a285983acf097cba9de108983d1e6e44639d4b611d2
Dec 13 22:23:33.593 INFO running `"docker" "start" "-a" "7f86629fad4b2a2aa4155a285983acf097cba9de108983d1e6e44639d4b611d2"`
Dec 13 22:23:34.499 INFO [stderr] usermod: no changes
Dec 13 22:23:34.595 INFO [stderr]    Compiling resol-vbus v0.2.0 (/source)
Dec 13 22:23:43.807 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 9.29s
Dec 13 22:23:43.807 INFO [stderr] su: No module specific data is present
Dec 13 22:23:44.458 INFO running `"docker" "inspect" "7f86629fad4b2a2aa4155a285983acf097cba9de108983d1e6e44639d4b611d2"`
Dec 13 22:23:44.768 INFO running `"docker" "rm" "-f" "7f86629fad4b2a2aa4155a285983acf097cba9de108983d1e6e44639d4b611d2"`
Dec 13 22:23:45.221 INFO [stdout] 7f86629fad4b2a2aa4155a285983acf097cba9de108983d1e6e44639d4b611d2
Dec 13 22:23:45.224 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-4/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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:45.682 INFO [stdout] 0e5622873e7fc3255d753605b05300a8c077ffd5a0a773851573e6a5c17aa3b8
Dec 13 22:23:45.684 INFO running `"docker" "start" "-a" "0e5622873e7fc3255d753605b05300a8c077ffd5a0a773851573e6a5c17aa3b8"`
Dec 13 22:23:46.866 INFO [stderr] usermod: no changes
Dec 13 22:23:46.961 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.07s
Dec 13 22:23:46.961 INFO [stderr]      Running /target/debug/deps/resol_vbus-2fe70435608fd16f
Dec 13 22:23:46.967 INFO [stdout] 
Dec 13 22:23:46.967 INFO [stdout] running 83 tests
Dec 13 22:23:46.975 INFO [stdout] test blob_reader::tests::test_as_bytes ... ok
Dec 13 22:23:46.975 INFO [stdout] test blob_reader::tests::test_new ... ok
Dec 13 22:23:46.975 INFO [stdout] test blob_reader::tests::test_consume ... ok
Dec 13 22:23:46.975 INFO [stdout] test data::tests::test_as_header ... ok
Dec 13 22:23:46.975 INFO [stdout] test blob_reader::tests::test_read ... ok
Dec 13 22:23:46.975 INFO [stdout] test data::tests::test_into_packet ... ok
Dec 13 22:23:46.975 INFO [stdout] test data::tests::test_eq ... ok
Dec 13 22:23:46.975 INFO [stdout] test blob_reader::tests::test_read_with_timeout ... ok
Dec 13 22:23:46.975 INFO [stdout] test data::tests::test_id_hash ... ok
Dec 13 22:23:46.975 INFO [stdout] test data::tests::test_is_packet ... ok
Dec 13 22:23:46.975 INFO [stdout] test data::tests::test_is_telegram ... ok
Dec 13 22:23:46.975 INFO [stdout] test data::tests::test_partial_cmp ... ok
Dec 13 22:23:46.975 INFO [stdout] test data::tests::test_is_datagram ... ok
Dec 13 22:23:46.975 INFO [stdout] test data_set::tests::test_add_data_set ... ok
Dec 13 22:23:46.975 INFO [stdout] test data_set::tests::test_add_data ... ok
Dec 13 22:23:46.975 INFO [stdout] test data_set::tests::test_clear_packets_older_than ... ok
Dec 13 22:23:46.975 INFO [stdout] test data_set::tests::test_id_hash ... ok
Dec 13 22:23:46.975 INFO [stdout] test data_set::tests::test_remove_data_older_than ... ok
Dec 13 22:23:46.975 INFO [stdout] test data_set::tests::test_sort ... ok
Dec 13 22:23:46.975 INFO [stdout] test data_set::tests::test_sort_by ... ok
Dec 13 22:23:46.975 INFO [stdout] test datagram::tests::test_debug_fmt ... ok
Dec 13 22:23:46.975 INFO [stdout] test header::tests::test_debug_fmt ... ok
Dec 13 22:23:46.975 INFO [stdout] test live_data_decoder::tests::test_data_from_checked_bytes ... ok
Dec 13 22:23:46.975 INFO [stdout] test live_data_decoder::tests::test_length_from_bytes ... ok
Dec 13 22:23:46.975 INFO [stdout] test data::tests::test_into_datagram ... ok
Dec 13 22:23:46.980 INFO [stdout] test live_data_encoder::tests::test_length_from_data ... ok
Dec 13 22:23:46.980 INFO [stdout] test live_data_encoder::tests::test_bytes_from_data ... ok
Dec 13 22:23:46.980 INFO [stdout] test data::tests::test_into_telegram ... ok
Dec 13 22:23:46.983 INFO [stdout] test live_data_recording_reader::tests::test_read_topology_data_set ... ok
Dec 13 22:23:46.983 INFO [stdout] test live_data_recording_reader::tests::test_read_data ... ok
Dec 13 22:23:46.983 INFO [stdout] test live_data_stream::tests::test_get_caps1 ... ok
Dec 13 22:23:46.983 INFO [stdout] test live_data_reader::tests::test_read_bytes_with_timeout ... ok
Dec 13 22:23:46.983 INFO [stdout] test live_data_reader::tests::test_read_data ... ok
Dec 13 22:23:46.991 INFO [stdout] test live_data_reader::tests::test_read_data_with_timeout ... ok
Dec 13 22:23:46.999 INFO [stdout] test live_data_stream::tests::test_commit_bulk_value_transaction ... ok
Dec 13 22:23:46.999 INFO [stdout] test live_data_recording_writer::tests::test_write_live_data ... ok
Dec 13 22:23:46.999 INFO [stdout] test live_data_stream::tests::test_begin_bulk_value_transaction ... ok
Dec 13 22:23:46.999 INFO [stdout] test live_data_stream::tests::test_get_value_by_index ... ok
Dec 13 22:23:46.999 INFO [stdout] test live_data_stream::tests::test_get_value_id_hash_by_index ... ok
Dec 13 22:23:46.999 INFO [stdout] test live_data_reader::tests::test_read_bytes ... ok
Dec 13 22:23:46.999 INFO [stdout] test live_data_stream::tests::test_get_value_index_by_id_hash ... ok
Dec 13 22:23:46.999 INFO [stdout] test live_data_stream::tests::test_new ... ok
Dec 13 22:23:47.007 INFO [stdout] test live_data_stream::tests::test_transmit ... ok
Dec 13 22:23:47.007 INFO [stdout] test live_data_stream::tests::test_transceive ... ok
Dec 13 22:23:47.007 INFO [stdout] test live_data_stream::tests::test_rollback_bulk_value_transaction ... ok
Dec 13 22:23:47.007 INFO [stdout] test live_data_stream::tests::test_set_bulk_value_by_index ... ok
Dec 13 22:23:47.007 INFO [stdout] test live_data_stream::tests::test_release_bus ... ok
Dec 13 22:23:47.007 INFO [stdout] test recording_decoder::tests::test_data_from_checked_bytes ... ok
Dec 13 22:23:47.007 INFO [stdout] test recording_decoder::tests::test_length_from_bytes ... ok
Dec 13 22:23:47.007 INFO [stdout] test recording_decoder::tests::test_timestamp_from_checked_bytes ... ok
Dec 13 22:23:47.007 INFO [stdout] test recording_encoder::tests::test_bytes_from_channel ... ok
Dec 13 22:23:47.007 INFO [stdout] test recording_encoder::tests::test_bytes_from_data ... ok
Dec 13 22:23:47.007 INFO [stdout] test recording_encoder::tests::test_bytes_from_record ... ok
Dec 13 22:23:47.007 INFO [stdout] test recording_encoder::tests::test_bytes_from_timestamp ... ok
Dec 13 22:23:47.007 INFO [stdout] test recording_encoder::tests::test_length_from_data ... ok
Dec 13 22:23:47.010 INFO [stdout] test live_data_stream::tests::test_receive ... ok
Dec 13 22:23:47.010 INFO [stdout] test live_data_stream::tests::test_wait_for_free_bus ... ok
Dec 13 22:23:47.010 INFO [stdout] test recording_reader::tests::test_read_data_set ... ok
Dec 13 22:23:47.010 INFO [stdout] test live_data_writer::tests::test_write_data ... ok
Dec 13 22:23:47.010 INFO [stdout] test packet::tests::test_debug_fmt ... ok
Dec 13 22:23:47.010 INFO [stdout] test packet::tests::test_str_to_packet_id ... ok
Dec 13 22:23:47.010 INFO [stdout] test recording_decoder::tests::test_data_from_bytes ... ok
Dec 13 22:23:47.011 INFO [stdout] test recording_reader::tests::test_read_to_next_data_set_record ... ok
Dec 13 22:23:47.023 INFO [stdout] test recording_reader::tests::test_read_record ... ok
Dec 13 22:23:47.023 INFO [stdout] test recording_writer::tests::test_write_data_set ... ok
Dec 13 22:23:47.023 INFO [stdout] test specification::tests::test_from_file ... ok
Dec 13 22:23:47.027 INFO [stdout] test specification::tests::test_get_device_spec ... ok
Dec 13 22:23:47.027 INFO [stdout] test specification::tests::test_get_field_spec ... ok
Dec 13 22:23:47.027 INFO [stdout] test specification::tests::test_get_packet_spec ... ok
Dec 13 22:23:47.027 INFO [stdout] test live_data_stream::tests::test_set_value_by_index ... ok
Dec 13 22:23:47.027 INFO [stdout] test specification::tests::test_fmt_raw_value ... ok
Dec 13 22:23:47.027 INFO [stdout] test specification::tests::test_raw_value_i64 ... ok
Dec 13 22:23:47.027 INFO [stdout] test specification::tests::test_power_of_ten_i64 ... ok
Dec 13 22:23:47.027 INFO [stdout] test telegram::tests::test_debug_fmt ... ok
Dec 13 22:23:47.027 INFO [stdout] test test_utils::test_buffer ... ok
Dec 13 22:23:47.027 INFO [stdout] test test_utils::test_to_hex_string ... ok
Dec 13 22:23:47.039 INFO [stdout] test specification::tests::test_fields_in_data_set ... ok
Dec 13 22:23:47.039 INFO [stdout] test specification::tests::test_raw_value_f64 ... ok
Dec 13 22:23:47.039 INFO [stdout] test specification_file::tests::test_from_bytes ... ok
Dec 13 22:23:47.039 INFO [stdout] test specification::tests::test_raw_value_formatter ... ok
Dec 13 22:23:47.039 INFO [stdout] test specification::tests::test_power_of_ten_f64 ... ok
Dec 13 22:23:47.048 INFO [stdout] test tcp_connector::tests::test_connect ... ok
Dec 13 22:23:47.083 INFO [stderr]    Doc-tests resol-vbus
Dec 13 22:23:47.083 INFO [stdout] test specification_file::tests::test_new_default ... ok
Dec 13 22:23:47.083 INFO [stdout] 
Dec 13 22:23:47.083 INFO [stdout] test result: ok. 83 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 22:23:47.083 INFO [stdout] 
Dec 13 22:23:48.303 INFO [stdout] 
Dec 13 22:23:48.303 INFO [stdout] running 53 tests
Dec 13 22:23:56.323 INFO [stdout] test src/data.rs - data::Data (line 15) ... ok
Dec 13 22:23:56.557 INFO [stdout] test src/blob_reader.rs - blob_reader::BlobReader (line 15) ... ok
Dec 13 22:23:58.223 INFO [stdout] test src/header.rs - header::Header::id_string (line 67) ... ok
Dec 13 22:23:58.592 INFO [stdout] test src/datagram.rs - datagram::Datagram::id_hash (line 113) ... ok
Dec 13 22:23:58.599 INFO [stdout] test src/data_set.rs - data_set::DataSet (line 16) ... ok
Dec 13 22:23:58.815 INFO [stdout] test src/datagram.rs - datagram::Datagram::id_string (line 54) ... ok
Dec 13 22:24:00.131 INFO [stdout] test src/header.rs - header::Header::id_hash (line 101) ... ok
Dec 13 22:24:00.131 INFO [stdout] test src/file_list_reader.rs - file_list_reader::FileListReader (line 13) ... ok
Dec 13 22:24:03.635 INFO [stdout] test src/lib.rs -  (line 50) ... ok
Dec 13 22:24:05.183 INFO [stdout] test src/id_hash.rs - id_hash::id_hash (line 16) ... ok
Dec 13 22:24:06.131 INFO [stdout] test src/live_data_reader.rs - live_data_reader::LiveDataReader (line 17) ... ok
Dec 13 22:24:06.459 INFO [stdout] test src/lib.rs -  (line 97) ... ok
Dec 13 22:24:07.852 INFO [stdout] test src/live_data_recording_reader.rs - live_data_recording_reader::LiveDataRecordingReader (line 29) ... ok
Dec 13 22:24:07.871 INFO [stdout] test src/packet.rs - packet::Packet::id_string (line 395) ... ok
Dec 13 22:24:08.063 INFO [stdout] test src/packet.rs - packet::Packet::id_hash (line 435) ... ok
Dec 13 22:24:09.467 INFO [stdout] test src/packet.rs - packet::Packet::packet_id (line 360) ... ok
Dec 13 22:24:12.806 INFO [stdout] test src/packet.rs - packet::Packet::valid_frame_data (line 294) ... ok
Dec 13 22:24:12.908 INFO [stdout] test src/packet.rs - packet::Packet::valid_frame_data_mut (line 323) ... ok
Dec 13 22:24:12.923 INFO [stdout] test src/packet.rs - packet::Packet::valid_frame_data_len (line 267) ... ok
Dec 13 22:24:14.651 INFO [stdout] test src/packet.rs - packet::PacketFieldId<'a>::packet_field_id_string (line 165) ... ok
Dec 13 22:24:15.395 INFO [stdout] test src/packet.rs - packet::str::to_packet_id (line 62) ... ok
Dec 13 22:24:15.600 INFO [stdout] test src/packet.rs - packet::str::to_packet_field_id (line 202) ... ok
Dec 13 22:24:15.791 INFO [stdout] test src/packet.rs - packet::PacketFieldId<'a>::packet_id_string (line 151) ... ok
Dec 13 22:24:17.659 INFO [stdout] test src/packet.rs - packet::PacketId::packet_id_string (line 26) ... ok
Dec 13 22:24:17.803 INFO [stdout] test src/specification.rs - specification::DeviceSpec (line 19) ... ok
Dec 13 22:24:19.696 INFO [stdout] test src/specification.rs - specification::DataSetPacketFieldIterator (line 213) ... ok
Dec 13 22:24:22.173 INFO [stdout] test src/recording_reader.rs - recording_reader::RecordingReader (line 16) ... ok
Dec 13 22:24:23.499 INFO [stdout] test src/specification.rs - specification::Specification::fields_in_data_set (line 557) ... ok
Dec 13 22:24:23.835 INFO [stdout] test src/specification.rs - specification::Specification (line 186) ... ok
Dec 13 22:24:23.866 INFO [stdout] test src/specification.rs - specification::PacketSpec (line 54) ... ok
Dec 13 22:24:24.327 INFO [stdout] test src/specification.rs - specification::PacketFieldSpec (line 104) ... ok
Dec 13 22:24:25.847 INFO [stdout] test src/specification.rs - specification::Specification::from_file (line 463) ... ok
Dec 13 22:24:27.019 INFO [stdout] test src/specification.rs - specification::Specification::fmt_timestamp (line 586) ... ok
Dec 13 22:24:28.211 INFO [stdout] test src/tcp_connector.rs - tcp_connector::TcpConnector (line 10) ... ok
Dec 13 22:24:30.115 INFO [stdout] test src/specification.rs - specification::Specification::get_device_spec (line 487) ... ok
Dec 13 22:24:31.871 INFO [stdout] test src/telegram.rs - telegram::Telegram::frame_count (line 55) ... ok
Dec 13 22:24:32.111 INFO [stdout] test src/specification.rs - specification::Specification::get_packet_spec (line 508) ... ok
Dec 13 22:24:32.453 INFO [stdout] test src/specification_file.rs - specification_file::Language (line 106) ... ok
Dec 13 22:24:33.635 INFO [stdout] test src/specification.rs - specification::Specification::get_packet_spec_by_id (line 533) ... ok
Dec 13 22:24:34.722 INFO [stdout] test src/telegram.rs - telegram::Telegram::frame_count_from_command (line 39) ... ok
Dec 13 22:24:35.747 INFO [stdout] test src/telegram.rs - telegram::Telegram::id_hash (line 208) ... ok
Dec 13 22:24:35.883 INFO [stdout] test src/telegram.rs - telegram::Telegram::id_string (line 169) ... ok
Dec 13 22:24:38.181 INFO [stdout] test src/telegram.rs - telegram::Telegram::valid_frame_data (line 107) ... ok
Dec 13 22:24:40.552 INFO [stdout] test src/telegram.rs - telegram::Telegram::valid_frame_data_len (line 81) ... ok
Dec 13 22:24:40.915 INFO [stdout] test src/telegram.rs - telegram::Telegram::valid_frame_data_mut (line 134) ... ok
Dec 13 22:24:41.475 INFO [stdout] test src/utils.rs - utils::calc_and_set_checksum_v0 (line 49) ... ok
Dec 13 22:24:41.607 INFO [stdout] test src/utils.rs - utils::calc_and_compare_checksum_v0 (line 30) ... ok
Dec 13 22:24:41.696 INFO [stdout] test src/utils.rs - utils::copy_bytes_extracting_septett (line 70) ... ok
Dec 13 22:24:42.039 INFO [stdout] test src/utils.rs - utils::calc_checksum_v0 (line 9) ... ok
Dec 13 22:24:42.907 INFO [stdout] test src/utils.rs - utils::calc_crc16 (line 190) ... ok
Dec 13 22:24:44.815 INFO [stdout] test src/utils.rs - utils::has_msb_set (line 137) ... ok
Dec 13 22:24:44.884 INFO [stdout] test src/utils.rs - utils::copy_bytes_injecting_septett (line 105) ... ok
Dec 13 22:24:45.139 INFO [stdout] test src/utils.rs - utils::utc_timestamp (line 209) ... ok
Dec 13 22:24:45.139 INFO [stdout] 
Dec 13 22:24:45.139 INFO [stdout] test result: ok. 53 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 22:24:45.139 INFO [stdout] 
Dec 13 22:24:45.163 INFO [stderr] su: No module specific data is present
Dec 13 22:24:45.475 INFO running `"docker" "inspect" "0e5622873e7fc3255d753605b05300a8c077ffd5a0a773851573e6a5c17aa3b8"`
Dec 13 22:24:45.681 INFO running `"docker" "rm" "-f" "0e5622873e7fc3255d753605b05300a8c077ffd5a0a773851573e6a5c17aa3b8"`
Dec 13 22:24:45.868 INFO [stdout] 0e5622873e7fc3255d753605b05300a8c077ffd5a0a773851573e6a5c17aa3b8