[INFO] cloning repository https://github.com/andrewgonzales/otp
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/andrewgonzales/otp" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fandrewgonzales%2Fotp", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fandrewgonzales%2Fotp'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 35921ac806b1a508acd1f82c710274cf348b0ade
[INFO] testing andrewgonzales/otp against try#8de4c7234dd9b97c9d76b58671343fdbbc9a433e+target=x86_64-unknown-linux-musl for musl_upgrade_1_2_5_with_libc_patch_0
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fandrewgonzales%2Fotp" "/workspace/builds/worker-1-tc1/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-1-tc1/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/andrewgonzales/otp
[INFO] finished tweaking git repo https://github.com/andrewgonzales/otp
[INFO] tweaked toml for git repo https://github.com/andrewgonzales/otp written to /workspace/builds/worker-1-tc1/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/andrewgonzales/otp on toolchain 8de4c7234dd9b97c9d76b58671343fdbbc9a433e
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+8de4c7234dd9b97c9d76b58671343fdbbc9a433e" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/andrewgonzales/otp already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+8de4c7234dd9b97c9d76b58671343fdbbc9a433e" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded quote v1.0.17
[INFO] [stderr]   Downloaded rust-argon2 v1.0.0
[INFO] [stderr]   Downloaded blake2b_simd v1.0.0
[INFO] [stderr]   Downloaded zeroize v1.4.3
[INFO] [stderr]   Downloaded clap_derive v3.1.4
[INFO] [stderr]   Downloaded clap v3.1.6
[INFO] [stderr]   Downloaded syn v1.0.89
[INFO] [stderr]   Downloaded libc v0.2.121
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:90999bfc7ae267e83380e433d8e61a7c072ca6729e92edbae886d3423b3a6f4c" "/opt/rustwide/cargo-home/bin/cargo" "+8de4c7234dd9b97c9d76b58671343fdbbc9a433e" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 06573088de0b524f7e3fe6b059a22e4c1ccb288282b05aea4b6435978ee0e60a
[INFO] running `Command { std: "docker" "start" "-a" "06573088de0b524f7e3fe6b059a22e4c1ccb288282b05aea4b6435978ee0e60a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "06573088de0b524f7e3fe6b059a22e4c1ccb288282b05aea4b6435978ee0e60a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "06573088de0b524f7e3fe6b059a22e4c1ccb288282b05aea4b6435978ee0e60a", kill_on_drop: false }`
[INFO] [stdout] 06573088de0b524f7e3fe6b059a22e4c1ccb288282b05aea4b6435978ee0e60a
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:90999bfc7ae267e83380e433d8e61a7c072ca6729e92edbae886d3423b3a6f4c" "/opt/rustwide/cargo-home/bin/cargo" "+8de4c7234dd9b97c9d76b58671343fdbbc9a433e" "build" "--frozen" "--message-format=json" "--target" "x86_64-unknown-linux-musl", kill_on_drop: false }`
[INFO] [stdout] d6734c6dae5cdfffc04e3e3060d407b4227de70d4faead4c8a2630b25afc32b3
[INFO] running `Command { std: "docker" "start" "-a" "d6734c6dae5cdfffc04e3e3060d407b4227de70d4faead4c8a2630b25afc32b3", kill_on_drop: false }`
[INFO] [stderr]    Compiling version_check v0.9.4
[INFO] [stderr]    Compiling typenum v1.15.0
[INFO] [stderr]    Compiling proc-macro2 v1.0.36
[INFO] [stderr]    Compiling libc v0.2.121
[INFO] [stderr]    Compiling syn v1.0.89
[INFO] [stderr]    Compiling subtle v2.4.1
[INFO] [stderr]    Compiling cpufeatures v0.2.2
[INFO] [stderr]    Compiling memchr v2.4.1
[INFO] [stderr]    Compiling serde_derive v1.0.136
[INFO] [stderr]    Compiling autocfg v1.1.0
[INFO] [stderr]    Compiling serde v1.0.136
[INFO] [stderr]    Compiling crossbeam-utils v0.8.8
[INFO] [stderr]    Compiling ppv-lite86 v0.2.16
[INFO] [stderr]    Compiling arrayvec v0.7.2
[INFO] [stderr]    Compiling opaque-debug v0.3.0
[INFO] [stderr]    Compiling heck v0.4.0
[INFO] [stderr]    Compiling arrayref v0.3.6
[INFO] [stderr]    Compiling zeroize v1.4.3
[INFO] [stderr]    Compiling hashbrown v0.11.2
[INFO] [stderr]    Compiling constant_time_eq v0.1.5
[INFO] [stderr]    Compiling blake2b_simd v1.0.0
[INFO] [stderr]    Compiling termcolor v1.1.3
[INFO] [stderr]    Compiling base64 v0.13.0
[INFO] [stderr]    Compiling textwrap v0.15.0
[INFO] [stderr]    Compiling strsim v0.10.0
[INFO] [stderr]    Compiling data-encoding v2.3.2
[INFO] [stderr]    Compiling generic-array v0.14.5
[INFO] [stderr]    Compiling proc-macro-error-attr v1.0.4
[INFO] [stderr]    Compiling proc-macro-error v1.0.4
[INFO] [stderr]    Compiling os_str_bytes v6.0.0
[INFO] [stderr]    Compiling indexmap v1.8.0
[INFO] [stderr]    Compiling quote v1.0.17
[INFO] [stderr]    Compiling rust-argon2 v1.0.0
[INFO] [stderr]    Compiling getrandom v0.2.5
[INFO] [stderr]    Compiling dirs-sys v0.3.7
[INFO] [stderr]    Compiling atty v0.2.14
[INFO] [stderr]    Compiling dirs v4.0.0
[INFO] [stderr]    Compiling rand_core v0.6.3
[INFO] [stderr]    Compiling rand_chacha v0.3.1
[INFO] [stderr]    Compiling rand v0.8.5
[INFO] [stderr]    Compiling block-buffer v0.10.2
[INFO] [stderr]    Compiling crypto-common v0.1.3
[INFO] [stderr]    Compiling cipher v0.3.0
[INFO] [stderr]    Compiling universal-hash v0.4.1
[INFO] [stderr]    Compiling aead v0.4.3
[INFO] [stderr]    Compiling poly1305 v0.7.2
[INFO] [stderr]    Compiling digest v0.10.3
[INFO] [stderr]    Compiling chacha20 v0.8.1
[INFO] [stderr]    Compiling hmac v0.12.1
[INFO] [stderr]    Compiling sha2 v0.10.2
[INFO] [stderr]    Compiling sha1 v0.10.1
[INFO] [stderr]    Compiling chacha20poly1305 v0.9.0
[INFO] [stderr]    Compiling clap_derive v3.1.4
[INFO] [stderr]    Compiling clap v3.1.6
[INFO] [stderr]    Compiling toml v0.5.8
[INFO] [stderr]    Compiling otp v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 18.71s
[INFO] running `Command { std: "docker" "inspect" "d6734c6dae5cdfffc04e3e3060d407b4227de70d4faead4c8a2630b25afc32b3", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "d6734c6dae5cdfffc04e3e3060d407b4227de70d4faead4c8a2630b25afc32b3", kill_on_drop: false }`
[INFO] [stdout] d6734c6dae5cdfffc04e3e3060d407b4227de70d4faead4c8a2630b25afc32b3
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:90999bfc7ae267e83380e433d8e61a7c072ca6729e92edbae886d3423b3a6f4c" "/opt/rustwide/cargo-home/bin/cargo" "+8de4c7234dd9b97c9d76b58671343fdbbc9a433e" "test" "--frozen" "--no-run" "--message-format=json" "--target" "x86_64-unknown-linux-musl", kill_on_drop: false }`
[INFO] [stdout] 325b39812de9de9cd28ef848a50191dd69ce5ce5b9aed6097c26555a8aaa65bd
[INFO] running `Command { std: "docker" "start" "-a" "325b39812de9de9cd28ef848a50191dd69ce5ce5b9aed6097c26555a8aaa65bd", kill_on_drop: false }`
[INFO] [stderr]    Compiling otp v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 2.67s
[INFO] running `Command { std: "docker" "inspect" "325b39812de9de9cd28ef848a50191dd69ce5ce5b9aed6097c26555a8aaa65bd", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "325b39812de9de9cd28ef848a50191dd69ce5ce5b9aed6097c26555a8aaa65bd", kill_on_drop: false }`
[INFO] [stdout] 325b39812de9de9cd28ef848a50191dd69ce5ce5b9aed6097c26555a8aaa65bd
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:90999bfc7ae267e83380e433d8e61a7c072ca6729e92edbae886d3423b3a6f4c" "/opt/rustwide/cargo-home/bin/cargo" "+8de4c7234dd9b97c9d76b58671343fdbbc9a433e" "test" "--frozen" "--target" "x86_64-unknown-linux-musl", kill_on_drop: false }`
[INFO] [stdout] 5b44cf9d9af9c89570bb5b1e378666c01855c3718defaf71528657046c2f38c8
[INFO] running `Command { std: "docker" "start" "-a" "5b44cf9d9af9c89570bb5b1e378666c01855c3718defaf71528657046c2f38c8", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.09s
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/x86_64-unknown-linux-musl/debug/deps/otp-c0e288e3cab09a4d)
[INFO] [stdout] 
[INFO] [stdout] running 62 tests
[INFO] [stdout] test account::tests::is_initialized_false ... ok
[INFO] [stdout] test cmd::add::tests::requires_key ... ok
[INFO] [stdout] test cmd::add::tests::requires_account_name ... ok
[INFO] [stdout] test cmd::add::tests::validates_key_encoding ... ok
[INFO] [stdout] test cmd::delete::tests::requires_account_name ... ok
[INFO] [stdout] test cmd::generate::tests::generates_a_20_byte_secret_for_hotp ... ok
[INFO] [stdout] test cmd::generate::tests::generates_a_32_byte_secret_for_totp ... ok
[INFO] [stdout] test account::tests::is_initialized_true ... ok
[INFO] [stdout] test cmd::delete::tests::records_errors_on_save_failure ... ok
[INFO] [stdout] test cmd::add::tests::adds_an_account_with_hotp ... ok
[INFO] [stdout] test cmd::delete::tests::does_not_delete_an_account_that_does_not_exist ... ok
[INFO] [stdout] test cmd::delete::tests::deletes_an_account ... ok
[INFO] [stdout] test cmd::add::tests::errors_on_save_failure ... ok
[INFO] [stdout] test cmd::get::tests::requires_account_name ... ok
[INFO] [stdout] test cmd::init::tests::requires_pin ... ok
[INFO] [stdout] test cmd::add::tests::errors_if_account_exists ... ok
[INFO] [stdout] test account::tests::adds_an_account ... ok
[INFO] [stdout] test account::tests::sets_secrets ... ok
[INFO] [stdout] test account::tests::validates_incorrect_pin ... ok
[INFO] [stdout] test cmd::list::tests::lists_account_names ... ok
[INFO] [stdout] test cmd::get::tests::gets_totp_for_account ... ok
[INFO] [stdout] test cmd::validate::tests::requires_account_name ... ok
[INFO] [stdout] test cmd::validate::tests::requires_token ... ok
[INFO] [stdout] test cmd::init::tests::initializes_a_new_account_store ... ok
[INFO] [stdout] test cmd::add::tests::adds_an_account ... ok
[INFO] [stdout] test account::tests::sets_counter_value ... ok
[INFO] [stdout] test cmd::get::tests::does_not_get_when_account_does_not_exist ... ok
[INFO] [stdout] test crypto::tests::generates_a_24_byte_nonce ... ok
[INFO] [stdout] test account::tests::deletes_an_account ... ok
[INFO] [stdout] test hotp::tests::gets_an_otp_value ... ok
[INFO] [stdout] test hotp::tests::validate_otp_looks_ahead ... ok
[INFO] [stdout] test hotp::tests::validate_otp_returns_error_for_invalid_code ... ok
[INFO] [stdout] test hotp::tests::validates_an_otp_value ... ok
[INFO] [stdout] test main_tests::checks_for_account_store_initialized ... ok
[INFO] [stdout] test cmd::get::tests::gets_hotp_for_account ... ok
[INFO] [stdout] test totp::tests::gets_a_totp_value ... ok
[INFO] [stdout] test totp::tests::gets_moving_factor_from_system_time ... ok
[INFO] [stdout] test totp::tests::validate_totp_fails_when_wrong ... ok
[INFO] [stdout] test totp::tests::validate_totp_looks_ahead_and_behind ... ok
[INFO] [stdout] test totp::tests::validates_a_totp_value ... ok
[INFO] [stdout] test utils::tests::generates_a_20_byte_base32_secret ... ok
[INFO] [stdout] test utils::tests::is_base32_key_returns_error_if_not_base32 ... ok
[INFO] [stdout] test utils::tests::is_base32_key_returns_ok_if_base32 ... ok
[INFO] [stdout] test utils::tests::validate_pin_checks_pin_length ... ok
[INFO] [stdout] test utils::tests::validate_pin_returns_error_if_account_store_is_not_initialized ... ok
[INFO] [stdout] test account::tests::lists_accounts ... ok
[INFO] [stdout] test cmd::validate::tests::does_not_accept_invalid_totp ... ok
[INFO] [stdout] test cmd::validate::tests::errors_when_token_cannot_be_parsed ... ok
[INFO] [stdout] test cmd::validate::tests::errors_when_account_not_found ... ok
[INFO] [stdout] test cmd::get::tests::records_errors_on_save_failure ... ok
[INFO] [stdout] test cmd::get::tests::increments_hotp_counter ... ok
[INFO] [stdout] test cmd::validate::tests::validates_totp ... ok
[INFO] [stdout] test cmd::validate::tests::validates_hotp ... ok
[INFO] [stdout] test cmd::validate::tests::does_not_accept_invalid_hotp ... ok
[INFO] [stdout] test account::tests::validates_correct_pin ... ok
[INFO] [stdout] test utils::tests::validate_pin_returns_ok_if_pin_is_valid ... ok
[INFO] [stdout] test crypto::tests::hashes_and_verifies_a_password ... ok
[INFO] [stdout] test cmd::init::tests::returns_error_on_save_failure ... ok
[INFO] [stdout] test crypto::tests::encrypts_and_decrypts_a_string ... ok
[INFO] [stdout] test crypto::tests::fails_to_verify_a_password_with_wrong_hash ... ok
[INFO] [stdout] test utils::tests::validate_pin_returns_error_if_pin_is_invalid ... ok
[INFO] [stdout] test main_tests::verifies_pin ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 62 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 3.17s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "5b44cf9d9af9c89570bb5b1e378666c01855c3718defaf71528657046c2f38c8", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "5b44cf9d9af9c89570bb5b1e378666c01855c3718defaf71528657046c2f38c8", kill_on_drop: false }`
[INFO] [stdout] 5b44cf9d9af9c89570bb5b1e378666c01855c3718defaf71528657046c2f38c8