Dec 13 21:04:33.194 INFO testing dotboris/alt against master#bd47d6825bf4090517549d33cfef10d3300b4a75 for pr-56550
Dec 13 21:04:33.194 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-4/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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.371 INFO [stdout] 2a3917c65c3e7bc473f6dbf48d7786047913238775dcb3329790320c69219afa
Dec 13 21:04:33.376 INFO running `"docker" "start" "-a" "2a3917c65c3e7bc473f6dbf48d7786047913238775dcb3329790320c69219afa"`
Dec 13 21:04:33.787 INFO [stderr] usermod: no changes
Dec 13 21:04:33.965 INFO [stderr]    Compiling syn v0.15.15
Dec 13 21:04:33.966 INFO [stderr]    Compiling dialoguer v0.1.0
Dec 13 21:04:42.267 INFO [stderr]    Compiling serde_derive v1.0.80
Dec 13 21:04:50.470 INFO [stderr]    Compiling serde v1.0.80
Dec 13 21:04:58.941 INFO [stderr]    Compiling toml v0.4.8
Dec 13 21:05:05.363 INFO [stderr]    Compiling alt v1.0.2 (/source)
Dec 13 21:05:13.267 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 39.44s
Dec 13 21:05:13.267 INFO [stderr] su: No module specific data is present
Dec 13 21:05:14.383 INFO running `"docker" "inspect" "2a3917c65c3e7bc473f6dbf48d7786047913238775dcb3329790320c69219afa"`
Dec 13 21:05:14.711 INFO running `"docker" "rm" "-f" "2a3917c65c3e7bc473f6dbf48d7786047913238775dcb3329790320c69219afa"`
Dec 13 21:05:15.015 INFO [stdout] 2a3917c65c3e7bc473f6dbf48d7786047913238775dcb3329790320c69219afa
Dec 13 21:05:15.019 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-4/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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:05:15.413 INFO [stdout] f250c34a07217bf6e4e8a4280b987535f06fc5ee4f7dc4a0e94ed65c8deee1c8
Dec 13 21:05:15.420 INFO running `"docker" "start" "-a" "f250c34a07217bf6e4e8a4280b987535f06fc5ee4f7dc4a0e94ed65c8deee1c8"`
Dec 13 21:05:16.819 INFO [stderr] usermod: no changes
Dec 13 21:05:17.043 INFO [stderr]    Compiling predicates-core v1.0.0
Dec 13 21:05:17.043 INFO [stderr]    Compiling treeline v0.1.0
Dec 13 21:05:17.043 INFO [stderr]    Compiling serde_json v1.0.32
Dec 13 21:05:18.643 INFO [stderr]    Compiling predicates v1.0.0
Dec 13 21:05:18.643 INFO [stderr]    Compiling predicates-tree v1.0.0
Dec 13 21:05:24.591 INFO [stderr]    Compiling escargot v0.3.1
Dec 13 21:05:26.919 INFO [stderr]    Compiling assert_cmd v0.10.1
Dec 13 21:05:29.009 INFO [stderr]    Compiling alt v1.0.2 (/source)
Dec 13 21:05:36.547 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 19.68s
Dec 13 21:05:36.547 INFO [stderr] su: No module specific data is present
Dec 13 21:05:37.554 INFO running `"docker" "inspect" "f250c34a07217bf6e4e8a4280b987535f06fc5ee4f7dc4a0e94ed65c8deee1c8"`
Dec 13 21:05:37.873 INFO running `"docker" "rm" "-f" "f250c34a07217bf6e4e8a4280b987535f06fc5ee4f7dc4a0e94ed65c8deee1c8"`
Dec 13 21:05:38.159 INFO [stdout] f250c34a07217bf6e4e8a4280b987535f06fc5ee4f7dc4a0e94ed65c8deee1c8
Dec 13 21:05:38.161 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-4/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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:38.807 INFO [stdout] 5dbd8d437a693a13f26a7101be6d1ce44a6e167234b6be74c29a27322bdbc35c
Dec 13 21:05:38.815 INFO running `"docker" "start" "-a" "5dbd8d437a693a13f26a7101be6d1ce44a6e167234b6be74c29a27322bdbc35c"`
Dec 13 21:05:40.195 INFO [stderr] usermod: no changes
Dec 13 21:05:40.371 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.14s
Dec 13 21:05:40.383 INFO [stderr]      Running /target/debug/deps/alt-a6508dd6d39f5e59
Dec 13 21:05:40.391 INFO [stdout] 
Dec 13 21:05:40.391 INFO [stdout] running 23 tests
Dec 13 21:05:40.391 INFO [stdout] test config::tests::home_dir_should_default ... ok
Dec 13 21:05:40.391 INFO [stdout] test config::tests::home_dir_should_read_alt_home_env ... ok
Dec 13 21:05:40.391 INFO [stdout] test scan::homebrew::tests::test_parse_version_path_php ... ok
Dec 13 21:05:40.391 INFO [stdout] test scan::path_suffix::tests::command_only_should_not_parse ... ok
Dec 13 21:05:40.391 INFO [stdout] test scan::path_suffix::tests::command_trailing_period_in_suffix_should_not_parse ... ok
Dec 13 21:05:40.391 INFO [stdout] test scan::homebrew::tests::test_parse_version_path_node ... ok
Dec 13 21:05:40.391 INFO [stdout] test def_file::tests::find_bin_returns_none_on_empty_defs ... ok
Dec 13 21:05:40.391 INFO [stdout] test def_file::tests::find_bin_returns_path_when_command_and_version_defined ... ok
Dec 13 21:05:40.391 INFO [stdout] test def_file::tests::find_bin_returns_none_when_version_not_defined ... ok
Dec 13 21:05:40.391 INFO [stdout] test def_file::tests::find_bin_returns_none_when_command_not_defined ... ok
Dec 13 21:05:40.391 INFO [stdout] test scan::path_suffix::tests::command_with_simple_number_suffix_should_parse ... ok
Dec 13 21:05:40.391 INFO [stdout] test scan::path_suffix::tests::command_with_version_suffix_should_parse ... ok
Dec 13 21:05:40.391 INFO [stdout] test shim::tests::test_absolute_alt_is_not_shim ... ok
Dec 13 21:05:40.391 INFO [stdout] test shim::tests::test_absolute_non_alt_is_not_shim ... ok
Dec 13 21:05:40.391 INFO [stdout] test shim::tests::test_alt_command_is_not_shim ... ok
Dec 13 21:05:40.391 INFO [stdout] test shim::tests::test_get_command_absolute ... ok
Dec 13 21:05:40.391 INFO [stdout] test shim::tests::test_get_command_command ... ok
Dec 13 21:05:40.391 INFO [stdout] test shim::tests::test_get_command_relative ... ok
Dec 13 21:05:40.391 INFO [stdout] test shim::tests::test_non_alt_command_is_shim ... ok
Dec 13 21:05:40.391 INFO [stdout] test shim::tests::test_relative_alt_is_not_shim ... ok
Dec 13 21:05:40.391 INFO [stdout] test shim::tests::test_relative_non_alt_is_not_shim ... ok
Dec 13 21:05:40.403 INFO [stderr]      Running /target/debug/deps/integration_test-a0ece54cb6b68438
Dec 13 21:05:40.403 INFO [stdout] test scan::path_suffix::tests::command_with_version_suffix_and_dash_should_parse ... ok
Dec 13 21:05:40.403 INFO [stdout] test scan::path_suffix::tests::command_with_text_suffix_should_not_parse ... ok
Dec 13 21:05:40.403 INFO [stdout] 
Dec 13 21:05:40.403 INFO [stdout] test result: ok. 23 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 21:05:40.403 INFO [stdout] 
Dec 13 21:05:40.407 INFO [stdout] 
Dec 13 21:05:40.407 INFO [stdout] running 5 tests
Dec 13 21:06:40.407 INFO [stdout] test use_with_subdir_overwrite ... test use_with_subdir_overwrite has been running for over 60 seconds
Dec 13 21:06:40.407 INFO [stdout] test system_with_no_use ... test system_with_no_use has been running for over 60 seconds
Dec 13 21:06:40.407 INFO [stdout] test reset_with_use_system ... test reset_with_use_system has been running for over 60 seconds
Dec 13 21:06:40.407 INFO [stdout] test use_with_subdir ... test use_with_subdir has been running for over 60 seconds
Dec 13 21:06:40.407 INFO [stdout] test def_and_use ... test def_and_use has been running for over 60 seconds
Dec 13 21:07:31.876 INFO [stdout] test use_with_subdir ... ok
Dec 13 21:07:32.003 INFO [stdout] test use_with_subdir_overwrite ... ok
Dec 13 21:07:32.011 INFO [stdout] test reset_with_use_system ... ok
Dec 13 21:07:32.070 INFO [stdout] test def_and_use ... ok
Dec 13 21:07:32.106 INFO [stdout] test system_with_no_use ... ok
Dec 13 21:07:32.106 INFO [stdout] 
Dec 13 21:07:32.106 INFO [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 21:07:32.106 INFO [stdout] 
Dec 13 21:07:32.108 INFO [stderr]      Running /target/debug/deps/test_env-9dcf00ec23147beb
Dec 13 21:07:32.119 INFO [stdout] 
Dec 13 21:07:32.119 INFO [stdout] running 0 tests
Dec 13 21:07:32.119 INFO [stdout] 
Dec 13 21:07:32.119 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 21:07:32.119 INFO [stdout] 
Dec 13 21:07:32.122 INFO [stderr] su: No module specific data is present
Dec 13 21:07:33.520 INFO running `"docker" "inspect" "5dbd8d437a693a13f26a7101be6d1ce44a6e167234b6be74c29a27322bdbc35c"`
Dec 13 21:07:33.885 INFO running `"docker" "rm" "-f" "5dbd8d437a693a13f26a7101be6d1ce44a6e167234b6be74c29a27322bdbc35c"`
Dec 13 21:07:34.239 INFO [stdout] 5dbd8d437a693a13f26a7101be6d1ce44a6e167234b6be74c29a27322bdbc35c