Nov 17 04:26:59.215 INFO testing dotboris/alt against try#2e026d6e5d7621634147ad4c8074f1d964dbb1be for pr-55982
Nov 17 04:26:59.216 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-2/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/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 17 04:26:59.663 INFO [stdout] 1d13e3c217dc9c7fe287c809148ec102078595c825bcec77ad49ab8ad29e3b50
Nov 17 04:26:59.665 INFO running `"docker" "start" "-a" "1d13e3c217dc9c7fe287c809148ec102078595c825bcec77ad49ab8ad29e3b50"`
Nov 17 04:27:00.931 INFO [stderr] usermod: no changes
Nov 17 04:27:01.203 INFO [stderr]    Compiling syn v0.15.15
Nov 17 04:27:01.219 INFO [stderr]    Compiling console v0.6.2
Nov 17 04:27:04.575 INFO [stderr]    Compiling dialoguer v0.1.0
Nov 17 04:27:12.707 INFO [stderr]    Compiling serde_derive v1.0.80
Nov 17 04:27:24.354 INFO [stderr]    Compiling serde v1.0.80
Nov 17 04:27:35.259 INFO [stderr]    Compiling toml v0.4.8
Nov 17 04:27:41.667 INFO [stderr]    Compiling alt v1.0.2 (/source)
Nov 17 04:27:49.429 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 48.45s
Nov 17 04:27:49.444 INFO [stderr] su: No module specific data is present
Nov 17 04:27:50.027 INFO running `"docker" "inspect" "1d13e3c217dc9c7fe287c809148ec102078595c825bcec77ad49ab8ad29e3b50"`
Nov 17 04:27:50.273 INFO running `"docker" "rm" "-f" "1d13e3c217dc9c7fe287c809148ec102078595c825bcec77ad49ab8ad29e3b50"`
Nov 17 04:27:50.539 INFO [stdout] 1d13e3c217dc9c7fe287c809148ec102078595c825bcec77ad49ab8ad29e3b50
Nov 17 04:27:50.548 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-2/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/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 17 04:27:51.024 INFO [stdout] 56852fea2e0b0f39c9830b60a6992afb5d597ff11703cdd522d14e209a6ba6e5
Nov 17 04:27:51.035 INFO running `"docker" "start" "-a" "56852fea2e0b0f39c9830b60a6992afb5d597ff11703cdd522d14e209a6ba6e5"`
Nov 17 04:27:52.631 INFO [stderr] usermod: no changes
Nov 17 04:27:52.863 INFO [stderr]    Compiling escargot v0.3.1
Nov 17 04:27:52.863 INFO [stderr]    Compiling predicates-core v1.0.0
Nov 17 04:27:52.863 INFO [stderr]    Compiling treeline v0.1.0
Nov 17 04:27:52.863 INFO [stderr]    Compiling serde_json v1.0.32
Nov 17 04:27:54.750 INFO [stderr]    Compiling predicates-tree v1.0.0
Nov 17 04:27:54.750 INFO [stderr]    Compiling predicates v1.0.0
Nov 17 04:28:03.055 INFO [stderr]    Compiling assert_cmd v0.10.1
Nov 17 04:28:05.095 INFO [stderr]    Compiling alt v1.0.2 (/source)
Nov 17 04:28:12.188 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 19.53s
Nov 17 04:28:12.198 INFO [stderr] su: No module specific data is present
Nov 17 04:28:13.135 INFO running `"docker" "inspect" "56852fea2e0b0f39c9830b60a6992afb5d597ff11703cdd522d14e209a6ba6e5"`
Nov 17 04:28:13.469 INFO running `"docker" "rm" "-f" "56852fea2e0b0f39c9830b60a6992afb5d597ff11703cdd522d14e209a6ba6e5"`
Nov 17 04:28:13.743 INFO [stdout] 56852fea2e0b0f39c9830b60a6992afb5d597ff11703cdd522d14e209a6ba6e5
Nov 17 04:28:13.750 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-2/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/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 17 04:28:14.170 INFO [stdout] 96dc965ed7f1253ad9aea60fd23f49648c20dc544299bd12100911140a2f0932
Nov 17 04:28:14.173 INFO running `"docker" "start" "-a" "96dc965ed7f1253ad9aea60fd23f49648c20dc544299bd12100911140a2f0932"`
Nov 17 04:28:15.151 INFO [stderr] usermod: no changes
Nov 17 04:28:15.334 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.15s
Nov 17 04:28:15.349 INFO [stderr]      Running /target/debug/deps/alt-66e81d801c228b7a
Nov 17 04:28:15.376 INFO [stdout] 
Nov 17 04:28:15.376 INFO [stdout] running 23 tests
Nov 17 04:28:15.377 INFO [stdout] test scan::homebrew::tests::test_parse_version_path_php ... ok
Nov 17 04:28:15.377 INFO [stdout] test scan::homebrew::tests::test_parse_version_path_node ... ok
Nov 17 04:28:15.377 INFO [stdout] test def_file::tests::find_bin_returns_path_when_command_and_version_defined ... ok
Nov 17 04:28:15.377 INFO [stdout] test def_file::tests::find_bin_returns_none_when_version_not_defined ... ok
Nov 17 04:28:15.378 INFO [stdout] test config::tests::home_dir_should_default ... ok
Nov 17 04:28:15.383 INFO [stdout] test def_file::tests::find_bin_returns_none_on_empty_defs ... ok
Nov 17 04:28:15.384 INFO [stdout] test config::tests::home_dir_should_read_alt_home_env ... ok
Nov 17 04:28:15.384 INFO [stdout] test def_file::tests::find_bin_returns_none_when_command_not_defined ... ok
Nov 17 04:28:15.386 INFO [stdout] test scan::path_suffix::tests::command_only_should_not_parse ... ok
Nov 17 04:28:15.386 INFO [stdout] test scan::path_suffix::tests::command_trailing_period_in_suffix_should_not_parse ... ok
Nov 17 04:28:15.387 INFO [stdout] test scan::path_suffix::tests::command_with_simple_number_suffix_should_parse ... ok
Nov 17 04:28:15.387 INFO [stdout] test scan::path_suffix::tests::command_with_text_suffix_should_not_parse ... ok
Nov 17 04:28:15.387 INFO [stdout] test scan::path_suffix::tests::command_with_version_suffix_and_dash_should_parse ... ok
Nov 17 04:28:15.388 INFO [stdout] test shim::tests::test_alt_command_is_not_shim ... ok
Nov 17 04:28:15.388 INFO [stdout] test shim::tests::test_get_command_absolute ... ok
Nov 17 04:28:15.388 INFO [stdout] test shim::tests::test_relative_non_alt_is_not_shim ... ok
Nov 17 04:28:15.389 INFO [stdout] test shim::tests::test_get_command_command ... ok
Nov 17 04:28:15.389 INFO [stdout] test shim::tests::test_get_command_relative ... ok
Nov 17 04:28:15.389 INFO [stdout] test shim::tests::test_non_alt_command_is_shim ... ok
Nov 17 04:28:15.389 INFO [stdout] test shim::tests::test_relative_alt_is_not_shim ... ok
Nov 17 04:28:15.395 INFO [stdout] test shim::tests::test_absolute_alt_is_not_shim ... ok
Nov 17 04:28:15.395 INFO [stdout] test scan::path_suffix::tests::command_with_version_suffix_should_parse ... ok
Nov 17 04:28:15.396 INFO [stdout] test shim::tests::test_absolute_non_alt_is_not_shim ... ok
Nov 17 04:28:15.396 INFO [stdout] 
Nov 17 04:28:15.396 INFO [stdout] test result: ok. 23 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 04:28:15.397 INFO [stdout] 
Nov 17 04:28:15.397 INFO [stderr]      Running /target/debug/deps/integration_test-2596bb341279f273
Nov 17 04:28:15.404 INFO [stdout] 
Nov 17 04:28:15.404 INFO [stdout] running 5 tests
Nov 17 04:29:15.427 INFO [stdout] test system_with_no_use ... test system_with_no_use has been running for over 60 seconds
Nov 17 04:29:15.427 INFO [stdout] test use_with_subdir ... test use_with_subdir has been running for over 60 seconds
Nov 17 04:29:15.428 INFO [stdout] test use_with_subdir_overwrite ... test use_with_subdir_overwrite has been running for over 60 seconds
Nov 17 04:29:15.428 INFO [stdout] test def_and_use ... test def_and_use has been running for over 60 seconds
Nov 17 04:29:15.428 INFO [stdout] test reset_with_use_system ... test reset_with_use_system has been running for over 60 seconds
Nov 17 04:30:13.059 INFO [stdout] test system_with_no_use ... ok
Nov 17 04:30:13.124 INFO [stdout] test def_and_use ... ok
Nov 17 04:30:13.209 INFO [stdout] test reset_with_use_system ... ok
Nov 17 04:30:13.227 INFO [stdout] test use_with_subdir_overwrite ... ok
Nov 17 04:30:13.227 INFO [stdout] test use_with_subdir ... ok
Nov 17 04:30:13.227 INFO [stdout] 
Nov 17 04:30:13.227 INFO [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 04:30:13.227 INFO [stdout] 
Nov 17 04:30:13.235 INFO [stderr]      Running /target/debug/deps/test_env-fb016efe5e4a6193
Nov 17 04:30:13.239 INFO [stdout] 
Nov 17 04:30:13.239 INFO [stdout] running 0 tests
Nov 17 04:30:13.239 INFO [stdout] 
Nov 17 04:30:13.239 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 04:30:13.240 INFO [stdout] 
Nov 17 04:30:13.251 INFO [stderr] su: No module specific data is present
Nov 17 04:30:14.191 INFO running `"docker" "inspect" "96dc965ed7f1253ad9aea60fd23f49648c20dc544299bd12100911140a2f0932"`
Nov 17 04:30:14.433 INFO running `"docker" "rm" "-f" "96dc965ed7f1253ad9aea60fd23f49648c20dc544299bd12100911140a2f0932"`
Nov 17 04:30:14.690 INFO [stdout] 96dc965ed7f1253ad9aea60fd23f49648c20dc544299bd12100911140a2f0932