[INFO] fetching crate opcua-crypto 0.9.1...
[INFO] testing opcua-crypto-0.9.1 against 1.60.0 for beta-1.61-1
[INFO] extracting crate opcua-crypto 0.9.1 into /workspace/builds/worker-43/source
[INFO] validating manifest of crates.io crate opcua-crypto 0.9.1 on toolchain 1.60.0
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+1.60.0" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking crates.io crate opcua-crypto 0.9.1
[INFO] finished tweaking crates.io crate opcua-crypto 0.9.1
[INFO] tweaked toml for crates.io crate opcua-crypto 0.9.1 written to /workspace/builds/worker-43/source/Cargo.toml
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+1.60.0" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update", kill_on_drop: false }`
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+1.60.0" "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] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-43/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-43/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:b0c94ce3c1162fcb8e57cac5b65ec2f72eabb1eebea4fcc35e269e823f681646" "/opt/rustwide/cargo-home/bin/cargo" "+1.60.0" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] fbbe343d27386362a2d76dfa63dfbed351b694e7e9fff2032f9c6de3a88d24ed
[INFO] running `Command { std: "docker" "start" "-a" "fbbe343d27386362a2d76dfa63dfbed351b694e7e9fff2032f9c6de3a88d24ed", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "fbbe343d27386362a2d76dfa63dfbed351b694e7e9fff2032f9c6de3a88d24ed", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "fbbe343d27386362a2d76dfa63dfbed351b694e7e9fff2032f9c6de3a88d24ed", kill_on_drop: false }`
[INFO] [stdout] fbbe343d27386362a2d76dfa63dfbed351b694e7e9fff2032f9c6de3a88d24ed
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-43/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-43/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=warn" "-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:b0c94ce3c1162fcb8e57cac5b65ec2f72eabb1eebea4fcc35e269e823f681646" "/opt/rustwide/cargo-home/bin/cargo" "+1.60.0" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 68ff39fd7600e9f48a2c86243138c39a1a297ecb8add1dbdfd930b6502f9c8b0
[INFO] running `Command { std: "docker" "start" "-a" "68ff39fd7600e9f48a2c86243138c39a1a297ecb8add1dbdfd930b6502f9c8b0", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]    Compiling pkg-config v0.3.25
[INFO] [stderr]    Compiling syn v1.0.91
[INFO] [stderr]    Compiling serde v1.0.136
[INFO] [stderr]    Compiling foreign-types-shared v0.1.1
[INFO] [stderr]    Compiling bitflags v1.3.2
[INFO] [stderr]    Compiling regex-syntax v0.6.25
[INFO] [stderr]    Compiling openssl v0.10.38
[INFO] [stderr]    Compiling base64 v0.12.3
[INFO] [stderr]    Compiling bytes v1.1.0
[INFO] [stderr]    Compiling num-traits v0.2.14
[INFO] [stderr]    Compiling num-integer v0.1.44
[INFO] [stderr]    Compiling time v0.1.43
[INFO] [stderr]    Compiling getrandom v0.2.6
[INFO] [stderr]    Compiling gethostname v0.2.3
[INFO] [stderr]    Compiling foreign-types v0.3.2
[INFO] [stderr]    Compiling uuid v0.8.2
[INFO] [stderr]    Compiling openssl-sys v0.9.72
[INFO] [stderr]    Compiling regex v1.5.5
[INFO] [stderr]    Compiling chrono v0.4.19
[INFO] [stderr]    Compiling serde_derive v1.0.136
[INFO] [stderr]    Compiling opcua-types v0.9.1
[INFO] [stderr]    Compiling opcua-crypto v0.9.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1m 27s
[INFO] running `Command { std: "docker" "inspect" "68ff39fd7600e9f48a2c86243138c39a1a297ecb8add1dbdfd930b6502f9c8b0", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "68ff39fd7600e9f48a2c86243138c39a1a297ecb8add1dbdfd930b6502f9c8b0", kill_on_drop: false }`
[INFO] [stdout] 68ff39fd7600e9f48a2c86243138c39a1a297ecb8add1dbdfd930b6502f9c8b0
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-43/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-43/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=warn" "-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:b0c94ce3c1162fcb8e57cac5b65ec2f72eabb1eebea4fcc35e269e823f681646" "/opt/rustwide/cargo-home/bin/cargo" "+1.60.0" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 571829b31fdc6e85a2eaa17fb2d7e1ca5e9a3b27b3aa2339f1f084ad19bf33fd
[INFO] running `Command { std: "docker" "start" "-a" "571829b31fdc6e85a2eaa17fb2d7e1ca5e9a3b27b3aa2339f1f084ad19bf33fd", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]    Compiling termcolor v1.1.3
[INFO] [stderr]    Compiling remove_dir_all v0.5.3
[INFO] [stderr]    Compiling rustc-serialize v0.3.24
[INFO] [stderr]    Compiling humantime v1.3.0
[INFO] [stderr]    Compiling atty v0.2.14
[INFO] [stderr]    Compiling rand v0.4.6
[INFO] [stderr]    Compiling env_logger v0.5.13
[INFO] [stderr]    Compiling tempdir v0.3.7
[INFO] [stderr]    Compiling opcua-console-logging v0.9.1
[INFO] [stderr]    Compiling opcua-crypto v0.9.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 7.59s
[INFO] running `Command { std: "docker" "inspect" "571829b31fdc6e85a2eaa17fb2d7e1ca5e9a3b27b3aa2339f1f084ad19bf33fd", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "571829b31fdc6e85a2eaa17fb2d7e1ca5e9a3b27b3aa2339f1f084ad19bf33fd", kill_on_drop: false }`
[INFO] [stdout] 571829b31fdc6e85a2eaa17fb2d7e1ca5e9a3b27b3aa2339f1f084ad19bf33fd
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-43/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-43/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=warn" "-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:b0c94ce3c1162fcb8e57cac5b65ec2f72eabb1eebea4fcc35e269e823f681646" "/opt/rustwide/cargo-home/bin/cargo" "+1.60.0" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 06a7d8538f1d7e3b1efa61a2004559f9f20b010983c0e81883241347920fe493
[INFO] running `Command { std: "docker" "start" "-a" "06a7d8538f1d7e3b1efa61a2004559f9f20b010983c0e81883241347920fe493", kill_on_drop: false }`
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.07s
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/opcua_crypto-e33f8510c4f7e6b3)
[INFO] [stdout] 
[INFO] [stdout] running 32 tests
[INFO] [stderr] 2022-04-13 20:02:36.557 - ERROR - opcua_types::service_types::impls - Authentication error: User name xyz supplied by client is recognised but password is not
[INFO] [stderr] 2022-04-13 20:02:36.558 - ERROR - opcua_types::service_types::impls - Authentication error: User name supplied by client is unrecognised
[INFO] [stderr] 2022-04-13 20:02:36.623 - ERROR - opcua_crypto::hash - Signature buffer length must be exactly 32 bytes to receive hmac_sha256 signature
[INFO] [stderr] 2022-04-13 20:02:36.625 - ERROR - opcua_crypto::hash - Signature buffer length must be exactly 20 bytes to receive hmac_sha1 signature
[INFO] [stderr] 2022-04-13 20:02:36.685 - ERROR - opcua_crypto::x509 - Cannot parse ASN1 date, err = ParseError(TooShort)
[INFO] [stdout] test tests::authentication::user_name_identity_token_valid ... ok
[INFO] [stderr] 2022-04-13 20:02:36.686 - ERROR - opcua_crypto::x509 - Cannot parse ASN1 date, err = ParseError(OutOfRange)
[INFO] [stdout] test tests::authentication::user_name_identity_token_plaintext ... ok
[INFO] [stdout] test tests::crypto::aes_test ... ok
[INFO] [stdout] test tests::crypto::create_cert ... ok
[INFO] [stdout] test aeskey::tests::test_aeskey_cross_thread ... ok
[INFO] [stdout] test tests::authentication::user_name_identity_token_encrypted ... ok
[INFO] [stdout] test tests::crypto::calculate_cipher_text_size ... ok
[INFO] [stdout] test tests::crypto::generate_nonce ... ok
[INFO] [stdout] test tests::crypto::sign_hmac_sha256 ... ok
[INFO] [stdout] test tests::crypto::sign_hmac_sha1 ... ok
[INFO] [stdout] test tests::crypto::derive_keys_from_nonce_basic128rsa15 ... ok
[INFO] [stdout] test tests::crypto::derive_keys_from_nonce ... ok
[INFO] [stdout] test tests::crypto::encrypt_decrypt_password ... ok
[INFO] [stdout] test tests::crypto::ensure_pki_path ... ok
[INFO] [stderr] 2022-04-13 20:02:36.693 - ERROR - opcua_crypto::security_policy - Specified security policy "" is not recognized
[INFO] [stderr] 2022-04-13 20:02:36.693 - ERROR - opcua_crypto::security_policy - Specified security policy "none" is not recognized
[INFO] [stderr] 2022-04-13 20:02:36.693 - ERROR - opcua_crypto::security_policy - Specified security policy " None" is not recognized
[INFO] [stderr] 2022-04-13 20:02:36.693 - ERROR - opcua_crypto::security_policy - Specified security policy "Basic256 " is not recognized
[INFO] [stderr] 2022-04-13 20:02:36.693 - ERROR - opcua_crypto::security_policy - Specified security policy "http://opcfoundation.org/UA/SecurityPolicy#" is not recognized
[INFO] [stderr] 2022-04-13 20:02:36.707 - ERROR - opcua_crypto::certificate_store - Certificate in memory does not match the one on disk /tmp/pki.tuJMDEF3Bp4x/trusted/x [1794b7e676cf920455f07ed12eae91ac02f4136d].der so cert will automatically be treated as untrusted
[INFO] [stderr] 2022-04-13 20:02:36.715 - ERROR - opcua_crypto::x509 - Cert application uri urn:testapplication does not match supplied uri urn:WrongURI
[INFO] [stdout] test tests::security_policy::is_deprecated ... ok
[INFO] [stdout] test tests::security_policy::to_uri ... ok
[INFO] [stdout] test x509::tests::parse_asn1_date_test ... ok
[INFO] [stdout] test tests::security_policy::is_valid_keylength ... ok
[INFO] [stdout] test tests::security_policy::from_str ... ok
[INFO] [stdout] test tests::crypto::test_and_trust_application_instance_cert ... ok
[INFO] [stdout] test tests::crypto::create_rejected_cert_in_pki ... ok
[INFO] [stdout] test tests::crypto::test_and_reject_thumbprint_mismatch ... ok
[INFO] [stdout] test tests::crypto::sign_verify_sha256_pss ... ok
[INFO] [stdout] test tests::crypto::certificate_with_application_uri_mismatch ... ok
[INFO] [stdout] test tests::crypto::test_and_reject_application_instance_cert ... ok
[INFO] [stderr] 2022-04-13 20:02:36.762 - ERROR - opcua_crypto::x509 - Hostname is empty
[INFO] [stderr] 2022-04-13 20:02:36.763 - ERROR - opcua_crypto::x509 - Cannot find a matching hostname for input uri:foo, alt names = host2, www.google.com, 192.168.1.1, ::1
[INFO] [stderr] 2022-04-13 20:02:36.763 - ERROR - opcua_crypto::x509 - Cannot find a matching hostname for input 192.168.1.0, alt names = host2, www.google.com, 192.168.1.1, ::1
[INFO] [stderr] 2022-04-13 20:02:36.763 - ERROR - opcua_crypto::x509 - Cannot find a matching hostname for input www.cnn.com, alt names = host2, www.google.com, 192.168.1.1, ::1
[INFO] [stderr] 2022-04-13 20:02:36.763 - ERROR - opcua_crypto::x509 - Cannot find a matching hostname for input host1, alt names = host2, www.google.com, 192.168.1.1, ::1
[INFO] [stdout] test x509::tests::alt_hostnames ... ok
[INFO] [stdout] test tests::crypto::sign_verify_sha256 ... ok
[INFO] [stdout] test tests::crypto::sign_verify_sha1 ... ok
[INFO] [stdout] test tests::crypto::create_own_cert_in_pki ... ok
[INFO] [stderr] 2022-04-13 20:02:36.941 - ERROR - opcua_crypto::x509 - Cannot find a matching hostname for input wrong_testhost, alt names = foo, foo2, testhost, foo3
[INFO] [stdout] test tests::crypto::certificate_with_hostname_mismatch ... ok
[INFO] [stdout] test tests::crypto::asymmetric_encrypt_and_decrypt ... ok
[INFO] [stderr]    Doc-tests opcua-crypto
[INFO] [stdout] test tests::crypto::calculate_cipher_text_size2 ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 32 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.18s
[INFO] [stdout] 
[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] running `Command { std: "docker" "inspect" "06a7d8538f1d7e3b1efa61a2004559f9f20b010983c0e81883241347920fe493", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "06a7d8538f1d7e3b1efa61a2004559f9f20b010983c0e81883241347920fe493", kill_on_drop: false }`
[INFO] [stdout] 06a7d8538f1d7e3b1efa61a2004559f9f20b010983c0e81883241347920fe493