[INFO] crate butlerd 0.1.2 is already in cache
[INFO] testing butlerd-0.1.2 against beta-2019-09-28 for beta-1.39-1
[INFO] extracting crate butlerd 0.1.2 into work/builds/worker-4/source
[INFO] validating manifest of crates.io crate butlerd 0.1.2 on toolchain beta-2019-09-28
[INFO] running `"/big/crater/work/cargo-home/bin/cargo" "+beta-2019-09-28" "read-manifest" "--manifest-path" "Cargo.toml"`
[INFO] started tweaking crates.io crate butlerd 0.1.2
[INFO] finished tweaking crates.io crate butlerd 0.1.2
[INFO] tweaked toml for crates.io crate butlerd 0.1.2 written to work/builds/worker-4/source/Cargo.toml
[INFO] running `"/big/crater/work/cargo-home/bin/cargo" "+beta-2019-09-28" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update"`
[INFO] running `"/big/crater/work/cargo-home/bin/cargo" "+beta-2019-09-28" "fetch" "--locked" "--manifest-path" "Cargo.toml"`
[INFO] running `"docker" "create" "-v" "/big/crater/work/builds/worker-4/target:/opt/rustwide/target:rw,Z" "-v" "/big/crater/work/builds/worker-4/source:/opt/rustwide/workdir:ro,Z" "-v" "/big/crater/work/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/big/crater/work/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "MAP_USER_ID=1000" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--network" "none" "rustops/crates-build-env" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2019-09-28" "build" "--frozen"`
[INFO] [stdout] e7f3c6c0e86330a51873bd07adf1e80a2586d10604192608ae21c9e1fe9188ac
[INFO] running `"docker" "start" "-a" "e7f3c6c0e86330a51873bd07adf1e80a2586d10604192608ae21c9e1fe9188ac"`
[INFO] [stderr]    Compiling butlerd v0.1.2 (/opt/rustwide/workdir)
[INFO] [stderr] warning: variant `i386` should have an upper camel case name
[INFO] [stderr]    --> src/Responses.rs:100:5
[INFO] [stderr]     |
[INFO] [stderr] 100 |     i386,
[INFO] [stderr]     |     ^^^^ help: convert the identifier to upper camel case: `I386`
[INFO] [stderr]     |
[INFO] [stderr]     = note: `#[warn(non_camel_case_types)]` on by default
[INFO] [stderr] 
[INFO] [stderr] warning: unnecessary trailing semicolon
[INFO] [stderr]    --> src/lib.rs:158:57
[INFO] [stderr]     |
[INFO] [stderr] 158 |             .expect("Couldn't shut down butler daemon");;
[INFO] [stderr]     |                                                         ^ help: remove this semicolon
[INFO] [stderr]     |
[INFO] [stderr]     = note: `#[warn(redundant_semicolon)]` on by default
[INFO] [stderr] 
[INFO] [stderr] warning: use of deprecated item 'std::env::home_dir': This function's behavior is unexpected and probably not what you want. Consider using the home_dir function from https://crates.io/crates/dirs instead.
[INFO] [stderr]    --> src/lib.rs:678:25
[INFO] [stderr]     |
[INFO] [stderr] 678 |     return String::from(env::home_dir().unwrap().to_str().unwrap());
[INFO] [stderr]     |                         ^^^^^^^^^^^^^
[INFO] [stderr]     |
[INFO] [stderr]     = note: `#[warn(deprecated)]` on by default
[INFO] [stderr] 
[INFO] [stderr] warning: use of deprecated item 'std::thread::sleep_ms': replaced by `std::thread::sleep`
[INFO] [stderr]    --> src/lib.rs:100:17
[INFO] [stderr]     |
[INFO] [stderr] 100 |                 ::std::thread::sleep_ms(250);
[INFO] [stderr]     |                 ^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] 
[INFO] [stderr] warning: use of deprecated item 'std::thread::sleep_ms': replaced by `std::thread::sleep`
[INFO] [stderr]    --> src/lib.rs:546:13
[INFO] [stderr]     |
[INFO] [stderr] 546 |             ::std::thread::sleep_ms(1000);
[INFO] [stderr]     |             ^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] 
[INFO] [stderr] warning: value assigned to `file` is never read
[INFO] [stderr]   --> src/lib.rs:64:13
[INFO] [stderr]    |
[INFO] [stderr] 64 |             file = fs::File::create(log_path).unwrap();
[INFO] [stderr]    |             ^^^^
[INFO] [stderr]    |
[INFO] [stderr]    = note: `#[warn(unused_assignments)]` on by default
[INFO] [stderr]    = help: maybe it is overwritten before being read?
[INFO] [stderr] 
[INFO] [stderr] warning: value assigned to `b` is never read
[INFO] [stderr]    --> src/lib.rs:652:17
[INFO] [stderr]     |
[INFO] [stderr] 652 |         let mut b = String::new();
[INFO] [stderr]     |                 ^
[INFO] [stderr]     |
[INFO] [stderr]     = help: maybe it is overwritten before being read?
[INFO] [stderr] 
[INFO] [stderr] warning: value assigned to `b` is never read
[INFO] [stderr]    --> src/lib.rs:666:17
[INFO] [stderr]     |
[INFO] [stderr] 666 |         let mut b = String::new();
[INFO] [stderr]     |                 ^
[INFO] [stderr]     |
[INFO] [stderr]     = help: maybe it is overwritten before being read?
[INFO] [stderr] 
[INFO] [stderr] warning: unused `hyper::client::ResponseFuture` that must be used
[INFO] [stderr]    --> src/lib.rs:543:9
[INFO] [stderr]     |
[INFO] [stderr] 543 |         self.hclient.request(request);
[INFO] [stderr]     |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr]     |
[INFO] [stderr]     = note: `#[warn(unused_must_use)]` on by default
[INFO] [stderr]     = note: futures do nothing unless polled
[INFO] [stderr] 
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 13.28s
[INFO] running `"docker" "inspect" "e7f3c6c0e86330a51873bd07adf1e80a2586d10604192608ae21c9e1fe9188ac"`
[INFO] running `"docker" "rm" "-f" "e7f3c6c0e86330a51873bd07adf1e80a2586d10604192608ae21c9e1fe9188ac"`
[INFO] [stdout] e7f3c6c0e86330a51873bd07adf1e80a2586d10604192608ae21c9e1fe9188ac
[INFO] running `"docker" "create" "-v" "/big/crater/work/builds/worker-4/target:/opt/rustwide/target:rw,Z" "-v" "/big/crater/work/builds/worker-4/source:/opt/rustwide/workdir:ro,Z" "-v" "/big/crater/work/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/big/crater/work/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "MAP_USER_ID=1000" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--network" "none" "rustops/crates-build-env" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2019-09-28" "test" "--frozen" "--no-run"`
[INFO] [stdout] e8e20748148593fbbaa7b85ed514e39b215376f74a8cb8958498eb8f41bb6670
[INFO] running `"docker" "start" "-a" "e8e20748148593fbbaa7b85ed514e39b215376f74a8cb8958498eb8f41bb6670"`
[INFO] [stderr]    Compiling butlerd v0.1.2 (/opt/rustwide/workdir)
[INFO] [stderr] warning: constant `b` should have an upper case name
[INFO] [stderr]   --> tests/lib.rs:12:22
[INFO] [stderr]    |
[INFO] [stderr] 12 | thread_local!(static b : Butler = Butler::new().unwrap());
[INFO] [stderr]    |                      ^ help: convert the identifier to upper case: `B`
[INFO] [stderr]    |
[INFO] [stderr]    = note: `#[warn(non_upper_case_globals)]` on by default
[INFO] [stderr] 
[INFO] [stderr] warning: unused `std::result::Result` that must be used
[INFO] [stderr]   --> tests/lib.rs:52:13
[INFO] [stderr]    |
[INFO] [stderr] 52 |             but.login_api_key(&key.unwrap().into_string().unwrap());
[INFO] [stderr]    |             ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr]    = note: `#[warn(unused_must_use)]` on by default
[INFO] [stderr]    = note: this `Result` may be an `Err` variant, which should be handled
[INFO] [stderr] 
[INFO] [stderr] warning: unused `std::result::Result` that must be used
[INFO] [stderr]    --> tests/lib.rs:151:9
[INFO] [stderr]     |
[INFO] [stderr] 151 |         but.check_update(vec!["e97cd944-386d-4c6c-b1e9-76a3175f4ca9".to_string()]);
[INFO] [stderr]     |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr]     |
[INFO] [stderr]     = note: this `Result` may be an `Err` variant, which should be handled
[INFO] [stderr] 
[INFO] [stderr] warning: variant `i386` should have an upper camel case name
[INFO] [stderr]    --> src/Responses.rs:100:5
[INFO] [stderr]     |
[INFO] [stderr] 100 |     i386,
[INFO] [stderr]     |     ^^^^ help: convert the identifier to upper camel case: `I386`
[INFO] [stderr]     |
[INFO] [stderr]     = note: `#[warn(non_camel_case_types)]` on by default
[INFO] [stderr] 
[INFO] [stderr] warning: unnecessary trailing semicolon
[INFO] [stderr]    --> src/lib.rs:158:57
[INFO] [stderr]     |
[INFO] [stderr] 158 |             .expect("Couldn't shut down butler daemon");;
[INFO] [stderr]     |                                                         ^ help: remove this semicolon
[INFO] [stderr]     |
[INFO] [stderr]     = note: `#[warn(redundant_semicolon)]` on by default
[INFO] [stderr] 
[INFO] [stderr] warning: use of deprecated item 'std::env::home_dir': This function's behavior is unexpected and probably not what you want. Consider using the home_dir function from https://crates.io/crates/dirs instead.
[INFO] [stderr]    --> src/lib.rs:678:25
[INFO] [stderr]     |
[INFO] [stderr] 678 |     return String::from(env::home_dir().unwrap().to_str().unwrap());
[INFO] [stderr]     |                         ^^^^^^^^^^^^^
[INFO] [stderr]     |
[INFO] [stderr]     = note: `#[warn(deprecated)]` on by default
[INFO] [stderr] 
[INFO] [stderr] warning: use of deprecated item 'std::thread::sleep_ms': replaced by `std::thread::sleep`
[INFO] [stderr]    --> src/lib.rs:100:17
[INFO] [stderr]     |
[INFO] [stderr] 100 |                 ::std::thread::sleep_ms(250);
[INFO] [stderr]     |                 ^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] 
[INFO] [stderr] warning: use of deprecated item 'std::thread::sleep_ms': replaced by `std::thread::sleep`
[INFO] [stderr]    --> src/lib.rs:546:13
[INFO] [stderr]     |
[INFO] [stderr] 546 |             ::std::thread::sleep_ms(1000);
[INFO] [stderr]     |             ^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] 
[INFO] [stderr] warning: value assigned to `file` is never read
[INFO] [stderr]   --> src/lib.rs:64:13
[INFO] [stderr]    |
[INFO] [stderr] 64 |             file = fs::File::create(log_path).unwrap();
[INFO] [stderr]    |             ^^^^
[INFO] [stderr]    |
[INFO] [stderr]    = note: `#[warn(unused_assignments)]` on by default
[INFO] [stderr]    = help: maybe it is overwritten before being read?
[INFO] [stderr] 
[INFO] [stderr] warning: value assigned to `b` is never read
[INFO] [stderr]    --> src/lib.rs:652:17
[INFO] [stderr]     |
[INFO] [stderr] 652 |         let mut b = String::new();
[INFO] [stderr]     |                 ^
[INFO] [stderr]     |
[INFO] [stderr]     = help: maybe it is overwritten before being read?
[INFO] [stderr] 
[INFO] [stderr] warning: value assigned to `b` is never read
[INFO] [stderr]    --> src/lib.rs:666:17
[INFO] [stderr]     |
[INFO] [stderr] 666 |         let mut b = String::new();
[INFO] [stderr]     |                 ^
[INFO] [stderr]     |
[INFO] [stderr]     = help: maybe it is overwritten before being read?
[INFO] [stderr] 
[INFO] [stderr] warning: unused `hyper::client::ResponseFuture` that must be used
[INFO] [stderr]    --> src/lib.rs:543:9
[INFO] [stderr]     |
[INFO] [stderr] 543 |         self.hclient.request(request);
[INFO] [stderr]     |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr]     |
[INFO] [stderr]     = note: `#[warn(unused_must_use)]` on by default
[INFO] [stderr]     = note: futures do nothing unless polled
[INFO] [stderr] 
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 11.28s
[INFO] running `"docker" "inspect" "e8e20748148593fbbaa7b85ed514e39b215376f74a8cb8958498eb8f41bb6670"`
[INFO] running `"docker" "rm" "-f" "e8e20748148593fbbaa7b85ed514e39b215376f74a8cb8958498eb8f41bb6670"`
[INFO] [stdout] e8e20748148593fbbaa7b85ed514e39b215376f74a8cb8958498eb8f41bb6670
[INFO] running `"docker" "create" "-v" "/big/crater/work/builds/worker-4/target:/opt/rustwide/target:rw,Z" "-v" "/big/crater/work/builds/worker-4/source:/opt/rustwide/workdir:ro,Z" "-v" "/big/crater/work/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/big/crater/work/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "MAP_USER_ID=1000" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--network" "none" "rustops/crates-build-env" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2019-09-28" "test" "--frozen"`
[INFO] [stdout] da8fd356de13b893d938cb5cb1c775a74ba6efe3a79e68be245a875cd65d725b
[INFO] running `"docker" "start" "-a" "da8fd356de13b893d938cb5cb1c775a74ba6efe3a79e68be245a875cd65d725b"`
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.08s
[INFO] [stderr]      Running /opt/rustwide/target/debug/deps/butlerd-ff9e874f61202164
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
[INFO] [stdout] 
[INFO] [stderr]      Running /opt/rustwide/target/debug/deps/lib-4c779f92c53e0f7a
[INFO] [stdout] 
[INFO] [stdout] running 20 tests
[INFO] [stdout] test install ... ignored
[INFO] [stdout] test install_location_add ... ignored
[INFO] [stdout] test launch_game ... ignored
[INFO] [stdout] test login_api_key ... ignored
[INFO] [stdout] test test_login_fetch_keys ... ignored
[INFO] [stderr] sh: 1: sh: 1: sh: 1: sh: 1: sh: 1: sh: 1: sh: 1: sh: 1: sh: 1: sh: 1: butler: not foundbutler: not foundbutler: not foundbutler: not foundbutler: not foundbutler: not foundbutler: not found
[INFO] [stderr] 
[INFO] [stderr] butler: not found
[INFO] [stderr] butler: not found
[INFO] [stderr] 
[INFO] [stderr] 
[INFO] [stderr] 
[INFO] [stderr] 
[INFO] [stderr] 
[INFO] [stderr] butler: not found
[INFO] [stderr] sh: 1: butler: not found
[INFO] [stderr] sh: 1: butler: not found
[INFO] [stderr] sh: 1: butler: not found
[INFO] [stderr] sh: 1: butler: not found
[INFO] [stderr] sh: 1: butler: not found
[INFO] [stdout] test check_sale_none ... test check_sale_none has been running for over 60 seconds
[INFO] [stdout] test check_updates ... test check_updates has been running for over 60 seconds
[INFO] [stdout] test commons ... test commons has been running for over 60 seconds
[INFO] [stdout] test expireall ... test expireall has been running for over 60 seconds
[INFO] [stdout] test fetch_cave ... test fetch_cave has been running for over 60 seconds
[INFO] [stdout] test fetch_game ... test fetch_game has been running for over 60 seconds
[INFO] [stdout] test fetch_uploads ... test fetch_uploads has been running for over 60 seconds
[INFO] [stdout] test fetch_version ... test fetch_version has been running for over 60 seconds
[INFO] [stdout] test fetchall ... test fetchall has been running for over 60 seconds
[INFO] [stdout] test pin ... test pin has been running for over 60 seconds
[INFO] [stdout] test get_install_locations ... test get_install_locations has been running for over 60 seconds
[INFO] [stdout] test profile_list ... test profile_list has been running for over 60 seconds
[INFO] [stdout] test set_offline ... test set_offline has been running for over 60 seconds
[INFO] [stdout] test set_throttle ... test set_throttle has been running for over 60 seconds
[INFO] [stdout] test snooze_cave ... test snooze_cave has been running for over 60 seconds
[ERROR] error running command: no output for 300 seconds
[INFO] running `"docker" "inspect" "da8fd356de13b893d938cb5cb1c775a74ba6efe3a79e68be245a875cd65d725b"`
[INFO] running `"docker" "rm" "-f" "da8fd356de13b893d938cb5cb1c775a74ba6efe3a79e68be245a875cd65d725b"`
[INFO] [stdout] da8fd356de13b893d938cb5cb1c775a74ba6efe3a79e68be245a875cd65d725b