[INFO] cloning repository https://github.com/Spider4Tech/gh [INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/Spider4Tech/gh" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FSpider4Tech%2Fgh", kill_on_drop: false }` [INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FSpider4Tech%2Fgh'... [INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }` [INFO] [stdout] 70c214413b24523a8f2f2faa16fff51285fd5adc [INFO] testing Spider4Tech/gh against try#bd7d74411512a3dd3b35d2f699c51dd2557c7e7e+cargoflags=-Zbuild-dir-new-layout for pr-149852-1 [INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FSpider4Tech%2Fgh" "/workspace/builds/worker-4-tc2/source", kill_on_drop: false }` [INFO] [stderr] Cloning into '/workspace/builds/worker-4-tc2/source'... [INFO] [stderr] done. [INFO] started tweaking git repo https://github.com/Spider4Tech/gh [INFO] finished tweaking git repo https://github.com/Spider4Tech/gh [INFO] tweaked toml for git repo https://github.com/Spider4Tech/gh written to /workspace/builds/worker-4-tc2/source/Cargo.toml [INFO] validating manifest of git repo https://github.com/Spider4Tech/gh on toolchain bd7d74411512a3dd3b35d2f699c51dd2557c7e7e [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+bd7d74411512a3dd3b35d2f699c51dd2557c7e7e" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] crate git repo https://github.com/Spider4Tech/gh 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" "+bd7d74411512a3dd3b35d2f699c51dd2557c7e7e" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Blocking waiting for file lock on package cache [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/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:aa71247004a7fa38d13ec170f48f06cdedf5bc50b2a8645e56ed7e992e6fa513" "/opt/rustwide/cargo-home/bin/cargo" "+bd7d74411512a3dd3b35d2f699c51dd2557c7e7e" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] d65fc0709edbc2e1c5a503669bf42aa0bc606259fdfe68b2755328a19334a98e [INFO] running `Command { std: "docker" "start" "-a" "d65fc0709edbc2e1c5a503669bf42aa0bc606259fdfe68b2755328a19334a98e", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "d65fc0709edbc2e1c5a503669bf42aa0bc606259fdfe68b2755328a19334a98e", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "d65fc0709edbc2e1c5a503669bf42aa0bc606259fdfe68b2755328a19334a98e", kill_on_drop: false }` [INFO] [stdout] d65fc0709edbc2e1c5a503669bf42aa0bc606259fdfe68b2755328a19334a98e [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/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:aa71247004a7fa38d13ec170f48f06cdedf5bc50b2a8645e56ed7e992e6fa513" "/opt/rustwide/cargo-home/bin/cargo" "+bd7d74411512a3dd3b35d2f699c51dd2557c7e7e" "build" "--frozen" "--message-format=json" "-Zbuild-dir-new-layout", kill_on_drop: false }` [INFO] [stdout] b0dad587bd710b2ae4b45ebe3d4c08b20d39d96ed727a4aa228de02f31176119 [INFO] running `Command { std: "docker" "start" "-a" "b0dad587bd710b2ae4b45ebe3d4c08b20d39d96ed727a4aa228de02f31176119", kill_on_drop: false }` [INFO] [stderr] Compiling typenum v1.18.0 [INFO] [stderr] Compiling cfg-if v1.0.3 [INFO] [stderr] Compiling subtle v2.6.1 [INFO] [stderr] Compiling find-msvc-tools v0.1.1 [INFO] [stderr] Compiling const-oid v0.10.1 [INFO] [stderr] Compiling lock_api v0.4.13 [INFO] [stderr] Compiling base64ct v1.8.0 [INFO] [stderr] Compiling arrayvec v0.7.6 [INFO] [stderr] Compiling hashbrown v0.14.5 [INFO] [stderr] Compiling secrecy v0.8.0 [INFO] [stderr] Compiling getrandom v0.2.16 [INFO] [stderr] Compiling parking_lot_core v0.9.11 [INFO] [stderr] Compiling cc v1.2.36 [INFO] [stderr] Compiling rand_core v0.6.4 [INFO] [stderr] Compiling password-hash v0.5.0 [INFO] [stderr] Compiling rand_chacha v0.3.1 [INFO] [stderr] Compiling rand v0.8.5 [INFO] [stderr] Compiling dashmap v6.1.0 [INFO] [stderr] Compiling hybrid-array v0.4.0 [INFO] [stderr] Compiling generic-array v0.14.7 [INFO] [stderr] Compiling ring v0.17.14 [INFO] [stderr] Compiling blake3 v1.8.2 [INFO] [stderr] Compiling block-buffer v0.11.0-rc.5 [INFO] [stderr] Compiling crypto-common v0.2.0-rc.4 [INFO] [stderr] Compiling block-buffer v0.10.4 [INFO] [stderr] Compiling crypto-common v0.1.6 [INFO] [stderr] Compiling digest v0.10.7 [INFO] [stderr] Compiling blake2 v0.10.6 [INFO] [stderr] Compiling argon2 v0.5.3 [INFO] [stderr] Compiling digest v0.11.0-rc.1 [INFO] [stderr] Compiling hmac v0.13.0-rc.1 [INFO] [stderr] Compiling sha2 v0.11.0-rc.2 [INFO] [stderr] Compiling hkdf v0.13.0-rc.1 [INFO] [stderr] Compiling horizon v0.9.4 (/opt/rustwide/workdir) [INFO] [stderr] Finished `dev` profile [unoptimized + debuginfo] target(s) in 13.59s [INFO] running `Command { std: "docker" "inspect" "b0dad587bd710b2ae4b45ebe3d4c08b20d39d96ed727a4aa228de02f31176119", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "b0dad587bd710b2ae4b45ebe3d4c08b20d39d96ed727a4aa228de02f31176119", kill_on_drop: false }` [INFO] [stdout] b0dad587bd710b2ae4b45ebe3d4c08b20d39d96ed727a4aa228de02f31176119 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/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:aa71247004a7fa38d13ec170f48f06cdedf5bc50b2a8645e56ed7e992e6fa513" "/opt/rustwide/cargo-home/bin/cargo" "+bd7d74411512a3dd3b35d2f699c51dd2557c7e7e" "test" "--frozen" "--no-run" "--message-format=json" "-Zbuild-dir-new-layout", kill_on_drop: false }` [INFO] [stdout] 5b0fa1e5d479a714ca1f24219d34f0a7c7a77652603319a49d8b61bed6772f35 [INFO] running `Command { std: "docker" "start" "-a" "5b0fa1e5d479a714ca1f24219d34f0a7c7a77652603319a49d8b61bed6772f35", kill_on_drop: false }` [INFO] [stderr] Compiling horizon v0.9.4 (/opt/rustwide/workdir) [INFO] [stdout] warning: variable `cnt00` is assigned to, but never used [INFO] [stdout] --> src/lib.rs:763:21 [INFO] [stdout] | [INFO] [stdout] 763 | let mut cnt00 = 0usize; [INFO] [stdout] | ^^^^^^^^^ [INFO] [stdout] | [INFO] [stdout] = note: consider using `_cnt00` instead [INFO] [stdout] = note: `#[warn(unused_variables)]` (part of `#[warn(unused)]`) on by default [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: value assigned to `cnt00` is never read [INFO] [stdout] --> src/lib.rs:772:34 [INFO] [stdout] | [INFO] [stdout] 772 | (0,0) => cnt00 += 1, [INFO] [stdout] | ^^^^^^^^^^ [INFO] [stdout] | [INFO] [stdout] = help: maybe it is overwritten before being read? [INFO] [stdout] = note: `#[warn(unused_assignments)]` (part of `#[warn(unused)]`) on by default [INFO] [stdout] [INFO] [stdout] [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 2.48s [INFO] running `Command { std: "docker" "inspect" "5b0fa1e5d479a714ca1f24219d34f0a7c7a77652603319a49d8b61bed6772f35", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "5b0fa1e5d479a714ca1f24219d34f0a7c7a77652603319a49d8b61bed6772f35", kill_on_drop: false }` [INFO] [stdout] 5b0fa1e5d479a714ca1f24219d34f0a7c7a77652603319a49d8b61bed6772f35 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/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:aa71247004a7fa38d13ec170f48f06cdedf5bc50b2a8645e56ed7e992e6fa513" "/opt/rustwide/cargo-home/bin/cargo" "+bd7d74411512a3dd3b35d2f699c51dd2557c7e7e" "test" "--frozen" "-Zbuild-dir-new-layout", kill_on_drop: false }` [INFO] [stdout] 178aeda58221ec00e65360ba7d92b8191b16db60f321ce8b361bb2b3bd6213bd [INFO] running `Command { std: "docker" "start" "-a" "178aeda58221ec00e65360ba7d92b8191b16db60f321ce8b361bb2b3bd6213bd", kill_on_drop: false }` [INFO] [stderr] warning: variable `cnt00` is assigned to, but never used [INFO] [stderr] --> src/lib.rs:763:21 [INFO] [stderr] | [INFO] [stderr] 763 | let mut cnt00 = 0usize; [INFO] [stderr] | ^^^^^^^^^ [INFO] [stderr] | [INFO] [stderr] = note: consider using `_cnt00` instead [INFO] [stderr] = note: `#[warn(unused_variables)]` (part of `#[warn(unused)]`) on by default [INFO] [stderr] [INFO] [stderr] warning: value assigned to `cnt00` is never read [INFO] [stderr] --> src/lib.rs:772:34 [INFO] [stderr] | [INFO] [stderr] 772 | (0,0) => cnt00 += 1, [INFO] [stderr] | ^^^^^^^^^^ [INFO] [stderr] | [INFO] [stderr] = help: maybe it is overwritten before being read? [INFO] [stderr] = note: `#[warn(unused_assignments)]` (part of `#[warn(unused)]`) on by default [INFO] [stderr] [INFO] [stderr] warning: `horizon` (lib test) generated 2 warnings [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.11s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/build/horizon/1ba963c453f03bdd/deps/horizon-1ba963c453f03bdd) [INFO] [stdout] [INFO] [stdout] running 39 tests [INFO] [stdout] test tests::test_custom_sbox_avalanche_and_sac ... ok [INFO] [stdout] test tests::test_custom_sbox_is_bijective ... ok [INFO] [stdout] test tests::test_custom_sbox_key_sensitivity ... ok [INFO] [stdout] test tests::test_custom_sbox_no_fixed_points ... ok [INFO] [stdout] test tests::test_custom_sbox_inverse_correctness ... ok [INFO] [stdout] test tests::test_custom_sbox_nonlinearity ... ok [INFO] [stdout] test tests::test_custom_sbox_bijective ... ok [INFO] [stdout] test tests::test_custom_sbox_differential_uniformity ... ok [INFO] [stdout] test tests::test_custom_sbox_performance ... ok [INFO] [stdout] test tests::test_custom_sbox_bit_independence_criterion ... ok [INFO] [stdout] test tests::test_hmac_integrity ... ok [INFO] [stdout] test tests::test_custom_sbox_linear_approximation_table ... ok [INFO] [stdout] test tests::test_encryption_decryption ... ok [INFO] [stdout] test tests::test_key_generation ... ok [INFO] [stdout] test tests::test_hmac_tag_corruption ... ok [INFO] [stdout] test tests::test_encrypt_decrypt ... ok [INFO] [stdout] test tests::test_chosen_plaintext_attack ... ok [INFO] [stdout] test tests::test_data_corruption_resistance ... ok [INFO] [stdout] test tests::test_empty_input ... ok [INFO] [stdout] test tests::test_decrypt_too_short_error ... ok [INFO] [stdout] test tests::test_header_version_algid_validation ... ok [INFO] [stdout] test tests::test_different_data_types ... ok [INFO] [stdout] test tests::test_nonce_reuse_resistance ... ok [INFO] [stdout] test tests::test_bit_flipping_attack ... ok [INFO] [stdout] test tests::test_repeated_encryption_differs ... ok [INFO] [stdout] test tests::test_key_reuse_resistance ... ok [INFO] [stdout] test tests::test_known_plaintext_attack ... ok [INFO] [stdout] test tests::test_tamper_resistance ... ok [INFO] [stdout] test tests::test_salt_reuse ... ok [INFO] [stdout] test tests::test_unicode_roundtrip ... ok [INFO] [stdout] test tests::test_collision_resistance ... ok [INFO] [stdout] test tests::test_timing_attack_resistance ... FAILED [INFO] [stdout] test tests::test_wrong_key_rejects ... ok [INFO] [stdout] test tests::test_round_key_edge_counts ... ok [INFO] [stdout] test tests::test_large_data ... ok [INFO] [stdout] test tests::test_timing_leak_resistance ... ok [INFO] [stdout] test tests::test_chunk_boundary_lengths_roundtrip ... ok [INFO] [stdout] test tests::test_randomized_roundtrip ... ok [INFO] [stdout] test tests::test_brute_force_attack ... ok [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] [INFO] [stdout] ---- tests::test_timing_attack_resistance stdout ---- [INFO] [stdout] Encrypting 4 rounds [INFO] [stdout] Round 1 [INFO] [stdout] - Building new cipher cache [INFO] [stdout] - Cache building total: 677.407687ms [INFO] [stdout] Core encryption: 1.85313ms [INFO] [stdout] Bit shift: 9.39µs [INFO] [stdout] Round 2 [INFO] [stdout] - Building new cipher cache [INFO] [stdout] - Cache building total: 640.246171ms [INFO] [stdout] Core encryption: 970.91µs [INFO] [stdout] Bit shift: 8.19µs [INFO] [stdout] Round 3 [INFO] [stdout] - Building new cipher cache [INFO] [stdout] - Cache building total: 1.276754894s [INFO] [stdout] Core encryption: 218.38µs [INFO] [stdout] Bit shift: 2.03µs [INFO] [stdout] Round 4 [INFO] [stdout] - Building new cipher cache [INFO] [stdout] - Cache building total: 898.633818ms [INFO] [stdout] Core encryption: 11.989266ms [INFO] [stdout] Bit shift: 5.57µs [INFO] [stdout] Encrypting 4 rounds [INFO] [stdout] Round 1 [INFO] [stdout] - Building new cipher cache [INFO] [stdout] - Cache building total: 1.410402166s [INFO] [stdout] Core encryption: 30.707529ms [INFO] [stdout] Bit shift: 5.31µs [INFO] [stdout] Round 2 [INFO] [stdout] - Building new cipher cache [INFO] [stdout] - Cache building total: 342.352667ms [INFO] [stdout] Core encryption: 235.23µs [INFO] [stdout] Bit shift: 4.12µs [INFO] [stdout] Round 3 [INFO] [stdout] - Building new cipher cache [INFO] [stdout] - Cache building total: 348.552305ms [INFO] [stdout] Core encryption: 245.76µs [INFO] [stdout] Bit shift: 3.59µs [INFO] [stdout] Round 4 [INFO] [stdout] - Building new cipher cache [INFO] [stdout] - Cache building total: 1.264236388s [INFO] [stdout] Core encryption: 1.266409ms [INFO] [stdout] Bit shift: 5.62µs [INFO] [stdout] Decrypting 4 rounds [INFO] [stdout] Round 4 [INFO] [stdout] Bit unshift: 9.66µs [INFO] [stdout] - Building new cipher cache [INFO] [stdout] - Cache building total: 39.056766ms [INFO] [stdout] Core decryption: 361.484941ms [INFO] [stdout] Round 3 [INFO] [stdout] Bit unshift: 13.08µs [INFO] [stdout] - Building new cipher cache [INFO] [stdout] - Cache building total: 488.880285ms [INFO] [stdout] Core decryption: 448.86µs [INFO] [stdout] Round 2 [INFO] [stdout] Bit unshift: 8.67µs [INFO] [stdout] - Building new cipher cache [INFO] [stdout] - Cache building total: 333.809921ms [INFO] [stdout] Core decryption: 727.219µs [INFO] [stdout] Round 1 [INFO] [stdout] Bit unshift: 11.46µs [INFO] [stdout] - Building new cipher cache [INFO] [stdout] - Cache building total: 32.620268ms [INFO] [stdout] Core decryption: 4.142758ms [INFO] [stdout] Encryption time: 3.854782172s [INFO] [stdout] Decryption time: 1.502172223s [INFO] [stdout] [INFO] [stdout] thread 'tests::test_timing_attack_resistance' (76) panicked at src/lib.rs:335:9: [INFO] [stdout] Encryption must not be too slow [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x580d23161ab2 - std[840933623431fd06]::backtrace_rs::backtrace::libunwind::trace [INFO] [stdout] at /rustc/bd7d74411512a3dd3b35d2f699c51dd2557c7e7e/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9 [INFO] [stdout] 1: 0x580d23161ab2 - std[840933623431fd06]::backtrace_rs::backtrace::trace_unsynchronized:: [INFO] [stdout] at /rustc/bd7d74411512a3dd3b35d2f699c51dd2557c7e7e/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14 [INFO] [stdout] 2: 0x580d23161ab2 - std[840933623431fd06]::sys::backtrace::_print_fmt [INFO] [stdout] at /rustc/bd7d74411512a3dd3b35d2f699c51dd2557c7e7e/library/std/src/sys/backtrace.rs:74:9 [INFO] [stdout] 3: 0x580d23161ab2 - <::print::DisplayBacktrace as core[8f93d80df273fe75]::fmt::Display>::fmt [INFO] [stdout] at /rustc/bd7d74411512a3dd3b35d2f699c51dd2557c7e7e/library/std/src/sys/backtrace.rs:44:26 [INFO] [stdout] 4: 0x580d23176c1a - ::fmt [INFO] [stdout] at /rustc/bd7d74411512a3dd3b35d2f699c51dd2557c7e7e/library/core/src/fmt/rt.rs:152:76 [INFO] [stdout] 5: 0x580d23176c1a - core[8f93d80df273fe75]::fmt::write [INFO] [stdout] 6: 0x580d23166936 - std[840933623431fd06]::io::default_write_fmt::> [INFO] [stdout] at /rustc/bd7d74411512a3dd3b35d2f699c51dd2557c7e7e/library/std/src/io/mod.rs:639:11 [INFO] [stdout] 7: 0x580d23166936 - as std[840933623431fd06]::io::Write>::write_fmt [INFO] [stdout] at /rustc/bd7d74411512a3dd3b35d2f699c51dd2557c7e7e/library/std/src/io/mod.rs:1994:13 [INFO] [stdout] 8: 0x580d2313fc0f - ::print [INFO] [stdout] at /rustc/bd7d74411512a3dd3b35d2f699c51dd2557c7e7e/library/std/src/sys/backtrace.rs:47:9 [INFO] [stdout] 9: 0x580d2313fc0f - std[840933623431fd06]::panicking::default_hook::{closure#0} [INFO] [stdout] at /rustc/bd7d74411512a3dd3b35d2f699c51dd2557c7e7e/library/std/src/panicking.rs:292:27 [INFO] [stdout] 10: 0x580d23159ba9 - std[840933623431fd06]::panicking::default_hook [INFO] [stdout] at /rustc/bd7d74411512a3dd3b35d2f699c51dd2557c7e7e/library/std/src/panicking.rs:316:9 [INFO] [stdout] 11: 0x580d22fefc0e - core[8f93d80df273fe75]::ops::function::Fn<(&'a std[840933623431fd06]::panic::PanicHookInfo<'b>,), Output = ()> + core[8f93d80df273fe75]::marker::Sync + core[8f93d80df273fe75]::marker::Send> as core[8f93d80df273fe75]::ops::function::Fn<(&std[840933623431fd06]::panic::PanicHookInfo,)>>::call [INFO] [stdout] at /rustc/bd7d74411512a3dd3b35d2f699c51dd2557c7e7e/library/alloc/src/boxed.rs:2220:9 [INFO] [stdout] 12: 0x580d22fefc0e - test[2f1252070343a163]::test_main_with_exit_callback::::{closure#0} [INFO] [stdout] at /rustc/bd7d74411512a3dd3b35d2f699c51dd2557c7e7e/library/test/src/lib.rs:145:21 [INFO] [stdout] 13: 0x580d23159e12 - core[8f93d80df273fe75]::ops::function::Fn<(&'a std[840933623431fd06]::panic::PanicHookInfo<'b>,), Output = ()> + core[8f93d80df273fe75]::marker::Sync + core[8f93d80df273fe75]::marker::Send> as core[8f93d80df273fe75]::ops::function::Fn<(&std[840933623431fd06]::panic::PanicHookInfo,)>>::call [INFO] [stdout] at /rustc/bd7d74411512a3dd3b35d2f699c51dd2557c7e7e/library/alloc/src/boxed.rs:2220:9 [INFO] [stdout] 14: 0x580d23159e12 - std[840933623431fd06]::panicking::panic_with_hook [INFO] [stdout] at /rustc/bd7d74411512a3dd3b35d2f699c51dd2557c7e7e/library/std/src/panicking.rs:833:13 [INFO] [stdout] 15: 0x580d2313fcfa - std[840933623431fd06]::panicking::panic_handler::{closure#0} [INFO] [stdout] at /rustc/bd7d74411512a3dd3b35d2f699c51dd2557c7e7e/library/std/src/panicking.rs:691:13 [INFO] [stdout] 16: 0x580d23137699 - std[840933623431fd06]::sys::backtrace::__rust_end_short_backtrace:: [INFO] [stdout] at /rustc/bd7d74411512a3dd3b35d2f699c51dd2557c7e7e/library/std/src/sys/backtrace.rs:182:18 [INFO] [stdout] 17: 0x580d23140b1d - __rustc[5b4a0c6d8cc4a177]::rust_begin_unwind [INFO] [stdout] at /rustc/bd7d74411512a3dd3b35d2f699c51dd2557c7e7e/library/std/src/panicking.rs:689:5 [INFO] [stdout] 18: 0x580d2317732c - core[8f93d80df273fe75]::panicking::panic_fmt [INFO] [stdout] at /rustc/bd7d74411512a3dd3b35d2f699c51dd2557c7e7e/library/core/src/panicking.rs:80:14 [INFO] [stdout] 19: 0x580d22f86f34 - horizon[c128f32c2ce3d50d]::tests::test_timing_attack_resistance [INFO] [stdout] at /opt/rustwide/workdir/src/lib.rs:335:9 [INFO] [stdout] 20: 0x580d22f7a677 - horizon[c128f32c2ce3d50d]::tests::test_timing_attack_resistance::{closure#0} [INFO] [stdout] at /opt/rustwide/workdir/src/lib.rs:305:39 [INFO] [stdout] 21: 0x580d22fbf5a6 - >::call_once [INFO] [stdout] at /rustc/bd7d74411512a3dd3b35d2f699c51dd2557c7e7e/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 22: 0x580d22fe3fbb - core[8f93d80df273fe75]::result::Result<(), alloc[40b2bae28975ac47]::string::String> as core[8f93d80df273fe75]::ops::function::FnOnce<()>>::call_once [INFO] [stdout] at /rustc/bd7d74411512a3dd3b35d2f699c51dd2557c7e7e/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 23: 0x580d22fe3fbb - test[2f1252070343a163]::__rust_begin_short_backtrace::, fn() -> core[8f93d80df273fe75]::result::Result<(), alloc[40b2bae28975ac47]::string::String>> [INFO] [stdout] at /rustc/bd7d74411512a3dd3b35d2f699c51dd2557c7e7e/library/test/src/lib.rs:663:18 [INFO] [stdout] 24: 0x580d22ff081a - test[2f1252070343a163]::run_test_in_process::{closure#0} [INFO] [stdout] at /rustc/bd7d74411512a3dd3b35d2f699c51dd2557c7e7e/library/test/src/lib.rs:686:74 [INFO] [stdout] 25: 0x580d22ff081a - as core[8f93d80df273fe75]::ops::function::FnOnce<()>>::call_once [INFO] [stdout] at /rustc/bd7d74411512a3dd3b35d2f699c51dd2557c7e7e/library/core/src/panic/unwind_safe.rs:274:9 [INFO] [stdout] 26: 0x580d22ff081a - std[840933623431fd06]::panicking::catch_unwind::do_call::, core[8f93d80df273fe75]::result::Result<(), alloc[40b2bae28975ac47]::string::String>> [INFO] [stdout] at /rustc/bd7d74411512a3dd3b35d2f699c51dd2557c7e7e/library/std/src/panicking.rs:581:40 [INFO] [stdout] 27: 0x580d22ff081a - std[840933623431fd06]::panicking::catch_unwind::, core[8f93d80df273fe75]::panic::unwind_safe::AssertUnwindSafe> [INFO] [stdout] at /rustc/bd7d74411512a3dd3b35d2f699c51dd2557c7e7e/library/std/src/panicking.rs:544:19 [INFO] [stdout] 28: 0x580d22ff081a - std[840933623431fd06]::panic::catch_unwind::, core[8f93d80df273fe75]::result::Result<(), alloc[40b2bae28975ac47]::string::String>> [INFO] [stdout] at /rustc/bd7d74411512a3dd3b35d2f699c51dd2557c7e7e/library/std/src/panic.rs:359:14 [INFO] [stdout] 29: 0x580d22ff081a - test[2f1252070343a163]::run_test_in_process [INFO] [stdout] at /rustc/bd7d74411512a3dd3b35d2f699c51dd2557c7e7e/library/test/src/lib.rs:686:27 [INFO] [stdout] 30: 0x580d22ff081a - test[2f1252070343a163]::run_test::{closure#0} [INFO] [stdout] at /rustc/bd7d74411512a3dd3b35d2f699c51dd2557c7e7e/library/test/src/lib.rs:607:43 [INFO] [stdout] 31: 0x580d22feace4 - test[2f1252070343a163]::run_test::{closure#1} [INFO] [stdout] at /rustc/bd7d74411512a3dd3b35d2f699c51dd2557c7e7e/library/test/src/lib.rs:637:41 [INFO] [stdout] 32: 0x580d22feace4 - std[840933623431fd06]::sys::backtrace::__rust_begin_short_backtrace:: [INFO] [stdout] at /rustc/bd7d74411512a3dd3b35d2f699c51dd2557c7e7e/library/std/src/sys/backtrace.rs:166:18 [INFO] [stdout] 33: 0x580d22ff3312 - std[840933623431fd06]::thread::lifecycle::spawn_unchecked::::{closure#1}::{closure#0} [INFO] [stdout] at /rustc/bd7d74411512a3dd3b35d2f699c51dd2557c7e7e/library/std/src/thread/lifecycle.rs:91:13 [INFO] [stdout] 34: 0x580d22ff3312 - ::{closure#1}::{closure#0}> as core[8f93d80df273fe75]::ops::function::FnOnce<()>>::call_once [INFO] [stdout] at /rustc/bd7d74411512a3dd3b35d2f699c51dd2557c7e7e/library/core/src/panic/unwind_safe.rs:274:9 [INFO] [stdout] 35: 0x580d22ff3312 - std[840933623431fd06]::panicking::catch_unwind::do_call::::{closure#1}::{closure#0}>, ()> [INFO] [stdout] at /rustc/bd7d74411512a3dd3b35d2f699c51dd2557c7e7e/library/std/src/panicking.rs:581:40 [INFO] [stdout] 36: 0x580d22ff3312 - std[840933623431fd06]::panicking::catch_unwind::<(), core[8f93d80df273fe75]::panic::unwind_safe::AssertUnwindSafe::{closure#1}::{closure#0}>> [INFO] [stdout] at /rustc/bd7d74411512a3dd3b35d2f699c51dd2557c7e7e/library/std/src/panicking.rs:544:19 [INFO] [stdout] 37: 0x580d22ff3312 - std[840933623431fd06]::panic::catch_unwind::::{closure#1}::{closure#0}>, ()> [INFO] [stdout] at /rustc/bd7d74411512a3dd3b35d2f699c51dd2557c7e7e/library/std/src/panic.rs:359:14 [INFO] [stdout] 38: 0x580d22ff3312 - std[840933623431fd06]::thread::lifecycle::spawn_unchecked::::{closure#1} [INFO] [stdout] at /rustc/bd7d74411512a3dd3b35d2f699c51dd2557c7e7e/library/std/src/thread/lifecycle.rs:89:26 [INFO] [stdout] 39: 0x580d22ff3312 - ::{closure#1} as core[8f93d80df273fe75]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0} [INFO] [stdout] at /rustc/bd7d74411512a3dd3b35d2f699c51dd2557c7e7e/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 40: 0x580d2316131f - + core[8f93d80df273fe75]::marker::Send> as core[8f93d80df273fe75]::ops::function::FnOnce<()>>::call_once [INFO] [stdout] at /rustc/bd7d74411512a3dd3b35d2f699c51dd2557c7e7e/library/alloc/src/boxed.rs:2206:9 [INFO] [stdout] 41: 0x580d2316131f - ::new::thread_start [INFO] [stdout] at /rustc/bd7d74411512a3dd3b35d2f699c51dd2557c7e7e/library/std/src/sys/thread/unix.rs:119:17 [INFO] [stdout] 42: 0x7219d7c78aa4 - [INFO] [stdout] 43: 0x7219d7d05a64 - clone [INFO] [stdout] 44: 0x0 - [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] tests::test_timing_attack_resistance [INFO] [stdout] [INFO] [stdout] test result: FAILED. 38 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 58.56s [INFO] [stdout] [INFO] [stderr] error: test failed, to rerun pass `--lib` [INFO] running `Command { std: "docker" "inspect" "178aeda58221ec00e65360ba7d92b8191b16db60f321ce8b361bb2b3bd6213bd", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "178aeda58221ec00e65360ba7d92b8191b16db60f321ce8b361bb2b3bd6213bd", kill_on_drop: false }` [INFO] [stdout] 178aeda58221ec00e65360ba7d92b8191b16db60f321ce8b361bb2b3bd6213bd