Dec 06 01:19:48.240 INFO testing dotboris/alt against master#906deae0790bd18681b937fe9a141a3c26cf1855 for pr-56514
Dec 06 01:19:48.240 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-2/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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:48.507 INFO [stdout] 0dacdacec6a127325cbfb5737e68d79903289dcafc9026564b42d47e11a10556
Dec 06 01:19:48.509 INFO running `"docker" "start" "-a" "0dacdacec6a127325cbfb5737e68d79903289dcafc9026564b42d47e11a10556"`
Dec 06 01:19:48.993 INFO [stderr] usermod: no changes
Dec 06 01:19:49.168 INFO [stderr]    Compiling serde v1.0.80
Dec 06 01:19:49.172 INFO [stderr]    Compiling syn v0.15.15
Dec 06 01:19:49.172 INFO [stderr]    Compiling console v0.6.2
Dec 06 01:19:53.244 INFO [stderr]    Compiling dialoguer v0.1.0
Dec 06 01:19:59.280 INFO [stderr]    Compiling serde_derive v1.0.80
Dec 06 01:20:17.110 INFO [stderr]    Compiling toml v0.4.8
Dec 06 01:20:24.097 INFO [stderr]    Compiling alt v1.0.2 (/source)
Dec 06 01:20:31.827 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 42.80s
Dec 06 01:20:31.836 INFO [stderr] su: No module specific data is present
Dec 06 01:20:32.946 INFO running `"docker" "inspect" "0dacdacec6a127325cbfb5737e68d79903289dcafc9026564b42d47e11a10556"`
Dec 06 01:20:33.246 INFO running `"docker" "rm" "-f" "0dacdacec6a127325cbfb5737e68d79903289dcafc9026564b42d47e11a10556"`
Dec 06 01:20:33.559 INFO [stdout] 0dacdacec6a127325cbfb5737e68d79903289dcafc9026564b42d47e11a10556
Dec 06 01:20:33.564 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-2/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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:33.942 INFO [stdout] 7341e54dcc6d1e67120dd5f93fd4b79d93a3ebf53d1c654ab92e677b798d5dd2
Dec 06 01:20:33.950 INFO running `"docker" "start" "-a" "7341e54dcc6d1e67120dd5f93fd4b79d93a3ebf53d1c654ab92e677b798d5dd2"`
Dec 06 01:20:35.404 INFO [stderr] usermod: no changes
Dec 06 01:20:35.573 INFO [stderr]    Compiling escargot v0.3.1
Dec 06 01:20:35.574 INFO [stderr]    Compiling predicates-core v1.0.0
Dec 06 01:20:35.584 INFO [stderr]    Compiling serde_json v1.0.32
Dec 06 01:20:37.324 INFO [stderr]    Compiling predicates v1.0.0
Dec 06 01:20:37.324 INFO [stderr]    Compiling predicates-tree v1.0.0
Dec 06 01:20:45.052 INFO [stderr]    Compiling assert_cmd v0.10.1
Dec 06 01:20:46.887 INFO [stderr]    Compiling alt v1.0.2 (/source)
Dec 06 01:20:54.250 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 18.81s
Dec 06 01:20:54.264 INFO [stderr] su: No module specific data is present
Dec 06 01:20:55.020 INFO running `"docker" "inspect" "7341e54dcc6d1e67120dd5f93fd4b79d93a3ebf53d1c654ab92e677b798d5dd2"`
Dec 06 01:20:55.309 INFO running `"docker" "rm" "-f" "7341e54dcc6d1e67120dd5f93fd4b79d93a3ebf53d1c654ab92e677b798d5dd2"`
Dec 06 01:20:55.499 INFO [stdout] 7341e54dcc6d1e67120dd5f93fd4b79d93a3ebf53d1c654ab92e677b798d5dd2
Dec 06 01:20:55.501 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-2/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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.965 INFO [stdout] 00dd4e86a0d816866ffb800a4eeb616ee13e26f5fefa018f10c1b26bde531245
Dec 06 01:20:55.967 INFO running `"docker" "start" "-a" "00dd4e86a0d816866ffb800a4eeb616ee13e26f5fefa018f10c1b26bde531245"`
Dec 06 01:20:56.956 INFO [stderr] usermod: no changes
Dec 06 01:20:57.119 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.14s
Dec 06 01:20:57.134 INFO [stderr]      Running /target/debug/deps/alt-0bf6a6186c227afe
Dec 06 01:20:57.138 INFO [stdout] 
Dec 06 01:20:57.138 INFO [stdout] running 23 tests
Dec 06 01:20:57.148 INFO [stdout] test config::tests::home_dir_should_read_alt_home_env ... ok
Dec 06 01:20:57.149 INFO [stdout] test config::tests::home_dir_should_default ... ok
Dec 06 01:20:57.149 INFO [stdout] test def_file::tests::find_bin_returns_none_on_empty_defs ... ok
Dec 06 01:20:57.149 INFO [stdout] test def_file::tests::find_bin_returns_none_when_command_not_defined ... ok
Dec 06 01:20:57.149 INFO [stdout] test def_file::tests::find_bin_returns_none_when_version_not_defined ... ok
Dec 06 01:20:57.149 INFO [stdout] test def_file::tests::find_bin_returns_path_when_command_and_version_defined ... ok
Dec 06 01:20:57.149 INFO [stdout] test scan::homebrew::tests::test_parse_version_path_php ... ok
Dec 06 01:20:57.149 INFO [stdout] test scan::homebrew::tests::test_parse_version_path_node ... ok
Dec 06 01:20:57.153 INFO [stdout] test scan::path_suffix::tests::command_only_should_not_parse ... ok
Dec 06 01:20:57.153 INFO [stdout] test scan::path_suffix::tests::command_with_simple_number_suffix_should_parse ... ok
Dec 06 01:20:57.153 INFO [stdout] test scan::path_suffix::tests::command_with_version_suffix_and_dash_should_parse ... ok
Dec 06 01:20:57.153 INFO [stdout] test shim::tests::test_absolute_alt_is_not_shim ... ok
Dec 06 01:20:57.153 INFO [stdout] test scan::path_suffix::tests::command_with_version_suffix_should_parse ... ok
Dec 06 01:20:57.153 INFO [stdout] test scan::path_suffix::tests::command_with_text_suffix_should_not_parse ... ok
Dec 06 01:20:57.154 INFO [stdout] test shim::tests::test_absolute_non_alt_is_not_shim ... ok
Dec 06 01:20:57.154 INFO [stdout] test shim::tests::test_get_command_absolute ... ok
Dec 06 01:20:57.154 INFO [stdout] test shim::tests::test_get_command_command ... ok
Dec 06 01:20:57.154 INFO [stdout] test shim::tests::test_alt_command_is_not_shim ... ok
Dec 06 01:20:57.154 INFO [stdout] test scan::path_suffix::tests::command_trailing_period_in_suffix_should_not_parse ... ok
Dec 06 01:20:57.157 INFO [stdout] test shim::tests::test_get_command_relative ... ok
Dec 06 01:20:57.157 INFO [stdout] test shim::tests::test_relative_alt_is_not_shim ... ok
Dec 06 01:20:57.157 INFO [stdout] test shim::tests::test_relative_non_alt_is_not_shim ... ok
Dec 06 01:20:57.157 INFO [stdout] test shim::tests::test_non_alt_command_is_shim ... ok
Dec 06 01:20:57.157 INFO [stdout] 
Dec 06 01:20:57.157 INFO [stdout] test result: ok. 23 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 06 01:20:57.157 INFO [stdout] 
Dec 06 01:20:57.159 INFO [stderr]      Running /target/debug/deps/integration_test-f5bf65f95ae81d22
Dec 06 01:20:57.162 INFO [stdout] 
Dec 06 01:20:57.162 INFO [stdout] running 5 tests
Dec 06 01:21:57.172 INFO [stdout] test system_with_no_use ... test system_with_no_use has been running for over 60 seconds
Dec 06 01:21:57.172 INFO [stdout] test def_and_use ... test def_and_use has been running for over 60 seconds
Dec 06 01:21:57.172 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 use_with_subdir_overwrite ... test use_with_subdir_overwrite 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:22:26.393 INFO [stdout] test use_with_subdir ... ok
Dec 06 01:22:26.615 INFO [stdout] test system_with_no_use ... ok
Dec 06 01:22:26.668 INFO [stdout] test def_and_use ... ok
Dec 06 01:22:26.696 INFO [stdout] test use_with_subdir_overwrite ... ok
Dec 06 01:22:26.732 INFO [stderr]      Running /target/debug/deps/test_env-110c79570194fda6
Dec 06 01:22:26.732 INFO [stdout] test reset_with_use_system ... ok
Dec 06 01:22:26.732 INFO [stdout] 
Dec 06 01:22:26.732 INFO [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 06 01:22:26.732 INFO [stdout] 
Dec 06 01:22:26.744 INFO [stdout] 
Dec 06 01:22:26.744 INFO [stdout] running 0 tests
Dec 06 01:22:26.744 INFO [stdout] 
Dec 06 01:22:26.744 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 06 01:22:26.744 INFO [stdout] 
Dec 06 01:22:26.748 INFO [stderr] su: No module specific data is present
Dec 06 01:22:27.927 INFO running `"docker" "inspect" "00dd4e86a0d816866ffb800a4eeb616ee13e26f5fefa018f10c1b26bde531245"`
Dec 06 01:22:28.206 INFO running `"docker" "rm" "-f" "00dd4e86a0d816866ffb800a4eeb616ee13e26f5fefa018f10c1b26bde531245"`
Dec 06 01:22:28.636 INFO [stdout] 00dd4e86a0d816866ffb800a4eeb616ee13e26f5fefa018f10c1b26bde531245