Dec 06 01:19:47.520 INFO testing dotboris/alt against try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468 for pr-56514
Dec 06 01:19:47.520 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-3/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-56514/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/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 +6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468-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 06 01:19:47.753 INFO [stdout] c8fd8a70b923b7fac4d902d4174945f1d55cae58782923706e64860e60c330e5
Dec 06 01:19:47.758 INFO running `"docker" "start" "-a" "c8fd8a70b923b7fac4d902d4174945f1d55cae58782923706e64860e60c330e5"`
Dec 06 01:19:48.316 INFO [stderr] usermod: no changes
Dec 06 01:19:48.476 INFO [stderr]    Compiling tempfile v2.2.0
Dec 06 01:19:48.476 INFO [stderr]    Compiling syn v0.15.15
Dec 06 01:19:49.336 INFO [stderr]    Compiling dialoguer v0.1.0
Dec 06 01:19:57.200 INFO [stderr]    Compiling serde_derive v1.0.80
Dec 06 01:20:06.430 INFO [stderr]    Compiling serde v1.0.80
Dec 06 01:20:15.623 INFO [stderr]    Compiling toml v0.4.8
Dec 06 01:20:24.038 INFO [stderr]    Compiling alt v1.0.2 (/source)
Dec 06 01:20:32.312 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 43.97s
Dec 06 01:20:32.321 INFO [stderr] su: No module specific data is present
Dec 06 01:20:33.194 INFO running `"docker" "inspect" "c8fd8a70b923b7fac4d902d4174945f1d55cae58782923706e64860e60c330e5"`
Dec 06 01:20:33.596 INFO running `"docker" "rm" "-f" "c8fd8a70b923b7fac4d902d4174945f1d55cae58782923706e64860e60c330e5"`
Dec 06 01:20:33.960 INFO [stdout] c8fd8a70b923b7fac4d902d4174945f1d55cae58782923706e64860e60c330e5
Dec 06 01:20:33.962 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-3/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-56514/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/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 +6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468-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 06 01:20:34.452 INFO [stdout] 8982f563c71b3184f91b9b4d2e6101e2380ed87f56172da22717b9f24e66f929
Dec 06 01:20:34.458 INFO running `"docker" "start" "-a" "8982f563c71b3184f91b9b4d2e6101e2380ed87f56172da22717b9f24e66f929"`
Dec 06 01:20:36.239 INFO [stderr] usermod: no changes
Dec 06 01:20:36.404 INFO [stderr]    Compiling predicates v1.0.0
Dec 06 01:20:36.408 INFO [stderr]    Compiling serde_json v1.0.32
Dec 06 01:20:42.247 INFO [stderr]    Compiling escargot v0.3.1
Dec 06 01:20:44.524 INFO [stderr]    Compiling assert_cmd v0.10.1
Dec 06 01:20:46.758 INFO [stderr]    Compiling alt v1.0.2 (/source)
Dec 06 01:20:54.141 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 17.87s
Dec 06 01:20:54.151 INFO [stderr] su: No module specific data is present
Dec 06 01:20:54.906 INFO running `"docker" "inspect" "8982f563c71b3184f91b9b4d2e6101e2380ed87f56172da22717b9f24e66f929"`
Dec 06 01:20:55.256 INFO running `"docker" "rm" "-f" "8982f563c71b3184f91b9b4d2e6101e2380ed87f56172da22717b9f24e66f929"`
Dec 06 01:20:55.492 INFO [stdout] 8982f563c71b3184f91b9b4d2e6101e2380ed87f56172da22717b9f24e66f929
Dec 06 01:20:55.498 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-3/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-56514/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/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 +6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468-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 06 01:20:55.854 INFO [stdout] e094ef56f63a9f121163672035b31afc4f1dc84aa1a03e8478526ad07fa5aaae
Dec 06 01:20:55.858 INFO running `"docker" "start" "-a" "e094ef56f63a9f121163672035b31afc4f1dc84aa1a03e8478526ad07fa5aaae"`
Dec 06 01:20:56.975 INFO [stderr] usermod: no changes
Dec 06 01:20:57.129 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.13s
Dec 06 01:20:57.144 INFO [stderr]      Running /target/debug/deps/alt-6dae872c06b2588f
Dec 06 01:20:57.156 INFO [stdout] 
Dec 06 01:20:57.157 INFO [stdout] running 23 tests
Dec 06 01:20:57.157 INFO [stdout] test config::tests::home_dir_should_default ... ok
Dec 06 01:20:57.157 INFO [stdout] test config::tests::home_dir_should_read_alt_home_env ... ok
Dec 06 01:20:57.157 INFO [stdout] test def_file::tests::find_bin_returns_none_on_empty_defs ... ok
Dec 06 01:20:57.157 INFO [stdout] test scan::homebrew::tests::test_parse_version_path_php ... ok
Dec 06 01:20:57.157 INFO [stdout] test def_file::tests::find_bin_returns_none_when_command_not_defined ... ok
Dec 06 01:20:57.158 INFO [stdout] test def_file::tests::find_bin_returns_none_when_version_not_defined ... ok
Dec 06 01:20:57.160 INFO [stdout] test def_file::tests::find_bin_returns_path_when_command_and_version_defined ... ok
Dec 06 01:20:57.160 INFO [stdout] test scan::homebrew::tests::test_parse_version_path_node ... ok
Dec 06 01:20:57.163 INFO [stdout] test shim::tests::test_absolute_alt_is_not_shim ... ok
Dec 06 01:20:57.163 INFO [stdout] test shim::tests::test_absolute_non_alt_is_not_shim ... ok
Dec 06 01:20:57.164 INFO [stdout] test scan::path_suffix::tests::command_with_version_suffix_and_dash_should_parse ... ok
Dec 06 01:20:57.164 INFO [stdout] test scan::path_suffix::tests::command_with_simple_number_suffix_should_parse ... ok
Dec 06 01:20:57.164 INFO [stdout] test shim::tests::test_get_command_command ... ok
Dec 06 01:20:57.164 INFO [stdout] test shim::tests::test_get_command_relative ... ok
Dec 06 01:20:57.165 INFO [stdout] test shim::tests::test_get_command_absolute ... ok
Dec 06 01:20:57.165 INFO [stdout] test shim::tests::test_alt_command_is_not_shim ... ok
Dec 06 01:20:57.165 INFO [stdout] test shim::tests::test_non_alt_command_is_shim ... ok
Dec 06 01:20:57.165 INFO [stdout] test shim::tests::test_relative_alt_is_not_shim ... ok
Dec 06 01:20:57.165 INFO [stdout] test shim::tests::test_relative_non_alt_is_not_shim ... ok
Dec 06 01:20:57.165 INFO [stdout] test scan::path_suffix::tests::command_trailing_period_in_suffix_should_not_parse ... ok
Dec 06 01:20:57.165 INFO [stdout] test scan::path_suffix::tests::command_with_text_suffix_should_not_parse ... ok
Dec 06 01:20:57.165 INFO [stdout] test scan::path_suffix::tests::command_with_version_suffix_should_parse ... ok
Dec 06 01:20:57.165 INFO [stdout] test scan::path_suffix::tests::command_only_should_not_parse ... ok
Dec 06 01:20:57.166 INFO [stdout] 
Dec 06 01:20:57.166 INFO [stdout] test result: ok. 23 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 06 01:20:57.166 INFO [stdout] 
Dec 06 01:20:57.166 INFO [stderr]      Running /target/debug/deps/integration_test-58dab8e03201eaf4
Dec 06 01:20:57.174 INFO [stdout] 
Dec 06 01:20:57.174 INFO [stdout] running 5 tests
Dec 06 01:21:57.180 INFO [stdout] test system_with_no_use ... test system_with_no_use has been running for over 60 seconds
Dec 06 01:21:57.180 INFO [stdout] test reset_with_use_system ... test reset_with_use_system has been running for over 60 seconds
Dec 06 01:21:57.180 INFO [stdout] test def_and_use ... test def_and_use has been running for over 60 seconds
Dec 06 01:21:57.180 INFO [stdout] test use_with_subdir ... test use_with_subdir has been running for over 60 seconds
Dec 06 01:21:57.180 INFO [stdout] test use_with_subdir_overwrite ... test use_with_subdir_overwrite has been running for over 60 seconds
Dec 06 01:22:26.521 INFO [stdout] test use_with_subdir_overwrite ... ok
Dec 06 01:22:26.696 INFO [stdout] test def_and_use ... ok
Dec 06 01:22:26.732 INFO [stdout] test use_with_subdir ... ok
Dec 06 01:22:26.812 INFO [stdout] test system_with_no_use ... ok
Dec 06 01:22:26.841 INFO [stdout] test reset_with_use_system ... ok
Dec 06 01:22:26.841 INFO [stdout] 
Dec 06 01:22:26.841 INFO [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 06 01:22:26.841 INFO [stdout] 
Dec 06 01:22:26.852 INFO [stderr]      Running /target/debug/deps/test_env-003cfc5f8210c534
Dec 06 01:22:26.880 INFO [stderr] su: No module specific data is present
Dec 06 01:22:26.880 INFO [stdout] 
Dec 06 01:22:26.880 INFO [stdout] running 0 tests
Dec 06 01:22:26.880 INFO [stdout] 
Dec 06 01:22:26.880 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 06 01:22:26.880 INFO [stdout] 
Dec 06 01:22:28.070 INFO running `"docker" "inspect" "e094ef56f63a9f121163672035b31afc4f1dc84aa1a03e8478526ad07fa5aaae"`
Dec 06 01:22:28.334 INFO running `"docker" "rm" "-f" "e094ef56f63a9f121163672035b31afc4f1dc84aa1a03e8478526ad07fa5aaae"`
Dec 06 01:22:28.546 INFO [stdout] e094ef56f63a9f121163672035b31afc4f1dc84aa1a03e8478526ad07fa5aaae