Nov 18 17:10:29.910 INFO testing conserve-0.4.2 against try#2e026d6e5d7621634147ad4c8074f1d964dbb1be for pr-55982
Nov 18 17:10:29.910 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-4/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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.076 INFO [stdout] cc5986aebc8da02099dd66cb7d9521a7583c2bf8f009c60a80cbbce0398856cd
Nov 18 17:10:30.080 INFO running `"docker" "start" "-a" "cc5986aebc8da02099dd66cb7d9521a7583c2bf8f009c60a80cbbce0398856cd"`
Nov 18 17:10:30.494 INFO [stderr] usermod: no changes
Nov 18 17:10:30.712 INFO [stderr]    Compiling clap v2.29.4
Nov 18 17:10:30.713 INFO [stderr]    Compiling conserve v0.4.2 (/source)
Nov 18 17:10:30.715 INFO [stderr]    Compiling globset v0.2.1
Nov 18 17:10:30.716 INFO [stderr]    Compiling chrono v0.2.25
Nov 18 17:10:56.640 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 26.12s
Nov 18 17:10:56.649 INFO [stderr] su: No module specific data is present
Nov 18 17:10:56.905 INFO running `"docker" "inspect" "cc5986aebc8da02099dd66cb7d9521a7583c2bf8f009c60a80cbbce0398856cd"`
Nov 18 17:10:57.060 INFO running `"docker" "rm" "-f" "cc5986aebc8da02099dd66cb7d9521a7583c2bf8f009c60a80cbbce0398856cd"`
Nov 18 17:10:57.359 INFO [stdout] cc5986aebc8da02099dd66cb7d9521a7583c2bf8f009c60a80cbbce0398856cd
Nov 18 17:10:57.367 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-4/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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:57.808 INFO [stdout] 7def0608fa5fedb3f0ca4345be20d843530be2740e98e159b6ca419f760cc5b4
Nov 18 17:10:57.811 INFO running `"docker" "start" "-a" "7def0608fa5fedb3f0ca4345be20d843530be2740e98e159b6ca419f760cc5b4"`
Nov 18 17:10:59.202 INFO [stderr] usermod: no changes
Nov 18 17:10:59.412 INFO [stderr]    Compiling conserve v0.4.2 (/source)
Nov 18 17:11:18.028 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 18.80s
Nov 18 17:11:18.039 INFO [stderr] su: No module specific data is present
Nov 18 17:11:18.421 INFO running `"docker" "inspect" "7def0608fa5fedb3f0ca4345be20d843530be2740e98e159b6ca419f760cc5b4"`
Nov 18 17:11:18.567 INFO running `"docker" "rm" "-f" "7def0608fa5fedb3f0ca4345be20d843530be2740e98e159b6ca419f760cc5b4"`
Nov 18 17:11:18.726 INFO [stdout] 7def0608fa5fedb3f0ca4345be20d843530be2740e98e159b6ca419f760cc5b4
Nov 18 17:11:18.735 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-4/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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:19.057 INFO [stdout] abaf782b61a91e9dfde541021300a4834e82ff4fe024b0c4f8f9227e91d3e705
Nov 18 17:11:19.071 INFO running `"docker" "start" "-a" "abaf782b61a91e9dfde541021300a4834e82ff4fe024b0c4f8f9227e91d3e705"`
Nov 18 17:11:20.259 INFO [stderr] usermod: no changes
Nov 18 17:11:20.507 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.21s
Nov 18 17:11:20.535 INFO [stderr]      Running /target/debug/deps/conserve-1c1321411d991763
Nov 18 17:11:20.539 INFO [stdout] 
Nov 18 17:11:20.539 INFO [stdout] running 53 tests
Nov 18 17:11:20.547 INFO [stdout] test archive::tests::init_empty_dir ... ok
Nov 18 17:11:20.547 INFO [stdout] test apath::tests::valid_and_ordered ... ok
Nov 18 17:11:20.547 INFO [stdout] test apath::tests::invalid ... ok
Nov 18 17:11:20.785 INFO [stdout] test band::tests::create_existing_band ... ok
Nov 18 17:11:20.785 INFO [stdout] test bandid::tests::as_string ... ok
Nov 18 17:11:20.794 INFO [stdout] test archive::tests::create_then_open_archive ... ok
Nov 18 17:11:20.800 INFO [stdout] test bandid::tests::equality ... ok
Nov 18 17:11:20.866 INFO [stdout] test backup::tests::empty_file_uses_zero_blocks ... ok
Nov 18 17:11:20.866 INFO [stdout] test band::tests::create_and_reopen_band ... ok
Nov 18 17:11:20.866 INFO [stdout] test bandid::tests::format ... ok
Nov 18 17:11:20.867 INFO [stdout] test archive::tests::create_bands ... ok
Nov 18 17:11:20.871 INFO [stdout] test backup::tests::symlink ... ok
Nov 18 17:11:20.871 INFO [stdout] test archive::tests::empty_archive ... ok
Nov 18 17:11:20.872 INFO [stdout] test bandid::tests::next ... ok
Nov 18 17:11:20.882 INFO [stdout] test bandid::tests::zero ... ok
Nov 18 17:11:20.907 INFO [stdout] test excludes::tests::extendend_pattern_parse ... ok
Nov 18 17:11:20.907 INFO [stdout] test bandid::tests::from_string_valid ... ok
Nov 18 17:11:20.927 INFO [stdout] test bandid::tests::from_string_detects_invalid ... ok
Nov 18 17:11:20.935 INFO [stdout] test excludes::tests::nothing_parse ... ok
Nov 18 17:11:20.975 INFO [stdout] test excludes::tests::simple_parse ... ok
Nov 18 17:11:20.978 INFO [stdout] test index::tests::excluded_entries ... ok
Nov 18 17:11:20.983 INFO [stdout] test excludes::tests::path_parse ... ok
Nov 18 17:11:20.983 INFO [stdout] test index::tests::basic ... ok
Nov 18 17:11:21.003 INFO [stdout] test index::tests::multiple_hunks ... ok
Nov 18 17:11:21.063 INFO [stdout] test bandid::tests::empty_id_not_allowed ... ok
Nov 18 17:11:21.064 INFO [stdout] test index::tests::no_duplicate_paths ... ok
Nov 18 17:11:21.064 INFO [stdout] test index::tests::index_builder_checks_order ... ok
Nov 18 17:11:21.064 INFO [stdout] test index::tests::index_builder_checks_names ... ok
Nov 18 17:11:21.065 INFO [stdout] test backup::tests::excludes ... ok
Nov 18 17:11:21.070 INFO [stdout] test live_tree::tests::exclude_entries_directory ... ok
Nov 18 17:11:21.070 INFO [stdout] test index::tests::path_for_hunk ... ok
Nov 18 17:11:21.070 INFO [stdout] test jsonio::tests::read_write_json ... ok
Nov 18 17:11:21.070 INFO [stdout] test block::tests::write_to_file ... ok
Nov 18 17:11:21.070 INFO [stdout] test index::tests::serialize_index ... ok
Nov 18 17:11:21.070 INFO [stdout] test index::tests::no_duplicate_paths_across_hunks ... ok
Nov 18 17:11:21.086 INFO [stdout] test live_tree::tests::open_tree ... ok
Nov 18 17:11:21.086 INFO [stdout] test live_tree::tests::simple_directory ... ok
Nov 18 17:11:21.086 INFO [stdout] test live_tree::tests::symlinks ... ok
Nov 18 17:11:21.086 INFO [stdout] test report::tests::count ... ok
Nov 18 17:11:21.086 INFO [stdout] test report::tests::display ... ok
Nov 18 17:11:21.086 INFO [stdout] test report::tests::merge_reports ... ok
Nov 18 17:11:21.123 INFO [stdout] test stored_tree::test::cant_open_no_versions ... ok
Nov 18 17:11:21.325 INFO [stdout] test block::tests::write_same_data_again ... ok
Nov 18 17:11:21.331 INFO [stdout] test ui::tests::best_ui ... ok
Nov 18 17:11:21.331 INFO [stdout] test ui::tests::by_name ... ok
Nov 18 17:11:21.331 INFO [stdout] test ui::tests::test_compression_ratio ... ok
Nov 18 17:11:21.979 INFO [stdout] test restore::tests::decline_to_overwrite ... ok
Nov 18 17:11:22.088 INFO [stdout] test restore::tests::forced_overwrite ... ok
Nov 18 17:11:22.411 INFO [stdout] test restore::tests::restore_named_band ... ok
Nov 18 17:11:22.431 INFO [stdout] test restore::tests::simple_restore ... ok
Nov 18 17:11:22.635 INFO [stdout] test restore::tests::exclude_files ... ok
Nov 18 17:11:22.643 INFO [stdout] test stored_tree::test::open_stored_tree ... ok
Nov 18 17:11:28.055 INFO [stdout] test block::tests::large_file ... ok
Nov 18 17:11:28.055 INFO [stdout] 
Nov 18 17:11:28.055 INFO [stdout] test result: ok. 53 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 17:11:28.055 INFO [stdout] 
Nov 18 17:11:28.058 INFO [stderr]      Running /target/debug/deps/conserve-da8cfb4d9a140d44
Nov 18 17:11:28.060 INFO [stdout] 
Nov 18 17:11:28.060 INFO [stdout] running 0 tests
Nov 18 17:11:28.061 INFO [stdout] 
Nov 18 17:11:28.061 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 17:11:28.061 INFO [stdout] 
Nov 18 17:11:28.063 INFO [stderr]      Running /target/debug/deps/blackbox-5daf8086c6aa6959
Nov 18 17:11:28.069 INFO [stdout] 
Nov 18 17:11:28.069 INFO [stdout] running 6 tests
Nov 18 17:11:28.103 INFO [stdout] test blackbox_no_args ... ok
Nov 18 17:11:28.111 INFO [stdout] test blackbox_help ... ok
Nov 18 17:11:28.461 INFO [stdout] test clean_error_on_non_archive ... ok
Nov 18 17:11:28.647 INFO [stdout] test empty_archive ... ok
Nov 18 17:11:29.155 INFO [stdout] test incomplete_version ... ok
Nov 18 17:11:29.882 INFO [stdout] test blackbox_backup ... ok
Nov 18 17:11:29.883 INFO [stderr]      Running /target/debug/deps/integration-035bbd5c73723f29
Nov 18 17:11:29.883 INFO [stdout] 
Nov 18 17:11:29.883 INFO [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 17:11:29.884 INFO [stdout] 
Nov 18 17:11:29.885 INFO [stdout] 
Nov 18 17:11:29.885 INFO [stdout] running 3 tests
Nov 18 17:11:30.295 INFO [stdout] test simple_backup ... ok
Nov 18 17:11:30.303 INFO [stdout] test simple_backup_with_excludes ... ok
Nov 18 17:11:35.977 INFO [stdout] test large_file ... ok
Nov 18 17:11:35.977 INFO [stdout] 
Nov 18 17:11:35.977 INFO [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 17:11:35.977 INFO [stdout] 
Nov 18 17:11:35.986 INFO [stderr]    Doc-tests conserve
Nov 18 17:11:41.486 INFO [stdout] 
Nov 18 17:11:41.486 INFO [stdout] running 0 tests
Nov 18 17:11:41.486 INFO [stdout] 
Nov 18 17:11:41.486 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 17:11:41.486 INFO [stdout] 
Nov 18 17:11:41.497 INFO [stderr] su: No module specific data is present
Nov 18 17:11:42.168 INFO running `"docker" "inspect" "abaf782b61a91e9dfde541021300a4834e82ff4fe024b0c4f8f9227e91d3e705"`
Nov 18 17:11:42.397 INFO running `"docker" "rm" "-f" "abaf782b61a91e9dfde541021300a4834e82ff4fe024b0c4f8f9227e91d3e705"`
Nov 18 17:11:42.699 INFO [stdout] abaf782b61a91e9dfde541021300a4834e82ff4fe024b0c4f8f9227e91d3e705