Dec 15 19:27:29.622 INFO testing cfb-0.3.1 against try#aa49d8ef14939ddec0e34b346b60174a5673d48f for pr-56550
Dec 15 19:27:29.622 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-1/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/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 15 19:27:30.104 INFO [stdout] fc4525a86d16661b0a5e65f4cd7c4b7802f5b209e29530ed0c4d3e023ed4fc4a
Dec 15 19:27:30.107 INFO running `"docker" "start" "-a" "fc4525a86d16661b0a5e65f4cd7c4b7802f5b209e29530ed0c4d3e023ed4fc4a"`
Dec 15 19:27:30.982 INFO [stderr] usermod: no changes
Dec 15 19:27:31.071 INFO [stderr]    Compiling cfb v0.3.1 (/source)
Dec 15 19:27:34.427 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 3.43s
Dec 15 19:27:34.434 INFO [stderr] su: No module specific data is present
Dec 15 19:27:35.236 INFO running `"docker" "inspect" "fc4525a86d16661b0a5e65f4cd7c4b7802f5b209e29530ed0c4d3e023ed4fc4a"`
Dec 15 19:27:35.491 INFO running `"docker" "rm" "-f" "fc4525a86d16661b0a5e65f4cd7c4b7802f5b209e29530ed0c4d3e023ed4fc4a"`
Dec 15 19:27:35.729 INFO [stdout] fc4525a86d16661b0a5e65f4cd7c4b7802f5b209e29530ed0c4d3e023ed4fc4a
Dec 15 19:27:35.733 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-1/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/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 15 19:27:36.414 INFO [stdout] 9551b03512034da75e0efd081e02881438d205f0e657d90848c39b3a32395f79
Dec 15 19:27:36.416 INFO running `"docker" "start" "-a" "9551b03512034da75e0efd081e02881438d205f0e657d90848c39b3a32395f79"`
Dec 15 19:27:37.063 INFO [stderr] usermod: no changes
Dec 15 19:27:37.130 INFO [stderr]    Compiling cfb v0.3.1 (/source)
Dec 15 19:27:42.823 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 5.73s
Dec 15 19:27:42.823 INFO [stderr] su: No module specific data is present
Dec 15 19:27:43.495 INFO running `"docker" "inspect" "9551b03512034da75e0efd081e02881438d205f0e657d90848c39b3a32395f79"`
Dec 15 19:27:43.610 INFO running `"docker" "rm" "-f" "9551b03512034da75e0efd081e02881438d205f0e657d90848c39b3a32395f79"`
Dec 15 19:27:43.711 INFO [stdout] 9551b03512034da75e0efd081e02881438d205f0e657d90848c39b3a32395f79
Dec 15 19:27:43.711 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-1/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/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 15 19:27:43.903 INFO [stdout] 947f25f6c36f2ff93671570897ad9f2e608bde3a4257880d2687c92511fda396
Dec 15 19:27:43.908 INFO running `"docker" "start" "-a" "947f25f6c36f2ff93671570897ad9f2e608bde3a4257880d2687c92511fda396"`
Dec 15 19:27:44.466 INFO [stderr] usermod: no changes
Dec 15 19:27:44.523 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.04s
Dec 15 19:27:44.527 INFO [stderr]      Running /target/debug/deps/cfb-fc22aaa5d45f1343
Dec 15 19:27:44.527 INFO [stdout] 
Dec 15 19:27:44.527 INFO [stdout] running 64 tests
Dec 15 19:27:44.527 INFO [stdout] test internal::entry::tests::nonrecursive_entries_from_storage ... ok
Dec 15 19:27:44.530 INFO [stdout] test internal::entry::tests::preorder_entries_from_root ... ok
Dec 15 19:27:44.530 INFO [stdout] test internal::entry::tests::preorder_entries_from_storage ... ok
Dec 15 19:27:44.530 INFO [stdout] test internal::entry::tests::nonrecursive_entries_from_root ... ok
Dec 15 19:27:44.530 INFO [stdout] test internal::path::tests::absolute_path_is_valid ... ok
Dec 15 19:27:44.531 INFO [stdout] test internal::direntry::tests::parse_valid_storage_entry ... ok
Dec 15 19:27:44.531 INFO [stdout] test internal::path::tests::name_ordering ... ok
Dec 15 19:27:44.531 INFO [stdout] test internal::path::tests::canonical_path_is_absolute ... ok
Dec 15 19:27:44.532 INFO [stdout] test internal::path::tests::relative_path_is_valid ... ok
Dec 15 19:27:44.533 INFO [stdout] test internal::sector::tests::sector_init ... ok
Dec 15 19:27:44.535 INFO [stdout] test internal::path::tests::path_with_parents_is_valid ... ok
Dec 15 19:27:44.535 INFO [stdout] test internal::path::tests::short_name_is_valid ... ok
Dec 15 19:27:44.535 INFO [stdout] test internal::sector::tests::sector_read ... ok
Dec 15 19:27:44.535 INFO [stdout] test internal::sector::tests::sector_seek ... ok
Dec 15 19:27:44.535 INFO [stdout] test internal::time::tests::system_time ... ok
Dec 15 19:27:44.540 INFO [stdout] test tests::create_all_storages ... ok
Dec 15 19:27:44.541 INFO [stdout] test tests::create_directory_tree ... ok
Dec 15 19:27:44.541 INFO [stdout] test internal::sector::tests::sector_write ... ok
Dec 15 19:27:44.541 INFO [stdout] test tests::create_empty_compound_file ... ok
Dec 15 19:27:44.541 INFO [stdout] test internal::version::tests::number_round_trip ... ok
Dec 15 19:27:44.541 INFO [stdout] test tests::create_large_stream ... ok
Dec 15 19:27:44.574 INFO [stdout] test internal::direntry::tests::invalid_object_type ... ok
Dec 15 19:27:44.574 INFO [stdout] test internal::direntry::tests::invalid_color ... ok
Dec 15 19:27:44.574 INFO [stdout] test internal::path::tests::long_name_is_invalid ... ok
Dec 15 19:27:44.574 INFO [stdout] test internal::path::tests::name_with_slash_is_invalid ... ok
Dec 15 19:27:44.574 INFO [stdout] test internal::path::tests::parent_of_root_is_invalid ... ok
Dec 15 19:27:44.575 INFO [stdout] test tests::create_new_stream_where_storage_exists ... ok
Dec 15 19:27:44.576 INFO [stdout] test tests::create_root_storage ... ok
Dec 15 19:27:44.576 INFO [stdout] test tests::create_new_stream_where_stream_exists ... ok
Dec 15 19:27:44.577 INFO [stdout] test tests::create_storage_where_stream_exists ... ok
Dec 15 19:27:44.580 INFO [stdout] test tests::create_streams ... ok
Dec 15 19:27:44.583 INFO [stdout] test tests::create_storage_where_storage_exists ... ok
Dec 15 19:27:44.583 INFO [stdout] test tests::create_storage_all_with_stream_in_the_way ... ok
Dec 15 19:27:44.583 INFO [stdout] test tests::create_stream_where_storage_exists ... ok
Dec 15 19:27:44.584 INFO [stdout] test tests::create_stream_where_stream_exists ... ok
Dec 15 19:27:44.584 INFO [stdout] test tests::empty_compound_file_has_no_children ... ok
Dec 15 19:27:44.587 INFO [stdout] test tests::path_is_storage ... ok
Dec 15 19:27:44.587 INFO [stdout] test tests::path_is_stream ... ok
Dec 15 19:27:44.588 INFO [stdout] test tests::remove_non_empty_storage ... ok
Dec 15 19:27:44.588 INFO [stdout] test tests::create_small_stream ... ok
Dec 15 19:27:44.591 INFO [stdout] test tests::remove_non_storage ... ok
Dec 15 19:27:44.591 INFO [stdout] test tests::remove_nonexistent_storage ... ok
Dec 15 19:27:44.591 INFO [stdout] test tests::remove_non_stream ... ok
Dec 15 19:27:44.596 INFO [stdout] test tests::remove_root_storage ... ok
Dec 15 19:27:44.596 INFO [stdout] test tests::remove_storage_all_on_root ... ok
Dec 15 19:27:44.599 INFO [stdout] test tests::remove_small_stream ... ok
Dec 15 19:27:44.603 INFO [stdout] test tests::remove_storage_all_on_storage ... ok
Dec 15 19:27:44.605 INFO [stdout] test tests::read_storage_on_stream ... ok
Dec 15 19:27:44.607 INFO [stdout] test tests::remove_nonexistent_stream ... ok
Dec 15 19:27:44.607 INFO [stdout] test tests::path_exists ... ok
Dec 15 19:27:44.614 INFO [stdout] test tests::set_storage_clsid_for_stream ... ok
Dec 15 19:27:44.614 INFO [stdout] test tests::remove_streams ... ok
Dec 15 19:27:44.614 INFO [stdout] test tests::remove_storages ... ok
Dec 15 19:27:44.615 INFO [stdout] test tests::set_nonexistent_state_bits ... ok
Dec 15 19:27:44.617 INFO [stdout] test tests::set_nonexistent_storage_clsid ... ok
Dec 15 19:27:44.617 INFO [stdout] test tests::extend_stream ... ok
Dec 15 19:27:44.617 INFO [stdout] test tests::root_entry ... ok
Dec 15 19:27:44.618 INFO [stdout] test tests::state_bits ... ok
Dec 15 19:27:44.623 INFO [stdout] test tests::wrong_magic_number ... ok
Dec 15 19:27:44.623 INFO [stdout] test tests::walk_directory_tree ... ok
Dec 15 19:27:44.624 INFO [stdout] test tests::storage_clsids ... ok
Dec 15 19:27:44.628 INFO [stdout] test tests::truncate_stream ... ok
Dec 15 19:27:44.628 INFO [stdout] test tests::stream_seek ... ok
Dec 15 19:27:44.855 INFO [stdout] test tests::create_very_large_stream ... ok
Dec 15 19:27:44.855 INFO [stderr]    Doc-tests cfb
Dec 15 19:27:44.855 INFO [stdout] 
Dec 15 19:27:44.855 INFO [stdout] test result: ok. 64 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 19:27:44.855 INFO [stdout] 
Dec 15 19:27:45.903 INFO [stdout] 
Dec 15 19:27:45.903 INFO [stdout] running 2 tests
Dec 15 19:27:47.250 INFO [stdout] test src/lib.rs -  (line 17) ... ok
Dec 15 19:27:47.578 INFO [stdout] test src/internal/version.rs - internal::version::Version::sector_len (line 42) ... ok
Dec 15 19:27:47.578 INFO [stdout] 
Dec 15 19:27:47.578 INFO [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 19:27:47.578 INFO [stdout] 
Dec 15 19:27:47.592 INFO [stderr] su: No module specific data is present
Dec 15 19:27:47.957 INFO running `"docker" "inspect" "947f25f6c36f2ff93671570897ad9f2e608bde3a4257880d2687c92511fda396"`
Dec 15 19:27:48.059 INFO running `"docker" "rm" "-f" "947f25f6c36f2ff93671570897ad9f2e608bde3a4257880d2687c92511fda396"`
Dec 15 19:27:48.197 INFO [stdout] 947f25f6c36f2ff93671570897ad9f2e608bde3a4257880d2687c92511fda396