Dec 15 18:12:49.845 INFO testing conserve-0.5.1 against master#bd47d6825bf4090517549d33cfef10d3300b4a75 for pr-56550
Dec 15 18:12:49.845 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 15 18:12:50.046 INFO [stdout] 23966a4c3d42ff34df01450e9c3e673a95165bf0f847f5efe7922f3b7f99e025
Dec 15 18:12:50.048 INFO running `"docker" "start" "-a" "23966a4c3d42ff34df01450e9c3e673a95165bf0f847f5efe7922f3b7f99e025"`
Dec 15 18:12:50.455 INFO [stderr] usermod: no changes
Dec 15 18:12:50.694 INFO [stderr]    Compiling thousands v0.1.2
Dec 15 18:12:50.694 INFO [stderr]    Compiling snap v0.2.5
Dec 15 18:12:52.263 INFO [stderr]    Compiling conserve v0.5.1 (/source)
Dec 15 18:13:08.247 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 17.77s
Dec 15 18:13:08.259 INFO [stderr] su: No module specific data is present
Dec 15 18:13:09.017 INFO running `"docker" "inspect" "23966a4c3d42ff34df01450e9c3e673a95165bf0f847f5efe7922f3b7f99e025"`
Dec 15 18:13:09.296 INFO running `"docker" "rm" "-f" "23966a4c3d42ff34df01450e9c3e673a95165bf0f847f5efe7922f3b7f99e025"`
Dec 15 18:13:09.451 INFO [stdout] 23966a4c3d42ff34df01450e9c3e673a95165bf0f847f5efe7922f3b7f99e025
Dec 15 18:13:09.457 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 15 18:13:09.899 INFO [stdout] cd507d76c508aa521ae29cf6be06939e15ffa7f487bc361c3c7fa1a9fd8da06e
Dec 15 18:13:09.942 INFO running `"docker" "start" "-a" "cd507d76c508aa521ae29cf6be06939e15ffa7f487bc361c3c7fa1a9fd8da06e"`
Dec 15 18:13:10.655 INFO [stderr] usermod: no changes
Dec 15 18:13:10.847 INFO [stderr]    Compiling serde v1.0.82
Dec 15 18:13:10.859 INFO [stderr]    Compiling predicates v1.0.0
Dec 15 18:13:10.859 INFO [stderr]    Compiling globwalk v0.5.0
Dec 15 18:13:15.214 INFO [stderr]    Compiling assert_fs v0.10.0
Dec 15 18:13:25.267 INFO [stderr]    Compiling serde_json v1.0.33
Dec 15 18:13:30.214 INFO [stderr]    Compiling escargot v0.3.1
Dec 15 18:13:32.499 INFO [stderr]    Compiling assert_cmd v0.10.2
Dec 15 18:13:35.835 INFO [stderr]    Compiling conserve v0.5.1 (/source)
Dec 15 18:13:57.187 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 46.50s
Dec 15 18:13:57.195 INFO [stderr] su: No module specific data is present
Dec 15 18:13:57.965 INFO running `"docker" "inspect" "cd507d76c508aa521ae29cf6be06939e15ffa7f487bc361c3c7fa1a9fd8da06e"`
Dec 15 18:13:58.241 INFO running `"docker" "rm" "-f" "cd507d76c508aa521ae29cf6be06939e15ffa7f487bc361c3c7fa1a9fd8da06e"`
Dec 15 18:13:58.596 INFO [stdout] cd507d76c508aa521ae29cf6be06939e15ffa7f487bc361c3c7fa1a9fd8da06e
Dec 15 18:13:58.600 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 15 18:13:59.035 INFO [stdout] 19310341b75be5919ecc7e4733e40b70db930449eef61468ed0b615d77d97d8e
Dec 15 18:13:59.037 INFO running `"docker" "start" "-a" "19310341b75be5919ecc7e4733e40b70db930449eef61468ed0b615d77d97d8e"`
Dec 15 18:14:00.635 INFO [stderr] usermod: no changes
Dec 15 18:14:00.927 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.25s
Dec 15 18:14:00.943 INFO [stderr]      Running /target/debug/deps/conserve-f90515151f476550
Dec 15 18:14:00.947 INFO [stdout] 
Dec 15 18:14:00.947 INFO [stdout] running 52 tests
Dec 15 18:14:00.947 INFO [stdout] test apath::tests::invalid ... ok
Dec 15 18:14:00.959 INFO [stdout] test apath::tests::valid_and_ordered ... ok
Dec 15 18:14:00.963 INFO [stdout] test archive::tests::empty_archive ... ok
Dec 15 18:14:00.963 INFO [stdout] test archive::tests::init_empty_dir ... ok
Dec 15 18:14:00.975 INFO [stdout] test backup::tests::empty_file_uses_zero_blocks ... ok
Dec 15 18:14:01.015 INFO [stdout] test archive::tests::create_then_open_archive ... ok
Dec 15 18:14:01.015 INFO [stdout] test bandid::tests::format ... ok
Dec 15 18:14:01.015 INFO [stdout] test band::tests::create_and_reopen_band ... ok
Dec 15 18:14:01.015 INFO [stdout] test backup::tests::symlink ... ok
Dec 15 18:14:01.015 INFO [stdout] test band::tests::create_existing_band ... ok
Dec 15 18:14:01.015 INFO [stdout] test archive::tests::create_bands ... ok
Dec 15 18:14:01.015 INFO [stdout] test bandid::tests::equality ... ok
Dec 15 18:14:01.015 INFO [stdout] test bandid::tests::next ... ok
Dec 15 18:14:01.015 INFO [stdout] test bandid::tests::to_string ... ok
Dec 15 18:14:01.015 INFO [stdout] test bandid::tests::zero ... ok
Dec 15 18:14:01.015 INFO [stdout] test bandid::tests::from_string_valid ... ok
Dec 15 18:14:01.015 INFO [stdout] test bandid::tests::from_string_detects_invalid ... ok
Dec 15 18:14:01.035 INFO [stdout] test excludes::tests::nothing_parse ... ok
Dec 15 18:14:01.051 INFO [stdout] test excludes::tests::path_parse ... ok
Dec 15 18:14:01.103 INFO [stdout] test excludes::tests::extendend_pattern_parse ... ok
Dec 15 18:14:01.103 INFO [stdout] test excludes::tests::simple_parse ... ok
Dec 15 18:14:01.119 INFO [stdout] test index::tests::basic ... ok
Dec 15 18:14:01.161 INFO [stdout] test index::tests::excluded_entries ... ok
Dec 15 18:14:01.161 INFO [stdout] test index::tests::multiple_hunks ... ok
Dec 15 18:14:01.303 INFO [stdout] test blockdir::tests::write_to_file ... ok
Dec 15 18:14:01.314 INFO [stdout] test index::tests::index_builder_checks_names ... ok
Dec 15 18:14:01.315 INFO [stdout] test index::tests::index_builder_checks_order ... ok
Dec 15 18:14:01.315 INFO [stdout] test index::tests::no_duplicate_paths ... ok
Dec 15 18:14:01.320 INFO [stdout] test jsonio::tests::read_write_json ... ok
Dec 15 18:14:01.320 INFO [stdout] test index::tests::serialize_index ... ok
Dec 15 18:14:01.322 INFO [stdout] test live_tree::tests::open_tree ... ok
Dec 15 18:14:01.323 INFO [stdout] test live_tree::tests::simple_directory ... ok
Dec 15 18:14:01.324 INFO [stdout] test live_tree::tests::symlinks ... ok
Dec 15 18:14:01.324 INFO [stdout] test report::tests::count ... ok
Dec 15 18:14:01.324 INFO [stdout] test report::tests::display ... ok
Dec 15 18:14:01.327 INFO [stdout] test report::tests::merge_reports ... ok
Dec 15 18:14:01.332 INFO [stdout] test live_tree::tests::exclude_entries_directory ... ok
Dec 15 18:14:01.335 INFO [stdout] test index::tests::no_duplicate_paths_across_hunks ... ok
Dec 15 18:14:01.339 INFO [stdout] test index::tests::path_for_hunk ... ok
Dec 15 18:14:01.363 INFO [stdout] test backup::tests::excludes ... ok
Dec 15 18:14:01.375 INFO [stdout] test bandid::tests::empty_id_not_allowed ... ok
Dec 15 18:14:01.391 INFO [stdout] test stored_tree::test::cant_open_no_versions ... ok
Dec 15 18:14:01.959 INFO [stdout] test blockdir::tests::write_same_data_again ... ok
Dec 15 18:14:01.967 INFO [stdout] test ui::tests::by_name ... ok
Dec 15 18:14:01.967 INFO [stdout] test ui::tests::test_compression_ratio ... ok
Dec 15 18:14:02.756 INFO [stdout] test restore::tests::decline_to_overwrite ... ok
Dec 15 18:14:02.914 INFO [stdout] test restore::tests::restore_named_band ... ok
Dec 15 18:14:03.127 INFO [stdout] test restore::tests::exclude_files ... ok
Dec 15 18:14:03.159 INFO [stdout] test restore::tests::simple_restore ... ok
Dec 15 18:14:03.310 INFO [stdout] test stored_tree::test::open_stored_tree ... ok
Dec 15 18:14:03.359 INFO [stdout] test restore::tests::forced_overwrite ... ok
Dec 15 18:14:06.633 INFO [stdout] test blockdir::tests::large_file ... ok
Dec 15 18:14:06.633 INFO [stdout] 
Dec 15 18:14:06.633 INFO [stdout] test result: ok. 52 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 18:14:06.633 INFO [stdout] 
Dec 15 18:14:06.637 INFO [stderr]      Running /target/debug/deps/conserve-7e667066cb4e2b47
Dec 15 18:14:06.646 INFO [stdout] 
Dec 15 18:14:06.646 INFO [stdout] running 0 tests
Dec 15 18:14:06.646 INFO [stdout] 
Dec 15 18:14:06.646 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 18:14:06.646 INFO [stdout] 
Dec 15 18:14:06.647 INFO [stderr]      Running /target/debug/deps/blackbox-83f2d4821f415c14
Dec 15 18:14:06.657 INFO [stdout] 
Dec 15 18:14:06.657 INFO [stdout] running 6 tests
Dec 15 18:14:06.947 INFO [stdout] test blackbox_help ... ok
Dec 15 18:14:06.968 INFO [stdout] test clean_error_on_non_archive ... ok
Dec 15 18:14:06.970 INFO [stdout] test blackbox_no_args ... ok
Dec 15 18:14:07.011 INFO [stdout] test incomplete_version ... ok
Dec 15 18:14:07.011 INFO [stdout] test empty_archive ... ok
Dec 15 18:14:07.383 INFO [stdout] test blackbox_backup ... ok
Dec 15 18:14:07.383 INFO [stdout] 
Dec 15 18:14:07.384 INFO [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 18:14:07.384 INFO [stdout] 
Dec 15 18:14:07.386 INFO [stderr]      Running /target/debug/deps/integration-b56ecee7b1b0bf5e
Dec 15 18:14:07.389 INFO [stdout] 
Dec 15 18:14:07.389 INFO [stdout] running 3 tests
Dec 15 18:14:07.673 INFO [stdout] test simple_backup ... ok
Dec 15 18:14:07.699 INFO [stdout] test simple_backup_with_excludes ... ok
Dec 15 18:14:12.723 INFO [stdout] test large_file ... ok
Dec 15 18:14:12.723 INFO [stdout] 
Dec 15 18:14:12.723 INFO [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 18:14:12.723 INFO [stdout] 
Dec 15 18:14:12.737 INFO [stderr]    Doc-tests conserve
Dec 15 18:14:19.717 INFO [stdout] 
Dec 15 18:14:19.717 INFO [stdout] running 0 tests
Dec 15 18:14:19.717 INFO [stdout] 
Dec 15 18:14:19.718 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 18:14:19.718 INFO [stdout] 
Dec 15 18:14:19.740 INFO [stderr] su: No module specific data is present
Dec 15 18:14:21.193 INFO running `"docker" "inspect" "19310341b75be5919ecc7e4733e40b70db930449eef61468ed0b615d77d97d8e"`
Dec 15 18:14:21.531 INFO running `"docker" "rm" "-f" "19310341b75be5919ecc7e4733e40b70db930449eef61468ed0b615d77d97d8e"`
Dec 15 18:14:21.896 INFO [stdout] 19310341b75be5919ecc7e4733e40b70db930449eef61468ed0b615d77d97d8e