Dec 13 21:04:33.056 INFO testing dotboris/alt against try#aa49d8ef14939ddec0e34b346b60174a5673d48f for pr-56550
Dec 13 21:04:33.056 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-0/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/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 13 21:04:33.282 INFO [stdout] e3f180882491387028dfd6b8403abe6ef9b2ad7d1c886095de3ac4fc76dfd1a9
Dec 13 21:04:33.285 INFO running `"docker" "start" "-a" "e3f180882491387028dfd6b8403abe6ef9b2ad7d1c886095de3ac4fc76dfd1a9"`
Dec 13 21:04:33.709 INFO [stderr] usermod: no changes
Dec 13 21:04:33.905 INFO [stderr]    Compiling tempfile v2.2.0
Dec 13 21:04:33.906 INFO [stderr]    Compiling serde v1.0.80
Dec 13 21:04:33.907 INFO [stderr]    Compiling console v0.6.2
Dec 13 21:04:38.775 INFO [stderr]    Compiling dialoguer v0.1.0
Dec 13 21:04:46.047 INFO [stderr]    Compiling toml v0.4.8
Dec 13 21:04:50.767 INFO [stderr]    Compiling alt v1.0.2 (/source)
Dec 13 21:04:57.172 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 23.43s
Dec 13 21:04:57.200 INFO [stderr] su: No module specific data is present
Dec 13 21:04:58.503 INFO running `"docker" "inspect" "e3f180882491387028dfd6b8403abe6ef9b2ad7d1c886095de3ac4fc76dfd1a9"`
Dec 13 21:04:58.583 INFO running `"docker" "rm" "-f" "e3f180882491387028dfd6b8403abe6ef9b2ad7d1c886095de3ac4fc76dfd1a9"`
Dec 13 21:04:59.687 INFO [stdout] e3f180882491387028dfd6b8403abe6ef9b2ad7d1c886095de3ac4fc76dfd1a9
Dec 13 21:04:59.689 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-0/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/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 13 21:04:59.871 INFO [stdout] 09ba5e4822a59c475505aee28c692a74533b17698f536a80b690b11a098ee5a5
Dec 13 21:04:59.873 INFO running `"docker" "start" "-a" "09ba5e4822a59c475505aee28c692a74533b17698f536a80b690b11a098ee5a5"`
Dec 13 21:05:00.340 INFO [stderr] usermod: no changes
Dec 13 21:05:00.489 INFO [stderr]    Compiling serde_json v1.0.32
Dec 13 21:05:05.475 INFO [stderr]    Compiling escargot v0.3.1
Dec 13 21:05:07.279 INFO [stderr]    Compiling assert_cmd v0.10.1
Dec 13 21:05:09.499 INFO [stderr]    Compiling alt v1.0.2 (/source)
Dec 13 21:05:18.483 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 18.12s
Dec 13 21:05:18.487 INFO [stderr] su: No module specific data is present
Dec 13 21:05:19.661 INFO running `"docker" "inspect" "09ba5e4822a59c475505aee28c692a74533b17698f536a80b690b11a098ee5a5"`
Dec 13 21:05:20.089 INFO running `"docker" "rm" "-f" "09ba5e4822a59c475505aee28c692a74533b17698f536a80b690b11a098ee5a5"`
Dec 13 21:05:20.357 INFO [stdout] 09ba5e4822a59c475505aee28c692a74533b17698f536a80b690b11a098ee5a5
Dec 13 21:05:20.359 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-0/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/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 13 21:05:20.860 INFO [stdout] 9a280a1d46fa16da1af5999c4b4ea8738bd84aa5e6af7451268ff840e686921b
Dec 13 21:05:20.867 INFO running `"docker" "start" "-a" "9a280a1d46fa16da1af5999c4b4ea8738bd84aa5e6af7451268ff840e686921b"`
Dec 13 21:05:22.374 INFO [stderr] usermod: no changes
Dec 13 21:05:22.619 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.21s
Dec 13 21:05:22.635 INFO [stderr]      Running /target/debug/deps/alt-6db481211645def4
Dec 13 21:05:22.639 INFO [stdout] 
Dec 13 21:05:22.639 INFO [stdout] running 23 tests
Dec 13 21:05:22.651 INFO [stdout] test config::tests::home_dir_should_default ... ok
Dec 13 21:05:22.651 INFO [stdout] test config::tests::home_dir_should_read_alt_home_env ... ok
Dec 13 21:05:22.651 INFO [stdout] test def_file::tests::find_bin_returns_none_on_empty_defs ... ok
Dec 13 21:05:22.651 INFO [stdout] test def_file::tests::find_bin_returns_none_when_command_not_defined ... ok
Dec 13 21:05:22.651 INFO [stdout] test def_file::tests::find_bin_returns_none_when_version_not_defined ... ok
Dec 13 21:05:22.651 INFO [stdout] test def_file::tests::find_bin_returns_path_when_command_and_version_defined ... ok
Dec 13 21:05:22.651 INFO [stdout] test scan::homebrew::tests::test_parse_version_path_node ... ok
Dec 13 21:05:22.651 INFO [stdout] test scan::homebrew::tests::test_parse_version_path_php ... ok
Dec 13 21:05:22.651 INFO [stdout] test scan::path_suffix::tests::command_only_should_not_parse ... ok
Dec 13 21:05:22.651 INFO [stdout] test scan::path_suffix::tests::command_with_version_suffix_and_dash_should_parse ... ok
Dec 13 21:05:22.651 INFO [stdout] test scan::path_suffix::tests::command_with_simple_number_suffix_should_parse ... ok
Dec 13 21:05:22.655 INFO [stdout] test scan::path_suffix::tests::command_trailing_period_in_suffix_should_not_parse ... ok
Dec 13 21:05:22.655 INFO [stdout] test shim::tests::test_get_command_absolute ... ok
Dec 13 21:05:22.655 INFO [stdout] test shim::tests::test_alt_command_is_not_shim ... ok
Dec 13 21:05:22.655 INFO [stdout] test shim::tests::test_get_command_command ... ok
Dec 13 21:05:22.655 INFO [stdout] test scan::path_suffix::tests::command_with_version_suffix_should_parse ... ok
Dec 13 21:05:22.655 INFO [stdout] test scan::path_suffix::tests::command_with_text_suffix_should_not_parse ... ok
Dec 13 21:05:22.655 INFO [stdout] test shim::tests::test_absolute_alt_is_not_shim ... ok
Dec 13 21:05:22.655 INFO [stdout] test shim::tests::test_absolute_non_alt_is_not_shim ... ok
Dec 13 21:05:22.655 INFO [stdout] test shim::tests::test_get_command_relative ... ok
Dec 13 21:05:22.655 INFO [stdout] test shim::tests::test_non_alt_command_is_shim ... ok
Dec 13 21:05:22.655 INFO [stdout] test shim::tests::test_relative_alt_is_not_shim ... ok
Dec 13 21:05:22.655 INFO [stdout] test shim::tests::test_relative_non_alt_is_not_shim ... ok
Dec 13 21:05:22.655 INFO [stdout] 
Dec 13 21:05:22.655 INFO [stdout] test result: ok. 23 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 21:05:22.655 INFO [stdout] 
Dec 13 21:05:22.663 INFO [stderr]      Running /target/debug/deps/integration_test-7a0b0b8556d36159
Dec 13 21:05:22.671 INFO [stdout] 
Dec 13 21:05:22.671 INFO [stdout] running 5 tests
Dec 13 21:06:22.671 INFO [stdout] test system_with_no_use ... test system_with_no_use has been running for over 60 seconds
Dec 13 21:06:22.671 INFO [stdout] test reset_with_use_system ... test reset_with_use_system has been running for over 60 seconds
Dec 13 21:06:22.671 INFO [stdout] test def_and_use ... test def_and_use has been running for over 60 seconds
Dec 13 21:06:22.671 INFO [stdout] test use_with_subdir ... test use_with_subdir has been running for over 60 seconds
Dec 13 21:06:22.671 INFO [stdout] test use_with_subdir_overwrite ... test use_with_subdir_overwrite has been running for over 60 seconds
Dec 13 21:07:15.659 INFO [stdout] test def_and_use ... ok
Dec 13 21:07:15.787 INFO [stdout] test reset_with_use_system ... ok
Dec 13 21:07:15.815 INFO [stdout] test use_with_subdir_overwrite ... ok
Dec 13 21:07:15.835 INFO [stdout] test system_with_no_use ... ok
Dec 13 21:07:15.842 INFO [stdout] test use_with_subdir ... ok
Dec 13 21:07:15.842 INFO [stdout] 
Dec 13 21:07:15.843 INFO [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 21:07:15.843 INFO [stdout] 
Dec 13 21:07:15.844 INFO [stderr]      Running /target/debug/deps/test_env-9317e568f7e7b8ff
Dec 13 21:07:15.856 INFO [stdout] 
Dec 13 21:07:15.856 INFO [stdout] running 0 tests
Dec 13 21:07:15.856 INFO [stdout] 
Dec 13 21:07:15.856 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 21:07:15.856 INFO [stdout] 
Dec 13 21:07:15.860 INFO [stderr] su: No module specific data is present
Dec 13 21:07:17.579 INFO running `"docker" "inspect" "9a280a1d46fa16da1af5999c4b4ea8738bd84aa5e6af7451268ff840e686921b"`
Dec 13 21:07:17.973 INFO running `"docker" "rm" "-f" "9a280a1d46fa16da1af5999c4b4ea8738bd84aa5e6af7451268ff840e686921b"`
Dec 13 21:07:18.255 INFO [stdout] 9a280a1d46fa16da1af5999c4b4ea8738bd84aa5e6af7451268ff840e686921b