Dec 15 18:12:42.748 INFO testing conserve-0.5.1 against try#aa49d8ef14939ddec0e34b346b60174a5673d48f for pr-56550
Dec 15 18:12:42.748 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-2/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/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 18:12:43.260 INFO [stdout] 453f0694d53c7f50524e878f76c9b649d63f6f6c9add2002a92e922f8db93838
Dec 15 18:12:43.267 INFO running `"docker" "start" "-a" "453f0694d53c7f50524e878f76c9b649d63f6f6c9add2002a92e922f8db93838"`
Dec 15 18:12:44.278 INFO [stderr] usermod: no changes
Dec 15 18:12:44.555 INFO [stderr]    Compiling thousands v0.1.2
Dec 15 18:12:45.616 INFO [stderr]    Compiling conserve v0.5.1 (/source)
Dec 15 18:13:01.154 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 16.84s
Dec 15 18:13:01.161 INFO [stderr] su: No module specific data is present
Dec 15 18:13:01.555 INFO running `"docker" "inspect" "453f0694d53c7f50524e878f76c9b649d63f6f6c9add2002a92e922f8db93838"`
Dec 15 18:13:01.634 INFO running `"docker" "rm" "-f" "453f0694d53c7f50524e878f76c9b649d63f6f6c9add2002a92e922f8db93838"`
Dec 15 18:13:01.740 INFO [stdout] 453f0694d53c7f50524e878f76c9b649d63f6f6c9add2002a92e922f8db93838
Dec 15 18:13:01.742 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-2/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/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 18:13:02.018 INFO [stdout] 2f9df66e6810f02bc1dbb688e424bd8e09e818acb065c997e9433b322a48aaae
Dec 15 18:13:02.020 INFO running `"docker" "start" "-a" "2f9df66e6810f02bc1dbb688e424bd8e09e818acb065c997e9433b322a48aaae"`
Dec 15 18:13:02.439 INFO [stderr] usermod: no changes
Dec 15 18:13:02.605 INFO [stderr]    Compiling predicates v1.0.0
Dec 15 18:13:02.606 INFO [stderr]    Compiling serde_json v1.0.33
Dec 15 18:13:02.608 INFO [stderr]    Compiling ignore v0.4.4
Dec 15 18:13:09.747 INFO [stderr]    Compiling globwalk v0.5.0
Dec 15 18:13:10.445 INFO [stderr]    Compiling escargot v0.3.1
Dec 15 18:13:12.911 INFO [stderr]    Compiling assert_cmd v0.10.2
Dec 15 18:13:13.452 INFO [stderr]    Compiling assert_fs v0.10.0
Dec 15 18:13:18.391 INFO [stderr]    Compiling conserve v0.5.1 (/source)
Dec 15 18:13:37.747 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 35.29s
Dec 15 18:13:37.760 INFO [stderr] su: No module specific data is present
Dec 15 18:13:38.591 INFO running `"docker" "inspect" "2f9df66e6810f02bc1dbb688e424bd8e09e818acb065c997e9433b322a48aaae"`
Dec 15 18:13:38.839 INFO running `"docker" "rm" "-f" "2f9df66e6810f02bc1dbb688e424bd8e09e818acb065c997e9433b322a48aaae"`
Dec 15 18:13:39.111 INFO [stdout] 2f9df66e6810f02bc1dbb688e424bd8e09e818acb065c997e9433b322a48aaae
Dec 15 18:13:39.113 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-2/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/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 18:13:39.647 INFO [stdout] 8189f183fc920c9ddeb6a175b11671ac47c93366caee0118ea4f3187b74fc5f6
Dec 15 18:13:39.653 INFO running `"docker" "start" "-a" "8189f183fc920c9ddeb6a175b11671ac47c93366caee0118ea4f3187b74fc5f6"`
Dec 15 18:13:40.634 INFO [stderr] usermod: no changes
Dec 15 18:13:40.805 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.15s
Dec 15 18:13:40.863 INFO [stderr]      Running /target/debug/deps/conserve-d702fefa2d36bc16
Dec 15 18:13:40.863 INFO [stdout] 
Dec 15 18:13:40.863 INFO [stdout] running 52 tests
Dec 15 18:13:40.863 INFO [stdout] test archive::tests::empty_archive ... ok
Dec 15 18:13:40.863 INFO [stdout] test apath::tests::valid_and_ordered ... ok
Dec 15 18:13:40.863 INFO [stdout] test apath::tests::invalid ... ok
Dec 15 18:13:40.863 INFO [stdout] test archive::tests::create_then_open_archive ... ok
Dec 15 18:13:40.863 INFO [stdout] test archive::tests::init_empty_dir ... ok
Dec 15 18:13:40.891 INFO [stdout] test bandid::tests::equality ... ok
Dec 15 18:13:40.894 INFO [stdout] test band::tests::create_and_reopen_band ... ok
Dec 15 18:13:40.911 INFO [stdout] test bandid::tests::format ... ok
Dec 15 18:13:40.923 INFO [stdout] test bandid::tests::from_string_valid ... ok
Dec 15 18:13:40.923 INFO [stdout] test bandid::tests::from_string_detects_invalid ... ok
Dec 15 18:13:40.923 INFO [stdout] test backup::tests::empty_file_uses_zero_blocks ... ok
Dec 15 18:13:40.923 INFO [stdout] test bandid::tests::to_string ... ok
Dec 15 18:13:40.923 INFO [stdout] test band::tests::create_existing_band ... ok
Dec 15 18:13:40.923 INFO [stdout] test archive::tests::create_bands ... ok
Dec 15 18:13:40.935 INFO [stdout] test backup::tests::symlink ... ok
Dec 15 18:13:40.935 INFO [stdout] test excludes::tests::extendend_pattern_parse ... ok
Dec 15 18:13:40.935 INFO [stdout] test excludes::tests::nothing_parse ... ok
Dec 15 18:13:40.935 INFO [stdout] test excludes::tests::path_parse ... ok
Dec 15 18:13:40.947 INFO [stdout] test excludes::tests::simple_parse ... ok
Dec 15 18:13:40.947 INFO [stdout] test bandid::tests::next ... ok
Dec 15 18:13:40.947 INFO [stdout] test bandid::tests::zero ... ok
Dec 15 18:13:41.043 INFO [stdout] test index::tests::excluded_entries ... ok
Dec 15 18:13:41.083 INFO [stdout] test index::tests::basic ... ok
Dec 15 18:13:41.087 INFO [stdout] test index::tests::multiple_hunks ... ok
Dec 15 18:13:41.203 INFO [stdout] test index::tests::index_builder_checks_names ... ok
Dec 15 18:13:41.203 INFO [stdout] test index::tests::no_duplicate_paths ... ok
Dec 15 18:13:41.203 INFO [stdout] test bandid::tests::empty_id_not_allowed ... ok
Dec 15 18:13:41.203 INFO [stdout] test index::tests::serialize_index ... ok
Dec 15 18:13:41.203 INFO [stdout] test index::tests::path_for_hunk ... ok
Dec 15 18:13:41.203 INFO [stdout] test index::tests::no_duplicate_paths_across_hunks ... ok
Dec 15 18:13:41.219 INFO [stdout] test jsonio::tests::read_write_json ... ok
Dec 15 18:13:41.219 INFO [stdout] test live_tree::tests::open_tree ... ok
Dec 15 18:13:41.219 INFO [stdout] test live_tree::tests::simple_directory ... ok
Dec 15 18:13:41.224 INFO [stdout] test report::tests::count ... ok
Dec 15 18:13:41.225 INFO [stdout] test live_tree::tests::symlinks ... ok
Dec 15 18:13:41.233 INFO [stdout] test report::tests::display ... ok
Dec 15 18:13:41.234 INFO [stdout] test report::tests::merge_reports ... ok
Dec 15 18:13:41.236 INFO [stdout] test index::tests::index_builder_checks_order ... ok
Dec 15 18:13:41.239 INFO [stdout] test backup::tests::excludes ... ok
Dec 15 18:13:41.267 INFO [stdout] test live_tree::tests::exclude_entries_directory ... ok
Dec 15 18:13:41.395 INFO [stdout] test blockdir::tests::write_to_file ... ok
Dec 15 18:13:41.407 INFO [stdout] test stored_tree::test::cant_open_no_versions ... ok
Dec 15 18:13:41.923 INFO [stdout] test blockdir::tests::write_same_data_again ... ok
Dec 15 18:13:41.947 INFO [stdout] test ui::tests::by_name ... ok
Dec 15 18:13:41.947 INFO [stdout] test ui::tests::test_compression_ratio ... ok
Dec 15 18:13:42.631 INFO [stdout] test restore::tests::restore_named_band ... ok
Dec 15 18:13:42.839 INFO [stdout] test restore::tests::decline_to_overwrite ... ok
Dec 15 18:13:42.871 INFO [stdout] test restore::tests::forced_overwrite ... ok
Dec 15 18:13:42.911 INFO [stdout] test restore::tests::simple_restore ... ok
Dec 15 18:13:42.935 INFO [stdout] test restore::tests::exclude_files ... ok
Dec 15 18:13:43.270 INFO [stdout] test stored_tree::test::open_stored_tree ... ok
Dec 15 18:13:46.491 INFO [stdout] test blockdir::tests::large_file ... ok
Dec 15 18:13:46.491 INFO [stdout] 
Dec 15 18:13:46.491 INFO [stdout] test result: ok. 52 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 18:13:46.491 INFO [stdout] 
Dec 15 18:13:46.493 INFO [stderr]      Running /target/debug/deps/conserve-cf0ed240b86e5869
Dec 15 18:13:46.494 INFO [stdout] 
Dec 15 18:13:46.494 INFO [stdout] running 0 tests
Dec 15 18:13:46.497 INFO [stdout] 
Dec 15 18:13:46.497 INFO [stderr]      Running /target/debug/deps/blackbox-ae1be1433e208a0f
Dec 15 18:13:46.497 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 18:13:46.497 INFO [stdout] 
Dec 15 18:13:46.497 INFO [stdout] 
Dec 15 18:13:46.497 INFO [stdout] running 6 tests
Dec 15 18:13:46.856 INFO [stdout] test blackbox_help ... ok
Dec 15 18:13:46.856 INFO [stdout] test clean_error_on_non_archive ... ok
Dec 15 18:13:46.883 INFO [stdout] test blackbox_no_args ... ok
Dec 15 18:13:46.891 INFO [stdout] test empty_archive ... ok
Dec 15 18:13:46.915 INFO [stdout] test incomplete_version ... ok
Dec 15 18:13:47.163 INFO [stdout] test blackbox_backup ... ok
Dec 15 18:13:47.163 INFO [stdout] 
Dec 15 18:13:47.163 INFO [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 18:13:47.163 INFO [stdout] 
Dec 15 18:13:47.171 INFO [stderr]      Running /target/debug/deps/integration-d1d09b8f09c3b831
Dec 15 18:13:47.183 INFO [stdout] 
Dec 15 18:13:47.183 INFO [stdout] running 3 tests
Dec 15 18:13:47.519 INFO [stdout] test simple_backup ... ok
Dec 15 18:13:47.519 INFO [stdout] test simple_backup_with_excludes ... ok
Dec 15 18:13:52.055 INFO [stdout] test large_file ... ok
Dec 15 18:13:52.055 INFO [stdout] 
Dec 15 18:13:52.055 INFO [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 18:13:52.055 INFO [stdout] 
Dec 15 18:13:52.059 INFO [stderr]    Doc-tests conserve
Dec 15 18:13:59.203 INFO [stdout] 
Dec 15 18:13:59.203 INFO [stdout] running 0 tests
Dec 15 18:13:59.203 INFO [stdout] 
Dec 15 18:13:59.203 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 18:13:59.203 INFO [stdout] 
Dec 15 18:13:59.214 INFO [stderr] su: No module specific data is present
Dec 15 18:13:59.971 INFO running `"docker" "inspect" "8189f183fc920c9ddeb6a175b11671ac47c93366caee0118ea4f3187b74fc5f6"`
Dec 15 18:14:00.269 INFO running `"docker" "rm" "-f" "8189f183fc920c9ddeb6a175b11671ac47c93366caee0118ea4f3187b74fc5f6"`
Dec 15 18:14:00.622 INFO [stdout] 8189f183fc920c9ddeb6a175b11671ac47c93366caee0118ea4f3187b74fc5f6