Nov 18 17:10:30.219 INFO testing conserve-0.4.2 against master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c for pr-55982
Nov 18 17:10:30.220 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-0/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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"`
Nov 18 17:10:30.412 INFO [stdout] 25489f22deb2d8176ead27682c3336e861860cfc71313b062ffbc746b58309e4
Nov 18 17:10:30.417 INFO running `"docker" "start" "-a" "25489f22deb2d8176ead27682c3336e861860cfc71313b062ffbc746b58309e4"`
Nov 18 17:10:31.084 INFO [stderr] usermod: no changes
Nov 18 17:10:31.327 INFO [stderr]    Compiling snap v0.2.5
Nov 18 17:10:31.327 INFO [stderr]    Compiling clap v2.29.4
Nov 18 17:10:31.333 INFO [stderr]    Compiling conserve v0.4.2 (/source)
Nov 18 17:10:31.339 INFO [stderr]    Compiling chrono v0.2.25
Nov 18 17:10:54.651 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 23.52s
Nov 18 17:10:54.663 INFO [stderr] su: No module specific data is present
Nov 18 17:10:55.353 INFO running `"docker" "inspect" "25489f22deb2d8176ead27682c3336e861860cfc71313b062ffbc746b58309e4"`
Nov 18 17:10:55.697 INFO running `"docker" "rm" "-f" "25489f22deb2d8176ead27682c3336e861860cfc71313b062ffbc746b58309e4"`
Nov 18 17:10:55.876 INFO [stdout] 25489f22deb2d8176ead27682c3336e861860cfc71313b062ffbc746b58309e4
Nov 18 17:10:55.882 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-0/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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"`
Nov 18 17:10:56.181 INFO [stdout] 9209e34dec6e183fd3985b00ca98885cc53553efbaac0fbc93d49a7cd508744d
Nov 18 17:10:56.183 INFO running `"docker" "start" "-a" "9209e34dec6e183fd3985b00ca98885cc53553efbaac0fbc93d49a7cd508744d"`
Nov 18 17:10:56.762 INFO [stderr] usermod: no changes
Nov 18 17:10:56.908 INFO [stderr]    Compiling conserve v0.4.2 (/source)
Nov 18 17:11:11.419 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 14.63s
Nov 18 17:11:11.426 INFO [stderr] su: No module specific data is present
Nov 18 17:11:12.026 INFO running `"docker" "inspect" "9209e34dec6e183fd3985b00ca98885cc53553efbaac0fbc93d49a7cd508744d"`
Nov 18 17:11:12.289 INFO running `"docker" "rm" "-f" "9209e34dec6e183fd3985b00ca98885cc53553efbaac0fbc93d49a7cd508744d"`
Nov 18 17:11:12.590 INFO [stdout] 9209e34dec6e183fd3985b00ca98885cc53553efbaac0fbc93d49a7cd508744d
Nov 18 17:11:12.601 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-0/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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"`
Nov 18 17:11:12.996 INFO [stdout] c7dcab924a5a6cf7e38d8f89d300cd51cd3dbe575e56241b4317eb5cae777f07
Nov 18 17:11:12.998 INFO running `"docker" "start" "-a" "c7dcab924a5a6cf7e38d8f89d300cd51cd3dbe575e56241b4317eb5cae777f07"`
Nov 18 17:11:15.186 INFO [stderr] usermod: no changes
Nov 18 17:11:15.420 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.19s
Nov 18 17:11:15.451 INFO [stdout] 
Nov 18 17:11:15.451 INFO [stdout] running 53 tests
Nov 18 17:11:15.451 INFO [stderr]      Running /target/debug/deps/conserve-f4d51000d94095ff
Nov 18 17:11:15.455 INFO [stdout] test archive::tests::init_empty_dir ... ok
Nov 18 17:11:15.479 INFO [stdout] test apath::tests::valid_and_ordered ... ok
Nov 18 17:11:15.479 INFO [stdout] test apath::tests::invalid ... ok
Nov 18 17:11:15.680 INFO [stdout] test band::tests::create_existing_band ... ok
Nov 18 17:11:15.680 INFO [stdout] test bandid::tests::as_string ... ok
Nov 18 17:11:15.689 INFO [stdout] test archive::tests::create_then_open_archive ... ok
Nov 18 17:11:15.715 INFO [stdout] test bandid::tests::equality ... ok
Nov 18 17:11:15.763 INFO [stdout] test backup::tests::excludes ... ok
Nov 18 17:11:15.771 INFO [stdout] test bandid::tests::format ... ok
Nov 18 17:11:15.771 INFO [stdout] test band::tests::create_and_reopen_band ... ok
Nov 18 17:11:15.780 INFO [stdout] test archive::tests::empty_archive ... ok
Nov 18 17:11:15.780 INFO [stdout] test bandid::tests::next ... ok
Nov 18 17:11:15.807 INFO [stdout] test backup::tests::symlink ... ok
Nov 18 17:11:15.807 INFO [stdout] test bandid::tests::zero ... ok
Nov 18 17:11:15.807 INFO [stdout] test archive::tests::create_bands ... ok
Nov 18 17:11:15.807 INFO [stdout] test backup::tests::empty_file_uses_zero_blocks ... ok
Nov 18 17:11:15.808 INFO [stdout] test excludes::tests::nothing_parse ... ok
Nov 18 17:11:15.815 INFO [stdout] test excludes::tests::extendend_pattern_parse ... ok
Nov 18 17:11:15.815 INFO [stdout] test excludes::tests::path_parse ... ok
Nov 18 17:11:15.815 INFO [stdout] test excludes::tests::simple_parse ... ok
Nov 18 17:11:15.815 INFO [stdout] test index::tests::basic ... ok
Nov 18 17:11:15.819 INFO [stdout] test index::tests::excluded_entries ... ok
Nov 18 17:11:15.825 INFO [stdout] test bandid::tests::from_string_valid ... ok
Nov 18 17:11:15.833 INFO [stdout] test index::tests::multiple_hunks ... ok
Nov 18 17:11:15.851 INFO [stdout] test bandid::tests::from_string_detects_invalid ... ok
Nov 18 17:11:15.918 INFO [stdout] test bandid::tests::empty_id_not_allowed ... ok
Nov 18 17:11:15.921 INFO [stdout] test index::tests::index_builder_checks_order ... ok
Nov 18 17:11:15.922 INFO [stdout] test index::tests::path_for_hunk ... ok
Nov 18 17:11:15.923 INFO [stdout] test index::tests::no_duplicate_paths_across_hunks ... ok
Nov 18 17:11:15.924 INFO [stdout] test index::tests::no_duplicate_paths ... ok
Nov 18 17:11:15.924 INFO [stdout] test index::tests::index_builder_checks_names ... ok
Nov 18 17:11:15.927 INFO [stdout] test index::tests::serialize_index ... ok
Nov 18 17:11:15.931 INFO [stdout] test jsonio::tests::read_write_json ... ok
Nov 18 17:11:15.934 INFO [stdout] test live_tree::tests::exclude_entries_directory ... ok
Nov 18 17:11:15.935 INFO [stdout] test live_tree::tests::open_tree ... ok
Nov 18 17:11:15.936 INFO [stdout] test live_tree::tests::simple_directory ... ok
Nov 18 17:11:15.936 INFO [stdout] test live_tree::tests::symlinks ... ok
Nov 18 17:11:15.936 INFO [stdout] test report::tests::count ... ok
Nov 18 17:11:15.951 INFO [stdout] test report::tests::display ... ok
Nov 18 17:11:15.951 INFO [stdout] test report::tests::merge_reports ... ok
Nov 18 17:11:16.086 INFO [stdout] test block::tests::write_same_data_again ... ok
Nov 18 17:11:16.101 INFO [stdout] test stored_tree::test::cant_open_no_versions ... ok
Nov 18 17:11:16.173 INFO [stdout] test block::tests::write_to_file ... ok
Nov 18 17:11:16.175 INFO [stdout] test ui::tests::best_ui ... ok
Nov 18 17:11:16.177 INFO [stdout] test ui::tests::by_name ... ok
Nov 18 17:11:16.195 INFO [stdout] test ui::tests::test_compression_ratio ... ok
Nov 18 17:11:16.815 INFO [stdout] test restore::tests::restore_named_band ... ok
Nov 18 17:11:16.815 INFO [stdout] test stored_tree::test::open_stored_tree ... ok
Nov 18 17:11:16.816 INFO [stdout] test restore::tests::exclude_files ... ok
Nov 18 17:11:16.829 INFO [stdout] test restore::tests::decline_to_overwrite ... ok
Nov 18 17:11:17.152 INFO [stdout] test restore::tests::forced_overwrite ... ok
Nov 18 17:11:17.274 INFO [stdout] test restore::tests::simple_restore ... ok
Nov 18 17:11:21.347 INFO [stdout] test block::tests::large_file ... ok
Nov 18 17:11:21.347 INFO [stdout] 
Nov 18 17:11:21.347 INFO [stdout] test result: ok. 53 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 17:11:21.347 INFO [stdout] 
Nov 18 17:11:21.363 INFO [stdout] 
Nov 18 17:11:21.363 INFO [stderr]      Running /target/debug/deps/conserve-f37bd04a75c24fb5
Nov 18 17:11:21.364 INFO [stdout] running 0 tests
Nov 18 17:11:21.364 INFO [stderr]      Running /target/debug/deps/blackbox-0c92e066a6b8bd81
Nov 18 17:11:21.364 INFO [stdout] 
Nov 18 17:11:21.365 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 17:11:21.365 INFO [stdout] 
Nov 18 17:11:21.365 INFO [stdout] 
Nov 18 17:11:21.365 INFO [stdout] running 6 tests
Nov 18 17:11:21.447 INFO [stdout] test blackbox_no_args ... ok
Nov 18 17:11:21.455 INFO [stdout] test blackbox_help ... ok
Nov 18 17:11:21.731 INFO [stdout] test clean_error_on_non_archive ... ok
Nov 18 17:11:21.959 INFO [stdout] test empty_archive ... ok
Nov 18 17:11:22.561 INFO [stdout] test incomplete_version ... ok
Nov 18 17:11:23.131 INFO [stdout] test blackbox_backup ... ok
Nov 18 17:11:23.132 INFO [stdout] 
Nov 18 17:11:23.132 INFO [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 17:11:23.132 INFO [stdout] 
Nov 18 17:11:23.134 INFO [stderr]      Running /target/debug/deps/integration-19f617a34796cfa6
Nov 18 17:11:23.138 INFO [stdout] 
Nov 18 17:11:23.138 INFO [stdout] running 3 tests
Nov 18 17:11:23.603 INFO [stdout] test simple_backup_with_excludes ... ok
Nov 18 17:11:23.603 INFO [stdout] test simple_backup ... ok
Nov 18 17:11:28.706 INFO [stdout] test large_file ... ok
Nov 18 17:11:28.706 INFO [stdout] 
Nov 18 17:11:28.707 INFO [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 17:11:28.707 INFO [stdout] 
Nov 18 17:11:28.715 INFO [stderr]    Doc-tests conserve
Nov 18 17:11:32.615 INFO [stdout] 
Nov 18 17:11:32.615 INFO [stdout] running 0 tests
Nov 18 17:11:32.615 INFO [stdout] 
Nov 18 17:11:32.615 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 17:11:32.615 INFO [stdout] 
Nov 18 17:11:32.635 INFO [stderr] su: No module specific data is present
Nov 18 17:11:33.498 INFO running `"docker" "inspect" "c7dcab924a5a6cf7e38d8f89d300cd51cd3dbe575e56241b4317eb5cae777f07"`
Nov 18 17:11:33.769 INFO running `"docker" "rm" "-f" "c7dcab924a5a6cf7e38d8f89d300cd51cd3dbe575e56241b4317eb5cae777f07"`
Nov 18 17:11:34.094 INFO [stdout] c7dcab924a5a6cf7e38d8f89d300cd51cd3dbe575e56241b4317eb5cae777f07