[INFO] fetching crate osshkeys 0.5.2...
[INFO] testing osshkeys-0.5.2 against master#a77da2d454e6caa227a85b16410b95f93495e7e0 for pr-91031
[INFO] extracting crate osshkeys 0.5.2 into /workspace/builds/worker-38/source
[INFO] validating manifest of crates.io crate osshkeys 0.5.2 on toolchain a77da2d454e6caa227a85b16410b95f93495e7e0
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+a77da2d454e6caa227a85b16410b95f93495e7e0" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking crates.io crate osshkeys 0.5.2
[INFO] finished tweaking crates.io crate osshkeys 0.5.2
[INFO] tweaked toml for crates.io crate osshkeys 0.5.2 written to /workspace/builds/worker-38/source/Cargo.toml
[INFO] crate crates.io crate osshkeys 0.5.2 already has a lockfile, it will not be regenerated
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+a77da2d454e6caa227a85b16410b95f93495e7e0" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded hex-literal v0.3.3
[INFO] [stderr]   Downloaded zeroize v1.4.2
[INFO] [stderr]   Downloaded zeroize_derive v1.2.0
[INFO] [stderr]   Downloaded ed25519 v1.2.0
[INFO] [stderr]   Downloaded bcrypt-pbkdf v0.7.1
[INFO] [stderr]   Downloaded blowfish v0.8.0
[INFO] [stderr]   Downloaded nom_pem v4.0.0
[INFO] [stderr]   Downloaded pbkdf2 v0.9.0
[INFO] [stderr]   Downloaded cryptovec v0.6.1
[INFO] [stderr]   Downloaded libc v0.2.105
[INFO] [stderr]   Downloaded ctr v0.8.0
[INFO] [stderr]   Downloaded aes v0.7.5
[INFO] [stderr]   Downloaded backtrace v0.3.62
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-38/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-38/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:5736fa189c1c60b01babf4b8b698fe57b6ecc41933a7ff2e0b8d7a221459412b" "/opt/rustwide/cargo-home/bin/cargo" "+a77da2d454e6caa227a85b16410b95f93495e7e0" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 68b0c9fe39c273604a39dff3e527faa9abcc516b70fc34699860ed1d436de113
[INFO] running `Command { std: "docker" "start" "-a" "68b0c9fe39c273604a39dff3e527faa9abcc516b70fc34699860ed1d436de113", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "68b0c9fe39c273604a39dff3e527faa9abcc516b70fc34699860ed1d436de113", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "68b0c9fe39c273604a39dff3e527faa9abcc516b70fc34699860ed1d436de113", kill_on_drop: false }`
[INFO] [stdout] 68b0c9fe39c273604a39dff3e527faa9abcc516b70fc34699860ed1d436de113
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-38/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-38/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" "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:5736fa189c1c60b01babf4b8b698fe57b6ecc41933a7ff2e0b8d7a221459412b" "/opt/rustwide/cargo-home/bin/cargo" "+a77da2d454e6caa227a85b16410b95f93495e7e0" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 25488b59f9f317cf37389df7dbd64cdcf98326f01b5a3d19b65e2f793f4b67c0
[INFO] running `Command { std: "docker" "start" "-a" "25488b59f9f317cf37389df7dbd64cdcf98326f01b5a3d19b65e2f793f4b67c0", kill_on_drop: false }`
[INFO] [stderr]    Compiling version_check v0.9.3
[INFO] [stderr]    Compiling typenum v1.14.0
[INFO] [stderr]    Compiling cfg-if v1.0.0
[INFO] [stderr]    Compiling libc v0.2.105
[INFO] [stderr]    Compiling proc-macro2 v1.0.32
[INFO] [stderr]    Compiling unicode-xid v0.2.2
[INFO] [stderr]    Compiling autocfg v1.0.1
[INFO] [stderr]    Compiling cc v1.0.71
[INFO] [stderr]    Compiling syn v1.0.81
[INFO] [stderr]    Compiling getrandom v0.1.16
[INFO] [stderr]    Compiling memchr v2.4.1
[INFO] [stderr]    Compiling opaque-debug v0.3.0
[INFO] [stderr]    Compiling pkg-config v0.3.22
[INFO] [stderr]    Compiling subtle v2.4.1
[INFO] [stderr]    Compiling version_check v0.1.5
[INFO] [stderr]    Compiling cpufeatures v0.2.1
[INFO] [stderr]    Compiling byteorder v1.4.3
[INFO] [stderr]    Compiling winapi v0.3.9
[INFO] [stderr]    Compiling serde v1.0.130
[INFO] [stderr]    Compiling ppv-lite86 v0.2.15
[INFO] [stderr]    Compiling signature v1.4.0
[INFO] [stderr]    Compiling foreign-types-shared v0.1.1
[INFO] [stderr]    Compiling log v0.4.14
[INFO] [stderr]    Compiling adler v1.0.2
[INFO] [stderr]    Compiling openssl v0.10.36
[INFO] [stderr]    Compiling gimli v0.25.0
[INFO] [stderr]    Compiling rustc-demangle v0.1.21
[INFO] [stderr]    Compiling once_cell v1.8.0
[INFO] [stderr]    Compiling bitflags v1.3.2
[INFO] [stderr]    Compiling base64 v0.13.0
[INFO] [stderr]    Compiling foreign-types v0.3.2
[INFO] [stderr]    Compiling ed25519 v1.2.0
[INFO] [stderr]    Compiling nom v4.2.3
[INFO] [stderr]    Compiling generic-array v0.14.4
[INFO] [stderr]    Compiling miniz_oxide v0.4.4
[INFO] [stderr]    Compiling object v0.27.1
[INFO] [stderr]    Compiling quote v1.0.10
[INFO] [stderr]    Compiling cryptovec v0.6.1
[INFO] [stderr]    Compiling openssl-sys v0.9.67
[INFO] [stderr]    Compiling backtrace v0.3.62
[INFO] [stderr]    Compiling rand_core v0.5.1
[INFO] [stderr]    Compiling rand_chacha v0.2.2
[INFO] [stderr]    Compiling rand v0.7.3
[INFO] [stderr]    Compiling nom_pem v4.0.0
[INFO] [stderr]    Compiling digest v0.9.0
[INFO] [stderr]    Compiling block-buffer v0.9.0
[INFO] [stderr]    Compiling crypto-mac v0.11.1
[INFO] [stderr]    Compiling cipher v0.3.0
[INFO] [stderr]    Compiling sha2 v0.9.8
[INFO] [stderr]    Compiling md-5 v0.9.1
[INFO] [stderr]    Compiling sha-1 v0.9.8
[INFO] [stderr]    Compiling pbkdf2 v0.9.0
[INFO] [stderr]    Compiling blowfish v0.8.0
[INFO] [stderr]    Compiling addr2line v0.16.0
[INFO] [stderr]    Compiling synstructure v0.12.6
[INFO] [stderr]    Compiling zeroize_derive v1.2.0
[INFO] [stderr]    Compiling zeroize v1.4.2
[INFO] [stderr]    Compiling curve25519-dalek v3.2.0
[INFO] [stderr]    Compiling bcrypt-pbkdf v0.7.1
[INFO] [stderr]    Compiling ed25519-dalek v1.0.1
[INFO] [stderr]    Compiling osshkeys v0.5.2 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 13.77s
[INFO] running `Command { std: "docker" "inspect" "25488b59f9f317cf37389df7dbd64cdcf98326f01b5a3d19b65e2f793f4b67c0", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "25488b59f9f317cf37389df7dbd64cdcf98326f01b5a3d19b65e2f793f4b67c0", kill_on_drop: false }`
[INFO] [stdout] 25488b59f9f317cf37389df7dbd64cdcf98326f01b5a3d19b65e2f793f4b67c0
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-38/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-38/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" "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:5736fa189c1c60b01babf4b8b698fe57b6ecc41933a7ff2e0b8d7a221459412b" "/opt/rustwide/cargo-home/bin/cargo" "+a77da2d454e6caa227a85b16410b95f93495e7e0" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 94cdeacd13ea9b485c8f2ac919718364bdd266127caf89cefe07c210c0c6ee2f
[INFO] running `Command { std: "docker" "start" "-a" "94cdeacd13ea9b485c8f2ac919718364bdd266127caf89cefe07c210c0c6ee2f", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]    Compiling hex-literal v0.3.3
[INFO] [stderr]    Compiling hex v0.4.3
[INFO] [stderr]    Compiling osshkeys v0.5.2 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 2.82s
[INFO] running `Command { std: "docker" "inspect" "94cdeacd13ea9b485c8f2ac919718364bdd266127caf89cefe07c210c0c6ee2f", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "94cdeacd13ea9b485c8f2ac919718364bdd266127caf89cefe07c210c0c6ee2f", kill_on_drop: false }`
[INFO] [stdout] 94cdeacd13ea9b485c8f2ac919718364bdd266127caf89cefe07c210c0c6ee2f
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-38/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-38/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" "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:5736fa189c1c60b01babf4b8b698fe57b6ecc41933a7ff2e0b8d7a221459412b" "/opt/rustwide/cargo-home/bin/cargo" "+a77da2d454e6caa227a85b16410b95f93495e7e0" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 65d4491f885c6a124c6f092f7ef86bc415ad5f85d3a69a54e00735f19364a1f4
[INFO] running `Command { std: "docker" "start" "-a" "65d4491f885c6a124c6f092f7ef86bc415ad5f85d3a69a54e00735f19364a1f4", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 1.02s
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/osshkeys-4261b3dd2c907a83)
[INFO] [stdout] 
[INFO] [stdout] running 14 tests
[INFO] [stdout] test format::ossh_pubkey::test::ed25519_publickey_parse_serialize ... ok
[INFO] [stdout] test format::ossh_pubkey::test::rsa256_publickey_parse_serialize ... ok
[INFO] [stdout] test format::ossh_pubkey::test::dsa_publickey_parse_serialize ... ok
[INFO] [stdout] test format::ossh_pubkey::test::ecdsa_publickey_parse_serialize ... ok
[INFO] [stdout] test format::ossh_pubkey::test::rsa_publickey_parse_serialize ... ok
[INFO] [stdout] test keys::dsa::test::dsa_publickey_serialize ... ok
[INFO] [stdout] test keys::ecdsa::test::ecdsa_publickey_serialize ... ok
[INFO] [stdout] test keys::dsa::test::dsa_publickey_size ... ok
[INFO] [stdout] test keys::ecdsa::test::ecdsa_publickey_size ... ok
[INFO] [stdout] test keys::ed25519::test::ed25519_publickey_serialize ... ok
[INFO] [stderr]      Running tests/keyfiles.rs (/opt/rustwide/target/debug/deps/keyfiles-f110d6227debde38)
[INFO] [stdout] test keys::ed25519::test::ed25519_publickey_size ... ok
[INFO] [stdout] test keys::test_size ... ignored
[INFO] [stdout] test keys::rsa::test::rsa_publickey_size ... ok
[INFO] [stdout] test keys::rsa::test::rsa_publickey_serialize ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 13 passed; 0 failed; 1 ignored; 0 measured; 0 filtered out; finished in 0.03s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 20 tests
[INFO] [stdout] test keyfile_openssh_ed25519 ... ok
[INFO] [stdout] test keyfile_openssh_ecdsa ... ok
[INFO] [stdout] test keyfile_openssh_dsa ... ok
[INFO] [stdout] test keyfile_pem_dsa_enc ... ok
[INFO] [stdout] test keyfile_pem_dsa ... ok
[INFO] [stdout] test keyfile_openssh_rsa ... ok
[INFO] [stdout] test keyfile_pem_ecdsa ... ok
[INFO] [stdout] test keyfile_pem_ecdsa_enc ... ok
[INFO] [stdout] test keyfile_pkcs8_rsa_enc ... ok
[INFO] [stdout] test keyfile_pkcs8_rsa ... ok
[INFO] [stdout] test keyfile_pem_rsa ... ok
[INFO] [stdout] test keyfile_pem_rsa_enc ... ok
[INFO] [stdout] test keyfile_pem_ed25519_wrong - should panic ... ok
[INFO] [stdout] test keyfile_pem_rsa_wrong - should panic ... ok
[INFO] [stdout] test keyfile_pem_ecdsa_wrong - should panic ... ok
[INFO] [stdout] test keyfile_pem_dsa_wrong - should panic ... ok
[INFO] [stdout] test keyfile_openssh_dsa_enc ... ok
[INFO] [stdout] test keyfile_openssh_rsa_enc ... ok
[INFO] [stdout] test keyfile_openssh_ecdsa_enc ... ok
[INFO] [stdout] test keyfile_openssh_ed25519_enc ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 20 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 2.68s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/keyfiles_serde.rs (/opt/rustwide/target/debug/deps/keyfiles_serde-c094d0e95eb92ffd)
[INFO] [stdout] 
[INFO] [stdout] running 6 tests
[INFO] [stdout] test serde_pem ... ok
[INFO] [stdout] test serde_pkcs8_encrypt ... ok
[INFO] [stdout] test serde_openssh ... ok
[INFO] [stdout] test serde_pkcs8 ... ok
[INFO] [stdout] test serde_pem_encrypt ... ok
[INFO] [stdout] test serde_openssh_encrypt has been running for over 60 seconds
[INFO] [stderr]      Running tests/keypair_gererate.rs (/opt/rustwide/target/debug/deps/keypair_gererate-7498ba3f95ce4b8a)
[INFO] [stdout] test serde_openssh_encrypt ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 126.99s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 12 tests
[INFO] [stdout] test ed25519_generate ... ok
[INFO] [stdout] test ecdsa_521_generate ... ok
[INFO] [stdout] test ecdsa_384_generate ... ok
[INFO] [stdout] test ecdsa_256_generate ... ok
[INFO] [stdout] test dsa_generate ... ok
[INFO] [stdout] test rsa_generate ... ok
[INFO] [stdout] test rsa_generate_strange ... ok
[INFO] [stdout] test ed25519_generate_invalid - should panic ... ok
[INFO] [stdout] test rsa_generate_maxsize - should panic ... ok
[INFO] [stdout] test rsa_generate_minsize - should panic ... ok
[INFO] [stdout] test ecdsa_generate_invalid - should panic ... ok
[INFO] [stdout] test dsa_generate_invalid_size - should panic ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 12 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.73s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/pubkey_serialize_pem.rs (/opt/rustwide/target/debug/deps/pubkey_serialize_pem-2cdc134cddc59ad7)
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test pem_serialize_openssh_rsa ... ok
[INFO] [stdout] test pem_serialize_openssh_dsa ... ok
[INFO] [stdout] test pem_serialize_openssh_ecdsa ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/random_sign_verify.rs (/opt/rustwide/target/debug/deps/random_sign_verify-49afc49d3f3b32fd)
[INFO] [stdout] 
[INFO] [stdout] running 6 tests
[INFO] [stdout] test ecdsa_sign_verify ... ok
[INFO] [stdout] test ed25519_sign_verify ... ok
[INFO] [stdout] test dsa_sign_verify ... ok
[INFO] [stdout] test rsa_sign_verify ... ok
[INFO] [stdout] test rsa_sign_sha512_verify ... ok
[INFO] [stdout] test rsa_sign_sha256_verify ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.30s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/utils.rs (/opt/rustwide/target/debug/deps/utils-ab59c2f41ce797b5)
[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; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests osshkeys
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test src/cipher.rs - cipher::Cipher::calc_buffer_len (line 84) ... ok
[INFO] [stdout] test src/lib.rs - (line 28) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 5.90s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "65d4491f885c6a124c6f092f7ef86bc415ad5f85d3a69a54e00735f19364a1f4", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "65d4491f885c6a124c6f092f7ef86bc415ad5f85d3a69a54e00735f19364a1f4", kill_on_drop: false }`
[INFO] [stdout] 65d4491f885c6a124c6f092f7ef86bc415ad5f85d3a69a54e00735f19364a1f4