[INFO] fetching crate might_sleep 0.2.0...
[INFO] testing might_sleep-0.2.0 against 1.76.0 for beta-1.77-1
[INFO] extracting crate might_sleep 0.2.0 into /workspace/builds/worker-3-tc1/source
[INFO] validating manifest of crates.io crate might_sleep 0.2.0 on toolchain 1.76.0
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.76.0" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking crates.io crate might_sleep 0.2.0
[INFO] finished tweaking crates.io crate might_sleep 0.2.0
[INFO] tweaked toml for crates.io crate might_sleep 0.2.0 written to /workspace/builds/worker-3-tc1/source/Cargo.toml
[INFO] crate crates.io crate might_sleep 0.2.0 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" "+1.76.0" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-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:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+1.76.0" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 252bcc3ee47f7e29b7c022f7a49545b95fb9dbb733855a198f2991d2766d5a85
[INFO] running `Command { std: "docker" "start" "-a" "252bcc3ee47f7e29b7c022f7a49545b95fb9dbb733855a198f2991d2766d5a85", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "252bcc3ee47f7e29b7c022f7a49545b95fb9dbb733855a198f2991d2766d5a85", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "252bcc3ee47f7e29b7c022f7a49545b95fb9dbb733855a198f2991d2766d5a85", kill_on_drop: false }`
[INFO] [stdout] 252bcc3ee47f7e29b7c022f7a49545b95fb9dbb733855a198f2991d2766d5a85
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-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=warn" "-e" "RUSTDOCFLAGS=--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:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+1.76.0" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 03e1f5139d937799f58b9d36180bdd30f18215bab1717cb2b980b56f903104fb
[INFO] running `Command { std: "docker" "start" "-a" "03e1f5139d937799f58b9d36180bdd30f18215bab1717cb2b980b56f903104fb", kill_on_drop: false }`
[INFO] [stderr]    Compiling might_sleep v0.2.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.15s
[INFO] running `Command { std: "docker" "inspect" "03e1f5139d937799f58b9d36180bdd30f18215bab1717cb2b980b56f903104fb", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "03e1f5139d937799f58b9d36180bdd30f18215bab1717cb2b980b56f903104fb", kill_on_drop: false }`
[INFO] [stdout] 03e1f5139d937799f58b9d36180bdd30f18215bab1717cb2b980b56f903104fb
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-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=warn" "-e" "RUSTDOCFLAGS=--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:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+1.76.0" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 0dc13ee7bdc6c92ac6acdde1abe4516395fa5b841bacce446ece2f58390894eb
[INFO] running `Command { std: "docker" "start" "-a" "0dc13ee7bdc6c92ac6acdde1abe4516395fa5b841bacce446ece2f58390894eb", kill_on_drop: false }`
[INFO] [stderr]    Compiling might_sleep v0.2.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.78s
[INFO] running `Command { std: "docker" "inspect" "0dc13ee7bdc6c92ac6acdde1abe4516395fa5b841bacce446ece2f58390894eb", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "0dc13ee7bdc6c92ac6acdde1abe4516395fa5b841bacce446ece2f58390894eb", kill_on_drop: false }`
[INFO] [stdout] 0dc13ee7bdc6c92ac6acdde1abe4516395fa5b841bacce446ece2f58390894eb
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-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=warn" "-e" "RUSTDOCFLAGS=--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:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+1.76.0" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 4ab16131d387429be2edcd5d4d7c17e071c668ca47781eb0d017916845c00c70
[INFO] running `Command { std: "docker" "start" "-a" "4ab16131d387429be2edcd5d4d7c17e071c668ca47781eb0d017916845c00c70", kill_on_drop: false }`
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/might_sleep-e9d83cd6ce753b3b)
[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]      Running tests/tests.rs (/opt/rustwide/target/debug/deps/tests-4526f374a2093f3e)
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test test_cpu_limiter_get_time_slept ... ok
[INFO] [stdout] test test_cpu_limiter_change_duration ... ok
[INFO] [stdout] test test_slow_computation ... ok
[INFO] [stdout] test test_cpu_limiter_idle ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 10.01s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests might_sleep
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test src/cpu_limiter.rs - cpu_limiter::CpuLimiter (line 6) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.59s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "4ab16131d387429be2edcd5d4d7c17e071c668ca47781eb0d017916845c00c70", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "4ab16131d387429be2edcd5d4d7c17e071c668ca47781eb0d017916845c00c70", kill_on_drop: false }`
[INFO] [stdout] 4ab16131d387429be2edcd5d4d7c17e071c668ca47781eb0d017916845c00c70
[INFO] testing might_sleep-0.2.0 against beta-2024-02-10 for beta-1.77-1
[INFO] extracting crate might_sleep 0.2.0 into /workspace/builds/worker-3-tc2/source
[INFO] validating manifest of crates.io crate might_sleep 0.2.0 on toolchain beta-2024-02-10
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+beta-2024-02-10" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking crates.io crate might_sleep 0.2.0
[INFO] finished tweaking crates.io crate might_sleep 0.2.0
[INFO] tweaked toml for crates.io crate might_sleep 0.2.0 written to /workspace/builds/worker-3-tc2/source/Cargo.toml
[INFO] crate crates.io crate might_sleep 0.2.0 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" "+beta-2024-02-10" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-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:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2024-02-10" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] a53b0222607179c0262d06b1afd1ff2f0861663ffb9178a0b885af2dbb888e62
[INFO] running `Command { std: "docker" "start" "-a" "a53b0222607179c0262d06b1afd1ff2f0861663ffb9178a0b885af2dbb888e62", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "a53b0222607179c0262d06b1afd1ff2f0861663ffb9178a0b885af2dbb888e62", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "a53b0222607179c0262d06b1afd1ff2f0861663ffb9178a0b885af2dbb888e62", kill_on_drop: false }`
[INFO] [stdout] a53b0222607179c0262d06b1afd1ff2f0861663ffb9178a0b885af2dbb888e62
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-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=warn" "-e" "RUSTDOCFLAGS=--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:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2024-02-10" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 2a58959edbfc524b440b3a275c814e666ad24c7e80137d149f7ee1d3d6dc34f7
[INFO] running `Command { std: "docker" "start" "-a" "2a58959edbfc524b440b3a275c814e666ad24c7e80137d149f7ee1d3d6dc34f7", kill_on_drop: false }`
[INFO] [stderr]    Compiling might_sleep v0.2.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.10s
[INFO] running `Command { std: "docker" "inspect" "2a58959edbfc524b440b3a275c814e666ad24c7e80137d149f7ee1d3d6dc34f7", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "2a58959edbfc524b440b3a275c814e666ad24c7e80137d149f7ee1d3d6dc34f7", kill_on_drop: false }`
[INFO] [stdout] 2a58959edbfc524b440b3a275c814e666ad24c7e80137d149f7ee1d3d6dc34f7
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-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=warn" "-e" "RUSTDOCFLAGS=--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:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2024-02-10" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 59af0f1beceac0feccdb7033e6baa7ec19df638b66331a67a39ed028847c0361
[INFO] running `Command { std: "docker" "start" "-a" "59af0f1beceac0feccdb7033e6baa7ec19df638b66331a67a39ed028847c0361", kill_on_drop: false }`
[INFO] [stderr]    Compiling might_sleep v0.2.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.54s
[INFO] running `Command { std: "docker" "inspect" "59af0f1beceac0feccdb7033e6baa7ec19df638b66331a67a39ed028847c0361", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "59af0f1beceac0feccdb7033e6baa7ec19df638b66331a67a39ed028847c0361", kill_on_drop: false }`
[INFO] [stdout] 59af0f1beceac0feccdb7033e6baa7ec19df638b66331a67a39ed028847c0361
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-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=warn" "-e" "RUSTDOCFLAGS=--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:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2024-02-10" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 450715d26e1eb1fb253f345a2f48c30d3d48c5527f8bfada33379cdf80f3e7e1
[INFO] running `Command { std: "docker" "start" "-a" "450715d26e1eb1fb253f345a2f48c30d3d48c5527f8bfada33379cdf80f3e7e1", kill_on_drop: false }`
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.01s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/might_sleep-47d146e123bfe87f)
[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]      Running tests/tests.rs (/opt/rustwide/target/debug/deps/tests-938b52ab9abda91e)
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test test_cpu_limiter_get_time_slept ... ok
[INFO] [stdout] test test_cpu_limiter_change_duration ... FAILED
[INFO] [stdout] test test_slow_computation ... ok
[INFO] [stdout] test test_cpu_limiter_idle ... ok
[INFO] [stderr] error: test failed, to rerun pass `--test tests`
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- test_cpu_limiter_change_duration stdout ----
[INFO] [stdout] normal_time: 0
[INFO] [stdout] normal_time: 1
[INFO] [stdout] normal_time: 2
[INFO] [stdout] normal_time: 3
[INFO] [stdout] normal_time: 4
[INFO] [stdout] normal_time: 5
[INFO] [stdout] normal_time: 6
[INFO] [stdout] normal_time: 7
[INFO] [stdout] normal_time: 8
[INFO] [stdout] normal_time: 9
[INFO] [stdout] normal_time: 10
[INFO] [stdout] normal_time: 11
[INFO] [stdout] normal_time: 12
[INFO] [stdout] normal_time: 13
[INFO] [stdout] normal_time: 14
[INFO] [stdout] normal_time: 15
[INFO] [stdout] normal_time: 16
[INFO] [stdout] normal_time: 17
[INFO] [stdout] normal_time: 18
[INFO] [stdout] normal_time: 19
[INFO] [stdout] normal_time: 20
[INFO] [stdout] normal_time: 21
[INFO] [stdout] normal_time: 22
[INFO] [stdout] normal_time: 23
[INFO] [stdout] normal_time: 24
[INFO] [stdout] normal_time: 25
[INFO] [stdout] normal_time: 26
[INFO] [stdout] normal_time: 27
[INFO] [stdout] normal_time: 28
[INFO] [stdout] normal_time: 29
[INFO] [stdout] normal_time: 30
[INFO] [stdout] normal_time: 31
[INFO] [stdout] normal_time: 32
[INFO] [stdout] normal_time: 33
[INFO] [stdout] normal_time: 34
[INFO] [stdout] normal_time: 35
[INFO] [stdout] normal_time: 36
[INFO] [stdout] normal_time: 37
[INFO] [stdout] normal_time: 38
[INFO] [stdout] normal_time: 39
[INFO] [stdout] normal_time: 40
[INFO] [stdout] normal_time: 41
[INFO] [stdout] normal_time: 42
[INFO] [stdout] normal_time: 43
[INFO] [stdout] normal_time: 44
[INFO] [stdout] normal_time: 45
[INFO] [stdout] normal_time: 46
[INFO] [stdout] normal_time: 47
[INFO] [stdout] normal_time: 48
[INFO] [stdout] normal_time: 49
[INFO] [stdout] normal_time: 50
[INFO] [stdout] normal_time: 51
[INFO] [stdout] normal_time: 52
[INFO] [stdout] normal_time: 53
[INFO] [stdout] normal_time: 54
[INFO] [stdout] normal_time: 55
[INFO] [stdout] normal_time: 56
[INFO] [stdout] normal_time: 57
[INFO] [stdout] normal_time: 58
[INFO] [stdout] normal_time: 59
[INFO] [stdout] normal_time: 60
[INFO] [stdout] normal_time: 61
[INFO] [stdout] normal_time: 62
[INFO] [stdout] normal_time: 63
[INFO] [stdout] normal_time: 64
[INFO] [stdout] normal_time: 65
[INFO] [stdout] normal_time: 66
[INFO] [stdout] normal_time: 67
[INFO] [stdout] normal_time: 68
[INFO] [stdout] normal_time: 69
[INFO] [stdout] normal_time: 70
[INFO] [stdout] normal_time: 71
[INFO] [stdout] normal_time: 72
[INFO] [stdout] normal_time: 73
[INFO] [stdout] normal_time: 74
[INFO] [stdout] normal_time: 75
[INFO] [stdout] normal_time: 76
[INFO] [stdout] normal_time: 77
[INFO] [stdout] normal_time: 78
[INFO] [stdout] normal_time: 79
[INFO] [stdout] normal_time: 80
[INFO] [stdout] normal_time: 81
[INFO] [stdout] normal_time: 82
[INFO] [stdout] normal_time: 83
[INFO] [stdout] normal_time: 84
[INFO] [stdout] normal_time: 85
[INFO] [stdout] normal_time: 86
[INFO] [stdout] normal_time: 87
[INFO] [stdout] normal_time: 88
[INFO] [stdout] normal_time: 89
[INFO] [stdout] normal_time: 90
[INFO] [stdout] normal_time: 91
[INFO] [stdout] normal_time: 92
[INFO] [stdout] normal_time: 93
[INFO] [stdout] normal_time: 94
[INFO] [stdout] normal_time: 95
[INFO] [stdout] normal_time: 96
[INFO] [stdout] normal_time: 97
[INFO] [stdout] normal_time: 98
[INFO] [stdout] normal_time: 99
[INFO] [stdout] 1.0303314 1
[INFO] [stdout] thread 'test_cpu_limiter_change_duration' panicked at tests/tests.rs:59:5:
[INFO] [stdout] assertion failed: fuzzy_eq(now.elapsed().as_secs_f32(), 1f32, RANGE)
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x565397ea6eb6 - std::backtrace_rs::backtrace::libunwind::trace::h140b6deb6a45c671
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/std/src/../../backtrace/src/backtrace/libunwind.rs:104:5
[INFO] [stdout]    1:     0x565397ea6eb6 - std::backtrace_rs::backtrace::trace_unsynchronized::h8450d7e4e9219aed
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5
[INFO] [stdout]    2:     0x565397ea6eb6 - std::sys_common::backtrace::_print_fmt::hbd28e5e6c6fc3a3f
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/std/src/sys_common/backtrace.rs:68:5
[INFO] [stdout]    3:     0x565397ea6eb6 - <std::sys_common::backtrace::_print::DisplayBacktrace as core::fmt::Display>::fmt::hb058f285768cf98e
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/std/src/sys_common/backtrace.rs:44:22
[INFO] [stdout]    4:     0x565397ed0170 - core::fmt::rt::Argument::fmt::h87dec7a95127da24
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/core/src/fmt/rt.rs:142:9
[INFO] [stdout]    5:     0x565397ed0170 - core::fmt::write::h873f89a91a4832db
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/core/src/fmt/mod.rs:1120:17
[INFO] [stdout]    6:     0x565397ea470f - std::io::Write::write_fmt::h90f651e9e694a4bb
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/std/src/io/mod.rs:1846:15
[INFO] [stdout]    7:     0x565397ea6c94 - std::sys_common::backtrace::_print::h7719d1529d9a9e13
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/std/src/sys_common/backtrace.rs:47:5
[INFO] [stdout]    8:     0x565397ea6c94 - std::sys_common::backtrace::print::haa3afbd0e7947108
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/std/src/sys_common/backtrace.rs:34:9
[INFO] [stdout]    9:     0x565397ea8a07 - std::panicking::default_hook::{{closure}}::h10e3de2ec0eaff22
[INFO] [stdout]   10:     0x565397ea86ed - std::panicking::default_hook::hbdc1b1b7fe073dab
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/std/src/panicking.rs:289:9
[INFO] [stdout]   11:     0x565397e77ec7 - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::h20f7887d7cd0a330
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/alloc/src/boxed.rs:2029:9
[INFO] [stdout]   12:     0x565397e77ec7 - test::test_main::{{closure}}::hc125a615957b5cb1
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/test/src/lib.rs:138:21
[INFO] [stdout]   13:     0x565397ea9026 - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::h8c19e0590f8443c4
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/alloc/src/boxed.rs:2029:9
[INFO] [stdout]   14:     0x565397ea9026 - std::panicking::rust_panic_with_hook::hb81692602460473d
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/std/src/panicking.rs:785:13
[INFO] [stdout]   15:     0x565397ea8d39 - std::panicking::begin_panic_handler::{{closure}}::h2470593aa2678e9c
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/std/src/panicking.rs:651:13
[INFO] [stdout]   16:     0x565397ea73b6 - std::sys_common::backtrace::__rust_end_short_backtrace::h560bb50225b0562b
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/std/src/sys_common/backtrace.rs:171:18
[INFO] [stdout]   17:     0x565397ea8ac4 - rust_begin_unwind
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/std/src/panicking.rs:647:5
[INFO] [stdout]   18:     0x565397e41765 - core::panicking::panic_fmt::h29b6f262f6458dc3
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/core/src/panicking.rs:72:14
[INFO] [stdout]   19:     0x565397e41823 - core::panicking::panic::h239eacb54d7c53ba
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/core/src/panicking.rs:144:5
[INFO] [stdout]   20:     0x565397e43964 - tests::test_cpu_limiter_change_duration::h81f3b4e841461d8a
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/tests.rs:59:5
[INFO] [stdout]   21:     0x565397e437d7 - tests::test_cpu_limiter_change_duration::{{closure}}::h4bf11aa1d8aeb497
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/tests.rs:48:38
[INFO] [stdout]   22:     0x565397e423f6 - core::ops::function::FnOnce::call_once::he907debed05543e2
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   23:     0x565397e7dabf - core::ops::function::FnOnce::call_once::h692fe6f34484199f
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24:     0x565397e7dabf - test::__rust_begin_short_backtrace::h48b62e76bd60e2de
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/test/src/lib.rs:621:18
[INFO] [stdout]   25:     0x565397e7c8e1 - test::run_test_in_process::{{closure}}::h1c68597526ef43db
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/test/src/lib.rs:644:60
[INFO] [stdout]   26:     0x565397e7c8e1 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::hcae98092997f976b
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/core/src/panic/unwind_safe.rs:272:9
[INFO] [stdout]   27:     0x565397e7c8e1 - std::panicking::try::do_call::h8d1548c2f5bb535a
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/std/src/panicking.rs:554:40
[INFO] [stdout]   28:     0x565397e7c8e1 - std::panicking::try::hbb7a2054f484688e
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/std/src/panicking.rs:518:19
[INFO] [stdout]   29:     0x565397e7c8e1 - std::panic::catch_unwind::h696d12d9d80497c2
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/std/src/panic.rs:142:14
[INFO] [stdout]   30:     0x565397e7c8e1 - test::run_test_in_process::h053367a7217c825b
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/test/src/lib.rs:644:27
[INFO] [stdout]   31:     0x565397e7c8e1 - test::run_test::{{closure}}::hfa524e9fabcba78e
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/test/src/lib.rs:567:43
[INFO] [stdout]   32:     0x565397e43d46 - test::run_test::{{closure}}::h2c4d0bb741ef7f9f
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/test/src/lib.rs:595:41
[INFO] [stdout]   33:     0x565397e43d46 - std::sys_common::backtrace::__rust_begin_short_backtrace::h0d2c708415550075
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/std/src/sys_common/backtrace.rs:155:18
[INFO] [stdout]   34:     0x565397e48d07 - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::h76e2635840d7293f
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/std/src/thread/mod.rs:529:17
[INFO] [stdout]   35:     0x565397e48d07 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::haa640b67a810a214
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/core/src/panic/unwind_safe.rs:272:9
[INFO] [stdout]   36:     0x565397e48d07 - std::panicking::try::do_call::h41892e3bc2807ab5
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/std/src/panicking.rs:554:40
[INFO] [stdout]   37:     0x565397e48d07 - std::panicking::try::h861a16d180681c80
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/std/src/panicking.rs:518:19
[INFO] [stdout]   38:     0x565397e48d07 - std::panic::catch_unwind::h8195f9064b1509fa
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/std/src/panic.rs:142:14
[INFO] [stdout]   39:     0x565397e48d07 - std::thread::Builder::spawn_unchecked_::{{closure}}::h73cc419da1217371
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/std/src/thread/mod.rs:528:30
[INFO] [stdout]   40:     0x565397e48d07 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h79dc6499ff073b09
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   41:     0x565397ead3c5 - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::ha718e8cdc51a5182
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/alloc/src/boxed.rs:2015:9
[INFO] [stdout]   42:     0x565397ead3c5 - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h86072abc877c5474
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/alloc/src/boxed.rs:2015:9
[INFO] [stdout]   43:     0x565397ead3c5 - std::sys::pal::unix::thread::Thread::new::thread_start::hac4056197c365894
[INFO] [stdout]                                at /rustc/f2048098a1ca42a520e7bed68e321a66edc0a1b1/library/std/src/sys/pal/unix/thread.rs:108:17
[INFO] [stdout]   44:     0x7f28b5e29ac3 - <unknown>
[INFO] [stdout]   45:     0x7f28b5ebabf4 - __clone
[INFO] [stdout]   46:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     test_cpu_limiter_change_duration
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 3 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 10.01s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "450715d26e1eb1fb253f345a2f48c30d3d48c5527f8bfada33379cdf80f3e7e1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "450715d26e1eb1fb253f345a2f48c30d3d48c5527f8bfada33379cdf80f3e7e1", kill_on_drop: false }`
[INFO] [stdout] 450715d26e1eb1fb253f345a2f48c30d3d48c5527f8bfada33379cdf80f3e7e1
