Dec 15 02:52:57.051 INFO testing opcua-core-0.5.0 against try#aa49d8ef14939ddec0e34b346b60174a5673d48f for pr-56550
Dec 15 02:52:57.051 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-3/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-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 15 02:52:57.294 INFO [stdout] 79a9fee725275ce4ac4053cd344d9e14fdea9548d91c18ed6d3e6a45fbee18c2
Dec 15 02:52:57.300 INFO running `"docker" "start" "-a" "79a9fee725275ce4ac4053cd344d9e14fdea9548d91c18ed6d3e6a45fbee18c2"`
Dec 15 02:52:57.820 INFO [stderr] usermod: no changes
Dec 15 02:52:58.043 INFO [stderr]    Compiling opcua-types v0.5.0
Dec 15 02:53:30.035 INFO [stderr]    Compiling opcua-core v0.5.0 (/source)
Dec 15 02:53:38.276 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 40.42s
Dec 15 02:53:38.289 INFO [stderr] su: No module specific data is present
Dec 15 02:53:39.302 INFO running `"docker" "inspect" "79a9fee725275ce4ac4053cd344d9e14fdea9548d91c18ed6d3e6a45fbee18c2"`
Dec 15 02:53:39.627 INFO running `"docker" "rm" "-f" "79a9fee725275ce4ac4053cd344d9e14fdea9548d91c18ed6d3e6a45fbee18c2"`
Dec 15 02:53:39.955 INFO [stdout] 79a9fee725275ce4ac4053cd344d9e14fdea9548d91c18ed6d3e6a45fbee18c2
Dec 15 02:53:39.963 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-3/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-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 15 02:53:40.523 INFO [stdout] e28c4070b61dff509b0923f0a887000d55d23c54434f9b1730079e6fb8e17b39
Dec 15 02:53:40.526 INFO running `"docker" "start" "-a" "e28c4070b61dff509b0923f0a887000d55d23c54434f9b1730079e6fb8e17b39"`
Dec 15 02:53:42.594 INFO [stderr] usermod: no changes
Dec 15 02:53:42.966 INFO [stderr]    Compiling opcua-core v0.5.0 (/source)
Dec 15 02:53:56.527 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 13.88s
Dec 15 02:53:56.536 INFO [stderr] su: No module specific data is present
Dec 15 02:53:56.876 INFO running `"docker" "inspect" "e28c4070b61dff509b0923f0a887000d55d23c54434f9b1730079e6fb8e17b39"`
Dec 15 02:53:56.984 INFO running `"docker" "rm" "-f" "e28c4070b61dff509b0923f0a887000d55d23c54434f9b1730079e6fb8e17b39"`
Dec 15 02:53:57.107 INFO [stdout] e28c4070b61dff509b0923f0a887000d55d23c54434f9b1730079e6fb8e17b39
Dec 15 02:53:57.115 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-3/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-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 15 02:53:57.302 INFO [stdout] 31440937d967502f33c47b82ff7716ab9af05eadbc34436cc5177451e1c8049c
Dec 15 02:53:57.304 INFO running `"docker" "start" "-a" "31440937d967502f33c47b82ff7716ab9af05eadbc34436cc5177451e1c8049c"`
Dec 15 02:53:57.913 INFO [stderr] usermod: no changes
Dec 15 02:53:58.075 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.12s
Dec 15 02:53:58.085 INFO [stderr]      Running /target/debug/deps/opcua_core-ad8124e036e0f0ca
Dec 15 02:53:58.106 INFO [stdout] 
Dec 15 02:53:58.106 INFO [stdout] running 47 tests
Dec 15 02:53:58.107 INFO [stdout] test tests::chunk::chunk_open_secure_channel ... ok
Dec 15 02:53:58.115 INFO [stdout] test tests::authentication::user_name_identity_token_plaintext ... ok
Dec 15 02:53:58.115 INFO [stdout] test crypto::x509::parse_asn1_date_test ... ok
Dec 15 02:53:58.115 INFO [stdout] test tests::authentication::user_name_identity_token_valid ... ok
Dec 15 02:53:58.123 INFO [stdout] test tests::chunk::open_secure_channel ... ok
Dec 15 02:53:58.123 INFO [stdout] test tests::chunk::security_policy_symmetric_encrypt_decrypt ... ok
Dec 15 02:53:58.123 INFO [stdout] test tests::chunk::open_secure_channel_response ... ok
Dec 15 02:53:58.127 INFO [stdout] test tests::comms::acknowledge ... ok
Dec 15 02:53:58.135 INFO [stdout] test tests::comms::hello ... ok
Dec 15 02:53:58.135 INFO [stdout] test tests::chunk::asymmetric_decrypt_and_verify_sample_chunk ... ok
Dec 15 02:53:58.147 INFO [stdout] test tests::comms::secure_channel_nonce ... ok
Dec 15 02:53:58.147 INFO [stdout] test tests::crypto::aes_test ... ok
Dec 15 02:53:58.547 INFO [stdout] test tests::chunk::validate_chunks_sequence_number ... ok
Dec 15 02:53:58.660 INFO [stdout] test tests::crypto::calculate_cipher_text_size ... ok
Dec 15 02:53:58.842 INFO [stdout] test tests::chunk::validate_chunks_request_id ... ok
Dec 15 02:53:58.881 INFO [stdout] test tests::chunk::chunk_multi_chunk_intermediate_final ... ok
Dec 15 02:53:58.883 INFO [stdout] test tests::chunk::validate_chunks_secure_channel_id ... ok
Dec 15 02:53:58.919 INFO [stdout] test tests::chunk::max_message_size ... ok
Dec 15 02:53:59.073 INFO [stdout] test tests::crypto::create_cert ... ok
Dec 15 02:53:59.081 INFO [stdout] test tests::crypto::create_rejected_cert_in_pki ... ok
Dec 15 02:53:59.083 INFO [stdout] test tests::crypto::derive_keys_from_nonce_basic128rsa15 ... ok
Dec 15 02:53:59.109 INFO [stdout] test tests::crypto::ensure_pki_path ... ok
Dec 15 02:53:59.115 INFO [stdout] test tests::crypto::generate_nonce ... ok
Dec 15 02:53:59.120 INFO [stdout] test tests::crypto::sign_hmac_sha1 ... ok
Dec 15 02:53:59.143 INFO [stdout] test tests::crypto::sign_hmac_sha256 ... ok
Dec 15 02:53:59.183 INFO [stdout] test tests::chunk::chunk_multi_encode_decode ... ok
Dec 15 02:53:59.195 INFO [stdout] test tests::crypto::derive_keys_from_nonce ... ok
Dec 15 02:53:59.196 INFO [stdout] test tests::crypto::certificate_with_application_uri_mismatch ... ok
Dec 15 02:53:59.319 INFO [stdout] test tests::crypto::certificate_with_hostname_mismatch ... ok
Dec 15 02:53:59.379 INFO [stdout] test tests::crypto::test_and_reject_application_instance_cert ... ok
Dec 15 02:53:59.455 INFO [stdout] test tests::crypto::sign_verify_sha1 ... ok
Dec 15 02:53:59.487 INFO [stdout] test tests::crypto::sign_verify_sha256 ... ok
Dec 15 02:53:59.503 INFO [stdout] test tests::crypto::test_and_trust_application_instance_cert ... ok
Dec 15 02:53:59.507 INFO [stdout] test tests::secure_channel::symmetric_sign_and_encrypt_message_chunk_basic128rsa15 ... ok
Dec 15 02:53:59.512 INFO [stdout] test tests::secure_channel::symmetric_sign_and_encrypt_message_chunk_basic256 ... ok
Dec 15 02:53:59.524 INFO [stdout] test tests::secure_channel::symmetric_sign_and_encrypt_message_chunk_basic256sha256 ... ok
Dec 15 02:53:59.535 INFO [stdout] test tests::crypto::asymmetric_encrypt_and_decrypt ... ok
Dec 15 02:53:59.538 INFO [stdout] test tests::secure_channel::symmetric_sign_message_chunk_basic128rsa15 ... ok
Dec 15 02:53:59.538 INFO [stdout] test tests::secure_channel::symmetric_sign_message_chunk_basic256 ... ok
Dec 15 02:53:59.539 INFO [stdout] test tests::services::get_endpoints_request ... ok
Dec 15 02:53:59.545 INFO [stdout] test tests::secure_channel::symmetric_sign_message_chunk_basic256sha256 ... ok
Dec 15 02:53:59.899 INFO [stdout] test tests::crypto::test_and_reject_thumbprint_mismatch ... ok
Dec 15 02:54:00.355 INFO [stdout] test tests::crypto::create_own_cert_in_pki ... ok
Dec 15 02:54:00.703 INFO [stdout] test tests::crypto::calculate_cipher_text_size2 ... ok
Dec 15 02:54:00.789 INFO [stdout] test tests::secure_channel::asymmetric_sign_and_encrypt_message_chunk_basic256sha256 ... ok
Dec 15 02:54:00.823 INFO [stdout] test tests::secure_channel::asymmetric_sign_and_encrypt_message_chunk_basic256 ... ok
Dec 15 02:54:01.477 INFO [stdout] test tests::secure_channel::asymmetric_sign_and_encrypt_message_chunk_basic128rsa15 ... ok
Dec 15 02:54:01.477 INFO [stdout] 
Dec 15 02:54:01.477 INFO [stdout] test result: ok. 47 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 02:54:01.477 INFO [stdout] 
Dec 15 02:54:01.483 INFO [stderr]    Doc-tests opcua-core
Dec 15 02:54:07.514 INFO [stdout] 
Dec 15 02:54:07.514 INFO [stdout] running 0 tests
Dec 15 02:54:07.514 INFO [stdout] 
Dec 15 02:54:07.514 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 02:54:07.514 INFO [stdout] 
Dec 15 02:54:07.527 INFO [stderr] su: No module specific data is present
Dec 15 02:54:07.864 INFO running `"docker" "inspect" "31440937d967502f33c47b82ff7716ab9af05eadbc34436cc5177451e1c8049c"`
Dec 15 02:54:07.999 INFO running `"docker" "rm" "-f" "31440937d967502f33c47b82ff7716ab9af05eadbc34436cc5177451e1c8049c"`
Dec 15 02:54:08.142 INFO [stdout] 31440937d967502f33c47b82ff7716ab9af05eadbc34436cc5177451e1c8049c