[INFO] fetching crate rsext4 0.3.6...
[INFO] testing rsext4-0.3.6 against master#562dee4820c458d823175268e41601d4c060588a for pr-154210-2
[INFO] extracting crate rsext4 0.3.6 into /workspace/builds/worker-6-tc1/source
[INFO] started tweaking crates.io crate rsext4 0.3.6
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate rsext4 0.3.6
[INFO] tweaked toml for crates.io crate rsext4 0.3.6 written to /workspace/builds/worker-6-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate rsext4 0.3.6 on toolchain 562dee4820c458d823175268e41601d4c060588a
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate rsext4 0.3.6 already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-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" "+562dee4820c458d823175268e41601d4c060588a" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 36521df09d6db4f52834f37e14fcf84d0cb70eb95d994d3867c904d335015fda
[INFO] running `Command { std: "docker" "start" "-a" "36521df09d6db4f52834f37e14fcf84d0cb70eb95d994d3867c904d335015fda", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "36521df09d6db4f52834f37e14fcf84d0cb70eb95d994d3867c904d335015fda", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "36521df09d6db4f52834f37e14fcf84d0cb70eb95d994d3867c904d335015fda", kill_on_drop: false }`
[INFO] [stdout] 36521df09d6db4f52834f37e14fcf84d0cb70eb95d994d3867c904d335015fda
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-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=forbid" "-e" "RUSTDOCFLAGS=--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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 6a615d83e4c9ba369b90bc9906eb070947fa0481a4bb739707e7786f1d4ff9f5
[INFO] running `Command { std: "docker" "start" "-a" "6a615d83e4c9ba369b90bc9906eb070947fa0481a4bb739707e7786f1d4ff9f5", kill_on_drop: false }`
[INFO] [stderr]    Compiling spin v0.9.8
[INFO] [stderr]    Compiling lazy_static v1.5.0
[INFO] [stderr]    Compiling rsext4 v0.3.6 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 2.42s
[INFO] running `Command { std: "docker" "inspect" "6a615d83e4c9ba369b90bc9906eb070947fa0481a4bb739707e7786f1d4ff9f5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "6a615d83e4c9ba369b90bc9906eb070947fa0481a4bb739707e7786f1d4ff9f5", kill_on_drop: false }`
[INFO] [stdout] 6a615d83e4c9ba369b90bc9906eb070947fa0481a4bb739707e7786f1d4ff9f5
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-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=forbid" "-e" "RUSTDOCFLAGS=--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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 0a482ce494f1e2d37111e4146c74528d63e8f0acd910099dc5d27e0459b401ca
[INFO] running `Command { std: "docker" "start" "-a" "0a482ce494f1e2d37111e4146c74528d63e8f0acd910099dc5d27e0459b401ca", kill_on_drop: false }`
[INFO] [stderr]    Compiling rsext4 v0.3.6 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 8.62s
[INFO] running `Command { std: "docker" "inspect" "0a482ce494f1e2d37111e4146c74528d63e8f0acd910099dc5d27e0459b401ca", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "0a482ce494f1e2d37111e4146c74528d63e8f0acd910099dc5d27e0459b401ca", kill_on_drop: false }`
[INFO] [stdout] 0a482ce494f1e2d37111e4146c74528d63e8f0acd910099dc5d27e0459b401ca
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-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=forbid" "-e" "RUSTDOCFLAGS=--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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 62b3f99069fbde8c1cc5f0bfd40d62064e734bf7e0dd28aea4e6b5c466885130
[INFO] running `Command { std: "docker" "start" "-a" "62b3f99069fbde8c1cc5f0bfd40d62064e734bf7e0dd28aea4e6b5c466885130", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.04s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/rsext4-e66042380922ff33)
[INFO] [stdout] 
[INFO] [stdout] running 66 tests
[INFO] [stdout] test bitmap::block::tests::test_block_bitmap_find_free ... ok
[INFO] [stdout] test bitmap::block::tests::test_block_bitmap_basic ... ok
[INFO] [stdout] test bitmap::inode::tests::test_inode_bitmap_basic ... ok
[INFO] [stdout] test blockgroup_description::tests::test_group_desc_flags ... ok
[INFO] [stdout] test bmalloc::block::tests::test_block_allocator_contiguous ... ok
[INFO] [stdout] test bmalloc::block::tests::test_block_allocator_single ... ok
[INFO] [stdout] test blockgroup_description::tests::test_group_desc_64bit_values ... ok
[INFO] [stdout] test bitmap::block::tests::test_block_bitmap_mut_allocate ... ok
[INFO] [stdout] test bmalloc::inode::tests::test_inode_allocator ... ok
[INFO] [stdout] test bmalloc::inode::tests::test_inode_global_conversion ... ok
[INFO] [stdout] test bitmap::bitmap_utils::tests::test_bitmap_utils ... ok
[INFO] [stdout] test bmalloc::tests::block_group_and_absolute_block_round_trip ... ok
[INFO] [stdout] test bmalloc::tests::inode_group_round_trip ... ok
[INFO] [stdout] test bmalloc::tests::inode_number_rejects_zero ... ok
[INFO] [stdout] test cache::bitmap::tests::test_bitmap_cache_basic ... ok
[INFO] [stdout] test bmalloc::tests::absolute_block_to_u32_checks_overflow ... ok
[INFO] [stdout] test cache::data_block::tests::test_create_new_block ... ok
[INFO] [stdout] test cache::data_block::tests::test_invalidate ... ok
[INFO] [stdout] test cache::data_block::tests::test_datablock_cache_basic ... ok
[INFO] [stdout] test crc32c::crc32c::tests::crc32c_detects_payload_corruption ... ok
[INFO] [stdout] test checksum::tests::dirblock_checksum_is_stored_and_detects_corruption ... ok
[INFO] [stdout] test cache::inode_table::tests::test_inode_location_calc ... ok
[INFO] [stdout] test crc32c::crc32c::tests::crc32c_empty_is_zero ... ok
[INFO] [stdout] test checksum::tests::inode_checksum_is_split_and_persisted_to_disk ... ok
[INFO] [stdout] test crc32c::crc32c::tests::seed_uses_uuid_crc_when_csum_seed_feature_is_disabled ... ok
[INFO] [stdout] test crc32c::crc32c::tests::seed_uses_stored_checksum_seed_when_feature_is_enabled ... ok
[INFO] [stdout] test cache::bitmap::tests::test_cache_key ... ok
[INFO] [stdout] test disknode::tests::chmod_preserves_inode_type_bits ... ok
[INFO] [stdout] test cache::bitmap::tests::test_cached_bitmap ... ok
[INFO] [stdout] test disknode::tests::flag_masking_respects_inode_type_rules ... ok
[INFO] [stdout] test cache::inode_table::tests::test_inode_cache_basic ... ok
[INFO] [stdout] test disknode::tests::uid_gid_roundtrip_keeps_high_bits ... ok
[INFO] [stdout] test disknode::tests::extra_isize_fit_boundaries_follow_inode_size ... ok
[INFO] [stdout] test checksum::tests::superblock_checksum_round_trips_and_corruption_returns_euclean ... ok
[INFO] [stdout] test disknode::tests::timestamp_without_extra_fields_clamps_to_legacy_seconds ... ok
[INFO] [stdout] test crc32c::crc32c::tests::crc32c_incremental_equals_one_shot ... ok
[INFO] [stdout] test crc32c::crc32c::tests::crc32c_standard_test_vector ... ok
[INFO] [stdout] test endian::tests::test_u64_conversion ... ok
[INFO] [stdout] test error::tests::errno_values_match_linux ... ok
[INFO] [stdout] test disknode::tests::extra_timestamp_roundtrip_works_when_extra_fields_fit ... ok
[INFO] [stdout] test endian::tests::test_u16_conversion ... ok
[INFO] [stdout] test error::tests::ext4_error_display_keeps_context ... ok
[INFO] [stdout] test endian::tests::test_u32_conversion ... ok
[INFO] [stdout] test hashtree::tests::test_hash_tree_node_types ... ok
[INFO] [stdout] test hashtree::tests::test_inode_htree_check ... ok
[INFO] [stdout] test hashtree::tests::test_htree_hash_calculation ... ok
[INFO] [stdout] test jbd2::jbdstruct::tests::test_block_tag_and_tag3_roundtrip ... ok
[INFO] [stdout] test hashtree::tests::test_dx_entry_parsing ... ok
[INFO] [stdout] test jbd2::jbdstruct::tests::test_commit_header_roundtrip ... ok
[INFO] [stdout] test jbd2::jbdstruct::tests::test_journal_header_roundtrip ... ok
[INFO] [stdout] test hashtree::tests::test_hash_tree_manager_creation ... ok
[INFO] [stdout] test extents_tree::remove::tests::remove_extend_multi_extent_skip_hole_and_verify ... ok
[INFO] [stdout] test jbd2::jbdstruct::tests::test_block_tail_and_revoke_roundtrip ... ok
[INFO] [stdout] test superblock::tests::tests::test_superblock_64bit_values ... ok
[INFO] [stdout] test hashtree::tests::test_fallback_to_linear_search ... ok
[INFO] [stdout] test jbd2::jbdstruct::tests::test_journal_superblock_roundtrip ... ok
[INFO] [stdout] test extents_tree::remove::tests::remove_extend_partial_delete_splits_extent_and_updates_bitmap ... ok
[INFO] [stdout] test superblock::tests::tests::test_superblock_disk_format_roundtrip ... ok
[INFO] [stdout] test extents_tree::remove::tests::remove_extend_root_leaf_no_degeneration ... ok
[INFO] [stdout] test extents_tree::remove::tests::remove_extend_frees_block_bitmap_bit ... ok
[INFO] [stdout] test extents_tree::remove::tests::remove_extend_root_leaf_degeneration_to_empty ... ok
[INFO] [stdout] test extents_tree::remove::tests::remove_extend_multilevel_to_root_promotion ... ok
[INFO] [stdout] test extents_tree::remove::tests::remove_extend_repeated_deletions_consistency ... ok
[INFO] [stdout] test extents_tree::remove::tests::remove_extend_full_delete_single_extent_bitmap_and_metadata ... ok
[INFO] [stdout] test extents_tree::remove::tests::remove_extend_over_length_errors_and_does_not_delete_unrelated ... ok
[INFO] [stdout] test superblock::tests::tests::test_superblock_disk_size ... ok
[INFO] [stdout] 
[INFO] [stderr]      Running tests/api_functional.rs (/opt/rustwide/target/debug/deps/api_functional-95d863f7d822cb84)
[INFO] [stdout] test result: ok. 66 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.27s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 8 tests
[INFO] [stdout] test api_functional_tests::test_boundary_conditions ... ok
[INFO] [stdout] test api_functional_tests::test_random_read_write ... ok
[INFO] [stdout] test api_functional_tests::test_lseek ... ok
[INFO] [stdout] test api_functional_tests::test_concurrent_file_operations ... ok
[INFO] [stdout] test api_functional_tests::test_write_at ... ok
[INFO] [stdout] test api_functional_tests::test_open_and_read ... ok
[INFO] [stdout] test api_functional_tests::test_large_file_operations ... ok
[INFO] [stdout] test api_functional_tests::test_api_error_handling ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.18s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/crc_integrity.rs (/opt/rustwide/target/debug/deps/crc_integrity-f39166621c48b22f)
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test corrupted_block_bitmap_payload_is_reported_as_euclean_on_mount ... ok
[INFO] [stdout] test checksums_are_persisted_and_clean_remount_preserves_the_written_file ... ok
[INFO] [stdout] test corrupted_group_descriptor_checksum_is_reported_as_euclean_on_mount ... ok
[INFO] [stdout] test corrupted_superblock_checksum_is_reported_as_euclean_on_mount ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.08s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/directory_operations.rs (/opt/rustwide/target/debug/deps/directory_operations-f56aebde21875ee6)
[INFO] [stdout] 
[INFO] [stdout] running 6 tests
[INFO] [stdout] test directory_functional_tests::test_directory_delete ... ok
[INFO] [stdout] test directory_functional_tests::test_directory_create ... ok
[INFO] [stdout] test directory_functional_tests::test_directory_error_handling ... ok
[INFO] [stdout] test directory_functional_tests::test_directory_file_find ... ok
[INFO] [stdout] test directory_functional_tests::test_directory_file_operations ... ok
[INFO] [stdout] test directory_functional_tests::test_complex_directory_structure ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.11s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/error_handling.rs (/opt/rustwide/target/debug/deps/error_handling-fa05164cf1441b25)
[INFO] [stdout] 
[INFO] [stdout] running 7 tests
[INFO] [stdout] test error_handling_tests::test_inconsistent_state_handling ... ok
[INFO] [stdout] test error_handling_tests::test_permission_handling ... ok
[INFO] [stdout] test error_handling_tests::test_concurrent_operation_errors ... ok
[INFO] [stdout] test error_handling_tests::test_block_device_errors ... ok
[INFO] [stdout] test error_handling_tests::test_invalid_paths ... ok
[INFO] [stdout] test error_handling_tests::test_filesystem_boundaries ... ok
[INFO] [stderr]      Running tests/file_operations.rs (/opt/rustwide/target/debug/deps/file_operations-aa04056670c4dbd7)
[INFO] [stdout] test error_handling_tests::test_resource_exhaustion ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 4.46s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 8 tests
[INFO] [stdout] test file_functional_tests::test_hard_link ... ok
[INFO] [stdout] test file_functional_tests::test_file_operation_errors ... ok
[INFO] [stdout] test file_functional_tests::test_file_delete ... ok
[INFO] [stdout] test file_functional_tests::test_file_create_and_rw ... ok
[INFO] [stdout] test file_functional_tests::test_file_truncate ... ok
[INFO] [stdout] test file_functional_tests::test_symbolic_link ... ok
[INFO] [stdout] test file_functional_tests::test_file_rename ... ok
[INFO] [stdout] test file_functional_tests::test_file_move ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.10s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/integration_test.rs (/opt/rustwide/target/debug/deps/integration_test-e4b90cf77abb1f77)
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test test_file_operations ... ok
[INFO] [stdout] test test_basic_mount_mkfs ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.06s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/metadata_lifecycle.rs (/opt/rustwide/target/debug/deps/metadata_lifecycle-42865368a9b6962b)
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test test_parent_directory_timestamps_follow_entry_changes ... ok
[INFO] [stdout] test test_create_delete_and_reallocate_inode_updates_dtime ... ok
[INFO] [stdout] test test_metadata_mutators_and_project_inheritance ... ok
[INFO] [stdout] test test_read_write_truncate_and_noatime_update_expected_timestamps ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.06s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests rsext4
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "62b3f99069fbde8c1cc5f0bfd40d62064e734bf7e0dd28aea4e6b5c466885130", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "62b3f99069fbde8c1cc5f0bfd40d62064e734bf7e0dd28aea4e6b5c466885130", kill_on_drop: false }`
[INFO] [stdout] 62b3f99069fbde8c1cc5f0bfd40d62064e734bf7e0dd28aea4e6b5c466885130