[INFO] fetching crate pingora-timeout 0.2.0... [INFO] testing pingora-timeout-0.2.0 against master#595316b4006932405a63862d8fe65f71a6356293 for pr-128254 [INFO] extracting crate pingora-timeout 0.2.0 into /workspace/builds/worker-1-tc1/source [INFO] validating manifest of crates.io crate pingora-timeout 0.2.0 on toolchain 595316b4006932405a63862d8fe65f71a6356293 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+595316b4006932405a63862d8fe65f71a6356293" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] started tweaking crates.io crate pingora-timeout 0.2.0 [INFO] finished tweaking crates.io crate pingora-timeout 0.2.0 [INFO] tweaked toml for crates.io crate pingora-timeout 0.2.0 written to /workspace/builds/worker-1-tc1/source/Cargo.toml [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+595316b4006932405a63862d8fe65f71a6356293" "generate-lockfile" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Updating crates.io index [INFO] [stderr] Locking 50 packages to latest compatible versions [INFO] [stderr] Adding addr2line v0.22.0 (latest: v0.24.1) [INFO] [stderr] Adding gimli v0.29.0 (latest: v0.31.0) [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+595316b4006932405a63862d8fe65f71a6356293" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [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:923055f121b5182466d55868a8b05e67af8ba4a3a3f6bad814e953ca3cd3ac2a" "/opt/rustwide/cargo-home/bin/cargo" "+595316b4006932405a63862d8fe65f71a6356293" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 5f4297b1576f335f3f6848b934131d14a08a7a23bca7bd8dc8ed2556b44f760a [INFO] running `Command { std: "docker" "start" "-a" "5f4297b1576f335f3f6848b934131d14a08a7a23bca7bd8dc8ed2556b44f760a", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "5f4297b1576f335f3f6848b934131d14a08a7a23bca7bd8dc8ed2556b44f760a", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "5f4297b1576f335f3f6848b934131d14a08a7a23bca7bd8dc8ed2556b44f760a", kill_on_drop: false }` [INFO] [stdout] 5f4297b1576f335f3f6848b934131d14a08a7a23bca7bd8dc8ed2556b44f760a [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:923055f121b5182466d55868a8b05e67af8ba4a3a3f6bad814e953ca3cd3ac2a" "/opt/rustwide/cargo-home/bin/cargo" "+595316b4006932405a63862d8fe65f71a6356293" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 3f013e6b822e206fea8a4b7a68d02f2998b372cf804ac3a4095648382f225158 [INFO] running `Command { std: "docker" "start" "-a" "3f013e6b822e206fea8a4b7a68d02f2998b372cf804ac3a4095648382f225158", kill_on_drop: false }` [INFO] [stderr] Compiling lock_api v0.4.12 [INFO] [stderr] Compiling parking_lot_core v0.9.10 [INFO] [stderr] Compiling smallvec v1.13.2 [INFO] [stderr] Compiling scopeguard v1.2.0 [INFO] [stderr] Compiling thread_local v1.1.8 [INFO] [stderr] Compiling futures-macro v0.3.30 [INFO] [stderr] Compiling tokio-macros v2.4.0 [INFO] [stderr] Compiling parking_lot v0.12.3 [INFO] [stderr] Compiling futures-util v0.3.30 [INFO] [stderr] Compiling tokio v1.39.2 [INFO] [stderr] Compiling futures-executor v0.3.30 [INFO] [stderr] Compiling futures v0.3.30 [INFO] [stderr] Compiling pingora-timeout v0.2.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished `dev` profile [unoptimized + debuginfo] target(s) in 3.68s [INFO] running `Command { std: "docker" "inspect" "3f013e6b822e206fea8a4b7a68d02f2998b372cf804ac3a4095648382f225158", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "3f013e6b822e206fea8a4b7a68d02f2998b372cf804ac3a4095648382f225158", kill_on_drop: false }` [INFO] [stdout] 3f013e6b822e206fea8a4b7a68d02f2998b372cf804ac3a4095648382f225158 [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:923055f121b5182466d55868a8b05e67af8ba4a3a3f6bad814e953ca3cd3ac2a" "/opt/rustwide/cargo-home/bin/cargo" "+595316b4006932405a63862d8fe65f71a6356293" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 5d7cdcfe1a294a543651c47a103bc0e9e719671f859ed2209483dfe8c5c19d1c [INFO] running `Command { std: "docker" "start" "-a" "5d7cdcfe1a294a543651c47a103bc0e9e719671f859ed2209483dfe8c5c19d1c", kill_on_drop: false }` [INFO] [stderr] Compiling bencher v0.1.5 [INFO] [stderr] Compiling pingora-timeout v0.2.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.89s [INFO] running `Command { std: "docker" "inspect" "5d7cdcfe1a294a543651c47a103bc0e9e719671f859ed2209483dfe8c5c19d1c", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "5d7cdcfe1a294a543651c47a103bc0e9e719671f859ed2209483dfe8c5c19d1c", kill_on_drop: false }` [INFO] [stdout] 5d7cdcfe1a294a543651c47a103bc0e9e719671f859ed2209483dfe8c5c19d1c [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:923055f121b5182466d55868a8b05e67af8ba4a3a3f6bad814e953ca3cd3ac2a" "/opt/rustwide/cargo-home/bin/cargo" "+595316b4006932405a63862d8fe65f71a6356293" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 79ea8673c8f96dc041f3b7be1ad4c381b85ac93fb59a6c54fb1d77e75f626ce3 [INFO] running `Command { std: "docker" "start" "-a" "79ea8673c8f96dc041f3b7be1ad4c381b85ac93fb59a6c54fb1d77e75f626ce3", kill_on_drop: false }` [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.03s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/pingora_timeout-a5614a76658da9ed) [INFO] [stdout] [INFO] [stdout] running 13 tests [INFO] [stdout] test fast_timeout::tests::test_instantly_return ... ok [INFO] [stdout] test timer::tests::test_round ... ok [INFO] [stdout] test tests::test_instantly_return ... ok [INFO] [stdout] test timer::tests::test_time ... ok [INFO] [stdout] test timer::tests::test_timer_manager_start_check ... ok [INFO] [stdout] test fast_timeout::tests::test_delayed_return ... ok [INFO] [stdout] test tests::test_delayed_return ... ok [INFO] [stdout] test fast_timeout::tests::test_timeout ... ok [INFO] [stdout] test tests::test_timeout ... ok [INFO] [stdout] test timer::tests::test_timer_manager ... ok [INFO] [stdout] test fast_timeout::tests::test_sleep ... ok [INFO] [stdout] test timer::tests::test_timer_manager_pause ... ok [INFO] [stdout] test timer::tests::test_timer_manager_watchdog ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 13 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 3.00s [INFO] [stdout] [INFO] [stderr] Doc-tests pingora_timeout [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" "79ea8673c8f96dc041f3b7be1ad4c381b85ac93fb59a6c54fb1d77e75f626ce3", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "79ea8673c8f96dc041f3b7be1ad4c381b85ac93fb59a6c54fb1d77e75f626ce3", kill_on_drop: false }` [INFO] [stdout] 79ea8673c8f96dc041f3b7be1ad4c381b85ac93fb59a6c54fb1d77e75f626ce3 [INFO] testing pingora-timeout-0.2.0 against try#5e89635d7bf1d3033c2eb20378b7b3cbe2dd1a9b for pr-128254 [INFO] extracting crate pingora-timeout 0.2.0 into /workspace/builds/worker-1-tc2/source [INFO] validating manifest of crates.io crate pingora-timeout 0.2.0 on toolchain 5e89635d7bf1d3033c2eb20378b7b3cbe2dd1a9b [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+5e89635d7bf1d3033c2eb20378b7b3cbe2dd1a9b" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] started tweaking crates.io crate pingora-timeout 0.2.0 [INFO] finished tweaking crates.io crate pingora-timeout 0.2.0 [INFO] tweaked toml for crates.io crate pingora-timeout 0.2.0 written to /workspace/builds/worker-1-tc2/source/Cargo.toml [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+5e89635d7bf1d3033c2eb20378b7b3cbe2dd1a9b" "generate-lockfile" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Updating crates.io index [INFO] [stderr] Locking 50 packages to latest compatible versions [INFO] [stderr] Adding addr2line v0.22.0 (latest: v0.24.1) [INFO] [stderr] Adding gimli v0.29.0 (latest: v0.31.0) [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+5e89635d7bf1d3033c2eb20378b7b3cbe2dd1a9b" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-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:923055f121b5182466d55868a8b05e67af8ba4a3a3f6bad814e953ca3cd3ac2a" "/opt/rustwide/cargo-home/bin/cargo" "+5e89635d7bf1d3033c2eb20378b7b3cbe2dd1a9b" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 3a972ff5555c30622de642130abcdf7cc501652d342437f82d37e338d4313bdc [INFO] running `Command { std: "docker" "start" "-a" "3a972ff5555c30622de642130abcdf7cc501652d342437f82d37e338d4313bdc", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "3a972ff5555c30622de642130abcdf7cc501652d342437f82d37e338d4313bdc", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "3a972ff5555c30622de642130abcdf7cc501652d342437f82d37e338d4313bdc", kill_on_drop: false }` [INFO] [stdout] 3a972ff5555c30622de642130abcdf7cc501652d342437f82d37e338d4313bdc [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-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:923055f121b5182466d55868a8b05e67af8ba4a3a3f6bad814e953ca3cd3ac2a" "/opt/rustwide/cargo-home/bin/cargo" "+5e89635d7bf1d3033c2eb20378b7b3cbe2dd1a9b" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 2daf93fc539b94e49668fa86b03bd6a1b7936352833c5f715d2f4cbd494f8b47 [INFO] running `Command { std: "docker" "start" "-a" "2daf93fc539b94e49668fa86b03bd6a1b7936352833c5f715d2f4cbd494f8b47", kill_on_drop: false }` [INFO] [stderr] Compiling parking_lot_core v0.9.10 [INFO] [stderr] Compiling scopeguard v1.2.0 [INFO] [stderr] Compiling smallvec v1.13.2 [INFO] [stderr] Compiling lock_api v0.4.12 [INFO] [stderr] Compiling thread_local v1.1.8 [INFO] [stderr] Compiling futures-macro v0.3.30 [INFO] [stderr] Compiling tokio-macros v2.4.0 [INFO] [stderr] Compiling parking_lot v0.12.3 [INFO] [stderr] Compiling tokio v1.39.2 [INFO] [stderr] Compiling futures-util v0.3.30 [INFO] [stderr] Compiling futures-executor v0.3.30 [INFO] [stderr] Compiling futures v0.3.30 [INFO] [stderr] Compiling pingora-timeout v0.2.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished `dev` profile [unoptimized + debuginfo] target(s) in 3.33s [INFO] running `Command { std: "docker" "inspect" "2daf93fc539b94e49668fa86b03bd6a1b7936352833c5f715d2f4cbd494f8b47", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "2daf93fc539b94e49668fa86b03bd6a1b7936352833c5f715d2f4cbd494f8b47", kill_on_drop: false }` [INFO] [stdout] 2daf93fc539b94e49668fa86b03bd6a1b7936352833c5f715d2f4cbd494f8b47 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-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:923055f121b5182466d55868a8b05e67af8ba4a3a3f6bad814e953ca3cd3ac2a" "/opt/rustwide/cargo-home/bin/cargo" "+5e89635d7bf1d3033c2eb20378b7b3cbe2dd1a9b" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 7651d9a249f26ff1dde76e547af7f5c2d6d3cb3e9b7017d6fd04847ac35b0a94 [INFO] running `Command { std: "docker" "start" "-a" "7651d9a249f26ff1dde76e547af7f5c2d6d3cb3e9b7017d6fd04847ac35b0a94", kill_on_drop: false }` [INFO] [stderr] Compiling bencher v0.1.5 [INFO] [stderr] Compiling pingora-timeout v0.2.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.82s [INFO] running `Command { std: "docker" "inspect" "7651d9a249f26ff1dde76e547af7f5c2d6d3cb3e9b7017d6fd04847ac35b0a94", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "7651d9a249f26ff1dde76e547af7f5c2d6d3cb3e9b7017d6fd04847ac35b0a94", kill_on_drop: false }` [INFO] [stdout] 7651d9a249f26ff1dde76e547af7f5c2d6d3cb3e9b7017d6fd04847ac35b0a94 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-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:923055f121b5182466d55868a8b05e67af8ba4a3a3f6bad814e953ca3cd3ac2a" "/opt/rustwide/cargo-home/bin/cargo" "+5e89635d7bf1d3033c2eb20378b7b3cbe2dd1a9b" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 91db3bb1e7b02e8a15c9ecb1a9df007fa6cfa49f3d34efeaf2cbad062e3a7baf [INFO] running `Command { std: "docker" "start" "-a" "91db3bb1e7b02e8a15c9ecb1a9df007fa6cfa49f3d34efeaf2cbad062e3a7baf", kill_on_drop: false }` [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.03s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/pingora_timeout-a5614a76658da9ed) [INFO] [stdout] [INFO] [stdout] running 13 tests [INFO] [stdout] test fast_timeout::tests::test_instantly_return ... ok [INFO] [stdout] test tests::test_instantly_return ... ok [INFO] [stdout] test timer::tests::test_time ... ok [INFO] [stdout] test timer::tests::test_round ... ok [INFO] [stdout] test timer::tests::test_timer_manager_start_check ... ok [INFO] [stdout] test fast_timeout::tests::test_timeout ... ok [INFO] [stdout] test fast_timeout::tests::test_sleep ... ok [INFO] [stdout] test tests::test_timeout ... ok [INFO] [stdout] test fast_timeout::tests::test_delayed_return ... ok [INFO] [stdout] test tests::test_delayed_return ... ok [INFO] [stdout] test timer::tests::test_timer_manager ... ok [INFO] [stdout] test timer::tests::test_timer_manager_pause ... FAILED [INFO] [stdout] test timer::tests::test_timer_manager_watchdog ... ok [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] [INFO] [stdout] ---- timer::tests::test_timer_manager_pause stdout ---- [INFO] [stdout] thread 'timer::tests::test_timer_manager_pause' panicked at src/timer.rs:325:9: [INFO] [stdout] assertion `left == right` failed [INFO] [stdout] left: 1 [INFO] [stdout] right: 2 [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x565386c4e315 - std::backtrace_rs::backtrace::libunwind::trace::h68acc96dd29bfb25 [INFO] [stdout] at /rustc/5e89635d7bf1d3033c2eb20378b7b3cbe2dd1a9b/library/std/src/../../backtrace/src/backtrace/libunwind.rs:116:5 [INFO] [stdout] 1: 0x565386c4e315 - std::backtrace_rs::backtrace::trace_unsynchronized::hc833f810d39d5258 [INFO] [stdout] at /rustc/5e89635d7bf1d3033c2eb20378b7b3cbe2dd1a9b/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 [INFO] [stdout] 2: 0x565386c4e315 - std::sys::backtrace::_print_fmt::h5f23caa5b7652308 [INFO] [stdout] at /rustc/5e89635d7bf1d3033c2eb20378b7b3cbe2dd1a9b/library/std/src/sys/backtrace.rs:66:9 [INFO] [stdout] 3: 0x565386c4e315 - ::fmt::hc81f55a2b3efffd1 [INFO] [stdout] at /rustc/5e89635d7bf1d3033c2eb20378b7b3cbe2dd1a9b/library/std/src/sys/backtrace.rs:39:26 [INFO] [stdout] 4: 0x565386c75f5b - core::fmt::rt::Argument::fmt::h731d8ba6b9c446d6 [INFO] [stdout] at /rustc/5e89635d7bf1d3033c2eb20378b7b3cbe2dd1a9b/library/core/src/fmt/rt.rs:173:76 [INFO] [stdout] 5: 0x565386c75f5b - core::fmt::write::h6e5816e68b47f713 [INFO] [stdout] at /rustc/5e89635d7bf1d3033c2eb20378b7b3cbe2dd1a9b/library/core/src/fmt/mod.rs:1178:21 [INFO] [stdout] 6: 0x565386c4b62f - std::io::Write::write_fmt::h3726facc0aa8eb67 [INFO] [stdout] at /rustc/5e89635d7bf1d3033c2eb20378b7b3cbe2dd1a9b/library/std/src/io/mod.rs:1823:15 [INFO] [stdout] 7: 0x565386c4fb31 - std::sys::backtrace::BacktraceLock::print::hb98272574ac3b700 [INFO] [stdout] at /rustc/5e89635d7bf1d3033c2eb20378b7b3cbe2dd1a9b/library/std/src/sys/backtrace.rs:42:9 [INFO] [stdout] 8: 0x565386c4fb31 - std::panicking::default_hook::{{closure}}::h979aa01ca70f7f8f [INFO] [stdout] at /rustc/5e89635d7bf1d3033c2eb20378b7b3cbe2dd1a9b/library/std/src/panicking.rs:266:22 [INFO] [stdout] 9: 0x565386c4f6d8 - std::panicking::default_hook::h17e52f086133e6fd [INFO] [stdout] at /rustc/5e89635d7bf1d3033c2eb20378b7b3cbe2dd1a9b/library/std/src/panicking.rs:290:9 [INFO] [stdout] 10: 0x565386b9b3fa - as core::ops::function::Fn>::call::h4e1615f02d9f709a [INFO] [stdout] at /rustc/5e89635d7bf1d3033c2eb20378b7b3cbe2dd1a9b/library/alloc/src/boxed.rs:2162:9 [INFO] [stdout] 11: 0x565386b9b3fa - test::test_main::{{closure}}::hc8df444856fcb257 [INFO] [stdout] at /rustc/5e89635d7bf1d3033c2eb20378b7b3cbe2dd1a9b/library/test/src/lib.rs:132:21 [INFO] [stdout] 12: 0x565386c5043f - as core::ops::function::Fn>::call::hac606c4d68b970a6 [INFO] [stdout] at /rustc/5e89635d7bf1d3033c2eb20378b7b3cbe2dd1a9b/library/alloc/src/boxed.rs:2162:9 [INFO] [stdout] 13: 0x565386c5043f - std::panicking::rust_panic_with_hook::h694bfcec6451de5a [INFO] [stdout] at /rustc/5e89635d7bf1d3033c2eb20378b7b3cbe2dd1a9b/library/std/src/panicking.rs:805:13 [INFO] [stdout] 14: 0x565386c50067 - std::panicking::begin_panic_handler::{{closure}}::hbd9f22f307951c96 [INFO] [stdout] at /rustc/5e89635d7bf1d3033c2eb20378b7b3cbe2dd1a9b/library/std/src/panicking.rs:671:13 [INFO] [stdout] 15: 0x565386c4e7d9 - std::sys::backtrace::__rust_end_short_backtrace::h6e4502eb3c3e7834 [INFO] [stdout] at /rustc/5e89635d7bf1d3033c2eb20378b7b3cbe2dd1a9b/library/std/src/sys/backtrace.rs:170:18 [INFO] [stdout] 16: 0x565386c4fcf4 - rust_begin_unwind [INFO] [stdout] at /rustc/5e89635d7bf1d3033c2eb20378b7b3cbe2dd1a9b/library/std/src/panicking.rs:662:5 [INFO] [stdout] 17: 0x565386c742e3 - core::panicking::panic_fmt::ha6b90685af50061f [INFO] [stdout] at /rustc/5e89635d7bf1d3033c2eb20378b7b3cbe2dd1a9b/library/core/src/panicking.rs:74:14 [INFO] [stdout] 18: 0x565386c7470e - core::panicking::assert_failed_inner::h04f590ce609aabea [INFO] [stdout] at /rustc/5e89635d7bf1d3033c2eb20378b7b3cbe2dd1a9b/library/core/src/panicking.rs:412:17 [INFO] [stdout] 19: 0x565386ba579e - core::panicking::assert_failed::h0795ea0f103a0e42 [INFO] [stdout] at /rustc/5e89635d7bf1d3033c2eb20378b7b3cbe2dd1a9b/library/core/src/panicking.rs:367:5 [INFO] [stdout] 20: 0x565386b6010e - pingora_timeout::timer::tests::test_timer_manager_pause::{{closure}}::hec47f9a1f6072722 [INFO] [stdout] at /opt/rustwide/workdir/src/timer.rs:325:9 [INFO] [stdout] 21: 0x565386b5bcf2 - as core::future::future::Future>::poll::h62e1e79d6f9e5617 [INFO] [stdout] at /rustc/5e89635d7bf1d3033c2eb20378b7b3cbe2dd1a9b/library/core/src/future/future.rs:123:9 [INFO] [stdout] 22: 0x565386b5bc2d - as core::future::future::Future>::poll::h038f372bc05d6908 [INFO] [stdout] at /rustc/5e89635d7bf1d3033c2eb20378b7b3cbe2dd1a9b/library/core/src/future/future.rs:123:9 [INFO] [stdout] 23: 0x565386b4b61f - tokio::runtime::scheduler::current_thread::CoreGuard::block_on::{{closure}}::{{closure}}::{{closure}}::h525dc221bf82716e [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.39.2/src/runtime/scheduler/current_thread/mod.rs:673:57 [INFO] [stdout] 24: 0x565386b4b565 - tokio::runtime::coop::with_budget::hfaf1d3ad527aaea1 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.39.2/src/runtime/coop.rs:107:5 [INFO] [stdout] 25: 0x565386b4b565 - tokio::runtime::coop::budget::hab51fb5d979352f8 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.39.2/src/runtime/coop.rs:73:5 [INFO] [stdout] 26: 0x565386b4b565 - tokio::runtime::scheduler::current_thread::CoreGuard::block_on::{{closure}}::{{closure}}::hd616a2a91f2cee24 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.39.2/src/runtime/scheduler/current_thread/mod.rs:673:25 [INFO] [stdout] 27: 0x565386b48be0 - tokio::runtime::scheduler::current_thread::Context::enter::h22fbb426012bc176 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.39.2/src/runtime/scheduler/current_thread/mod.rs:412:19 [INFO] [stdout] 28: 0x565386b4ac2b - tokio::runtime::scheduler::current_thread::CoreGuard::block_on::{{closure}}::h5ff53dd7b5277ffb [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.39.2/src/runtime/scheduler/current_thread/mod.rs:672:36 [INFO] [stdout] 29: 0x565386b4a0a4 - tokio::runtime::scheduler::current_thread::CoreGuard::enter::{{closure}}::h9868acbfb49a8047 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.39.2/src/runtime/scheduler/current_thread/mod.rs:751:68 [INFO] [stdout] 30: 0x565386b4d35b - tokio::runtime::context::scoped::Scoped::set::h82a9be483ea90a12 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.39.2/src/runtime/context/scoped.rs:40:9 [INFO] [stdout] 31: 0x565386b5d1d9 - tokio::runtime::context::set_scheduler::{{closure}}::h5cb786357cf1d6e7 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.39.2/src/runtime/context.rs:180:26 [INFO] [stdout] 32: 0x565386b4c24a - std::thread::local::LocalKey::try_with::h90cdca5f1d319ed6 [INFO] [stdout] at /rustc/5e89635d7bf1d3033c2eb20378b7b3cbe2dd1a9b/library/std/src/thread/local.rs:283:12 [INFO] [stdout] 33: 0x565386b4b91a - std::thread::local::LocalKey::with::h93b20464be9b6f3e [INFO] [stdout] at /rustc/5e89635d7bf1d3033c2eb20378b7b3cbe2dd1a9b/library/std/src/thread/local.rs:260:9 [INFO] [stdout] 34: 0x565386b5d18d - tokio::runtime::context::set_scheduler::h995243c19c5af911 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.39.2/src/runtime/context.rs:180:9 [INFO] [stdout] 35: 0x565386b49a30 - tokio::runtime::scheduler::current_thread::CoreGuard::enter::h4a81d7c053bf563e [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.39.2/src/runtime/scheduler/current_thread/mod.rs:751:27 [INFO] [stdout] 36: 0x565386b4a143 - tokio::runtime::scheduler::current_thread::CoreGuard::block_on::hf3c0a1c895feb518 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.39.2/src/runtime/scheduler/current_thread/mod.rs:660:19 [INFO] [stdout] 37: 0x565386b4837a - tokio::runtime::scheduler::current_thread::CurrentThread::block_on::{{closure}}::hdf31ac0324105076 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.39.2/src/runtime/scheduler/current_thread/mod.rs:180:28 [INFO] [stdout] 38: 0x565386b5a896 - tokio::runtime::context::runtime::enter_runtime::h277900219ad255c5 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.39.2/src/runtime/context/runtime.rs:65:16 [INFO] [stdout] 39: 0x565386b47bf1 - tokio::runtime::scheduler::current_thread::CurrentThread::block_on::h205a7f0562185fc4 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.39.2/src/runtime/scheduler/current_thread/mod.rs:168:9 [INFO] [stdout] 40: 0x565386b4d48d - tokio::runtime::runtime::Runtime::block_on_inner::h78a4379b3f8ffb24 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.39.2/src/runtime/runtime.rs:361:47 [INFO] [stdout] 41: 0x565386b4d6b5 - tokio::runtime::runtime::Runtime::block_on::h9f98de69bed453ff [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.39.2/src/runtime/runtime.rs:335:13 [INFO] [stdout] 42: 0x565386b5f86e - pingora_timeout::timer::tests::test_timer_manager_pause::he7ef6a2ea51e6a89 [INFO] [stdout] at /opt/rustwide/workdir/src/timer.rs:325:9 [INFO] [stdout] 43: 0x565386b5f717 - pingora_timeout::timer::tests::test_timer_manager_pause::{{closure}}::h3281a521b16ba039 [INFO] [stdout] at /opt/rustwide/workdir/src/timer.rs:307:40 [INFO] [stdout] 44: 0x565386b461a6 - core::ops::function::FnOnce::call_once::h3a7411a33e8ca035 [INFO] [stdout] at /rustc/5e89635d7bf1d3033c2eb20378b7b3cbe2dd1a9b/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 45: 0x565386b9ff7b - core::ops::function::FnOnce::call_once::h321fab3f659b8f1c [INFO] [stdout] at /rustc/5e89635d7bf1d3033c2eb20378b7b3cbe2dd1a9b/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 46: 0x565386b9ff7b - test::__rust_begin_short_backtrace::h7bcdd7ef45d5d11d [INFO] [stdout] at /rustc/5e89635d7bf1d3033c2eb20378b7b3cbe2dd1a9b/library/test/src/lib.rs:620:18 [INFO] [stdout] 47: 0x565386b9f795 - test::run_test_in_process::{{closure}}::h7e5c4c77b843c962 [INFO] [stdout] at /rustc/5e89635d7bf1d3033c2eb20378b7b3cbe2dd1a9b/library/test/src/lib.rs:643:60 [INFO] [stdout] 48: 0x565386b9f795 - as core::ops::function::FnOnce<()>>::call_once::h9465365bda0c96e1 [INFO] [stdout] at /rustc/5e89635d7bf1d3033c2eb20378b7b3cbe2dd1a9b/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 49: 0x565386b9f795 - std::panicking::try::do_call::h464859d60fe36ac8 [INFO] [stdout] at /rustc/5e89635d7bf1d3033c2eb20378b7b3cbe2dd1a9b/library/std/src/panicking.rs:554:40 [INFO] [stdout] 50: 0x565386b9f795 - std::panicking::try::h10bb685f1ad0ff38 [INFO] [stdout] at /rustc/5e89635d7bf1d3033c2eb20378b7b3cbe2dd1a9b/library/std/src/panicking.rs:518:19 [INFO] [stdout] 51: 0x565386b9f795 - std::panic::catch_unwind::hf3392dbe1556c5c7 [INFO] [stdout] at /rustc/5e89635d7bf1d3033c2eb20378b7b3cbe2dd1a9b/library/std/src/panic.rs:345:14 [INFO] [stdout] 52: 0x565386b9f795 - test::run_test_in_process::hc7c0ab6dab190e49 [INFO] [stdout] at /rustc/5e89635d7bf1d3033c2eb20378b7b3cbe2dd1a9b/library/test/src/lib.rs:643:27 [INFO] [stdout] 53: 0x565386b9f795 - test::run_test::{{closure}}::hdb2183b5de946a08 [INFO] [stdout] at /rustc/5e89635d7bf1d3033c2eb20378b7b3cbe2dd1a9b/library/test/src/lib.rs:564:43 [INFO] [stdout] 54: 0x565386b65064 - test::run_test::{{closure}}::h8dcd9e4e5e3fbe5f [INFO] [stdout] at /rustc/5e89635d7bf1d3033c2eb20378b7b3cbe2dd1a9b/library/test/src/lib.rs:594:41 [INFO] [stdout] 55: 0x565386b65064 - std::sys::backtrace::__rust_begin_short_backtrace::h41bf17e9b9ace8bc [INFO] [stdout] at /rustc/5e89635d7bf1d3033c2eb20378b7b3cbe2dd1a9b/library/std/src/sys/backtrace.rs:154:18 [INFO] [stdout] 56: 0x565386b68832 - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::h63bd96fcdd52032f [INFO] [stdout] at /rustc/5e89635d7bf1d3033c2eb20378b7b3cbe2dd1a9b/library/std/src/thread/mod.rs:524:17 [INFO] [stdout] 57: 0x565386b68832 - as core::ops::function::FnOnce<()>>::call_once::ha8ff5ca2e19c8b3f [INFO] [stdout] at /rustc/5e89635d7bf1d3033c2eb20378b7b3cbe2dd1a9b/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 58: 0x565386b68832 - std::panicking::try::do_call::h2c72967242944c46 [INFO] [stdout] at /rustc/5e89635d7bf1d3033c2eb20378b7b3cbe2dd1a9b/library/std/src/panicking.rs:554:40 [INFO] [stdout] 59: 0x565386b68832 - std::panicking::try::h84beb938f6030c53 [INFO] [stdout] at /rustc/5e89635d7bf1d3033c2eb20378b7b3cbe2dd1a9b/library/std/src/panicking.rs:518:19 [INFO] [stdout] 60: 0x565386b68832 - std::panic::catch_unwind::hca4383a7a644e749 [INFO] [stdout] at /rustc/5e89635d7bf1d3033c2eb20378b7b3cbe2dd1a9b/library/std/src/panic.rs:345:14 [INFO] [stdout] 61: 0x565386b68832 - std::thread::Builder::spawn_unchecked_::{{closure}}::h88915884a74f8e2a [INFO] [stdout] at /rustc/5e89635d7bf1d3033c2eb20378b7b3cbe2dd1a9b/library/std/src/thread/mod.rs:523:30 [INFO] [stdout] 62: 0x565386b68832 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h4c032c9efdb7212a [INFO] [stdout] at /rustc/5e89635d7bf1d3033c2eb20378b7b3cbe2dd1a9b/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 63: 0x565386c546ab - as core::ops::function::FnOnce>::call_once::h809cb44187f4e360 [INFO] [stdout] at /rustc/5e89635d7bf1d3033c2eb20378b7b3cbe2dd1a9b/library/alloc/src/boxed.rs:2148:9 [INFO] [stdout] 64: 0x565386c546ab - as core::ops::function::FnOnce>::call_once::h670f08f0ab61db1b [INFO] [stdout] at /rustc/5e89635d7bf1d3033c2eb20378b7b3cbe2dd1a9b/library/alloc/src/boxed.rs:2148:9 [INFO] [stdout] 65: 0x565386c546ab - std::sys::pal::unix::thread::Thread::new::thread_start::hee2b0396d7ed5bcb [INFO] [stdout] at /rustc/5e89635d7bf1d3033c2eb20378b7b3cbe2dd1a9b/library/std/src/sys/pal/unix/thread.rs:105:17 [INFO] [stdout] 66: 0x7f9157f82ac3 - [INFO] [stdout] 67: 0x7f9158013a04 - clone [INFO] [stdout] 68: 0x0 - [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] timer::tests::test_timer_manager_pause [INFO] [stdout] [INFO] [stdout] test result: FAILED. 12 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 3.00s [INFO] [stdout] [INFO] [stderr] error: test failed, to rerun pass `--lib` [INFO] running `Command { std: "docker" "inspect" "91db3bb1e7b02e8a15c9ecb1a9df007fa6cfa49f3d34efeaf2cbad062e3a7baf", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "91db3bb1e7b02e8a15c9ecb1a9df007fa6cfa49f3d34efeaf2cbad062e3a7baf", kill_on_drop: false }` [INFO] [stdout] 91db3bb1e7b02e8a15c9ecb1a9df007fa6cfa49f3d34efeaf2cbad062e3a7baf